1569295 - Intermittent raptor-main Critical: TEST-UNEXPECTED-FAIL: test 'raptor-tp6m-booking-geckoview-cold' timed out loading test page: https://www.booking.com

Status: RESOLVED INCOMPLETE

(Testing :: Raptor, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=258526761&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/OuRAYI7HRK6PPNocLIcJ3w/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-07-26T17:35:03.499Z] 17:33:25 INFO - adb shell_output: adb -s ZY322LDS6T wait-for-device shell am start -W -n org.mozilla.geckoview_example/org.mozilla.geckoview_example.GeckoViewActivity -a android.intent.action.MAIN -d about:blank; echo adb_returncode=$?, timeout: None, root: False, timedout: None, exitcode: 0, output: Starting: Intent { act=android.intent.action.MAIN dat=about:blank cmp=org.mozilla.geckoview_example/.GeckoViewActivity }
[task 2019-07-26T17:35:03.499Z] 17:33:25 INFO - Status: ok
[task 2019-07-26T17:35:03.499Z] 17:33:25 INFO - Activity: org.mozilla.geckoview_example/.GeckoViewActivity
[task 2019-07-26T17:35:03.499Z] 17:33:25 INFO - ThisTime: 644
[task 2019-07-26T17:35:03.499Z] 17:33:25 INFO - TotalTime: 644
[task 2019-07-26T17:35:03.499Z] 17:33:25 INFO - WaitTime: 659
[task 2019-07-26T17:35:03.499Z] 17:33:25 INFO - Complete
[task 2019-07-26T17:35:03.499Z] 17:33:25 INFO - adb shell_output: adb -s ZY322LDS6T wait-for-device shell pidof org.mozilla.geckoview_example; echo adb_returncode=$?, timeout: None, root: False, timedout: None, exitcode: 0, output: 7672
[task 2019-07-26T17:35:03.499Z] 17:33:28 INFO - raptor-control-server Info: received webext_status: raptor runner.js is loaded!
[task 2019-07-26T17:35:03.499Z] 17:33:28 INFO - raptor-control-server Info: reading test settings from json/raptor-tp6m-booking-geckoview-cold.json
[task 2019-07-26T17:35:03.499Z] 17:33:28 INFO - raptor-control-server Info: sent test settings to web ext runner
[task 2019-07-26T17:35:03.499Z] 17:33:28 INFO - raptor-control-server Info: received webext_status: * pausing 30 seconds to let browser settle... *
[task 2019-07-26T17:35:03.499Z] 17:33:58 INFO - raptor-control-server Info: received webext_status: running 1 pagecycles of https://www.booking.com/
[task 2019-07-26T17:35:03.499Z] 17:33:59 INFO - raptor-control-server Info: received webext_status: begin pagecycle 1
[task 2019-07-26T17:35:03.499Z] 17:34:00 INFO - raptor-control-server Info: received webext_status: update tab: 0
[task 2019-07-26T17:35:03.499Z] 17:34:00 INFO - raptor-control-server Info: received webext_status: test tab updated: 0
[task 2019-07-26T17:35:03.499Z] 17:35:02 INFO - raptor-control-server Info: received webext_raptor-page-timeout: [u'raptor-tp6m-booking-geckoview-cold', u'https://www.booking.com/', {u'fcp': True, u'hero': False, u'dcf': False, u'fnb paint': True, u'ttfi': False, u'load time': False}]
[task 2019-07-26T17:35:20.701Z] 17:35:02 INFO - raptor-control-server Info: received webext_screenshot
[task 2019-07-26T17:35:20.701Z] 17:35:02 INFO - raptor-results-handler Info: received screenshot
[task 2019-07-26T17:35:20.701Z] 17:35:02 INFO - raptor-control-server Info: received request to shutdown the browser
[task 2019-07-26T17:35:20.701Z] 17:35:02 INFO - raptor-control-server Info: shutting down android app org.mozilla.geckoview_example
[task 2019-07-26T17:35:20.701Z] 17:35:03 INFO - adb shell_output: adb -s ZY322LDS6T wait-for-device shell am force-stop org.mozilla.geckoview_example; echo adb_returncode=$?, timeout: None, root: False, timedout: None, exitcode: 0, output:
[task 2019-07-26T17:35:20.701Z] 17:35:04 INFO - raptor-main Info: removing reverse socket connections
[task 2019-07-26T17:35:20.701Z] 17:35:04 INFO - adb command_output: adb -s ZY322LDS6T wait-for-device reverse --remove-all, timeout: None, timedout: None, exitcode: 0, output:
[task 2019-07-26T17:35:20.701Z] 17:35:04 INFO - adb command_output: adb -s ZY322LDS6T wait-for-device pull /sdcard/raptor/profile/minidumps /tmp/tmpwUMNSj/minidumps, timeout: None, timedout: None, exitcode: 0, output: /sdcard/raptor/profile/minidumps/: 0 files pulled.
[task 2019-07-26T17:35:20.701Z] 17:35:04 INFO - mozproxy Stopping mitmproxy playback, killing process 799
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - mozproxy Successfully killed the mitmproxy playback process
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - raptor-main Info: removing webext /builds/task_1564162212/workspace/build/tests/raptor/raptor/../webext/raptor
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - raptor-results-handler Info: summarizing raptor test results
[task 2019-07-26T17:35:20.701Z] 17:35:05 ERROR - raptor-output Error: no raptor test results found for raptor-tp6m-booking-geckoview-cold
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - raptor-output Info: error: no raptor test results found, so no need to combine browser cycles
[task 2019-07-26T17:35:20.701Z] 17:35:05 ERROR - raptor-output Error: no summarized raptor results found for raptor-tp6m-booking-geckoview-cold
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - raptor-output Info: screen captures can be found locally at: /builds/task_1564162212/workspace/build/screenshots.html
[task 2019-07-26T17:35:20.701Z] 17:35:05 CRITICAL - raptor-results-handler Critical: PERFHERDER_DATA was seen 0 times, expected 1.
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - raptor-main Info: removing test folder for raptor: /sdcard/raptor
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - adb shell_output: adb -s ZY322LDS6T wait-for-device shell rm -r /sdcard/raptor; echo adb_returncode=$?, timeout: None, root: False, timedout: None, exitcode: 0, output:
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - raptor-control-server Info: shutting down control server
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - raptor-main Info: finished
[task 2019-07-26T17:35:20.701Z] 17:35:05 ERROR - raptor-main Critical: TEST-UNEXPECTED-FAIL: no raptor test results were found for raptor-tp6m-booking-geckoview-cold
[task 2019-07-26T17:35:20.701Z] 17:35:05 ERROR - Return code: 1
[task 2019-07-26T17:35:20.701Z] 17:35:05 WARNING - setting return code to 1
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - Killing logcat pid 580.
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - copying raptor results to upload dir:
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - /builds/task_1564162212/workspace/build/blobber_upload_dir/perfherder-data.json
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - copying raptor results from /builds/task_1564162212/workspace/build/raptor.json to /builds/task_1564162212/workspace/build/blobber_upload_dir/perfherder-data.json
[task 2019-07-26T17:35:20.701Z] 17:35:05 CRITICAL - Error copying results /builds/task_1564162212/workspace/build/raptor.json to upload dir /builds/task_1564162212/workspace/build/blobber_upload_dir/perfherder-data.json
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - [Errno 2] No such file or directory: u'/builds/task_1564162212/workspace/build/raptor.json'
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - /builds/task_1564162212/workspace/build/blobber_upload_dir/screenshots.html
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - copying raptor results from /builds/task_1564162212/workspace/build/screenshots.html to /builds/task_1564162212/workspace/build/blobber_upload_dir/screenshots.html
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - Running post-action listener: _package_coverage_data
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - Running post-action listener: _resource_record_post_action
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - Running post-action listener: process_java_coverage_data
[task 2019-07-26T17:35:20.701Z] 17:35:05 INFO - Running post-action listener: stop_device
[task 2019-07-26T17:35:20.701Z] 17:35:14 INFO - /data/tombstones/dsps deleted
[task 2019-07-26T17:35:20.701Z] 17:35:16 INFO - /data/tombstones/lpass deleted
[task 2019-07-26T17:35:20.701Z] 17:35:18 INFO - /data/tombstones/modem deleted
[task 2019-07-26T17:35:20.701Z] 17:35:20 INFO - /data/tombstones/wcnss deleted
[task 2019-07-26T17:35:20.701Z] 17:35:20 INFO - Killing logcat pid 580.
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - [mozharness: 2019-07-26 17:35:20.665100Z] Finished run-tests step (success)
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - Running post-run listener: _resource_record_post_run
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - Total resource usage - Wall time: 237s; CPU: 12.0%; Read bytes: 197996544; Write bytes: 39391899648; Read time: 38284; Write time: 16425852
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - TinderboxPrint: CPU usage<br/>12.5%
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - TinderboxPrint: I/O read bytes / time<br/>197,996,544 / 38,284
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - TinderboxPrint: I/O write bytes / time<br/>39,391,899,648 / 16,425,852
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - TinderboxPrint: CPU idle<br/>698.5 (74.5%)
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - TinderboxPrint: CPU iowait<br/>121.0 (12.9%)
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - TinderboxPrint: CPU system<br/>31.9 (3.4%)
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - TinderboxPrint: CPU user<br/>82.5 (8.8%)
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - TinderboxPrint: Swap in / out<br/>0 / 1,675,264
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - install - Wall time: 13s; CPU: 28.0%; Read bytes: 145428480; Write bytes: 7987642368; Read time: 2560; Write time: 8304124
[task 2019-07-26T17:35:20.917Z] 17:35:20 INFO - run-tests - Wall time: 210s; CPU: 11.0%; Read bytes: 49221632; Write bytes: 26078212096; Read time: 35204; Write time: 7812536
[task 2019-07-26T17:35:20.917Z] 17:35:20 WARNING - returning nonzero exit status 1
[task 2019-07-26T17:35:20.917Z] cleanup

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

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

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

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

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

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

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

Comment 4

[Florin Strugariu [:Bebe]]

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

Comment 5

[Alexandru Michis [:malexandru]]

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1569295&startday=2019-10-14&endday=2019-10-20&tree=all

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1569295&startday=2019-10-21&endday=2019-10-27&tree=all

Comment 8

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1569295&startday=2019-10-28&endday=2019-11-03&tree=all

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

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

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

Comment 10

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

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

Comment 11

[Natalia Csoregi [:nataliaCs]]

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=279405877&repo=mozilla-central&lineNumber=1504

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

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

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

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

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

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

Comment 14

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

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

Comment 15

[Razvan Maries]

New occurrence: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=285180290&repo=mozilla-central&lineNumber=1565

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• mozilla-central: 1

Platform breakdown:

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

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

Comment 17

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 1

Platform breakdown:

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

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

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

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

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

Comment 19

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

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

Comment 20

[Henrik Skupin [:whimboo][⌚️UTC+2]]

[task 2020-01-29T20:34:18.606Z] 20:33:55 INFO - raptor-control-server Info: received webext_raptor-page-timeout: [u'raptor-tp6m-booking-geckoview-cold', u'https://www.booking.com/', {u'fcp': True, u'hero': False, u'dcf': False, u'fnb paint': True, u'ttfi': False, u'load time': False}]