Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=414393374&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HuJhypU5TeWnVZi-K0kdcg/runs/0/artifacts/public/logs/live_backing.log
[task 2023-05-02T19:50:38.297Z] 19:50:38 INFO - TEST-START | telemetry/marionette/tests/client/test_dynamic_probes.py TestDynamicProbes.test_dynamic_probes
[task 2023-05-02T19:50:38.301Z] 19:50:38 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -no-remote -marionette -profile /builds/worker/workspace/build/tmptmn5g42a.mozrunner
[task 2023-05-02T19:50:40.712Z] 19:50:40 INFO - 1683057040711 Toolkit.Telemetry TRACE TelemetryController::observe - profile-after-change notified.
[task 2023-05-02T19:50:40.719Z] 19:50:40 INFO - 1683057040712 Toolkit.Telemetry TRACE TelemetryController::setupTelemetry
[task 2023-05-02T19:50:40.722Z] 19:50:40 INFO - 1683057040712 Toolkit.Telemetry TRACE TelemetryController::registerJsProbes - registering builtin JS probes
[task 2023-05-02T19:50:40.727Z] 19:50:40 INFO - 1683057040713 Toolkit.Telemetry TRACE TelemetryController::registerScalarProbes - registering scalar builtin JS probes
[task 2023-05-02T19:50:40.731Z] 19:50:40 INFO - 1683057040714 Toolkit.Telemetry TRACE TelemetryController::loadProbeDefinitions - no builtin JS probe file ScalarArtifactDefinitions.json
[task 2023-05-02T19:50:40.739Z] 19:50:40 INFO - 1683057040738 Toolkit.Telemetry TRACE TelemetryReportingPolicy::setup
[task 2023-05-02T19:50:40.744Z] 19:50:40 INFO - 1683057040739 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: true
[task 2023-05-02T19:50:40.748Z] 19:50:40 INFO - 1683057040742 Toolkit.Telemetry TRACE TelemetrySession::earlyInit
[task 2023-05-02T19:50:40.790Z] 19:50:40 INFO - 1683057040789 Toolkit.Telemetry TRACE UpdatePing::init - enabled: true
[task 2023-05-02T19:50:40.851Z] 19:50:40 INFO - 1683057040849 Toolkit.Telemetry TRACE TelemetryEnvironment::constructor
[task 2023-05-02T19:50:41.558Z] 19:50:41 INFO - ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-05-02T19:50:41.600Z] 19:50:41 INFO - ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-05-02T19:50:41.705Z] 19:50:41 INFO - 1683057041704 Toolkit.Telemetry TRACE TelemetryEnvironment::_getGFXData - Only one display adapter detected.
[task 2023-05-02T19:50:41.726Z] 19:50:41 INFO - 1683057041726 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateSearchEngine - ignoring early call
[task 2023-05-02T19:50:41.730Z] 19:50:41 INFO - 1683057041727 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateAddons
[task 2023-05-02T19:50:41.741Z] 19:50:41 INFO - 1683057041740 Toolkit.Telemetry TRACE TelemetryEnvironment::registerChangeListener for CrashAnnotator
[task 2023-05-02T19:50:41.816Z] 19:50:41 INFO - 1683057041815 Marionette INFO Marionette enabled
[task 2023-05-02T19:50:41.828Z] 19:50:41 INFO - 1683057041828 Marionette TRACE Received observer notification final-ui-startup
[task 2023-05-02T19:50:41.840Z] 19:50:41 INFO - 1683057041840 Marionette INFO Listening on port 2828
[task 2023-05-02T19:50:41.845Z] 19:50:41 INFO - 1683057041841 Marionette DEBUG Marionette is listening
[task 2023-05-02T19:50:42.481Z] 19:50:42 INFO - 1683057042480 Toolkit.Telemetry TRACE TelemetryController::registerEventProbes - registering builtin JS Event probes
[task 2023-05-02T19:50:42.484Z] 19:50:42 INFO - 1683057042480 Toolkit.Telemetry TRACE TelemetryController::loadProbeDefinitions - no builtin JS probe file EventArtifactDefinitions.json
[task 2023-05-02T19:50:42.516Z] 19:50:42 INFO - 1683057042516 Toolkit.Telemetry TRACE TelemetryEnvironment::_updateAddons: addons differ
[task 2023-05-02T19:50:42.632Z] 19:50:42 INFO - 1683057042631 Marionette DEBUG Accepted connection 0 from 127.0.0.1:59918
[task 2023-05-02T19:50:42.798Z] 19:50:42 INFO - 1683057042797 Marionette DEBUG Closed connection 0
[task 2023-05-02T19:50:42.808Z] 19:50:42 INFO - 1683057042807 Marionette DEBUG Accepted connection 1 from 127.0.0.1:59920
[task 2023-05-02T19:50:43.062Z] 19:50:43 INFO - 1683057043061 Marionette DEBUG Accepted connection 2 from 127.0.0.1:59922
[task 2023-05-02T19:50:43.066Z] 19:50:43 INFO - 1683057043064 Marionette DEBUG Closed connection 1
[task 2023-05-02T19:50:44.654Z] 19:50:44 INFO - 1683057044653 Marionette DEBUG 2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2023-05-02T19:50:44.687Z] 19:50:44 INFO - 1683057044685 Marionette DEBUG Waiting for initial application window
[task 2023-05-02T19:50:45.083Z] 19:50:45 INFO - 1683057045082 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - Topic gmp-provider-registered
[task 2023-05-02T19:50:45.407Z] 19:50:45 INFO - 1683057045405 Toolkit.Telemetry TRACE TelemetryController::observe - content-process-ready-for-script notified.
[task 2023-05-02T19:50:45.411Z] 19:50:45 INFO - 1683057045410 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: true
[task 2023-05-02T19:50:45.617Z] 19:50:45 INFO - console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-05-02T19:50:46.477Z] 19:50:46 INFO - 1683057046476 Toolkit.Telemetry TRACE TelemetryController::observe - content-process-ready-for-script notified.
[task 2023-05-02T19:50:46.482Z] 19:50:46 INFO - 1683057046481 Toolkit.Telemetry CONFIG TelemetryController::enableTelemetryRecording - canRecordBase:true, canRecordExtended: true
[task 2023-05-02T19:50:47.208Z] 19:50:47 INFO - 1683057047207 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: compositor:created, aData: null
[task 2023-05-02T19:50:47.423Z] 19:50:47 INFO - 1683057047422 Toolkit.Telemetry TRACE TelemetrySession::observe - user-interaction-inactive notified.
[task 2023-05-02T19:50:48.701Z] 19:50:48 INFO - 1683057048700 Toolkit.Telemetry TRACE TelemetrySession::observe - xul-window-visible notified.
[task 2023-05-02T19:50:53.207Z] 19:50:53 INFO - console.error: ({})
[task 2023-05-02T19:50:53.281Z] 19:50:53 INFO - 1683057053280 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: sessionstore-windows-restored, aData: null
<...>
[task 2023-05-02T19:51:12.236Z] 19:51:12 INFO - [Child 4886, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-02T19:51:12.250Z] 19:51:12 INFO - [Child 4886, Main Thread] WARNING: JSWindowActorChild::SendRawMessage (Conduits, ConduitClosed) not sent: !CanSend() || !mManager || !mManager->CanSend(): file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:57
[task 2023-05-02T19:51:12.256Z] 19:51:12 INFO - 1683057072255 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: app.update.lastUpdateTime.browser-cleanup-thumbnails
[task 2023-05-02T19:51:12.259Z] 19:51:12 INFO - 1683057072258 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: app.update.lastUpdateTime.services-settings-poll-changes
[task 2023-05-02T19:51:12.262Z] 19:51:12 INFO - 1683057072261 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: app.update.lastUpdateTime.addon-background-update-timer
[task 2023-05-02T19:51:12.265Z] 19:51:12 INFO - 1683057072265 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: app.update.lastUpdateTime.background-update-timer
[task 2023-05-02T19:51:12.268Z] 19:51:12 INFO - 1683057072268 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: app.update.lastUpdateTime.xpi-signature-verification
[task 2023-05-02T19:51:12.271Z] 19:51:12 INFO - 1683057072270 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: app.update.lastUpdateTime.region-update-timer
[task 2023-05-02T19:51:12.434Z] 19:51:12 INFO - 1683057072433 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: toolkit.startup.last_success
[task 2023-05-02T19:51:12.627Z] 19:51:12 INFO - 1683057072627 Marionette TRACE Received observer notification quit-application
[task 2023-05-02T19:51:12.634Z] 19:51:12 INFO - 1683057072633 Toolkit.Telemetry TRACE TelemetryEnvironment::observe - aTopic: nsPref:changed, aData: browser.sessionstore.resume_session_once
[task 2023-05-02T19:51:12.641Z] 19:51:12 INFO - 1683057072640 Marionette TRACE Received observer notification quit-application
[task 2023-05-02T19:51:12.643Z] 19:51:12 INFO - 1683057072642 Marionette DEBUG Marionette stopped listening
[task 2023-05-02T19:51:12.649Z] 19:51:12 INFO - 1683057072648 Marionette DEBUG 2 <- [1,32,null,{"cause":"restart","forced":false,"in_app":true}]
[task 2023-05-02T19:51:12.748Z] 19:51:12 INFO - console.error: (new Error("Polling for changes failed: Unexpected content-type \"text/plain;charset=US-ASCII\".", "resource://services-settings/remote-settings.sys.mjs", 324))
[task 2023-05-02T19:51:12.795Z] 19:51:12 INFO - 1683057072794 addons.xpi ERROR System addon update list error SyntaxError: The URI is malformed.
[task 2023-05-02T19:51:12.952Z] 19:51:12 INFO - console.error: "DEPRECATION WARNING: Calling FileUtils.getDir(..., ..., true) causes main thread I/O and should be avoided especially during startup/shutdown\nYou may find more details about this deprecation at: https://bugzilla.mozilla.org/show_bug.cgi?id=921157\nresource://gre/modules/FileUtils.sys.mjs 62 FileUtils_getDir\nresource://gre/modules/UpdateService.sys.mjs 1056 getUpdateDirCreate\nresource://gre/modules/UpdateService.sys.mjs 1095 getUpdateFile\nresource://gre/modules/UpdateService.sys.mjs 4449 UM__loadXMLFileIntoArray\nresource://gre/modules/UpdateService.sys.mjs 4282 UpdateManager\nresource://gre/modules/XPCOMUtils.sys.mjs 137 defineLazyServiceGetter/<\nresource://gre/modules/UpdateService.sys.mjs 3381 get _pingSuffix\nresource://gre/modules/UpdateService.sys.mjs 3424 AUS__checkForBackgroundUpdates\nresource://gre/modules/UpdateService.sys.mjs 3369 AUS_notify\nresource://gre/modules/UpdateTimerManager.sys.mjs 205 TM_notify/</<\n"
[task 2023-05-02T19:51:12.958Z] 19:51:12 INFO - console.error: "DEPRECATION WARNING: Calling FileUtils.getDir(..., ..., true) causes main thread I/O and should be avoided especially during startup/shutdown\nYou may find more details about this deprecation at: https://bugzilla.mozilla.org/show_bug.cgi?id=921157\nresource://gre/modules/FileUtils.sys.mjs 62 FileUtils_getDir\nresource://gre/modules/UpdateService.sys.mjs 1056 getUpdateDirCreate\nresource://gre/modules/UpdateService.sys.mjs 1095 getUpdateFile\nresource://gre/modules/UpdateService.sys.mjs 774 getCanApplyUpdates\nresource://gre/modules/UpdateService.sys.mjs 3432 AUS__checkForBackgroundUpdates\nresource://gre/modules/UpdateService.sys.mjs 3369 AUS_notify\nresource://gre/modules/UpdateTimerManager.sys.mjs 205 TM_notify/</<\n"
[task 2023-05-02T19:51:13.035Z] 19:51:13 INFO - FATAL ERROR: Non-local network connections are disabled and a connection attempt to aus5.mozilla.org (35.244.181.201) was made.
[task 2023-05-02T19:51:13.036Z] 19:51:13 INFO - You should only access hostnames available via the test networking proxy (if running mochitests) or from a test-specific httpd.js server (if running xpcshell tests). Browser services should be disabled or redirected to a local server.
[task 2023-05-02T19:51:13.038Z] 19:51:13 INFO - FATAL ERROR: Non-local network connections are disabled and a connection attempt to aus5.mozilla.org (35.244.181.201) was made.
[task 2023-05-02T19:51:13.039Z] 19:51:13 INFO - You should only access hostnames available via the test networking proxy (if running mochitests) or from a test-specific httpd.js server (if running xpcshell tests). Browser services should be disabled or redirected to a local server.
[task 2023-05-02T19:51:13.060Z] 19:51:13 INFO - ThreadSanitizer:DEADLYSIGNAL
[task 2023-05-02T19:51:13.060Z] 19:51:13 INFO - ==4821==ERROR: ThreadSanitizer: SEGV on unknown address 0x000000000000 (pc 0x7f838cb262b3 bp 0x000000000003 sp 0x7ffc86fe1a00 T4821)
[task 2023-05-02T19:51:13.060Z] 19:51:13 INFO - ==4821==The signal is caused by a WRITE memory access.
[task 2023-05-02T19:51:13.060Z] 19:51:13 INFO - ==4821==Hint: address points to the zero page.
[task 2023-05-02T19:51:13.125Z] 19:51:13 INFO - #0 MOZ_Crash /builds/worker/workspace/obj-build/dist/include/mozilla/Assertions.h:261:3 (libxul.so+0x4d082b3) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.126Z] 19:51:13 INFO - #1 mozilla::net::nsHttpChannel::OnStartRequest(nsIRequest*) /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpChannel.cpp:7055:5 (libxul.so+0x4d082b3)
[task 2023-05-02T19:51:13.128Z] 19:51:13 INFO - #2 non-virtual thunk to mozilla::net::nsHttpChannel::OnStartRequest(nsIRequest*) /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpChannel.cpp (libxul.so+0x4d08635) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.128Z] 19:51:13 INFO - #3 nsInputStreamPump::OnStateStart() /builds/worker/checkouts/gecko/netwerk/base/nsInputStreamPump.cpp:503:20 (libxul.so+0x45d14df) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.129Z] 19:51:13 INFO - #4 nsInputStreamPump::OnInputStreamReady(nsIAsyncInputStream*) /builds/worker/checkouts/gecko/netwerk/base/nsInputStreamPump.cpp:408:21 (libxul.so+0x45d0fb2) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.130Z] 19:51:13 INFO - #5 non-virtual thunk to nsInputStreamPump::OnInputStreamReady(nsIAsyncInputStream*) /builds/worker/checkouts/gecko/netwerk/base/nsInputStreamPump.cpp (libxul.so+0x45d23c9) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.130Z] 19:51:13 INFO - #6 operator() /builds/worker/checkouts/gecko/xpcom/io/nsPipe3.cpp:73:47 (libxul.so+0x439ce41) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.131Z] 19:51:13 INFO - #7 already_AddRefed<mozilla::CancelableRunnable> NS_NewCancelableRunnableFunction<CallbackHolder::CallbackHolder(nsIAsyncInputStream*, nsIInputStreamCallback*, unsigned int, nsIEventTarget*)::'lambda'()>(char const*, CallbackHolder::CallbackHolder(nsIAsyncInputStream*, nsIInputStreamCallback*, unsigned int, nsIEventTarget*)::'lambda'()&&)::FuncCancelableRunnable::Run() /builds/worker/workspace/obj-build/dist/include/nsThreadUtils.h:667:9 (libxul.so+0x439ce41)
[task 2023-05-02T19:51:13.132Z] 19:51:13 INFO - #8 mozilla::RunnableTask::Run() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:555:16 (libxul.so+0x43d6362) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.132Z] 19:51:13 INFO - #9 mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:879:26 (libxul.so+0x43cf3a4) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #10 mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:702:15 (libxul.so+0x43cd876) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #11 mozilla::TaskController::ProcessPendingMTTask(bool) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:491:36 (libxul.so+0x43cdc6f) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #12 operator() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:218:37 (libxul.so+0x43d8af4) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #13 mozilla::detail::RunnableFunction<mozilla::TaskController::TaskController()::$_0>::Run() /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.h:548:5 (libxul.so+0x43d8af4)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #14 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1239:16 (libxul.so+0x43f0166) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #15 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:479:10 (libxul.so+0x43f6e96) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #16 mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:85:21 (libxul.so+0x51139de) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #17 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:369:10 (libxul.so+0x5029e88) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #18 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:362:3 (libxul.so+0x5029e88)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #19 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:344:3 (libxul.so+0x5029e88)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #20 nsBaseAppShell::Run() /builds/worker/checkouts/gecko/widget/nsBaseAppShell.cpp:148:27 (libxul.so+0x948c623) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #21 nsAppStartup::Run() /builds/worker/checkouts/gecko/toolkit/components/startup/nsAppStartup.cpp:295:30 (libxul.so+0xbe637a2) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #22 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5648:22 (libxul.so+0xbfc82ab) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #23 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5848:8 (libxul.so+0xbfc8df3) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #24 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5904:21 (libxul.so+0xbfc94a1) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.136Z] 19:51:13 INFO - #25 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0xbfd8762) (BuildId: 1f3ee286c9cb74dedd099dc7f4990169d623b275)
[task 2023-05-02T19:51:13.137Z] 19:51:13 INFO - #26 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:227:22 (firefox-bin+0x13436e) (BuildId: 121bf3b97206b32f25515de23c251e4028d4b19d)
[task 2023-05-02T19:51:13.137Z] 19:51:13 INFO - #27 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:445:16 (firefox-bin+0x13436e)
[task 2023-05-02T19:51:13.137Z] 19:51:13 INFO - #28 __libc_start_main /tmp/glibc/csu/../csu/libc-start.c:310 (libc.so.6+0x21b96) (BuildId: 3247775c25514939b9a5ffa9d3be63f3a4cb9641)
[task 2023-05-02T19:51:13.137Z] 19:51:13 INFO - #29 _start <null> (firefox-bin+0x7e1e8) (BuildId: 121bf3b97206b32f25515de23c251e4028d4b19d)
[task 2023-05-02T19:51:13.137Z] 19:51:13 INFO - ThreadSanitizer can not provide additional info.
[task 2023-05-02T19:51:13.138Z] 19:51:13 INFO - SUMMARY: ThreadSanitizer: SEGV /builds/worker/workspace/obj-build/dist/include/mozilla/Assertions.h:261:3 in MOZ_Crash
[task 2023-05-02T19:51:13.138Z] 19:51:13 INFO - ==4821==ABORTING
[task 2023-05-02T19:51:13.189Z] 19:51:13 INFO - Exiting due to channel error.
[task 2023-05-02T19:51:13.190Z] 19:51:13 INFO - Exiting due to channel error.
[task 2023-05-02T19:51:13.190Z] 19:51:13 INFO - Exiting due to channel error.
[task 2023-05-02T19:51:14.203Z] 19:51:14 INFO - Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: CompositorBridgeChild receives IPC close with reason=AbnormalShutdown (t=9.56329) Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: CompositorBridgeChild receives IPC close with reason=AbnormalShutdown (t=12.1154) Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: CompositorBridgeChild receives IPC close with reason=AbnormalShutdown (t=9.65419)
[task 2023-05-02T19:59:22.787Z] 19:59:22 INFO - TEST-UNEXPECTED-ERROR | telemetry/marionette/tests/client/test_dynamic_probes.py TestDynamicProbes.test_dynamic_probes | OSError: Process has been unexpectedly closed (Exit code: -6) (Reason: Process unexpectedly quit without restarting (exit code: -6))
[task 2023-05-02T19:59:22.788Z] 19:59:22 INFO - Traceback (most recent call last):
[task 2023-05-02T19:59:22.789Z] 19:59:22 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_harness/marionette_test/testcases.py", line 198, in run
[task 2023-05-02T19:59:22.789Z] 19:59:22 INFO - testMethod()
[task 2023-05-02T19:59:22.790Z] 19:59:22 INFO - File "/builds/worker/workspace/build/tests/telemetry/marionette/tests/client/test_dynamic_probes.py", line 19, in test_dynamic_probes
[task 2023-05-02T19:59:22.790Z] 19:59:22 INFO - ping = self.wait_for_ping(self.restart_browser, MAIN_SHUTDOWN_PING)
[task 2023-05-02T19:59:22.791Z] 19:59:22 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/telemetry_harness/testcase.py", line 138, in wait_for_ping
[task 2023-05-02T19:59:22.791Z] 19:59:22 INFO - action_func, ping_filter, 1, ping_server=ping_server
[task 2023-05-02T19:59:22.792Z] 19:59:22 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/telemetry_harness/testcase.py", line 124, in wait_for_pings
[task 2023-05-02T19:59:22.792Z] 19:59:22 INFO - action_func()
[task 2023-05-02T19:59:22.793Z] 19:59:22 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/telemetry_harness/testcase.py", line 144, in restart_browser
[task 2023-05-02T19:59:22.793Z] 19:59:22 INFO - return self.marionette.restart(clean=False, in_app=True)
[task 2023-05-02T19:59:22.793Z] 19:59:22 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py", line 34, in _
[task 2023-05-02T19:59:22.794Z] 19:59:22 INFO - m._handle_socket_failure()
[task 2023-05-02T19:59:22.794Z] 19:59:22 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 781, in _handle_socket_failure
[task 2023-05-02T19:59:22.795Z] 19:59:22 INFO - IOError, IOError(message.format(returncode=returncode, reason=exc)), tb
[task 2023-05-02T19:59:22.795Z] 19:59:22 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/six.py", line 702, in reraise
[task 2023-05-02T19:59:22.796Z] 19:59:22 INFO - raise value.with_traceback(tb)
[task 2023-05-02T19:59:22.796Z] 19:59:22 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py", line 24, in _
[task 2023-05-02T19:59:22.796Z] 19:59:22 INFO - return func(*args, **kwargs)
[task 2023-05-02T19:59:22.797Z] 19:59:22 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 1218, in restart
[task 2023-05-02T19:59:22.797Z] 19:59:22 INFO - tb,
[task 2023-05-02T19:59:22.798Z] 19:59:22 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/six.py", line 702, in reraise
[task 2023-05-02T19:59:22.798Z] 19:59:22 INFO - raise value.with_traceback(tb)
[task 2023-05-02T19:59:22.799Z] 19:59:22 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 1198, in restart
[task 2023-05-02T19:59:22.799Z] 19:59:22 INFO - self.raise_for_port(timeout=timeout_restart, check_process_status=False)
[task 2023-05-02T19:59:22.799Z] 19:59:22 INFO - File "/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py", line 673, in raise_for_port
[task 2023-05-02T19:59:22.800Z] 19:59:22 INFO - self.host, self.port
[task 2023-05-02T19:59:22.800Z] 19:59:22 INFO - TEST-INFO took 524485ms
[task 2023-05-02T19:59:22.801Z] 19:59:22 ERROR - test_end for telemetry/marionette/tests/client/test_dynamic_probes.py TestDynamicProbes.test_dynamic_probes logged while not in progress. Logged with data: {"message": "marionette_driver.errors.InvalidSessionIdException: Please start a session", "expected": "PASS", "stack": "Traceback (most recent call last):\n File \"/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_harness/marionette_test/testcases.py\", line 231, in run\n self.tearDown()\n File \"/builds/worker/workspace/build/venv/lib/python3.7/site-packages/telemetry_harness/testcase.py\", line 231, in tearDown\n super(TelemetryTestCase, self).tearDown()\n File \"/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_harness/runner/mixins/window_manager.py\", line 22, in tearDown\n if len(self.marionette.chrome_window_handles) > len(self.start_windows):\n File \"/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py\", line 1453, in chrome_window_handles\n with self.using_context(\"chrome\"):\n File \"/usr/lib/python3.7/contextlib.py\", line 112, in __enter__\n return next(self.gen)\n File \"/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py\", line 1522, in using_context\n scope = self._send_message(\"Marionette:GetContext\", key=\"value\")\n File \"/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/decorators.py\", line 24, in _\n return func(*args, **kwargs)\n File \"/builds/worker/workspace/build/venv/lib/python3.7/site-packages/marionette_driver/marionette.py\", line 693, in _send_message\n raise errors.InvalidSessionIdException(\"Please start a session\")\n", "extra": {"class_name": "test_dynamic_probes.TestDynamicProbes", "method_name": "test_dynamic_probes"}, "test": "telemetry/marionette/tests/client/test_dynamic_probes.py TestDynamicProbes.test_dynamic_probes", "status": "ERROR"}
[task 2023-05-02T19:59:22.802Z] 19:59:22 INFO - TEST-START | telemetry/marionette/tests/client/test_shutdown_pings_succeed.py TestShutdownPingsSucced.test_shutdown_pings_succeed_pingsender1
Description
•