Closed Bug 1718240 Opened 5 years ago Closed 5 years ago

Intermittent TinderboxPrint: Per-test run of .../setSinkId-with-selectAudioOutput.https.html<br/>: FAILURE

Categories

(Core :: Audio/Video: Playback, defect, P5)

Product:
Core
Component:
Audio/Video: Playback
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2021-06-25T08:45:44.438Z] 08:45:44     INFO - TEST-START | /audio-output/setSinkId-with-selectAudioOutput.https.html
[task 2021-06-25T08:45:44.438Z] 08:45:44     INFO - Run 9/10
[task 2021-06-25T08:45:44.469Z] 08:45:44     INFO - Closing window 11bde404-8634-5746-8a48-fc39d9aeff9c
[task 2021-06-25T08:45:50.034Z] 08:45:50     INFO - ..
[task 2021-06-25T08:45:50.034Z] 08:45:50     INFO - TEST-OK | /audio-output/setSinkId-with-selectAudioOutput.https.html | took 5596ms
[task 2021-06-25T08:45:50.034Z] 08:45:50     INFO - No more tests
[task 2021-06-25T08:45:50.086Z] 08:45:50     INFO - PID 1756 | 1624610750085	Marionette	INFO	Stopped listening on port 49466
[task 2021-06-25T08:45:51.588Z] 08:45:51     INFO - PID 1756 | JavaScript error: resource:///modules/Interactions.jsm, line 377: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIUserIdleService.removeIdleObserver]
[task 2021-06-25T08:45:53.234Z] 08:45:53     INFO - PID 1756 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-06-25T08:45:53.651Z] 08:45:53     INFO - Browser exited with return code 0
[task 2021-06-25T08:45:53.653Z] 08:45:53     INFO - Closing logging queue
[task 2021-06-25T08:45:53.654Z] 08:45:53     INFO - queue closed
[task 2021-06-25T08:45:53.669Z] 08:45:53     INFO - Got 0 unexpected results, with 0 unexpected passes
[task 2021-06-25T08:45:53.669Z] 08:45:53     INFO - SUITE-END | took 88s
[task 2021-06-25T08:45:54.021Z] 08:45:54     INFO - wptserve Stopped http server on 127.0.0.1:8000
[task 2021-06-25T08:45:54.522Z] 08:45:54     INFO - wptserve Stopped http server on 127.0.0.1:8001
[task 2021-06-25T08:45:55.134Z] 08:45:55     INFO - wptserve Stopped http server on 127.0.0.1:8443
[task 2021-06-25T08:45:55.501Z] 08:45:55     INFO - wptserve Stopped http server on 127.0.0.1:8444
[task 2021-06-25T08:45:55.893Z] 08:45:55     INFO - wptserve Close on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8888))
[task 2021-06-25T08:45:55.944Z] 08:45:55     INFO - wptserve Close on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8889))
[task 2021-06-25T08:45:56.298Z] 08:45:56     INFO - wptserve Stopped http server on 127.0.0.1:9000
[task 2021-06-25T08:45:56.374Z] 08:45:56     INFO - Closing logging queue
[task 2021-06-25T08:45:56.374Z] 08:45:56     INFO - queue closed
[task 2021-06-25T08:45:56.379Z] 08:45:56     INFO - ## All results ##
[task 2021-06-25T08:45:56.379Z] 08:45:56     INFO - 
[task 2021-06-25T08:45:56.379Z] 08:45:56     INFO - ### /audio-output/setSinkId-with-selectAudioOutput.https.html ###
[task 2021-06-25T08:45:56.380Z] 08:45:56     INFO - |                     Subtest                     | Results |                                            Messages                                           |
[task 2021-06-25T08:45:56.380Z] 08:45:56     INFO - |-------------------------------------------------|---------|-----------------------------------------------------------------------------------------------|
[task 2021-06-25T08:45:56.381Z] 08:45:56     INFO - |                                                 | OK      |                                                                                               |
[task 2021-06-25T08:45:56.381Z] 08:45:56     INFO - | `setSinkId() after selectAudioOutput()`         | PASS    |                                                                                               |
[task 2021-06-25T08:45:56.381Z] 08:45:56     INFO - | `setSinkId() with deviceID from another window` | FAIL    | `assert_unreached: Should have rejected: before selectAudioOutput() Reached unreachable code` |
[task 2021-06-25T08:45:56.382Z] 08:45:56     INFO - 
[task 2021-06-25T08:45:56.382Z] 08:45:56     INFO - ## Slow tests ##
[task 2021-06-25T08:45:56.382Z] 08:45:56     INFO - 
[task 2021-06-25T08:45:56.382Z] 08:45:56     INFO - |                             Test                            | Result | Longest duration (ms) | Timeout (ms) |
[task 2021-06-25T08:45:56.383Z] 08:45:56     INFO - |-------------------------------------------------------------|--------|-----------------------|--------------|
[task 2021-06-25T08:45:56.383Z] 08:45:56     INFO - | `/audio-output/setSinkId-with-selectAudioOutput.https.html` | `OK`   | `8668`                | `10000`      |
[task 2021-06-25T08:45:56.383Z] 08:45:56     INFO - 
[task 2021-06-25T08:45:56.384Z] 08:45:56     INFO - ::: Running tests in a loop 10 times : PASS
[task 2021-06-25T08:45:56.384Z] 08:45:56     INFO - ::: Running tests in a loop with restarts 5 times : PASS
[task 2021-06-25T08:45:56.385Z] 08:45:56     INFO - ::: Running tests in a loop 10 times with flags chaos_mode_flags=0xfb : FAIL
[task 2021-06-25T08:45:56.385Z] 08:45:56     INFO - :::
[task 2021-06-25T08:45:56.385Z] 08:45:56    ERROR - ::: Test verification FAIL
[task 2021-06-25T08:45:56.386Z] 08:45:56     INFO - :::
[task 2021-06-25T08:45:56.725Z] 08:45:56    ERROR - Return code: 1
[task 2021-06-25T08:45:56.726Z] 08:45:56    ERROR - # TBPL FAILURE #
[task 2021-06-25T08:45:56.726Z] 08:45:56  WARNING - setting return code to 2
[task 2021-06-25T08:45:56.726Z] 08:45:56    ERROR - TinderboxPrint: Per-test run of .../setSinkId-with-selectAudioOutput.https.html<br/>: FAILURE
[task 2021-06-25T08:45:56.728Z] 08:45:56     INFO - Running command: ['/opt/worker/tasks/task_162460958094681/build/venv/bin/python', '-u', '/opt/worker/tasks/task_162460958094681/build/tests/web-platform/runtests.py', '--log-raw=-', '--log-raw=/opt/worker/tasks/task_162460958094681/build/blobber_upload_dir/wpt-test2_raw.log', '--log-wptreport=/opt/worker/tasks/task_162460958094681/build/blobber_upload_dir/wptreport.json', '--log-errorsummary=/opt/worker/tasks/task_162460958094681/build/blobber_upload_dir/wpt-test2_errorsummary.log', '--binary=/opt/worker/tasks/task_162460958094681/build/application/Firefox Nightly.app/Contents/MacOS/firefox', '--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Pe8xZLWDRJy4Yhz5ebfJHA/artifacts/public/build/target.crashreporter-symbols.zip', '--stackwalk-binary=/opt/worker/tasks/task_162460958094681/fetches/minidump_stackwalk/minidump_stackwalk', '--stackfix-dir=/opt/worker/tasks/task_162460958094681/build/tests/bin', '--no-pause-after-test', '--instrument-to-file=/opt/worker/tasks/task_162460958094681/build/blobber_upload_dir/wpt_instruments.txt', '--specialpowers-path=/opt/worker/tasks/task_162460958094681/build/tests/extensions/specialpowers@mozilla.org.xpi', '--processes=2', '--install-fonts', '--test-type=testharness', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--enable-webrender', '--prefs-root=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/prefs', '--config=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/wptrunner.ini', '--ca-cert-path=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/tests/tools/certs/cacert.pem', '--host-key-path=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/tests/tools/certs/web-platform.test.key', '--host-cert-path=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/tests/tools/certs/web-platform.test.pem', '--certutil-binary=/opt/worker/tasks/task_162460958094681/build/tests/bin/certutil', '--verify-log-full', '--verify', 'tests/web-platform/tests/audio-output/setSinkId.https.html'] in /opt/worker/tasks/task_162460958094681/build
[task 2021-06-25T08:45:56.729Z] 08:45:56     INFO - Copy/paste: /opt/worker/tasks/task_162460958094681/build/venv/bin/python -u /opt/worker/tasks/task_162460958094681/build/tests/web-platform/runtests.py --log-raw=- --log-raw=/opt/worker/tasks/task_162460958094681/build/blobber_upload_dir/wpt-test2_raw.log --log-wptreport=/opt/worker/tasks/task_162460958094681/build/blobber_upload_dir/wptreport.json --log-errorsummary=/opt/worker/tasks/task_162460958094681/build/blobber_upload_dir/wpt-test2_errorsummary.log "--binary=/opt/worker/tasks/task_162460958094681/build/application/Firefox Nightly.app/Contents/MacOS/firefox" --symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Pe8xZLWDRJy4Yhz5ebfJHA/artifacts/public/build/target.crashreporter-symbols.zip --stackwalk-binary=/opt/worker/tasks/task_162460958094681/fetches/minidump_stackwalk/minidump_stackwalk --stackfix-dir=/opt/worker/tasks/task_162460958094681/build/tests/bin --no-pause-after-test --instrument-to-file=/opt/worker/tasks/task_162460958094681/build/blobber_upload_dir/wpt_instruments.txt --specialpowers-path=/opt/worker/tasks/task_162460958094681/build/tests/extensions/specialpowers@mozilla.org.xpi --processes=2 --install-fonts --test-type=testharness --setpref=media.peerconnection.mtransport_process=false --setpref=network.process.enabled=false --setpref=layers.d3d11.enable-blacklist=false --enable-webrender --prefs-root=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/prefs --config=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/wptrunner.ini --ca-cert-path=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/tests/tools/certs/cacert.pem --host-key-path=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/tests/tools/certs/web-platform.test.key --host-cert-path=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/tests/tools/certs/web-platform.test.pem --certutil-binary=/opt/worker/tasks/task_162460958094681/build/tests/bin/certutil --verify-log-full --verify tests/web-platform/tests/audio-output/setSinkId.https.html
[task 2021-06-25T08:45:56.729Z] 08:45:56     INFO - Using env: (same as previous command)
[task 2021-06-25T08:45:56.729Z] 08:45:56     INFO - Calling ['/opt/worker/tasks/task_162460958094681/build/venv/bin/python', '-u', '/opt/worker/tasks/task_162460958094681/build/tests/web-platform/runtests.py', '--log-raw=-', '--log-raw=/opt/worker/tasks/task_162460958094681/build/blobber_upload_dir/wpt-test2_raw.log', '--log-wptreport=/opt/worker/tasks/task_162460958094681/build/blobber_upload_dir/wptreport.json', '--log-errorsummary=/opt/worker/tasks/task_162460958094681/build/blobber_upload_dir/wpt-test2_errorsummary.log', '--binary=/opt/worker/tasks/task_162460958094681/build/application/Firefox Nightly.app/Contents/MacOS/firefox', '--symbols-path=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Pe8xZLWDRJy4Yhz5ebfJHA/artifacts/public/build/target.crashreporter-symbols.zip', '--stackwalk-binary=/opt/worker/tasks/task_162460958094681/fetches/minidump_stackwalk/minidump_stackwalk', '--stackfix-dir=/opt/worker/tasks/task_162460958094681/build/tests/bin', '--no-pause-after-test', '--instrument-to-file=/opt/worker/tasks/task_162460958094681/build/blobber_upload_dir/wpt_instruments.txt', '--specialpowers-path=/opt/worker/tasks/task_162460958094681/build/tests/extensions/specialpowers@mozilla.org.xpi', '--processes=2', '--install-fonts', '--test-type=testharness', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--setpref=layers.d3d11.enable-blacklist=false', '--enable-webrender', '--prefs-root=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/prefs', '--config=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/wptrunner.ini', '--ca-cert-path=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/tests/tools/certs/cacert.pem', '--host-key-path=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/tests/tools/certs/web-platform.test.key', '--host-cert-path=/opt/worker/tasks/task_162460958094681/build/tests/web-platform/tests/tools/certs/web-platform.test.pem', '--certutil-binary=/opt/worker/tasks/task_162460958094681/build/tests/bin/certutil', '--verify-log-full', '--verify', 'tests/web-platform/tests/audio-output/setSinkId.https.html'] with output_timeout 1000
[task 2021-06-25T08:45:56.981Z] 08:45:56     INFO - :::
[task 2021-06-25T08:45:56.981Z] 08:45:56     INFO - ::: Running test verification step "Running tests in a loop 10 times"...
[task 2021-06-25T08:45:56.982Z] 08:45:56     INFO - :::
[task 2021-06-25T08:45:57.158Z] 08:45:57     INFO - mozversion application_buildid: 20210625082854
[task 2021-06-25T08:45:57.159Z] 08:45:57     INFO - mozversion application_changeset: 799062439b4758ce6e5a80b60d57e58c2dc308e3
[task 2021-06-25T08:45:57.159Z] 08:45:57     INFO - mozversion application_display_name: Firefox Nightly
[task 2021-06-25T08:45:57.159Z] 08:45:57     INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384}
[task 2021-06-25T08:45:57.160Z] 08:45:57     INFO - mozversion application_name: Firefox
[task 2021-06-25T08:45:57.160Z] 08:45:57     INFO - mozversion application_remotingname: firefox
[task 2021-06-25T08:45:57.160Z] 08:45:57     INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland
[task 2021-06-25T08:45:57.161Z] 08:45:57     INFO - mozversion application_vendor: Mozilla
[task 2021-06-25T08:45:57.161Z] 08:45:57     INFO - mozversion application_version: 91.0a1
[task 2021-06-25T08:45:57.162Z] 08:45:57     INFO - mozversion platform_buildid: 20210625082854
[task 2021-06-25T08:45:57.162Z] 08:45:57     INFO - mozversion platform_changeset: 799062439b4758ce6e5a80b60d57e58c2dc308e3
[task 2021-06-25T08:45:57.162Z] 08:45:57     INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland
[task 2021-06-25T08:45:57.163Z] 08:45:57     INFO - mozversion platform_version: 91.0a1
[task 2021-06-25T08:45:59.833Z] 08:45:59     INFO - Using 2 client processes
[task 2021-06-25T08:46:03.159Z] 08:46:03     INFO - Installed font: Ahem.ttf
[task 2021-06-25T08:46:03.558Z] 08:46:03     INFO - wptserve Starting http server on 127.0.0.1:8000
[task 2021-06-25T08:46:03.921Z] 08:46:03     INFO - wptserve Starting http server on 127.0.0.1:8001
[task 2021-06-25T08:46:04.287Z] 08:46:04     INFO - wptserve Starting https server on 127.0.0.1:8443
[task 2021-06-25T08:46:04.645Z] 08:46:04     INFO - wptserve Starting https server on 127.0.0.1:8444
[task 2021-06-25T08:46:05.001Z] 08:46:05     INFO - wptserve Create socket on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8888))
[task 2021-06-25T08:46:05.002Z] 08:46:05     INFO - wptserve Bind on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8888))
[task 2021-06-25T08:46:05.002Z] 08:46:05     INFO - wptserve Listen on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8888))
[task 2021-06-25T08:46:05.359Z] 08:46:05     INFO - wptserve Create socket on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8889))
[task 2021-06-25T08:46:05.361Z] 08:46:05     INFO - wptserve Bind on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8889))
[task 2021-06-25T08:46:05.361Z] 08:46:05     INFO - wptserve Listen on: (<AddressFamily.AF_INET: 2>, <SocketKind.SOCK_STREAM: 1>, 6, '', ('127.0.0.1', 8889))
[task 2021-06-25T08:46:05.721Z] 08:46:05     INFO - wptserve Starting http2 server on 127.0.0.1:9000
[task 2021-06-25T08:46:06.301Z] 08:46:06     INFO - SUITE-START | Running 1 tests
[task 2021-06-25T08:46:06.301Z] 08:46:06     INFO - Running testharness tests
[task 2021-06-25T08:46:06.341Z] 08:46:06     INFO - Setting up ssl
[task 2021-06-25T08:46:06.341Z] 08:46:06     INFO - Setting up ssl
[task 2021-06-25T08:46:06.372Z] 08:46:06     INFO - certutil | b''
[task 2021-06-25T08:46:06.372Z] 08:46:06     INFO - certutil | b''
[task 2021-06-25T08:46:06.405Z] 08:46:06     INFO - certutil | b''
[task 2021-06-25T08:46:06.406Z] 08:46:06     INFO - certutil | b''
[task 2021-06-25T08:46:06.432Z] 08:46:06     INFO - certutil | b'\nCertificate Nickname                                         Trust Attributes\n                                                             SSL,S/MIME,JAR/XPI\n\nweb-platform-tests                                           CT,, \n'
[task 2021-06-25T08:46:06.433Z] 08:46:06     INFO - certutil | b'\nCertificate Nickname                                         Trust Attributes\n                                                             SSL,S/MIME,JAR/XPI\n\nweb-platform-tests                                           CT,, \n'
[task 2021-06-25T08:46:06.434Z] 08:46:06     INFO - No more tests
[task 2021-06-25T08:46:06.437Z] 08:46:06     INFO - Application command: /opt/worker/tasks/task_162460958094681/build/application/Firefox Nightly.app/Contents/MacOS/firefox --marionette about:blank -foreground -profile /var/folders/py/s6z6j44x1c948949d7cpl9hm000014/T/tmplswc507g
[task 2021-06-25T08:46:06.442Z] 08:46:06     INFO - Starting runner
[task 2021-06-25T08:46:06.859Z] 08:46:06     INFO - PID 1898 | 1624610766858	Marionette	INFO	Marionette enabled
[task 2021-06-25T08:46:07.672Z] 08:46:07     INFO - PID 1898 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /var/folders/py/s6z6j44x1c948949d7cpl9hm000014/T/tmplswc507g/search.json.mozlz4", (void 0)))
[task 2021-06-25T08:46:08.440Z] 08:46:08     INFO - PID 1898 | 1624610768440	Marionette	INFO	Listening on port 49644
[task 2021-06-25T08:46:08.713Z] 08:46:08     INFO - TEST-START | /audio-output/setSinkId.https.html

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

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.