Closed Bug 1883099 Opened 1 year ago Closed 1 year ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /FileAPI/blob/Blob-constructor.any.html | single tracking bug

Categories

(Core :: DOM: File, defect, P5)

defect

Tracking

()

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=449119341&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/K2YPJMNjSpee43mp4E8sNw/runs/0/artifacts/public/logs/live_backing.log


TEST-START | /FileAPI/blob/Blob-constructor.any.html
[task 2024-03-01T16:06:38.061Z] 16:06:38     INFO - Closing window 105f60ae-cafb-41af-bbe0-65529b5df46e
[task 2024-03-01T16:06:38.361Z] 16:06:38     INFO - PID 8992 | [WARN  firefox_on_glean::private::timing_distribution] Elapsed milliseconds larger than fits into 32-bytes. Saturating at u32::MAX.
[task 2024-03-01T16:06:59.551Z] 16:06:59     INFO - PID 8992 | [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-03-01T16:06:59.636Z] 16:06:59     INFO - PID 8992 | console.error: URLBar - QuickSuggest.SuggestBackendRust: "Ingest error: Error from Remote Settings: Error parsing URL: relative URL with a cannot-be-a-base base"
[task 2024-03-01T16:07:23.067Z] 16:07:23     INFO - Got timeout in harness
[task 2024-03-01T16:07:23.068Z] 16:07:23     INFO - TEST-UNEXPECTED-TIMEOUT | /FileAPI/blob/Blob-constructor.any.html | TestRunner hit external timeout (this may indicate a hang)
[task 2024-03-01T16:07:23.068Z] 16:07:23     INFO - TEST-INFO took 45016ms
[task 2024-03-01T16:07:23.949Z] 16:07:23     INFO - PID 8992 | 1709309243948	Marionette	INFO	Stopped listening on port 53915
[task 2024-03-01T16:07:24.750Z] 16:07:24     INFO - PID 8992 | [Child 8176, 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-03-01T16:07:24.751Z] 16:07:24     INFO - PID 8992 | [Child 8176, 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-03-01T16:07:24.758Z] 16:07:24     INFO - PID 8992 | [Child 8176, 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-03-01T16:07:24.759Z] 16:07:24     INFO - PID 8992 | [Child 8176, 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-03-01T16:07:24.762Z] 16:07:24     INFO - PID 8992 | [Child 8176, 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-03-01T16:07:24.763Z] 16:07:24     INFO - PID 8992 | [Child 8176, 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-03-01T16:07:24.776Z] 16:07:24     INFO - PID 8992 | [Child 8176, 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-03-01T16:07:24.916Z] 16:07:24     INFO - PID 8992 | [Child 7788, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4198
[task 2024-03-01T16:07:24.918Z] 16:07:24     INFO - PID 8992 | [Child 2656, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4198
[task 2024-03-01T16:07:25.138Z] 16:07:25     INFO - PID 8992 | [GPU 10104, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:395
[task 2024-03-01T16:07:25.177Z] 16:07:25     INFO - PID 8992 | [WARN  glean_core::error_recording] network.dns_start: 1 samples are longer than the maximum of 600000000000000000
[task 2024-03-01T16:07:25.183Z] 16:07:25     INFO - PID 8992 | [Child 7788, IPC I/O Child] WARNING: [1E46AF0678EADDC3.32F6929DAB6073E0]: GetUserData call for port '7B28B4F20B43424E.843365DAF715C7C2' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:407
[task 2024-03-01T16:07:25.200Z] 16:07:25     INFO - PID 8992 | [Parent 4432, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:233
[task 2024-03-01T16:07:25.576Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.582Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.583Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.584Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.584Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.585Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: fs: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.586Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.587Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.587Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.588Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.596Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: idb: '0.016000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.597Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: idb: '0.016000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.597Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: idb: '0.016000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.598Z] 16:07:25     INFO - PID 8992 | [Parent 4432, QuotaManager IO] WARNING: quota manager shutdown step: '0.016000s: ShutdownStorageOp::DoDirectoryWork -> ShutdownStorageInternal.', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.609Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: quota manager shutdown step: '0.031000s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.614Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: quota manager shutdown step: '0.031000s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.615Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: idb: '0.031000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: cache: '0.031000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.616Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: sdb: '0.031000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.617Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: fs: '0.031000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.617Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: ls: '0.031000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.618Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: quota manager shutdown step: '0.031000s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.619Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: quota manager shutdown step: '0.031000s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:25.620Z] 16:07:25     INFO - PID 8992 | [Parent 4432, IPDL Background] WARNING: quota manager shutdown step: '0.031000s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2222
[task 2024-03-01T16:07:26.015Z] 16:07:26     INFO - PID 8992 | [Utility 8976, Main Thread] WARNING: IPC message 'PUtilityProcess::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:534
[task 2024-03-01T16:07:26.034Z] 16:07:26     INFO - PID 8992 | [GPU 10104, Main Thread] WARNING: IPC message 'PGPU::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:534
[task 2024-03-01T16:07:26.039Z] 16:07:26     INFO - PID 8992 | Destroying context 6ec0000 surface 51845e0 on display 6e2ae00
[task 2024-03-01T16:07:26.630Z] 16:07:26     INFO - PID 8992 | [Parent 4432, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2024-03-01T16:07:27.018Z] 16:07:27     INFO - Browser exited with return code 0
[task 2024-03-01T16:07:27.020Z] 16:07:27     INFO - PROCESS LEAKS C:\Users\task_170930265115020\AppData\Local\Temp\tmp4rbq9wuv\runtests_leaks_7256.log
[task 2024-03-01T16:07:27.021Z] 16:07:27     INFO - leakcheck | Processing log file C:\Users\task_170930265115020\AppData\Local\Temp\tmp4rbq9wuv\runtests_leaks_7256.log for scope /FileAPI/blob
[task 2024-03-01T16:07:27.022Z] 16:07:27     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 16000 bytes
[task 2024-03-01T16:07:27.022Z] 16:07:27     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2024-03-01T16:07:27.023Z] 16:07:27     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2024-03-01T16:07:27.023Z] 16:07:27     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 10000 bytes
[task 2024-03-01T16:07:27.023Z] 16:07:27     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2024-03-01T16:07:27.024Z] 16:07:27     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2024-03-01T16:07:27.024Z] 16:07:27     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2024-03-01T16:07:27.025Z] 16:07:27     INFO - TEST-INFO | leakcheck | sandboxbroker process: leak threshold set at 0 bytes
[task 2024-03-01T16:07:27.025Z] 16:07:27     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2024-03-01T16:07:27.026Z] 16:07:27     INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2024-03-01T16:07:27.027Z] 16:07:27     INFO - leakcheck | Processing leak log file C:\Users\task_170930265115020\AppData\Local\Temp\tmp4rbq9wuv\runtests_leaks_7256.log
[task 2024-03-01T16:07:27.027Z] 16:07:27     INFO - TEST-PASS | leakcheck | default no leaks detected!
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.