Closed Bug 1572605 Opened 6 years ago Closed 6 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | toolkit/components/antitracking/test/browser/browser_referrerDefaultPolicy.js | application timed out after 370 seconds with no output

Categories

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

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

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

Filed by: ccoroiu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=260631104&repo=try
Full log: https://queue.taskcluster.net/v1/task/XnV_SGPiQBez4NR2x4uRVg/runs/2/artifacts/public/logs/live_backing.log


task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - TEST-PASS | toolkit/components/antitracking/test/browser/browser_referrerDefaultPolicy.js | We sent the correct Referer header -
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Buffered messages logged at 18:54:39
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Creating a new normal window
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Buffered messages logged at 18:54:45
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Wait tab event: load
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Buffered messages logged at 18:54:52
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Tab event received: load
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Loading tracking scripts and tracking images
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Buffered messages logged at 18:54:53
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 0}]
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Buffered messages logged at 18:54:56
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/referrer.sjs?what=script” was blocked because it came from a tracker and content blocking is enabled." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 0}]
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “https://tracking.example.org/browser/toolkit/components/antitracking/test/browser/referrer.sjs?what=image” was blocked because it came from a tracker and content blocking is enabled." {file: "http://example.net/browser/toolkit/components/antitracking/test/browser/page.html" line: 0}]
[task 2019-08-08T19:07:46.186Z] 19:07:46 INFO - Buffered messages finished
[task 2019-08-08T19:07:46.491Z] 19:07:46 ERROR - TEST-UNEXPECTED-TIMEOUT | toolkit/components/antitracking/test/browser/browser_referrerDefaultPolicy.js | application timed out after 370 seconds with no output
[task 2019-08-08T19:07:46.502Z] 19:07:46 ERROR - Force-terminating active process(es).
[task 2019-08-08T19:07:46.502Z] 19:07:46 INFO - Determining child pids from psutil...
[task 2019-08-08T19:07:46.741Z] 19:07:46 INFO - [4362, 4376, 4432, 4460, 4484, 4511]
[task 2019-08-08T19:07:47.088Z] 19:07:46 INFO - ==> process 4313 launched child process 4330
[task 2019-08-08T19:07:47.160Z] 19:07:46 INFO - ==> process 4313 launched child process 4362
[task 2019-08-08T19:07:47.160Z] 19:07:46 INFO - ==> process 4313 launched child process 4376
[task 2019-08-08T19:07:47.160Z] 19:07:46 INFO - ==> process 4313 launched child process 4432
[task 2019-08-08T19:07:47.160Z] 19:07:46 INFO - ==> process 4313 launched child process 4460
[task 2019-08-08T19:07:47.161Z] 19:07:46 INFO - ==> process 4313 launched child process 4484
[task 2019-08-08T19:07:47.161Z] 19:07:46 INFO - ==> process 4313 launched child process 4511
[task 2019-08-08T19:07:47.161Z] 19:07:46 INFO - Found child pids: set([4484, 4362, 4460, 4432, 4376, 4330, 4511])
[task 2019-08-08T19:07:47.161Z] 19:07:46 INFO - Failed to get child procs
[task 2019-08-08T19:07:47.161Z] 19:07:46 INFO - Killing process: 4484
[task 2019-08-08T19:07:47.161Z] 19:07:46 INFO - TEST-INFO | started process screentopng
[task 2019-08-08T19:08:47.647Z] 19:08:47 INFO - TEST-INFO | screentopng: exit 0
[task 2019-08-08T19:08:47.674Z] 19:08:47 INFO - Can't trigger Breakpad, just killing process
[task 2019-08-08T19:09:00.118Z] 19:09:00 INFO - psutil found pid 4484 dead
[task 2019-08-08T19:09:00.118Z] 19:09:00 INFO - Killing process: 4362
[task 2019-08-08T19:09:00.118Z] 19:09:00 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-08-08T19:09:00.118Z] 19:09:00 INFO - Can't trigger Breakpad, just killing process
[task 2019-08-08T19:09:00.237Z] 19:09:00 INFO - psutil found pid 4362 dead
[task 2019-08-08T19:09:00.237Z] 19:09:00 INFO - Killing process: 4460
[task 2019-08-08T19:09:00.237Z] 19:09:00 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-08-08T19:09:00.238Z] 19:09:00 INFO - Can't trigger Breakpad, just killing process
[task 2019-08-08T19:09:00.475Z] 19:09:00 INFO - psutil found pid 4460 dead
[task 2019-08-08T19:09:00.475Z] 19:09:00 INFO - Killing process: 4432

Whiteboard: [domsecurity-intermittent]
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.