Closed
Bug 1897694
Opened 2 years ago
Closed 2 years ago
Intermittent xul-document-load/reftest.list | application terminated with exit code 127
Categories
(Core :: Graphics: WebRender, defect, P5)
Core
Graphics: WebRender
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=458827431&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/e2oGFrtGReOSdH6zjn_XzA/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/e2oGFrtGReOSdH6zjn_XzA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2024-05-20T03:58:27.805Z] 03:58:27 INFO - REFTEST TEST-PASS | layout/reftests/xul-document-load/test022.xhtml == layout/reftests/xul-document-load/reference-green-window.xhtml | image comparison, max difference: 0, number of differing pixels: 0
[task 2024-05-20T03:58:27.806Z] 03:58:27 INFO - REFTEST TEST-END | layout/reftests/xul-document-load/test022.xhtml == layout/reftests/xul-document-load/reference-green-window.xhtml
[task 2024-05-20T03:58:27.832Z] 03:58:27 INFO - REFTEST INFO | Slowest test took 514ms (chrome://reftest/content/xul-document-load/test013.xhtml)
[task 2024-05-20T03:58:27.832Z] 03:58:27 INFO - REFTEST INFO | Total canvas count = 2
[task 2024-05-20T03:58:28.014Z] 03:58:28 INFO - [Child 11676, 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-05-20T03:58:28.015Z] 03:58:28 INFO - [Child 11676, 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-05-20T03:58:28.039Z] 03:58:28 INFO - [Child 11676, 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-05-20T03:58:28.063Z] 03:58:28 INFO - [Child 11676, 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-05-20T03:58:28.078Z] 03:58:28 INFO - [Child 11676, 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-05-20T03:58:28.079Z] 03:58:28 INFO - [Child 11676, 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-05-20T03:58:28.103Z] 03:58:28 INFO - [Child 11676, 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-05-20T03:58:28.369Z] 03:58:28 INFO - 1716177508368 Marionette TRACE Received observer notification quit-application
[task 2024-05-20T03:58:28.370Z] 03:58:28 INFO - 1716177508369 Marionette INFO Stopped listening on port 2828
[task 2024-05-20T03:58:28.373Z] 03:58:28 INFO - 1716177508372 Marionette DEBUG Marionette stopped listening
[task 2024-05-20T03:58:28.403Z] 03:58:28 INFO - [Parent 11602, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:395
[task 2024-05-20T03:58:28.426Z] 03:58:28 INFO - [Parent 11602, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:395
[task 2024-05-20T03:58:28.455Z] 03:58:28 INFO - [Child 11676, Main Thread] WARNING: IPC message 'PNecko::Msg_RemoveRequestContext' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:551
[task 2024-05-20T03:58:28.465Z] 03:58:28 INFO - [Child 11761, Main Thread] WARNING: 'AppShutdown::IsInOrBeyond(ShutdownPhase::AppShutdownConfirmed)', file /builds/worker/checkouts/gecko/gfx/ipc/CanvasShutdownManager.cpp:97
[task 2024-05-20T03:58:28.466Z] 03:58:28 INFO - [Child 11761, Main Thread] WARNING: 'AppShutdown::IsInOrBeyond(ShutdownPhase::AppShutdownConfirmed)', file /builds/worker/checkouts/gecko/gfx/ipc/CanvasShutdownManager.cpp:97
[task 2024-05-20T03:58:28.470Z] 03:58:28 INFO - [Child 11761, Main Thread] WARNING: IPC message 'PNecko::Msg_RemoveRequestContext' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:551
[task 2024-05-20T03:58:28.477Z] 03:58:28 INFO - [Parent 11602, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:240
[task 2024-05-20T03:58:28.478Z] 03:58:28 INFO - [Parent 11602, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:240
[task 2024-05-20T03:58:29.021Z] 03:58:29 INFO - [Parent 11602, IPC I/O Parent] WARNING: [1.1]: GetUserData call for port '6042AD0283257289.AD4B6EF24B4AB590' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:425
[task 2024-05-20T03:58:29.027Z] 03:58:29 INFO - [Child 11865, IPC I/O Child] WARNING: [C85765A8D6354A58.10B170A2AF5FBAB2]: GetUserData call for port 'BE75ADB0381CDBA5.1666E522A4F1121D' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:425
[task 2024-05-20T03:58:29.038Z] 03:58:29 INFO - [Child 11810, Main Thread] WARNING: 'AppShutdown::IsInOrBeyond(ShutdownPhase::AppShutdownConfirmed)', file /builds/worker/checkouts/gecko/gfx/ipc/CanvasShutdownManager.cpp:97
[task 2024-05-20T03:58:29.039Z] 03:58:29 INFO - [Child 11810, Main Thread] WARNING: 'AppShutdown::IsInOrBeyond(ShutdownPhase::AppShutdownConfirmed)', file /builds/worker/checkouts/gecko/gfx/ipc/CanvasShutdownManager.cpp:97
[task 2024-05-20T03:58:29.106Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.108Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.109Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.110Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.110Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.110Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: fs: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.110Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.110Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.110Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.110Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.112Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: idb: '0.004000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.113Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: idb: '0.004000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.113Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: idb: '0.004000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.114Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: idb: '0.004000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.130Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: idb: '0.024000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.131Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: idb: '0.024000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.132Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: idb: '0.024000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.133Z] 03:58:29 INFO - [Parent 11602, QuotaManager IO] WARNING: quota manager shutdown step: '0.024000s: ShutdownStorageOp::DoDirectoryWork -> ShutdownStorageInternal.', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.136Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: quota manager shutdown step: '0.024000s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.137Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: quota manager shutdown step: '0.024000s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.137Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: idb: '0.024000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.138Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: cache: '0.024000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.139Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: sdb: '0.024000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.139Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: fs: '0.024000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.140Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: ls: '0.024000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.141Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: quota manager shutdown step: '0.024000s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.142Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: quota manager shutdown step: '0.024000s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.144Z] 03:58:29 INFO - [Parent 11602, IPDL Background] WARNING: quota manager shutdown step: '0.024000s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2231
[task 2024-05-20T03:58:29.369Z] 03:58:29 INFO - [Utility 11753, Main Thread] WARNING: IPC message 'PUtilityProcess::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:551
[task 2024-05-20T03:58:29.395Z] 03:58:29 INFO - [Parent 11602, Main Thread] WARNING: 'AppShutdown::IsInOrBeyond(ShutdownPhase::AppShutdownConfirmed)', file /builds/worker/checkouts/gecko/gfx/ipc/CanvasShutdownManager.cpp:97
[task 2024-05-20T03:58:29.397Z] 03:58:29 INFO - [Parent 11602, Main Thread] WARNING: 'AppShutdown::IsInOrBeyond(ShutdownPhase::AppShutdownConfirmed)', file /builds/worker/checkouts/gecko/gfx/ipc/CanvasShutdownManager.cpp:97
[task 2024-05-20T03:58:29.415Z] 03:58:29 INFO - Destroying context 7f94e3130ba0 surface 0 on display 7f94e0532f00
[task 2024-05-20T03:58:29.891Z] 03:58:29 INFO - [Parent 11602, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2024-05-20T03:58:30.105Z] 03:58:30 INFO - REFTEST INFO | Process mode: e10s
[task 2024-05-20T03:58:30.106Z] 03:58:30 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2024-05-20T03:58:30.119Z] 03:58:30 INFO - REFTEST INFO | Running tests in file:///builds/worker/workspace/build/tests/reftest/tests/layout/reftests/css-disabled/textarea/reftest.list
[task 2024-05-20T03:58:30.126Z] 03:58:30 INFO - REFTEST INFO | Running with e10s: True
[task 2024-05-20T03:58:30.126Z] 03:58:30 INFO - REFTEST INFO | Running with fission: True
[task 2024-05-20T03:58:30.128Z] 03:58:30 INFO - REFTEST INFO | Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -profile /tmp/tmpiccbu9_c.mozrunner
[task 2024-05-20T03:58:30.149Z] 03:58:30 INFO - ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmpiccbu9_c.mozrunner/runreftest_leaks.log
[task 2024-05-20T03:58:30.634Z] 03:58:30 INFO - [WARN rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2024-05-20T03:58:31.016Z] 03:58:31 INFO - Inconsistency detected by ld.so: ../elf/dl-tls.c: 481: _dl_allocate_tls_init: Assertion `listp->slotinfo[cnt].gen <= GL(dl_tls_generation)' failed!
[task 2024-05-20T04:01:30.197Z] 04:01:30 INFO - REFTEST TEST-UNEXPECTED-FAIL | file:///builds/worker/workspace/build/tests/reftest/tests/layout/reftests/xul-document-load/reftest.list | application terminated with exit code 127
[task 2024-05-20T04:01:30.223Z] 04:01:30 INFO - Traceback (most recent call last):
[task 2024-05-20T04:01:30.224Z] 04:01:30 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1193, in <module>
[task 2024-05-20T04:01:30.224Z] 04:01:30 INFO - sys.exit(run_test_harness(parser, options))
[task 2024-05-20T04:01:30.225Z] 04:01:30 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1185, in run_test_harness
[task 2024-05-20T04:01:30.226Z] 04:01:30 INFO - result = reftest.runTests(options.tests, options)
[task 2024-05-20T04:01:30.226Z] 04:01:30 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 704, in runTests
[task 2024-05-20T04:01:30.226Z] 04:01:30 INFO - return self.runSerialTests(manifests, options, cmdargs)
[task 2024-05-20T04:01:30.227Z] 04:01:30 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1127, in runSerialTests
[task 2024-05-20T04:01:30.228Z] 04:01:30 INFO - status = run(tests=tests)
[task 2024-05-20T04:01:30.228Z] 04:01:30 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1069, in run
[task 2024-05-20T04:01:30.229Z] 04:01:30 INFO - status = self.runApp(
[task 2024-05-20T04:01:30.229Z] 04:01:30 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 1015, in runApp
[task 2024-05-20T04:01:30.229Z] 04:01:30 INFO - raise reraise(exc, value, tb)
[task 2024-05-20T04:01:30.229Z] 04:01:30 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/six.py", line 696, in reraise
[task 2024-05-20T04:01:30.229Z] 04:01:30 INFO - raise value
[task 2024-05-20T04:01:30.229Z] 04:01:30 INFO - File "/builds/worker/workspace/build/tests/reftest/runreftest.py", line 954, in runApp
[task 2024-05-20T04:01:30.229Z] 04:01:30 INFO - marionette.start_session()
[task 2024-05-20T04:01:30.229Z] 04:01:30 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/decorators.py", line 34, in _
[task 2024-05-20T04:01:30.229Z] 04:01:30 INFO - m._handle_socket_failure()
[task 2024-05-20T04:01:30.231Z] 04:01:30 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 820, in _handle_socket_failure
[task 2024-05-20T04:01:30.231Z] 04:01:30 INFO - reraise(exc_cls, exc, tb)
[task 2024-05-20T04:01:30.231Z] 04:01:30 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/six.py", line 696, in reraise
[task 2024-05-20T04:01:30.231Z] 04:01:30 INFO - raise value
[task 2024-05-20T04:01:30.232Z] 04:01:30 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2024-05-20T04:01:30.232Z] 04:01:30 INFO - return func(*args, **kwargs)
[task 2024-05-20T04:01:30.232Z] 04:01:30 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 1368, in start_session
[task 2024-05-20T04:01:30.232Z] 04:01:30 INFO - self.raise_for_port(timeout=timeout)
[task 2024-05-20T04:01:30.232Z] 04:01:30 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/marionette_driver/marionette.py", line 744, in raise_for_port
[task 2024-05-20T04:01:30.232Z] 04:01:30 INFO - raise socket.timeout(
[task 2024-05-20T04:01:30.232Z] 04:01:30 INFO - socket.timeout: Timed out waiting for connection on 127.0.0.1:2828!
[task 2024-05-20T04:01:30.244Z] 04:01:30 INFO - Exception ignored in: <function BaseProfile.__del__ at 0x7f301377a040>
[task 2024-05-20T04:01:30.244Z] 04:01:30 INFO - Traceback (most recent call last):
[task 2024-05-20T04:01:30.244Z] 04:01:30 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/mozprofile/profile.py", line 84, in __del__
[task 2024-05-20T04:01:30.244Z] 04:01:30 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/mozprofile/profile.py", line 294, in cleanup
[task 2024-05-20T04:01:30.245Z] 04:01:30 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/mozprofile/profile.py", line 92, in cleanup
[task 2024-05-20T04:01:30.246Z] 04:01:30 INFO - File "/builds/worker/workspace/build/venv/lib/python3.8/site-packages/mozfile/mozfile.py", line 248, in remove
[task 2024-05-20T04:01:30.246Z] 04:01:30 INFO - ImportError: sys.meta_path is None, Python is likely shutting down
[task 2024-05-20T04:01:30.258Z] 04:01:30 INFO - Return code: 1
[task 2024-05-20T04:01:30.259Z] 04:01:30 ERROR - No suite end message was emitted by this harness.
[task 2024-05-20T04:01:30.259Z] 04:01:30 INFO - TinderboxPrint: reftest-reftest<br/>4764/0/0
[task 2024-05-20T04:01:30.259Z] 04:01:30 WARNING - setting return code to 2
[task 2024-05-20T04:01:30.259Z] 04:01:30 INFO - The reftest suite: reftest ran with return status: FAILURE
[task 2024-05-20T04:01:30.260Z] 04:01:30 INFO - Running post-action listener: _package_coverage_data
[task 2024-05-20T04:01:30.260Z] 04:01:30 INFO - Running post-action listener: _resource_record_post_action
[task 2024-05-20T04:01:30.260Z] 04:01:30 INFO - Running post-action listener: process_java_coverage_data
[task 2024-05-20T04:01:30.260Z] 04:01:30 INFO - [mozharness: 2024-05-20 04:01:30.260464Z] Finished run-tests step (success)
[task 2024-05-20T04:01:30.260Z] 04:01:30 INFO - [mozharness: 2024-05-20 04:01:30.260591Z] Running uninstall step.
[task 2024-05-20T04:01:30.260Z] 04:01:30 INFO - Running pre-action listener: _resource_record_pre_action
[task 2024-05-20T04:01:30.260Z] 04:01:30 INFO - Running main action method: uninstall
[task 2024-05-20T04:01:30.261Z] 04:01:30 INFO - Skipping uninstall for non-MSIX test
[task 2024-05-20T04:01:30.261Z] 04:01:30 INFO - Running post-action listener: _resource_record_post_action
[task 2024-05-20T04:01:30.261Z] 04:01:30 INFO - [mozharness: 2024-05-20 04:01:30.261145Z] Finished uninstall step (success)
[task 2024-05-20T04:01:30.261Z] 04:01:30 INFO - Running post-run listener: _resource_record_post_run
[task 2024-05-20T04:01:30.648Z] 04:01:30 INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2024-05-20T04:01:30.651Z] 04:01:30 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "reftest.reftest.7.overall", "extraOptions": ["e10s", "taskcluster-projects/887720501152/machineTypes/n2-standard-2"], "subtests": [{"name": "cpu_percent", "value": 68.87905360688384}, {"name": "io_write_bytes", "value": 1300811776}, {"name": "io.read_bytes", "value": 6635520}, {"name": "io_write_time", "value": 495788}, {"name": "io_read_time", "value": 5144}]}, {"name": "reftest.reftest.7.start-pulseaudio", "subtests": [{"name": "time", "value": 0.029368654999984756}, {"name": "cpu_percent", "value": 0}]}, {"name": "reftest.reftest.7.install", "subtests": [{"name": "time", "value": 14.672825078000017}, {"name": "cpu_percent", "value": 50.15684931506849}]}, {"name": "reftest.reftest.7.stage-files", "subtests": [{"name": "time", "value": 0.0008662209999670267}, {"name": "cpu_percent", "value": 0}]}, {"name": "reftest.reftest.7.run-tests", "subtests": [{"name": "time", "value": 1193.974488047}, {"name": "cpu_percent", "value": 69.11255443886196}]}, {"name": "reftest.reftest.7.uninstall", "subtests": [{"name": "time", "value": 0.0003707759999542759}, {"name": "cpu_percent", "value": 0}]}]}
[task 2024-05-20T04:01:30.651Z] 04:01:30 INFO - Total resource usage - Wall time: 1209s; CPU: Can't collect data; Read bytes: 6635520; Write bytes: 1300811776; Read time: 5144; Write time: 495788
[task 2024-05-20T04:01:30.652Z] 04:01:30 INFO - TinderboxPrint: I/O read bytes / time<br/>6,635,520 / 5,144
[task 2024-05-20T04:01:30.653Z] 04:01:30 INFO - TinderboxPrint: I/O write bytes / time<br/>1,300,811,776 / 495,788
[task 2024-05-20T04:01:30.653Z] 04:01:30 INFO - TinderboxPrint: CPU idle<br/>720.2 (30.0%)
[task 2024-05-20T04:01:30.654Z] 04:01:30 INFO - TinderboxPrint: CPU iowait<br/>29.5 (1.2%)
[task 2024-05-20T04:01:30.654Z] 04:01:30 INFO - TinderboxPrint: CPU system<br/>127.5 (5.3%)
[task 2024-05-20T04:01:30.655Z] 04:01:30 INFO - TinderboxPrint: CPU user<br/>1,521.8 (63.3%)
[task 2024-05-20T04:01:30.655Z] 04:01:30 INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2024-05-20T04:01:30.656Z] 04:01:30 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-05-20T04:01:30.657Z] 04:01:30 INFO - install - Wall time: 15s; CPU: 50%; Read bytes: 16384; Write bytes: 16879616; Read time: 16; Write time: 1160
[task 2024-05-20T04:01:30.659Z] 04:01:30 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-05-20T04:01:30.698Z] 04:01:30 INFO - run-tests - Wall time: 1194s; CPU: 69%; Read bytes: 6541312; Write bytes: 1283932160; Read time: 5080; Write time: 494628
[task 2024-05-20T04:01:30.703Z] 04:01:30 INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-05-20T04:01:32.260Z] 04:01:32 WARNING - returning nonzero exit status 2
[task 2024-05-20T04:01:32.364Z] cleanup
[task 2024-05-20T04:01:32.364Z] + cleanup
[task 2024-05-20T04:01:32.364Z] + local rv=2
[task 2024-05-20T04:01:32.364Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2024-05-20T04:01:32.364Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2024-05-20T04:01:32.423Z] + '[' ']'
[task 2024-05-20T04:01:32.423Z] + true
[task 2024-05-20T04:01:32.423Z] + cleanup_xvfb
[task 2024-05-20T04:01:32.423Z] ++ pidof Xvfb
[task 2024-05-20T04:01:32.454Z] + local xvfb_pid=48
[task 2024-05-20T04:01:32.455Z] + local vnc=false
[task 2024-05-20T04:01:32.456Z] + local interactive=false
[task 2024-05-20T04:01:32.457Z] + '[' -n 48 ']'
[task 2024-05-20T04:01:32.458Z] + [[ false == false ]]
[task 2024-05-20T04:01:32.459Z] + [[ false == false ]]
[task 2024-05-20T04:01:32.459Z] + kill 48
[task 2024-05-20T04:01:32.460Z] + screen -XS xvfb quit
[task 2024-05-20T04:01:32.644Z] No screen session found.
[task 2024-05-20T04:01:32.645Z] + true
[task 2024-05-20T04:01:32.645Z] + exit 2
[taskcluster 2024-05-20 04:01:33.601Z] === Task Finished ===
[taskcluster 2024-05-20 04:01:35.186Z] Unsuccessful task run with exit code: 2 completed in 1581.282 seconds
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
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.
Description
•