Closed Bug 1679472 Opened 3 years ago Closed 3 years ago

Intermittent remote/test/browser/page/browser_printToPDF.js | Test timed out -

Categories

(Remote Protocol :: Agent, defect, P5)

Product:
Remote Protocol
Component:
Agent
Type:
defect

Tracking

(firefox84 unaffected, firefox85 fixed)

Status:
RESOLVED FIXED
Milestone:
85 Branch
Tracking Flags:
Tracking Status
firefox84 --- unaffected
firefox85 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Filed by: nerli [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=322828356&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/LxNalmCtSiaFXA-6VLp_uQ/runs/0/artifacts/public/logs/live_backing.log

[task 2020-11-26T21:03:55.866Z] 21:03:55     INFO - TEST-START | remote/test/browser/page/browser_printToPDF.js
[task 2020-11-26T21:03:56.255Z] 21:03:56     INFO - GECKO(1424) | 1606424636252	RemoteAgent	TRACE	(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d})-> {"id":1,"method":"Target.setDiscoverTargets","params":{"discover":true}}
[task 2020-11-26T21:03:56.255Z] 21:03:56     INFO - GECKO(1424) | 1606424636252	RemoteAgent	TRACE	<-(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":"d6260f87-379c-40fb-ba40-ca2cd9e4a262","type":"page","url":"about:blank"}}}
[task 2020-11-26T21:03:56.256Z] 21:03:56     INFO - GECKO(1424) | 1606424636252	RemoteAgent	TRACE	<-(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d}) {"method":"Target.targetCreated","params":{"targetInfo":{"targetId":"7238eaa2-93cf-4b6e-82d2-774ab7ca8036","type":"browser"}}}
[task 2020-11-26T21:03:56.256Z] 21:03:56     INFO - GECKO(1424) | 1606424636252	RemoteAgent	TRACE	<-(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":"a8b26901-791c-4e93-a0f8-6e38637595ae","type":"page","url":"about:blank"}}}
[task 2020-11-26T21:03:56.256Z] 21:03:56     INFO - GECKO(1424) | 1606424636252	RemoteAgent	TRACE	<-(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d}) {"id":1}
[task 2020-11-26T21:03:56.266Z] 21:03:56     INFO - GECKO(1424) | 1606424636264	RemoteAgent	TRACE	(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d})-> {"id":2,"method":"Page.printToPDF","params":{"transferMode":"ReturnAsBase64"}}
[task 2020-11-26T21:03:56.566Z] 21:03:56     INFO - GECKO(1424) | 1606424636561	RemoteAgent	TRACE	<-(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d}) {"id":2,"result":{"data":"JVBERi0xLjUKJbXtrvsKMyAwIG9iago8PCAvTGVuZ3RoIDQgMCBSCiAgIC9GaWx0ZXIgL0ZsYXRlRGVjb2RlCj4+CnN0cmVhbQp ... IAp0cmFpbGVyCjw8IC9TaXplIDE1CiAgIC9Sb290IDE0IDAgUgogICAvSW5mbyAxMyAwIFIKPj4Kc3RhcnR4cmVmCjEyNDQyCiUlRU9GCg==","stream":null}}
[task 2020-11-26T21:03:56.566Z] 21:03:56     INFO - GECKO(1424) | 1606424636562	RemoteAgent	TRACE	(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d})-> {"id":3,"method":"Page.printToPDF","params":{}}
[task 2020-11-26T21:03:56.811Z] 21:03:56     INFO - GECKO(1424) | 1606424636797	RemoteAgent	TRACE	<-(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d}) {"id":3,"result":{"data":"JVBERi0xLjUKJbXtrvsKMyAwIG9iago8PCAvTGVuZ3RoIDQgMCBSCiAgIC9GaWx0ZXIgL0ZsYXRlRGVjb2RlCj4+CnN0cmVhbQp ... IAp0cmFpbGVyCjw8IC9TaXplIDE1CiAgIC9Sb290IDE0IDAgUgogICAvSW5mbyAxMyAwIFIKPj4Kc3RhcnR4cmVmCjEyNDQyCiUlRU9GCg==","stream":null}}
[task 2020-11-26T21:03:56.811Z] 21:03:56     INFO - GECKO(1424) | 1606424636798	RemoteAgent	TRACE	(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d})-> {"id":4,"method":"Page.printToPDF","params":{"transferMode":"ReturnAsFoo"}}
[task 2020-11-26T21:03:57.033Z] 21:03:57     INFO - GECKO(1424) | 1606424637028	RemoteAgent	TRACE	<-(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d}) {"id":4,"result":{"data":"JVBERi0xLjUKJbXtrvsKMyAwIG9iago8PCAvTGVuZ3RoIDQgMCBSCiAgIC9GaWx0ZXIgL0ZsYXRlRGVjb2RlCj4+CnN0cmVhbQp ... IAp0cmFpbGVyCjw8IC9TaXplIDE1CiAgIC9Sb290IDE0IDAgUgogICAvSW5mbyAxMyAwIFIKPj4Kc3RhcnR4cmVmCjEyNDQyCiUlRU9GCg==","stream":null}}
[task 2020-11-26T21:03:57.033Z] 21:03:57     INFO - GECKO(1424) | 1606424637030	RemoteAgent	TRACE	(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d})-> {"id":5,"method":"Page.printToPDF","params":{"transferMode":"ReturnAsStream"}}
[task 2020-11-26T21:04:40.880Z] 21:04:40     INFO - TEST-INFO | started process screenshot
[task 2020-11-26T21:04:40.940Z] 21:04:40     INFO - TEST-INFO | screenshot: exit 0
[task 2020-11-26T21:04:40.942Z] 21:04:40     INFO - Buffered messages logged at 21:03:55
[task 2020-11-26T21:04:40.942Z] 21:04:40     INFO - Entering test bound transferModes
[task 2020-11-26T21:04:40.943Z] 21:04:40     INFO - CDP server started
[task 2020-11-26T21:04:40.943Z] 21:04:40     INFO - Buffered messages logged at 21:03:56
[task 2020-11-26T21:04:40.944Z] 21:04:40     INFO - CDP client instantiated
[task 2020-11-26T21:04:40.944Z] 21:04:40     INFO - Global property added while loading chrome://global/content/printUtils.js: gFocusedElement
[task 2020-11-26T21:04:40.944Z] 21:04:40     INFO - Global property added while loading chrome://global/content/printUtils.js: gPendingPrintPreviews
[task 2020-11-26T21:04:40.945Z] 21:04:40     INFO - Global property added while loading chrome://global/content/printUtils.js: PRINT_TAB_MODAL
[task 2020-11-26T21:04:40.945Z] 21:04:40     INFO - Global property added while loading chrome://global/content/printUtils.js: PRINT_ALWAYS_SILENT
[task 2020-11-26T21:04:40.945Z] 21:04:40     INFO - Global property added while loading chrome://global/content/printUtils.js: PromptUtils
[task 2020-11-26T21:04:40.946Z] 21:04:40     INFO - Global property added while loading chrome://global/content/printUtils.js: PrintingParent
[task 2020-11-26T21:04:40.946Z] 21:04:40     INFO - TEST-PASS | remote/test/browser/page/browser_printToPDF.js | No stream handle is returned - 
[task 2020-11-26T21:04:40.946Z] 21:04:40     INFO - TEST-PASS | remote/test/browser/page/browser_printToPDF.js | Base64 encoded data is returned - 
[task 2020-11-26T21:04:40.946Z] 21:04:40     INFO - TEST-PASS | remote/test/browser/page/browser_printToPDF.js | Decoded data starts with the PDF signature - 
[task 2020-11-26T21:04:40.947Z] 21:04:40     INFO - TEST-PASS | remote/test/browser/page/browser_printToPDF.js | Decoded data ends with the EOF flag - 
[task 2020-11-26T21:04:40.947Z] 21:04:40     INFO - TEST-PASS | remote/test/browser/page/browser_printToPDF.js | By default no stream handle is returned - 
[task 2020-11-26T21:04:40.947Z] 21:04:40     INFO - TEST-PASS | remote/test/browser/page/browser_printToPDF.js | By default base64 encoded data is returned - 
[task 2020-11-26T21:04:40.948Z] 21:04:40     INFO - TEST-PASS | remote/test/browser/page/browser_printToPDF.js | Decoded data starts with the PDF signature - 
[task 2020-11-26T21:04:40.948Z] 21:04:40     INFO - TEST-PASS | remote/test/browser/page/browser_printToPDF.js | Decoded data ends with the EOF flag - 
[task 2020-11-26T21:04:40.948Z] 21:04:40     INFO - Buffered messages logged at 21:03:57
[task 2020-11-26T21:04:40.948Z] 21:04:40     INFO - TEST-PASS | remote/test/browser/page/browser_printToPDF.js | Unknown mode doesn't return a stream - 
[task 2020-11-26T21:04:40.948Z] 21:04:40     INFO - TEST-PASS | remote/test/browser/page/browser_printToPDF.js | Unknown mode defaults to base64 encoded data - 
[task 2020-11-26T21:04:40.949Z] 21:04:40     INFO - TEST-PASS | remote/test/browser/page/browser_printToPDF.js | Decoded data starts with the PDF signature - 
[task 2020-11-26T21:04:40.949Z] 21:04:40     INFO - TEST-PASS | remote/test/browser/page/browser_printToPDF.js | Decoded data ends with the EOF flag - 
[task 2020-11-26T21:04:40.949Z] 21:04:40     INFO - Buffered messages finished
[task 2020-11-26T21:04:40.949Z] 21:04:40     INFO - TEST-UNEXPECTED-FAIL | remote/test/browser/page/browser_printToPDF.js | Test timed out - 
[task 2020-11-26T21:04:40.950Z] 21:04:40     INFO - GECKO(1424) | MEMORY STAT | vsize 2104365MB | vsizeMaxContiguous 65560912MB | residentFast 318MB | heapAllocated 80MB
[task 2020-11-26T21:04:40.950Z] 21:04:40     INFO - TEST-OK | remote/test/browser/page/browser_printToPDF.js | took 45041ms
[task 2020-11-26T21:04:40.950Z] 21:04:40     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-11-26T21:04:40.952Z] 21:04:40     INFO - TEST-UNEXPECTED-FAIL | remote/test/browser/page/browser_printToPDF.js | Found a tab after previous test timed out: data:text/html;charset=utf-8,%3C!doctype%20html%3E%0A%3Cmeta%20charset%3Dutf-8%3E%0A%3Cdiv%20style%3D'background-color%3A%20green'%3EHello%20world%3C%2Fdiv%3E - 
[task 2020-11-26T21:04:40.952Z] 21:04:40     INFO - GECKO(1424) | 1606424680911	RemoteAgent	TRACE	<-(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d}) {"method":"Target.targetDestroyed","params":{"targetId":"a8b26901-791c-4e93-a0f8-6e38637595ae"}}
[task 2020-11-26T21:04:40.952Z] 21:04:40     INFO - GECKO(1424) | 1606424680918	RemoteAgent	TRACE	<-(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d}) {"method":"Target.targetCreated","params":{"targetInfo":{"browserContextId":null,"targetId":"6616795e-0e78-4b4e-9795-02c629e7c584","type":"page","url":"about:blank"}}}
[task 2020-11-26T21:04:40.952Z] 21:04:40     INFO - GECKO(1424) | 1606424680925	RemoteAgent	TRACE	<-(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d}) {"method":"Target.targetDestroyed","params":{"targetId":"d6260f87-379c-40fb-ba40-ca2cd9e4a262"}}
[task 2020-11-26T21:04:40.952Z] 21:04:40     INFO - checking window state```

Here the failing lines:

[task 2020-11-26T21:03:57.033Z] 21:03:57     INFO - GECKO(1424) | 1606424637030	RemoteAgent	TRACE	(connection {1c6130dd-0cf3-4265-9fe1-0d0ba376297d})-> {"id":5,"method":"Page.printToPDF","params":{"transferMode":"ReturnAsStream"}}
[task 2020-11-26T21:04:40.880Z] 21:04:40     INFO - TEST-INFO | started process screenshot

There is no reply from the command.

The failures stopped after the backout of the patch from bug 1645528:

https://treeherder.mozilla.org/jobs?repo=autoland&searchStr=remote+mochi&fromchange=3a9dce7353402f5fc6cb2edf894822396417eea4&selectedTaskRun=RZn8tW2fSoaZ7IGZcUOVeQ.0&tochange=35411828d9d32ac316000388ae7b5da16c800cfc

Assignee: nobody → robert.mader
Status: NEW → RESOLVED
Closed: 3 years ago
status-firefox84: --- → unaffected
status-firefox85: --- → fixed
Regressed by: 1645528
Resolution: --- → FIXED
Target Milestone: --- → 85 Branch
You need to log in before you can comment on or make changes to this bug.