Closed Bug 1876857 Opened 1 year ago Closed 1 year ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webaudio/the-audio-api/the-audioworklet-interface/audioworkletnode-channel-count.https.html | single tracking bug

Categories

(Core :: Web Audio, defect, P5)

Product:
Core
Component:
Web Audio
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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

TEST-START | /webaudio/the-audio-api/the-audioworklet-interface/audioworkletnode-channel-count.https.html
[task 2024-01-26T21:39:34.372Z] 21:39:34     INFO - Closing window 7a07b8db-d854-420f-b549-2343aa9661df
[task 2024-01-26T21:39:56.075Z] 21:39:56     INFO - PID 6188 | JavaScript error: https://web-platform.test:8443/webaudio/the-audio-api/the-audioworklet-interface/audioworkletnode-channel-count.https.html, line 47: TypeError: context.suspend is not a function
[task 2024-01-26T21:40:19.373Z] 21:40:19     INFO - Got timeout in harness
[task 2024-01-26T21:40:19.376Z] 21:40:19     INFO - TEST-UNEXPECTED-TIMEOUT | /webaudio/the-audio-api/the-audioworklet-interface/audioworkletnode-channel-count.https.html | TestRunner hit external timeout (this may indicate a hang)
[task 2024-01-26T21:40:19.376Z] 21:40:19     INFO - TEST-INFO expected ERROR | took 45014ms
[task 2024-01-26T21:41:25.009Z] 21:41:25     INFO - PID 6188 | [Parent 1060, Jump List #2] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/widget/windows/WinUtils.cpp:950
[task 2024-01-26T21:41:25.010Z] 21:41:25     INFO - PID 6188 | [Parent 1060, Jump List #2] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/widget/windows/WinUtils.cpp:950
[task 2024-01-26T21:41:30.682Z] 21:41:30     INFO - Browser exited with return code 572
[task 2024-01-26T21:41:30.684Z] 21:41:30  WARNING - Firefox didn't exit cleanly, not processing leak logs
[task 2024-01-26T21:41:30.712Z] 21:41:30     INFO - Browser not responding, setting status to CRASH
[task 2024-01-26T21:41:30.714Z] 21:41:30  WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.TestharnessTest /webaudio/the-audio-api/the-audioworklet-interface/audioworkletnode-channel-count.https.html>, (<wptrunner.wpttest.TestharnessResult CRASH>, []))
[task 2024-01-26T21:41:30.714Z] 21:41:30     INFO - IOError on command, setting status to CRASH
[task 2024-01-26T21:41:30.714Z] 21:41:30     INFO - Closing logging queue
[task 2024-01-26T21:41:30.715Z] 21:41:30     INFO - queue closed
[task 2024-01-26T21:41:30.765Z] 21:41:30     INFO - Application command: Z:\task_170630118565320\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_170630118565320\AppData\Local\Temp\tmp4fuus0qr
[task 2024-01-26T21:41:30.773Z] 21:41:30     INFO - PID 9396 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170630118565320\AppData\Local\Temp\tmp4o4h1lkk\runtests_leaks_8048.log
[task 2024-01-26T21:41:30.773Z] 21:41:30     INFO - PID 9396 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-01-26T21:41:30.774Z] 21:41:30     INFO - PID 9396 | [Parent 1296, Main Thread] WARNING: '!mColorFilterWatcher', file /builds/worker/checkouts/gecko/widget/windows/nsLookAndFeel.cpp:58
[task 2024-01-26T21:41:30.775Z] 21:41:30     INFO - PID 9396 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170630118565320\AppData\Local\Temp\tmp4o4h1lkk\runtests_leaks_8048_gpu_pid3888.log
[task 2024-01-26T21:41:30.776Z] 21:41:30     INFO - PID 9396 | 1706305165667	Marionette	INFO	Marionette enabled
[task 2024-01-26T21:41:30.776Z] 21:41:30     INFO - PID 9396 | 1706305165869	Marionette	INFO	Listening on port 52936
[task 2024-01-26T21:41:30.777Z] 21:41:30     INFO - PID 9396 | [Parent 1296, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1227
[task 2024-01-26T21:41:30.778Z] 21:41:30     INFO - PID 9396 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-01-26T21:41:30.778Z] 21:41:30     INFO - PID 9396 | [Parent 1296, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1904
[task 2024-01-26T21:41:30.779Z] 21:41:30     INFO - PID 9396 | [WARN  webrender::renderer::init] asking to enable_gpu_markers but no supporting extension was found
[task 2024-01-26T21:41:30.780Z] 21:41:30     INFO - PID 9396 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170630118565320\AppData\Local\Temp\tmp4o4h1lkk\runtests_leaks_8048_tab_pid5732.log
[task 2024-01-26T21:41:30.780Z] 21:41:30     INFO - PID 9396 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-01-26T21:41:30.781Z] 21:41:30     INFO - PID 9396 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-01-26T21:41:30.782Z] 21:41:30     INFO - PID 9396 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-01-26T21:41:30.782Z] 21:41:30     INFO - PID 9396 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170630118565320\AppData\Local\Temp\tmp4o4h1lkk\runtests_leaks_8048_tab_pid8676.log
[task 2024-01-26T21:41:30.784Z] 21:41:30     INFO - PID 9396 | [Child 8676, Main Thread] WARNING: Fallback to FallbackRenderer: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2631
[task 2024-01-26T21:41:30.784Z] 21:41:30     INFO - PID 9396 | [Child 8676, Main Thread] WARNING: Fallback to FallbackRenderer: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2631
[task 2024-01-26T21:41:30.785Z] 21:41:30     INFO - PID 9396 | [Child 8676, Main Thread] WARNING: Fallback to FallbackRenderer: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2631
[task 2024-01-26T21:41:30.786Z] 21:41:30     INFO - PID 9396 | [Child 8676, Main Thread] WARNING: Fallback to FallbackRenderer: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2631
[task 2024-01-26T21:41:30.786Z] 21:41:30     INFO - PID 9396 | [Child 8676, Main Thread] WARNING: Fallback to FallbackRenderer: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2631
[task 2024-01-26T21:41:30.787Z] 21:41:30     INFO - PID 9396 | [WARN  webrender::renderer::init] asking to enable_gpu_markers but no supporting extension was found
 INFO - PID 9396 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170630118565320\AppData\Local\Temp\tmp4o4h1lkk\runtests_leaks_8048_tab_pid3596.log
