Filed by: ncsoregi [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=163472838&repo=autoland https://queue.taskcluster.net/v1/task/YHi8eagnR-maSGGD9yTwvA/runs/0/artifacts/public/logs/live_backing.log [task 2018-02-21T16:29:09.398Z] 16:29:09 INFO - Assertion failure: sdb_p->sqlXactDB, at /builds/worker/workspace/build/src/security/nss/lib/softoken/sdb.c:1396 [task 2018-02-21T16:29:09.399Z] 16:29:09 INFO - Redirecting call to abort() to mozalloc_abort [task 2018-02-21T16:29:09.399Z] 16:29:09 INFO - Hit MOZ_CRASH() at /builds/worker/workspace/build/src/memory/mozalloc/mozalloc_abort.cpp:33 [task 2018-02-21T16:29:09.400Z] 16:29:09 INFO - ExceptionHandler::GenerateDump cloned child 3672 [task 2018-02-21T16:29:09.400Z] 16:29:09 INFO - ExceptionHandler::SendContinueSignalToChild sent continue signal to child [task 2018-02-21T16:29:09.400Z] 16:29:09 INFO - ExceptionHandler::WaitForContinueSignal waiting for continue signal... [task 2018-02-21T16:29:09.845Z] 16:29:09 INFO - [Child 3640, Chrome_ChildThread] WARNING: pipe error (3): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 353 [task 2018-02-21T16:29:09.846Z] 16:29:09 INFO - Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2534 [task 2018-02-21T16:29:09.846Z] 16:29:09 INFO - #01: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x1078776] [task 2018-02-21T16:29:09.847Z] 16:29:09 INFO - #02: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x105c1e0] [task 2018-02-21T16:29:09.848Z] 16:29:09 INFO - #03: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x105c8cd] [task 2018-02-21T16:29:09.848Z] 16:29:09 INFO - #04: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x10490a2] [task 2018-02-21T16:29:09.849Z] 16:29:09 INFO - #05: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x104ad1d] [task 2018-02-21T16:29:09.850Z] 16:29:09 INFO - #06: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x104ad49] [task 2018-02-21T16:29:09.850Z] 16:29:09 INFO - #07: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x10542b8] [task 2018-02-21T16:29:09.851Z] 16:29:09 INFO - #08: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x1046b33] [task 2018-02-21T16:29:09.852Z] 16:29:09 INFO - #09: ???[/lib/x86_64-linux-gnu/libpthread.so.0 +0x76ba] [task 2018-02-21T16:29:09.852Z] 16:29:09 INFO - Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2534 [task 2018-02-21T16:29:09.853Z] 16:29:09 INFO - #10: clone[/lib/x86_64-linux-gnu/libc.so.6 +0x10741d] [task 2018-02-21T16:29:09.853Z] 16:29:09 INFO - #11: ??? (???:???) [task 2018-02-21T16:29:09.854Z] 16:29:09 INFO - #01: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x1078776] [task 2018-02-21T16:29:09.854Z] 16:29:09 INFO - #02: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x105c1e0] [task 2018-02-21T16:29:09.855Z] 16:29:09 INFO - #03: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x105c8cd] [task 2018-02-21T16:29:09.855Z] 16:29:09 INFO - #04: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x10490a2] [task 2018-02-21T16:29:09.856Z] 16:29:09 INFO - #05: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x104ad1d] [task 2018-02-21T16:29:09.858Z] 16:29:09 INFO - #06: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x104ad49] [task 2018-02-21T16:29:09.860Z] 16:29:09 INFO - #07: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x10542b8] [task 2018-02-21T16:29:09.862Z] 16:29:09 INFO - #08: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x1046b33] [task 2018-02-21T16:29:09.865Z] 16:29:09 INFO - #09: ???[/lib/x86_64-linux-gnu/libpthread.so.0 +0x76ba] [task 2018-02-21T16:29:09.866Z] 16:29:09 INFO - #10: clone[/lib/x86_64-linux-gnu/libc.so.6 +0x10741d] [task 2018-02-21T16:29:09.868Z] 16:29:09 INFO - #11: ??? (???:???) [task 2018-02-21T16:29:10.230Z] 16:29:10 INFO - TEST-UNEXPECTED-ERROR | testing/marionette/harness/marionette_harness/tests/unit/test_profile_management.py TestSwitchProfileWithWorkspace.test_new_random_profile_name | IOError: Process has been unexpectedly closed (Exit code: 11) (Reason: No data received over socket)

Looks like there is a missing transitioning db, and as such we fail at the above line. So the following lines from the log file which haven't been attached yet look helpful but might not offer all the information (I don't see which component this might be) [task 2018-02-21T16:29:08.841Z] 16:29:08 INFO - [Parent 3557, IndexedDB #1] WARNING: '!exists', file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4956 [task 2018-02-21T16:29:08.842Z] 16:29:08 INFO - [Parent 3557, IndexedDB #1] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 12018 [task 2018-02-21T16:29:08.842Z] 16:29:08 INFO - [Parent 3557, IndexedDB #1] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 13994 [task 2018-02-21T16:29:08.843Z] 16:29:08 INFO - [Parent 3557, IndexedDB #1] WARNING: 'NS_FAILED(rv)', file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 23523 [task 2018-02-21T16:29:08.869Z] 16:29:08 INFO - console.error: PushService: [task 2018-02-21T16:29:08.870Z] 16:29:08 INFO - clearOriginData: Error clearing origin data: [task 2018-02-21T16:29:08.871Z] 16:29:08 INFO - UnknownError [task 2018-02-21T16:29:08.872Z] 16:29:08 INFO - console.error: PushService: [task 2018-02-21T16:29:08.873Z] 16:29:08 INFO - stateChangeProcessEnqueue: Error transitioning state [task 2018-02-21T16:29:08.874Z] 16:29:08 INFO - UnknownError [task 2018-02-21T16:29:09.398Z] 16:29:09 INFO - Assertion failure: sdb_p->sqlXactDB, at /builds/worker/workspace/build/src/security/nss/lib/softoken/sdb.c:1396 David, do you have an idea?

At a wild guess, something is going wrong with the cert8.db -> cert9.db (berkeleydb -> sqlite) upgrade. Does marionette rely on files using the old format? You might try upgrading to the new format statically, so the upgrade isn't done every time the tests run. Those stack traces don't really tell me much (probably because of bug 1311173).

(In reply to David Keeler [:keeler] (use needinfo) from comment #2) > At a wild guess, something is going wrong with the cert8.db -> cert9.db > (berkeleydb -> sqlite) upgrade. Does marionette rely on files using the old > format? You might try upgrading to the new format statically, so the upgrade > isn't done every time the tests run. No, the profile as used by Marionette tests are freshly populated with prefs, but we don't write data to that database file. So not sure why this upgrade path is used. > Those stack traces don't really tell me much (probably because of bug > 1311173). No, it's related to bug 1348961. The bug you are referencing is for web-platform-tests.

https://treeherder.mozilla.org/logviewer.html#?job_id=202262773&repo=mozilla-beta&lineNumber=39655 [task 2018-09-28T17:51:05.884Z] 17:51:05 INFO - TEST-START | testing/marionette/harness/marionette_harness/tests/unit/test_profile_management.py TestQuitRestartWithWorkspace.test_restart_clean_creates_new_profile [task 2018-09-28T17:51:06.060Z] 17:51:06 INFO - [Parent 2865, Cache2 I/O] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/netwerk/cache2/CacheFileIOManager.cpp, line 4083 [task 2018-09-28T17:51:06.062Z] 17:51:06 INFO - [Parent 2865, Cache2 I/O] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/netwerk/cache2/CacheFileIOManager.cpp, line 3724 [task 2018-09-28T17:51:06.063Z] 17:51:06 INFO - [Parent 2865, Cache2 I/O] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012: file /builds/worker/workspace/build/src/netwerk/cache2/CacheFileIOManager.cpp, line 2091 [task 2018-09-28T17:51:06.064Z] 17:51:06 INFO - [Parent 2865, Cache2 I/O] WARNING: 'NS_FAILED(aResult)', file /builds/worker/workspace/build/src/netwerk/cache2/CacheFileChunk.cpp, line 662 [task 2018-09-28T17:51:06.164Z] 17:51:06 INFO - Assertion failure: sdb_p->sqlXactDB, at /builds/worker/workspace/build/src/security/nss/lib/softoken/sdb.c:1406 [task 2018-09-28T17:51:06.166Z] 17:51:06 INFO - Redirecting call to abort() to mozalloc_abort [task 2018-09-28T17:51:06.167Z] 17:51:06 INFO - Hit MOZ_CRASH() at /builds/worker/workspace/build/src/memory/mozalloc/mozalloc_abort.cpp:35 [task 2018-09-28T17:51:06.175Z] 17:51:06 INFO - ExceptionHandler::GenerateDump cloned child 3054 [task 2018-09-28T17:51:06.176Z] 17:51:06 INFO - ExceptionHandler::SendContinueSignalToChild sent continue signal to child [task 2018-09-28T17:51:06.177Z] 17:51:06 INFO - ExceptionHandler::WaitForContinueSignal waiting for continue signal... [task 2018-09-28T17:51:06.331Z] 17:51:06 INFO - Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2662 [task 2018-09-28T17:51:06.333Z] 17:51:06 INFO - #01: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x10b492b] [task 2018-09-28T17:51:06.340Z] 17:51:06 INFO - #02: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x1091dd2] [task 2018-09-28T17:51:06.347Z] 17:51:06 INFO - #03: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x108f741] [task 2018-09-28T17:51:06.348Z] 17:51:06 INFO - #04: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x108018d] [task 2018-09-28T17:51:06.351Z] 17:51:06 INFO - #05: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x107e7e5] [task 2018-09-28T17:51:06.355Z] 17:51:06 INFO - #06: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x10874ac] [task 2018-09-28T17:51:06.355Z] 17:51:06 INFO - #07: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x10833ca] [task 2018-09-28T17:51:06.357Z] 17:51:06 INFO - #08: ???[/lib/x86_64-linux-gnu/libpthread.so.0 +0x76ba] [task 2018-09-28T17:51:06.357Z] 17:51:06 INFO - #09: clone[/lib/x86_64-linux-gnu/libc.so.6 +0x10741d] [task 2018-09-28T17:51:06.358Z] 17:51:06 INFO - #10: ??? (???:???) [task 2018-09-28T17:51:06.359Z] 17:51:06 INFO - [Child 2985, Chrome_ChildThread] WARNING: pipe error (3): Connection reset by peer: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_posix.cc, line 356 [task 2018-09-28T17:51:06.360Z] 17:51:06 INFO - Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2662 [task 2018-09-28T17:51:06.360Z] 17:51:06 INFO - #01: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x10b492b] [task 2018-09-28T17:51:06.361Z] 17:51:06 INFO - #02: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x1091dd2] [task 2018-09-28T17:51:06.364Z] 17:51:06 INFO - #03: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x108f741] [task 2018-09-28T17:51:06.365Z] 17:51:06 INFO - #04: ???[/builds/worker/workspace/build/application/firefox/libxul.so +0x108018d] [task 2018-09-28T17:51:06.366Z] 17:51:06 INFO - Hit MOZ_CRASH(Aborting on channel error.) at /builds/worker/workspace/build/src/ipc/glue/MessageChannel.cpp:2662