Filed by: ccozmuta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=389483161&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IduuEtzOQUeozVkE6jopyQ/runs/0/artifacts/public/logs/live_backing.log
[task 2022-09-05T09:01:27.390Z] 09:01:27 INFO - TEST-START | /html/browsers/browsing-the-web/navigating-across-documents/initial-empty-document/iframe-src-204-fragment.html
[task 2022-09-05T09:01:27.394Z] 09:01:27 INFO - Closing window 3060bfa6-b002-433f-bbae-e0da3b02cd7d
[task 2022-09-05T09:01:27.405Z] 09:01:27 INFO - PID 6602 | [Child 6813, Main Thread] WARNING: IPC message 'PSessionStore::Msg_SessionStoreUpdate' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2022-09-05T09:01:27.413Z] 09:01:27 INFO - PID 6602 | [Child 6843, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-09-05T09:01:27.459Z] 09:01:27 INFO - PID 6602 | [Child 6843, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:498
[task 2022-09-05T09:01:27.615Z] 09:01:27 INFO - PID 6602 | [Child 6843, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp:12444
[task 2022-09-05T09:01:27.664Z] 09:01:27 INFO - PID 6602 | [Child 6813, Main Thread] WARNING: IPC message 'PSessionStore::Msg_SessionStoreUpdate' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2022-09-05T09:01:27.865Z] 09:01:27 INFO - PID 6602 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /var/folders/_h/_ptw4k5n7g5288_0xrxn1mzc000014/T/tmp8nnuk1w9/runtests_leaks_5828_tab_pid6885.log
[task 2022-09-05T09:01:27.866Z] 09:01:27 INFO - PID 6602 | [6885, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-09-05T09:01:28.029Z] 09:01:28 INFO - PID 6602 | [Child 6843, Main Thread] WARNING: NS_ENSURE_TRUE(presShell) failed: file /builds/worker/checkouts/gecko/docshell/base/nsDocShell.cpp:12444
[task 2022-09-05T09:01:29.788Z] 09:01:29 INFO -
[task 2022-09-05T09:01:29.788Z] 09:01:29 INFO - TEST-UNEXPECTED-FAIL | /html/browsers/browsing-the-web/navigating-across-documents/initial-empty-document/iframe-src-204-fragment.html | src - assert_equals: history.length must not change after normal navigation from initial empty document expected 1 but got 2
[task 2022-09-05T09:01:29.788Z] 09:01:29 INFO - @http://web-platform.test:8000/html/browsers/browsing-the-web/navigating-across-documents/initial-empty-document/iframe-src-204-fragment.html:40:16
[task 2022-09-05T09:01:29.789Z] 09:01:29 INFO -
[task 2022-09-05T09:01:29.789Z] 09:01:29 INFO - TEST-UNEXPECTED-FAIL | /html/browsers/browsing-the-web/navigating-across-documents/initial-empty-document/iframe-src-204-fragment.html | location.href - assert_equals: history.length must not change after fragment navigation on initial empty document expected 2 but got 1
[task 2022-09-05T09:01:29.789Z] 09:01:29 INFO - @http://web-platform.test:8000/html/browsers/browsing-the-web/navigating-across-documents/initial-empty-document/iframe-src-204-fragment.html:57:16
[task 2022-09-05T09:01:29.792Z] 09:01:29 INFO - ...
[task 2022-09-05T09:01:29.792Z] 09:01:29 INFO - TEST-OK | /html/browsers/browsing-the-web/navigating-across-documents/initial-empty-document/iframe-src-204-fragment.html | took 2401ms
[task 2022-09-05T09:01:29.793Z] 09:01:29 INFO - PID 6602 | 1662368489789 Marionette INFO Stopped listening on port 50899
[task 2022-09-05T09:01:29.796Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=1590ea200 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-05T09:01:29.796Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=1590ea200 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-05T09:01:29.797Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=159499b00 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-05T09:01:29.797Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=159499b00 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-05T09:01:29.798Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=12fa1e500 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-05T09:01:29.798Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=12fa1e500 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-05T09:01:29.798Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=134ac0b00 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-05T09:01:29.799Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=134ac0b00 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-05T09:01:29.799Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=12fa20800 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-05T09:01:29.800Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=12fa20800 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-05T09:01:29.800Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=134ac0600 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-05T09:01:29.801Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=134ac0600 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-05T09:01:29.801Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=1597d7a00 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-05T09:01:29.801Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=1597d7a00 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-05T09:01:29.802Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=12fa22100 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-05T09:01:29.803Z] 09:01:29 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: ContentParent: id=12fa22100 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-05T09:01:29.820Z] 09:01:29 INFO - PID 6602 | [Child 6611, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-09-05T09:01:29.820Z] 09:01:29 INFO - PID 6602 | [Child 6611, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-09-05T09:01:29.823Z] 09:01:29 INFO - PID 6602 | [Child 6611, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-09-05T09:01:29.825Z] 09:01:29 INFO - PID 6602 | [Child 6611, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-09-05T09:01:29.828Z] 09:01:29 INFO - PID 6602 | [Child 6611, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-09-05T09:01:29.829Z] 09:01:29 INFO - PID 6602 | [Child 6611, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-09-05T09:01:29.838Z] 09:01:29 INFO - PID 6602 | [Child 6611, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-09-05T09:01:29.897Z] 09:01:29 INFO - PID 6602 | [Child 6843, 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:4347
[task 2022-09-05T09:01:29.897Z] 09:01:29 INFO - PID 6602 | [Child 6758, 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:4347
[task 2022-09-05T09:01:29.898Z] 09:01:29 INFO - PID 6602 | [Child 6842, 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:4347
[task 2022-09-05T09:01:29.899Z] 09:01:29 INFO - PID 6602 | [Child 6813, 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:4347
<...>
[task 2022-09-05T09:01:30.282Z] 09:01:30 INFO - PID 6602 | [Parent 6602, IPDL Background] WARNING: quota manager shutdown step: '0.002911s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3846
[task 2022-09-05T09:01:30.283Z] 09:01:30 INFO - PID 6602 | [Parent 6602, IPDL Background] WARNING: quota manager shutdown step: '0.002926s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3846
[task 2022-09-05T09:01:30.446Z] 09:01:30 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: nsAppShell::Exit() called redundantly: file /builds/worker/checkouts/gecko/widget/cocoa/nsAppShell.mm:824
[task 2022-09-05T09:01:30.452Z] 09:01:30 INFO - PID 6602 | [Parent 6602, Compositor] WARNING: IPC Connection Error: [Parent][PImageBridgeParent] RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1882
[task 2022-09-05T09:01:31.054Z] 09:01:31 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-09-05T09:01:31.064Z] 09:01:31 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-09-05T09:01:31.082Z] 09:01:31 INFO - PID 6602 | [Parent 6602, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4604
[task 2022-09-05T09:01:31.126Z] 09:01:31 INFO - Browser exited with return code 0
[task 2022-09-05T09:01:31.127Z] 09:01:31 INFO - PROCESS LEAKS /var/folders/_h/_ptw4k5n7g5288_0xrxn1mzc000014/T/tmp8nnuk1w9/runtests_leaks_5828.log
[task 2022-09-05T09:01:31.128Z] 09:01:31 INFO - leakcheck | Processing log file /var/folders/_h/_ptw4k5n7g5288_0xrxn1mzc000014/T/tmp8nnuk1w9/runtests_leaks_5828.log for scope /html/browsers/browsing-the-web
[task 2022-09-05T09:01:31.129Z] 09:01:31 INFO - TEST-INFO | leakcheck | default process: leak threshold set at 102400 bytes
[task 2022-09-05T09:01:31.129Z] 09:01:31 INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 307200 bytes
[task 2022-09-05T09:01:31.130Z] 09:01:31 INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2022-09-05T09:01:31.130Z] 09:01:31 INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 0 bytes
[task 2022-09-05T09:01:31.130Z] 09:01:31 INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2022-09-05T09:01:31.131Z] 09:01:31 INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2022-09-05T09:01:31.131Z] 09:01:31 INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2022-09-05T09:01:31.132Z] 09:01:31 INFO - TEST-INFO | leakcheck | sandboxbroker process: leak threshold set at 0 bytes
[task 2022-09-05T09:01:31.132Z] 09:01:31 INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2022-09-05T09:01:31.132Z] 09:01:31 INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2022-09-05T09:01:31.133Z] 09:01:31 INFO - leakcheck | Processing leak log file /var/folders/_h/_ptw4k5n7g5288_0xrxn1mzc000014/T/tmp8nnuk1w9/runtests_leaks_5828_tab_pid6706.log
[task 2022-09-05T09:01:31.133Z] 09:01:31 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-09-05T09:01:31.133Z] 09:01:31 INFO - leakcheck | Processing leak log file /var/folders/_h/_ptw4k5n7g5288_0xrxn1mzc000014/T/tmp8nnuk1w9/runtests_leaks_5828_tab_pid6712.log
[task 2022-09-05T09:01:31.135Z] 09:01:31 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-09-05T09:01:31.135Z] 09:01:31 INFO - leakcheck | Processing leak log file /var/folders/_h/_ptw4k5n7g5288_0xrxn1mzc000014/T/tmp8nnuk1w9/runtests_leaks_5828_tab_pid6699.log
[task 2022-09-05T09:01:31.138Z] 09:01:31 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-09-05T09:01:31.139Z] 09:01:31 INFO - leakcheck | Processing leak log file /var/folders/_h/_ptw4k5n7g5288_0xrxn1mzc000014/T/tmp8nnuk1w9/runtests_leaks_5828_tab_pid6841.log
[task 2022-09-05T09:01:31.141Z] 09:01:31 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-09-05T09:01:31.142Z] 09:01:31 INFO - leakcheck | Processing leak log file /var/folders/_h/_ptw4k5n7g5288_0xrxn1mzc000014/T/tmp8nnuk1w9/runtests_leaks_5828_tab_pid6670.log
[task 2022-09-05T09:01:31.144Z] 09:01:31 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-09-05T09:01:31.145Z] 09:01:31 INFO - leakcheck | Processing leak log file /var/folders/_h/_ptw4k5n7g5288_0xrxn1mzc000014/T/tmp8nnuk1w9/runtests_leaks_5828_tab_pid6664.log
[task 2022-09-05T09:01:31.148Z] 09:01:31 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-09-05T09:01:31.148Z] 09:01:31 INFO - leakcheck | Processing leak log file /var/folders/_h/_ptw4k5n7g5288_0xrxn1mzc000014/T/tmp8nnuk1w9/runtests_leaks_5828_tab_pid6843.log
[task 2022-09-05T09:01:31.151Z] 09:01:31 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-09-05T09:01:31.151Z] 09:01:31 INFO - leakcheck | Processing leak log file /var/folders/_h/_ptw4k5n7g5288_0xrxn1mzc000014/T/tmp8nnuk1w9/runtests_leaks_5828_tab_pid6711.log
[task 2022-09-05T09:01:31.154Z] 09:01:31 INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-09-05T09:01:31.155Z] 09:01:31 INFO - leakcheck | Processing leak log file /var/folders/_h/_ptw4k5n7g5288_0xrxn1mzc000014/T/tmp8nnuk1w9/runtests_leaks_5828_tab_pid6842.log
[task 2022-09-05T09:01:31.158Z] 09:01:31 INFO - TEST-PASS | leakcheck | tab no leaks detected!
Description
•