[SERVER-7633] V8 segfault in jstests/evald.js on Linux 64-bit v8 Created: 12/Nov/12  Updated: 11/Jul/16  Resolved: 22/Nov/12

Status: Closed
Project: Core Server
Component/s: JavaScript, Testing Infrastructure
Affects Version/s: None
Fix Version/s: 2.3.1

Type: Bug Priority: Major - P3
Reporter: Tad Marshall Assignee: Ben Becker
Resolution: Done Votes: 0
Labels: buildbot
Remaining Estimate: Not Specified
Time Spent: Not Specified
Original Estimate: Not Specified
Environment:

Linux 64-bit v8 build slave (is this the same as Linux 64-bit now?)


Issue Links:
Related
is related to SERVER-2407 Switch to v8 Closed
Operating System: ALL
Participants:

 Description   

http://buildlogs.mongodb.org/Linux%2064-bit%20v8/builds/4156/test/js/evald.js

Test runs successfully for a bit, then segfaults on a killop:

Mon Nov 12 19:36:05.972 [initandlisten] connection accepted from 127.0.0.1:45534 #14 (2 connections now open)
Mon Nov 12 19:36:08.314 [conn7] going to kill op: op: 17239.0
sh9517| MongoDB shell version: 2.3.1-pre-
Mon Nov 12 19:36:08.314 Invalid access at address: 0 from thread: conn14
 
Mon Nov 12 19:36:08.314 Got signal: 11 (Segmentation fault).
 
Mon Nov 12 19:36:08.321 Backtrace:
0xb7a436 0x6ff4ed 0x6ff857 0x7fcdcce0c500 0xc73143 0xb23fb1 0xb2b1ee 0xb3878c 0xb27faa 0xaa5d2a45139 
 /data/buildslaves/Linux_64bit_v8/mongo/mongod(_ZN5mongo15printStackTraceERSo+0x26) [0xb7a436]
 /data/buildslaves/Linux_64bit_v8/mongo/mongod(_ZN5mongo10abruptQuitEi+0x39d) [0x6ff4ed]
 /data/buildslaves/Linux_64bit_v8/mongo/mongod(_ZN5mongo24abruptQuitWithAddrSignalEiP7siginfoPv+0x247) [0x6ff857]
 /lib64/libpthread.so.0(+0xf500) [0x7fcdcce0c500]
 /data/buildslaves/Linux_64bit_v8/mongo/mongod(_ZN2v86Object16SetInternalFieldEiNS_6HandleINS_5ValueEEE+0x23) [0xc73143]
 /data/buildslaves/Linux_64bit_v8/mongo/mongod(_ZN5mongo7V8Scope14wrapBSONObjectEN2v85LocalINS1_6ObjectEEEPNS_10BSONHolderE+0x31) [0xb23fb1]
 /data/buildslaves/Linux_64bit_v8/mongo/mongod(_ZN5mongo7V8Scope11mongoToLZV8ERKNS_7BSONObjEbb+0x2fe) [0xb2b1ee]
 /data/buildslaves/Linux_64bit_v8/mongo/mongod(_ZN5mongo18internalCursorNextEPNS_7V8ScopeERKN2v89ArgumentsE+0x14c) [0xb3878c]
 /data/buildslaves/Linux_64bit_v8/mongo/mongod(_ZN5mongo7V8Scope10v8CallbackERKN2v89ArgumentsE+0x7a) [0xb27faa]
 [0xaa5d2a45139]



 Comments   
Comment by auto [ 22/Nov/12 ]

Author:

{u'date': u'2012-11-22T01:04:26Z', u'email': u'ben.becker@10gen.com', u'name': u'Ben Becker'}

Message: SERVER-7633: massert on null TemplateObject
Branch: master
https://github.com/mongodb/mongo/commit/fa64548884d610ebee36c52e75425117dcb4f512

Comment by Tad Marshall [ 20/Nov/12 ]

Linux 64-bit DEBUG hits V8 assertion (so no segfault):
http://buildlogs.mongodb.org/Linux%2064-bit%20DEBUG/builds/1687/test/small%20oplog/evald.js

