Closed Bug 1509467 Opened 6 years ago Closed 6 years ago

Intermittent browser/components/aboutconfig/test/browser/browser_search.js | 3337 == 3339 -

Categories

(Firefox :: General, defect, P5)

defect

Tracking

()

RESOLVED DUPLICATE of bug 1510162

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

Attachments

(1 obsolete file)

Filed by: shindli [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=213530678&repo=mozilla-central

https://queue.taskcluster.net/v1/task/Sk7Cb3d2SQimG0h7bgajVQ/runs/0/artifacts/public/logs/live_backing.log

[task 2018-11-23T10:41:10.419Z] 10:41:10     INFO - TEST-START | browser/components/aboutconfig/test/browser/browser_search.js
[task 2018-11-23T10:41:14.873Z] 10:41:14     INFO - TEST-INFO | started process screentopng
[task 2018-11-23T10:41:15.351Z] 10:41:15     INFO - TEST-INFO | screentopng: exit 0
[task 2018-11-23T10:41:15.352Z] 10:41:15     INFO - Buffered messages logged at 10:41:10
[task 2018-11-23T10:41:15.353Z] 10:41:15     INFO - Entering test bound setup
[task 2018-11-23T10:41:15.354Z] 10:41:15     INFO - Leaving test bound setup
[task 2018-11-23T10:41:15.354Z] 10:41:15     INFO - Entering test bound test_search
[task 2018-11-23T10:41:15.354Z] 10:41:15     INFO - Buffered messages finished
[task 2018-11-23T10:41:15.354Z] 10:41:15     INFO - TEST-UNEXPECTED-FAIL | browser/components/aboutconfig/test/browser/browser_search.js | 3337 == 3339 - 
[task 2018-11-23T10:41:15.355Z] 10:41:15     INFO - Stack trace:
[task 2018-11-23T10:41:15.355Z] 10:41:15     INFO - resource://testing-common/content-task.js line 59 > eval:null:4
[task 2018-11-23T10:41:15.355Z] 10:41:15     INFO - resource://testing-common/content-task.js:null:60
[task 2018-11-23T10:41:15.651Z] 10:41:15     INFO - Console message: [JavaScript Warning: "Key event not available on some keyboard layouts: key=“r” modifiers=“accel,alt” id=“key_toggleReaderMode”" {file: "chrome://mochikit/content/tests/SimpleTest/EventUtils.js" line: 869}]
[task 2018-11-23T10:41:15.652Z] 10:41:15     INFO - Console message: [JavaScript Warning: "Key event not available on some keyboard layouts: key=“r” modifiers=“accel,alt” id=“key_quickRestart”" {file: "chrome://mochikit/content/tests/SimpleTest/EventUtils.js" line: 869}]
[task 2018-11-23T10:41:15.654Z] 10:41:15     INFO - Console message: [JavaScript Warning: "Key event not available on some keyboard layouts: key=“i” modifiers=“accel,alt,shift” id=“key_browserToolbox”" {file: "chrome://mochikit/content/tests/SimpleTest/EventUtils.js" line: 869}]
[task 2018-11-23T10:41:15.752Z] 10:41:15     INFO - TEST-PASS | browser/components/aboutconfig/test/browser/browser_search.js | 15 == 15 - 
[task 2018-11-23T10:41:18.794Z] 10:41:18     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-11-23T10:41:18.796Z] 10:41:18     INFO - TEST-UNEXPECTED-FAIL | browser/components/aboutconfig/test/browser/browser_search.js | 3337 == 3339 - 
[task 2018-11-23T10:41:18.798Z] 10:41:18     INFO - Stack trace:
[task 2018-11-23T10:41:18.799Z] 10:41:18     INFO - resource://testing-common/content-task.js line 59 > eval:null:4
[task 2018-11-23T10:41:18.800Z] 10:41:18     INFO - resource://testing-common/content-task.js:null:60
[task 2018-11-23T10:41:19.362Z] 10:41:19     INFO - TEST-PASS | browser/components/aboutconfig/test/browser/browser_search.js | 0 == 0 - 
[task 2018-11-23T10:41:19.399Z] 10:41:19     INFO - TEST-PASS | browser/components/aboutconfig/test/browser/browser_search.js | 2 == 2 - 
[task 2018-11-23T10:41:19.476Z] 10:41:19     INFO - Leaving test bound test_search
[task 2018-11-23T10:41:19.525Z] 10:41:19     INFO - GECKO(2048) | MEMORY STAT | vsize 20974069MB | residentFast 1226MB
[task 2018-11-23T10:41:19.527Z] 10:41:19     INFO - TEST-OK | browser/components/aboutconfig/test/browser/browser_search.js | took 9107ms
[task 2018-11-23T10:41:19.602Z] 10:41:19     INFO - checking window state
[task 2018-11-23T10:41:22.335Z] 10:41:22     INFO - GECKO(2048) | Completed ShutdownLeaks collections in process 2252
[task 2018-11-23T10:41:22.335Z] 10:41:22     INFO - GECKO(2048) | Completed ShutdownLeaks collections in process 2269
[task 2018-11-23T10:41:22.339Z] 10:41:22     INFO - GECKO(2048) | Completed ShutdownLeaks collections in process 2164
[task 2018-11-23T10:41:22.357Z] 10:41:22     INFO - GECKO(2048) | Completed ShutdownLeaks collections in process 2221
[task 2018-11-23T10:41:22.397Z] 10:41:22     INFO - GECKO(2048) | Completed ShutdownLeaks collections in process 2147
[task 2018-11-23T10:41:23.728Z] 10:41:23     INFO - GECKO(2048) | Completed ShutdownLeaks collections in process 2048
[task 2018-11-23T10:41:23.730Z] 10:41:23     INFO - TEST-START | Shutdown
[task 2018-11-23T10:41:23.733Z] 10:41:23     INFO - Browser Chrome Test Summary
[task 2018-11-23T10:41:23.736Z] 10:41:23     INFO - Passed:  17
[task 2018-11-23T10:41:23.738Z] 10:41:23     INFO - Failed:  2
[task 2018-11-23T10:41:23.741Z] 10:41:23     INFO - Todo:    0
[task 2018-11-23T10:41:23.743Z] 10:41:23     INFO - Mode:    e10s
[task 2018-11-23T10:41:23.745Z] 10:41:23     INFO - *** End BrowserChrome Test Results ***
[task 2018-11-23T10:41:24.393Z] 10:41:24     INFO - GECKO(2048) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-11-23T10:41:24.395Z] 10:41:24     INFO - GECKO(2048) | ###!!! [Child][MessageChannel] Error: (msgtype=0x580029,name=PNecko::Msg_RemoveRequestContext) Closed channel: cannot send/recv
[task 2018-11-23T10:41:24.456Z] 10:41:24     INFO - GECKO(2048) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-11-23T10:41:26.517Z] 10:41:26     INFO - GECKO(2048) | 1542969686509	Marionette	DEBUG	Received observer notification xpcom-will-shutdown
[task 2018-11-23T10:41:26.538Z] 10:41:26     INFO - GECKO(2048) | 1542969686525	Marionette	INFO	Stopped listening on port 2828
[task 2018-11-23T10:41:26.541Z] 10:41:26     INFO - GECKO(2048) | 1542969686527	Marionette	DEBUG	Remote service is inactive
[task 2018-11-23T10:41:27.666Z] 10:41:27     INFO - GECKO(2048) | -----------------------------------------------------
[task 2018-11-23T10:41:27.667Z] 10:41:27     INFO - GECKO(2048) | Suppressions used:
[task 2018-11-23T10:41:27.667Z] 10:41:27     INFO - GECKO(2048) |   count      bytes template
[task 2018-11-23T10:41:27.669Z] 10:41:27     INFO - GECKO(2048) |     515      16432 nsComponentManagerImpl
[task 2018-11-23T10:41:27.670Z] 10:41:27     INFO - GECKO(2048) |       3        624 mozJSComponentLoader::LoadModule
[task 2018-11-23T10:41:27.671Z] 10:41:27     INFO - GECKO(2048) |     611      17713 libfontconfig.so
[task 2018-11-23T10:41:27.673Z] 10:41:27     INFO - GECKO(2048) |       1         29 libglib-2.0.so
[task 2018-11-23T10:41:27.674Z] 10:41:27     INFO - GECKO(2048) | -----------------------------------------------------
[task 2018-11-23T10:41:27.756Z] 10:41:27     INFO - GECKO(2048) | -----------------------------------------------------
[task 2018-11-23T10:41:27.756Z] 10:41:27     INFO - GECKO(2048) | Suppressions used:
[task 2018-11-23T10:41:27.757Z] 10:41:27     INFO - GECKO(2048) |   count      bytes template
[task 2018-11-23T10:41:27.758Z] 10:41:27     INFO - GECKO(2048) |     515      16432 nsComponentManagerImpl
[task 2018-11-23T10:41:27.758Z] 10:41:27     INFO - GECKO(2048) |       3        624 mozJSComponentLoader::LoadModule
[task 2018-11-23T10:41:27.759Z] 10:41:27     INFO - GECKO(2048) |     611      17713 libfontconfig.so
[task 2018-11-23T10:41:27.760Z] 10:41:27     INFO - GECKO(2048) |       1         29 libglib-2.0.so
[task 2018-11-23T10:41:27.761Z] 10:41:27     INFO - GECKO(2048) | -----------------------------------------------------
[task 2018-11-23T10:41:27.901Z] 10:41:27     INFO - GECKO(2048) | -----------------------------------------------------
[task 2018-11-23T10:41:27.902Z] 10:41:27     INFO - GECKO(2048) | Suppressions used:
[task 2018-11-23T10:41:27.902Z] 10:41:27     INFO - GECKO(2048) |   count      bytes template
[task 2018-11-23T10:41:27.902Z] 10:41:27     INFO - GECKO(2048) |     515      16432 nsComponentManagerImpl
[task 2018-11-23T10:41:27.902Z] 10:41:27     INFO - GECKO(2048) |       3        624 mozJSComponentLoader::LoadModule
[task 2018-11-23T10:41:27.902Z] 10:41:27     INFO - GECKO(2048) |     611      17713 libfontconfig.so
[task 2018-11-23T10:41:27.902Z] 10:41:27     INFO - GECKO(2048) |       1         29 libglib-2.0.so
[task 2018-11-23T10:41:27.903Z] 10:41:27     INFO - GECKO(2048) | -----------------------------------------------------
[task 2018-11-23T10:41:27.912Z] 10:41:27     INFO - GECKO(2048) | -----------------------------------------------------
[task 2018-11-23T10:41:27.914Z] 10:41:27     INFO - GECKO(2048) | Suppressions used:
[task 2018-11-23T10:41:27.915Z] 10:41:27     INFO - GECKO(2048) |   count      bytes template
[task 2018-11-23T10:41:27.916Z] 10:41:27     INFO - GECKO(2048) |     515      16432 nsComponentManagerImpl
[task 2018-11-23T10:41:27.917Z] 10:41:27     INFO - GECKO(2048) |       3        624 mozJSComponentLoader::LoadModule
[task 2018-11-23T10:41:27.919Z] 10:41:27     INFO - GECKO(2048) |     611      17713 libfontconfig.so
[task 2018-11-23T10:41:27.920Z] 10:41:27     INFO - GECKO(2048) |       1         29 libglib-2.0.so
[task 2018-11-23T10:41:27.922Z] 10:41:27     INFO - GECKO(2048) | -----------------------------------------------------
[task 2018-11-23T10:41:28.179Z] 10:41:28     INFO - GECKO(2048) | -----------------------------------------------------
[task 2018-11-23T10:41:28.180Z] 10:41:28     INFO - GECKO(2048) | Suppressions used:
[task 2018-11-23T10:41:28.182Z] 10:41:28     INFO - GECKO(2048) |   count      bytes template
[task 2018-11-23T10:41:28.184Z] 10:41:28     INFO - GECKO(2048) |     515      16432 nsComponentManagerImpl
[task 2018-11-23T10:41:28.185Z] 10:41:28     INFO - GECKO(2048) |       4        832 mozJSComponentLoader::LoadModule
[task 2018-11-23T10:41:28.186Z] 10:41:28     INFO - GECKO(2048) |     611      17713 libfontconfig.so
[task 2018-11-23T10:41:28.187Z] 10:41:28     INFO - GECKO(2048) |       1         29 libglib-2.0.so
[task 2018-11-23T10:41:28.189Z] 10:41:28     INFO - GECKO(2048) | -----------------------------------------------------
[task 2018-11-23T10:41:30.160Z] 10:41:30     INFO - GECKO(2048) | -----------------------------------------------------
[task 2018-11-23T10:41:30.162Z] 10:41:30     INFO - GECKO(2048) | Suppressions used:
[task 2018-11-23T10:41:30.163Z] 10:41:30     INFO - GECKO(2048) |   count      bytes template
[task 2018-11-23T10:41:30.164Z] 10:41:30     INFO - GECKO(2048) |     528      16832 nsComponentManagerImpl
[task 2018-11-23T10:41:30.165Z] 10:41:30     INFO - GECKO(2048) |      40       8320 mozJSComponentLoader::LoadModule
[task 2018-11-23T10:41:30.167Z] 10:41:30     INFO - GECKO(2048) |     611      17509 libfontconfig.so
[task 2018-11-23T10:41:30.168Z] 10:41:30     INFO - GECKO(2048) |      10        440 _PR_Getfd
[task 2018-11-23T10:41:30.172Z] 10:41:30     INFO - GECKO(2048) |       1         29 libglib-2.0.so
[task 2018-11-23T10:41:30.172Z] 10:41:30     INFO - GECKO(2048) |       5        140 libresolv.so
[task 2018-11-23T10:41:30.173Z] 10:41:30     INFO - GECKO(2048) | -----------------------------------------------------
[task 2018-11-23T10:41:30.372Z] 10:41:30     INFO - TEST-INFO | Main app process: exit 0
[task 2018-11-23T10:41:30.374Z] 10:41:30     INFO - runtests.py | Application ran for: 0:00:48.138234
[task 2018-11-23T10:41:30.375Z] 10:41:30     INFO - zombiecheck | Reading PID log: /tmp/tmpdYFMpHpidlog
[task 2018-11-23T10:41:30.376Z] 10:41:30     INFO - ==> process 2048 launched child process 2067
[task 2018-11-23T10:41:30.376Z] 10:41:30     INFO - ==> process 2048 launched child process 2147
[task 2018-11-23T10:41:30.377Z] 10:41:30     INFO - ==> process 2048 launched child process 2164
[task 2018-11-23T10:41:30.377Z] 10:41:30     INFO - ==> process 2048 launched child process 2221
[task 2018-11-23T10:41:30.378Z] 10:41:30     INFO - ==> process 2048 launched child process 2252
[task 2018-11-23T10:41:30.378Z] 10:41:30     INFO - ==> process 2048 launched child process 2269
[task 2018-11-23T10:41:30.379Z] 10:41:30     INFO - zombiecheck | Checking for orphan process with PID: 2147
[task 2018-11-23T10:41:30.380Z] 10:41:30     INFO - zombiecheck | Checking for orphan process with PID: 2252
[task 2018-11-23T10:41:30.380Z] 10:41:30     INFO - zombiecheck | Checking for orphan process with PID: 2221
[task 2018-11-23T10:41:30.381Z] 10:41:30     INFO - zombiecheck | Checking for orphan process with PID: 2067
[task 2018-11-23T10:41:30.382Z] 10:41:30     INFO - zombiecheck | Checking for orphan process with PID: 2164
[task 2018-11-23T10:41:30.382Z] 10:41:30     INFO - zombiecheck | Checking for orphan process with PID: 2269
[task 2018-11-23T10:41:30.383Z] 10:41:30     INFO - Stopping web server
[task 2018-11-23T10:41:30.399Z] 10:41:30     INFO - Stopping web socket server
[task 2018-11-23T10:41:30.417Z] 10:41:30     INFO - Stopping ssltunnel
[task 2018-11-23T10:41:30.437Z] 10:41:30  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2018-11-23T10:41:30.439Z] 10:41:30     INFO - runtests.py | Running tests: end.
[task 2018-11-23T10:41:30.457Z] 10:41:30     INFO - Buffered messages finished
[task 2018-11-23T10:41:30.457Z] 10:41:30     INFO - Running manifest: browser/components/enterprisepolicies/tests/browser/disable_app_update/browser.ini
[task 2018-11-23T10:41:30.457Z] 10:41:30     INFO - The following extra prefs will be set:
[task 2018-11-23T10:41:30.457Z] 10:41:30     INFO -   app.update.disabledForTesting=false
[task 2018-11-23T10:41:30.457Z] 10:41:30     INFO -   browser.policies.alternatePath='<test-root>/browser/components/enterprisepolicies/tests/browser/disable_app_update/config_disable_app_update.json'
[task 2018-11-23T10:41:30.486Z] 10:41:30     INFO -  Setting pipeline to PAUSED ...
[task 2018-11-23T10:41:30.486Z] 10:41:30     INFO -  Pipeline is PREROLLING ...
[task 2018-11-23T10:41:30.488Z] 10:41:30     INFO -  Pipeline is PREROLLED ...
[task 2018-11-23T10:41:30.488Z] 10:41:30     INFO -  Setting pipeline to PLAYING ...
[task 2018-11-23T10:41:30.489Z] 10:41:30     INFO -  New clock: GstSystemClock
[task 2018-11-23T10:41:30.526Z] 10:41:30     INFO -  Got EOS from element "pipeline0".
[task 2018-11-23T10:41:30.526Z] 10:41:30     INFO -  Execution ended after 0:00:00.033353270
[task 2018-11-23T10:41:30.526Z] 10:41:30     INFO -  Setting pipeline to PAUSED ...
[task 2018-11-23T10:41:30.526Z] 10:41:30     INFO -  Setting pipeline to READY ...
[task 2018-11-23T10:41:30.526Z] 10:41:30     INFO -  (gst-launch-1.0:2305): GStreamer-CRITICAL **: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2018-11-23T10:41:30.528Z] 10:41:30     INFO -  Setting pipeline to NULL ...
[task 2018-11-23T10:41:30.528Z] 10:41:30     INFO -  Freeing pipeline ...
[task 2018-11-23T10:41:30.557Z] 10:41:30     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2018-11-23T10:41:30.565Z] 10:41:30     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2018-11-23T10:41:30.784Z] 10:41:30     INFO -  pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2018-11-23T10:41:30.984Z] 10:41:30     INFO - Increasing default timeout to 90 seconds
[task 2018-11-23T10:41:30.984Z] 10:41:30     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2018-11-23T10:41:30.984Z] 10:41:30     INFO - LSan enabled.
[task 2018-11-23T10:41:30.984Z] 10:41:30     INFO - LSan using suppression file /builds/worker/workspace/build/tests/mochitest/lsan_suppressions.txt
[task 2018-11-23T10:41:30.984Z] 10:41:30     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2018-11-23T10:41:30.984Z] 10:41:30     INFO - UBSan enabled.
[task 2018-11-23T10:41:30.985Z] 10:41:30     INFO - UBSan using suppression file /builds/worker/workspace/build/tests/mochitest/ubsan_suppressions.txt
[task 2018-11-23T10:41:30.986Z] 10:41:30     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2018-11-23T10:41:30.990Z] 10:41:30     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2018-11-23T10:41:31.007Z] 10:41:31     INFO - MochitestServer : launching [u'/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/tmp3pIr_E.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 2018-11-23T10:41:31.007Z] 10:41:31     INFO - runtests.py | Server pid: 2333
[task 2018-11-23T10:41:31.024Z] 10:41:31     INFO - runtests.py | Websocket server pid: 2336
[task 2018-11-23T10:41:31.024Z] 10:41:31     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2018-11-23T10:41:31.048Z] 10:41:31     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2018-11-23T10:41:31.084Z] 10:41:31     INFO - runtests.py | SSL tunnel pid: 2341
[task 2018-11-23T10:41:31.490Z] 10:41:31     INFO - runtests.py | Running with e10s: True
[task 2018-11-23T10:41:31.491Z] 10:41:31     INFO - runtests.py | Running with serviceworker_e10s: False
[task 2018-11-23T10:41:31.492Z] 10:41:31     INFO - runtests.py | Running tests: start.
[task 2018-11-23T10:41:31.494Z] 10:41:31     INFO - 
[task 2018-11-23T10:41:31.526Z] 10:41:31     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmp3pIr_E.mozrunner
[task 2018-11-23T10:41:31.550Z] 10:41:31     INFO - runtests.py | Application pid: 2361
[task 2018-11-23T10:41:31.553Z] 10:41:31     INFO - TEST-INFO | started process GECKO(2361)
[task 2018-11-23T10:41:33.455Z] 10:41:33     INFO - GECKO(2361) | 1542969693449	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid host permission: resource://pdf.js/
[task 2018-11-23T10:41:33.456Z] 10:41:33     INFO - GECKO(2361) | 1542969693450	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid host permission: about:reader*
[task 2018-11-23T10:41:34.064Z] 10:41:34     INFO - GECKO(2361) | 1542969694051	Marionette	DEBUG	Received observer notification profile-after-change
[task 2018-11-23T10:41:34.283Z] 10:41:34     INFO - GECKO(2361) | 1542969694272	Marionette	DEBUG	Received observer notification command-line-startup
[task 2018-11-23T10:41:34.283Z] 10:41:34     INFO - GECKO(2361) | 1542969694272	Marionette	DEBUG	Received observer notification nsPref:changed
[task 2018-11-23T10:41:34.283Z] 10:41:34     INFO - GECKO(2361) | 1542969694273	Marionette	DEBUG	Init aborted (running=false, enabled=true, finalUIStartup=false)
[task 2018-11-23T10:41:34.765Z] 10:41:34     INFO - GECKO(2361) | 1542969694753	Marionette	DEBUG	Received observer notification toplevel-window-ready
[task 2018-11-23T10:41:38.910Z] 10:41:38     INFO - GECKO(2361) | 1542969698902	Marionette	DEBUG	Received observer notification sessionstore-windows-restored
[task 2018-11-23T10:41:38.910Z] 10:41:38     INFO - GECKO(2361) | 1542969698903	Marionette	DEBUG	Waiting for delayed startup...
[task 2018-11-23T10:41:40.678Z] 10:41:40     INFO - GECKO(2361) | 1542969700675	Marionette	DEBUG	Waiting for startup tests...
[task 2018-11-23T10:41:41.078Z] 10:41:41     INFO - GECKO(2361) | 1542969701070	Marionette	INFO	Listening on port 2828
[task 2018-11-23T10:41:41.080Z] 10:41:41     INFO - GECKO(2361) | 1542969701072	Marionette	DEBUG	Remote service is active
[task 2018-11-23T10:41:41.117Z] 10:41:41     INFO - GECKO(2361) | 1542969701112	Marionette	DEBUG	Accepted connection 0 from 127.0.0.1:43648
[task 2018-11-23T10:41:41.125Z] 10:41:41     INFO - GECKO(2361) | 1542969701120	Marionette	DEBUG	Accepted connection 1 from 127.0.0.1:43650
[task 2018-11-23T10:41:41.127Z] 10:41:41     INFO - GECKO(2361) | 1542969701123	Marionette	DEBUG	Closed connection 0
[task 2018-11-23T10:41:41.143Z] 10:41:41     INFO - GECKO(2361) | 1542969701137	Marionette	TRACE	1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2018-11-23T10:41:41.523Z] 10:41:41     INFO - GECKO(2361) | 1542969701514	Marionette	DEBUG	[4294967297] Frame script loaded
[task 2018-11-23T10:41:41.541Z] 10:41:41     INFO - GECKO(2361) | 1542969701535	Marionette	DEBUG	[4294967297] Frame script registered
[task 2018-11-23T10:41:41.558Z] 10:41:41     INFO - GECKO(2361) | 1542969701556	Marionette	TRACE	1 <- [1,1,null,{"sessionId":"93880dc2-d1bc-4061-936c-75af59207118","capabilities":{"browserName":"firefox","browserVersion":"65.0a ... p/tmp3pIr_E.mozrunner","moz:shutdownTimeout":180000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2018-11-23T10:41:41.608Z] 10:41:41     INFO - GECKO(2361) | 1542969701603	Marionette	TRACE	1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpUJ4I3D.zip","temporary":false}]
[task 2018-11-23T10:41:41.834Z] 10:41:41     INFO - GECKO(2361) | 1542969701825	Marionette	TRACE	1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2018-11-23T10:41:41.931Z] 10:41:41     INFO - GECKO(2361) | 1542969701920	Marionette	TRACE	1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpdbcsvT.zip","temporary":false}]
[task 2018-11-23T10:41:42.089Z] 10:41:42     INFO - GECKO(2361) | 1542969702080	Marionette	TRACE	1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2018-11-23T10:41:42.091Z] 10:41:42     INFO - GECKO(2361) | 1542969702084	Marionette	TRACE	1 -> [0,4,"Marionette:GetContext",{}]
[task 2018-11-23T10:41:42.092Z] 10:41:42     INFO - GECKO(2361) | 1542969702085	Marionette	TRACE	1 <- [1,4,null,{"value":"content"}]
[task 2018-11-23T10:41:42.097Z] 10:41:42     INFO - GECKO(2361) | 1542969702091	Marionette	TRACE	1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2018-11-23T10:41:42.099Z] 10:41:42     INFO - GECKO(2361) | 1542969702094	Marionette	TRACE	1 <- [1,5,null,{"value":null}]
[task 2018-11-23T10:41:42.108Z] 10:41:42     INFO - GECKO(2361) | 1542969702103	Marionette	TRACE	1 -> [0,6,"WebDriver:ExecuteScript",{"scriptTimeout":null,"newSandbox":true,"args":[{"testUrl":"about:blank","flavor":"browser-chr ...  new CustomEvent(\"mochitest-load\", {\"detail\": [flavor, url]});\nwin.dispatchEvent(ev);","sandbox":"default","line":1716}]
[task 2018-11-23T10:41:42.151Z] 10:41:42     INFO - GECKO(2361) | 1542969702149	Marionette	TRACE	1 <- [1,6,null,{"value":null}]
[task 2018-11-23T10:41:42.290Z] 10:41:42     INFO - GECKO(2361) | 1542969702284	Marionette	TRACE	1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2018-11-23T10:41:42.292Z] 10:41:42     INFO - GECKO(2361) | 1542969702286	Marionette	TRACE	1 <- [1,7,null,{"value":null}]
[task 2018-11-23T10:41:42.308Z] 10:41:42     INFO - GECKO(2361) | 1542969702297	Marionette	TRACE	1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2018-11-23T10:41:42.309Z] 10:41:42     INFO - GECKO(2361) | 1542969702302	Marionette	TRACE	1 <- [1,8,null,{"value":null}]
[task 2018-11-23T10:41:42.310Z] 10:41:42     INFO - runtests.py | Waiting for browser...
[task 2018-11-23T10:41:42.326Z] 10:41:42     INFO - GECKO(2361) | 1542969702313	Marionette	DEBUG	Closed connection 1
[task 2018-11-23T10:41:42.752Z] 10:41:42     INFO - *** Start BrowserChrome Test Results ***
[task 2018-11-23T10:41:42.851Z] 10:41:42     INFO - checking window state
[task 2018-11-23T10:41:42.961Z] 10:41:42     INFO - TEST-START | browser/components/enterprisepolicies/tests/browser/disable_app_update/browser_policy_disable_app_update.js
[task 2018-11-23T10:41:46.691Z] 10:41:46     INFO - GECKO(2361) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2018-11-23T10:41:46.691Z] 10:41:46     INFO - GECKO(2361) | MEMORY STAT heapAllocated not supported in this build configuration.
[task 2018-11-23T10:41:46.691Z] 10:41:46     INFO - GECKO(2361) | MEMORY STAT | vsize 20973875MB | residentFast 1027MB
[task 2018-11-23T10:41:46.691Z] 10:41:46     INFO - TEST-OK | browser/components/enterprisepolicies/tests/browser/disable_app_update/browser_policy_disable_app_update.js | took 3728ms
[task 2018-11-23T10:41:46.776Z] 10:41:46     INFO - checking window state
[task 2018-11-23T10:41:49.148Z] 10:41:49     INFO - GECKO(2361) | Completed ShutdownLeaks collections in process 2520
[task 2018-11-23T10:41:49.185Z] 10:41:49     INFO - GECKO(2361) | Completed ShutdownLeaks collections in process 2553
[task 2018-11-23T10:41:49.323Z] 10:41:49     INFO - GECKO(2361) | Completed ShutdownLeaks collections in process 2468
[task 2018-11-23T10:41:49.343Z] 10:41:49     INFO - GECKO(2361) | Completed ShutdownLeaks collections in process 2451
[task 2018-11-23T10:41:50.129Z] 10:41:50     INFO - GECKO(2361) | Completed ShutdownLeaks collections in process 2361
[task 2018-11-23T10:41:50.130Z] 10:41:50     INFO - TEST-START | Shutdown
[task 2018-11-23T10:41:50.131Z] 10:41:50     INFO - Browser Chrome Test Summary
[task 2018-11-23T10:41:50.132Z] 10:41:50     INFO - Passed:  3
[task 2018-11-23T10:41:50.137Z] 10:41:50     INFO - Failed:  0
[task 2018-11-23T10:41:50.138Z] 10:41:50     INFO - Todo:    0
[task 2018-11-23T10:41:50.139Z] 10:41:50     INFO - Mode:    e10s
[task 2018-11-23T10:41:50.140Z] 10:41:50     INFO - *** End BrowserChrome Test Results ***
[task 2018-11-23T10:41:52.690Z] 10:41:52     INFO - GECKO(2361) | 1542969712678	Marionette	DEBUG	Received observer notification xpcom-will-shutdown
[task 2018-11-23T10:41:52.690Z] 10:41:52     INFO - GECKO(2361) | 1542969712679	Marionette	INFO	Stopped listening on port 2828
[task 2018-11-23T10:41:52.691Z] 10:41:52     INFO - GECKO(2361) | 1542969712679	Marionette	DEBUG	Remote service is inactive
[task 2018-11-23T10:41:53.927Z] 10:41:53     INFO - GECKO(2361) | -----------------------------------------------------
[task 2018-11-23T10:41:53.927Z] 10:41:53     INFO - GECKO(2361) | Suppressions used:
[task 2018-11-23T10:41:53.928Z] 10:41:53     INFO - GECKO(2361) |   count      bytes template
[task 2018-11-23T10:41:53.928Z] 10:41:53     INFO - GECKO(2361) |     515      16432 nsComponentManagerImpl
[task 2018-11-23T10:41:53.928Z] 10:41:53     INFO - GECKO(2361) |       3        624 mozJSComponentLoader::LoadModule
[task 2018-11-23T10:41:53.928Z] 10:41:53     INFO - GECKO(2361) |     611      17713 libfontconfig.so
[task 2018-11-23T10:41:53.929Z] 10:41:53     INFO - GECKO(2361) |       1         29 libglib-2.0.so
[task 2018-11-23T10:41:53.929Z] 10:41:53     INFO - GECKO(2361) | -----------------------------------------------------
[task 2018-11-23T10:41:54.172Z] 10:41:54     INFO - GECKO(2361) | -----------------------------------------------------
[task 2018-11-23T10:41:54.172Z] 10:41:54     INFO - GECKO(2361) | Suppressions used:
[task 2018-11-23T10:41:54.174Z] 10:41:54     INFO - GECKO(2361) |   count      bytes template
[task 2018-11-23T10:41:54.175Z] 10:41:54     INFO - GECKO(2361) |     515      16432 nsComponentManagerImpl
[task 2018-11-23T10:41:54.176Z] 10:41:54     INFO - GECKO(2361) |       3        624 mozJSComponentLoader::LoadModule
[task 2018-11-23T10:41:54.177Z] 10:41:54     INFO - GECKO(2361) |     611      17713 libfontconfig.so
[task 2018-11-23T10:41:54.178Z] 10:41:54     INFO - GECKO(2361) |       1         29 libglib-2.0.so
[task 2018-11-23T10:41:54.180Z] 10:41:54     INFO - GECKO(2361) | -----------------------------------------------------
[task 2018-11-23T10:41:54.282Z] 10:41:54     INFO - GECKO(2361) | -----------------------------------------------------
[task 2018-11-23T10:41:54.282Z] 10:41:54     INFO - GECKO(2361) | Suppressions used:
[task 2018-11-23T10:41:54.283Z] 10:41:54     INFO - GECKO(2361) |   count      bytes template
[task 2018-11-23T10:41:54.283Z] 10:41:54     INFO - GECKO(2361) |     515      16432 nsComponentManagerImpl
[task 2018-11-23T10:41:54.283Z] 10:41:54     INFO - GECKO(2361) |       3        624 mozJSComponentLoader::LoadModule
[task 2018-11-23T10:41:54.284Z] 10:41:54     INFO - GECKO(2361) |       2        288 libfontconfig.so
[task 2018-11-23T10:41:54.284Z] 10:41:54     INFO - GECKO(2361) | -----------------------------------------------------
[task 2018-11-23T10:41:54.456Z] 10:41:54     INFO - GECKO(2361) | -----------------------------------------------------
[task 2018-11-23T10:41:54.457Z] 10:41:54     INFO - GECKO(2361) | Suppressions used:
[task 2018-11-23T10:41:54.457Z] 10:41:54     INFO - GECKO(2361) |   count      bytes template
[task 2018-11-23T10:41:54.457Z] 10:41:54     INFO - GECKO(2361) |     515      16432 nsComponentManagerImpl
[task 2018-11-23T10:41:54.457Z] 10:41:54     INFO - GECKO(2361) |       4        832 mozJSComponentLoader::LoadModule
[task 2018-11-23T10:41:54.457Z] 10:41:54     INFO - GECKO(2361) |     611      17713 libfontconfig.so
[task 2018-11-23T10:41:54.457Z] 10:41:54     INFO - GECKO(2361) |       1         29 libglib-2.0.so
[task 2018-11-23T10:41:54.458Z] 10:41:54     INFO - GECKO(2361) | -----------------------------------------------------
[task 2018-11-23T10:41:54.732Z] 10:41:54     INFO - GECKO(2361) | -----------------------------------------------------
[task 2018-11-23T10:41:54.732Z] 10:41:54     INFO - GECKO(2361) | Suppressions used:
[task 2018-11-23T10:41:54.733Z] 10:41:54     INFO - GECKO(2361) |   count      bytes template
[task 2018-11-23T10:41:54.734Z] 10:41:54     INFO - GECKO(2361) |     515      16432 nsComponentManagerImpl
[task 2018-11-23T10:41:54.735Z] 10:41:54     INFO - GECKO(2361) |       3        624 mozJSComponentLoader::LoadModule
[task 2018-11-23T10:41:54.736Z] 10:41:54     INFO - GECKO(2361) |     611      17713 libfontconfig.so
[task 2018-11-23T10:41:54.737Z] 10:41:54     INFO - GECKO(2361) |       1         29 libglib-2.0.so
[task 2018-11-23T10:41:54.738Z] 10:41:54     INFO - GECKO(2361) | -----------------------------------------------------
[task 2018-11-23T10:41:56.369Z] 10:41:56     INFO - GECKO(2361) | -----------------------------------------------------
[task 2018-11-23T10:41:56.370Z] 10:41:56     INFO - GECKO(2361) | Suppressions used:
[task 2018-11-23T10:41:56.370Z] 10:41:56     INFO - GECKO(2361) |   count      bytes template
[task 2018-11-23T10:41:56.370Z] 10:41:56     INFO - GECKO(2361) |     528      16832 nsComponentManagerImpl
[task 2018-11-23T10:41:56.370Z] 10:41:56     INFO - GECKO(2361) |      39       8112 mozJSComponentLoader::LoadModule
[task 2018-11-23T10:41:56.370Z] 10:41:56     INFO - GECKO(2361) |     611      17509 libfontconfig.so
[task 2018-11-23T10:41:56.370Z] 10:41:56     INFO - GECKO(2361) |      10        440 _PR_Getfd
[task 2018-11-23T10:41:56.370Z] 10:41:56     INFO - GECKO(2361) |       1         29 libglib-2.0.so
[task 2018-11-23T10:41:56.370Z] 10:41:56     INFO - GECKO(2361) |       6        168 libresolv.so
[task 2018-11-23T10:41:56.371Z] 10:41:56     INFO - GECKO(2361) | -----------------------------------------------------
[task 2018-11-23T10:41:56.582Z] 10:41:56     INFO - TEST-INFO | Main app process: exit 0
@paolo, can you, please, take a look?

Is this caused by bug 1497725?
Flags: needinfo?(paolo.mozmail)
Whiteboard: [stockwell needswork]
Flags: needinfo?(paolo.mozmail)
See Also: → 1510162
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → DUPLICATE
Attachment #9027482 - Attachment description: Bug 1509467 - Fix intermittent browser/components/aboutconfig/test/browser/browser_search.js. r=bgrins → Bug 1510162 - Fix intermittent browser/components/aboutconfig/test/browser/browser_search.js. r=bgrins
Attachment #9027482 - Attachment is obsolete: true
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: