1728222 - Intermittent docshell/test/navigation/test_scrollRestoration.html | The page should be scrolled down after being restored from the session history. - didn't expect +0, but got it

Status: RESOLVED WORKSFORME

(Core :: DOM: Navigation, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: mlaza [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=349985479&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Dxbx5nFqTH2dR1TdN-0IeA/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/Dxbx5nFqTH2dR1TdN-0IeA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2021-08-30T20:35:48.612Z] 20:35:48     INFO - TEST-START | docshell/test/navigation/test_scrollRestoration.html
[task 2021-08-30T20:35:56.933Z] 20:35:56     INFO - TEST-INFO | started process screentopng
[task 2021-08-30T20:35:57.265Z] 20:35:57     INFO - TEST-INFO | screentopng: exit 0
[task 2021-08-30T20:35:57.266Z] 20:35:57     INFO - Buffered messages logged at 20:35:48
[task 2021-08-30T20:35:57.266Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | A valid string reason is expected 
[task 2021-08-30T20:35:57.268Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Reason cannot be empty 
[task 2021-08-30T20:35:57.268Z] 20:35:57     INFO - Buffered messages logged at 20:35:49
[task 2021-08-30T20:35:57.268Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Shouldn't have persisted session history entry. 
[task 2021-08-30T20:35:57.268Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | correct case 
[task 2021-08-30T20:35:57.268Z] 20:35:57     INFO - Checking asserts for case 1
[task 2021-08-30T20:35:57.268Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | history.scrollRestoration's default value should be 'auto'. 
[task 2021-08-30T20:35:57.268Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Invalid enum value should not change the value of an attribute. 
[task 2021-08-30T20:35:57.268Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Valid enum value should change the value of an attribute. 
[task 2021-08-30T20:35:57.268Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Valid enum value should change the value of an attribute. 
[task 2021-08-30T20:35:57.268Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | History object has scrollRestoration property. 
[task 2021-08-30T20:35:57.268Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Shouldn't have persisted session history entry. 
[task 2021-08-30T20:35:57.269Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | correct case 
[task 2021-08-30T20:35:57.269Z] 20:35:57     INFO - Checking asserts for case 2
[task 2021-08-30T20:35:57.269Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have the same scrollRestoration as before reload. 
[task 2021-08-30T20:35:57.270Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have restored scrolling. 
[task 2021-08-30T20:35:57.270Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Shouldn't have persisted session history entry. 
[task 2021-08-30T20:35:57.271Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | correct case 
[task 2021-08-30T20:35:57.271Z] 20:35:57     INFO - Checking asserts for case 3
[task 2021-08-30T20:35:57.272Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should not have restored scrolling. 
[task 2021-08-30T20:35:57.272Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have the same scrollRestoration as before reload. 
[task 2021-08-30T20:35:57.273Z] 20:35:57     INFO - Buffered messages logged at 20:35:50
[task 2021-08-30T20:35:57.273Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Shouldn't have persisted session history entry. 
[task 2021-08-30T20:35:57.274Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have persisted session history entry. 
[task 2021-08-30T20:35:57.275Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | correct case 
[task 2021-08-30T20:35:57.275Z] 20:35:57     INFO - Checking asserts for case 2
[task 2021-08-30T20:35:57.275Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have the same scrollRestoration as before reload. 
[task 2021-08-30T20:35:57.276Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have kept the old scroll position. 
[task 2021-08-30T20:35:57.277Z] 20:35:57     INFO - Buffered messages logged at 20:35:52
[task 2021-08-30T20:35:57.277Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | correct case 
[task 2021-08-30T20:35:57.278Z] 20:35:57     INFO - Checking asserts for case 3
[task 2021-08-30T20:35:57.278Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have the same scrollRestoration mode as before fragment navigation. 
[task 2021-08-30T20:35:57.279Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have scrolled to #hash. 
[task 2021-08-30T20:35:57.280Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | correct case 
[task 2021-08-30T20:35:57.280Z] 20:35:57     INFO - Checking asserts for case 2
[task 2021-08-30T20:35:57.281Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have the same scrollRestoration mode as before fragment navigation. 
[task 2021-08-30T20:35:57.282Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have scrolled to #hash. 
[task 2021-08-30T20:35:57.282Z] 20:35:57     INFO - Buffered messages logged at 20:35:53
[task 2021-08-30T20:35:57.283Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | correct scroll restoration 
[task 2021-08-30T20:35:57.284Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Shouldn't have persisted session history entry. 
[task 2021-08-30T20:35:57.284Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | correct case 
[task 2021-08-30T20:35:57.285Z] 20:35:57     INFO - Checking asserts for case 3
[task 2021-08-30T20:35:57.286Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Shouldn't have kept the old scroll position. 
[task 2021-08-30T20:35:57.286Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have the same scrollRestoration mode as before fragment navigation. 
[task 2021-08-30T20:35:57.287Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | correct case 
[task 2021-08-30T20:35:57.288Z] 20:35:57     INFO - Checking asserts for case 4
[task 2021-08-30T20:35:57.288Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Unexpected state. 
[task 2021-08-30T20:35:57.289Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have scrolled back to the state1's position 
[task 2021-08-30T20:35:57.290Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | correct case 
[task 2021-08-30T20:35:57.290Z] 20:35:57     INFO - Checking asserts for case 5
[task 2021-08-30T20:35:57.291Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Shouldn't have scrolled back to the state3's position 
[task 2021-08-30T20:35:57.291Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Unexpected state. 
[task 2021-08-30T20:35:57.292Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have scrolled to 'bottom'. 
[task 2021-08-30T20:35:57.292Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | correct case 
[task 2021-08-30T20:35:57.293Z] 20:35:57     INFO - Checking asserts for case 7
[task 2021-08-30T20:35:57.293Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Should have scrolled back to the state5's position 
[task 2021-08-30T20:35:57.293Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | correct case 
[task 2021-08-30T20:35:57.294Z] 20:35:57     INFO - Checking asserts for case 9
[task 2021-08-30T20:35:57.294Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Did get an exception 
[task 2021-08-30T20:35:57.294Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | Did get an exception 
[task 2021-08-30T20:35:57.294Z] 20:35:57     INFO - Buffered messages logged at 20:35:55
[task 2021-08-30T20:35:57.294Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | The first page should not be persisted initially. 
[task 2021-08-30T20:35:57.295Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | The second page should not be persisted. 
[task 2021-08-30T20:35:57.295Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | The page should be initially scrolled to top. 
[task 2021-08-30T20:35:57.295Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | The page should be then scrolled down. 
[task 2021-08-30T20:35:57.296Z] 20:35:57     INFO - Buffered messages logged at 20:35:56
[task 2021-08-30T20:35:57.296Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | The first page should be persisted. 
[task 2021-08-30T20:35:57.296Z] 20:35:57     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | The second page should not be persisted. 
[task 2021-08-30T20:35:57.296Z] 20:35:57     INFO - Buffered messages finished
[task 2021-08-30T20:35:57.296Z] 20:35:57     INFO - TEST-UNEXPECTED-FAIL | docshell/test/navigation/test_scrollRestoration.html | The page should be scrolled down after being restored from the session history. - didn't expect +0, but got it
[task 2021-08-30T20:35:57.296Z] 20:35:57     INFO -     SimpleTest.isnot@SimpleTest/SimpleTest.js:519:14
[task 2021-08-30T20:35:57.296Z] 20:35:57     INFO -     test4/bc4b.onmessage@docshell/test/navigation/test_scrollRestoration.html:196:18
[task 2021-08-30T20:35:57.296Z] 20:35:57     INFO - GECKO(1543) | MEMORY STAT | vsize 2506MB | residentFast 114MB | heapAllocated 9MB
[task 2021-08-30T20:35:57.297Z] 20:35:57     INFO - TEST-OK | docshell/test/navigation/test_scrollRestoration.html | took 8461ms
[task 2021-08-30T20:35:58.120Z] 20:35:58     INFO - TEST-START | Shutdown
[task 2021-08-30T20:35:58.121Z] 20:35:58     INFO - Passed:  571
[task 2021-08-30T20:35:58.122Z] 20:35:58     INFO - Failed:  1
[task 2021-08-30T20:35:58.122Z] 20:35:58     INFO - Todo:    0
[task 2021-08-30T20:35:58.123Z] 20:35:58     INFO - Mode:    e10s
[task 2021-08-30T20:35:58.123Z] 20:35:58     INFO - Slowest: 9037ms - /tests/docshell/test/navigation/test_scrollRestoration.html
[task 2021-08-30T20:35:58.124Z] 20:35:58     INFO - TEST-INFO | Ran 11 Loops
[task 2021-08-30T20:35:58.124Z] 20:35:58     INFO - SimpleTest FINISHED
[task 2021-08-30T20:35:58.166Z] 20:35:58     INFO - GECKO(1543) | Exiting due to channel error.
[task 2021-08-30T20:35:58.169Z] 20:35:58     INFO - GECKO(1543) | Exiting due to channel error.
[task 2021-08-30T20:35:58.170Z] 20:35:58     INFO - GECKO(1543) | Exiting due to channel error.
[task 2021-08-30T20:35:58.170Z] 20:35:58     INFO - GECKO(1543) | Exiting due to channel error.
[task 2021-08-30T20:35:58.171Z] 20:35:58     INFO - GECKO(1543) | Exiting due to channel error.
[task 2021-08-30T20:35:58.172Z] 20:35:58     INFO - GECKO(1543) | Exiting due to channel error.
[task 2021-08-30T20:35:58.213Z] 20:35:58     INFO - TEST-INFO | Main app process: exit 0
[task 2021-08-30T20:35:58.213Z] 20:35:58     INFO - runtests.py | Application ran for: 0:01:42.493165
[task 2021-08-30T20:35:58.213Z] 20:35:58     INFO - zombiecheck | Reading PID log: /tmp/tmpcfe18mf7pidlog
[task 2021-08-30T20:35:58.214Z] 20:35:58     INFO - ==> process 1543 launched child process 1563
[task 2021-08-30T20:35:58.215Z] 20:35:58     INFO - ==> process 1543 launched child process 1620
[task 2021-08-30T20:35:58.216Z] 20:35:58     INFO - ==> process 1543 launched child process 1641
[task 2021-08-30T20:35:58.216Z] 20:35:58     INFO - ==> process 1543 launched child process 1678
[task 2021-08-30T20:35:58.217Z] 20:35:58     INFO - ==> process 1543 launched child process 1706
[task 2021-08-30T20:35:58.218Z] 20:35:58     INFO - ==> process 1543 launched child process 1711
[task 2021-08-30T20:35:58.218Z] 20:35:58     INFO - ==> process 1543 launched child process 1802
[task 2021-08-30T20:35:58.219Z] 20:35:58     INFO - ==> process 1543 launched child process 1813
[task 2021-08-30T20:35:58.220Z] 20:35:58     INFO - zombiecheck | Checking for orphan process with PID: 1641
[task 2021-08-30T20:35:58.220Z] 20:35:58     INFO - zombiecheck | Checking for orphan process with PID: 1706
[task 2021-08-30T20:35:58.221Z] 20:35:58     INFO - zombiecheck | Checking for orphan process with PID: 1802
[task 2021-08-30T20:35:58.223Z] 20:35:58     INFO - zombiecheck | Checking for orphan process with PID: 1678
[task 2021-08-30T20:35:58.223Z] 20:35:58     INFO - zombiecheck | Checking for orphan process with PID: 1711
[task 2021-08-30T20:35:58.223Z] 20:35:58     INFO - zombiecheck | Checking for orphan process with PID: 1620
[task 2021-08-30T20:35:58.223Z] 20:35:58     INFO - zombiecheck | Checking for orphan process with PID: 1813
[task 2021-08-30T20:35:58.223Z] 20:35:58     INFO - zombiecheck | Checking for orphan process with PID: 1563
[task 2021-08-30T20:35:58.224Z] 20:35:58     INFO - Stopping web server
[task 2021-08-30T20:35:58.227Z] 20:35:58     INFO - Server shut down.
[task 2021-08-30T20:35:58.248Z] 20:35:58     INFO - Web server killed.
[task 2021-08-30T20:35:58.249Z] 20:35:58     INFO - Stopping web socket server
[task 2021-08-30T20:35:58.270Z] 20:35:58     INFO - Stopping ssltunnel
[task 2021-08-30T20:35:58.290Z] 20:35:58  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2021-08-30T20:35:58.291Z] 20:35:58     INFO - runtests.py | Running tests: end.
[task 2021-08-30T20:35:58.312Z] 20:35:58     INFO - Buffered messages finished
[task 2021-08-30T20:35:58.312Z] 20:35:58     INFO -  0 INFO TEST-START | Shutdown
[task 2021-08-30T20:35:58.313Z] 20:35:58     INFO -  1 INFO Passed:  3431
[task 2021-08-30T20:35:58.313Z] 20:35:58     INFO -  2 INFO Failed:  1
[task 2021-08-30T20:35:58.314Z] 20:35:58     INFO -  3 INFO Todo:    0
[task 2021-08-30T20:35:58.315Z] 20:35:58     INFO -  4 INFO Mode:    e10s
[task 2021-08-30T20:35:58.316Z] 20:35:58     INFO -  5 INFO SimpleTest FINISHED
[task 2021-08-30T20:35:58.317Z] 20:35:58     INFO - Buffered messages finished
[task 2021-08-30T20:35:58.318Z] 20:35:58     INFO - SUITE-END | took 103s
[task 2021-08-30T20:35:58.320Z] 20:35:58     INFO - SUITE-START | Running 1 tests
[task 2021-08-30T20:35:58.320Z] 20:35:58     INFO - :::
[task 2021-08-30T20:35:58.320Z] 20:35:58     INFO - ::: Test verification summary for:
[task 2021-08-30T20:35:58.321Z] 20:35:58     INFO - :::
[task 2021-08-30T20:35:58.321Z] 20:35:58     INFO - ::: docshell/test/navigation/test_scrollRestoration.html
[task 2021-08-30T20:35:58.321Z] 20:35:58     INFO - :::
[task 2021-08-30T20:35:58.321Z] 20:35:58     INFO - ::: 1. Run each test 10 times in one browser. : FAIL
[task 2021-08-30T20:35:58.322Z] 20:35:58     INFO - ::: 2. Run each test 5 times in a new browser each time. : not run / incomplete
[task 2021-08-30T20:35:58.324Z] 20:35:58     INFO - ::: 3. Run each test 10 times in one browser, in chaos mode. : not run / incomplete
[task 2021-08-30T20:35:58.325Z] 20:35:58     INFO - ::: 4. Run each test 5 times in a new browser each time, in chaos mode. : not run / incomplete
[task 2021-08-30T20:35:58.325Z] 20:35:58     INFO - :::
[task 2021-08-30T20:35:58.326Z] 20:35:58     INFO - ::: Test verification FAILED!
[task 2021-08-30T20:35:58.327Z] 20:35:58     INFO - :::
[task 2021-08-30T20:35:58.328Z] 20:35:58     INFO - Buffered messages finished
[task 2021-08-30T20:35:58.328Z] 20:35:58     INFO - SUITE-END | took 0s
[task 2021-08-30T20:35:58.349Z] 20:35:58     INFO - Return code: 0
[task 2021-08-30T20:35:58.350Z] 20:35:58    ERROR - Got 1 unexpected statuses
[task 2021-08-30T20:35:58.351Z] 20:35:58     INFO - TinderboxPrint: mochitest-mochitest-plain<br/>62/<em class="testfail">1</em>/0
[task 2021-08-30T20:35:58.351Z] 20:35:58  WARNING - # TBPL WARNING #
[task 2021-08-30T20:35:58.351Z] 20:35:58  WARNING - setting return code to 1
[task 2021-08-30T20:35:58.351Z] 20:35:58  WARNING - TinderboxPrint: Per-test run of .../test_scrollRestoration.html<br/>: WARNING
[task 2021-08-30T20:35:58.351Z] 20:35:58     INFO - Per-test suites: {}
[task 2021-08-30T20:35:58.351Z] 20:35:58     INFO - Per-test suites: {}
[task 2021-08-30T20:35:58.351Z] 20:35:58     INFO - Running post-action listener: _package_coverage_data
[task 2021-08-30T20:35:58.351Z] 20:35:58     INFO - Running post-action listener: _resource_record_post_action
[task 2021-08-30T20:35:58.351Z] 20:35:58     INFO - Running post-action listener: process_java_coverage_data
[task 2021-08-30T20:35:58.351Z] 20:35:58     INFO - [mozharness: 2021-08-30 20:35:58.350963Z] Finished run-tests step (success)
[task 2021-08-30T20:35:58.352Z] 20:35:58     INFO - Running post-run listener: _resource_record_post_run
[task 2021-08-30T20:35:58.421Z] 20:35:58     INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2021-08-30T20:35:58.423Z] 20:35:58     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": ".overall", "extraOptions": ["e10s", "taskcluster-m5.large"], "subtests": [{"name": "cpu_percent", "value": 60.10570175438595}, {"name": "io_write_bytes", "value": 858124288}, {"name": "io.read_bytes", "value": 3895296}, {"name": "io_write_time", "value": 94632}, {"name": "io_read_time", "value": 920}]}, {"name": ".start-pulseaudio", "subtests": [{"name": "time", "value": 0.03400015830993652}]}, {"name": ".install", "subtests": [{"name": "time", "value": 10.81153154373169}, {"name": "cpu_percent", "value": 50.34444444444444}]}, {"name": ".stage-files", "subtests": [{"name": "time", "value": 0.002153635025024414}]}, {"name": ".run-tests", "subtests": [{"name": "time", "value": 104.03485798835754}, {"name": "cpu_percent", "value": 61.12184466019417}]}]}
[task 2021-08-30T20:35:58.425Z] 20:35:58     INFO - Total resource usage - Wall time: 114s; CPU: 61%; Read bytes: 3895296; Write bytes: 858124288; Read time: 920; Write time: 94632
[task 2021-08-30T20:35:58.426Z] 20:35:58     INFO - TinderboxPrint: CPU usage<br/>61.1%
[task 2021-08-30T20:35:58.427Z] 20:35:58     INFO - TinderboxPrint: I/O read bytes / time<br/>3,895,296 / 920
[task 2021-08-30T20:35:58.427Z] 20:35:58     INFO - TinderboxPrint: I/O write bytes / time<br/>858,124,288 / 94,632
[task 2021-08-30T20:35:58.428Z] 20:35:58     INFO - TinderboxPrint: CPU idle<br/>89.0 (39.2%)
[task 2021-08-30T20:35:58.429Z] 20:35:58     INFO - TinderboxPrint: CPU system<br/>8.6 (3.8%)
[task 2021-08-30T20:35:58.430Z] 20:35:58     INFO - TinderboxPrint: CPU user<br/>128.0 (56.3%)
[task 2021-08-30T20:35:58.431Z] 20:35:58     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2021-08-30T20:35:58.432Z] 20:35:58     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-08-30T20:35:58.433Z] 20:35:58     INFO - install - Wall time: 11s; CPU: 50%; Read bytes: 0; Write bytes: 933888; Read time: 0; Write time: 0
[task 2021-08-30T20:35:58.433Z] 20:35:58     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-08-30T20:35:58.434Z] 20:35:58     INFO - run-tests - Wall time: 104s; CPU: 61%; Read bytes: 3895296; Write bytes: 857190400; Read time: 920; Write time: 94632
[task 2021-08-30T20:35:58.445Z] 20:35:58  WARNING - returning nonzero exit status 1
[task 2021-08-30T20:35:58.470Z] cleanup
[task 2021-08-30T20:35:58.470Z] + cleanup
[task 2021-08-30T20:35:58.471Z] + local rv=1
[task 2021-08-30T20:35:58.471Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2021-08-30T20:35:58.471Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2021-08-30T20:35:58.475Z] + '[' ']'
[task 2021-08-30T20:35:58.475Z] + true
[task 2021-08-30T20:35:58.475Z] + cleanup_xvfb
[task 2021-08-30T20:35:58.476Z] ++ pidof Xvfb
[task 2021-08-30T20:35:58.480Z] + local xvfb_pid=49
[task 2021-08-30T20:35:58.480Z] + local vnc=false
[task 2021-08-30T20:35:58.481Z] + local interactive=false
[task 2021-08-30T20:35:58.481Z] + '[' -n 49 ']'
[task 2021-08-30T20:35:58.481Z] + [[ false == false ]]
[task 2021-08-30T20:35:58.481Z] + [[ false == false ]]
[task 2021-08-30T20:35:58.481Z] + kill 49
[task 2021-08-30T20:35:58.482Z] + screen -XS xvfb quit
[task 2021-08-30T20:35:58.528Z] No screen session found.
[task 2021-08-30T20:35:58.532Z] + true
[task 2021-08-30T20:35:58.532Z] + exit 1
[taskcluster 2021-08-30 20:35:58.944Z] === Task Finished ===
[taskcluster 2021-08-30 20:36:00.308Z] Unsuccessful task run with exit code: 1 completed in 436.667 seconds

Comment 1

[Intermittent Failures Robot]

16 failures in 3084 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 11
• mozilla-central: 5

Platform and build breakdown:

• linux1804-64-qr: 13
  • opt: 1
  • debug: 12
• linux1804-64-asan-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-08-30&endday=2021-09-05&tree=all

Comment 2

[Intermittent Failures Robot]

10 failures in 3266 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• mozilla-central: 3

Platform and build breakdown:

• linux1804-64-qr: 9
  • debug: 9
• linux1804-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-09-06&endday=2021-09-12&tree=all

Comment 3

[Intermittent Failures Robot]

21 failures in 3375 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 14
• mozilla-central: 5
• try: 2

Platform and build breakdown:

• linux1804-64-qr: 15
  • debug: 15
• linux1804-64-asan-qr: 5
  • opt: 5
• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-09-13&endday=2021-09-19&tree=all

Comment 4

[Andreea Pavel [:apavel]]

There are 39 total failures in the last 7 days on

• linux1804-64-asan-qr opt
• linux1804-64-qr debug and opt
  Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=352672180&repo=autoland&lineNumber=5420

[task 2021-09-24T19:24:24.031Z] 19:24:24     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | The first page should be persisted. 
[task 2021-09-24T19:24:24.032Z] 19:24:24     INFO - Buffered messages logged at 19:24:23
[task 2021-09-24T19:24:24.033Z] 19:24:24     INFO - TEST-PASS | docshell/test/navigation/test_scrollRestoration.html | The second page should not be persisted. 
[task 2021-09-24T19:24:24.034Z] 19:24:24     INFO - Buffered messages finished
[task 2021-09-24T19:24:24.035Z] 19:24:24     INFO - TEST-UNEXPECTED-FAIL | docshell/test/navigation/test_scrollRestoration.html | The page should be scrolled down after being restored from the session history. - didn't expect +0, but got it
[task 2021-09-24T19:24:24.035Z] 19:24:24     INFO -     SimpleTest.isnot@SimpleTest/SimpleTest.js:519:14
[task 2021-09-24T19:24:24.036Z] 19:24:24     INFO -     test4/bc4b.onmessage@docshell/test/navigation/test_scrollRestoration.html:196:18
[task 2021-09-24T19:24:24.037Z] 19:24:24     INFO - GECKO(1545) | MEMORY STAT | vsize 2541MB | residentFast 153MB | heapAllocated 9MB
[task 2021-09-24T19:24:24.038Z] 19:24:24     INFO - GECKO(1545) | [Child 1689, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4190
[task 2021-09-24T19:24:24.050Z] 19:24:24     INFO - GECKO(1545) | [Parent 1545, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:361
[task 2021-09-24T19:24:24.254Z] 19:24:24     INFO - TEST-OK | docshell/test/navigation/test_scrollRestoration.html | took 17504ms
[task 2021-09-24T19:24:24.271Z] 19:24:24     INFO - GECKO(1545) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-09-24T19:24:24.274Z] 19:24:24     INFO - GECKO(1545) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-09-24T19:24:24.279Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-09-24T19:24:24.328Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-09-24T19:24:24.328Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-09-24T19:24:24.343Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-09-24T19:24:24.405Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:327
[task 2021-09-24T19:24:24.408Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-09-24T19:24:24.424Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-09-24T19:24:24.431Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:327
[task 2021-09-24T19:24:24.434Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-09-24T19:24:24.442Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-09-24T19:24:24.449Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-09-24T19:24:24.451Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-09-24T19:24:24.460Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:527
[task 2021-09-24T19:24:24.470Z] 19:24:24     INFO - GECKO(1545) | [Child 3488, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4438

Olli are there any updates here?

Comment 5

[Intermittent Failures Robot]

36 failures in 2873 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #30 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 28
• mozilla-central: 7
• try: 1

Platform and build breakdown:

• linux1804-64-asan-qr: 13
  • opt: 13
• linux1804-64-qr: 20
  • debug: 17
  • opt: 3
• linux1804-64-shippable-qr: 1
  • opt: 1
• linux1804-64-ccov-qr: 1
  • opt: 1
• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-09-20&endday=2021-09-26&tree=all

Comment 6

[Intermittent Failures Robot]

26 failures in 3376 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 22
• mozilla-central: 4

Platform and build breakdown:

• linux1804-64-asan-qr: 5
  • opt: 5
• linux1804-64-qr: 18
  • debug: 18
• linux1804-64-shippable-qr: 2
  • opt: 2
• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-09-27&endday=2021-10-03&tree=all

Comment 7

[Intermittent Failures Robot]

14 failures in 3626 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 10
• mozilla-central: 4

Platform and build breakdown:

• linux1804-64-qr: 12
  • debug: 12
• linux1804-64-asan-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-10-04&endday=2021-10-10&tree=all

Comment 8

[Intermittent Failures Robot]

12 failures in 3315 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 5
• autoland: 7

Platform and build breakdown:

• linux1804-64-qr: 9
  • debug: 9
• linux1804-64-asan-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-10-11&endday=2021-10-17&tree=all

Comment 9

[Intermittent Failures Robot]

16 failures in 3186 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 10
• mozilla-central: 5
• try: 1

Platform and build breakdown:

• linux1804-64-qr: 8
  • debug: 8
• linux1804-64-asan-qr: 8
  • opt: 8

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-10-18&endday=2021-10-24&tree=all

Comment 10

[Intermittent Failures Robot]

8 failures in 2845 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 4
• autoland: 4

Platform and build breakdown:

• linux1804-64-asan-qr: 4
  • opt: 4
• linux1804-64-qr: 3
  • debug: 3
• linux1804-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-10-25&endday=2021-10-31&tree=all

Comment 11

[Intermittent Failures Robot]

12 failures in 3030 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 4
• autoland: 8

Platform and build breakdown:

• linux1804-64-qr: 7
  • debug: 7
• linux1804-64-asan-qr: 4
  • opt: 4
• linux1804-64-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-11-01&endday=2021-11-07&tree=all

Comment 12

[Intermittent Failures Robot]

13 failures in 2723 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• mozilla-central: 6

Platform and build breakdown:

• linux1804-64-qr: 9
  • debug: 9
• linux1804-64-asan-qr: 3
  • opt: 3
• linux1804-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-11-08&endday=2021-11-14&tree=all

Comment 13

[Intermittent Failures Robot]

12 failures in 3259 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 9
• mozilla-central: 3

Platform and build breakdown:

• linux1804-64-qr: 9
  • debug: 9
• linux1804-64-asan-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-11-15&endday=2021-11-21&tree=all

Comment 14

[Intermittent Failures Robot]

11 failures in 2819 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 8
• mozilla-central: 3

Platform and build breakdown:

• linux1804-64-qr: 6
  • debug: 6
• linux1804-64-shippable-qr: 1
  • opt: 1
• linux1804-64-asan-qr: 4
  • opt: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-11-22&endday=2021-11-28&tree=all

Comment 15

[Intermittent Failures Robot]

9 failures in 3170 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4
• mozilla-central: 3
• try: 2

Platform and build breakdown:

• linux1804-64-asan-qr: 2
  • opt: 2
• linux1804-64-qr: 7
  • debug: 6
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2021-11-29&endday=2021-12-05&tree=all

Comment 16

[Intermittent Failures Robot]

1 failures in 2992 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows10-32-2004-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1728222&startday=2022-02-21&endday=2022-02-27&tree=all

Comment 17

[Olli Pettay [:smaug][[e-mail]]]

No failures recently.