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

[task 2021-12-21T09:33:02.846Z] 09:33:02     INFO - TEST-PASS | browser/base/content/test/webrtc/browser_devices_get_user_media_unprompted_access_queue_request.js | popup WebRTC indicator hidden - 
[task 2021-12-21T09:33:02.911Z] 09:33:02     INFO - Leaving test bound test
[task 2021-12-21T09:33:02.972Z] 09:33:02     INFO - GECKO(2918) | (firefox:2918): Gdk-ERROR **: 09:33:02.966: The program 'firefox' received an X Window System error.
[task 2021-12-21T09:33:02.972Z] 09:33:02     INFO - GECKO(2918) | This probably reflects a bug in the program.
[task 2021-12-21T09:33:02.972Z] 09:33:02     INFO - GECKO(2918) | The error was 'BadDrawable (invalid Pixmap or Window parameter)'.
[task 2021-12-21T09:33:02.972Z] 09:33:02     INFO - GECKO(2918) |   (Details: serial 32514 error_code 9 request_code 55 (core protocol) minor_code 0)
[task 2021-12-21T09:33:02.972Z] 09:33:02     INFO - GECKO(2918) |   (Note to programmers: normally, X errors are reported asynchronously;
[task 2021-12-21T09:33:02.972Z] 09:33:02     INFO - GECKO(2918) |    that is, you will receive the error a while after causing it.
[task 2021-12-21T09:33:02.972Z] 09:33:02     INFO - GECKO(2918) |    To debug your program, run it with the GDK_SYNCHRONIZE environment
[task 2021-12-21T09:33:02.972Z] 09:33:02     INFO - GECKO(2918) |    variable to change this behavior. You can then get a meaningful
[task 2021-12-21T09:33:02.972Z] 09:33:02     INFO - GECKO(2918) |    backtrace from your debugger if you break on the gdk_x_error() function.)
[task 2021-12-21T09:33:03.057Z] 09:33:03     INFO - GECKO(2918) | Exiting due to channel error.
[task 2021-12-21T09:33:03.057Z] 09:33:03     INFO - GECKO(2918) | Exiting due to channel error.
[task 2021-12-21T09:33:03.059Z] 09:33:03     INFO - GECKO(2918) | Exiting due to channel error.
[task 2021-12-21T09:33:03.060Z] 09:33:03     INFO - GECKO(2918) | Exiting due to channel error.
[task 2021-12-21T09:33:03.061Z] 09:33:03     INFO - GECKO(2918) | Exiting due to channel error.
[task 2021-12-21T09:33:03.062Z] 09:33:03     INFO - GECKO(2918) | Exiting due to channel error.
[task 2021-12-21T09:33:03.181Z] 09:33:03     INFO - TEST-INFO | Main app process: killed by SIGTRAP
[task 2021-12-21T09:33:03.181Z] 09:33:03     INFO - Buffered messages logged at 09:33:01
[task 2021-12-21T09:33:03.182Z] 09:33:03     INFO - Entering test bound test
[task 2021-12-21T09:33:03.182Z] 09:33:03     INFO - Buffered messages finished
[task 2021-12-21T09:33:03.182Z] 09:33:03    ERROR - TEST-UNEXPECTED-FAIL | browser/base/content/test/webrtc/browser_devices_get_user_media_unprompted_access_queue_request.js | application terminated with exit code -5
[task 2021-12-21T09:33:03.183Z] 09:33:03     INFO - runtests.py | Application ran for: 0:01:18.168448
[task 2021-12-21T09:33:03.183Z] 09:33:03     INFO - zombiecheck | Reading PID log: /tmp/tmphm2t8zjrpidlog
[task 2021-12-21T09:33:03.184Z] 09:33:03     INFO - ==> process 2918 launched child process 2933
[task 2021-12-21T09:33:03.184Z] 09:33:03     INFO - ==> process 2918 launched child process 2993
[task 2021-12-21T09:33:03.184Z] 09:33:03     INFO - ==> process 2918 launched child process 3010
[task 2021-12-21T09:33:03.184Z] 09:33:03     INFO - ==> process 2918 launched child process 3055
[task 2021-12-21T09:33:03.187Z] 09:33:03     INFO - ==> process 2918 launched child process 3095
[task 2021-12-21T09:33:03.187Z] 09:33:03     INFO - ==> process 2918 launched child process 3110
[task 2021-12-21T09:33:03.188Z] 09:33:03     INFO - ==> process 2918 launched child process 3152
[task 2021-12-21T09:33:03.189Z] 09:33:03     INFO - zombiecheck | Checking for orphan process with PID: 3010
[task 2021-12-21T09:33:03.190Z] 09:33:03     INFO - zombiecheck | Checking for orphan process with PID: 3110
[task 2021-12-21T09:33:03.191Z] 09:33:03     INFO - zombiecheck | Checking for orphan process with PID: 3055
[task 2021-12-21T09:33:03.191Z] 09:33:03     INFO - zombiecheck | Checking for orphan process with PID: 3152
[task 2021-12-21T09:33:03.192Z] 09:33:03     INFO - zombiecheck | Checking for orphan process with PID: 2993
[task 2021-12-21T09:33:03.193Z] 09:33:03     INFO - zombiecheck | Checking for orphan process with PID: 2933
[task 2021-12-21T09:33:03.194Z] 09:33:03     INFO - zombiecheck | Checking for orphan process with PID: 3095
[task 2021-12-21T09:33:03.194Z] 09:33:03     INFO - Stopping web server
[task 2021-12-21T09:33:03.195Z] 09:33:03     INFO - Server shut down.
[task 2021-12-21T09:33:03.214Z] 09:33:03     INFO - Web server killed.
[task 2021-12-21T09:33:03.215Z] 09:33:03     INFO - Stopping web socket server
[task 2021-12-21T09:33:03.234Z] 09:33:03     INFO - Stopping ssltunnel
[task 2021-12-21T09:33:03.255Z] 09:33:03  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2021-12-21T09:33:03.255Z] 09:33:03     INFO - runtests.py | Running tests: end.
[task 2021-12-21T09:33:03.273Z] 09:33:03     INFO - Buffered messages finished
[task 2021-12-21T09:33:03.273Z] 09:33:03     INFO - Running manifest: browser/components/pocket/test/browser.ini
[task 2021-12-21T09:33:03.291Z] 09:33:03     INFO -  Setting pipeline to PAUSED ...
[task 2021-12-21T09:33:03.292Z] 09:33:03     INFO -  Pipeline is PREROLLING ...
[task 2021-12-21T09:33:03.294Z] 09:33:03     INFO -  Pipeline is PREROLLED ...
[task 2021-12-21T09:33:03.294Z] 09:33:03     INFO -  Setting pipeline to PLAYING ...
[task 2021-12-21T09:33:03.295Z] 09:33:03     INFO -  New clock: GstSystemClock
[task 2021-12-21T09:33:03.328Z] 09:33:03     INFO -  Got EOS from element "pipeline0".
[task 2021-12-21T09:33:03.328Z] 09:33:03     INFO -  Execution ended after 0:00:00.033416767
[task 2021-12-21T09:33:03.328Z] 09:33:03     INFO -  Setting pipeline to PAUSED ...
[task 2021-12-21T09:33:03.328Z] 09:33:03     INFO -  Setting pipeline to READY ...
[task 2021-12-21T09:33:03.329Z] 09:33:03     INFO -  (gst-launch-1.0:3995): GStreamer-CRITICAL **: 09:33:03.327: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2021-12-21T09:33:03.330Z] 09:33:03     INFO -  Setting pipeline to NULL ...
[task 2021-12-21T09:33:03.330Z] 09:33:03     INFO -  Freeing pipeline ...
[task 2021-12-21T09:33:03.361Z] 09:33:03     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-12-21T09:33:03.365Z] 09:33:03     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2021-12-21T09:33:03.366Z] 09:33:03     INFO - UBSan enabled.
[task 2021-12-21T09:33:03.729Z] 09:33:03     INFO - PID 4013 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2021-12-21T09:33:03.760Z] 09:33:03     INFO - Increasing default timeout to 90 seconds
[task 2021-12-21T09:33:03.762Z] 09:33:03     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-12-21T09:33:03.767Z] 09:33:03     INFO - LSan enabled.
[task 2021-12-21T09:33:03.767Z] 09:33:03     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2021-12-21T09:33:03.767Z] 09:33:03     INFO - UBSan enabled.
[task 2021-12-21T09:33:03.767Z] 09:33:03     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-12-21T09:33:03.771Z] 09:33:03     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2021-12-21T09:33:03.771Z] 09:33:03     INFO - UBSan enabled.
[task 2021-12-21T09:33:03.775Z] 09:33:03     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/tmpl_f77397.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-12-21T09:33:03.776Z] 09:33:03     INFO - runtests.py | Server pid: 4020
[task 2021-12-21T09:33:03.795Z] 09:33:03     INFO - runtests.py | Websocket server pid: 4023
[task 2021-12-21T09:33:03.796Z] 09:33:03     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-12-21T09:33:03.810Z] 09:33:03     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2021-12-21T09:33:03.810Z] 09:33:03     INFO - UBSan enabled.
[task 2021-12-21T09:33:03.833Z] 09:33:03     INFO - runtests.py | SSL tunnel pid: 4029
[task 2021-12-21T09:33:04.135Z] 09:33:04     INFO - runtests.py | Running with scheme: http
[task 2021-12-21T09:33:04.136Z] 09:33:04     INFO - runtests.py | Running with e10s: True
[task 2021-12-21T09:33:04.137Z] 09:33:04     INFO - runtests.py | Running with fission: False
[task 2021-12-21T09:33:04.137Z] 09:33:04     INFO - runtests.py | Running with cross-origin iframes: False
[task 2021-12-21T09:33:04.138Z] 09:33:04     INFO - runtests.py | Running with serviceworker_e10s: True
[task 2021-12-21T09:33:04.139Z] 09:33:04     INFO - runtests.py | Running with socketprocess_e10s: False
[task 2021-12-21T09:33:04.140Z] 09:33:04     INFO - runtests.py | Running tests: start.
[task 2021-12-21T09:33:04.140Z] 09:33:04     INFO - 
[task 2021-12-21T09:33:04.157Z] 09:33:04     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpl_f77397.mozrunner
[task 2021-12-21T09:33:04.170Z] 09:33:04     INFO - runtests.py | Application pid: 4043
[task 2021-12-21T09:33:04.170Z] 09:33:04     INFO - TEST-INFO | started process GECKO(4043)
[task 2021-12-21T09:33:05.419Z] 09:33:05     INFO - GECKO(4043) | 1640079185417	Marionette	INFO	Marionette enabled
[task 2021-12-21T09:33:05.629Z] 09:33:05     INFO - GECKO(4043) | 1640079185627	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2021-12-21T09:33:08.811Z] 09:33:08     INFO - GECKO(4043) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpl_f77397.mozrunner/search.json.mozlz4", (void 0)))
[task 2021-12-21T09:33:11.666Z] 09:33:11     INFO - GECKO(4043) | 1640079191664	Marionette	TRACE	Received observer notification marionette-startup-requested
[task 2021-12-21T09:33:11.668Z] 09:33:11     INFO - GECKO(4043) | 1640079191667	Marionette	TRACE	Waiting until startup recorder finished recording startup scripts...
[task 2021-12-21T09:33:11.724Z] 09:33:11     INFO - GECKO(4043) | 1640079191723	Marionette	TRACE	All scripts recorded.
[task 2021-12-21T09:33:11.731Z] 09:33:11     INFO - GECKO(4043) | 1640079191730	Marionette	INFO	Listening on port 2828
[task 2021-12-21T09:33:11.733Z] 09:33:11     INFO - GECKO(4043) | 1640079191731	Marionette	DEBUG	Marionette is listening
[task 2021-12-21T09:33:11.833Z] 09:33:11     INFO - GECKO(4043) | 1640079191832	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:40606
[task 2021-12-21T09:33:11.839Z] 09:33:11     INFO - GECKO(4043) | 1640079191838	Marionette	DEBUG	Closed connection 0
[task 2021-12-21T09:33:11.840Z] 09:33:11     INFO - GECKO(4043) | 1640079191839	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:40608
[task 2021-12-21T09:33:11.874Z] 09:33:11     INFO - GECKO(4043) | 1640079191873	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-12-21T09:33:11.925Z] 09:33:11     INFO - GECKO(4043) | 1640079191924	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"c06c71db-5083-4d16-8ef4-752f7972e511","capabilities":{"browserName":"firefox","browserVersion":"97.0a ... .mozrunner","moz:shutdownTimeout":300000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-12-21T09:33:11.948Z] 09:33:11     INFO - GECKO(4043) | 1640079191947	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpmvygk51g.zip","temporary":false}]
[task 2021-12-21T09:33:12.120Z] 09:33:12     INFO - GECKO(4043) | 1640079192118	Marionette	DEBUG	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2021-12-21T09:33:12.168Z] 09:33:12     INFO - GECKO(4043) | 1640079192164	Marionette	DEBUG	1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpdq5jjhjp.zip","temporary":false}]
[task 2021-12-21T09:33:12.221Z] 09:33:12     INFO - GECKO(4043) | 1640079192220	Marionette	DEBUG	1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2021-12-21T09:33:12.227Z] 09:33:12     INFO - GECKO(4043) | 1640079192226	Marionette	DEBUG	1 -> [0,4,"Marionette:GetContext",{}]
[task 2021-12-21T09:33:12.229Z] 09:33:12     INFO - GECKO(4043) | 1640079192228	Marionette	DEBUG	1 <- [1,4,null,{"value":"content"}]
[task 2021-12-21T09:33:12.241Z] 09:33:12     INFO - GECKO(4043) | 1640079192240	Marionette	DEBUG	1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-12-21T09:33:12.243Z] 09:33:12     INFO - GECKO(4043) | 1640079192242	Marionette	DEBUG	1 <- [1,5,null,{"value":null}]
[task 2021-12-21T09:33:12.250Z] 09:33:12     INFO - GECKO(4043) | 1640079192249	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-12-21T09:33:12.268Z] 09:33:12     INFO - GECKO(4043) | 1640079192267	Marionette	TRACE	[8] MarionetteCommands actor created for window id 2
[task 2021-12-21T09:33:12.362Z] 09:33:12     INFO - GECKO(4043) | 1640079192361	Marionette	TRACE	Received observer notification domwindowopened
[task 2021-12-21T09:33:12.406Z] 09:33:12     INFO - GECKO(4043) | 1640079192405	Marionette	DEBUG	1 <- [1,6,null,{"value":null}]
[task 2021-12-21T09:33:12.423Z] 09:33:12     INFO - GECKO(4043) | 1640079192422	Marionette	DEBUG	1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2021-12-21T09:33:12.426Z] 09:33:12     INFO - GECKO(4043) | 1640079192425	Marionette	DEBUG	1 <- [1,7,null,{"value":null}]
[task 2021-12-21T09:33:12.558Z] 09:33:12     INFO - GECKO(4043) | 1640079192557	Marionette	DEBUG	1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2021-12-21T09:33:12.565Z] 09:33:12     INFO - GECKO(4043) | 1640079192564	Marionette	DEBUG	1 <- [1,8,null,{"value":null}]
[task 2021-12-21T09:33:12.605Z] 09:33:12     INFO - runtests.py | Waiting for browser...
[task 2021-12-21T09:33:12.611Z] 09:33:12     INFO - GECKO(4043) | 1640079192609	Marionette	DEBUG	Closed connection 1
[task 2021-12-21T09:33:13.242Z] 09:33:13     INFO - TEST-START | browser/components/pocket/test/browser_pocket_button_icon_state.js