1570086 - OSX Mojave (10.14) - TEST-UNEXPECTED-TIMEOUT | browser/tools/mozscreenshots/preferences/browser_preferences.js

Status: REOPENED

(Firefox :: Settings UI, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: egao [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=259043591&repo=try
Full log: https://queue.taskcluster.net/v1/task/cheb44W3SJGKFa4RBvhYuA/runs/0/artifacts/public/logs/live_backing.log

---

Platform: macosx1014
Suite: browser-screenshots

Test consumes 370 seconds timeout and then crashes to an unrecoverable state.

Context:
[task 2019-07-30T17:43:53.545Z] 17:43:53 INFO - TEST-OK | browser/tools/mozscreenshots/preferences/browser_preferences.js | took 743308ms
[task 2019-07-30T17:43:53.588Z] 17:43:53 INFO - checking window state
[task 2019-07-30T17:43:53.589Z] 17:43:53 INFO - GECKO(1732) | must wait for focus
[task 2019-07-30T17:43:53.589Z] 17:43:53 INFO - GECKO(1732) | JavaScript error: resource://testing-common/PromiseTestUtils.jsm, line 112: uncaught exception: Object
[task 2019-07-30T17:43:53.590Z] 17:43:53 INFO - Console message: [JavaScript Error: "uncaught exception: Object" {file: "resource://testing-common/PromiseTestUtils.jsm" line: 112}]
[task 2019-07-30T17:50:03.608Z] 17:50:03 INFO - Buffered messages finished
[task 2019-07-30T17:50:03.608Z] 17:50:03 ERROR - TEST-UNEXPECTED-TIMEOUT | Last test finished | application timed out after 370 seconds with no output
[task 2019-07-30T17:50:03.608Z] 17:50:03 ERROR - Force-terminating active process(es).
[task 2019-07-30T17:50:03.608Z] 17:50:03 INFO - Determining child pids from psutil...
[task 2019-07-30T17:50:03.608Z] 17:50:03 INFO - [1733, 1734, 1735, 1736, 1737, 1766]
[task 2019-07-30T17:50:03.609Z] 17:50:03 INFO - ==> process 1732 launched child process 1733
[task 2019-07-30T17:50:03.609Z] 17:50:03 INFO - ==> process 1732 launched child process 1734
[task 2019-07-30T17:50:03.609Z] 17:50:03 INFO - ==> process 1732 launched child process 1735
[task 2019-07-30T17:50:03.609Z] 17:50:03 INFO - ==> process 1732 launched child process 1736
[task 2019-07-30T17:50:03.609Z] 17:50:03 INFO - ==> process 1732 launched child process 1737
[task 2019-07-30T17:50:03.609Z] 17:50:03 INFO - ==> process 1732 launched child process 1766
[task 2019-07-30T17:50:03.609Z] 17:50:03 INFO - Found child pids: set([1733, 1766, 1735, 1736, 1737, 1734])
[task 2019-07-30T17:50:03.609Z] 17:50:03 INFO - Killing process: 1733
[task 2019-07-30T17:50:03.609Z] 17:50:03 INFO - TEST-INFO | started process screencapture
[task 2019-07-30T17:50:03.716Z] 17:50:03 INFO - TEST-INFO | screencapture: exit 0
[task 2019-07-30T17:50:03.716Z] 17:50:03 INFO - Killing process: 1766
[task 2019-07-30T17:50:03.716Z] 17:50:03 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-07-30T17:50:03.716Z] 17:50:03 INFO - Killing process: 1735
[task 2019-07-30T17:50:03.716Z] 17:50:03 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-07-30T17:50:03.716Z] 17:50:03 INFO - Killing process: 1736
[task 2019-07-30T17:50:03.716Z] 17:50:03 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-07-30T17:50:03.716Z] 17:50:03 INFO - Killing process: 1737
[task 2019-07-30T17:50:03.716Z] 17:50:03 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-07-30T17:50:03.716Z] 17:50:03 INFO - Killing process: 1734
[task 2019-07-30T17:50:03.716Z] 17:50:03 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-07-30T17:50:04.588Z] 17:50:04 INFO - psutil found pid 1733 dead
[task 2019-07-30T17:50:04.588Z] 17:50:04 INFO - psutil found pid 1737 dead
[task 2019-07-30T17:50:04.589Z] 17:50:04 INFO - psutil found pid 1766 dead
[task 2019-07-30T17:50:04.590Z] 17:50:04 INFO - psutil found pid 1735 dead
[task 2019-07-30T17:50:04.590Z] 17:50:04 INFO - psutil found pid 1736 dead
[task 2019-07-30T17:50:04.590Z] 17:50:04 INFO - psutil found pid 1734 dead
[task 2019-07-30T17:50:04.590Z] 17:50:04 INFO - Killing process: 1732
[task 2019-07-30T17:50:04.590Z] 17:50:04 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-07-30T17:50:04.713Z] 17:50:04 INFO - psutil found pid 1732 dead
[task 2019-07-30T17:50:04.803Z] 17:50:04 WARNING - runtests.py | Failed to get app exit code - running/crashed?
[task 2019-07-30T17:50:04.803Z] 17:50:04 INFO - TEST-INFO | Main app process: exit 0
[task 2019-07-30T17:50:04.803Z] 17:50:04 INFO - runtests.py | Application ran for: 0:18:38.047946
[task 2019-07-30T17:50:04.803Z] 17:50:04 INFO - zombiecheck | Reading PID log: /var/folders/f1/6_hbwy093470lw97xsdhtwbc000017/T/tmpq0Z3Kipidlog
[task 2019-07-30T17:50:04.803Z] 17:50:04 INFO - ==> process 1732 launched child process 1733
[task 2019-07-30T17:50:04.804Z] 17:50:04 INFO - ==> process 1732 launched child process 1734
[task 2019-07-30T17:50:04.804Z] 17:50:04 INFO - ==> process 1732 launched child process 1735
[task 2019-07-30T17:50:04.804Z] 17:50:04 INFO - ==> process 1732 launched child process 1736
[task 2019-07-30T17:50:04.804Z] 17:50:04 INFO - ==> process 1732 launched child process 1737
[task 2019-07-30T17:50:04.804Z] 17:50:04 INFO - ==> process 1732 launched child process 1766
[task 2019-07-30T17:50:04.804Z] 17:50:04 INFO - zombiecheck | Checking for orphan process with PID: 1733
[task 2019-07-30T17:50:04.804Z] 17:50:04 INFO - zombiecheck | Checking for orphan process with PID: 1766
[task 2019-07-30T17:50:04.804Z] 17:50:04 INFO - zombiecheck | Checking for orphan process with PID: 1735
[task 2019-07-30T17:50:04.804Z] 17:50:04 INFO - zombiecheck | Checking for orphan process with PID: 1736
[task 2019-07-30T17:50:04.804Z] 17:50:04 INFO - zombiecheck | Checking for orphan process with PID: 1737
[task 2019-07-30T17:50:04.805Z] 17:50:04 INFO - zombiecheck | Checking for orphan process with PID: 1734
[task 2019-07-30T17:50:04.805Z] 17:50:04 INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/A6K4U0zpTjm3lFtwd8Q4rw/artifacts/public/build/target.crashreporter-symbols.zip
[task 2019-07-30T17:50:08.920Z] 17:50:08 INFO - mozcrash Copy/paste: /Users/cltbld/tasks/task_1564506618/build/macosx64-minidump_stackwalk /var/folders/f1/6_hbwy093470lw97xsdhtwbc000017/T/tmpHZ306z.mozrunner/minidumps/E3E3D39C-CB55-464D-B949-6BAD9613A3D7.dmp /var/folders/f1/6_hbwy093470lw97xsdhtwbc000017/T/tmp4KXjW2
[task 2019-07-30T17:50:12.853Z] 17:50:12 INFO - mozcrash Saved minidump as /Users/cltbld/tasks/task_1564506618/build/blobber_upload_dir/E3E3D39C-CB55-464D-B949-6BAD9613A3D7.dmp
[task 2019-07-30T17:50:12.853Z] 17:50:12 INFO - mozcrash Saved app info as /Users/cltbld/tasks/task_1564506618/build/blobber_upload_dir/E3E3D39C-CB55-464D-B949-6BAD9613A3D7.extra
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ 0x7fff7b21722a]
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - Crash dump filename: /var/folders/f1/6_hbwy093470lw97xsdhtwbc000017/T/tmpHZ306z.mozrunner/minidumps/E3E3D39C-CB55-464D-B949-6BAD9613A3D7.dmp
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - Operating system: Mac OS X
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - 10.14.5 18F132
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - CPU: amd64
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - family 6 model 69 stepping 1
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - 4 CPUs
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO -
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - GPU: UNKNOWN
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO -
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - Crash reason: EXC_SOFTWARE / SIGABRT
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - Crash address: 0x7fff7b21722a
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - Process uptime: 1117 seconds
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO -
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - Thread 0 (crashed)
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - 0 0x7fff7b21722a
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - rax = 0x000000000100001f rdx = 0x0000000000000000
[task 2019-07-30T17:50:12.918Z] 17:50:12 INFO - rcx = 0x00007ffee8e56db8 rbx = 0x0000000000000102
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - rsi = 0x0000000000000102 rdi = 0x00007ffee8e56e50
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - rbp = 0x00007ffee8e56e10 rsp = 0x00007ffee8e56db8
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - r8 = 0x0000000000005d07 r9 = 0x0000000000001388
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - r10 = 0x000000000000041c r11 = 0x0000000000000202
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - r12 = 0x0000000000000102 r13 = 0x000000000000041c
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - r14 = 0x00007ffee8e56e50 r15 = 0x0000000000000000
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - rip = 0x00007fff7b21722a
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - Found by: given as instruction pointer in context
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - 1 XUL!google_breakpad::CrashGenerationClient::RequestDumpForException(int, int, int, unsigned int) [crash_generation_client.cc:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 77 + 0x5c]
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - rbp = 0x00007ffee8e576c0 rsp = 0x00007ffee8e56e20
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - rip = 0x000000010bce5080
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - 2 XUL!google_breakpad::ExceptionHandler::WriteMinidumpWithException(int, int, int, __darwin_ucontext*, unsigned int, bool, bool) [exception_handler.cc:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 382 + 0xe]
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - rbp = 0x00007ffee8e577c0 rsp = 0x00007ffee8e576d0
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - rip = 0x000000010bce6df2
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.919Z] 17:50:12 INFO - 3 XUL!google_breakpad::ExceptionHandler::SignalHandler(int, __siginfo*, void*) [exception_handler.cc:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 628 + 0x21]
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - rbp = 0x00007ffee8e577f0 rsp = 0x00007ffee8e577d0
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - rip = 0x000000010bce7457
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - 4 0x7fff7b2cdb5d
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - rbp = 0x00007ffee8e57800 rsp = 0x00007ffee8e57800
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - rip = 0x00007fff7b2cdb5d
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - 5 0x11452e000
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - rbp = 0x00007ffee8e57dd0 rsp = 0x00007ffee8e57810
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - rip = 0x000000011452e000
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - 6 libmozglue.dylib!<name omitted> [ConditionVariable_posix.cpp:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 109 + 0x5]
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - rbp = 0x00007ffee8e57de0 rsp = 0x00007ffee8e57de0
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - rip = 0x000000011220dd79
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - 7 XUL!mozilla::ThreadEventQueue<mozilla::PrioritizedEventQueue>::GetEvent(bool, mozilla::EventQueuePriority*) [ThreadEventQueue.cpp:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 153 + 0x51]
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - rbp = 0x00007ffee8e57e40 rsp = 0x00007ffee8e57df0
[task 2019-07-30T17:50:12.920Z] 17:50:12 INFO - rip = 0x00000001072efad6
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - 8 XUL!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 1133 + 0x15]
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - rbp = 0x00007ffee8e58330 rsp = 0x00007ffee8e57e50
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - rip = 0x00000001072fa4a4
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - 9 XUL!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 486 + 0xd]
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - rbp = 0x00007ffee8e58360 rsp = 0x00007ffee8e58340
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - rip = 0x00000001072fd709
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - 10 XUL!mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [MessagePump.cpp:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 110 + 0xa]
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - rbp = 0x00007ffee8e583b0 rsp = 0x00007ffee8e58370
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - rip = 0x0000000107a3c24d
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - 11 XUL!nsBaseAppShell::Run() [nsBaseAppShell.cpp:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 137 + 0x45]
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - rbp = 0x00007ffee8e583f0 rsp = 0x00007ffee8e583c0
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - rip = 0x000000010a67ffbc
[task 2019-07-30T17:50:12.921Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - 12 XUL!nsAppShell::Run() [nsAppShell.mm:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 705 + 0x8]
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - rbp = 0x00007ffee8e58430 rsp = 0x00007ffee8e58400
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - rip = 0x000000010a70490b
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - 13 XUL!XRE_RunAppShell() [nsEmbedFunctions.cpp:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 919 + 0x9]
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - rbp = 0x00007ffee8e58490 rsp = 0x00007ffee8e58440
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - rip = 0x000000010bd5c2eb
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - 14 XUL!XRE_InitChildProcess(int, char**, XREChildData const*) [message_loop.cc:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 315 + 0xc]
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - rbp = 0x00007ffee8e58790 rsp = 0x00007ffee8e584a0
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - rip = 0x000000010bd5bee2
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - 15 plugin-container!main [MozillaRuntimeMain.cpp:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 23 + 0x2c]
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - rbp = 0x00007ffee8e587d0 rsp = 0x00007ffee8e587a0
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - rip = 0x0000000106da6f07
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - 16 0x7fff7b0e23d5
[task 2019-07-30T17:50:12.922Z] 17:50:12 INFO - rbp = 0x00007ffee8e587e0 rsp = 0x00007ffee8e587e0
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - rip = 0x00007fff7b0e23d5
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO -
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - Thread 1
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - 0 0x7fff7b21722a
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - rax = 0x000000000100001f rdx = 0x0000000000000000
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - rcx = 0x000070000d13dbe8 rbx = 0x0000000000000006
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - rsi = 0x0000000000000006 rdi = 0x000070000d13dc90
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - rbp = 0x000070000d13dc40 rsp = 0x000070000d13dbe8
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - r8 = 0x0000000000005403 r9 = 0x0000000000000000
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - r10 = 0x000000000000024c r11 = 0x0000000000000206
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - r12 = 0x0000000000000006 r13 = 0x000000000000024c
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - r14 = 0x000070000d13dc90 r15 = 0x0000000000000000
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - rip = 0x00007fff7b21722a
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - Found by: given as instruction pointer in context
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - 1 XUL!google_breakpad::ExceptionHandler::WaitForMessage(void*) [exception_handler.cc:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 494 + 0x1d]
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - rbp = 0x000070000d13df10 rsp = 0x000070000d13dc50
[task 2019-07-30T17:50:12.923Z] 17:50:12 INFO - rip = 0x000000010bce70ca
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - 2 0x7fff7b2d62eb
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - rbp = 0x000070000d13df30 rsp = 0x000070000d13df20
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - rip = 0x00007fff7b2d62eb
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - 3 0x7fff7b2d9249
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - rbp = 0x000070000d13df50 rsp = 0x000070000d13df40
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - rip = 0x00007fff7b2d9249
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - 4 0x7fff7b2d540d
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - rbp = 0x000070000d13df78 rsp = 0x000070000d13df60
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - rip = 0x00007fff7b2d540d
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - 5 XUL + 0x4ba4020
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - rsp = 0x000070000d13e090 rip = 0x000000010bce7020
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - Found by: stack scanning
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO -
[task 2019-07-30T17:50:12.924Z] 17:50:12 INFO - Thread 2
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - 0 0x7fff7b21722a
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - rax = 0x000000000100001f rdx = 0x0000000000000000
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - rcx = 0x000070000d1c0e08 rbx = 0x0000000000000002
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - rsi = 0x0000000000000002 rdi = 0x0000000112419800
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - rbp = 0x000070000d1c0e60 rsp = 0x000070000d1c0e08
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - r8 = 0x0000000000002103 r9 = 0x0000000000000000
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - r10 = 0x000000000000041c r11 = 0x0000000000000202
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - r12 = 0x0000000000000002 r13 = 0x000000000000041c
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - r14 = 0x0000000112419800 r15 = 0x0000000000000000
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - rip = 0x00007fff7b21722a
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - Found by: given as instruction pointer in context
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - 1 XUL!mozilla::ipc::PortServerThread(void*) [SharedMemoryBasic_mach.mm:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 344 + 0x46]
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - rbp = 0x000070000d1c0f10 rsp = 0x000070000d1c0e70
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - rip = 0x0000000107a48037
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - 2 0x7fff7b2d62eb
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - rbp = 0x000070000d1c0f30 rsp = 0x000070000d1c0f20
[task 2019-07-30T17:50:12.925Z] 17:50:12 INFO - rip = 0x00007fff7b2d62eb
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - 3 0x7fff7b2d9249
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - rbp = 0x000070000d1c0f50 rsp = 0x000070000d1c0f40
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - rip = 0x00007fff7b2d9249
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - 4 0x7fff7b2d540d
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - rbp = 0x000070000d1c0f78 rsp = 0x000070000d1c0f60
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - rip = 0x00007fff7b2d540d
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - 5 XUL + 0x904f50
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - rsp = 0x000070000d1c1090 rip = 0x0000000107a47f50
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - Found by: stack scanning
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO -
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - Thread 3
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - 0 0x7fff7b21d78e
[task 2019-07-30T17:50:12.926Z] 17:50:12 INFO - rax = 0x000000000200016b rdx = 0x0000000000000000
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - rcx = 0x000070000d286bb8 rbx = 0x0000000000000040
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - rsi = 0x000000011241a000 rdi = 0x0000000000000006
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - rbp = 0x000070000d286c20 rsp = 0x000070000d286bb8
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - r8 = 0x0000000000000040 r9 = 0x0000000000000000
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - r10 = 0x000000011241a800 r11 = 0x0000000000000246
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - r12 = 0x000070000d286ce8 r13 = 0x000000011248a800
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - r14 = 0x000000011241a000 r15 = 0x0000000112434c70
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - rip = 0x00007fff7b21d78e
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - Found by: given as instruction pointer in context
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - 1 XUL!event_base_loop [event.c:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 1947 + 0xd]
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - rbp = 0x000070000d286cc0 rsp = 0x000070000d286c30
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - rip = 0x0000000107a04176
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - 2 XUL!base::MessagePumpLibevent::Run(base::MessagePump::Delegate*) [message_pump_libevent.cc:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 0 + 0xe]
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - rbp = 0x000070000d286d20 rsp = 0x000070000d286cd0
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - rip = 0x00000001079f1238
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.927Z] 17:50:12 INFO - 3 XUL!base::Thread::ThreadMain() [thread.cc:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 192 + 0x4d]
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - rbp = 0x000070000d286f00 rsp = 0x000070000d286d30
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - rip = 0x00000001079f838f
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - 4 XUL!ThreadFunc(void*) [platform_thread_posix.cc:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 40 + 0x6]
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - rbp = 0x000070000d286f10 rsp = 0x000070000d286f10
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - rip = 0x00000001079f4b8a
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - 5 0x7fff7b2d62eb
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - rbp = 0x000070000d286f30 rsp = 0x000070000d286f20
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - rip = 0x00007fff7b2d62eb
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - 6 0x7fff7b2d9249
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - rbp = 0x000070000d286f50 rsp = 0x000070000d286f40
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - rip = 0x00007fff7b2d9249
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - 7 0x7fff7b2d540d
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - rbp = 0x000070000d286f78 rsp = 0x000070000d286f60
[task 2019-07-30T17:50:12.928Z] 17:50:12 INFO - rip = 0x00007fff7b2d540d
[task 2019-07-30T17:50:12.929Z] 17:50:12 INFO - Found by: previous frame's frame pointer
[task 2019-07-30T17:50:12.929Z] 17:50:12 INFO - 8 XUL!std::__1::__deque_base<MessageLoop::PendingTask, std::__1::allocator<MessageLoop::PendingTask> >::clear() [deque:50df4b75c9b6c7fec8c8c4685fd188634d193e75 : 1196 + 0xf]
[task 2019-07-30T17:50:12.929Z] 17:50:12 INFO - rsp = 0x000070000d287090 rip = 0x00000001079f4b80
[task 2019-07-30T17:50:12.929Z] 17:50:12 INFO - Found by: stack scanning

