Closed Bug 1334555 Opened 7 years ago Closed 5 years ago

Intermittent toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js | Test timed out -

Categories

(Toolkit :: General, defect)

defect
Not set
normal

Tracking

()

RESOLVED FIXED
mozilla65
Tracking Status
firefox65 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: Gijs)

Details

(Keywords: bulk-close-intermittents, intermittent-failure, Whiteboard: [stockwell unknown])

Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 7 years ago7 years ago
Resolution: --- → INCOMPLETE
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 7 years ago6 years ago
Resolution: --- → INCOMPLETE
Fail reappeared on autoland.

Log: https://treeherder.mozilla.org/logviewer.html#?job_id=164920738&repo=autoland&lineNumber=26962
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=175160698&repo=mozilla-beta&lineNumber=5545

[task 2018-04-23T18:41:27.376Z] 18:41:27     INFO - TEST-START | toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js
[task 2018-04-23T18:42:12.387Z] 18:42:12     INFO - TEST-INFO | started process screentopng
[task 2018-04-23T18:42:12.816Z] 18:42:12     INFO - TEST-INFO | screentopng: exit 0
[task 2018-04-23T18:42:12.816Z] 18:42:12     INFO - Buffered messages logged at 18:41:27
[task 2018-04-23T18:42:12.817Z] 18:42:12     INFO - Entering test bound 
[task 2018-04-23T18:42:12.818Z] 18:42:12     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: "data:text/html,<body%20style="height:10000px;"></body>" line: 0}]
[task 2018-04-23T18:42:12.819Z] 18:42:12     INFO - Buffered messages finished
[task 2018-04-23T18:42:12.821Z] 18:42:12     INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js | Test timed out - 
[task 2018-04-23T18:42:12.821Z] 18:42:12     INFO - GECKO(6046) | MEMORY STAT | vsize 2503MB | residentFast 307MB | heapAllocated 110MB
[task 2018-04-23T18:42:12.821Z] 18:42:12     INFO - TEST-OK | toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js | took 45032ms
[task 2018-04-23T18:42:12.821Z] 18:42:12     INFO - checking window state
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
This bug has failed 34 times in the last 7 days, failures happen on Linux, osx and windows platforms on all build types.

Log:
https://treeherder.mozilla.org/logviewer.html#?job_id=195627444&repo=mozilla-inbound&lineNumber=16618

