1659985 - Intermittent raptor-perftest Critical: [chrome] Test failed to finish. Application timed out after 1821 seconds

Status: RESOLVED DUPLICATE of bug 1659120

(Testing :: Raptor, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nbeleuzu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=313434851&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/DlXEPhtCTPur4HnW8kGphQ/runs/0/artifacts/public/logs/live_backing.log

---

[task 2020-08-19T12:19:37.922Z] 12:19:37 INFO - Application command: C:\Progra~2\Google\Chrome\Application\chrome.exe --allow-downgrade --user-data-dir=c:\users\task_1597839164\appdata\local\temp\tmpj6yyjl.mozrunner --profile-directory=Default --no-first-run --use-mock-keychain --no-default-browser-check --proxy-server=127.0.0.1:49790 --proxy-bypass-list=localhost;127.0.0.1 --ignore-certificate-errors --load-extension=C:\Users\task_1597839164\build\tests\raptor\raptor\webextension....\webext\raptor
[task 2020-08-19T12:19:38.610Z] 12:19:38 INFO - raptor-control-server Info: received webext_loaded: raptor runner.js is loaded!
[task 2020-08-19T12:19:38.610Z] 12:19:38 INFO - raptor-control-server Info: received webext_status: testing on Chrome 84.0.4147.125
[task 2020-08-19T12:19:38.615Z] 12:19:38 INFO - raptor-control-server Info: received webext_status: test name is: raptor-tp6-docs-chrome
[task 2020-08-19T12:19:38.617Z] 12:19:38 INFO - raptor-control-server Info: received webext_status: test settings url is: http://127.0.0.1:49789/json/raptor-tp6-docs-chrome.json
[task 2020-08-19T12:19:38.619Z] 12:19:38 INFO - raptor-control-server Info: received webext_status: starting raptorRunner
[task 2020-08-19T12:19:38.624Z] 12:19:38 INFO - raptor-control-server Info: reading test settings from json/raptor-tp6-docs-chrome.json
[task 2020-08-19T12:19:38.624Z] 12:19:38 INFO - raptor-control-server Info: sent test settings to webext runner
[task 2020-08-19T12:19:38.643Z] 12:19:38 INFO - raptor-control-server Info: received webext_status: * pausing 1 seconds to let browser settle... *
[task 2020-08-19T12:19:39.653Z] 12:19:39 INFO - raptor-control-server Info: received webext_status: opening new tab
[task 2020-08-19T12:19:39.672Z] 12:19:39 INFO - raptor-control-server Info: received webext_status: opened new empty tab: 3
[task 2020-08-19T12:19:39.674Z] 12:19:39 INFO - raptor-control-server Info: received webext_status: found active tab with id 3
[task 2020-08-19T12:19:39.680Z] 12:19:39 INFO - raptor-control-server Info: received webext_status: running 25 pagecycles of https://docs.google.com/document/d/1US-07msg12slQtI_xchzYxcKlTs6Fp7WqIc6W5GK5M8/edit?usp=sharing
[task 2020-08-19T12:19:40.684Z] 12:19:40 INFO - raptor-control-server Info: received webext_status: begin page cycle 1
[task 2020-08-19T12:19:40.686Z] 12:19:40 INFO - raptor-control-server Info: received webext_status: update tab 3 for https://docs.google.com/document/d/1US-07msg12slQtI_xchzYxcKlTs6Fp7WqIc6W5GK5M8/edit?usp=sharing
[task 2020-08-19T12:19:40.691Z] 12:19:40 INFO - raptor-control-server Info: received webext_status: tab 3 updated
[task 2020-08-19T12:19:40.696Z] 12:19:40 INFO - raptor-control-server Info: received webext_status: ended page cycle 1
[task 2020-08-19T12:19:44.281Z] 12:19:44 INFO - raptor-control-server Info: received webext_status: results received
[task 2020-08-19T12:19:45.292Z] 12:19:45 INFO - raptor-control-server Info: received webext_status: begin page cycle 2
[task 2020-08-19T12:19:45.292Z] 12:19:45 INFO - raptor-control-server Info: received webext_status: update tab 3 for https://docs.google.com/document/d/1US-07msg12slQtI_xchzYxcKlTs6Fp7WqIc6W5GK5M8/edit?usp=sharing
[task 2020-08-19T12:19:45.292Z] 12:19:45 INFO - raptor-control-server Info: received webext_status: tab 3 updated
[task 2020-08-19T12:19:45.297Z] 12:19:45 INFO - raptor-control-server Info: received webext_status: ended page cycle 2
[task 2020-08-19T12:19:47.619Z] 12:19:47 INFO - raptor-control-server Info: received webext_status: results received
[task 2020-08-19T12:19:48.619Z] 12:19:48 INFO - raptor-control-server Info: received webext_status: begin page cycle 3
[task 2020-08-19T12:19:48.624Z] 12:19:48 INFO - raptor-control-server Info: received webext_status: update tab 3 for https://docs.google.com/document/d/1US-07msg12slQtI_xchzYxcKlTs6Fp7WqIc6W5GK5M8/edit?usp=sharing
[task 2020-08-19T12:19:48.626Z] 12:19:48 INFO - raptor-control-server Info: received webext_status: tab 3 updated
[task 2020-08-19T12:19:48.630Z] 12:19:48 INFO - raptor-control-server Info: received webext_status: ended page cycle 3
[task 2020-08-19T12:19:51.012Z] 12:19:51 INFO - raptor-control-server Info: received webext_status: results received
[task 2020-08-19T12:19:52.015Z] 12:19:52 INFO - raptor-control-server Info: received webext_status: begin page cycle 4
[task 2020-08-19T12:19:52.017Z] 12:19:52 INFO - raptor-control-server Info: received webext_status: update tab 3 for https://docs.google.com/document/d/1US-07msg12slQtI_xchzYxcKlTs6Fp7WqIc6W5GK5M8/edit?usp=sharing
[task 2020-08-19T12:19:52.022Z] 12:19:52 INFO - raptor-control-server Info: received webext_status: tab 3 updated
[task 2020-08-19T12:19:52.027Z] 12:19:52 INFO - raptor-control-server Info: received webext_status: ended page cycle 4
[task 2020-08-19T12:50:10.720Z] 12:50:10 CRITICAL - raptor-perftest Critical: [chrome] Test failed to finish. Application timed out after 1821 seconds
[task 2020-08-19T12:50:10.720Z] 12:50:10 INFO - raptor-mitmproxy Info: Stopping mitmproxy playback, killing process 712
[task 2020-08-19T12:50:10.720Z] 12:50:10 INFO - raptor-mitmproxy Info: Sending CTRL_BREAK_EVENT to mitmproxy
[task 2020-08-19T12:50:12.721Z] 12:50:12 INFO - raptor-mitmproxy Info: Successfully killed the mitmproxy playback process
[task 2020-08-19T12:50:12.721Z] 12:50:12 INFO - raptor-mitmproxy Info: Reading confidence values from: C:\Users\task_1597839164\build\blobber_upload_dir\mitm_netlocs_mitm5-linux-firefox-google-docs.json
[task 2020-08-19T12:50:12.721Z] 12:50:12 INFO - perftest-results-handler Info: RaptorResultsHandler.add_supporting_data received mozproxy data
[task 2020-08-19T12:50:12.721Z] 12:50:12 INFO - raptor-webext Info: removing webext C:\Users\task_1597839164\build\tests\raptor\raptor\webextension....\webext\raptor
[task 2020-08-19T12:50:12.721Z] 12:50:12 INFO - raptor-control-server Info: shutting down control server
[task 2020-08-19T12:50:13.088Z] 12:50:13 INFO - raptor-webext Info: finished
[task 2020-08-19T12:50:13.195Z] 12:50:13 ERROR - Return code: 1
[task 2020-08-19T12:50:13.195Z] 12:50:13 WARNING - setting return code to 1
[task 2020-08-19T12:50:13.195Z] 12:50:13 INFO - Copying Raptor results to upload dir:
[task 2020-08-19T12:50:13.195Z] 12:50:13 INFO - C:\Users\task_1597839164\build\blobber_upload_dir\perfherder-data.json

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 5
• autoland: 8

Platform breakdown:

• windows10-64-shippable: 5
• windows7-32-shippable: 5
• macosx1014-64-shippable: 3

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 5

Platform breakdown:

• windows10-64-shippable: 2
• linux64-shippable: 1
• macosx1014-64-shippable: 1
• windows7-32-shippable: 1

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