Closed Bug 1435462 Opened 7 years ago Closed 7 years ago

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

Categories

(Core :: WebRTC, defect, P5)

Product:
Core
Component:
WebRTC
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=160158790&repo=mozilla-central https://queue.taskcluster.net/v1/task/HqvohvgcR9OhAHIvfTHW6A/runs/0/artifacts/public/logs/live_backing.log [task 2018-02-02T22:27:06.379Z] 22:27:06 INFO - GECKO(1123) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150082,name=PBrowser::Msg_Destroy) Channel error: cannot send/recv [task 2018-02-02T22:27:06.417Z] 22:27:06 INFO - GECKO(1123) | ++DOCSHELL 0x7f8338562000 == 6 [pid = 1123] [id = {a7e3c387-5c77-4c60-83e7-aa2ff741b1ff}] [task 2018-02-02T22:27:06.418Z] 22:27:06 INFO - GECKO(1123) | ++DOMWINDOW == 11 (0x7f83474ba010) [pid = 1123] [serial = 36] [outer = (nil)] [task 2018-02-02T22:27:06.595Z] 22:27:06 INFO - GECKO(1123) | ++DOMWINDOW == 12 (0x7f833845f000) [pid = 1123] [serial = 37] [outer = 0x7f83474ba010] [task 2018-02-02T22:27:06.732Z] 22:27:06 INFO - GECKO(1123) | [Parent 1123, Main Thread] WARNING: NS_ENSURE_TRUE(aSecondURI) failed: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 98 [task 2018-02-02T22:27:06.734Z] 22:27:06 INFO - GECKO(1123) | ++DOMWINDOW == 13 (0x7f8338461800) [pid = 1123] [serial = 38] [outer = 0x7f83474ba010] [task 2018-02-02T22:27:07.046Z] 22:27:07 INFO - GECKO(1123) | --DOCSHELL 0x7f8341906000 == 5 [pid = 1123] [id = {11992ed9-db6b-4839-9b81-caa0fa878b50}] [task 2018-02-02T22:27:07.047Z] 22:27:07 INFO - GECKO(1123) | --DOCSHELL 0x7f834582b000 == 4 [pid = 1123] [id = {cba380d5-1d9b-4392-b923-bbefb869d30f}] [task 2018-02-02T22:27:15.444Z] 22:27:15 INFO - GECKO(1123) | --DOMWINDOW == 12 (0x7f83474b9e20) [pid = 1123] [serial = 34] [outer = (nil)] [url = chrome://browser/content/webrtcIndicator.xul] [task 2018-02-02T22:27:15.445Z] 22:27:15 INFO - GECKO(1123) | --DOMWINDOW == 11 (0x7f83474b9c30) [pid = 1123] [serial = 32] [outer = (nil)] [url = chrome://browser/content/webrtcIndicator.xul] [task 2018-02-02T22:27:19.843Z] 22:27:19 INFO - GECKO(1123) | --DOMWINDOW == 10 (0x7f833bcc7800) [pid = 1123] [serial = 35] [outer = (nil)] [url = about:blank] [task 2018-02-02T22:27:19.844Z] 22:27:19 INFO - GECKO(1123) | --DOMWINDOW == 9 (0x7f833845f000) [pid = 1123] [serial = 37] [outer = (nil)] [url = about:blank] [task 2018-02-02T22:27:19.845Z] 22:27:19 INFO - GECKO(1123) | --DOMWINDOW == 8 (0x7f8338b91400) [pid = 1123] [serial = 33] [outer = (nil)] [url = about:blank] [task 2018-02-02T22:33:29.847Z] 22:33:29 INFO - Buffered messages logged at 22:27:03 [task 2018-02-02T22:33:29.847Z] 22:33:29 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_threeUnbundledConnections.html | A valid string reason is expected [task 2018-02-02T22:33:29.848Z] 22:33:29 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_threeUnbundledConnections.html | Reason cannot be empty [task 2018-02-02T22:33:29.850Z] 22:33:29 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_threeUnbundledConnections.html | A valid string reason is expected [task 2018-02-02T22:33:29.850Z] 22:33:29 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_threeUnbundledConnections.html | Reason cannot be empty [task 2018-02-02T22:33:29.851Z] 22:33:29 INFO - Skipping ICE Server for this test [task 2018-02-02T22:33:29.852Z] 22:33:29 INFO - Buffered messages finished [task 2018-02-02T22:33:29.853Z] 22:33:29 ERROR - TEST-UNEXPECTED-TIMEOUT | dom/media/tests/mochitest/test_peerConnection_threeUnbundledConnections.html | application timed out after 370 seconds with no output [task 2018-02-02T22:33:29.854Z] 22:33:29 ERROR - Force-terminating active process(es). [task 2018-02-02T22:33:29.855Z] 22:33:29 INFO - Determining child pids from psutil... [task 2018-02-02T22:33:29.863Z] 22:33:29 INFO - [1221] [task 2018-02-02T22:33:29.864Z] 22:33:29 INFO - ==> process 1123 launched child process 1144 [task 2018-02-02T22:33:29.864Z] 22:33:29 INFO - ==> process 1123 launched child process 1174 [task 2018-02-02T22:33:29.865Z] 22:33:29 INFO - ==> process 1123 launched child process 1221 [task 2018-02-02T22:33:29.865Z] 22:33:29 INFO - ==> process 1123 launched child process 1729 [task 2018-02-02T22:33:29.866Z] 22:33:29 INFO - Found child pids: set([1144, 1729, 1221, 1174]) [task 2018-02-02T22:33:29.867Z] 22:33:29 INFO - Failed to get child procs [task 2018-02-02T22:33:29.868Z] 22:33:29 INFO - Killing process: 1144 [task 2018-02-02T22:33:29.869Z] 22:33:29 INFO - TEST-INFO | started process screentopng [task 2018-02-02T22:33:30.151Z] 22:33:30 INFO - TEST-INFO | screentopng: exit 0 [task 2018-02-02T22:33:30.152Z] 22:33:30 INFO - Can't trigger Breakpad, process no longer exists [task 2018-02-02T22:33:30.153Z] 22:33:30 INFO - Killing process: 1729 [task 2018-02-02T22:33:30.154Z] 22:33:30 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-02-02T22:33:30.155Z] 22:33:30 INFO - Can't trigger Breakpad, process no longer exists [task 2018-02-02T22:33:30.156Z] 22:33:30 INFO - Killing process: 1221 [task 2018-02-02T22:33:30.157Z] 22:33:30 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-02-02T22:33:30.158Z] 22:33:30 INFO - Killing process: 1174 [task 2018-02-02T22:33:30.160Z] 22:33:30 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-02-02T22:33:30.161Z] 22:33:30 INFO - Can't trigger Breakpad, process no longer exists [task 2018-02-02T22:33:30.163Z] 22:33:30 INFO - Killing process: 1123 [task 2018-02-02T22:33:30.164Z] 22:33:30 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-02-02T22:33:30.284Z] 22:33:30 INFO - psutil found pid 1123 dead [task 2018-02-02T22:33:30.411Z] 22:33:30 WARNING - runtests.py | Failed to get app exit code - running/crashed? [task 2018-02-02T22:33:30.411Z] 22:33:30 INFO - TEST-INFO | Main app process: exit 0 [task 2018-02-02T22:33:30.412Z] 22:33:30 INFO - runtests.py | Application ran for: 0:09:27.352314 [task 2018-02-02T22:33:30.412Z] 22:33:30 INFO - zombiecheck | Reading PID log: /tmp/tmpAVbj2Epidlog [task 2018-02-02T22:33:30.413Z] 22:33:30 INFO - ==> process 1123 launched child process 1144 [task 2018-02-02T22:33:30.414Z] 22:33:30 INFO - ==> process 1123 launched child process 1174 [task 2018-02-02T22:33:30.414Z] 22:33:30 INFO - ==> process 1123 launched child process 1221 [task 2018-02-02T22:33:30.415Z] 22:33:30 INFO - ==> process 1123 launched child process 1729 [task 2018-02-02T22:33:30.416Z] 22:33:30 INFO - zombiecheck | Checking for orphan process with PID: 1144 [task 2018-02-02T22:33:30.417Z] 22:33:30 INFO - zombiecheck | Checking for orphan process with PID: 1729 [task 2018-02-02T22:33:30.417Z] 22:33:30 INFO - zombiecheck | Checking for orphan process with PID: 1221 [task 2018-02-02T22:33:30.418Z] 22:33:30 INFO - zombiecheck | Checking for orphan process with PID: 1174 [task 2018-02-02T22:33:30.419Z] 22:33:30 INFO - mozcrash Copy/paste: /usr/local/bin/linux64-minidump_stackwalk /tmp/tmpePHuqP.mozrunner/minidumps/54791014-61c6-40f1-f620-df670dedb968.dmp /builds/worker/workspace/build/symbols [task 2018-02-02T22:33:38.928Z] 22:33:38 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/54791014-61c6-40f1-f620-df670dedb968.dmp [task 2018-02-02T22:33:38.930Z] 22:33:38 INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/54791014-61c6-40f1-f620-df670dedb968.extra [task 2018-02-02T22:33:38.992Z] 22:33:38 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ libc-2.23.so + 0xfb74d] [task 2018-02-02T22:33:38.994Z] 22:33:38 INFO - Crash dump filename: /tmp/tmpePHuqP.mozrunner/minidumps/54791014-61c6-40f1-f620-df670dedb968.dmp [task 2018-02-02T22:33:38.994Z] 22:33:38 INFO - Operating system: Linux [task 2018-02-02T22:33:38.996Z] 22:33:38 INFO - 0.0.0 Linux 4.4.0-98-generic #121~14.04.1-Ubuntu SMP Wed Oct 11 11:54:55 UTC 2017 x86_64 [task 2018-02-02T22:33:38.996Z] 22:33:38 INFO - CPU: amd64 [task 2018-02-02T22:33:38.997Z] 22:33:38 INFO - family 6 model 62 stepping 4 [task 2018-02-02T22:33:38.998Z] 22:33:38 INFO - 2 CPUs [task 2018-02-02T22:33:38.998Z] 22:33:38 INFO - [task 2018-02-02T22:33:38.999Z] 22:33:38 INFO - GPU: UNKNOWN [task 2018-02-02T22:33:39.000Z] 22:33:38 INFO - [task 2018-02-02T22:33:39.000Z] 22:33:38 INFO - Crash reason: SIGABRT [task 2018-02-02T22:33:39.000Z] 22:33:38 INFO - Crash address: 0x3e8000003c7 [task 2018-02-02T22:33:39.002Z] 22:33:38 INFO - Process uptime: not available [task 2018-02-02T22:33:39.002Z] 22:33:38 INFO - [task 2018-02-02T22:33:39.002Z] 22:33:38 INFO - Thread 0 (crashed) [task 2018-02-02T22:33:39.003Z] 22:33:38 INFO - 0 libc-2.23.so + 0xfb74d [task 2018-02-02T22:33:39.005Z] 22:33:38 INFO - rax = 0x0000000000000001 rdx = 0x00000000ffffffff [task 2018-02-02T22:33:39.006Z] 22:33:38 INFO - rcx = 0x00007f837530474d rbx = 0x00007f83750bf450 [task 2018-02-02T22:33:39.006Z] 22:33:38 INFO - rsi = 0x0000000000000005 rdi = 0x00007f83458bac10 [task 2018-02-02T22:33:39.008Z] 22:33:38 INFO - rbp = 0x00007ffed755c050 rsp = 0x00007ffed755c020 [task 2018-02-02T22:33:39.008Z] 22:33:38 INFO - r8 = 0x0000000000000005 r9 = 0x0000000000000001 [task 2018-02-02T22:33:39.008Z] 22:33:38 INFO - r10 = 0x00007f834748fba0 r11 = 0x0000000000000293 [task 2018-02-02T22:33:39.009Z] 22:33:38 INFO - r12 = 0x00007f83458bac10 r13 = 0x00000000ffffffff [task 2018-02-02T22:33:39.011Z] 22:33:38 INFO - r14 = 0x00007f83661a1ee9 r15 = 0x0000000000000005 [task 2018-02-02T22:33:39.011Z] 22:33:38 INFO - rip = 0x00007f837530474d [task 2018-02-02T22:33:39.011Z] 22:33:38 INFO - Found by: given as instruction pointer in context [task 2018-02-02T22:33:39.012Z] 22:33:38 INFO - 1 libxul.so!PollWrapper [nsAppShell.cpp:9207588939fdb5355f12c9b48efe733daaa3725e : 53 + 0x10] [task 2018-02-02T22:33:39.013Z] 22:33:38 INFO - rbp = 0x00007ffed755c050 rsp = 0x00007ffed755c030 [task 2018-02-02T22:33:39.013Z] 22:33:38 INFO - rip = 0x00007f83661a1f19 [task 2018-02-02T22:33:39.014Z] 22:33:39 INFO - Found by: stack scanning [task 2018-02-02T22:33:39.015Z] 22:33:39 INFO - 2 libglib-2.0.so.0.4800.2 + 0x4a38c [task 2018-02-02T22:33:39.015Z] 22:33:39 INFO - rbp = 0x0000000000000005 rsp = 0x00007ffed755c060 [task 2018-02-02T22:33:39.016Z] 22:33:39 INFO - rip = 0x00007f83701a738c [task 2018-02-02T22:33:39.016Z] 22:33:39 INFO - Found by: call frame info [task 2018-02-02T22:33:39.017Z] 22:33:39 INFO - 3 libglib-2.0.so.0.4800.2 + 0x4a49c [task 2018-02-02T22:33:39.018Z] 22:33:39 INFO - rsp = 0x00007ffed755c0c0 rip = 0x00007f83701a749c [task 2018-02-02T22:33:39.019Z] 22:33:39 INFO - Found by: stack scanning [task 2018-02-02T22:33:39.020Z] 22:33:39 INFO - 4 libxul.so!nsAppShell::ProcessNextNativeEvent [nsAppShell.cpp:9207588939fdb5355f12c9b48efe733daaa3725e : 295 + 0x5] [task 2018-02-02T22:33:39.021Z] 22:33:39 INFO - rsp = 0x00007ffed755c0e0 rip = 0x00007f83661a1f5f [task 2018-02-02T22:33:39.021Z] 22:33:39 INFO - Found by: stack scanning [task 2018-02-02T22:33:39.022Z] 22:33:39 INFO - 5 libxul.so!nsBaseAppShell::DoProcessNextNativeEvent [nsBaseAppShell.cpp:9207588939fdb5355f12c9b48efe733daaa3725e : 139 + 0x10] [task 2018-02-02T22:33:39.022Z] 22:33:39 INFO - rsp = 0x00007ffed755c0f0 rip = 0x00007f8366172411 [task 2018-02-02T22:33:39.023Z] 22:33:39 INFO - Found by: stack scanning [task 2018-02-02T22:33:39.024Z] 22:33:39 INFO - 6 libxul.so!nsBaseAppShell::OnProcessNextEvent [nsBaseAppShell.cpp:9207588939fdb5355f12c9b48efe733daaa3725e : 290 + 0x8] [task 2018-02-02T22:33:39.024Z] 22:33:39 INFO - rsp = 0x00007ffed755c130 rip = 0x00007f83661750f1 [task 2018-02-02T22:33:39.025Z] 22:33:39 INFO - Found by: stack scanning [task 2018-02-02T22:33:39.025Z] 22:33:39 INFO - 7 libxul.so!nsThread::ProcessNextEvent [nsThread.cpp:9207588939fdb5355f12c9b48efe733daaa3725e : 952 + 0x1b] [task 2018-02-02T22:33:39.026Z] 22:33:39 INFO - rsp = 0x00007ffed755c180 rip = 0x00007f836459193b [task 2018-02-02T22:33:39.026Z] 22:33:39 INFO - Found by: stack scanning [task 2018-02-02T22:33:39.027Z] 22:33:39 INFO - 8 firefox!arena_t::SplitRun [mozjemalloc.cpp:9207588939fdb5355f12c9b48efe733daaa3725e : 2338 + 0xe] [task 2018-02-02T22:33:39.028Z] 22:33:39 INFO - rsp = 0x00007ffed755c250 rip = 0x000000000040cf00 [task 2018-02-02T22:33:39.028Z] 22:33:39 INFO - Found by: stack scanning [task 2018-02-02T22:33:39.029Z] 22:33:39 INFO - 9 firefox!arena_t::AllocRun [rb.h:9207588939fdb5355f12c9b48efe733daaa3725e : 271 + 0x2] [task 2018-02-02T22:33:39.030Z] 22:33:39 INFO - rsp = 0x00007ffed755c2d0 rip = 0x000000000040d101 [task 2018-02-02T22:33:39.030Z] 22:33:39 INFO - Found by: stack scanning [task 2018-02-02T22:33:30.163Z] 22:33:30 INFO - Killing process: 1123 [task 2018-02-02T22:33:30.164Z] 22:33:30 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-02-02T22:33:30.284Z] 22:33:30 INFO - psutil found pid 1123 dead [task 2018-02-02T22:33:30.411Z] 22:33:30 WARNING - runtests.py | Failed to get app exit code - running/crashed? [task 2018-02-02T22:33:30.411Z] 22:33:30 INFO - TEST-INFO | Main app process: exit 0 [task 2018-02-02T22:33:30.412Z] 22:33:30 INFO - runtests.py | Application ran for: 0:09:27.352314 [task 2018-02-02T22:33:30.412Z] 22:33:30 INFO - zombiecheck | Reading PID log: /tmp/tmpAVbj2Epidlog [task 2018-02-02T22:33:30.413Z] 22:33:30 INFO - ==> process 1123 launched child process 1144 [task 2018-02-02T22:33:30.414Z] 22:33:30 INFO - ==> process 1123 launched child process 1174 [task 2018-02-02T22:33:30.414Z] 22:33:30 INFO - ==> process 1123 launched child process 1221 [task 2018-02-02T22:33:30.415Z] 22:33:30 INFO - ==> process 1123 launched child process 1729 [task 2018-02-02T22:33:30.416Z] 22:33:30 INFO - zombiecheck | Checking for orphan process with PID: 1144 [task 2018-02-02T22:33:30.417Z] 22:33:30 INFO - zombiecheck | Checking for orphan process with PID: 1729 [task 2018-02-02T22:33:30.417Z] 22:33:30 INFO - zombiecheck | Checking for orphan process with PID: 1221 [task 2018-02-02T22:33:30.418Z] 22:33:30 INFO - zombiecheck | Checking for orphan process with PID: 1174 [task 2018-02-02T22:33:30.419Z] 22:33:30 INFO - mozcrash Copy/paste: /usr/local/bin/linux64-minidump_stackwalk /tmp/tmpePHuqP.mozrunner/minidumps/54791014-61c6-40f1-f620-df670dedb968.dmp /builds/worker/workspace/build/symbols [task 2018-02-02T22:33:38.928Z] 22:33:38 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/54791014-61c6-40f1-f620-df670dedb968.dmp [task 2018-02-02T22:33:38.930Z] 22:33:38 INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/54791014-61c6-40f1-f620-df670dedb968.extra [task 2018-02-02T22:33:38.992Z] 22:33:38 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ libc-2.23.so + 0xfb74d]
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.