TEST-INFO | started process screentopng
[task 2018-08-23T23:03:07.474Z] 23:03:07     INFO - TEST-INFO | screentopng: exit 0
[task 2018-08-23T23:03:07.476Z] 23:03:07     INFO - Buffered messages logged at 22:58:36
[task 2018-08-23T23:03:07.477Z] 23:03:07     INFO - Entering test bound 
[task 2018-08-23T23:03:07.478Z] 23:03:07     INFO - Buffered messages logged at 22:58:37
[task 2018-08-23T23:03:07.479Z] 23:03:07     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: "data:text/html,<body style="height:10000px;"></body>" line: 0}]
[task 2018-08-23T23:03:07.480Z] 23:03:07     INFO - Buffered messages logged at 22:59:50
[task 2018-08-23T23:03:07.482Z] 23:03:07     INFO - Console message: [JavaScript Error: "getScreenshot(https://example.com/browser/toolkit/content/tests/browser/file_empty.html) failed: TypeError: NetworkError when attempting to fetch resource." {file: "resource://activity-stream/lib/Screenshots.jsm" line: 47}]
[task 2018-08-23T23:03:07.483Z] 23:03:07     INFO - getScreenshotForURL@resource://activity-stream/lib/Screenshots.jsm:47:7
[task 2018-08-23T23:03:07.484Z] 23:03:07     INFO - async*maybeCacheScreenshot@resource://activity-stream/lib/Screenshots.jsm:101:32
[task 2018-08-23T23:03:07.485Z] 23:03:07     INFO - async*_fetchScreenshot@resource://activity-stream/lib/TopSitesFeed.jsm:436:11
[task 2018-08-23T23:03:07.487Z] 23:03:07     INFO - async*_fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:424:11
[task 2018-08-23T23:03:07.488Z] 23:03:07     INFO - async*getLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:326:11
[task 2018-08-23T23:03:07.489Z] 23:03:07     INFO - async*refresh@resource://activity-stream/lib/TopSitesFeed.jsm:349:25
[task 2018-08-23T23:03:07.490Z] 23:03:07     INFO - async*onAction@resource://activity-stream/lib/TopSitesFeed.jsm:649:9
[task 2018-08-23T23:03:07.492Z] 23:03:07     INFO - _middleware/</<@resource://activity-stream/lib/Store.jsm:51:11
[task 2018-08-23T23:03:07.493Z] 23:03:07     INFO - Store/this[method]@resource://activity-stream/lib/Store.jsm:29:54
[task 2018-08-23T23:03:07.496Z] 23:03:07     INFO - init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:18:41
[task 2018-08-23T23:03:07.499Z] 23:03:07     INFO - notify@resource://gre/modules/Timer.jsm:42:7
[task 2018-08-23T23:03:07.501Z] 23:03:07     INFO - 
[task 2018-08-23T23:03:07.504Z] 23:03:07     INFO - Console message: [JavaScript Error: "getScreenshot(https://test1.example.org/browser/toolkit/content/tests/browser/file_autoplay_three_layers_frame1.html) failed: TypeError: NetworkError when attempting to fetch resource." {file: "resource://activity-stream/lib/Screenshots.jsm" line: 47}]
[task 2018-08-23T23:03:07.506Z] 23:03:07     INFO - getScreenshotForURL@resource://activity-stream/lib/Screenshots.jsm:47:7
[task 2018-08-23T23:03:07.507Z] 23:03:07     INFO - async*maybeCacheScreenshot@resource://activity-stream/lib/Screenshots.jsm:101:32
[task 2018-08-23T23:03:07.508Z] 23:03:07     INFO - async*_fetchScreenshot@resource://activity-stream/lib/TopSitesFeed.jsm:436:11
[task 2018-08-23T23:03:07.509Z] 23:03:07     INFO - async*_fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:424:11
[task 2018-08-23T23:03:07.510Z] 23:03:07     INFO - async*getLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:326:11
[task 2018-08-23T23:03:07.511Z] 23:03:07     INFO - async*refresh@resource://activity-stream/lib/TopSitesFeed.jsm:349:25
[task 2018-08-23T23:03:07.513Z] 23:03:07     INFO - async*onAction@resource://activity-stream/lib/TopSitesFeed.jsm:649:9
[task 2018-08-23T23:03:07.513Z] 23:03:07     INFO - _middleware/</<@resource://activity-stream/lib/Store.jsm:51:11
[task 2018-08-23T23:03:07.514Z] 23:03:07     INFO - Store/this[method]@resource://activity-stream/lib/Store.jsm:29:54
[task 2018-08-23T23:03:07.515Z] 23:03:07     INFO - init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:18:41
[task 2018-08-23T23:03:07.516Z] 23:03:07     INFO - notify@resource://gre/modules/Timer.jsm:42:7
[task 2018-08-23T23:03:07.517Z] 23:03:07     INFO - 
[task 2018-08-23T23:03:07.518Z] 23:03:07     INFO - Console message: [JavaScript Error: "NetworkError when attempting to fetch resource."]
[task 2018-08-23T23:03:07.518Z] 23:03:07     INFO - get@resource://services-settings/remote-settings.js:326:9
[task 2018-08-23T23:03:07.519Z] 23:03:07     INFO - async*getSite@resource://activity-stream/lib/FaviconFeed.jsm:158:25
[task 2018-08-23T23:03:07.520Z] 23:03:07     INFO - async*fetchIcon@resource://activity-stream/lib/FaviconFeed.jsm:132:24
[task 2018-08-23T23:03:07.521Z] 23:03:07     INFO - async*onAction@resource://activity-stream/lib/FaviconFeed.jsm:182:9
[task 2018-08-23T23:03:07.522Z] 23:03:07     INFO - _middleware/</<@resource://activity-stream/lib/Store.jsm:51:11
[task 2018-08-23T23:03:07.524Z] 23:03:07     INFO - Store/this[method]@resource://activity-stream/lib/Store.jsm:29:54
[task 2018-08-23T23:03:07.525Z] 23:03:07     INFO - _requestRichIcon@resource://activity-stream/lib/TopSitesFeed.jsm:457:5
[task 2018-08-23T23:03:07.526Z] 23:03:07     INFO - _fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:421:5
[task 2018-08-23T23:03:07.527Z] 23:03:07     INFO - async*getLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:326:11
[task 2018-08-23T23:03:07.529Z] 23:03:07     INFO - async*refresh@resource://activity-stream/lib/TopSitesFeed.jsm:349:25
[task 2018-08-23T23:03:07.530Z] 23:03:07     INFO - async*onAction@resource://activity-stream/lib/TopSitesFeed.jsm:649:9
[task 2018-08-23T23:03:07.531Z] 23:03:07     INFO - _middleware/</<@resource://activity-stream/lib/Store.jsm:51:11
[task 2018-08-23T23:03:07.532Z] 23:03:07     INFO - Store/this[method]@resource://activity-stream/lib/Store.jsm:29:54
[task 2018-08-23T23:03:07.534Z] 23:03:07     INFO - init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:18:41
[task 2018-08-23T23:03:07.535Z] 23:03:07     INFO - notify@resource://gre/modules/Timer.jsm:42:7
[task 2018-08-23T23:03:07.536Z] 23:03:07     INFO - 
[task 2018-08-23T23:03:07.538Z] 23:03:07     INFO - Console message: [JavaScript Error: "NetworkError when attempting to fetch resource."]
[task 2018-08-23T23:03:07.538Z] 23:03:07     INFO - get@resource://services-settings/remote-settings.js:326:9
[task 2018-08-23T23:03:07.539Z] 23:03:07     INFO - async*getSite@resource://activity-stream/lib/FaviconFeed.jsm:158:25
[task 2018-08-23T23:03:07.540Z] 23:03:07     INFO - async*fetchIcon@resource://activity-stream/lib/FaviconFeed.jsm:132:24
[task 2018-08-23T23:03:07.541Z] 23:03:07     INFO - async*onAction@resource://activity-stream/lib/FaviconFeed.jsm:182:9
[task 2018-08-23T23:03:07.541Z] 23:03:07     INFO - _middleware/</<@resource://activity-stream/lib/Store.jsm:51:11
[task 2018-08-23T23:03:07.542Z] 23:03:07     INFO - Store/this[method]@resource://activity-stream/lib/Store.jsm:29:54
[task 2018-08-23T23:03:07.543Z] 23:03:07     INFO - _requestRichIcon@resource://activity-stream/lib/TopSitesFeed.jsm:457:5
[task 2018-08-23T23:03:07.543Z] 23:03:07     INFO - _fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:421:5
[task 2018-08-23T23:03:07.544Z] 23:03:07     INFO - async*getLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:326:11
[task 2018-08-23T23:03:07.545Z] 23:03:07     INFO - async*refresh@resource://activity-stream/lib/TopSitesFeed.jsm:349:25
[task 2018-08-23T23:03:07.545Z] 23:03:07     INFO - async*onAction@resource://activity-stream/lib/TopSitesFeed.jsm:649:9
[task 2018-08-23T23:03:07.546Z] 23:03:07     INFO - _middleware/</<@resource://activity-stream/lib/Store.jsm:51:11
[task 2018-08-23T23:03:07.546Z] 23:03:07     INFO - Store/this[method]@resource://activity-stream/lib/Store.jsm:29:54
[task 2018-08-23T23:03:07.547Z] 23:03:07     INFO - init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:18:41
[task 2018-08-23T23:03:07.549Z] 23:03:07     INFO - notify@resource://gre/modules/Timer.jsm:42:7
[task 2018-08-23T23:03:07.550Z] 23:03:07     INFO - 
[task 2018-08-23T23:03:07.551Z] 23:03:07     INFO - Buffered messages logged at 23:01:15
[task 2018-08-23T23:03:07.552Z] 23:03:07     INFO - Console message: [JavaScript Error: "Polling for changes failed: Server error 404 Not Found: "JSON.parse: unexpected character at line 1 column 1 of the JSON data"." {file: "resource://services-settings/remote-settings.js" line: 721}]
[task 2018-08-23T23:03:07.553Z] 23:03:07     INFO - remoteSettingsFunction/remoteSettings.pollChanges@resource://services-settings/remote-settings.js:721:13
[task 2018-08-23T23:03:07.554Z] 23:03:07     INFO - async*notify@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/RemoteSettingsComponents.js:24:5
[task 2018-08-23T23:03:07.555Z] 23:03:07     INFO - TM_notify/<@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsUpdateTimerManager.js:197:48
[task 2018-08-23T23:03:07.556Z] 23:03:07     INFO - TM_notify@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/nsUpdateTimerManager.js:244:7
[task 2018-08-23T23:03:07.556Z] 23:03:07     INFO - 
[task 2018-08-23T23:03:07.558Z] 23:03:07     INFO - Buffered messages finished
[task 2018-08-23T23:03:07.559Z] 23:03:07     INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js | Test timed out - 
[task 2018-08-23T23:03:07.559Z] 23:03:07     INFO - GECKO(5399) | MEMORY STAT | vsize 830MB | residentFast 299MB | heapAllocated 82MB
[task 2018-08-23T23:03:07.561Z] 23:03:07     INFO - TEST-OK | toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js | took 270052ms
[task 2018-08-23T23:03:07.562Z] 23:03:07     INFO - GECKO(5399) | ++DOCSHELL 0xe9590400 == 1 [pid = 5846] [id = {13627447-c4a2-4dd0-b30b-5d261c607bf5}]
[task 2018-08-23T23:03:07.563Z] 23:03:07     INFO - GECKO(5399) | ++DOMWINDOW == 1 (0xe9581040) [pid = 5846] [serial = 38] [outer = (nil)]
[task 2018-08-23T23:03:07.564Z] 23:03:07     INFO - checking window state
[task 2018-08-23T23:03:07.565Z] 23:03:07     INFO - GECKO(5399) | ++DOMWINDOW == 2 (0xe9591400) [pid = 5846] [serial = 39] [outer = 0xe9581040]
[task 2018-08-23T23:03:07.566Z] 23:03:07     INFO - TEST-START | toolkit/content/tests/browser/browser_label_textlink.js

