-
Type:
Bug
-
Resolution: Done
-
Priority:
Minor - P4
-
None
-
Affects Version/s: Legacy C++ Implementation
-
Component/s: mongodump, mongorestore
-
Labels:
Logged many times per second during the "query". This warning is benign for mongodump --dbpath. See example session:
rassi@laptop:~ $ mongodump --dbpath /data/db -d test -c foo Tue Aug 13 14:12:35.767 [tools] DATABASE: test to dump/test Tue Aug 13 14:12:35.768 [tools] test.foo to dump/test/foo.bson Tue Aug 13 14:12:35.834 [tools] warning Listener::getElapsedTimeMillis returning 0ms Tue Aug 13 14:12:35.910 [tools] warning Listener::getElapsedTimeMillis returning 0ms Tue Aug 13 14:12:35.940 [tools] warning Listener::getElapsedTimeMillis returning 0ms Tue Aug 13 14:12:35.973 [tools] warning Listener::getElapsedTimeMillis returning 0ms Tue Aug 13 14:12:35.998 [tools] warning Listener::getElapsedTimeMillis returning 0ms Tue Aug 13 14:12:36.031 [tools] warning Listener::getElapsedTimeMillis returning 0ms Tue Aug 13 14:12:36.069 [tools] warning Listener::getElapsedTimeMillis returning 0ms Tue Aug 13 14:12:36.107 [tools] warning Listener::getElapsedTimeMillis returning 0ms Tue Aug 13 14:12:36.140 [tools] warning Listener::getElapsedTimeMillis returning 0ms Tue Aug 13 14:12:36.172 [tools] warning Listener::getElapsedTimeMillis returning 0ms Tue Aug 13 14:12:36.194 [tools] warning Listener::getElapsedTimeMillis returning 0ms Tue Aug 13 14:12:36.230 [tools] warning Listener::getElapsedTimeMillis returning 0ms Tue Aug 13 14:12:36.267 [tools] 202623 objects Tue Aug 13 14:12:36.268 [tools] Metadata for test.foo to dump/test/foo.metadata.json Tue Aug 13 14:12:36.268 dbexit: Tue Aug 13 14:12:36.268 [tools] shutdown: going to close listening sockets... Tue Aug 13 14:12:36.269 [tools] shutdown: going to flush diaglog... Tue Aug 13 14:12:36.269 [tools] shutdown: going to close sockets... Tue Aug 13 14:12:36.269 [tools] shutdown: waiting for fs preallocator... Tue Aug 13 14:12:36.269 [tools] shutdown: closing all files... Tue Aug 13 14:12:36.274 [tools] closeAllFiles() finished Tue Aug 13 14:12:36.274 [tools] shutdown: removing fs lock... Tue Aug 13 14:12:36.274 dbexit: really exiting now
This also affects mongorestore (the following output is based on a different dataset):
./mongodb-2.4.10/bin/mongorestore --dbpath=/data/db2 Thu Apr 3 17:12:58.220 [tools] dump/test/bigcoll.bson Thu Apr 3 17:12:58.220 [tools] going into namespace [test.bigcoll] Thu Apr 3 17:12:58.228 [tools] warning: Restoring to test.bigcoll without dropping. Restored data will be inserted without raising errors; check your server log Thu Apr 3 17:13:01.002 [tools] Progress: 1527900/33000000 4% (bytes) Thu Apr 3 17:13:04.002 [tools] Progress: 3128400/33000000 9% (bytes) Thu Apr 3 17:13:07.002 [tools] Progress: 4933500/33000000 14% (bytes) Thu Apr 3 17:13:10.004 [tools] Progress: 6758400/33000000 20% (bytes) Thu Apr 3 17:13:13.002 [tools] Progress: 8556900/33000000 25% (bytes) Thu Apr 3 17:13:16.000 [tools] Progress: 10272900/33000000 31% (bytes) Thu Apr 3 17:13:19.001 [tools] Progress: 11708400/33000000 35% (bytes) Thu Apr 3 17:13:22.004 [tools] Progress: 13348500/33000000 40% (bytes) Thu Apr 3 17:13:25.000 [tools] Progress: 15150300/33000000 45% (bytes) Thu Apr 3 17:13:27.820 [tools] warning Listener::getElapsedTimeMillis returning 0ms Thu Apr 3 17:13:28.004 [tools] Progress: 16962000/33000000 51% (bytes) Thu Apr 3 17:13:31.005 [tools] Progress: 18760500/33000000 56% (bytes) Thu Apr 3 17:13:34.003 [tools] Progress: 20585400/33000000 62% (bytes) Thu Apr 3 17:13:37.004 [tools] Progress: 22374000/33000000 67% (bytes) Thu Apr 3 17:13:40.003 [tools] Progress: 24189000/33000000 73% (bytes) Thu Apr 3 17:13:43.004 [tools] Progress: 25957800/33000000 78% (bytes) ... Thu Apr 3 17:13:55.625 dbexit: really exiting now