1618922 - Intermittent toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Test timed out -

Status: RESOLVED INCOMPLETE

(Toolkit :: Password Manager, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-02-28T16:57:26.561Z] 16:57:26 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | The second tab should be visible -
[task 2020-02-28T16:57:26.562Z] 16:57:26 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | The first tab should be backgrounded -
[task 2020-02-28T16:57:26.563Z] 16:57:26 INFO - Buffered messages logged at 16:56:06
[task 2020-02-28T16:57:26.564Z] 16:57:26 INFO - observeMasterPasswordDialog, caught exception from topicObserved: Wait for master password dialog - timed out after 50 tries.
[task 2020-02-28T16:57:26.565Z] 16:57:26 INFO - Buffered messages finished
[task 2020-02-28T16:57:26.566Z] 16:57:26 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Test timed out -
[task 2020-02-28T16:57:26.567Z] 16:57:26 INFO - GECKO(7509) | MP change from omgsecret! to
[task 2020-02-28T16:57:26.568Z] 16:57:26 INFO - GECKO(7509) | MEMORY STAT | vsize 3361MB | residentFast 355MB | heapAllocated 102MB
[task 2020-02-28T16:57:26.575Z] 16:57:26 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | took 90105ms
[task 2020-02-28T16:57:26.576Z] 16:57:26 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-28T16:57:26.577Z] 16:57:26 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Found a tab after previous test timed out: about:blank -
[task 2020-02-28T16:57:26.578Z] 16:57:26 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-28T16:57:26.579Z] 16:57:26 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Found a tab after previous test timed out: https://example.org/browser/toolkit/components/passwordmgr/test/browser/form_basic.html -
[task 2020-02-28T16:57:26.580Z] 16:57:26 INFO - GECKO(7509) | [Child 7947: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f74af522800 == 1 [pid = 7947] [id = {662504a5-a5a6-4f0c-8cb3-7aac25146f80}]
[task 2020-02-28T16:57:26.581Z] 16:57:26 INFO - GECKO(7509) | [Child 7947: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f74cc393350) [pid = 7947] [serial = 16] [outer = (nil)]
[task 2020-02-28T16:57:26.582Z] 16:57:26 INFO - GECKO(7509) | [Child 7947: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f74af546400) [pid = 7947] [serial = 17] [outer = 0x7f74cc393350]
[task 2020-02-28T16:57:26.583Z] 16:57:26 INFO - GECKO(7509) | [Child 7947: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7f74af54cc00) [pid = 7947] [serial = 18] [outer = 0x7f74cc393350]
[task 2020-02-28T16:57:26.584Z] 16:57:26 INFO - checking window state

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1618922&startday=2020-02-24&endday=2020-03-01&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux1804-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1618922&startday=2020-03-16&endday=2020-03-22&tree=all

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1618922&startday=2020-03-23&endday=2020-03-29&tree=all

Comment 4

[BugBot [:suhaib / :marco]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 5

[Razvan Maries]

New occurrence: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=309093150&repo=autoland&lineNumber=18300

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1618922&startday=2020-07-06&endday=2020-07-12&tree=all

Comment 7

[BugBot [:suhaib / :marco]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 8

[Razvan Maries]

New occurrence: https://treeherder.mozilla.org/logviewer?job_id=320674767&repo=autoland&lineNumber=2422

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• macosx1014-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1618922&startday=2020-11-02&endday=2020-11-08&tree=all

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• macosx1014-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1618922&startday=2020-11-16&endday=2020-11-22&tree=all

Comment 11

[BugBot [:suhaib / :marco]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 12

[Andreea Pavel [:apavel]]

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=342505959&repo=autoland&lineNumber=2458

[task 2021-06-11T12:29:46.947Z] 12:29:46 INFO - TEST-START | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js
[task 2021-06-11T12:29:53.234Z] 12:29:53 INFO - GECKO(2611) | 2021-06-11 12:29:53.221 firefox[2611:37720] 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)
[task 2021-06-11T12:30:11.515Z] 12:30:11 INFO - GECKO(2611) | MP change from to omgsecret!
[task 2021-06-11T12:31:17.312Z] 12:31:17 INFO - TEST-INFO | started process screencapture
[task 2021-06-11T12:31:17.444Z] 12:31:17 INFO - TEST-INFO | screencapture: exit 0
[task 2021-06-11T12:31:17.444Z] 12:31:17 INFO - Buffered messages logged at 12:29:46
[task 2021-06-11T12:31:17.444Z] 12:31:17 INFO - Entering test bound common_initialize
[task 2021-06-11T12:31:17.444Z] 12:31:17 INFO - Buffered messages logged at 12:29:47
[task 2021-06-11T12:31:17.444Z] 12:31:17 INFO - Leaving test bound common_initialize
[task 2021-06-11T12:31:17.444Z] 12:31:17 INFO - Entering test bound setup
[task 2021-06-11T12:31:17.445Z] 12:31:17 INFO - Leaving test bound setup
[task 2021-06-11T12:31:17.445Z] 12:31:17 INFO - Entering test bound test_processed_form_fired
[task 2021-06-11T12:31:17.445Z] 12:31:17 INFO - Buffered messages logged at 12:29:48
[task 2021-06-11T12:31:17.445Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | The first tab should be foreground -
[task 2021-06-11T12:31:17.445Z] 12:31:17 INFO - Buffered messages logged at 12:29:52
[task 2021-06-11T12:31:17.445Z] 12:31:17 INFO - listenForTestNotification, resolving for message: FormProcessed
[task 2021-06-11T12:31:17.445Z] 12:31:17 INFO - Leaving test bound test_processed_form_fired
[task 2021-06-11T12:31:17.445Z] 12:31:17 INFO - Entering test bound test_processed_form_fired
[task 2021-06-11T12:31:17.445Z] 12:31:17 INFO - Buffered messages logged at 12:29:53
[task 2021-06-11T12:31:17.445Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | The first tab should be foreground -
[task 2021-06-11T12:31:17.446Z] 12:31:17 INFO - Buffered messages logged at 12:29:55
[task 2021-06-11T12:31:17.446Z] 12:31:17 INFO - listenForTestNotification, resolving for message: FormProcessed
[task 2021-06-11T12:31:17.446Z] 12:31:17 INFO - Leaving test bound test_processed_form_fired
[task 2021-06-11T12:31:17.446Z] 12:31:17 INFO - Entering test bound test_defer_autofill_until_visible
[task 2021-06-11T12:31:17.446Z] 12:31:17 INFO - Buffered messages logged at 12:29:58
[task 2021-06-11T12:31:17.446Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | The first tab should be backgrounded -
[task 2021-06-11T12:31:17.446Z] 12:31:17 INFO - Buffered messages logged at 12:29:59
[task 2021-06-11T12:31:17.446Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Observer should not be notified when form is loaded into a hidden document -
[task 2021-06-11T12:31:17.446Z] 12:31:17 INFO - listenForTestNotification, resolving for message: FormProcessed
[task 2021-06-11T12:31:17.446Z] 12:31:17 INFO - Buffered messages logged at 12:30:00
[task 2021-06-11T12:31:17.446Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | The first tab should be foreground -
[task 2021-06-11T12:31:17.447Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Observer should be notified when input's document becomes visible -
[task 2021-06-11T12:31:17.447Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Checking filled username -
[task 2021-06-11T12:31:17.447Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Checking filled password -
[task 2021-06-11T12:31:17.447Z] 12:31:17 INFO - Leaving test bound test_defer_autofill_until_visible
[task 2021-06-11T12:31:17.447Z] 12:31:17 INFO - Entering test bound test_defer_autofill_until_visible
[task 2021-06-11T12:31:17.447Z] 12:31:17 INFO - Buffered messages logged at 12:30:03
[task 2021-06-11T12:31:17.447Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | The first tab should be backgrounded -
[task 2021-06-11T12:31:17.447Z] 12:31:17 INFO - Buffered messages logged at 12:30:04
[task 2021-06-11T12:31:17.447Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Observer should not be notified when form is loaded into a hidden document -
[task 2021-06-11T12:31:17.447Z] 12:31:17 INFO - Buffered messages logged at 12:30:05
[task 2021-06-11T12:31:17.448Z] 12:31:17 INFO - listenForTestNotification, resolving for message: FormProcessed
[task 2021-06-11T12:31:17.448Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | The first tab should be foreground -
[task 2021-06-11T12:31:17.448Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Observer should be notified when input's document becomes visible -
[task 2021-06-11T12:31:17.448Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Checking filled username -
[task 2021-06-11T12:31:17.448Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Checking filled password -
[task 2021-06-11T12:31:17.448Z] 12:31:17 INFO - Leaving test bound test_defer_autofill_until_visible
[task 2021-06-11T12:31:17.448Z] 12:31:17 INFO - Entering test bound test_defer_autofill_until_visible
[task 2021-06-11T12:31:17.448Z] 12:31:17 INFO - Buffered messages logged at 12:30:09
[task 2021-06-11T12:31:17.449Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | The first tab should be backgrounded -
[task 2021-06-11T12:31:17.449Z] 12:31:17 INFO - Buffered messages logged at 12:30:10
[task 2021-06-11T12:31:17.449Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Observer should not be notified when form is loaded into a hidden document -
[task 2021-06-11T12:31:17.449Z] 12:31:17 INFO - listenForTestNotification, resolving for message: FormProcessed
[task 2021-06-11T12:31:17.449Z] 12:31:17 INFO - Buffered messages logged at 12:30:11
[task 2021-06-11T12:31:17.449Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | The first tab should be foreground -
[task 2021-06-11T12:31:17.449Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Observer should be notified when input's document becomes visible -
[task 2021-06-11T12:31:17.450Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Checking filled username -
[task 2021-06-11T12:31:17.450Z] 12:31:17 INFO - Leaving test bound test_defer_autofill_until_visible
[task 2021-06-11T12:31:17.450Z] 12:31:17 INFO - Entering test bound test_immediate_autofill_with_masterpassword
[task 2021-06-11T12:31:17.450Z] 12:31:17 INFO - Have enabled masterPassword, now isLoggedIn? false
[task 2021-06-11T12:31:17.450Z] 12:31:17 INFO - Buffered messages logged at 12:30:13
[task 2021-06-11T12:31:17.450Z] 12:31:17 INFO - load a background login form tab with a matching saved login and wait to see if the master password dialog is shown
[task 2021-06-11T12:31:17.450Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | The second tab should be visible -
[task 2021-06-11T12:31:17.451Z] 12:31:17 INFO - TEST-PASS | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | The first tab should be backgrounded -
[task 2021-06-11T12:31:17.451Z] 12:31:17 INFO - Buffered messages logged at 12:30:22
[task 2021-06-11T12:31:17.451Z] 12:31:17 INFO - observeMasterPasswordDialog, caught exception from topicObserved: Wait for master password dialog - timed out after 50 tries.
[task 2021-06-11T12:31:17.451Z] 12:31:17 INFO - Buffered messages finished
[task 2021-06-11T12:31:17.451Z] 12:31:17 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Test timed out -
[task 2021-06-11T12:31:17.451Z] 12:31:17 INFO - GECKO(2611) | MP change from omgsecret! to
[task 2021-06-11T12:31:17.451Z] 12:31:17 INFO - GECKO(2611) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2021-06-11T12:31:17.451Z] 12:31:17 INFO - GECKO(2611) | MEMORY STAT | vsize 7791MB | residentFast 258MB | heapAllocated 92MB
[task 2021-06-11T12:31:17.452Z] 12:31:17 INFO - TEST-OK | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | took 90488ms
[task 2021-06-11T12:31:17.452Z] 12:31:17 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-11T12:31:17.452Z] 12:31:17 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Found a tab after previous test timed out: about:blank -
[task 2021-06-11T12:31:17.477Z] 12:31:17 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-06-11T12:31:17.477Z] 12:31:17 INFO - TEST-UNEXPECTED-FAIL | toolkit/components/passwordmgr/test/browser/browser_autofill_hidden_document.js | Found a tab after previous test timed out: https://example.org/browser/toolkit/components/passwordmgr/test/browser/form_basic.html -
[task 2021-06-11T12:31:17.541Z] 12:31:17 INFO - checking window state

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3

Platform and build breakdown:

• macosx1015-64-qr: 3
  • opt: 1
  • debug: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1618922&startday=2021-06-07&endday=2021-06-13&tree=all

Comment 14

[BugBot [:suhaib / :marco]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.