[task 2024-01-26T21:41:30.788Z] 21:41:30     INFO - PID 9396 | [GPU 3888, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2024-01-26T21:41:30.789Z] 21:41:30     INFO - PID 9396 | [Parent 1296, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/checkouts/gecko/layout/generic/nsFrameSelection.cpp:1618
[task 2024-01-26T21:41:30.790Z] 21:41:30     INFO - PID 9396 | [Child 5732, Main Thread] WARNING: IPC message 'PContent::Msg_CleanupPendingLoadState' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2024-01-26T21:41:30.791Z] 21:41:30     INFO - PID 9396 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-01-26T21:41:30.791Z] 21:41:30     INFO - PID 9396 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-01-26T21:41:30.792Z] 21:41:30     INFO - PID 9396 | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-01-26T21:41:30.793Z] 21:41:30     INFO - PID 9396 | [Parent 1296, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1227
[task 2024-01-26T21:41:30.793Z] 21:41:30     INFO - PID 9396 | [Parent 1296, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageConnection.cpp:1188
[task 2024-01-26T21:41:30.794Z] 21:41:30     INFO - PID 9396 | [Parent 1296, QuotaManager IO] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80630001 (NS_ERROR_STORAGE_BUSY): file /builds/worker/checkouts/gecko/storage/mozStorageService.cpp:682
[task 2024-01-26T21:41:30.795Z] 21:41:30     INFO - PID 9396 | [Parent 1296, QuotaManager IO] WARNING: QM_TRY failure (ERROR): '::mozilla::ToResultInvoke< nsCOMPtr<mozIStorageConnection>>( ::std::mem_fn( &::mozilla::detail::DerefedType<decltype(aStorageService)>::OpenDatabaseWithFileURL), (aStorageService), &aFileURL, telemetryFilename, mozIStorageService::CONNECTION_INTERRUPTIBLE) failed with resultCode 0x80630001, resultName NS_ERROR_STORAGE_BUSY', file dom/indexedDB/ActorsParent.cpp:712
[task 2024-01-26T21:41:30.796Z] 21:41:30     INFO - PID 9396 | [Parent 1296, QuotaManager IO] WARNING: QM_TRY failure (WARNING): '"OpenDatabase(aStorageService, aFileURL, aTelemetryId) .map([](auto connection) -> ConnectionType { return Some(std::move(connection)); })" failed with resultCode 0x80630001, resultName NS_ERROR_STORAGE_BUSY', file dom/indexedDB/ActorsParent.cpp:736
[task 2024-01-26T21:41:30.797Z] 21:41:30     INFO - PID 9396 | [Parent 1296, QuotaManager IO] WARNING: Received NS_ERROR_STORAGE_BUSY when attempting to open database '3870112724rsegmnoittet-es.sqlite', retrying for up to 10 seconds: file /builds/worker/checkouts/gecko/dom/indexedDB/ActorsParent.cpp:748
[task 2024-01-26T21:41:30.798Z] 21:41:30     INFO - PID 9396 | [Child 8676, Main Thread] WARNING: Fallback to FallbackRenderer: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2631
[task 2024-01-26T21:41:30.799Z] 21:41:30     INFO - PID 9396 | [Child 8676, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2024-01-26T21:41:30.800Z] 21:41:30     INFO - PID 9396 | [Child 8676, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:61
[task 2024-01-26T21:41:30.801Z] 21:41:30     INFO - PID 9396 | [Parent 1296, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1227
[task 2024-01-26T21:41:30.802Z] 21:41:30     INFO - PID 9396 | [Parent 1296, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2024-01-26T21:41:30.802Z] 21:41:30     INFO - PID 9396 | [Parent 1296, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2024-01-26T21:41:30.803Z] 21:41:30     INFO - PID 9396 | [Parent 1296, BgIOThreadPool #1] WARNING: 'GetLastError() != ERROR_FILE_NOT_FOUND', file /builds/worker/checkouts/gecko/browser/components/shell/nsWindowsShellService.cpp:1297
[task 2024-01-26T21:41:30.804Z] 21:41:30     INFO - PID 9396 | [Parent 1296, BgIOThreadPool #1] WARNING: 'GetLastError() != ERROR_FILE_NOT_FOUND', file /builds/worker/checkouts/gecko/browser/components/shell/nsWindowsShellService.cpp:1297
[task 2024-01-26T21:41:30.805Z] 21:41:30     INFO - PID 9396 | [Parent 1296, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2024-01-26T21:41:30.806Z] 21:41:30     INFO - PID 9396 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170630118565320\AppData\Local\Temp\tmp4o4h1lkk\runtests_leaks_8048_utility_pid1164.log
[task 2024-01-26T21:41:30.807Z] 21:41:30     INFO - PID 9396 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170630118565320\AppData\Local\Temp\tmp4o4h1lkk\runtests_leaks_8048_tab_pid8856.log
[task 2024-01-26T21:41:30.808Z] 21:41:30     INFO - PID 9396 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170630118565320\AppData\Local\Temp\tmp4o4h1lkk\runtests_leaks_8048_tab_pid6132.log
[task 2024-01-26T21:41:30.809Z] 21:41:30     INFO - PID 9396 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_170630118565320\AppData\Local\Temp\tmp4o4h1lkk\runtests_leaks_8048_tab_pid7484.log
[task 2024-01-26T21:41:30.809Z] 21:41:30     INFO - PID 9396 | [Parent 1296, Jump List #2] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/widget/windows/WinUtils.cpp:950
[task 2024-01-26T21:41:30.810Z] 21:41:30     INFO - PID 9396 | [Parent 1296, Jump List #2] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/widget/windows/WinUtils.cpp:950
[task 2024-01-26T21:41:30.810Z] 21:41:30     INFO - Starting runner
[task 2024-01-26T21:41:32.105Z] 21:41:32     INFO - TEST-START | /webaudio/the-audio-api/the-audioworklet-interface/audioworkletnode-construction.https.html

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

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