Closed Bug 1681867 Opened 5 years ago Closed 4 years ago

Intermittent macOS toolkit/components/printing/tests/browser_preview_navigation.js | Uncaught exception - 2 successive 'previous'/'next clicks correctly update the sheet indicator - timed out after 50 tries.

Categories

(Toolkit :: Printing, defect, P5)

defect

Tracking

()

RESOLVED FIXED
87 Branch
Tracking Status
firefox-esr78 --- unaffected
firefox83 --- unaffected
firefox84 --- unaffected
firefox85 --- wontfix
firefox86 --- fixed
firefox87 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [print2020_v88] [old-ui-] [retriggered])

Attachments

(1 file)

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


INFO - TEST-PASS | toolkit/components/printing/tests/browser_preview_navigation.js | Opaque toolbar - 
[task 2020-12-11T00:11:06.635Z] 00:11:06     INFO - Leaving test bound testToolbarVisibility
[task 2020-12-11T00:11:06.635Z] 00:11:06     INFO - Entering test bound testPreviewSheetCount
[task 2020-12-11T00:11:06.635Z] 00:11:06     INFO - withTestPage: http://example.com/browser/toolkit/components/printing/tests/longerArticle.html
[task 2020-12-11T00:11:06.635Z] 00:11:06     INFO - Buffered messages logged at 00:10:56
[task 2020-12-11T00:11:06.636Z] 00:11:06     INFO - compare10nArgs, expected sheetCount: 1, actual: 3
[task 2020-12-11T00:11:06.636Z] 00:11:06     INFO - Leaving test bound testPreviewSheetCount
[task 2020-12-11T00:11:06.636Z] 00:11:06     INFO - Entering test bound testPreviewScroll
[task 2020-12-11T00:11:06.636Z] 00:11:06     INFO - withTestPage: http://example.com/browser/toolkit/components/printing/tests/longerArticle.html
[task 2020-12-11T00:11:06.636Z] 00:11:06     INFO - Buffered messages logged at 00:10:57
[task 2020-12-11T00:11:06.636Z] 00:11:06     INFO - Leaving test bound testPreviewScroll
[task 2020-12-11T00:11:06.636Z] 00:11:06     INFO - Entering test bound testPreviewNavigationCommands
[task 2020-12-11T00:11:06.636Z] 00:11:06     INFO - withTestPage: http://example.com/browser/toolkit/components/printing/tests/longerArticle.html
[task 2020-12-11T00:11:06.636Z] 00:11:06     INFO - Buffered messages logged at 00:10:58
[task 2020-12-11T00:11:06.638Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.638Z] 00:11:06     INFO - Buffered messages logged at 00:10:59
[task 2020-12-11T00:11:06.639Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.639Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.640Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.640Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.640Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.640Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.640Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.642Z] 00:11:06     INFO - Buffered messages logged at 00:11:00
[task 2020-12-11T00:11:06.642Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.642Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.643Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.643Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.643Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.643Z] 00:11:06     INFO - Buffered messages logged at 00:11:01
[task 2020-12-11T00:11:06.643Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.643Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.644Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.644Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.644Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.644Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.648Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.648Z] 00:11:06     INFO - Buffered messages logged at 00:11:02
[task 2020-12-11T00:11:06.649Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.649Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.650Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - Buffered messages logged at 00:11:03
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - Buffered messages logged at 00:11:04
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - Buffered messages logged at 00:11:05
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.652Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.653Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.653Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.653Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.653Z] 00:11:06     INFO - Buffered messages logged at 00:11:06
[task 2020-12-11T00:11:06.661Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.661Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.661Z] 00:11:06     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:06.662Z] 00:11:06     INFO - Buffered messages finished
[task 2020-12-11T00:11:06.662Z] 00:11:06     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/printing/tests/browser_preview_navigation.js | Uncaught exception - 2 successive 'previous' clicks correctly update the sheet indicator - timed out after 50 tries.
[task 2020-12-11T00:11:06.662Z] 00:11:06     INFO - Leaving test bound testPreviewNavigationCommands
[task 2020-12-11T00:11:06.662Z] 00:11:06     INFO - Entering test bound testMultiplePreviewNavigation
[task 2020-12-11T00:11:06.662Z] 00:11:06     INFO - withTestPage: http://example.com/browser/toolkit/components/printing/tests/longerArticle.html
[task 2020-12-11T00:11:07.266Z] 00:11:07     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:07.416Z] 00:11:07     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:07.600Z] 00:11:07     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:07.862Z] 00:11:07     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:08.083Z] 00:11:08     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:08.316Z] 00:11:08     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:08.449Z] 00:11:08     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:08.550Z] 00:11:08     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:08.677Z] 00:11:08     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:08.901Z] 00:11:08     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:09.133Z] 00:11:09     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:09.333Z] 00:11:09     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:09.433Z] 00:11:09     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:09.633Z] 00:11:09     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:09.800Z] 00:11:09     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:10.044Z] 00:11:10     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:10.166Z] 00:11:10     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:10.332Z] 00:11:10     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:10.459Z] 00:11:10     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:10.566Z] 00:11:10     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:10.666Z] 00:11:10     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:10.900Z] 00:11:10     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:11.068Z] 00:11:11     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:11.176Z] 00:11:11     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:11.416Z] 00:11:11     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:11.675Z] 00:11:11     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:11.909Z] 00:11:11     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:12.066Z] 00:11:12     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:12.266Z] 00:11:12     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:12.416Z] 00:11:12     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:12.633Z] 00:11:12     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:12.866Z] 00:11:12     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:12.984Z] 00:11:12     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:13.416Z] 00:11:13     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:13.417Z] 00:11:13     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:13.417Z] 00:11:13     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:13.659Z] 00:11:13     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:13.753Z] 00:11:13     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:13.909Z] 00:11:13     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:14.100Z] 00:11:14     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:14.333Z] 00:11:14     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:14.459Z] 00:11:14     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:14.683Z] 00:11:14     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:14.800Z] 00:11:14     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:14.933Z] 00:11:14     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:15.149Z] 00:11:15     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:15.342Z] 00:11:15     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:15.449Z] 00:11:15     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:15.566Z] 00:11:15     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:15.676Z] 00:11:15     INFO - compare10nArgs, expected sheetNum: 1, actual: 2
[task 2020-12-11T00:11:15.917Z] 00:11:15     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-12-11T00:11:15.917Z] 00:11:15     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/printing/tests/browser_preview_navigation.js | Uncaught exception - Indicator has the correct initial sheetCount - timed out after 50 tries.
[task 2020-12-11T00:11:15.917Z] 00:11:15     INFO - Leaving test bound testMultiplePreviewNavigation
[task 2020-12-11T00:11:15.917Z] 00:11:15     INFO - GECKO(2743) | MEMORY STAT | vsize 7724MB | residentFast 297MB | heapAllocated 103MB
[task 2020-12-11T00:11:15.917Z] 00:11:15     INFO - TEST-OK | toolkit/components/printing/tests/browser_preview_navigation.js | took 22296ms
[task 2020-12-11T00:11:15.917Z] 00:11:15     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-12-11T00:11:15.917Z] 00:11:15     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/printing/tests/browser_preview_navigation.js | Found an unexpected tab at the end of test run: http://example.com/browser/toolkit/components/printing/tests/longerArticle.html - 
[task 2020-12-11T00:11:15.937Z] 00:11:15     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-12-11T00:11:15.937Z] 00:11:15     INFO - TEST-UNEXPECTED-FAIL | toolkit/components/printing/tests/browser_preview_navigation.js | Found an unexpected tab at the end of test run: http://example.com/browser/toolkit/components/printing/tests/longerArticle.html - 
[task 2020-12-11T00:11:15.967Z] 00:11:15     INFO - checking window state
[task 2020-12-11T00:11:15.976Z] 00:11:15     INFO - GECKO(2743) | Completed ShutdownLeaks collections in process 2743
[task 2020-12-11T00:11:15.976Z] 00:11:15     INFO - TEST-START | Shutdown
[task 2020-12-11T00:11:15.976Z] 00:11:15     INFO - Browser Chrome Test Summary
[task 2020-12-11T00:11:15.976Z] 00:11:15     INFO - Passed:  14
[task 2020-12-11T00:11:15.977Z] 00:11:15     INFO - Failed:  4
[task 2020-12-11T00:11:15.977Z] 00:11:15     INFO - Todo:    0
[task 2020-12-11T00:11:15.977Z] 00:11:15     INFO - Mode:    e10s
[task 2020-12-11T00:11:15.977Z] 00:11:15     INFO - *** End BrowserChrome Test Results ***```
Summary: Intermittent [TV] toolkit/components/printing/tests/browser_preview_navigation.js | Uncaught exception - 2 successive 'previous' clicks correctly update the sheet indicator - timed out after 50 tries. → Intermittent toolkit/components/printing/tests/browser_preview_navigation.js | Uncaught exception - 2 successive 'previous' clicks correctly update the sheet indicator - timed out after 50 tries.

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

Flags: needinfo?(sfoster)
Whiteboard: [retriggered]

Thanks, I'll look into this.

Assignee: nobody → sfoster
Flags: needinfo?(sfoster)
Severity: S4 → --
Priority: P5 → --
Summary: Intermittent toolkit/components/printing/tests/browser_preview_navigation.js | Uncaught exception - 2 successive 'previous' clicks correctly update the sheet indicator - timed out after 50 tries. → Intermittent macOS toolkit/components/printing/tests/browser_preview_navigation.js | Uncaught exception - 2 successive 'previous'/'next clicks correctly update the sheet indicator - timed out after 50 tries.
Whiteboard: [retriggered] → [print2020_v86] [old-ui-] [retriggered]
Severity: -- → S4
Priority: -- → P5

In the last 7 days there have been 20 occurrences on macosx1014-64-qr debug and opt.

Recent failure: https://treeherder.mozilla.org/logviewer?job_id=324908793&repo=autoland&lineNumber=7017

Assignee: sfoster → emalysz
Whiteboard: [print2020_v86] [old-ui-] [retriggered] → [print2020_v88] [old-ui-] [retriggered]
Pushed by emalysz@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/dbff302e8f1b remove await animation calls to fix intermittent test failure r=sfoster
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → FIXED
Target Milestone: --- → 87 Branch

Since the status are different for nightly and release, what's the status for beta?
For more information, please visit auto_nag documentation.

Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: