1570862 - Intermittent raptor-main Critical: TEST-UNEXPECTED-FAIL: test 'raptor-tp6m-facebook-cristiano-geckoview-cold' timed out loading test page: https://m.facebook.com/Cristiano pending metrics: fcp, fnb paint

Status: RESOLVED INCOMPLETE

(Testing :: Raptor, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2019-08-02T02:52:34.247Z] 02:52:30 INFO - raptor-output Info: PERFHERDER_DATA: {"framework": {"name": "raptor"}, "suites": [{"extraOptions": [], "name": "raptor-tp6m-facebook-cristiano-geckoview-cold", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 838.8, "subtests": [{"name": "dcf", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 755.5, "replicates": [735, 776], "unit": "ms"}, {"name": "fcp", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 742.5, "shouldAlert": true, "replicates": [609, 876], "unit": "ms"}, {"name": "fnbpaint", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 649.0, "replicates": [514, 784], "unit": "ms"}, {"name": "loadtime", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1359.5, "shouldAlert": true, "replicates": [1321, 1398], "unit": "ms"}], "type": "pageload", "unit": "ms"}]}
[task 2019-08-02T02:52:34.247Z] 02:52:30 INFO - raptor-output Info: results can also be found locally at: /builds/task_1564707658/workspace/build/raptor.json
[task 2019-08-02T02:52:34.247Z] 02:52:30 INFO - raptor-results-handler Info: Validating PERFHERDER_DATA against /builds/task_1564707658/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2019-08-02T02:52:34.247Z] 02:52:30 INFO - raptor-main Info: removing test folder for raptor: /sdcard/raptor
[task 2019-08-02T02:52:34.247Z] 02:52:30 INFO - adb shell_output: adb -s HT7A21A02882 wait-for-device shell rm -r /sdcard/raptor; echo adb_returncode=$?, timeout: None, root: False, timedout: None, exitcode: 0, output:
[task 2019-08-02T02:52:34.247Z] 02:52:30 INFO - adb shell_bool: adb -s HT7A21A02882 wait-for-device shell test -e /sdcard/raptor; echo adb_returncode=$?, timeout: None, root: False, timedout: None, exitcode: 1, output:
[task 2019-08-02T02:52:34.247Z] 02:52:30 INFO - raptor-control-server Info: shutting down control server
[task 2019-08-02T02:52:34.247Z] 02:52:31 INFO - raptor-main Info: finished
[task 2019-08-02T02:52:34.247Z] 02:52:31 ERROR - raptor-main Critical: TEST-UNEXPECTED-FAIL: test 'raptor-tp6m-facebook-cristiano-geckoview-cold' timed out loading test page: https://m.facebook.com/Cristiano pending metrics: fcp, fnb paint
[task 2019-08-02T02:52:34.247Z] 02:52:31 ERROR - Return code: 1
[task 2019-08-02T02:52:34.247Z] 02:52:31 WARNING - setting return code to 1
[task 2019-08-02T02:52:34.247Z] 02:52:31 INFO - Killing logcat pid 23295.
[task 2019-08-02T02:52:34.247Z] 02:52:31 INFO - copying raptor results to upload dir:
[task 2019-08-02T02:52:34.247Z] 02:52:31 INFO - /builds/task_1564707658/workspace/build/blobber_upload_dir/perfherder-data.json
[task 2019-08-02T02:52:34.247Z] 02:52:31 INFO - copying raptor results from /builds/task_1564707658/workspace/build/raptor.json to /builds/task_1564707658/workspace/build/blobber_upload_dir/perfherder-data.json
[task 2019-08-02T02:52:34.247Z] 02:52:31 INFO - /builds/task_1564707658/workspace/build/blobber_upload_dir/screenshots.html
[task 2019-08-02T02:52:34.247Z] 02:52:31 INFO - copying raptor results from /builds/task_1564707658/workspace/build/screenshots.html to /builds/task_1564707658/workspace/build/blobber_upload_dir/screenshots.html
[task 2019-08-02T02:52:34.247Z] 02:52:31 INFO - Running post-action listener: _package_coverage_data
[task 2019-08-02T02:52:34.247Z] 02:52:31 INFO - Running post-action listener: _resource_record_post_action
[task 2019-08-02T02:52:34.247Z] 02:52:31 INFO - Running post-action listener: process_java_coverage_data
[task 2019-08-02T02:52:34.247Z] 02:52:31 INFO - Running post-action listener: stop_device
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - Killing logcat pid 23295.
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - [mozharness: 2019-08-02 02:52:34.101480Z] Finished run-tests step (success)
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - Running post-run listener: _resource_record_post_run
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - Total resource usage - Wall time: 249s; CPU: 18.0%; Read bytes: 16384; Write bytes: 2895958016; Read time: 220; Write time: 3631340
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - TinderboxPrint: CPU usage<br/>18.4%
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - TinderboxPrint: I/O read bytes / time<br/>16,384 / 220
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - TinderboxPrint: I/O write bytes / time<br/>2,895,958,016 / 3,631,340
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - TinderboxPrint: CPU idle<br/>777.1 (78.7%)
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - TinderboxPrint: CPU iowait<br/>27.6 (2.8%)
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - TinderboxPrint: CPU system<br/>48.3 (4.9%)
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - TinderboxPrint: CPU user<br/>128.2 (13.0%)
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - install - Wall time: 9s; CPU: 20.0%; Read bytes: 0; Write bytes: 205066240; Read time: 0; Write time: 393736
[task 2019-08-02T02:52:34.247Z] 02:52:34 INFO - run-tests - Wall time: 238s; CPU: 19.0%; Read bytes: 16384; Write bytes: 2689679360; Read time: 220; Write time: 3237396
[task 2019-08-02T02:52:34.247Z] 02:52:34 WARNING - returning nonzero exit status 1
[task 2019-08-02T02:52:34.247Z] cleanup

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-hw-p2-8-0-android-aarch64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1570862&startday=2019-07-29&endday=2019-08-04&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-hw-p2-8-0-android-aarch64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1570862&startday=2019-08-19&endday=2019-08-25&tree=all

Comment 3

[Florin Strugariu [:Bebe]]

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

Comment 5

[Razvan Maries]

New occurrence :https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=293116426&repo=autoland&lineNumber=2677

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 2

Platform breakdown:

• android-hw-g5-7-0-arm7-api-16: 2

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

Comment 7

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

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