1728468 - Intermittent dom/encoding/test/reftest/bug945215-2.html == dom/encoding/test/reftest/bug945215-2-ref.html | load failed: timed out waiting for test to complete (trying to get into WaitForTestEnd)

Status: RESOLVED INCOMPLETE

(Core :: Internationalization, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=350102306&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dN2Jd84tTdaPAobzB03yCQ/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dN2Jd84tTdaPAobzB03yCQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2021-08-31T21:57:15.012Z] 21:57:15     INFO - REFTEST TEST-START | dom/encoding/test/reftest/bug945215-2.html == dom/encoding/test/reftest/bug945215-2-ref.html
[task 2021-08-31T21:57:15.013Z] 21:57:15     INFO - REFTEST TEST-LOAD | file:///builds/worker/workspace/build/tests/reftest/tests/dom/encoding/test/reftest/bug945215-2.html | 4 / 5 (80%)
[task 2021-08-31T21:57:15.116Z] 21:57:15     INFO - [Child 13043, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/layout/style/Loader.cpp:2014
[task 2021-08-31T21:57:15.123Z] 21:57:15     INFO - [Parent 12798, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2979
[task 2021-08-31T21:57:15.267Z] 21:57:15     INFO - JavaScript error: , line 0: NS_ERROR_LOSS_OF_SIGNIFICANT_DATA
[task 2021-08-31T21:57:15.299Z] 21:57:15     INFO - [Child 13015, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/checkouts/gecko/dom/base/WindowDestroyedEvent.cpp:113
[task 2021-08-31T21:57:16.134Z] 21:57:16     INFO - ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpr3h6p_in.mozrunner/runreftest_leaks_tab_pid13105.log
[task 2021-08-31T21:57:16.134Z] 21:57:16     INFO - [13105, 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-31T21:57:16.159Z] 21:57:16     INFO - [Child 13105, Main Thread] WARNING: could not set real-time limit in CubebUtils::InitLibrary: file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:642
[task 2021-08-31T22:02:15.026Z] 22:02:15     INFO - REFTEST TEST-UNEXPECTED-FAIL | dom/encoding/test/reftest/bug945215-2.html == dom/encoding/test/reftest/bug945215-2-ref.html | load failed: timed out waiting for test to complete (trying to get into WaitForTestEnd)
[task 2021-08-31T22:02:15.030Z] 22:02:15     INFO - REFTEST INFO | Saved log: START file:///builds/worker/workspace/build/tests/reftest/tests/dom/encoding/test/reftest/bug945215-2.html
[task 2021-08-31T22:02:15.030Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering WaitForTestEnd
[task 2021-08-31T22:02:15.031Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners
[task 2021-08-31T22:02:15.035Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad fired for previous document
[task 2021-08-31T22:02:15.035Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///builds/worker/workspace/build/tests/reftest/tests/dom/encoding/test/reftest/bug945215-2.html
[task 2021-08-31T22:02:15.035Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///builds/worker/workspace/build/tests/reftest/tests/dom/encoding/test/reftest/bug945215-1.html
[task 2021-08-31T22:02:15.035Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] FromChildAfterPaintListener from file:///builds/worker/workspace/build/tests/reftest/tests/dom/encoding/test/reftest/bug945215-1.html
[task 2021-08-31T22:02:15.035Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///builds/worker/workspace/build/tests/reftest/tests/dom/encoding/test/reftest/bug945215-2.html
[task 2021-08-31T22:02:15.038Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///builds/worker/workspace/build/tests/reftest/tests/dom/encoding/test/reftest/bug945215-1.html
[task 2021-08-31T22:02:15.038Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] FromChildAfterPaintListener from file:///builds/worker/workspace/build/tests/reftest/tests/dom/encoding/test/reftest/bug945215-1.html
[task 2021-08-31T22:02:15.038Z] 22:02:15     INFO - REFTEST INFO | Saved log: Initializing canvas snapshot
[task 2021-08-31T22:02:15.042Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] AttrModifiedListener fired
[task 2021-08-31T22:02:15.042Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] FromChildAfterPaintListener from data:text/html,PASS
[task 2021-08-31T22:02:15.042Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] FromChildAfterPaintListener from data:text/html,PASS
[task 2021-08-31T22:02:15.042Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] FromChildAfterPaintListener from data:text/html,PASS
[task 2021-08-31T22:02:15.044Z] 22:02:15     INFO - REFTEST INFO | Saved log: [CONTENT] FromChildAfterPaintListener from data:text/html,PASS
[task 2021-08-31T22:02:15.044Z] 22:02:15     INFO - REFTEST TEST-END | dom/encoding/test/reftest/bug945215-2.html == dom/encoding/test/reftest/bug945215-2-ref.html
[task 2021-08-31T22:02:15.066Z] 22:02:15     INFO - REFTEST INFO | Slowest test took 1418ms (file:///builds/worker/workspace/build/tests/reftest/tests/dom/encoding/test/reftest/bug863728-2.html)
[task 2021-08-31T22:02:15.067Z] 22:02:15     INFO - REFTEST INFO | Total canvas count = 1
[task 2021-08-31T22:02:15.166Z] 22:02:15     INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-08-31T22:02:15.170Z] 22:02:15     INFO - [Child 13043, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:02:15.196Z] 22:02:15     INFO - [Child 13043, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:02:15.237Z] 22:02:15     INFO - [Child 13043, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:02:15.252Z] 22:02:15     INFO - [Child 13043, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:02:15.263Z] 22:02:15     INFO - [Child 13043, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-08-31T22:02:15.273Z] 22:02:15     INFO - [Child 13043, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:02:15.284Z] 22:02:15     INFO - [Child 13043, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:02:15.285Z] 22:02:15     INFO - [Child 13043, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:02:15.291Z] 22:02:15     INFO - [Child 13043, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-08-31T22:02:15.301Z] 22:02:15     INFO - [Child 13043, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438
[task 2021-08-31T22:02:15.328Z] 22:02:15     INFO - 1630447335327	Marionette	TRACE	Received observer notification quit-application
[task 2021-08-31T22:02:15.328Z] 22:02:15     INFO - 1630447335327	Marionette	INFO	Stopped listening on port 2828
[task 2021-08-31T22:02:15.329Z] 22:02:15     INFO - 1630447335328	Marionette	DEBUG	Marionette stopped listening
[task 2021-08-31T22:02:15.352Z] 22:02:15     INFO - [Child 12890, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:40

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5
• mozilla-central: 2

Platform and build breakdown:

• linux1804-64-qr: 7
  • debug: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728468&startday=2021-08-30&endday=2021-09-05&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• mozilla-central: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728468&startday=2021-09-06&endday=2021-09-12&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728468&startday=2021-09-13&endday=2021-09-19&tree=all

Comment 4

[Marco Castelluccio [:marco]]

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

Comment 5

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

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