Open
Bug 1933899
Opened 13 days ago
Updated 9 days ago
Intermittent [tier 2] image/test/reftest/jxl/reftest.list | application timed out after 370 seconds with no output
Categories
(Core :: Graphics: WebRender, defect, P5)
Core
Graphics: WebRender
Tracking
()
NEW
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: amarc [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=484509095&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/YvSxCosITFGm5-z3a4JqYg/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/YvSxCosITFGm5-z3a4JqYg/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2024-11-28T05:36:44.868Z] 05:36:44 INFO - REFTEST TEST-PASS | image/test/reftest/jxl/jxl-size-33x33.jxl == image/test/reftest/jxl/jxl-size-33x33.png | image comparison, max difference: 0, number of differing pixels: 0
[task 2024-11-28T05:36:44.868Z] 05:36:44 INFO - REFTEST TEST-END | image/test/reftest/jxl/jxl-size-33x33.jxl == image/test/reftest/jxl/jxl-size-33x33.png
[task 2024-11-28T05:36:44.887Z] 05:36:44 INFO - REFTEST INFO | RESTORE PREFERENCE pref(image.jxl.enabled,false)
[task 2024-11-28T05:36:44.888Z] 05:36:44 INFO - REFTEST INFO | Slowest test took 254ms (file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/jxl/jxl-size-33x33.jxl)
[task 2024-11-28T05:36:44.889Z] 05:36:44 INFO - REFTEST INFO | Total canvas count = 0
[task 2024-11-28T05:36:52.953Z] 05:36:52 INFO - 1732772212952 Marionette TRACE Received observer notification quit-application
[task 2024-11-28T05:36:52.954Z] 05:36:52 INFO - 1732772212952 Marionette INFO Stopped listening on port 2828
[task 2024-11-28T05:36:52.954Z] 05:36:52 INFO - 1732772212953 Marionette DEBUG Marionette stopped listening
[task 2024-11-28T05:37:10.950Z] 05:37:10 INFO - WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ContentParent: id=7fc0a30cd100","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3725,"stack":""}] Barrier: profile-before-change
[task 2024-11-28T05:40:01.950Z] 05:40:01 INFO - FATAL ERROR: AsyncShutdown timeout in profile-before-change Conditions: [{"name":"ContentParent: id=7fc0a30cd100","state":{"remoteTypePrefix":"prealloc"},"filename":"/builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp","lineNumber":3725,"stack":""}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources.
[task 2024-11-28T05:40:01.951Z] 05:40:01 INFO - [Parent 2550, Main Thread] ###!!! ABORT: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3725
[task 2024-11-28T05:40:01.951Z] 05:40:01 INFO - ExceptionHandler::GenerateDump attempting to generate:/tmp/tmptjeknq_e.mozrunner/minidumps/1dba4d12-a095-ef30-ea37-e50197b2a62f.dmp
[task 2024-11-28T05:40:01.956Z] 05:40:01 INFO - ExceptionHandler::GenerateDump cloned child 2917
[task 2024-11-28T05:40:01.956Z] 05:40:01 INFO - ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2024-11-28T05:40:01.957Z] 05:40:01 INFO - ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2024-11-28T05:40:02.049Z] 05:40:02 INFO - ExceptionHandler::GenerateDump minidump generation succeeded
[task 2024-11-28T05:46:12.049Z] 05:46:12 ERROR - REFTEST ERROR | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/jxl/reftest.list | application timed out after 370 seconds with no output
[task 2024-11-28T05:46:12.049Z] 05:46:12 WARNING - REFTEST WARNING | Force-terminating active process(es).
[task 2024-11-28T05:46:12.049Z] 05:46:12 INFO - REFTEST TEST-INFO | started process screentopng
[task 2024-11-28T05:46:12.223Z] 05:46:12 INFO - REFTEST TEST-INFO | screentopng: exit 0
[task 2024-11-28T05:46:12.225Z] 05:46:12 INFO - REFTEST INFO | Downloading symbols from: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/N2KZUg1xSRCPbp5FKWK-JA/artifacts/public/build/target.crashreporter-symbols.zip
[task 2024-11-28T05:46:23.469Z] 05:46:23 INFO - REFTEST INFO | Copy/paste: /builds/worker/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --cyborg=/tmp/tmpsdjfaprp/1dba4d12-a095-ef30-ea37-e50197b2a62f.trace /tmp/tmptjeknq_e.mozrunner/minidumps/1dba4d12-a095-ef30-ea37-e50197b2a62f.dmp /tmp/tmps41pds_q
[task 2024-11-28T05:46:28.060Z] 05:46:28 INFO - REFTEST INFO | Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/1dba4d12-a095-ef30-ea37-e50197b2a62f.dmp
[task 2024-11-28T05:46:28.060Z] 05:46:28 INFO - REFTEST INFO | Saved app info as /builds/worker/workspace/build/blobber_upload_dir/1dba4d12-a095-ef30-ea37-e50197b2a62f.extra
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - REFTEST PROCESS-CRASH | application crashed [@ MOZ_Crash] | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/jxl/reftest.list
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Process type: main
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Process pid: 2550
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Crash dump filename: /tmp/tmptjeknq_e.mozrunner/minidumps/1dba4d12-a095-ef30-ea37-e50197b2a62f.dmp
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Operating system: Linux
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - 4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - CPU: amd64
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - family 6 model 85 stepping 7
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - 4 CPUs
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Linux Ubuntu 18.04 - bionic (Ubuntu 18.04.6 LTS)
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO -
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Crash reason: SIGSEGV / SEGV_MAPERR
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Crash address: 0x0000000000000000
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Crashing instruction: `mov dword [0x0], 0x210`
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Memory accessed by instruction:
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - 0. Address: 0x0000000000000000
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Size: 4
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Process uptime: not available
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO -
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Thread 0 firefox-bin (crashed)
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - 0 libxul.so!MOZ_Crash [Assertions.h:08a85f1b7545a8bb7ed6e720fd78705f4f17f570 : 337]
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Found by: inlining
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - 1 libxul.so!Abort [nsDebugImpl.cpp:08a85f1b7545a8bb7ed6e720fd78705f4f17f570 : 528]
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - Found by: inlining
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - 2 libxul.so!NS_DebugBreak [nsDebugImpl.cpp:08a85f1b7545a8bb7ed6e720fd78705f4f17f570 : 485 + 0x24]
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - rax = 0x0000555980c56890 rdx = 0x00007fc0ce4e8584
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - rcx = 0x00007fc0e21e44c0 rbx = 0x00007ffd72efa980
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - rsi = 0x0000000000000000 rdi = 0x0000000000000000
[task 2024-11-28T05:46:28.267Z] 05:46:28 INFO - rbp = 0x00007fc0cb8d5146 rsp = 0x00007ffd72efa580
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - r8 = 0x0000000000000000 r9 = 0x00000000061fa173
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - r10 = 0x5910ab655de711d4 r11 = 0x0000000000000000
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - r12 = 0x0000000000000000 r13 = 0x00007fc0dda09898
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - r14 = 0x00007ffd72efa608 r15 = 0x00007ffd72efa670
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - rip = 0x00007fc0d0ae82a6
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - Found by: given as instruction pointer in context
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - 3 libxul.so!nsDebugImpl::Abort(char const*, int) + 0x1b
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - rbx = 0x00007fc0cbc89790 rbp = 0x00007ffd72efacf0
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - rsp = 0x00007ffd72efacc0 r12 = 0x00007fc0c2e39320
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - r13 = 0x0000000000000009 r14 = 0x0000000000000002
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - r15 = 0x0000000000000000 rip = 0x00007fc0d0ae839c
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - Found by: call frame info
[task 2024-11-28T05:46:28.268Z] 05:46:28 INFO - 4 libxul.so!NS_InvokeByIndex + 0x8d
[task 2024-11-28T05:46:28.269Z] 05:46:28 INFO - rbx = 0x00007fc0cbc89790 rbp = 0x00007ffd72efacf0
[task 2024-11-28T05:46:28.269Z] 05:46:28 INFO - rsp = 0x00007ffd72efacd0 r12 = 0x00007fc0c2e39320
[task 2024-11-28T05:46:28.269Z] 05:46:28 INFO - r13 = 0x0000000000000009 r14 = 0x0000000000000002
[task 2024-11-28T05:46:28.269Z] 05:46:28 INFO - r15 = 0x0000000000000000 rip = 0x00007fc0d0cedf52
[task 2024-11-28T05:46:28.269Z] 05:46:28 INFO - Found by: call frame info
<...>
[task 2024-11-28T05:46:28.537Z] 05:46:28 INFO - 0x7ffd72f25000 - 0x7ffd72f26fff linux-gate.so 0.0.0.0
[task 2024-11-28T05:46:28.537Z] 05:46:28 INFO -
[task 2024-11-28T05:46:28.537Z] 05:46:28 INFO - Unloaded modules:
[task 2024-11-28T05:46:28.537Z] 05:46:28 INFO -
[task 2024-11-28T05:46:28.537Z] 05:46:28 INFO - Unimplemented streams encountered:
[task 2024-11-28T05:46:28.537Z] 05:46:28 INFO - Stream 0x4767000a LinuxDsoDebug (Google Extension) @ 0x000ec468
[task 2024-11-28T05:46:28.537Z] 05:46:28 INFO - Stream 0x47670008 LinuxAuxv (Google Extension) @ 0x000c71f0
[task 2024-11-28T05:46:28.537Z] 05:46:28 INFO - Stream 0x47670006 LinuxCmdLine (Google Extension) @ 0x000c5a18
[task 2024-11-28T05:46:28.902Z] 05:46:28 INFO - REFTEST INFO | Result summary:
[task 2024-11-28T05:46:28.902Z] 05:46:28 INFO - REFTEST INFO | Successful: 191 (191 pass, 0 load only)
[task 2024-11-28T05:46:28.902Z] 05:46:28 INFO - REFTEST INFO | Unexpected: 0 (0 unexpected fail, 0 unexpected pass, 0 unexpected asserts, 0 failed load, 0 exception)
[task 2024-11-28T05:46:28.902Z] 05:46:28 INFO - REFTEST INFO | Known problems: 2 (0 known fail, 0 known asserts, 2 random, 0 skipped, 0 slow)
[task 2024-11-28T05:46:28.902Z] 05:46:28 INFO - REFTEST SUITE-END | Shutdown
[task 2024-11-28T05:46:28.915Z] 05:46:28 INFO - REFTEST INFO | Process mode: e10s
[task 2024-11-28T05:46:28.927Z] 05:46:28 INFO - REFTEST INFO | Running tests in file:///builds/worker/workspace/build/tests/reftest/tests/layout/reftests/canvas/reftest.list
[task 2024-11-28T05:46:28.937Z] 05:46:28 INFO - REFTEST INFO | Running with e10s: True
[task 2024-11-28T05:46:28.938Z] 05:46:28 INFO - REFTEST INFO | Running with fission: True
[task 2024-11-28T05:46:28.938Z] 05:46:28 INFO - REFTEST INFO | Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -profile /tmp/tmp2pe3f0nl.mozrunner
[task 2024-11-28T05:46:29.000Z] 05:46:29 INFO - [CodeCoverage] Setting handlers for process 2939.
[task 2024-11-28T05:46:29.779Z] 05:46:29 INFO - ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-11-28T05:46:29.826Z] 05:46:29 INFO - 1732772789825 Marionette INFO Marionette enabled
[task 2024-11-28T05:46:29.829Z] 05:46:29 INFO - 1732772789828 Marionette TRACE Received observer notification final-ui-startup
[task 2024-11-28T05:46:29.961Z] 05:46:29 INFO - ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2024-11-28T05:46:30.036Z] 05:46:30 INFO - 1732772790035 Marionette INFO Listening on port 2828
[task 2024-11-28T05:46:30.041Z] 05:46:30 INFO - 1732772790040 Marionette DEBUG Marionette is listening
[task 2024-11-28T05:46:30.069Z] 05:46:30 INFO - 1732772790068 Marionette DEBUG Accepted connection 0 from 127.0.0.1:42998
[task 2024-11-28T05:46:30.109Z] 05:46:30 INFO - 1732772790108 Marionette DEBUG Closed connection 0
[task 2024-11-28T05:46:30.110Z] 05:46:30 INFO - 1732772790109 Marionette DEBUG Accepted connection 1 from 127.0.0.1:43000
[task 2024-11-28T05:46:30.422Z] 05:46:30 INFO - 1732772790421 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2024-11-28T05:46:30.431Z] 05:46:30 INFO - 1732772790430 Marionette DEBUG Waiting for initial application window
[task 2024-11-28T05:46:31.515Z] 05:46:31 INFO - [CodeCoverage] Setting handlers for process 3036.
[task 2024-11-28T05:46:31.637Z] 05:46:31 INFO - [CodeCoverage] Setting handlers for process 3044.
[task 2024-11-28T05:46:33.193Z] 05:46:33 INFO - console.error: ({})
[task 2024-11-28T05:46:34.745Z] 05:46:34 INFO - [ERROR error_support::handling] logins-unexpected: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-11-28T05:46:34.746Z] 05:46:34 INFO - [ERROR error_support::handling] suggest-unexpected: Error from Remote Settings: Remote settings error: Error parsing URL: relative URL with a cannot-be-a-base base
[task 2024-11-28T05:46:34.746Z] 05:46:34 INFO - console.error: URLBar - QuickSuggest.SuggestBackendRust: "Error initializing SuggestStore" (new Error("reason: Error from Remote Settings: Remote settings error: Error parsing URL: relative URL with a cannot-be-a-base base", "resource://gre/modules/RustSuggest.sys.mjs", 2228))
[task 2024-11-28T05:46:35.183Z] 05:46:35 INFO - 1732772795176 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2024-11-28T05:46:35.217Z] 05:46:35 INFO - 1732772795216 RemoteAgent TRACE [9] ProgressListener Start: expectNavigation=false resolveWhenStarted=false unloadTimeout=80000 waitForExplicitStart=false
[task 2024-11-28T05:46:35.225Z] 05:46:35 INFO - 1732772795224 RemoteAgent TRACE [9] ProgressListener Setting unload timer (80000ms)
[task 2024-11-28T05:46:35.231Z] 05:46:35 INFO - 1732772795229 RemoteAgent TRACE [9] Wait for initial navigation: isInitial=false, isLoadingDocument=false
[task 2024-11-28T05:46:35.234Z] 05:46:35 INFO - 1732772795233 RemoteAgent TRACE [9] Document already finished loading: about:blank
[task 2024-11-28T05:46:35.235Z] 05:46:35 INFO - 1732772795234 RemoteAgent TRACE [9] ProgressListener Stop: has error=false url=about:blank
[task 2024-11-28T05:46:35.293Z] 05:46:35 INFO - 1732772795291 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"393679b3-76b4-44fa-95c5-1fe02a9cedae","capabilities":{"acceptInsecureCerts":false,"browserName":"firefox","browserVersion":"135.0a1","platformName":"linux","unhandledPromptBehavior":"dismiss and notify","userAgent":"Mozilla/5.0 (X11; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0","moz:buildID":"20241128042549","moz:headless":false,"moz:platformVersion":"4.4.0-1014-aws","moz:processID":2939,"moz:profile":"/tmp/tmp2pe3f0nl.mozrunner","moz:shutdownTimeout":180000,"pageLoadStrategy":"normal","timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"setWindowRect":true,"strictFileInteractability":true,"moz:accessibilityChecks":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2024-11-28T05:46:35.324Z] 05:46:35 INFO - 1732772795323 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"/builds/worker/workspace/build/tests/reftest/specialpowers","temporary":true}]
[task 2024-11-28T05:46:35.429Z] 05:46:35 INFO - 1732772795428 Marionette DEBUG 1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2024-11-28T05:46:35.452Z] 05:46:35 INFO - 1732772795451 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"/builds/worker/workspace/build/tests/reftest/reftest","temporary":true}]
[task 2024-11-28T05:46:35.649Z] 05:46:35 INFO - 1732772795648 RemoteAgent TRACE Received observer notification domwindowopened
[task 2024-11-28T05:46:35.671Z] 05:46:35 INFO - 1732772795670 Marionette DEBUG 1 <- [1,3,null,{"value":"reftest@mozilla.org"}]
[task 2024-11-28T05:46:35.698Z] 05:46:35 INFO - 1732772795697 Marionette DEBUG 1 -> [0,4,"WebDriver:DeleteSession",{}]
[task 2024-11-28T05:46:35.704Z] 05:46:35 INFO - 1732772795703 Marionette DEBUG 1 <- [1,4,null,{"value":null}]
[task 2024-11-28T05:46:36.118Z] 05:46:36 INFO - [CodeCoverage] Setting handlers for process 3129.
[task 2024-11-28T05:46:36.126Z] 05:46:36 INFO - 1732772796125 Marionette DEBUG Closed connection 1
[task 2024-11-28T05:46:36.304Z] 05:46:36 INFO - [CodeCoverage] Setting handlers for process 3127.
[task 2024-11-28T05:46:36.354Z] 05:46:36 INFO - [CodeCoverage] Setting handlers for process 3132.
[task 2024-11-28T05:46:36.355Z] 05:46:36 INFO - [CodeCoverage] Setting handlers for process 3131.
[task 2024-11-28T05:46:36.471Z] 05:46:36 INFO - [GFX1-]: Failed to connect WebRenderBridgeChild. isParent=false
[task 2024-11-28T05:46:36.584Z] 05:46:36 INFO - REFTEST TEST-START | layout/reftests/canvas/default-size.html == layout/reftests/canvas/default-size-ref.html
[task 2024-11-28T05:46:36.585Z] 05:46:36 INFO - REFTEST TEST-LOAD | file:///builds/worker/workspace/build/tests/reftest/tests/layout/reftests/canvas/default-size.html | 0 / 85 (0%)
[task 2024-11-28T05:46:37.137Z] 05:46:37 INFO - [CodeCoverage] Setting handlers for process 3238.
[task 2024-11-28T05:46:37.347Z] 05:46:37 INFO - REFTEST INFO | drawWindow flags = DRAWWINDOW_DRAW_CARET | DRAWWINDOW_DRAW_VIEW | DRAWWINDOW_USE_WIDGET_LAYERS; window size = 800,1000; test browser size = 800,1000
[task 2024-11-28T05:46:37.474Z] 05:46:37 INFO - REFTEST TEST-LOAD | file:///builds/worker/workspace/build/tests/reftest/tests/layout/reftests/canvas/default-size-ref.html | 0 / 85 (0%)
[task 2024-11-28T05:46:37.664Z] 05:46:37 INFO - REFTEST TEST-PASS | layout/reftests/canvas/default-size.html == layout/reftests/canvas/default-size-ref.html | image comparison, max difference: 0, number of differing pixels: 0
| Comment hidden (Intermittent Failures Robot) |
You need to log in
before you can comment on or make changes to this bug.
Description
•