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

[task 2021-11-08T18:18:52.428Z] 18:18:52     INFO - TEST-START | dom/media/test/test_eme_protection_query.html
[task 2021-11-08T18:18:54.493Z] 18:18:54     INFO - GECKO(8429) | (firefox:8429): Gdk-ERROR **: 18:18:54.488: The program 'firefox' received an X Window System error.
[task 2021-11-08T18:18:54.493Z] 18:18:54     INFO - GECKO(8429) | This probably reflects a bug in the program.
[task 2021-11-08T18:18:54.495Z] 18:18:54     INFO - GECKO(8429) | The error was 'BadDrawable (invalid Pixmap or Window parameter)'.
[task 2021-11-08T18:18:54.495Z] 18:18:54     INFO - GECKO(8429) |   (Details: serial 1614 error_code 9 request_code 55 (core protocol) minor_code 0)
[task 2021-11-08T18:18:54.495Z] 18:18:54     INFO - GECKO(8429) |   (Note to programmers: normally, X errors are reported asynchronously;
[task 2021-11-08T18:18:54.495Z] 18:18:54     INFO - GECKO(8429) |    that is, you will receive the error a while after causing it.
[task 2021-11-08T18:18:54.495Z] 18:18:54     INFO - GECKO(8429) |    To debug your program, run it with the GDK_SYNCHRONIZE environment
[task 2021-11-08T18:18:54.495Z] 18:18:54     INFO - GECKO(8429) |    variable to change this behavior. You can then get a meaningful
[task 2021-11-08T18:18:54.495Z] 18:18:54     INFO - GECKO(8429) |    backtrace from your debugger if you break on the gdk_x_error() function.)
[task 2021-11-08T18:18:54.575Z] 18:18:54     INFO - GECKO(8429) | Exiting due to channel error.
[task 2021-11-08T18:18:54.575Z] 18:18:54     INFO - GECKO(8429) | Exiting due to channel error.
[task 2021-11-08T18:18:54.575Z] 18:18:54     INFO - GECKO(8429) | Exiting due to channel error.
[task 2021-11-08T18:18:54.587Z] 18:18:54     INFO - GECKO(8429) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=45.1145) [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2021-11-08T18:18:54.588Z] 18:18:54     INFO - GECKO(8429) | Exiting due to channel error.
[task 2021-11-08T18:18:54.675Z] 18:18:54     INFO - TEST-INFO | Main app process: killed by SIGTRAP
[task 2021-11-08T18:18:54.676Z] 18:18:54     INFO - Buffered messages logged at 18:18:52
[task 2021-11-08T18:18:54.676Z] 18:18:54     INFO - TEST-PASS | dom/media/test/test_eme_protection_query.html | A valid string reason is expected 
[task 2021-11-08T18:18:54.676Z] 18:18:54     INFO - TEST-PASS | dom/media/test/test_eme_protection_query.html | Reason cannot be empty 
[task 2021-11-08T18:18:54.676Z] 18:18:54     INFO - add_task | Entering test setupEnvironment
[task 2021-11-08T18:18:54.676Z] 18:18:54     INFO - add_task | Leaving test setupEnvironment
[task 2021-11-08T18:18:54.676Z] 18:18:54     INFO - add_task | Entering test testProtectionQueryWithCaptureDuringVideo
[task 2021-11-08T18:18:54.678Z] 18:18:54     INFO - Buffered messages logged at 18:18:53
[task 2021-11-08T18:18:54.678Z] 18:18:54     INFO - TEST-PASS | dom/media/test/test_eme_protection_query.html | Should be usable as capture hasn't started 
[task 2021-11-08T18:18:54.679Z] 18:18:54     INFO - Buffered messages logged at 18:18:54
[task 2021-11-08T18:18:54.679Z] 18:18:54     INFO - TEST-PASS | dom/media/test/test_eme_protection_query.html | Should be output-restricted as capture is happening 
[task 2021-11-08T18:18:54.679Z] 18:18:54     INFO - Buffered messages finished
[task 2021-11-08T18:18:54.680Z] 18:18:54    ERROR - TEST-UNEXPECTED-FAIL | dom/media/test/test_eme_protection_query.html | application terminated with exit code -5
[task 2021-11-08T18:18:54.680Z] 18:18:54     INFO - runtests.py | Application ran for: 0:00:47.971021
[task 2021-11-08T18:18:54.681Z] 18:18:54     INFO - zombiecheck | Reading PID log: /tmp/tmp0fa39wqxpidlog
[task 2021-11-08T18:18:54.681Z] 18:18:54     INFO - ==> process 8429 launched child process 8446
<...>
[task 2021-11-08T18:18:54.699Z] 18:18:54     INFO - zombiecheck | Checking for orphan process with PID: 8446
[task 2021-11-08T18:18:54.699Z] 18:18:54     INFO - Stopping web server
[task 2021-11-08T18:18:54.699Z] 18:18:54     INFO - Server shut down.
[task 2021-11-08T18:18:54.729Z] 18:18:54     INFO - Web server killed.
[task 2021-11-08T18:18:54.730Z] 18:18:54     INFO - Stopping web socket server
[task 2021-11-08T18:18:54.749Z] 18:18:54     INFO - Stopping ssltunnel
[task 2021-11-08T18:18:54.784Z] 18:18:54  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2021-11-08T18:18:54.785Z] 18:18:54     INFO - runtests.py | Running tests: end.
[task 2021-11-08T18:18:54.823Z] 18:18:54     INFO - Buffered messages finished
[task 2021-11-08T18:18:54.825Z] 18:18:54     INFO - Running manifest: dom/media/webspeech/synth/test/mochitest.ini
[task 2021-11-08T18:18:54.851Z] 18:18:54     INFO -  Setting pipeline to PAUSED ...
[task 2021-11-08T18:18:54.851Z] 18:18:54     INFO -  Pipeline is PREROLLING ...
[task 2021-11-08T18:18:54.853Z] 18:18:54     INFO -  Pipeline is PREROLLED ...
[task 2021-11-08T18:18:54.854Z] 18:18:54     INFO -  Setting pipeline to PLAYING ...
[task 2021-11-08T18:18:54.854Z] 18:18:54     INFO -  New clock: GstSystemClock
[task 2021-11-08T18:18:54.887Z] 18:18:54     INFO -  Got EOS from element "pipeline0".
[task 2021-11-08T18:18:54.887Z] 18:18:54     INFO -  Execution ended after 0:00:00.033414033
[task 2021-11-08T18:18:54.887Z] 18:18:54     INFO -  Setting pipeline to PAUSED ...
[task 2021-11-08T18:18:54.887Z] 18:18:54     INFO -  Setting pipeline to READY ...
[task 2021-11-08T18:18:54.889Z] 18:18:54     INFO -  (gst-launch-1.0:8804): GStreamer-CRITICAL **: 18:18:54.887: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2021-11-08T18:18:54.889Z] 18:18:54     INFO -  Setting pipeline to NULL ...
[task 2021-11-08T18:18:54.889Z] 18:18:54     INFO -  Freeing pipeline ...
[task 2021-11-08T18:18:54.921Z] 18:18:54     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-11-08T18:18:54.925Z] 18:18:54     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2021-11-08T18:18:54.926Z] 18:18:54     INFO - UBSan enabled.
[task 2021-11-08T18:18:55.283Z] 18:18:55     INFO - PID 8822 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2021-11-08T18:18:55.315Z] 18:18:55     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-11-08T18:18:55.321Z] 18:18:55     INFO - LSan enabled.
[task 2021-11-08T18:18:55.321Z] 18:18:55     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2021-11-08T18:18:55.322Z] 18:18:55     INFO - UBSan enabled.
[task 2021-11-08T18:18:55.323Z] 18:18:55     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-11-08T18:18:55.328Z] 18:18:55     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2021-11-08T18:18:55.328Z] 18:18:55     INFO - UBSan enabled.
[task 2021-11-08T18:18:55.334Z] 18:18:55     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/tmpxgw9ycbe.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-08T18:18:55.335Z] 18:18:55     INFO - runtests.py | Server pid: 8829
[task 2021-11-08T18:18:55.347Z] 18:18:55     INFO - runtests.py | Websocket server pid: 8832
[task 2021-11-08T18:18:55.347Z] 18:18:55     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-11-08T18:18:55.372Z] 18:18:55     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2021-11-08T18:18:55.372Z] 18:18:55     INFO - UBSan enabled.
[task 2021-11-08T18:18:55.402Z] 18:18:55     INFO - runtests.py | SSL tunnel pid: 8838
[task 2021-11-08T18:18:55.658Z] 18:18:55     INFO - runtests.py | Running with scheme: http
[task 2021-11-08T18:18:55.658Z] 18:18:55     INFO - runtests.py | Running with e10s: True
[task 2021-11-08T18:18:55.659Z] 18:18:55     INFO - runtests.py | Running with fission: False
[task 2021-11-08T18:18:55.659Z] 18:18:55     INFO - runtests.py | Running with cross-origin iframes: False
[task 2021-11-08T18:18:55.659Z] 18:18:55     INFO - runtests.py | Running with serviceworker_e10s: True
[task 2021-11-08T18:18:55.659Z] 18:18:55     INFO - runtests.py | Running with socketprocess_e10s: True
[task 2021-11-08T18:18:55.659Z] 18:18:55     INFO - runtests.py | Running tests: start.
[task 2021-11-08T18:18:55.659Z] 18:18:55     INFO - 
[task 2021-11-08T18:18:55.669Z] 18:18:55     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpxgw9ycbe.mozrunner
[task 2021-11-08T18:18:55.678Z] 18:18:55     INFO - runtests.py | Application pid: 8853
[task 2021-11-08T18:18:55.678Z] 18:18:55     INFO - TEST-INFO | started process GECKO(8853)
[task 2021-11-08T18:18:57.023Z] 18:18:57     INFO - GECKO(8853) | 1636395537022	Marionette	INFO	Marionette enabled
[task 2021-11-08T18:18:57.219Z] 18:18:57     INFO - GECKO(8853) | 1636395537218	Marionette	TRACE	Received observer notification toplevel-window-ready
[task 2021-11-08T18:19:03.285Z] 18:19:03     INFO - GECKO(8853) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpxgw9ycbe.mozrunner/search.json.mozlz4", (void 0)))
[task 2021-11-08T18:19:05.225Z] 18:19:05     INFO - GECKO(8853) | 1636395545222	Marionette	TRACE	Received observer notification marionette-startup-requested
[task 2021-11-08T18:19:05.227Z] 18:19:05     INFO - GECKO(8853) | 1636395545224	Marionette	TRACE	Waiting until startup recorder finished recording startup scripts...
[task 2021-11-08T18:19:05.263Z] 18:19:05     INFO - GECKO(8853) | 1636395545262	Marionette	TRACE	All scripts recorded.
[task 2021-11-08T18:19:05.271Z] 18:19:05     INFO - GECKO(8853) | 1636395545270	Marionette	INFO	Listening on port 2828
[task 2021-11-08T18:19:05.275Z] 18:19:05     INFO - GECKO(8853) | 1636395545270	Marionette	DEBUG	Marionette is listening
[task 2021-11-08T18:19:05.363Z] 18:19:05     INFO - GECKO(8853) | 1636395545362	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:35488
[task 2021-11-08T18:19:05.374Z] 18:19:05     INFO - GECKO(8853) | 1636395545373	Marionette	DEBUG	Closed connection 0
[task 2021-11-08T18:19:05.377Z] 18:19:05     INFO - GECKO(8853) | 1636395545375	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:35490
[task 2021-11-08T18:19:05.409Z] 18:19:05     INFO - GECKO(8853) | 1636395545408	Marionette	DEBUG	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-11-08T18:19:05.473Z] 18:19:05     INFO - GECKO(8853) | 1636395545471	Marionette	DEBUG	1 <- [1,1,null,{"sessionId":"374fcf19-1557-4fa5-88bb-66f834f76a15","capabilities":{"browserName":"firefox","browserVersion":"95.0" ... .mozrunner","moz:shutdownTimeout":300000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-11-08T18:19:05.501Z] 18:19:05     INFO - GECKO(8853) | 1636395545499	Marionette	DEBUG	1 -> [0,2,"Addon:Install",{"path":"/tmp/tmp6zv4e7zl.zip","temporary":false}]
[task 2021-11-08T18:19:06.200Z] 18:19:06     INFO - GECKO(8853) | 1636395546199	Marionette	DEBUG	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2021-11-08T18:19:06.234Z] 18:19:06     INFO - GECKO(8853) | 1636395546232	Marionette	DEBUG	1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpfm0fyobq.zip","temporary":false}]
[task 2021-11-08T18:19:06.312Z] 18:19:06     INFO - GECKO(8853) | 1636395546310	Marionette	DEBUG	1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2021-11-08T18:19:06.317Z] 18:19:06     INFO - GECKO(8853) | 1636395546316	Marionette	DEBUG	1 -> [0,4,"Marionette:GetContext",{}]
[task 2021-11-08T18:19:06.319Z] 18:19:06     INFO - GECKO(8853) | 1636395546318	Marionette	DEBUG	1 <- [1,4,null,{"value":"content"}]
[task 2021-11-08T18:19:06.323Z] 18:19:06     INFO - GECKO(8853) | 1636395546322	Marionette	DEBUG	1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-11-08T18:19:06.326Z] 18:19:06     INFO - GECKO(8853) | 1636395546324	Marionette	DEBUG	1 <- [1,5,null,{"value":null}]
[task 2021-11-08T18:19:06.330Z] 18:19:06     INFO - GECKO(8853) | 1636395546329	Marionette	DEBUG	1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... ry=%2Ftmp&cleanupCrashes=true"}],"newSandbox":true,"sandbox":"default","line":1937,"filename":"tests/mochitest/runtests.py"}]
[task 2021-11-08T18:19:06.348Z] 18:19:06     INFO - GECKO(8853) | 1636395546347	Marionette	TRACE	[8] MarionetteCommands actor created for window id 2
[task 2021-11-08T18:19:06.373Z] 18:19:06     INFO - GECKO(8853) | 1636395546372	Marionette	DEBUG	1 <- [1,6,null,{"value":null}]
[task 2021-11-08T18:19:06.406Z] 18:19:06     INFO - GECKO(8853) | 1636395546405	Marionette	DEBUG	1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2021-11-08T18:19:06.410Z] 18:19:06     INFO - GECKO(8853) | 1636395546408	Marionette	DEBUG	1 <- [1,7,null,{"value":null}]
[task 2021-11-08T18:19:06.435Z] 18:19:06     INFO - GECKO(8853) | 1636395546434	Marionette	DEBUG	1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2021-11-08T18:19:06.446Z] 18:19:06     INFO - GECKO(8853) | 1636395546444	Marionette	DEBUG	1 <- [1,8,null,{"value":null}]
[task 2021-11-08T18:19:06.458Z] 18:19:06     INFO - runtests.py | Waiting for browser...
[task 2021-11-08T18:19:06.461Z] 18:19:06     INFO - GECKO(8853) | 1636395546460	Marionette	DEBUG	Closed connection 1
[task 2021-11-08T18:19:07.618Z] 18:19:07     INFO - TEST-START | dom/media/webspeech/synth/test/test_bfcache.html

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

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=368225179&repo=try

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