1467635 - Intermittent browser/base/content/test/trackingUI/browser_trackingUI_appMenu_toggle.js | Found a browser window after previous test timed out -

Status: RESOLVED DUPLICATE of bug 1467898

(Firefox :: Protections UI, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=182417186&repo=autoland

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

[task 2018-06-07T23:41:32.791Z] 23:41:32     INFO - TEST-START | browser/base/content/test/trackingUI/browser_trackingUI_appMenu_toggle.js
[task 2018-06-07T23:42:17.808Z] 23:42:17     INFO - TEST-INFO | started process screentopng
[task 2018-06-07T23:42:18.398Z] 23:42:18     INFO - TEST-INFO | screentopng: exit 0
[task 2018-06-07T23:42:18.398Z] 23:42:18     INFO - Buffered messages logged at 23:41:32
[task 2018-06-07T23:42:18.398Z] 23:42:18     INFO - Entering test bound testGlobalToggle
[task 2018-06-07T23:42:18.398Z] 23:42:18     INFO - Buffered messages logged at 23:41:33
[task 2018-06-07T23:42:18.398Z] 23:42:18     INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/FileUtils.jsm" line: 170}]
[task 2018-06-07T23:42:18.398Z] 23:42:18     INFO - Opening main menu
[task 2018-06-07T23:42:18.399Z] 23:42:18     INFO - Buffered messages finished
[task 2018-06-07T23:42:18.400Z] 23:42:18     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/trackingUI/browser_trackingUI_appMenu_toggle.js | Test timed out - 
[task 2018-06-07T23:42:18.402Z] 23:42:18     INFO - GECKO(2251) | MEMORY STAT | vsize 994MB | residentFast 291MB | heapAllocated 89MB
[task 2018-06-07T23:42:18.404Z] 23:42:18     INFO - TEST-OK | browser/base/content/test/trackingUI/browser_trackingUI_appMenu_toggle.js | took 45035ms
[task 2018-06-07T23:42:18.406Z] 23:42:18     INFO - checking window state
[task 2018-06-07T23:42:18.407Z] 23:42:18     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-06-07T23:42:18.409Z] 23:42:18     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/trackingUI/browser_trackingUI_appMenu_toggle.js | Found a browser window after previous test timed out - 
[task 2018-06-07T23:42:18.410Z] 23:42:18     INFO - GECKO(2251) | must wait for focus
[task 2018-06-07T23:42:19.597Z] 23:42:19     INFO - GECKO(2251) | Completed ShutdownLeaks collections in process 2424
[task 2018-06-07T23:42:19.629Z] 23:42:19     INFO - GECKO(2251) | Completed ShutdownLeaks collections in process 2365
[task 2018-06-07T23:42:19.646Z] 23:42:19     INFO - GECKO(2251) | Completed ShutdownLeaks collections in process 2302
[task 2018-06-07T23:42:19.647Z] 23:42:19     INFO - GECKO(2251) | Completed ShutdownLeaks collections in process 2388
[task 2018-06-07T23:42:20.089Z] 23:42:20     INFO - GECKO(2251) | Completed ShutdownLeaks collections in process 2251
[task 2018-06-07T23:42:20.090Z] 23:42:20     INFO - TEST-START | Shutdown
[task 2018-06-07T23:42:20.090Z] 23:42:20     INFO - Browser Chrome Test Summary
[task 2018-06-07T23:42:20.090Z] 23:42:20     INFO - Passed:  2
[task 2018-06-07T23:42:20.094Z] 23:42:20     INFO - Failed:  2
[task 2018-06-07T23:42:20.095Z] 23:42:20     INFO - Todo:    0
[task 2018-06-07T23:42:20.098Z] 23:42:20     INFO - Mode:    e10s
[task 2018-06-07T23:42:20.100Z] 23:42:20     INFO - *** End BrowserChrome Test Results ***
[task 2018-06-07T23:42:20.366Z] 23:42:20     INFO - GECKO(2251) | 1528414940364	Marionette	DEBUG	Received observer notification xpcom-will-shutdown
[task 2018-06-07T23:42:20.371Z] 23:42:20     INFO - GECKO(2251) | 1528414940364	Marionette	INFO	Stopped listening on port 2828
[task 2018-06-07T23:42:20.373Z] 23:42:20     INFO - GECKO(2251) | 1528414940364	Marionette	DEBUG	Remote service is inactive
[task 2018-06-07T23:42:20.770Z] 23:42:20     INFO - TEST-INFO | Main app process: exit 0
[task 2018-06-07T23:42:20.771Z] 23:42:20     INFO - runtests.py | Application ran for: 0:00:54.194339
[task 2018-06-07T23:42:20.771Z] 23:42:20     INFO - zombiecheck | Reading PID log: /tmp/tmpaD1qsCpidlog
[task 2018-06-07T23:42:20.772Z] 23:42:20     INFO - ==> process 2251 launched child process 2274
[task 2018-06-07T23:42:20.772Z] 23:42:20     INFO - ==> process 2251 launched child process 2302
[task 2018-06-07T23:42:20.773Z] 23:42:20     INFO - ==> process 2251 launched child process 2365
[task 2018-06-07T23:42:20.774Z] 23:42:20     INFO - ==> process 2251 launched child process 2388
[task 2018-06-07T23:42:20.774Z] 23:42:20     INFO - ==> process 2251 launched child process 2424
[task 2018-06-07T23:42:20.775Z] 23:42:20     INFO - zombiecheck | Checking for orphan process with PID: 2424
[task 2018-06-07T23:42:20.776Z] 23:42:20     INFO - zombiecheck | Checking for orphan process with PID: 2274
[task 2018-06-07T23:42:20.777Z] 23:42:20     INFO - zombiecheck | Checking for orphan process with PID: 2388
[task 2018-06-07T23:42:20.778Z] 23:42:20     INFO - zombiecheck | Checking for orphan process with PID: 2365
[task 2018-06-07T23:42:20.779Z] 23:42:20     INFO - zombiecheck | Checking for orphan process with PID: 2302
[task 2018-06-07T23:42:20.780Z] 23:42:20     INFO - Stopping web server
[task 2018-06-07T23:42:20.797Z] 23:42:20     INFO - Stopping web socket server
[task 2018-06-07T23:42:20.813Z] 23:42:20     INFO - Stopping ssltunnel
[task 2018-06-07T23:42:20.829Z] 23:42:20  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2018-06-07T23:42:20.830Z] 23:42:20     INFO - runtests.py | Running tests: end.
[task 2018-06-07T23:42:20.846Z] 23:42:20     INFO - Buffered messages finished
[task 2018-06-07T23:42:20.847Z] 23:42:20     INFO - Running manifest: browser/components/enterprisepolicies/tests/browser/disable_developer_tools/browser.ini
[task 2018-06-07T23:42:20.848Z] 23:42:20     INFO - The following extra prefs will be set:
[task 2018-06-07T23:42:20.848Z] 23:42:20     INFO -   browser.policies.alternatePath='<test-root>/browser/components/enterprisepolicies/tests/browser/disable_developer_tools/config_disable_developer_tools.json'
[task 2018-06-07T23:42:20.877Z] 23:42:20     INFO -  Setting pipeline to PAUSED ...
[task 2018-06-07T23:42:20.881Z] 23:42:20     INFO -  Pipeline is PREROLLING ...
[task 2018-06-07T23:42:20.882Z] 23:42:20     INFO -  Pipeline is PREROLLED ...
[task 2018-06-07T23:42:20.883Z] 23:42:20     INFO -  Setting pipeline to PLAYING ...
[task 2018-06-07T23:42:20.883Z] 23:42:20     INFO -  New clock: GstSystemClock
[task 2018-06-07T23:42:20.922Z] 23:42:20     INFO -  Got EOS from element "pipeline0".
[task 2018-06-07T23:42:20.922Z] 23:42:20     INFO -  Execution ended after 0:00:00.033357842
[task 2018-06-07T23:42:20.922Z] 23:42:20     INFO -  Setting pipeline to PAUSED ...
[task 2018-06-07T23:42:20.922Z] 23:42:20     INFO -  Setting pipeline to READY ...
[task 2018-06-07T23:42:20.922Z] 23:42:20     INFO -  (gst-launch-1.0:2457): GStreamer-CRITICAL **: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2018-06-07T23:42:20.922Z] 23:42:20     INFO -  Setting pipeline to NULL ...
[task 2018-06-07T23:42:20.922Z] 23:42:20     INFO -  Freeing pipeline ...
[task 2018-06-07T23:42:21.081Z] 23:42:21     INFO -  pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2018-06-07T23:42:21.199Z] 23:42:21     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/tmpx26KF1.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-06-07T23:42:21.199Z] 23:42:21     INFO - runtests.py | Server pid: 2480
[task 2018-06-07T23:42:21.216Z] 23:42:21     INFO - runtests.py | Websocket server pid: 2483
[task 2018-06-07T23:42:21.236Z] 23:42:21     INFO - runtests.py | SSL tunnel pid: 2486
[task 2018-06-07T23:42:21.395Z] 23:42:21     INFO - runtests.py | Running with e10s: True
[task 2018-06-07T23:42:21.395Z] 23:42:21     INFO - runtests.py | Running tests: start.
[task 2018-06-07T23:42:21.396Z] 23:42:21     INFO - 
[task 2018-06-07T23:42:21.411Z] 23:42:21     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpx26KF1.mozrunner
[task 2018-06-07T23:42:21.420Z] 23:42:21     INFO - runtests.py | Application pid: 2506

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1467635&startday=2018-06-04&endday=2018-06-10&tree=trunk

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-central: 1
* autoland: 1

Platform breakdown:
* linux64-nightly: 1
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1467635&startday=2018-06-18&endday=2018-06-24&tree=trunk

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:
* try: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1467635&startday=2018-06-25&endday=2018-07-01&tree=trunk

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-release: 2

Platform breakdown:
* linux32-nightly: 1
* linux64-nightly: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1467635&startday=2018-09-17&endday=2018-09-23&tree=all