1759720 - Intermittent TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\system32\msvcp140.dll' (normalized from 'C:\Windows\System32\msvcp140.dll') was accessed and we were not expecting it. DiskReadCount: 6, DiskWriteCount: 0, DiskReadBytes: 114688, DiskWrite

Status: RESOLVED INCOMPLETE

(Testing :: Talos, defect, P5)

Description

[Treeherder Bug Filer]

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

---

INFO -  PID 6624 | Values: 457.1
[task 2022-03-15T15:36:32.156Z] 15:36:32     INFO -  PID 6624 |
[task 2022-03-15T15:36:32.156Z] 15:36:32     INFO -  PID 6624 | [#99] people.com.cn-talos/tests/tp5n/people.com.cn/people.com.cn/index.html  Cycles:1  Average:614.84  Median:614.84  stddev:0.00 (0.0%)
[task 2022-03-15T15:36:32.156Z] 15:36:32     INFO -  PID 6624 | Values: 614.8
[task 2022-03-15T15:36:32.156Z] 15:36:32     INFO -  PID 6624 | -------- Summary: end --------
[task 2022-03-15T15:36:32.156Z] 15:36:32     INFO -  PID 6624 |
[task 2022-03-15T15:36:32.524Z] 15:36:32     INFO -  PID 6624 |
[task 2022-03-15T15:36:32.538Z] 15:36:32     INFO -  PID 6624 | ###!!! [Parent][PImageBridgeParent] Error: RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost
[task 2022-03-15T15:36:32.538Z] 15:36:32     INFO -  PID 6624 |
[task 2022-03-15T15:36:32.640Z] 15:36:32     INFO -  TEST-INFO | 6624: exit 0
[task 2022-03-15T15:36:34.265Z] 15:36:34     INFO -  The trace you have just captured "Z:\task_164735601377046\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.
[task 2022-03-15T15:36:34.267Z] 15:36:34     INFO -  The trace you have just captured "Z:\task_164735601377046\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.
[task 2022-03-15T15:37:11.994Z] 15:37:11     INFO -  Merged Etl: test.etl
[task 2022-03-15T15:41:44.357Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\system32\msvcp140.dll' (normalized from 'C:\Windows\System32\msvcp140.dll') was accessed and we were not expecting it.  DiskReadCount: 6, DiskWriteCount: 0, DiskReadBytes: 114688, DiskWriteBytes: 0
[task 2022-03-15T15:41:44.362Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\system32\dbghelp.dll' (normalized from 'C:\Windows\System32\dbghelp.dll') was accessed and we were not expecting it.  DiskReadCount: 12, DiskWriteCount: 0, DiskReadBytes: 176128, DiskWriteBytes: 0
[task 2022-03-15T15:41:44.362Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\programdata\mozilla-1de4eec8-1241-4177-a864-e594e8d1fb38' (normalized from 'C:\ProgramData\Mozilla-1de4eec8-1241-4177-a864-e594e8d1fb38') was accessed and we were not expecting it.  DiskReadCount: 2, DiskWriteCount: 0, DiskReadBytes: 8192, DiskWriteBytes: 0
[task 2022-03-15T15:41:44.363Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\programdata\mozilla-1de4eec8-1241-4177-a864-e594e8d1fb38\updates' (normalized from 'C:\ProgramData\Mozilla-1de4eec8-1241-4177-a864-e594e8d1fb38\updates') was accessed and we were not expecting it.  DiskReadCount: 2, DiskWriteCount: 0, DiskReadBytes: 8192, DiskWriteBytes: 0
[task 2022-03-15T15:41:44.363Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\$secure:$sdh:$index_allocation' (normalized from 'C:\$Secure:$SDH:$INDEX_ALLOCATION') was accessed and we were not expecting it.  DiskReadCount: 2, DiskWriteCount: 0, DiskReadBytes: 8192, DiskWriteBytes: 0
[task 2022-03-15T15:41:44.363Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\$secure:$sds' (normalized from 'C:\$Secure:$SDS') was accessed and we were not expecting it.  DiskReadCount: 4, DiskWriteCount: 0, DiskReadBytes: 16384, DiskWriteBytes: 0
[task 2022-03-15T15:41:44.364Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File '{profile}\cookies.sqlite-journal' (normalized from 'C:\Users\task_164735601377046\AppData\Local\Temp\tmppp6vho17\profile\cookies.sqlite-journal') was accessed and we were not expecting it.  DiskReadCount: 0, DiskWriteCount: 12, DiskReadBytes: 0, DiskWriteBytes: 132120
[task 2022-03-15T15:41:44.364Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\system32\devobj.dll' (normalized from 'C:\Windows\System32\devobj.dll') was accessed and we were not expecting it.  DiskReadCount: 8, DiskWriteCount: 0, DiskReadBytes: 93184, DiskWriteBytes: 0
[task 2022-03-15T15:41:44.364Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\system32\imm32.dll' (normalized from 'C:\Windows\System32\imm32.dll') was accessed and we were not expecting it.  DiskReadCount: 2, DiskWriteCount: 0, DiskReadBytes: 65536, DiskWriteBytes: 0
[task 2022-03-15T15:41:44.365Z] 15:41:44     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: 20, DiskWriteCount: 0, DiskReadBytes: 655360, DiskWriteBytes: 0
[task 2022-03-15T15:41:44.365Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\system32\powrprof.dll' (normalized from 'C:\Windows\System32\powrprof.dll') was accessed and we were not expecting it.  DiskReadCount: 2, DiskWriteCount: 0, DiskReadBytes: 9216, DiskWriteBytes: 0
[task 2022-03-15T15:41:44.365Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File 'c:\windows\fonts\times.ttf' (normalized from 'C:\Windows\Fonts\times.ttf') was accessed and we were not expecting it.  DiskReadCount: 4, DiskWriteCount: 0, DiskReadBytes: 131072, DiskWriteBytes: 0
[task 2022-03-15T15:41:44.366Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File '{profile}\cert9.db-journal' (normalized from 'C:\Users\task_164735601377046\AppData\Local\Temp\tmppp6vho17\profile\cert9.db-journal') was accessed and we were not expecting it.  DiskReadCount: 2, DiskWriteCount: 10, DiskReadBytes: 16, DiskWriteBytes: 17456
[task 2022-03-15T15:41:44.366Z] 15:41:44     INFO -  TEST-UNEXPECTED-FAIL : xperf: File '{profile}\key4.db-journal' (normalized from 'C:\Users\task_164735601377046\AppData\Local\Temp\tmppp6vho17\profile\key4.db-journal') was accessed and we were not expecting it.  DiskReadCount: 4, DiskWriteCount: 30, DiskReadBytes: 32, DiskWriteBytes: 231544
[task 2022-03-15T15:41:46.680Z] 15:41:46     INFO -  extending with xperf!
[task 2022-03-15T15:41:46.680Z] 15:41:46     INFO -  mozcrash checking C:\Users\task_164735601377046\AppData\Local\Temp\tmppp6vho17\profile\minidumps for minidumps...
[task 2022-03-15T15:41:46.680Z] 15:41:46     INFO -  Detected a regression for tp5n
[task 2022-03-15T15:41:46.708Z] 15:41:46     INFO -  TEST-UNEXPECTED-FAIL | tp5n | Talos has found a regression, if you have questions ask for help in irc on #perf
[task 2022-03-15T15:41:46.711Z] 15:41:46    ERROR -  Traceback (most recent call last):
[task 2022-03-15T15:41:46.711Z] 15:41:46     INFO -    File "Z:\task_164735601377046\build\tests\talos\talos\run_tests.py", line 341, in run_tests
[task 2022-03-15T15:41:46.711Z] 15:41:46     INFO -      talos_results.add(mytest.runTest(browser_config, test))
[task 2022-03-15T15:41:46.711Z] 15:41:46     INFO -    File "Z:\task_164735601377046\build\tests\talos\talos\ttest.py", line 65, in runTest
[task 2022-03-15T15:41:46.711Z] 15:41:46     INFO -      return self._runTest(browser_config, test_config, setup)
[task 2022-03-15T15:41:46.712Z] 15:41:46     INFO -    File "Z:\task_164735601377046\build\tests\talos\talos\ttest.py", line 258, in _runTest
[task 2022-03-15T15:41:46.712Z] 15:41:46     INFO -      raise TalosRegression(
[task 2022-03-15T15:41:46.712Z] 15:41:46     INFO -  talos.utils.TalosRegression: Talos has found a regression, if you have questions ask for help in irc on #perf
[task 2022-03-15T15:41:46.712Z] 15:41:46     INFO -  TEST-INFO took 463657ms
[task 2022-03-15T15:41:46.712Z] 15:41:46     INFO -  SUITE-END | took 463s
[task 2022-03-15T15:41:47.084Z] 15:41:47    ERROR - Return code: 1

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows10-64-2004-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1759720&startday=2022-03-14&endday=2022-03-20&tree=all

Comment 2

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 3

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=391516483&repo=autoland

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows10-64-2004-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1759720&startday=2022-09-26&endday=2022-10-02&tree=all

Comment 5

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 6

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=395367807&repo=mozilla-central

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows10-64-2004-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1759720&startday=2022-10-31&endday=2022-11-06&tree=all

Comment 8

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 9

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=399407418&repo=autoland

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform and build breakdown:

• windows10-64-2004-shippable-qr: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1759720&startday=2022-12-12&endday=2022-12-18&tree=all

Comment 11

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 12

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=402586946&repo=mozilla-central

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• mozilla-beta: 1
• autoland: 1

Platform and build breakdown:

• windows10-64-2004-shippable-qr: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1759720&startday=2023-01-16&endday=2023-01-22&tree=all

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• windows10-64-2004-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1759720&startday=2023-01-30&endday=2023-02-05&tree=all

Comment 15

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.