[SERVER-9419] initial_sync4.js failing on Windows DEBUG Created: 22/Apr/13  Updated: 11/Jul/16  Resolved: 01/May/13

Status: Closed
Project: Core Server
Component/s: Replication
Affects Version/s: None
Fix Version/s: 2.5.0

Type: Bug Priority: Major - P3
Reporter: Ian Whalen (Inactive) Assignee: Eric Milkie
Resolution: Done Votes: 0
Labels: buildbot
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified

Attachments: Text File initial_sync4.txt    
Issue Links:
Related
is related to SERVER-4781 replica set initial sync failure when... Closed
Operating System: ALL
Participants:

 Description   

http://buildlogs.mongodb.org/Windows%2064-bit%20DEBUG/builds/665/test/recent%20failures/initial_sync4.js

m31002| Mon Apr 22 10:19:58.565 [rsSync] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.080 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
 m31002| Mon Apr 22 10:19:59.096 [repl prefetch worker] warning: we think data is in ram but system says no
{
	"set" : "jstests_initsync4",
	"date" : ISODate("2013-04-22T14:19:59Z"),
	"myState" : 3,
	"syncingTo" : "AMAZONA-J7UBCUV:31000",
	"members" : [
		{
			"_id" : 0,
			"name" : "AMAZONA-J7UBCUV:31000",
			"health" : 1,
			"state" : 1,
			"stateStr" : "PRIMARY",
			"uptime" : 1382,
			"optime" : Timestamp(1366640190, 338),
			"optimeDate" : ISODate("2013-04-22T14:16:30Z"),
			"lastHeartbeat" : ISODate("2013-04-22T14:19:58Z"),
			"lastHeartbeatRecv" : ISODate("2013-04-22T14:19:59Z"),
			"pingMs" : 0
		},
		{
			"_id" : 2,
			"name" : "AMAZONA-J7UBCUV:31002",
			"health" : 1,
			"state" : 3,
			"stateStr" : "RECOVERING",
			"uptime" : 1385,
			"optime" : Timestamp(1366640177, 149),
			"optimeDate" : ISODate("2013-04-22T14:16:17Z"),
			"self" : true
		}
	],
	"ok" : 1
}
2013-04-22 10:20:00 EDT	
{
	"set" : "jstests_initsync4",
	"date" : ISODate("2013-04-22T14:20:00Z"),
	"myState" : 3,
	"syncingTo" : "AMAZONA-J7UBCUV:31000",
	"members" : [
		{
			"_id" : 0,
			"name" : "AMAZONA-J7UBCUV:31000",
			"health" : 1,
			"state" : 1,
			"stateStr" : "PRIMARY",
			"uptime" : 1383,
			"optime" : Timestamp(1366640190, 338),
			"optimeDate" : ISODate("2013-04-22T14:16:30Z"),
			"lastHeartbeat" : ISODate("2013-04-22T14:20:00Z"),
			"lastHeartbeatRecv" : ISODate("1970-01-01T00:00:00Z"),
			"pingMs" : 1
		},
		{
			"_id" : 2,
			"name" : "AMAZONA-J7UBCUV:31002",
			"health" : 1,
			"state" : 3,
			"stateStr" : "RECOVERING",
			"uptime" : 1386,
			"optime" : Timestamp(1366640177, 149),
			"optimeDate" : ISODate("2013-04-22T14:16:17Z"),
			"self" : true
		}
	],
	"ok" : 1
}
{
	"set" : "jstests_initsync4",
	"date" : ISODate("2013-04-22T14:20:01Z"),
	"myState" : 3,
	"syncingTo" : "AMAZONA-J7UBCUV:31000",
	"members" : [
		{
			"_id" : 0,
			"name" : "AMAZONA-J7UBCUV:31000",
			"health" : 1,
			"state" : 1,
			"stateStr" : "PRIMARY",
			"uptime" : 1384,
			"optime" : Timestamp(1366640190, 338),
			"optimeDate" : ISODate("2013-04-22T14:16:30Z"),
			"lastHeartbeat" : ISODate("2013-04-22T14:20:00Z"),
			"lastHeartbeatRecv" : ISODate("2013-04-22T14:20:01Z"),
			"pingMs" : 1
		},
		{
			"_id" : 2,
			"name" : "AMAZONA-J7UBCUV:31002",
			"health" : 1,
			"state" : 3,
			"stateStr" : "RECOVERING",
			"uptime" : 1387,
			"optime" : Timestamp(1366640177, 149),
			"optimeDate" : ISODate("2013-04-22T14:16:17Z"),
			"self" : true
		}
	],
	"ok" : 1
}
assert failed : tried 200 times, giving up on undefined
Error: Printing Stack Trace
    at printStackTrace (src/mongo/shell/utils.js:37:15)
    at doassert (src/mongo/shell/assert.js:6:5)
    at assert (src/mongo/shell/assert.js:14:5)
    at wait (jstests/replsets/rslib.js:14:9)
    at D:\slave\Windows_64bit_DEBUG\mongo\jstests\replsets\initial_sync4.js:65:1
Mon Apr 22 10:20:01.748 JavaScript execution failed: assert failed : tried 200 times, giving up on undefined at src/mongo/shell/assert.js:L7
failed to load: D:\slave\Windows_64bit_DEBUG\mongo\jstests\replsets\initial_sync4.js



 Comments   
Comment by auto [ 30/Apr/13 ]

Author:

{u'date': u'2013-04-30T15:45:53Z', u'name': u'Eric Milkie', u'email': u'milkie@10gen.com'}

Message: SERVER-9419 reduce amount of data being inserted (windows debug builder can't handle)
Branch: master
https://github.com/mongodb/mongo/commit/41198192d48e3324b6ff804bf042446debc863da

Comment by Tad Marshall [ 28/Apr/13 ]

Git bisect indicates that this test began failing with commit 85d5487e2e40b7351566b914f8df4d454beb5835 .

This commit re-enabled the test and expanded it.

The test had been added originally for SERVER-4781 in commit https://github.com/mongodb/mongo/commit/5750a5b7a1b7bb5ae495534f9228d5773c28db4b on Feb 07 2012 01:18:05 EST and then immediately disabled in commit https://github.com/mongodb/mongo/commit/65e2ffc8d28e46148593512fbb976e970fbf2a52 .

Generated at Thu Feb 08 03:20:21 UTC 2024 using Jira 9.7.1#970001-sha1:2222b88b221c4928ef0de3161136cc90c8356a66.