1746265 - Intermittent TEST-UNEXPECTED-TIMEOUT | browser/components/sessionstore/test/browser_windowStateContainer.js | application timed out after 370 seconds with no output

Status: RESOLVED INCOMPLETE

(Firefox :: Session Restore, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2021-12-15T20:29:14.857Z] 20:29:14     INFO - TEST-PASS | browser/components/sessionstore/test/browser_windowStateContainer.js | 1st Window: tabs[1].userContextId should be 1 - 1 == 1 - 
[task 2021-12-15T20:29:14.858Z] 20:29:14     INFO - Buffered messages finished
[task 2021-12-15T20:29:14.858Z] 20:29:14    ERROR - TEST-UNEXPECTED-TIMEOUT | browser/components/sessionstore/test/browser_windowStateContainer.js | application timed out after 370 seconds with no output
[task 2021-12-15T20:29:14.858Z] 20:29:14    ERROR - Force-terminating active process(es).
[task 2021-12-15T20:29:14.859Z] 20:29:14     INFO - Determining child pids from psutil...
[task 2021-12-15T20:29:14.859Z] 20:29:14     INFO - [1661, 1684, 1750, 1781, 1811, 1846, 2060]
[task 2021-12-15T20:29:14.860Z] 20:29:14     INFO - ==> process 1586 launched child process 1600
[task 2021-12-15T20:29:14.860Z] 20:29:14     INFO - ==> process 1586 launched child process 1661
[task 2021-12-15T20:29:14.860Z] 20:29:14     INFO - ==> process 1586 launched child process 1684
[task 2021-12-15T20:29:14.862Z] 20:29:14     INFO - ==> process 1586 launched child process 1750
[task 2021-12-15T20:29:14.862Z] 20:29:14     INFO - ==> process 1586 launched child process 1781
[task 2021-12-15T20:29:14.862Z] 20:29:14     INFO - ==> process 1586 launched child process 1811
[task 2021-12-15T20:29:14.862Z] 20:29:14     INFO - ==> process 1586 launched child process 1846
[task 2021-12-15T20:29:14.862Z] 20:29:14     INFO - ==> process 1586 launched child process 2034
[task 2021-12-15T20:29:14.862Z] 20:29:14     INFO - ==> process 1586 launched child process 2359
[task 2021-12-15T20:29:14.862Z] 20:29:14     INFO - ==> process 1586 launched child process 2543
[task 2021-12-15T20:29:14.862Z] 20:29:14     INFO - ==> process 1586 launched child process 2568
[task 2021-12-15T20:29:14.862Z] 20:29:14     INFO - Found child pids: {1600, 2568, 2060, 2543, 2034, 1811, 1684, 1781, 1750, 1846, 2359, 1661}
[task 2021-12-15T20:29:14.862Z] 20:29:14     INFO - Failed to get child procs
[task 2021-12-15T20:29:14.863Z] 20:29:14     INFO - Killing process: 1600
[task 2021-12-15T20:29:14.863Z] 20:29:14     INFO - TEST-INFO | started process screentopng
[task 2021-12-15T20:29:15.350Z] 20:29:15     INFO - TEST-INFO | screentopng: killed by SIGSEGV
[task 2021-12-15T20:29:15.410Z] 20:29:15     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:29:16.738Z] 20:29:16     INFO - Error: Failed to kill process 1600: psutil.NoSuchProcess no process found with pid 1600
[task 2021-12-15T20:29:17.555Z] 20:29:16     INFO - Killing process: 2568
[task 2021-12-15T20:29:17.651Z] 20:29:17     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-15T20:29:17.656Z] 20:29:17     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:29:19.435Z] 20:29:19     INFO - Error: Failed to kill process 2568: psutil.NoSuchProcess no process found with pid 2568
[task 2021-12-15T20:29:19.989Z] 20:29:19     INFO - Killing process: 2060
[task 2021-12-15T20:29:20.532Z] 20:29:19     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-15T20:29:20.634Z] 20:29:20     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:29:52.296Z] 20:29:52     INFO - failed to kill pid 2060 after 30s
[task 2021-12-15T20:29:53.474Z] 20:29:53     INFO - Killing process: 2543
[task 2021-12-15T20:29:54.207Z] 20:29:53     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-15T20:29:54.612Z] 20:29:54     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:29:56.203Z] 20:29:56     INFO - Error: Failed to kill process 2543: psutil.NoSuchProcess no process found with pid 2543
[task 2021-12-15T20:29:57.278Z] 20:29:57     INFO - Killing process: 2034
[task 2021-12-15T20:29:57.809Z] 20:29:57     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-15T20:29:57.815Z] 20:29:57     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:29:57.815Z] 20:29:57     INFO - Error: Failed to kill process 2034: psutil.NoSuchProcess no process found with pid 2034
[task 2021-12-15T20:29:57.815Z] 20:29:57     INFO - Killing process: 1811
[task 2021-12-15T20:29:57.815Z] 20:29:57     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-15T20:29:57.815Z] 20:29:57     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:29:58.742Z] 20:29:58     INFO - psutil found pid 1811 dead
[task 2021-12-15T20:29:58.743Z] 20:29:58     INFO - Killing process: 1684
[task 2021-12-15T20:29:58.743Z] 20:29:58     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-15T20:29:58.744Z] 20:29:58     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:29:59.281Z] 20:29:59     INFO - psutil found pid 1684 dead
[task 2021-12-15T20:29:59.281Z] 20:29:59     INFO - Killing process: 1781
[task 2021-12-15T20:29:59.282Z] 20:29:59     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-15T20:29:59.282Z] 20:29:59     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:30:00.263Z] 20:30:00     INFO - psutil found pid 1781 dead
[task 2021-12-15T20:30:00.263Z] 20:30:00     INFO - Killing process: 1750
[task 2021-12-15T20:30:00.263Z] 20:30:00     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-15T20:30:00.263Z] 20:30:00     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:30:05.431Z] 20:30:05     INFO - psutil found pid 1750 dead
[task 2021-12-15T20:30:05.431Z] 20:30:05     INFO - Killing process: 1846
[task 2021-12-15T20:30:05.432Z] 20:30:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-15T20:30:05.432Z] 20:30:05     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:30:05.650Z] 20:30:05     INFO - psutil found pid 1846 dead
[task 2021-12-15T20:30:05.651Z] 20:30:05     INFO - Killing process: 2359
[task 2021-12-15T20:30:05.651Z] 20:30:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-15T20:30:05.651Z] 20:30:05     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:30:05.651Z] 20:30:05     INFO - Error: Failed to kill process 2359: psutil.NoSuchProcess no process found with pid 2359
[task 2021-12-15T20:30:05.651Z] 20:30:05     INFO - Killing process: 1661
[task 2021-12-15T20:30:05.651Z] 20:30:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-15T20:30:05.651Z] 20:30:05     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:30:05.906Z] 20:30:05     INFO - psutil found pid 1661 dead
[task 2021-12-15T20:30:05.907Z] 20:30:05     INFO - Killing process: 1586
[task 2021-12-15T20:30:05.908Z] 20:30:05     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-12-15T20:30:05.908Z] 20:30:05     INFO - Can't trigger Breakpad, just killing process
[task 2021-12-15T20:30:35.934Z] 20:30:35     INFO - psutil found pid 1586 dead
[task 2021-12-15T20:30:35.934Z] 20:30:35     INFO - failed to kill pid 2060 after 30s
[task 2021-12-15T20:30:35.934Z] 20:30:35     INFO - psutil found pid 1586 dead
[task 2021-12-15T20:30:35.934Z] 20:30:35     INFO - TEST-INFO | Main app process: exit 0

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1746265&startday=2021-12-13&endday=2021-12-19&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=365029908&repo=autoland

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1746265&startday=2022-01-17&endday=2022-01-23&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=368412131&repo=autoland

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-tsan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1746265&startday=2022-02-14&endday=2022-02-20&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.