1507391 - Intermittent toolkit/mozapps/update/tests/browser/browser_doorhanger_sp_patch_partialApplyFailure.js | Test timed out -

Status: RESOLVED DUPLICATE of bug 1520672

(Toolkit :: Application Update, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

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

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

04:18:17     INFO - TEST-START | toolkit/mozapps/update/tests/browser/browser_updatesPartialPatchApplyFailure.js
04:18:17     INFO - GECKO(1616) | *** AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: C:\ProgramData\Mozilla\updates\5366C8F09728951C\active-update.xml
04:18:17     INFO - GECKO(1616) | *** AUS:SVC UpdateManager:_loadXMLFileIntoArray - XML file does not exist. path: C:\ProgramData\Mozilla\updates\5366C8F09728951C\active-update.xml
04:18:17     INFO - GECKO(1616) | *** AUS:SVC UpdateManager:_loadXMLFileIntoArray - update xml file updates.xml exists but doesn't contain any updates
04:18:17     INFO - GECKO(1616) | *** AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
04:18:17     INFO - GECKO(1616) | *** AUS:SVC isServiceInstalled - returning true
04:18:17     INFO - GECKO(1616) | *** AUS:SVC shouldUseService - returning true
04:18:17     INFO - GECKO(1616) | *** AUS:SVC getCanApplyUpdates - bypass the write checks because the Windows Maintenance Service can be used
04:18:17     INFO - GECKO(1616) | *** AUS:SVC readStatusFile - status: failed: 4, path: C:\ProgramData\Mozilla\updates\5366C8F09728951C\updates\0\update.status
04:18:17     INFO - GECKO(1616) | *** AUS:SVC readStringFromFile - file doesn't exist: C:\ProgramData\Mozilla\updates\5366C8F09728951C\updates\0\bt.result
04:18:17     INFO - GECKO(1616) | *** AUS:SVC readBinaryTransparencyResult - result: null, path: C:\ProgramData\Mozilla\updates\5366C8F09728951C\updates\0\bt.result
04:18:17     INFO - GECKO(1616) | *** AUS:SVC UpdateService:_postUpdateProcessing - previous patch failed and no patch available
04:18:17     INFO - GECKO(1616) | *** AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: C:\ProgramData\Mozilla\updates\5366C8F09728951C\active-update.xml
04:18:17     INFO - GECKO(1616) | *** AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: C:\ProgramData\Mozilla\updates\5366C8F09728951C\updates.xml
04:19:02     INFO - TEST-INFO | started process screenshot
04:19:02     INFO - TEST-INFO | screenshot: exit 0
04:19:02     INFO - Buffered messages logged at 04:18:17
04:19:02     INFO - Entering test bound setDefaults
04:19:02     INFO - Leaving test bound setDefaults
04:19:02     INFO - Entering test bound testPartialPatchApplyFailure
04:19:02     INFO - Console message: AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: C:\ProgramData\Mozilla\updates\5366C8F09728951C\active-update.xml
04:19:02     INFO - Console message: AUS:SVC UpdateManager:_loadXMLFileIntoArray - XML file does not exist. path: C:\ProgramData\Mozilla\updates\5366C8F09728951C\active-update.xml
04:19:02     INFO - Console message: AUS:SVC UpdateManager:_loadXMLFileIntoArray - update xml file updates.xml exists but doesn't contain any updates
04:19:02     INFO - Console message: AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
04:19:02     INFO - Console message: AUS:SVC isServiceInstalled - returning true
04:19:02     INFO - Console message: AUS:SVC shouldUseService - returning true
04:19:02     INFO - Console message: AUS:SVC getCanApplyUpdates - bypass the write checks because the Windows Maintenance Service can be used
04:19:02     INFO - Console message: AUS:SVC readStatusFile - status: failed: 4, path: C:\ProgramData\Mozilla\updates\5366C8F09728951C\updates\0\update.status
04:19:02     INFO - Console message: AUS:SVC readStringFromFile - file doesn't exist: C:\ProgramData\Mozilla\updates\5366C8F09728951C\updates\0\bt.result
04:19:02     INFO - Console message: AUS:SVC readBinaryTransparencyResult - result: null, path: C:\ProgramData\Mozilla\updates\5366C8F09728951C\updates\0\bt.result
04:19:02     INFO - Console message: AUS:SVC UpdateService:_postUpdateProcessing - previous patch failed and no patch available
04:19:02     INFO - Console message: AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: C:\ProgramData\Mozilla\updates\5366C8F09728951C\active-update.xml
04:19:02     INFO - Console message: AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: C:\ProgramData\Mozilla\updates\5366C8F09728951C\updates.xml
04:19:02     INFO - Buffered messages finished
04:19:02     INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/browser/browser_updatesPartialPatchApplyFailure.js | Test timed out - 
04:19:02     INFO - GECKO(1616) | MEMORY STAT | vsize 733MB | vsizeMaxContiguous 385MB | residentFast 225MB | heapAllocated 79MB
04:19:02     INFO - TEST-OK | toolkit/mozapps/update/tests/browser/browser_updatesPartialPatchApplyFailure.js | took 45026ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1507391&startday=2018-11-12&endday=2018-11-18&tree=all

Comment 2

[Firefox Bug Husbandry Bot]

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

Comment 3

[Andreea Pavel [:apavel]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=220631843&repo=mozilla-inbound&lineNumber=6494

09:51:04 INFO - TEST-START | toolkit/mozapps/update/tests/browser/browser_updatesPartialPatchApplyFailure.js
09:51:04 INFO - GECKO(1270) | *** AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/active-update.xml
09:51:04 INFO - GECKO(1270) | *** AUS:SVC UpdateManager:_loadXMLFileIntoArray - XML file does not exist. path: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/active-update.xml
09:51:04 INFO - GECKO(1270) | *** AUS:SVC UpdateManager:_loadXMLFileIntoArray - update xml file updates.xml exists but doesn't contain any updates
09:51:04 INFO - GECKO(1270) | *** AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
09:51:04 INFO - GECKO(1270) | *** AUS:SVC getCanApplyUpdates - bypass the write since elevation can be used on Mac OS X
09:51:04 INFO - GECKO(1270) | *** AUS:SVC readStatusFile - status: failed: 4, path: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/updates/0/update.status
09:51:04 INFO - GECKO(1270) | *** AUS:SVC readStringFromFile - file doesn't exist: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/updates/0/bt.result
09:51:04 INFO - GECKO(1270) | *** AUS:SVC readBinaryTransparencyResult - result: null, path: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/updates/0/bt.result
09:51:04 INFO - GECKO(1270) | *** AUS:SVC UpdateService:_postUpdateProcessing - previous patch failed and no patch available
09:51:04 INFO - GECKO(1270) | *** AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/active-update.xml
09:51:04 INFO - GECKO(1270) | *** AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/updates.xml
09:51:23 INFO - GECKO(1270) | 2019-01-08 09:51:23.485 firefox[1270:22060] Persistent UI failed to open file file:///Users/cltbld/Library/Saved%20Application%20State/org.mozilla.nightly.savedState/window_1.data: No such file or directory (2)
09:51:49 INFO - TEST-INFO | started process screencapture
09:51:50 INFO - TEST-INFO | screencapture: exit 0
09:51:50 INFO - Buffered messages logged at 09:51:04
09:51:50 INFO - Entering test bound setDefaults
09:51:50 INFO - Leaving test bound setDefaults
09:51:50 INFO - Entering test bound testPartialPatchApplyFailure
09:51:50 INFO - Console message: AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/active-update.xml
09:51:50 INFO - Console message: AUS:SVC UpdateManager:_loadXMLFileIntoArray - XML file does not exist. path: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/active-update.xml
09:51:50 INFO - Console message: AUS:SVC UpdateManager:_loadXMLFileIntoArray - update xml file updates.xml exists but doesn't contain any updates
09:51:50 INFO - Console message: AUS:SVC UpdateService.canCheckForUpdates - able to check for updates
09:51:50 INFO - Console message: AUS:SVC getCanApplyUpdates - bypass the write since elevation can be used on Mac OS X
09:51:50 INFO - Console message: AUS:SVC readStatusFile - status: failed: 4, path: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/updates/0/update.status
09:51:50 INFO - Console message: AUS:SVC readStringFromFile - file doesn't exist: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/updates/0/bt.result
09:51:50 INFO - Console message: AUS:SVC readBinaryTransparencyResult - result: null, path: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/updates/0/bt.result
09:51:50 INFO - Console message: AUS:SVC UpdateService:_postUpdateProcessing - previous patch failed and no patch available
09:51:50 INFO - Console message: AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/active-update.xml
09:51:50 INFO - Console message: AUS:SVC UpdateManager:_writeUpdatesToXMLFile - no updates to write. removing file: /Users/cltbld/Library/Caches/Mozilla/updates/Users/cltbld/tasks/task_1546968621/build/application/Firefox Nightly/updates.xml
09:51:50 INFO - Buffered messages finished
09:51:50 INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/browser/browser_updatesPartialPatchApplyFailure.js | Test timed out -
09:51:50 INFO - GECKO(1270) | MEMORY STAT | vsize 4388MB | residentFast 329MB | heapAllocated 77MB
09:51:50 INFO - TEST-OK | toolkit/mozapps/update/tests/browser/browser_updatesPartialPatchApplyFailure.js | took 45023ms

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-inbound: 1

Platform breakdown:

• osx-10-10: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1507391&startday=2019-01-07&endday=2019-01-13&tree=all

Comment 5

[Robert Strong (they/them - no direct email)]

Test name changed

Comment 6

[Robert Strong (they/them - no direct email)]

This is likely fixed by the patch in bug 1434033 which also fixed bug 1520672.

Comment 7

[Daniel Varga [:dvarga]]

This seems to happen again on autoland: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=243682346&repo=autoland&lineNumber=5906

17:24:31     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 4
17:24:31     INFO - Buffered messages logged at 17:22:16
17:24:31     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 3
17:24:31     INFO - Buffered messages logged at 17:23:01
17:24:31     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 2
17:24:31     INFO - Buffered messages logged at 17:23:46
17:24:31     INFO - Longer timeout required, waiting longer...  Remaining timeouts: 1
17:24:31     INFO - Buffered messages finished
17:24:31     INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/browser/browser_doorhanger_sp_patch_partialApplyFailure.js | Test timed out - 
17:24:31     INFO - GECKO(4076) | MEMORY STAT | vsize 2104262MB | vsizeMaxContiguous 65760584MB | residentFast 288MB | heapAllocated 69MB
17:24:31     INFO - TEST-OK | toolkit/mozapps/update/tests/browser/browser_doorhanger_sp_patch_partialApplyFailure.js | took 450121ms
17:24:31     INFO - checking window state
17:24:31     INFO - TEST-START | toolkit/mozapps/update/tests/browser/browser_doorhanger_sp_patch_partialApplyFailure_complete.js
17:24:32     INFO - Not taking screenshot here: see the one that was previously logged
17:24:32     INFO - Buffered messages logged at 17:24:31
17:24:32     INFO - Entering test bound setupTestCommon
17:24:32     INFO - Leaving test bound setupTestCommon
17:24:32     INFO - Entering test bound doorhanger_sp_patch_partialApplyFailure_complete
17:24:32     INFO - Buffered messages finished
17:24:32     INFO - TEST-UNEXPECTED-FAIL | toolkit/mozapps/update/tests/browser/browser_doorhanger_sp_patch_partialApplyFailure.js | Uncaught exception received from previously timed out test - at chrome://mochitests/content/browser/toolkit/mozapps/update/tests/browser/head.js:533 - ReferenceError: is is not defined
17:24:32     INFO - Stack trace:
17:24:32     INFO - runDoorhangerUpdateTest/processDoorhangerStep/<@chrome://mochitests/content/browser/toolkit/mozapps/update/tests/browser/head.js:533:7
17:24:32     INFO - async*processDoorhangerStep@chrome://mochitests/content/browser/toolkit/mozapps/update/tests/browser/head.js:560:7
17:24:32     INFO - runDoorhangerUpdateTest/<@chrome://mochitests/content/browser/toolkit/mozapps/update/tests/browser/head.js:598:13
17:24:32     INFO - async*runDoorhangerUpdateTest@chrome://mochitests/content/browser/toolkit/mozapps/update/tests/browser/head.js:600:5
17:24:32     INFO - doorhanger_sp_patch_partialApplyFailure@chrome://mochitests/content/browser/toolkit/mozapps/update/tests/browser/browser_doorhanger_sp_patch_partialApplyFailure.js:16:9
17:24:32     INFO - Tester_execTest/<@chrome://mochikit/content/browser-test.js:1116:34
17:24:32     INFO - async*Tester_execTest@chrome://mochikit/content/browser-test.js:1144:12
17:24:32     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1005:14
17:24:32     INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59
17:24:32     INFO - TEST-PASS | toolkit/mozapps/update/tests/browser/browser_doorhanger_sp_patch_partialApplyFailure_complete.js | The right notification showed up. - 
17:24:32     INFO - TEST-PASS | toolkit/mozapps/update/tests/browser/browser_doorhanger_sp_patch_partialApplyFailure_complete.js | There should be an active update - 
17:24:32     INFO - TEST-PASS | toolkit/mozapps/update/tests/browser/browser_doorhanger_sp_patch_partialApplyFailure_complete.js | The active update state should equal pending - 
17:24:32     INFO - TEST-PASS | toolkit/mozapps/update/tests/browser/browser_doorhanger_sp_patch_partialApplyFailure_complete.js | update-restart notification is showing - 
17:24:32     INFO - Leaving test bound doorhanger_sp_patch_partialApplyFailure_complete

Comment 8

[Robert Strong (they/them - no direct email)]

dvarga, please file a new bug. This one was specific to Linux and that failure is on Windows.

Comment 9

[Daniel Varga [:dvarga]]

Ok, will do so, thank you for the info.