Closed Bug 1584875 Opened 5 years ago Closed 5 years ago

Intermittent devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Test timed out - | application timed out after 370 seconds with no output

Categories

(DevTools :: Console, defect, P5)

Product:
DevTools
Component:
Console
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, regression)

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

[task 2019-09-30T00:17:30.362Z] 00:17:30 INFO - TEST-START | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js
[task 2019-09-30T00:18:26.920Z] 00:18:26 INFO - GECKO(2066) | console.error: (new TypeError("this._walker is undefined", "resource://devtools/client/accessibility/accessibility-startup.js", 141))
[task 2019-09-30T00:20:29.026Z] 00:20:29 INFO - GECKO(2066) | console.error: (new TypeError("this._walker is undefined", "resource://devtools/client/accessibility/accessibility-startup.js", 141))
[task 2019-09-30T00:21:33.538Z] 00:21:33 INFO - GECKO(2066) | console.error: (new TypeError("this._walker is undefined", "resource://devtools/client/accessibility/accessibility-startup.js", 141))
[task 2019-09-30T00:24:07.417Z] 00:24:07 INFO - TEST-INFO | started process screentopng
[task 2019-09-30T00:24:13.447Z] 00:24:13 INFO - TEST-INFO | screentopng: exit 0
[task 2019-09-30T00:24:13.450Z] 00:24:13 INFO - Buffered messages logged at 00:17:30
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Entering test bound testContentBlockingMessage
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Buffered messages logged at 00:17:31
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Adding a new tab with URL: http://tracking.example.org/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Console message: [JavaScript Warning: "The resource at “http://tracking.example.com/” was blocked because content blocking is enabled." {file: "http://tracking.example.org/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 0}]
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Console message: [JavaScript Warning: "The character encoding of a framed document was not declared. The document may appear different if viewed without the document framing it." {file: "http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html" line: 0}]
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Tab added and finished loading
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Opening the toolbox
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Buffered messages logged at 00:17:35
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Toolbox opened and focused
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Test content blocking message
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Clicking on the Learn More link
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Buffered messages logged at 00:17:36
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Clicking the provided link opens https://developer.mozilla.org/Firefox/Privacy/Tracking_Protection?utm_source=mozilla&utm_medium=firefox-console-errors&utm_campaign=default -
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Clicking the provided link opens in expected tab -
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Leaving test bound testContentBlockingMessage
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Entering test bound testForeignCookieBlockedMessage
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Test foreign cookie blocked message
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Buffered messages logged at 00:17:38
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Buffered messages logged at 00:17:42
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 0}]
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 0}]
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Buffered messages logged at 00:17:43
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Tab added and finished loading
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 5 column: 4 source: "http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html"}]
[task 2019-09-30T00:24:13.451Z] 00:24:13 INFO - Buffered messages logged at 00:18:15
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Buffered messages logged at 00:18:18
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Clicking on the Learn More link
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Buffered messages logged at 00:18:19
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Clicking the provided link opens https://developer.mozilla.org/docs/Mozilla/Firefox/Privacy/Storage_access_policy/Errors/CookieBlockedForeign?utm_source=devtools&utm_medium=firefox-cookie-errors&utm_campaign=default -
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Clicking the provided link opens in expected tab -
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Leaving test bound testForeignCookieBlockedMessage
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Entering test bound testLimitForeignCookieBlockedMessage
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Test unvisited eTLD foreign cookies blocked message
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Buffered messages logged at 00:18:42
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Buffered messages logged at 00:19:15
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Longer timeout required, waiting longer... Remaining timeouts: 1
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Buffered messages logged at 00:19:23
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Tab added and finished loading
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Buffered messages logged at 00:19:33
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html” was blocked because we are blocking all third-party storage access requests and content blocking is enabled." {file: "http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 5 column: 4 source: "http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html"}]
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Buffered messages logged at 00:20:25
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Buffered messages logged at 00:20:26
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Third-party storage access blocked message was displayed -
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Check that clicking on the Learn More link works as expected
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Clicking on the Learn More link
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Clicking the provided link opens https://developer.mozilla.org/docs/Mozilla/Firefox/Privacy/Storage_access_policy/Errors/CookieBlockedForeign?utm_source=devtools&utm_medium=firefox-cookie-errors&utm_campaign=default -
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Clicking the provided link opens in expected tab -
[task 2019-09-30T00:24:13.452Z] 00:24:13 INFO - Leaving test bound testLimitForeignCookieBlockedMessage
[task 2019-09-30T00:24:13.453Z] 00:24:13 INFO - Entering test bound testAllCookieBlockedMessage
[task 2019-09-30T00:24:13.454Z] 00:24:13 INFO - Test all cookies blocked message
[task 2019-09-30T00:24:13.454Z] 00:24:13 INFO - Buffered messages logged at 00:20:34
[task 2019-09-30T00:24:13.455Z] 00:24:13 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html
[task 2019-09-30T00:24:13.455Z] 00:24:13 INFO - Buffered messages logged at 00:20:36
[task 2019-09-30T00:24:13.455Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html” was blocked because we are blocking all storage access requests." {file: "about:blank" line: 0}]
[task 2019-09-30T00:24:13.457Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html” was blocked because we are blocking all storage access requests." {file: "about:blank" line: 0}]
[task 2019-09-30T00:24:13.457Z] 00:24:13 INFO - Buffered messages logged at 00:20:37
[task 2019-09-30T00:24:13.457Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html” was blocked because we are blocking all storage access requests." {file: "http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 0}]
[task 2019-09-30T00:24:13.458Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.com/” was blocked because we are blocking all storage access requests." {file: "http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 0}]
[task 2019-09-30T00:24:13.459Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html” was blocked because we are blocking all storage access requests." {file: "http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 0}]
[task 2019-09-30T00:24:13.460Z] 00:24:13 INFO - Tab added and finished loading
[task 2019-09-30T00:24:13.460Z] 00:24:13 INFO - Buffered messages logged at 00:20:38
[task 2019-09-30T00:24:13.460Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.com/” was blocked because we are blocking all storage access requests." {file: "http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 0}]
[task 2019-09-30T00:24:13.462Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html” was blocked because we are blocking all storage access requests." {file: "http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 5 column: 4 source: "http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html"}]
[task 2019-09-30T00:24:13.462Z] 00:24:13 INFO - Buffered messages logged at 00:21:14
[task 2019-09-30T00:24:13.463Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-09-30T00:24:13.463Z] 00:24:13 INFO - Buffered messages logged at 00:21:22
[task 2019-09-30T00:24:13.464Z] 00:24:13 INFO - Clicking on the Learn More link
[task 2019-09-30T00:24:13.465Z] 00:24:13 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Clicking the provided link opens https://developer.mozilla.org/docs/Mozilla/Firefox/Privacy/Storage_access_policy/Errors/CookieBlockedAll?utm_source=devtools&utm_medium=firefox-cookie-errors&utm_campaign=default -
[task 2019-09-30T00:24:13.465Z] 00:24:13 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Clicking the provided link opens in expected tab -
[task 2019-09-30T00:24:13.465Z] 00:24:13 INFO - Leaving test bound testAllCookieBlockedMessage
[task 2019-09-30T00:24:13.466Z] 00:24:13 INFO - Entering test bound testTrackerCookieBlockedMessage
[task 2019-09-30T00:24:13.466Z] 00:24:13 INFO - Test tracker cookie blocked message
[task 2019-09-30T00:24:13.467Z] 00:24:13 INFO - Buffered messages logged at 00:21:42
[task 2019-09-30T00:24:13.469Z] 00:24:13 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html
[task 2019-09-30T00:24:13.472Z] 00:24:13 INFO - Buffered messages logged at 00:22:13
[task 2019-09-30T00:24:13.472Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html” was blocked because it came from a tracker and content blocking is enabled." {file: "http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 0}]
[task 2019-09-30T00:24:13.473Z] 00:24:13 INFO - Buffered messages logged at 00:22:19
[task 2019-09-30T00:24:13.474Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html” was blocked because it came from a tracker and content blocking is enabled." {file: "http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 0}]
[task 2019-09-30T00:24:13.474Z] 00:24:13 INFO - Buffered messages logged at 00:22:51
[task 2019-09-30T00:24:13.474Z] 00:24:13 INFO - Tab added and finished loading
[task 2019-09-30T00:24:13.474Z] 00:24:13 INFO - Console message: [JavaScript Warning: "Request to access cookie or storage on “http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html” was blocked because it came from a tracker and content blocking is enabled." {file: "http://example.com/browser/devtools/client/webconsole/test/browser/test-trackingprotection-securityerrors.html" line: 5 column: 4 source: "http://tracking.example.org/browser/devtools/client/webconsole/test/browser/cookieSetter.html"}]
[task 2019-09-30T00:24:13.474Z] 00:24:13 INFO - Buffered messages finished
[task 2019-09-30T00:24:13.475Z] 00:24:13 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | Test timed out -
[task 2019-09-30T00:31:01.645Z] 00:30:52 INFO - Buffered messages finished
[task 2019-09-30T00:31:29.296Z] 00:31:21 ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/webconsole/test/browser/browser_webconsole_trackingprotection_errors.js | application timed out after 370 seconds with no output
[task 2019-09-30T00:32:05.076Z] 00:31:54 ERROR - Force-terminating active process(es).
[task 2019-09-30T00:32:42.024Z] 00:32:33 INFO - Determining child pids from psutil...
[task 2019-09-30T00:32:47.455Z] 00:32:46 INFO - [2120, 2144, 2196, 2227, 2262]
[task 2019-09-30T00:32:49.444Z] 00:32:48 INFO - ==> process 2066 launched child process 2087
[task 2019-09-30T00:32:50.718Z] 00:32:50 INFO - ==> process 2066 launched child process 2120
[task 2019-09-30T00:32:50.727Z] 00:32:50 INFO - ==> process 2066 launched child process 2144
[task 2019-09-30T00:32:50.727Z] 00:32:50 INFO - ==> process 2066 launched child process 2196
[task 2019-09-30T00:32:50.727Z] 00:32:50 INFO - ==> process 2066 launched child process 2227
[task 2019-09-30T00:32:50.727Z] 00:32:50 INFO - ==> process 2066 launched child process 2262
[task 2019-09-30T00:32:50.728Z] 00:32:50 INFO - Found child pids: set([2144, 2087, 2120, 2227, 2196, 2262])
[task 2019-09-30T00:32:50.728Z] 00:32:50 INFO - Failed to get child procs
[task 2019-09-30T00:32:50.728Z] 00:32:50 INFO - Killing process: 2144
[task 2019-09-30T00:32:50.729Z] 00:32:50 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-30T00:32:50.729Z] 00:32:50 INFO - Can't trigger Breakpad, just killing process
[task 2019-09-30T00:32:53.520Z] 00:32:53 INFO - psutil found pid 2144 dead
[task 2019-09-30T00:32:53.520Z] 00:32:53 INFO - Killing process: 2087
[task 2019-09-30T00:32:53.521Z] 00:32:53 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-30T00:32:53.521Z] 00:32:53 INFO - Can't trigger Breakpad, just killing process
[task 2019-09-30T00:32:53.521Z] 00:32:53 INFO - Error: Failed to kill process 2087: psutil.NoSuchProcess no process found with pid 2087
[task 2019-09-30T00:32:53.522Z] 00:32:53 INFO - Killing process: 2120
[task 2019-09-30T00:32:53.522Z] 00:32:53 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-30T00:32:53.522Z] 00:32:53 INFO - Can't trigger Breakpad, just killing process
[task 2019-09-30T00:32:53.788Z] 00:32:53 INFO - psutil found pid 2120 dead
[task 2019-09-30T00:32:53.789Z] 00:32:53 INFO - Killing process: 2227

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.