1585231 - Intermittent toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Test timed out -

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

Filed by: aciure [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=269184682&repo=mozilla-inbound
Full log: https://queue.taskcluster.net/v1/task/G5n5-AnySA-WZXcm-8KGfA/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-10-01T02:36:05.271Z] 02:36:05 INFO - TEST-PASS | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | The tab should not be blocked -
[task 2019-10-01T02:36:05.271Z] 02:36:05 INFO - - open new background tab2, and tab2 should be blocked -
[task 2019-10-01T02:36:05.272Z] 02:36:05 INFO - Block state doens't match, wait for attributes changes.
[task 2019-10-01T02:36:05.272Z] 02:36:05 INFO - Console message: [JavaScript Error: "AbortError: The fetching process for the media resource was aborted by the user agent at the user's request."]
[task 2019-10-01T02:36:05.272Z] 02:36:05 INFO - Buffered messages finished
[task 2019-10-01T02:36:05.272Z] 02:36:05 INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Test timed out -
[task 2019-10-01T02:36:05.272Z] 02:36:05 INFO - GECKO(2178) | MEMORY STAT | vsize 7695MB | residentFast 385MB | heapAllocated 119MB
[task 2019-10-01T02:36:05.272Z] 02:36:05 INFO - TEST-OK | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | took 45099ms
[task 2019-10-01T02:36:05.272Z] 02:36:05 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-01T02:36:05.272Z] 02:36:05 INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Found a tab after previous test timed out: https://example.com/browser/toolkit/content/tests/browser/file_blockMedia_shouldPlay.html -
[task 2019-10-01T02:36:05.272Z] 02:36:05 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-10-01T02:36:05.273Z] 02:36:05 INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Found a tab after previous test timed out: https://example.com/browser/toolkit/content/tests/browser/file_blockMedia_shouldNotPlay.html -
[task 2019-10-01T02:36:05.273Z] 02:36:05 INFO - checking window state
[task 2019-10-01T02:36:05.273Z] 02:36:05 INFO - GECKO(2178) | JavaScript error: resource://gre/actors/AudioPlaybackParent.jsm, line 19: TypeError: browser is null
[task 2019-10-01T02:36:05.273Z] 02:36:05 INFO - GECKO(2178) | JavaScript error: , line 0: NS_ERROR_UNEXPECTED:

Comment 1

[Intermittent Failures Robot]

19 failures in 4292 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-inbound: 2
• autoland: 12
• mozilla-central: 3
• try: 2

Platform breakdown:

• macosx1014-64-shippable: 7
• macosx1014-64: 7
• linux64-shippable: 2
• linux64: 1
• linux64-devedition: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-09-30&endday=2019-10-06&tree=all

Comment 2

[Intermittent Failures Robot]

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

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 32
• mozilla-central: 4
• try: 2

Platform breakdown:

• macosx1014-64: 13
• linux64-shippable: 13
• linux64: 4
• macosx1014-64-shippable: 7
• macosx1014-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-10-07&endday=2019-10-13&tree=all

Comment 3

[Intermittent Failures Robot]

19 failures in 3964 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 15
• try: 2
• mozilla-central: 2

Platform breakdown:

• macosx1014-64: 12
• linux64: 1
• macosx1014-64-shippable: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-10-14&endday=2019-10-20&tree=all

Comment 4

[Byron Campen [:bwc]]

Seeing a number of these browser_delay_autoplay* tests failing right now, any idea what might be happening?

Comment 5

[Byron Campen [:bwc]]

Bug 1377626 might be a duplicate of this (this is tracking the old name of this test).

Comment 6

[Intermittent Failures Robot]

23 failures in 4182 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 22
• mozilla-beta: 1

Platform breakdown:

• macosx1014-64: 8
• macosx1014-64-shippable: 13
• linux64-shippable: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-10-21&endday=2019-10-27&tree=all

Comment 7

[Cristina Coroiu [:ccoroiu]]

Started retriggers:
https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=OS%2CX%2C10.14%2CShippable%2Copt%2CMochitests%2Ctest-macosx1014-64-shippable%2Fopt-mochitest-browser-chrome-e10s-4%2CM%28bc4%29&tochange=0670f00093b2e0e334f759eb2cd035be9e6707d3&fromchange=67df5a390da4d47be169c54f4af7674722a6d1d9&selectedJob=274249259

Comment 8

[Intermittent Failures Robot]

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

This is the #41 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 39
• try: 3
• mozilla-inbound: 1
• mozilla-beta: 1
• mozilla-central: 1

Platform breakdown:

• macosx1014-64-shippable: 7
• linux64: 24
• linux64-shippable: 2
• macosx1014-64: 11
• linux64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-10-28&endday=2019-11-03&tree=all

Comment 9

[Alastor Wu [:alwu]]

I have added some debug logs and pushed them to the try server, trying to figure out what's happen.

Comment 10

[Intermittent Failures Robot]

45 failures in 4850 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

This is the #38 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-beta: 2
• autoland: 36
• try: 4
• mozilla-central: 3

Platform breakdown:

• macosx1014-64: 8
• macosx1014-64-shippable: 10
• linux64: 26
• macosx1014-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-11-04&endday=2019-11-10&tree=all

Comment 11

[Intermittent Failures Robot]

50 failures in 820 pushes (0.061 failures/push) were associated with this bug yesterday.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 33
• mozilla-central: 17

Platform breakdown:

• windows10-64-qr: 35
• macosx1014-64: 2
• windows10-64: 13

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-11-13&endday=2019-11-13&tree=all

Comment 12

[Andreea Pavel [:apavel]]

Attachment: Bug 1585231 - disable browser_delay_autoplay_media_pausedAfterPlay.js on fission r=gbrown

Comment 13

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/4466d109fc51
disable browser_delay_autoplay_media_pausedAfterPlay.js on fission r=mccr8

Comment 14

[Arthur Iakab [arthur_iakab]]

https://hg.mozilla.org/mozilla-central/rev/4466d109fc51

Comment 15

[Intermittent Failures Robot]

77 failures in 4207 pushes (0.018 failures/push) were associated with this bug in the last 7 days.

This is the #15 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• mozilla-central: 23
• autoland: 51
• try: 1
• mozilla-beta: 1
• mozilla-inbound: 1

Platform breakdown:

• macosx1014-64: 7
• macosx1014-64-shippable: 13
• windows10-64: 17
• linux64: 1
• windows10-64-qr: 39

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-11-11&endday=2019-11-17&tree=all

Comment 16

[Bogdan Tara[:bogdan_tara | bogdant]]

Seems to have started to perma fail on tier 2 fission with the changes from bug 1576946.

https://treeherder.mozilla.org/#/jobs?repo=autoland&fromchange=d1ea0724b6caa33111426ec69a7e288cfde79dee&searchStr=windows%2C10%2Cx64%2Cquantumrender%2Copt%2Cmochitests%2Cwith%2Cfission%2Cenabled%2Ctest-windows10-64-qr%2Fopt-mochitest-browser-chrome-fis-e10s&tochange=138b1dd63e101b22e3b3aa008516efe543e1dcc9&selectedJob=276847121

Comment 17

[Intermittent Failures Robot]

26 failures in 5444 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 5
• mozilla-beta: 2
• autoland: 18
• mozilla-central: 1

Platform breakdown:

• linux64-shippable: 1
• macosx1014-64: 13
• macosx1014-64-shippable: 12

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-11-18&endday=2019-11-24&tree=all

Comment 18

[Andreea Pavel [:apavel]]

Since this got disabled on fission it's failing on mochitest-browser-chrome-e10s tests.

There are 22 total failures in the last 7 days on macosx1014-64 debug and macosx1014-64-shippable opt.

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

[task 2019-11-26T22:42:46.517Z] 22:42:46 INFO - TEST-START | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js
[task 2019-11-26T22:42:47.019Z] 22:42:47 INFO - GECKO(2289) | JavaScript error: , line 0: AbortError: The fetching process for the media resource was aborted by the user agent at the user's request.
[task 2019-11-26T22:43:31.529Z] 22:43:31 INFO - TEST-INFO | started process screencapture
[task 2019-11-26T22:43:31.683Z] 22:43:31 INFO - TEST-INFO | screencapture: exit 0
[task 2019-11-26T22:43:31.683Z] 22:43:31 INFO - Buffered messages logged at 22:42:46
[task 2019-11-26T22:43:31.683Z] 22:43:31 INFO - Entering test bound setup_test_preference
[task 2019-11-26T22:43:31.683Z] 22:43:31 INFO - Leaving test bound setup_test_preference
[task 2019-11-26T22:43:31.684Z] 22:43:31 INFO - Entering test bound block_autoplay_media
[task 2019-11-26T22:43:31.684Z] 22:43:31 INFO - - open new background tab1, and tab1 should not be blocked -
[task 2019-11-26T22:43:31.684Z] 22:43:31 INFO - TEST-PASS | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | The tab should not be blocked -
[task 2019-11-26T22:43:31.684Z] 22:43:31 INFO - - open new background tab2, and tab2 should be blocked -
[task 2019-11-26T22:43:31.684Z] 22:43:31 INFO - Block state doens't match, wait for attributes changes.
[task 2019-11-26T22:43:31.684Z] 22:43:31 INFO - Buffered messages logged at 22:42:47
[task 2019-11-26T22:43:31.684Z] 22:43:31 INFO - Console message: [JavaScript Error: "AbortError: The fetching process for the media resource was aborted by the user agent at the user's request."]
[task 2019-11-26T22:43:31.685Z] 22:43:31 INFO - Buffered messages finished
[task 2019-11-26T22:43:31.685Z] 22:43:31 INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Test timed out -
[task 2019-11-26T22:43:31.685Z] 22:43:31 INFO - GECKO(2289) | MEMORY STAT | vsize 7662MB | residentFast 378MB | heapAllocated 119MB
[task 2019-11-26T22:43:31.685Z] 22:43:31 INFO - TEST-OK | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | took 45034ms
[task 2019-11-26T22:43:31.685Z] 22:43:31 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-11-26T22:43:31.685Z] 22:43:31 INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Found a tab after previous test timed out: https://example.com/browser/toolkit/content/tests/browser/file_blockMedia_shouldPlay.html -
[task 2019-11-26T22:43:31.686Z] 22:43:31 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-11-26T22:43:31.686Z] 22:43:31 INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Found a tab after previous test timed out: https://example.com/browser/toolkit/content/tests/browser/file_blockMedia_shouldNotPlay.html -
[task 2019-11-26T22:43:31.686Z] 22:43:31 INFO - checking window state
[task 2019-11-26T22:43:31.686Z] 22:43:31 INFO - GECKO(2289) | JavaScript error: resource://gre/actors/AudioPlaybackParent.jsm, line 19: TypeError: browser is null
[task 2019-11-26T22:43:31.686Z] 22:43:31 INFO - GECKO(2289) | JavaScript error: , line 0: NS_ERROR_UNEXPECTED:

Alastor do you have any updates here? Are you still working on this?

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 8
• try: 2

Platform breakdown:

• macosx1014-64: 4
• macosx1014-64-shippable: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-11-25&endday=2019-12-01&tree=all

Comment 20

[Intermittent Failures Robot]

16 failures in 5144 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-release: 1
• autoland: 8
• mozilla-central: 2
• try: 4
• mozilla-beta: 1

Platform breakdown:

• macosx1014-64: 9
• macosx1014-64-shippable: 5
• windows10-64: 1
• windows10-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-12-02&endday=2019-12-08&tree=all

Comment 21

[Andreea Pavel [:apavel]]

There are 13 total failures in the last 7 days on mac platforms and debug and opt builds.

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

[task 2019-12-09T20:41:01.603Z] 20:41:01 INFO - GECKO(2009) | [Child 2017: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x14aa2f000) [pid = 2017] [serial = 33] [outer = 0x0] [url = https://example.com/browser/toolkit/content/tests/browser/file_multipleAudio.html]
[task 2019-12-09T20:41:02.177Z] 20:41:02 INFO - GECKO(2009) | [Child 2048: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x12862b400) [pid = 2048] [serial = 2] [outer = 0x0] [url = about:blank]
[task 2019-12-09T20:42:19.348Z] 20:42:19 INFO - TEST-INFO | started process screencapture
[task 2019-12-09T20:42:19.496Z] 20:42:19 INFO - TEST-INFO | screencapture: exit 0
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - Buffered messages logged at 20:40:49
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - Entering test bound setup_test_preference
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - Leaving test bound setup_test_preference
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - Entering test bound block_autoplay_media
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - - open new background tab1, and tab1 should not be blocked -
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - TEST-PASS | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | The tab should not be blocked -
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - - open new background tab2, and tab2 should be blocked -
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - Block state doens't match, wait for attributes changes.
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - Buffered messages logged at 20:40:50
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - Console message: [JavaScript Error: "AbortError: The fetching process for the media resource was aborted by the user agent at the user's request."]
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - Buffered messages finished
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Test timed out -
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - GECKO(2009) | MEMORY STAT | vsize 7675MB | residentFast 383MB | heapAllocated 129MB
[task 2019-12-09T20:42:19.499Z] 20:42:19 INFO - TEST-OK | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | took 90079ms
[task 2019-12-09T20:42:19.500Z] 20:42:19 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-12-09T20:42:19.500Z] 20:42:19 INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Found a tab after previous test timed out: https://example.com/browser/toolkit/content/tests/browser/file_blockMedia_shouldPlay.html -
[task 2019-12-09T20:42:19.500Z] 20:42:19 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-12-09T20:42:19.500Z] 20:42:19 INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Found a tab after previous test timed out: https://example.com/browser/toolkit/content/tests/browser/file_blockMedia_shouldNotPlay.html -
[task 2019-12-09T20:42:19.500Z] 20:42:19 INFO - GECKO(2009) | [Child 2019: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x10d23b800 == 1 [pid = 2019] [id = {782e7351-ea75-c84b-829d-162f8beee0c4}]
[task 2019-12-09T20:42:19.500Z] 20:42:19 INFO - GECKO(2009) | [Child 2019: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x114f5f020) [pid = 2019] [serial = 6] [outer = 0x0]
[task 2019-12-09T20:42:19.500Z] 20:42:19 INFO - GECKO(2009) | [Child 2019, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 847
[task 2019-12-09T20:42:19.501Z] 20:42:19 INFO - GECKO(2009) | [Child 2019: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x10f344800) [pid = 2019] [serial = 7] [outer = 0x114f5f020]
[task 2019-12-09T20:42:19.501Z] 20:42:19 INFO - GECKO(2009) | [Child 2019: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x10f34a800) [pid = 2019] [serial = 8] [outer = 0x114f5f020]
[task 2019-12-09T20:42:19.501Z] 20:42:19 INFO - checking window state
[task 2019-12-09T20:42:19.502Z] 20:42:19 INFO - GECKO(2009) | JavaScript error: resource://gre/actors/AudioPlaybackParent.jsm, line 19: TypeError: browser is null
[task 2019-12-09T20:42:19.502Z] 20:42:19 INFO - GECKO(2009) | [Parent 2009, Main Thread] WARNING: 'error.Failed()', file /builds/worker/workspace/build/src/dom/ipc/JSWindowActor.cpp, line 250
[task 2019-12-09T20:42:19.502Z] 20:42:19 INFO - GECKO(2009) | JavaScript error: , line 0: NS_ERROR_UNEXPECTED:

Nils can you assign someone to take a look?

Comment 22

[Alastor Wu [:alwu]]

Sorry, I was working on other stuffs recently, will go back to check this again later.

Comment 23

[Andreea Pavel [:apavel]]

Thank you.

Comment 24

[Intermittent Failures Robot]

21 failures in 4867 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 14
• mozilla-release: 1
• mozilla-central: 4
• mozilla-beta: 2

Platform breakdown:

• macosx1014-64-shippable: 11
• macosx1014-64: 5
• macosx1014-64-devedition: 1
• linux64-shippable: 3
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-12-09&endday=2019-12-15&tree=all

Comment 25

[Andreea Pavel [:apavel]]

Hi Alastor, are there updates here?

There are 29 total failures in the last 7 days on linux64, linux64-shippable, macosx debug and macosx-shippable opt

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=282320817&repo=mozilla-central&lineNumber=6402

[task 2019-12-22T10:50:02.708Z] 10:50:02 INFO - TEST-START | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js
[task 2019-12-22T10:50:03.118Z] 10:50:03 INFO - GECKO(12204) | JavaScript error: , line 0: AbortError: The fetching process for the media resource was aborted by the user agent at the user's request.
[task 2019-12-22T10:50:47.720Z] 10:50:47 INFO - TEST-INFO | started process screentopng
[task 2019-12-22T10:50:48.042Z] 10:50:48 INFO - TEST-INFO | screentopng: exit 0
[task 2019-12-22T10:50:48.043Z] 10:50:48 INFO - Buffered messages logged at 10:50:02
[task 2019-12-22T10:50:48.043Z] 10:50:48 INFO - Entering test bound setup_test_preference
[task 2019-12-22T10:50:48.044Z] 10:50:48 INFO - Leaving test bound setup_test_preference
[task 2019-12-22T10:50:48.045Z] 10:50:48 INFO - Entering test bound block_autoplay_media
[task 2019-12-22T10:50:48.046Z] 10:50:48 INFO - - open new background tab1, and tab1 should not be blocked -
[task 2019-12-22T10:50:48.046Z] 10:50:48 INFO - Buffered messages logged at 10:50:03
[task 2019-12-22T10:50:48.046Z] 10:50:48 INFO - TEST-PASS | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | The tab should not be blocked -
[task 2019-12-22T10:50:48.047Z] 10:50:48 INFO - - open new background tab2, and tab2 should be blocked -
[task 2019-12-22T10:50:48.047Z] 10:50:48 INFO - Block state doens't match, wait for attributes changes.
[task 2019-12-22T10:50:48.048Z] 10:50:48 INFO - Console message: [JavaScript Error: "AbortError: The fetching process for the media resource was aborted by the user agent at the user's request."]
[task 2019-12-22T10:50:48.048Z] 10:50:48 INFO - Buffered messages finished
[task 2019-12-22T10:50:48.049Z] 10:50:48 INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Test timed out -
[task 2019-12-22T10:50:48.049Z] 10:50:48 INFO - GECKO(12204) | MEMORY STAT | vsize 3280MB | residentFast 319MB | heapAllocated 112MB
[task 2019-12-22T10:50:48.050Z] 10:50:48 INFO - TEST-OK | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | took 45012ms
[task 2019-12-22T10:50:48.050Z] 10:50:48 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-12-22T10:50:48.051Z] 10:50:48 INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Found a tab after previous test timed out: https://example.com/browser/toolkit/content/tests/browser/file_blockMedia_shouldPlay.html -
[task 2019-12-22T10:50:48.051Z] 10:50:48 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-12-22T10:50:48.052Z] 10:50:48 INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_delay_autoplay_media_pausedAfterPlay.js | Found a tab after previous test timed out: https://example.com/browser/toolkit/content/tests/browser/file_blockMedia_shouldNotPlay.html -
[task 2019-12-22T10:50:48.053Z] 10:50:48 INFO - checking window state
[task 2019-12-22T10:50:48.053Z] 10:50:48 INFO - GECKO(12204) | JavaScript error: resource://gre/actors/AudioPlaybackParent.jsm, line 19: TypeError: browser is null
[task 2019-12-22T10:50:48.054Z] 10:50:48 INFO - GECKO(12204) | JavaScript error: , line 0: NS_ERROR_UNEXPECTED:

Comment 26

[Intermittent Failures Robot]

38 failures in 4582 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

This is the #38 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 26
• try: 9
• oak: 1
• mozilla-central: 2

Platform breakdown:

• macosx1014-64-shippable: 16
• linux64: 4
• macosx1014-64: 3
• linux64-shippable: 13
• linux64-devedition: 1
• macosx1014-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-12-16&endday=2019-12-22&tree=all

Comment 27

[Alastor Wu [:alwu]]

Attachment: Bug 1585231 - refactor test.

We remove file_blockMedia_shouldPlay.html and file_blockMedia_shouldNotPlay.html and do those operations in test directly, and make sure doing so after media fully loading.

Comment 28

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/20b3a68275d5
refactor test. r=bryce

Comment 29

[Noemi Erli[:noemi_erli]]

https://hg.mozilla.org/mozilla-central/rev/20b3a68275d5

Comment 30

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform breakdown:

• macosx1014-64-shippable: 2
• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-12-23&endday=2019-12-29&tree=all

Comment 31

[Alastor Wu [:alwu]]

If next week we still can't see any new failure, then I will close this bug. Add NI as a reminder.

Comment 32

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2019-12-30&endday=2020-01-05&tree=all

Comment 33

[Razvan Maries]

New occurrence: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=286794590&repo=autoland&lineNumber=45699

Comment 34

[Alastor Wu [:alwu]]

This failure is not as same as what we got before, from the log, it seems audio decoding failed which cause the fail.

audio.ogg could not be decoded, error: Error Code: NS_ERROR_DOM_MEDIA_MEDIASINK_ERR (0x806e000b)

Comment 35

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1585231&startday=2020-01-27&endday=2020-02-02&tree=all

Comment 36

[Chris Peterson [:cpeterson]]

Alastor, do you think we can re-enable this test on Fission again?

https://searchfox.org/mozilla-central/rev/0e09b9191c02097034e46b193930f91c45b7885d/toolkit/content/tests/browser/browser.ini#69-71

[browser_delay_autoplay_media_pausedAfterPlay.js]
skip-if = (os == "win" && processor == "aarch64") || fission # aarch64 due to 1536573, Bug 1585231

Comment 37

[Alastor Wu [:alwu]]

Yes, I think so, let see if it would fail or not on m-c first.
https://treeherder.mozilla.org/#/jobs?repo=try&revision=c910c733ae1b09221d092d91ef9d1ba8c1fc53e3

Comment 38

[Alastor Wu [:alwu]]

Attachment: Bug 1585231 - enable 'browser_delay_autoplay_media_pausedAfterPlay.js' when Fission is enabled.

Comment 39

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/789bdea432a2
enable 'browser_delay_autoplay_media_pausedAfterPlay.js' when Fission is enabled. r=bryce

Comment 40

[Alexandru Michis [:malexandru]]

https://hg.mozilla.org/mozilla-central/rev/789bdea432a2