Code Monkey home page Code Monkey logo

Comments (21)

bjori avatar bjori commented on August 11, 2024 1

That is quite odd.
Can you share the full script?
It looks like there is a loop running here -- are the timestamps really correct? it took almost a minute to fail?

Can you try this:

<?php
$manager = new MongoDB\Driver\Manager("mongodb://localhost:27017");

$command = new MongoDB\Driver\Command(array("ping" => 1));
$result = $manager->executeCommand("test", $command);

var_dump($result, $result->toArray());

from mongo-php-driver.

bjori avatar bjori commented on August 11, 2024

mongodb.debug accepts either a directory or stderr/stdout as argument.
When running from the command line I usually do:

$ php -dmongodb.debug=stderr myscript.php

Not sure how well that will work on Windows, so can you try:

 php.exe -dmongodb.debug=. myscript.php

. means the current working directory, so it'll create a log file called PHONGO-<timestamp><random>
Can you then post the contents of that file?

Also, is there anything in the mongod.log from when you tried connecting?

from mongo-php-driver.

adisoftbn avatar adisoftbn commented on August 11, 2024

The output is pretty big :) Cannot upload it here as file.

[2015-09-20T18:51:35+00:00] PHONGO: DEBUG > Connection string: 'mongodb://localhost:27017'
[2015-09-20T18:51:35+00:00] PHONGO: TRACE > ENTRY: php_phongo_make_mongo_client():1810
[2015-09-20T18:51:35+00:00] PHONGO: DEBUG > Creating Manager, phongo-1.0.0beta1[beta] - mongoc-1.1.2, libbson-1.1.3
[2015-09-20T18:51:35+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_init():51
[2015-09-20T18:51:35+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_init():67
[2015-09-20T18:51:35+00:00] mongoc: TRACE > ENTRY: mongoc_server_description_init():97
[2015-09-20T18:51:35+00:00] mongoc: TRACE > EXIT: mongoc_server_description_init():132
[2015-09-20T18:51:35+00:00] cluster: TRACE > ENTRY: mongoc_cluster_init():1367
[2015-09-20T18:51:35+00:00] cluster: TRACE > EXIT: mongoc_cluster_init():1390
[2015-09-20T18:51:35+00:00] client: DEBUG > Using custom stream initiator.
[2015-09-20T18:51:35+00:00] PHONGO: TRACE > EXIT: php_phongo_make_mongo_client():1858
[2015-09-20T18:51:35+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_new():118
[2015-09-20T18:51:35+00:00] cursor: TRACE > EXIT: _mongoc_cursor_new():314
[2015-09-20T18:51:35+00:00] cursor: TRACE > ENTRY: mongoc_cursor_next():725
[2015-09-20T18:51:35+00:00] cursor: TRACE > TRACE: mongoc_cursor_next():730 cursor_id(0)
[2015-09-20T18:51:35+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_next():761
[2015-09-20T18:51:35+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_query():472
[2015-09-20T18:51:35+00:00] cluster: TRACE > ENTRY: mongoc_cluster_sendv_to_server():2150
[2015-09-20T18:51:35+00:00] cluster: TRACE > EXIT: mongoc_cluster_sendv_to_server():2162
[2015-09-20T18:51:35+00:00] cluster: TRACE > ENTRY: mongoc_cluster_select():1596
[2015-09-20T18:51:35+00:00] cluster: TRACE > ENTRY: _mongoc_cluster_select_by_optype():1449
[2015-09-20T18:51:35+00:00] PHONGO: TRACE > ENTRY: phongo_stream_initiator():1143
[2015-09-20T18:51:35+00:00] PHONGO: DEBUG > Connecting to 'localhost:27017[mongodb://localhost:27017]'
[2015-09-20T18:51:35+00:00] PHONGO: DEBUG > Created: RSRC#121 as 'localhost:27017[mongodb://localhost:27017]'
[2015-09-20T18:51:35+00:00] PHONGO: WARNING > setsockopt TCP_NODELAY failed
[2015-09-20T18:51:35+00:00] PHONGO: TRACE > EXIT: phongo_stream_initiator():1267
[2015-09-20T18:51:45+00:00] stream: TRACE > ENTRY: mongoc_stream_failed():78
[2015-09-20T18:51:45+00:00] PHONGO: DEBUG > Destroying RSRC#121
[2015-09-20T18:51:45+00:00] stream: TRACE > EXIT: mongoc_stream_failed():88
[2015-09-20T18:51:45+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_select():430
[2015-09-20T18:51:45+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_select():444
[2015-09-20T18:51:45+00:00] cluster: TRACE > EXIT: _mongoc_cluster_select_by_optype():1460
[2015-09-20T18:51:45+00:00] cursor: TRACE > GOTO: _mongoc_cursor_query():500 failure
[2015-09-20T18:51:45+00:00] cursor: TRACE > EXIT: _mongoc_cursor_query():560
[2015-09-20T18:51:45+00:00] cursor: TRACE > EXIT: _mongoc_cursor_next():816
[2015-09-20T18:51:45+00:00] cursor: TRACE > EXIT: mongoc_cursor_next():750
[2015-09-20T18:51:45+00:00] cursor: TRACE > ENTRY: mongoc_cursor_error():668
[2015-09-20T18:51:45+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_error():702
[2015-09-20T18:51:45+00:00] cursor: TRACE > EXIT: _mongoc_cursor_error():712
[2015-09-20T18:51:45+00:00] cursor: TRACE > EXIT: mongoc_cursor_error():694
[2015-09-20T18:51:45+00:00] cursor: TRACE > ENTRY: mongoc_cursor_destroy():321
[2015-09-20T18:51:45+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_destroy():337
[2015-09-20T18:51:45+00:00] cursor: TRACE > EXIT: _mongoc_cursor_destroy():367
[2015-09-20T18:51:45+00:00] cursor: TRACE > EXIT: mongoc_cursor_destroy():331
[2015-09-20T18:51:45+00:00] PHONGO: DEBUG > Connection string: 'mongodb://localhost:27017'
[2015-09-20T18:51:45+00:00] PHONGO: TRACE > ENTRY: php_phongo_make_mongo_client():1810
[2015-09-20T18:51:45+00:00] PHONGO: DEBUG > Creating Manager, phongo-1.0.0beta1[beta] - mongoc-1.1.2, libbson-1.1.3
[2015-09-20T18:51:45+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_init():51
[2015-09-20T18:51:45+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_init():67
[2015-09-20T18:51:45+00:00] mongoc: TRACE > ENTRY: mongoc_server_description_init():97
[2015-09-20T18:51:45+00:00] mongoc: TRACE > EXIT: mongoc_server_description_init():132
[2015-09-20T18:51:45+00:00] cluster: TRACE > ENTRY: mongoc_cluster_init():1367
[2015-09-20T18:51:45+00:00] cluster: TRACE > EXIT: mongoc_cluster_init():1390
[2015-09-20T18:51:45+00:00] client: DEBUG > Using custom stream initiator.
[2015-09-20T18:51:45+00:00] PHONGO: TRACE > EXIT: php_phongo_make_mongo_client():1858
[2015-09-20T18:51:45+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_destroy():88
[2015-09-20T18:51:45+00:00] mongoc: TRACE > ENTRY: mongoc_server_description_destroy():154
[2015-09-20T18:51:45+00:00] mongoc: TRACE > EXIT: mongoc_server_description_destroy():160
[2015-09-20T18:51:45+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_destroy():102
[2015-09-20T18:51:45+00:00] cluster: TRACE > ENTRY: mongoc_cluster_destroy():1413
[2015-09-20T18:51:45+00:00] cluster: TRACE > EXIT: mongoc_cluster_destroy():1423
[2015-09-20T18:51:45+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_new():118
[2015-09-20T18:51:45+00:00] cursor: TRACE > EXIT: _mongoc_cursor_new():314
[2015-09-20T18:51:45+00:00] cursor: TRACE > ENTRY: mongoc_cursor_next():725
[2015-09-20T18:51:45+00:00] cursor: TRACE > TRACE: mongoc_cursor_next():730 cursor_id(0)
[2015-09-20T18:51:45+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_next():761
[2015-09-20T18:51:45+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_query():472
[2015-09-20T18:51:45+00:00] cluster: TRACE > ENTRY: mongoc_cluster_sendv_to_server():2150
[2015-09-20T18:51:45+00:00] cluster: TRACE > EXIT: mongoc_cluster_sendv_to_server():2162
[2015-09-20T18:51:45+00:00] cluster: TRACE > ENTRY: mongoc_cluster_select():1596
[2015-09-20T18:51:45+00:00] cluster: TRACE > ENTRY: _mongoc_cluster_select_by_optype():1449
[2015-09-20T18:51:45+00:00] PHONGO: TRACE > ENTRY: phongo_stream_initiator():1143
[2015-09-20T18:51:45+00:00] PHONGO: DEBUG > Connecting to 'localhost:27017[mongodb://localhost:27017]'
[2015-09-20T18:51:45+00:00] PHONGO: DEBUG > Created: RSRC#126 as 'localhost:27017[mongodb://localhost:27017]'
[2015-09-20T18:51:45+00:00] PHONGO: WARNING > setsockopt TCP_NODELAY failed
[2015-09-20T18:51:45+00:00] PHONGO: TRACE > EXIT: phongo_stream_initiator():1267
[2015-09-20T18:51:55+00:00] stream: TRACE > ENTRY: mongoc_stream_failed():78
[2015-09-20T18:51:55+00:00] PHONGO: DEBUG > Destroying RSRC#126
[2015-09-20T18:51:56+00:00] stream: TRACE > EXIT: mongoc_stream_failed():88
[2015-09-20T18:51:56+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_select():430
[2015-09-20T18:51:56+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_select():444
[2015-09-20T18:51:56+00:00] cluster: TRACE > EXIT: _mongoc_cluster_select_by_optype():1460
[2015-09-20T18:51:56+00:00] cursor: TRACE > GOTO: _mongoc_cursor_query():500 failure
[2015-09-20T18:51:56+00:00] cursor: TRACE > EXIT: _mongoc_cursor_query():560
[2015-09-20T18:51:56+00:00] cursor: TRACE > EXIT: _mongoc_cursor_next():816
[2015-09-20T18:51:56+00:00] cursor: TRACE > EXIT: mongoc_cursor_next():750
[2015-09-20T18:51:56+00:00] cursor: TRACE > ENTRY: mongoc_cursor_error():668
[2015-09-20T18:51:56+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_error():702
[2015-09-20T18:51:56+00:00] cursor: TRACE > EXIT: _mongoc_cursor_error():712
[2015-09-20T18:51:56+00:00] cursor: TRACE > EXIT: mongoc_cursor_error():694
[2015-09-20T18:51:56+00:00] cursor: TRACE > ENTRY: mongoc_cursor_destroy():321
[2015-09-20T18:51:56+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_destroy():337
[2015-09-20T18:51:56+00:00] cursor: TRACE > EXIT: _mongoc_cursor_destroy():367
[2015-09-20T18:51:56+00:00] cursor: TRACE > EXIT: mongoc_cursor_destroy():331
[2015-09-20T18:51:56+00:00] PHONGO: DEBUG > Connection string: 'mongodb://localhost:27017'
[2015-09-20T18:51:56+00:00] PHONGO: TRACE > ENTRY: php_phongo_make_mongo_client():1810
[2015-09-20T18:51:56+00:00] PHONGO: DEBUG > Creating Manager, phongo-1.0.0beta1[beta] - mongoc-1.1.2, libbson-1.1.3
[2015-09-20T18:51:56+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_init():51
[2015-09-20T18:51:56+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_init():67
[2015-09-20T18:51:56+00:00] mongoc: TRACE > ENTRY: mongoc_server_description_init():97
[2015-09-20T18:51:56+00:00] mongoc: TRACE > EXIT: mongoc_server_description_init():132
[2015-09-20T18:51:56+00:00] cluster: TRACE > ENTRY: mongoc_cluster_init():1367
[2015-09-20T18:51:56+00:00] cluster: TRACE > EXIT: mongoc_cluster_init():1390
[2015-09-20T18:51:56+00:00] client: DEBUG > Using custom stream initiator.
[2015-09-20T18:51:56+00:00] PHONGO: TRACE > EXIT: php_phongo_make_mongo_client():1858
[2015-09-20T18:51:56+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_destroy():88
[2015-09-20T18:51:56+00:00] mongoc: TRACE > ENTRY: mongoc_server_description_destroy():154
[2015-09-20T18:51:56+00:00] mongoc: TRACE > EXIT: mongoc_server_description_destroy():160
[2015-09-20T18:51:56+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_destroy():102
[2015-09-20T18:51:56+00:00] cluster: TRACE > ENTRY: mongoc_cluster_destroy():1413
[2015-09-20T18:51:56+00:00] cluster: TRACE > EXIT: mongoc_cluster_destroy():1423
[2015-09-20T18:51:56+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_new():118
[2015-09-20T18:51:56+00:00] cursor: TRACE > EXIT: _mongoc_cursor_new():314
[2015-09-20T18:51:56+00:00] cursor: TRACE > ENTRY: mongoc_cursor_next():725
[2015-09-20T18:51:56+00:00] cursor: TRACE > TRACE: mongoc_cursor_next():730 cursor_id(0)
[2015-09-20T18:51:56+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_next():761
[2015-09-20T18:51:56+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_query():472
[2015-09-20T18:51:56+00:00] cluster: TRACE > ENTRY: mongoc_cluster_sendv_to_server():2150
[2015-09-20T18:51:56+00:00] cluster: TRACE > EXIT: mongoc_cluster_sendv_to_server():2162
[2015-09-20T18:51:56+00:00] cluster: TRACE > ENTRY: mongoc_cluster_select():1596
[2015-09-20T18:51:56+00:00] cluster: TRACE > ENTRY: _mongoc_cluster_select_by_optype():1449
[2015-09-20T18:51:56+00:00] PHONGO: TRACE > ENTRY: phongo_stream_initiator():1143
[2015-09-20T18:51:56+00:00] PHONGO: DEBUG > Connecting to 'localhost:27017[mongodb://localhost:27017]'
[2015-09-20T18:51:56+00:00] PHONGO: DEBUG > Created: RSRC#130 as 'localhost:27017[mongodb://localhost:27017]'
[2015-09-20T18:51:56+00:00] PHONGO: WARNING > setsockopt TCP_NODELAY failed
[2015-09-20T18:51:56+00:00] PHONGO: TRACE > EXIT: phongo_stream_initiator():1267
[2015-09-20T18:52:06+00:00] stream: TRACE > ENTRY: mongoc_stream_failed():78
[2015-09-20T18:52:06+00:00] PHONGO: DEBUG > Destroying RSRC#130
[2015-09-20T18:52:07+00:00] stream: TRACE > EXIT: mongoc_stream_failed():88
[2015-09-20T18:52:07+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_select():430
[2015-09-20T18:52:07+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_select():444
[2015-09-20T18:52:07+00:00] cluster: TRACE > EXIT: _mongoc_cluster_select_by_optype():1460
[2015-09-20T18:52:07+00:00] cursor: TRACE > GOTO: _mongoc_cursor_query():500 failure
[2015-09-20T18:52:07+00:00] cursor: TRACE > EXIT: _mongoc_cursor_query():560
[2015-09-20T18:52:07+00:00] cursor: TRACE > EXIT: _mongoc_cursor_next():816
[2015-09-20T18:52:07+00:00] cursor: TRACE > EXIT: mongoc_cursor_next():750
[2015-09-20T18:52:07+00:00] cursor: TRACE > ENTRY: mongoc_cursor_error():668
[2015-09-20T18:52:07+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_error():702
[2015-09-20T18:52:07+00:00] cursor: TRACE > EXIT: _mongoc_cursor_error():712
[2015-09-20T18:52:07+00:00] cursor: TRACE > EXIT: mongoc_cursor_error():694
[2015-09-20T18:52:07+00:00] cursor: TRACE > ENTRY: mongoc_cursor_destroy():321
[2015-09-20T18:52:07+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_destroy():337
[2015-09-20T18:52:07+00:00] cursor: TRACE > EXIT: _mongoc_cursor_destroy():367
[2015-09-20T18:52:07+00:00] cursor: TRACE > EXIT: mongoc_cursor_destroy():331
[2015-09-20T18:52:07+00:00] PHONGO: DEBUG > Connection string: 'mongodb://localhost:27017'
[2015-09-20T18:52:07+00:00] PHONGO: TRACE > ENTRY: php_phongo_make_mongo_client():1810
[2015-09-20T18:52:07+00:00] PHONGO: DEBUG > Creating Manager, phongo-1.0.0beta1[beta] - mongoc-1.1.2, libbson-1.1.3
[2015-09-20T18:52:07+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_init():51
[2015-09-20T18:52:07+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_init():67
[2015-09-20T18:52:07+00:00] mongoc: TRACE > ENTRY: mongoc_server_description_init():97
[2015-09-20T18:52:07+00:00] mongoc: TRACE > EXIT: mongoc_server_description_init():132
[2015-09-20T18:52:07+00:00] cluster: TRACE > ENTRY: mongoc_cluster_init():1367
[2015-09-20T18:52:07+00:00] cluster: TRACE > EXIT: mongoc_cluster_init():1390
[2015-09-20T18:52:07+00:00] client: DEBUG > Using custom stream initiator.
[2015-09-20T18:52:07+00:00] PHONGO: TRACE > EXIT: php_phongo_make_mongo_client():1858
[2015-09-20T18:52:07+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_destroy():88
[2015-09-20T18:52:07+00:00] mongoc: TRACE > ENTRY: mongoc_server_description_destroy():154
[2015-09-20T18:52:07+00:00] mongoc: TRACE > EXIT: mongoc_server_description_destroy():160
[2015-09-20T18:52:07+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_destroy():102
[2015-09-20T18:52:07+00:00] cluster: TRACE > ENTRY: mongoc_cluster_destroy():1413
[2015-09-20T18:52:07+00:00] cluster: TRACE > EXIT: mongoc_cluster_destroy():1423
[2015-09-20T18:52:07+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_new():118
[2015-09-20T18:52:07+00:00] cursor: TRACE > EXIT: _mongoc_cursor_new():314
[2015-09-20T18:52:07+00:00] cursor: TRACE > ENTRY: mongoc_cursor_next():725
[2015-09-20T18:52:07+00:00] cursor: TRACE > TRACE: mongoc_cursor_next():730 cursor_id(0)
[2015-09-20T18:52:07+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_next():761
[2015-09-20T18:52:07+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_query():472
[2015-09-20T18:52:07+00:00] cluster: TRACE > ENTRY: mongoc_cluster_sendv_to_server():2150
[2015-09-20T18:52:07+00:00] cluster: TRACE > EXIT: mongoc_cluster_sendv_to_server():2162
[2015-09-20T18:52:07+00:00] cluster: TRACE > ENTRY: mongoc_cluster_select():1596
[2015-09-20T18:52:07+00:00] cluster: TRACE > ENTRY: _mongoc_cluster_select_by_optype():1449
[2015-09-20T18:52:07+00:00] PHONGO: TRACE > ENTRY: phongo_stream_initiator():1143
[2015-09-20T18:52:07+00:00] PHONGO: DEBUG > Connecting to 'localhost:27017[mongodb://localhost:27017]'
[2015-09-20T18:52:07+00:00] PHONGO: DEBUG > Created: RSRC#133 as 'localhost:27017[mongodb://localhost:27017]'
[2015-09-20T18:52:07+00:00] PHONGO: WARNING > setsockopt TCP_NODELAY failed
[2015-09-20T18:52:07+00:00] PHONGO: TRACE > EXIT: phongo_stream_initiator():1267
[2015-09-20T18:52:17+00:00] stream: TRACE > ENTRY: mongoc_stream_failed():78
[2015-09-20T18:52:17+00:00] PHONGO: DEBUG > Destroying RSRC#133
[2015-09-20T18:52:17+00:00] stream: TRACE > EXIT: mongoc_stream_failed():88
[2015-09-20T18:52:17+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_select():430
[2015-09-20T18:52:17+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_select():444
[2015-09-20T18:52:17+00:00] cluster: TRACE > EXIT: _mongoc_cluster_select_by_optype():1460
[2015-09-20T18:52:17+00:00] cursor: TRACE > GOTO: _mongoc_cursor_query():500 failure
[2015-09-20T18:52:17+00:00] cursor: TRACE > EXIT: _mongoc_cursor_query():560
[2015-09-20T18:52:17+00:00] cursor: TRACE > EXIT: _mongoc_cursor_next():816
[2015-09-20T18:52:17+00:00] cursor: TRACE > EXIT: mongoc_cursor_next():750
[2015-09-20T18:52:17+00:00] cursor: TRACE > ENTRY: mongoc_cursor_error():668
[2015-09-20T18:52:17+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_error():702
[2015-09-20T18:52:17+00:00] cursor: TRACE > EXIT: _mongoc_cursor_error():712
[2015-09-20T18:52:17+00:00] cursor: TRACE > EXIT: mongoc_cursor_error():694
[2015-09-20T18:52:17+00:00] cursor: TRACE > ENTRY: mongoc_cursor_destroy():321
[2015-09-20T18:52:17+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_destroy():337
[2015-09-20T18:52:17+00:00] cursor: TRACE > EXIT: _mongoc_cursor_destroy():367
[2015-09-20T18:52:17+00:00] cursor: TRACE > EXIT: mongoc_cursor_destroy():331
[2015-09-20T18:52:17+00:00] PHONGO-BSON: TRACE > encoding as-if was stdclass
[2015-09-20T18:52:18+00:00] PHONGO-BSON: TRACE > encoding as-if was stdclass
[2015-09-20T18:52:18+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_new():118
[2015-09-20T18:52:18+00:00] cursor: TRACE > EXIT: _mongoc_cursor_new():314
[2015-09-20T18:52:18+00:00] cursor: TRACE > ENTRY: mongoc_cursor_next():725
[2015-09-20T18:52:18+00:00] cursor: TRACE > TRACE: mongoc_cursor_next():730 cursor_id(0)
[2015-09-20T18:52:18+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_next():761
[2015-09-20T18:52:18+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_query():472
[2015-09-20T18:52:18+00:00] cluster: TRACE > ENTRY: mongoc_cluster_sendv_to_server():2150
[2015-09-20T18:52:18+00:00] cluster: TRACE > EXIT: mongoc_cluster_sendv_to_server():2162
[2015-09-20T18:52:18+00:00] cluster: TRACE > ENTRY: mongoc_cluster_select():1596
[2015-09-20T18:52:18+00:00] cluster: TRACE > ENTRY: _mongoc_cluster_select_by_optype():1449
[2015-09-20T18:52:18+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_select():430
[2015-09-20T18:52:18+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_select():444
[2015-09-20T18:52:18+00:00] cluster: TRACE > EXIT: _mongoc_cluster_select_by_optype():1460
[2015-09-20T18:52:18+00:00] cursor: TRACE > GOTO: _mongoc_cursor_query():500 failure
[2015-09-20T18:52:18+00:00] cursor: TRACE > EXIT: _mongoc_cursor_query():560
[2015-09-20T18:52:18+00:00] cursor: TRACE > EXIT: _mongoc_cursor_next():816
[2015-09-20T18:52:18+00:00] cursor: TRACE > EXIT: mongoc_cursor_next():750
[2015-09-20T18:52:18+00:00] cursor: TRACE > ENTRY: mongoc_cursor_error():668
[2015-09-20T18:52:18+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_error():702
[2015-09-20T18:52:18+00:00] cursor: TRACE > EXIT: _mongoc_cursor_error():712
[2015-09-20T18:52:18+00:00] cursor: TRACE > EXIT: mongoc_cursor_error():694
[2015-09-20T18:52:18+00:00] cursor: TRACE > ENTRY: mongoc_cursor_destroy():321
[2015-09-20T18:52:18+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_destroy():337
[2015-09-20T18:52:18+00:00] cursor: TRACE > EXIT: _mongoc_cursor_destroy():367
[2015-09-20T18:52:18+00:00] cursor: TRACE > EXIT: mongoc_cursor_destroy():331

from mongo-php-driver.

adisoftbn avatar adisoftbn commented on August 11, 2024

Btw, nothing else in php logs. All i get is a php exception that i catch.

from mongo-php-driver.

adisoftbn avatar adisoftbn commented on August 11, 2024

My script is actually a huge hybrid(sql+mongo) framework. I created some compatible wrappers with both old and new drivers, because i wanna use php7 on production.

So my framework code works perfect with the legacy driver. I only made the wrappers for the new driver to be able to switch it very easy.

And yes the timestamps are corect :) it hangs for a while. Very strange really. Looks like a timeout somewhere, but not in my script. All the script runs in about <10ms with the legacy driver :)

I get the same hang also in the linux box(debian 7.8) where i built the branch by my own.

the full output for your code is:

[2015-09-20T19:13:00+00:00] PHONGO: DEBUG > Connection string: 'mongodb://localhost:27017'
[2015-09-20T19:13:00+00:00] PHONGO: TRACE > ENTRY: php_phongo_make_mongo_client():1810
[2015-09-20T19:13:00+00:00] PHONGO: DEBUG > Creating Manager, phongo-1.0.0beta1[beta] - mongoc-1.1.2, libbson-1.1.3
[2015-09-20T19:13:00+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_init():51
[2015-09-20T19:13:00+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_init():67
[2015-09-20T19:13:00+00:00] mongoc: TRACE > ENTRY: mongoc_server_description_init():97
[2015-09-20T19:13:00+00:00] mongoc: TRACE > EXIT: mongoc_server_description_init():132
[2015-09-20T19:13:00+00:00] cluster: TRACE > ENTRY: mongoc_cluster_init():1367
[2015-09-20T19:13:00+00:00] cluster: TRACE > EXIT: mongoc_cluster_init():1390
[2015-09-20T19:13:00+00:00] client: DEBUG > Using custom stream initiator.
[2015-09-20T19:13:00+00:00] PHONGO: TRACE > EXIT: php_phongo_make_mongo_client():1858
[2015-09-20T19:13:00+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_new():118
[2015-09-20T19:13:00+00:00] cursor: TRACE > EXIT: _mongoc_cursor_new():314
[2015-09-20T19:13:00+00:00] cursor: TRACE > ENTRY: mongoc_cursor_next():725
[2015-09-20T19:13:00+00:00] cursor: TRACE > TRACE: mongoc_cursor_next():730 cursor_id(0)
[2015-09-20T19:13:00+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_next():761
[2015-09-20T19:13:00+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_query():472
[2015-09-20T19:13:00+00:00] cluster: TRACE > ENTRY: mongoc_cluster_sendv_to_server():2150
[2015-09-20T19:13:00+00:00] cluster: TRACE > EXIT: mongoc_cluster_sendv_to_server():2162
[2015-09-20T19:13:00+00:00] cluster: TRACE > ENTRY: mongoc_cluster_select():1596
[2015-09-20T19:13:00+00:00] cluster: TRACE > ENTRY: _mongoc_cluster_select_by_optype():1449
[2015-09-20T19:13:00+00:00] PHONGO: TRACE > ENTRY: phongo_stream_initiator():1143
[2015-09-20T19:13:00+00:00] PHONGO: DEBUG > Connecting to 'localhost:27017[mongodb://localhost:27017]'
[2015-09-20T19:13:00+00:00] PHONGO: DEBUG > Created: RSRC#5 as 'localhost:27017[mongodb://localhost:27017]'
[2015-09-20T19:13:00+00:00] PHONGO: WARNING > setsockopt TCP_NODELAY failed
[2015-09-20T19:13:00+00:00] PHONGO: TRACE > EXIT: phongo_stream_initiator():1267
[2015-09-20T19:13:11+00:00] stream: TRACE > ENTRY: mongoc_stream_failed():78
[2015-09-20T19:13:11+00:00] PHONGO: DEBUG > Destroying RSRC#5
[2015-09-20T19:13:11+00:00] stream: TRACE > EXIT: mongoc_stream_failed():88
[2015-09-20T19:13:11+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_select():430
[2015-09-20T19:13:11+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_select():444
[2015-09-20T19:13:11+00:00] cluster: TRACE > EXIT: _mongoc_cluster_select_by_optype():1460
[2015-09-20T19:13:11+00:00] cursor: TRACE > GOTO: _mongoc_cursor_query():500 failure
[2015-09-20T19:13:11+00:00] cursor: TRACE > EXIT: _mongoc_cursor_query():560
[2015-09-20T19:13:11+00:00] cursor: TRACE > EXIT: _mongoc_cursor_next():816
[2015-09-20T19:13:11+00:00] cursor: TRACE > EXIT: mongoc_cursor_next():750
[2015-09-20T19:13:11+00:00] cursor: TRACE > ENTRY: mongoc_cursor_error():668
[2015-09-20T19:13:11+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_error():702
[2015-09-20T19:13:11+00:00] cursor: TRACE > EXIT: _mongoc_cursor_error():712
[2015-09-20T19:13:11+00:00] cursor: TRACE > EXIT: mongoc_cursor_error():694
[2015-09-20T19:13:11+00:00] cursor: TRACE > ENTRY: mongoc_cursor_destroy():321
[2015-09-20T19:13:11+00:00] cursor: TRACE > ENTRY: _mongoc_cursor_destroy():337
[2015-09-20T19:13:11+00:00] cursor: TRACE > EXIT: _mongoc_cursor_destroy():367
[2015-09-20T19:13:11+00:00] cursor: TRACE > EXIT: mongoc_cursor_destroy():331
PHP Fatal error: Uncaught exception 'MongoDB\Driver\Exception\RuntimeException' with message 'No suitable servers found' in test_mongo2.php:5
Stack trace:
#0 test_mongo2.php(5): MongoDB\Driver\Manager->executeCommand('test', Object(MongoDB\Driver\Command))
#1 {main}
thrown in test_mongo2.php on line 5

Fatal error: Uncaught exception 'MongoDB\Driver\Exception\RuntimeException' with message 'No suitable servers found' in test_mongo2.php:5
Stack trace:
#0 test_mongo2.php(5): MongoDB\Driver\Manager->executeCommand('test', Object(MongoDB\Driver\Command))
#1 {main}
thrown in test_mongo2.php on line 5
[2015-09-20T19:13:11+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_destroy():88
[2015-09-20T19:13:11+00:00] mongoc: TRACE > ENTRY: mongoc_server_description_destroy():154
[2015-09-20T19:13:11+00:00] mongoc: TRACE > EXIT: mongoc_server_description_destroy():160
[2015-09-20T19:13:11+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_destroy():102
[2015-09-20T19:13:11+00:00] cluster: TRACE > ENTRY: mongoc_cluster_destroy():1413
[2015-09-20T19:13:11+00:00] cluster: TRACE > EXIT: mongoc_cluster_destroy():1423

from mongo-php-driver.

bjori avatar bjori commented on August 11, 2024

These 11 seconds of nothingness are confusing:

[2015-09-20T19:13:00+00:00] PHONGO: TRACE > EXIT: phongo_stream_initiator():1267
[2015-09-20T19:13:11+00:00] stream: TRACE > ENTRY: mongoc_stream_failed():78

The fact setsockopt also fails is weird:

[2015-09-20T19:13:00+00:00] PHONGO: WARNING > setsockopt TCP_NODELAY failed

The weirdest thing is there doesn't appear to be any attempts to write anything (e.g. mongoc_stream_writev() call to write isMaster discovery).
So its never actually able to connect to localhost:27017.

You said you tried this both on Windows and Linux with the same results?
I can't account for those 11seconds.. Before beta#1 that would be the internal SDAM loop retrying, but that was fixed before the beta#1 I believe.

If you could try the current master on linux and see if that changes anything that would be great.
Make sure you git submodule update --init and use the currently bundled mongoc (there are a few changes we haven't caught up with yet, so using newer version won't work).

from mongo-php-driver.

adisoftbn avatar adisoftbn commented on August 11, 2024

I cannot compile the master branch in my linux box, because i have php7 in all containers.
I compiled the php7 branch there which seems to be 19 days old.

redownloaded php7 branch, compiled and now it seems that it works on linux.... very strange

here are the results: http://ady.titanfw.net/uploads/mongo_test_linux.txt

But when i run my big sync scripts(which moves like 50MB of text fields from sql to mongo) i get a segmentation fault at the end, but the data syncs fully. So the segmentation fault cames after php script finishes. Maybe is something memory related.

I dont get segmentation fault from your small script.

So it might be a windows related problem ? 1 hour ago i got the same "No suitable servers found". but i changed a lot the config, so maybe it was a really config problem.

But on my windows box I have all extensions installed, and i can change them easily, and I still have the problem.

But anyway i'm happy that i can test it on my php7 install. this was the reason for the beta install.

from mongo-php-driver.

bjori avatar bjori commented on August 11, 2024

Can you post the backtrace from the segmentation fault? (either if you have to core dump, or can reproduce it).

See instruction for Windows and *nix.

from mongo-php-driver.

adisoftbn avatar adisoftbn commented on August 11, 2024

Here is the debug from Debian 7.8+php7

http://ady.titanfw.net/uploads/mongo_dbg_linux.txt

from mongo-php-driver.

bjori avatar bjori commented on August 11, 2024

Ah. Thats likely fixed by #105

Although, that pr is against master, similar check should be done in the PHP7 branch

from mongo-php-driver.

bjori avatar bjori commented on August 11, 2024

See also https://jira.mongodb.org/browse/PHPC-431

from mongo-php-driver.

adisoftbn avatar adisoftbn commented on August 11, 2024

Updated from php7 branch, compiled and installed, but i still get that segmentation fault. :)

[2015-09-24T18:14:08+00:00] mongoc: TRACE > ENTRY: mongoc_topology_description_destroy():92
[2015-09-24T18:14:08+00:00] mongoc: TRACE > ENTRY: mongoc_server_description_destroy():158
[2015-09-24T18:14:08+00:00] mongoc: TRACE > EXIT: mongoc_server_description_destroy():164
[2015-09-24T18:14:08+00:00] mongoc: TRACE > EXIT: mongoc_topology_description_destroy():106
[2015-09-24T18:14:08+00:00] stream: TRACE > ENTRY: mongoc_stream_destroy():102
Segmentation fault

The server where I have php7 i use it for production, so i can't do many tests. But I will be able to experiment it on windows when i can get it running atleast partially, on both php7 and php 5.6 :)

from mongo-php-driver.

bjori avatar bjori commented on August 11, 2024

Can you post the new backtrace?

I find it hard to believe that would be identical as the previous fix verifies base_stream->stream.
Maybe you ran into a new/different issue exposed as result of this fix.

from mongo-php-driver.

adisoftbn avatar adisoftbn commented on August 11, 2024

Well, i have changed the branch to php-7.0.0 from master few days ago for php, then recompiled my php and mongo extension, and now, suddenly, i get no segmentation fault...

I'm still testing it. Now it seems to work also via apache(i get full output content everywhere since now). Still testing it, and i'm thinking to update dev env to php7 to be able to test it more further.

I'm still trying to find a workaround for my win php 5.6 "No suitable servers found". And also waiting for a php 7 win build.

Until now it seems to be a really great job on this mongodb driver! :)

from mongo-php-driver.

bjori avatar bjori commented on August 11, 2024

Thanks! :D

Great that the PHP7 things seem to be working.
1.0.0-beta2 is being released in the next couple of days which upgrades to mongoc-1.2-rc build.
The beta2 is still only compatible with PHP5.[4,5,6], but I'd recommend trying it and see if it resolves the problem -- at the very least the error messaging have improved and should give you a better hint as to why there are no servers found.

from mongo-php-driver.

adisoftbn avatar adisoftbn commented on August 11, 2024

Updated my windows php 5.6 with latest mongodb beta 2.

And if i connect to 0.0.0.0 or localhost i get:
Fatal error: Uncaught exception 'MongoDB\Driver\Exception\RuntimeException' with message 'No suitable servers found (serverselectiontryonce set): [connection error calling ismaster on '0.0.0.0:27017']'

If I try to connect to 127.0.0.1 or any other interface IP, PHP crashes:

[fcgid:warn] [pid 2808:tid 1136](OS 109)The pipe has been ended. : [client 127.0.0.1:16536] mod_fcgid: get overlap result error, referer:
[core:error] [pid 2808:tid 1136] [client 127.0.0.1:16536] End of script output before headers: index.php, referer:

from mongo-php-driver.

bjori avatar bjori commented on August 11, 2024

The ismaster failure on 0.0.0.0:27017 is good (0.0.0.0 is non-routable address, so you can't connect to it, even though you can listen to it).

The crash is very bad.
Can you recreate the backtrace? (see https://bugs.php.net/bugs-generating-backtrace-win32.php)

Also;
Which MongoDB version is this (still 3.0.6?)? - Are there log entries recorded during the time of the crash? (any asserts messages would be very valuable)

from mongo-php-driver.

adisoftbn avatar adisoftbn commented on August 11, 2024

the same happens to localhost. I wanted to see if the resolve of "localhost" is the problem. I will try to make a backtrace.

PS: My mongodb version is 3.0.6

mod_fcgid: stderr: PHP Fatal error: Uncaught exception 'MongoDB\Driver\Exception\RuntimeException' with message 'No suitable servers found (serverselectiontryonce set): [connection error calling ismaster on 'localhost:27017']'

from mongo-php-driver.

derickr avatar derickr commented on August 11, 2024

Did you have any luck creating a backtrace?

from mongo-php-driver.

 avatar commented on August 11, 2024

Same error on php 5.6.12 - php_mongodb-1.0.1-5.6-nts-vc11-x86-1

Change to:
$manager = new MongoDB\Driver\Manager("mongodb://127.0.0.1:27017");

Resolve this issue, Windows 10

from mongo-php-driver.

jmikola avatar jmikola commented on August 11, 2024

Resolving this as a dupe of #133, as the same issue appears to have been diagnosed there.

from mongo-php-driver.

Related Issues (20)

Recommend Projects

  • React photo React

    A declarative, efficient, and flexible JavaScript library for building user interfaces.

  • Vue.js photo Vue.js

    🖖 Vue.js is a progressive, incrementally-adoptable JavaScript framework for building UI on the web.

  • Typescript photo Typescript

    TypeScript is a superset of JavaScript that compiles to clean JavaScript output.

  • TensorFlow photo TensorFlow

    An Open Source Machine Learning Framework for Everyone

  • Django photo Django

    The Web framework for perfectionists with deadlines.

  • D3 photo D3

    Bring data to life with SVG, Canvas and HTML. 📊📈🎉

Recommend Topics

  • javascript

    JavaScript (JS) is a lightweight interpreted programming language with first-class functions.

  • web

    Some thing interesting about web. New door for the world.

  • server

    A server is a program made to process requests and deliver data to clients.

  • Machine learning

    Machine learning is a way of modeling and interpreting data that allows a piece of software to respond intelligently.

  • Game

    Some thing interesting about game, make everyone happy.

Recommend Org

  • Facebook photo Facebook

    We are working to build community through open source technology. NB: members must have two-factor auth.

  • Microsoft photo Microsoft

    Open source projects and samples from Microsoft.

  • Google photo Google

    Google ❤️ Open Source for everyone.

  • D3 photo D3

    Data-Driven Documents codes.