1807401 - Intermittent /dom/events/scrolling/scrollend-event-for-user-scroll.html | single tracking bug

Status: RESOLVED INCOMPLETE

(Core :: DOM: Core & HTML, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=400665959&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/To4VNSXfT2-LTeMqgd35fw/runs/0/artifacts/public/logs/live_backing.log

---

[task 2022-12-23T23:57:34.970Z] 23:57:34     INFO - TEST-START | /dom/events/scrolling/scrollend-event-for-user-scroll.html
[task 2022-12-23T23:57:34.983Z] 23:57:34     INFO - Setting pref privacy.reduceTimerPrecision to false
[task 2022-12-23T23:57:34.997Z] 23:57:34     INFO - Setting pref dom.w3c_touch_events.legacy_apis.enabled to true
[task 2022-12-23T23:57:35.006Z] 23:57:35     INFO - Setting pref apz.scrollend-event.content.enabled to true
[task 2022-12-23T23:57:35.141Z] 23:57:35     INFO - PID 13371 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2022-12-23T23:57:35.468Z] 23:57:35     INFO - {'actions': [{'type': 'none', 'actions': [{'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 200}], 'id': '0'}, {'type': 'pointer', 'actions': [{'type': 'pointerMove', 'x': 0, 'y': 0, 'origin': {'element-6066-11e4-a52e-4f735466cecf': 'd45f73e1-bfa5-4c84-aec7-5967aea6948e'}}, {'type': 'pointerDown', 'button': 0}, {'type': 'pointerMove', 'x': 0, 'y': -40, 'origin': {'element-6066-11e4-a52e-4f735466cecf': 'd45f73e1-bfa5-4c84-aec7-5967aea6948e'}}, {'type': 'pointerUp', 'button': 0}], 'parameters': {'pointerType': 'touch'}, 'id': 'TestPointer'}]}
[task 2022-12-23T23:57:35.655Z] 23:57:35     INFO - PID 13117 | JavaScript error: , line 0: uncaught exception: No Scrollend event received for target [object HTMLDivElement]
[task 2022-12-23T23:57:35.751Z] 23:57:35     INFO - {'actions': [{'type': 'none', 'actions': [{'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}], 'id': '1'}, {'type': 'pointer', 'actions': [{'type': 'pointerMove', 'x': 194, 'y': 180, 'origin': 'viewport'}, {'type': 'pointerDown', 'button': 0}, {'type': 'pointerUp', 'button': 0}], 'parameters': {'pointerType': 'mouse'}, 'id': 'TestPointer'}]}
[task 2022-12-23T23:57:35.923Z] 23:57:35     INFO - {'actions': [{'type': 'none', 'actions': [{'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}], 'id': '2'}, {'type': 'pointer', 'actions': [{'type': 'pointerMove', 'x': 194, 'y': 30, 'origin': 'viewport'}, {'type': 'pointerDown', 'button': 0}, {'type': 'pointerMove', 'x': 194, 'y': 60, 'origin': 'viewport'}, {'type': 'pointerUp', 'button': 0}], 'parameters': {'pointerType': 'mouse'}, 'id': 'TestPointer'}]}
[task 2022-12-23T23:57:36.121Z] 23:57:36     INFO - {'actions': [{'type': 'none', 'actions': [{'type': 'pause', 'duration': 16}], 'id': '3'}, {'type': 'wheel', 'actions': [{'type': 'scroll', 'x': 0, 'y': 0, 'deltaX': 0, 'deltaY': 40, 'origin': {'element-6066-11e4-a52e-4f735466cecf': 'd45f73e1-bfa5-4c84-aec7-5967aea6948e'}}], 'id': '4'}]}
[task 2022-12-23T23:57:36.493Z] 23:57:36     INFO - TEST-UNEXPECTED-ERROR | /dom/events/scrolling/scrollend-event-for-user-scroll.html | Unhandled rejection
[task 2022-12-23T23:57:36.493Z] 23:57:36     INFO - TEST-INFO took 1523ms
[task 2022-12-23T23:57:36.495Z] 23:57:36     INFO - PID 13117 | 1671839856492	Marionette	INFO	Stopped listening on port 54632
[task 2022-12-23T23:57:36.695Z] 23:57:36     INFO - PID 13117 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-23T23:57:36.695Z] 23:57:36     INFO - PID 13117 |           Blocker:  Waiting for ping task
[task 2022-12-23T23:57:36.695Z] 23:57:36     INFO - PID 13117 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2022-12-23T23:57:36.695Z] 23:57:36     INFO - PID 13117 |           State: (none)
[task 2022-12-23T23:57:36.697Z] 23:57:36     INFO - PID 13117 | 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-12-23T23:57:36.703Z] 23:57:36     INFO - PID 13117 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:712:15
[task 2022-12-23T23:57:36.703Z] 23:57:36     INFO - PID 13117 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2022-12-23T23:57:36.703Z] 23:57:36     INFO - PID 13117 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2022-12-23T23:57:36.703Z] 23:57:36     INFO - PID 13117 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-23T23:57:36.705Z] 23:57:36     INFO - PID 13117 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-23T23:57:36.706Z] 23:57:36     INFO - PID 13117 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-23T23:57:36.706Z] 23:57:36     INFO - PID 13117 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2022-12-23T23:57:36.706Z] 23:57:36     INFO - PID 13117 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2022-12-23T23:57:36.708Z] 23:57:36     INFO - PID 13117 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2022-12-23T23:57:36.708Z] 23:57:36     INFO - PID 13117 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2022-12-23T23:57:36.708Z] 23:57:36     INFO - PID 13117 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2022-12-23T23:57:36.708Z] 23:57:36     INFO - PID 13117 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2022-12-23T23:57:36.723Z] 23:57:36     INFO - PID 13117 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-23T23:57:36.723Z] 23:57:36     INFO - PID 13117 |           Blocker:  Waiting for ping task
[task 2022-12-23T23:57:36.738Z] 23:57:36     INFO - PID 13117 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2022-12-23T23:57:36.738Z] 23:57:36     INFO - PID 13117 |           State: (none)
[task 2022-12-23T23:57:36.739Z] 23:57:36     INFO - PID 13117 | 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-12-23T23:57:36.739Z] 23:57:36     INFO - PID 13117 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:712:15
[task 2022-12-23T23:57:36.740Z] 23:57:36     INFO - PID 13117 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2022-12-23T23:57:36.740Z] 23:57:36     INFO - PID 13117 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2022-12-23T23:57:36.740Z] 23:57:36     INFO - PID 13117 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-23T23:57:36.740Z] 23:57:36     INFO - PID 13117 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-23T23:57:36.741Z] 23:57:36     INFO - PID 13117 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-23T23:57:36.741Z] 23:57:36     INFO - PID 13117 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2022-12-23T23:57:36.754Z] 23:57:36     INFO - PID 13117 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2022-12-23T23:57:36.754Z] 23:57:36     INFO - PID 13117 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2022-12-23T23:57:36.754Z] 23:57:36     INFO - PID 13117 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2022-12-23T23:57:36.754Z] 23:57:36     INFO - PID 13117 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2022-12-23T23:57:36.754Z] 23:57:36     INFO - PID 13117 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2022-12-23T23:57:36.756Z] 23:57:36     INFO - PID 13117 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-12-23T23:57:36.757Z] 23:57:36     INFO - PID 13117 |           Blocker:  TelemetryController: shutting down
[task 2022-12-23T23:57:36.757Z] 23:57:36     INFO - PID 13117 |           Phase: profile-before-change-telemetry
[task 2022-12-23T23:57:36.757Z] 23:57:36     INFO - PID 13117 |           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.sys.mjs:712:15
[task 2022-12-23T23:57:36.758Z] 23:57:36     INFO - PID 13117 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2022-12-23T23:57:36.758Z] 23:57:36     INFO - PID 13117 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2022-12-23T23:57:36.758Z] 23:57:36     INFO - PID 13117 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-23T23:57:36.758Z] 23:57:36     INFO - PID 13117 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-23T23:57:36.759Z] 23:57:36     INFO - PID 13117 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-23T23:57:36.760Z] 23:57:36     INFO - PID 13117 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2022-12-23T23:57:36.760Z] 23:57:36     INFO - PID 13117 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2022-12-23T23:57:36.761Z] 23:57:36     INFO - PID 13117 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2022-12-23T23:57:36.761Z] 23:57:36     INFO - PID 13117 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2022-12-23T23:57:36.762Z] 23:57:36     INFO - PID 13117 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2022-12-23T23:57:36.762Z] 23:57:36     INFO - PID 13117 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2022-12-23T23:57:36.762Z] 23:57:36     INFO - PID 13117 | 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-12-23T23:57:36.762Z] 23:57:36     INFO - PID 13117 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:712:15
[task 2022-12-23T23:57:36.763Z] 23:57:36     INFO - PID 13117 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2022-12-23T23:57:36.764Z] 23:57:36     INFO - PID 13117 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2022-12-23T23:57:36.765Z] 23:57:36     INFO - PID 13117 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2022-12-23T23:57:36.766Z] 23:57:36     INFO - PID 13117 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2022-12-23T23:57:36.767Z] 23:57:36     INFO - PID 13117 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-12-23T23:57:36.768Z] 23:57:36     INFO - PID 13117 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2022-12-23T23:57:36.768Z] 23:57:36     INFO - PID 13117 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2022-12-23T23:57:36.769Z] 23:57:36     INFO - PID 13117 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2022-12-23T23:57:36.770Z] 23:57:36     INFO - PID 13117 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2022-12-23T23:57:36.771Z] 23:57:36     INFO - PID 13117 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2022-12-23T23:57:36.772Z] 23:57:36     INFO - PID 13117 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2022-12-23T23:57:36.773Z] 23:57:36     INFO - PID 13117 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-12-23T23:57:36.774Z] 23:57:36     INFO - PID 13117 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-12-23T23:57:36.775Z] 23:57:36     INFO - PID 13117 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-12-23T23:57:36.776Z] 23:57:36     INFO - PID 13117 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-12-23T23:57:36.777Z] 23:57:36     INFO - PID 13117 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-12-23T23:57:37.003Z] 23:57:37     INFO - Browser exited with return code 0
[task 2022-12-23T23:57:37.007Z] 23:57:37     INFO - Closing logging queue
[task 2022-12-23T23:57:37.008Z] 23:57:37     INFO - queue closed
[task 2022-12-23T23:57:37.018Z] 23:57:37     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpsqjko375
[task 2022-12-23T23:57:37.029Z] 23:57:37     INFO - Starting runner
[task 2022-12-23T23:57:37.548Z] 23:57:37     INFO - PID 13371 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmp4qrler50/search.json.mozlz4", (void 0)))
[task 2022-12-23T23:57:37.722Z] 23:57:37     INFO - PID 13605 | 1671839857721	Marionette	INFO	Marionette enabled
[task 2022-12-23T23:57:37.725Z] 23:57:37     INFO - PID 13605 | 1671839857724	Marionette	INFO	Listening on port 34579
[task 2022-12-23T23:57:38.392Z] 23:57:38     INFO - PID 13371 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2022-12-23T23:57:38.393Z] 23:57:38     INFO - PID 13371 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2022-12-23T23:57:38.394Z] 23:57:38     INFO - PID 13371 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2022-12-23T23:57:38.572Z] 23:57:38     INFO - TEST-START | /editing/other/body-should-not-deleted-even-if-empty.html

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1807401&startday=2022-12-19&endday=2022-12-25&tree=all

Comment 2

[Iulian Moraru]

First occurrence, so far: backfill range and retriggers.

Comment 3

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 1804780

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1807401&startday=2022-12-26&endday=2023-01-01&tree=all

Comment 5

[BugBot [:suhaib / :marco/ :calixte]]

Set release status flags based on info from the regressing bug 1804780

Comment 6

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 7

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=408720140&repo=mozilla-esr102

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr102: 1

Platform and build breakdown:

• macosx1015-64-qr: 1
  • debug: 1

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

Comment 9

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 10

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=429047673&repo=mozilla-central