1740904 - Intermittent browser/base/content/test/webrtc/browser_devices_get_user_media.js | application terminated with exit code -5

Status: RESOLVED INCOMPLETE

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-11-12T10:04:01.117Z] 10:04:01     INFO - TEST-PASS | browser/base/content/test/webrtc/browser_devices_get_user_media.js | WebRTC indicator hidden - 
[task 2021-11-12T10:04:01.118Z] 10:04:01     INFO - TEST-PASS | browser/base/content/test/webrtc/browser_devices_get_user_media.js | camera global indicator as expected - 
[task 2021-11-12T10:04:01.118Z] 10:04:01     INFO - TEST-PASS | browser/base/content/test/webrtc/browser_devices_get_user_media.js | microphone global indicator as expected - 
[task 2021-11-12T10:04:01.119Z] 10:04:01     INFO - TEST-PASS | browser/base/content/test/webrtc/browser_devices_get_user_media.js | screen global indicator as expected - 
[task 2021-11-12T10:04:01.120Z] 10:04:01     INFO - TEST-PASS | browser/base/content/test/webrtc/browser_devices_get_user_media.js | WebRTC menu should be hidden - 
[task 2021-11-12T10:04:01.121Z] 10:04:01     INFO - TEST-PASS | browser/base/content/test/webrtc/browser_devices_get_user_media.js | popup WebRTC indicator hidden - 
[task 2021-11-12T10:04:01.121Z] 10:04:01     INFO - audio only, user denies, expect audio perm set to deny, video not set
[task 2021-11-12T10:04:01.122Z] 10:04:01     INFO - requesting devices
[task 2021-11-12T10:04:01.123Z] 10:04:01     INFO - Buffered messages finished
[task 2021-11-12T10:04:01.123Z] 10:04:01    ERROR - TEST-UNEXPECTED-FAIL | browser/base/content/test/webrtc/browser_devices_get_user_media.js | application terminated with exit code -5
[task 2021-11-12T10:04:01.124Z] 10:04:01     INFO - runtests.py | Application ran for: 0:00:40.989496
[task 2021-11-12T10:04:01.124Z] 10:04:01     INFO - zombiecheck | Reading PID log: /tmp/tmp7_mym7vdpidlog
[task 2021-11-12T10:04:01.125Z] 10:04:01     INFO - ==> process 2439 launched child process 2454
[task 2021-11-12T10:04:01.126Z] 10:04:01     INFO - ==> process 2439 launched child process 2515
[task 2021-11-12T10:04:01.126Z] 10:04:01     INFO - ==> process 2439 launched child process 2533
[task 2021-11-12T10:04:01.129Z] 10:04:01     INFO - ==> process 2439 launched child process 2586
[task 2021-11-12T10:04:01.129Z] 10:04:01     INFO - ==> process 2439 launched child process 2620
[task 2021-11-12T10:04:01.129Z] 10:04:01     INFO - ==> process 2439 launched child process 2646
[task 2021-11-12T10:04:01.130Z] 10:04:01     INFO - ==> process 2439 launched child process 2674
[task 2021-11-12T10:04:01.130Z] 10:04:01     INFO - zombiecheck | Checking for orphan process with PID: 2533
[task 2021-11-12T10:04:01.130Z] 10:04:01     INFO - zombiecheck | Checking for orphan process with PID: 2674
[task 2021-11-12T10:04:01.130Z] 10:04:01     INFO - zombiecheck | Checking for orphan process with PID: 2515
[task 2021-11-12T10:04:01.131Z] 10:04:01     INFO - zombiecheck | Checking for orphan process with PID: 2454
[task 2021-11-12T10:04:01.131Z] 10:04:01     INFO - zombiecheck | Checking for orphan process with PID: 2646
[task 2021-11-12T10:04:01.131Z] 10:04:01     INFO - zombiecheck | Checking for orphan process with PID: 2586
[task 2021-11-12T10:04:01.131Z] 10:04:01     INFO - zombiecheck | Checking for orphan process with PID: 2620
[task 2021-11-12T10:04:01.132Z] 10:04:01     INFO - Stopping web server
[task 2021-11-12T10:04:01.132Z] 10:04:01     INFO - Server shut down.
[task 2021-11-12T10:04:01.132Z] 10:04:01     INFO - Web server killed.
[task 2021-11-12T10:04:01.132Z] 10:04:01     INFO - Stopping web socket server
[task 2021-11-12T10:04:01.133Z] 10:04:01     INFO - Stopping ssltunnel
[task 2021-11-12T10:04:01.133Z] 10:04:01  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2021-11-12T10:04:01.133Z] 10:04:01     INFO - runtests.py | Running tests: end.
[task 2021-11-12T10:04:01.133Z] 10:04:01     INFO - Buffered messages finished
[task 2021-11-12T10:04:01.133Z] 10:04:01     INFO - Running manifest: browser/components/urlbar/tests/browser-updateResults/browser.ini
task 2021-11-12T10:04:01.144Z] 10:04:01     INFO -  Setting pipeline to PAUSED ...
[task 2021-11-12T10:04:01.145Z] 10:04:01     INFO -  Pipeline is PREROLLING ...
[task 2021-11-12T10:04:01.147Z] 10:04:01     INFO -  Pipeline is PREROLLED ...
[task 2021-11-12T10:04:01.147Z] 10:04:01     INFO -  Setting pipeline to PLAYING ...
[task 2021-11-12T10:04:01.147Z] 10:04:01     INFO -  New clock: GstSystemClock
[task 2021-11-12T10:04:01.180Z] 10:04:01     INFO -  Got EOS from element "pipeline0".
[task 2021-11-12T10:04:01.181Z] 10:04:01     INFO -  Execution ended after 0:00:00.033437933
[task 2021-11-12T10:04:01.181Z] 10:04:01     INFO -  Setting pipeline to PAUSED ...
[task 2021-11-12T10:04:01.182Z] 10:04:01     INFO -  Setting pipeline to READY ...
[task 2021-11-12T10:04:01.182Z] 10:04:01     INFO -  (gst-launch-1.0:2791): GStreamer-CRITICAL **: 10:04:01.180: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2021-11-12T10:04:01.182Z] 10:04:01     INFO -  Setting pipeline to NULL ...
[task 2021-11-12T10:04:01.182Z] 10:04:01     INFO -  Freeing pipeline ...
[task 2021-11-12T10:04:01.212Z] 10:04:01     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-11-12T10:04:01.548Z] 10:04:01     INFO - PID 2798 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2021-11-12T10:04:01.898Z] 10:04:01     INFO - Increasing default timeout to 120 seconds
[task 2021-11-12T10:04:01.899Z] 10:04:01     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-11-12T10:04:01.899Z] 10:04:01     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-11-12T10:04:01.903Z] 10:04:01     INFO - MochitestServer : launching ['/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-f', '/builds/worker/workspace/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpnwec80r3.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2021-11-12T10:04:01.904Z] 10:04:01     INFO - runtests.py | Server pid: 2810
[task 2021-11-12T10:04:01.907Z] 10:04:01     INFO - runtests.py | Websocket server pid: 2813
[task 2021-11-12T10:04:01.909Z] 10:04:01     INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-11-12T10:04:01.922Z] 10:04:01     INFO - runtests.py | SSL tunnel pid: 2817
[task 2021-11-12T10:04:02.525Z] 10:04:02     INFO - runtests.py | Running with scheme: http
[task 2021-11-12T10:04:02.525Z] 10:04:02     INFO - runtests.py | Running with e10s: True
[task 2021-11-12T10:04:02.527Z] 10:04:02     INFO - runtests.py | Running with fission: False
[task 2021-11-12T10:04:02.527Z] 10:04:02     INFO - runtests.py | Running with cross-origin iframes: False
[task 2021-11-12T10:04:02.527Z] 10:04:02     INFO - runtests.py | Running with serviceworker_e10s: True
[task 2021-11-12T10:04:02.528Z] 10:04:02     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2021-11-12T10:04:02.528Z] 10:04:02     INFO - runtests.py | Running tests: start.
[task 2021-11-12T10:04:02.528Z] 10:04:02     INFO - 
[task 2021-11-12T10:04:02.542Z] 10:04:02     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpnwec80r3.mozrunner
[task 2021-11-12T10:04:02.547Z] 10:04:02     INFO - runtests.py | Application pid: 2836
[task 2021-11-12T10:04:07.100Z] 10:04:07     INFO - GECKO(2836) | 1636711447098	Marionette	INFO	Marionette enabled
[task 2021-11-12T10:04:08.006Z] 10:04:08     INFO - GECKO(2836) | 1636711448005	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2021-11-12T10:04:16.469Z] 10:04:16     INFO - GECKO(2836) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpnwec80r3.mozrunner/search.json.mozlz4", (void 0)))
[task 2021-11-12T10:04:25.043Z] 10:04:25     INFO - GECKO(2836) | 1636711465042	Marionette	TRACE	Received observer notification marionette-startup-requested
[task 2021-11-12T10:04:25.045Z] 10:04:25     INFO - GECKO(2836) | 1636711465044	Marionette	TRACE	Waiting until startup recorder finished recording startup scripts...
[task 2021-11-12T10:04:25.228Z] 10:04:25     INFO - GECKO(2836) | 1636711465227	Marionette	TRACE	All scripts recorded.
[task 2021-11-12T10:04:25.252Z] 10:04:25     INFO - GECKO(2836) | 1636711465251	Marionette	INFO	Listening on port 2828
[task 2021-11-12T10:04:25.254Z] 10:04:25     INFO - GECKO(2836) | 1636711465253	Marionette	DEBUG	Marionette is listening
[task 2021-11-12T10:04:25.482Z] 10:04:25     INFO - GECKO(2836) | 1636711465481	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:56700
[task 2021-11-12T10:04:25.504Z] 10:04:25     INFO - GECKO(2836) | 1636711465503	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:56702
[task 2021-11-12T10:04:25.507Z] 10:04:25     INFO - GECKO(2836) | 1636711465506	Marionette	DEBUG	Closed connection 0
[task 2021-11-12T10:04:25.591Z] 10:04:25     INFO - GECKO(2836) | 1636711465590	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-11-12T10:04:25.731Z] 10:04:25     INFO - GECKO(2836) | 1636711465729	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"7c73eddd-3612-4934-abc4-c246cfaac38f","capabilities":{"browserName":"firefox","browserVersion":"96.0a ... .mozrunner","moz:shutdownTimeout":360000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-11-12T10:04:25.768Z] 10:04:25     INFO - GECKO(2836) | 1636711465767	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpvjf4u0zs.zip","temporary":false}]
[task 2021-11-12T10:04:26.968Z] 10:04:26     INFO - GECKO(2836) | 1636711466967	Marionette	DEBUG	1 <- [1,2,null,{"value":"[e-mail]"}]
[task 2021-11-12T10:04:27.111Z] 10:04:27     INFO - GECKO(2836) | 1636711467109	Marionette	DEBUG	1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpyh5e1iri.zip","temporary":false}]
[task 2021-11-12T10:04:27.338Z] 10:04:27     INFO - GECKO(2836) | 1636711467336	Marionette	DEBUG	1 <- [1,3,null,{"value":"[e-mail]"}]
[task 2021-11-12T10:04:27.349Z] 10:04:27     INFO - GECKO(2836) | 1636711467348	Marionette	DEBUG	1 -> [0,4,"Marionette:GetContext",{}]
[task 2021-11-12T10:04:27.353Z] 10:04:27     INFO - GECKO(2836) | 1636711467351	Marionette	DEBUG	1 <- [1,4,null,{"value":"content"}]
[task 2021-11-12T10:04:27.362Z] 10:04:27     INFO - GECKO(2836) | 1636711467360	Marionette	DEBUG	1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-11-12T10:04:27.365Z] 10:04:27     INFO - GECKO(2836) | 1636711467364	Marionette	DEBUG	1 <- [1,5,null,{"value":null}]
[task 2021-11-12T10:04:27.376Z] 10:04:27     INFO - GECKO(2836) | 1636711467374	Marionette	DEBUG	1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... hrome","testUrl":"about:blank"}],"newSandbox":true,"sandbox":"default","line":1937,"filename":"tests/mochitest/runtests.py"}]
[task 2021-11-12T10:04:27.443Z] 10:04:27     INFO - GECKO(2836) | 1636711467441	Marionette	TRACE	[8] MarionetteCommands actor created for window id 2
[task 2021-11-12T10:04:27.727Z] 10:04:27     INFO - GECKO(2836) | 1636711467726	Marionette	TRACE	Received observer notification domwindowopened
[task 2021-11-12T10:04:27.783Z] 10:04:27     INFO - GECKO(2836) | 1636711467781	Marionette	DEBUG	1 <- [1,6,null,{"value":null}]
[task 2021-11-12T10:04:27.816Z] 10:04:27     INFO - GECKO(2836) | 1636711467815	Marionette	DEBUG	1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2021-11-12T10:04:27.819Z] 10:04:27     INFO - GECKO(2836) | 1636711467817	Marionette	DEBUG	1 <- [1,7,null,{"value":null}]
[task 2021-11-12T10:04:28.037Z] 10:04:28     INFO - GECKO(2836) | 1636711468036	Marionette	DEBUG	1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2021-11-12T10:04:28.056Z] 10:04:28     INFO - GECKO(2836) | 1636711468055	Marionette	DEBUG	1 <- [1,8,null,{"value":null}]
[task 2021-11-12T10:04:28.175Z] 10:04:28     INFO - runtests.py | Waiting for browser...
[task 2021-11-12T10:04:28.262Z] 10:04:28     INFO - GECKO(2836) | 1636711468261	Marionette	DEBUG	Closed connection 1
[task 2021-11-12T10:04:29.459Z] 10:04:29     INFO - TEST-START | browser/components/urlbar/tests/browser-updateResults/browser_appendSpanCount.js

Comment 1

[Intermittent Failures Robot]

1 failures in 2723 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1740904&startday=2021-11-08&endday=2021-11-14&tree=all

Comment 2

[Intermittent Failures Robot]

2 failures in 2819 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• linux1804-64-tsan-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1740904&startday=2021-11-22&endday=2021-11-28&tree=all

Comment 3

[Intermittent Failures Robot]

1 failures in 3170 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1740904&startday=2021-11-29&endday=2021-12-05&tree=all

Comment 4

[Intermittent Failures Robot]

1 failures in 3319 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1740904&startday=2021-12-06&endday=2021-12-12&tree=all

Comment 5

[BugBot [:suhaib / :marco/ :calixte]]

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