Closed
Bug 1643981
Opened 5 years ago
Closed 5 years ago
Intermittent raptor-main Critical: TEST-UNEXPECTED-FAIL: test 'raptor-tp6-fandom-firefox' timed out loading test page: waiting for pending metrics
Categories
(Testing :: Raptor, defect, P5)
Testing
Raptor
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
References
Details
(Keywords: intermittent-failure)
Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=305353632&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eCU1Vt7zQSubTr2miAqdqw/runs/0/artifacts/public/logs/live_backing.log
[task 2020-06-06T23:45:47.998Z] 23:45:47 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:48.247Z] 23:45:48 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:48.491Z] 23:45:48 INFO - raptor-control-server Info: received webext_raptor-page-timeout: [u'raptor-tp6-fandom-firefox', u'https://www.fandom.com/articles/fallout-76-will-live-and-die-on-the-creativity-of-its-playerbase', 6, {u'fcp': True, u'hero': False, u'dcf': False, u'fnb paint': False, u'ttfi': False, u'load time': False}]
[task 2020-06-06T23:45:48.491Z] 23:45:48 INFO - PID 2620 | console.error: "ERROR: [raptor-runnerjs] raptor-page-timeout on https://www.fandom.com/articles/fallout-76-will-live-and-die-on-the-creativity-of-its-playerbase"
[task 2020-06-06T23:45:48.493Z] 23:45:48 INFO - PID 2620 | console.info: "[raptor-runnerjs] capturing screenshot"
[task 2020-06-06T23:45:48.495Z] 23:45:48 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:48.754Z] 23:45:48 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:49.002Z] 23:45:49 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:49.252Z] 23:45:49 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:49.503Z] 23:45:49 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:49.757Z] 23:45:49 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:50.009Z] 23:45:50 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:50.191Z] 23:45:50 INFO - raptor-control-server Info: received webext_screenshot
[task 2020-06-06T23:45:50.191Z] 23:45:50 INFO - perftest-results-handler Info: received screenshot
[task 2020-06-06T23:45:50.201Z] 23:45:50 INFO - raptor-control-server Info: received webext_status: closing Tab: 2
[task 2020-06-06T23:45:50.246Z] 23:45:50 INFO - raptor-control-server Info: received webext_status: closed tab: 2
[task 2020-06-06T23:45:50.252Z] 23:45:50 INFO - PID 2620 | console.info: "[raptor-runnerjs] pageload test finished"
[task 2020-06-06T23:45:50.252Z] 23:45:50 INFO - raptor-control-server Info: received request to shutdown the browser
[task 2020-06-06T23:45:50.252Z] 23:45:50 INFO - raptor-control-server Info: shutting down browser (pid: 2620)
[task 2020-06-06T23:45:50.262Z] 23:45:50 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:50.515Z] 23:45:50 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:50.759Z] 23:45:50 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:51.018Z] 23:45:51 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:51.263Z] 23:45:51 INFO - PID 2620 | console.info: "[raptor-runnerjs] results pending..."
[task 2020-06-06T23:45:52.027Z] 23:45:52 INFO - raptor-mitmproxy Info: Stopping mitmproxy playback, killing process 6680
[task 2020-06-06T23:45:52.027Z] 23:45:52 INFO - raptor-mitmproxy Info: Sending CTRL_BREAK_EVENT to mitmproxy
[task 2020-06-06T23:45:54.060Z] 23:45:54 INFO - raptor-mitmproxy Info: Successfully killed the mitmproxy playback process
[task 2020-06-06T23:45:54.060Z] 23:45:54 INFO - raptor-mitmproxy Info: Turning off the browser proxy
[task 2020-06-06T23:45:54.060Z] 23:45:54 INFO - raptor-mitmproxy Info: writing: C:\Users\task_1591484568\build\application\firefox\distribution\policies.json
[task 2020-06-06T23:45:54.061Z] 23:45:54 INFO - raptor-mitmproxy Info: Reading confidence values from: C:\Users\task_1591484568\build\blobber_upload_dir\mitm_netlocs_fandom.json
[task 2020-06-06T23:45:54.063Z] 23:45:54 INFO - perftest-results-handler Info: RaptorResultsHandler.add_supporting_data received mozproxy data
[task 2020-06-06T23:45:54.063Z] 23:45:54 INFO - raptor-webext Info: removing webext C:\Users\task_1591484568\build\tests\raptor\raptor\webextension\..\..\webext\raptor
[task 2020-06-06T23:45:54.068Z] 23:45:54 INFO - perftest-results-handler Info: summarizing raptor test results
[task 2020-06-06T23:45:54.068Z] 23:45:54 INFO - perftest-output Error: no raptor test results found for raptor-tp6-fandom-firefox
[task 2020-06-06T23:45:54.068Z] 23:45:54 INFO - perftest-output Info: error: no raptor test results found, so no need to combine browser cycles
[task 2020-06-06T23:45:54.069Z] 23:45:54 INFO - perftest-output Error: no summarized raptor results found for raptor-tp6-fandom-firefox
[task 2020-06-06T23:45:54.071Z] 23:45:54 INFO - perftest-output Info: screen captures can be found locally at: C:\Users\task_1591484568\build\screenshots.html
[task 2020-06-06T23:45:54.946Z] 23:45:54 INFO - perftest-results-handler Critical: PERFHERDER_DATA was seen 0 times, expected 1.
[task 2020-06-06T23:45:54.946Z] 23:45:54 INFO - raptor-perftest Info: Removing temporary directory: c:\users\task_1591484568\appdata\local\temp\tmpq7uuup
[task 2020-06-06T23:45:55.369Z] 23:45:55 INFO - raptor-perftest Info: Removing temporary directory: c:\users\task_1591484568\appdata\local\temp\tmpaclrrz
[task 2020-06-06T23:45:55.801Z] 23:45:55 INFO - raptor-control-server Info: shutting down control server
[task 2020-06-06T23:45:55.892Z] 23:45:55 INFO - raptor-webext Info: finished
[task 2020-06-06T23:45:55.892Z] 23:45:55 INFO - raptor-main Warning: page cycle 6 has pending metrics: fcp
[task 2020-06-06T23:45:55.892Z] 23:45:55 ERROR - raptor-main Critical: TEST-UNEXPECTED-FAIL: test 'raptor-tp6-fandom-firefox' timed out loading test page: waiting for pending metrics
[task 2020-06-06T23:45:55.970Z] 23:45:55 ERROR - Return code: 1
[task 2020-06-06T23:45:55.970Z] 23:45:55 WARNING - setting return code to 1
[task 2020-06-06T23:45:55.970Z] 23:45:55 INFO - Copying Raptor results to upload dir:
[task 2020-06-06T23:45:55.970Z] 23:45:55 INFO - C:\Users\task_1591484568\build\blobber_upload_dir\perfherder-data.json
[task 2020-06-06T23:45:55.971Z] 23:45:55 INFO - Copying raptor results from C:\Users\task_1591484568\build\raptor.json to C:\Users\task_1591484568\build\blobber_upload_dir\perfherder-data.json
[task 2020-06-06T23:45:55.971Z] 23:45:55 CRITICAL - Error copying results C:\Users\task_1591484568\build\raptor.json to upload dir C:\Users\task_1591484568\build\blobber_upload_dir\perfherder-data.json
[task 2020-06-06T23:45:55.971Z] 23:45:55 INFO - [Errno 2] No such file or directory: u'C:\\Users\\task_1591484568\\build\\raptor.json'
[task 2020-06-06T23:45:55.973Z] 23:45:55 INFO - C:\Users\task_1591484568\build\blobber_upload_dir\screenshots.html
[task 2020-06-06T23:45:55.973Z] 23:45:55 INFO - Copying raptor results from C:\Users\task_1591484568\build\screenshots.html to C:\Users\task_1591484568\build\blobber_upload_dir\screenshots.html
[task 2020-06-06T23:45:55.975Z] 23:45:55 INFO - Running post-action listener: _package_coverage_data
[task 2020-06-06T23:45:55.975Z] 23:45:55 INFO - Running post-action listener: _resource_record_post_action
[task 2020-06-06T23:45:55.975Z] 23:45:55 INFO - Running post-action listener: process_java_coverage_data
[task 2020-06-06T23:45:55.975Z] 23:45:55 INFO - Running post-action listener: stop_device
[task 2020-06-06T23:45:55.975Z] 23:45:55 INFO - [mozharness: 2020-06-06 23:45:55.975000Z] Finished run-tests step (success)
[task 2020-06-06T23:45:55.975Z] 23:45:55 INFO - Running post-run listener: _resource_record_post_run
[task 2020-06-06T23:46:00.412Z] 23:46:00 INFO - Total resource usage - Wall time: 126s; CPU: 14.0%; Read bytes: 545271296; Write bytes: 532828160; Read time: 244; Write time: 235
[task 2020-06-06T23:46:00.412Z] 23:46:00 INFO - TinderboxPrint: CPU usage<br/>14.1%
[task 2020-06-06T23:46:00.412Z] 23:46:00 INFO - TinderboxPrint: I/O read bytes / time<br/>545,271,296 / 244
[task 2020-06-06T23:46:00.412Z] 23:46:00 INFO - TinderboxPrint: I/O write bytes / time<br/>532,828,160 / 235
[task 2020-06-06T23:46:00.412Z] 23:46:00 INFO - TinderboxPrint: CPU idle<br/>432.8 (85.9%)
[task 2020-06-06T23:46:00.412Z] 23:46:00 INFO - TinderboxPrint: CPU system<br/>21.9 (4.3%)
[task 2020-06-06T23:46:00.412Z] 23:46:00 INFO - TinderboxPrint: CPU user<br/>47.9 (9.5%)
[task 2020-06-06T23:46:00.413Z] 23:46:00 INFO - install - Wall time: 6s; CPU: 15.0%; Read bytes: 208896; Write bytes: 79876096; Read time: 1; Write time: 18
[task 2020-06-06T23:46:00.414Z] 23:46:00 INFO - run-tests - Wall time: 120s; CPU: 14.0%; Read bytes: 545062400; Write bytes: 445267968; Read time: 243; Write time: 202
[task 2020-06-06T23:46:00.441Z] 23:46:00 WARNING - returning nonzero exit status 1
[fetches 2020-06-06T23:46:00.460Z] removing C:/Users/task_1591484568/fetches
[fetches 2020-06-06T23:46:00.660Z] finished
[taskcluster 2020-06-06T23:46:00.991Z] Exit Code: 1
[taskcluster 2020-06-06T23:46:00.991Z] User Time: 0s
[taskcluster 2020-06-06T23:46:00.991Z] Kernel Time: 15.625ms
[taskcluster 2020-06-06T23:46:00.991Z] Wall Time: 3m55.1367107s
[taskcluster 2020-06-06T23:46:00.991Z] Result: FAILED
[taskcluster 2020-06-06T23:46:01.004Z] === Task Finished ===
[taskcluster 2020-06-06T23:46:01.004Z] Task Duration: 3m56.4859475s
[taskcluster 2020-06-06T23:46:01.400Z] Uploading artifact public/logs/localconfig.json from file logs\localconfig.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2021-06-06T21:53:12.458Z
[taskcluster 2020-06-06T23:46:02.396Z] Uploading artifact public/test_info/mitm_netlocs_fandom.json from file build\blobber_upload_dir\mitm_netlocs_fandom.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2021-06-06T21:53:12.458Z
[taskcluster 2020-06-06T23:46:02.479Z] Uploading artifact public/test_info/mitmproxy.log from file build\blobber_upload_dir\mitmproxy.log with content encoding "gzip", mime type "text/plain" and expiry 2021-06-06T21:53:12.458Z
[taskcluster 2020-06-06T23:46:02.634Z] Uploading artifact public/test_info/resource-usage.json from file build\blobber_upload_dir\resource-usage.json with content encoding "gzip", mime type "application/octet-stream" and expiry 2021-06-06T21:53:12.458Z
[taskcluster 2020-06-06T23:46:02.812Z] Uploading artifact public/test_info/screenshots.html from file build\blobber_upload_dir\screenshots.html with content encoding "gzip", mime type "text/html; charset=utf-8" and expiry 2021-06-06T21:53:12.458Z
[taskcluster 2020-06-06T23:46:03.065Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eCU1Vt7zQSubTr2miAqdqw/runs/0/artifacts/public/logs/live_backing.log with mime type "text/plain; charset=utf-8" and expiry 2021-06-06T21:53:12.458Z
[taskcluster:error] exit status 1
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•5 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•