1579822 - Intermittent raptor-main Critical: TEST-UNEXPECTED-FAIL: test 'raptor-tp6-paypal-firefox' timed out loading test page: https://www.paypal.com/myaccount/summary/ pending metrics: fcp, dcf

Status: RESOLVED INCOMPLETE

(Testing :: Raptor, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=265665169&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/FwQtJIFiR9ewDR_ca85exg/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-09-09T10:03:21.053Z] 10:03:21 INFO - raptor-control-server Info: received request to shutdown the browser
[task 2019-09-09T10:03:21.053Z] 10:03:21 INFO - raptor-results-handler Info: received results in RaptorResultsHandler.add
[task 2019-09-09T10:03:21.053Z] 10:03:21 INFO - raptor-control-server Info: shutting down browser (pid: 4436)
[task 2019-09-09T10:03:21.053Z] 10:03:21 INFO - PID 4436 | console.log: "[raptor-runnerjs] post success"
[task 2019-09-09T10:03:21.053Z] 10:03:21 INFO - PID 4436 | console.log: "[raptor-runnerjs] post success"
[task 2019-09-09T10:03:21.095Z] 10:03:21 INFO - PID 4436 | console.log: "[raptor-runnerjs] Removed tab: 2"
[task 2019-09-09T10:03:21.095Z] 10:03:21 INFO - PID 4436 | console.log: "[raptor-runnerjs] posting to control server"
[task 2019-09-09T10:03:21.095Z] 10:03:21 INFO - PID 4436 | console.log: "[raptor-runnerjs] Removed tab: 2"
[task 2019-09-09T10:03:21.104Z] 10:03:21 INFO - raptor-control-server Info: received webext_status: Removed tab: 2
[task 2019-09-09T10:03:21.104Z] 10:03:21 INFO - PID 4436 | console.log: "[raptor-runnerjs] post success"
[task 2019-09-09T10:03:22.911Z] 10:03:22 INFO - mozproxy Stopping mitmproxy playback, killing process 6796
[task 2019-09-09T10:03:22.930Z] 10:03:22 INFO - mozproxy Mitmproxy exited with error code 572
[task 2019-09-09T10:03:22.930Z] 10:03:22 INFO - mozproxy Turning off the browser proxy
[task 2019-09-09T10:03:22.930Z] 10:03:22 INFO - mozproxy writing: C:\Users\task_1568012354\build\application\firefox\distribution\policies.json
[task 2019-09-09T10:03:22.930Z] 10:03:22 INFO - raptor-main Info: removing webext C:\Users\task_1568012354\build\tests\raptor\raptor..\webext\raptor
[task 2019-09-09T10:03:22.930Z] 10:03:22 INFO - raptor-results-handler Info: summarizing raptor test results
[task 2019-09-09T10:03:22.931Z] 10:03:22 INFO - raptor-output Info: ignoring the first dcf value due to initial pageload noise
[task 2019-09-09T10:03:22.931Z] 10:03:22 INFO - raptor-output Info: ignoring the first fcp value due to initial pageload noise
[task 2019-09-09T10:03:22.931Z] 10:03:22 INFO - raptor-output Info: turning on subtest alerting for measurement type: fcp
[task 2019-09-09T10:03:22.931Z] 10:03:22 INFO - raptor-output Info: ignoring the first fnbpaint value due to initial pageload noise
[task 2019-09-09T10:03:22.931Z] 10:03:22 INFO - raptor-output Info: ignoring the first loadtime value due to initial pageload noise
[task 2019-09-09T10:03:22.931Z] 10:03:22 INFO - raptor-output Info: turning on subtest alerting for measurement type: loadtime
[task 2019-09-09T10:03:22.931Z] 10:03:22 INFO - raptor-output Info: screen captures can be found locally at: C:\Users\task_1568012354\build\screenshots.html
[task 2019-09-09T10:03:22.931Z] 10:03:22 INFO - raptor-output Info: PERFHERDER_DATA: {"framework": {"name": "raptor"}, "suites": [{"extraOptions": [], "name": "raptor-tp6-tumblr-firefox", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 476.68, "subtests": [{"name": "dcf", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 418.0, "replicates": [1138, 562, 555, 710, 417, 417, 425, 425, 411, 429, 421, 417, 411, 421, 419, 418, 409, 418, 403, 413, 419, 408, 413, 413, 420], "unit": "ms"}, {"name": "fcp", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 453.0, "shouldAlert": true, "replicates": [1423, 583, 691, 718, 448, 443, 455, 456, 526, 452, 454, 442, 444, 451, 449, 449, 438, 448, 427, 444, 555, 540, 537, 551, 555], "unit": "ms"}, {"name": "fnbpaint", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 434.5, "replicates": [1282, 559, 671, 707, 427, 426, 435, 435, 518, 439, 434, 427, 425, 433, 429, 427, 419, 427, 413, 424, 529, 516, 525, 526, 533], "unit": "ms"}, {"name": "loadtime", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 627.5, "shouldAlert": true, "replicates": [1619, 800, 803, 847, 628, 631, 642, 639, 617, 641, 623, 624, 620, 630, 630, 622, 616, 632, 614, 618, 624, 616, 627, 623, 631], "unit": "ms"}], "type": "pageload", "unit": "ms"}]}
[task 2019-09-09T10:03:22.932Z] 10:03:22 INFO - raptor-output Info: results can also be found locally at: C:\Users\task_1568012354\build\raptor.json
[task 2019-09-09T10:03:22.942Z] 10:03:22 INFO - raptor-results-handler Info: Validating PERFHERDER_DATA against C:\Users\task_1568012354\mozharness\external_tools\performance-artifact-schema.json
[task 2019-09-09T10:03:22.942Z] 10:03:22 INFO - raptor-control-server Info: shutting down control server
[task 2019-09-09T10:03:23.105Z] 10:03:23 INFO - raptor-main Info: finished
[task 2019-09-09T10:03:23.105Z] 10:03:23 ERROR - raptor-main Critical: TEST-UNEXPECTED-FAIL: test 'raptor-tp6-paypal-firefox' timed out loading test page: https://www.paypal.com/myaccount/summary/ pending metrics: fcp, dcf
[task 2019-09-09T10:03:23.254Z] 10:03:23 ERROR - Return code: 1
[task 2019-09-09T10:03:23.254Z] 10:03:23 WARNING - setting return code to 1
[task 2019-09-09T10:03:23.254Z] 10:03:23 INFO - Copying Raptor results to upload dir:
[task 2019-09-09T10:03:23.254Z] 10:03:23 INFO - C:\Users\task_1568012354\build\blobber_upload_dir\perfherder-data.json
[task 2019-09-09T10:03:23.254Z] 10:03:23 INFO - Copying raptor results from C:\Users\task_1568012354\build\raptor.json to C:\Users\task_1568012354\build\blobber_upload_dir\perfherder-data.json
[task 2019-09-09T10:03:23.255Z] 10:03:23 INFO - C:\Users\task_1568012354\build\blobber_upload_dir\screenshots.html
[task 2019-09-09T10:03:23.255Z] 10:03:23 INFO - Copying raptor results from C:\Users\task_1568012354\build\screenshots.html to C:\Users\task_1568012354\build\blobber_upload_dir\screenshots.html
[task 2019-09-09T10:03:23.256Z] 10:03:23 INFO - Running post-action listener: _package_coverage_data
[task 2019-09-09T10:03:23.256Z] 10:03:23 INFO - Running post-action listener: _resource_record_post_action
[task 2019-09-09T10:03:23.256Z] 10:03:23 INFO - Running post-action listener: process_java_coverage_data
[task 2019-09-09T10:03:23.256Z] 10:03:23 INFO - Running post-action listener: stop_device
[task 2019-09-09T10:03:23.256Z] 10:03:23 INFO - [mozharness: 2019-09-09 10:03:23.256000Z] Finished run-tests step (success)
[task 2019-09-09T10:03:23.256Z] 10:03:23 INFO - Running post-run listener: _resource_record_post_run
[task 2019-09-09T10:03:23.359Z] 10:03:23 INFO - Total resource usage - Wall time: 211s; CPU: 5.0%; Read bytes: 139084800; Write bytes: 550249472; Read time: 1; Write time: 5

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64-shippable-qr: 1

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

Comment 2

[Florin Strugariu [:Bebe]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 3

[Oana Pop-Rus]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=281884273&repo=mozilla-central&lineNumber=4800

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux64-shippable-qr: 1

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

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows10-64-shippable-qr: 1

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

Comment 6

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

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

Comment 7

[Dorel Luca [:dluca]]

Recent log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=289180051&repo=autoland&lineNumber=5021

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• windows10-64-shippable-qr: 1

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

Comment 9

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

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