1636316 - Intermittent dom/indexedDB/test/test_upgrade_add_index.html | application terminated with exit code 1 | application crashed [@ mozilla::dom::AutoJSAPI::Init()]

Reporter

Description

•

4 years ago

treeherder

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

[task 2020-05-07T22:38:39.332Z] 22:38:39 [task 2020-05-07T22:38:39.780Z] 22:38:39 [task 2020-05-07T22:38:39.838Z] 22:38:39 [task 2020-05-07T22:38:39.840Z] 22:38:39 [task 2020-05-07T22:38:39.841Z] 22:38:39 [task 2020-05-07T22:38:39.841Z] 22:38:39 [task 2020-05-07T22:38:39.841Z] 22:38:39 [task 2020-05-07T22:38:39.841Z] 22:38:39 [task 2020-05-07T22:38:39.841Z] 22:38:39 [task 2020-05-07T22:38:39.841Z] 22:38:39 [task 2020-05-07T22:38:39.841Z] 22:38:39 [task 2020-05-07T22:38:39.842Z] 22:38:39 [task 2020-05-07T22:38:39.842Z] 22:38:39 [task 2020-05-07T22:38:39.842Z] 22:38:39 [task 2020-05-07T22:38:39.842Z] 22:38:39 [task 2020-05-07T22:38:39.842Z] 22:38:39 [task 2020-05-07T22:38:39.842Z] 22:38:39 [task 2020-05-07T22:38:39.842Z] 22:38:39 [task 2020-05-07T22:38:39.842Z] 22:38:39 [task 2020-05-07T22:38:39.842Z] 22:38:39 [task 2020-05-07T22:38:39.843Z] 22:38:39 [task 2020-05-07T22:38:39.843Z] 22:38:39 [task 2020-05-07T22:38:39.843Z] 22:38:39 [task 2020-05-07T22:38:39.843Z] 22:38:39 [task 2020-05-07T22:38:39.843Z] 22:38:39 [task 2020-05-07T22:38:39.843Z] 22:38:39 [task 2020-05-07T22:38:39.843Z] 22:38:39 [task 2020-05-07T22:38:39.843Z] 22:38:39 [task 2020-05-07T22:38:39.843Z] 22:38:39 [task 2020-05-07T22:38:39.844Z] 22:38:39 [task 2020-05-07T22:38:39.844Z] 22:38:39 [task 2020-05-07T22:38:39.844Z] 22:38:39 [task 2020-05-07T22:38:39.844Z] 22:38:39 [task 2020-05-07T22:38:39.844Z] 22:38:39 [task 2020-05-07T22:38:42.755Z] 22:38:42 [task 2020-05-07T22:38:48.038Z] 22:38:48 [task 2020-05-07T22:38:48.038Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.108Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.109Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.110Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.111Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.112Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.113Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 [task 2020-05-07T22:38:48.114Z] 22:38:48 INFO - TEST-START | dom/indexedDB/test/test_upgrade_add_index.html
INFO - GECKO(10092) | Exiting due to channel error.
INFO - TEST-INFO | Main app process: exit 1
INFO - Buffered messages logged at 22:38:39
INFO - Running 'test_upgrade_add_index.js'
INFO - Pushing preferences
INFO - Pushing permissions
INFO - Clearing old databases
INFO - TEST-PASS | dom/indexedDB/test/test_upgrade_add_index.html | Unsupported function type
INFO - Running test in a worker
INFO - Worker: loading ["https://example.com/tests/dom/indexedDB/test/unit/test_upgrade_add_index.js"]
INFO - Worker: starting tests
INFO - TEST-PASS | dom/indexedDB/test/test_upgrade_add_index.html | Unsupported function type: undefined
INFO - creating crypto key
INFO - opening initial database
INFO - add()ing crypto
INFO - closing initial database
INFO - opening database for upgrade to v2
INFO - in upgrade, creating index
INFO - Buffered messages finished
ERROR - TEST-UNEXPECTED-FAIL | dom/indexedDB/test/test_upgrade_add_index.html | application terminated with exit code 1
INFO - runtests.py | Application ran for: 0:00:04.007000
INFO - zombiecheck | Reading PID log: c:\users\task_1588889046\appdata\local\temp\tmprovxp8pidlog
INFO - ==> process 6272 launched child process 6856 ("Z:\task_1588889046\build\application\firefox\firefox.exe" -contentproc --channel="6272.0.1066465549\1021317043" -parentBuildID 20200507205336 -prefsHandle 2116 -prefMapHandle 2108 -prefsLen 1 -prefMapSize 238281 -appdir "Z:\task_1588889046\build\application\firefox\browser" - 6272 "\.\pipe\gecko-crash-server-pipe.6272" 2172 gpu)
INFO - ==> process 6272 launched child process 10028 ("Z:\task_1588889046\build\application\firefox\firefox.exe" -contentproc --channel="6272.6.973637480\1515385861" -childID 1 -isForBrowser -prefsHandle 5424 -prefMapHandle 5420 -prefsLen 193 -prefMapSize 238281 -parentBuildID 20200507205336 -appdir "Z:\task_1588889046\build\application\firefox\browser" - 6272 "\.\pipe\gecko-crash-server-pipe.6272" 5444 tab)
INFO - ==> process 6272 launched child process 2264 ("Z:\task_1588889046\build\application\firefox\firefox.exe" -contentproc --channel="6272.13.2015955351\34204682" -childID 2 -isForBrowser -prefsHandle 3780 -prefMapHandle 3832 -prefsLen 233 -prefMapSize 238281 -parentBuildID 20200507205336 -appdir "Z:\task_1588889046\build\application\firefox\browser" - 6272 "\.\pipe\gecko-crash-server-pipe.6272" 4408 tab)
INFO - ==> process 6272 launched child process 3364 ("Z:\task_1588889046\build\application\firefox\firefox.exe" -contentproc --channel="6272.20.845877458\1914013519" -childID 3 -isForBrowser -prefsHandle 3204 -prefMapHandle 3172 -prefsLen 8025 -prefMapSize 238281 -parentBuildID 20200507205336 -appdir "Z:\task_1588889046\build\application\firefox\browser" - 6272 "\.\pipe\gecko-crash-server-pipe.6272" 4012 tab)
INFO - ==> process 6272 launched child process 10720 ("Z:\task_1588889046\build\application\firefox\firefox.exe" -contentproc --channel="6272.27.219081937\1114608020" -childID 4 -isForBrowser -prefsHandle 3948 -prefMapHandle 4868 -prefsLen 8062 -prefMapSize 238281 -parentBuildID 20200507205336 -appdir "Z:\task_1588889046\build\application\firefox\browser" - 6272 "\.\pipe\gecko-crash-server-pipe.6272" 2972 tab)
INFO - zombiecheck | Checking for orphan process with PID: 6856
INFO - zombiecheck | Checking for orphan process with PID: 2264
INFO - zombiecheck | Checking for orphan process with PID: 10028
INFO - zombiecheck | Checking for orphan process with PID: 10720
INFO - zombiecheck | Checking for orphan process with PID: 3364
INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/M6ovv4T0Q_iHuF18Chib_w/artifacts/public/build/target.crashreporter-symbols.zip
INFO - mozcrash Copy/paste: Z:/task_1588889046/fetches\minidump_stackwalk\minidump_stackwalk.exe c:\users\task_1588889046\appdata\local\temp\tmpw3qlxu.mozrunner\minidumps\7335c043-488a-46a2-aec7-ae13f3353210.dmp c:\users\task_1588889046\appdata\local\temp\tmpyp9phq
INFO - mozcrash Saved minidump as Z:\task_1588889046\build\blobber_upload_dir\7335c043-488a-46a2-aec7-ae13f3353210.dmp
INFO - mozcrash Saved app info as Z:\task_1588889046\build\blobber_upload_dir\7335c043-488a-46a2-aec7-ae13f3353210.extra
INFO - PROCESS-CRASH | dom/indexedDB/test/test_upgrade_add_index.html | application crashed [@ mozilla::dom::AutoJSAPI::Init()]
INFO - Crash dump filename: c:\users\task_1588889046\appdata\local\temp\tmpw3qlxu.mozrunner\minidumps\7335c043-488a-46a2-aec7-ae13f3353210.dmp
INFO - Operating system: Windows NT
INFO - 10.0.17134
INFO - CPU: amd64
INFO - family 6 model 85 stepping 7
INFO - 8 CPUs
INFO -
INFO - GPU: UNKNOWN
INFO -
INFO - Crash reason: EXCEPTION_ACCESS_VIOLATION_READ
INFO - Crash address: 0x8
INFO - Process uptime: 4 seconds
INFO -
INFO - Thread 80 (crashed)
INFO - 0 xul.dll!mozilla::dom::AutoJSAPI::Init() [ScriptSettings.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 395 + 0x20]
INFO - rax = 0x0000000000000000 rdx = 0x000001d5fb51bb50
INFO - rcx = 0x000001d5e81f2700 rbx = 0x000001d5fbf5c550
INFO - rsi = 0x000000aadfd3d8f8 rdi = 0x000001d5fbf5c518
INFO - rbp = 0x000001d5fbf5c4e0 rsp = 0x000000aadfd3d7e0
INFO - r8 = 0x000001d5e68002c0 r9 = 0x0000000000000028
INFO - r10 = 0x0000000000000001 r11 = 0x000000aadfd3e4a0
INFO - r12 = 0x000000aadfd3e418 r13 = 0x000001d5fb51bab0
INFO - r14 = 0x000000aadfd3d8f8 r15 = 0x0000000000000001
INFO - rip = 0x00007ffbea8c4929
INFO - Found by: given as instruction pointer in context
INFO - 1 xul.dll!mozilla::dom::indexedDB::anonymous namespace'::CreateIndexOp::UpdateIndexDataValuesFunction::OnFunctionCall(mozIStorageValueArray*, nsIVariant**) [ActorsParent.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 24267 + 0x101] [task 2020-05-07T22:38:48.109Z] 22:38:48 INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0 [task 2020-05-07T22:38:48.109Z] 22:38:48 INFO - rsp = 0x000000aadfd3d820 r12 = 0x000000aadfd3e418 [task 2020-05-07T22:38:48.109Z] 22:38:48 INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8 [task 2020-05-07T22:38:48.109Z] 22:38:48 INFO - r15 = 0x0000000000000001 rip = 0x00007ffbed128d5b [task 2020-05-07T22:38:48.109Z] 22:38:48 INFO - Found by: call frame info [task 2020-05-07T22:38:48.109Z] 22:38:48 INFO - 2 xul.dll!mozilla::storage::anonymous namespace'::basicFunctionHelper(sqlite3_context*, int, sqlite3_value**) [mozStorageConnection.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 215 + 0x11]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3e590 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbeb94a282
INFO - Found by: call frame info
INFO - 3 nss3.dll!sqlite3VdbeExec(Vdbe*) [sqlite3.c:b76e3e988fdd20bb726468ab1684127b53ddf833 : 92505 + 0x12]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3e660 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffc21a7591a
INFO - Found by: call frame info
INFO - 4 nss3.dll!sqlite3_step(sqlite3_stmt*) [sqlite3.c:b76e3e988fdd20bb726468ab1684127b53ddf833 : 83275 + 0xc6]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3e830 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffc21a4b362
INFO - Found by: call frame info
INFO - 5 xul.dll!mozilla::storage::Connection::stepStatement(sqlite3*, sqlite3_stmt*) [mozStorageConnection.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 1118 + 0x8]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3ea50 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbeb9479b2
INFO - Found by: call frame info
INFO - 6 xul.dll!mozilla::storage::Statement::ExecuteStep(bool*) [mozStorageStatement.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 519 + 0x9]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3eb00 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbeb958000
INFO - Found by: call frame info
INFO - 7 xul.dll!mozilla::storage::Statement::Execute() [mozStorageStatement.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 488 + 0xd]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3ebc0 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbeb957e99
INFO - Found by: call frame info
INFO - 8 xul.dll!mozilla::dom::indexedDB::anonymous namespace'::CreateIndexOp::DoDatabaseWork(mozilla::dom::indexedDB::anonymous namespace'::DatabaseConnection*) [ActorsParent.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 24198 + 0x158]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3ec10 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbed1288aa
INFO - Found by: call frame info
INFO - 9 xul.dll!mozilla::dom::indexedDB::anonymous namespace'::TransactionDatabaseOperationBase::RunOnConnectionThread() [ActorsParent.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 22981 + 0xc] [task 2020-05-07T22:38:48.111Z] 22:38:48 INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0 [task 2020-05-07T22:38:48.111Z] 22:38:48 INFO - rsp = 0x000000aadfd3ed50 r12 = 0x000000aadfd3e418 [task 2020-05-07T22:38:48.111Z] 22:38:48 INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8 [task 2020-05-07T22:38:48.111Z] 22:38:48 INFO - r15 = 0x0000000000000001 rip = 0x00007ffbed10307a [task 2020-05-07T22:38:48.111Z] 22:38:48 INFO - Found by: call frame info [task 2020-05-07T22:38:48.111Z] 22:38:48 INFO - 10 xul.dll!mozilla::dom::indexedDB::anonymous namespace'::TransactionDatabaseOperationBase::Run() [ActorsParent.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 23147 + 0x9]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3ee60 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbed102a9a
INFO - Found by: call frame info
INFO - 11 xul.dll!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 1200 + 0xa]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3ee90 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbea7b52d7
INFO - Found by: call frame info
INFO - 12 xul.dll!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 481 + 0x26]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3f490 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbea7b4255
INFO - Found by: call frame info
INFO - 13 xul.dll!mozilla::dom::indexedDB::`anonymous namespace'::ConnectionPool::ThreadRunnable::Run() [ActorsParent.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 13113 + 0x45]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3f4e0 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbed100470
INFO - Found by: call frame info
INFO - 14 xul.dll!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 1200 + 0xa]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3f540 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbea7b52d7
INFO - Found by: call frame info
INFO - 15 xul.dll!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 481 + 0x26]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3fb40 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbea7b4255
INFO - Found by: call frame info
INFO - 16 xul.dll!mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) [MessagePump.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 332 + 0xa]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3fb90 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbea7b400f
INFO - Found by: call frame info
INFO - 17 xul.dll!MessageLoop::RunHandler() [message_loop.cc:b76e3e988fdd20bb726468ab1684127b53ddf833 : 308 + 0xf]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3fc00 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbeb5db758
INFO - Found by: call frame info
INFO - 18 xul.dll!MessageLoop::Run() [message_loop.cc:b76e3e988fdd20bb726468ab1684127b53ddf833 : 290 + 0x5]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3fc50 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbea78b141
INFO - Found by: call frame info
INFO - 19 xul.dll!static nsThread::ThreadFunc(void*) [nsThread.cpp:b76e3e988fdd20bb726468ab1684127b53ddf833 : 444 + 0x8]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3fca0 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffbeb144b1d
INFO - Found by: call frame info
INFO - 20 nss3.dll!_PR_NativeRunThread(void*) [pruthr.c:b76e3e988fdd20bb726468ab1684127b53ddf833 : 399 + 0x7]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3fe60 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffc21b3f9f4
INFO - Found by: call frame info
INFO - 21 nss3.dll!pr_root(void*) [w95thred.c:b76e3e988fdd20bb726468ab1684127b53ddf833 : 139 + 0x6]
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3fee0 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffc21b304aa
INFO - Found by: call frame info
INFO - 22 ucrtbase.dll!guard_dispatch_icall_nop + 0x11b5e
INFO - rbx = 0x000001d5fbf5c550 rbp = 0x000001d5fbf5c4e0
INFO - rsp = 0x000000aadfd3ff10 r12 = 0x000000aadfd3e418
INFO - r13 = 0x000001d5fb51bab0 r14 = 0x000000aadfd3d8f8
INFO - r15 = 0x0000000000000001 rip = 0x00007ffc2b9ec4be
INFO - Found by: call frame info
INFO - 23 kernel32.dll!guard_dispatch_icall_nop + 0x86d4
INFO - rbp = 0x000001d5fbf5c4e0 rsp = 0x000000aadfd3ff40
INFO - rip = 0x00007ffc2e0b3034
INFO - Found by: stack scanning
INFO - 24 ntdll.dll!guard_dispatch_icall_nop + 0x66b01
INFO - rbp = 0x000001d5fbf5c4e0 rsp = 0x000000aadfd3ff70
INFO - rip = 0x00007ffc2ed51461
INFO - Found by: stack scanning
INFO - 25 KERNELBASE.dll!guard_dispatch_icall_nop + 0xe15b0
INFO - rbp = 0x000001d5fbf5c4e0 rsp = 0x000000aadfd3ffa0
INFO - rip = 0x00007ffc2bbbbf10
INFO - Found by: stack scanning

Comment hidden (Intermittent Failures Robot)

Bogdan Tara[:bogdan_tara | bogdant]

Comment 2

•

4 years ago

This seems to have started with the changes from bug 1630982.

https://treeherder.mozilla.org/#/jobs?repo=autoland&group_state=expanded&searchStr=windows%2C10%2Cx64%2Cquantumrender%2Cshippable%2Copt%2Cmochitests%2Ctest-windows10-64-shippable-qr%2Fopt-mochitest-plain-e10s&tochange=a8cd211f539318bf8056dbe4cef945b3433e9662&fromchange=f9356df325cd5df64ef952636b788db27fbfd19f&test_paths=dom%2Findexeddb%2Ftest%2F&selectedTaskRun=YVKwSjjJTM-26RCwaF699w-0

florian, could you, please, take a look?

Flags: needinfo?(florian)

Regressed by: 1630982

BMO Automation

Updated

•

4 years ago

Has Regression Range: --- → yes

Keywords: regression

Bogdan Tara[:bogdan_tara | bogdant]

Updated

•

4 years ago

Whiteboard: [retriggered]

Comment hidden (Intermittent Failures Robot)

Florian Quèze [:florian]

Comment 5

•

4 years ago

How many of these failures happened 'organically' and how many of them were the result of the retriggers to identify the regression range?

BugBot [:suhaib / :marco/ :calixte]

Comment 6

•

4 years ago

Set release status flags based on info from the regressing bug 1630982

status-firefox76: --- → unaffected

status-firefox77: --- → unaffected

status-firefox78: --- → affected

Florian Quèze [:florian]

Comment 7

•

4 years ago

After bug 1630982, on opt builds, we terminate the browser with an exit(0) as soon as we are done collecting test results. The rationale is that we don't care about doing a clean shutdown as the profile directory won't be used once we are done with the tests.

Bug there's an edge case: https://searchfox.org/mozilla-central/rev/dc4560dcaafd79375b9411fdbbaaebb0a59a93ac/testing/mochitest/runtests.py#2758,2836 creates the user profile once per test manifest, but if a test manifest restarts the browser to run tests with different schemes, the same profile will be re-used.

This is what's happening here, the test_upgrade_add_index.html test runs with the https scheme (https://searchfox.org/mozilla-central/rev/fa52bedc4b401c12251513fa1c9df1753a29abb2/dom/indexedDB/test/mochitest.ini#271-272), so it runs after the browser has been closed abruptly, and then restarted.

Crashing at https://searchfox.org/mozilla-central/rev/fa52bedc4b401c12251513fa1c9df1753a29abb2/dom/indexedDB/test/unit/test_upgrade_add_index.js#101 when calling createIndex when running the test on a profile that previously didn't shutdown cleanly seems like a real bug, not a test issue.

I can reproduce locally every once in a while when running ./mach mochitest dom/indexedDB/test/mochitest.ini

Andrew, any thoughts on this crash?

Flags: needinfo?(florian) → needinfo?(bugmail)

Simon Giesecke [:sg] [he/him]

Assignee

Comment 8

•

4 years ago

What's interesting to note here is that the if-case which is apparently executed here is not normally covered: https://coverage.moz.tools/#revision=latest&path=dom%2FindexedDB%2FActorsParent.cpp&view=file&line=10323 Maybe the assumption that this does not need to be dispatched to the main thread is wrong?

Comment hidden (Intermittent Failures Robot)

Andreea Pavel [:apavel]

Comment 10

•

4 years ago

Hi, can the test be disabled or the regressed by bug backed out to reduce the failures here, until there is a fix?

Flags: needinfo?(florian)

Andrew Sutherland [:asuth] (he/him)

Comment 11

•

4 years ago

(In reply to Simon Giesecke [:sg] [he/him] from comment #8)

Maybe the assumption that this does not need to be dispatched to the main thread is wrong?

Yes. I understand the rationale of the optimization to be: "we don't need a real JS sandbox because there's no structured clone data to deserialize". However, the use of AutoJSAPI ends up calling CycleCollectedJSContext::Get()->Context() to get the thread's JSContext*. There's no such runtime on an IDB I/O thread, however, so this gets and dereferences a null pointer immediately. For the purposes of sanity, I think we want a (non-system-principaled) JS context, which means we either need to instantiate a runtime on that thread when this happens or we need to use the existing code-path.

We do want to stop involving the main thread in this situation, but it seems like the immediate fix is to eliminate the optimization and always dispatch.

Flags: needinfo?(bugmail)

Comment hidden (Intermittent Failures Robot)

Simon Giesecke [:sg] [he/him]

Assignee

Updated

•

4 years ago

Assignee: nobody → sgiesecke

Simon Giesecke [:sg] [he/him]

Assignee

Comment 13

•

4 years ago

(In reply to Andreea Pavel [:apavel] from comment #10)

Hi, can the test be disabled or the regressed by bug backed out to reduce the failures here, until there is a fix?

I'll provide a preliminary fix as sketched by Andrew today.

Simon Giesecke [:sg] [he/him]

Assignee

Updated

•

4 years ago

Blocks: 1637530

Simon Giesecke [:sg] [he/him]

Assignee

Comment 14

•

4 years ago

Attached file Bug 1636316 - Disable defective optimization in DeserializeIndexValueHelper::DispatchAndWait. r=#dom-workers-and-storage,asuth (deleted) — Details

Florian Quèze [:florian]

Updated

•

4 years ago

Flags: needinfo?(florian)

Pulsebot

Comment 15

•

4 years ago

Pushed by bugmail@asutherland.org: https://hg.mozilla.org/integration/autoland/rev/d3d7530ced83 Disable defective optimization in DeserializeIndexValueHelper::DispatchAndWait. r=asuth,dom-workers-and-storage-reviewers

Cristian Brindusan [:cbrindusan]

Comment 16

•

4 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/d3d7530ced83

Status: NEW → RESOLVED

Closed: 4 years ago

status-firefox78: affected → fixed

Resolution: --- → FIXED

Target Milestone: --- → mozilla78

Narcis Beleuzu [:NarcisB]

Updated

•

4 years ago

Regressions: 1637715

Comment hidden (Intermittent Failures Robot)

Simon Giesecke [:sg] [he/him]

Assignee

Updated

•

4 years ago

Crash Signature: mozilla::dom::AutoJSAPI::Init

Simon Giesecke [:sg] [he/him]

Assignee

Updated

•

4 years ago

Crash Signature: mozilla::dom::AutoJSAPI::Init → [@ mozilla::dom::AutoJSAPI::Init]

Comment hidden (Intermittent Failures Robot)