Tue Nov 20 06:00:05.776 [replslave] build index done.  scanned 0 total records. 0.001 secs
MongoDB shell version: 2.3.1-pre-
connecting to: 127.0.0.1:27999/test
true
Tue Nov 20 06:00:06.915 [replslave] build index test.jstests_evald { _id: 1 }
Tue Nov 20 06:00:06.916 [replslave] build index done.  scanned 0 total records. 0 secs
Tue Nov 20 06:00:06.956 shell: started program /home/yellow/buildslave/Linux_64bit_DEBUG/mongo/mongo --eval db = db.getSiblingDB('test');db.jstests_evald.count( { $where: function() { while( 1 ) { sleep(1); } } } ) 127.0.0.1:27999/admin
sh10833| MongoDB shell version: 2.3.1-pre-
sh10833| connecting to: 127.0.0.1:27999/admin
sh10833| Tue Nov 20 06:00:09.604 exec error: src/mongo/shell/query.js:180 count failed: {
sh10833| 	"errmsg" : "10072 unknown error in invocation of $where function",
sh10833| 	"ok" : 0
sh10833| }
sh10833| throw "count failed: " + tojson( res );
sh10833|                        ^
sh10833| 
Tue Nov 20 06:00:09.610 shell: started program /home/yellow/buildslave/Linux_64bit_DEBUG/mongo/mongo --eval db = db.getSiblingDB('test');db.jstests_evald.count( { $where: function() { while( 1 ) { sleep(1); } } } ) 127.0.0.1:27999/admin
sh10838| MongoDB shell version: 2.3.1-pre-
sh10838| connecting to: 127.0.0.1:27999/admin
sh10838| Tue Nov 20 06:00:10.322 exec error: src/mongo/shell/query.js:180 count failed: { "errmsg" : "13475 exec error: interrupted", "ok" : 0 }
sh10838| throw "count failed: " + tojson( res );
sh10838|                        ^
sh10838| 
Tue Nov 20 06:00:10.327 shell: started program /home/yellow/buildslave/Linux_64bit_DEBUG/mongo/mongo --eval db = db.getSiblingDB('test');db.eval( 'while( true ) { sleep(1);}' ) 127.0.0.1:27999/admin
sh10843| MongoDB shell version: 2.3.1-pre-
sh10843| connecting to: 127.0.0.1:27999/admin
sh10843| Tue Nov 20 06:00:11.031 exec error: src/mongo/shell/db.js:496 {
sh10843| 	"errmsg" : "exception: exec error: interrupted",
sh10843| 	"code" : 13475,
sh10843| 	"ok" : 0
sh10843| }
sh10843| throw tojson( res );
sh10843|       ^
sh10843| 
Tue Nov 20 06:00:11.035 shell: started program /home/yellow/buildslave/Linux_64bit_DEBUG/mongo/mongo --eval db = db.getSiblingDB('test');db.eval( 'while( true ) { sleep(1);}' ) 127.0.0.1:27999/admin
sh10848| MongoDB shell version: 2.3.1-pre-
sh10848| connecting to: 127.0.0.1:27999/admin
sh10848| Tue Nov 20 06:00:13.674 exec error: src/mongo/shell/db.js:496 {
sh10848| 	"errno" : 1,
sh10848| 	"errmsg" : "invoke failed: error in invoke: _funcs1:0 Interruption in V8 native callback\n_funcs1 = function(){ while( true ) { sleep(1);}}\n                                      ^\n",
sh10848| 	"ok" : 0
sh10848| }
sh10848| throw tojson( res );
sh10848|       ^
sh10848| 
Tue Nov 20 06:00:13.680 shell: started program /home/yellow/buildslave/Linux_64bit_DEBUG/mongo/mongo --eval db = db.getSiblingDB('test');db.eval( 'while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count( {i:10} ); }' ) 127.0.0.1:27999/admin
sh10853| MongoDB shell version: 2.3.1-pre-
sh10853| connecting to: 127.0.0.1:27999/admin
sh10853| Tue Nov 20 06:00:16.311 exec error: src/mongo/shell/db.js:496 {
sh10853| 	"errno" : 1,
sh10853| 	"errmsg" : "invoke failed: error in invoke: interrupted",
sh10853| 	"ok" : 0
sh10853| }
sh10853| throw tojson( res );
sh10853|       ^
sh10853| 
Tue Nov 20 06:00:16.316 shell: started program /home/yellow/buildslave/Linux_64bit_DEBUG/mongo/mongo --eval db = db.getSiblingDB('test');db.eval( 'while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count( {i:10} ); }' ) 127.0.0.1:27999/admin
sh10858| MongoDB shell version: 2.3.1-pre-
sh10858| connecting to: 127.0.0.1:27999/admin
sh10858| Tue Nov 20 06:00:17.020 exec error: src/mongo/shell/db.js:496 {
sh10858| 	"errmsg" : "exception: exec error: interrupted",
sh10858| 	"code" : 13475,
sh10858| 	"ok" : 0
sh10858| }
sh10858| throw tojson( res );
sh10858|       ^
sh10858| 
Tue Nov 20 06:00:17.024 shell: started program /home/yellow/buildslave/Linux_64bit_DEBUG/mongo/mongo --eval db = db.getSiblingDB('test');db.eval( 'while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count(); }' ) 127.0.0.1:27999/admin
Tue Nov 20 06:00:19.681 [replslave] repl: AssertionException dbclient error communicating with server: localhost:27999
sh10863| MongoDB shell version: 2.3.1-pre-
 
 
#
# Fatal error in src/third_party/v8/src/handles-inl.h, line 64
# CHECK(location_ != __null) failed
#
 
