-
Type: Bug
-
Resolution: Done
-
Priority: Major - P3
-
Affects Version/s: None
-
Component/s: Testing Infrastructure
-
Fully Compatible
-
ALL
Occurs occasionally on my machine.
warning wanted to sleep for: 5 but took: 53 assert: [2] != [1] are not equal : X3 : { "ts" : ISODate("2012-03-23T00:23:13.975Z"), "op" : "command", "ns" : "profile1.$cmd", "command" : { "$eval" : "sleep(120)" }, "ntoreturn" : 1, "keyUpdates" : 0, "responseLength" : 45, "millis" : 121, "client" : "127.0.0.1", "user" : "jstests_profile1_user" } , Error("Printing Stack Trace")@:0 ()@src/mongo/shell/utils.js:37 ("[2] != [1] are not equal : X3 : \n{\n\t\"ts\" : ISODate(\"2012-03-23T00:23:13.975Z\"),\n\t\"op\" : \"command\",\n\t\"ns\" : \"profile1.$cmd\",\n\t\"command\" : {\n\t\t\"$eval\" : \"sleep(120)\"\n\t},\n\t\"ntoreturn\" : 1,\n\t\"keyUpdates\" : 0,\n\t\"responseLength\" : 45,\n\t\"millis\" : 121,\n\t\"client\" : \"127.0.0.1\",\n\t\"user\" : \"jstests_profile1_user\"\n} ,\n")@src/mongo/shell/utils.js:58 (2,1,"X3 : \n{\n\t\"ts\" : ISODate(\"2012-03-23T00:23:13.975Z\"),\n\t\"op\" : \"command\",\n\t\"ns\" : \"profile1.$cmd\",\n\t\"command\" : {\n\t\t\"$eval\" : \"sleep(120)\"\n\t},\n\t\"ntoreturn\" : 1,\n\t\"keyUpdates\" : 0,\n\t\"responseLength\" : 45,\n\t\"millis\" : 121,\n\t\"client\" : \"127.0.0.1\",\n\t\"user\" : \"jstests_profile1_user\"\n} ,\n")@src/mongo/shell/utils.js:84 @jstests/profile1.js:126 Thu Mar 22 17:23:13 uncaught exception: [2] != [1] are not equal : X3 : { "ts" : ISODate("2012-03-23T00:23:13.975Z"), "op" : "command", "ns" : "profile1.$cmd", "command" : { "$eval" : "sleep(120)" }, "ntoreturn" : 1, "keyUpdates" : 0, "responseLength" : 45, "millis" : 121, "client" : "127.0.0.1", "user" : "jstests_profile1_user" } , failed to load: jstests/profile1.js
Looks initially like the sleep(5) ran in under 20ms on mongod, but the test observed a sleep of 53ms.
- related to
-
SERVER-8728 jstests/profile1.js is a race and fails randomly
- Closed