Closed
Bug 1798720
Opened 3 years ago
Closed 3 years ago
Intermittent /event-timing/interactionid-press-key-as-input.html | single tracking bug
Categories
(Testing :: web-platform-tests, defect, P5)
Testing
web-platform-tests
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
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=395260634&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ceo9bp6fQDuo-vwyf5-M_w/runs/0/artifacts/public/logs/live_backing.log
[task 2022-11-02T15:05:15.013Z] 15:05:15 INFO - TEST-START | /event-timing/interactionid-press-key-as-input.html
[task 2022-11-02T15:05:15.020Z] 15:05:15 INFO - Closing window 9b02139b-8bdb-4336-94df-8992465c6f41
[task 2022-11-02T15:05:41.973Z] 15:05:41 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:05:41.974Z] 15:05:41 INFO - PID 5707 | Suppressions used:
[task 2022-11-02T15:05:41.974Z] 15:05:41 INFO - PID 5707 | count bytes template
[task 2022-11-02T15:05:41.974Z] 15:05:41 INFO - PID 5707 | 2 288 libfontconfig.so
[task 2022-11-02T15:05:41.974Z] 15:05:41 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.254Z] 15:08:15 INFO -
[task 2022-11-02T15:08:15.254Z] 15:08:15 INFO - TEST-UNEXPECTED-TIMEOUT | /event-timing/interactionid-press-key-as-input.html | Event Timing: compare event timing interactionId for key press as input. - Test timed out
[task 2022-11-02T15:08:15.254Z] 15:08:15 INFO - TEST-INFO | expected FAIL
[task 2022-11-02T15:08:15.256Z] 15:08:15 INFO - TEST-UNEXPECTED-TIMEOUT | /event-timing/interactionid-press-key-as-input.html | expected OK
[task 2022-11-02T15:08:15.256Z] 15:08:15 INFO - TEST-INFO took 180242ms
[task 2022-11-02T15:08:15.257Z] 15:08:15 INFO - PID 5707 | 1667401695256 Marionette INFO Stopped listening on port 41800
[task 2022-11-02T15:08:15.706Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.708Z] 15:08:15 INFO - PID 5707 | Suppressions used:
[task 2022-11-02T15:08:15.709Z] 15:08:15 INFO - PID 5707 | count bytes template
[task 2022-11-02T15:08:15.710Z] 15:08:15 INFO - PID 5707 | 2 288 libfontconfig.so
[task 2022-11-02T15:08:15.711Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.761Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.762Z] 15:08:15 INFO - PID 5707 | Suppressions used:
[task 2022-11-02T15:08:15.763Z] 15:08:15 INFO - PID 5707 | count bytes template
[task 2022-11-02T15:08:15.764Z] 15:08:15 INFO - PID 5707 | 2 288 libfontconfig.so
[task 2022-11-02T15:08:15.764Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.783Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.783Z] 15:08:15 INFO - PID 5707 | Suppressions used:
[task 2022-11-02T15:08:15.783Z] 15:08:15 INFO - PID 5707 | count bytes template
[task 2022-11-02T15:08:15.783Z] 15:08:15 INFO - PID 5707 | 2 288 libfontconfig.so
[task 2022-11-02T15:08:15.783Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.785Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.786Z] 15:08:15 INFO - PID 5707 | Suppressions used:
[task 2022-11-02T15:08:15.787Z] 15:08:15 INFO - PID 5707 | count bytes template
[task 2022-11-02T15:08:15.787Z] 15:08:15 INFO - PID 5707 | 2 288 libfontconfig.so
[task 2022-11-02T15:08:15.788Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.795Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.795Z] 15:08:15 INFO - PID 5707 | Suppressions used:
[task 2022-11-02T15:08:15.795Z] 15:08:15 INFO - PID 5707 | count bytes template
[task 2022-11-02T15:08:15.795Z] 15:08:15 INFO - PID 5707 | 2 288 libfontconfig.so
[task 2022-11-02T15:08:15.795Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.809Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.809Z] 15:08:15 INFO - PID 5707 | Suppressions used:
[task 2022-11-02T15:08:15.809Z] 15:08:15 INFO - PID 5707 | count bytes template
[task 2022-11-02T15:08:15.809Z] 15:08:15 INFO - PID 5707 | 2 288 libfontconfig.so
[task 2022-11-02T15:08:15.809Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.821Z] 15:08:15 INFO - PID 5707 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-02T15:08:15.821Z] 15:08:15 INFO - PID 5707 | Blocker: Waiting for ping task
[task 2022-11-02T15:08:15.821Z] 15:08:15 INFO - PID 5707 | Phase: TelemetryController: Waiting for pending ping activity
[task 2022-11-02T15:08:15.821Z] 15:08:15 INFO - PID 5707 | State: (none)
[task 2022-11-02T15:08:15.826Z] 15:08:15 INFO - PID 5707 | 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-02T15:08:15.826Z] 15:08:15 INFO - PID 5707 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-02T15:08:15.826Z] 15:08:15 INFO - PID 5707 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-02T15:08:15.826Z] 15:08:15 INFO - PID 5707 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-02T15:08:15.826Z] 15:08:15 INFO - PID 5707 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-02T15:08:15.826Z] 15:08:15 INFO - PID 5707 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-02T15:08:15.826Z] 15:08:15 INFO - PID 5707 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-02T15:08:15.826Z] 15:08:15 INFO - PID 5707 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-02T15:08:15.831Z] 15:08:15 INFO - PID 5707 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-11-02T15:08:15.831Z] 15:08:15 INFO - PID 5707 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-11-02T15:08:15.831Z] 15:08:15 INFO - PID 5707 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:554:48
[task 2022-11-02T15:08:15.831Z] 15:08:15 INFO - PID 5707 | send@resource://gre/modules/TelemetryControllerParent.jsm:642:24
[task 2022-11-02T15:08:15.831Z] 15:08:15 INFO - PID 5707 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-11-02T15:08:15.831Z] 15:08:15 INFO - PID 5707 | sendPing@resource://gre/modules/EventPing.jsm:51:30
[task 2022-11-02T15:08:15.831Z] 15:08:15 INFO - PID 5707 | _submitPing@resource://gre/modules/EventPing.jsm:229:12
[task 2022-11-02T15:08:15.831Z] 15:08:15 INFO - PID 5707 | shutdown@resource://gre/modules/EventPing.jsm:112:10
[task 2022-11-02T15:08:15.832Z] 15:08:15 INFO - PID 5707 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:954:31
[task 2022-11-02T15:08:15.832Z] 15:08:15 INFO - PID 5707 | shutdown/<@resource://gre/modules/TelemetryControllerParent.jsm:1024:24
[task 2022-11-02T15:08:15.832Z] 15:08:15 INFO - PID 5707 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-02T15:08:15.857Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.858Z] 15:08:15 INFO - PID 5707 | Suppressions used:
[task 2022-11-02T15:08:15.858Z] 15:08:15 INFO - PID 5707 | count bytes template
[task 2022-11-02T15:08:15.859Z] 15:08:15 INFO - PID 5707 | 2 288 libfontconfig.so
[task 2022-11-02T15:08:15.859Z] 15:08:15 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:15.889Z] 15:08:15 INFO - PID 5707 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-02T15:08:15.890Z] 15:08:15 INFO - PID 5707 | Blocker: Waiting for ping task
[task 2022-11-02T15:08:15.890Z] 15:08:15 INFO - PID 5707 | Phase: TelemetryController: Waiting for pending ping activity
[task 2022-11-02T15:08:15.891Z] 15:08:15 INFO - PID 5707 | State: (none)
[task 2022-11-02T15:08:15.891Z] 15:08:15 INFO - PID 5707 | 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-02T15:08:15.892Z] 15:08:15 INFO - PID 5707 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-02T15:08:15.892Z] 15:08:15 INFO - PID 5707 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-02T15:08:15.893Z] 15:08:15 INFO - PID 5707 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-02T15:08:15.893Z] 15:08:15 INFO - PID 5707 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-02T15:08:15.894Z] 15:08:15 INFO - PID 5707 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-02T15:08:15.894Z] 15:08:15 INFO - PID 5707 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-02T15:08:15.894Z] 15:08:15 INFO - PID 5707 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-02T15:08:15.895Z] 15:08:15 INFO - PID 5707 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-11-02T15:08:15.895Z] 15:08:15 INFO - PID 5707 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-11-02T15:08:15.896Z] 15:08:15 INFO - PID 5707 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:554:48
[task 2022-11-02T15:08:15.896Z] 15:08:15 INFO - PID 5707 | send@resource://gre/modules/TelemetryControllerParent.jsm:642:24
[task 2022-11-02T15:08:15.896Z] 15:08:15 INFO - PID 5707 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-11-02T15:08:15.897Z] 15:08:15 INFO - PID 5707 | saveShutdownPings@resource://gre/modules/TelemetrySession.jsm:982:34
[task 2022-11-02T15:08:15.897Z] 15:08:15 INFO - PID 5707 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1232:20
[task 2022-11-02T15:08:15.898Z] 15:08:15 INFO - PID 5707 | cleanup@resource://gre/modules/TelemetrySession.jsm:1239:9
[task 2022-11-02T15:08:15.898Z] 15:08:15 INFO - PID 5707 | shutdownChromeProcess@resource://gre/modules/TelemetrySession.jsm:1256:14
[task 2022-11-02T15:08:15.898Z] 15:08:15 INFO - PID 5707 | shutdown@resource://gre/modules/TelemetrySession.jsm:247:17
[task 2022-11-02T15:08:15.899Z] 15:08:15 INFO - PID 5707 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:973:35
[task 2022-11-02T15:08:15.899Z] 15:08:15 INFO - PID 5707 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-02T15:08:15.923Z] 15:08:15 INFO - PID 5707 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-02T15:08:15.924Z] 15:08:15 INFO - PID 5707 | Blocker: TelemetryController: shutting down
[task 2022-11-02T15:08:15.924Z] 15:08:15 INFO - PID 5707 | Phase: profile-before-change-telemetry
[task 2022-11-02T15:08:15.925Z] 15:08:15 INFO - PID 5707 | 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-02T15:08:15.926Z] 15:08:15 INFO - PID 5707 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-02T15:08:15.926Z] 15:08:15 INFO - PID 5707 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-02T15:08:15.927Z] 15:08:15 INFO - PID 5707 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-02T15:08:15.927Z] 15:08:15 INFO - PID 5707 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-02T15:08:15.928Z] 15:08:15 INFO - PID 5707 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-02T15:08:15.928Z] 15:08:15 INFO - PID 5707 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-02T15:08:15.928Z] 15:08:15 INFO - PID 5707 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:1687:7
[task 2022-11-02T15:08:15.929Z] 15:08:15 INFO - PID 5707 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:369:30
[task 2022-11-02T15:08:15.930Z] 15:08:15 INFO - PID 5707 | _getState@resource://gre/modules/TelemetryControllerParent.jsm:1072:38
[task 2022-11-02T15:08:15.930Z] 15:08:15 INFO - PID 5707 | setupTelemetry/<@resource://gre/modules/TelemetryControllerParent.jsm:931:18
[task 2022-11-02T15:08:15.931Z] 15:08:15 INFO - PID 5707 | safeGetState@resource://gre/modules/AsyncShutdown.jsm:242:17
[task 2022-11-02T15:08:15.931Z] 15:08:15 INFO - PID 5707 | Barrier/addBlocker/promise<@resource://gre/modules/AsyncShutdown.jsm:751:20
[task 2022-11-02T15:08:15.932Z] 15:08:15 INFO - PID 5707 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-02T15:08:15.932Z] 15:08:15 INFO - PID 5707 | 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-02T15:08:15.933Z] 15:08:15 INFO - PID 5707 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-02T15:08:15.933Z] 15:08:15 INFO - PID 5707 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-02T15:08:15.934Z] 15:08:15 INFO - PID 5707 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-02T15:08:15.934Z] 15:08:15 INFO - PID 5707 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-02T15:08:15.935Z] 15:08:15 INFO - PID 5707 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-02T15:08:15.935Z] 15:08:15 INFO - PID 5707 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-02T15:08:15.936Z] 15:08:15 INFO - PID 5707 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-02T15:08:15.937Z] 15:08:15 INFO - PID 5707 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:735:5
[task 2022-11-02T15:08:15.937Z] 15:08:15 INFO - PID 5707 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:249:17
[task 2022-11-02T15:08:15.938Z] 15:08:15 INFO - PID 5707 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1235:42
[task 2022-11-02T15:08:15.938Z] 15:08:15 INFO - PID 5707 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-02T15:08:15.939Z] 15:08:15 INFO - PID 5707 | 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-02T15:08:15.940Z] 15:08:15 INFO - PID 5707 | 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-02T15:08:15.940Z] 15:08:15 INFO - PID 5707 | 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-02T15:08:15.941Z] 15:08:15 INFO - PID 5707 | 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-02T15:08:15.942Z] 15:08:15 INFO - PID 5707 | 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-02T15:08:17.113Z] 15:08:17 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:17.113Z] 15:08:17 INFO - PID 5707 | Suppressions used:
[task 2022-11-02T15:08:17.114Z] 15:08:17 INFO - PID 5707 | count bytes template
[task 2022-11-02T15:08:17.114Z] 15:08:17 INFO - PID 5707 | 1 32 nsComponentManagerImpl
[task 2022-11-02T15:08:17.115Z] 15:08:17 INFO - PID 5707 | 633 18115 libfontconfig.so
[task 2022-11-02T15:08:17.115Z] 15:08:17 INFO - PID 5707 | 2 16393 libglib-2.0.so
[task 2022-11-02T15:08:17.115Z] 15:08:17 INFO - PID 5707 | 1 32 XPCWrappedNativeJSOps
[task 2022-11-02T15:08:17.115Z] 15:08:17 INFO - PID 5707 | -----------------------------------------------------
[task 2022-11-02T15:08:17.173Z] 15:08:17 INFO - Browser exited with return code 0
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•