Closed Bug 1559161 Opened 7 years ago Closed 1 year ago

Intermittent /encrypted-media/clearkey-mp4-playback-temporary-multikey-sequential.https.html | org.w3.clearkey, successful playback, temporary, mp4, multiple keys, sequential - assert_equals: It should require all keys to reach end of content

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [retriggered][stockwell disabled])

Attachments

(1 file)

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


13:46:05 INFO - TEST-START | /encrypted-media/clearkey-mp4-playback-temporary-multikey-sequential.https.html
13:46:05 INFO - Closing window 34359738369
13:46:05 INFO - PID 5244 | [GMP 5812, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:46:05 INFO - PID 5244 | [GMP 5812, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/c[Parent 4188, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:46:05 INFO - PID 5244 | [Child 4504, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:46:08 INFO -
13:46:08 INFO - TEST-UNEXPECTED-FAIL | /encrypted-media/clearkey-mp4-playback-temporary-multikey-sequential.https.html | org.w3.clearkey, successful playback, temporary, mp4, multiple keys, sequential - assert_equals: It should require all keys to reach end of content expected 2 but got 1
13:46:08 INFO - onTimeupdate@https://web-platform.test:8443/encrypted-media/scripts/playback-temporary-multikey-sequential.js:95:17
13:46:08 INFO - Test.prototype.step@https://web-platform.test:8443/resources/testharness.js:1594:25
13:46:08 INFO - Test.prototype.step_func/<@https://web-platform.test:8443/resources/testharness.js:1618:35
13:46:08 INFO - TEST-OK | /encrypted-media/clearkey-mp4-playback-temporary-multikey-sequential.https.html | took 2141ms
13:46:08 INFO - PID 5244 | [Child 4504, Chrome_ChildThread] WARNING: pipe error: 109: 1560433568022 Marionette INFO Stopped listening on port 2828
13:46:08 INFO - PID 5244 | [GMP 2652, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:46:08 INFO - PID 5244 | [Child 5236, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:46:08 INFO - PID 5244 | [Child 5236, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:46:08 INFO - PID 5244 | [Child 5268, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:46:08 INFO - PID 5244 | [Child 5268, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:46:08 INFO - PID 5244 | [Parent 4188, Gecko_IOThread] WARNING: file z:/task_1560419820/build/src/ipc/chromium/src/base/process_util_win.cc, line 160
13:46:08 INFO - PID 5244 | [Child 2556, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:46:08 INFO - PID 5244 | [Child 2556, Chrome_ChildThread] WARNING: pipe error[Parent 4188, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:46:08 INFO - PID 5244 | [Child 3240, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:46:08 INFO - PID 5244 | [Child 3240, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1560419820/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
13:46:08 INFO - Browser exited with return code 0
13:46:08 INFO - PROCESS LEAKS None
13:46:08 INFO - Closing logging queue
13:46:08 INFO - queue closed
13:46:08 INFO - Setting up ssl
13:46:08 INFO - certutil |
13:46:08 INFO - certutil |
13:46:08 INFO - certutil |
13:46:08 INFO - Certificate Nickname Trust Attributes
13:46:08 INFO - SSL,S/MIME,JAR/XPI
13:46:08 INFO -
13:46:08 INFO - web-platform-tests CT,,
13:46:08 INFO -
13:46:08 INFO - Application command: Z:\task_1560427614\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile c:\users\task_1560427614\appdata\local\temp\tmpltfagi.mozrunner
13:46:08 INFO - Starting runner
13:46:09 INFO - PID 3896 | 1560433569265 addons.xpi WARN Failed to parse recommendation: TypeError: NetworkError when attempting to fetch resource.(resource://gre/modules/addons/XPIProvider.jsm:200:15) JS Stack trace: awaitPromise@XPIProvider.jsm:200:15
13:46:09 INFO - PID 3896 | syncLoadManifest@XPIInstall.jsm:685:22
13:46:09 INFO - PID 3896 | addMetadata@XPIDatabase.jsm:2437:32
13:46:09 INFO - PID 3896 | processFileChanges@XPIDatabase.jsm:2803:26
13:46:09 INFO - PID 3896 | checkForChanges@XPIProvider.jsm:2728:55
13:46:09 INFO - PID 3896 | startup@XPIProvider.jsm:2265:12
13:46:09 INFO - PID 3896 | callProvider@AddonManager.jsm:193:31
13:46:09 INFO - PID 3896 | _startProvider@AddonManager.jsm:569:5
13:46:09 INFO - PID 3896 | startup@AddonManager.jsm:725:14
13:46:09 INFO - PID 3896 | startup@AddonManager.jsm:2797:26
13:46:09 INFO - PID 3896 | observe@addonManager.js:71:29
13:46:09 INFO - PID 3896 | 1560433569330 addons.xpi WARN Failed to parse recommendation: TypeError: NetworkError when attempting to fetch resource.(resource://gre/modules/addons/XPIProvider.jsm:200:15) JS Stack trace: awaitPromise@XPIProvider.jsm:200:15
13:46:09 INFO - PID 3896 | syncLoadManifest@XPIInstall.jsm:685:22
13:46:09 INFO - PID 3896 | addMetadata@XPIDatabase.jsm:2437:32
13:46:09 INFO - PID 3896 | processFileChanges@XPIDatabase.jsm:2803:26
13:46:09 INFO - PID 3896 | checkForChanges@XPIProvider.jsm:2728:55
13:46:09 INFO - PID 3896 | startup@XPIProvider.jsm:2265:12
13:46:09 INFO - PID 3896 | callProvider@AddonManager.jsm:193:31
13:46:09 INFO - PID 3896 | _startProvider@AddonManager.jsm:569:5
13:46:09 INFO - PID 3896 | startup@AddonManager.jsm:725:14
13:46:09 INFO - PID 3896 | startup@AddonManager.jsm:2797:26
13:46:09 INFO - PID 3896 | observe@addonManager.js:71:29
13:46:09 INFO - PID 3896 | 1560433569346 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
13:46:09 INFO - PID 3896 | 1560433569346 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
13:46:09 INFO - PID 3896 | 1560433569346 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
13:46:09 INFO - PID 3896 | 1560433569346 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
13:46:09 INFO - PID 3896 | 1560433569403 addons.xpi WARN Failed to parse recommendation: TypeError: NetworkError when attempting to fetch resource.(resource://gre/modules/addons/XPIProvider.jsm:200:15) JS Stack trace: awaitPromise@XPIProvider.jsm:200:15
13:46:09 INFO - PID 3896 | syncLoadManifest@XPIInstall.jsm:685:22
13:46:09 INFO - PID 3896 | addMetadata@XPIDatabase.jsm:2437:32
13:46:09 INFO - PID 3896 | processFileChanges@XPIDatabase.jsm:2803:26
13:46:09 INFO - PID 3896 | checkForChanges@XPIProvider.jsm:2728:55
13:46:09 INFO - PID 3896 | startup@XPIProvider.jsm:2265:12
13:46:09 INFO - PID 3896 | callProvider@AddonManager.jsm:193:31
13:46:09 INFO - PID 3896 | _startProvider@AddonManager.jsm:569:5
13:46:09 INFO - PID 3896 | startup@AddonManager.jsm:725:14
13:46:09 INFO - PID 3896 | startup@AddonManager.jsm:2797:26
13:46:09 INFO - PID 3896 | observe@addonManager.js:71:29
13:46:09 INFO - PID 3896 | 1560433569410 addons.xpi WARN Failed to parse recommendation: TypeError: NetworkError when attempting to fetch resource.(resource://gre/modules/addons/XPIProvider.jsm:200:15) JS Stack trace: awaitPromise@XPIProvider.jsm:200:15
13:46:09 INFO - PID 3896 | syncLoadManifest@XPIInstall.jsm:685:22
13:46:09 INFO - PID 3896 | addMetadata@XPIDatabase.jsm:2437:32
13:46:09 INFO - PID 3896 | processFileChanges@XPIDatabase.jsm:2803:26
13:46:09 INFO - PID 3896 | checkForChanges@XPIProvider.jsm:2728:55
13:46:09 INFO - PID 3896 | startup@XPIProvider.jsm:2265:12
13:46:09 INFO - PID 3896 | callProvider@AddonManager.jsm:193:31
13:46:09 INFO - PID 3896 | _startProvider@AddonManager.jsm:569:5
13:46:09 INFO - PID 3896 | startup@AddonManager.jsm:725:14
13:46:09 INFO - PID 3896 | startup@AddonManager.jsm:2797:26
13:46:09 INFO - PID 3896 | observe@addonManager.js:71:29
13:46:09 INFO - PID 3896 | 1560433569417 addons.xpi WARN Failed to parse recommendation: TypeError: NetworkError when attempting to fetch resource.(resource://gre/modules/addons/XPIProvider.jsm:200:15) JS Stack trace: awaitPromise@XPIProvider.jsm:200:15
13:46:09 INFO - PID 3896 | syncLoadManifest@XPIInstall.jsm:685:22
13:46:09 INFO - PID 3896 | addMetadata@XPIDatabase.jsm:2437:32
13:46:09 INFO - PID 3896 | processFileChanges@XPIDatabase.jsm:2803:26
13:46:09 INFO - PID 3896 | checkForChanges@XPIProvider.jsm:2728:55
13:46:09 INFO - PID 3896 | startup@XPIProvider.jsm:2265:12
13:46:09 INFO - PID 3896 | callProvider@AddonManager.jsm:193:31
13:46:09 INFO - PID 3896 | _startProvider@AddonManager.jsm:569:5
13:46:09 INFO - PID 3896 | startup@AddonManager.jsm:725:14
13:46:09 INFO - PID 3896 | startup@AddonManager.jsm:2797:26
13:46:09 INFO - PID 3896 | observe@addonManager.js:71:29
13:46:10 INFO - PID 3896 | 1560433570306 addons.xpi WARN Failed to parse recommendation: TypeError: aPackage.readString is not a function(resource://gre/modules/addons/XPIInstall.jsm:539:52) JS Stack trace: readRecommendationStates@XPIInstall.jsm:539:52
13:46:10 INFO - PID 3896 | loadManifest@XPIInstall.jsm:636:39
13:46:10 INFO - PID 3896 | 1560433570451 addons.xpi WARN Failed to parse recommendation: TypeError: aPackage.readString is not a function(resource://gre/modules/addons/XPIInstall.jsm:539:52) JS Stack trace: readRecommendationStates@XPIInstall.jsm:539:52
13:46:10 INFO - PID 3896 | loadManifest@XPIInstall.jsm:636:39
13:46:10 INFO - PID 3896 | 1560433570479 addons.xpi WARN Failed to parse recommendation: TypeError: aPackage.readString is not a function(resource://gre/modules/addons/XPIInstall.jsm:539:52) JS Stack trace: readRecommendationStates@XPIInstall.jsm:539:52
13:46:10 INFO - PID 3896 | loadManifest@XPIInstall.jsm:636:39
13:46:10 INFO - PID 3896 | 1560433570507 addons.xpi WARN Failed to parse recommendation: TypeError: aPackage.readString is not a function(resource://gre/modules/addons/XPIInstall.jsm:539:52) JS Stack trace: readRecommendationStates@XPIInstall.jsm:539:52
13:46:10 INFO - PID 3896 | loadManifest@XPIInstall.jsm:636:39
13:46:10 INFO - PID 3896 | 1560433570573 addons.xpi WARN Failed to parse recommendation: TypeError: aPackage.readString is not a function(resource://gre/modules/addons/XPIInstall.jsm:539:52) JS Stack trace: readRecommendationStates@XPIInstall.jsm:539:52
13:46:10 INFO - PID 3896 | loadManifest@XPIInstall.jsm:636:39
13:46:10 INFO - PID 3896 | 1560433570596 addons.xpi WARN Failed to parse recommendation: TypeError: aPackage.readString is not a function(resource://gre/modules/addons/XPIInstall.jsm:539:52) JS Stack trace: readRecommendationStates@XPIInstall.jsm:539:52
13:46:10 INFO - PID 3896 | loadManifest@XPIInstall.jsm:636:39
13:46:10 INFO - PID 3896 | 1560433570641 addons.xpi WARN Failed to parse recommendation: TypeError: aPackage.readString is not a function(resource://gre/modules/addons/XPIInstall.jsm:539:52) JS Stack trace: readRecommendationStates@XPIInstall.jsm:539:52
13:46:10 INFO - PID 3896 | loadManifest@XPIInstall.jsm:636:39
13:46:10 INFO - PID 3896 | 1560433570759 Marionette INFO Listening on port 2828
13:46:10 INFO - TEST-START | /encrypted-media/clearkey-mp4-playback-temporary-multikey.https.html
13:46:10 INFO - Setting pref dom.security.featurePolicy.enabled (true)
13:46:10 INFO - Setting pref dom.security.featurePolicy.header.enabled (true)
13:46:10 INFO - Setting pref dom.security.featurePolicy.webidl.enabled (true)
13:46:13 INFO - .
13:46:13 INFO - TEST-OK | /encrypted-media/clearkey-mp4-playback-temporary-multikey.https.html | took 2187ms

In the last 7 days, there have been 35 failures. The failures are on windows10-64, windows10-64-shippable and windows7-32-shippable platforms. The affected build types are: opt, asan and debug.

An example of a recent log file: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=253208133&repo=mozilla-inbound&lineNumber=3380

And the relevant part of the log:
00:04:31 INFO - TEST-START | /encrypted-media/clearkey-mp4-playback-temporary-multikey-sequential.https.html
00:04:31 INFO - Closing window 36507222017
00:04:31 INFO - PID 5276 | [Child 3676, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1561407319/build/src/ipc/chromium/src/chrome/common/ipc_cha[[Child 5124, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1561407319/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
00:04:31 INFO - PID 5276 | [Child 5124, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1561407319/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
00:04:33 INFO -
00:04:33 INFO - TEST-UNEXPECTED-FAIL | /encrypted-media/clearkey-mp4-playback-temporary-multikey-sequential.https.html | org.w3.clearkey, successful playback, temporary, mp4, multiple keys, sequential - assert_equals: It should require all keys to reach end of content expected 2 but got 1
00:04:33 INFO - onTimeupdate@https://web-platform.test:8443/encrypted-media/scripts/playback-temporary-multikey-sequential.js:95:17
00:04:33 INFO - Test.prototype.step@https://web-platform.test:8443/resources/testharness.js:1594:25
00:04:33 INFO - Test.prototype.step_func/<@https://web-platform.test:8443/resources/testharness.js:1618:35
00:04:33 INFO - EventListener.handleEventrunTest/</<@https://web-platform.test:8443/encrypted-media/scripts/playback-temporary-multikey-sequential.js:110:20
00:04:33 INFO - promise callback
runTest/<@https://web-platform.test:8443/encrypted-media/scripts/playback-temporary-multikey-sequential.js:107:12
00:04:33 INFO - Test.prototype.step@https://web-platform.test:8443/resources/testharness.js:1594:25
00:04:33 INFO - async_test@https://web-platform.test:8443/resources/testharness.js:576:22
00:04:33 INFO - runTest@https://web-platform.test:8443/encrypted-media/scripts/playback-temporary-multikey-sequential.js:17:5
00:04:33 INFO - @https://web-platform.test:8443/encrypted-media/clearkey-mp4-playback-temporary-multikey-sequential.https.html:51:9
00:04:33 INFO - TEST-OK | /encrypted-media/clearkey-mp4-playback-temporary-multikey-sequential.https.html | took 2163ms
00:04:33 INFO - PID 5276 | 1561421073807 Marionette INFO Stopped listening on port 49436
00:04:33 INFO - PID 5276 | [GMP 592[Parent 5976, Gecko_IOThread] WARNING: pipe error: 109: file z:/task_1561407319/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
00:04:33 INFO - PID 5276 | [Child 4272, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1561407319/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
00:04:33 INFO - PID 5276 | [Child 4272, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1561407319/build/src[Child 4540, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_156140[Child 4688, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1561407319/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
00:04:34 INFO - PID 5276 | [Child 4688, Chrome_ChildThread] WARNING: pipe error: 109: file z:/task_1561407319/build/src/ipc/chromiu[Child 3156,
00:04:34 INFO - Browser exited with return code 0
00:04:34 INFO - PROCESS LEAKS None
00:04:34 INFO - Closing logging queue
00:04:34 INFO - queue closed
00:04:34 INFO - Setting up ssl
00:04:34 INFO - certutil |
00:04:34 INFO - certutil |
00:04:34 INFO - certutil |
00:04:34 INFO - Certificate Nickname Trust Attributes
00:04:34 INFO - SSL,S/MIME,JAR/XPI
00:04:34 INFO -
00:04:34 INFO - web-platform-tests CT,,
00:04:34 INFO -
00:04:34 INFO - Application command: Z:\task_1561418141\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile c:\users\task_1561418141\appdata\local\temp\tmprf8uh3.mozrunner
00:04:34 INFO - Starting runner
00:04:35 INFO - PID 4496 | 1561421075174 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
00:04:35 INFO - PID 4496 | 1561421075175 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
00:04:35 INFO - PID 4496 | 1561421075175 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
00:04:35 INFO - PID 4496 | 1561421075175 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
00:04:37 INFO - PID 4496 | 1561421077019 Marionette INFO Listening on port 49436

Nils, can you please take a look at this?

Flags: needinfo?(drno)
Whiteboard: [retriggered] → [retriggered][stockwell needswork:owner]

There are 49 failures in the last 7 days on windows10-64 debug & opt, windows10-64-shippable, windows7-32 opt & debug and windows7-32-shippable: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-06-26&endday=2019-07-03&tree=trunk&bug=1559161

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

In the last 7 days there were 73 failures associated with this bug: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-07-05&endday=2019-07-12&tree=all&bug=1559161
Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=256151178&repo=autoland&lineNumber=25258
Occurs on windows10-64, windows10-64-shippable, windows7-32, windows7-32-devedition build type.

Given that this has now reached the disable recommended bugs list and Nils hasn't responded to the ni for 18 days I went ahead and made the disable patch for this.

Pushed by csabou@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/7709e79a8f85 Disable clearkey-mp4-playback-temporary-multikey-sequential.https.html test on windows for frequent failures. r=jmaher
Keywords: leave-open
Whiteboard: [retriggered][stockwell disable-recommended] → [retriggered][stockwell disabled]
Severity: normal → S4
Flags: needinfo?(drno)
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: