Open Bug 1879652 Opened 9 months ago Updated 2 months ago

Intermittent browser/base/content/test/captivePortal/browser_closeCapPortalTabCanonicalURL.js | single tracking bug

Categories

(Firefox :: Tabbed Browser, defect, P5)

defect

Tracking

()

Tracking Status
firefox-esr115 --- unaffected
firefox122 --- unaffected
firefox123 --- unaffected
firefox124 --- affected
firefox125 --- affected

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

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

Attachments

(1 obsolete file)

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=446741849&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Fl6AUx2gT2y0Gtdf7SyUgA/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/Fl6AUx2gT2y0Gtdf7SyUgA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2024-02-09T20:46:10.494Z] 20:46:10     INFO - TEST-PASS | browser/base/content/test/captivePortal/browser_closeCapPortalTabCanonicalURL.js | Captive Portal login page is now open in a new foreground tab. - 
[task 2024-02-09T20:46:10.495Z] 20:46:10     INFO - Clicking the login button on the captive portal tab page
[task 2024-02-09T20:46:10.495Z] 20:46:10     INFO - Buffered messages logged at 20:45:24
[task 2024-02-09T20:46:10.496Z] 20:46:10     INFO - Re-direct to canonical URL in the captive portal tab was succcessful after login
[task 2024-02-09T20:46:10.497Z] 20:46:10     INFO - Captive portal tab was closed on re-direct to canonical URL after login as expected
[task 2024-02-09T20:46:10.498Z] 20:46:10     INFO - Captive portal error page was reloaded
[task 2024-02-09T20:46:10.498Z] 20:46:10     INFO - Leaving test bound checkCaptivePortalTabCloseOnCanonicalURL_two
[task 2024-02-09T20:46:10.499Z] 20:46:10     INFO - Entering test bound checkCaptivePortalTabCloseOnCanonicalURL_three
[task 2024-02-09T20:46:10.500Z] 20:46:10     INFO - A cert error page was opened
[task 2024-02-09T20:46:10.500Z] 20:46:10     INFO - Captive portal error page UI is visible
[task 2024-02-09T20:46:10.501Z] 20:46:10     INFO - Click on the login button on the captive portal error page
[task 2024-02-09T20:46:10.502Z] 20:46:10     INFO - Buffered messages finished
[task 2024-02-09T20:46:10.502Z] 20:46:10     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/captivePortal/browser_closeCapPortalTabCanonicalURL.js | Test timed out - 
[task 2024-02-09T20:46:10.503Z] 20:46:10     INFO - GECKO(1347) | Completed ShutdownLeaks collections in process 1347
[task 2024-02-09T20:46:10.504Z] 20:46:10     INFO - TEST-START | Shutdown
[task 2024-02-09T20:46:10.505Z] 20:46:10     INFO - Browser Chrome Test Summary
[task 2024-02-09T20:46:10.505Z] 20:46:10     INFO - Passed:  2
[task 2024-02-09T20:46:10.506Z] 20:46:10     INFO - Failed:  1
[task 2024-02-09T20:46:10.507Z] 20:46:10     INFO - Todo:    0
[task 2024-02-09T20:46:10.507Z] 20:46:10     INFO - Mode:    e10s
[task 2024-02-09T20:46:10.508Z] 20:46:10     INFO - *** End BrowserChrome Test Results ***
[task 2024-02-09T20:46:10.509Z] 20:46:10     INFO - GECKO(1347) | Exiting due to channel error.
[task 2024-02-09T20:46:10.510Z] 20:46:10     INFO - GECKO(1347) | Exiting due to channel error.
[task 2024-02-09T20:46:10.510Z] 20:46:10     INFO - GECKO(1347) | Exiting due to channel error.
[task 2024-02-09T20:46:10.511Z] 20:46:10     INFO - GECKO(1347) | Exiting due to channel error.
[task 2024-02-09T20:46:10.512Z] 20:46:10     INFO - GECKO(1347) | Exiting due to channel error.
[task 2024-02-09T20:46:10.513Z] 20:46:10     INFO - GECKO(1347) | Exiting due to channel error.
[task 2024-02-09T20:46:10.513Z] 20:46:10     INFO - GECKO(1347) | Exiting due to channel error.
[task 2024-02-09T20:46:10.514Z] 20:46:10     INFO - GECKO(1347) | Exiting due to channel error.
[task 2024-02-09T20:46:10.515Z] 20:46:10     INFO - TEST-INFO | Main app process: exit 0
[task 2024-02-09T20:46:10.515Z] 20:46:10     INFO - runtests.py | Application ran for: 0:00:57.769921
[task 2024-02-09T20:46:10.516Z] 20:46:10     INFO - zombiecheck | Reading PID log: /tmp/tmp_a_yx9ampidlog
[task 2024-02-09T20:46:10.517Z] 20:46:10     INFO - ==> process 1347 launched child process 1453
[task 2024-02-09T20:46:10.518Z] 20:46:10     INFO - ==> process 1347 launched child process 1486
[task 2024-02-09T20:46:10.518Z] 20:46:10     INFO - ==> process 1347 launched child process 1528
[task 2024-02-09T20:46:10.520Z] 20:46:10     INFO - ==> process 1347 launched child process 1555
[task 2024-02-09T20:46:10.521Z] 20:46:10     INFO - ==> process 1347 launched child process 1557
[task 2024-02-09T20:46:10.522Z] 20:46:10     INFO - ==> process 1347 launched child process 1566
[task 2024-02-09T20:46:10.523Z] 20:46:10     INFO - ==> process 1347 launched child process 1591
[task 2024-02-09T20:46:10.523Z] 20:46:10     INFO - ==> process 1347 launched child process 1651
[task 2024-02-09T20:46:10.524Z] 20:46:10     INFO - ==> process 1347 launched child process 1654
[task 2024-02-09T20:46:10.524Z] 20:46:10     INFO - ==> process 1347 launched child process 1696
[task 2024-02-09T20:46:10.525Z] 20:46:10     INFO - ==> process 1347 launched child process 1736
[task 2024-02-09T20:46:10.525Z] 20:46:10     INFO - ==> process 1347 launched child process 1741
[task 2024-02-09T20:46:10.526Z] 20:46:10     INFO - ==> process 1347 launched child process 1746
[task 2024-02-09T20:46:10.527Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1696
[task 2024-02-09T20:46:10.527Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1736
[task 2024-02-09T20:46:10.528Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1453
[task 2024-02-09T20:46:10.528Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1486
[task 2024-02-09T20:46:10.529Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1741
[task 2024-02-09T20:46:10.529Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1746
[task 2024-02-09T20:46:10.530Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1555
[task 2024-02-09T20:46:10.530Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1651
[task 2024-02-09T20:46:10.535Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1557
[task 2024-02-09T20:46:10.536Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1654
[task 2024-02-09T20:46:10.537Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1591
[task 2024-02-09T20:46:10.538Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1528
[task 2024-02-09T20:46:10.539Z] 20:46:10     INFO - zombiecheck | Checking for orphan process with PID: 1566
[task 2024-02-09T20:46:10.540Z] 20:46:10     INFO - Stopping web server
[task 2024-02-09T20:46:10.542Z] 20:46:10     INFO - Server shut down.
[task 2024-02-09T20:46:10.543Z] 20:46:10     INFO - Web server killed.
[task 2024-02-09T20:46:10.544Z] 20:46:10     INFO - Stopping web socket server
[task 2024-02-09T20:46:10.545Z] 20:46:10     INFO - Stopping ssltunnel
[task 2024-02-09T20:46:10.546Z] 20:46:10     INFO - Stopping gst for v4l2loopback
[task 2024-02-09T20:46:10.547Z] 20:46:10  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2024-02-09T20:46:10.548Z] 20:46:10     INFO - runtests.py | Running tests: end.
[task 2024-02-09T20:46:10.605Z] 20:46:10     INFO - Buffered messages finished
[task 2024-02-09T20:46:10.607Z] 20:46:10     INFO -  TEST-INFO | checking window state
[task 2024-02-09T20:46:10.608Z] 20:46:10     INFO -  Browser Chrome Test Summary
[task 2024-02-09T20:46:10.609Z] 20:46:10     INFO -  	Passed: 2
[task 2024-02-09T20:46:10.612Z] 20:46:10     INFO -  	Failed: 1
[task 2024-02-09T20:46:10.613Z] 20:46:10     INFO -  	Todo: 0
[task 2024-02-09T20:46:10.615Z] 20:46:10     INFO -  	Mode: e10s
[task 2024-02-09T20:46:10.617Z] 20:46:10     INFO -  *** End BrowserChrome Test Results ***
[task 2024-02-09T20:46:10.618Z] 20:46:10     INFO - Buffered messages finished
[task 2024-02-09T20:46:10.622Z] 20:46:10     INFO - SUITE-END | took 58s
[task 2024-02-09T20:46:10.623Z] 20:46:10     INFO - SUITE-START | Running 1 tests
[task 2024-02-09T20:46:10.624Z] 20:46:10     INFO - :::
[task 2024-02-09T20:46:10.625Z] 20:46:10     INFO - ::: Test verification summary for:
[task 2024-02-09T20:46:10.626Z] 20:46:10     INFO - :::
[task 2024-02-09T20:46:10.627Z] 20:46:10     INFO - ::: browser/base/content/test/captivePortal/browser_closeCapPortalTabCanonicalURL.js
[task 2024-02-09T20:46:10.628Z] 20:46:10     INFO - :::
[task 2024-02-09T20:46:10.629Z] 20:46:10     INFO - ::: 1. Run each test 10 times in one browser. : FAIL
[task 2024-02-09T20:46:10.630Z] 20:46:10     INFO - ::: 2. Run each test 5 times in a new browser each time. : not run / incomplete
[task 2024-02-09T20:46:10.632Z] 20:46:10     INFO - ::: 3. Run each test 10 times in one browser, in chaos mode. : not run / incomplete
[task 2024-02-09T20:46:10.634Z] 20:46:10     INFO - ::: 4. Run each test 5 times in a new browser each time, in chaos mode. : not run / incomplete
[task 2024-02-09T20:46:10.635Z] 20:46:10     INFO - :::
[task 2024-02-09T20:46:10.636Z] 20:46:10     INFO - ::: Test verification FAILED!
[task 2024-02-09T20:46:10.637Z] 20:46:10     INFO - :::
[task 2024-02-09T20:46:10.638Z] 20:46:10     INFO - Buffered messages finished
[task 2024-02-09T20:46:10.639Z] 20:46:10     INFO - SUITE-END | took 0s
[task 2024-02-09T20:46:10.661Z] 20:46:10     INFO - Return code: 0
[task 2024-02-09T20:46:10.662Z] 20:46:10  WARNING - Got 1 unexpected statuses
[task 2024-02-09T20:46:10.662Z] 20:46:10    ERROR - No checks run.
[task 2024-02-09T20:46:10.662Z] 20:46:10     INFO - TinderboxPrint: mochitest-mochitest-browser-chrome<br/>2/<em class="testfail">1</em>/0
[task 2024-02-09T20:46:10.663Z] 20:46:10  WARNING - setting return code to 2
[task 2024-02-09T20:46:10.663Z] 20:46:10    ERROR - TinderboxPrint: Per-test run of .../browser_closeCapPortalTabCanonicalURL.js<br/>: FAILURE
[task 2024-02-09T20:46:10.664Z] 20:46:10     INFO - Per-test suites: {}
[task 2024-02-09T20:46:10.664Z] 20:46:10     INFO - Per-test suites: {}
[task 2024-02-09T20:46:10.665Z] 20:46:10     INFO - Running post-action listener: _package_coverage_data
[task 2024-02-09T20:46:10.665Z] 20:46:10     INFO - Running post-action listener: _resource_record_post_action
[task 2024-02-09T20:46:10.665Z] 20:46:10     INFO - Running post-action listener: process_java_coverage_data
[task 2024-02-09T20:46:10.665Z] 20:46:10     INFO - [mozharness: 2024-02-09 20:46:10.665178Z] Finished run-tests step (success)
[task 2024-02-09T20:46:10.665Z] 20:46:10     INFO - [mozharness: 2024-02-09 20:46:10.665232Z] Running uninstall step.
[task 2024-02-09T20:46:10.665Z] 20:46:10     INFO - Running pre-action listener: _resource_record_pre_action
[task 2024-02-09T20:46:10.666Z] 20:46:10     INFO - Running main action method: uninstall
[task 2024-02-09T20:46:10.666Z] 20:46:10     INFO - Skipping uninstall for non-MSIX test
[task 2024-02-09T20:46:10.666Z] 20:46:10     INFO - Running post-action listener: _resource_record_post_action
[task 2024-02-09T20:46:10.666Z] 20:46:10     INFO - [mozharness: 2024-02-09 20:46:10.665458Z] Finished uninstall step (success)
[task 2024-02-09T20:46:10.666Z] 20:46:10     INFO - Running post-run listener: _resource_record_post_run
[task 2024-02-09T20:46:10.793Z] 20:46:10     INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2024-02-09T20:46:10.797Z] 20:46:10     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": ".overall", "extraOptions": ["e10s", "taskcluster-projects/887720501152/machineTypes/n2-standard-2"], "subtests": [{"name": "cpu_percent", "value": 26.47373806275578}, {"name": "io_write_bytes", "value": 865128448}, {"name": "io.read_bytes", "value": 101711872}, {"name": "io_write_time", "value": 154000}, {"name": "io_read_time", "value": 544}]}, {"name": ".start-pulseaudio", "subtests": [{"name": "time", "value": 0.025778261999221286}, {"name": "cpu_percent", "value": 0}]}, {"name": ".install", "subtests": [{"name": "time", "value": 14.079489956999168}, {"name": "cpu_percent", "value": 51.09357142857143}]}, {"name": ".stage-files", "subtests": [{"name": "time", "value": 0.002698909000173444}, {"name": "cpu_percent", "value": 0}]}, {"name": ".run-tests", "subtests": [{"name": "time", "value": 59.25704814399978}, {"name": "cpu_percent", "value": 20.494331641285985}]}, {"name": ".uninstall", "subtests": [{"name": "time", "value": 0.00015560499923594762}, {"name": "cpu_percent", "value": 0}]}]}
[task 2024-02-09T20:46:10.797Z] 20:46:10     INFO - Total resource usage - Wall time: 73s; CPU: Can't collect data; Read bytes: 101711872; Write bytes: 865128448; Read time: 544; Write time: 154000
[task 2024-02-09T20:46:10.798Z] 20:46:10     INFO - TinderboxPrint: I/O read bytes / time<br/>101,711,872 / 544
[task 2024-02-09T20:46:10.798Z] 20:46:10     INFO - TinderboxPrint: I/O write bytes / time<br/>865,128,448 / 154,000
[task 2024-02-09T20:46:10.798Z] 20:46:10     INFO - TinderboxPrint: CPU idle<br/>107.0 (73.3%)
[task 2024-02-09T20:46:10.798Z] 20:46:10     INFO - TinderboxPrint: CPU system<br/>4.7 (3.2%)
[task 2024-02-09T20:46:10.799Z] 20:46:10     INFO - TinderboxPrint: CPU user<br/>34.1 (23.4%)
[task 2024-02-09T20:46:10.799Z] 20:46:10     INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2024-02-09T20:46:10.799Z] 20:46:10     INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-02-09T20:46:10.801Z] 20:46:10     INFO - install - Wall time: 14s; CPU: 51%; Read bytes: 49152; Write bytes: 18685952; Read time: 8; Write time: 232
[task 2024-02-09T20:46:10.801Z] 20:46:10     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-02-09T20:46:10.804Z] 20:46:10     INFO - run-tests - Wall time: 59s; CPU: 20%; Read bytes: 101662720; Write bytes: 846442496; Read time: 536; Write time: 153768
[task 2024-02-09T20:46:10.805Z] 20:46:10     INFO - uninstall - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2024-02-09T20:46:10.923Z] 20:46:10  WARNING - returning nonzero exit status 2
[task 2024-02-09T20:46:10.976Z] cleanup
[task 2024-02-09T20:46:10.976Z] + cleanup
[task 2024-02-09T20:46:10.976Z] + local rv=2
[task 2024-02-09T20:46:10.976Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2024-02-09T20:46:10.976Z] + cp /builds/worker/.xsession-errors /builds/worker/artifacts/public/xsession-errors.log
[task 2024-02-09T20:46:10.979Z] + '[' ']'
[task 2024-02-09T20:46:10.980Z] + true
[task 2024-02-09T20:46:10.980Z] + cleanup_xvfb
[task 2024-02-09T20:46:10.981Z] ++ pidof Xvfb
[task 2024-02-09T20:46:10.987Z] + local xvfb_pid=49
[task 2024-02-09T20:46:10.987Z] + local vnc=false
[task 2024-02-09T20:46:10.987Z] + local interactive=false
[task 2024-02-09T20:46:10.987Z] + '[' -n 49 ']'
[task 2024-02-09T20:46:10.987Z] + [[ false == false ]]
[task 2024-02-09T20:46:10.987Z] + [[ false == false ]]
[task 2024-02-09T20:46:10.987Z] + kill 49
[task 2024-02-09T20:46:11.000Z] + screen -XS xvfb quit
[task 2024-02-09T20:46:11.061Z] No screen session found.
[task 2024-02-09T20:46:11.062Z] + true
[task 2024-02-09T20:46:11.062Z] + exit 2
[taskcluster 2024-02-09 20:46:11.376Z] === Task Finished ===
[taskcluster 2024-02-09 20:46:12.913Z] Unsuccessful task run with exit code: 2 completed in 214.211 seconds

:valentin, since you are the author of the regressor, bug 1824218, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(valentin.gosu)

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

[task 2024-02-09T20:46:10.493Z] 20:46:10     INFO - Console message: [JavaScript Warning: "This page is in Quirks Mode. Page layout may be impacted. For Standards Mode use “<!DOCTYPE html>”." {file: "http://localhost:8080/login" line: 0}]
[task 2024-02-09T20:46:10.494Z] 20:46:10     INFO - TEST-PASS | browser/base/content/test/captivePortal/browser_closeCapPortalTabCanonicalURL.js | Captive Portal login page is now open in a new foreground tab. - 
[task 2024-02-09T20:46:10.495Z] 20:46:10     INFO - Clicking the login button on the captive portal tab page
[task 2024-02-09T20:46:10.495Z] 20:46:10     INFO - Buffered messages logged at 20:45:24
[task 2024-02-09T20:46:10.496Z] 20:46:10     INFO - Re-direct to canonical URL in the captive portal tab was succcessful after login
[task 2024-02-09T20:46:10.497Z] 20:46:10     INFO - Captive portal tab was closed on re-direct to canonical URL after login as expected
[task 2024-02-09T20:46:10.498Z] 20:46:10     INFO - Captive portal error page was reloaded
[task 2024-02-09T20:46:10.498Z] 20:46:10     INFO - Leaving test bound checkCaptivePortalTabCloseOnCanonicalURL_two
[task 2024-02-09T20:46:10.499Z] 20:46:10     INFO - Entering test bound checkCaptivePortalTabCloseOnCanonicalURL_three
[task 2024-02-09T20:46:10.500Z] 20:46:10     INFO - A cert error page was opened
[task 2024-02-09T20:46:10.500Z] 20:46:10     INFO - Captive portal error page UI is visible
[task 2024-02-09T20:46:10.501Z] 20:46:10     INFO - Click on the login button on the captive portal error page
[task 2024-02-09T20:46:10.502Z] 20:46:10     INFO - Buffered messages finished
[task 2024-02-09T20:46:10.502Z] 20:46:10     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/captivePortal/browser_closeCapPortalTabCanonicalURL.js | Test timed out - 

The click on the loging button message comes from here, meaning the hanging line in the test is this one
Technically it's the same as the previous test, so I'm not 100% sure it's a regression from bug 1824218.

Flags: needinfo?(valentin.gosu)
See Also: → 1727534

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

Summary: Intermittent TV browser/base/content/test/captivePortal/browser_closeCapPortalTabCanonicalURL.js | single tracking bug → Intermittent browser/base/content/test/captivePortal/browser_closeCapPortalTabCanonicalURL.js | single tracking bug
Attachment #9387377 - Attachment is obsolete: true

The Bugbug bot thinks this bug should belong to the 'Firefox::Tabbed Browser' component, and is moving the bug to that component. Please correct in case you think the bot is wrong.

Component: General → Tabbed Browser
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: