Open Bug 1876507 Opened 1 year ago Updated 1 year ago

Intermittent TV browser/extensions/formautofill/test/browser/address/browser_address_doorhanger_confirmation_popup.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but

Categories

(Toolkit :: Form Autofill, defect, P5)

Product:
Toolkit
Component:
Form Autofill
Type:
defect

Tracking

()

Tracking Flags:
Tracking Status
firefox-esr115 --- unaffected
firefox122 --- unaffected
firefox123 --- unaffected
firefox124 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, test-verify-fail)

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=444557154&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/QWS-5MgXQXGyAw4_Wm1TiQ/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/QWS-5MgXQXGyAw4_Wm1TiQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2024-01-25T10:08:47.518Z] 10:08:47     INFO - TEST-PASS | browser/extensions/formautofill/test/browser/address/browser_address_doorhanger_confirmation_popup.js | hint popup is open - "open" == "open" - 
[task 2024-01-25T10:08:47.518Z] 10:08:47     INFO - TEST-PASS | browser/extensions/formautofill/test/browser/address/browser_address_doorhanger_confirmation_popup.js | hint anchorNode is visible - true == true - 
[task 2024-01-25T10:08:47.519Z] 10:08:47     INFO - TEST-PASS | browser/extensions/formautofill/test/browser/address/browser_address_doorhanger_confirmation_popup.js | Hint should be anchored on the expected notification icon - "identity-icon" == "identity-icon" - 
[task 2024-01-25T10:08:47.519Z] 10:08:47     INFO - verifyConfirmationHint, hint is shown and has its anchorNode
[task 2024-01-25T10:08:47.520Z] 10:08:47     INFO - verifyConfirmationHint, assertion ok, wait for poopuphidden
[task 2024-01-25T10:08:47.520Z] 10:08:47     INFO - Buffered messages logged at 10:08:46
[task 2024-01-25T10:08:47.521Z] 10:08:47     INFO - verifyConfirmationHint, hintElem popup is hidden
[task 2024-01-25T10:08:47.523Z] 10:08:47     INFO - verifyConfirmationHint promise finalized
[task 2024-01-25T10:08:47.524Z] 10:08:47     INFO - expecting record retrievals: addresses
[task 2024-01-25T10:08:47.525Z] 10:08:47     INFO - TEST-PASS | browser/extensions/formautofill/test/browser/address/browser_address_doorhanger_confirmation_popup.js | 1 address in the storage - 
[task 2024-01-25T10:08:47.525Z] 10:08:47     INFO - expecting record retrievals: addresses
[task 2024-01-25T10:08:47.526Z] 10:08:47     INFO - expecting address removed
[task 2024-01-25T10:08:47.526Z] 10:08:47     INFO - expecting record retrievals: creditCards
[task 2024-01-25T10:08:47.526Z] 10:08:47     INFO - Leaving test bound test_edit_doorhanger_show_confirmation
[task 2024-01-25T10:08:47.527Z] 10:08:47     INFO - expecting record retrievals: addresses
[task 2024-01-25T10:08:47.527Z] 10:08:47     INFO - expecting record retrievals: creditCards
[task 2024-01-25T10:08:47.528Z] 10:08:47     INFO - Buffered messages finished
[task 2024-01-25T10:08:47.528Z] 10:08:47     INFO - TEST-UNEXPECTED-FAIL | browser/extensions/formautofill/test/browser/address/browser_address_doorhanger_confirmation_popup.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. - 
[task 2024-01-25T10:08:47.533Z] 10:08:47     INFO - GECKO(5735) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2024-01-25T10:08:47.534Z] 10:08:47     INFO - GECKO(5735) | MEMORY STAT | vsize 11666MB | residentFast 535MB | heapAllocated 300MB
[task 2024-01-25T10:08:47.534Z] 10:08:47     INFO - TEST-OK | browser/extensions/formautofill/test/browser/address/browser_address_doorhanger_confirmation_popup.js | took 442782ms
[task 2024-01-25T10:08:47.540Z] 10:08:47     INFO - GECKO(5735) | [Child 5839: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f193d018000 == 1 [pid = 5839] [id = 1]
[task 2024-01-25T10:08:47.541Z] 10:08:47     INFO - GECKO(5735) | [Child 5839: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (7f193d00a020) [pid = 5839] [serial = 5] [outer = 0]
[task 2024-01-25T10:08:47.541Z] 10:08:47     INFO - GECKO(5735) | [Child 5839: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (7f193d018400) [pid = 5839] [serial = 6] [outer = 7f193d00a020]
[task 2024-01-25T10:08:47.542Z] 10:08:47     INFO - checking window state
[task 2024-01-25T10:08:47.593Z] 10:08:47     INFO - GECKO(5735) | [Parent 5735: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (7f0f08f14c00) [pid = 5735] [serial = 12] [outer = 7f0f1811b4c0]
[task 2024-01-25T10:08:47.955Z] 10:08:47     INFO - GECKO(5735) | [Child 5879: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f5ccfb50000 == 0 [pid = 5879] [id = 0] [url = about:blank]
[task 2024-01-25T10:08:48.196Z] 10:08:48     INFO - GECKO(5735) | [Child 5956: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f3f24337800 == 0 [pid = 5956] [id = 0] [url = about:newtab]
[task 2024-01-25T10:08:48.206Z] 10:08:48     INFO - GECKO(5735) | [Child 5956, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/base/GlobalTeardownObserver.cpp:51
[task 2024-01-25T10:08:49.136Z] 10:08:49     INFO - GECKO(5735) | [Child 6017, Main Thread] WARNING: IPC message 'PContent::Msg_AccumulateChildKeyedHistograms' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2024-01-25T10:08:49.137Z] 10:08:49     INFO - GECKO(5735) | [Child 6017, Main Thread] WARNING: '!ipcActor->SendAccumulateChildKeyedHistograms(keyedHistogramsToSend)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:282
[task 2024-01-25T10:08:49.137Z] 10:08:49     INFO - GECKO(5735) | [Child 6017, Main Thread] WARNING: IPC message 'PContent::Msg_RecordDiscardedData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2024-01-25T10:08:49.138Z] 10:08:49     INFO - GECKO(5735) | [Child 6017, Main Thread] WARNING: '!ipcActor->SendRecordDiscardedData(discardedData)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:297
[task 2024-01-25T10:08:49.154Z] 10:08:49     INFO - GECKO(5735) | [Child 6097, Main Thread] WARNING: IPC message 'PContent::Msg_AccumulateChildKeyedHistograms' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2024-01-25T10:08:49.155Z] 10:08:49     INFO - GECKO(5735) | [Child 6097, Main Thread] WARNING: '!ipcActor->SendAccumulateChildKeyedHistograms(keyedHistogramsToSend)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:282
[task 2024-01-25T10:08:49.155Z] 10:08:49     INFO - GECKO(5735) | [Child 6097, Main Thread] WARNING: IPC message 'PContent::Msg_RecordDiscardedData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2024-01-25T10:08:49.157Z] 10:08:49     INFO - GECKO(5735) | [Child 6097, Main Thread] WARNING: '!ipcActor->SendRecordDiscardedData(discardedData)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:297
[task 2024-01-25T10:08:49.172Z] 10:08:49     INFO - GECKO(5735) | [Child 6078, Main Thread] WARNING: IPC message 'PContent::Msg_AccumulateChildKeyedHistograms' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2024-01-25T10:08:49.172Z] 10:08:49     INFO - GECKO(5735) | [Child 6078, Main Thread] WARNING: '!ipcActor->SendAccumulateChildKeyedHistograms(keyedHistogramsToSend)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:282
[task 2024-01-25T10:08:49.174Z] 10:08:49     INFO - GECKO(5735) | [Child 6078, Main Thread] WARNING: IPC message 'PContent::Msg_RecordDiscardedData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:545
[task 2024-01-25T10:08:49.177Z] 10:08:49     INFO - GECKO(5735) | [Child 6078, Main Thread] WARNING: '!ipcActor->SendRecordDiscardedData(discardedData)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:297
[task 2024-01-25T10:08:49.376Z] 10:08:49     INFO - GECKO(5735) | [Child 5879: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f5ccfb50800) [pid = 5879] [serial = 2] [outer = 0] [url = about:blank]
[task 2024-01-25T10:08:49.376Z] 10:08:49     INFO - GECKO(5735) | [Child 5879: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f5cd0564020) [pid = 5879] [serial = 1] [outer = 0] [url = about:blank]
[task 2024-01-25T10:08:49.741Z] 10:08:49     INFO - GECKO(5735) | [Child 5956: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f3f427c1020) [pid = 5956] [serial = 1] [outer = 0] [url = about:newtab]
[task 2024-01-25T10:08:49.743Z] 10:08:49     INFO - GECKO(5735) | [Child 5956: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f3f2433bc00) [pid = 5956] [serial = 3] [outer = 0] [url = about:newtab]
[task 2024-01-25T10:08:50.528Z] 10:08:50     INFO - GECKO(5735) | Completed ShutdownLeaks collections in process 5839
[task 2024-01-25T10:08:50.541Z] 10:08:50     INFO - GECKO(5735) | Completed ShutdownLeaks collections in process 5879
[task 2024-01-25T10:08:50.862Z] 10:08:50     INFO - GECKO(5735) | [Parent 5735: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f0f15ebf000) [pid = 5735] [serial = 6] [outer = 0] [url = about:blank]
[task 2024-01-25T10:08:50.873Z] 10:08:50     INFO - GECKO(5735) | Completed ShutdownLeaks collections in process 5956
[task 2024-01-25T10:08:51.083Z] 10:08:51     INFO - GECKO(5735) | Completed ShutdownLeaks collections in process 5808
[task 2024-01-25T10:08:51.516Z] 10:08:51     INFO - GECKO(5735) | [Parent 5735: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (7f0f08f0d800) [pid = 5735] [serial = 13] [outer = 7f0f1811b4c0]
[task 2024-01-25T10:08:53.732Z] 10:08:53     INFO - GECKO(5735) | [Parent 5735: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f0f08f14c00) [pid = 5735] [serial = 12] [outer = 0] [url = about:blank]
[task 2024-01-25T10:08:54.262Z] 10:08:54     INFO - GECKO(5735) | Completed ShutdownLeaks collections in process 5735
[task 2024-01-25T10:08:54.264Z] 10:08:54     INFO - TEST-START | Shutdown
[task 2024-01-25T10:08:54.266Z] 10:08:54     INFO - Browser Chrome Test Summary
[task 2024-01-25T10:08:54.268Z] 10:08:54     INFO - Passed:  23
[task 2024-01-25T10:08:54.269Z] 10:08:54     INFO - Failed:  1
[task 2024-01-25T10:08:54.271Z] 10:08:54     INFO - Todo:    0
[task 2024-01-25T10:08:54.272Z] 10:08:54     INFO - Mode:    e10s
[task 2024-01-25T10:08:54.272Z] 10:08:54     INFO - *** End BrowserChrome Test Results ***

:dimi, since you are the author of the regressor, bug 1875253, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(dlee)

Set release status flags based on info from the regressing bug 1875253

status-firefox122: --- → unaffected
status-firefox123: --- → unaffected
status-firefox124: --- → affected
status-firefox-esr115: --- → unaffected
Flags: needinfo?(dlee)
You need to log in before you can comment on or make changes to this bug.