Intermittent position-dynamic-changes/mixed-abspos-root/reftest_border_abspos.list | application timed out after 370 seconds with no output
Categories
(Core :: Layout: Positioned, defect, P5)
Tracking
()
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=425574724&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/avyjKjYLT5Sq-Y-42ZOS1A/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/avyjKjYLT5Sq-Y-42ZOS1A/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1
[task 2023-08-10T18:30:15.820Z] 18:30:15 INFO - REFTEST TEST-START | layout/reftests/position-dynamic-changes/mixed-abspos-root/mixed-dimentionA.html?border_abspos == layout/reftests/position-dynamic-changes/mixed-abspos-root/mixed-dimentionA-ref.html?border_abspos
[task 2023-08-10T18:30:15.821Z] 18:30:15 INFO - REFTEST TEST-LOAD | file:///Z:/task_169168876432904/build/tests/reftest/tests/layout/reftests/position-dynamic-changes/mixed-abspos-root/mixed-dimentionA.html?border_abspos | 1 / 2 (50%)
[task 2023-08-10T18:30:16.180Z] 18:30:16 INFO - REFTEST TEST-LOAD | file:///Z:/task_169168876432904/build/tests/reftest/tests/layout/reftests/position-dynamic-changes/mixed-abspos-root/mixed-dimentionA-ref.html?border_abspos | 1 / 2 (50%)
[task 2023-08-10T18:30:16.275Z] 18:30:16 INFO - REFTEST TEST-PASS | layout/reftests/position-dynamic-changes/mixed-abspos-root/mixed-dimentionA.html?border_abspos == layout/reftests/position-dynamic-changes/mixed-abspos-root/mixed-dimentionA-ref.html?border_abspos | image comparison, max difference: 0, number of differing pixels: 0
[task 2023-08-10T18:30:16.275Z] 18:30:16 INFO - REFTEST TEST-END | layout/reftests/position-dynamic-changes/mixed-abspos-root/mixed-dimentionA.html?border_abspos == layout/reftests/position-dynamic-changes/mixed-abspos-root/mixed-dimentionA-ref.html?border_abspos
[task 2023-08-10T18:30:16.317Z] 18:30:16 INFO - REFTEST INFO | Slowest test took 412ms (file:///Z:/task_169168876432904/build/tests/reftest/tests/layout/reftests/position-dynamic-changes/mixed-abspos-root/mixed-dimentionN.html?border_abspos)
[task 2023-08-10T18:30:16.318Z] 18:30:16 INFO - REFTEST INFO | Total canvas count = 2
[task 2023-08-10T18:30:17.253Z] 18:30:17 INFO - 1691692217252 Marionette TRACE Received observer notification quit-application
[task 2023-08-10T18:30:17.254Z] 18:30:17 INFO - 1691692217253 Marionette INFO Stopped listening on port 2828
[task 2023-08-10T18:30:17.255Z] 18:30:17 INFO - 1691692217254 Marionette DEBUG Marionette stopped listening
[task 2023-08-10T18:30:17.506Z] 18:30:17 INFO - console.error: ({})
[task 2023-08-10T18:30:17.586Z] 18:30:17 INFO - console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-10T18:30:19.746Z] 18:30:19 INFO - REFTEST INFO | Process mode: e10s
[task 2023-08-10T18:30:19.747Z] 18:30:19 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2023-08-10T18:30:19.747Z] 18:30:19 INFO - REFTEST INFO | Running tests in file:///Z:/task_169168876432904/build/tests/reftest/tests/layout/reftests/datalist/reftest.list
[task 2023-08-10T18:30:19.771Z] 18:30:19 INFO - REFTEST INFO | Running with e10s: True
[task 2023-08-10T18:30:19.772Z] 18:30:19 INFO - REFTEST INFO | Running with fission: True
[task 2023-08-10T18:30:19.772Z] 18:30:19 INFO - REFTEST INFO | INFO | runtests.py | ASan using symbolizer at Z:\task_169168876432904\build\application\firefox\llvm-symbolizer.exe
[task 2023-08-10T18:30:19.881Z] 18:30:19 INFO - REFTEST INFO | Failed determine available memory, disabling ASan low-memory configuration
[task 2023-08-10T18:30:19.882Z] 18:30:19 INFO - REFTEST INFO | Application command: Z:\task_169168876432904\build\application\firefox\firefox.exe -marionette --wait-for-browser -profile C:\Users\task_169168876432904\AppData\Local\Temp\tmp2q30c661.mozrunner
[task 2023-08-10T18:30:21.823Z] 18:30:21 INFO - 1691692221822 Marionette INFO Marionette enabled
[task 2023-08-10T18:30:21.832Z] 18:30:21 INFO - 1691692221831 Marionette TRACE Received observer notification final-ui-startup
[task 2023-08-10T18:30:22.227Z] 18:30:22 INFO - 1691692222226 Marionette INFO Listening on port 2828
[task 2023-08-10T18:30:22.235Z] 18:30:22 INFO - 1691692222234 Marionette DEBUG Marionette is listening
[task 2023-08-10T18:30:22.389Z] 18:30:22 INFO - 1691692222389 Marionette DEBUG Accepted connection 0 from 127.0.0.1:53859
[task 2023-08-10T18:30:22.602Z] 18:30:22 INFO - 1691692222601 Marionette DEBUG Closed connection 0
[task 2023-08-10T18:30:22.603Z] 18:30:22 INFO - 1691692222602 Marionette DEBUG Accepted connection 1 from 127.0.0.1:53860
[task 2023-08-10T18:30:23.825Z] 18:30:23 INFO - 1691692223822 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2023-08-10T18:30:23.844Z] 18:30:23 INFO - 1691692223843 Marionette DEBUG Waiting for initial application window
[task 2023-08-10T18:30:24.403Z] 18:30:24 INFO - JavaScript error: resource://gre/modules/XULStore.sys.mjs, line 60: Error: Can't find profile directory.
[task 2023-08-10T18:30:25.456Z] 18:30:25 INFO - console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-10T18:30:25.783Z] 18:30:25 INFO - console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-10T18:30:30.347Z] 18:30:30 INFO - console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-10T18:30:30.348Z] 18:30:30 INFO - console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-10T18:30:30.349Z] 18:30:30 INFO - console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-10T18:31:15.171Z] 18:31:15 INFO - console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-10T18:37:25.187Z] 18:37:25 ERROR - REFTEST ERROR | file:///Z:/task_169168876432904/build/tests/reftest/tests/layout/reftests/position-dynamic-changes/mixed-abspos-root/reftest_border_abspos.list | application timed out after 370 seconds with no output
[task 2023-08-10T18:37:25.188Z] 18:37:25 WARNING - REFTEST WARNING | Force-terminating active process(es).
[task 2023-08-10T18:37:25.188Z] 18:37:25 INFO - REFTEST TEST-INFO | started process screenshot
[task 2023-08-10T18:37:25.569Z] 18:37:25 INFO - REFTEST TEST-INFO | screenshot: exit 0
[task 2023-08-10T18:37:25.570Z] 18:37:25 INFO - REFTEST INFO | Can't trigger Breakpad, just killing process
[task 2023-08-10T18:37:25.729Z] 18:37:25 ERROR - TEST-UNEXPECTED-FAIL | file:///Z:/task_169168876432904/build/tests/reftest/tests/layout/reftests/position-dynamic-changes/mixed-abspos-root/reftest_border_abspos.list | application terminated with exit code 572
[task 2023-08-10T18:37:25.782Z] 18:37:25 INFO - Traceback (most recent call last):
[task 2023-08-10T18:37:25.782Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\tests\reftest\runreftest.py", line 1194, in <module>
[task 2023-08-10T18:37:25.783Z] 18:37:25 INFO - sys.exit(run_test_harness(parser, options))
[task 2023-08-10T18:37:25.783Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\tests\reftest\runreftest.py", line 1186, in run_test_harness
[task 2023-08-10T18:37:25.783Z] 18:37:25 INFO - result = reftest.runTests(options.tests, options)
[task 2023-08-10T18:37:25.784Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\tests\reftest\runreftest.py", line 707, in runTests
[task 2023-08-10T18:37:25.784Z] 18:37:25 INFO - return self.runSerialTests(manifests, options, cmdargs)
[task 2023-08-10T18:37:25.784Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\tests\reftest\runreftest.py", line 1128, in runSerialTests
[task 2023-08-10T18:37:25.784Z] 18:37:25 INFO - status = run(tests=tests)
[task 2023-08-10T18:37:25.784Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\tests\reftest\runreftest.py", line 1077, in run
[task 2023-08-10T18:37:25.784Z] 18:37:25 INFO - status = self.runApp(
[task 2023-08-10T18:37:25.785Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\tests\reftest\runreftest.py", line 1023, in runApp
[task 2023-08-10T18:37:25.785Z] 18:37:25 INFO - raise reraise(exc, value, tb)
[task 2023-08-10T18:37:25.785Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\six.py", line 696, in reraise
[task 2023-08-10T18:37:25.785Z] 18:37:25 INFO - raise value
[task 2023-08-10T18:37:25.785Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\tests\reftest\runreftest.py", line 958, in runApp
[task 2023-08-10T18:37:25.785Z] 18:37:25 INFO - marionette.start_session()
[task 2023-08-10T18:37:25.785Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\marionette_driver\decorators.py", line 34, in _
[task 2023-08-10T18:37:25.785Z] 18:37:25 INFO - m._handle_socket_failure()
[task 2023-08-10T18:37:25.786Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\marionette_driver\marionette.py", line 736, in _handle_socket_failure
[task 2023-08-10T18:37:25.786Z] 18:37:25 INFO - reraise(exc_cls, exc, tb)
[task 2023-08-10T18:37:25.786Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\six.py", line 696, in reraise
[task 2023-08-10T18:37:25.786Z] 18:37:25 INFO - raise value
[task 2023-08-10T18:37:25.786Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\marionette_driver\decorators.py", line 24, in _
[task 2023-08-10T18:37:25.786Z] 18:37:25 INFO - return func(*args, **kwargs)
[task 2023-08-10T18:37:25.787Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\marionette_driver\marionette.py", line 1287, in start_session
[task 2023-08-10T18:37:25.787Z] 18:37:25 INFO - resp = self._send_message("WebDriver:NewSession", capabilities)
[task 2023-08-10T18:37:25.787Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\marionette_driver\decorators.py", line 34, in _
[task 2023-08-10T18:37:25.787Z] 18:37:25 INFO - m._handle_socket_failure()
[task 2023-08-10T18:37:25.787Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\marionette_driver\marionette.py", line 736, in _handle_socket_failure
[task 2023-08-10T18:37:25.787Z] 18:37:25 INFO - reraise(exc_cls, exc, tb)
[task 2023-08-10T18:37:25.788Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\six.py", line 696, in reraise
[task 2023-08-10T18:37:25.788Z] 18:37:25 INFO - raise value
[task 2023-08-10T18:37:25.788Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\marionette_driver\decorators.py", line 24, in _
[task 2023-08-10T18:37:25.789Z] 18:37:25 INFO - return func(*args, **kwargs)
[task 2023-08-10T18:37:25.789Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\marionette_driver\marionette.py", line 685, in _send_message
[task 2023-08-10T18:37:25.789Z] 18:37:25 INFO - msg = self.client.request(name, params)
[task 2023-08-10T18:37:25.789Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\marionette_driver\transport.py", line 382, in request
[task 2023-08-10T18:37:25.789Z] 18:37:25 INFO - return self.receive()
[task 2023-08-10T18:37:25.789Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\marionette_driver\transport.py", line 223, in receive
[task 2023-08-10T18:37:25.790Z] 18:37:25 INFO - chunk = sock.recv(recv_bytes)
[task 2023-08-10T18:37:25.790Z] 18:37:25 INFO - ConnectionResetError: [WinError 10054] An existing connection was forcibly closed by the remote host
[task 2023-08-10T18:37:25.826Z] 18:37:25 INFO - Exception ignored in: <function BaseProfile.__del__ at 0x0000027668432940>
[task 2023-08-10T18:37:25.826Z] 18:37:25 INFO - Traceback (most recent call last):
[task 2023-08-10T18:37:25.826Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\mozprofile\profile.py", line 84, in __del__
[task 2023-08-10T18:37:25.826Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\mozprofile\profile.py", line 294, in cleanup
[task 2023-08-10T18:37:25.827Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\mozprofile\profile.py", line 92, in cleanup
[task 2023-08-10T18:37:25.827Z] 18:37:25 INFO - File "Z:\task_169168876432904\build\venv\lib\site-packages\mozfile\mozfile.py", line 247, in remove
[task 2023-08-10T18:37:25.827Z] 18:37:25 INFO - ImportError: sys.meta_path is None, Python is likely shutting down
[task 2023-08-10T18:37:25.864Z] 18:37:25 INFO - Return code: 1
[task 2023-08-10T18:37:25.864Z] 18:37:25 ERROR - No suite end message was emitted by this harness.
[task 2023-08-10T18:37:25.864Z] 18:37:25 INFO - TinderboxPrint: reftest-reftest<br/>9062/0/0
[task 2023-08-10T18:37:25.864Z] 18:37:25 WARNING - setting return code to 2
[task 2023-08-10T18:37:25.865Z] 18:37:25 INFO - The reftest suite: reftest ran with return status: FAILURE
[task 2023-08-10T18:37:25.865Z] 18:37:25 INFO - Running post-action listener: _package_coverage_data
[task 2023-08-10T18:37:25.865Z] 18:37:25 INFO - Running post-action listener: _resource_record_post_action
[task 2023-08-10T18:37:25.865Z] 18:37:25 INFO - Running post-action listener: process_java_coverage_data
[task 2023-08-10T18:37:25.865Z] 18:37:25 INFO - [mozharness: 2023-08-10 18:37:25.865893Z] Finished run-tests step (success)
[task 2023-08-10T18:37:25.865Z] 18:37:25 INFO - [mozharness: 2023-08-10 18:37:25.865893Z] Running uninstall step.
[task 2023-08-10T18:37:25.865Z] 18:37:25 INFO - Running pre-action listener: _resource_record_pre_action
[task 2023-08-10T18:37:25.865Z] 18:37:25 INFO - Running main action method: uninstall
[task 2023-08-10T18:37:25.865Z] 18:37:25 INFO - Skipping uninstall for non-MSIX test
[task 2023-08-10T18:37:25.865Z] 18:37:25 INFO - Running post-action listener: _resource_record_post_action
[task 2023-08-10T18:37:25.865Z] 18:37:25 INFO - [mozharness: 2023-08-10 18:37:25.865893Z] Finished uninstall step (success)
[task 2023-08-10T18:37:25.865Z] 18:37:25 INFO - Running post-run listener: _resource_record_post_run
[task 2023-08-10T18:37:26.257Z] 18:37:26 INFO - instance_metadata.json not found; unable to determine instance type
[task 2023-08-10T18:37:26.278Z] 18:37:26 INFO - Validating Perfherder data against Z:\task_169168876432904\mozharness\external_tools\performance-artifact-schema.json
[task 2023-08-10T18:37:26.281Z] 18:37:26 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "reftest.reftest.2.overall", "extraOptions": ["e10s", "buildbot-unknown"], "subtests": [{"name": "cpu_percent", "value": 25.555710491367876}, {"name": "io_write_bytes", "value": 11372464128}, {"name": "io.read_bytes", "value": 12064674304}, {"name": "io_write_time", "value": 308}, {"name": "io_read_time", "value": 1127}]}, {"name": "reftest.reftest.2.start-pulseaudio", "subtests": [{"name": "time", "value": 0.0008234977722167969}, {"name": "cpu_percent", "value": 0}]}, {"name": "reftest.reftest.2.install", "subtests": [{"name": "time", "value": 11.159371137619019}, {"name": "cpu_percent", "value": 20.539166666666667}]}, {"name": "reftest.reftest.2.stage-files", "subtests": [{"name": "time", "value": 0.0010538101196289062}, {"name": "cpu_percent", "value": 0}]}, {"name": "reftest.reftest.2.run-tests", "subtests": [{"name": "time", "value": 3290.78471660614}, {"name": "cpu_percent", "value": 25.575784132841346}]}, {"name": "reftest.reftest.2.uninstall", "subtests": [{"name": "time", "value": 0.0}, {"name": "cpu_percent", "value": 0}]}]}
[task 2023-08-10T18:37:26.281Z] 18:37:26 INFO - Total resource usage - Wall time: 3301s; CPU: Can't collect data; Read bytes: 12064674304; Write bytes: 11372464128; Read time: 1127; Write time: 308
[task 2023-08-10T18:37:26.281Z] 18:37:26 INFO - TinderboxPrint: I/O read bytes / time<br/>12,064,674,304 / 1,127
[task 2023-08-10T18:37:26.281Z] 18:37:26 INFO - TinderboxPrint: I/O write bytes / time<br/>11,372,464,128 / 308
[task 2023-08-10T18:37:26.282Z] 18:37:26 INFO - TinderboxPrint: CPU idle<br/>29,355.0 (73.9%)
[task 2023-08-10T18:37:26.282Z] 18:37:26 INFO - TinderboxPrint: CPU system<br/>2,758.6 (6.9%)
[task 2023-08-10T18:37:26.282Z] 18:37:26 INFO - TinderboxPrint: CPU user<br/>7,497.4 (18.9%)
[task 2023-08-10T18:37:26.283Z] 18:37:26 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-08-10T18:37:26.284Z] 18:37:26 INFO - install - Wall time: 11s; CPU: 21%; Read bytes: 254464; Write bytes: 560975872; Read time: 0; Write time: 14
[task 2023-08-10T18:37:26.286Z] 18:37:26 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-08-10T18:37:26.325Z] 18:37:26 INFO - run-tests - Wall time: 3291s; CPU: 26%; Read bytes: 12064419840; Write bytes: 10711848960; Read time: 1127; Write time: 292
[task 2023-08-10T18:37:26.327Z] 18:37:26 INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2023-08-10T18:37:27.522Z] 18:37:27 WARNING - returning nonzero exit status 2
[taskcluster 2023-08-10T18:37:27.668Z] Exit Code: 2
[taskcluster 2023-08-10T18:37:27.668Z] User Time: 0s
[taskcluster 2023-08-10T18:37:27.668Z] Kernel Time: 0s
[taskcluster 2023-08-10T18:37:27.668Z] Wall Time: 59m19.739567s
[taskcluster 2023-08-10T18:37:27.668Z] Result: FAILED
[taskcluster 2023-08-10T18:37:27.668Z] === Task Finished ===
[taskcluster 2023-08-10T18:37:27.669Z] Task Duration: 59m19.7427816s
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 4•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Reporter | ||
Comment 5•1 year ago
|
||
treeherder |
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=444815547&repo=mozilla-central
Comment hidden (Intermittent Failures Robot) |
Comment hidden (Intermittent Failures Robot) |
Comment 8•1 year ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Reporter | ||
Comment 9•9 months ago
|
||
treeherder |
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=463311416&repo=mozilla-beta
Comment hidden (Intermittent Failures Robot) |
Comment 11•8 months ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Reporter | ||
Comment 12•6 months ago
|
||
treeherder |
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=474757682&repo=mozilla-beta
Comment hidden (Intermittent Failures Robot) |
Comment 14•5 months ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Description
•