Comment 1

[Edwin Takahashi (:egao | infrequent contributor)]

This test appears to be one of the blockers for migrating browser-screenshots to macosx1014, but I have been able to arrive at a solution via the following:

• disable browser_controlCenter.js
• disable browser_devtools.js
• disable browser_permissionPrompts.js
• disable browser_primaryUI.js
• disable browser_preferences.js

This results in a green mochitest-browser-screenshots run for macosx1014.

Enabling any one of these tests individually would cause the suite to time out at 60 minutes. Longer timeouts do not help, as the suite keeps expanding in time to consume any additional timeouts that are given.

The current proposal is to disable the tests mentioned above, which includes test detailed in this bug, so that browser-screenshots job can be moved to macosx1014 leading to decommisioning of macosx1010 machines.

:jaws - if you have any comments regarding this proposal, please let me know.

Comment 2

[Edwin Takahashi (:egao | infrequent contributor)]

I will do additional pushes in isolation to ensure this test can run, because I do slightly suspect that this test is crashing due to another test earlier.

Comment 3

[Edwin Takahashi (:egao | infrequent contributor)]

Looks like this test is not at fault, though the the way it fails make it look like one of the causes of suite failure.

Removing ni from :jaws and closing this bug as invalid.

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1570086&startday=2019-07-29&endday=2019-08-04&tree=all

Comment 6

[Edwin Takahashi (:egao | infrequent contributor)]

Bug has been reopened.

On autoland it has been found that browser/tools/mozscreenshots/preferences/browser_preferences.js times out and crashes intermittently:

https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=os%2Cx%2C10.14%2Cshippable%2Copt%2Cmochitests%2Ctest-macosx1014-64-shippable%2Fopt-browser-screenshots-e10s%2Cm%28ss%29&fromchange=f5659268fcf30ea62e5d4699ceb8aff86e8ce602

I've put a patch up to https://bugzilla.mozilla.org/show_bug.cgi?id=1555454 to disable this test.

:jaws - could you please take a look or redirect the ni to someone that may be able to investigate why this test may take a long time, thanks.

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1570086&startday=2020-06-08&endday=2020-06-14&tree=all

Comment 8

[Jared Wein [:jaws] (please needinfo? me)]

Sorry I don't have time to look in to this and I don't know who else could.