Closed Bug 1428171 Opened 7 years ago Closed 7 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | security/manager/ssl/tests/unit/test_nsCertType.js | Test timed out

Categories

(Core :: Security: PSM, defect, P5)

Product:
Core
Component:
Security: PSM
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=154134497&repo=autoland https://queue.taskcluster.net/v1/task/OrKMp5yqR2GbyqbhSyKWbQ/runs/0/artifacts/public/logs/live_backing.log 17:13:07 INFO - TEST-START | security/manager/ssl/tests/unit/test_nsCertType.js 17:18:07 WARNING - TEST-UNEXPECTED-TIMEOUT | security/manager/ssl/tests/unit/test_nsCertType.js | Test timed out 17:18:07 INFO - TEST-INFO took 300000ms 17:18:07 INFO - >>>>>>> 17:18:07 INFO - (xpcshell/head.js) | test MAIN run_test pending (1) 17:18:07 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2) 17:18:07 INFO - (xpcshell/head.js) | test MAIN run_test finished (2) 17:18:07 INFO - running event loop 17:18:07 INFO - security/manager/ssl/tests/unit/test_nsCertType.js | Starting 17:18:07 INFO - (xpcshell/head.js) | test pending (2) 17:18:07 INFO - TEST-PASS | security/manager/ssl/tests/unit/test_nsCertType.js | - Binary util BadCertServer should exist - true == true 17:18:07 INFO - TEST-PASS | security/manager/ssl/tests/unit/test_nsCertType.js | - certificate folder (bad_certs) should exist - true == true 17:18:07 INFO - <<<<<<< 17:18:07 INFO - mozcrash kill_pid(): wait failed (-1) terminating pid 2844: error 5 17:18:07 INFO - xpcshell return code: None 17:18:07 INFO - security/manager/ssl/tests/unit/test_nsCertType.js | Process still running after test! 17:34:47 INFO - Automation Error: mozprocess timed out after 1000 seconds running ['Z:\\task_1515085036\\build\\venv\\Scripts\\python', '-u', 'Z:\\task_1515085036\\build\\tests\\xpcshell\\runxpcshelltests.py', '--symbols-path=https://queue.taskcluster.net/v1/task/QVJvE1iRQGKC_S3Jl2CADA/artifacts/public/build/target.crashreporter-symbols.zip', '--test-plugin-path=Z:\\task_1515085036\\build\\application\\firefox\\plugins', '--log-raw=Z:\\task_1515085036\\build\\blobber_upload_dir\\xpcshell_raw.log', '--log-errorsummary=Z:\\task_1515085036\\build\\blobber_upload_dir\\xpcshell_errorsummary.log', '--utility-path=tests/bin', '--xpcshell=Z:\\task_1515085036\\build\\application\\firefox/xpcshell.exe', '--manifest=tests/xpcshell/tests/xpcshell.ini'] 17:34:47 ERROR - timed out after 1000 seconds of no output 17:34:47 ERROR - Return code: 572 17:34:47 ERROR - No tests run or test summary not found 17:34:47 INFO - TinderboxPrint: xpcshell-xpcshell<br/><em class="testfail">T-FAIL</em> 17:34:47 WARNING - # TBPL FAILURE # 17:34:47 WARNING - setting return code to 2 17:34:47 WARNING - The xpcshell suite: xpcshell ran with return status: FAILURE 17:34:47 INFO - Running post-action listener: _package_coverage_data 17:34:47 INFO - Running post-action listener: _resource_record_post_action 17:34:47 INFO - [mozharness: 2018-01-04 17:34:47.474000Z] Finished run-tests step (success) 17:34:47 INFO - Running post-run listener: _resource_record_post_run 17:34:47 INFO - Validating Perfherder data against Z:\task_1515085036\mozharness\external_tools\performance-artifact-schema.json 17:34:47 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"subtests": [{"name": "cpu_percent", "value": 25.312926003734734}, {"name": "io_write_bytes", "value": 11663605248}, {"name": "io.read_bytes", "value": 838384128}, {"name": "io_write_time", "value": 37972353}, {"name": "io_read_time", "value": 4378525}], "extraOptions": ["taskcluster-c4.2xlarge"], "name": "xpcshell.xpcshell.xpcshell.overall"}, {"subtests": [{"name": "time", "value": 2.1679999828338623}, {"name": "cpu_percent", "value": 14.05}], "name": "xpcshell.xpcshell.xpcshell.install"}, {"subtests": [{"name": "time", "value": 0.03600001335144043}], "name": "xpcshell.xpcshell.xpcshell.stage-files"}, {"subtests": [{"name": "time", "value": 2148.076999902725}, {"name": "cpu_percent", "value": 25.323633177570002}], "name": "xpcshell.xpcshell.xpcshell.run-tests"}]} 17:34:47 INFO - Total resource usage - Wall time: 2150s; CPU: 25.0%; Read bytes: 838384128; Write bytes: 11663605248; Read time: 4378525; Write time: 37972353 17:34:47 INFO - TinderboxPrint: CPU usage<br/>25.3% 17:34:47 INFO - TinderboxPrint: I/O read bytes / time<br/>838,384,128 / 4,378,525 17:34:47 INFO - TinderboxPrint: I/O write bytes / time<br/>11,663,605,248 / 37,972,353 17:34:47 INFO - TinderboxPrint: CPU idle<br/>12,843.6 (74.7%) 17:34:47 INFO - TinderboxPrint: CPU system<br/>2,016.0 (11.7%) 17:34:47 INFO - TinderboxPrint: CPU user<br/>2,337.8 (13.6%) 17:34:47 INFO - install - Wall time: 2s; CPU: 14.0%; Read bytes: 0; Write bytes: 4582400; Read time: 0; Write time: 12109 17:34:47 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:34:47 INFO - run-tests - Wall time: 2148s; CPU: 25.0%; Read bytes: 838384128; Write bytes: 11643766272; Read time: 4378525; Write time: 37937154 17:34:48 INFO - Running post-run listener: _upload_blobber_files 17:34:48 WARNING - Blob upload gear skipped. Missing cmdline options. 17:34:48 INFO - Running post-run listener: copy_logs_to_upload_dir 17:34:48 INFO - Copying logs to upload dir... 17:34:48 INFO - mkdir: Z:\task_1515085036\build\upload\logs 17:34:48 INFO - Copying logs to upload dir... 17:34:48 INFO - Using _rmtree_windows ... 17:34:48 INFO - Running command: del /F /Q "Z:\task_1515085036\build\upload\logs\localconfig.json" 17:34:48 INFO - Return code: 0 17:34:48 INFO - Using _rmtree_windows ... 17:34:48 INFO - Running command: del /F /Q "Z:\task_1515085036\build\upload\logs\log_info.log" 17:34:48 INFO - Return code: 0 17:34:48 INFO - Using _rmtree_windows ... 17:34:48 INFO - Running command: del /F /Q "Z:\task_1515085036\build\upload\logs\log_raw.log" 17:34:48 INFO - Return code: 0 17:34:48 INFO - Using _rmtree_windows ... 17:34:48 INFO - Running command: del /F /Q "Z:\task_1515085036\build\upload\logs\log_warning.log" 17:34:48 INFO - Return code: 0 17:34:48 INFO - Using _rmtree_windows ... 17:34:48 INFO - Running command: del /F /Q "Z:\task_1515085036\build\upload\logs\log_critical.log" 17:34:48 INFO - Return code: 0 17:34:48 INFO - Using _rmtree_windows ... 17:34:48 INFO - Running command: del /F /Q "Z:\task_1515085036\build\upload\logs\log_error.log" 17:34:48 INFO - Return code: 0 17:34:48 INFO - Using _rmtree_windows ... 17:34:48 INFO - Running command: del /F /Q "Z:\task_1515085036\build\upload\logs\log_fatal.log" 17:34:48 INFO - Return code: 0 17:34:48 WARNING - returning nonzero exit status 2 [taskcluster 2018-01-04T17:34:48.396Z] Exit Code: 2 [taskcluster 2018-01-04T17:34:48.396Z] User Time: 0s [taskcluster 2018-01-04T17:34:48.396Z] Kernel Time: 15.625ms [taskcluster 2018-01-04T17:34:48.396Z] Wall Time: 37m27.6629606s [taskcluster 2018-01-04T17:34:48.396Z] Peak Memory: 5939200 [taskcluster 2018-01-04T17:34:48.397Z] Result: FAILED [taskcluster 2018-01-04T17:34:48.397Z] === Task Finished === [taskcluster 2018-01-04T17:34:48.397Z] Task Duration: 37m27.6949666s
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.