Closed Bug 1573633 Opened 5 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | dom/security/test/csp/test_worker_src.html | application timed out after 370 seconds with no output

Categories

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

defect

Tracking

()

RESOLVED DUPLICATE of bug 1576316

People

(Reporter: intermittent-bug-filer, Assigned: ehsan.akhgari)

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [domsecurity-intermittent][retriggered])

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


[task 2019-08-13T17:02:48.150Z] 17:02:48     INFO - TEST-START | dom/security/test/csp/test_worker_src.html
[task 2019-08-13T17:08:59.059Z] 17:08:59     INFO - Buffered messages logged at 17:02:48
[task 2019-08-13T17:08:59.060Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | allowing worker from https://example.com (worker-allowed) 
[task 2019-08-13T17:08:59.060Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | allowing worker from https://example.com (shared-worker-allowed) 
[task 2019-08-13T17:08:59.060Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | allowing worker from https://example.com (service-worker-allowed) 
[task 2019-08-13T17:08:59.061Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | allowing worker from https://example.com (shared-worker-allowed) 
[task 2019-08-13T17:08:59.062Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | allowing worker from https://example.com (worker-allowed) 
[task 2019-08-13T17:08:59.063Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | allowing worker from https://example.com (service-worker-allowed) 
[task 2019-08-13T17:08:59.063Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | allowing worker from https://example.com (shared-worker-allowed) 
[task 2019-08-13T17:08:59.063Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | allowing worker from https://example.com (worker-allowed) 
[task 2019-08-13T17:08:59.063Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | allowing worker from https://example.com (service-worker-allowed) 
[task 2019-08-13T17:08:59.063Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | blocking worker from https://test1.example.com (service-worker-blocked) 
[task 2019-08-13T17:08:59.063Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | blocking worker from https://test1.example.com (worker-blocked) 
[task 2019-08-13T17:08:59.063Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | blocking worker from https://test1.example.com (shared-worker-blocked) 
[task 2019-08-13T17:08:59.063Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | blocking worker from https://test1.example.com (shared-worker-blocked) 
[task 2019-08-13T17:08:59.064Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | blocking worker from https://test1.example.com (service-worker-blocked) 
[task 2019-08-13T17:08:59.064Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | blocking worker from https://test1.example.com (worker-blocked) 
[task 2019-08-13T17:08:59.065Z] 17:08:59     INFO - Buffered messages logged at 17:02:49
[task 2019-08-13T17:08:59.065Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | blocking worker from https://test1.example.com (service-worker-blocked) 
[task 2019-08-13T17:08:59.065Z] 17:08:59     INFO - TEST-PASS | dom/security/test/csp/test_worker_src.html | blocking worker from https://test1.example.com (worker-blocked) 
[task 2019-08-13T17:08:59.066Z] 17:08:59     INFO - Buffered messages finished
[task 2019-08-13T17:08:59.066Z] 17:08:59    ERROR - TEST-UNEXPECTED-TIMEOUT | dom/security/test/csp/test_worker_src.html | application timed out after 370 seconds with no output
[task 2019-08-13T17:08:59.067Z] 17:08:59    ERROR - Force-terminating active process(es).
[task 2019-08-13T17:08:59.067Z] 17:08:59     INFO - Determining child pids from psutil...
[task 2019-08-13T17:08:59.071Z] 17:08:59     INFO - [1579, 1561, 1455, 2098, 1533, 1419]
[task 2019-08-13T17:08:59.071Z] 17:08:59     INFO - ==> process 1382 launched child process 1403
[task 2019-08-13T17:08:59.072Z] 17:08:59     INFO - ==> process 1382 launched child process 1419
[task 2019-08-13T17:08:59.072Z] 17:08:59     INFO - ==> process 1382 launched child process 1455
[task 2019-08-13T17:08:59.073Z] 17:08:59     INFO - ==> process 1382 launched child process 1474
[task 2019-08-13T17:08:59.073Z] 17:08:59     INFO - ==> process 1382 launched child process 1533
[task 2019-08-13T17:08:59.074Z] 17:08:59     INFO - ==> process 1382 launched child process 1561
[task 2019-08-13T17:08:59.074Z] 17:08:59     INFO - ==> process 1382 launched child process 1579
[task 2019-08-13T17:08:59.074Z] 17:08:59     INFO - ==> process 1382 launched child process 1628
[task 2019-08-13T17:08:59.075Z] 17:08:59     INFO - ==> process 1382 launched child process 1657
[task 2019-08-13T17:08:59.075Z] 17:08:59     INFO - ==> process 1382 launched child process 1687
[task 2019-08-13T17:08:59.075Z] 17:08:59     INFO - ==> process 1382 launched child process 1715
[task 2019-08-13T17:08:59.076Z] 17:08:59     INFO - ==> process 1382 launched child process 1806
[task 2019-08-13T17:08:59.076Z] 17:08:59     INFO - ==> process 1382 launched child process 1836
[task 2019-08-13T17:08:59.077Z] 17:08:59     INFO - ==> process 1382 launched child process 1866
[task 2019-08-13T17:08:59.077Z] 17:08:59     INFO - ==> process 1382 launched child process 1894
[task 2019-08-13T17:08:59.077Z] 17:08:59     INFO - ==> process 1382 launched child process 1922
[task 2019-08-13T17:08:59.078Z] 17:08:59     INFO - ==> process 1382 launched child process 1950
[task 2019-08-13T17:08:59.078Z] 17:08:59     INFO - ==> process 1382 launched child process 1981
[task 2019-08-13T17:08:59.079Z] 17:08:59     INFO - ==> process 1382 launched child process 2009
[task 2019-08-13T17:08:59.079Z] 17:08:59     INFO - ==> process 1382 launched child process 2037
[task 2019-08-13T17:08:59.079Z] 17:08:59     INFO - ==> process 1382 launched child process 2066
[task 2019-08-13T17:08:59.080Z] 17:08:59     INFO - ==> process 1382 launched child process 2098
[task 2019-08-13T17:08:59.080Z] 17:08:59     INFO - Found child pids: set([1922, 1419, 1806, 2066, 1687, 1561, 1950, 1579, 1836, 1455, 2098, 1715, 1981, 1474, 1866, 2009, 1628, 1894, 2037, 1657, 1403, 1533])
[task 2019-08-13T17:08:59.080Z] 17:08:59     INFO - Failed to get child procs
[task 2019-08-13T17:08:59.081Z] 17:08:59     INFO - Killing process: 1922
[task 2019-08-13T17:08:59.081Z] 17:08:59     INFO - TEST-INFO | started process screentopng
[task 2019-08-13T17:08:59.330Z] 17:08:59     INFO - TEST-INFO | screentopng: exit 0
[task 2019-08-13T17:08:59.331Z] 17:08:59     INFO - Can't trigger Breakpad, process no longer exists
Whiteboard: [domsecurity-intermittent]
Whiteboard: [domsecurity-intermittent] → [domsecurity-intermittent][retriggered]
Blocks: 1576316

My patch in bug 1576316 fixes this.

Assignee: nobody → ehsan
No longer blocks: 1576316
Status: NEW → RESOLVED
Closed: 5 years ago
Flags: needinfo?(ehsan)
Resolution: --- → DUPLICATE

Only one occurrence after that landing, so extremely rare intermittent. Unfortunately don't have time to look into it further at the moment, sorry about that.

Flags: needinfo?(ehsan)
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.