Dolske: Can you please take a look at this bug?
Flags: needinfo?(dolske)
Whiteboard: [stockwell needswork]
This failure occurred 31 times in the past week. A spike happened on 3rd of Nov.
Mostly occurring on linux32 and linux64 platforms.

Recent log link: https://treeherder.mozilla.org/logviewer.html#?job_id=210634994&repo=mozilla-central&lineNumber=8345
Recent log snippet:
[task 2018-11-08T19:48:55.301Z] 19:48:55     INFO - TEST-START | toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js
[task 2018-11-08T19:49:40.303Z] 19:49:40     INFO - TEST-INFO | started process screentopng
[task 2018-11-08T19:49:40.725Z] 19:49:40     INFO - TEST-INFO | screentopng: exit 0
[task 2018-11-08T19:49:40.726Z] 19:49:40     INFO - Buffered messages logged at 19:48:55
[task 2018-11-08T19:49:40.727Z] 19:49:40     INFO - Entering test bound 
[task 2018-11-08T19:49:40.728Z] 19:49:40     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: "data:text/html,<body style="height:10000px;"></body>" line: 0}]
[task 2018-11-08T19:49:40.728Z] 19:49:40     INFO - Buffered messages finished
[task 2018-11-08T19:49:40.728Z] 19:49:40     INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js | Test timed out - 
[task 2018-11-08T19:49:40.730Z] 19:49:40     INFO - GECKO(13465) | MEMORY STAT | vsize 2202MB | residentFast 314MB | heapAllocated 108MB
Failure rate is still high here, Justin do you have any updates here?
Whiteboard: [stockwell needswork] → [stockwell needswork:owner]
Dave can you please take a look at this?

