Skip to content

Instantly share code, notes, and snippets.

@danielsmithsd

danielsmithsd/test-elasticsearch-river-mongo.log

Last active August 29, 2015 14:10
Show Gist options
  • Save danielsmithsd/60686da08a6869263b10 to your computer and use it in GitHub Desktop.
Save danielsmithsd/60686da08a6869263b10 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
test-elasticsearch-river-mongo.log
2014-12-06 09:12:59,609 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - *** beforeSuite ***
2014-12-06 09:12:59,616 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - *** setupElasticsearchServer ***
2014-12-06 09:13:03,887 INFO org.elasticsearch.node - [X-23] version[1.4.0], pid[10596], build[bc94bd8/2014-11-05T14:26:12Z]
2014-12-06 09:13:03,888 INFO org.elasticsearch.node - [X-23] initializing ...
2014-12-06 09:13:03,924 INFO org.elasticsearch.plugins - [X-23] loaded [mongodb-river, mapper-attachments, lang-javascript], sites []
2014-12-06 09:13:06,353 INFO org.elasticsearch.script - [X-23] compiling script file [/home/ubuntu/elasticsearch-river-mongodb/target/config/scripts/advanced-transformation-groovy-script.groovy]
2014-12-06 09:13:06,905 TRACE org.elasticsearch.rest.action.mongodb - [X-23] RestMongoDBRiverAction - baseUrl: /_river/mongodb
2014-12-06 09:13:07,025 INFO org.elasticsearch.node - [X-23] initialized
2014-12-06 09:13:07,026 INFO org.elasticsearch.node - [X-23] starting ...
2014-12-06 09:13:07,029 INFO org.elasticsearch.transport - [X-23] bound_address {local[1]}, publish_address {local[1]}
2014-12-06 09:13:07,039 INFO org.elasticsearch.discovery - [X-23] es-test-cluster/cF5Vs9SuSLiiNy4mx4pMvg
2014-12-06 09:13:07,041 INFO org.elasticsearch.cluster.service - [X-23] master {new [X-23][cF5Vs9SuSLiiNy4mx4pMvg][ip-172-31-2-36][local[1]]{local=true}}, removed {[X-23][6OkcbmlZQYCuqghIp7APjA][ip-172-31-2-36][local[1]]{local=true},}, reason: local-disco-initial_connect(master)
2014-12-06 09:13:07,124 INFO org.elasticsearch.gateway - [X-23] recovered [0] indices into cluster_state
2014-12-06 09:13:07,164 INFO org.elasticsearch.http - [X-23] bound_address {inet[/0:0:0:0:0:0:0:0:9200]}, publish_address {inet[/172.31.2.36:9200]}
2014-12-06 09:13:07,164 INFO org.elasticsearch.node - [X-23] started
2014-12-06 09:13:07,165 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - *** initMongoInstances(VANILLA) ***
2014-12-06 09:13:07,173 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - replSetName in config: es-test-mongodb
2014-12-06 09:13:10,235 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Server #1: localhost:37017
2014-12-06 09:13:10,236 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - replSetName in config: es-test-mongodb
2014-12-06 09:13:10,638 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Server #2: localhost:37018
2014-12-06 09:13:10,638 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - replSetName in config: es-test-mongodb
2014-12-06 09:13:11,014 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Server #3: localhost:37019
2014-12-06 09:13:13,210 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - isMaster: { "serverUsed" : "localhost:37017" , "ismaster" : false , "secondary" : false , "info" : "can't get local.system.replset config from self or any seed (EMPTYCONFIG)" , "isreplicaset" : true , "maxBsonObjectSize" : 16777216 , "maxMessageSizeBytes" : 48000000 , "maxWriteBatchSize" : 1000 , "localTime" : { "$date" : "2014-12-06T09:13:13.208Z"} , "maxWireVersion" : 2 , "minWireVersion" : 0 , "ok" : 1.0}
2014-12-06 09:13:13,266 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - replSetInitiate result: { "serverUsed" : "localhost:37017" , "info" : "Config now saved locally. Should come online in about a minute." , "ok" : 1.0}
2014-12-06 09:13:18,269 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - replSetGetStatus result: { "serverUsed" : "localhost:37017" , "set" : "es-test-mongodb" , "date" : { "$date" : "2014-12-06T09:13:18.000Z"} , "myState" : 2 , "members" : [ { "_id" : 0 , "name" : "localhost:37017" , "health" : 1.0 , "state" : 2 , "stateStr" : "SECONDARY" , "uptime" : 9 , "optime" : { "$ts" : 1417857193 , "$inc" : 1} , "optimeDate" : { "$date" : "2014-12-06T09:13:13.000Z"} , "infoMessage" : "not electing self, not all members up and we have been up less than 5 minutes" , "self" : true} , { "_id" : 1 , "name" : "localhost:37018" , "health" : 1.0 , "state" : 5 , "stateStr" : "STARTUP2" , "uptime" : 5 , "optime" : { "$ts" : 0 , "$inc" : 0} , "optimeDate" : { "$date" : "1970-01-01T00:00:00.000Z"} , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:17.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:17.000Z"} , "pingMs" : 0 , "lastHeartbeatMessage" : "initial sync need a member to be primary or secondary to do our initial sync"} , { "_id" : 2 , "name" : "localhost:37019" , "health" : 1.0 , "state" : 5 , "stateStr" : "STARTUP2" , "uptime" : 5 , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:17.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:18.000Z"} , "pingMs" : 0}] , "ok" : 1.0}
2014-12-06 09:13:18,270 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 0 , "name" : "localhost:37017" , "health" : 1.0 , "state" : 2 , "stateStr" : "SECONDARY" , "uptime" : 9 , "optime" : { "$ts" : 1417857193 , "$inc" : 1} , "optimeDate" : { "$date" : "2014-12-06T09:13:13.000Z"} , "infoMessage" : "not electing self, not all members up and we have been up less than 5 minutes" , "self" : true}
2014-12-06 09:13:18,271 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 2
2014-12-06 09:13:18,272 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 1 , "name" : "localhost:37018" , "health" : 1.0 , "state" : 5 , "stateStr" : "STARTUP2" , "uptime" : 5 , "optime" : { "$ts" : 0 , "$inc" : 0} , "optimeDate" : { "$date" : "1970-01-01T00:00:00.000Z"} , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:17.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:17.000Z"} , "pingMs" : 0 , "lastHeartbeatMessage" : "initial sync need a member to be primary or secondary to do our initial sync"}
2014-12-06 09:13:18,272 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 5
2014-12-06 09:13:18,272 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Waiting 3 seconds for replicaset to change status...
2014-12-06 09:13:21,275 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 0 , "name" : "localhost:37017" , "health" : 1.0 , "state" : 2 , "stateStr" : "SECONDARY" , "uptime" : 12 , "optime" : { "$ts" : 1417857193 , "$inc" : 1} , "optimeDate" : { "$date" : "2014-12-06T09:13:13.000Z"} , "infoMessage" : "not electing self, not all members up and we have been up less than 5 minutes" , "self" : true}
2014-12-06 09:13:21,275 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 2
2014-12-06 09:13:21,276 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 1 , "name" : "localhost:37018" , "health" : 1.0 , "state" : 5 , "stateStr" : "STARTUP2" , "uptime" : 8 , "optime" : { "$ts" : 0 , "$inc" : 0} , "optimeDate" : { "$date" : "1970-01-01T00:00:00.000Z"} , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:21.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:19.000Z"} , "pingMs" : 0 , "lastHeartbeatMessage" : "initial sync need a member to be primary or secondary to do our initial sync"}
2014-12-06 09:13:21,277 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 5
2014-12-06 09:13:21,277 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Waiting 3 seconds for replicaset to change status...
2014-12-06 09:13:24,279 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 0 , "name" : "localhost:37017" , "health" : 1.0 , "state" : 1 , "stateStr" : "PRIMARY" , "uptime" : 15 , "optime" : { "$ts" : 1417857193 , "$inc" : 1} , "optimeDate" : { "$date" : "2014-12-06T09:13:13.000Z"} , "electionTime" : { "$ts" : 1417857201 , "$inc" : 1} , "electionDate" : { "$date" : "2014-12-06T09:13:21.000Z"} , "self" : true}
2014-12-06 09:13:24,279 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 1
2014-12-06 09:13:24,280 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 1 , "name" : "localhost:37018" , "health" : 1.0 , "state" : 5 , "stateStr" : "STARTUP2" , "uptime" : 11 , "optime" : { "$ts" : 0 , "$inc" : 0} , "optimeDate" : { "$date" : "1970-01-01T00:00:00.000Z"} , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:23.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:23.000Z"} , "pingMs" : 0 , "lastHeartbeatMessage" : "initial sync need a member to be primary or secondary to do our initial sync"}
2014-12-06 09:13:24,281 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 5
2014-12-06 09:13:24,281 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Waiting 3 seconds for replicaset to change status...
2014-12-06 09:13:27,283 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 0 , "name" : "localhost:37017" , "health" : 1.0 , "state" : 1 , "stateStr" : "PRIMARY" , "uptime" : 18 , "optime" : { "$ts" : 1417857193 , "$inc" : 1} , "optimeDate" : { "$date" : "2014-12-06T09:13:13.000Z"} , "electionTime" : { "$ts" : 1417857201 , "$inc" : 1} , "electionDate" : { "$date" : "2014-12-06T09:13:21.000Z"} , "self" : true}
2014-12-06 09:13:27,283 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 1
2014-12-06 09:13:27,284 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 1 , "name" : "localhost:37018" , "health" : 1.0 , "state" : 5 , "stateStr" : "STARTUP2" , "uptime" : 14 , "optime" : { "$ts" : 0 , "$inc" : 0} , "optimeDate" : { "$date" : "1970-01-01T00:00:00.000Z"} , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:27.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:25.000Z"} , "pingMs" : 0 , "lastHeartbeatMessage" : "initial sync need a member to be primary or secondary to do our initial sync"}
2014-12-06 09:13:27,285 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 5
2014-12-06 09:13:27,285 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Waiting 3 seconds for replicaset to change status...
2014-12-06 09:13:30,287 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 0 , "name" : "localhost:37017" , "health" : 1.0 , "state" : 1 , "stateStr" : "PRIMARY" , "uptime" : 21 , "optime" : { "$ts" : 1417857193 , "$inc" : 1} , "optimeDate" : { "$date" : "2014-12-06T09:13:13.000Z"} , "electionTime" : { "$ts" : 1417857201 , "$inc" : 1} , "electionDate" : { "$date" : "2014-12-06T09:13:21.000Z"} , "self" : true}
2014-12-06 09:13:30,288 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 1
2014-12-06 09:13:30,289 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 1 , "name" : "localhost:37018" , "health" : 1.0 , "state" : 5 , "stateStr" : "STARTUP2" , "uptime" : 17 , "optime" : { "$ts" : 0 , "$inc" : 0} , "optimeDate" : { "$date" : "1970-01-01T00:00:00.000Z"} , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:29.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:29.000Z"} , "pingMs" : 0 , "lastHeartbeatMessage" : "initial sync need a member to be primary or secondary to do our initial sync"}
2014-12-06 09:13:30,290 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 5
2014-12-06 09:13:30,290 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Waiting 3 seconds for replicaset to change status...
2014-12-06 09:13:33,292 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 0 , "name" : "localhost:37017" , "health" : 1.0 , "state" : 1 , "stateStr" : "PRIMARY" , "uptime" : 24 , "optime" : { "$ts" : 1417857193 , "$inc" : 1} , "optimeDate" : { "$date" : "2014-12-06T09:13:13.000Z"} , "electionTime" : { "$ts" : 1417857201 , "$inc" : 1} , "electionDate" : { "$date" : "2014-12-06T09:13:21.000Z"} , "self" : true}
2014-12-06 09:13:33,293 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 1
2014-12-06 09:13:33,294 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 1 , "name" : "localhost:37018" , "health" : 1.0 , "state" : 2 , "stateStr" : "SECONDARY" , "uptime" : 20 , "optime" : { "$ts" : 1417857193 , "$inc" : 1} , "optimeDate" : { "$date" : "2014-12-06T09:13:13.000Z"} , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:33.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:31.000Z"} , "pingMs" : 0 , "syncingTo" : "localhost:37017"}
2014-12-06 09:13:33,295 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 2
2014-12-06 09:13:33,296 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 2 , "name" : "localhost:37019" , "health" : 1.0 , "state" : 7 , "stateStr" : "ARBITER" , "uptime" : 20 , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:33.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:32.000Z"} , "pingMs" : 0}
2014-12-06 09:13:33,296 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 7
2014-12-06 09:13:33,311 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - *** initMongoInstances(TOKUMX) ***
2014-12-06 09:13:33,316 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - replSetName in config: es-test-tokumx
2014-12-06 09:13:35,314 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Server #1: localhost:37027
2014-12-06 09:13:35,315 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - replSetName in config: es-test-tokumx
2014-12-06 09:13:35,462 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Server #2: localhost:37028
2014-12-06 09:13:35,462 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - replSetName in config: es-test-tokumx
2014-12-06 09:13:36,273 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Server #3: localhost:37029
2014-12-06 09:13:38,283 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - isMaster: { "serverUsed" : "localhost:37027" , "ismaster" : false , "secondary" : false , "info" : "can't get local.system.replset config from self or any seed (EMPTYCONFIG)" , "isreplicaset" : true , "maxBsonObjectSize" : 16777216 , "maxMessageSizeBytes" : 48000000 , "localTime" : { "$date" : "2014-12-06T09:13:38.283Z"} , "ok" : 1.0}
2014-12-06 09:13:38,325 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - replSetInitiate result: { "serverUsed" : "localhost:37027" , "info" : "Config now saved locally. Should come online in about a minute." , "ok" : 1.0}
2014-12-06 09:13:43,327 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - replSetGetStatus result: { "serverUsed" : "localhost:37027" , "startupStatus" : 6 , "ok" : 0.0 , "errmsg" : "Received replSetInitiate - should come online shortly."}
2014-12-06 09:13:43,331 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Waiting 3 seconds for replicaset to change status...
2014-12-06 09:13:46,333 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 0 , "name" : "localhost:37027" , "health" : 1.0 , "state" : 2 , "stateStr" : "SECONDARY" , "uptime" : 12 , "optimeDate" : { "$date" : "2014-12-06T09:13:38.322Z"} , "lastGTID" : "GTID(1, 0)" , "lastUnappliedGTID" : "GTID(1, 0)" , "minLiveGTID" : "GTID(1, 1)" , "minUnappliedGTID" : "GTID(1, 1)" , "oplogVersion" : 3 , "errmsg" : "not electing self, not all members up and we have been up less than 5 minutes" , "self" : true}
2014-12-06 09:13:46,333 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 2
2014-12-06 09:13:46,335 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 1 , "name" : "localhost:37028" , "health" : 1.0 , "state" : 6 , "stateStr" : "UNKNOWN" , "uptime" : 1 , "optimeDate" : { "$date" : "1970-01-01T00:00:00.000Z"} , "lastGTID" : "GTID(0, 0)" , "lastUnappliedGTID" : "GTID(0, 0)" , "minLiveGTID" : "GTID(0, 0)" , "minUnappliedGTID" : "GTID(0, 0)" , "oplogVersion" : 0 , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:45.000Z"} , "lastHeartbeatRecv" : { "$date" : "1970-01-01T00:00:00.000Z"} , "pingMs" : 0 , "lastHeartbeatMessage" : "still initializing"}
2014-12-06 09:13:46,335 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 6
2014-12-06 09:13:46,336 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Waiting 3 seconds for replicaset to change status...
2014-12-06 09:13:49,338 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 0 , "name" : "localhost:37027" , "health" : 1.0 , "state" : 2 , "stateStr" : "SECONDARY" , "uptime" : 15 , "optimeDate" : { "$date" : "2014-12-06T09:13:38.322Z"} , "lastGTID" : "GTID(1, 0)" , "lastUnappliedGTID" : "GTID(1, 0)" , "minLiveGTID" : "GTID(1, 1)" , "minUnappliedGTID" : "GTID(1, 1)" , "oplogVersion" : 3 , "errmsg" : "not electing self, not all members up and we have been up less than 5 minutes" , "self" : true}
2014-12-06 09:13:49,339 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 2
2014-12-06 09:13:49,340 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 1 , "name" : "localhost:37028" , "health" : 1.0 , "state" : 5 , "stateStr" : "STARTUP2" , "uptime" : 4 , "optimeDate" : { "$date" : "1970-01-01T00:00:00.000Z"} , "lastGTID" : "GTID(0, 0)" , "lastUnappliedGTID" : "GTID(0, 0)" , "minLiveGTID" : "GTID(0, 1)" , "minUnappliedGTID" : "GTID(0, 0)" , "oplogVersion" : 3 , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:49.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:47.000Z"} , "pingMs" : 0 , "lastHeartbeatMessage" : "initial sync need a member to be primary or secondary to do our initial sync"}
2014-12-06 09:13:49,344 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 5
2014-12-06 09:13:49,344 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Waiting 3 seconds for replicaset to change status...
2014-12-06 09:13:52,346 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 0 , "name" : "localhost:37027" , "health" : 1.0 , "state" : 2 , "stateStr" : "SECONDARY" , "uptime" : 18 , "optimeDate" : { "$date" : "2014-12-06T09:13:38.322Z"} , "lastGTID" : "GTID(1, 0)" , "lastUnappliedGTID" : "GTID(1, 0)" , "minLiveGTID" : "GTID(1, 1)" , "minUnappliedGTID" : "GTID(1, 1)" , "oplogVersion" : 3 , "errmsg" : "not electing self, not all members up and we have been up less than 5 minutes" , "self" : true}
2014-12-06 09:13:52,347 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 2
2014-12-06 09:13:52,348 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 1 , "name" : "localhost:37028" , "health" : 1.0 , "state" : 5 , "stateStr" : "STARTUP2" , "uptime" : 7 , "optimeDate" : { "$date" : "1970-01-01T00:00:00.000Z"} , "lastGTID" : "GTID(0, 0)" , "lastUnappliedGTID" : "GTID(0, 0)" , "minLiveGTID" : "GTID(0, 1)" , "minUnappliedGTID" : "GTID(0, 0)" , "oplogVersion" : 3 , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:51.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:51.000Z"} , "pingMs" : 0 , "lastHeartbeatMessage" : "initial sync need a member to be primary or secondary to do our initial sync"}
2014-12-06 09:13:52,349 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 5
2014-12-06 09:13:52,350 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Waiting 3 seconds for replicaset to change status...
2014-12-06 09:13:55,351 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 0 , "name" : "localhost:37027" , "health" : 1.0 , "state" : 1 , "stateStr" : "PRIMARY" , "uptime" : 21 , "optimeDate" : { "$date" : "2014-12-06T09:13:38.322Z"} , "lastGTID" : "GTID(1, 0)" , "lastUnappliedGTID" : "GTID(1, 0)" , "minLiveGTID" : "GTID(1, 1)" , "minUnappliedGTID" : "GTID(1, 1)" , "oplogVersion" : 3 , "self" : true}
2014-12-06 09:13:55,352 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 1
2014-12-06 09:13:55,353 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 1 , "name" : "localhost:37028" , "health" : 1.0 , "state" : 5 , "stateStr" : "STARTUP2" , "uptime" : 10 , "optimeDate" : { "$date" : "1970-01-01T00:00:00.000Z"} , "lastGTID" : "GTID(0, 0)" , "lastUnappliedGTID" : "GTID(0, 0)" , "minLiveGTID" : "GTID(0, 1)" , "minUnappliedGTID" : "GTID(0, 0)" , "oplogVersion" : 3 , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:55.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:53.000Z"} , "pingMs" : 0 , "lastHeartbeatMessage" : "initial sync need a member to be primary or secondary to do our initial sync"}
2014-12-06 09:13:55,354 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 5
2014-12-06 09:13:55,355 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Waiting 3 seconds for replicaset to change status...
2014-12-06 09:13:58,356 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 0 , "name" : "localhost:37027" , "health" : 1.0 , "state" : 1 , "stateStr" : "PRIMARY" , "uptime" : 24 , "optimeDate" : { "$date" : "2014-12-06T09:13:38.322Z"} , "lastGTID" : "GTID(1, 0)" , "lastUnappliedGTID" : "GTID(1, 0)" , "minLiveGTID" : "GTID(1, 1)" , "minUnappliedGTID" : "GTID(1, 1)" , "oplogVersion" : 3 , "self" : true}
2014-12-06 09:13:58,358 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 1
2014-12-06 09:13:58,363 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 1 , "name" : "localhost:37028" , "health" : 1.0 , "state" : 5 , "stateStr" : "STARTUP2" , "uptime" : 13 , "optimeDate" : { "$date" : "1970-01-01T00:00:00.000Z"} , "lastGTID" : "GTID(0, 0)" , "lastUnappliedGTID" : "GTID(0, 0)" , "minLiveGTID" : "GTID(0, 1)" , "minUnappliedGTID" : "GTID(0, 0)" , "oplogVersion" : 3 , "lastHeartbeat" : { "$date" : "2014-12-06T09:13:57.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:57.000Z"} , "pingMs" : 0 , "lastHeartbeatMessage" : "initial sync need a member to be primary or secondary to do our initial sync"}
2014-12-06 09:13:58,364 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 5
2014-12-06 09:13:58,364 DEBUG org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Waiting 3 seconds for replicaset to change status...
2014-12-06 09:14:01,368 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 0 , "name" : "localhost:37027" , "health" : 1.0 , "state" : 1 , "stateStr" : "PRIMARY" , "uptime" : 27 , "optimeDate" : { "$date" : "2014-12-06T09:13:38.322Z"} , "lastGTID" : "GTID(1, 0)" , "lastUnappliedGTID" : "GTID(1, 0)" , "minLiveGTID" : "GTID(1, 1)" , "minUnappliedGTID" : "GTID(1, 1)" , "oplogVersion" : 3 , "self" : true}
2014-12-06 09:14:01,369 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 1
2014-12-06 09:14:01,370 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 1 , "name" : "localhost:37028" , "health" : 1.0 , "state" : 2 , "stateStr" : "SECONDARY" , "uptime" : 16 , "optimeDate" : { "$date" : "2014-12-06T09:13:38.322Z"} , "lastGTID" : "GTID(1, 0)" , "lastUnappliedGTID" : "GTID(1, 0)" , "minLiveGTID" : "GTID(1, 1)" , "minUnappliedGTID" : "GTID(1, 1)" , "oplogVersion" : 3 , "lastHeartbeat" : { "$date" : "2014-12-06T09:14:01.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:13:59.000Z"} , "pingMs" : 0 , "lastHeartbeatMessage" : "syncing to: localhost:37027" , "syncingTo" : "localhost:37027"}
2014-12-06 09:14:01,371 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 2
2014-12-06 09:14:01,372 TRACE org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member: { "_id" : 2 , "name" : "localhost:37029" , "health" : 1.0 , "state" : 7 , "stateStr" : "ARBITER" , "uptime" : 16 , "lastHeartbeat" : { "$date" : "2014-12-06T09:14:01.000Z"} , "lastHeartbeatRecv" : { "$date" : "2014-12-06T09:14:00.000Z"} , "pingMs" : 0}
2014-12-06 09:14:01,372 INFO org.elasticsearch.river.mongodb.tokumx.RiverTokuMXOplogRefTest - Member state: 7
2014-12-06 09:14:01,393 DEBUG org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - createDatabase d-rivermongoadvancedtransformationgr-1417857178557
2014-12-06 09:14:01,394 INFO org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - Start createCollection
2014-12-06 09:14:01,396 DEBUG org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - Start testSimpleTransformationScript
2014-12-06 09:14:01,396 DEBUG org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - Create river testsimpletransformationscriptgroovyriver-1417857241396
2014-12-06 09:14:01,397 INFO org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - Create river [testsimpletransformationscriptgroovyriver-1417857241396]
2014-12-06 09:14:01,397 DEBUG org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - Get river setting
2014-12-06 09:14:01,399 INFO org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - River setting [{
"type": "mongodb",
"mongodb": {
"servers": [{
"host": "localhost",
"port": 37027
},
{
"host": "localhost",
"port": 37028
},
{
"host": "localhost",
"port": 37029
}],
"options": {
"secondary_read_preference": true,
"advanced_transformation": true,
"parent_types": []
},
"db": "d-rivermongoadvancedtransformationgr-1417857178557",
"collection": "c-rivermongoadvancedtransformationgr-1417857178557",
"gridfs": false,
"script_type": "groovy",
"script": "ctx.documents << [data: [id: 12345, name: '99'], operation: 'i'] << [data: [id: 6666, name: 'document-ignored'], ignore: true] "
},
"index": {
"name": "testsimpletransformationscriptgroovyindex-1417857241396",
"type": "d-rivermongoadvancedtransformationgr-1417857178557",
"throttle_size": 2000
}
}]
2014-12-06 09:14:02,150 INFO org.elasticsearch.cluster.metadata - [X-23] [_river] creating index, cause [auto(index api)], shards [1]/[0], mappings []
2014-12-06 09:14:02,721 INFO org.elasticsearch.cluster.metadata - [X-23] [_river] update_mapping [testsimpletransformationscriptgroovyriver-1417857241396] (dynamic)
2014-12-06 09:14:02,821 DEBUG org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - Running Cluster Health
2014-12-06 09:14:02,825 INFO org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - Done Cluster Health, status GREEN
2014-12-06 09:14:02,901 DEBUG org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - Wait for river [testsimpletransformationscriptgroovyriver-1417857241396] to start
2014-12-06 09:14:03,755 TRACE org.elasticsearch.river.mongodb.MongoDBRiver - Initializing river : [testsimpletransformationscriptgroovyriver-1417857241396]
2014-12-06 09:14:03,757 TRACE org.elasticsearch.river.mongodb - Parse river settings for testsimpletransformationscriptgroovyriver-1417857241396
2014-12-06 09:14:03,759 TRACE org.elasticsearch.river.mongodb - mongoServersSettings: [{port=37027, host=localhost}, {port=37028, host=localhost}, {port=37029, host=localhost}]
2014-12-06 09:14:03,759 TRACE org.elasticsearch.river.mongodb - Server: localhost - 37027
2014-12-06 09:14:03,760 TRACE org.elasticsearch.river.mongodb - Server: localhost - 37028
2014-12-06 09:14:03,760 TRACE org.elasticsearch.river.mongodb - Server: localhost - 37029
2014-12-06 09:14:03,760 TRACE org.elasticsearch.river.mongodb - mongoOptionsSettings: {advanced_transformation=true, secondary_read_preference=true, parent_types=[]}
2014-12-06 09:14:03,761 TRACE org.elasticsearch.river.mongodb - parentTypesSettings: []
2014-12-06 09:14:03,763 INFO org.elasticsearch.river.mongodb.MongoDBRiver - Starting river testsimpletransformationscriptgroovyriver-1417857241396
2014-12-06 09:14:03,764 INFO org.elasticsearch.river.mongodb.MongoDBRiver - MongoDB River Plugin - version[2.0.5-SNAPSHOT] - hash[27f199c] - time[2014-12-06T09:12:46Z]
2014-12-06 09:14:03,765 INFO org.elasticsearch.river.mongodb.MongoDBRiver - starting mongodb stream. options: secondaryreadpreference [true], drop_collection [false], include_collection [], throttlesize [2000], gridfs [false], filter [null], db [d-rivermongoadvancedtransformationgr-1417857178557], collection [c-rivermongoadvancedtransformationgr-1417857178557], script [ctx.documents << [data: [id: 12345, name: '99'], operation: 'i'] << [data: [id: 6666, name: 'document-ignored'], ignore: true] ], indexing to [testsimpletransformationscriptgroovyindex-1417857241396]/[d-rivermongoadvancedtransformationgr-1417857178557]
2014-12-06 09:14:03,766 INFO org.elasticsearch.river.mongodb.util - setRiverStatus called with testsimpletransformationscriptgroovyriver-1417857241396 - RUNNING
2014-12-06 09:14:03,778 INFO org.elasticsearch.cluster.metadata - [X-23] [_river] update_mapping [testsimpletransformationscriptgroovyriver-1417857241396] (dynamic)
2014-12-06 09:14:03,778 DEBUG org.elasticsearch.river.mongodb.MongoDBRiver - Using mongodb server(s): host [localhost], port [37027]
2014-12-06 09:14:03,780 DEBUG org.elasticsearch.river.mongodb.MongoDBRiver - Using mongodb server(s): host [localhost], port [37028]
2014-12-06 09:14:03,781 DEBUG org.elasticsearch.river.mongodb.MongoDBRiver - Using mongodb server(s): host [localhost], port [37029]
2014-12-06 09:14:05,945 INFO org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - WriteResult: { "serverUsed" : "localhost:37027" , "n" : 0 , "lastGTID" : <Binary Data> , "connectionId" : 10 , "writtenTo" : [ { "_id" : 0 , "host" : "localhost:37027"} , { "host" : "127.0.0.1:50318" , "upgradeNeeded" : true}] , "wtime" : 0 , "err" : null , "ok" : 1.0}
2014-12-06 09:14:05,947 ERROR org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - testSimpleTransformationScript failed.
org.elasticsearch.indices.IndexMissingException: [testsimpletransformationscriptgroovyindex-1417857241396] missing
at org.elasticsearch.cluster.metadata.MetaData.convertFromWildcards(MetaData.java:828)
at org.elasticsearch.cluster.metadata.MetaData.concreteIndices(MetaData.java:685)
at org.elasticsearch.action.support.broadcast.TransportBroadcastOperationAction$AsyncBroadcastAction.<init>(TransportBroadcastOperationAction.java:120)
at org.elasticsearch.action.support.broadcast.TransportBroadcastOperationAction.doExecute(TransportBroadcastOperationAction.java:71)
at org.elasticsearch.action.support.broadcast.TransportBroadcastOperationAction.doExecute(TransportBroadcastOperationAction.java:47)
at org.elasticsearch.action.support.TransportAction.execute(TransportAction.java:75)
at org.elasticsearch.action.support.TransportAction.execute(TransportAction.java:55)
at org.elasticsearch.client.node.NodeIndicesAdminClient.execute(NodeIndicesAdminClient.java:69)
at org.elasticsearch.client.support.AbstractIndicesAdminClient.refresh(AbstractIndicesAdminClient.java:413)
at org.elasticsearch.river.mongodb.RiverMongoDBTestAbstract.refreshIndex(RiverMongoDBTestAbstract.java:388)
at org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest.testSimpleTransformationScript(RiverMongoAdvancedTransformationGroovyScriptTest.java:95)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:84)
at org.testng.internal.Invoker.invokeMethod(Invoker.java:714)
at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:901)
at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:1231)
at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:127)
at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:111)
at org.testng.TestRunner.privateRun(TestRunner.java:767)
at org.testng.TestRunner.run(TestRunner.java:617)
at org.testng.SuiteRunner.runTest(SuiteRunner.java:348)
at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:343)
at org.testng.SuiteRunner.privateRun(SuiteRunner.java:305)
at org.testng.SuiteRunner.run(SuiteRunner.java:254)
at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:86)
at org.testng.TestNG.runSuitesSequentially(TestNG.java:1224)
at org.testng.TestNG.runSuitesLocally(TestNG.java:1149)
at org.testng.TestNG.run(TestNG.java:1057)
at org.apache.maven.surefire.testng.TestNGExecutor.run(TestNGExecutor.java:115)
at org.apache.maven.surefire.testng.TestNGDirectoryTestSuite.executeMulti(TestNGDirectoryTestSuite.java:204)
at org.apache.maven.surefire.testng.TestNGDirectoryTestSuite.execute(TestNGDirectoryTestSuite.java:107)
at org.apache.maven.surefire.testng.TestNGProvider.invoke(TestNGProvider.java:112)
at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:200)
at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:153)
at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:103)
2014-12-06 09:14:05,969 INFO org.elasticsearch.river.mongodb.advanced.RiverMongoAdvancedTransformationGroovyScriptTest - Delete river [testsimpletransformationscriptgroovyriver-1417857241396]
2014-12-06 09:14:06,023 INFO org.elasticsearch.cluster.metadata - [X-23] [[_river]] remove_mapping [[testsimpletransformationscriptgroovyriver-1417857241396]]
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment