Intermittent /css/css-contain/contain-crash.html | single tracking bug
Categories
(Core :: Layout, defect, P5)
Tracking
()
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, intermittent-testcase)
Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=397082177&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/b1JZy8nxRmSt9fN2MV1r7g/runs/0/artifacts/public/logs/live_backing.log
[task 2022-11-19T21:34:32.843Z] 21:34:32 INFO - TEST-START | /css/css-contain/crashtests/contain-nested-crash-002.html
[task 2022-11-19T21:34:32.957Z] 21:34:32 INFO - TEST-PASS | /css/css-contain/crashtests/contain-nested-crash-002.html | took 114ms
[task 2022-11-19T21:34:32.958Z] 21:34:32 INFO - Restarting browser for new test group
[task 2022-11-19T21:34:32.960Z] 21:34:32 INFO - PID 5948 | 1668893672959 Marionette INFO Stopped listening on port 38141
[task 2022-11-19T21:34:33.401Z] 21:34:33 INFO - PID 5948 | -----------------------------------------------------
[task 2022-11-19T21:34:33.402Z] 21:34:33 INFO - PID 5948 | Suppressions used:
[task 2022-11-19T21:34:33.402Z] 21:34:33 INFO - PID 5948 | count bytes template
[task 2022-11-19T21:34:33.403Z] 21:34:33 INFO - PID 5948 | 2 288 libfontconfig.so
[task 2022-11-19T21:34:33.404Z] 21:34:33 INFO - PID 5948 | -----------------------------------------------------
[task 2022-11-19T21:34:33.432Z] 21:34:33 INFO - PID 5948 | -----------------------------------------------------
[task 2022-11-19T21:34:33.432Z] 21:34:33 INFO - PID 5948 | Suppressions used:
[task 2022-11-19T21:34:33.433Z] 21:34:33 INFO - PID 5948 | count bytes template
[task 2022-11-19T21:34:33.434Z] 21:34:33 INFO - PID 5948 | 2 288 libfontconfig.so
[task 2022-11-19T21:34:33.434Z] 21:34:33 INFO - PID 5948 | -----------------------------------------------------
[task 2022-11-19T21:34:33.522Z] 21:34:33 INFO - PID 5948 | -----------------------------------------------------
[task 2022-11-19T21:34:33.523Z] 21:34:33 INFO - PID 5948 | Suppressions used:
[task 2022-11-19T21:34:33.523Z] 21:34:33 INFO - PID 5948 | count bytes template
[task 2022-11-19T21:34:33.524Z] 21:34:33 INFO - PID 5948 | 2 288 libfontconfig.so
[task 2022-11-19T21:34:33.524Z] 21:34:33 INFO - PID 5948 | -----------------------------------------------------
[task 2022-11-19T21:34:33.527Z] 21:34:33 INFO - PID 5948 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-19T21:34:33.528Z] 21:34:33 INFO - PID 5948 | Blocker: Waiting for ping task
[task 2022-11-19T21:34:33.528Z] 21:34:33 INFO - PID 5948 | Phase: TelemetryController: Waiting for pending ping activity
[task 2022-11-19T21:34:33.528Z] 21:34:33 INFO - PID 5948 | State: (none)
[task 2022-11-19T21:34:33.529Z] 21:34:33 INFO - PID 5948 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-11-19T21:34:33.530Z] 21:34:33 INFO - PID 5948 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-19T21:34:33.531Z] 21:34:33 INFO - PID 5948 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-19T21:34:33.532Z] 21:34:33 INFO - PID 5948 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-19T21:34:33.533Z] 21:34:33 INFO - PID 5948 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-19T21:34:33.534Z] 21:34:33 INFO - PID 5948 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-19T21:34:33.535Z] 21:34:33 INFO - PID 5948 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-19T21:34:33.535Z] 21:34:33 INFO - PID 5948 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-19T21:34:33.536Z] 21:34:33 INFO - PID 5948 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-11-19T21:34:33.537Z] 21:34:33 INFO - PID 5948 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-11-19T21:34:33.538Z] 21:34:33 INFO - PID 5948 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:540:48
[task 2022-11-19T21:34:33.538Z] 21:34:33 INFO - PID 5948 | send@resource://gre/modules/TelemetryControllerParent.jsm:628:24
[task 2022-11-19T21:34:33.539Z] 21:34:33 INFO - PID 5948 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-11-19T21:34:33.540Z] 21:34:33 INFO - PID 5948 | sendPing@resource://gre/modules/EventPing.jsm:51:30
[task 2022-11-19T21:34:33.541Z] 21:34:33 INFO - PID 5948 | _submitPing@resource://gre/modules/EventPing.jsm:229:12
[task 2022-11-19T21:34:33.541Z] 21:34:33 INFO - PID 5948 | shutdown@resource://gre/modules/EventPing.jsm:112:10
[task 2022-11-19T21:34:33.542Z] 21:34:33 INFO - PID 5948 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:940:31
[task 2022-11-19T21:34:33.543Z] 21:34:33 INFO - PID 5948 | shutdown/<@resource://gre/modules/TelemetryControllerParent.jsm:1010:24
[task 2022-11-19T21:34:33.543Z] 21:34:33 INFO - PID 5948 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-19T21:34:33.597Z] 21:34:33 INFO - PID 5948 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-19T21:34:33.598Z] 21:34:33 INFO - PID 5948 | Blocker: Waiting for ping task
[task 2022-11-19T21:34:33.599Z] 21:34:33 INFO - PID 5948 | Phase: TelemetryController: Waiting for pending ping activity
[task 2022-11-19T21:34:33.599Z] 21:34:33 INFO - PID 5948 | State: (none)
[task 2022-11-19T21:34:33.600Z] 21:34:33 INFO - PID 5948 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-11-19T21:34:33.601Z] 21:34:33 INFO - PID 5948 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-19T21:34:33.601Z] 21:34:33 INFO - PID 5948 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-19T21:34:33.602Z] 21:34:33 INFO - PID 5948 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-19T21:34:33.603Z] 21:34:33 INFO - PID 5948 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-19T21:34:33.603Z] 21:34:33 INFO - PID 5948 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-19T21:34:33.604Z] 21:34:33 INFO - PID 5948 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-19T21:34:33.605Z] 21:34:33 INFO - PID 5948 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-19T21:34:33.605Z] 21:34:33 INFO - PID 5948 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-11-19T21:34:33.606Z] 21:34:33 INFO - PID 5948 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-11-19T21:34:33.607Z] 21:34:33 INFO - PID 5948 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:540:48
[task 2022-11-19T21:34:33.607Z] 21:34:33 INFO - PID 5948 | send@resource://gre/modules/TelemetryControllerParent.jsm:628:24
[task 2022-11-19T21:34:33.608Z] 21:34:33 INFO - PID 5948 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-11-19T21:34:33.609Z] 21:34:33 INFO - PID 5948 | saveShutdownPings@resource://gre/modules/TelemetrySession.jsm:982:34
[task 2022-11-19T21:34:33.609Z] 21:34:33 INFO - PID 5948 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1232:20
[task 2022-11-19T21:34:33.610Z] 21:34:33 INFO - PID 5948 | cleanup@resource://gre/modules/TelemetrySession.jsm:1239:9
[task 2022-11-19T21:34:33.611Z] 21:34:33 INFO - PID 5948 | shutdownChromeProcess@resource://gre/modules/TelemetrySession.jsm:1256:14
[task 2022-11-19T21:34:33.611Z] 21:34:33 INFO - PID 5948 | shutdown@resource://gre/modules/TelemetrySession.jsm:247:17
[task 2022-11-19T21:34:33.612Z] 21:34:33 INFO - PID 5948 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:959:35
[task 2022-11-19T21:34:33.613Z] 21:34:33 INFO - PID 5948 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-19T21:34:33.639Z] 21:34:33 INFO - PID 5948 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-19T21:34:33.639Z] 21:34:33 INFO - PID 5948 | Blocker: TelemetryController: shutting down
[task 2022-11-19T21:34:33.639Z] 21:34:33 INFO - PID 5948 | Phase: profile-before-change-telemetry
[task 2022-11-19T21:34:33.640Z] 21:34:33 INFO - PID 5948 | State: Error getting state: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange" at addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-19T21:34:33.640Z] 21:34:33 INFO - PID 5948 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
<...>
[task 2022-11-19T21:34:33.697Z] 21:34:33 INFO - PID 5948 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 703: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-11-19T21:34:33.698Z] 21:34:33 INFO - PID 5948 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 703: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-11-19T21:34:35.552Z] 21:34:35 INFO - PID 5948 | -----------------------------------------------------
[task 2022-11-19T21:34:35.554Z] 21:34:35 INFO - PID 5948 | Suppressions used:
[task 2022-11-19T21:34:35.554Z] 21:34:35 INFO - PID 5948 | count bytes template
[task 2022-11-19T21:34:35.555Z] 21:34:35 INFO - PID 5948 | 1 32 nsComponentManagerImpl
[task 2022-11-19T21:34:35.555Z] 21:34:35 INFO - PID 5948 | 633 18115 libfontconfig.so
[task 2022-11-19T21:34:35.556Z] 21:34:35 INFO - PID 5948 | 2 16393 libglib-2.0.so
[task 2022-11-19T21:34:35.556Z] 21:34:35 INFO - PID 5948 | 1 32 XPCWrappedNativeJSOps
[task 2022-11-19T21:34:35.556Z] 21:34:35 INFO - PID 5948 | -----------------------------------------------------
[task 2022-11-19T21:34:35.614Z] 21:34:35 INFO - Browser exited with return code 0
[task 2022-11-19T21:34:35.617Z] 21:34:35 INFO - Closing logging queue
[task 2022-11-19T21:34:35.617Z] 21:34:35 INFO - queue closed
[task 2022-11-19T21:34:35.626Z] 21:34:35 INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2022-11-19T21:34:35.632Z] 21:34:35 INFO - LSan enabled.
[task 2022-11-19T21:34:35.633Z] 21:34:35 INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2022-11-19T21:34:35.633Z] 21:34:35 INFO - UBSan enabled.
[task 2022-11-19T21:34:35.634Z] 21:34:35 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmp4fx0g8qj
[task 2022-11-19T21:34:35.640Z] 21:34:35 INFO - Starting runner
[task 2022-11-19T21:34:36.340Z] 21:34:36 INFO - TEST-UNEXPECTED-TIMEOUT | /css/css-contain/contain-crash.html | expected PASS
[task 2022-11-19T21:34:36.340Z] 21:34:36 INFO - TEST-INFO took 35265ms
[task 2022-11-19T21:34:36.342Z] 21:34:36 INFO - Restarting browser for new test group
[task 2022-11-19T21:34:36.347Z] 21:34:36 INFO - PID 5062 | 1668893676346 Marionette INFO Stopped listening on port 44719
[task 2022-11-19T21:34:36.532Z] 21:34:36 INFO - PID 5062 | ALSA lib seq_hw.c:466:(snd_seq_hw_open) open /dev/snd/seq failed: No such file or directory
[task 2022-11-19T21:34:36.859Z] 21:34:36 INFO - PID 5062 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-19T21:34:36.860Z] 21:34:36 INFO - PID 5062 | Blocker: Waiting for ping task
[task 2022-11-19T21:34:36.860Z] 21:34:36 INFO - PID 5062 | Phase: TelemetryController: Waiting for pending ping activity
[task 2022-11-19T21:34:36.860Z] 21:34:36 INFO - PID 5062 | State: (none)
[task 2022-11-19T21:34:36.864Z] 21:34:36 INFO - PID 5062 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-11-19T21:34:36.867Z] 21:34:36 INFO - PID 5062 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
<...>
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 auto_nag documentation.
Reporter | ||
Comment 3•2 years ago
|
||
treeherder |
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=414188248&repo=autoland
Comment hidden (Intermittent Failures Robot) |
Comment 5•1 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Reporter | ||
Comment 6•11 months ago
|
||
treeherder |
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=448234513&repo=mozilla-central
Comment hidden (Intermittent Failures Robot) |
Comment 8•9 months ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Description
•