1494698 - Perma fail xperf: File 'c:\windows\fonts\* " was accessed and we were not expecting it. DiskReadCount: X, DiskWriteCount: Y, DiskReadBytes: Z, DiskWriteBytes: W when Gecko 64 merges to Beta on 2018-10-15

Status: RESOLVED WORKSFORME

(Core :: Graphics: Text, defect, P3)

Description

[Raul Gurzau (:RaulG)]

[Tracking Requested - why for this release]:

Central as beta simulation: https://treeherder.mozilla.org/#/jobs?repo=try&resultStatus=testfailed,busted,exception&revision=79343dbcfb456f960ddf43e6037cfba5e4cc8f21&selectedJob=201944210

Log link: https://treeherder.mozilla.org/logviewer.html#?job_id=201944210&repo=try&lineNumber=1422

12:59:45     INFO -  PID 3508 | [#97] facebook.com/www.facebook.com/Google.html  Cycles:1  Average:153.00  Median:153.00  stddev:0.00 (0.0%)
12:59:45     INFO -  PID 3508 | Values: 153.0
12:59:45     INFO -  PID 3508 |
12:59:45     INFO -  PID 3508 | [#98] youtube.com/www.youtube.com/music.html  Cycles:1  Average:459.00  Median:459.00  stddev:0.00 (0.0%)
12:59:45     INFO -  PID 3508 | Values: 459.0
12:59:45     INFO -  PID 3508 |
12:59:45     INFO -  PID 3508 | [#99] people.com.cn/people.com.cn/index.html  Cycles:1  Average:1056.00  Median:1056.00  stddev:0.00 (0.0%)
12:59:45     INFO -  PID 3508 | Values: 1056.0
12:59:45     INFO -  PID 3508 | -------- Summary: end --------
12:59:45     INFO -  PID 3508 |
12:59:45     INFO -  PID 3508 | [Parent 3508, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
12:59:45     INFO -  PID 3508 | [Child 8584, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
12:59:45     INFO -  PID 3508 | [Parent 3508, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
12:59:45     INFO -  PID 3508 | [Child 5912, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
12:59:45     INFO -  PID 3508 | [Child 5912, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346
12:59:46     INFO -  TEST-INFO | 3508: exit 0
12:59:48     INFO -  The trace you have just captured "Z:\task_1538052250\build\test.etl.kernel" may contain personally identifiable information, including but not necessarily limited to paths to files accessed, paths to registry accessed and process names. Exact information depends on the events that were logged. Please be aware of this when sharing out this trace with other people.
12:59:48     INFO -  The trace you have just captured "Z:\task_1538052250\build\test.etl.user" may contain personally identifiable information, including but not necessarily limited to paths to files accessed, paths to registry accessed and process names. Exact information depends on the events that were logged. Please be aware of this when sharing out this trace with other people.
12:59:58     INFO -  Merged Etl: test.etl
13:02:08     INFO -  reading etl filename: test.etl
13:02:08     INFO -  etlparser: in readfile: test.etl.csv
13:02:08     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\fonts\timesbi.ttf' (normalized from 'C:\Windows\Fonts\timesbi.ttf') was accessed and we were not expecting it.  DiskReadCount: 4, DiskWriteCount: 0, DiskReadBytes: 114688, DiskWriteBytes: 0
13:02:08     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\fonts\segoeuib.ttf' (normalized from 'C:\Windows\Fonts\segoeuib.ttf') was accessed and we were not expecting it.  DiskReadCount: 2, DiskWriteCount: 0, DiskReadBytes: 65536, DiskWriteBytes: 0
13:02:08     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\fonts\verdanaz.ttf' (normalized from 'C:\Windows\Fonts\verdanaz.ttf') was accessed and we were not expecting it.  DiskReadCount: 4, DiskWriteCount: 0, DiskReadBytes: 131072, DiskWriteBytes: 0
13:02:08     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\fonts\arialbi.ttf' (normalized from 'C:\Windows\Fonts\arialbi.ttf') was accessed and we were not expecting it.  DiskReadCount: 12, DiskWriteCount: 0, DiskReadBytes: 253952, DiskWriteBytes: 0
13:02:08     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\fonts\impact.ttf' (normalized from 'C:\Windows\Fonts\impact.ttf') was accessed and we were not expecting it.  DiskReadCount: 8, DiskWriteCount: 0, DiskReadBytes: 262144, DiskWriteBytes: 0
13:02:08     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\serviceprofiles\localservice\appdata\local\fontcache\~fontcache-fontface.dat' (normalized from 'C:\Windows\ServiceProfiles\LocalService\AppData\Local\FontCache\~FontCache-FontFace.dat') was accessed and we were not expecting it.  DiskReadCount: 84, DiskWriteCount: 0, DiskReadBytes: 3301376, DiskWriteBytes: 0
13:02:08     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\fonts\l_10646.ttf' (normalized from 'C:\Windows\Fonts\l_10646.ttf') was accessed and we were not expecting it.  DiskReadCount: 4, DiskWriteCount: 0, DiskReadBytes: 122880, DiskWriteBytes: 0
13:02:08     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\fonts\arialbd.ttf' (normalized from 'C:\Windows\Fonts\arialbd.ttf') was accessed and we were not expecting it.  DiskReadCount: 28, DiskWriteCount: 0, DiskReadBytes: 819200, DiskWriteBytes: 0

Comment 1

[Intermittent Failures Robot]

15 failures in 522 pushes (0.029 failures/push) were associated with this bug yesterday.

Repository breakdown:
* try: 15

Platform breakdown:
* windows7-32: 2
* windows10-64: 7
* windows7-32-msvc: 6

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

Comment 2

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

xperf got touched by bug 1488554. Aaron, please check if it is from those commits.

Comment 3

[Intermittent Failures Robot]

18 failures in 2773 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* try: 18

Platform breakdown:
* windows7-32-msvc: 7
* windows7-32: 3
* windows10-64: 8

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

Comment 4

[(No longer employed by Mozilla) Aaron Klotz]

This might be because Talos needs to run differently depending on whether the launcher process runs by default. On Beta, the launcher process is turned off.

What happens if you back 8461e2f532ed out from the simulated beta repo?

Comment 5

[Raul Gurzau (:RaulG)]

Seems that the backout of 8461e2f532ed solved the failure.

Central as beta simulation links:

https://treeherder.mozilla.org/#/jobs?repo=try&resultStatus=testfailed,busted,exception,runnable&revision=7e35956f6e0553a6408d3f31e307e04b143574de

https://treeherder.mozilla.org/#/jobs?repo=try&resultStatus=testfailed,busted,exception&revision=5a9baf6903c1837b95b5e087e554b1f1ca8356dd

Comment 6

[(No longer employed by Mozilla) Aaron Klotz]

Okay. We'll have to make the backout when we make the merge to beta. We can't do it ahead of time on Nightly.

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:
* try: 4

Platform breakdown:
* windows7-32: 2
* windows10-64: 2

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

Comment 8

[Ryan VanderMeulen [:RyanVM]]

This was backed out from Beta.
https://hg.mozilla.org/releases/mozilla-beta/rev/f76d65097eb36e929926ebf9ebe86052bbd2f67e

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:
* try: 2

Platform breakdown:
* windows10-64: 1
* windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1494698&startday=2018-10-22&endday=2018-10-28&tree=all

Comment 11

[Andreea Pavel [:apavel]]

Update: this occurs on latest beta simulations:

https://treeherder.mozilla.org/#/jobs?repo=try&resultStatus=testfailed%2Cbusted%2Cexception%2Cretry%2Cusercancel%2Crunnable&revision=658045845a5beee8ce27a6b00ccfb79302fda273&selectedJob=209691236

Failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=209691236&repo=try&lineNumber=1424

Comment 12

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

The branching of 65 for beta is a month away - when will we know if we need another backout for beta?

Comment 13

[(No longer employed by Mozilla) Aaron Klotz]

We will be needing another backout for 65 beta. I expect that we won't for 66.

Comment 14

[Intermittent Failures Robot]

8 failures in 3007 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:
* try: 8

Platform breakdown:
* windows7-32: 3
* windows10-64: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1494698&startday=2018-11-05&endday=2018-11-11&tree=all

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:
* try: 1

Platform breakdown:
* windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1494698&startday=2018-11-26&endday=2018-12-02&tree=all

Comment 16

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

Backed out the changeset of bug 1488554 from beta: https://hg.mozilla.org/releases/mozilla-beta/rev/f9bba4d1865f88fd683053ab3fa5c32bf9b69a2a

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-beta: 1

Platform breakdown:
* windows7-32: 1

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

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* windows10-64: 1

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

Comment 19

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

Backed out once more (from 66 beta): https://hg.mozilla.org/releases/mozilla-beta/rev/bbeefec15359ea3c5d49896307b840d4a562fe14

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 4

Platform breakdown:

• windows10-64: 2
• windows7-32: 2

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

Comment 21

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 2

Platform breakdown:

• windows10-64: 1
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1494698&startday=2019-01-28&endday=2019-02-03&tree=all

Comment 22

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

This hasn't failed in beta simulations since Gecko uses version 67 and also doesn't fail on beta (first merge of Gecko 67 to it was yesterday). Aaron, can this bug be closed as worksforme?

Comment 23

[(No longer employed by Mozilla) Aaron Klotz]

Yes. 67 will be shipping with --enable-launcher-process, so we can consider this to be fixed.