Closed Bug 1439646 Opened 7 years ago Closed 7 years ago

Intermittent devtools/client/animationinspector/test/browser_animation_animated_properties_progress_indicator.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout

Categories

(DevTools :: Inspector: Animations, defect, P5)

Product:
DevTools
Component:
Inspector: Animations
Type:
defect

Tracking

(Not tracked)

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=163145260&repo=autoland https://queue.taskcluster.net/v1/task/P7lJ3AtyT0SuWo9lAHOZ_Q/runs/0/artifacts/public/logs/live_backing.log [task 2018-02-20T12:01:04.951Z] 12:01:04 INFO - Buffered messages logged at 12:00:54 [task 2018-02-20T12:01:04.957Z] 12:01:04 INFO - Tab removed and finished closing [task 2018-02-20T12:01:04.961Z] 12:01:04 INFO - Buffered messages finished [task 2018-02-20T12:01:04.965Z] 12:01:04 INFO - TEST-UNEXPECTED-FAIL | devtools/client/animationinspector/test/browser_animation_animated_properties_progress_indicator.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. - [task 2018-02-20T12:01:04.969Z] 12:01:04 INFO - GECKO(1069) | MEMORY STAT | vsize 20974085MB | residentFast 1206MB [task 2018-02-20T12:01:04.974Z] 12:01:04 INFO - TEST-OK | devtools/client/animationinspector/test/browser_animation_animated_properties_progress_indicator.js | took 107846ms [task 2018-02-20T12:01:04.978Z] 12:01:04 INFO - checking window state [task 2018-02-20T12:01:04.982Z] 12:01:04 INFO - TEST-START | devtools/client/animationinspector/test/browser_animation_click_selects_animation.js [task 2018-02-20T12:03:35.520Z] 12:03:35 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-02-20T12:03:35.534Z] 12:03:35 INFO - Buffered messages logged at 12:00:58 [task 2018-02-20T12:03:35.551Z] 12:03:35 INFO - Entering test bound [task 2018-02-20T12:03:35.567Z] 12:03:35 INFO - Buffered messages logged at 12:00:59 [task 2018-02-20T12:03:35.590Z] 12:03:35 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/animationinspector/test/doc_simple_animation.html [task 2018-02-20T12:03:35.604Z] 12:03:35 INFO - Buffered messages logged at 12:01:05 [task 2018-02-20T12:03:35.621Z] 12:03:35 INFO - Tab added and finished loading [task 2018-02-20T12:03:35.646Z] 12:03:35 INFO - Loading the helper frame script chrome://mochitests/content/browser/devtools/client/animationinspector/test/doc_frame_script.js [task 2018-02-20T12:03:35.663Z] 12:03:35 INFO - Loading the helper frame script chrome://devtools/content/shared/frame-script-utils.js [task 2018-02-20T12:03:35.675Z] 12:03:35 INFO - Opening the inspector [task 2018-02-20T12:03:35.696Z] 12:03:35 INFO - Opening the toolbox [task 2018-02-20T12:03:35.730Z] 12:03:35 INFO - Buffered messages logged at 12:01:29 [task 2018-02-20T12:03:35.759Z] 12:03:35 INFO - Console message: [JavaScript Warning: "Unknown property ‘user-select’. Declaration dropped." {file: "resource://devtools/client/shared/components/reps/reps.css" line: 270}] [task 2018-02-20T12:03:35.781Z] 12:03:35 INFO - Console message: [JavaScript Warning: "Unknown property ‘user-select’. Declaration dropped." {file: "resource://devtools/client/shared/components/reps/reps.css" line: 270}] [task 2018-02-20T12:03:35.823Z] 12:03:35 INFO - Buffered messages logged at 12:02:18 [task 2018-02-20T12:03:35.853Z] 12:03:35 INFO - Toolbox opened and focused [task 2018-02-20T12:03:35.871Z] 12:03:35 INFO - Buffered messages logged at 12:02:30 [task 2018-02-20T12:03:35.905Z] 12:03:35 INFO - Selecting the animationinspector sidebar [task 2018-02-20T12:03:35.935Z] 12:03:35 INFO - Buffered messages logged at 12:02:33 [task 2018-02-20T12:03:35.949Z] 12:03:35 INFO - Waiting for the inspector and sidebar to be ready [task 2018-02-20T12:03:35.959Z] 12:03:35 INFO - Buffered messages logged at 12:02:45 [task 2018-02-20T12:03:35.974Z] 12:03:35 INFO - Waiting for the animation controller and panel to be ready [task 2018-02-20T12:03:35.997Z] 12:03:35 INFO - Buffered messages logged at 12:03:07 [task 2018-02-20T12:03:36.005Z] 12:03:36 INFO - TEST-PASS | devtools/client/animationinspector/test/browser_animation_click_selects_animation.js | There are no animations selected by default - [task 2018-02-20T12:03:36.025Z] 12:03:36 INFO - Click on the first animation, expect the right event and right class [task 2018-02-20T12:03:36.033Z] 12:03:36 INFO - Click on animation 0 in the timeline [task 2018-02-20T12:03:36.050Z] 12:03:36 INFO - Buffered messages logged at 12:03:19 [task 2018-02-20T12:03:36.063Z] 12:03:36 INFO - TEST-PASS | devtools/client/animationinspector/test/browser_animation_click_selects_animation.js | The selected event was emitted with the right animation - [task 2018-02-20T12:03:36.074Z] 12:03:36 INFO - Buffered messages logged at 12:03:20 [task 2018-02-20T12:03:36.086Z] 12:03:36 INFO - TEST-PASS | devtools/client/animationinspector/test/browser_animation_click_selects_animation.js | The time block has the right selected class - [task 2018-02-20T12:03:36.098Z] 12:03:36 INFO - Click on the second animation, expect it to be selected too [task 2018-02-20T12:03:36.099Z] 12:03:36 INFO - Click on animation 1 in the timeline [task 2018-02-20T12:03:36.103Z] 12:03:36 INFO - Buffered messages logged at 12:03:25 [task 2018-02-20T12:03:36.114Z] 12:03:36 INFO - TEST-PASS | devtools/client/animationinspector/test/browser_animation_click_selects_animation.js | The selected event was emitted with the right animation - [task 2018-02-20T12:03:36.123Z] 12:03:36 INFO - TEST-PASS | devtools/client/animationinspector/test/browser_animation_click_selects_animation.js | The second time block has the right selected class - [task 2018-02-20T12:03:36.131Z] 12:03:36 INFO - TEST-PASS | devtools/client/animationinspector/test/browser_animation_click_selects_animation.js | The first time block has been unselected - [task 2018-02-20T12:03:36.144Z] 12:03:36 INFO - Click again on the first animation and check if it unselects [task 2018-02-20T12:03:36.154Z] 12:03:36 INFO - Click on animation 0 in the timeline [task 2018-02-20T12:03:36.156Z] 12:03:36 INFO - Buffered messages logged at 12:03:28 [task 2018-02-20T12:03:36.158Z] 12:03:36 INFO - TEST-PASS | devtools/client/animationinspector/test/browser_animation_click_selects_animation.js | The time block has the right selected class again - [task 2018-02-20T12:03:36.171Z] 12:03:36 INFO - TEST-PASS | devtools/client/animationinspector/test/browser_animation_click_selects_animation.js | The second time block has been unselected - [task 2018-02-20T12:03:36.179Z] 12:03:36 INFO - Leaving test bound [task 2018-02-20T12:03:36.186Z] 12:03:36 INFO - Buffered messages logged at 12:03:32 [task 2018-02-20T12:03:36.202Z] 12:03:36 INFO - Removing tab. [task 2018-02-20T12:03:36.203Z] 12:03:36 INFO - Waiting for event: 'TabClose' on [object XULElement]. [task 2018-02-20T12:03:36.203Z] 12:03:36 INFO - Buffered messages logged at 12:03:33 [task 2018-02-20T12:03:36.214Z] 12:03:36 INFO - Got event: 'TabClose' on [object XULElement]. [task 2018-02-20T12:03:36.233Z] 12:03:36 INFO - Tab removed and finished closing [task 2018-02-20T12:03:36.252Z] 12:03:36 INFO - Buffered messages finished [task 2018-02-20T12:03:36.263Z] 12:03:36 INFO - TEST-UNEXPECTED-FAIL | devtools/client/animationinspector/test/browser_animation_click_selects_animation.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. - [task 2018-02-20T12:03:36.274Z] 12:03:36 INFO - GECKO(1069) | MEMORY STAT | vsize 20974078MB | residentFast 1230MB [task 2018-02-20T12:03:36.287Z] 12:03:36 INFO - TEST-OK | devtools/client/animationinspector/test/browser_animation_click_selects_animation.js | took 157740ms [task 2018-02-20T12:03:36.859Z] 12:03:36 INFO - checking window state [task 2018-02-20T12:03:37.661Z] 12:03:37 INFO - TEST-START | devtools/client/animationinspector/test/browser_animation_controller_exposes_document_currentTime.js [task 2018-02-20T12:06:39.129Z] 12:06:39 INFO - Not taking screenshot here: see the one that was previously logged [task 2018-02-20T12:06:39.147Z] 12:06:39 INFO - Buffered messages logged at 12:03:38 [task 2018-02-20T12:06:39.170Z] 12:06:39 INFO - Entering test bound [task 2018-02-20T12:06:39.184Z] 12:06:39 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/animationinspector/test/doc_simple_animation.html [task 2018-02-20T12:06:39.190Z] 12:06:39 INFO - Buffered messages logged at 12:03:45 [task 2018-02-20T12:06:39.195Z] 12:06:39 INFO - Tab added and finished loading [task 2018-02-20T12:06:39.202Z] 12:06:39 INFO - Loading the helper frame script chrome://mochitests/content/browser/devtools/client/animationinspector/test/doc_frame_script.js [task 2018-02-20T12:06:39.223Z] 12:06:39 INFO - Loading the helper frame script chrome://devtools/content/shared/frame-script-utils.js [task 2018-02-20T12:06:39.237Z] 12:06:39 INFO - Opening the inspector [task 2018-02-20T12:06:39.252Z] 12:06:39 INFO - Opening the toolbox [task 2018-02-20T12:06:39.264Z] 12:06:39 INFO - Buffered messages logged at 12:04:18 [task 2018-02-20T12:06:39.283Z] 12:06:39 INFO - Console message: [JavaScript Warning: "Unknown property ‘user-select’. Declaration dropped." {file: "resource://devtools/client/shared/components/reps/reps.css" line: 270}] [task 2018-02-20T12:06:39.303Z] 12:06:39 INFO - Buffered messages logged at 12:04:19 [task 2018-02-20T12:06:39.315Z] 12:06:39 INFO - Console message: [JavaScript Warning: "Unknown property ‘user-select’. Declaration dropped." {file: "resource://devtools/client/shared/components/reps/reps.css" line: 270}] [task 2018-02-20T12:06:39.327Z] 12:06:39 INFO - Buffered messages logged at 12:04:52 [task 2018-02-20T12:06:39.340Z] 12:06:39 INFO - Console message: [JavaScript Error: "getScreenshot error: Unix error 2 during operation open on file /tmp/tmpWi9aaA.mozrunner/thumbnails/66838c3f6a463613df1f95e856ab05e3.png (No such file or directory)" {file: "resource://activity-stream/lib/Screenshots.jsm" line: 64}] [task 2018-02-20T12:06:39.354Z] 12:06:39 INFO - getScreenshotForURL@resource://activity-stream/lib/Screenshots.jsm:64:7 [task 2018-02-20T12:06:39.363Z] 12:06:39 INFO - async*maybeCacheScreenshot@resource://activity-stream/lib/Screenshots.jsm:107:32 [task 2018-02-20T12:06:39.373Z] 12:06:39 INFO - async*_fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:185:13 [task 2018-02-20T12:06:39.380Z] 12:06:39 INFO - async*getLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:134:9 [task 2018-02-20T12:06:39.386Z] 12:06:39 INFO - async*refresh@resource://activity-stream/lib/TopSitesFeed.jsm:153:25 [task 2018-02-20T12:06:39.392Z] 12:06:39 INFO - async*onAction@resource://activity-stream/lib/TopSitesFeed.jsm:311:9 [task 2018-02-20T12:06:39.395Z] 12:06:39 INFO - _middleware/</<@resource://activity-stream/lib/Store.jsm:49:11 [task 2018-02-20T12:06:39.399Z] 12:06:39 INFO - Store/this[method]@resource://activity-stream/lib/Store.jsm:28:55 [task 2018-02-20T12:06:39.405Z] 12:06:39 INFO - init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:18:41 [task 2018-02-20T12:06:39.415Z] 12:06:39 INFO - notify@resource://gre/modules/Timer.jsm:42:7 [task 2018-02-20T12:06:39.417Z] 12:06:39 INFO - [task 2018-02-20T12:06:39.427Z] 12:06:39 INFO - Buffered messages logged at 12:05:16 [task 2018-02-20T12:06:39.432Z] 12:06:39 INFO - Toolbox opened and focused [task 2018-02-20T12:06:39.447Z] 12:06:39 INFO - Buffered messages logged at 12:05:19 [task 2018-02-20T12:06:39.460Z] 12:06:39 INFO - Selecting the animationinspector sidebar [task 2018-02-20T12:06:39.475Z] 12:06:39 INFO - Waiting for the inspector and sidebar to be ready [task 2018-02-20T12:06:39.492Z] 12:06:39 INFO - Buffered messages logged at 12:05:25 [task 2018-02-20T12:06:39.512Z] 12:06:39 INFO - Waiting for the animation controller and panel to be ready [task 2018-02-20T12:06:39.534Z] 12:06:39 INFO - Buffered messages logged at 12:05:45 [task 2018-02-20T12:06:39.549Z] 12:06:39 INFO - TEST-PASS | devtools/client/animationinspector/test/browser_animation_controller_exposes_document_currentTime.js | The documentCurrentTime getter exists - [task 2018-02-20T12:06:39.562Z] 12:06:39 INFO - TEST-PASS | devtools/client/animationinspector/test/browser_animation_controller_exposes_document_currentTime.js | The documentCurrentTime is correct - [task 2018-02-20T12:06:39.577Z] 12:06:39 INFO - Add a new animation to the page and check the time again [task 2018-02-20T12:06:39.585Z] 12:06:39 INFO - Sending message devtools:test:setAttribute to content [task 2018-02-20T12:06:39.597Z] 12:06:39 INFO - Expecting message devtools:test:setAttribute from content [task 2018-02-20T12:06:39.606Z] 12:06:39 INFO - Buffered messages logged at 12:06:28 [task 2018-02-20T12:06:39.621Z] 12:06:39 INFO - TEST-PASS | devtools/client/animationinspector/test/browser_animation_controller_exposes_document_currentTime.js | The documentCurrentTime is correct - [task 2018-02-20T12:06:39.643Z] 12:06:39 INFO - TEST-PASS | devtools/client/animationinspector/test/browser_animation_controller_exposes_document_currentTime.js | The new documentCurrentTime is higher than the old one - [task 2018-02-20T12:06:39.665Z] 12:06:39 INFO - Leaving test bound [task 2018-02-20T12:06:39.681Z] 12:06:39 INFO - Buffered messages logged at 12:06:38 [task 2018-02-20T12:06:39.701Z] 12:06:39 INFO - Removing tab. [task 2018-02-20T12:06:39.710Z] 12:06:39 INFO - Waiting for event: 'TabClose' on [object XULElement]. [task 2018-02-20T12:06:39.728Z] 12:06:39 INFO - Got event: 'TabClose' on [object XULElement]. [task 2018-02-20T12:06:39.748Z] 12:06:39 INFO - Tab removed and finished closing [task 2018-02-20T12:06:39.768Z] 12:06:39 INFO - Buffered messages finished [task 2018-02-20T12:06:39.786Z] 12:06:39 INFO - TEST-UNEXPECTED-FAIL | devtools/client/animationinspector/test/browser_animation_controller_exposes_document_currentTime.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. - [task 2018-02-20T12:06:39.792Z] 12:06:39 INFO - GECKO(1069) | MEMORY STAT | vsize 20974102MB | residentFast 1296MB [task 2018-02-20T12:06:39.801Z] 12:06:39 INFO - TEST-OK | devtools/client/animationinspector/test/browser_animation_controller_exposes_document_currentTime.js | took 181496ms [task 2018-02-20T12:06:39.967Z] 12:06:39 INFO - checking window state
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
Product: Firefox → DevTools
You need to log in before you can comment on or make changes to this bug.