sh10863| connecting to: 127.0.0.1:27999/admin
sh10863| Tue Nov 20 06:00:19.681 DBClientCursor::init call() failed
sh10863| Tue Nov 20 06:00:19.689 exec error: src/mongo/shell/query.js:78 error doing query: failed
sh10863| this._cursor = this._mongo.find( this._ns , this._query , this._fields , this.
sh10863|                            ^
sh10863| 
Tue Nov 20 06:00:19.693 shell: started program /home/yellow/buildslave/Linux_64bit_DEBUG/mongo/mongo --eval db = db.getSiblingDB('test');db.eval( 'while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count(); }' ) 127.0.0.1:27999/admin
Tue Nov 20 06:00:19.696 DBClientCursor::init call() failed
Tue Nov 20 06:00:19.703 exec error: src/mongo/shell/query.js:78 error doing query: failed
this._cursor = this._mongo.find( this._ns , this._query , this._fields , this.
                           ^
 
failed to load: /home/yellow/buildslave/Linux_64bit_DEBUG/mongo/jstests/evald.js
Tue Nov 20 06:00:19.681 [replslave] repl: sleep 2 sec before next pass
 
Tue Nov 20 06:00:20.926 got signal 15 (Terminated), will terminate after current cmd ends
Tue Nov 20 06:00:20.926 [interruptThread] now exiting
Tue Nov 20 06:00:20.926 dbexit: 
Tue Nov 20 06:00:20.926 [interruptThread] shutdown: going to close listening sockets...
Tue Nov 20 06:00:20.926 [interruptThread] closing listening socket: 8
Tue Nov 20 06:00:20.926 [interruptThread] closing listening socket: 9
Tue Nov 20 06:00:20.926 [interruptThread] closing listening socket: 10
Tue Nov 20 06:00:20.926 [interruptThread] removing socket file: /tmp/mongodb-28000.sock
Tue Nov 20 06:00:20.926 [interruptThread] shutdown: going to flush diaglog...
Tue Nov 20 06:00:20.926 [interruptThread] shutdown: going to close sockets...
Tue Nov 20 06:00:20.926 [interruptThread] shutdown: waiting for fs preallocator...
Tue Nov 20 06:00:20.926 [interruptThread] shutdown: lock for final commit...
Tue Nov 20 06:00:20.926 [interruptThread] shutdown: final commit...
Tue Nov 20 06:00:20.935 [websvr] thread websvr stack usage was 7032 bytes,  which is the most so far
Tue Nov 20 06:00:20.939 [interruptThread] shutdown: closing all files...
Tue Nov 20 06:00:20.952 [interruptThread] closeAllFiles() finished
Tue Nov 20 06:00:20.952 [interruptThread] journalCleanup...
Tue Nov 20 06:00:20.952 [interruptThread] removeJournalFiles
Tue Nov 20 06:00:20.953 [interruptThread] shutdown: removing fs lock...
Tue Nov 20 06:00:20.953 dbexit: really exiting now
Tue Nov 20 06:00:20.953 [interruptThread] thread interruptThread stack usage was 10840 bytes,  which is the most so far

Comment by Tad Marshall [ 20/Nov/12 ]

Similar crash on OS X 10.5 64-bit DUR OFF:
http://buildlogs.mongodb.org/OS%20X%2010.5%2064-bit%20DUR%20OFF/builds/1580/test/js/evald.js

 
Mon Nov 19 22:55:14.473 [conn5] build index done.  scanned 0 total records. 0.001 secs
Mon Nov 19 22:55:14.504 [conn5] end connection 127.0.0.1:64320 (2 connections now open)
Mon Nov 19 22:55:14.514 [conn4] end connection 127.0.0.1:64315 (1 connection now open)
Mon Nov 19 22:55:14.874 [initandlisten] connection accepted from 127.0.0.1:64333 #6 (2 connections now open)
Mon Nov 19 22:55:15.527 kern.sched unavailable
MongoDB shell version: 2.3.1-pre-
connecting to: 127.0.0.1:27999/test
null
Mon Nov 19 22:55:15.563 [initandlisten] connection accepted from 127.0.0.1:64338 #7 (3 connections now open)
Mon Nov 19 22:55:15.890 [conn7] CMD: drop test.jstests_evald
Mon Nov 19 22:55:15.893 [conn7] build index test.jstests_evald { _id: 1 }
Mon Nov 19 22:55:15.894 [conn7] build index done.  scanned 0 total records. 0 secs
Mon Nov 19 22:55:15.896 shell: started program /Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/mongo --eval TestData = {
	"testPath" : "/Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/jstests/evald.js",
	"testFile" : "evald.js",
	"testName" : "evald",
	"noJournal" : false,
	"noJournalPrealloc" : false,
	"auth" : false,
	"keyFile" : null,
	"keyFileData" : null
};db = db.getSiblingDB('test');db.jstests_evald.count( { $where: function() { while( 1 ) { sleep(1); } } } ) 127.0.0.1:27999/admin
Mon Nov 19 22:55:15.941 [initandlisten] connection accepted from 127.0.0.1:64339 #8 (4 connections now open)
Mon Nov 19 22:55:18.105 [conn7] going to kill op: op: 30876.0
sh10595| Mon Nov 19 22:55:15.905 kern.sched unavailable
Mon Nov 19 22:55:18.107 [conn8] error in invoke: _funcs1:0 Interruption in V8 native callback
_funcs1 = function () { while( 1 ) { sleep(1); } }
                                     ^
 
Mon Nov 19 22:55:18.107 [conn8] Count with ns: test.jstests_evald and query: { $where: function () { while( 1 ) { sleep(1); } } } failed with exception: 10072 unknown error in invocation of $where function code: 10072
Mon Nov 19 22:55:18.107 [conn8] exec error: interrupted
Mon Nov 19 22:55:18.107 [conn8] javascript scope cleanup interrupted :: caused by :: 13475 exec error: interrupted
Mon Nov 19 22:55:18.107 [conn8] command test.$cmd command: { count: "jstests_evald", query: { $where: function () { while( 1 ) { sleep(1); } } }, fields: {} } ntoreturn:1 keyUpdates:0 locks(micros) r:2164085 reslen:102 2164ms
Mon Nov 19 22:55:18.113 [conn8] end connection 127.0.0.1:64339 (3 connections now open)
Mon Nov 19 22:55:18.164 [initandlisten] connection accepted from 127.0.0.1:64342 #9 (4 connections now open)
sh10595| MongoDB shell version: 2.3.1-pre-
sh10595| connecting to: 127.0.0.1:27999/admin
sh10595| Mon Nov 19 22:55:18.110 exec error: src/mongo/shell/query.js:180 count failed: {
sh10595| 	"errmsg" : "10072 unknown error in invocation of $where function",
sh10595| 	"ok" : 0
sh10595| }
sh10595| throw "count failed: " + tojson( res );
sh10595|                        ^
sh10595| 
Mon Nov 19 22:55:18.115 shell: started program /Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/mongo --eval TestData = {
	"testPath" : "/Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/jstests/evald.js",
	"testFile" : "evald.js",
	"testName" : "evald",
	"noJournal" : false,
	"noJournalPrealloc" : false,
	"auth" : false,
	"keyFile" : null,
	"keyFileData" : null
};db = db.getSiblingDB('test');db.jstests_evald.count( { $where: function() { while( 1 ) { sleep(1); } } } ) 127.0.0.1:27999/admin
Mon Nov 19 22:55:18.337 [conn7] going to kill op: op: 30879.0
Mon Nov 19 22:55:18.338 [conn9] error in invoke: _funcs1:0 Interruption in V8 native callback
_funcs1 = function () { while( 1 ) { sleep(1); } }
                                     ^
 
Mon Nov 19 22:55:18.339 [conn9] Count with ns: test.jstests_evald and query: { $where: function () { while( 1 ) { sleep(1); } } } failed with exception: 10072 unknown error in invocation of $where function code: 10072
Mon Nov 19 22:55:18.339 [conn9] exec error: interrupted
Mon Nov 19 22:55:18.339 [conn9] javascript scope cleanup interrupted :: caused by :: 13475 exec error: interrupted
Mon Nov 19 22:55:18.339 [conn9] command test.$cmd command: { count: "jstests_evald", query: { $where: function () { while( 1 ) { sleep(1); } } }, fields: {} } ntoreturn:1 keyUpdates:0 locks(micros) r:147775 reslen:102 147ms
Mon Nov 19 22:55:18.344 [conn9] end connection 127.0.0.1:64342 (3 connections now open)
sh10596| Mon Nov 19 22:55:18.122 kern.sched unavailable
sh10596| MongoDB shell version: 2.3.1-pre-
sh10596| connecting to: 127.0.0.1:27999/admin
sh10596| Mon Nov 19 22:55:18.341 exec error: src/mongo/shell/query.js:180 count failed: {
sh10596| 	"errmsg" : "10072 unknown error in invocation of $where function",
sh10596| 	"ok" : 0
sh10596| }
sh10596| throw "count failed: " + tojson( res );
sh10596|                        ^
sh10596| 
Mon Nov 19 22:55:18.346 shell: started program /Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/mongo --eval TestData = {
	"testPath" : "/Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/jstests/evald.js",
	"testFile" : "evald.js",
	"testName" : "evald",
	"noJournal" : false,
	"noJournalPrealloc" : false,
	"auth" : false,
	"keyFile" : null,
	"keyFileData" : null
};db = db.getSiblingDB('test');db.eval( 'while( true ) { sleep(1);}' ) 127.0.0.1:27999/admin
Mon Nov 19 22:55:18.388 [initandlisten] connection accepted from 127.0.0.1:64345 #10 (4 connections now open)
Mon Nov 19 22:55:18.557 [conn7] going to kill op: op: 30882.0
Mon Nov 19 22:55:18.558 [conn10] error in invoke: _funcs1:0 Interruption in V8 native callback
_funcs1 = function(){ while( true ) { sleep(1);}}
                                      ^
 
Mon Nov 19 22:55:18.558 [conn10] dbeval slow, time: 143ms test
Mon Nov 19 22:55:18.559 [conn10] command test.$cmd command: { $eval: "while( true ) { sleep(1);}" } ntoreturn:1 keyUpdates:0 locks(micros) W:169006 reslen:232 169ms
Mon Nov 19 22:55:18.563 [conn10] end connection 127.0.0.1:64345 (3 connections now open)
sh10597| Mon Nov 19 22:55:18.352 kern.sched unavailable
sh10597| MongoDB shell version: 2.3.1-pre-
sh10597| connecting to: 127.0.0.1:27999/admin
sh10597| Mon Nov 19 22:55:18.561 exec error: src/mongo/shell/db.js:496 {
sh10597| 	"errno" : 1,
sh10597| 	"errmsg" : "invoke failed: error in invoke: _funcs1:0 Interruption in V8 native callback\n_funcs1 = function(){ while( true ) { sleep(1);}}\n                                      ^\n",
sh10597| 	"ok" : 0
sh10597| }
sh10597| throw tojson( res );
sh10597|       ^
sh10597| 
Mon Nov 19 22:55:18.565 shell: started program /Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/mongo --eval TestData = {
	"testPath" : "/Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/jstests/evald.js",
	"testFile" : "evald.js",
	"testName" : "evald",
	"noJournal" : false,
	"noJournalPrealloc" : false,
	"auth" : false,
	"keyFile" : null,
	"keyFileData" : null
};db = db.getSiblingDB('test');db.eval( 'while( true ) { sleep(1);}' ) 127.0.0.1:27999/admin
Mon Nov 19 22:55:18.606 [initandlisten] connection accepted from 127.0.0.1:64346 #11 (4 connections now open)
Mon Nov 19 22:55:20.771 [conn7] going to kill op: op: 30885.0
sh10598| Mon Nov 19 22:55:18.570 kern.sched unavailable
sh10598| MongoDB shell version: 2.3.1-pre-
sh10598| connecting to: 127.0.0.1:27999/admin
sh10598| Mon Nov 19 22:55:20.775 exec error: src/mongo/shell/db.js:496 {
sh10598| 	"errno" : 1,
sh10598| 	"errmsg" : "invoke failed: error in invoke: _funcs1:0 Interruption in V8 native callback\n_funcs1 = function(){ while( true ) { sleep(1);}}\n                                      ^\n",
sh10598| 	"ok" : 0
sh10598| }
sh10598| throw tojson( res );
sh10598|       ^
sh10598| 
Mon Nov 19 22:55:20.779 shell: started program /Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/mongo --eval TestData = {
	"testPath" : "/Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/jstests/evald.js",
	"testFile" : "evald.js",
	"testName" : "evald",
	"noJournal" : false,
	"noJournalPrealloc" : false,
	"auth" : false,
	"keyFile" : null,
	"keyFileData" : null
};db = db.getSiblingDB('test');db.eval( 'while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count( {i:10} ); }' ) 127.0.0.1:27999/admin
Mon Nov 19 22:55:20.773 [conn11] error in invoke: _funcs1:0 Interruption in V8 native callback
_funcs1 = function(){ while( true ) { sleep(1);}}
                                      ^
 
Mon Nov 19 22:55:20.774 [conn11] dbeval slow, time: 2140ms test
Mon Nov 19 22:55:20.774 [conn11] while( true ) { sleep(1);}
Mon Nov 19 22:55:20.774 [conn11] command test.$cmd command: { $eval: "while( true ) { sleep(1);}" } ntoreturn:1 keyUpdates:0 locks(micros) W:2166127 reslen:232 2166ms
Mon Nov 19 22:55:20.781 [conn11] end connection 127.0.0.1:64346 (3 connections now open)
Mon Nov 19 22:55:20.845 [initandlisten] connection accepted from 127.0.0.1:64349 #12 (4 connections now open)
Mon Nov 19 22:55:22.985 [conn7] going to kill op: op: 30888.0
sh10599| Mon Nov 19 22:55:20.789 kern.sched unavailable
Mon Nov 19 22:55:22.986 [conn12] Count with ns: test.jstests_evald and query: { i: 10.0 } failed with exception: 11601 operation was interrupted code: 11601
sh10599| MongoDB shell version: 2.3.1-pre-
Mon Nov 19 22:55:22.987 [conn12] error in invoke: src/mongo/shell/collection.js:0 Interruption in V8 native callback
sh10599| connecting to: 127.0.0.1:27999/admin
dify( { update : ... , remove : bool [, query: {}, sort: {}, 'new': false] } )
sh10599| Mon Nov 19 22:55:22.989 exec error: src/mongo/shell/db.js:496 {
                                                                    ^
 
Mon Nov 19 22:55:22.987 [conn12] dbeval slow, time: 2114ms test
sh10599| 	"errno" : 1,
sh10599| 	"errmsg" : "invoke failed: error in invoke: src/mongo/shell/collection.js:0 Interruption in V8 native callback\ndify( { update : ... , remove : bool [, query: {}, sort: {}, 'new': false] } )\n                                                                    ^\n",
sh10599| 	"ok" : 0
Mon Nov 19 22:55:22.987 [conn12] while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count( {i:10} ); }
sh10599| }
Mon Nov 19 22:55:22.987 [conn12] command test.$cmd command: { $eval: "while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count( {i:10} ); }" } ntoreturn:1 keyUpdates:0 locks(micros) W:2140759 reslen:313 2140ms
sh10599| throw tojson( res );
Mon Nov 19 22:55:22.994 [conn12] end connection 127.0.0.1:64349 (3 connections now open)
sh10599|       ^
Mon Nov 19 22:55:23.063 [initandlisten] connection accepted from 127.0.0.1:64356 #13 (4 connections now open)
sh10599| 
Mon Nov 19 22:55:22.993 shell: started program /Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/mongo --eval TestData = {
	"testPath" : "/Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/jstests/evald.js",
	"testFile" : "evald.js",
	"testName" : "evald",
	"noJournal" : false,
	"noJournalPrealloc" : false,
	"auth" : false,
	"keyFile" : null,
	"keyFileData" : null
};db = db.getSiblingDB('test');db.eval( 'while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count( {i:10} ); }' ) 127.0.0.1:27999/admin
Mon Nov 19 22:55:23.436 [conn7] going to kill op: op: 42262.0
Mon Nov 19 22:55:23.436 [conn13] error in invoke: interrupted
Mon Nov 19 22:55:23.436 [conn13] dbeval slow, time: 342ms test
Mon Nov 19 22:55:23.436 [conn13] command test.$cmd command: { $eval: "while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count( {i:10} ); }" } ntoreturn:1 keyUpdates:0 locks(micros) W:371187 reslen:108 371ms
sh10600| Mon Nov 19 22:55:23.012 kern.sched unavailable
sh10600| MongoDB shell version: 2.3.1-pre-
sh10600| connecting to: 127.0.0.1:27999/admin
sh10600| Mon Nov 19 22:55:23.439 exec error: src/mongo/shell/db.js:496 {
sh10600| 	"errno" : 1,
sh10600| 	"errmsg" : "invoke failed: error in invoke: interrupted",
sh10600| 	"ok" : 0
sh10600| }
sh10600| throw tojson( res );
sh10600|       ^
sh10600| 
Mon Nov 19 22:55:23.443 shell: started program /Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/mongo --eval TestData = {
	"testPath" : "/Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/jstests/evald.js",
	"testFile" : "evald.js",
	"testName" : "evald",
	"noJournal" : false,
Mon Nov 19 22:55:23.442 [conn13] end connection 127.0.0.1:64356 (3 connections now open)
	"noJournalPrealloc" : false,
	"auth" : false,
	"keyFile" : null,
	"keyFileData" : null
};db = db.getSiblingDB('test');db.eval( 'while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count(); }' ) 127.0.0.1:27999/admin
Mon Nov 19 22:55:23.484 [initandlisten] connection accepted from 127.0.0.1:64357 #14 (4 connections now open)
Mon Nov 19 22:55:25.646 [conn7] going to kill op: op: 44162.0
sh10601| Mon Nov 19 22:55:23.449 kern.sched unavailable
Mon Nov 19 22:55:25.647 [conn14] error in invoke: src/mongo/shell/collection.js:0 Interruption in V8 native callback
dify( { update : ... , remove : bool [, query: {}, sort: {}, 'new': false] } )
                                                                    ^
 
Mon Nov 19 22:55:25.647 [conn14] dbeval slow, time: 2135ms test
Mon Nov 19 22:55:25.647 [conn14] while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count(); }
Mon Nov 19 22:55:25.647 [conn14] command test.$cmd command: { $eval: "while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count(); }" } ntoreturn:1 keyUpdates:0 locks(micros) W:2161270 reslen:313 2161ms
Mon Nov 19 22:55:25.652 [conn14] end connection 127.0.0.1:64357 (3 connections now open)
Mon Nov 19 22:55:25.704 [initandlisten] connection accepted from 127.0.0.1:64360 #15 (4 connections now open)
sh10601| MongoDB shell version: 2.3.1-pre-
sh10601| connecting to: 127.0.0.1:27999/admin
sh10601| Mon Nov 19 22:55:25.649 exec error: src/mongo/shell/db.js:496 {
sh10601| 	"errno" : 1,
sh10601| 	"errmsg" : "invoke failed: error in invoke: src/mongo/shell/collection.js:0 Interruption in V8 native callback\ndify( { update : ... , remove : bool [, query: {}, sort: {}, 'new': false] } )\n                                                                    ^\n",
sh10601| 	"ok" : 0
sh10601| }
sh10601| throw tojson( res );
sh10601|       ^
sh10601| 
Mon Nov 19 22:55:25.652 shell: started program /Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/mongo --eval TestData = {
	"testPath" : "/Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/jstests/evald.js",
	"testFile" : "evald.js",
	"testName" : "evald",
	"noJournal" : false,
	"noJournalPrealloc" : false,
	"auth" : false,
	"keyFile" : null,
	"keyFileData" : null
};db = db.getSiblingDB('test');db.eval( 'while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count(); }' ) 127.0.0.1:27999/admin
Mon Nov 19 22:55:25.858 [conn7] going to kill op: op: 59353.0
Mon Nov 19 22:55:25.859 [conn15] error in invoke: src/mongo/shell/collection.js:0 Interruption in V8 native callback
var cursor = new DBQuery( this._mongo , this._db , this ,
             ^
 
Mon Nov 19 22:55:25.859 [conn15] dbeval slow, time: 127ms test
Mon Nov 19 22:55:25.860 [conn15] command test.$cmd command: { $eval: "while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count(); }" } ntoreturn:1 keyUpdates:0 locks(micros) W:153531 reslen:237 153ms
Mon Nov 19 22:55:25.864 [conn15] end connection 127.0.0.1:64360 (3 connections now open)
sh10602| Mon Nov 19 22:55:25.659 kern.sched unavailable
sh10602| MongoDB shell version: 2.3.1-pre-
sh10602| connecting to: 127.0.0.1:27999/admin
sh10602| Mon Nov 19 22:55:25.862 exec error: src/mongo/shell/db.js:496 {
sh10602| 	"errno" : 1,
sh10602| 	"errmsg" : "invoke failed: error in invoke: src/mongo/shell/collection.js:0 Interruption in V8 native callback\nvar cursor = new DBQuery( this._mongo , this._db , this ,\n             ^\n",
sh10602| 	"ok" : 0
sh10602| }
sh10602| throw tojson( res );
sh10602|       ^
sh10602| 
Mon Nov 19 22:55:25.866 shell: started program /Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/mongo --eval TestData = {
	"testPath" : "/Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/jstests/evald.js",
	"testFile" : "evald.js",
	"testName" : "evald",
	"noJournal" : false,
	"noJournalPrealloc" : false,
	"auth" : false,
	"keyFile" : null,
	"keyFileData" : null
};db = db.getSiblingDB('test');db.eval( 'while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} try { db.jstests_evald.count( {i:10} ); } catch ( e ) { } }' ) 127.0.0.1:27999/admin
Mon Nov 19 22:55:25.908 [initandlisten] connection accepted from 127.0.0.1:64363 #16 (4 connections now open)
Mon Nov 19 22:55:28.276 [conn7] going to kill op: op: 60117.0
sh10604| Mon Nov 19 22:55:25.872 kern.sched unavailable
Mon Nov 19 22:55:28.277 Invalid access at address: 0 from thread: conn16
 
Mon Nov 19 22:55:28.278 Got signal: 11 (Segmentation fault).
 
Mon Nov 19 22:55:28.281 Backtrace:
0x1000528fb 0x10007661b 0x100076cd1 0x7fff8a3761ba 0x1b9b7e67dc39 0x1005d5cef 0x1005dde56 0x1005eb1d1 0x1005d7d5c 0x7cbc9e45379 
 0   mongod                              0x00000001000528fb _ZN5mongo15printStackTraceERSo + 43
 1   mongod                              0x000000010007661b _ZN5mongo10abruptQuitEi + 987
 2   mongod                              0x0000000100076cd1 _ZN5mongo24abruptQuitWithAddrSignalEiP9__siginfoPv + 673
 3   libSystem.B.dylib                   0x00007fff8a3761ba _sigtramp + 26
 4   ???                                 0x00001b9b7e67dc39 0x0 + 30354654616633
 5   mongod                              0x00000001005d5cef _ZN5mongo7V8Scope14wrapBSONObjectEN2v85LocalINS1_6ObjectEEEPNS_10BSONHolderE + 47
 6   mongod                              0x00000001005dde56 _ZN5mongo7V8Scope11mongoToLZV8ERKNS_7BSONObjEbb + 662
 7   mongod                              0x00000001005eb1d1 _ZN5mongo18internalCursorNextEPNS_7V8ScopeERKN2v89ArgumentsE + 257
 8   mongod                              0x00000001005d7d5c _ZN5mongo7V8Scope10v8CallbackERKN2v89ArgumentsE + 124
 9   ???                                 0x000007cbc9e45379 0x0 + 8571846939513
 
sh10604| MongoDB shell version: 2.3.1-pre-
sh10604| connecting to: 127.0.0.1:27999/admin
sh10604| Mon Nov 19 22:55:28.323 DBClientCursor::init call() failed
sh10604| Mon Nov 19 22:55:28.324 exec error: src/mongo/shell/query.js:78 error doing query: failed
sh10604| this._cursor = this._mongo.find( this._ns , this._query , this._fields , this.
sh10604|                            ^
sh10604| 
Mon Nov 19 22:55:28.329 shell: started program /Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/mongo --eval TestData = {
	"testPath" : "/Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/jstests/evald.js",
	"testFile" : "evald.js",
	"testName" : "evald",
	"noJournal" : false,
	"noJournalPrealloc" : false,
	"auth" : false,
	"keyFile" : null,
	"keyFileData" : null
};db = db.getSiblingDB('test');db.eval( 'while( 1 ) { try { while( 1 ) { sleep(1); } } catch ( e ) { } }' ) 127.0.0.1:27999/admin
Mon Nov 19 22:55:28.340 Socket recv() errno:54 Connection reset by peer 127.0.0.1:27999
Mon Nov 19 22:55:28.340 SocketException: remote: 127.0.0.1:27999 error: 9001 socket exception [1] server [127.0.0.1:27999] 
Mon Nov 19 22:55:28.340 DBClientCursor::init call() failed
Mon Nov 19 22:55:28.342 exec error: src/mongo/shell/query.js:78 error doing query: failed
this._cursor = this._mongo.find( this._ns , this._query , this._fields , this.
                           ^
 
failed to load: /Users/yellow/buildslave/OS_X_105_64bit_DUR_OFF/mongo/jstests/evald.js

Comment by Tad Marshall [ 19/Nov/12 ]

Similar crash on RHEL 64-bit:
http://buildlogs.mongodb.org/Nightly%20Linux%20RHEL%2064-bit/builds/296/test/small%20oplog/evald.js

MongoDB shell version: 2.3.1-pre-
connecting to: 127.0.0.1:27999/test
true
Mon Nov 19 07:31:02.800 [replslave] build index test.jstests_evald { _id: 1 }
Mon Nov 19 07:31:02.801 [replslave] build index done.  scanned 0 total records. 0 secs
Mon Nov 19 07:31:02.807 shell: started program /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongo --eval db = db.getSiblingDB('test');db.jstests_evald.count( { $where: function() { while( 1 ) { sleep(1); } } } ) 127.0.0.1:27999/admin
sh31987| MongoDB shell version: 2.3.1-pre-
sh31987| connecting to: 127.0.0.1:27999/admin
sh31987| Mon Nov 19 07:31:05.021 exec error: src/mongo/shell/query.js:180 count failed: {
sh31987| 	"errmsg" : "10072 unknown error in invocation of $where function",
sh31987| 	"ok" : 0
sh31987| }
sh31987| throw "count failed: " + tojson( res );
sh31987|                        ^
sh31987| 
Mon Nov 19 07:31:05.038 shell: started program /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongo --eval db = db.getSiblingDB('test');db.jstests_evald.count( { $where: function() { while( 1 ) { sleep(1); } } } ) 127.0.0.1:27999/admin
sh31992| MongoDB shell version: 2.3.1-pre-
sh31992| connecting to: 127.0.0.1:27999/admin
sh31992| Mon Nov 19 07:31:05.249 exec error: src/mongo/shell/query.js:180 count failed: {
sh31992| 	"errmsg" : "10072 unknown error in invocation of $where function",
sh31992| 	"ok" : 0
sh31992| }
sh31992| throw "count failed: " + tojson( res );
sh31992|                        ^
sh31992| 
Mon Nov 19 07:31:05.253 shell: started program /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongo --eval db = db.getSiblingDB('test');db.eval( 'while( true ) { sleep(1);}' ) 127.0.0.1:27999/admin
sh31997| MongoDB shell version: 2.3.1-pre-
sh31997| connecting to: 127.0.0.1:27999/admin
sh31997| Mon Nov 19 07:31:05.465 exec error: src/mongo/shell/db.js:496 {
sh31997| 	"errno" : 1,
sh31997| 	"errmsg" : "invoke failed: error in invoke: _funcs1:0 Interruption in V8 native callback\n_funcs1 = function(){ while( true ) { sleep(1);}}\n                                      ^\n",
sh31997| 	"ok" : 0
sh31997| }
sh31997| throw tojson( res );
sh31997|       ^
sh31997| 
Mon Nov 19 07:31:05.469 shell: started program /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongo --eval db = db.getSiblingDB('test');db.eval( 'while( true ) { sleep(1);}' ) 127.0.0.1:27999/admin
sh32002| MongoDB shell version: 2.3.1-pre-
sh32002| connecting to: 127.0.0.1:27999/admin
sh32002| Mon Nov 19 07:31:07.688 exec error: src/mongo/shell/db.js:496 {
sh32002| 	"errno" : 1,
sh32002| 	"errmsg" : "invoke failed: error in invoke: _funcs1:0 Interruption in V8 native callback\n_funcs1 = function(){ while( true ) { sleep(1);}}\n                                      ^\n",
sh32002| 	"ok" : 0
sh32002| }
sh32002| throw tojson( res );
sh32002|       ^
sh32002| 
Mon Nov 19 07:31:07.698 shell: started program /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongo --eval db = db.getSiblingDB('test');db.eval( 'while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count( {i:10} ); }' ) 127.0.0.1:27999/admin
Mon Nov 19 07:31:10.198 [replslave] repl: AssertionException dbclient error communicating with server: localhost:27999
sh32007| MongoDB shell version: 2.3.1-pre-
Mon Nov 19 07:31:10.138 Invalid access at address: 0 from thread: conn1562
 
Mon Nov 19 07:31:10.139 Got signal: 11 (Segmentation fault).
 
Mon Nov 19 07:31:10.163 Backtrace:
0xcf0341 0x6cdd49 0x6ce2d2 0x30dbc0eb70 0xde8455 0xc87270 0xc91a90 0xc9f458 0xc87ddd 0x13490b94063b 
 /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongod(_ZN5mongo15printStackTraceERSo+0x21) [0xcf0341]
 /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongod(_ZN5mongo10abruptQuitEi+0x399) [0x6cdd49]
 /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongod(_ZN5mongo24abruptQuitWithAddrSignalEiP7siginfoPv+0x262) [0x6ce2d2]
 /lib64/libpthread.so.0 [0x30dbc0eb70]
 /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongod(_ZN2v86Object16SetInternalFieldEiNS_6HandleINS_5ValueEEE+0x35) [0xde8455]
 /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongod(_ZN5mongo7V8Scope14wrapBSONObjectEN2v85LocalINS1_6ObjectEEEPNS_10BSONHolderE+0x30) [0xc87270]
 /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongod(_ZN5mongo7V8Scope11mongoToLZV8ERKNS_7BSONObjEbb+0x2e0) [0xc91a90]
 /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongod(_ZN5mongo18internalCursorNextEPNS_7V8ScopeERKN2v89ArgumentsE+0x108) [0xc9f458]
 /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongod(_ZN5mongo7V8Scope10v8CallbackERKN2v89ArgumentsE+0x7d) [0xc87ddd]
 [0x13490b94063b]
 
Mon Nov 19 07:31:10.198 [replslave] repl: sleep 2 sec before next pass
sh32007| connecting to: 127.0.0.1:27999/admin
sh32007| Mon Nov 19 07:31:10.198 DBClientCursor::init call() failed
sh32007| Mon Nov 19 07:31:10.199 exec error: src/mongo/shell/query.js:78 error doing query: failed
sh32007| this._cursor = this._mongo.find( this._ns , this._query , this._fields , this.
sh32007|                            ^
sh32007| 
Mon Nov 19 07:31:10.203 shell: started program /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/mongo --eval db = db.getSiblingDB('test');db.eval( 'while( 1 ) { for( var i = 0; i < 10000; ++i ) {;} db.jstests_evald.count( {i:10} ); }' ) 127.0.0.1:27999/admin
Mon Nov 19 07:31:10.203 DBClientCursor::init call() failed
Mon Nov 19 07:31:10.205 exec error: src/mongo/shell/query.js:78 error doing query: failed
this._cursor = this._mongo.find( this._ns , this._query , this._fields , this.
                           ^
 
failed to load: /data/buildslaves/Linux_RHEL_64bit_Nightly/mongo/jstests/evald.js
 
Mon Nov 19 07:31:11.421 got signal 15 (Terminated), will terminate after current cmd ends
Mon Nov 19 07:31:11.421 [interruptThread] now exiting
Mon Nov 19 07:31:11.421 dbexit: 
Mon Nov 19 07:31:11.421 [interruptThread] shutdown: going to close listening sockets...
Mon Nov 19 07:31:11.421 [interruptThread] closing listening socket: 11
Mon Nov 19 07:31:11.421 [interruptThread] closing listening socket: 12
Mon Nov 19 07:31:11.421 [interruptThread] closing listening socket: 13
Mon Nov 19 07:31:11.421 [interruptThread] removing socket file: /tmp/mongodb-28000.sock
Mon Nov 19 07:31:11.422 [interruptThread] shutdown: going to flush diaglog...
Mon Nov 19 07:31:11.422 [interruptThread] shutdown: going to close sockets...
Mon Nov 19 07:31:11.422 [interruptThread] shutdown: waiting for fs preallocator...
Mon Nov 19 07:31:11.422 [interruptThread] shutdown: lock for final commit...
Mon Nov 19 07:31:11.422 [interruptThread] shutdown: final commit...
Mon Nov 19 07:31:12.635 [interruptThread] shutdown: closing all files...
Mon Nov 19 07:31:12.682 [interruptThread] closeAllFiles() finished
Mon Nov 19 07:31:12.682 [interruptThread] journalCleanup...
Mon Nov 19 07:31:12.682 [interruptThread] removeJournalFiles
Mon Nov 19 07:31:12.689 [interruptThread] shutdown: removing fs lock...
Mon Nov 19 07:31:12.700 dbexit: really exiting now

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