1640357 - Intermittent Reftest printing/<anything> print printing/<anything>-ref.html | Couldn't parse C:\Users\<path>.pdf, exception: InvalidPDFException: Invalid PDF structure.

Status: RESOLVED FIXED

(Core :: Printing: Output, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-05-22T23:49:23.358Z] 23:49:23 INFO - REFTEST TEST-START | layout/reftests/printing/test-async-print.html print layout/reftests/printing/test-text-ref.html
[task 2020-05-22T23:49:23.358Z] 23:49:23 INFO - REFTEST TEST-LOAD | file:///Z:/task_1590188968/build/tests/reftest/tests/layout/reftests/printing/test-async-print.html | 7 / 10 (70%)
[task 2020-05-22T23:49:23.438Z] 23:49:23 WARNING - REFTEST WARNING | [CONTENT] MozInvalidateEvent didn't invalidate
[task 2020-05-22T23:49:23.623Z] 23:49:23 INFO - REFTEST TEST-LOAD | file:///Z:/task_1590188968/build/tests/reftest/tests/layout/reftests/printing/test-text-ref.html | 7 / 10 (70%)
[task 2020-05-22T23:49:25.752Z] 23:49:25 INFO - console.log: "Warning: Indexing all PDF objects"
[task 2020-05-22T23:49:25.771Z] 23:49:25 INFO - REFTEST TEST-UNEXPECTED-FAIL | layout/reftests/printing/test-async-print.html print layout/reftests/printing/test-text-ref.html | Couldn't parse C:\Users\task_1590188968\AppData\Local\Temp\reftest-print-1590191364138-a272e553.pdf, exception: InvalidPDFException: Invalid PDF structure.
[task 2020-05-22T23:49:25.771Z] 23:49:25 INFO - REFTEST TEST-END | layout/reftests/printing/test-async-print.html print layout/reftests/printing/test-text-ref.html
[task 2020-05-22T23:49:25.772Z] 23:49:25 INFO - REFTEST TEST-END | layout/reftests/printing/test-async-print.html print layout/reftests/printing/test-text-ref.html

Comment 3

[Intermittent Failures Robot]

10 failures in 4727 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 4
• autoland: 6

Platform breakdown:

• windows10-64-ccov: 3
• windows10-64: 2
• windows10-64-shippable: 1
• windows10-64-shippable-qr: 1
• linux1804-64-asan-qr: 1
• windows10-64-mingwclang: 2

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

Comment 4

[Bob Owen (:bobowen)]

All of the failures that I have checked have this line in the log just before the failure:
console.log: "Warning: Indexing all PDF objects"

which comes from [1]
The error is thrown from indexObjects, which make me wonder if in the other cases it uses readXRef.
I can't quite see where recoveryMode gets originally passed in.

[1] https://searchfox.org/mozilla-central/rev/ea7f70dac1c5fd18400f6d2a92679777d4b21492/browser/extensions/pdfjs/content/build/pdf.worker.js#4679

Comment 5

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

Bug 1638364 updated pdf.js and also modified this file. The issue affects only Windows for which we already have known issues with temporary files (e.g. with XPCshell).

Comment 6

[Ryan VanderMeulen [:RyanVM]]

If I'm not mistaken, this first started happening 2 days after that pdf.js update landed? Have we for sure bisected this to be the root cause of the failure?

Comment 7

[Bob Owen (:bobowen)]

(In reply to Bob Owen (:bobowen) from comment #4)
...

I can't quite see where recoveryMode gets originally passed in.

Dug a bit further, this comes ultimately from the loadDocumnt call [1].
With it looks like loadDocument(true) being called if the first one fails.

[1] https://searchfox.org/mozilla-central/rev/ea7f70dac1c5fd18400f6d2a92679777d4b21492/browser/extensions/pdfjs/content/build/pdf.worker.js#434

Comment 8

[Intermittent Failures Robot]

20 failures in 5458 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 13
• mozilla-central: 4
• try: 3

Platform breakdown:

• windows10-64-mingwclang: 3
• windows10-64-shippable: 3
• windows10-64: 7
• windows10-64-ccov: 2
• windows10-64-shippable-qr: 1
• windows10-64-qr: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1640357&startday=2020-05-25&endday=2020-05-31&tree=all

Comment 9

[Intermittent Failures Robot]

12 failures in 4949 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 8
• try: 1
• mozilla-central: 2
• mozilla-beta: 1

Platform breakdown:

• windows10-64: 7
• windows10-64-shippable: 1
• windows10-64-shippable-qr: 3
• windows10-64-devedition: 1

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

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1
• autoland: 1

Platform breakdown:

• windows10-64: 1
• linux1804-64-asan: 1

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

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 3

Platform breakdown:

• windows10-64-shippable-qr: 3

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

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• windows10-64-shippable: 1

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

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr78: 1

Platform breakdown:

• windows10-64-shippable: 1

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

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr78: 1

Platform breakdown:

• windows10-64-mingwclang: 1

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

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr78: 2

Platform breakdown:

• windows10-64: 1
• windows10-64-shippable: 1

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

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr78: 1
• autoland: 1

Platform breakdown:

• windows10-64-mingwclang: 1
• macosx1014-64: 1

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

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr78: 1

Platform breakdown:

• windows10-64: 1

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

Comment 18

[Ryan VanderMeulen [:RyanVM]]

Looks like this stopped right around the time bug 1641733 landed.

Comment 19

[Ryan VanderMeulen [:RyanVM]]

Try push of bug 1641733 grafted to ESR78:
https://treeherder.mozilla.org/#/jobs?repo=try&revision=750edb9d2e3f87b6fc95620b52477f2d392e3ecf

Failures definitely look gone in comparison to the baseline rate shown on ESR78. I'm calling this fixed by bug 1641733.

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr78: 5

Platform breakdown:

• windows10-64-shippable: 2
• windows10-64: 1
• windows10-64-mingwclang: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1640357&startday=2020-09-21&endday=2020-09-27&tree=all