Closed Bug 1823047 Opened 2 years ago Closed 2 years ago

Intermittent /encrypted-media/clearkey-mp4-setmediakeys-multiple-times-with-different-mediakeys.https.html | single tracking bug

Categories

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

Product:
Core
Component:
Audio/Video: Playback
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

Filed by: sstanca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=409277466&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/XUfNVlSOQO-LyM369kLH-g/runs/0/artifacts/public/logs/live_backing.log

[task 2023-03-17T13:05:04.035Z] 13:05:04     INFO - TEST-OK | /encrypted-media/clearkey-mp4-setmediakeys-at-same-time.https.html | took 532ms
[task 2023-03-17T13:05:04.049Z] 13:05:04     INFO - TEST-START | /encrypted-media/clearkey-mp4-setmediakeys-multiple-times-with-different-mediakeys.https.html
[task 2023-03-17T13:05:04.055Z] 13:05:04     INFO - Closing window b126d2d2-fcdf-4537-8967-7611df1c2c3e
[task 2023-03-17T13:05:04.082Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: IPC message 'PGMP::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-03-17T13:05:04.091Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: Can't get observer service!: file /builds/worker/checkouts/gecko/xpcom/threads/nsMemoryPressure.cpp:65
[task 2023-03-17T13:05:04.091Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-17T13:05:04.092Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object ProfilerParentTracker destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.093Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object nsStringBuffer released from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.094Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object nsStringBuffer released from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.095Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object BackgroundEventTarget released from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.096Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object nsThreadPool released from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.097Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object CondVar destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.097Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object Mutex destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.098Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object nsThreadPool released from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.098Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object CondVar destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.099Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object Mutex destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.099Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object Mutex destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.100Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object nsThread released from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.101Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object TaskManager released from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.101Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object IdlePeriod released from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.102Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object TaskController::ExecutePendingMTTasks() released from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.103Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object TaskController::ExecutePendingMTTasks() released from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.104Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object CondVar destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.104Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object CondVar destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.105Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object Mutex destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.106Z] 13:05:04     INFO - PID 5688 | [GMP 6536, Main Thread] WARNING: XPCOM object Mutex destroyed from static ctor/dtor: file /builds/worker/checkouts/gecko/xpcom/base/nsTraceRefcnt.cpp:216
[task 2023-03-17T13:05:04.106Z] 13:05:04     INFO - PID 5688 | nsStringStats
[task 2023-03-17T13:05:04.107Z] 13:05:04     INFO - PID 5688 |  => mAllocCount:             73
[task 2023-03-17T13:05:04.107Z] 13:05:04     INFO - PID 5688 |  => mReallocCount:            0
[task 2023-03-17T13:05:04.108Z] 13:05:04     INFO - PID 5688 |  => mFreeCount:              71  --  LEAKED 2 !!!
[task 2023-03-17T13:05:04.108Z] 13:05:04     INFO - PID 5688 |  => mShareCount:             68
[task 2023-03-17T13:05:04.109Z] 13:05:04     INFO - PID 5688 |  => mAdoptCount:              0
[task 2023-03-17T13:05:04.110Z] 13:05:04     INFO - PID 5688 |  => mAdoptFreeCount:          0
[task 2023-03-17T13:05:04.111Z] 13:05:04     INFO - PID 5688 |  => Process ID: 6536, Thread ID: 8212
[task 2023-03-17T13:05:49.060Z] 13:05:49     INFO - Got timeout in harness
[task 2023-03-17T13:05:49.063Z] 13:05:49     INFO - TEST-UNEXPECTED-TIMEOUT | /encrypted-media/clearkey-mp4-setmediakeys-multiple-times-with-different-mediakeys.https.html | TestRunner hit external timeout (this may indicate a hang)
[task 2023-03-17T13:05:49.063Z] 13:05:49     INFO - TEST-INFO took 45027ms
[task 2023-03-17T13:07:00.448Z] 13:07:00     INFO - Browser exited with return code 572
[task 2023-03-17T13:07:00.450Z] 13:07:00  WARNING - Firefox didn't exit cleanly, not processing leak logs
[task 2023-03-17T13:07:10.464Z] 13:07:10  WARNING - Forcibly terminating runner process
[task 2023-03-17T13:07:10.543Z] 13:07:10     INFO - Application command: Z:\task_167905550007029\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167905550007029\AppData\Local\Temp\tmp1ik_ga1w
[task 2023-03-17T13:07:10.555Z] 13:07:10     INFO - PID 6300 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167905550007029\AppData\Local\Temp\tmpekxoipy2\runtests_leaks_5376.log
[task 2023-03-17T13:07:10.556Z] 13:07:10     INFO - PID 6300 | [7556, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-03-17T13:07:10.557Z] 13:07:10     INFO - PID 6300 | [7556, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-17T13:07:10.558Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: Rejected attempt to change type of pref extensions.formautofill.creditCards.available's user value from bool to string: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:1895
[task 2023-03-17T13:07:10.559Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-17T13:07:10.559Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-17T13:07:10.560Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-17T13:07:10.561Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-17T13:07:10.561Z] 13:07:10     INFO - PID 6300 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2023-03-17T13:07:10.562Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-17T13:07:10.563Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-17T13:07:10.564Z] 13:07:10     INFO - PID 6300 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167905550007029\AppData\Local\Temp\tmpekxoipy2\runtests_leaks_5376_gpu_pid8260.log
[task 2023-03-17T13:07:10.565Z] 13:07:10     INFO - PID 6300 | [8260, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-03-17T13:07:10.565Z] 13:07:10     INFO - PID 6300 | 1679058247073	Marionette	INFO	Marionette enabled
[task 2023-03-17T13:07:10.566Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: NS_ENSURE_SUCCESS_VOID(rv) failed with result 0x8000FFFF (NS_ERROR_UNEXPECTED): file /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:2415
[task 2023-03-17T13:07:10.567Z] 13:07:10     INFO - PID 6300 | 1679058247079	Marionette	INFO	Listening on port 51999
[task 2023-03-17T13:07:10.567Z] 13:07:10     INFO - PID 6300 | Initializing context 0735E000 surface 055815E0 on display 07338C00
[task 2023-03-17T13:07:10.568Z] 13:07:10     INFO - PID 6300 | [GPU 8260, Renderer] WARNING: Failed to make GL context current!: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:473
[task 2023-03-17T13:07:10.568Z] 13:07:10     INFO - PID 6300 | EGL Error: 0x3009
[task 2023-03-17T13:07:10.569Z] 13:07:10     INFO - PID 6300 | [GPU 8260, Renderer] WARNING: GLContext::InitWithPrefix failed!: file /builds/worker/checkouts/gecko/gfx/gl/GLContext.cpp:328
[task 2023-03-17T13:07:10.569Z] 13:07:10     INFO - PID 6300 | Destroying context 0735E000 surface 055815E0 on display 07338C00
[task 2023-03-17T13:07:10.570Z] 13:07:10     INFO - PID 6300 | [GPU 8260, Renderer] WARNING: Failed to create GLContext from PBuffer: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderEGL.cpp:1171
[task 2023-03-17T13:07:10.571Z] 13:07:10     INFO - PID 6300 | Initializing context 07370000 surface 05581280 on display 07338C00
[task 2023-03-17T13:07:10.572Z] 13:07:10     INFO - PID 6300 | GL_VENDOR: Google Inc. (Microsoft)
[task 2023-03-17T13:07:10.572Z] 13:07:10     INFO - PID 6300 | mVendor: Unknown
[task 2023-03-17T13:07:10.573Z] 13:07:10     INFO - PID 6300 | GL_RENDERER: ANGLE (Microsoft, Microsoft Basic Render Driver Direct3D11 vs_5_0 ps_5_0, D3D11-10.0.22621.608)
[task 2023-03-17T13:07:10.573Z] 13:07:10     INFO - PID 6300 | mRenderer: Microsoft Basic Render Driver
[task 2023-03-17T13:07:10.574Z] 13:07:10     INFO - PID 6300 | mIsMesa: 0
[task 2023-03-17T13:07:10.574Z] 13:07:10     INFO - PID 6300 | [GPU 8260, Renderer] WARNING: framebuffer_multisample marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:632
[task 2023-03-17T13:07:10.575Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1235
<...>
[task 2023-03-17T13:07:10.627Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/dom/base/ThirdPartyUtil.cpp:421
[task 2023-03-17T13:07:10.628Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1235
[task 2023-03-17T13:07:10.629Z] 13:07:10     INFO - PID 6300 | [Parent 7556, BgIOThreadPool #1] WARNING: 'GetLastError() != ERROR_FILE_NOT_FOUND', file /builds/worker/checkouts/gecko/browser/components/shell/nsWindowsShellService.cpp:1646
[task 2023-03-17T13:07:10.630Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2023-03-17T13:07:10.631Z] 13:07:10     INFO - PID 6300 | [Parent 7556, BgIOThreadPool #1] WARNING: 'GetLastError() != ERROR_FILE_NOT_FOUND', file /builds/worker/checkouts/gecko/browser/components/shell/nsWindowsShellService.cpp:1646
[task 2023-03-17T13:07:10.631Z] 13:07:10     INFO - PID 6300 | [Parent 7556, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2023-03-17T13:07:10.631Z] 13:07:10     INFO - PID 6300 | [ERROR glean_core] Error setting metrics feature config: Json(Error("EOF while parsing a value", line: 1, column: 0))
[task 2023-03-17T13:07:10.632Z] 13:07:10     INFO - PID 6300 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-17T13:07:10.633Z] 13:07:10     INFO - PID 6300 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-17T13:07:10.634Z] 13:07:10     INFO - PID 6300 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-17T13:07:10.635Z] 13:07:10     INFO - PID 6300 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167905550007029\AppData\Local\Temp\tmpekxoipy2\runtests_leaks_5376_utility_pid7252.log
[task 2023-03-17T13:07:10.635Z] 13:07:10     INFO - PID 6300 | [7252, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-03-17T13:07:10.636Z] 13:07:10     INFO - Starting runner
[task 2023-03-17T13:07:12.143Z] 13:07:12     INFO - TEST-START | /encrypted-media/clearkey-mp4-setmediakeys-multiple-times-with-the-same-mediakeys.https.html

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.