1450179 - Intermittent TEST-UNEXPECTED-TIMEOUT | /encrypted-media/clearkey-events-session-closed-event.https.html | org.w3.clearkey test MediaKeySession closed event. - Test timed out

Status: RESOLVED INCOMPLETE

(Core :: Audio/Video: Playback, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=171138592&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/JFAnXiJGQf2urElE8HYM1g/runs/0/artifacts/public/logs/live_backing.log

23:31:10     INFO - TEST-START | /encrypted-media/clearkey-events-session-closed-event.https.html
23:31:10     INFO - PID 8340 | ++DOCSHELL 000002900EBCD800 == 4 [pid = 8600] [id = {2951b85c-c9bf-4cbd-ae08-1bf64ffc9a7e}]
23:31:10     INFO - PID 8340 | ++DOMWINDOW == 12 (000002900F267800) [pid = 8600] [serial = 12] [outer = 0000000000000000]
23:31:10     INFO - PID 8340 | ++DOMWINDOW == 13 (000002900FB33000) [pid = 8600] [serial = 13] [outer = 000002900F267800]
23:31:10     INFO - PID 8340 | ++DOMWINDOW == 14 (000002900FB31C00) [pid = 8600] [serial = 14] [outer = 000002900F267800]
23:31:14     INFO - PID 8340 | --DOCSHELL 0000012F1F7D7800 == 4 [pid = 8340] [id = {3ccba6f5-f5fb-42a6-b386-4404d87459d9}]
23:31:15     INFO - PID 8340 | --DOCSHELL 0000012F22ECF800 == 3 [pid = 8340] [id = {ca34cb01-023f-4953-8ccc-ca818dbd76a1}]
23:31:17     INFO - PID 8340 | --DOCSHELL 000001E062318000 == 0 [pid = 8348] [id = {54d75ee2-9ff7-401f-a778-e303ff8924e0}]
23:31:17     INFO - PID 8340 | --DOCSHELL 000002900DDE5000 == 3 [pid = 8600] [id = {bcc948f6-5c31-4870-974b-fb0a025a69b2}]
23:31:23     INFO - PID 8340 | --DOMWINDOW == 10 (0000012F1EF44000) [pid = 8340] [serial = 4] [outer = 0000000000000000] [url = about:blank]
23:31:23     INFO - PID 8340 | --DOMWINDOW == 9 (0000012F2262FC00) [pid = 8340] [serial = 9] [outer = 0000000000000000] [url = about:blank]
23:31:25     INFO - PID 8340 | --DOMWINDOW == 2 (000001E062ABDC00) [pid = 8348] [serial = 2] [outer = 0000000000000000] [url = about:blank]
23:31:25     INFO - PID 8340 | --DOMWINDOW == 1 (000001E062ABC800) [pid = 8348] [serial = 1] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanitytest.html]
23:31:25     INFO - PID 8340 | --DOMWINDOW == 13 (000002900C1CAC00) [pid = 8600] [serial = 2] [outer = 0000000000000000] [url = about:blank]
23:31:25     INFO - PID 8340 | --DOMWINDOW == 12 (000002900C1D3400) [pid = 8600] [serial = 3] [outer = 0000000000000000] [url = about:blank]
23:31:25     INFO - PID 8340 | --DOMWINDOW == 11 (000002900E29A000) [pid = 8600] [serial = 4] [outer = 0000000000000000] [url = http://web-platform.test:8000/testharness_runner.html]
23:31:25     INFO - PID 8340 | --DOMWINDOW == 10 (000002900EB88400) [pid = 8600] [serial = 7] [outer = 0000000000000000] [url = about:blank]
23:31:25     INFO - PID 8340 | --DOMWINDOW == 9 (000002900F25E800) [pid = 8600] [serial = 10] [outer = 0000000000000000] [url = about:blank]
23:31:25     INFO - PID 8340 | --DOMWINDOW == 8 (000002900FB33000) [pid = 8600] [serial = 13] [outer = 0000000000000000] [url = about:blank]
23:31:25     INFO - PID 8340 | --DOMWINDOW == 7 (000002900EB87C00) [pid = 8600] [serial = 6] [outer = 0000000000000000] [url = https://web-platform.test:8443/encrypted-media/clearkey-check-initdata-type.https.html]
23:31:27     INFO - PID 8340 | --DOMWINDOW == 0 (000001E062AC6800) [pid = 8348] [serial = 3] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanitytest.html]
23:31:27     INFO - PID 8340 | --DOMWINDOW == 8 (0000012F244EF000) [pid = 8340] [serial = 10] [outer = 0000000000000000] [url = about:blank]
23:31:27     INFO - PID 8340 | [Parent 8340, StreamTrans #7] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/modules/libjar/nsJARChannel.cpp, line 428
23:31:27     INFO - PID 8340 | --DOMWINDOW == 6 (000002900EB88000) [pid = 8600] [serial = 8] [outer = 0000000000000000] [url = about:blank]
23:31:40     INFO - 
23:31:40     INFO - TEST-UNEXPECTED-TIMEOUT | /encrypted-media/clearkey-events-session-closed-event.https.html | org.w3.clearkey test MediaKeySession closed event. - Test timed out
23:31:40     INFO - TEST-UNEXPECTED-TIMEOUT | /encrypted-media/clearkey-events-session-closed-event.https.html | expected OK
23:31:40     INFO - TEST-INFO took 30488ms
23:31:40     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: Unable to terminate process: 5: file z:/build/build/src/ipc/chromium/src/base/process_util_win.cc, line 440
23:31:40     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Child 8600, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Child 8600, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | 
23:31:41     INFO - PID 8340 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
23:31:41     INFO - PID 8340 | 
23:31:41     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Child 8600, Main Thread] WARNING: NS_ENSURE_TRUE(maybeContext) failed: file z:/build/build/src/xpcom/threads/nsThread.cpp, line 809
23:31:41     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Child 8600, Main Thread] WARNING: '!gThread', file z:/build/build/src/xpcom/threads/nsTimerImpl.cpp, line 399
23:31:41     INFO - PID 8340 | [Child 8600, Main Thread] WARNING: '!gThread', file z:/build/build/src/xpcom/threads/nsTimerImpl.cpp, line 399
23:31:41     INFO - PID 8340 | [Child 8600, Main Thread] WARNING: '!gThread', file z:/build/build/src/xpcom/threads/nsTimerImpl.cpp, line 399
23:31:41     INFO - PID 8340 | [Child 8600, Main Thread] WARNING: '!gThread', file z:/build/build/src/xpcom/threads/nsTimerImpl.cpp, line 399
23:31:41     INFO - PID 8340 | [Child 8600, Main Thread] WARNING: '!gThread', file z:/build/build/src/xpcom/threads/nsTimerImpl.cpp, line 399
23:31:41     INFO - PID 8340 | [Child 8600, Main Thread] WARNING: '!gThread', file z:/build/build/src/xpcom/threads/nsTimerImpl.cpp, line 399
23:31:41     INFO - PID 8340 | [Child 8600, Main Thread] WARNING: '!gThread', file z:/build/build/src/xpcom/threads/nsTimerImpl.cpp, line 399
23:31:41     INFO - PID 8340 | [Child 8600, Main Thread] WARNING: '!gThread', file z:/build/build/src/xpcom/threads/nsTimerImpl.cpp, line 399
23:31:41     INFO - PID 8340 | --DOCSHELL 0000012F21D09800 == 2 [pid = 8340] [id = {f78ef251-9a0d-4018-8f8c-19e94f4db99a}]
23:31:41     INFO - PID 8340 | --DOCSHELL 0000012F2269A800 == 1 [pid = 8340] [id = {93deeb96-3bda-4e90-af6f-eed63b760ec5}]
23:31:41     INFO - PID 8340 | --DOCSHELL 0000012F1F080000 == 0 [pid = 8340] [id = {2b5a2cc5-3b17-4794-85dd-08fee5622e75}]
23:31:41     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Child 8348, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Child 8348, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | 
23:31:41     INFO - PID 8340 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
23:31:41     INFO - PID 8340 | 
23:31:41     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
23:31:41     INFO - PID 8340 | [Child 8348, Main Thread] WARNING: NS_ENSURE_TRUE(maybeContext) failed: file z:/build/build/src/xpcom/threads/nsThread.cpp, line 809
23:31:41     INFO - PID 8340 | [Parent 8340, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346

Comment 1

[Intermittent Failures Robot]

9 failures in 718 pushes (0.013 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 6
* autoland: 2
* mozilla-central: 1

Platform breakdown:
* windows10-64: 9

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1450179&startday=2018-03-26&endday=2018-04-01&tree=trunk

Comment 2

[Intermittent Failures Robot]

8 failures in 765 pushes (0.01 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* autoland: 4
* mozilla-inbound: 3
* mozilla-central: 1

Platform breakdown:
* windows10-64: 8

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1450179&startday=2018-04-02&endday=2018-04-08&tree=trunk

Comment 3

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 4

[Andreea Pavel [:apavel]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=189198903&repo=autoland&lineNumber=7808

[task 2018-07-20T15:06:18.247Z] 15:06:18     INFO - TEST-START | /encrypted-media/clearkey-events-session-closed-event.https.html
[task 2018-07-20T15:06:18.814Z] 15:06:18     INFO - PID 11456 | 1532099178801	Marionette	DEBUG	[2147483660] Frame script loaded
[task 2018-07-20T15:06:18.815Z] 15:06:18     INFO - PID 11456 | 1532099178802	Marionette	DEBUG	[2147483660] Frame script registered
[task 2018-07-20T15:06:48.954Z] 15:06:48     INFO - 
[task 2018-07-20T15:06:48.954Z] 15:06:48     INFO - TEST-UNEXPECTED-TIMEOUT | /encrypted-media/clearkey-events-session-closed-event.https.html | org.w3.clearkey test MediaKeySession closed event. - Test timed out
[task 2018-07-20T15:06:48.954Z] 15:06:48     INFO - TEST-UNEXPECTED-TIMEOUT | /encrypted-media/clearkey-events-session-closed-event.https.html | expected OK

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1450179&startday=2018-07-16&endday=2018-07-22&tree=trunk

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1450179&startday=2018-07-23&endday=2018-07-29&tree=trunk

Comment 7

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup