1542372 - Intermittent dom/media/test/test_eme_canvas_blocked.html | 400x300 as-is then 400x300 presented as 533x300-15 - audio: error fetching bipbop_300_215kbps-cenc-audio-key1-init.mp4

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

#[markdown(off)]
Filed by: dluca [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=238462191&repo=autoland

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

INFO - TEST-START | dom/media/test/test_eme_autoplay.html
19:35:26 INFO - GECKO(849) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
19:35:26 INFO - GECKO(849) | MEMORY STAT | vsize 3917MB | residentFast 94MB | heapAllocated 11MB
19:35:26 INFO - TEST-OK | dom/media/test/test_eme_autoplay.html | took 1614ms
19:35:26 INFO - TEST-START | dom/media/test/test_eme_canvas_blocked.html
19:35:26 INFO - file=[xpconnect wrapped nsIFile]
19:35:26 INFO - file=[xpconnect wrapped nsIFile]
19:35:26 INFO - file=[xpconnect wrapped nsIFile]
19:35:27 INFO - file=[xpconnect wrapped nsIFile]
19:35:27 INFO - file=[xpconnect wrapped nsIFile]
19:35:27 INFO - file=[xpconnect wrapped nsIFile]
19:35:27 INFO - file=[xpconnect wrapped nsIFile]
19:35:27 INFO - file=[xpconnect wrapped nsIFile]
19:35:27 INFO - file=[xpconnect wrapped nsIFile]
19:35:27 INFO - file=[xpconnect wrapped nsIFile]
19:35:28 INFO - TEST-INFO | started process screencapture
19:35:29 INFO - TEST-INFO | screencapture: exit 0
19:35:29 INFO - <snipped 502 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
19:35:29 INFO - Buffered messages logged at 19:35:28
19:35:29 INFO - [19:35:28.469] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: updateend for bipbop_480_624kbps-cenc-audio-key1-init.mp4, SourceBuffer{AppendMode=-, updating=false, buffered=-, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.469] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-audio-key1-1.m4s
19:35:29 INFO - [19:35:28.470] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 video: fetch of bipbop_480_624kbps-cenc-video-key1-init.mp4 complete, appending
19:35:29 INFO - [19:35:28.471] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 got encrypted(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d047e571d047e571d047e571d2100000000) event.
19:35:29 INFO - [19:35:28.471] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 got encrypted(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1100000000) event.
19:35:29 INFO - [19:35:28.471] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 video: updateend for bipbop_480_624kbps-cenc-video-key1-init.mp4, SourceBuffer{AppendMode=-, updating=false, buffered=-, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.472] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 video: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-video-key1-1.m4s
19:35:29 INFO - [19:35:28.497] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 video: fetch of bipbop_480_624kbps-cenc-video-key2-init.mp4 complete, appending
19:35:29 INFO - [19:35:28.498] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 got encrypted(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1200000000) event.
19:35:29 INFO - [19:35:28.498] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 video: updateend for bipbop_480_624kbps-cenc-video-key2-init.mp4, SourceBuffer{AppendMode=-, updating=false, buffered=0-0.801666, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.498] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 video: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-video-key2-2.m4s
19:35:29 INFO - [19:35:28.524] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 audio: fetch of bipbop_480_959kbps-cenc-audio-key1-4.m4s complete, appending
19:35:29 INFO - [19:35:28.525] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 audio: updateend for bipbop_480_959kbps-cenc-audio-key1-4.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-1.578956, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.525] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 audio: end of track
19:35:29 INFO - [19:35:28.549] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: fetch of bipbop_480_624kbps-cenc-audio-key1-1.m4s complete, appending
19:35:29 INFO - [19:35:28.550] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: updateend for bipbop_480_624kbps-cenc-audio-key1-1.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-0.464399, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.550] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-audio-key1-2.m4s
19:35:29 INFO - [19:35:28.587] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: fetch of bipbop_480_624kbps-cenc-audio-key1-2.m4s complete, appending
19:35:29 INFO - [19:35:28.588] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: updateend for bipbop_480_624kbps-cenc-audio-key1-2.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-0.928798, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.588] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-audio-key1-3.m4s
19:35:29 INFO - [19:35:28.592] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 video: fetch of bipbop_480_624kbps-cenc-video-key1-1.m4s complete, appending
19:35:29 INFO - [19:35:28.593] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 video: updateend for bipbop_480_624kbps-cenc-video-key1-1.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-0.801666, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.593] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 video: addNextFragment() fetching next fragment bipbop_480_959kbps-cenc-video-key2-init.mp4
19:35:29 INFO - [19:35:28.623] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 video: fetch of bipbop_480_624kbps-cenc-video-key2-2.m4s complete, appending
19:35:29 INFO - [19:35:28.624] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 video: updateend for bipbop_480_624kbps-cenc-video-key2-2.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-1.601666, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.624] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 video: end of track
19:35:29 INFO - [19:35:28.624] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 Tracks loaded, calling MediaSource.endOfStream()
19:35:29 INFO - [19:35:28.624] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 session[].generateRequest(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d047e571d047e571d047e571d2100000000)
19:35:29 INFO - [19:35:28.624] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 session[].generateRequest(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1100000000)
19:35:29 INFO - [19:35:28.625] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 session[].generateRequest(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1200000000)
19:35:29 INFO - [19:35:28.625] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 sending update message to CDM: {"keys":[{"kty":"oct","kid":"flcdBH5XHQR-Vx0EflcdIQ","k":"fldERH5XRER-V0REfldEIQ"}],"type":"temporary"}
19:35:29 INFO - [19:35:28.625] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 sending update message to CDM: {"keys":[{"kty":"oct","kid":"flcdA35XHQN-Vx0DflcdEQ","k":"flczM35XMzN-VzMzflczEQ"}],"type":"temporary"}
19:35:29 INFO - [19:35:28.625] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 sending update message to CDM: {"keys":[{"kty":"oct","kid":"flcdA35XHQN-Vx0DflcdEg","k":"flczM35XMzN-VzMzflczEg"}],"type":"temporary"}
19:35:29 INFO - [19:35:28.625] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 MediaKeySession update ok!
19:35:29 INFO - [19:35:28.625] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 MediaKeySession update ok!
19:35:29 INFO - [19:35:28.626] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 MediaKeySession update ok!
19:35:29 INFO - TEST-PASS | dom/media/test/test_eme_canvas_blocked.html | [19:35:28.640] 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 - Should throw an error when trying to draw EME video to canvas.
19:35:29 INFO - [finished 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12] remaining= 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13
19:35:29 INFO - TEST-PASS | dom/media/test/test_eme_canvas_blocked.html | [finished 640x480@959kbps with 1st keys then 640x480@624kbps with 2nd keys-12 t=1.9] Length of array should match number of running tests
19:35:29 INFO - TEST-PASS | dom/media/test/test_eme_canvas_blocked.html | [started 400x300 with presentation size 533x300-14 t=1.9] Length of array should match number of running tests
19:35:29 INFO - [19:35:28.645] 400x300 with presentation size 533x300-14 sourceopen
19:35:29 INFO - [19:35:28.645] 400x300 with presentation size 533x300-14 audio: addSourceBuffer(audio/mp4; codecs="mp4a.40.2")
19:35:29 INFO - [19:35:28.645] 400x300 with presentation size 533x300-14 audio: addNextFragment() fetching next fragment bipbop_300wp_227kbps-cenc-audio-key1-init.mp4
19:35:29 INFO - [19:35:28.646] 400x300 with presentation size 533x300-14 video: addSourceBuffer(video/mp4; codecs="avc1.64000d")
19:35:29 INFO - [19:35:28.646] 400x300 with presentation size 533x300-14 video: addNextFragment() fetching next fragment bipbop_300wp_227kbps-cenc-video-key1-init.mp4
19:35:29 INFO - [19:35:28.646] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: fetch of bipbop_480_624kbps-cenc-audio-key1-3.m4s complete, appending
19:35:29 INFO - [19:35:28.647] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: updateend for bipbop_480_624kbps-cenc-audio-key1-3.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-1.393197, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.647] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: addNextFragment() fetching next fragment bipbop_480_624kbps-cenc-audio-key1-4.m4s
19:35:29 INFO - [19:35:28.648] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 video: fetch of bipbop_480_959kbps-cenc-video-key2-init.mp4 complete, appending
19:35:29 INFO - [19:35:28.649] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 got encrypted(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1200000000) event.
19:35:29 INFO - [19:35:28.650] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 video: updateend for bipbop_480_959kbps-cenc-video-key2-init.mp4, SourceBuffer{AppendMode=-, updating=false, buffered=0-0.801666, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.650] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 video: addNextFragment() fetching next fragment bipbop_480_959kbps-cenc-video-key2-2.m4s
19:35:29 INFO - [19:35:28.707] 400x300 with presentation size 533x300-14 audio: fetch of bipbop_300wp_227kbps-cenc-audio-key1-init.mp4 complete, appending
19:35:29 INFO - [19:35:28.708] 400x300 with presentation size 533x300-14 video: fetch of bipbop_300wp_227kbps-cenc-video-key1-init.mp4 complete, appending
19:35:29 INFO - [19:35:28.708] 400x300 with presentation size 533x300-14 audio: updateend for bipbop_300wp_227kbps-cenc-audio-key1-init.mp4, SourceBuffer{AppendMode=-, updating=false, buffered=-, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.708] 400x300 with presentation size 533x300-14 audio: addNextFragment() fetching next fragment bipbop_300wp_227kbps-cenc-audio-key1-1.m4s
19:35:29 INFO - [19:35:28.710] 400x300 with presentation size 533x300-14 got encrypted(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d047e571d047e571d047e571d2100000000) event.
19:35:29 INFO - [19:35:28.710] 400x300 with presentation size 533x300-14 got encrypted(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1100000000) event.
19:35:29 INFO - [19:35:28.710] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: fetch of bipbop_480_624kbps-cenc-audio-key1-4.m4s complete, appending
19:35:29 INFO - [19:35:28.710] 400x300 with presentation size 533x300-14 video: updateend for bipbop_300wp_227kbps-cenc-video-key1-init.mp4, SourceBuffer{AppendMode=-, updating=false, buffered=-, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.710] 400x300 with presentation size 533x300-14 video: addNextFragment() fetching next fragment bipbop_300wp_227kbps-cenc-video-key1-1.m4s
19:35:29 INFO - [19:35:28.710] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: updateend for bipbop_480_624kbps-cenc-audio-key1-4.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-1.578956, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.710] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 audio: end of track
19:35:29 INFO - [19:35:28.763] 400x300 with presentation size 533x300-14 audio: fetch of bipbop_300wp_227kbps-cenc-audio-key1-1.m4s complete, appending
19:35:29 INFO - [19:35:28.764] 400x300 with presentation size 533x300-14 audio: updateend for bipbop_300wp_227kbps-cenc-audio-key1-1.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-0.464399, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.764] 400x300 with presentation size 533x300-14 audio: addNextFragment() fetching next fragment bipbop_300wp_227kbps-cenc-audio-key1-2.m4s
19:35:29 INFO - [19:35:28.780] 400x300 with presentation size 533x300-14 video: fetch of bipbop_300wp_227kbps-cenc-video-key1-1.m4s complete, appending
19:35:29 INFO - [19:35:28.780] 400x300 with presentation size 533x300-14 video: updateend for bipbop_300wp_227kbps-cenc-video-key1-1.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-0.801666, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.780] 400x300 with presentation size 533x300-14 video: addNextFragment() fetching next fragment bipbop_300wp_227kbps-cenc-video-key1-2.m4s
19:35:29 INFO - [19:35:28.789] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 video: fetch of bipbop_480_959kbps-cenc-video-key2-2.m4s complete, appending
19:35:29 INFO - [19:35:28.789] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 video: updateend for bipbop_480_959kbps-cenc-video-key2-2.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-1.601666, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.789] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 video: end of track
19:35:29 INFO - [19:35:28.789] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 Tracks loaded, calling MediaSource.endOfStream()
19:35:29 INFO - [19:35:28.789] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 session[].generateRequest(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d047e571d047e571d047e571d2100000000)
19:35:29 INFO - [19:35:28.790] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 session[].generateRequest(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1100000000)
19:35:29 INFO - [19:35:28.790] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 session[].generateRequest(cenc, 0000003470737368010000001077efecc0b24d02ace33c1e52e2fb4b000000017e571d037e571d037e571d037e571d1200000000)
19:35:29 INFO - [19:35:28.791] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 sending update message to CDM: {"keys":[{"kty":"oct","kid":"flcdBH5XHQR-Vx0EflcdIQ","k":"fldERH5XRER-V0REfldEIQ"}],"type":"temporary"}
19:35:29 INFO - [19:35:28.791] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 sending update message to CDM: {"keys":[{"kty":"oct","kid":"flcdA35XHQN-Vx0DflcdEQ","k":"flczM35XMzN-VzMzflczEQ"}],"type":"temporary"}
19:35:29 INFO - [19:35:28.791] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 sending update message to CDM: {"keys":[{"kty":"oct","kid":"flcdA35XHQN-Vx0DflcdEg","k":"flczM35XMzN-VzMzflczEg"}],"type":"temporary"}
19:35:29 INFO - [19:35:28.791] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 MediaKeySession update ok!
19:35:29 INFO - [19:35:28.791] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 MediaKeySession update ok!
19:35:29 INFO - [19:35:28.792] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 MediaKeySession update ok!
19:35:29 INFO - [19:35:28.797] 400x300 with presentation size 533x300-14 audio: fetch of bipbop_300wp_227kbps-cenc-audio-key1-2.m4s complete, appending
19:35:29 INFO - [19:35:28.798] 400x300 with presentation size 533x300-14 audio: updateend for bipbop_300wp_227kbps-cenc-audio-key1-2.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-0.928798, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.798] 400x300 with presentation size 533x300-14 audio: addNextFragment() fetching next fragment bipbop_300wp_227kbps-cenc-audio-key1-3.m4s
19:35:29 INFO - TEST-PASS | dom/media/test/test_eme_canvas_blocked.html | [19:35:28.810] 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 - Should throw an error when trying to draw EME video to canvas.
19:35:29 INFO - [finished 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13] remaining= 400x300 with presentation size 533x300-14
19:35:29 INFO - TEST-PASS | dom/media/test/test_eme_canvas_blocked.html | [finished 640x480@624kbps with 1st keys then 640x480@959kbps with 2nd keys-13 t=2.068] Length of array should match number of running tests
19:35:29 INFO - TEST-PASS | dom/media/test/test_eme_canvas_blocked.html | [started 400x300 as-is then 400x300 presented as 533x300-15 t=2.069] Length of array should match number of running tests
19:35:29 INFO - [19:35:28.813] 400x300 as-is then 400x300 presented as 533x300-15 sourceopen
19:35:29 INFO - [19:35:28.813] 400x300 as-is then 400x300 presented as 533x300-15 audio: addSourceBuffer(audio/mp4; codecs="mp4a.40.2")
19:35:29 INFO - [19:35:28.813] 400x300 as-is then 400x300 presented as 533x300-15 audio: addNextFragment() fetching next fragment bipbop_300_215kbps-cenc-audio-key1-init.mp4
19:35:29 INFO - [19:35:28.815] 400x300 as-is then 400x300 presented as 533x300-15 video: addSourceBuffer(video/mp4; codecs="avc1.64000d")
19:35:29 INFO - [19:35:28.815] 400x300 as-is then 400x300 presented as 533x300-15 video: addNextFragment() fetching next fragment bipbop_300_215kbps-cenc-video-key1-init.mp4
19:35:29 INFO - [19:35:28.818] 400x300 with presentation size 533x300-14 video: fetch of bipbop_300wp_227kbps-cenc-video-key1-2.m4s complete, appending
19:35:29 INFO - [19:35:28.819] 400x300 with presentation size 533x300-14 audio: fetch of bipbop_300wp_227kbps-cenc-audio-key1-3.m4s complete, appending
19:35:29 INFO - [19:35:28.820] 400x300 with presentation size 533x300-14 video: updateend for bipbop_300wp_227kbps-cenc-video-key1-2.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-1.6017, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.820] 400x300 with presentation size 533x300-14 video: end of track
19:35:29 INFO - [19:35:28.820] 400x300 with presentation size 533x300-14 audio: updateend for bipbop_300wp_227kbps-cenc-audio-key1-3.m4s, SourceBuffer{AppendMode=-, updating=false, buffered=0-1.393197, audioTracks=-, videoTracks=-}
19:35:29 INFO - [19:35:28.820] 400x300 with presentation size 533x300-14 audio: addNextFragment() fetching next fragment bipbop_300wp_227kbps-cenc-audio-key1-4.m4s
19:35:29 INFO - Buffered messages finished
19:35:29 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_eme_canvas_blocked.html | 400x300 as-is then 400x300 presented as 533x300-15 - audio: error fetching bipbop_300_215kbps-cenc-audio-key1-init.mp4
19:35:29 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:275:18
19:35:29 INFO - startTest/<@https://example.com/tests/dom/media/test/test_eme_canvas_blocked.html:43:20
19:35:29 INFO - promise callback*startTest@https://example.com/tests/dom/media/test/test_eme_canvas_blocked.html:43:4
19:35:29 INFO - MediaTestManager/this.nextTest@https://example.com/tests/dom/media/test/manifest.js:1834:12
19:35:29 INFO - MediaTestManager/this.finished@https://example.com/tests/dom/media/test/manifest.js:1813:12
19:35:29 INFO - startTest/<@https://example.com/tests/dom/media/test/test_eme_canvas_blocked.html:46:13
19:35:29 INFO - [finished 400x300 as-is then 400x300 presented as 533x300-15] remaining= 400x300 with presentation size 533x300-14
19:35:29 INFO - TEST-PASS | dom/media/test/test_eme_canvas_blocked.html | [finished 400x300 as-is then 400x300 presented as 533x300-15 t=2.084] Length of array should match number of running tests
19:35:29 INFO - TEST-PASS | dom/media/test/test_eme_canvas_blocked.html | [started 400x225-16 t=2.084] Length of array should match number of running tests
19:35:29 INFO - [19:35:28.829] 400x225-16 sourceopen
19:35:29 INFO - [19:35:28.829] 400x225-16 audio: addSourceBuffer(audio/mp4; codecs="mp4a.40.2")
19:35:29 INFO - [19:35:28.829] 400x225-16 audio: addNextFragment() fetching next fragment bipbop_225w_175kbps-cenc-audio-key1-init.mp4
19:35:29 INFO - [19:35:28.829] 400x225-16 video: addSourceBuffer(video/mp4; codecs="avc1.64000d")
19:35:29 INFO - [19:35:28.829] 400x225-16 video: addNextFragment() fetching next fragment bipbop_225w_175kbps-cenc-video-key1-init.mp4
19:35:29 INFO - [19:35:28.838] 400x300 as-is then 400x300 presented as 533x300-15 video: fetch of bipbop_300_215kbps-cenc-video-key1-init.mp4 complete, appending
19:35:29 INFO - Not taking screenshot here: see the one that was previously logged
19:35:29 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_eme_canvas_blocked.html | uncaught exception - InvalidStateError: An attempt was made to use an object that is not, or is no longer, usable at addNextFragment/<@https://example.com/tests/dom/media/test/eme.js:183:12
19:35:29 INFO - EventListener.handleEvent*addNextFragment@https://example.com/tests/dom/media/test/eme.js:181:11
19:35:29 INFO - AppendTrack/<@https://example.com/tests/dom/media/test/eme.js:204:5
19:35:29 INFO - AppendTrack@https://example.com/tests/dom/media/test/eme.js:160:10
19:35:29 INFO - LoadTest/</</<@https://example.com/tests/dom/media/test/eme.js:225:16
19:35:29 INFO - LoadTest/</<@https://example.com/tests/dom/media/test/eme.js:224:31
19:35:29 INFO - EventListener.handleEvent*LoadTest/<@https://example.com/tests/dom/media/test/eme.js:222:8
19:35:29 INFO - LoadTest@https://example.com/tests/dom/media/test/eme.js:221:10
19:35:29 INFO - SetupEME@https://example.com/tests/dom/media/test/eme.js:397:5
19:35:29 INFO - startTest@https://example.com/tests/dom/media/test/test_eme_canvas_blocked.html:40:12
19:35:29 INFO - MediaTestManager/this.nextTest@https://example.com/tests/dom/media/test/manifest.js:1834:12
19:35:29 INFO - MediaTestManager/this.finished@https://example.com/tests/dom/media/test/manifest.js:1813:12
19:35:29 INFO - startTest/<@https://example.com/tests/dom/media/test/test_eme_canvas_blocked.html:46:13
19:35:29 INFO -
19:35:29 INFO - simpletestOnerror@https://example.com/tests/SimpleTest/SimpleTest.js:1665:24
19:35:29 INFO - OnErrorEventHandlerNonNull*@https://example.com/tests/SimpleTest/SimpleTest.js:1645:1
19:35:29 INFO - GECKO(849) | JavaScript error: https://example.com/tests/dom/media/test/eme.js, line 183: InvalidStateError: An attempt was made to use an object that is not, or is no longer, usable
19:35:29 INFO - Test timed out. Remaining tests=400x300 with presentation size 533x300-14,400x225-16
19:35:29 INFO - GECKO(849) | MEMORY STAT | vsize 3953MB | residentFast 123MB | heapAllocated 17MB
19:35:29 INFO - TEST-OK | dom/media/test/test_eme_canvas_blocked.html | took 2159ms
19:35:29 INFO - Not taking screenshot here: see the one that was previously logged
19:35:29 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_eme_canvas_blocked.html | 400x300 with presentation size 533x300-14 - audio: aborted fetching bipbop_300wp_227kbps-cenc-audio-key1-4.m4s
19:35:29 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:275:18
19:35:29 INFO - startTest/<@https://example.com/tests/dom/media/test/test_eme_canvas_blocked.html:43:20
19:35:29 INFO - promise callback*startTest@https://example.com/tests/dom/media/test/test_eme_canvas_blocked.html:43:4
19:35:29 INFO - MediaTestManager/this.nextTest@https://example.com/tests/dom/media/test/manifest.js:1834:12
19:35:29 INFO - MediaTestManager/this.finished@https://example.com/tests/dom/media/test/manifest.js:1813:12
19:35:29 INFO - startTest/<@https://example.com/tests/dom/media/test/test_eme_canvas_blocked.html:46:13
19:35:29 INFO - [finished 400x300 with presentation size 533x300-14] remaining= 400x225-16
19:35:29 INFO - TEST-PASS | dom/media/test/test_eme_canvas_blocked.html | [finished 400x300 with presentation size 533x300-14 t=2.11] Length of array should match number of running tests
19:35:29 INFO - TEST-PASS | dom/media/test/test_eme_canvas_blocked.html | [started 640x360-17 t=2.11] Length of array should match number of running tests
19:35:29 INFO - Not taking screenshot here: see the one that was previously logged
19:35:29 INFO - TEST-UNEXPECTED-FAIL | dom/media/test/test_eme_canvas_blocked.html | 400x225-16 - audio: aborted fetching bipbop_225w_175kbps-cenc-audio-key1-init.mp4
19:35:29 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:275:18
19:35:29 INFO - startTest/<@https://example.com/tests/dom/media/test/test_eme_canvas_blocked.html:43:20
19:35:29 INFO - promise callback*startTest@https://example.com/tests/dom/media/test/test_eme_canvas_blocked.html:43:4
19:35:29 INFO - MediaTestManager/this.nextTest@https://example.com/tests/dom/media/test/manifest.js:1834:12
19:35:29 INFO - MediaTestManager/this.finished@https://example.com/tests/dom/media/test/manifest.js:1813:12
19:35:29 INFO - startTest/<@https://example.com/tests/dom/media/test/test_eme_canvas_blocked.html:46:13
19:35:29 INFO - [finished 400x225-16] remaining= 640x360-17
19:35:29 INFO - TEST-PASS | dom/media/test/test_eme_canvas_blocked.html | [finished 400x225-16 t=2.113] Length of array should match number of running tests

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• osx-10-10-shippable: 1

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

Comment 2

[Firefox Bug Husbandry Bot]

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