24 total failures in the last 7 days: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=215172118&repo=mozilla-central&lineNumber=7124

[task 2018-12-02T13:03:40.635Z] 13:03:40     INFO - TEST-START | toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js
[task 2018-12-02T13:04:25.642Z] 13:04:25     INFO - TEST-INFO | started process screentopng
[task 2018-12-02T13:04:26.051Z] 13:04:26     INFO - TEST-INFO | screentopng: exit 0
[task 2018-12-02T13:04:26.052Z] 13:04:26     INFO - Buffered messages logged at 13:03:40
[task 2018-12-02T13:04:26.052Z] 13:04:26     INFO - Entering test bound 
[task 2018-12-02T13:04:26.053Z] 13:04:26     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: "data:text/html,<body style="height:10000px;"></body>" line: 0}]
[task 2018-12-02T13:04:26.055Z] 13:04:26     INFO - Buffered messages finished
[task 2018-12-02T13:04:26.055Z] 13:04:26     INFO - TEST-UNEXPECTED-FAIL | toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js | Test timed out - 
[task 2018-12-02T13:04:26.056Z] 13:04:26     INFO - GECKO(11635) | MEMORY STAT | vsize 818MB | residentFast 283MB | heapAllocated 93MB
[task 2018-12-02T13:04:26.057Z] 13:04:26     INFO - TEST-OK | toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js | took 45020ms
[task 2018-12-02T13:04:26.058Z] 13:04:26     INFO - checking window state
Flags: needinfo?(dtownsend)
Gijs, can I ask you to take a look here? Looks like maybe it just isn't catching the initial load?
Flags: needinfo?(dtownsend) → needinfo?(gijskruitbosch+bugs)
Assignee: nobody → gijskruitbosch+bugs
Status: REOPENED → ASSIGNED
Flags: needinfo?(gijskruitbosch+bugs)
Keywords: leave-open
Flags: needinfo?(dolske)
Pushed by gijskruitbosch@gmail.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/7d32febb06fd
add logging to toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js and simplify slightly, rs=test-only,firebot
(In reply to Pulsebot from comment #48)
> Pushed by gijskruitbosch@gmail.com:
> https://hg.mozilla.org/integration/mozilla-inbound/rev/7d32febb06fd
> add logging to
> toolkit/content/tests/browser/browser_keyevents_during_autoscrolling.js and
> simplify slightly, rs=test-only,firebot

Hm, I was kind of hoping (one of) the TV job(s) would go orange so I'd get my logging info quickly, but the linux64 opt one is green... will have to wait a bit longer for this to reoccur, if switching to loading the magical long page in a new tab instead of the "main" test tab (and focusing there) isn't going to fix this outright.
Seems to be fixed!
Status: ASSIGNED → RESOLVED
Closed: 6 years ago5 years ago
Keywords: leave-open
Resolution: --- → FIXED
Target Milestone: --- → mozilla65
You need to log in before you can comment on or make changes to this bug.