Closed Bug 1779304 Opened 4 years ago Closed 3 years ago

Intermittent dom/media/webrtc/tests/mochitests/test_peerConnection_stats_relayProtocol.html (finished) | single tracking bug

Categories

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

defect

Tracking

()

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=384128942&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/DlhZ4iRoTXe9vNEQCP9SQw/runs/0/artifacts/public/logs/live_backing.log


[task 2022-07-12T18:52:46.245Z] 18:52:46     INFO - TEST-OK | dom/media/webrtc/tests/mochitests/test_peerConnection_stats_relayProtocol.html | took 38705ms
[task 2022-07-12T18:52:46.249Z] 18:52:46     INFO - GECKO(11448) | (turn/INFO) TURN(relay(IP4:10.7.204.34:0/TCP|IP4:0.0.0.0:3478/TCP)): deallocating
[task 2022-07-12T18:52:46.254Z] 18:52:46     INFO - GECKO(11448) | (generic/INFO) TestNrS
[task 2022-07-12T18:52:46.257Z] 18:52:46     INFO - ICE Server websocket closed
[task 2022-07-12T18:52:46.260Z] 18:52:46     INFO - GECKO(11448) | ocket
[task 2022-07-12T18:52:46.263Z] 18:52:46     INFO - GECKO(11448) | IP4:10.7.204.34:0/TCP destroying port mapping IP4:10.7.204.34:0/TCP -> IP4:0.0.0.0:3478/TCP
[task 2022-07-12T18:52:46.266Z] 18:52:46     INFO - GECKO(11448) | (generic/ERR) Write error for IP4:0.0.0.0:3478/TCP - 3
[task 2022-07-12T18:52:46.268Z] 18:52:46     INFO - GECKO(11448) | (turn/WARNING) TURN(relay(IP4:10.7.204.34:0/TCP|IP4:0.0.0.0:3478/TCP)): Failed sending request
[task 2022-07-12T18:52:46.271Z] 18:52:46     INFO - GECKO(11448) | (turn/INFO) TURN(relay(IP4:10.7.204.34:0/TLS|IP4:0.0.0.0:5349/TLS)): deallocating
[task 2022-07-12T18:52:46.274Z] 18:52:46     INFO - GECKO(11448) | (generic/INFO) TestNrSocket IP4:10.7.204.34:0/TLS destroying port mapping IP4:10.7.204.34:0/TLS -> IP4:0.0.0.0:5349/TLS
[task 2022-07-12T18:52:46.276Z] 18:52:46     INFO - GECKO(11448) | (generic/ERR) Write error for IP4:0.0.0.0:5349/TLS - 3
[task 2022-07-12T18:52:46.279Z] 18:52:46     INFO - GECKO(11448) | (turn/WARNING) TURN(relay(IP4:10.7.204.34:0/TLS|IP4:0.0.0.0:5349/TLS)): Failed sending request
[task 2022-07-12T18:52:46.282Z] 18:52:46     INFO - GECKO(11448) | (generic/ERR) NrTcpSocket::OnClose 0000013B7A54D600 reason=2152398868 name=NS_ERROR_NET_RESET
[task 2022-07-12T18:52:46.285Z] 18:52:46    ERROR - GECKO(11448) | A content process crashed and MOZ_CRASHREPORTER_SHUTDOWN is set, shutting down
[task 2022-07-12T18:52:46.288Z] 18:52:46     INFO - GECKO(11448) | 1657651966221	Marionette	TRACE	Received observer notification quit-application
[task 2022-07-12T18:52:46.291Z] 18:52:46     INFO - GECKO(11448) | 1657651966222	Marionette	INFO	Stopped listening on port 2828
[task 2022-07-12T18:52:46.293Z] 18:52:46     INFO - GECKO(11448) | 1657651966222	Marionette	DEBUG	Marionette stopped listening
[task 2022-07-12T18:52:46.296Z] 18:52:46     INFO - GECKO(11448) | [Parent 3724, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2022-07-12T18:52:46.300Z] 18:52:46     INFO - GECKO(11448) | [Parent 3724, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2022-07-12T18:52:46.624Z] 18:52:46     INFO - TEST-INFO | Main app process: exit f5
[task 2022-07-12T18:52:46.626Z] 18:52:46     INFO - Buffered messages finished
[task 2022-07-12T18:52:46.629Z] 18:52:46    ERROR - TEST-UNEXPECTED-FAIL | dom/media/webrtc/tests/mochitests/test_peerConnection_stats_relayProtocol.html (finished) | application terminated with exit code 245
[task 2022-07-12T18:52:46.631Z] 18:52:46     INFO - runtests.py | Application ran for: 0:08:12.190269
[task 2022-07-12T18:52:46.634Z] 18:52:46     INFO - zombiecheck | Reading PID log: C:\Users\testdroid.YOGA-014\AppData\Local\Temp\tmpb4bkpe5vpidlog
[task 2022-07-12T18:52:46.638Z] 18:52:46     INFO - ==> process 3724 launched child process 10784 ("C:\tasks\task_1657637188\build\application\firefox\firefox.exe" -contentproc --channel="3724.0.450452404\179296415" -parentBuildID 20220712162801 -prefsHandle 2140 -prefMapHandle 2132 -prefsLen 20337 -prefMapSize 239775 -appDir "C:\tasks\task_1657637188\build\application\firefox\browser" - 3724 "\\.\pipe\gecko-crash-server-pipe.3724" 2220 143a4df0548 gpu)
[task 2022-07-12T18:52:46.642Z] 18:52:46     INFO - ==> process 3724 launched child process 3804 ("C:\tasks\task_1657637188\build\application\firefox\firefox.exe" -contentproc --channel="3724.1.1517432909\488698994" -childID 1 -isForBrowser -prefsHandle 2824 -prefMapHandle 2816 -prefsLen 21489 -prefMapSize 239775 -jsInitHandle 1396 -jsInitLen 267752 -a11yResourceId 64 -parentBuildID 20220712162801 -appDir "C:\tasks\task_1657637188\build\application\firefox\browser" - 3724 "\\.\pipe\gecko-crash-server-pipe.3724" 2836 143a84ed348 tab)
[task 2022-07-12T18:52:46.646Z] 18:52:46     INFO - ==> process 3724 launched child process 5384 ("C:\tasks\task_1657637188\build\application\firefox\firefox.exe" -contentproc --channel="3724.2.650204288\1110978447" -childID 2 -isForBrowser -prefsHandle 3096 -prefMapHandle 3044 -prefsLen 21597 -prefMapSize 239775 -jsInitHandle 1396 -jsInitLen 267752 -a11yResourceId 64 -parentBuildID 20220712162801 -appDir "C:\tasks\task_1657637188\build\application\firefox\browser" - 3724 "\\.\pipe\gecko-crash-server-pipe.3724" 2804 1439736db48 tab)
[task 2022-07-12T18:52:46.651Z] 18:52:46     INFO - ==> process 3724 launched child process 11004 ("C:\tasks\task_1657637188\build\application\firefox\firefox.exe" -contentproc --channel="3724.3.584661629\776379595" -childID 3 -isForBrowser -prefsHandle 3292 -prefMapHandle 3296 -prefsLen 21674 -prefMapSize 239775 -jsInitHandle 1396 -jsInitLen 267752 -a11yResourceId 64 -parentBuildID 20220712162801 -appDir "C:\tasks\task_1657637188\build\application\firefox\browser" - 3724 "\\.\pipe\gecko-crash-server-pipe.3724" 3380 143aa112e48 tab)
[task 2022-07-12T18:52:46.655Z] 18:52:46     INFO - ==> process 3724 launched child process 8300 ("C:\tasks\task_1657637188\build\application\firefox\firefox.exe" -contentproc --channel="3724.4.1331006407\1384437813" -childID 4 -isForBrowser -prefsHandle 3824 -prefMapHandle 3816 -prefsLen 29757 -prefMapSize 239775 -jsInitHandle 1396 -jsInitLen 267752 -a11yResourceId 64 -parentBuildID 20220712162801 -appDir "C:\tasks\task_1657637188\build\application\firefox\browser" - 3724 "\\.\pipe\gecko-crash-server-pipe.3724" 3680 143a84ebb48 tab)
[task 2022-07-12T18:52:46.660Z] 18:52:46     INFO - ==> process 3724 launched child process 6164 ("C:\tasks\task_1657637188\build\application\firefox\firefox.exe" -contentproc --channel="3724.5.2053221964\19398600" -childID 5 -isForBrowser -prefsHandle 3836 -prefMapHandle 3832 -prefsLen 29757 -prefMapSize 239775 -jsInitHandle 1396 -jsInitLen 267752 -a11yResourceId 64 -parentBuildID 20220712162801 -appDir "C:\tasks\task_1657637188\build\application\firefox\browser" - 3724 "\\.\pipe\gecko-crash-server-pipe.3724" 3860 143ace28348 tab)
[task 2022-07-12T18:52:46.665Z] 18:52:46     INFO - ==> process 3724 launched child process 5280 ("C:\tasks\task_1657637188\build\application\firefox\firefox.exe" -contentproc --channel="3724.6.1763941771\1499678052" -childID 6 -isForBrowser -prefsHandle 3976 -prefMapHandle 3852 -prefsLen 29757 -prefMapSize 239775 -jsInitHandle 1396 -jsInitLen 267752 -a11yResourceId 64 -parentBuildID 20220712162801 -appDir "C:\tasks\task_1657637188\build\application\firefox\browser" - 3724 "\\.\pipe\gecko-crash-server-pipe.3724" 4072 143ace27748 tab)
[task 2022-07-12T18:52:46.670Z] 18:52:46     INFO - ==> process 3724 launched child process 5776 ("C:\tasks\task_1657637188\build\application\firefox\firefox.exe" -contentproc --channel="3724.7.791197904\1037980447" -childID 7 -isForBrowser -prefsHandle 4608 -prefMapHandle 4632 -prefsLen 30063 -prefMapSize 239775 -jsInitHandle 1396 -jsInitLen 267752 -a11yResourceId 64 -parentBuildID 20220712162801 -appDir "C:\tasks\task_1657637188\build\application\firefox\browser" - 3724 "\\.\pipe\gecko-crash-server-pipe.3724" 4192 143ae093f48 tab)
[task 2022-07-12T18:52:46.674Z] 18:52:46     INFO - ==> process 3724 launched child process 6624 ("C:\tasks\task_1657637188\build\application\firefox\firefox.exe" -contentproc --channel="3724.8.140778506\173073306" -childID 8 -isForBrowser -prefsHandle 4748 -prefMapHandle 4752 -prefsLen 30063 -prefMapSize 239775 -jsInitHandle 1396 -jsInitLen 267752 -a11yResourceId 64 -parentBuildID 20220712162801 -appDir "C:\tasks\task_1657637188\build\application\firefox\browser" - 3724 "\\.\pipe\gecko-crash-server-pipe.3724" 4736 143ae0bf948 tab)
[task 2022-07-12T18:52:46.676Z] 18:52:46     INFO - zombiecheck | Checking for orphan process with PID: 10784
[task 2022-07-12T18:52:46.678Z] 18:52:46     INFO - zombiecheck | Checking for orphan process with PID: 5280
[task 2022-07-12T18:52:46.680Z] 18:52:46     INFO - zombiecheck | Checking for orphan process with PID: 6624
[task 2022-07-12T18:52:46.682Z] 18:52:46     INFO - zombiecheck | Checking for orphan process with PID: 11004
[task 2022-07-12T18:52:46.684Z] 18:52:46     INFO - zombiecheck | Checking for orphan process with PID: 5384
[task 2022-07-12T18:52:46.685Z] 18:52:46     INFO - zombiecheck | Checking for orphan process with PID: 8300
[task 2022-07-12T18:52:46.687Z] 18:52:46     INFO - zombiecheck | Checking for orphan process with PID: 5776
[task 2022-07-12T18:52:46.689Z] 18:52:46     INFO - zombiecheck | Checking for orphan process with PID: 6164
[task 2022-07-12T18:52:46.691Z] 18:52:46     INFO - zombiecheck | Checking for orphan process with PID: 3804
[task 2022-07-12T18:52:46.693Z] 18:52:46     INFO - runtests.py | Running with scheme: https
[task 2022-07-12T18:52:46.694Z] 18:52:46     INFO - runtests.py | Running with e10s: True
[task 2022-07-12T18:52:46.696Z] 18:52:46     INFO - runtests.py | Running with fission: True
[task 2022-07-12T18:52:46.698Z] 18:52:46     INFO - runtests.py | Running with cross-origin iframes: False
[task 2022-07-12T18:52:46.700Z] 18:52:46     INFO - runtests.py | Running with serviceworker_e10s: True
[task 2022-07-12T18:52:46.702Z] 18:52:46     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2022-07-12T18:52:46.704Z] 18:52:46     INFO - runtests.py | Running tests: start.
[task 2022-07-12T18:52:46.704Z] 18:52:46     INFO - 
[task 2022-07-12T18:52:47.089Z] 18:52:47     INFO - Application command: C:\tasks\task_1657637188\build\application\firefox\firefox.exe -marionette --wait-for-browser -foreground -profile C:\Users\testdroid.YOGA-014\AppData\Local\Temp\tmpj3c6n6ti.mozrunner
[task 2022-07-12T18:52:47.101Z] 18:52:47     INFO - runtests.py | Application pid: 11100
[task 2022-07-12T18:52:47.104Z] 18:52:47     INFO - TEST-INFO | started process GECKO(11100)
[task 2022-07-12T18:52:47.287Z] 18:52:47     INFO - GECKO(11100) | C:\Users\testdroid.YOGA-014\AppData\Local\Temp\tmpj3c6n6ti.mozrunner\user.js:227: prefs parse error: unexpected character
[task 2022-07-12T18:52:47.426Z] 18:52:47     INFO - GECKO(11100) | 1657651967423	Marionette	INFO	Marionette enabled
[task 2022-07-12T18:52:47.436Z] 18:52:47     INFO - GECKO(11100) | 1657651967432	Marionette	TRACE	Received observer notification final-ui-startup
[task 2022-07-12T18:52:47.442Z] 18:52:47     INFO - GECKO(11100) | 1657651967439	Marionette	INFO	Listening on port 2828
[task 2022-07-12T18:52:47.445Z] 18:52:47     INFO - GECKO(11100) | 1657651967439	Marionette	DEBUG	Marionette is listening
[task 2022-07-12T18:52:47.652Z] 18:52:47     INFO - GECKO(11100) | 1657651967647	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:51708
[task 2022-07-12T18:52:47.706Z] 18:52:47     INFO - GECKO(11100) | 1657651967701	Marionette	DEBUG	Closed connection 0
[task 2022-07-12T18:52:47.755Z] 18:52:47     INFO - GECKO(11100) | 1657651967751	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:51711
[task 2022-07-12T18:52:47.771Z] 18:52:47     INFO - GECKO(11100) | 1657651967766	Marionette	DEBUG	Closed connection 1
[task 2022-07-12T18:52:47.773Z] 18:52:47     INFO - GECKO(11100) | 1657651967767	Marionette	DEBUG	Accepted connection 2 from 127.0.0.1:51712
[task 2022-07-12T18:52:47.798Z] 18:52:47     INFO - GECKO(11100) | 1657651967794	Marionette	DEBUG	2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-07-12T18:52:47.800Z] 18:52:47     INFO - GECKO(11100) | 1657651967795	Marionette	DEBUG	Waiting for initial application window
[task 2022-07-12T18:52:48.729Z] 18:52:48     INFO - GECKO(11100) | 1657651968724	Marionette	TRACE	Received observer notification browser-idle-startup-tasks-finished
[task 2022-07-12T18:52:48.732Z] 18:52:48     INFO - GECKO(11100) | 1657651968727	RemoteAgent	TRACE	[34] Document already finished loading: about:blank
[task 2022-07-12T18:52:48.744Z] 18:52:48     INFO - GECKO(11100) | 1657651968736	Marionette	DEBUG	2 <- [1,1,null,{"sessionId":"7eccea33-ca19-4272-a72b-98f03c8e5141","capabilities":{"browserName":"firefox","browserVersion":"103.0 ... ownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2022-07-12T18:52:48.785Z] 18:52:48     INFO - GECKO(11100) | 1657651968780	Marionette	DEBUG	2 -> [0,2,"Addon:Install",{"path":"C:\\Users\\testdroid.YOGA-014\\AppData\\Local\\Temp\\tmp959is_on.zip","temporary":false}]
[task 2022-07-12T18:52:48.858Z] 18:52:48     INFO - GECKO(11100) | 1657651968854	Marionette	DEBUG	2 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2022-07-12T18:52:48.976Z] 18:52:48     INFO - GECKO(11100) | 1657651968963	Marionette	DEBUG	2 -> [0,3,"Addon:Install",{"path":"C:\\Users\\testdroid.YOGA-014\\AppData\\Local\\Temp\\tmp2j3cla93.zip","temporary":false}]
[task 2022-07-12T18:52:49.067Z] 18:52:49     INFO - GECKO(11100) | 1657651969062	Marionette	DEBUG	2 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2022-07-12T18:52:49.075Z] 18:52:49     INFO - GECKO(11100) | 1657651969067	Marionette	DEBUG	2 -> [0,4,"Marionette:GetContext",{}]
[task 2022-07-12T18:52:49.079Z] 18:52:49     INFO - GECKO(11100) | 1657651969067	Marionette	DEBUG	2 <- [1,4,null,{"value":"content"}]
[task 2022-07-12T18:52:49.083Z] 18:52:49     INFO - GECKO(11100) | 1657651969069	Marionette	DEBUG	2 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-07-12T18:52:49.088Z] 18:52:49     INFO - GECKO(11100) | 1657651969070	Marionette	DEBUG	2 <- [1,5,null,{"value":null}]
[task 2022-07-12T18:52:49.094Z] 18:52:49     INFO - GECKO(11100) | 1657651969075	Marionette	DEBUG	2 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... Sandbox":true,"sandbox":"default","line":2016,"filename":"C:\\tasks\\task_1657637188\\build\\tests\\mochitest\\runtests.py"}]
[task 2022-07-12T18:52:49.097Z] 18:52:49     INFO - GECKO(11100) | 1657651969078	Marionette	TRACE	[16] MarionetteCommands actor created for window id 2
[task 2022-07-12T18:52:49.102Z] 18:52:49     INFO - GECKO(11100) | 1657651969086	Marionette	DEBUG	2 <- [1,6,null,{"value":null}]
[task 2022-07-12T18:52:49.121Z] 18:52:49     INFO - GECKO(11100) | 1657651969115	Marionette	DEBUG	2 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2022-07-12T18:52:49.124Z] 18:52:49     INFO - GECKO(11100) | 1657651969116	Marionette	DEBUG	2 <- [1,7,null,{"value":null}]
[task 2022-07-12T18:52:49.130Z] 18:52:49     INFO - GECKO(11100) | 1657651969122	Marionette	DEBUG	2 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2022-07-12T18:52:49.134Z] 18:52:49     INFO - GECKO(11100) | 1657651969125	Marionette	DEBUG	2 <- [1,8,null,{"value":null}]
[task 2022-07-12T18:52:49.137Z] 18:52:49     INFO - runtests.py | Waiting for browser...
[task 2022-07-12T18:52:49.140Z] 18:52:49     INFO - GECKO(11100) | 1657651969133	Marionette	DEBUG	Closed connection 2
[task 2022-07-12T18:52:49.506Z] 18:52:49     INFO - SimpleTest START
[task 2022-07-12T18:52:49.511Z] 18:52:49     INFO - Dumping test context:
[task 2022-07-12T18:52:49.512Z] 18:52:49     INFO -   fission.autostart=true
[task 2022-07-12T18:52:49.517Z] 18:52:49     INFO - TEST-START | dom/media/webrtc/tests/mochitests/test_peerConnection_addAudioTrackToExistingVideoStream.html
Summary: Intermittent [tier 2] dom/media/webrtc/tests/mochitests/test_peerConnection_stats_relayProtocol.html (finished) | single tracking bug → Intermittent dom/media/webrtc/tests/mochitests/test_peerConnection_stats_relayProtocol.html (finished) | single tracking bug
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.