Recent log:
https://treeherder.mozilla.org/logviewer?job_id=341427037&repo=mozilla-beta&lineNumber=10006
INFO - TEST-START | editor/spellchecker/tests/test_bug1209414.html
[task 2021-06-01T15:44:17.239Z] 15:44:17 INFO - GECKO(9972) | Gdk-Message: 15:44:17.233: firefox: Fatal IO error 11 (Resource temporarily unavailable) on X server :0.
[task 2021-06-01T15:44:17.256Z] 15:44:17 INFO - GECKO(9972) | Exiting due to channel error.
[task 2021-06-01T15:44:17.271Z] 15:44:17 INFO - GECKO(9972) | Exiting due to channel error.
[task 2021-06-01T15:44:17.273Z] 15:44:17 INFO - GECKO(9972) | Exiting due to channel error.
[task 2021-06-01T15:44:17.274Z] 15:44:17 INFO - GECKO(9972) | Exiting due to channel error.
[task 2021-06-01T15:44:17.311Z] 15:44:17 INFO - TEST-INFO | Main app process: exit 1
[task 2021-06-01T15:44:17.312Z] 15:44:17 INFO - Buffered messages logged at 15:44:14
[task 2021-06-01T15:44:17.313Z] 15:44:17 INFO - must wait for load
[task 2021-06-01T15:44:17.314Z] 15:44:17 INFO - TEST-PASS | editor/spellchecker/tests/test_bug1209414.html | Got context menu XUL
[task 2021-06-01T15:44:17.315Z] 15:44:17 INFO - TEST-PASS | editor/spellchecker/tests/test_bug1209414.html | true expected (de_DE directory should exist)
[task 2021-06-01T15:44:17.315Z] 15:44:17 INFO - Buffered messages logged at 15:44:16
[task 2021-06-01T15:44:17.315Z] 15:44:17 INFO - TEST-PASS | editor/spellchecker/tests/test_bug1209414.html | expected de-DE
[task 2021-06-01T15:44:17.316Z] 15:44:17 INFO - TEST-PASS | editor/spellchecker/tests/test_bug1209414.html | some misspelled words expected: today is a good day
[task 2021-06-01T15:44:17.316Z] 15:44:17 INFO - Buffered messages finished
[task 2021-06-01T15:44:17.316Z] 15:44:17 ERROR - TEST-UNEXPECTED-FAIL | editor/spellchecker/tests/test_bug1209414.html | application terminated with exit code 1
[task 2021-06-01T15:44:17.316Z] 15:44:17 INFO - runtests.py | Application ran for: 0:00:43.843530
[task 2021-06-01T15:44:17.316Z] 15:44:17 INFO - zombiecheck | Reading PID log: /tmp/tmpdl5b2z65pidlog
[task 2021-06-01T15:44:17.316Z] 15:44:17 INFO - ==> process 9972 launched child process 9992
[task 2021-06-01T15:44:17.316Z] 15:44:17 INFO - ==> process 9972 launched child process 10037
[task 2021-06-01T15:44:17.317Z] 15:44:17 INFO - ==> process 9972 launched child process 10055
[task 2021-06-01T15:44:17.317Z] 15:44:17 INFO - ==> process 9972 launched child process 10113
[task 2021-06-01T15:44:17.317Z] 15:44:17 INFO - ==> process 9972 launched child process 10178
[task 2021-06-01T15:44:17.317Z] 15:44:17 INFO - zombiecheck | Checking for orphan process with PID: 10113
[task 2021-06-01T15:44:17.317Z] 15:44:17 INFO - zombiecheck | Checking for orphan process with PID: 10178
[task 2021-06-01T15:44:17.317Z] 15:44:17 INFO - zombiecheck | Checking for orphan process with PID: 10055
[task 2021-06-01T15:44:17.317Z] 15:44:17 INFO - zombiecheck | Checking for orphan process with PID: 9992
[task 2021-06-01T15:44:17.321Z] 15:44:17 INFO - zombiecheck | Checking for orphan process with PID: 10037
[task 2021-06-01T15:44:17.324Z] 15:44:17 INFO - Stopping web server
[task 2021-06-01T15:44:17.324Z] 15:44:17 INFO - Server shut down.
[task 2021-06-01T15:44:17.344Z] 15:44:17 INFO - Web server killed.
[task 2021-06-01T15:44:17.344Z] 15:44:17 INFO - Stopping web socket server
[task 2021-06-01T15:44:17.363Z] 15:44:17 INFO - Stopping ssltunnel
[task 2021-06-01T15:44:17.383Z] 15:44:17 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2021-06-01T15:44:17.384Z] 15:44:17 INFO - runtests.py | Running tests: end.
[task 2021-06-01T15:44:17.408Z] 15:44:17 INFO - Buffered messages finished
[task 2021-06-01T15:44:17.408Z] 15:44:17 INFO - Running manifest: js/xpconnect/tests/mochitest/hasinstance/mochitest.ini
[task 2021-06-01T15:44:17.408Z] 15:44:17 INFO - The following extra prefs will be set:
[task 2021-06-01T15:44:17.408Z] 15:44:17 INFO - dom.webidl.crosscontext_hasinstance.enabled=false
[task 2021-06-01T15:44:17.428Z] 15:44:17 INFO - Setting pipeline to PAUSED ...
[task 2021-06-01T15:44:17.428Z] 15:44:17 INFO - Pipeline is PREROLLING ...
[task 2021-06-01T15:44:17.430Z] 15:44:17 INFO - Pipeline is PREROLLED ...
[task 2021-06-01T15:44:17.430Z] 15:44:17 INFO - Setting pipeline to PLAYING ...
[task 2021-06-01T15:44:17.431Z] 15:44:17 INFO - New clock: GstSystemClock
[task 2021-06-01T15:44:17.464Z] 15:44:17 INFO - Got EOS from element "pipeline0".
[task 2021-06-01T15:44:17.464Z] 15:44:17 INFO - Execution ended after 0:00:00.033393521
[task 2021-06-01T15:44:17.464Z] 15:44:17 INFO - Setting pipeline to PAUSED ...
[task 2021-06-01T15:44:17.464Z] 15:44:17 INFO - Setting pipeline to READY ...
[task 2021-06-01T15:44:17.466Z] 15:44:17 INFO - (gst-launch-1.0:10227): GStreamer-CRITICAL **: 15:44:17.464: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2021-06-01T15:44:17.466Z] 15:44:17 INFO - Setting pipeline to NULL ...
[task 2021-06-01T15:44:17.466Z] 15:44:17 INFO - Freeing pipeline ...
[task 2021-06-01T15:44:17.690Z] 15:44:17 INFO - PID 10237 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2021-06-01T15:44:17.795Z] 15:44:17 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/tmpvmrrkoym.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-06-01T15:44:17.795Z] 15:44:17 INFO - runtests.py | Server pid: 10246
[task 2021-06-01T15:44:17.812Z] 15:44:17 INFO - runtests.py | Websocket server pid: 10249
[task 2021-06-01T15:44:17.827Z] 15:44:17 INFO - runtests.py | SSL tunnel pid: 10254
[task 2021-06-01T15:44:17.930Z] 15:44:17 INFO - runtests.py | Running with scheme: http
[task 2021-06-01T15:44:17.930Z] 15:44:17 INFO - runtests.py | Running with e10s: True
[task 2021-06-01T15:44:17.931Z] 15:44:17 INFO - runtests.py | Running with fission: False
[task 2021-06-01T15:44:17.932Z] 15:44:17 INFO - runtests.py | Running with cross-origin iframes: False
[task 2021-06-01T15:44:17.932Z] 15:44:17 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2021-06-01T15:44:17.932Z] 15:44:17 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2021-06-01T15:44:17.933Z] 15:44:17 INFO - runtests.py | Running tests: start.
[task 2021-06-01T15:44:17.933Z] 15:44:17 INFO -
[task 2021-06-01T15:44:17.964Z] 15:44:17 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpvmrrkoym.mozrunner
[task 2021-06-01T15:44:17.975Z] 15:44:17 INFO - runtests.py | Application pid: 10269
[task 2021-06-01T15:44:17.976Z] 15:44:17 INFO - TEST-INFO | started process GECKO(10269)
[task 2021-06-01T15:44:18.439Z] 15:44:18 INFO - GECKO(10269) | 1622562258438 Marionette INFO Marionette enabled
[task 2021-06-01T15:44:18.516Z] 15:44:18 INFO - GECKO(10269) | 1622562258515 Marionette TRACE Received observer notification toplevel-window-ready
[task 2021-06-01T15:44:22.085Z] 15:44:22 INFO - GECKO(10269) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmpvmrrkoym.mozrunner/search.json.mozlz4", (void 0)))
[task 2021-06-01T15:44:22.883Z] 15:44:22 INFO - GECKO(10269) | 1622562262882 Marionette TRACE Received observer notification marionette-startup-requested
[task 2021-06-01T15:44:22.884Z] 15:44:22 INFO - GECKO(10269) | 1622562262883 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2021-06-01T15:44:22.905Z] 15:44:22 INFO - GECKO(10269) | 1622562262904 Marionette TRACE All scripts recorded.
[task 2021-06-01T15:44:22.907Z] 15:44:22 INFO - GECKO(10269) | 1622562262906 Marionette INFO Listening on port 2828
[task 2021-06-01T15:44:22.908Z] 15:44:22 INFO - GECKO(10269) | 1622562262906 Marionette DEBUG Marionette is listening
[task 2021-06-01T15:44:23.005Z] 15:44:23 INFO - GECKO(10269) | 1622562263004 Marionette DEBUG Accepted connection 0 from 127.0.0.1:35228
[task 2021-06-01T15:44:23.008Z] 15:44:23 INFO - GECKO(10269) | 1622562263007 Marionette DEBUG Closed connection 0
[task 2021-06-01T15:44:23.010Z] 15:44:23 INFO - GECKO(10269) | 1622562263008 Marionette DEBUG Accepted connection 1 from 127.0.0.1:35230
[task 2021-06-01T15:44:23.017Z] 15:44:23 INFO - GECKO(10269) | 1622562263016 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-06-01T15:44:23.029Z] 15:44:23 INFO - GECKO(10269) | 1622562263027 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"eb149ca2-ab15-471c-821b-6d8631083251","capabilities":{"browserName":"firefox","browserVersion":"90.0" ... m.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"proxy":{}}}]
[task 2021-06-01T15:44:23.041Z] 15:44:23 INFO - GECKO(10269) | 1622562263040 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpbswm89sc.zip","temporary":false}]
[task 2021-06-01T15:44:23.214Z] 15:44:23 INFO - GECKO(10269) | 1622562263213 Marionette DEBUG 1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2021-06-01T15:44:23.241Z] 15:44:23 INFO - GECKO(10269) | 1622562263240 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpnj5ads6s.zip","temporary":false}]
[task 2021-06-01T15:44:23.257Z] 15:44:23 INFO - GECKO(10269) | 1622562263256 Marionette DEBUG 1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2021-06-01T15:44:23.259Z] 15:44:23 INFO - GECKO(10269) | 1622562263258 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2021-06-01T15:44:23.260Z] 15:44:23 INFO - GECKO(10269) | 1622562263258 Marionette DEBUG 1 <- [1,4,null,{"value":"content"}]
[task 2021-06-01T15:44:23.261Z] 15:44:23 INFO - GECKO(10269) | 1622562263260 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-06-01T15:44:23.262Z] 15:44:23 INFO - GECKO(10269) | 1622562263260 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2021-06-01T15:44:23.264Z] 15:44:23 INFO - GECKO(10269) | 1622562263263 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":1935,"filename":"tests/mochitest/runtests.py"}]
[task 2021-06-01T15:44:23.267Z] 15:44:23 INFO - GECKO(10269) | 1622562263266 Marionette TRACE [7] MarionetteCommands actor created for window id 2
[task 2021-06-01T15:44:23.273Z] 15:44:23 INFO - GECKO(10269) | 1622562263271 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2021-06-01T15:44:23.274Z] 15:44:23 INFO - GECKO(10269) | 1622562263272 Marionette TRACE [19] MarionetteEvents actor created for window id 2147483649
[task 2021-06-01T15:44:23.278Z] 15:44:23 INFO - GECKO(10269) | 1622562263274 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2021-06-01T15:44:23.278Z] 15:44:23 INFO - GECKO(10269) | 1622562263274 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2021-06-01T15:44:23.295Z] 15:44:23 INFO - GECKO(10269) | 1622562263294 Marionette DEBUG 1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2021-06-01T15:44:23.297Z] 15:44:23 INFO - GECKO(10269) | 1622562263295 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2021-06-01T15:44:23.299Z] 15:44:23 INFO - runtests.py | Waiting for browser...
[task 2021-06-01T15:44:23.301Z] 15:44:23 INFO - GECKO(10269) | 1622562263300 Marionette DEBUG Closed connection 1
[task 2021-06-01T15:44:23.509Z] 15:44:23 INFO - GECKO(10269) | 1622562263507 Marionette TRACE [39] MarionetteEvents actor created for window id 6442450945
[task 2021-06-01T15:44:23.514Z] 15:44:23 INFO - GECKO(10269) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'MarionetteEvents'
[task 2021-06-01T15:44:23.608Z] 15:44:23 INFO - TEST-START | js/xpconnect/tests/mochitest/hasinstance/test_bug870423.html
[task 2021-06-01T15:44:24.615Z] 15:44:24 INFO - GECKO(10269) | JavaScript error: resource://gre/modules/XULStore.jsm, line 66: Error: Can't find profile directory.
[task 2021-06-01T15:44:24.623Z] 15:44:24 INFO - GECKO(10269) | JavaScript error: resource://gre/modules/XULStore.jsm, line 66: Error: Can't find profile directory.
[task 2021-06-01T15:44:24.625Z] 15:44:24 INFO - GECKO(10269) | JavaScript error: resource://gre/modules/XULStore.jsm, line 66: Error: Can't find profile directory.
[task 2021-06-01T15:44:24.626Z] 15:44:24 INFO - GECKO(10269) | JavaScript error: resource://gre/modules/XULStore.jsm, line 66: Error: Can't find profile directory.
[task 2021-06-01T15:44:24.629Z] 15:44:24 INFO - GECKO(10269) | JavaScript error: resource://gre/modules/XULStore.jsm, line 66: Error: Can't find profile directory.
[task 2021-06-01T15:44:24.874Z] 15:44:24 INFO - GECKO(10269) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2021-06-01T15:44:24.876Z] 15:44:24 INFO - GECKO(10269) | MEMORY STAT | vsize 2511MB | residentFast 91MB | heapAllocated 9MB
[task 2021-06-01T15:44:24.912Z] 15:44:24 INFO - TEST-OK | js/xpconnect/tests/mochitest/hasinstance/test_bug870423.html | took 1304ms
[task 2021-06-01T15:44:24.932Z] 15:44:24 INFO - TEST-START | Shutdown
Description
•