Closed Bug 1620116 Opened 5 years ago Closed 5 years ago

Intermittent toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resor

Categories

(Toolkit :: Password Manager, defect, P5)

defect

Tracking

()

RESOLVED FIXED
81 Branch
Tracking Status
firefox81 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: jmaher)

Details

(Keywords: intermittent-failure)

Attachments

(1 file)

Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=291731875&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/NHJaAqHVQGSVnKHrRKy3vQ/runs/0/artifacts/public/logs/live_backing.log


[task 2020-03-05T00:51:57.756Z] 00:51:57 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js
[task 2020-03-05T00:52:43.256Z] 00:52:43 INFO - TEST-INFO | started process screencapture
[task 2020-03-05T00:52:43.358Z] 00:52:43 INFO - TEST-INFO | screencapture: exit 0
[task 2020-03-05T00:52:43.359Z] 00:52:43 INFO - <snipped 507 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2020-03-05T00:52:43.359Z] 00:52:43 INFO - Buffered messages logged at 00:52:33
[task 2020-03-05T00:52:43.359Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Main action label matches update doorhanger. -
[task 2020-03-05T00:52:43.359Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | password-change doorhanger was created -
[task 2020-03-05T00:52:43.360Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check notification dismissed property -
[task 2020-03-05T00:52:43.360Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check icon extraAttr attribute -
[task 2020-03-05T00:52:43.361Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Confirm popup is open -
[task 2020-03-05T00:52:43.362Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Doorhanger password field has generated 15-char value -
[task 2020-03-05T00:52:43.367Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Doorhanger username field was popuplated -
[task 2020-03-05T00:52:43.367Z] 00:52:43 INFO - clicking change button
[task 2020-03-05T00:52:43.368Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Looking for action at index button -
[task 2020-03-05T00:52:43.368Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | at least one notification displayed -
[task 2020-03-05T00:52:43.368Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | 1 notification(s) -
[task 2020-03-05T00:52:43.368Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Triggering main action -
[task 2020-03-05T00:52:43.368Z] 00:52:43 INFO - passwordmgr-storage-changed, action: modifyLogin
[task 2020-03-05T00:52:43.368Z] 00:52:43 INFO - subject: {"length":2}
[task 2020-03-05T00:52:43.369Z] 00:52:43 INFO - passwordmgr-storage-changed, action: removeLogin
[task 2020-03-05T00:52:43.369Z] 00:52:43 INFO - subject: {"guid":"{42c538e3-9fa1-4248-a0f7-274243d270be}","timeCreated":1583369550374,"timeLastUsed":1583369550374,"timePasswordChanged":1583369550374,"timesUsed":1,"username":"","password":"8FLRZKasapQRSfr","displayOrigin":"example.com","origin":"https://example.com","hostname":"https://example.com","formActionOrigin":"https://example.com","formSubmitURL":"https://example.com","httpRealm":null,"usernameField":"","passwordField":""}
[task 2020-03-05T00:52:43.369Z] 00:52:43 INFO - Waiting for modifyLogin promise
[task 2020-03-05T00:52:43.369Z] 00:52:43 INFO - Waiting for removeLogin promise
[task 2020-03-05T00:52:43.370Z] 00:52:43 INFO - storage-change promises resolved
[task 2020-03-05T00:52:43.370Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check actual number of logins matches the number of provided expected property-sets -
[task 2020-03-05T00:52:43.370Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check username -
[task 2020-03-05T00:52:43.370Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check password -
[task 2020-03-05T00:52:43.370Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check timePasswordChanged -
[task 2020-03-05T00:52:43.371Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check timeCreated -
[task 2020-03-05T00:52:43.371Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | No doorhanger is open -
[task 2020-03-05T00:52:43.371Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | No notifications - [task 2020-03-05T00:52:43.371Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Generated password cache entry has the expected password value -
[task 2020-03-05T00:52:43.371Z] 00:52:43 INFO - Leaving test bound autosaved_login_updated_to_existing_login_onsubmit
[task 2020-03-05T00:52:43.371Z] 00:52:43 INFO - Entering test bound form_change_from_autosaved_login_to_existing_login
[task 2020-03-05T00:52:43.371Z] 00:52:43 INFO - Buffered messages logged at 00:52:34
[task 2020-03-05T00:52:43.373Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check autofilled password value - "xyzpassword" == "xyzpassword" -
[task 2020-03-05T00:52:43.373Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check autofilled username value - "user1" == "user1" -
[task 2020-03-05T00:52:43.373Z] 00:52:43 INFO - waiting to fill generated password using context menu
[task 2020-03-05T00:52:43.374Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | generated password item is visible -
[task 2020-03-05T00:52:43.376Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | separator is visible -
[task 2020-03-05T00:52:43.376Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Got popup -
[task 2020-03-05T00:52:43.376Z] 00:52:43 INFO - Buffered messages logged at 00:52:35
[task 2020-03-05T00:52:43.376Z] 00:52:43 INFO - Clicking the generated password AC item
[task 2020-03-05T00:52:43.376Z] 00:52:43 INFO - Waiting for the content input value to change
[task 2020-03-05T00:52:43.377Z] 00:52:43 INFO - Waiting for the passwordGeneratedPromise
[task 2020-03-05T00:52:43.377Z] 00:52:43 INFO - waiting for dismissed password-change notification
[task 2020-03-05T00:52:43.377Z] 00:52:43 INFO - verifyConfirmationHint
[task 2020-03-05T00:52:43.377Z] 00:52:43 INFO - verifyConfirmationHint, hintPromiseShown resolved
[task 2020-03-05T00:52:43.377Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Hint should be anchored on the password notification icon -
[task 2020-03-05T00:52:43.379Z] 00:52:43 INFO - verifyConfirmationHint, assertion ok, wait for poopuphidden
[task 2020-03-05T00:52:43.379Z] 00:52:43 INFO - Buffered messages logged at 00:52:37
[task 2020-03-05T00:52:43.379Z] 00:52:43 INFO - verifyConfirmationHint, /popuphidden
[task 2020-03-05T00:52:43.379Z] 00:52:43 INFO - waiting for addLogin
[task 2020-03-05T00:52:43.380Z] 00:52:43 INFO - addLogin promise resolved
[task 2020-03-05T00:52:43.380Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check actual number of logins matches the number of provided expected property-sets -
[task 2020-03-05T00:52:43.381Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check timesUsed -
[task 2020-03-05T00:52:43.382Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check passwordLength -
[task 2020-03-05T00:52:43.382Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check username -
[task 2020-03-05T00:52:43.382Z] 00:52:43 INFO - user1LoginSnapshot, guid: {9172eda1-8b13-e848-aa4e-de7a85c00cd8}
[task 2020-03-05T00:52:43.382Z] 00:52:43 INFO - autoSavedLogin, guid: {4f0931bb-55fb-e845-aa6d-6c9ae24b28fc}
[task 2020-03-05T00:52:43.382Z] 00:52:43 INFO - verifyLogins ok
[task 2020-03-05T00:52:43.384Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Got the cached generated password entry for https://example.com -
[task 2020-03-05T00:52:43.384Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Cached password matches the auto-saved login password -
[task 2020-03-05T00:52:43.384Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Cached password guid matches the auto-saved login guid -
[task 2020-03-05T00:52:43.384Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Looking for password-change popup notification -
[task 2020-03-05T00:52:43.384Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Notification type matches. -
[task 2020-03-05T00:52:43.384Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Main action label matches update doorhanger. -
[task 2020-03-05T00:52:43.385Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | password-change doorhanger was created -
[task 2020-03-05T00:52:43.394Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check notification dismissed property -
[task 2020-03-05T00:52:43.394Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check icon extraAttr attribute -
[task 2020-03-05T00:52:43.394Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Confirm popup is open -
[task 2020-03-05T00:52:43.394Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Doorhanger password field has generated 15-char value -
[task 2020-03-05T00:52:43.394Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Doorhanger username field was popuplated -
[task 2020-03-05T00:52:43.395Z] 00:52:43 INFO - Buffered messages logged at 00:52:38
[task 2020-03-05T00:52:43.395Z] 00:52:43 INFO - updating form
[task 2020-03-05T00:52:43.395Z] 00:52:43 INFO - changeContentFormValues, update: #form-basic-password, to: xyzpassword[task 2020-03-05T00:52:43.395Z] 00:52:43 INFO - waiting for changedPromise
[task 2020-03-05T00:52:43.395Z] 00:52:43 INFO - change event on #form-basic-password: xyzpassword
[task 2020-03-05T00:52:43.395Z] 00:52:43 INFO - changeContentFormValues, update: #form-basic-username, to: user1
[task 2020-03-05T00:52:43.395Z] 00:52:43 INFO - waiting for changedPromise
[task 2020-03-05T00:52:43.396Z] 00:52:43 INFO - change event on #form-basic-username: user1
[task 2020-03-05T00:52:43.396Z] 00:52:43 INFO - form edited, waiting for test notification of PasswordEditedOrGenerated
[task 2020-03-05T00:52:43.396Z] 00:52:43 INFO - Resolved listenForTestNotification promise
[task 2020-03-05T00:52:43.396Z] 00:52:43 INFO - Buffered messages logged at 00:52:42
[task 2020-03-05T00:52:43.396Z] 00:52:43 INFO - Got expected timeout from the waitForCondition:
[task 2020-03-05T00:52:43.396Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | No confirmation hint shown -
[task 2020-03-05T00:52:43.397Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Looking for password-change popup notification -
[task 2020-03-05T00:52:43.397Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Notification type matches. -
[task 2020-03-05T00:52:43.397Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Main action label matches update doorhanger. -
[task 2020-03-05T00:52:43.397Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | password-change doorhanger was created -
[task 2020-03-05T00:52:43.397Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check notification dismissed property -
[task 2020-03-05T00:52:43.397Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check icon extraAttr attribute -
[task 2020-03-05T00:52:43.397Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Confirm popup is open -
[task 2020-03-05T00:52:43.398Z] 00:52:43 INFO - Waiting for password value to be 11 chars long
[task 2020-03-05T00:52:43.398Z] 00:52:43 INFO - Buffered messages logged at 00:52:43
[task 2020-03-05T00:52:43.398Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Doorhanger password field has generated 15-char value -
[task 2020-03-05T00:52:43.398Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Doorhanger username field was popuplated -
[task 2020-03-05T00:52:43.398Z] 00:52:43 INFO - Now submit the form
[task 2020-03-05T00:52:43.399Z] 00:52:43 INFO - form submitted, waiting for storage changed
[task 2020-03-05T00:52:43.399Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check actual number of logins matches the number of provided expected property-sets -
[task 2020-03-05T00:52:43.399Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check username -
[task 2020-03-05T00:52:43.399Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check password -
[task 2020-03-05T00:52:43.399Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check timeLastUsed -
[task 2020-03-05T00:52:43.399Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check timeCreated -
[task 2020-03-05T00:52:43.400Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check username -
[task 2020-03-05T00:52:43.400Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check password -
[task 2020-03-05T00:52:43.400Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Check timeCreated -
[task 2020-03-05T00:52:43.400Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | No doorhanger is open -
[task 2020-03-05T00:52:43.400Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | No notifications -
[task 2020-03-05T00:52:43.400Z] 00:52:43 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | Generated password cache entry has the expected password value -
[task 2020-03-05T00:52:43.400Z] 00:52:43 INFO - Leaving test bound form_change_from_autosaved_login_to_existing_login
[task 2020-03-05T00:52:43.401Z] 00:52:43 INFO - Buffered messages finished
[task 2020-03-05T00:52:43.401Z] 00:52:43 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_doorhanger_generated_password.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

extend timeout of browser_doorhanger_generated_password.js, common hit on osx.

Assignee: nobody → jmaher
Pushed by jmaher@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/71dfcb93f8d8 extend timeout of browser_doorhanger_generated_password.js. r=bdanforth
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → FIXED
Target Milestone: --- → 81 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: