Skip to content

Instantly share code, notes, and snippets.

@theoboldt

theoboldt/mongo-php-log-automaticfailover-fails

Created April 8, 2015 15:15
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save theoboldt/2d5aaa641b07e0687453 to your computer and use it in GitHub Desktop.
Save theoboldt/2d5aaa641b07e0687453 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
mongo-php-log-automaticfailover-fails
2015-04-08 16:55:17 | PARSE (INFO): Parsing mongodb://mongoUbuntu:8001,mongoUbuntu:8002,mongoUbuntu:8003
2015-04-08 16:55:17 | PARSE (INFO): - Found node: mongoUbuntu:8001
2015-04-08 16:55:17 | PARSE (INFO): - Found node: mongoUbuntu:8002
2015-04-08 16:55:17 | PARSE (INFO): - Found node: mongoUbuntu:8003
2015-04-08 16:55:17 | PARSE (INFO): - Connection type: MULTIPLE
2015-04-08 16:55:17 | PARSE (INFO): - Found option 'replicaSet': 'rs1'
2015-04-08 16:55:17 | PARSE (INFO): - Switching connection type: REPLSET
2015-04-08 16:55:17 | PARSE (INFO): - Found option 'readPreference': 'primaryPreferred'
2015-04-08 16:55:17 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (read)
2015-04-08 16:55:17 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8001
2015-04-08 16:55:17 | CON (FINE): Connecting to tcp://mongoUbuntu:8001 (mongoUbuntu:8001;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:17 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8001
2015-04-08 16:55:17 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:17 | CON (INFO): ismaster: start
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:17 | CON (INFO): ismaster: set name: rs1, ismaster: 1, secondary: 0, is_arbiter: 0
2015-04-08 16:55:17 | CON (INFO): ismaster: last ran at 1428504917
2015-04-08 16:55:17 | CON (INFO): get_server_version: start
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 713
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (INFO): get_server_version: server version: 2.6.3 (0)
2015-04-08 16:55:17 | CON (INFO): is_ping: pinging mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 17
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (INFO): is_ping: last pinged at 1428504917; time: 0ms
2015-04-08 16:55:17 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:17 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:17 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:17 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:17 | CON (INFO): ismaster: start
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:17 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:17 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:17 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:17 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:17 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8003
2015-04-08 16:55:17 | CON (FINE): Connecting to tcp://mongoUbuntu:8003 (mongoUbuntu:8003;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:17 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8003
2015-04-08 16:55:17 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:17 | CON (INFO): ismaster: start
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:17 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 1, is_arbiter: 0
2015-04-08 16:55:17 | CON (INFO): ismaster: last ran at 1428504917
2015-04-08 16:55:17 | CON (INFO): get_server_version: start
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 713
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (INFO): get_server_version: server version: 2.6.3 (0)
2015-04-08 16:55:17 | CON (INFO): is_ping: pinging mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 17
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (INFO): is_ping: last pinged at 1428504917; time: 0ms
2015-04-08 16:55:17 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:17 | CON (INFO): ismaster: start
2015-04-08 16:55:17 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:17 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:17 | CON (INFO): ismaster: set name: rs1, ismaster: 1, secondary: 0, is_arbiter: 0
2015-04-08 16:55:17 | CON (FINE): ismaster: the server name matches what we thought it'd be (mongoUbuntu:8001).
2015-04-08 16:55:17 | CON (FINE): ismaster: the found replicaset name matches the expected one (rs1).
2015-04-08 16:55:17 | CON (INFO): found host: mongoUbuntu:8001
2015-04-08 16:55:17 | CON (INFO): found host: mongoUbuntu:8002
2015-04-08 16:55:17 | CON (INFO): found host: mongoUbuntu:8003
2015-04-08 16:55:17 | CON (INFO): found host: mongoUbuntu:8004
2015-04-08 16:55:17 | CON (INFO): ismaster: last ran at 1428504917
2015-04-08 16:55:17 | CON (FINE): discover_topology: ismaster worked
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:17 | CON (INFO): discover_topology: found new host: mongoUbuntu:8002
2015-04-08 16:55:17 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:17 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:17 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:17 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:17 | CON (INFO): ismaster: start
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:17 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:17 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:17 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:17 | CON (WARN): discover_topology: could not connect to new host: mongoUbuntu:8002: ismaster: got unknown node type
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:17 | CON (INFO): discover_topology: found new host: mongoUbuntu:8004
2015-04-08 16:55:17 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8004
2015-04-08 16:55:17 | CON (FINE): Connecting to tcp://mongoUbuntu:8004 (mongoUbuntu:8004;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:17 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8004
2015-04-08 16:55:17 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:17 | CON (INFO): ismaster: start
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:17 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 1, is_arbiter: 0
2015-04-08 16:55:17 | CON (INFO): ismaster: last ran at 1428504917
2015-04-08 16:55:17 | CON (INFO): get_server_version: start
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 713
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (INFO): get_server_version: server version: 2.6.3 (0)
2015-04-08 16:55:17 | CON (INFO): is_ping: pinging mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 17
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (INFO): is_ping: last pinged at 1428504917; time: 0ms
2015-04-08 16:55:17 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504917, time left: 15
2015-04-08 16:55:17 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:17 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:17 | CON (INFO): ismaster: start
2015-04-08 16:55:17 | CON (FINE): No timeout changes for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:17 | CON (FINE): No timeout changes for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:17 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 1, is_arbiter: 0
2015-04-08 16:55:17 | CON (FINE): ismaster: the server name matches what we thought it'd be (mongoUbuntu:8003).
2015-04-08 16:55:17 | CON (FINE): ismaster: the found replicaset name matches the expected one (rs1).
2015-04-08 16:55:17 | CON (INFO): found host: mongoUbuntu:8003
2015-04-08 16:55:17 | CON (INFO): found host: mongoUbuntu:8001
2015-04-08 16:55:17 | CON (INFO): found host: mongoUbuntu:8002
2015-04-08 16:55:17 | CON (INFO): found host: mongoUbuntu:8004
2015-04-08 16:55:17 | CON (INFO): ismaster: last ran at 1428504917
2015-04-08 16:55:17 | CON (FINE): discover_topology: ismaster worked
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:17 | CON (INFO): discover_topology: found new host: mongoUbuntu:8002
2015-04-08 16:55:17 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:17 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:17 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:17 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:17 | CON (INFO): ismaster: start
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:17 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:17 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:17 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:17 | CON (WARN): discover_topology: could not connect to new host: mongoUbuntu:8002: ismaster: got unknown node type
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8004;rs1;.;2965 (looking for mongoUbuntu:8004;rs1;.;2965)
2015-04-08 16:55:17 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8004;rs1;.;2965 (looking for mongoUbuntu:8004;rs1;.;2965)
2015-04-08 16:55:17 | CON (INFO): ismaster: start
2015-04-08 16:55:17 | CON (FINE): No timeout changes for mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:17 | CON (FINE): No timeout changes for mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:17 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 1, is_arbiter: 0
2015-04-08 16:55:17 | CON (FINE): ismaster: the server name matches what we thought it'd be (mongoUbuntu:8004).
2015-04-08 16:55:17 | CON (FINE): ismaster: the found replicaset name matches the expected one (rs1).
2015-04-08 16:55:17 | CON (INFO): found host: mongoUbuntu:8004
2015-04-08 16:55:17 | CON (INFO): found host: mongoUbuntu:8001
2015-04-08 16:55:17 | CON (INFO): found host: mongoUbuntu:8002
2015-04-08 16:55:17 | CON (INFO): found host: mongoUbuntu:8003
2015-04-08 16:55:17 | CON (INFO): ismaster: last ran at 1428504917
2015-04-08 16:55:17 | CON (FINE): discover_topology: ismaster worked
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8004;rs1;.;2965 (looking for mongoUbuntu:8004;rs1;.;2965)
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:17 | CON (INFO): discover_topology: found new host: mongoUbuntu:8002
2015-04-08 16:55:17 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:17 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:17 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:17 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:17 | CON (INFO): ismaster: start
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:17 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:17 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:17 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:17 | CON (WARN): discover_topology: could not connect to new host: mongoUbuntu:8002: ismaster: got unknown node type
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:17 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:17 | REPLSET (FINE): - all servers
2015-04-08 16:55:17 | REPLSET (FINE): - collect any
2015-04-08 16:55:17 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:17 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:17 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:17 | REPLSET (FINE): sorting servers by priority and ping time
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): sorting servers: done
2015-04-08 16:55:17 | REPLSET (FINE): selecting near servers
2015-04-08 16:55:17 | REPLSET (FINE): selecting near servers: nearest is 0ms
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): selecting near server: done
2015-04-08 16:55:17 | REPLSET (INFO): pick server: the primary
2015-04-08 16:55:17 | REPLSET (INFO): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | PARSE (INFO): Parsing mongodb://mongoUbuntu:8001,mongoUbuntu:8002,mongoUbuntu:8003
2015-04-08 16:55:17 | PARSE (INFO): - Found node: mongoUbuntu:8001
2015-04-08 16:55:17 | PARSE (INFO): - Found node: mongoUbuntu:8002
2015-04-08 16:55:17 | PARSE (INFO): - Found node: mongoUbuntu:8003
2015-04-08 16:55:17 | PARSE (INFO): - Connection type: MULTIPLE
2015-04-08 16:55:17 | PARSE (INFO): - Found option 'replicaSet': 'rs1'
2015-04-08 16:55:17 | PARSE (INFO): - Switching connection type: REPLSET
2015-04-08 16:55:17 | PARSE (INFO): - Found option 'readPreference': 'primaryPreferred'
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:17 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (read)
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:17 | CON (FINE): is_ping: skipping: last ran at 1428504917, now: 1428504917, time left: 5
2015-04-08 16:55:17 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:17 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:17 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:17 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:17 | CON (INFO): ismaster: start
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:17 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:17 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:17 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:17 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:17 | CON (FINE): is_ping: skipping: last ran at 1428504917, now: 1428504917, time left: 5
2015-04-08 16:55:17 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:17 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504917, time left: 15
2015-04-08 16:55:17 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:17 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:17 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:17 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504917, time left: 15
2015-04-08 16:55:17 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:17 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:17 | REPLSET (FINE): - all servers
2015-04-08 16:55:17 | REPLSET (FINE): - collect any
2015-04-08 16:55:17 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:17 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:17 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:17 | REPLSET (FINE): sorting servers by priority and ping time
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): sorting servers: done
2015-04-08 16:55:17 | REPLSET (FINE): selecting near servers
2015-04-08 16:55:17 | REPLSET (FINE): selecting near servers: nearest is 0ms
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: SECONDARY, socket: 42, ping: 0, hash: mongoUbuntu:8004;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): selecting near server: done
2015-04-08 16:55:17 | REPLSET (INFO): pick server: the primary
2015-04-08 16:55:17 | REPLSET (INFO): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): Initializing cursor timeout to 30000 (from connection options)
2015-04-08 16:55:17 | CON (INFO): forcing primary for command
2015-04-08 16:55:17 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (write)
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:17 | CON (FINE): is_ping: skipping: last ran at 1428504917, now: 1428504917, time left: 5
2015-04-08 16:55:17 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:17 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:17 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:17 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:17 | CON (INFO): ismaster: start
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:17 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:17 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:17 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:17 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:17 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:17 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:17 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:17 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:17 | CON (FINE): is_ping: skipping: last ran at 1428504917, now: 1428504917, time left: 5
2015-04-08 16:55:17 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:17 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504917, time left: 15
2015-04-08 16:55:17 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:17 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:17 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:17 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:17 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504917, time left: 15
2015-04-08 16:55:17 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:17 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:17 | REPLSET (FINE): - all servers
2015-04-08 16:55:17 | REPLSET (FINE): - collect primary
2015-04-08 16:55:17 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:17 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:17 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:17 | REPLSET (FINE): sorting servers by priority and ping time
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): sorting servers: done
2015-04-08 16:55:17 | REPLSET (FINE): selecting near servers
2015-04-08 16:55:17 | REPLSET (FINE): selecting near servers: nearest is 0ms
2015-04-08 16:55:17 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | REPLSET (FINE): selecting near server: done
2015-04-08 16:55:17 | REPLSET (INFO): pick server: the primary
2015-04-08 16:55:17 | REPLSET (INFO): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | IO (FINE): getting reply
2015-04-08 16:55:17 | IO (FINE): getting cursor header
2015-04-08 16:55:17 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:17 | IO (FINE): getting cursor body
2015-04-08 16:55:17 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:18 | CON (FINE): Initializing cursor timeout to 30000 (from connection options)
2015-04-08 16:55:18 | CON (INFO): forcing primary for command
2015-04-08 16:55:18 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (write)
2015-04-08 16:55:18 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:18 | CON (FINE): is_ping: skipping: last ran at 1428504917, now: 1428504918, time left: 4
2015-04-08 16:55:18 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:18 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:18 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:18 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:18 | CON (INFO): ismaster: start
2015-04-08 16:55:18 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:18 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:18 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:18 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:18 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:18 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:18 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:18 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:18 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:18 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:18 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:18 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:18 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:18 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:18 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:18 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:18 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:18 | CON (FINE): is_ping: skipping: last ran at 1428504917, now: 1428504918, time left: 4
2015-04-08 16:55:18 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:18 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:18 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504918, time left: 14
2015-04-08 16:55:18 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:18 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:18 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:18 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:18 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:18 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504918, time left: 14
2015-04-08 16:55:18 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:18 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:18 | REPLSET (FINE): - all servers
2015-04-08 16:55:18 | REPLSET (FINE): - collect primary
2015-04-08 16:55:18 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:18 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:18 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:18 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:18 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:18 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:18 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:18 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:18 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:18 | REPLSET (FINE): sorting servers by priority and ping time
2015-04-08 16:55:18 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:18 | REPLSET (FINE): sorting servers: done
2015-04-08 16:55:18 | REPLSET (FINE): selecting near servers
2015-04-08 16:55:18 | REPLSET (FINE): selecting near servers: nearest is 0ms
2015-04-08 16:55:18 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:18 | REPLSET (FINE): selecting near server: done
2015-04-08 16:55:18 | REPLSET (INFO): pick server: the primary
2015-04-08 16:55:18 | REPLSET (INFO): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:18 | IO (FINE): getting reply
2015-04-08 16:55:18 | IO (FINE): getting cursor header
2015-04-08 16:55:18 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:18 | IO (FINE): getting cursor body
2015-04-08 16:55:18 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:19 | CON (FINE): Initializing cursor timeout to 30000 (from connection options)
2015-04-08 16:55:19 | CON (INFO): forcing primary for command
2015-04-08 16:55:19 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (write)
2015-04-08 16:55:19 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:19 | CON (FINE): is_ping: skipping: last ran at 1428504917, now: 1428504919, time left: 3
2015-04-08 16:55:19 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:19 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:19 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:19 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:19 | CON (INFO): ismaster: start
2015-04-08 16:55:19 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:19 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:19 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:19 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:19 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:19 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:19 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:19 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:19 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:19 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:19 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:19 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:19 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:19 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:19 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:19 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:19 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:19 | CON (FINE): is_ping: skipping: last ran at 1428504917, now: 1428504919, time left: 3
2015-04-08 16:55:19 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:19 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:19 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504919, time left: 13
2015-04-08 16:55:19 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:19 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:19 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:19 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:19 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:19 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504919, time left: 13
2015-04-08 16:55:19 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:19 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:19 | REPLSET (FINE): - all servers
2015-04-08 16:55:19 | REPLSET (FINE): - collect primary
2015-04-08 16:55:19 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:19 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:19 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:19 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:19 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:19 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:19 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:19 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:19 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:19 | REPLSET (FINE): sorting servers by priority and ping time
2015-04-08 16:55:19 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:19 | REPLSET (FINE): sorting servers: done
2015-04-08 16:55:19 | REPLSET (FINE): selecting near servers
2015-04-08 16:55:19 | REPLSET (FINE): selecting near servers: nearest is 0ms
2015-04-08 16:55:19 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:19 | REPLSET (FINE): selecting near server: done
2015-04-08 16:55:19 | REPLSET (INFO): pick server: the primary
2015-04-08 16:55:19 | REPLSET (INFO): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:19 | IO (FINE): getting reply
2015-04-08 16:55:19 | IO (FINE): getting cursor header
2015-04-08 16:55:19 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:19 | IO (FINE): getting cursor body
2015-04-08 16:55:19 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:20 | CON (FINE): Initializing cursor timeout to 30000 (from connection options)
2015-04-08 16:55:20 | CON (INFO): forcing primary for command
2015-04-08 16:55:20 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (write)
2015-04-08 16:55:20 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:20 | CON (FINE): is_ping: skipping: last ran at 1428504917, now: 1428504920, time left: 2
2015-04-08 16:55:20 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:20 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:20 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:20 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:20 | CON (INFO): ismaster: start
2015-04-08 16:55:20 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:20 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:20 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:20 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:20 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:20 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:20 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:20 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:20 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:20 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:20 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:20 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:20 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:20 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:20 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:20 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:20 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:20 | CON (FINE): is_ping: skipping: last ran at 1428504917, now: 1428504920, time left: 2
2015-04-08 16:55:20 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:20 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:20 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504920, time left: 12
2015-04-08 16:55:20 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:20 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:20 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:20 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:20 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:20 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504920, time left: 12
2015-04-08 16:55:20 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:20 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:20 | REPLSET (FINE): - all servers
2015-04-08 16:55:20 | REPLSET (FINE): - collect primary
2015-04-08 16:55:20 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:20 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:20 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:20 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:20 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:20 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:20 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:20 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:20 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:20 | REPLSET (FINE): sorting servers by priority and ping time
2015-04-08 16:55:20 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:20 | REPLSET (FINE): sorting servers: done
2015-04-08 16:55:20 | REPLSET (FINE): selecting near servers
2015-04-08 16:55:20 | REPLSET (FINE): selecting near servers: nearest is 0ms
2015-04-08 16:55:20 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:20 | REPLSET (FINE): selecting near server: done
2015-04-08 16:55:20 | REPLSET (INFO): pick server: the primary
2015-04-08 16:55:20 | REPLSET (INFO): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:20 | IO (FINE): getting reply
2015-04-08 16:55:20 | IO (FINE): getting cursor header
2015-04-08 16:55:20 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:20 | IO (FINE): getting cursor body
2015-04-08 16:55:20 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:21 | CON (FINE): Initializing cursor timeout to 30000 (from connection options)
2015-04-08 16:55:21 | CON (INFO): forcing primary for command
2015-04-08 16:55:21 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (write)
2015-04-08 16:55:21 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:21 | CON (FINE): is_ping: skipping: last ran at 1428504917, now: 1428504921, time left: 1
2015-04-08 16:55:21 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:21 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:21 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:21 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:21 | CON (INFO): ismaster: start
2015-04-08 16:55:21 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:21 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:21 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:21 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:21 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:21 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:21 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:21 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:21 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:21 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:21 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:21 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:21 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:21 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:21 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:21 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:21 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:21 | CON (FINE): is_ping: skipping: last ran at 1428504917, now: 1428504921, time left: 1
2015-04-08 16:55:21 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:21 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:21 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504921, time left: 11
2015-04-08 16:55:21 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:21 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:21 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:21 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:21 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:21 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504921, time left: 11
2015-04-08 16:55:21 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:21 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:21 | REPLSET (FINE): - all servers
2015-04-08 16:55:21 | REPLSET (FINE): - collect primary
2015-04-08 16:55:21 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:21 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:21 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:21 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:21 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:21 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:21 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:21 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:21 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:21 | REPLSET (FINE): sorting servers by priority and ping time
2015-04-08 16:55:21 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:21 | REPLSET (FINE): sorting servers: done
2015-04-08 16:55:21 | REPLSET (FINE): selecting near servers
2015-04-08 16:55:21 | REPLSET (FINE): selecting near servers: nearest is 0ms
2015-04-08 16:55:21 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:21 | REPLSET (FINE): selecting near server: done
2015-04-08 16:55:21 | REPLSET (INFO): pick server: the primary
2015-04-08 16:55:21 | REPLSET (INFO): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:21 | IO (FINE): getting reply
2015-04-08 16:55:21 | IO (FINE): getting cursor header
2015-04-08 16:55:21 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:21 | IO (FINE): getting cursor body
2015-04-08 16:55:21 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:22 | CON (FINE): Initializing cursor timeout to 30000 (from connection options)
2015-04-08 16:55:22 | CON (INFO): forcing primary for command
2015-04-08 16:55:22 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (write)
2015-04-08 16:55:22 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:22 | CON (INFO): is_ping: pinging mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:22 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:22 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:22 | CON (FINE): send_packet: data_size: 17
2015-04-08 16:55:22 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:22 | CON (INFO): is_ping: last pinged at 1428504922; time: 0ms
2015-04-08 16:55:22 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:22 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:22 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:22 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:22 | CON (INFO): ismaster: start
2015-04-08 16:55:22 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:22 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:22 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:22 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:22 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:22 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:22 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:22 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:22 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:22 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:22 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:22 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:22 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:22 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:22 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:22 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:22 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:22 | CON (INFO): is_ping: pinging mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:22 | CON (FINE): No timeout changes for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:22 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:22 | CON (FINE): send_packet: data_size: 17
2015-04-08 16:55:22 | CON (FINE): No timeout changes for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:22 | CON (INFO): is_ping: last pinged at 1428504922; time: 0ms
2015-04-08 16:55:22 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:22 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:22 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504922, time left: 10
2015-04-08 16:55:22 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:22 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:22 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:22 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:22 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:22 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504922, time left: 10
2015-04-08 16:55:22 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:22 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:22 | REPLSET (FINE): - all servers
2015-04-08 16:55:22 | REPLSET (FINE): - collect primary
2015-04-08 16:55:22 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:22 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:22 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:22 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:22 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:22 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:22 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:22 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:22 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:22 | REPLSET (FINE): sorting servers by priority and ping time
2015-04-08 16:55:22 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:22 | REPLSET (FINE): sorting servers: done
2015-04-08 16:55:22 | REPLSET (FINE): selecting near servers
2015-04-08 16:55:22 | REPLSET (FINE): selecting near servers: nearest is 0ms
2015-04-08 16:55:22 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:22 | REPLSET (FINE): selecting near server: done
2015-04-08 16:55:22 | REPLSET (INFO): pick server: the primary
2015-04-08 16:55:22 | REPLSET (INFO): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:22 | IO (FINE): getting reply
2015-04-08 16:55:22 | IO (FINE): getting cursor header
2015-04-08 16:55:22 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:22 | IO (FINE): getting cursor body
2015-04-08 16:55:22 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:23 | CON (FINE): Initializing cursor timeout to 30000 (from connection options)
2015-04-08 16:55:23 | CON (INFO): forcing primary for command
2015-04-08 16:55:23 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (write)
2015-04-08 16:55:23 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:23 | CON (FINE): is_ping: skipping: last ran at 1428504922, now: 1428504923, time left: 4
2015-04-08 16:55:23 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:23 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:23 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:23 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:23 | CON (INFO): ismaster: start
2015-04-08 16:55:23 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:23 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:23 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:23 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:23 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:23 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:23 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:23 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:23 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:23 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:23 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:23 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:23 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:23 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:23 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:23 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:23 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:23 | CON (FINE): is_ping: skipping: last ran at 1428504922, now: 1428504923, time left: 4
2015-04-08 16:55:23 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:23 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:23 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504923, time left: 9
2015-04-08 16:55:23 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:23 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:23 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:23 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:23 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:23 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504923, time left: 9
2015-04-08 16:55:23 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:23 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:23 | REPLSET (FINE): - all servers
2015-04-08 16:55:23 | REPLSET (FINE): - collect primary
2015-04-08 16:55:23 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:23 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:23 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:23 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:23 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:23 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:23 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:23 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:23 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:23 | REPLSET (FINE): sorting servers by priority and ping time
2015-04-08 16:55:23 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:23 | REPLSET (FINE): sorting servers: done
2015-04-08 16:55:23 | REPLSET (FINE): selecting near servers
2015-04-08 16:55:23 | REPLSET (FINE): selecting near servers: nearest is 0ms
2015-04-08 16:55:23 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:23 | REPLSET (FINE): selecting near server: done
2015-04-08 16:55:23 | REPLSET (INFO): pick server: the primary
2015-04-08 16:55:23 | REPLSET (INFO): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:23 | IO (FINE): getting reply
2015-04-08 16:55:23 | IO (FINE): getting cursor header
2015-04-08 16:55:23 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:23 | IO (FINE): getting cursor body
2015-04-08 16:55:23 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:24 | CON (FINE): Initializing cursor timeout to 30000 (from connection options)
2015-04-08 16:55:24 | CON (INFO): forcing primary for command
2015-04-08 16:55:24 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (write)
2015-04-08 16:55:24 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:24 | CON (FINE): is_ping: skipping: last ran at 1428504922, now: 1428504924, time left: 3
2015-04-08 16:55:24 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:24 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:24 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:24 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:24 | CON (INFO): ismaster: start
2015-04-08 16:55:24 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:24 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:24 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:24 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:24 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:24 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:24 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:24 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:24 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:24 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:24 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:24 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:24 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:24 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:24 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:24 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:24 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:24 | CON (FINE): is_ping: skipping: last ran at 1428504922, now: 1428504924, time left: 3
2015-04-08 16:55:24 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:24 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:24 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504924, time left: 8
2015-04-08 16:55:24 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:24 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:24 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:24 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:24 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:24 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504924, time left: 8
2015-04-08 16:55:24 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:24 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:24 | REPLSET (FINE): - all servers
2015-04-08 16:55:24 | REPLSET (FINE): - collect primary
2015-04-08 16:55:24 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:24 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:24 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:24 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:24 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:24 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:24 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:24 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:24 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:24 | REPLSET (FINE): sorting servers by priority and ping time
2015-04-08 16:55:24 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:24 | REPLSET (FINE): sorting servers: done
2015-04-08 16:55:24 | REPLSET (FINE): selecting near servers
2015-04-08 16:55:24 | REPLSET (FINE): selecting near servers: nearest is 0ms
2015-04-08 16:55:24 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:24 | REPLSET (FINE): selecting near server: done
2015-04-08 16:55:24 | REPLSET (INFO): pick server: the primary
2015-04-08 16:55:24 | REPLSET (INFO): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:24 | IO (FINE): getting reply
2015-04-08 16:55:24 | IO (FINE): getting cursor header
2015-04-08 16:55:24 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:24 | IO (FINE): getting cursor body
2015-04-08 16:55:24 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:25 | CON (FINE): Initializing cursor timeout to 30000 (from connection options)
2015-04-08 16:55:25 | CON (INFO): forcing primary for command
2015-04-08 16:55:25 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (write)
2015-04-08 16:55:25 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:25 | CON (FINE): is_ping: skipping: last ran at 1428504922, now: 1428504925, time left: 2
2015-04-08 16:55:25 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:25 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:25 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:25 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:25 | CON (INFO): ismaster: start
2015-04-08 16:55:25 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:25 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:25 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:25 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:25 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:25 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:25 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:25 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:25 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:25 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:25 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:25 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:25 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:25 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:25 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:25 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:25 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:25 | CON (FINE): is_ping: skipping: last ran at 1428504922, now: 1428504925, time left: 2
2015-04-08 16:55:25 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:25 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:25 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504925, time left: 7
2015-04-08 16:55:25 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:25 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:25 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:25 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:25 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:25 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504925, time left: 7
2015-04-08 16:55:25 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:25 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:25 | REPLSET (FINE): - all servers
2015-04-08 16:55:25 | REPLSET (FINE): - collect primary
2015-04-08 16:55:25 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:25 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:25 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:25 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:25 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:25 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:25 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:25 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:25 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:25 | REPLSET (FINE): sorting servers by priority and ping time
2015-04-08 16:55:25 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:25 | REPLSET (FINE): sorting servers: done
2015-04-08 16:55:25 | REPLSET (FINE): selecting near servers
2015-04-08 16:55:25 | REPLSET (FINE): selecting near servers: nearest is 0ms
2015-04-08 16:55:25 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:25 | REPLSET (FINE): selecting near server: done
2015-04-08 16:55:25 | REPLSET (INFO): pick server: the primary
2015-04-08 16:55:25 | REPLSET (INFO): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:25 | IO (FINE): getting reply
2015-04-08 16:55:25 | IO (FINE): getting cursor header
2015-04-08 16:55:25 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:25 | IO (FINE): getting cursor body
2015-04-08 16:55:25 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:26 | CON (FINE): Initializing cursor timeout to 30000 (from connection options)
2015-04-08 16:55:26 | CON (INFO): forcing primary for command
2015-04-08 16:55:26 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (write)
2015-04-08 16:55:26 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:26 | CON (FINE): is_ping: skipping: last ran at 1428504922, now: 1428504926, time left: 1
2015-04-08 16:55:26 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:26 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:26 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:26 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:26 | CON (INFO): ismaster: start
2015-04-08 16:55:26 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:26 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:26 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:26 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:26 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:26 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:26 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:26 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:26 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:26 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:26 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:26 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:26 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:26 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:26 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:26 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:26 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:26 | CON (FINE): is_ping: skipping: last ran at 1428504922, now: 1428504926, time left: 1
2015-04-08 16:55:26 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:26 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:26 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504926, time left: 6
2015-04-08 16:55:26 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:26 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:26 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:26 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:26 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:26 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504926, time left: 6
2015-04-08 16:55:26 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:26 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:26 | REPLSET (FINE): - all servers
2015-04-08 16:55:26 | REPLSET (FINE): - collect primary
2015-04-08 16:55:26 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:26 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:26 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:26 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:26 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:26 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:26 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:26 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:26 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:26 | REPLSET (FINE): sorting servers by priority and ping time
2015-04-08 16:55:26 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:26 | REPLSET (FINE): sorting servers: done
2015-04-08 16:55:26 | REPLSET (FINE): selecting near servers
2015-04-08 16:55:26 | REPLSET (FINE): selecting near servers: nearest is 0ms
2015-04-08 16:55:26 | REPLSET (FINE): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:26 | REPLSET (FINE): selecting near server: done
2015-04-08 16:55:26 | REPLSET (INFO): pick server: the primary
2015-04-08 16:55:26 | REPLSET (INFO): - connection: type: PRIMARY, socket: 42, ping: 0, hash: mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:26 | IO (FINE): getting reply
2015-04-08 16:55:26 | IO (FINE): getting cursor header
2015-04-08 16:55:26 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:26 | IO (FINE): getting cursor body
2015-04-08 16:55:26 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:27 | CON (FINE): Initializing cursor timeout to 30000 (from connection options)
2015-04-08 16:55:27 | CON (INFO): forcing primary for command
2015-04-08 16:55:27 | CON (INFO): mongo_get_read_write_connection: finding a REPLSET connection (write)
2015-04-08 16:55:27 | CON (FINE): found connection mongoUbuntu:8001;rs1;.;2965 (looking for mongoUbuntu:8001;rs1;.;2965)
2015-04-08 16:55:27 | CON (INFO): is_ping: pinging mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:27 | CON (FINE): No timeout changes for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:27 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:27 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8001;rs1;.;2965.
2015-04-08 16:55:27 | CON (WARN): Couldn't connect to 'mongoUbuntu:8001': Remote server has closed the connection
2015-04-08 16:55:27 | CON (INFO): connection_create: creating new connection for mongoUbuntu:8002
2015-04-08 16:55:27 | CON (FINE): Connecting to tcp://mongoUbuntu:8002 (mongoUbuntu:8002;rs1;.;2965) with connection timeout: 60.000000
2015-04-08 16:55:27 | CON (INFO): stream_connect: Not establishing SSL for mongoUbuntu:8002
2015-04-08 16:55:27 | CON (FINE): Setting stream timeout to 30.000000
2015-04-08 16:55:27 | CON (INFO): ismaster: start
2015-04-08 16:55:27 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:27 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:27 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:27 | CON (FINE): send_packet: data_size: 391
2015-04-08 16:55:27 | CON (FINE): Setting the stream timeout to 60.000000
2015-04-08 16:55:27 | CON (FINE): Now setting stream timeout back to 30.000000
2015-04-08 16:55:27 | CON (FINE): ismaster: setting minWireVersion to 0
2015-04-08 16:55:27 | CON (FINE): ismaster: setting maxWireVersion to 2
2015-04-08 16:55:27 | CON (FINE): ismaster: setting maxBsonObjectSize to 16777216
2015-04-08 16:55:27 | CON (FINE): ismaster: setting maxMessageSizeBytes to 48000000
2015-04-08 16:55:27 | CON (FINE): ismaster: setting maxWriteBatchSize to 1000
2015-04-08 16:55:27 | CON (INFO): ismaster: set name: rs1, ismaster: 0, secondary: 0, is_arbiter: 0
2015-04-08 16:55:27 | CON (WARN): ismaster: error running ismaster: ismaster: got unknown node type
2015-04-08 16:55:27 | CON (FINE): mongo_connection_destroy: Destroying connection object for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:27 | CON (FINE): mongo_connection_destroy: Closing socket for mongoUbuntu:8002;rs1;.;2965.
2015-04-08 16:55:27 | CON (WARN): Couldn't connect to 'mongoUbuntu:8002': ismaster: got unknown node type
2015-04-08 16:55:27 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:27 | CON (INFO): is_ping: pinging mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:27 | CON (FINE): No timeout changes for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:27 | CON (FINE): send_packet: read from header: 36
2015-04-08 16:55:27 | CON (FINE): send_packet: data_size: 17
2015-04-08 16:55:27 | CON (FINE): No timeout changes for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:27 | CON (INFO): is_ping: last pinged at 1428504927; time: 0ms
2015-04-08 16:55:27 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:27 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8001;rs1;.;2965
2015-04-08 16:55:27 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:27 | CON (WARN): discover_topology: couldn't create a connection for mongoUbuntu:8002;rs1;.;2965
2015-04-08 16:55:27 | CON (FINE): discover_topology: checking ismaster for mongoUbuntu:8003;rs1;.;2965
2015-04-08 16:55:27 | CON (FINE): found connection mongoUbuntu:8003;rs1;.;2965 (looking for mongoUbuntu:8003;rs1;.;2965)
2015-04-08 16:55:27 | CON (FINE): ismaster: skipping: last ran at 1428504917, now: 1428504927, time left: 5
2015-04-08 16:55:27 | CON (FINE): discover_topology: ismaster got skipped
2015-04-08 16:55:27 | REPLSET (FINE): finding candidate servers
2015-04-08 16:55:27 | REPLSET (FINE): - all servers
2015-04-08 16:55:27 | REPLSET (FINE): - collect primary
2015-04-08 16:55:27 | REPLSET (FINE): filter_connections: adding connections:
2015-04-08 16:55:27 | REPLSET (FINE): filter_connections: done
2015-04-08 16:55:27 | REPLSET (FINE): limiting to servers with same replicaset name
2015-04-08 16:55:27 | REPLSET (FINE): limiting to servers with same replicaset name: done
2015-04-08 16:55:27 | REPLSET (FINE): limiting by credentials
2015-04-08 16:55:27 | REPLSET (FINE): limiting by credentials: done
2015-04-08 16:55:27 | EXIT (EXIT): EXIT
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment