Closed Bug 1550442 Opened 6 months ago Closed 4 months ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /html/semantics/embedded-content/media-elements/track/track-element/track-mode.html | TextTrack mode attribute - Test timed out

Categories

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

defect

Tracking

()

RESOLVED FIXED
mozilla70
Tracking Status
firefox-esr60 --- unaffected
firefox-esr68 --- fixed
firefox68 --- wontfix
firefox69 --- fixed
firefox70 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: alwu)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [wptsync upstream][stockwell disable-recommended])

Attachments

(2 files)

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


[task 2019-05-09T08:41:49.246Z] 08:41:49 INFO - TEST-START | /html/semantics/embedded-content/media-elements/track/track-element/track-mode.html
[task 2019-05-09T08:41:49.262Z] 08:41:49 INFO - Closing window 160
[task 2019-05-09T08:41:49.420Z] 08:41:49 INFO - PID 3531 | ++DOCSHELL 0xdd651800 == 25 [pid = 3531] [id = {4772dfd5-480a-4270-9a2a-dae4fec186eb}]
[task 2019-05-09T08:41:49.420Z] 08:41:49 INFO - PID 3531 | ++DOMWINDOW == 79 (0xe0257230) [pid = 3531] [serial = 163] [outer = (nil)]
[task 2019-05-09T08:41:49.428Z] 08:41:49 INFO - PID 3531 | ++DOMWINDOW == 80 (0xdd65ac00) [pid = 3531] [serial = 164] [outer = 0xe0257230]
[task 2019-05-09T08:41:49.489Z] 08:41:49 INFO - PID 3531 | ++DOMWINDOW == 81 (0xe08d6400) [pid = 3531] [serial = 165] [outer = 0xe0257230]
[task 2019-05-09T08:41:50.339Z] 08:41:50 INFO - PID 3531 | [3531, Main Thread] WARNING: Can't add a range if the end is older that the start.: file /builds/worker/workspace/build/src/dom/html/TimeRanges.cpp, line 73
[task 2019-05-09T08:41:50.339Z] 08:41:50 INFO - PID 3531 | [3531, Main Thread] WARNING: Can't add a range if the end is older that the start.: file /builds/worker/workspace/build/src/dom/html/TimeRanges.cpp, line 73
[task 2019-05-09T08:41:50.339Z] 08:41:50 INFO - PID 3531 | [3531, Main Thread] WARNING: Can't add a range if the end is older that the start.: file /builds/worker/workspace/build/src/dom/html/TimeRanges.cpp, line 73
[task 2019-05-09T08:41:50.339Z] 08:41:50 INFO - PID 3531 | [3531, Main Thread] WARNING: Can't add a range if the end is older that the start.: file /builds/worker/workspace/build/src/dom/html/TimeRanges.cpp, line 73
[task 2019-05-09T08:41:50.444Z] 08:41:50 INFO - PID 3531 | --DOCSHELL 0xdaaf4400 == 24 [pid = 3531] [id = {d90f8488-8574-4d15-9317-ff05b9d5633e}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-element-dom-change.html]
[task 2019-05-09T08:41:50.445Z] 08:41:50 INFO - PID 3531 | --DOCSHELL 0xdaaf4800 == 23 [pid = 3531] [id = {a34121d4-c792-463a-86de-ba8706727d8b}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-element-src-change-error.html]
[task 2019-05-09T08:41:50.469Z] 08:41:50 INFO - PID 3531 | --DOCSHELL 0xdaedc400 == 22 [pid = 3531] [id = {5ad736a6-c7e3-4f9a-8425-c0b7d9b59a9d}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-disabled.html]
[task 2019-05-09T08:41:50.470Z] 08:41:50 INFO - PID 3531 | --DOMWINDOW == 80 (0xdaed7400) [pid = 3531] [serial = 122] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:50.471Z] 08:41:50 INFO - PID 3531 | --DOMWINDOW == 79 (0xdaee1c00) [pid = 3531] [serial = 125] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:50.471Z] 08:41:50 INFO - PID 3531 | --DOMWINDOW == 78 (0xdaed3400) [pid = 3531] [serial = 119] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:50.487Z] 08:41:50 INFO - PID 3531 | --DOMWINDOW == 77 (0xdbc14000) [pid = 3531] [serial = 108] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:50.488Z] 08:41:50 INFO - PID 3531 | --DOMWINDOW == 76 (0xe0b56400) [pid = 3531] [serial = 111] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:50.488Z] 08:41:50 INFO - PID 3531 | --DOMWINDOW == 75 (0xdaafb000) [pid = 3531] [serial = 102] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:50.488Z] 08:41:50 INFO - PID 3531 | --DOMWINDOW == 74 (0xdaedc800) [pid = 3531] [serial = 105] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:50.490Z] 08:41:50 INFO - PID 3531 | --DOMWINDOW == 73 (0xdaafc000) [pid = 3531] [serial = 113] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:50.490Z] 08:41:50 INFO - PID 3531 | --DOMWINDOW == 72 (0xdaf68800) [pid = 3531] [serial = 116] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:50.490Z] 08:41:50 INFO - PID 3531 | --DOMWINDOW == 71 (0xdc982c00) [pid = 3531] [serial = 117] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:50.490Z] 08:41:50 INFO - PID 3531 | --DOMWINDOW == 70 (0xdaee0c00) [pid = 3531] [serial = 99] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:51.152Z] 08:41:51 INFO - PID 3531 | [3531, Main Thread] WARNING: Wrong inner/outer window combination!: file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 4804
[task 2019-05-09T08:41:51.152Z] 08:41:51 INFO - PID 3531 | [3531, Main Thread] WARNING: Wrong inner/outer window combination!: file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 4804
[task 2019-05-09T08:41:51.153Z] 08:41:51 INFO - PID 3531 | [3531, Main Thread] WARNING: Wrong inner/outer window combination!: file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 4804
[task 2019-05-09T08:41:51.153Z] 08:41:51 INFO - PID 3531 | [3531, Main Thread] WARNING: Wrong inner/outer window combination!: file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 4804
[task 2019-05-09T08:41:51.179Z] 08:41:51 INFO - PID 3531 | --DOMWINDOW == 69 (0xde33e020) [pid = 3531] [serial = 121] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-disabled-addcue.html]
[task 2019-05-09T08:41:51.179Z] 08:41:51 INFO - PID 3531 | --DOMWINDOW == 68 (0xe0256bd0) [pid = 3531] [serial = 124] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-disabled.html]
[task 2019-05-09T08:41:51.180Z] 08:41:51 INFO - PID 3531 | --DOMWINDOW == 67 (0xdb2deef0) [pid = 3531] [serial = 118] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-delete-during-setup.html]
[task 2019-05-09T08:41:51.183Z] 08:41:51 INFO - PID 3531 | --DOMWINDOW == 66 (0xe0256ac0) [pid = 3531] [serial = 133] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-element-src-change.html]
[task 2019-05-09T08:41:51.183Z] 08:41:51 INFO - PID 3531 | --DOMWINDOW == 65 (0xe0256570) [pid = 3531] [serial = 130] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-element-src-change-error.html]
[task 2019-05-09T08:41:51.185Z] 08:41:51 INFO - PID 3531 | --DOMWINDOW == 64 (0xe0256350) [pid = 3531] [serial = 127] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-element-dom-change.html]
[task 2019-05-09T08:41:51.185Z] 08:41:51 INFO - PID 3531 | --DOMWINDOW == 63 (0xe0256ce0) [pid = 3531] [serial = 136] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-id.html]
[task 2019-05-09T08:41:54.604Z] 08:41:54 INFO - PID 3531 | --DOMWINDOW == 62 (0xe0256f00) [pid = 3531] [serial = 139] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-insert-after-load.html]
[task 2019-05-09T08:41:55.044Z] 08:41:55 INFO - PID 3531 | --DOCSHELL 0xdbc16800 == 21 [pid = 3531] [id = {a5afd4fe-d382-4a8b-adff-5691c1878efd}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-load-from-element-readyState.html]
[task 2019-05-09T08:41:55.046Z] 08:41:55 INFO - PID 3531 | --DOCSHELL 0xdaaef400 == 20 [pid = 3531] [id = {d495c29b-ea32-431e-a27d-ff0903f01597}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-large-timestamp.html]
[task 2019-05-09T08:41:55.049Z] 08:41:55 INFO - PID 3531 | --DOCSHELL 0xdbf0a400 == 19 [pid = 3531] [id = {6b5070b7-51ac-4941-99d0-a2a77192d698}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-load-from-src-readyState.html]
[task 2019-05-09T08:41:55.049Z] 08:41:55 INFO - PID 3531 | --DOCSHELL 0xda9d4400 == 18 [pid = 3531] [id = {b144e2f3-c488-442c-8548-76d017426211}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-load-error-readyState.html]
[task 2019-05-09T08:41:55.050Z] 08:41:55 INFO - PID 3531 | --DOCSHELL 0xdbc1a800 == 17 [pid = 3531] [id = {999f6aec-761b-4796-b90d-6729ee5a290f}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-insert-after-load.html]
[task 2019-05-09T08:41:55.050Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 61 (0xdbf17000) [pid = 3531] [serial = 141] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.050Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 60 (0xe5025c00) [pid = 3531] [serial = 135] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.055Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 59 (0xe2b31c00) [pid = 3531] [serial = 132] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.056Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 58 (0xdbf10000) [pid = 3531] [serial = 140] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.056Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 57 (0xe110fc00) [pid = 3531] [serial = 129] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.057Z] 08:41:55 INFO - PID 3531 | --DOCSHELL 0xdbf0b400 == 16 [pid = 3531] [id = {f442f69e-42f5-401b-8b66-63482ce527c4}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-mode-triggers-loading.html]
[task 2019-05-09T08:41:55.057Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 56 (0xdbf0fc00) [pid = 3531] [serial = 149] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.058Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 55 (0xdc7aac00) [pid = 3531] [serial = 120] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.059Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 54 (0xdc0bb400) [pid = 3531] [serial = 123] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.060Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 53 (0xe13fc400) [pid = 3531] [serial = 126] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.060Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 52 (0xe7e09800) [pid = 3531] [serial = 138] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.061Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 51 (0xdbc19c00) [pid = 3531] [serial = 134] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.062Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 50 (0xe1bc3000) [pid = 3531] [serial = 137] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.063Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 49 (0xdbc16000) [pid = 3531] [serial = 131] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.064Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 48 (0xdaf6dc00) [pid = 3531] [serial = 128] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.065Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 47 (0xdbf0d800) [pid = 3531] [serial = 143] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.065Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 46 (0xdc0bbc00) [pid = 3531] [serial = 152] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.066Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 45 (0xdb3b5800) [pid = 3531] [serial = 146] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:55.067Z] 08:41:55 INFO - PID 3531 | --DOCSHELL 0xdb3b6000 == 15 [pid = 3531] [id = {7debed41-cbe2-4eb2-a9db-3c2e3cf37fae}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-id.html]
[task 2019-05-09T08:41:55.068Z] 08:41:55 INFO - PID 3531 | --DOCSHELL 0xdaedd800 == 14 [pid = 3531] [id = {f3703ef8-8cfb-4fdd-88fd-466351404df8}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-element-src-change.html]
[task 2019-05-09T08:41:55.069Z] 08:41:55 INFO - PID 3531 | --DOCSHELL 0xdbf0f400 == 13 [pid = 3531] [id = {19845eae-1675-481c-b4a9-acf8f43045f8}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-mode-disabled.html]
[task 2019-05-09T08:41:55.071Z] 08:41:55 INFO - PID 3531 | --DOCSHELL 0xdbf10800 == 12 [pid = 3531] [id = {28219c2f-54ea-4f89-a26c-70deb2abdd2b}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-mode-not-changed-by-new-track.html]
[task 2019-05-09T08:41:55.531Z] 08:41:55 INFO - PID 3531 | [3531, Main Thread] WARNING: Wrong inner/outer window combination!: file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 4804
[task 2019-05-09T08:41:55.531Z] 08:41:55 INFO - PID 3531 | [3531, Main Thread] WARNING: Wrong inner/outer window combination!: file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 4804
[task 2019-05-09T08:41:55.532Z] 08:41:55 INFO - PID 3531 | [3531, Main Thread] WARNING: Wrong inner/outer window combination!: file /builds/worker/workspace/build/src/dom/base/Document.cpp, line 4804
[task 2019-05-09T08:41:55.532Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 44 (0xe0842130) [pid = 3531] [serial = 154] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-mode-disabled.html]
[task 2019-05-09T08:41:55.532Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 43 (0xdb2de9a0) [pid = 3531] [serial = 112] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-data-url.html]
[task 2019-05-09T08:41:55.532Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 42 (0xf7120010) [pid = 3531] [serial = 157] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-mode-not-changed-by-new-track.html]
[task 2019-05-09T08:41:55.533Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 41 (0xdb2de890) [pid = 3531] [serial = 160] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-mode-triggers-loading.html]
[task 2019-05-09T08:41:55.533Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 40 (0xe0257340) [pid = 3531] [serial = 148] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-load-from-element-readyState.html]
[task 2019-05-09T08:41:55.535Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 39 (0xe0257010) [pid = 3531] [serial = 142] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-large-timestamp.html]
[task 2019-05-09T08:41:55.535Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 38 (0xe0257ab0) [pid = 3531] [serial = 151] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-load-from-src-readyState.html]
[task 2019-05-09T08:41:55.535Z] 08:41:55 INFO - PID 3531 | --DOMWINDOW == 37 (0xdb201790) [pid = 3531] [serial = 145] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-load-error-readyState.html]
[task 2019-05-09T08:41:59.401Z] 08:41:59 INFO - PID 3531 | --DOMWINDOW == 36 (0xdc982400) [pid = 3531] [serial = 150] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.402Z] 08:41:59 INFO - PID 3531 | --DOMWINDOW == 35 (0xdbf14000) [pid = 3531] [serial = 147] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.402Z] 08:41:59 INFO - PID 3531 | --DOMWINDOW == 34 (0xdbf15c00) [pid = 3531] [serial = 144] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.402Z] 08:41:59 INFO - PID 3531 | --DOMWINDOW == 33 (0xe13f2800) [pid = 3531] [serial = 153] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.403Z] 08:41:59 INFO - PID 3531 | --DOMWINDOW == 32 (0xe02a0800) [pid = 3531] [serial = 162] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.403Z] 08:41:59 INFO - PID 3531 | --DOMWINDOW == 31 (0xdd652c00) [pid = 3531] [serial = 161] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.404Z] 08:41:59 INFO - PID 3531 | --DOMWINDOW == 30 (0xdd65d000) [pid = 3531] [serial = 159] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.404Z] 08:41:59 INFO - PID 3531 | --DOMWINDOW == 29 (0xe13ed400) [pid = 3531] [serial = 155] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.405Z] 08:41:59 INFO - PID 3531 | --DOMWINDOW == 28 (0xdd657400) [pid = 3531] [serial = 158] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.405Z] 08:41:59 INFO - PID 3531 | --DOMWINDOW == 27 (0xe13eec00) [pid = 3531] [serial = 114] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.406Z] 08:41:59 INFO - PID 3531 | --DOMWINDOW == 26 (0xdd65ac00) [pid = 3531] [serial = 164] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.407Z] 08:41:59 INFO - PID 3531 | --DOMWINDOW == 25 (0xe1b0e400) [pid = 3531] [serial = 156] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:42:19.989Z] 08:42:19 INFO -
[task 2019-05-09T08:42:19.989Z] 08:42:19 INFO - TEST-UNEXPECTED-TIMEOUT | /html/semantics/embedded-content/media-elements/track/track-element/track-mode.html | TextTrack mode attribute - Test timed out
[task 2019-05-09T08:42:19.989Z] 08:42:19 INFO - TEST-UNEXPECTED-TIMEOUT | /html/semantics/embedded-content/media-elements/track/track-element/track-mode.html | expected OK
[task 2019-05-09T08:42:19.990Z] 08:42:19 INFO - TEST-INFO took 30744ms
[task 2019-05-09T08:42:20.026Z] 08:42:20 INFO - PID 3531 | 1557391340022 Marionette INFO Stopped listening on port 2828
[task 2019-05-09T08:42:20.776Z] 08:42:20 INFO - PID 3531 | --DOCSHELL 0xe1b08800 == 11 [pid = 3531] [id = {f87d8201-e1dd-46e8-8ff7-f833ee6a98c8}] [url = resource://gre-resources/hiddenWindow.html]
[task 2019-05-09T08:42:20.776Z] 08:42:20 INFO - PID 3531 | --DOCSHELL 0xe25d3000 == 10 [pid = 3531] [id = {edd153aa-fb91-4f23-a5c6-4278ec4e35e4}] [url = chrome://extensions/content/dummy.xul]
[task 2019-05-09T08:42:20.777Z] 08:42:20 INFO - PID 3531 | --DOCSHELL 0xe2b37800 == 9 [pid = 3531] [id = {cced7e08-01f0-4b0d-b20d-f284c0d24501}] [url = about:blank]
[task 2019-05-09T08:42:20.777Z] 08:42:20 INFO - PID 3531 | --DOCSHELL 0xe3553c00 == 8 [pid = 3531] [id = {82818385-6918-48a7-a0a4-4b2ac3a47fdc}] [url = chrome://browser/content/browser.xul]
[task 2019-05-09T08:42:21.115Z] 08:42:21 INFO - PID 3531 | [3531, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/workspace/build/src/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1285
[task 2019-05-09T08:42:21.196Z] 08:42:21 INFO - PID 3531 | --DOCSHELL 0xe1b0c400 == 7 [pid = 3531] [id = {09311278-ebd2-47df-b19f-ad52c9eb28e0}] [url = moz-extension://25ae4c49-4fef-400b-b23c-51407ffc668d/_generated_background_page.html]
[task 2019-05-09T08:42:21.196Z] 08:42:21 INFO - PID 3531 | --DOCSHELL 0xe1b0b800 == 6 [pid = 3531] [id = {7c88771d-f27e-4b11-861e-e234da95b301}] [url = moz-extension://176a0ac9-9bfe-480b-928b-e1fba430b5a8/_generated_background_page.html]
[task 2019-05-09T08:42:21.212Z] 08:42:21 INFO - PID 3531 | --DOCSHELL 0xe33de000 == 5 [pid = 3531] [id = {b1ee334b-7c5c-4c87-b223-218e39c75a01}] [url = about:blank]
[task 2019-05-09T08:42:21.212Z] 08:42:21 INFO - PID 3531 | --DOCSHELL 0xe1b0ac00 == 4 [pid = 3531] [id = {4cb33bfd-672f-415a-ae84-38267050f593}] [url = moz-extension://57e56eaf-d760-41b9-9c1c-01816a7c1a2d/_generated_background_page.html]
[task 2019-05-09T08:42:21.214Z] 08:42:21 INFO - PID 3531 | --DOCSHELL 0xe1b09800 == 3 [pid = 3531] [id = {0750f542-3b77-4b74-aff7-dde1827e57f8}] [url = moz-extension://d5649a11-7c87-44f3-925a-0d1782be0303/_generated_background_page.html]
[task 2019-05-09T08:42:21.215Z] 08:42:21 INFO - PID 3531 | --DOCSHELL 0xdd651800 == 2 [pid = 3531] [id = {4772dfd5-480a-4270-9a2a-dae4fec186eb}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-mode.html]
[task 2019-05-09T08:42:21.217Z] 08:42:21 INFO - PID 3531 | --DOCSHELL 0xdbc1d000 == 1 [pid = 3531] [id = {15ce47d5-3a8b-4fab-9550-7de44473e06e}] [url = about:newtab]
[task 2019-05-09T08:42:21.217Z] 08:42:21 INFO - PID 3531 | --DOCSHELL 0xe25cd800 == 0 [pid = 3531] [id = {79c6b9e1-d427-4035-b8e6-76c08301cb3b}] [url = http://web-platform.test:8000/testharness_runner.html]
[task 2019-05-09T08:42:22.248Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 24 (0xe3554800) [pid = 3531] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:42:22.256Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 23 (0xe08d6400) [pid = 3531] [serial = 165] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:42:22.257Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 22 (0xf7120780) [pid = 3531] [serial = 1] [outer = (nil)] [url = chrome://browser/content/browser.xul]
[task 2019-05-09T08:42:22.259Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 21 (0xdc0bc400) [pid = 3531] [serial = 33] [outer = (nil)] [url = http://web-platform.test:8000/testharness_runner.html]
[task 2019-05-09T08:42:22.259Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 20 (0xe2d07ef0) [pid = 3531] [serial = 20] [outer = (nil)] [url = http://web-platform.test:8000/testharness_runner.html]
[task 2019-05-09T08:42:22.260Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 19 (0xe0257230) [pid = 3531] [serial = 163] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-mode.html]
[task 2019-05-09T08:42:22.260Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 18 (0xe2d07890) [pid = 3531] [serial = 13] [outer = (nil)] [url = moz-extension://25ae4c49-4fef-400b-b23c-51407ffc668d/_generated_background_page.html]
[task 2019-05-09T08:42:22.262Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 17 (0xe1b07c00) [pid = 3531] [serial = 9] [outer = (nil)] [url = chrome://extensions/content/dummy.xul]
[task 2019-05-09T08:42:22.262Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 16 (0xe2d07780) [pid = 3531] [serial = 12] [outer = (nil)] [url = moz-extension://176a0ac9-9bfe-480b-928b-e1fba430b5a8/_generated_background_page.html]
[task 2019-05-09T08:42:22.263Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 15 (0xf7120cd0) [pid = 3531] [serial = 3] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:42:22.264Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 14 (0xe0b57400) [pid = 3531] [serial = 26] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:42:22.265Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 13 (0xdb2ddbd0) [pid = 3531] [serial = 34] [outer = (nil)] [url = about:newtab]
[task 2019-05-09T08:42:22.265Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 12 (0xe2d07670) [pid = 3531] [serial = 11] [outer = (nil)] [url = moz-extension://57e56eaf-d760-41b9-9c1c-01816a7c1a2d/_generated_background_page.html]
[task 2019-05-09T08:42:22.265Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 11 (0xe2d07560) [pid = 3531] [serial = 10] [outer = (nil)] [url = moz-extension://d5649a11-7c87-44f3-925a-0d1782be0303/_generated_background_page.html]
[task 2019-05-09T08:42:22.266Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 10 (0xe2d07340) [pid = 3531] [serial = 4] [outer = (nil)] [url = chrome://extensions/content/dummy.xul]
[task 2019-05-09T08:42:22.266Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 9 (0xe2d07450) [pid = 3531] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:42:22.268Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 8 (0xe1b02400) [pid = 3531] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:42:22.268Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 7 (0xe0843890) [pid = 3531] [serial = 28] [outer = (nil)] [url = resource://gre-resources/hiddenWindow.html]
[task 2019-05-09T08:42:22.268Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 6 (0xe1b01400) [pid = 3531] [serial = 7] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:42:22.269Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 5 (0xe027c000) [pid = 3531] [serial = 32] [outer = (nil)] [url = resource://gre-resources/hiddenWindow.html]
[task 2019-05-09T08:42:22.270Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 4 (0xe0b52c00) [pid = 3531] [serial = 21] [outer = (nil)] [url = moz-extension://d5649a11-7c87-44f3-925a-0d1782be0303/_generated_background_page.html]
[task 2019-05-09T08:42:22.270Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 3 (0xe0b53800) [pid = 3531] [serial = 22] [outer = (nil)] [url = moz-extension://57e56eaf-d760-41b9-9c1c-01816a7c1a2d/_generated_background_page.html]
[task 2019-05-09T08:42:22.272Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 2 (0xe1102000) [pid = 3531] [serial = 36] [outer = (nil)] [url = about:newtab]
[task 2019-05-09T08:42:22.272Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 1 (0xe0b54400) [pid = 3531] [serial = 23] [outer = (nil)] [url = moz-extension://176a0ac9-9bfe-480b-928b-e1fba430b5a8/_generated_background_page.html]
[task 2019-05-09T08:42:22.272Z] 08:42:22 INFO - PID 3531 | --DOMWINDOW == 0 (0xe0b55000) [pid = 3531] [serial = 24] [outer = (nil)] [url = moz-extension://25ae4c49-4fef-400b-b23c-51407ffc668d/_generated_background_page.html]
[task 2019-05-09T08:42:22.333Z] 08:42:22 INFO - PID 3531 | [3531, Main Thread] WARNING: OOPDeinit() without successful OOPInit(): file /builds/worker/workspace/build/src/toolkit/crashreporter/nsExceptionHandler.cpp, line 3104
[task 2019-05-09T08:42:22.335Z] 08:42:22 INFO - PID 3531 | nsStringStats
[task 2019-05-09T08:42:22.336Z] 08:42:22 INFO - PID 3531 | => mAllocCount: 214950
[task 2019-05-09T08:42:22.336Z] 08:42:22 INFO - PID 3531 | => mReallocCount: 0
[task 2019-05-09T08:42:22.337Z] 08:42:22 INFO - PID 3531 | => mFreeCount: 214950
[task 2019-05-09T08:42:22.337Z] 08:42:22 INFO - PID 3531 | => mShareCount: 229449
[task 2019-05-09T08:42:22.339Z] 08:42:22 INFO - PID 3531 | => mAdoptCount: 5903
[task 2019-05-09T08:42:22.339Z] 08:42:22 INFO - PID 3531 | => mAdoptFreeCount: 6351
[task 2019-05-09T08:42:22.339Z] 08:42:22 INFO - PID 3531 | => Process ID: 3531, Thread ID: 4147562240
[task 2019-05-09T08:42:22.407Z] 08:42:22 INFO - Browser exited with return code 0
[task 2019-05-09T08:42:22.408Z] 08:42:22 INFO - PROCESS LEAKS /tmp/tmpTOomRY.mozrunner/runtests_leaks_843.log
[task 2019-05-09T08:42:22.409Z] 08:42:22 INFO - leakcheck | Processing log file /tmp/tmpTOomRY.mozrunner/runtests_leaks_843.log for scope /html/semantics/embedded-content

@alwu: do you have an idea why the test times out sometimes? Does it make sense to move the issue to the "Audio/Video" component?

Flags: needinfo?(alwu)

The only reason I can think of is that we had finished loading track before started running the code in async_test. I don't have any strong preference about whether this bug should be moved.

Assignee: nobody → alwu
Flags: needinfo?(alwu)

If the track has already finished loading, we can directly run trackLoaded().

Priority: -- → P2
Pushed by alwu@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/5db4cd334c5b
check track's ready state in the beginning of the test. r=baku
Created web-platform-tests PR https://github.com/web-platform-tests/wpt/pull/17859 for changes under testing/web-platform/tests
Whiteboard: [stockwell needswork:owner] → [stockwell needswork:owner][wptsync upstream]
Upstream web-platform-tests status checks passed, PR will merge once commit reaches central.
Status: NEW → RESOLVED
Closed: 4 months ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla70

This is still happening on autoland: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=245507481&repo=autoland&lineNumber=8313

task 2019-05-09T08:41:55.532Z] 08:41:55     INFO - PID 3531 | --DOMWINDOW == 44 (0xe0842130) [pid = 3531] [serial = 154] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-mode-disabled.html]
[task 2019-05-09T08:41:55.532Z] 08:41:55     INFO - PID 3531 | --DOMWINDOW == 43 (0xdb2de9a0) [pid = 3531] [serial = 112] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-data-url.html]
[task 2019-05-09T08:41:55.532Z] 08:41:55     INFO - PID 3531 | --DOMWINDOW == 42 (0xf7120010) [pid = 3531] [serial = 157] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-mode-not-changed-by-new-track.html]
[task 2019-05-09T08:41:55.533Z] 08:41:55     INFO - PID 3531 | --DOMWINDOW == 41 (0xdb2de890) [pid = 3531] [serial = 160] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-mode-triggers-loading.html]
[task 2019-05-09T08:41:55.533Z] 08:41:55     INFO - PID 3531 | --DOMWINDOW == 40 (0xe0257340) [pid = 3531] [serial = 148] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-load-from-element-readyState.html]
[task 2019-05-09T08:41:55.535Z] 08:41:55     INFO - PID 3531 | --DOMWINDOW == 39 (0xe0257010) [pid = 3531] [serial = 142] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-large-timestamp.html]
[task 2019-05-09T08:41:55.535Z] 08:41:55     INFO - PID 3531 | --DOMWINDOW == 38 (0xe0257ab0) [pid = 3531] [serial = 151] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-load-from-src-readyState.html]
[task 2019-05-09T08:41:55.535Z] 08:41:55     INFO - PID 3531 | --DOMWINDOW == 37 (0xdb201790) [pid = 3531] [serial = 145] [outer = (nil)] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-load-error-readyState.html]
[task 2019-05-09T08:41:59.401Z] 08:41:59     INFO - PID 3531 | --DOMWINDOW == 36 (0xdc982400) [pid = 3531] [serial = 150] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.402Z] 08:41:59     INFO - PID 3531 | --DOMWINDOW == 35 (0xdbf14000) [pid = 3531] [serial = 147] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.402Z] 08:41:59     INFO - PID 3531 | --DOMWINDOW == 34 (0xdbf15c00) [pid = 3531] [serial = 144] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.402Z] 08:41:59     INFO - PID 3531 | --DOMWINDOW == 33 (0xe13f2800) [pid = 3531] [serial = 153] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.403Z] 08:41:59     INFO - PID 3531 | --DOMWINDOW == 32 (0xe02a0800) [pid = 3531] [serial = 162] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.403Z] 08:41:59     INFO - PID 3531 | --DOMWINDOW == 31 (0xdd652c00) [pid = 3531] [serial = 161] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.404Z] 08:41:59     INFO - PID 3531 | --DOMWINDOW == 30 (0xdd65d000) [pid = 3531] [serial = 159] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.404Z] 08:41:59     INFO - PID 3531 | --DOMWINDOW == 29 (0xe13ed400) [pid = 3531] [serial = 155] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.405Z] 08:41:59     INFO - PID 3531 | --DOMWINDOW == 28 (0xdd657400) [pid = 3531] [serial = 158] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.405Z] 08:41:59     INFO - PID 3531 | --DOMWINDOW == 27 (0xe13eec00) [pid = 3531] [serial = 114] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.406Z] 08:41:59     INFO - PID 3531 | --DOMWINDOW == 26 (0xdd65ac00) [pid = 3531] [serial = 164] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:41:59.407Z] 08:41:59     INFO - PID 3531 | --DOMWINDOW == 25 (0xe1b0e400) [pid = 3531] [serial = 156] [outer = (nil)] [url = about:blank]
[task 2019-05-09T08:42:19.989Z] 08:42:19     INFO - 
[task 2019-05-09T08:42:19.989Z] 08:42:19     INFO - TEST-UNEXPECTED-TIMEOUT | /html/semantics/embedded-content/media-elements/track/track-element/track-mode.html | TextTrack mode attribute - Test timed out
[task 2019-05-09T08:42:19.989Z] 08:42:19     INFO - TEST-UNEXPECTED-TIMEOUT | /html/semantics/embedded-content/media-elements/track/track-element/track-mode.html | expected OK
[task 2019-05-09T08:42:19.990Z] 08:42:19     INFO - TEST-INFO took 30744ms
[task 2019-05-09T08:42:20.026Z] 08:42:20     INFO - PID 3531 | 1557391340022	Marionette	INFO	Stopped listening on port 2828
[task 2019-05-09T08:42:20.776Z] 08:42:20     INFO - PID 3531 | --DOCSHELL 0xe1b08800 == 11 [pid = 3531] [id = {f87d8201-e1dd-46e8-8ff7-f833ee6a98c8}] [url = resource://gre-resources/hiddenWindow.html]
[task 2019-05-09T08:42:20.776Z] 08:42:20     INFO - PID 3531 | --DOCSHELL 0xe25d3000 == 10 [pid = 3531] [id = {edd153aa-fb91-4f23-a5c6-4278ec4e35e4}] [url = chrome://extensions/content/dummy.xul]
[task 2019-05-09T08:42:20.777Z] 08:42:20     INFO - PID 3531 | --DOCSHELL 0xe2b37800 == 9 [pid = 3531] [id = {cced7e08-01f0-4b0d-b20d-f284c0d24501}] [url = about:blank]
[task 2019-05-09T08:42:20.777Z] 08:42:20     INFO - PID 3531 | --DOCSHELL 0xe3553c00 == 8 [pid = 3531] [id = {82818385-6918-48a7-a0a4-4b2ac3a47fdc}] [url = chrome://browser/content/browser.xul]
[task 2019-05-09T08:42:21.115Z] 08:42:21     INFO - PID 3531 | [3531, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/workspace/build/src/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1285
[task 2019-05-09T08:42:21.196Z] 08:42:21     INFO - PID 3531 | --DOCSHELL 0xe1b0c400 == 7 [pid = 3531] [id = {09311278-ebd2-47df-b19f-ad52c9eb28e0}] [url = moz-extension://25ae4c49-4fef-400b-b23c-51407ffc668d/_generated_background_page.html]
[task 2019-05-09T08:42:21.196Z] 08:42:21     INFO - PID 3531 | --DOCSHELL 0xe1b0b800 == 6 [pid = 3531] [id = {7c88771d-f27e-4b11-861e-e234da95b301}] [url = moz-extension://176a0ac9-9bfe-480b-928b-e1fba430b5a8/_generated_background_page.html]
[task 2019-05-09T08:42:21.212Z] 08:42:21     INFO - PID 3531 | --DOCSHELL 0xe33de000 == 5 [pid = 3531] [id = {b1ee334b-7c5c-4c87-b223-218e39c75a01}] [url = about:blank]
[task 2019-05-09T08:42:21.212Z] 08:42:21     INFO - PID 3531 | --DOCSHELL 0xe1b0ac00 == 4 [pid = 3531] [id = {4cb33bfd-672f-415a-ae84-38267050f593}] [url = moz-extension://57e56eaf-d760-41b9-9c1c-01816a7c1a2d/_generated_background_page.html]
[task 2019-05-09T08:42:21.214Z] 08:42:21     INFO - PID 3531 | --DOCSHELL 0xe1b09800 == 3 [pid = 3531] [id = {0750f542-3b77-4b74-aff7-dde1827e57f8}] [url = moz-extension://d5649a11-7c87-44f3-925a-0d1782be0303/_generated_background_page.html]
[task 2019-05-09T08:42:21.215Z] 08:42:21     INFO - PID 3531 | --DOCSHELL 0xdd651800 == 2 [pid = 3531] [id = {4772dfd5-480a-4270-9a2a-dae4fec186eb}] [url = http://web-platform.test:8000/html/semantics/embedded-content/media-elements/track/track-element/track-mode.html]
[task 2019-05-09T08:42:21.217Z] 08:42:21     INFO - PID 3531 | --DOCSHELL 0xdbc1d000 == 1 [pid = 3531] [id = {15ce47d5-3a8b-4fab-9550-7de44473e06e}] [url = about:newtab]
[task 2019-05-09T08:42:21.217Z] 08:42:21     INFO - PID 3531 | --DOCSHELL 0xe25cd800 == 0 [pid = 3531] [id = {79c6b9e1-d427-4035-b8e6-76c08301cb3b}] [url = http://web-platform.test:8000/testharness_runner.html]
[task 2019-05-09T08:42:22.248Z] 08:42:22     INFO - PID 3531 | --DOMWINDOW == 24 (0xe3554800) [pid = 3531] [serial = 2] [outer = (nil)] [url = about:blank]
Flags: needinfo?(alwu)
Upstream PR merged

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

16:30:36 INFO - TEST-START | /html/semantics/embedded-content/media-elements/track/track-element/track-mode.html
16:30:36 INFO - Closing window 94489280513
16:30:46 INFO -
16:30:46 INFO - TEST-UNEXPECTED-TIMEOUT | /html/semantics/embedded-content/media-elements/track/track-element/track-mode.html | TextTrack mode attribute - Test timed out
16:30:46 INFO - TEST-UNEXPECTED-TIMEOUT | /html/semantics/embedded-content/media-elements/track/track-element/track-mode.html | expected OK
16:30:46 INFO - TEST-INFO took 10446ms
16:30:46 INFO - PID 2206 | 1563381046440 Marionette INFO Stopped listening on port 50210
16:30:47 INFO - Browser exited with return code 0
16:30:47 INFO - PROCESS LEAKS None
16:30:47 INFO - Closing logging queue
16:30:47 INFO - queue closed
16:30:47 INFO - Setting up ssl
16:30:47 INFO - certutil |
16:30:47 INFO - certutil |
16:30:47 INFO - certutil |
16:30:47 INFO - Certificate Nickname Trust Attributes
16:30:47 INFO - SSL,S/MIME,JAR/XPI
16:30:47 INFO -
16:30:47 INFO - web-platform-tests CT,,
16:30:47 INFO -
16:30:47 INFO - Application command: /Users/cltbld/tasks/task_1563380458/build/application/Firefox Nightly.app/Contents/MacOS/firefox --marionette about:blank -foreground -profile /var/folders/t7/9w5rxbrd5cn05qhqx1j_tfr8000017/T/tmpTjdAdK.mozrunner
16:30:47 INFO - Starting runner
16:30:47 INFO - PID 2260 | 1563381047829 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
16:30:47 INFO - PID 2260 | 1563381047829 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
16:30:47 INFO - PID 2260 | 1563381047829 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
16:30:47 INFO - PID 2260 | 1563381047829 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
16:30:50 INFO - PID 2260 | 1563381050151 Marionette INFO Listening on port 50210

Status: RESOLVED → REOPENED
Resolution: FIXED → ---
Target Milestone: mozilla70 → ---

Ok, I found out the real root cause. In this test, we would switch the track mode to hidden after receiving timeupdate which would dispatch a task to run TimeMarchesOn later. However, the timeupdate was dispatched too late, so at the point we set the track to hidden , the current playback time had reached over 0.7s where the second cues [0.3:0.7] has ended. Therefore, we miss a chance to dispatch enter and exit for the second cue.

Flags: needinfo?(alwu)

In order not to miss the second cue, we extend the duration of both the second and third cue to 1 second. And adjust the third cue's start time to keep them non-overlapping.

Whiteboard: [wptsync upstream][stockwell disable-recommended] → [wptsync upstream][stockwell needswork]
Whiteboard: [wptsync upstream][stockwell disable-recommended] → [wptsync upstream][stockwell needswork]
Pushed by alwu@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/233df0152aa3
part2 : extend cue's duration. r=baku
Created web-platform-tests PR https://github.com/web-platform-tests/wpt/pull/17966 for changes under testing/web-platform/tests
Upstream web-platform-tests status checks passed, PR will merge once commit reaches central.
Whiteboard: [wptsync upstream][stockwell disable-recommended] → [wptsync upstream]
Status: REOPENED → RESOLVED
Closed: 4 months ago4 months ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla70
Upstream PR merged
You need to log in before you can comment on or make changes to this bug.