Closed Bug 1782804 Opened 3 years ago Closed 3 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCRtpTransceiver-stop.html | TestRunner hit external timeout (this may indicate a hang)

Categories

(Core :: WebRTC, defect, P5)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1785692

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

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


[task 2022-08-02T18:36:12.776Z] 18:36:12     INFO - PID 3596 | [Child 3748: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:392: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {48ab8860-11d0-4af5-8c36-2ef19c3992db}
[task 2022-08-02T18:36:12.777Z] 18:36:12     INFO - PID 3596 | [Child 3748: Socket Thread]: I/mtransport NrIceCtx(PC:{48ab8860-11d0-4af5-8c36-2ef19c3992db} 1659465364371872 (id=77309411333 url=https://web-platform.test:8443/webrtc/RTCRtpSender.): Destroy
[task 2022-08-02T18:36:14.621Z] 18:36:14     INFO - PID 3596 | (ice/INFO) ICE(PC:{5bda2cf9-9597-4aac-a5d9-8de9f8d79c32} 1659465364560096 (id=79456894981 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei): peer (PC:{5bda2cf9-9597-4aac-a5d9-8de9f8d79c32} 1659465364560096 (id=79456894981 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei:default) Trickle grace period is over; marking every component with only failed pairs as failed.
[task 2022-08-02T18:36:14.757Z] 18:36:14     INFO - PID 3596 | (ice/INFO) ICE(PC:{95caf551-63e5-4306-a817-a824219d99b0} 1659465364663298 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei): peer (PC:{95caf551-63e5-4306-a817-a824219d99b0} 1659465364663298 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei:default) Trickle grace period is over; marking every component with only failed pairs as failed.
[task 2022-08-02T18:36:14.757Z] 18:36:14     INFO - PID 3596 | (ice/INFO) ICE(PC:{cf59468c-be79-4d5d-80d6-534890f68dcb} 1659465364663602 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei): peer (PC:{cf59468c-be79-4d5d-80d6-534890f68dcb} 1659465364663602 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei:default) Trickle grace period is over; marking every component with only failed pairs as failed.
[task 2022-08-02T18:36:14.758Z] 18:36:14     INFO - PID 3596 | (ice/INFO) ICE(PC:{ca0c4609-f73f-4715-8e91-6b5a6e8c0184} 1659465364678121 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei): peer (PC:{ca0c4609-f73f-4715-8e91-6b5a6e8c0184} 1659465364678121 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei:default) Trickle grace period is over; marking every component with only failed pairs as failed.
[task 2022-08-02T18:36:14.759Z] 18:36:14     INFO - PID 3596 | (ice/INFO) ICE(PC:{ae3c4972-78a9-4e3f-a546-469b83945d3d} 1659465364678420 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei): peer (PC:{ae3c4972-78a9-4e3f-a546-469b83945d3d} 1659465364678420 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei:default) Trickle grace period is over; marking every component with only failed pairs as failed.
[task 2022-08-02T18:36:14.760Z] 18:36:14     INFO - PID 3596 | (ice/INFO) ICE(PC:{ed2011b2-d846-4cc2-b59d-43fe322143a3} 1659465364689852 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei): peer (PC:{ed2011b2-d846-4cc2-b59d-43fe322143a3} 1659465364689852 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei:default) Trickle grace period is over; marking every component with only failed pairs as failed.
[task 2022-08-02T18:36:14.761Z] 18:36:14     INFO - PID 3596 | (ice/INFO) ICE(PC:{7698fc57-82d1-4811-979b-ec53f62391a6} 1659465364690162 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei): peer (PC:{7698fc57-82d1-4811-979b-ec53f62391a6} 1659465364690162 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei:default) Trickle grace period is over; marking every component with only failed pairs as failed.
[task 2022-08-02T18:36:14.761Z] 18:36:14     INFO - PID 3596 | (ice/INFO) ICE(PC:{aaae1189-15f9-45d7-9542-d973d8e4c317} 1659465364700769 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei): peer (PC:{aaae1189-15f9-45d7-9542-d973d8e4c317} 1659465364700769 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei:default) Trickle grace period is over; marking every component with only failed pairs as failed.
[task 2022-08-02T18:36:14.762Z] 18:36:14     INFO - PID 3596 | (ice/INFO) ICE(PC:{7c6475f4-a126-463d-9f5e-2123a175c107} 1659465364701070 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei): peer (PC:{7c6475f4-a126-463d-9f5e-2123a175c107} 1659465364701070 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei:default) Trickle grace period is over; marking every component with only failed pairs as failed.
[task 2022-08-02T18:36:14.763Z] 18:36:14     INFO - PID 3596 | (ice/INFO) ICE(PC:{b7f56583-be9a-4dff-ba11-8922f247dcde} 1659465364715078 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei): peer (PC:{b7f56583-be9a-4dff-ba11-8922f247dcde} 1659465364715078 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei:default) Trickle grace period is over; marking every component with only failed pairs as failed.
[task 2022-08-02T18:36:14.764Z] 18:36:14     INFO - PID 3596 | (ice/INFO) ICE(PC:{133739a7-53ba-4510-8e78-aa3dd68c9a59} 1659465364714688 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei): peer (PC:{133739a7-53ba-4510-8e78-aa3dd68c9a59} 1659465364714688 (id=79456894985 url=http://web-platform.test:8000/webrtc/RTCRtpTranscei:default) Trickle grace period is over; marking every component with only failed pairs as failed.
[task 2022-08-02T18:36:29.725Z] 18:36:29     INFO - Got timeout in harness
[task 2022-08-02T18:36:29.727Z] 18:36:29     INFO - TEST-UNEXPECTED-TIMEOUT | /webrtc/RTCRtpTransceiver-stop.html | TestRunner hit external timeout (this may indicate a hang)
[task 2022-08-02T18:36:29.727Z] 18:36:29     INFO - TEST-INFO took 25147ms
[task 2022-08-02T18:37:49.081Z] 18:37:49     INFO - Browser exited with return code -15
[task 2022-08-02T18:37:49.095Z] 18:37:49     INFO - IOError on command, setting status to CRASH
[task 2022-08-02T18:37:49.095Z] 18:37:49     INFO - Browser not responding, setting status to CRASH
[task 2022-08-02T18:37:49.096Z] 18:37:49  WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.TestharnessTest /webrtc/RTCRtpTransceiver-stop.html>, (<wptrunner.wpttest.TestharnessResult CRASH>, []))
[task 2022-08-02T18:37:49.096Z] 18:37:49     INFO - Closing logging queue
[task 2022-08-02T18:37:49.096Z] 18:37:49     INFO - queue closed
[task 2022-08-02T18:37:49.132Z] 18:37:49     INFO - Application command: /opt/worker/tasks/task_165946322141249/build/application/Firefox Nightly.app/Contents/MacOS/firefox --marionette about:blank -foreground -profile /var/folders/d4/x_vfvw310vn5_1j9_sjhm7gw000014/T/tmp0zcp01c_
[task 2022-08-02T18:37:49.140Z] 18:37:49     INFO - PID 3608 | 1659465180258	Marionette	INFO	Marionette enabled
[task 2022-08-02T18:37:49.140Z] 18:37:49     INFO - PID 3608 | 1659465180284	Marionette	INFO	Listening on port 60474
[task 2022-08-02T18:37:49.141Z] 18:37:49     INFO - PID 3608 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /var/folders/d4/x_vfvw310vn5_1j9_sjhm7gw000014/T/tmp506mf3a8/search.json.mozlz4", (void 0)))
[task 2022-08-02T18:37:49.142Z] 18:37:49     INFO - PID 3608 | 2022-08-02 18:33:30.132 firefox[3608:98415] Persistent UI failed to open file file:///Users/cltbld/Library/Saved%20Application%20State/org.mozilla.nightly.savedState/window_1.data: No such file or directory (2)
[task 2022-08-02T18:37:49.142Z] 18:37:49     INFO - Starting runner
[task 2022-08-02T18:37:49.734Z] 18:37:49     INFO - TEST-START | /webrtc/RTCRtpTransceiver-stopping.https.html
[task 2022-08-02T18:37:49.743Z] 18:37:49     INFO - Setting pref media.navigator.permission.disabled to true
[task 2022-08-02T18:37:49.749Z] 18:37:49     INFO - Setting pref media.navigator.streams.fake to true
[task 2022-08-02T18:37:49.756Z] 18:37:49     INFO - Setting pref privacy.resistFingerprinting.reduceTimerPrecision.jitter to false
[task 2022-08-02T18:37:49.761Z] 18:37:49     INFO - Setting pref privacy.reduceTimerPrecision to false
[task 2022-08-02T18:37:49.765Z] 18:37:49     INFO - Setting pref media.peerconnection.ice.trickle_grace_period to 10000
[task 2022-08-02T18:37:49.771Z] 18:37:49     INFO - Setting pref media.peerconnection.ice.obfuscate_host_addresses to false
[task 2022-08-02T18:37:49.898Z] 18:37:49     INFO - PID 3608 | [Child 3631: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:366: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2022-08-02T18:37:49.898Z] 18:37:49     INFO - PID 3608 | (unknown/INFO) insert '' (registry) succeeded:
[task 2022-08-02T18:37:49.899Z] 18:37:49     INFO - PID 3608 | (registry/INFO) Initialized registry
[task 2022-08-02T18:37:49.901Z] 18:37:49     INFO - PID 3608 | [Parent 3608: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
[task 2022-08-02T18:37:49.902Z] 18:37:49     INFO - PID 3608 | [Child 3631: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:366: PeerConnectionImpl: PeerConnectionImpl constructor for
[task 2022-08-02T18:37:49.903Z] 18:37:49     INFO - PID 3608 | [Parent 3608: Socket Thread]: D/mtransport NrIceCtx static call to find local stun addresses
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.