Closed Bug 1723885 Opened 5 years ago Closed 2 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /html/cross-origin-opener-policy/popup-coop-by-sw.https.html?1-4 | expected CRASH

Categories

(Core :: DOM: Service Workers, defect, P3)

defect

Tracking

()

RESOLVED INCOMPLETE

People

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

References

Details

(Keywords: intermittent-failure)

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


[task 2021-08-04T00:47:47.824Z] 00:47:47     INFO - TEST-START | /html/cross-origin-opener-policy/popup-coop-by-sw.https.html?1-4
[task 2021-08-04T00:47:47.857Z] 00:47:47     INFO - Setting pref browser.tabs.remote.useCrossOriginOpenerPolicy (true)
[task 2021-08-04T00:47:47.900Z] 00:47:47     INFO - Setting pref browser.tabs.remote.useCrossOriginEmbedderPolicy (true)
[task 2021-08-04T00:47:48.940Z] 00:47:48     INFO - PID 12068 | [Child 12304, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4227
[task 2021-08-04T00:47:48.952Z] 00:47:48     INFO - PID 12068 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp94o1rhip/runtests_leaks_1143_tab_pid12527.log
[task 2021-08-04T00:47:48.953Z] 00:47:48     INFO - PID 12068 | [12527, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-08-04T00:47:48.973Z] 00:47:48     INFO - PID 12068 | [Child 12527, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-08-04T00:47:49.193Z] 00:47:49     INFO - PID 12068 | [Parent 12068, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:563
[task 2021-08-04T00:47:49.258Z] 00:47:49     INFO - PID 12068 | [Parent 12068, DOMCacheThread] WARNING: QM_TRY failure (WARNING): '"ToResult(file->Remove( false))" failed with resultCode 0x80520006, resultName NS_ERROR_FILE_TARGET_DOES_NOT_EXIST', file dom/cache/FileUtils.cpp:748
[task 2021-08-04T00:47:49.258Z] 00:47:49     INFO - PID 12068 | [Parent 12068, DOMCacheThread] WARNING: QM_TRY failure (WARNING): '"ToResult(file->Remove( false))" failed with resultCode 0x80520006, resultName NS_ERROR_FILE_TARGET_DOES_NOT_EXIST', file dom/cache/FileUtils.cpp:748
[task 2021-08-04T00:47:49.346Z] 00:47:49     INFO - PID 12068 | [Child 12304, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:563
[task 2021-08-04T00:47:49.368Z] 00:47:49     INFO - PID 12068 | [Child 12304, Main Thread] WARNING: '!window', file /builds/worker/checkouts/gecko/dom/cache/CacheStorage.cpp:563
[task 2021-08-04T00:47:49.385Z] 00:47:49     INFO - PID 12068 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp94o1rhip/runtests_leaks_1143_tab_pid12545.log
[task 2021-08-04T00:47:49.387Z] 00:47:49     INFO - PID 12068 | [12545, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-08-04T00:47:49.473Z] 00:47:49     INFO - PID 12068 | [Child 12545, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-08-04T00:47:49.556Z] 00:47:49     INFO - PID 12068 | JavaScript error: https://web-platform.test:8443/html/cross-origin-opener-policy/resources/sw_executor.js?pipe=&uuid=a56c379f-6522-4907-b1fa-eefc2d5ac17e line 21 > eval, line 21: ReferenceError: serviceWorker is not defined
[task 2021-08-04T00:47:50.251Z] 00:47:50     INFO - PID 12068 | [Child 12198, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-04T00:47:50.263Z] 00:47:50     INFO - PID 12068 | [Child 12198, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-04T00:48:19.448Z] 00:48:19     INFO - PID 12068 | [Parent 12068, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp:57
[task 2021-08-04T00:48:19.449Z] 00:48:19     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp:470
[task 2021-08-04T00:48:19.450Z] 00:48:19     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp:124
[task 2021-08-04T00:48:19.451Z] 00:48:19     INFO - PID 12068 | [Parent 12068, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp:57
[task 2021-08-04T00:50:49.853Z] 00:50:49     INFO - TEST-UNEXPECTED-TIMEOUT | /html/cross-origin-opener-policy/popup-coop-by-sw.https.html?1-4 | expected CRASH
[task 2021-08-04T00:50:49.853Z] 00:50:49     INFO - TEST-INFO expected CRASH | took 182028ms
[task 2021-08-04T00:50:50.035Z] 00:50:50     INFO - PID 12068 | 1628038250034	Marionette	INFO	Stopped listening on port 39793
[task 2021-08-04T00:50:50.077Z] 00:50:50     INFO - PID 12068 | [Child 12231, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-08-04T00:50:50.080Z] 00:50:50     INFO - PID 12068 | [Child 12231, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-08-04T00:50:50.092Z] 00:50:50     INFO - PID 12068 | [Child 12231, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-08-04T00:50:50.094Z] 00:50:50     INFO - PID 12068 | [Child 12231, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-08-04T00:50:50.100Z] 00:50:50     INFO - PID 12068 | [Child 12231, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-08-04T00:50:50.101Z] 00:50:50     INFO - PID 12068 | [Child 12231, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-08-04T00:50:50.207Z] 00:50:50     INFO - PID 12068 | [Child 12284, 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:4161
[task 2021-08-04T00:50:50.243Z] 00:50:50     INFO - PID 12068 | [Parent 12068, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:370
[task 2021-08-04T00:50:50.251Z] 00:50:50     INFO - PID 12068 | JavaScript error: resource:///modules/Interactions.jsm, line 230: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIUserIdleService.removeIdleObserver]
[task 2021-08-04T00:50:50.254Z] 00:50:50     INFO - PID 12068 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-08-04T00:50:50.273Z] 00:50:50     INFO - PID 12068 | [Parent 12068, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:196
[task 2021-08-04T00:50:50.276Z] 00:50:50     INFO - PID 12068 | [Parent 12068, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:196
[task 2021-08-04T00:50:50.276Z] 00:50:50     INFO - PID 12068 | [Parent 12068, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:196
[task 2021-08-04T00:50:50.281Z] 00:50:50     INFO - PID 12068 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-08-04T00:50:50.281Z] 00:50:50     INFO - PID 12068 | [Child 12304, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:327
[task 2021-08-04T00:50:50.333Z] 00:50:50     INFO - PID 12068 | [Child 12284, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-04T00:50:50.345Z] 00:50:50     INFO - PID 12068 | [Child 12284, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-04T00:50:50.405Z] 00:50:50     INFO - PID 12068 | [2021-08-04T00:50:50Z WARN  xulstore::persist] tried to remove key that isn't in the store
[task 2021-08-04T00:50:50.405Z] 00:50:50     INFO - PID 12068 | [2021-08-04T00:50:50Z WARN  xulstore::persist] tried to remove key that isn't in the store
[task 2021-08-04T00:50:50.429Z] 00:50:50     INFO - PID 12068 | [Child 12304, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-04T00:50:50.433Z] 00:50:50     INFO - PID 12068 | [Child 12231, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-04T00:50:50.438Z] 00:50:50     INFO - PID 12068 | [Child 12304, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-04T00:50:50.446Z] 00:50:50     INFO - PID 12068 | [Child 12231, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-04T00:50:50.832Z] 00:50:50     INFO - PID 12068 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-08-04T00:50:50.847Z] 00:50:50     INFO - PID 12068 | [Child 12441, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-04T00:50:50.853Z] 00:50:50     INFO - PID 12068 | [Child 12527, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-04T00:50:50.857Z] 00:50:50     INFO - PID 12068 | [Child 12545, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-04T00:50:50.857Z] 00:50:50     INFO - PID 12068 | [Child 12441, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-04T00:50:50.862Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: idb: '0.000002s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.866Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: idb: '0.000080s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.866Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: idb: '0.000090s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.866Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: idb: '0.000182s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.866Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: idb: '0.000192s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.871Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: idb: '0.000264s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.871Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: idb: '0.000337s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.871Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: idb: '0.000345s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.872Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: idb: '0.000441s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.872Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: idb: '0.000448s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.872Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: cache: '0.000486s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.872Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: sdb: '0.000494s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.872Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: ls: '0.000500s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.872Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: idb: '0.000513s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.872Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: cache: '0.000532s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.872Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: sdb: '0.000538s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.873Z] 00:50:50     INFO - PID 12068 | [Parent 12068, IPDL Background] WARNING: ls: '0.000543s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3709
[task 2021-08-04T00:50:50.873Z] 00:50:50     INFO - PID 12068 | [Child 12545, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-04T00:50:50.874Z] 00:50:50     INFO - PID 12068 | [Child 12527, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-04T00:50:51.982Z] 00:50:51     INFO - PID 12068 | [Parent 12068, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-04T00:50:52.022Z] 00:50:52     INFO - PID 12068 | [Parent 12068, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-04T00:50:52.042Z] 00:50:52     INFO - Browser exited with return code 0
[task 2021-08-04T00:50:52.043Z] 00:50:52     INFO - PROCESS LEAKS /tmp/tmp94o1rhip/runtests_leaks_1143.log
[task 2021-08-04T00:50:52.044Z] 00:50:52     INFO - leakcheck | Processing log file /tmp/tmp94o1rhip/runtests_leaks_1143.log for scope /html/cross-origin-opener-policy
[task 2021-08-04T00:50:52.044Z] 00:50:52     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 51200 bytes
[task 2021-08-04T00:50:52.045Z] 00:50:52     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2021-08-04T00:50:52.045Z] 00:50:52     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2021-08-04T00:50:52.046Z] 00:50:52     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 10000 bytes
[task 2021-08-04T00:50:52.046Z] 00:50:52     INFO - TEST-INFO | leakcheck | plugin process: leak threshold set at 0 bytes
[task 2021-08-04T00:50:52.046Z] 00:50:52     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 0 bytes
[task 2021-08-04T00:50:52.047Z] 00:50:52     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2021-08-04T00:50:52.047Z] 00:50:52     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 102400 bytes
[task 2021-08-04T00:50:52.048Z] 00:50:52     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2021-08-04T00:50:52.048Z] 00:50:52     INFO - leakcheck | Processing leak log file /tmp/tmp94o1rhip/runtests_leaks_1143_tab_pid12284.log
[task 2021-08-04T00:50:52.048Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.049Z] 00:50:52     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 12284
[task 2021-08-04T00:50:52.049Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.049Z] 00:50:52     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-08-04T00:50:52.050Z] 00:50:52     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-08-04T00:50:52.050Z] 00:50:52     INFO -    0 |TOTAL                                 |       57        0|   23297        0|
[task 2021-08-04T00:50:52.051Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.051Z] 00:50:52     INFO - nsTraceRefcnt::DumpStatistics: 786 entries
[task 2021-08-04T00:50:52.052Z] 00:50:52     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-08-04T00:50:52.052Z] 00:50:52     INFO - leakcheck | Processing leak log file /tmp/tmp94o1rhip/runtests_leaks_1143_tab_pid12545.log
[task 2021-08-04T00:50:52.052Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.053Z] 00:50:52     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 12545
[task 2021-08-04T00:50:52.053Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.053Z] 00:50:52     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-08-04T00:50:52.054Z] 00:50:52     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-08-04T00:50:52.054Z] 00:50:52     INFO -    0 |TOTAL                                 |       47        0|   11317        0|
[task 2021-08-04T00:50:52.054Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.055Z] 00:50:52     INFO - nsTraceRefcnt::DumpStatistics: 383 entries
[task 2021-08-04T00:50:52.055Z] 00:50:52     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-08-04T00:50:52.056Z] 00:50:52     INFO - leakcheck | Processing leak log file /tmp/tmp94o1rhip/runtests_leaks_1143_tab_pid12231.log
[task 2021-08-04T00:50:52.056Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.056Z] 00:50:52     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 12231
[task 2021-08-04T00:50:52.057Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.057Z] 00:50:52     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-08-04T00:50:52.058Z] 00:50:52     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-08-04T00:50:52.058Z] 00:50:52     INFO -    0 |TOTAL                                 |       62        0|   71423        0|
[task 2021-08-04T00:50:52.058Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.059Z] 00:50:52     INFO - nsTraceRefcnt::DumpStatistics: 792 entries
[task 2021-08-04T00:50:52.060Z] 00:50:52     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-08-04T00:50:52.060Z] 00:50:52     INFO - leakcheck | Processing leak log file /tmp/tmp94o1rhip/runtests_leaks_1143_tab_pid12198.log
[task 2021-08-04T00:50:52.060Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.061Z] 00:50:52     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 12198
[task 2021-08-04T00:50:52.061Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.062Z] 00:50:52     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-08-04T00:50:52.062Z] 00:50:52     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-08-04T00:50:52.063Z] 00:50:52     INFO -    0 |TOTAL                                 |       59        0|   22372        0|
[task 2021-08-04T00:50:52.063Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.063Z] 00:50:52     INFO - nsTraceRefcnt::DumpStatistics: 719 entries
[task 2021-08-04T00:50:52.064Z] 00:50:52     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-08-04T00:50:52.064Z] 00:50:52     INFO - leakcheck | Processing leak log file /tmp/tmp94o1rhip/runtests_leaks_1143_tab_pid12527.log
[task 2021-08-04T00:50:52.064Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.065Z] 00:50:52     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 12527
[task 2021-08-04T00:50:52.065Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.066Z] 00:50:52     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-08-04T00:50:52.066Z] 00:50:52     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-08-04T00:50:52.067Z] 00:50:52     INFO -    0 |TOTAL                                 |       47        0|   11336        0|
[task 2021-08-04T00:50:52.067Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.067Z] 00:50:52     INFO - nsTraceRefcnt::DumpStatistics: 383 entries
[task 2021-08-04T00:50:52.068Z] 00:50:52     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-08-04T00:50:52.068Z] 00:50:52     INFO - leakcheck | Processing leak log file /tmp/tmp94o1rhip/runtests_leaks_1143.log
[task 2021-08-04T00:50:52.069Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.069Z] 00:50:52     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, default process 12068
[task 2021-08-04T00:50:52.069Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.070Z] 00:50:52     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-08-04T00:50:52.076Z] 00:50:52     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-08-04T00:50:52.076Z] 00:50:52     INFO -    0 |TOTAL                                 |       57        0| 3313077        0|
[task 2021-08-04T00:50:52.076Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.076Z] 00:50:52     INFO - nsTraceRefcnt::DumpStatistics: 2114 entries
[task 2021-08-04T00:50:52.077Z] 00:50:52     INFO - TEST-PASS | leakcheck | default no leaks detected!
[task 2021-08-04T00:50:52.077Z] 00:50:52     INFO - leakcheck | Processing leak log file /tmp/tmp94o1rhip/runtests_leaks_1143_tab_pid12441.log
[task 2021-08-04T00:50:52.077Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.077Z] 00:50:52     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 12441
[task 2021-08-04T00:50:52.077Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.077Z] 00:50:52     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-08-04T00:50:52.077Z] 00:50:52     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-08-04T00:50:52.077Z] 00:50:52     INFO -    0 |TOTAL                                 |       49        0|   13049        0|
[task 2021-08-04T00:50:52.077Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.077Z] 00:50:52     INFO - nsTraceRefcnt::DumpStatistics: 384 entries
[task 2021-08-04T00:50:52.077Z] 00:50:52     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-08-04T00:50:52.078Z] 00:50:52     INFO - leakcheck | Processing leak log file /tmp/tmp94o1rhip/runtests_leaks_1143_tab_pid12304.log
[task 2021-08-04T00:50:52.078Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.078Z] 00:50:52     INFO - == BloatView: ALL (cumulative) LEAK AND BLOAT STATISTICS, tab process 12304
[task 2021-08-04T00:50:52.078Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.078Z] 00:50:52     INFO -      |<----------------Class--------------->|<-----Bytes------>|<----Objects---->|
[task 2021-08-04T00:50:52.078Z] 00:50:52     INFO -      |                                      | Per-Inst   Leaked|   Total      Rem|
[task 2021-08-04T00:50:52.078Z] 00:50:52     INFO -    0 |TOTAL                                 |       69        0| 1331056        0|
[task 2021-08-04T00:50:52.078Z] 00:50:52     INFO - 
[task 2021-08-04T00:50:52.078Z] 00:50:52     INFO - nsTraceRefcnt::DumpStatistics: 996 entries
[task 2021-08-04T00:50:52.079Z] 00:50:52     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2021-08-04T00:50:52.079Z] 00:50:52     INFO - Closing logging queue
[task 2021-08-04T00:50:52.079Z] 00:50:52     INFO - queue closed
[task 2021-08-04T00:50:52.083Z] 00:50:52     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpaphkor8q
[task 2021-08-04T00:50:52.100Z] 00:50:52     INFO - PID 12254 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpvnfy_se3/runtests_leaks_1143.log
[task 2021-08-04T00:50:52.101Z] 00:50:52     INFO - PID 12254 | [12254, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-08-04T00:50:52.102Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:43Z WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2021-08-04T00:50:52.103Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Main Thread] WARNING: GLX_swap_control unsupported, ASAP mode may still block on buffer swaps.: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderGLX.cpp:225
[task 2021-08-04T00:50:52.104Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Main Thread] WARNING: SGI_video_sync unsupported. Falling back to software vsync.: file /builds/worker/checkouts/gecko/gfx/thebes/gfxPlatformGtk.cpp:870
[task 2021-08-04T00:50:52.104Z] 00:50:52     INFO - PID 12254 | 1628038064363	Marionette	INFO	Marionette enabled
[task 2021-08-04T00:50:52.105Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4227
[task 2021-08-04T00:50:52.106Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4225
[task 2021-08-04T00:50:52.106Z] 00:50:52     INFO - PID 12254 | [GLX] window 40002c has VisualID 0x41
[task 2021-08-04T00:50:52.107Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Renderer] WARNING: robust_buffer_access_behavior marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:628
[task 2021-08-04T00:50:52.108Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Renderer] WARNING: Robustness supported, strategy is not LOSE_CONTEXT_ON_RESET!: file /builds/worker/checkouts/gecko/gfx/gl/GLContext.cpp:981
[task 2021-08-04T00:50:52.108Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Renderer] WARNING: robustness marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:628
[task 2021-08-04T00:50:52.109Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:45Z WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2021-08-04T00:50:52.110Z] 00:50:52     INFO - PID 12254 | [Parent 12254, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1623
[task 2021-08-04T00:50:52.110Z] 00:50:52     INFO - PID 12254 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpvnfy_se3/runtests_leaks_1143_tab_pid12392.log
[task 2021-08-04T00:50:52.111Z] 00:50:52     INFO - PID 12254 | [12392, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-08-04T00:50:52.112Z] 00:50:52     INFO - PID 12254 | [Child 12392, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-08-04T00:50:52.112Z] 00:50:52     INFO - PID 12254 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpvnfy_se3/runtests_leaks_1143_tab_pid12413.log
[task 2021-08-04T00:50:52.113Z] 00:50:52     INFO - PID 12254 | [12413, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-08-04T00:50:52.113Z] 00:50:52     INFO - PID 12254 | [Child 12413, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-08-04T00:50:52.114Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4225
[task 2021-08-04T00:50:52.115Z] 00:50:52     INFO - PID 12254 | [Child 12413, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2856
[task 2021-08-04T00:50:52.115Z] 00:50:52     INFO - PID 12254 | [Child 12413, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2856
[task 2021-08-04T00:50:52.116Z] 00:50:52     INFO - PID 12254 | [Child 12413, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2856
[task 2021-08-04T00:50:52.116Z] 00:50:52     INFO - PID 12254 | [Child 12413, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2856
[task 2021-08-04T00:50:52.117Z] 00:50:52     INFO - PID 12254 | [Child 12413, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2856
[task 2021-08-04T00:50:52.117Z] 00:50:52     INFO - PID 12254 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpvnfy_se3/runtests_leaks_1143_tab_pid12470.log
[task 2021-08-04T00:50:52.118Z] 00:50:52     INFO - PID 12254 | [12470, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-08-04T00:50:52.119Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:47Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_TL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 8, found at -1
[task 2021-08-04T00:50:52.119Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:47Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_TR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 10, found at -1
[task 2021-08-04T00:50:52.120Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:47Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_BL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 12, found at -1
[task 2021-08-04T00:50:52.120Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:47Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_BR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 14, found at -1
[task 2021-08-04T00:50:52.121Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Main Thread] WARNING: '!mLastFocusedWindow', file /builds/worker/checkouts/gecko/widget/gtk/IMContextWrapper.cpp:3145
[task 2021-08-04T00:50:52.122Z] 00:50:52     INFO - PID 12254 | [Child 12470, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-08-04T00:50:52.122Z] 00:50:52     INFO - PID 12254 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2021-08-04T00:50:52.123Z] 00:50:52     INFO - PID 12254 | [Child 12470, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:377
[task 2021-08-04T00:50:52.123Z] 00:50:52     INFO - PID 12254 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpvnfy_se3/runtests_leaks_1143_tab_pid12483.log
[task 2021-08-04T00:50:52.124Z] 00:50:52     INFO - PID 12254 | [12483, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-08-04T00:50:52.124Z] 00:50:52     INFO - PID 12254 | [Child 12483, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-08-04T00:50:52.125Z] 00:50:52     INFO - PID 12254 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2021-08-04T00:50:52.125Z] 00:50:52     INFO - PID 12254 | [Child 12483, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:377
[task 2021-08-04T00:50:52.126Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:47Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_TL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 8, found at -1
[task 2021-08-04T00:50:52.126Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:47Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_TR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 10, found at -1
[task 2021-08-04T00:50:52.127Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:47Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRadii_TR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 11, found at -1
[task 2021-08-04T00:50:52.127Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:47Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_BL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 12, found at -1
[task 2021-08-04T00:50:52.128Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:47Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRadii_BL", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 13, found at -1
[task 2021-08-04T00:50:52.128Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:47Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRect_BR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 14, found at -1
[task 2021-08-04T00:50:52.129Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:47Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipRadii_BR", count: 4, kind: F32 } is not found in the shader cs_clip_rectangle. Expected at 15, found at -1
[task 2021-08-04T00:50:52.130Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:49Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aUvRect1", count: 4, kind: F32 } is not found in the shader composite. Expected at 6, found at -1
[task 2021-08-04T00:50:52.130Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:49Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aUvRect2", count: 4, kind: F32 } is not found in the shader composite. Expected at 7, found at -1
[task 2021-08-04T00:50:52.131Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:49Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aColor", count: 4, kind: F32 } is not found in the shader composite. Expected at 3, found at -1
[task 2021-08-04T00:50:52.131Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:49Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aUvRect1", count: 4, kind: F32 } is not found in the shader composite. Expected at 6, found at -1
[task 2021-08-04T00:50:52.132Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:49Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aUvRect2", count: 4, kind: F32 } is not found in the shader composite. Expected at 7, found at -1
[task 2021-08-04T00:50:52.132Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:50Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2021-08-04T00:50:52.133Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:50Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2021-08-04T00:50:52.133Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:50Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2021-08-04T00:50:52.134Z] 00:50:52     INFO - PID 12254 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpvnfy_se3/search.json.mozlz4", (void 0)))
[task 2021-08-04T00:50:52.134Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Main Thread] WARNING: NS_ENSURE_TRUE(rootFrame) failed: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowOuter.cpp:4227
[task 2021-08-04T00:50:52.135Z] 00:50:52     INFO - PID 12254 | [Child 12413, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-08-04T00:50:52.139Z] 00:50:52     INFO - PID 12254 | [Child 12413, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40
[task 2021-08-04T00:50:52.139Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1371
[task 2021-08-04T00:50:52.139Z] 00:50:52     INFO - PID 12254 | [GLX] window 400040 has VisualID 0x41
[task 2021-08-04T00:50:52.139Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Renderer] WARNING: robust_buffer_access_behavior marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:628
[task 2021-08-04T00:50:52.140Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Renderer] WARNING: Robustness supported, strategy is not LOSE_CONTEXT_ON_RESET!: file /builds/worker/checkouts/gecko/gfx/gl/GLContext.cpp:981
[task 2021-08-04T00:50:52.140Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Renderer] WARNING: robustness marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:628
[task 2021-08-04T00:50:52.141Z] 00:50:52     INFO - PID 12254 | [2021-08-04T00:47:50Z WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2021-08-04T00:50:52.141Z] 00:50:52     INFO - PID 12254 | [Parent 12254, 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 2021-08-04T00:50:52.141Z] 00:50:52     INFO - PID 12254 | [Parent 12254, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1371
[task 2021-08-04T00:50:52.143Z] 00:50:52     INFO - PID 12254 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpvnfy_se3/runtests_leaks_1143_tab_pid12601.log
[task 2021-08-04T00:50:52.143Z] 00:50:52     INFO - PID 12254 | [12601, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:250
[task 2021-08-04T00:50:52.143Z] 00:50:52     INFO - PID 12254 | [Child 12601, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:619
[task 2021-08-04T00:50:52.144Z] 00:50:52     INFO - PID 12254 | 1628038071825	Marionette	INFO	Listening on port 49462
[task 2021-08-04T00:50:52.144Z] 00:50:52     INFO - Starting runner
[task 2021-08-04T00:50:53.208Z] 00:50:53     INFO - PID 12254 | [2021-08-04T00:50:53Z WARN  webrender::device::gl] Attribute VertexAttribute { name: "aClipDeviceArea", count: 4, kind: F32 } is not found in the shader cs_clip_image. Expected at 1, found at -1
Flags: needinfo?(jstutte) → needinfo?(valentin.gosu)

I thought it could be related to bug 1664128, but it seems the test just times out for some reason.
I see a lot of fetch requests being made by the SW, not sure exactly how to debug it.
The test also times out in other browsers https://wpt.fyi/results/html/cross-origin-opener-policy/popup-coop-by-sw.https.html?label=experimental&label=master&aligned
But if I run it in Chrome it does eventually complete.

Severity: S4 → --
Component: DOM: Core & HTML → DOM: Service Workers
Flags: needinfo?(valentin.gosu)
Priority: P5 → --

From its ini file we seem to basically not check any result of those two tests in our test harness (which might explain why we do not see any instances here), and looking at the history on wpt.fyi it seems we are the only browser to consistently time out.

There seems to be something Firefox specific to investigate here.

Severity: -- → S3
Flags: needinfo?(echuang)
Priority: -- → P3

As I remember, the problem is that do shell misunderstands the SW response's mime type and uses the wrong parser.

The interesting is if you copy the wpt as a local site and run with a local http server directly, it works correctly.

I will try to provide a patch to fix the timeout issues.

Assignee: nobody → echuang
Flags: needinfo?(echuang)
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.