Closed Bug 1443043 Opened 6 years ago Closed 5 years ago

Intermittent Mochitest devtools Aborting task - max run time exceeded!

Categories

(Testing :: Mochitest, defect)

Product:
Testing
Component:
Mochitest
Version:
Version 3
Type:
defect
Priority:
Not set
Severity:
normal

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1589796

People

(Reporter: apavel, Assigned: gbrown)

Details

(Keywords: intermittent-failure) 

Failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=165833199&repo=mozilla-central

Error: No objdir path for chrome://mochitests/content/browser/devtools/client/shadereditor/test/browser_webgl-actor-test-17.js.
Couldn't find source info for chrome://mochitests/content/browser/devtools/client/shadereditor/test/browser_webgl-actor-test-17.js, removing record
Error: No objdir path for chrome://mochitests/content/browser/devtools/client/shadereditor/test/browser_webgl-actor-test-18.js.
Couldn't find source info for chrome://mochitests/content/browser/devtools/client/shadereditor/test/browser_webgl-actor-test-18.js, removing record
Error: Couldn't find entry in manifest for dist/xpi-stage/special-powers/bootstrap.js.
Couldn't find source info for resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///c:/users/genericworker/appdata/local/temp/tmp0mufha.mozrunner/extensions/special-powers@mozilla.org.xpi!/bootstrap.js, removing record
Error: Couldn't find entry in manifest for dist/xpi-stage/mochikit/bootstrap.js.
Couldn't find source info for resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///c:/users/genericworker/appdata/local/temp/tmp0mufha.mozrunner/extensions/mochikit@mozilla.org.xpi!/bootstrap.js, removing record
Error: No objdir path for chrome://mochitests/content/browser/devtools/client/shared/components/test/browser/browser_notification_box_basic.js.
Couldn't find source info for chrome://mochitests/content/browser/devtools/client/shared/components/test/browser/browser_notification_box_basic.js, removing record
12:59:47     INFO - Running command: ['zip', '-q', 'Z:\\task_1520163069\\build\\blobber_upload_dir\\code-coverage-jsvm.zip', 'jsvm_lcov_output.info']
12:59:47     INFO - Copy/paste: zip -q Z:\task_1520163069\build\blobber_upload_dir\code-coverage-jsvm.zip jsvm_lcov_output.info
12:59:56     INFO - Return code: 0
12:59:56     INFO - Downloading https://queue.taskcluster.net/v1/task/bLNvQfMIT2CSYkg3cszcvw/artifacts/public/build/target.code-coverage-gcno.zip to c:\users\genericworker\appdata\local\temp\tmpimwmyx\target.code-coverage-gcno.zip
12:59:56     INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bLNvQfMIT2CSYkg3cszcvw/artifacts/public/build/target.code-coverage-gcno.zip', 'file_name': 'c:\\users\\genericworker\\appdata\\local\\temp\\tmpimwmyx\\target.code-coverage-gcno.zip'}, attempt #1
12:59:57     INFO - Downloaded 118385548 bytes.
12:59:57     INFO - Getting output from command: ['c:\\users\\genericworker\\appdata\\local\\temp\\tmpimwmyx\\grcov', '-t', 'lcov', '-p', 'z:/build/build/src/', '--ignore-dir', 'gcc', 'c:\\users\\genericworker\\appdata\\local\\temp\\tmpimwmyx\\target.code-coverage-gcno.zip', 'Z:\\task_1520163069\\code-coverage-gcda.zip', '--llvm']
12:59:57     INFO - Copy/paste: c:\users\genericworker\appdata\local\temp\tmpimwmyx\grcov -t lcov -p z:/build/build/src/ --ignore-dir gcc c:\users\genericworker\appdata\local\temp\tmpimwmyx\target.code-coverage-gcno.zip Z:\task_1520163069\code-coverage-gcda.zip --llvm
13:01:06     INFO - Running command: ['zip', '-q', 'Z:\\task_1520163069\\build\\blobber_upload_dir\\code-coverage-grcov.zip', 'grcov_lcov_output.info'] in c:\users\genericworker\appdata\local\temp\tmpimwmyx
13:01:06     INFO - Copy/paste: zip -q Z:\task_1520163069\build\blobber_upload_dir\code-coverage-grcov.zip grcov_lcov_output.info
[taskcluster 2018-03-04T13:01:11.445Z] Aborting task - max run time exceeded!
[taskcluster 2018-03-04T13:01:11.575Z]   Exit Code: 0
[taskcluster 2018-03-04T13:01:11.575Z]   User Time: 0s
[taskcluster 2018-03-04T13:01:11.575Z] Kernel Time: 15.625ms
[taskcluster 2018-03-04T13:01:11.575Z]   Wall Time: 1h29m57.9211952s
[taskcluster 2018-03-04T13:01:11.575Z] Peak Memory: 4870144
[taskcluster 2018-03-04T13:01:11.575Z]      Result: IDLENESS_LIMIT_EXCEEDED
[taskcluster 2018-03-04T13:01:11.575Z] === Task Finished ===
[taskcluster 2018-03-04T13:01:11.576Z] Task Duration: 1h29m57.9252261s
13:01:14     INFO - Return code: 0
13:01:14     INFO - Running post-action listener: _resource_record_post_action
13:01:14     INFO - [mozharness: 2018-03-04 13:01:14.992000Z] Finished run-tests step (success)
13:01:14     INFO - Running post-run listener: _resource_record_post_run
13:01:15     INFO - Validating Perfherder data against Z:\task_1520163069\mozharness\external_tools\performance-artifact-schema.json
13:01:15     INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"subtests": [{"name": "cpu_percent", "value": 25.155437921077965}, {"name": "io_write_bytes", "value": 17763415552}, {"name": "io.read_bytes", "value": 3577514496}, {"name": "io_write_time", "value": 1028}, {"name": "io_read_time", "value": 532}], "extraOptions": ["e10s", "taskcluster-c4.2xlarge"], "name": "mochitest.mochitest-devtools-chrome-chunked.mochitest-devtools-chrome-chunked.3.overall"}, {"subtests": [{"name": "time", "value": 4.7250001430511475}, {"name": "cpu_percent", "value": 15.290625}], "name": "mochitest.mochitest-devtools-chrome-chunked.mochitest-devtools-chrome-chunked.3.install"}, {"subtests": [{"name": "time", "value": 0.0009999275207519531}], "name": "mochitest.mochitest-devtools-chrome-chunked.mochitest-devtools-chrome-chunked.3.stage-files"}, {"subtests": [{"name": "time", "value": 5214.018000125885}, {"name": "cpu_percent", "value": 25.16508911368016}], "name": "mochitest.mochitest-devtools-chrome-chunked.mochitest-devtools-chrome-chunked.3.run-tests"}]}
13:01:15     INFO - Total resource usage - Wall time: 5218s; CPU: 25.0%; Read bytes: 3577514496; Write bytes: 17763415552; Read time: 532; Write time: 1028
13:01:15     INFO - TinderboxPrint: CPU usage<br/>25.2%
13:01:15     INFO - TinderboxPrint: I/O read bytes / time<br/>3,577,514,496 / 532
13:01:15     INFO - TinderboxPrint: I/O write bytes / time<br/>17,763,415,552 / 1,028
13:01:15     INFO - TinderboxPrint: CPU idle<br/>31,263.9 (74.8%)
13:01:15     INFO - TinderboxPrint: CPU system<br/>818.9 (2.0%)
13:01:15     INFO - TinderboxPrint: CPU user<br/>9,665.5 (23.1%)
13:01:15     INFO - install - Wall time: 5s; CPU: 15.0%; Read bytes: 0; Write bytes: 52785664; Read time: 0; Write time: 2
13:01:15     INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
13:01:15     INFO - run-tests - Wall time: 5214s; CPU: 25.0%; Read bytes: 3577510400; Write bytes: 17630604800; Read time: 532; Write time: 1025
13:01:17     INFO - Running post-run listener: _upload_blobber_files
13:01:17  WARNING - Blob upload gear skipped. Missing cmdline options.
13:01:17     INFO - Running post-run listener: copy_logs_to_upload_dir
13:01:17     INFO - Copying logs to upload dir...
13:01:17     INFO - mkdir: Z:\task_1520163069\build\upload\logs
13:01:17     INFO - Copying logs to upload dir...
13:01:17     INFO - Using _rmtree_windows ...
13:01:17     INFO - Running command: del /F /Q "Z:\task_1520163069\build\upload\logs\localconfig.json"
13:01:17     INFO - Return code: 0
13:01:17     INFO - Using _rmtree_windows ...
13:01:17     INFO - Running command: del /F /Q "Z:\task_1520163069\build\upload\logs\log_info.log"
13:01:17     INFO - Return code: 0
13:01:17     INFO - Using _rmtree_windows ...
13:01:17     INFO - Running command: del /F /Q "Z:\task_1520163069\build\upload\logs\log_raw.log"
13:01:17     INFO - Return code: 0
13:01:17     INFO - Using _rmtree_windows ...
13:01:17     INFO - Running command: del /F /Q "Z:\task_1520163069\build\upload\logs\log_warning.log"
13:01:17     INFO - Return code: 0
13:01:17     INFO - Using _rmtree_windows ...
13:01:17     INFO - Running command: del /F /Q "Z:\task_1520163069\build\upload\logs\log_critical.log"
13:01:17     INFO - Return code: 0
13:01:17     INFO - Using _rmtree_windows ...
13:01:17     INFO - Running command: del /F /Q "Z:\task_1520163069\build\upload\logs\log_error.log"
13:01:17     INFO - Return code: 0
13:01:17     INFO - Using _rmtree_windows ...
13:01:17     INFO - Running command: del /F /Q "Z:\task_1520163069\build\upload\logs\log_fatal.log"
13:01:17     INFO - Return code: 0
Assignee: nobody → gbrown
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.