1769523 - Intermittent /html/semantics/scripting-1/the-script-element/moving-between-documents/ordering/delay-load-event-2.html | Script elements (async) does not delay the load event in the new Document after move - assert_less_than: Load event should not be delay

Status: RESOLVED DUPLICATE of bug 1785556

(Core :: DOM: Core & HTML, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2022-05-16T09:51:49.465Z] 09:51:49     INFO - TEST-START | /html/semantics/scripting-1/the-script-element/moving-between-documents/ordering/delay-load-event-2.html
[task 2022-05-16T09:51:49.475Z] 09:51:49     INFO - Closing window bba53f82-e5b8-4bb4-ae94-022e67097ed0
[task 2022-05-16T09:51:50.165Z] 09:51:50     INFO - PID 8600 | [Parent 8600, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-05-16T09:51:50.202Z] 09:51:50     INFO - PID 8600 | [Parent 8600, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-05-16T09:51:50.233Z] 09:51:50     INFO - PID 8600 | [Parent 8600, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4571
[task 2022-05-16T09:51:50.258Z] 09:51:50     INFO - Browser exited with return code 0
[task 2022-05-16T09:51:50.258Z] 09:51:50     INFO - PROCESS LEAKS /tmp/tmp0kzs5nht/runtests_leaks_1151.log
[task 2022-05-16T09:51:50.258Z] 09:51:50     INFO - leakcheck | Processing log file /tmp/tmp0kzs5nht/runtests_leaks_1151.log for scope /wasm/jsapi/exception
[task 2022-05-16T09:51:50.258Z] 09:51:50     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 16000 bytes
[task 2022-05-16T09:51:50.258Z] 09:51:50     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2022-05-16T09:51:50.258Z] 09:51:50     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2022-05-16T09:51:50.258Z] 09:51:50     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 10000 bytes
[task 2022-05-16T09:51:50.258Z] 09:51:50     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2022-05-16T09:51:50.258Z] 09:51:50     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2022-05-16T09:51:50.258Z] 09:51:50     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2022-05-16T09:51:50.258Z] 09:51:50     INFO - TEST-INFO | leakcheck | sandboxbroker process: leak threshold set at 0 bytes
[task 2022-05-16T09:51:50.258Z] 09:51:50     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2022-05-16T09:51:50.259Z] 09:51:50     INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2022-05-16T09:51:50.259Z] 09:51:50     INFO - leakcheck | Processing leak log file /tmp/tmp0kzs5nht/runtests_leaks_1151_tab_pid8718.log
[task 2022-05-16T09:51:50.260Z] 09:51:50     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-05-16T09:51:50.261Z] 09:51:50     INFO - leakcheck | Processing leak log file /tmp/tmp0kzs5nht/runtests_leaks_1151_tab_pid8689.log
[task 2022-05-16T09:51:50.263Z] 09:51:50     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-05-16T09:51:50.264Z] 09:51:50     INFO - leakcheck | Processing leak log file /tmp/tmp0kzs5nht/runtests_leaks_1151_tab_pid8741.log
[task 2022-05-16T09:51:50.268Z] 09:51:50     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-05-16T09:51:50.268Z] 09:51:50     INFO - leakcheck | Processing leak log file /tmp/tmp0kzs5nht/runtests_leaks_1151_tab_pid8871.log
[task 2022-05-16T09:51:50.270Z] 09:51:50     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-05-16T09:51:50.271Z] 09:51:50     INFO - leakcheck | Processing leak log file /tmp/tmp0kzs5nht/runtests_leaks_1151_tab_pid8811.log
[task 2022-05-16T09:51:50.274Z] 09:51:50     INFO - TEST-PASS | leakcheck | tab no leaks detected!
[task 2022-05-16T09:51:50.275Z] 09:51:50     INFO - leakcheck | Processing leak log file /tmp/tmp0kzs5nht/runtests_leaks_1151.log
[task 2022-05-16T09:51:50.284Z] 09:51:50     INFO - TEST-PASS | leakcheck | default no leaks detected!
[task 2022-05-16T09:51:50.287Z] 09:51:50     INFO - Closing logging queue
[task 2022-05-16T09:51:50.287Z] 09:51:50     INFO - queue closed
[task 2022-05-16T09:51:50.313Z] 09:51:50     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmp2rqdjklv
[task 2022-05-16T09:51:50.340Z] 09:51:50     INFO - Starting runner
[task 2022-05-16T09:51:50.372Z] 09:51:50     INFO - PID 8977 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp2rqdjklv/runtests_leaks_1151.log
[task 2022-05-16T09:51:50.372Z] 09:51:50     INFO - PID 8977 | [8977, 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-05-16T09:51:50.553Z] 09:51:50     INFO - PID 8977 | [Parent 8977, 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:1721
[task 2022-05-16T09:51:51.397Z] 09:51:51     INFO - PID 8977 | [2022-05-16T09:51:51Z WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2022-05-16T09:51:51.866Z] 09:51:51     INFO - PID 8977 | [Parent 8977, Main Thread] WARNING: GLX_swap_control unsupported, ASAP mode may still block on buffer swaps.: file /builds/worker/checkouts/gecko/gfx/gl/GLContextProviderGLX.cpp:214
[task 2022-05-16T09:51:51.921Z] 09:51:51     INFO - PID 8977 | 1652694711920	Marionette	INFO	Marionette enabled
[task 2022-05-16T09:51:51.928Z] 09:51:51     INFO - PID 8977 | 1652694711927	Marionette	INFO	Listening on port 58201
[task 2022-05-16T09:51:52.658Z] 09:51:52     INFO - PID 16242 | JavaScript error: http://web-platform.test:8000/html/semantics/scripting-1/the-script-element/resources/throw.js?pipe=trickle(d2), line 1: SyntaxError: Document.querySelector: ':::not-going-to-be-valid' is not a valid selector
[task 2022-05-16T09:51:52.672Z] 09:51:52     INFO - PID 16242 | [2022-05-16T09:51:52Z WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2022-05-16T09:51:53.014Z] 09:51:53     INFO - PID 8977 | [Parent 8977, GMPThread] WARNING: Failed to delete GMP storage directory: file /builds/worker/checkouts/gecko/dom/media/gmp/GMPServiceParent.cpp:1754
[task 2022-05-16T09:51:53.037Z] 09:51:53     INFO - PID 8977 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp2rqdjklv/runtests_leaks_1151_tab_pid9071.log
[task 2022-05-16T09:51:53.038Z] 09:51:53     INFO - PID 8977 | [9071, 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-05-16T09:51:53.067Z] 09:51:53     INFO - PID 8977 | [Child 9071, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:693
[task 2022-05-16T09:51:53.173Z] 09:51:53     INFO - PID 8977 | [Child 9071, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2810
[task 2022-05-16T09:51:53.188Z] 09:51:53     INFO - PID 8977 | [Child 9071, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2810
[task 2022-05-16T09:51:53.198Z] 09:51:53     INFO - PID 8977 | [Child 9071, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2810
[task 2022-05-16T09:51:53.207Z] 09:51:53     INFO - PID 8977 | [Child 9071, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2810
[task 2022-05-16T09:51:53.215Z] 09:51:53     INFO - PID 8977 | [Child 9071, Main Thread] WARNING: Fallback to BasicLayerManager: file /builds/worker/checkouts/gecko/dom/ipc/BrowserChild.cpp:2810
[task 2022-05-16T09:51:53.290Z] 09:51:53     INFO - PID 8977 | [GLX] window 1e0002c has VisualID 0x41
[task 2022-05-16T09:51:53.302Z] 09:51:53     INFO - PID 8977 | GL_VENDOR: VMware, Inc.
[task 2022-05-16T09:51:53.304Z] 09:51:53     INFO - PID 8977 | mVendor: VMware, Inc.
[task 2022-05-16T09:51:53.304Z] 09:51:53     INFO - PID 8977 | GL_RENDERER: llvmpipe (LLVM 10.0.0, 128 bits)
[task 2022-05-16T09:51:53.306Z] 09:51:53     INFO - PID 8977 | mRenderer: Unknown
[task 2022-05-16T09:51:53.306Z] 09:51:53     INFO - PID 8977 | mIsMesa: 1
[task 2022-05-16T09:51:53.306Z] 09:51:53     INFO - PID 8977 | [Parent 8977, Renderer] WARNING: robust_buffer_access_behavior marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:632
[task 2022-05-16T09:51:53.306Z] 09:51:53     INFO - PID 8977 | [Parent 8977, Renderer] WARNING: Robustness supported, strategy is not LOSE_CONTEXT_ON_RESET!: file /builds/worker/checkouts/gecko/gfx/gl/GLContext.cpp:999
[task 2022-05-16T09:51:53.307Z] 09:51:53     INFO - PID 8977 | [Parent 8977, Renderer] WARNING: robustness marked as unsupported: file /builds/worker/checkouts/gecko/gfx/gl/GLContextFeatures.cpp:632
[task 2022-05-16T09:51:53.308Z] 09:51:53     INFO - PID 8977 | [2022-05-16T09:51:53Z WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2022-05-16T09:51:53.351Z] 09:51:53     INFO - PID 8977 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp2rqdjklv/runtests_leaks_1151_tab_pid9101.log
[task 2022-05-16T09:51:53.352Z] 09:51:53     INFO - PID 8977 | [9101, 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-05-16T09:51:53.377Z] 09:51:53     INFO - PID 8977 | [Child 9101, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:693
[task 2022-05-16T09:51:53.680Z] 09:51:53     INFO - 
[task 2022-05-16T09:51:53.680Z] 09:51:53     INFO - TEST-PASS | /html/semantics/scripting-1/the-script-element/moving-between-documents/ordering/delay-load-event-2.html | Script elements (async) still delay the load event in the original Document after move 
[task 2022-05-16T09:51:53.680Z] 09:51:53     INFO - TEST-UNEXPECTED-FAIL | /html/semantics/scripting-1/the-script-element/moving-between-documents/ordering/delay-load-event-2.html | Script elements (async) does not delay the load event in the new Document after move - assert_less_than: Load event should not be delayed until moved script is loaded expected a number less than 2500 but got 2550
[task 2022-05-16T09:51:53.680Z] 09:51:53     INFO - runDelayEventTest/window.onloadIframe<@http://web-platform.test:8000/html/semantics/scripting-1/the-script-element/moving-between-documents/ordering/helper.js:23:21
[task 2022-05-16T09:51:53.680Z] 09:51:53     INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25
[task 2022-05-16T09:51:53.680Z] 09:51:53     INFO - Test.prototype.step_func_done/<@http://web-platform.test:8000/resources/testharness.js:2665:32
[task 2022-05-16T09:51:53.681Z] 09:51:53     INFO - onload@http://web-platform.test:8000/html/semantics/scripting-1/the-script-element/moving-between-documents/ordering/delay-load-event-iframe.html:1:8
[task 2022-05-16T09:51:53.684Z] 09:51:53     INFO - TEST-OK | /html/semantics/scripting-1/the-script-element/moving-between-documents/ordering/delay-load-event-2.html | took 4220ms
[task 2022-05-16T09:51:53.689Z] 09:51:53     INFO - PID 16242 | 1652694713687	Marionette	INFO	Stopped listening on port 42442
[task 2022-05-16T09:51:53.779Z] 09:51:53     INFO - PID 16242 | [Child 16385, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-05-16T09:51:53.781Z] 09:51:53     INFO - PID 16242 | [Child 16385, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-05-16T09:51:53.798Z] 09:51:53     INFO - PID 16242 | [Child 16385, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-05-16T09:51:53.803Z] 09:51:53     INFO - PID 16242 | [Child 16385, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-05-16T09:51:53.804Z] 09:51:53     INFO - PID 16242 | [Child 16385, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-05-16T09:51:53.805Z] 09:51:53     INFO - PID 16242 | [Child 16385, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-05-16T09:51:53.813Z] 09:51:53     INFO - PID 16242 | [Child 16385, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-05-16T09:51:53.967Z] 09:51:53     INFO - PID 16242 | [Parent 16242, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:365

Comment 1

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 1765219

Comment 2

[Intermittent Failures Robot]

3 failures in 2694 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 3

Platform and build breakdown:

• linux1804-64-qr: 3
  • debug: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1769523&startday=2022-05-16&endday=2022-05-22&tree=all

Comment 3

[Intermittent Failures Robot]

5 failures in 2624 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5

Platform and build breakdown:

• linux1804-64-qr: 5
  • debug: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1769523&startday=2022-05-23&endday=2022-05-29&tree=all

Comment 4

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 1765219

Comment 5

[Intermittent Failures Robot]

5 failures in 2917 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5

Platform and build breakdown:

• linux1804-64-qr: 5
  • debug: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1769523&startday=2022-05-30&endday=2022-06-05&tree=all

Comment 6

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 7

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=384270274&repo=mozilla-esr102

Comment 8

[Intermittent Failures Robot]

2 failures in 3341 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-esr102: 1
• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 2
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1769523&startday=2022-07-11&endday=2022-07-17&tree=all

Comment 9

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 10

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=386952899&repo=mozilla-beta

Comment 11

[Intermittent Failures Robot]

1 failures in 3339 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• windows10-64-2004-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1769523&startday=2022-08-08&endday=2022-08-14&tree=all