1468685 - Intermittent TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/get_element_property/user_prompts.py | expected OK

Status: RESOLVED INCOMPLETE

(Remote Protocol :: Marionette, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: rgurzau [at] mozilla.com

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

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

[task 2018-06-14T04:37:33.071Z] 04:37:33     INFO - PID 6116 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-06-14T04:37:33.073Z] 04:37:33     INFO - PID 6116 | 
[task 2018-06-14T04:37:33.075Z] 04:37:33     INFO - PID 6116 | [Child 6503, Main Thread] WARNING: NS_ENSURE_TRUE(maybeContext) failed: file /builds/worker/workspace/build/src/xpcom/threads/nsThread.cpp, line 803
[task 2018-06-14T04:37:33.506Z] 04:37:33     INFO - PID 6116 | nsStringStats
[task 2018-06-14T04:37:33.508Z] 04:37:33     INFO - PID 6116 |  => mAllocCount:           6771
[task 2018-06-14T04:37:33.511Z] 04:37:33     INFO - PID 6116 |  => mReallocCount:          136
[task 2018-06-14T04:37:33.513Z] 04:37:33     INFO - PID 6116 |  => mFreeCount:            6771
[task 2018-06-14T04:37:33.516Z] 04:37:33     INFO - PID 6116 |  => mShareCount:           3876
[task 2018-06-14T04:37:33.519Z] 04:37:33     INFO - PID 6116 |  => mAdoptCount:            157
[task 2018-06-14T04:37:33.521Z] 04:37:33     INFO - PID 6116 |  => mAdoptFreeCount:        157
[task 2018-06-14T04:37:33.523Z] 04:37:33     INFO - PID 6116 |  => Process ID: 6503, Thread ID: 4147107584
[task 2018-06-14T04:37:34.126Z] 04:37:34     INFO - PID 6116 | 1528951054121	Marionette	DEBUG	Received observer notification xpcom-will-shutdown
[task 2018-06-14T04:37:34.132Z] 04:37:34     INFO - PID 6116 | 1528951054123	Marionette	DEBUG	Resetting recommended pref apz.content_response_timeout
[task 2018-06-14T04:37:34.136Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.138Z] 04:37:34     INFO - PID 6116 | 1528951054124	Marionette	DEBUG	Resetting recommended pref browser.download.panel.shown
[task 2018-06-14T04:37:34.141Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.145Z] 04:37:34     INFO - PID 6116 | 1528951054125	Marionette	DEBUG	Resetting recommended pref browser.pagethumbnails.capturing_disabled
[task 2018-06-14T04:37:34.147Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.151Z] 04:37:34     INFO - PID 6116 | 1528951054127	Marionette	DEBUG	Resetting recommended pref browser.search.update
[task 2018-06-14T04:37:34.154Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.158Z] 04:37:34     INFO - PID 6116 | 1528951054129	Marionette	DEBUG	Resetting recommended pref toolkit.cosmeticAnimations.enabled
[task 2018-06-14T04:37:34.161Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.163Z] 04:37:34     INFO - PID 6116 | 1528951054147	Marionette	DEBUG	Resetting recommended pref browser.tabs.disableBackgroundZombification
[task 2018-06-14T04:37:34.168Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.170Z] 04:37:34     INFO - PID 6116 | 1528951054149	Marionette	DEBUG	Resetting recommended pref browser.tabs.warnOnCloseOtherTabs
[task 2018-06-14T04:37:34.175Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.177Z] 04:37:34     INFO - PID 6116 | 1528951054150	Marionette	DEBUG	Resetting recommended pref browser.tabs.warnOnOpen
[task 2018-06-14T04:37:34.181Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.185Z] 04:37:34     INFO - PID 6116 | 1528951054154	Marionette	DEBUG	Resetting recommended pref browser.usedOnWindows10.introURL
[task 2018-06-14T04:37:34.190Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.193Z] 04:37:34     INFO - PID 6116 | 1528951054161	Marionette	DEBUG	Resetting recommended pref browser.urlbar.suggest.searches
[task 2018-06-14T04:37:34.197Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.201Z] 04:37:34     INFO - PID 6116 | 1528951054182	Marionette	DEBUG	Resetting recommended pref datareporting.policy.dataSubmissionPolicyAccepted
[task 2018-06-14T04:37:34.202Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.206Z] 04:37:34     INFO - PID 6116 | 1528951054184	Marionette	DEBUG	Resetting recommended pref dom.disable_beforeunload
[task 2018-06-14T04:37:34.208Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.212Z] 04:37:34     INFO - PID 6116 | 1528951054185	Marionette	DEBUG	Resetting recommended pref dom.disable_open_during_load
[task 2018-06-14T04:37:34.217Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.219Z] 04:37:34     INFO - PID 6116 | 1528951054186	Marionette	DEBUG	Resetting recommended pref dom.file.createInChild
[task 2018-06-14T04:37:34.222Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.227Z] 04:37:34     INFO - PID 6116 | 1528951054187	Marionette	DEBUG	Resetting recommended pref dom.max_chrome_script_run_time
[task 2018-06-14T04:37:34.229Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.231Z] 04:37:34     INFO - PID 6116 | 1528951054188	Marionette	DEBUG	Resetting recommended pref dom.max_script_run_time
[task 2018-06-14T04:37:34.236Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.238Z] 04:37:34     INFO - PID 6116 | 1528951054207	Marionette	DEBUG	Resetting recommended pref extensions.getAddons.cache.enabled
[task 2018-06-14T04:37:34.241Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.247Z] 04:37:34     INFO - PID 6116 | 1528951054208	Marionette	DEBUG	Resetting recommended pref extensions.webservice.discoverURL
[task 2018-06-14T04:37:34.249Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.251Z] 04:37:34     INFO - PID 6116 | 1528951054211	Marionette	DEBUG	Resetting recommended pref network.http.prompt-temp-redirect
[task 2018-06-14T04:37:34.253Z] 04:37:34     INFO - PID 6116 | 1528951054212	Marionette	DEBUG	Resetting recommended pref network.http.speculative-parallel-limit
[task 2018-06-14T04:37:34.255Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.257Z] 04:37:34     INFO - PID 6116 | 1528951054213	Marionette	DEBUG	Resetting recommended pref security.fileuri.strict_origin_policy
[task 2018-06-14T04:37:34.260Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: cannot post event if not initialized: file /builds/worker/workspace/build/src/netwerk/protocol/http/nsHttpConnectionMgr.cpp, line 301
[task 2018-06-14T04:37:34.263Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: cannot post event if not initialized: file /builds/worker/workspace/build/src/netwerk/protocol/http/nsHttpConnectionMgr.cpp, line 301
[task 2018-06-14T04:37:34.266Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.268Z] 04:37:34     INFO - PID 6116 | 1528951054214	Marionette	DEBUG	Resetting recommended pref security.notification_enable_delay
[task 2018-06-14T04:37:34.270Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: cannot post event if not initialized: file /builds/worker/workspace/build/src/netwerk/protocol/http/nsHttpConnectionMgr.cpp, line 301
[task 2018-06-14T04:37:34.275Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: cannot post event if not initialized: file /builds/worker/workspace/build/src/netwerk/protocol/http/nsHttpConnectionMgr.cpp, line 301
[task 2018-06-14T04:37:34.276Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.279Z] 04:37:34     INFO - PID 6116 | 1528951054214	Marionette	DEBUG	Resetting recommended pref signon.autofillForms
[task 2018-06-14T04:37:34.281Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.283Z] 04:37:34     INFO - PID 6116 | 1528951054217	Marionette	DEBUG	Resetting recommended pref signon.rememberSignons
[task 2018-06-14T04:37:34.286Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: Setting user pref after profile shutdown.: file /builds/worker/workspace/build/src/modules/libpref/Preferences.cpp, line 2652
[task 2018-06-14T04:37:34.289Z] 04:37:34     INFO - PID 6116 | 1528951054222	Marionette	DEBUG	Remote service is inactive
[task 2018-06-14T04:37:34.291Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: NS_ENSURE_TRUE(mDB) failed: file /builds/worker/workspace/build/src/netwerk/cache/nsDiskCacheDeviceSQL.cpp, line 1421
[task 2018-06-14T04:37:34.987Z] 04:37:34     INFO - PID 6116 | [Parent 6401, Main Thread] WARNING: NS_ENSURE_TRUE(maybeContext) failed: file /builds/worker/workspace/build/src/xpcom/threads/nsThread.cpp, line 803
[task 2018-06-14T04:37:38.209Z] 04:37:38     INFO - TEST-UNEXPECTED-TIMEOUT | /webdriver/tests/get_element_property/user_prompts.py | expected OK

Comment 1

[Henrik Skupin [:whimboo][⌚️UTC+2]]

There is a delayed startup of Firefox for restarts. So this depends on bug 1460193.

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 2

Platform breakdown:
* linux32: 2

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-central: 1

Platform breakdown:
* linux32: 1

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

Comment 4

[Intermittent Failures Robot]

1 failures in 653 pushes (0.002 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=1468685&startday=2018-07-16&endday=2018-07-22&tree=trunk

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux64: 1

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

Comment 6

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 7

[BMO Automation]

Moving bug to Remote Protocol::Marionette component per bug 1815831.