Closed Bug 1897977 Opened 1 year ago Closed 11 months ago

Intermittent toolkit/mozapps/update/tests/browser/browser_aboutDialog_bc_downloaded_staged.js (finished) | single tracking bug

Categories

(Toolkit :: Application Update, defect, P5)

Product:
Toolkit
Component:
Application Update
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

Filed by: tszentpeteri [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=459016183&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/B-jH4hmFREO3RaNTVkxRMQ/runs/0/artifacts/public/logs/live_backing.log

[task 2024-05-21T13:39:21.534Z] 13:39:21     INFO - TEST-START | toolkit/mozapps/update/tests/browser/browser_aboutDialog_bc_downloaded_staged.js
[task 2024-05-21T13:39:22.742Z] 13:39:22     INFO - GECKO(3484) | 2024-05-21 13:39:22.741 firefox[3484:102481] Persistent UI failed to open file file:///Users/cltbld/Library/Saved%20Application%20State/org.mozilla.nightly.savedState/window_1.data: No such file or directory (2)
[task 2024-05-21T13:39:22.925Z] 13:39:22     INFO - GECKO(3484) | MEMORY STAT | vsize 16350MB | residentFast 347MB | heapAllocated 153MB
[task 2024-05-21T13:39:22.926Z] 13:39:22     INFO - TEST-OK | toolkit/mozapps/update/tests/browser/browser_aboutDialog_bc_downloaded_staged.js | took 1392ms
[task 2024-05-21T13:39:22.933Z] 13:39:22     INFO - checking window state
[task 2024-05-21T13:39:22.933Z] 13:39:22     INFO - GECKO(3484) | must wait for focus
[task 2024-05-21T13:45:33.049Z] 13:45:33     INFO - TEST-UNEXPECTED-TIMEOUT | toolkit/mozapps/update/tests/browser/browser_aboutDialog_bc_downloaded_staged.js (finished) | application timed out after 370 seconds with no output
[task 2024-05-21T13:45:33.049Z] 13:45:33     INFO - TEST-INFO 
[task 2024-05-21T13:45:33.050Z] 13:45:33     INFO - Buffered messages finished
[task 2024-05-21T13:45:33.050Z] 13:45:33  WARNING - Force-terminating active process(es).
[task 2024-05-21T13:45:33.050Z] 13:45:33     INFO - Determining child pids from psutil...
[task 2024-05-21T13:45:33.052Z] 13:45:33     INFO - [3485, 3486, 3487, 3492, 3493, 3494, 3495, 3496, 3497]
[task 2024-05-21T13:45:33.052Z] 13:45:33     INFO - ==> process 3484 launched child process 3485
[task 2024-05-21T13:45:33.052Z] 13:45:33     INFO - ==> process 3484 launched child process 3486
[task 2024-05-21T13:45:33.053Z] 13:45:33     INFO - ==> process 3484 launched child process 3487
[task 2024-05-21T13:45:33.053Z] 13:45:33     INFO - ==> process 3484 launched child process 3492
[task 2024-05-21T13:45:33.053Z] 13:45:33     INFO - ==> process 3484 launched child process 3493
[task 2024-05-21T13:45:33.053Z] 13:45:33     INFO - ==> process 3484 launched child process 3494
[task 2024-05-21T13:45:33.054Z] 13:45:33     INFO - ==> process 3484 launched child process 3495
[task 2024-05-21T13:45:33.054Z] 13:45:33     INFO - ==> process 3484 launched child process 3496
[task 2024-05-21T13:45:33.054Z] 13:45:33     INFO - ==> process 3484 launched child process 3497
[task 2024-05-21T13:45:33.055Z] 13:45:33     INFO - Found child pids: {3492, 3493, 3494, 3495, 3496, 3497, 3485, 3486, 3487}
[task 2024-05-21T13:45:33.055Z] 13:45:33     INFO - Killing process: 3492
[task 2024-05-21T13:45:33.055Z] 13:45:33     INFO - TEST-INFO | started process screencapture
[task 2024-05-21T13:45:33.174Z] 13:45:33     INFO - TEST-INFO | screencapture: exit 0
[task 2024-05-21T13:45:33.174Z] 13:45:33     INFO - Killing process: 3493
[task 2024-05-21T13:45:33.174Z] 13:45:33     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-05-21T13:45:33.175Z] 13:45:33     INFO - Killing process: 3494
[task 2024-05-21T13:45:33.175Z] 13:45:33     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-05-21T13:45:33.175Z] 13:45:33     INFO - Killing process: 3495
[task 2024-05-21T13:45:33.176Z] 13:45:33     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-05-21T13:45:33.176Z] 13:45:33     INFO - Killing process: 3496
[task 2024-05-21T13:45:33.176Z] 13:45:33     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-05-21T13:45:33.177Z] 13:45:33     INFO - Killing process: 3497
[task 2024-05-21T13:45:33.177Z] 13:45:33     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-05-21T13:45:33.177Z] 13:45:33     INFO - Killing process: 3485
[task 2024-05-21T13:45:33.178Z] 13:45:33     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-05-21T13:45:33.178Z] 13:45:33     INFO - Killing process: 3486
[task 2024-05-21T13:45:33.178Z] 13:45:33     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-05-21T13:45:33.179Z] 13:45:33     INFO - Killing process: 3487
[task 2024-05-21T13:45:33.179Z] 13:45:33     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-05-21T13:46:03.192Z] 13:46:03     INFO - psutil found pid 3495 dead
[task 2024-05-21T13:46:03.192Z] 13:46:03     INFO - psutil found pid 3496 dead
[task 2024-05-21T13:46:03.192Z] 13:46:03     INFO - psutil found pid 3497 dead
[task 2024-05-21T13:46:03.193Z] 13:46:03     INFO - psutil found pid 3493 dead
[task 2024-05-21T13:46:03.193Z] 13:46:03     INFO - psutil found pid 3494 dead
[task 2024-05-21T13:46:03.193Z] 13:46:03  WARNING - failed to kill pid 3486 after 30s
[task 2024-05-21T13:46:03.193Z] 13:46:03  WARNING - failed to kill pid 3487 after 30s
[task 2024-05-21T13:46:03.194Z] 13:46:03  WARNING - failed to kill pid 3485 after 30s
[task 2024-05-21T13:46:03.194Z] 13:46:03  WARNING - failed to kill pid 3492 after 30s
[task 2024-05-21T13:46:03.194Z] 13:46:03     INFO - Killing process: 3484
[task 2024-05-21T13:46:03.194Z] 13:46:03     INFO - Not taking screenshot here: see the one that was previously logged
[task 2024-05-21T13:46:03.406Z] 13:46:03     INFO - psutil found pid 3484 dead
[task 2024-05-21T13:46:03.406Z] 13:46:03     INFO - TEST-INFO | Main app process: exit 0
[task 2024-05-21T13:46:03.407Z] 13:46:03     INFO - runtests.py | Application ran for: 0:06:57.345467
[task 2024-05-21T13:46:03.407Z] 13:46:03     INFO - zombiecheck | Reading PID log: /var/folders/jf/5t897n6x3zs7nj0xj_55dp3r000014/T/tmpyq2pmjh5pidlog
[task 2024-05-21T13:46:03.407Z] 13:46:03     INFO - ==> process 3484 launched child process 3485
[task 2024-05-21T13:46:03.408Z] 13:46:03     INFO - ==> process 3484 launched child process 3486
<...>
[task 2024-05-21T13:46:03.412Z] 13:46:03     INFO - zombiecheck | Checking for orphan process with PID: 3486
[task 2024-05-21T13:46:03.413Z] 13:46:03     INFO - zombiecheck | Checking for orphan process with PID: 3487
[task 2024-05-21T13:46:03.413Z] 13:46:03     INFO - mozcrash Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/EuZ4g5fpQmCnxyWip9qtsA/artifacts/public/build/target.crashreporter-symbols.zip
[task 2024-05-21T13:46:27.266Z] 13:46:27     INFO - mozcrash Copy/paste: /opt/worker/tasks/task_171629744749468/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --cyborg=/var/folders/jf/5t897n6x3zs7nj0xj_55dp3r000014/T/tmp5b24dgcx/CEAAA1B0-5546-4CBE-91D4-0FC599B410ED.trace /var/folders/jf/5t897n6x3zs7nj0xj_55dp3r000014/T/tmpma9lgirg.mozrunner/minidumps/CEAAA1B0-5546-4CBE-91D4-0FC599B410ED.dmp /var/folders/jf/5t897n6x3zs7nj0xj_55dp3r000014/T/tmpb_1r_41c
[task 2024-05-21T13:46:34.781Z] 13:46:34     INFO - mozcrash Saved minidump as /opt/worker/tasks/task_171629744749468/build/blobber_upload_dir/CEAAA1B0-5546-4CBE-91D4-0FC599B410ED.dmp
[task 2024-05-21T13:46:34.782Z] 13:46:34     INFO - mozcrash Saved app info as /opt/worker/tasks/task_171629744749468/build/blobber_upload_dir/CEAAA1B0-5546-4CBE-91D4-0FC599B410ED.extra
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - PROCESS-CRASH | application crashed [@ libsystem_kernel.dylib + 0x0000000000000dfa] | toolkit/mozapps/update/tests/browser/browser.toml 
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - Process type: content
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - Process pid: 3484
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - Crash dump filename: /var/folders/jf/5t897n6x3zs7nj0xj_55dp3r000014/T/tmpma9lgirg.mozrunner/minidumps/CEAAA1B0-5546-4CBE-91D4-0FC599B410ED.dmp
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - Operating system: Mac OS X
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO -                   10.15.7 19H524
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - CPU: amd64
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO -      family 6 model 158 stepping 10
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO -      12 CPUs
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - 
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - Crash reason:  EXC_SOFTWARE / SIGABRT
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - Crash address: 0x00007fff6fdc4dfa
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - Crashing instruction: `ret`
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - Memory accessed by instruction:
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO -   0. Address: 0x00007fff6fdc5170
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO -      Size: 1
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - Mac Crash Info:
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - 
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - Mac Boot Args: chunklist-security-epoch=0 -chunklist-no-rev2-dev
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - 
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - Process uptime: 387 seconds
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - 
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO - Thread 0 MainThread (crashed)
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO -  0  libsystem_kernel.dylib + 0xdfa
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO -      rax = 0x000000000100001f    rdx = 0x0000000000000000
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO -      rcx = 0x00007ffee45f8a48    rbx = 0x0000000000000002
[task 2024-05-21T13:46:34.919Z] 13:46:34     INFO -      rsi = 0x0000000000000002    rdi = 0x00007ffee45f8b08
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      rbp = 0x00007ffee45f8aa0    rsp = 0x00007ffee45f8a48
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -       r8 = 0x000000000000431f     r9 = 0x0000000000000000
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      r10 = 0x000000000000041c    r11 = 0x0000000000000202
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      r12 = 0x0000000000000002    r13 = 0x000000000000041c
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      r14 = 0x00007ffee45f8b08    r15 = 0x0000000000000000
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      rip = 0x00007fff6fdc4dfa
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -     Found by: given as instruction pointer in context
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -  1  XUL!google_breakpad::ReceivePort::WaitForMessage(google_breakpad::MachReceiveMessage*, unsigned int) [MachIPC.mm:2c85e75f63a911c3aafc7099c431d16d690afd69 : 249 + 0x11]
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      rbp = 0x00007ffee45f8ac0    rsp = 0x00007ffee45f8ab0
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      rip = 0x0000000115f552e2
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -     Found by: previous frame's frame pointer
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -  2  XUL!google_breakpad::CrashGenerationClient::RequestDumpForException(int, int, long long, unsigned int, unsigned int) [crash_generation_client.cc:2c85e75f63a911c3aafc7099c431d16d690afd69 : 68 + 0x9]
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      rbp = 0x00007ffee45f9370    rsp = 0x00007ffee45f8ad0
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      rip = 0x0000000115f4c6e0
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -     Found by: call frame info
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -  3  XUL!google_breakpad::ExceptionHandler::WriteMinidumpWithException(int, long long, long long, __darwin_ucontext*, unsigned int, unsigned int, bool, bool) [exception_handler.cc:2c85e75f63a911c3aafc7099c431d16d690afd69 : 467 + 0x9]
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      rbx = 0x0000000000000005    rbp = 0x00007ffee45f9490
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      rsp = 0x00007ffee45f9380    r12 = 0x0000000000010002
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      r13 = 0x0000000000000307    r14 = 0x000000010c51c300
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      r15 = 0x0000000000000000    rip = 0x0000000115f4e17d
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -     Found by: call frame info
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -  4  XUL!google_breakpad::ExceptionHandler::SignalHandler(int, __siginfo*, void*) [exception_handler.cc:2c85e75f63a911c3aafc7099c431d16d690afd69 : 726 + 0x29]
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      rbx = 0x00007ffee45f9970    rbp = 0x00007ffee45f94d0
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      rsp = 0x00007ffee45f94a0    r12 = 0x1bf32ae42ea29147
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      r13 = 0x0000000000000016    r14 = 0x000000010c51c300
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -      r15 = 0x0000000110b27dc0    rip = 0x0000000115f4e43a
[task 2024-05-21T13:46:34.920Z] 13:46:34     INFO -     Found by: call frame info
<...>
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO - 23  plugin-container!content_process_main(mozilla::Bootstrap*, int, char**) [plugin-container.cpp:2c85e75f63a911c3aafc7099c431d16d690afd69 : 57]
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO -     Found by: inlining
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO - 24  plugin-container!main [MozillaRuntimeMain.cpp:2c85e75f63a911c3aafc7099c431d16d690afd69 : 90 + 0x27]
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO -      rbx = 0x000000010c5030c0    rbp = 0x00007ffee45fa1c0
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO -      rsp = 0x00007ffee45fa190    r12 = 0x0000000000000022
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO -      r13 = 0x0000000000000000    r14 = 0x00007ffee45fa1e0
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO -      r15 = 0x0000000000000021    rip = 0x000000010b604f03
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO -     Found by: call frame info
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO - 25  libdyld.dylib!start + 0x0
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO -      rbx = 0x0000000000000000    rbp = 0x00007ffee45fa1d0
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO -      rsp = 0x00007ffee45fa1d0    r12 = 0x0000000000000000
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO -      r13 = 0x0000000000000000    r14 = 0x0000000000000000
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO -      r15 = 0x0000000000000000    rip = 0x00007fff6fc83cc9
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO -     Found by: call frame info
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO - 
[task 2024-05-21T13:46:34.923Z] 13:46:34     INFO - Thread 1

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

Status: NEW → RESOLVED
Closed: 11 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.