Closed Bug 1931700 Opened 1 year ago Closed 9 months ago

Intermittent /webrtc/protocol/video-codecs.https.html | single tracking bug

Categories

(Core :: WebRTC, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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


[task 2024-11-16T13:02:50.458Z] 13:02:50     INFO - TEST-START | /webrtc/protocol/video-codecs.https.html
[task 2024-11-16T13:02:50.462Z] 13:02:50     INFO - Setting pref media.navigator.permission.disabled to true
[task 2024-11-16T13:02:50.486Z] 13:02:50     INFO - Setting pref media.navigator.streams.fake to true
[task 2024-11-16T13:02:50.512Z] 13:02:50     INFO - Setting pref privacy.resistFingerprinting.reduceTimerPrecision.jitter to true
[task 2024-11-16T13:02:50.549Z] 13:02:50     INFO - Setting pref privacy.reduceTimerPrecision to true
[task 2024-11-16T13:02:50.569Z] 13:02:50     INFO - Setting pref media.peerconnection.ice.trickle_grace_period to 5000
[task 2024-11-16T13:02:50.594Z] 13:02:50     INFO - Setting pref media.peerconnection.ice.obfuscate_host_addresses to true
[task 2024-11-16T13:02:50.621Z] 13:02:50     INFO - Setting pref media.peerconnection.allow_old_setParameters to true
[task 2024-11-16T13:02:50.658Z] 13:02:50     INFO - Setting pref media.peerconnection.description.legacy.enabled to false
[task 2024-11-16T13:02:50.673Z] 13:02:50     INFO - Setting pref media.aboutwebrtc.hist.poll_interval_ms to 250
[task 2024-11-16T13:02:50.707Z] 13:02:50     INFO - Setting pref media.navigator.permission.disabled to true
[task 2024-11-16T13:02:50.737Z] 13:02:50     INFO - Setting pref media.navigator.streams.fake to true
[task 2024-11-16T13:02:50.768Z] 13:02:50     INFO - Setting pref privacy.resistFingerprinting.reduceTimerPrecision.jitter to false
[task 2024-11-16T13:02:50.804Z] 13:02:50     INFO - Setting pref privacy.reduceTimerPrecision to false
[task 2024-11-16T13:02:50.836Z] 13:02:50     INFO - Setting pref media.peerconnection.ice.trickle_grace_period to 10000
[task 2024-11-16T13:02:50.869Z] 13:02:50     INFO - Setting pref media.peerconnection.ice.obfuscate_host_addresses to false
[task 2024-11-16T13:02:50.901Z] 13:02:50     INFO - Setting pref media.peerconnection.allow_old_setParameters to false
[task 2024-11-16T13:02:50.934Z] 13:02:50     INFO - Setting pref media.peerconnection.description.legacy.enabled to false
[task 2024-11-16T13:02:50.966Z] 13:02:50     INFO - Setting pref media.aboutwebrtc.hist.poll_interval_ms to 2000
[task 2024-11-16T13:02:50.991Z] 13:02:50     INFO - Closing window 800614cf-7718-4896-b6ad-573babd1567d
[task 2024-11-16T13:02:51.485Z] 13:02:51     INFO - PID 31560 | [Socket 31764: Socket Thread]: I/mtransport NrIceCtx(PC:{74eaf6fc-db6f-4f25-836b-64c5548d36e0} 1731762170008230 (id=141733920769 url=http://web-platform.test:8000/webrtc/protocol/unknown-mediatypes.html)): Destroy
[task 2024-11-16T13:02:51.646Z] 13:02:51     INFO - PID 31560 | Timecard created 1731762170.004543
[task 2024-11-16T13:02:51.647Z] 13:02:51     INFO - PID 31560 |  Timestamp   | Delta       | Event                                  | File                         | Function
[task 2024-11-16T13:02:51.647Z] 13:02:51     INFO - PID 31560 | =========================================================================================================================
[task 2024-11-16T13:02:51.648Z] 13:02:51     INFO - PID 31560 |     0.001148 |    0.001148 | Constructor Completed                  | PeerConnectionImpl.cpp:414   | PeerConnectionImpl
[task 2024-11-16T13:02:51.648Z] 13:02:51     INFO - PID 31560 |     0.003748 |    0.002600 | Initializing PC Ctx                    | PeerConnectionImpl.cpp:499   | Initialize
[task 2024-11-16T13:02:51.652Z] 13:02:51     INFO - PID 31560 |     0.108753 |    0.105005 | Create Offer                           | PeerConnectionImpl.cpp:1579  | CreateOffer
[task 2024-11-16T13:02:51.652Z] 13:02:51     INFO - PID 31560 |     0.200208 |    0.091455 | Close                                  | PeerConnectionImpl.cpp:2505  | Close
[task 2024-11-16T13:02:51.652Z] 13:02:51     INFO - PID 31560 |     1.640545 |    1.440337 | Destructor Invoked                     | PeerConnectionImpl.cpp:431   | ~PeerConnectionImpl
[task 2024-11-16T13:02:51.652Z] 13:02:51     INFO - PID 31560 |     1.640547 |    0.000002 | {74eaf6fc-db6f-4f25-836b-64c5548d36e0} | PeerConnectionImpl.cpp:432   | ~PeerConnectionImpl
[task 2024-11-16T13:02:51.652Z] 13:02:51     INFO - PID 31560 | [Child 2373: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:439: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {74eaf6fc-db6f-4f25-836b-64c5548d36e0}
[task 2024-11-16T13:02:51.653Z] 13:02:51     INFO - PID 31560 | Timecard created 1731762170.052041
[task 2024-11-16T13:02:51.653Z] 13:02:51     INFO - PID 31560 |  Timestamp   | Delta       | Event                                  | File                         | Function
[task 2024-11-16T13:02:51.653Z] 13:02:51     INFO - PID 31560 | ==========================================================================================================================
[task 2024-11-16T13:02:51.653Z] 13:02:51     INFO - PID 31560 |     0.000602 |    0.000602 | Constructor Completed                  | PeerConnectionImpl.cpp:414   | PeerConnectionImpl
[task 2024-11-16T13:02:51.653Z] 13:02:51     INFO - PID 31560 |     0.002668 |    0.002066 | Initializing PC Ctx                    | PeerConnectionImpl.cpp:499   | Initialize
[task 2024-11-16T13:02:51.654Z] 13:02:51     INFO - PID 31560 |     0.126686 |    0.124018 | Set Remote Description                 | PeerConnectionImpl.cpp:1792  | SetRemoteDescription
[task 2024-11-16T13:02:51.654Z] 13:02:51     INFO - PID 31560 |     0.155584 |    0.028898 | Close                                  | PeerConnectionImpl.cpp:2505  | Close
[task 2024-11-16T13:02:51.655Z] 13:02:51     INFO - PID 31560 |     1.593949 |    1.438365 | Destructor Invoked                     | PeerConnectionImpl.cpp:431   | ~PeerConnectionImpl
[task 2024-11-16T13:02:51.655Z] 13:02:51     INFO - PID 31560 |     1.593951 |    0.000002 | {c62396d9-a3d0-4e6c-8404-4dd54fbee43c} | PeerConnectionImpl.cpp:432   | ~PeerConnectionImpl
[task 2024-11-16T13:02:51.656Z] 13:02:51     INFO - PID 31560 | [Child 2373: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:439: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {c62396d9-a3d0-4e6c-8404-4dd54fbee43c}
[task 2024-11-16T13:03:35.465Z] 13:03:35     INFO - Got timeout in harness
[task 2024-11-16T13:03:35.466Z] 13:03:35     INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/protocol/video-codecs.https.html | TestRunner hit external timeout (this may indicate a hang)
[task 2024-11-16T13:03:35.466Z] 13:03:35     INFO - TEST-INFO took 45009ms
[task 2024-11-16T13:03:45.472Z] 13:03:45  WARNING - Forcibly terminating runner process
[task 2024-11-16T13:20:25.474Z] 13:20:25     INFO - Automation Error: mozharness timed out after 1000 seconds running ['/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_errorsummary.log', '--symbols-path=None', '--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=layers.d3d11.enable-blacklist=false', '--setpref=javascript.options.mem.gc_parallel_marking=true', '--skip-timeout', '--skip-crash', '--skip-implementation-status=backlog', '--skip-implementation-status=not-implementing', '--test-groups=/builds/worker/fetches/wpt_tests_by_group.json', '--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', '--exclude-tag=webgpu', '--exclude-tag=canvas', '--exclude-tag=webcodecs', '--exclude-tag=eme', '/js-self-profiling', '/_mozilla/webtransport/bfcache', '/paint-timing', '/webxr/depth-sensing/cpu', '/_mozilla/css/cssom', '/wasm/jsapi/functions', '/webaudio/the-audio-api/the-stereopanner-interface', '/cookie-store', '/css/selectors/invalidation', '/client-hints/critical-ch', '/content-security-policy/frame-src', '/dom/nodes/Document-contentType', '/private-aggregation', '/measure-memory', '/service-workers/service-worker/multi-globals', '/svg/pservers/scripted', '/html/webappapis/timers', '/xhr/formdata', '/resource-timing', '/css/compositing/mix-blend-mode', '/fullscreen/model', '/uievents/textInput', '/css/css-cascade', '/eventsource/shared-worker', '/html/dom/elements', '/css/css-text/animations', '/mixed-content/gen/worker-classic.http-rp', '/css/css-overscroll-behavior', '/css/css-flexbox', '/html/semantics/edits', '/css/css-values', '/css/css-scroll-snap/parsing', '/fetch/cross-origin-resource-policy', '/bluetooth/characteristic/writeValue', '/web-locks', '/webrtc/protocol', '/css/css-images/gradient', '/streams/piping']
[task 2024-11-16T13:20:25.480Z] 13:20:25     INFO - Return code: -9
[task 2024-11-16T13:20:25.480Z] 13:20:25  WARNING - Got 1 unexpected statuses
[task 2024-11-16T13:20:25.480Z] 13:20:25    ERROR - No suite end message was emitted by this harness.
[task 2024-11-16T13:20:25.480Z] 13:20:25  WARNING - setting return code to 2
[task 2024-11-16T13:20:25.480Z] 13:20:25     INFO - Running post-action listener: _package_coverage_data
[task 2024-11-16T13:20:25.480Z] 13:20:25     INFO - Running post-action listener: _resource_record_post_action
[task 2024-11-16T13:20:25.481Z] 13:20:25     INFO - Running post-action listener: process_java_coverage_data
[task 2024-11-16T13:20:25.481Z] 13:20:25     INFO - Running post-action listener: stop_device
[task 2024-11-16T13:20:25.481Z] 13:20:25     INFO - [mozharness: 2024-11-16 13:20:25.481117Z] Finished run-tests step (success)
[task 2024-11-16T13:20:25.481Z] 13:20:25     INFO - Running post-run listener: _resource_record_post_run
[task 2024-11-16T13:20:26.514Z] 13:20:26     INFO - Total resource usage - Wall time: 3276s; CPU: 56%; Read bytes: 143360; Write bytes: 2726526976; Read time: 232; Write time: 1575592
[task 2024-11-16T13:20:26.514Z] 13:20:26     INFO - TinderboxPrint: CPU usage<br/>55.5%
[task 2024-11-16T13:20:26.514Z] 13:20:26     INFO - TinderboxPrint: I/O read bytes / time<br/>143,360 / 232
[task 2024-11-16T13:20:26.514Z] 13:20:26     INFO - TinderboxPrint: I/O write bytes / time<br/>2,726,526,976 / 1,575,592
[task 2024-11-16T13:20:26.514Z] 13:20:26     INFO - TinderboxPrint: CPU idle<br/>5,783.4 (44.4%)
[task 2024-11-16T13:20:26.514Z] 13:20:26     INFO - TinderboxPrint: CPU system<br/>778.5 (6.0%)
[task 2024-11-16T13:20:26.514Z] 13:20:26     INFO - TinderboxPrint: CPU user<br/>6,419.1 (49.3%)
[task 2024-11-16T13:20:26.514Z] 13:20:26     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2024-11-16T13:20:26.518Z] 13:20:26     INFO - pull - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-11-16T13:20:26.521Z] 13:20:26     INFO - start-emulator - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-11-16T13:20:26.525Z] 13:20:26     INFO - verify-device - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-11-16T13:20:26.530Z] 13:20:26     INFO - install - Wall time: 38s; CPU: 25%; Read bytes: 0; Write bytes: 798265344; Read time: 0; Write time: 805644
[task 2024-11-16T13:20:26.687Z] 13:20:26     INFO - run-tests - Wall time: 3236s; CPU: 56%; Read bytes: 143360; Write bytes: 1915416576; Read time: 232; Write time: 755108
[task 2024-11-16T13:20:31.877Z] 13:20:31  WARNING - returning nonzero exit status 2
[task 2024-11-16T13:20:32.097Z] cleanup
[task 2024-11-16T13:20:32.097Z] + cleanup
[task 2024-11-16T13:20:32.097Z] + local rv=2
[task 2024-11-16T13:20:32.097Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2024-11-16T13:20:32.097Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2024-11-16T13:20:32.112Z] + false
[task 2024-11-16T13:20:32.112Z] + '[' ']'
[task 2024-11-16T13:20:32.112Z] + true
[task 2024-11-16T13:20:32.112Z] + cleanup_xvfb
[task 2024-11-16T13:20:32.112Z] ++ pidof Xvfb
[task 2024-11-16T13:20:32.146Z] + local xvfb_pid=50
[task 2024-11-16T13:20:32.147Z] + local vnc=false
[task 2024-11-16T13:20:32.147Z] + local interactive=false
[task 2024-11-16T13:20:32.147Z] + '[' -n 50 ']'
[task 2024-11-16T13:20:32.147Z] + [[ false == false ]]
[task 2024-11-16T13:20:32.147Z] + [[ false == false ]]
[task 2024-11-16T13:20:32.147Z] + kill 50
[task 2024-11-16T13:20:32.147Z] + screen -XS xvfb quit
[task 2024-11-16T13:20:32.151Z] + exit 2
[taskcluster 2024-11-16 13:20:32.704Z] === Task Finished ===
[taskcluster 2024-11-16 13:20:35.464Z] Unsuccessful task run with exit code: 2 completed in 3531.699 seconds

Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago9 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.