1611597 - Intermittent /IndexedDB/idb-explicit-commit.any.worker.html | Transactions that explicitly commit and have errors should abort. - assert_equals: Expected abort event, but got complete event instead expected "abort" but got "complete"

Status: RESOLVED INCOMPLETE

(Core :: Storage: IndexedDB, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=286467884&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/NUiYh7bVTO-uaN0MpqtIyQ/runs/0/artifacts/public/logs/live_backing.log

---

[task 2020-01-25T11:42:45.524Z] 11:42:45 INFO - TEST-PASS | /IndexedDB/idb-explicit-commit.any.worker.html | Transactions with same scope should stay in program order, even if one calls commit.
[task 2020-01-25T11:42:45.524Z] 11:42:45 INFO - TEST-UNEXPECTED-FAIL | /IndexedDB/idb-explicit-commit.any.worker.html | Transactions that explicitly commit and have errors should abort. - assert_equals: Expected abort event, but got complete event instead expected "abort" but got "complete"
[task 2020-01-25T11:42:45.524Z] 11:42:45 INFO - transactionWatcher@http://web-platform.test:8000/IndexedDB/support-promises.js:20:10
[task 2020-01-25T11:42:45.524Z] 11:42:45 INFO - @http://web-platform.test:8000/IndexedDB/idb-explicit-commit.any.js:245:7
[task 2020-01-25T11:42:45.525Z] 11:42:45 INFO - asyncTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2024:25
[task 2020-01-25T11:42:45.525Z] 11:42:45 INFO - promise_test/tests.promise_tests</<@http://web-platform.test:8000/resources/testharness.js:605:36
[task 2020-01-25T11:42:45.525Z] 11:42:45 INFO - promise_test/tests.promise_tests<@http://web-platform.test:8000/resources/testharness.js:604:20
[task 2020-01-25T11:42:45.525Z] 11:42:45 INFO - promise callbackpromise_test@http://web-platform.test:8000/resources/testharness.js:603:51
[task 2020-01-25T11:42:45.525Z] 11:42:45 INFO - @http://web-platform.test:8000/IndexedDB/idb-explicit-commit.any.js:221:13
[task 2020-01-25T11:42:45.526Z] 11:42:45 INFO - @http://web-platform.test:8000/IndexedDB/idb-explicit-commit.any.worker.js:8:14
[task 2020-01-25T11:42:45.526Z] 11:42:45 INFO - TEST-UNEXPECTED-ERROR | /IndexedDB/idb-explicit-commit.any.worker.html | Error in remote: uncaught exception: Error: assert_unreached: Transaction with invalid "add" call should not be completed. Reached unreachable code
[task 2020-01-25T11:42:45.527Z] 11:42:45 INFO - TEST-INFO took 2348ms
[task 2020-01-25T11:42:45.546Z] 11:42:45 INFO - PID 2262 | 1579952565540 Marionette INFO Stopped listening on port 34978
[task 2020-01-25T11:42:45.866Z] 11:42:45 INFO - PID 2262 | [Child 3448, Main Thread] WARNING: A runnable was posted to a worker that is already shutting down!: file /builds/worker/workspace/build/src/dom/workers/WorkerPrivate.cpp, line 1418
[task 2020-01-25T11:42:45.867Z] 11:42:45 INFO - PID 2262 | [Child 3448, Main Thread] WARNING: Failed to dispatch offline status change event!: file /builds/worker/workspace/build/src/dom/workers/WorkerPrivate.cpp, line 1901
[task 2020-01-25T11:42:45.906Z] 11:42:45 INFO - PID 2262 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-25T11:42:45.967Z] 11:42:45 INFO - PID 2262 | [2020-01-25T11:42:45Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-01-25T11:42:45.967Z] 11:42:45 INFO - PID 2262 | [2020-01-25T11:42:45Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-01-25T11:42:46.156Z] 11:42:46 INFO - PID 2262 | [Child 2324, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-25T11:42:46.172Z] 11:42:46 INFO - PID 2262 | [Child 3448, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-25T11:42:46.188Z] 11:42:46 INFO - PID 2262 | nsStringStats
[task 2020-01-25T11:42:46.188Z] 11:42:46 INFO - PID 2262 | => mAllocCount: 24645
[task 2020-01-25T11:42:46.188Z] 11:42:46 INFO - PID 2262 | => mReallocCount: 0
[task 2020-01-25T11:42:46.189Z] 11:42:46 INFO - PID 2262 | => mFreeCount: 24645
[task 2020-01-25T11:42:46.189Z] 11:42:46 INFO - PID 2262 | => mShareCount: 12156
[task 2020-01-25T11:42:46.189Z] 11:42:46 INFO - PID 2262 | => mAdoptCount: 450
[task 2020-01-25T11:42:46.190Z] 11:42:46 INFO - PID 2262 | => mAdoptFreeCount: 486
[task 2020-01-25T11:42:46.190Z] 11:42:46 INFO - PID 2262 | => Process ID: 2324, Thread ID: 140292376225664
[task 2020-01-25T11:42:46.206Z] 11:42:46 INFO - PID 2262 | nsStringStats
[task 2020-01-25T11:42:46.207Z] 11:42:46 INFO - PID 2262 | => mAllocCount: 9978
[task 2020-01-25T11:42:46.207Z] 11:42:46 INFO - PID 2262 | => mReallocCount: 0
[task 2020-01-25T11:42:46.208Z] 11:42:46 INFO - PID 2262 | => mFreeCount: 9978
[task 2020-01-25T11:42:46.210Z] 11:42:46 INFO - PID 2262 | => mShareCount: 12155
[task 2020-01-25T11:42:46.211Z] 11:42:46 INFO - PID 2262 | => mAdoptCount: 450
[task 2020-01-25T11:42:46.212Z] 11:42:46 INFO - PID 2262 | => mAdoptFreeCount: 458
[task 2020-01-25T11:42:46.213Z] 11:42:46 INFO - PID 2262 | => Process ID: 3448, Thread ID: 140117540124544
[task 2020-01-25T11:42:42.571Z] 11:42:42 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:42.571Z] 11:42:42 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/1430881927d.bs%w2oFrIhntd+enxoe.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:42.692Z] 11:42:42 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageConnection.cpp, line 665
[task 2020-01-25T11:42:42.693Z] 11:42:42 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:42.693Z] 11:42:42 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/2239488408d.ba%t2aFdI+nedhetx+e.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:42.852Z] 11:42:42 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageConnection.cpp, line 665
[task 2020-01-25T11:42:42.852Z] 11:42:42 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:42.853Z] 11:42:42 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/3074794764d.bt%i2mFmIoncd+esxle.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:42.981Z] 11:42:42 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageConnection.cpp, line 665
[task 2020-01-25T11:42:42.981Z] 11:42:42 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:42.981Z] 11:42:42 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/3180157592d.bt%r2oFbIan+ddelxue.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:43.103Z] 11:42:43 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageConnection.cpp, line 665
[task 2020-01-25T11:42:43.104Z] 11:42:43 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:43.104Z] 11:42:43 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/2466880462d.br%e2lFdInnadhe+xre.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:43.170Z] 11:42:43 INFO - ............
[task 2020-01-25T11:42:43.171Z] 11:42:43 INFO - TEST-OK | /IndexedDB/idb-explicit-commit.any.html | took 2050ms
[task 2020-01-25T11:42:43.171Z] 11:42:43 INFO - TEST-START | /IndexedDB/idb-explicit-commit.any.worker.html
[task 2020-01-25T11:42:43.174Z] 11:42:43 INFO - Closing window 87
[task 2020-01-25T11:42:43.304Z] 11:42:43 INFO - PID 2262 | [Child 3448, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 845
[task 2020-01-25T11:42:43.427Z] 11:42:43 INFO - PID 2262 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp8P5O4r.mozrunner/runtests_leaks_988_tab_pid3478.log
[task 2020-01-25T11:42:43.545Z] 11:42:43 INFO - PID 2262 | [Child 3418, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-25T11:42:43.580Z] 11:42:43 INFO - PID 2262 | nsStringStats
[task 2020-01-25T11:42:43.580Z] 11:42:43 INFO - PID 2262 | => mAllocCount: 9337
[task 2020-01-25T11:42:43.581Z] 11:42:43 INFO - PID 2262 | => mReallocCount: 0
[task 2020-01-25T11:42:43.581Z] 11:42:43 INFO - PID 2262 | => mFreeCount: 9337
[task 2020-01-25T11:42:43.582Z] 11:42:43 INFO - PID 2262 | => mShareCount: 8980
[task 2020-01-25T11:42:43.582Z] 11:42:43 INFO - PID 2262 | => mAdoptCount: 446
[task 2020-01-25T11:42:43.582Z] 11:42:43 INFO - PID 2262 | => mAdoptFreeCount: 452
[task 2020-01-25T11:42:43.582Z] 11:42:43 INFO - PID 2262 | => Process ID: 3418, Thread ID: 140003695986560
[task 2020-01-25T11:42:43.586Z] 11:42:43 INFO - PID 2262 | [Child 3478, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 721
[task 2020-01-25T11:42:43.750Z] 11:42:43 INFO - PID 2262 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2020-01-25T11:42:43.752Z] 11:42:43 INFO - PID 2262 | [Child 3448, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannel, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannel.cpp, line 63
[task 2020-01-25T11:42:43.917Z] 11:42:43 INFO - PID 2262 | [Child 3448, Main Thread] WARNING: Workers don't support the 'mem.mem.' preference!: file /builds/worker/workspace/build/src/dom/workers/RuntimeService.cpp, line 536
[task 2020-01-25T11:42:43.917Z] 11:42:43 INFO - PID 2262 | [Child 3478, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1822
[task 2020-01-25T11:42:44.126Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageConnection.cpp, line 665
[task 2020-01-25T11:42:44.126Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:44.127Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/4056005263d.bs%r2oFrIrned+eexse.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:44.247Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageConnection.cpp, line 665
[task 2020-01-25T11:42:44.248Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:44.249Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/3903273879d.by%l2eFtIaniddeexme.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:44.385Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageConnection.cpp, line 665
[task 2020-01-25T11:42:44.385Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:44.385Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/1003107375d.bs%k2cFaIbnldleaxce.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:44.506Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageConnection.cpp, line 665
[task 2020-01-25T11:42:44.506Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:44.507Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/2604686529d.bd%e2lFlIinfdleuxfe.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:44.635Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageConnection.cpp, line 665
[task 2020-01-25T11:42:44.636Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:44.637Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/1816901894d.bs%w2oFrIhntd+enxoe.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:44.837Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageConnection.cpp, line 665
[task 2020-01-25T11:42:44.837Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:44.837Z] 11:42:44 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/3857416086d.ba%t2aFdI+nedhetx+e.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:45.034Z] 11:42:45 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageConnection.cpp, line 665
[task 2020-01-25T11:42:45.034Z] 11:42:45 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:45.034Z] 11:42:45 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/2048435835d.bt%i2mFmIoncd+esxle.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:45.404Z] 11:42:45 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageConnection.cpp, line 665
[task 2020-01-25T11:42:45.404Z] 11:42:45 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001: file /builds/worker/workspace/build/src/storage/mozStorageService.cpp, line 649
[task 2020-01-25T11:42:45.405Z] 11:42:45 INFO - PID 2262 | [Parent 2262, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '/tmp/tmp8P5O4r.mozrunner/storage/default/http+++web-platform.test+8000/idb/654453275d.br%e2lFdInnadhe+xre.sqlite', retrying for up to 10 seconds: file /builds/worker/workspace/build/src/dom/indexedDB/ActorsParent.cpp, line 4137
[task 2020-01-25T11:42:45.520Z] 11:42:45 INFO -
[task 2020-01-25T11:42:45.521Z] 11:42:45 INFO - TEST-PASS | /IndexedDB/idb-explicit-commit.any.worker.html | Explicitly committed data can be read back out.
[task 2020-01-25T11:42:45.521Z] 11:42:45 INFO - TEST-PASS | /IndexedDB/idb-explicit-commit.any.worker.html | commit() on a version change transaction does not cause errors.
[task 2020-01-25T11:42:45.521Z] 11:42:45 INFO - TEST-PASS | /IndexedDB/idb-explicit-commit.any.worker.html | A committed transaction becomes inactive immediately.
[task 2020-01-25T11:42:45.521Z] 11:42:45 INFO - TEST-PASS | /IndexedDB/idb-explicit-commit.any.worker.html | A committed transaction is inactive in future request callbacks.
[task 2020-01-25T11:42:45.523Z] 11:42:45 INFO - TEST-PASS | /IndexedDB/idb-explicit-commit.any.worker.html | Puts issued after commit are not fulfilled.
[task 2020-01-25T11:42:45.523Z] 11:42:45 INFO - TEST-PASS | /IndexedDB/idb-explicit-commit.any.worker.html | Calling commit on an aborted transaction throws.
[task 2020-01-25T11:42:45.523Z] 11:42:45 INFO - TEST-PASS | /IndexedDB/idb-explicit-commit.any.worker.html | Calling commit on a committed transaction throws.
[task 2020-01-25T11:42:45.523Z] 11:42:45 INFO - TEST-PASS | /IndexedDB/idb-explicit-commit.any.worker.html | Calling abort on a committed transaction throws and does not prevent persisting the data.
[task 2020-01-25T11:42:45.523Z] 11:42:45 INFO - TEST-PASS | /IndexedDB/idb-explicit-commit.any.worker.html | Calling txn.commit() when txn is inactive should throw.
[task 2020-01-25T11:42:45.524Z] 11:42:45 INFO - TEST-PASS | /IndexedDB/idb-explicit-commit.any.worker.html | Transactions with same scope should stay in program order, even if one calls commit.
[task 2020-01-25T11:42:45.524Z] 11:42:45 INFO - TEST-UNEXPECTED-FAIL | /IndexedDB/idb-explicit-commit.any.worker.html | Transactions that explicitly commit and have errors should abort. - assert_equals: Expected abort event, but got complete event instead expected "abort" but got "complete"
[task 2020-01-25T11:42:45.524Z] 11:42:45 INFO - transactionWatcher@http://web-platform.test:8000/IndexedDB/support-promises.js:20:10
[task 2020-01-25T11:42:45.524Z] 11:42:45 INFO - @http://web-platform.test:8000/IndexedDB/idb-explicit-commit.any.js:245:7
[task 2020-01-25T11:42:45.525Z] 11:42:45 INFO - asyncTest.prototype.step@http://web-platform.test:8000/resources/testharness.js:2024:25
[task 2020-01-25T11:42:45.525Z] 11:42:45 INFO - promise_test/tests.promise_tests</<@http://web-platform.test:8000/resources/testharness.js:605:36
[task 2020-01-25T11:42:45.525Z] 11:42:45 INFO - promise_test/tests.promise_tests<@http://web-platform.test:8000/resources/testharness.js:604:20
[task 2020-01-25T11:42:45.525Z] 11:42:45 INFO - promise callbackpromise_test@http://web-platform.test:8000/resources/testharness.js:603:51
[task 2020-01-25T11:42:45.525Z] 11:42:45 INFO - @http://web-platform.test:8000/IndexedDB/idb-explicit-commit.any.js:221:13
[task 2020-01-25T11:42:45.526Z] 11:42:45 INFO - @http://web-platform.test:8000/IndexedDB/idb-explicit-commit.any.worker.js:8:14
[task 2020-01-25T11:42:45.526Z] 11:42:45 INFO - TEST-UNEXPECTED-ERROR | /IndexedDB/idb-explicit-commit.any.worker.html | Error in remote: uncaught exception: Error: assert_unreached: Transaction with invalid "add" call should not be completed. Reached unreachable code
[task 2020-01-25T11:42:45.527Z] 11:42:45 INFO - TEST-INFO took 2348ms
[task 2020-01-25T11:42:45.546Z] 11:42:45 INFO - PID 2262 | 1579952565540 Marionette INFO Stopped listening on port 34978
[task 2020-01-25T11:42:45.866Z] 11:42:45 INFO - PID 2262 | [Child 3448, Main Thread] WARNING: A runnable was posted to a worker that is already shutting down!: file /builds/worker/workspace/build/src/dom/workers/WorkerPrivate.cpp, line 1418
[task 2020-01-25T11:42:45.867Z] 11:42:45 INFO - PID 2262 | [Child 3448, Main Thread] WARNING: Failed to dispatch offline status change event!: file /builds/worker/workspace/build/src/dom/workers/WorkerPrivate.cpp, line 1901
[task 2020-01-25T11:42:45.906Z] 11:42:45 INFO - PID 2262 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-25T11:42:45.967Z] 11:42:45 INFO - PID 2262 | [2020-01-25T11:42:45Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-01-25T11:42:45.967Z] 11:42:45 INFO - PID 2262 | [2020-01-25T11:42:45Z WARN xulstore::persist] tried to remove key that isn't in the store
[task 2020-01-25T11:42:46.156Z] 11:42:46 INFO - PID 2262 | [Child 2324, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-25T11:42:46.172Z] 11:42:46 INFO - PID 2262 | [Child 3448, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-25T11:42:46.188Z] 11:42:46 INFO - PID 2262 | nsStringStats
[task 2020-01-25T11:42:46.188Z] 11:42:46 INFO - PID 2262 | => mAllocCount: 24645
[task 2020-01-25T11:42:46.188Z] 11:42:46 INFO - PID 2262 | => mReallocCount: 0
[task 2020-01-25T11:42:46.189Z] 11:42:46 INFO - PID 2262 | => mFreeCount: 24645
[task 2020-01-25T11:42:46.189Z] 11:42:46 INFO - PID 2262 | => mShareCount: 12156
[task 2020-01-25T11:42:46.189Z] 11:42:46 INFO - PID 2262 | => mAdoptCount: 450
[task 2020-01-25T11:42:46.190Z] 11:42:46 INFO - PID 2262 | => mAdoptFreeCount: 486
[task 2020-01-25T11:42:46.190Z] 11:42:46 INFO - PID 2262 | => Process ID: 2324, Thread ID: 140292376225664
[task 2020-01-25T11:42:46.206Z] 11:42:46 INFO - PID 2262 | nsStringStats
[task 2020-01-25T11:42:46.207Z] 11:42:46 INFO - PID 2262 | => mAllocCount: 9978
[task 2020-01-25T11:42:46.207Z] 11:42:46 INFO - PID 2262 | => mReallocCount: 0
[task 2020-01-25T11:42:46.208Z] 11:42:46 INFO - PID 2262 | => mFreeCount: 9978
[task 2020-01-25T11:42:46.210Z] 11:42:46 INFO - PID 2262 | => mShareCount: 12155
[task 2020-01-25T11:42:46.211Z] 11:42:46 INFO - PID 2262 | => mAdoptCount: 450
[task 2020-01-25T11:42:46.212Z] 11:42:46 INFO - PID 2262 | => mAdoptFreeCount: 458
[task 2020-01-25T11:42:46.213Z] 11:42:46 INFO - PID 2262 | => Process ID: 3448, Thread ID: 140117540124544
[task 2020-01-25T11:42:46.229Z] 11:42:46 INFO - PID 2262 | [Child 2350, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/workspace/build/src/xpcom/base/nsCycleCollector.cpp, line 3362
[task 2020-01-25T11:42:46.252Z] 11:42:46 INFO - PID 2262 | nsStringStats
[task 2020-01-25T11:42:46.252Z] 11:42:46 INFO - PID 2262 | => mAllocCount: 15190
[task 2020-01-25T11:42:46.254Z] 11:42:46 INFO - PID 2262 | => mReallocCount: 0
[task 2020-01-25T11:42:46.254Z] 11:42:46 INFO - PID 2262 | => mFreeCount: 15190
[task 2020-01-25T11:42:46.254Z] 11:42:46 INFO - PID 2262 | => mShareCount: 26911
[task 2020-01-25T11:42:46.254Z] 11:42:46 INFO - PID 2262 | => mAdoptCount: 885
[task 2020-01-25T11:42:46.254Z] 11:42:46 INFO - PID 2262 | => mAdoptFreeCount: 895
[task 2020-01-25T11:42:46.254Z] 11:42:46 INFO - PID 2262 | => Process ID: 2350, Thread ID: 140688693245824
[task 2020-01-25T11:42:46.423Z] 11:42:46 INFO - PID 2262 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2020-01-25T11:42:46.470Z] 11:42:46 INFO - PID 2262 | nsStringStats
[task 2020-01-25T11:42:46.471Z] 11:42:46 INFO - PID 2262 | => mAllocCount: 5820
[task 2020-01-25T11:42:46.471Z] 11:42:46 INFO - PID 2262 | => mReallocCount: 0
[task 2020-01-25T11:42:46.471Z] 11:42:46 INFO - PID 2262 | => mFreeCount: 5820
[task 2020-01-25T11:42:46.471Z] 11:42:46 INFO - PID 2262 | => mShareCount: 3016
[task 2020-01-25T11:42:46.474Z] 11:42:46 INFO - PID 2262 | => mAdoptCount: 72
[task 2020-01-25T11:42:46.474Z] 11:42:46 INFO - PID 2262 | => mAdoptFreeCount: 72
[task 2020-01-25T11:42:46.475Z] 11:42:46 INFO - PID 2262 | => Process ID: 3478, Thread ID: 140699629406080
[task 2020-01-25T11:42:46.740Z] 11:42:46 INFO - PID 2262 | [Parent 2262, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/workspace/build/src/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1284
[task 2020-01-25T11:42:47.149Z] 11:42:47 INFO - PID 2262 | [Parent 2262, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/js/xpconnect/loader/mozJSComponentLoader.cpp, line 228
[task 2020-01-25T11:42:47.149Z] 11:42:47 INFO - PID 2262 | [Parent 2262, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file /builds/worker/workspace/build/src/js/xpconnect/loader/mozJSComponentLoader.cpp, line 255
[task 2020-01-25T11:42:47.998Z] 11:42:47 INFO - PID 2262 | nsStringStats
[task 2020-01-25T11:42:47.999Z] 11:42:47 INFO - PID 2262 | => mAllocCount: 269864
[task 2020-01-25T11:42:48.000Z] 11:42:47 INFO - PID 2262 | => mReallocCount: 0
[task 2020-01-25T11:42:48.000Z] 11:42:47 INFO - PID 2262 | => mFreeCount: 269864
[task 2020-01-25T11:42:48.000Z] 11:42:47 INFO - PID 2262 | => mShareCount: 264015
[task 2020-01-25T11:42:48.000Z] 11:42:47 INFO - PID 2262 | => mAdoptCount: 3882
[task 2020-01-25T11:42:48.000Z] 11:42:47 INFO - PID 2262 | => mAdoptFreeCount: 4952
[task 2020-01-25T11:42:48.000Z] 11:42:47 INFO - PID 2262 | => Process ID: 2262, Thread ID: 139731248269184
[task 2020-01-25T11:42:48.035Z] 11:42:48 INFO - Browser exited with return code 0
[task 2020-01-25T11:42:48.035Z] 11:42:48 INFO - PROCESS LEAKS /tmp/tmp8P5O4r.mozrunner/runtests_leaks_988.log
[task 2020-01-25T11:42:48.036Z] 11:42:48 INFO - leakcheck | Processing log file /tmp/tmp8P5O4r.mozrunner/runtests_leaks_988.log for scope /IndexedDB

Comment 1

[Intermittent Failures Robot]

2 failures in 4004 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform breakdown:

• linux1804-64: 1
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1611597&startday=2020-01-20&endday=2020-01-26&tree=all

Comment 2

[Intermittent Failures Robot]

5 failures in 2190 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1
• mozilla-central: 3
• try: 1

Platform breakdown:

• macosx1014-64-shippable: 1
• linux1804-64-shippable-qr: 1
• windows10-64-ccov: 1
• linux1804-64-qr: 1
• linux1804-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1611597&startday=2020-01-27&endday=2020-02-02&tree=all

Comment 3

[Intermittent Failures Robot]

6 failures in 3364 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 2
• mozilla-central: 2
• autoland: 2

Platform breakdown:

• android-em-7-0-x86_64: 1
• macosx1014-64: 2
• linux1804-64: 1
• windows7-32-shippable: 1
• linux1804-32-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1611597&startday=2020-02-03&endday=2020-02-09&tree=all

Comment 4

[Intermittent Failures Robot]

3 failures in 4690 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1
• mozilla-central: 1
• try: 1

Platform breakdown:

• windows7-32: 1
• linux1804-64-qr: 1
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1611597&startday=2020-02-10&endday=2020-02-16&tree=all

Comment 5

[Intermittent Failures Robot]

6 failures in 4045 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 3
• mozilla-central: 2
• try: 1

Platform breakdown:

• windows7-32: 1
• windows7-32-shippable: 1
• macosx1014-64: 3
• linux1804-64-asan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1611597&startday=2020-02-17&endday=2020-02-23&tree=all

Comment 6

[Intermittent Failures Robot]

2 failures in 5233 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2

Platform breakdown:

• windows7-32: 1
• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1611597&startday=2020-02-24&endday=2020-03-01&tree=all

Comment 7

[Intermittent Failures Robot]

1 failures in 5336 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• linux1804-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1611597&startday=2020-03-09&endday=2020-03-15&tree=all

Comment 8

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.