Closed Bug 1468396 Opened 7 years ago Closed 7 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | dom/tests/mochitest/fetch/test_headers.html | application timed out after 370 seconds with no output

Categories

(Core :: DOM: Core & HTML, defect, P5)

Product:
Core
Component:
DOM: Core & HTML
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=182945863&repo=autoland https://queue.taskcluster.net/v1/task/SWuv45-0QZms-2rDiC6tWA/runs/0/artifacts/public/logs/live_backing.log [task 2018-06-12T20:31:24.047Z] 20:31:24 INFO - TEST-PASS | dom/tests/mochitest/fetch/test_headers.html | NestedWorker: true: Correct entries iterator [task 2018-06-12T20:31:24.048Z] 20:31:24 INFO - TEST-PASS | dom/tests/mochitest/fetch/test_headers.html | NestedWorker: true === true: Both should either be arrays, or not [task 2018-06-12T20:31:24.049Z] 20:31:24 INFO - TEST-PASS | dom/tests/mochitest/fetch/test_headers.html | NestedWorker: true === true: Both should either be arrays, or not [task 2018-06-12T20:31:24.050Z] 20:31:24 INFO - TEST-PASS | dom/tests/mochitest/fetch/test_headers.html | NestedWorker: false === false: Both should either be arrays, or not [task 2018-06-12T20:31:24.051Z] 20:31:24 INFO - TEST-PASS | dom/tests/mochitest/fetch/test_headers.html | NestedWorker: false === false: Both should either be arrays, or not [task 2018-06-12T20:31:24.053Z] 20:31:24 INFO - TEST-PASS | dom/tests/mochitest/fetch/test_headers.html | NestedWorker: true === true: Both should either be arrays, or not [task 2018-06-12T20:31:24.054Z] 20:31:24 INFO - TEST-PASS | dom/tests/mochitest/fetch/test_headers.html | NestedWorker: false === false: Both should either be arrays, or not [task 2018-06-12T20:31:24.055Z] 20:31:24 INFO - TEST-PASS | dom/tests/mochitest/fetch/test_headers.html | NestedWorker: false === false: Both should either be arrays, or not [task 2018-06-12T20:31:24.056Z] 20:31:24 INFO - TEST-PASS | dom/tests/mochitest/fetch/test_headers.html | NestedWorker: true: Correct entries iterator [task 2018-06-12T20:31:24.057Z] 20:31:24 INFO - Buffered messages finished [task 2018-06-12T20:31:24.058Z] 20:31:24 ERROR - TEST-UNEXPECTED-TIMEOUT | dom/tests/mochitest/fetch/test_headers.html | application timed out after 370 seconds with no output [task 2018-06-12T20:31:24.059Z] 20:31:24 ERROR - Force-terminating active process(es). [task 2018-06-12T20:31:24.060Z] 20:31:24 INFO - Determining child pids from psutil... [task 2018-06-12T20:31:24.061Z] 20:31:24 INFO - [5140, 5077] [task 2018-06-12T20:31:24.063Z] 20:31:24 INFO - ==> process 5024 launched child process 5047 [task 2018-06-12T20:31:24.064Z] 20:31:24 INFO - ==> process 5024 launched child process 5077 [task 2018-06-12T20:31:24.065Z] 20:31:24 INFO - ==> process 5024 launched child process 5140 [task 2018-06-12T20:31:24.066Z] 20:31:24 INFO - Found child pids: set([5140, 5077, 5047]) [task 2018-06-12T20:31:24.066Z] 20:31:24 INFO - Failed to get child procs [task 2018-06-12T20:31:24.067Z] 20:31:24 INFO - Killing process: 5140 [task 2018-06-12T20:31:24.068Z] 20:31:24 INFO - TEST-INFO | started process screentopng [task 2018-06-12T20:31:24.469Z] 20:31:24 INFO - TEST-INFO | screentopng: exit 0 [task 2018-06-12T20:31:24.471Z] 20:31:24 INFO - Killing process: 5077 [task 2018-06-12T20:31:24.472Z] 20:31:24 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-06-12T20:31:24.473Z] 20:31:24 INFO - Killing process: 5047 [task 2018-06-12T20:31:24.474Z] 20:31:24 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-06-12T20:31:24.475Z] 20:31:24 INFO - Can't trigger Breakpad, process no longer exists [task 2018-06-12T20:31:24.477Z] 20:31:24 INFO - Killing process: 5024 [task 2018-06-12T20:31:24.478Z] 20:31:24 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-06-12T20:31:24.642Z] 20:31:24 INFO - psutil found pid 5024 dead [task 2018-06-12T20:31:24.698Z] 20:31:24 WARNING - runtests.py | Failed to get app exit code - running/crashed? [task 2018-06-12T20:31:24.699Z] 20:31:24 INFO - TEST-INFO | Main app process: exit 0 [task 2018-06-12T20:31:24.700Z] 20:31:24 INFO - runtests.py | Application ran for: 0:08:03.445780 [task 2018-06-12T20:31:24.701Z] 20:31:24 INFO - zombiecheck | Reading PID log: /tmp/tmp9jmwWZpidlog [task 2018-06-12T20:31:24.701Z] 20:31:24 INFO - ==> process 5024 launched child process 5047 [task 2018-06-12T20:31:24.702Z] 20:31:24 INFO - ==> process 5024 launched child process 5077 [task 2018-06-12T20:31:24.703Z] 20:31:24 INFO - ==> process 5024 launched child process 5140 [task 2018-06-12T20:31:24.704Z] 20:31:24 INFO - zombiecheck | Checking for orphan process with PID: 5140 [task 2018-06-12T20:31:24.705Z] 20:31:24 INFO - zombiecheck | Checking for orphan process with PID: 5077 [task 2018-06-12T20:31:24.705Z] 20:31:24 INFO - zombiecheck | Checking for orphan process with PID: 5047 [task 2018-06-12T20:31:24.706Z] 20:31:24 INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/Y2DIYTD6SJq8ooPp8GxY2w/artifacts/public/build/target.crashreporter-symbols.zip [task 2018-06-12T20:31:30.840Z] 20:31:30 INFO - mozcrash Copy/paste: /usr/local/bin/linux64-minidump_stackwalk /tmp/tmp6cvpNS.mozrunner/minidumps/1e68cef3-2a90-472c-5e8b-c058d115ff21.dmp /tmp/tmpW1ts_S [task 2018-06-12T20:31:30.868Z] 20:31:30 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/1e68cef3-2a90-472c-5e8b-c058d115ff21.dmp [task 2018-06-12T20:31:30.868Z] 20:31:30 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [None] [task 2018-06-12T20:31:30.868Z] 20:31:30 INFO - Crash dump filename: /tmp/tmp6cvpNS.mozrunner/minidumps/1e68cef3-2a90-472c-5e8b-c058d115ff21.dmp [task 2018-06-12T20:31:30.868Z] 20:31:30 INFO - stderr from minidump_stackwalk: [task 2018-06-12T20:31:30.868Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump.cc:4359: INFO: Minidump opened minidump /tmp/tmp6cvpNS.mozrunner/minidumps/1e68cef3-2a90-472c-5e8b-c058d115ff21.dmp [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump.cc:4479: INFO: Minidump not byte-swapping minidump [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump.cc:4956: INFO: GetStream: type 15 not present [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump.cc:4956: INFO: GetStream: type 7 not present [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump.cc:4956: INFO: GetStream: type 7 not present [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump.cc:4956: INFO: GetStream: type 1197932545 not present [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump.cc:4956: INFO: GetStream: type 6 not present [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump.cc:4956: INFO: GetStream: type 1197932546 not present [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump.cc:4956: INFO: GetStream: type 4 not present [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump.cc:4956: INFO: GetStream: type 5 not present [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump.cc:4956: INFO: GetStream: type 3 not present [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump_processor.cc:152: ERROR: Minidump /tmp/tmp6cvpNS.mozrunner/minidumps/1e68cef3-2a90-472c-5e8b-c058d115ff21.dmp has no thread list [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: stackwalk.cc:139: ERROR: MinidumpProcessor::Process failed [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - 2018-06-12 20:31:30: minidump.cc:4331: INFO: Minidump closing minidump [task 2018-06-12T20:31:30.869Z] 20:31:30 INFO - mozcrash Copy/paste: /usr/local/bin/linux64-minidump_stackwalk /tmp/tmp6cvpNS.mozrunner/minidumps/44fdd35d-4e2e-258a-8954-a96884a2337e.dmp /tmp/tmpW1ts_S [task 2018-06-12T20:31:38.747Z] 20:31:38 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/44fdd35d-4e2e-258a-8954-a96884a2337e.dmp [task 2018-06-12T20:31:38.749Z] 20:31:38 INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/44fdd35d-4e2e-258a-8954-a96884a2337e.extra [task 2018-06-12T20:31:38.890Z] 20:31:38 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ libc-2.23.so + 0xfb74d] [task 2018-06-12T20:31:38.891Z] 20:31:38 INFO - Crash dump filename: /tmp/tmp6cvpNS.mozrunner/minidumps/44fdd35d-4e2e-258a-8954-a96884a2337e.dmp [task 2018-06-12T20:31:38.891Z] 20:31:38 INFO - Operating system: Linux [task 2018-06-12T20:31:38.892Z] 20:31:38 INFO - 0.0.0 Linux 4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018 x86_64 [task 2018-06-12T20:31:38.892Z] 20:31:38 INFO - CPU: amd64 [task 2018-06-12T20:31:38.893Z] 20:31:38 INFO - family 6 model 62 stepping 4 [task 2018-06-12T20:31:38.893Z] 20:31:38 INFO - 2 CPUs [task 2018-06-12T20:31:38.893Z] 20:31:38 INFO - [task 2018-06-12T20:31:38.894Z] 20:31:38 INFO - GPU: UNKNOWN [task 2018-06-12T20:31:38.894Z] 20:31:38 INFO - [task 2018-06-12T20:31:38.895Z] 20:31:38 INFO - Crash reason: SIGABRT [task 2018-06-12T20:31:38.895Z] 20:31:38 INFO - Crash address: 0x3e8000003bf [task 2018-06-12T20:31:38.896Z] 20:31:38 INFO - Process uptime: not available [task 2018-06-12T20:31:38.896Z] 20:31:38 INFO - [task 2018-06-12T20:31:38.897Z] 20:31:38 INFO - Thread 0 (crashed) [task 2018-06-12T20:31:38.897Z] 20:31:38 INFO - 0 libc-2.23.so + 0xfb74d [task 2018-06-12T20:31:38.898Z] 20:31:38 INFO - rax = 0xfffffffffffffffc rdx = 0x00000000ffffffff [task 2018-06-12T20:31:38.898Z] 20:31:38 INFO - rcx = 0x00007fdd487d974d rbx = 0x00007fdd484473c0 [task 2018-06-12T20:31:38.899Z] 20:31:38 INFO - rsi = 0x0000000000000003 rdi = 0x00007fdd2acfe340 [task 2018-06-12T20:31:38.899Z] 20:31:38 INFO - rbp = 0x00007ffcfa7a4650 rsp = 0x00007ffcfa7a4600 [task 2018-06-12T20:31:38.899Z] 20:31:38 INFO - r8 = 0x0000000000000003 r9 = 0x0000000000000001 [task 2018-06-12T20:31:38.900Z] 20:31:38 INFO - r10 = 0x00007fdd2f481060 r11 = 0x0000000000000293 [task 2018-06-12T20:31:38.900Z] 20:31:38 INFO - r12 = 0x00007fdd2acfe340 r13 = 0x0000000000000003 [task 2018-06-12T20:31:38.901Z] 20:31:38 INFO - r14 = 0x00000000ffffffff r15 = 0x0000000000000002 [task 2018-06-12T20:31:38.901Z] 20:31:38 INFO - rip = 0x00007fdd487d974d [task 2018-06-12T20:31:38.902Z] 20:31:38 INFO - Found by: given as instruction pointer in context [task 2018-06-12T20:31:38.902Z] 20:31:38 INFO - 1 libxul.so!PollWrapper [nsAppShell.cpp:b01c4543a097b457f2bc554bdf5dead27c375632 : 54 + 0xf] [task 2018-06-12T20:31:38.902Z] 20:31:38 INFO - rbp = 0x00007ffcfa7a4650 rsp = 0x00007ffcfa7a4610 [task 2018-06-12T20:31:38.903Z] 20:31:38 INFO - rip = 0x00007fdd3953227a [task 2018-06-12T20:31:38.903Z] 20:31:38 INFO - Found by: stack scanning [task 2018-06-12T20:31:38.904Z] 20:31:38 INFO - 2 libglib-2.0.so.0.4800.2 + 0x4a38c [task 2018-06-12T20:31:38.904Z] 20:31:38 INFO - rbx = 0x00007fdd484bad40 rbp = 0x0000000000000003 [task 2018-06-12T20:31:38.904Z] 20:31:38 INFO - rsp = 0x00007ffcfa7a4660 r12 = 0x00007fdd2acfe340 [task 2018-06-12T20:31:38.905Z] 20:31:38 INFO - r13 = 0x00000000ffffffff r14 = 0x00007fdd395321e0 [task 2018-06-12T20:31:38.905Z] 20:31:38 INFO - r15 = 0x0000000000000003 rip = 0x00007fdd435b638c [task 2018-06-12T20:31:38.906Z] 20:31:38 INFO - Found by: call frame info [task 2018-06-12T20:31:38.906Z] 20:31:38 INFO - 3 libglib-2.0.so.0.4800.2 + 0x4a49c [task 2018-06-12T20:31:38.906Z] 20:31:38 INFO - rsp = 0x00007ffcfa7a46c0 rip = 0x00007fdd435b649c [task 2018-06-12T20:31:38.907Z] 20:31:38 INFO - Found by: stack scanning [task 2018-06-12T20:31:38.907Z] 20:31:38 INFO - 4 libxul.so!nsAppShell::ProcessNextNativeEvent(bool) [nsAppShell.cpp:b01c4543a097b457f2bc554bdf5dead27c375632 : 296 + 0x5] [task 2018-06-12T20:31:38.908Z] 20:31:38 INFO - rsp = 0x00007ffcfa7a46e0 rip = 0x00007fdd395322ff [task 2018-06-12T20:31:38.908Z] 20:31:38 INFO - Found by: stack scanning [task 2018-06-12T20:31:38.909Z] 20:31:38 INFO - 5 libxul.so!nsBaseAppShell::DoProcessNextNativeEvent(bool) [nsBaseAppShell.cpp:b01c4543a097b457f2bc554bdf5dead27c375632 : 139 + 0x10] [task 2018-06-12T20:31:38.909Z] 20:31:38 INFO - rsp = 0x00007ffcfa7a46f0 rip = 0x00007fdd394fe45c [task 2018-06-12T20:31:38.909Z] 20:31:38 INFO - Found by: stack scanning [task 2018-06-12T20:31:38.910Z] 20:31:38 INFO - 6 libxul.so!nsBaseAppShell::OnProcessNextEvent(nsIThreadInternal*, bool) [nsBaseAppShell.cpp:b01c4543a097b457f2bc554bdf5dead27c375632 : 290 + 0x8] [task 2018-06-12T20:31:38.910Z] 20:31:38 INFO - rsp = 0x00007ffcfa7a4720 rip = 0x00007fdd394fe600 [task 2018-06-12T20:31:38.911Z] 20:31:38 INFO - Found by: stack scanning [task 2018-06-12T20:31:38.911Z] 20:31:38 INFO - 7 libxul.so!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:b01c4543a097b457f2bc554bdf5dead27c375632 : 977 + 0xf] [task 2018-06-12T20:31:38.911Z] 20:31:38 INFO - rsp = 0x00007ffcfa7a4770 rip = 0x00007fdd3754b5de [task 2018-06-12T20:31:38.912Z] 20:31:38 INFO - Found by: stack scanning [task 2018-06-12T20:31:38.912Z] 20:31:38 INFO - 8 firefox!_fini + 0x1361 [task 2018-06-12T20:31:38.913Z] 20:31:38 INFO - rsp = 0x00007ffcfa7a4780 rip = 0x000000000042dadd [task 2018-06-12T20:31:38.913Z] 20:31:38 INFO - Found by: stack scanning [task 2018-06-12T20:31:38.913Z] 20:31:38 INFO - 9 libxul.so!nsTSubstring<char>::ReplaceASCII(unsigned int, unsigned int, char const*, unsigned int) [nsTSubstring.cpp:b01c4543a097b457f2bc554bdf5dead27c375632 : 686 + 0x5] [task 2018-06-12T20:31:38.914Z] 20:31:38 INFO - rsp = 0x00007ffcfa7a4790 rip = 0x00007fdd374c1299 [task 2018-06-12T20:31:38.914Z] 20:31:38 INFO - Found by: stack scanning
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 7 years ago → 7 years ago
Resolution: --- → INCOMPLETE
Component: DOM → DOM: Core & HTML
You need to log in before you can comment on or make changes to this bug.