Uploaded image for project: 'Core Server'
  1. Core Server
  2. SERVER-5373

sporadic failure in profile1.js test

    • 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.

        1. profile1_output.html
          40 kB
          Ian Whalen

            Assignee:
            tad Tad Marshall
            Reporter:
            aaron Aaron Staple
            Votes:
            0 Vote for this issue
            Watchers:
            0 Start watching this issue

              Created:
              Updated:
              Resolved: