Open Bug 1867234 Opened 1 year ago Updated 1 year ago

Intermittent TV TinderboxPrint: Per-test run of .../interactionid-auxclick.html<br/>: FAILURE

Categories

(Testing :: web-platform-tests, defect, P5)

Product:
Testing
Component:
web-platform-tests
Type:
defect

Tracking

(firefox-esr115 unaffected, firefox120 unaffected, firefox121 unaffected, firefox122 affected, firefox123 affected)

Tracking Flags:
Tracking Status
firefox-esr115 --- unaffected
firefox120 --- unaffected
firefox121 --- unaffected
firefox122 --- affected
firefox123 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, test-verify-fail)

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

[task 2023-11-29T10:04:31.997Z] 10:04:31     INFO - TEST-START | /event-timing/interactionid-auxclick.html
[task 2023-11-29T10:04:32.164Z] 10:04:32     INFO - {'actions': [{'type': 'none', 'actions': [{'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}, {'type': 'pause', 'duration': 16}], 'id': '0'}, {'type': 'pointer', 'actions': [{'type': 'pointerMove', 'x': 0, 'y': 0, 'origin': {'element-6066-11e4-a52e-4f735466cecf': '4a6ee3d1-060a-4524-95f3-8f682a6da85c'}}, {'type': 'pointerDown', 'button': 2}, {'type': 'pointerUp', 'button': 2}], 'parameters': {'pointerType': 'mouse'}, 'id': 'mousePointer'}]}
[task 2023-11-29T10:04:33.423Z] 10:04:33     INFO - .
[task 2023-11-29T10:04:33.423Z] 10:04:33     INFO - TEST-OK | /event-timing/interactionid-auxclick.html | took 1427ms
[task 2023-11-29T10:04:33.424Z] 10:04:33     INFO - No more tests
[task 2023-11-29T10:04:33.424Z] 10:04:33     INFO - No more tests
[task 2023-11-29T10:04:33.425Z] 10:04:33     INFO - PID 12492 | 1701252273424	Marionette	INFO	Stopped listening on port 52110
[task 2023-11-29T10:04:33.821Z] 10:04:33     INFO - PID 12492 | console.error: ({})
[task 2023-11-29T10:04:33.963Z] 10:04:33     INFO - Browser exited with return code 0
[task 2023-11-29T10:04:33.965Z] 10:04:33     INFO - Closing logging queue
[task 2023-11-29T10:04:33.965Z] 10:04:33     INFO - queue closed
[task 2023-11-29T10:04:33.971Z] 10:04:33     INFO - SUITE-END | took 6s
[task 2023-11-29T10:04:33.972Z] 10:04:33     INFO - Got 0 unexpected results, with 0 unexpected passes
[task 2023-11-29T10:04:33.973Z] 10:04:33     INFO - wptserve Stopped WebTransport over HTTP/3 server on 127.0.0.1:11000
[task 2023-11-29T10:04:34.141Z] 10:04:34     INFO - wptserve Stopped http server on 127.0.0.1:8000
[task 2023-11-29T10:04:34.175Z] 10:04:34     INFO - wptserve Stopped http server on 127.0.0.1:8445
[task 2023-11-29T10:04:34.175Z] 10:04:34     INFO - wptserve Close on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8888))
[task 2023-11-29T10:04:34.175Z] 10:04:34     INFO - wptserve Stopped http server on 127.0.0.1:9000
[task 2023-11-29T10:04:34.175Z] 10:04:34     INFO - wptserve Stopped http server on 127.0.0.1:8003
[task 2023-11-29T10:04:34.175Z] 10:04:34     INFO - wptserve Close on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8889))
[task 2023-11-29T10:04:34.175Z] 10:04:34     INFO - wptserve Stopped http server on 127.0.0.1:8446
[task 2023-11-29T10:04:34.175Z] 10:04:34     INFO - wptserve Stopped http server on 127.0.0.1:8444
[task 2023-11-29T10:04:34.175Z] 10:04:34     INFO - wptserve Stopped http server on 127.0.0.1:8443
[task 2023-11-29T10:04:34.176Z] 10:04:34     INFO - wptserve Stopped http server on 127.0.0.1:8001
[task 2023-11-29T10:04:34.176Z] 10:04:34     INFO - wptserve Stopped http server on 127.0.0.1:8002
[task 2023-11-29T10:04:34.337Z] 10:04:34     INFO - Removed font: Ahem.ttf
[task 2023-11-29T10:04:34.394Z] 10:04:34     INFO - Closing logging queue
[task 2023-11-29T10:04:34.394Z] 10:04:34     INFO - queue closed
[task 2023-11-29T10:04:34.425Z] 10:04:34     INFO - ::: Ran 5 of expected 5 iterations.
[task 2023-11-29T10:04:34.425Z] 10:04:34     INFO - ## All results ##
[task 2023-11-29T10:04:34.425Z] 10:04:34     INFO - 
[task 2023-11-29T10:04:34.426Z] 10:04:34     INFO - ### /event-timing/interactionid-auxclick.html ###
[task 2023-11-29T10:04:34.426Z] 10:04:34     INFO - |                                               Subtest                                               |           Results           |                                                                   Messages                                                                  |
[task 2023-11-29T10:04:34.427Z] 10:04:34     INFO - |-----------------------------------------------------------------------------------------------------|-----------------------------|---------------------------------------------------------------------------------------------------------------------------------------------|
[task 2023-11-29T10:04:34.429Z] 10:04:34     INFO - |                                                                                                     | **OK: 4/5, TIMEOUT: 1/5**   |                                                                                                                                             |
[task 2023-11-29T10:04:34.429Z] 10:04:34     INFO - | `Event Timing: verify pointerdown & pointerup reporting for mouse aux click triggered contextmenu.` | **FAIL: 4/5, TIMEOUT: 1/5** | `Test timed out;assert_greater_than: The pointerdown entry should have a non-trivial interactionId expected a number but got a "undefined"` |
[task 2023-11-29T10:04:34.429Z] 10:04:34     INFO - 
[task 2023-11-29T10:04:34.429Z] 10:04:34     INFO - ## Unstable results ##
[task 2023-11-29T10:04:34.429Z] 10:04:34     INFO - 
[task 2023-11-29T10:04:34.430Z] 10:04:34     INFO - |                     Test                    |                                               Subtest                                               |           Results           |                                                                   Messages                                                                  |
[task 2023-11-29T10:04:34.430Z] 10:04:34     INFO - |---------------------------------------------|-----------------------------------------------------------------------------------------------------|-----------------------------|---------------------------------------------------------------------------------------------------------------------------------------------|
[task 2023-11-29T10:04:34.430Z] 10:04:34     INFO - | `/event-timing/interactionid-auxclick.html` |                                                                                                     | **OK: 4/5, TIMEOUT: 1/5**   |                                                                                                                                             |
[task 2023-11-29T10:04:34.430Z] 10:04:34     INFO - | `/event-timing/interactionid-auxclick.html` | `Event Timing: verify pointerdown & pointerup reporting for mouse aux click triggered contextmenu.` | **FAIL: 4/5, TIMEOUT: 1/5** | `Test timed out;assert_greater_than: The pointerdown entry should have a non-trivial interactionId expected a number but got a "undefined"` |
[task 2023-11-29T10:04:34.431Z] 10:04:34     INFO - 
[task 2023-11-29T10:04:34.431Z] 10:04:34     INFO - ::: Running tests in a loop 10 times : PASS
[task 2023-11-29T10:04:34.431Z] 10:04:34     INFO - ::: Running tests in a loop with restarts 5 times : FAIL
[task 2023-11-29T10:04:34.431Z] 10:04:34     INFO - :::
[task 2023-11-29T10:04:34.432Z] 10:04:34    ERROR - ::: Test verification FAIL
[task 2023-11-29T10:04:34.432Z] 10:04:34     INFO - :::
[task 2023-11-29T10:04:34.866Z] 10:04:34     INFO - Return code: 1
[task 2023-11-29T10:04:34.867Z] 10:04:34  WARNING - setting return code to 2
[task 2023-11-29T10:04:34.867Z] 10:04:34    ERROR - TinderboxPrint: Per-test run of .../interactionid-auxclick.html<br/>: FAILURE
[task 2023-11-29T10:04:34.868Z] 10:04:34     INFO - Running command: ['/builds/worker/workspace/build/venv/bin/python', '-u', '/builds/worker/workspace/build/tests/web-platform/runtests.py', '--log-raw=-', '--log-wptreport=/builds/worker/workspace/build/blobber_upload_dir/wptreport.json', '--log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/wpt-test6_errorsummary.log', '--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/JeEPnR9uQfGb4pPji795WA/artifacts/public/build/target.crashreporter-symbols.zip', '--stackwalk-binary=/builds/worker/fetches/minidump-stackwalk/minidump-stackwalk', '--stackfix-dir=/builds/worker/workspace/build/tests/bin', '--no-pause-after-test', '--instrument-to-file=/builds/worker/workspace/build/blobber_upload_dir/wpt_instruments.txt', '--specialpowers-path=/builds/worker/workspace/build/tests/extensions/specialpowers@mozilla.org.xpi', '--suppress-handler-traceback', '--processes=1', '--binary=/builds/worker/workspace/build/application/firefox/firefox', '--product=firefox', '--install-fonts', '--test-type=testharness', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--prefs-root=/builds/worker/workspace/build/tests/web-platform/prefs', '--config=/builds/worker/workspace/build/tests/web-platform/wptrunner.ini', '--ca-cert-path=/builds/worker/workspace/build/tests/web-platform/tests/tools/certs/cacert.pem', '--host-key-path=/builds/worker/workspace/build/tests/web-platform/tests/tools/certs/web-platform.test.key', '--host-cert-path=/builds/worker/workspace/build/tests/web-platform/tests/tools/certs/web-platform.test.pem', '--certutil-binary=/builds/worker/workspace/build/tests/bin/certutil', '--verify-log-full', '--verify', 'tests/web-platform/tests/html/infrastructure/urls/base-url/document-base-url-window-initiator-is-not-opener.https.window.js'] in /builds/worker/workspace/build
[task 2023-11-29T10:04:34.868Z] 10:04:34     INFO - Copy/paste: /builds/worker/workspace/build/venv/bin/python -u /builds/worker/workspace/build/tests/web-platform/runtests.py --log-raw=- --log-wptreport=/builds/worker/workspace/build/blobber_upload_dir/wptreport.json --log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/wpt-test6_errorsummary.log --symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/JeEPnR9uQfGb4pPji795WA/artifacts/public/build/target.crashreporter-symbols.zip --stackwalk-binary=/builds/worker/fetches/minidump-stackwalk/minidump-stackwalk --stackfix-dir=/builds/worker/workspace/build/tests/bin --no-pause-after-test --instrument-to-file=/builds/worker/workspace/build/blobber_upload_dir/wpt_instruments.txt --specialpowers-path=/builds/worker/workspace/build/tests/extensions/specialpowers@mozilla.org.xpi --suppress-handler-traceback --processes=1 --binary=/builds/worker/workspace/build/application/firefox/firefox --product=firefox --install-fonts --test-type=testharness --setpref=media.peerconnection.mtransport_process=false --setpref=network.process.enabled=false --setpref=layers.d3d11.enable-blacklist=false --prefs-root=/builds/worker/workspace/build/tests/web-platform/prefs --config=/builds/worker/workspace/build/tests/web-platform/wptrunner.ini --ca-cert-path=/builds/worker/workspace/build/tests/web-platform/tests/tools/certs/cacert.pem --host-key-path=/builds/worker/workspace/build/tests/web-platform/tests/tools/certs/web-platform.test.key --host-cert-path=/builds/worker/workspace/build/tests/web-platform/tests/tools/certs/web-platform.test.pem --certutil-binary=/builds/worker/workspace/build/tests/bin/certutil --verify-log-full --verify tests/web-platform/tests/html/infrastructure/urls/base-url/document-base-url-window-initiator-is-not-opener.https.window.js
[task 2023-11-29T10:04:34.868Z] 10:04:34     INFO - Using env: (same as previous command)
[task 2023-11-29T10:04:34.872Z] 10:04:34     INFO - Calling ['/builds/worker/workspace/build/venv/bin/python', '-u', '/builds/worker/workspace/build/tests/web-platform/runtests.py', '--log-raw=-', '--log-wptreport=/builds/worker/workspace/build/blobber_upload_dir/wptreport.json', '--log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/wpt-test6_errorsummary.log', '--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/JeEPnR9uQfGb4pPji795WA/artifacts/public/build/target.crashreporter-symbols.zip', '--stackwalk-binary=/builds/worker/fetches/minidump-stackwalk/minidump-stackwalk', '--stackfix-dir=/builds/worker/workspace/build/tests/bin', '--no-pause-after-test', '--instrument-to-file=/builds/worker/workspace/build/blobber_upload_dir/wpt_instruments.txt', '--specialpowers-path=/builds/worker/workspace/build/tests/extensions/specialpowers@mozilla.org.xpi', '--suppress-handler-traceback', '--processes=1', '--binary=/builds/worker/workspace/build/application/firefox/firefox', '--product=firefox', '--install-fonts', '--test-type=testharness', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--prefs-root=/builds/worker/workspace/build/tests/web-platform/prefs', '--config=/builds/worker/workspace/build/tests/web-platform/wptrunner.ini', '--ca-cert-path=/builds/worker/workspace/build/tests/web-platform/tests/tools/certs/cacert.pem', '--host-key-path=/builds/worker/workspace/build/tests/web-platform/tests/tools/certs/web-platform.test.key', '--host-cert-path=/builds/worker/workspace/build/tests/web-platform/tests/tools/certs/web-platform.test.pem', '--certutil-binary=/builds/worker/workspace/build/tests/bin/certutil', '--verify-log-full', '--verify', 'tests/web-platform/tests/html/infrastructure/urls/base-url/document-base-url-window-initiator-is-not-opener.https.window.js'] with output_timeout 1000
[task 2023-11-29T10:04:35.169Z] 10:04:35     INFO - :::
[task 2023-11-29T10:04:35.170Z] 10:04:35     INFO - ::: Running test verification step "Running tests in a loop 10 times"...
[task 2023-11-29T10:04:35.170Z] 10:04:35     INFO - :::
[task 2023-11-29T10:04:35.198Z] 10:04:35     INFO - mozversion application_buildid: 20231129093118
[task 2023-11-29T10:04:35.200Z] 10:04:35     INFO - mozversion application_changeset: 267971ce0551e0c25b62d7fea7a87dee6da60e71
[task 2023-11-29T10:04:35.201Z] 10:04:35     INFO - mozversion application_display_name: Firefox Nightly
[task 2023-11-29T10:04:35.202Z] 10:04:35     INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
[task 2023-11-29T10:04:35.202Z] 10:04:35     INFO - mozversion application_name: Firefox
[task 2023-11-29T10:04:35.203Z] 10:04:35     INFO - mozversion application_remotingname: firefox-default
[task 2023-11-29T10:04:35.203Z] 10:04:35     INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland
[task 2023-11-29T10:04:35.203Z] 10:04:35     INFO - mozversion application_vendor: Mozilla
[task 2023-11-29T10:04:35.203Z] 10:04:35     INFO - mozversion application_version: 122.0a1
[task 2023-11-29T10:04:35.203Z] 10:04:35     INFO - mozversion platform_buildid: 20231129093118
[task 2023-11-29T10:04:35.203Z] 10:04:35     INFO - mozversion platform_changeset: 267971ce0551e0c25b62d7fea7a87dee6da60e71
[task 2023-11-29T10:04:35.203Z] 10:04:35     INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland
[task 2023-11-29T10:04:35.203Z] 10:04:35     INFO - mozversion platform_version: 122.0a1
[task 2023-11-29T10:04:37.611Z] 10:04:37     INFO - Installed font: Ahem.ttf
[task 2023-11-29T10:04:38.478Z] 10:04:38     INFO - wptserve Starting http server on http://127.0.0.1:8001
[task 2023-11-29T10:04:38.702Z] 10:04:38     INFO - wptserve Starting http server on http://127.0.0.1:8000
[task 2023-11-29T10:04:38.730Z] 10:04:38     INFO - wptserve Starting https server on https://127.0.0.1:8446
[task 2023-11-29T10:04:38.745Z] 10:04:38     INFO - wptserve Starting http server on http://127.0.0.1:8003
[task 2023-11-29T10:04:38.767Z] 10:04:38     INFO - wptserve Starting https server on https://127.0.0.1:8444
[task 2023-11-29T10:04:38.774Z] 10:04:38     INFO - wptserve Starting http2 server on https://127.0.0.1:9000
[task 2023-11-29T10:04:38.786Z] 10:04:38     INFO - wptserve Create socket on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8888))
[task 2023-11-29T10:04:38.787Z] 10:04:38     INFO - wptserve Bind on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8888))
[task 2023-11-29T10:04:38.788Z] 10:04:38     INFO - wptserve Listen on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8888))
[task 2023-11-29T10:04:38.793Z] 10:04:38     INFO - wptserve Starting http server on http://127.0.0.1:8002
[task 2023-11-29T10:04:38.795Z] 10:04:38     INFO - wptserve Starting https server on https://127.0.0.1:8443
[task 2023-11-29T10:04:38.796Z] 10:04:38     INFO - wptserve Starting https server on https://127.0.0.1:8445
[task 2023-11-29T10:04:38.803Z] 10:04:38     INFO - wptserve Create socket on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8889))
[task 2023-11-29T10:04:38.805Z] 10:04:38     INFO - wptserve Bind on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8889))
[task 2023-11-29T10:04:38.805Z] 10:04:38     INFO - wptserve Listen on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8889))
[task 2023-11-29T10:04:38.807Z] 10:04:38     INFO - wptserve Starting WebTransport over HTTP/3 server on 127.0.0.1:11000
[task 2023-11-29T10:04:40.041Z] 10:04:40     INFO - SUITE-START | Running 1 tests
[task 2023-11-29T10:04:40.042Z] 10:04:40     INFO - Using 1 child processes
[task 2023-11-29T10:04:40.055Z] 10:04:40     INFO - Setting up ssl
[task 2023-11-29T10:04:40.068Z] 10:04:40     INFO - certutil | b''
[task 2023-11-29T10:04:40.084Z] 10:04:40     INFO - certutil | b''
[task 2023-11-29T10:04:40.092Z] 10:04:40     INFO - certutil | b'\nCertificate Nickname                                         Trust Attributes\n                                                             SSL,S/MIME,JAR/XPI\n\nweb-platform-tests                                           CT,, \n'
[task 2023-11-29T10:04:40.094Z] 10:04:40     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpgzts5s5m
[task 2023-11-29T10:04:40.101Z] 10:04:40     INFO - Starting runner
[task 2023-11-29T10:04:40.550Z] 10:04:40     INFO - PID 12935 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-11-29T10:04:40.574Z] 10:04:40     INFO - PID 12935 | 1701252280573	Marionette	INFO	Marionette enabled
[task 2023-11-29T10:04:40.632Z] 10:04:40     INFO - PID 12935 | ATTENTION: default value of option mesa_glthread overridden by environment.
[task 2023-11-29T10:04:40.649Z] 10:04:40     INFO - PID 12935 | 1701252280648	RemoteAgent	WARN	PollPromise timed out after 5000 ms
[task 2023-11-29T10:04:40.650Z] 10:04:40     INFO - PID 12935 | 1701252280649	Marionette	INFO	Listening on port 33644
[task 2023-11-29T10:04:43.685Z] 10:04:43     INFO - PID 12935 | console.error: ({})
[task 2023-11-29T10:04:44.575Z] 10:04:44     INFO - TEST-START | /html/infrastructure/urls/base-url/document-base-url-window-initiator-is-not-opener.https.window.html

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

status-firefox120: --- → unaffected
status-firefox121: --- → unaffected
status-firefox122: --- → affected
status-firefox-esr115: --- → unaffected

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

status-firefox123: --- → affected
You need to log in before you can comment on or make changes to this bug.