1521781 - Intermittent TEST-UNEXPECTED-FAIL | damp | webconsole/typing.js: Test timed out

Status: RESOLVED FIXED

(DevTools :: Console, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: cbrindusan [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=223283587&repo=autoland

https://queue.taskcluster.net/v1/task/bb-FsilyQhqxceZvF3Rqtw/runs/0/artifacts/public/logs/live_backing.log

06:11:04 INFO - PID 20692 | Garbage collect
06:11:04 INFO - PID 20692 | debugger/simple.js took 2129ms.
06:11:04 INFO - PID 20692 | Loading test 'styleeditor/simple.js'
06:11:04 INFO - PID 20692 | Executing test 'styleeditor/simple.js'
06:11:05 INFO - PID 20692 | 'simple.styleeditor.open.settle.DAMP' took 11.37519799999427ms.
06:11:05 INFO - PID 20692 | Garbage collect
06:11:05 INFO - PID 20692 | Reload page on 'simple.styleeditor'
06:11:05 INFO - PID 20692 | Wait for pending paints on 'simple.styleeditor'
06:11:05 INFO - PID 20692 | 'simple.styleeditor.reload.settle.DAMP' took 22.853593999985605ms.
06:11:05 INFO - PID 20692 | Close toolbox on 'simple.styleeditor'
06:11:05 INFO - PID 20692 | Garbage collect
06:11:06 INFO - PID 20692 | styleeditor/simple.js took 1552ms.
06:11:06 INFO - PID 20692 | Loading test 'netmonitor/simple.js'
06:11:06 INFO - PID 20692 | Executing test 'netmonitor/simple.js'
06:11:06 INFO - PID 20692 | 'simple.netmonitor.open.settle.DAMP' took 44.061029000004055ms.
06:11:06 INFO - PID 20692 | Garbage collect
06:11:07 INFO - PID 20692 | Reload page on 'simple.netmonitor'
06:11:07 INFO - PID 20692 | Wait for pending paints on 'simple.netmonitor'
06:11:07 INFO - PID 20692 | 'simple.netmonitor.reload.settle.DAMP' took 0.22771799998008646ms.
06:11:07 INFO - PID 20692 | Close toolbox on 'simple.netmonitor'
06:11:07 INFO - PID 20692 | console.error: "Task cancelled"
06:11:07 INFO - PID 20692 | Garbage collect
06:11:08 INFO - PID 20692 | netmonitor/simple.js took 1818ms.
06:11:08 INFO - PID 20692 | Loading test 'webconsole/complicated.js'
06:11:08 INFO - PID 20692 | Executing test 'webconsole/complicated.js'
06:11:09 INFO - PID 20692 | 'complicated.webconsole.open.settle.DAMP' took 36.383050999982515ms.
06:11:09 INFO - PID 20692 | Garbage collect
06:11:09 INFO - PID 20692 | Reload page on 'complicated.webconsole'
06:11:11 INFO - PID 20692 | Wait for pending paints on 'complicated.webconsole'
06:11:11 INFO - PID 20692 | 'complicated.webconsole.reload.settle.DAMP' took 67.90635699999984ms.
06:11:11 INFO - PID 20692 | Close toolbox on 'complicated.webconsole'
06:11:11 INFO - PID 20692 | Garbage collect
06:11:12 INFO - PID 20692 | webconsole/complicated.js took 4108ms.
06:11:12 INFO - PID 20692 | Loading test 'inspector/complicated.js'
06:11:12 INFO - PID 20692 | Executing test 'inspector/complicated.js'
06:11:13 INFO - PID 20692 | 'complicated.inspector.open.settle.DAMP' took 53.24760800000513ms.
06:11:13 INFO - PID 20692 | Garbage collect
06:11:14 INFO - PID 20692 | Reload page on 'complicated.inspector'
06:11:14 INFO - PID 20692 | console.error: "Error while calling actor 'pagestyle's method 'getUsedFontFaces'" "node is null"
06:11:14 INFO - PID 20692 | console.error: "getUsedFontFaces@resource://devtools/server/actors/styles.js:302:11\nhandler@resource://devtools/shared/protocol.js:1206:21\nonPacket@resource://devtools/server/main.js:1275:15\nreceiveMessage@resource://devtools/shared/transport/child-transport.js:66:5\nMessageListener.receiveMessage*_addListener@resource://devtools/shared/transport/child-transport.js:40:5\nready@resource://devtools/shared/transport/child-transport.js:57:5\n_onConnection@resource://devtools/server/main.js:864:5\nconnectToParent@resource://devtools/server/main.js:285:12\nonConnect</<@resource://devtools/server/startup/frame.js:50:22\nonConnect<@resource://devtools/server/startup/frame.js:49:7\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14\nMessageListener.receiveMessage*@resource://devtools/server/startup/frame.js:72:5\n@resource://devtools/server/startup/frame.js:19:4\n"
06:11:14 INFO - PID 20692 | console.error: "Could not get the list of font families" "Protocol error (unknownError): node is null"
06:11:15 INFO - PID 20692 | Wait for pending paints on 'complicated.inspector'
06:11:15 INFO - PID 20692 | 'complicated.inspector.reload.settle.DAMP' took 35.18308899999829ms.
06:11:15 INFO - PID 20692 | Close toolbox on 'complicated.inspector'
06:11:15 INFO - PID 20692 | Garbage collect
06:11:16 INFO - PID 20692 | inspector/complicated.js took 4118ms.
06:11:16 INFO - PID 20692 | Loading test 'debugger/complicated.js'
06:11:16 INFO - PID 20692 | Executing test 'debugger/complicated.js'
06:11:17 INFO - PID 20692 | Waiting for state change: has source http://127.0.0.1:38305/tests/tp5n/bild.de/www.bild.de/index.html
06:11:17 INFO - PID 20692 | Finished waiting for state change: has source http://127.0.0.1:38305/tests/tp5n/bild.de/www.bild.de/index.html
06:11:17 INFO - PID 20692 | Selecting source: ga.js
06:11:17 INFO - PID 20692 | Waiting for state change: selected source
06:11:18 INFO - PID 20692 | Finished waiting for state change: selected source
06:11:18 INFO - PID 20692 | Waiting until: text is visible
06:11:18 INFO - PID 20692 | Finished Waiting until: text is visible
06:11:18 INFO - PID 20692 | Waiting until: has file metadata
06:11:18 INFO - PID 20692 | Finished Waiting until: has file metadata
06:11:18 INFO - PID 20692 | 'complicated.jsdebugger.open.settle.DAMP' took 0.36503000001539476ms.
06:11:18 INFO - PID 20692 | Garbage collect
06:11:19 INFO - PID 20692 | Reload page on 'complicated.jsdebugger'
06:11:19 INFO - PID 20692 | Waiting for state change: count sources
06:11:20 INFO - PID 20692 | Finished waiting for state change: count sources
06:11:20 INFO - PID 20692 | Waiting until: text is visible
06:11:20 INFO - PID 20692 | Finished Waiting until: text is visible
06:11:20 INFO - PID 20692 | Waiting until: has file metadata
06:11:22 INFO - PID 20692 | Finished Waiting until: has file metadata
06:11:22 INFO - PID 20692 | Wait for pending paints on 'complicated.jsdebugger'
06:11:22 INFO - PID 20692 | 'complicated.jsdebugger.reload.settle.DAMP' took 0.16252699997858144ms.
06:11:22 INFO - PID 20692 | Close toolbox on 'complicated.jsdebugger'
06:11:22 INFO - PID 20692 | Garbage collect
06:11:22 INFO - PID 20692 | debugger/complicated.js took 6593ms.
06:11:22 INFO - PID 20692 | Loading test 'styleeditor/complicated.js'
06:11:22 INFO - PID 20692 | Executing test 'styleeditor/complicated.js'
06:11:24 INFO - PID 20692 | 'complicated.styleeditor.open.settle.DAMP' took 0.12406500001088716ms.
06:11:24 INFO - PID 20692 | Garbage collect
06:11:25 INFO - PID 20692 | Reload page on 'complicated.styleeditor'
06:11:26 INFO - PID 20692 | Wait for pending paints on 'complicated.styleeditor'
06:11:26 INFO - PID 20692 | 'complicated.styleeditor.reload.settle.DAMP' took 0.1711879999784287ms.
06:11:26 INFO - PID 20692 | Close toolbox on 'complicated.styleeditor'
06:11:26 INFO - PID 20692 | Garbage collect
06:11:27 INFO - PID 20692 | styleeditor/complicated.js took 4504ms.
06:11:27 INFO - PID 20692 | Loading test 'netmonitor/complicated.js'
06:11:27 INFO - PID 20692 | Executing test 'netmonitor/complicated.js'
06:11:28 INFO - PID 20692 | 'complicated.netmonitor.open.settle.DAMP' took 31.530388000013772ms.
06:11:28 INFO - PID 20692 | Garbage collect
06:11:28 INFO - PID 20692 | Reload page on 'complicated.netmonitor'
06:11:31 INFO - PID 20692 | Wait for pending paints on 'complicated.netmonitor'
06:11:31 INFO - PID 20692 | 'complicated.netmonitor.reload.settle.DAMP' took 0.12124899998889305ms.
06:11:32 INFO - PID 20692 | Close toolbox on 'complicated.netmonitor'
06:11:33 INFO - PID 20692 | Garbage collect
06:11:34 INFO - PID 20692 | netmonitor/complicated.js took 6768ms.
06:11:34 INFO - PID 20692 | Loading test 'webconsole/custom.js'
06:11:34 INFO - PID 20692 | Executing test 'webconsole/custom.js'
06:11:35 INFO - PID 20692 | 'custom.webconsole.open.settle.DAMP' took 110.51276200002758ms.
06:11:35 INFO - PID 20692 | Garbage collect
06:11:36 INFO - PID 20692 | Reload page on 'custom.webconsole'
06:11:42 INFO - PID 20692 | Wait for pending paints on 'custom.webconsole'
06:11:42 INFO - PID 20692 | 'custom.webconsole.reload.settle.DAMP' took 33.60248999999021ms.
06:11:42 INFO - PID 20692 | Close toolbox on 'custom.webconsole'
06:11:43 INFO - PID 20692 | Garbage collect
06:11:44 INFO - PID 20692 | webconsole/custom.js took 10031ms.
06:11:44 INFO - PID 20692 | Loading test 'inspector/custom.js'
06:11:44 INFO - PID 20692 | Executing test 'inspector/custom.js'
06:11:45 INFO - PID 20692 | 'custom.inspector.open.settle.DAMP' took 71.22997600003146ms.
06:11:45 INFO - PID 20692 | Garbage collect
06:11:46 INFO - PID 20692 | Reload page on 'custom.inspector'
06:11:47 INFO - PID 20692 | Wait for pending paints on 'custom.inspector'
06:11:47 INFO - PID 20692 | 'custom.inspector.reload.settle.DAMP' took 204.36876300000586ms.
06:11:47 INFO - PID 20692 | Selecting .many-css-rules test node front
06:11:48 INFO - PID 20692 | Move the selection to a node with no rules
06:11:48 INFO - PID 20692 | Select expand-many-children node
06:11:48 INFO - PID 20692 | Expand all children of expand-many-children
06:11:49 INFO - PID 20692 | Collapse all children of expand-many-children
06:11:49 INFO - PID 20692 | Select expand-balanced node
06:11:49 INFO - PID 20692 | Expand all children of expand-balanced
06:11:50 INFO - PID 20692 | Collapse all children of expand-balanced
06:11:50 INFO - PID 20692 | Close toolbox on 'custom.inspector'
06:11:50 INFO - PID 20692 | Garbage collect
06:11:51 INFO - PID 20692 | inspector/custom.js took 7724ms.
06:11:51 INFO - PID 20692 | Loading test 'debugger/custom.js'
06:11:51 INFO - PID 20692 | Executing test 'debugger/custom.js'
06:11:52 INFO - PID 20692 | Waiting for state change: has source http://127.0.0.1:38305/tests/devtools/addon/content/pages/custom/debugger/static/js/App.js
06:11:52 INFO - PID 20692 | Finished waiting for state change: has source http://127.0.0.1:38305/tests/devtools/addon/content/pages/custom/debugger/static/js/App.js
06:11:52 INFO - PID 20692 | Selecting source: App.js
06:11:53 INFO - PID 20692 | Waiting for state change: selected source
06:11:53 INFO - PID 20692 | Finished waiting for state change: selected source
06:11:53 INFO - PID 20692 | Waiting until: text is visible
06:11:53 INFO - PID 20692 | Finished Waiting until: text is visible
06:11:53 INFO - PID 20692 | Waiting until: has file metadata
06:11:53 INFO - PID 20692 | Finished Waiting until: has file metadata
06:11:53 INFO - PID 20692 | 'custom.jsdebugger.open.settle.DAMP' took 0.1394929999660235ms.
06:11:53 INFO - PID 20692 | Garbage collect
06:11:53 INFO - PID 20692 | Reload page on 'custom.jsdebugger'
06:11:53 INFO - PID 20692 | Waiting for state change: count sources
06:11:54 INFO - PID 20692 | Finished waiting for state change: count sources
06:11:54 INFO - PID 20692 | Waiting until: text is visible
06:11:54 INFO - PID 20692 | Finished Waiting until: text is visible
06:11:54 INFO - PID 20692 | Waiting until: has file metadata
06:11:54 INFO - PID 20692 | Finished Waiting until: has file metadata
06:11:54 INFO - PID 20692 | Wait for pending paints on 'custom.jsdebugger'
06:11:54 INFO - PID 20692 | 'custom.jsdebugger.reload.settle.DAMP' took 29.00566599998274ms.
06:11:54 INFO - PID 20692 | Waiting for debugger panel
06:11:54 INFO - PID 20692 | Creating context
06:11:54 INFO - PID 20692 | Pausing debugger
06:11:54 INFO - PID 20692 | add breakpoint
06:11:54 INFO - PID 20692 | Run function in content process: window.hitBreakpoint()
06:11:55 INFO - PID 20692 | remove all breakpoints
06:11:55 INFO - PID 20692 | Garbage collect
06:11:55 INFO - PID 20692 | add breakpoint
06:11:55 INFO - PID 20692 | Run function in content process: window.hitBreakpoint()
06:11:56 INFO - PID 20692 | remove all breakpoints
06:11:56 INFO - PID 20692 | Garbage collect
06:11:57 INFO - PID 20692 | add breakpoint
06:11:58 INFO - PID 20692 | Run function in content process: window.hitBreakpoint()
06:11:58 INFO - PID 20692 | remove all breakpoints
06:11:58 INFO - PID 20692 | Garbage collect
06:11:59 INFO - PID 20692 | add breakpoint
06:11:59 INFO - PID 20692 | Run function in content process: window.hitBreakpoint()
06:12:00 INFO - PID 20692 | remove all breakpoints
06:12:00 INFO - PID 20692 | Garbage collect
06:12:01 INFO - PID 20692 | Close toolbox on 'custom.jsdebugger'
06:12:01 INFO - PID 20692 | Garbage collect
06:12:01 INFO - PID 20692 | debugger/custom.js took 9972ms.
06:12:01 INFO - PID 20692 | Loading test 'webconsole/bulklog.js'
06:12:01 INFO - PID 20692 | Executing test 'webconsole/bulklog.js'
06:12:02 INFO - PID 20692 | Garbage collect
06:12:03 INFO - PID 20692 | webconsole/bulklog.js took 1292ms.
06:12:03 INFO - PID 20692 | Loading test 'webconsole/autocomplete.js'
06:12:03 INFO - PID 20692 | Executing test 'webconsole/autocomplete.js'
06:12:04 INFO - PID 20692 | Garbage collect
06:12:05 INFO - PID 20692 | webconsole/autocomplete.js took 2479ms.
06:12:05 INFO - PID 20692 | Loading test 'webconsole/streamlog.js'
06:12:05 INFO - PID 20692 | Executing test 'webconsole/streamlog.js'
06:12:07 INFO - PID 20692 | Garbage collect
06:12:08 INFO - PID 20692 | webconsole/streamlog.js took 3019ms.
06:12:08 INFO - PID 20692 | Loading test 'webconsole/objectexpand.js'
06:12:08 INFO - PID 20692 | Executing test 'webconsole/objectexpand.js'
06:12:09 INFO - PID 20692 | Close toolbox on 'console.objectexpanded'
06:12:09 INFO - PID 20692 | Garbage collect
06:12:10 INFO - PID 20692 | webconsole/objectexpand.js took 2010ms.
06:12:10 INFO - PID 20692 | Loading test 'webconsole/openwithcache.js'
06:12:10 INFO - PID 20692 | Executing test 'webconsole/openwithcache.js'
06:12:11 INFO - PID 20692 | 'console.openwithcache.open.settle.DAMP' took 252.9859830000205ms.
06:12:11 INFO - PID 20692 | Garbage collect
06:12:12 INFO - PID 20692 | Garbage collect
06:12:13 INFO - PID 20692 | webconsole/openwithcache.js took 2938ms.
06:12:13 INFO - PID 20692 | Loading test 'webconsole/typing.js'
06:12:13 INFO - PID 20692 | Executing test 'webconsole/typing.js'
06:12:16 INFO - PID 20692 | Garbage collect
06:12:17 INFO - PID 20692 | webconsole/typing.js took 3992ms.
06:12:17 INFO - PID 20692 | Loading test 'inspector/mutations.js'
06:12:17 INFO - PID 20692 | Executing test 'inspector/mutations.js'
06:12:19 INFO - PID 20692 | Garbage collect
06:12:20 INFO - PID 20692 | inspector/mutations.js took 3053ms.
06:12:20 INFO - PID 20692 | Loading test 'inspector/layout.js'
06:12:20 INFO - PID 20692 | Executing test 'inspector/layout.js'
06:12:21 INFO - PID 20692 | Garbage collect
06:12:22 INFO - PID 20692 | inspector/layout.js took 2175ms.
06:12:22 INFO - PID 20692 | Loading test 'toolbox/panels-in-background.js'
06:12:22 INFO - PID 20692 | Executing test 'toolbox/panels-in-background.js'
06:12:23 INFO - PID 20692 | Reload page on 'panelsInBackground'
06:12:24 INFO - PID 20692 | Wait for pending paints on 'panelsInBackground'
06:12:24 INFO - PID 20692 | 'panelsInBackground.reload.settle.DAMP' took 0.18438099999912083ms.
06:12:26 INFO - PID 20692 | Garbage collect
06:12:28 INFO - PID 20692 | toolbox/panels-in-background.js took 5340ms.
06:12:28 INFO - PID 20692 | Loading test 'server/protocol.js'
06:12:28 INFO - PID 20692 | Executing test 'server/protocol.js'
06:12:30 INFO - PID 20692 | Garbage collect
06:12:30 INFO - PID 20692 | server/protocol.js took 2560ms.
06:12:30 INFO - PID 20692 | DAMP_RESULTS_JSON=[{"name":"simple.webconsole.open.DAMP","value":326.0444149999821},{"name":"simple.webconsole.reload.DAMP","value":89.71983499996713},{"name":"simple.webconsole.close.DAMP","value":22.62800799999968},{"name":"simple.inspector.open.DAMP","value":358.00976499999524},{"name":"simple.inspector.reload.DAMP","value":75.7792490000138},{"name":"simple.inspector.close.DAMP","value":30.453471999964677},{"name":"simple.jsdebugger.open.DAMP","value":677.3747679999797},{"name":"simple.jsdebugger.reload.DAMP","value":192.69840599998133},{"name":"simple.jsdebugger.close.DAMP","value":24.66293799999403},{"name":"simple.styleeditor.open.DAMP","value":219.97668299998622},{"name":"simple.styleeditor.reload.DAMP","value":33.65917100000661},{"name":"simple.styleeditor.close.DAMP","value":20.13995100001921},{"name":"simple.netmonitor.open.DAMP","value":242.10852099998738},{"name":"simple.netmonitor.reload.DAMP","value":39.96637500001816},{"name":"simple.netmonitor.requestsFinished.DAMP","value":162.70666299998993},{"name":"simple.netmonitor.exportHar","value":11.496552000025986},{"name":"simple.netmonitor.close.DAMP","value":19.006423999991966},{"name":"complicated.webconsole.open.DAMP","value":315.46650799998315},{"name":"complicated.webconsole.reload.DAMP","value":1483.3800089999859},{"name":"complicated.webconsole.close.DAMP","value":52.791196999984095},{"name":"complicated.inspector.open.DAMP","value":426.69336000000476},{"name":"complicated.inspector.reload.DAMP","value":1294.5785580000083},{"name":"complicated.inspector.close.DAMP","value":51.49772600000142},{"name":"complicated.jsdebugger.open.DAMP","value":1705.2070690000255},{"name":"complicated.jsdebugger.reload.DAMP","value":2416.487961000006},{"name":"complicated.jsdebugger.close.DAMP","value":64.73659300000872},{"name":"complicated.styleeditor.open.DAMP","value":843.8913849999954},{"name":"complicated.styleeditor.reload.DAMP","value":1220.6409039999708},{"name":"complicated.styleeditor.close.DAMP","value":36.11030000000028},{"name":"complicated.netmonitor.open.DAMP","value":258.4419249999919},{"name":"complicated.netmonitor.reload.DAMP","value":2384.32905499998},{"name":"complicated.netmonitor.requestsFinished.DAMP","value":2671.298335999978},{"name":"complicated.netmonitor.exportHar","value":1055.8636140000017},{"name":"complicated.netmonitor.close.DAMP","value":62.60824000000139},{"name":"custom.webconsole.open.DAMP","value":593.5078849999991},{"name":"custom.webconsole.reload.DAMP","value":6251.9572750000225},{"name":"custom.webconsole.close.DAMP","value":122.65998900000704},{"name":"custom.inspector.open.DAMP","value":1071.7326480000047},{"name":"custom.inspector.reload.DAMP","value":906.9712880000006},{"name":"custom.inspector.manyrules.selectnode","value":964.190448999987},{"name":"custom.inspector.manyrules.deselectnode","value":202.492372000037},{"name":"custom.inspector.expandall.manychildren","value":224.1853420000116},{"name":"custom.inspector.collapseall.manychildren","value":2.059406999993371},{"name":"custom.inspector.expandall.balanced","value":924.804649000027},{"name":"custom.inspector.collapseall.balanced","value":19.5691859999788},{"name":"custom.inspector.close.DAMP","value":88.4319259999902},{"name":"custom.jsdebugger.open.DAMP","value":993.931099999987},{"name":"custom.jsdebugger.reload.DAMP","value":940.9076269999787},{"name":"custom.jsdebugger.pause.DAMP","value":367.7741790000291},{"name":"custom.jsdebugger.stepIn.DAMP","value":435.6403070000233},{"name":"custom.jsdebugger.stepOver.DAMP","value":334.3920870000147},{"name":"custom.jsdebugger.stepOut.DAMP","value":642.6472560000257},{"name":"custom.jsdebugger.close.DAMP","value":42.32454200001666},{"name":"console.bulklog","value":114.65528200002154},{"name":"console.autocomplete","value":1157.5521090000111},{"name":"console.streamlog","value":16},{"name":"console.objectexpand","value":431.5958030000329},{"name":"console.objectexpanded.close.DAMP","value":18.0298269999912},{"name":"console.openwithcache.open.DAMP","value":338.96134199999506},{"name":"console.typing","value":374.7019909999799},{"name":"inspector.mutations","value":1053.7070839999942},{"name":"inspector.layout.open","value":372.5835360000492},{"name":"panelsInBackground.reload.DAMP","value":528.2059899999877},{"name":"server.protocoljs.DAMP","value":1595.3838450000621}]
06:12:30 INFO - PID 20692 |
06:12:30 INFO - PID 20692 | simple.webconsole.open.DAMP: 326.0
06:12:30 INFO - PID 20692 | simple.webconsole.reload.DAMP: 89.7
06:12:30 INFO - PID 20692 | simple.webconsole.close.DAMP: 22.6
06:12:30 INFO - PID 20692 | simple.inspector.open.DAMP: 358.0
06:12:30 INFO - PID 20692 | simple.inspector.reload.DAMP: 75.8
06:12:30 INFO - PID 20692 | simple.inspector.close.DAMP: 30.5
06:12:30 INFO - PID 20692 | simple.jsdebugger.open.DAMP: 677.4
06:12:30 INFO - PID 20692 | simple.jsdebugger.reload.DAMP: 192.7
06:12:30 INFO - PID 20692 | simple.jsdebugger.close.DAMP: 24.7
06:12:30 INFO - PID 20692 | simple.styleeditor.open.DAMP: 220.0
06:12:30 INFO - PID 20692 | simple.styleeditor.reload.DAMP: 33.7
06:12:30 INFO - PID 20692 | simple.styleeditor.close.DAMP: 20.1
06:12:30 INFO - PID 20692 | simple.netmonitor.open.DAMP: 242.1
06:12:30 INFO - PID 20692 | simple.netmonitor.reload.DAMP: 40.0
06:12:30 INFO - PID 20692 | simple.netmonitor.requestsFinished.DAMP: 162.7
06:12:30 INFO - PID 20692 | simple.netmonitor.exportHar: 11.5
06:12:30 INFO - PID 20692 | simple.netmonitor.close.DAMP: 19.0
06:12:30 INFO - PID 20692 | complicated.webconsole.open.DAMP: 315.5
06:12:30 INFO - PID 20692 | complicated.webconsole.reload.DAMP: 1483.4
06:12:30 INFO - PID 20692 | complicated.webconsole.close.DAMP: 52.8
06:12:30 INFO - PID 20692 | complicated.inspector.open.DAMP: 426.7
06:12:30 INFO - PID 20692 | complicated.inspector.reload.DAMP: 1294.6
06:12:30 INFO - PID 20692 | complicated.inspector.close.DAMP: 51.5
06:12:30 INFO - PID 20692 | complicated.jsdebugger.open.DAMP: 1705.2
06:12:30 INFO - PID 20692 | complicated.jsdebugger.reload.DAMP: 2416.5
06:12:30 INFO - PID 20692 | complicated.jsdebugger.close.DAMP: 64.7
06:12:30 INFO - PID 20692 | complicated.styleeditor.open.DAMP: 843.9
06:12:30 INFO - PID 20692 | complicated.styleeditor.reload.DAMP: 1220.6
06:12:30 INFO - PID 20692 | complicated.styleeditor.close.DAMP: 36.1
06:12:30 INFO - PID 20692 | complicated.netmonitor.open.DAMP: 258.4
06:12:30 INFO - PID 20692 | complicated.netmonitor.reload.DAMP: 2384.3
06:12:30 INFO - PID 20692 | complicated.netmonitor.requestsFinished.DAMP: 2671.3
06:12:30 INFO - PID 20692 | complicated.netmonitor.exportHar: 1055.9
06:12:30 INFO - PID 20692 | complicated.netmonitor.close.DAMP: 62.6
06:12:30 INFO - PID 20692 | custom.webconsole.open.DAMP: 593.5
06:12:30 INFO - PID 20692 | custom.webconsole.reload.DAMP: 6252.0
06:12:30 INFO - PID 20692 | custom.webconsole.close.DAMP: 122.7
06:12:30 INFO - PID 20692 | custom.inspector.open.DAMP: 1071.7
06:12:30 INFO - PID 20692 | custom.inspector.reload.DAMP: 907.0
06:12:30 INFO - PID 20692 | custom.inspector.manyrules.selectnode: 964.2
06:12:30 INFO - PID 20692 | custom.inspector.manyrules.deselectnode: 202.5
06:12:30 INFO - PID 20692 | custom.inspector.expandall.manychildren: 224.2
06:12:30 INFO - PID 20692 | custom.inspector.collapseall.manychildren: 2.1
06:12:30 INFO - PID 20692 | custom.inspector.expandall.balanced: 924.8
06:12:30 INFO - PID 20692 | custom.inspector.collapseall.balanced: 19.6
06:12:30 INFO - PID 20692 | custom.inspector.close.DAMP: 88.4
06:12:30 INFO - PID 20692 | custom.jsdebugger.open.DAMP: 993.9
06:12:30 INFO - PID 20692 | custom.jsdebugger.reload.DAMP: 940.9
06:12:30 INFO - PID 20692 | custom.jsdebugger.pause.DAMP: 367.8
06:12:30 INFO - PID 20692 | custom.jsdebugger.stepIn.DAMP: 435.6
06:12:30 INFO - PID 20692 | custom.jsdebugger.stepOver.DAMP: 334.4
06:12:30 INFO - PID 20692 | custom.jsdebugger.stepOut.DAMP: 642.6
06:12:30 INFO - PID 20692 | custom.jsdebugger.close.DAMP: 42.3
06:12:30 INFO - PID 20692 | console.bulklog: 114.7
06:12:30 INFO - PID 20692 | console.autocomplete: 1157.6
06:12:30 INFO - PID 20692 | console.streamlog: 16.0
06:12:30 INFO - PID 20692 | console.objectexpand: 431.6
06:12:30 INFO - PID 20692 | console.objectexpanded.close.DAMP: 18.0
06:12:30 INFO - PID 20692 | console.openwithcache.open.DAMP: 339.0
06:12:30 INFO - PID 20692 | console.typing: 374.7
06:12:30 INFO - PID 20692 | inspector.mutations: 1053.7
06:12:30 INFO - PID 20692 | inspector.layout.open: 372.6
06:12:30 INFO - PID 20692 | panelsInBackground.reload.DAMP: 528.2
06:12:30 INFO - PID 20692 | server.protocoljs.DAMP: 1595.4
06:12:30 INFO - PID 20692 | Cycle 1(3): loaded ext+damp:damp.html (next: ext+damp:damp.html)
06:12:31 INFO - PID 20692 | Initialize the head file with a reference to this DAMP instance
06:12:32 INFO - PID 20692 | Garbage collect
06:12:33 INFO - PID 20692 | Loading test 'webconsole/simple.js'
06:12:33 INFO - PID 20692 | Executing test 'webconsole/simple.js'
06:12:33 INFO - PID 20692 | 'simple.webconsole.open.settle.DAMP' took 41.7043770000455ms.
06:12:33 INFO - PID 20692 | Garbage collect
06:12:34 INFO - PID 20692 | Reload page on 'simple.webconsole'
06:12:34 INFO - PID 20692 | Wait for pending paints on 'simple.webconsole'
06:12:34 INFO - PID 20692 | 'simple.webconsole.reload.settle.DAMP' took 16.905045000079554ms.
06:12:34 INFO - PID 20692 | Close toolbox on 'simple.webconsole'
06:12:34 INFO - PID 20692 | Garbage collect
06:12:34 INFO - PID 20692 | webconsole/simple.js took 1393ms.
06:12:34 INFO - PID 20692 | Loading test 'inspector/simple.js'
06:12:34 INFO - PID 20692 | Executing test 'inspector/simple.js'
06:12:35 INFO - PID 20692 | 'simple.inspector.open.settle.DAMP' took 12.575466000067536ms.
06:12:35 INFO - PID 20692 | Garbage collect
06:12:35 INFO - PID 20692 | Reload page on 'simple.inspector'
06:12:35 INFO - PID 20692 | console.error: "Error while calling actor 'pagestyle's method 'getLayout'" "node is null"
06:12:35 INFO - PID 20692 | console.error: "getLayout@resource://devtools/server/actors/styles.js:814:5\nhandler@resource://devtools/shared/protocol.js:1206:21\nonPacket@resource://devtools/server/main.js:1275:15\nreceiveMessage@resource://devtools/shared/transport/child-transport.js:66:5\nMessageListener.receiveMessage*_addListener@resource://devtools/shared/transport/child-transport.js:40:5\nready@resource://devtools/shared/transport/child-transport.js:57:5\n_onConnection@resource://devtools/server/main.js:864:5\nconnectToParent@resource://devtools/server/main.js:285:12\nonConnect</<@resource://devtools/server/startup/frame.js:50:22\nonConnect<@resource://devtools/server/startup/frame.js:49:7\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14\nMessageListener.receiveMessage*@resource://devtools/server/startup/frame.js:72:5\n@resource://devtools/server/startup/frame.js:19:4\n"
06:12:35 INFO - PID 20692 | console.error: "Protocol error (unknownError): node is null"
06:12:35 INFO - PID 20692 | Wait for pending paints on 'simple.inspector'
06:12:35 INFO - PID 20692 | 'simple.inspector.reload.settle.DAMP' took 34.21001300000353ms.
06:12:35 INFO - PID 20692 | Close toolbox on 'simple.inspector'
06:12:35 INFO - PID 20692 | Garbage collect
06:12:36 INFO - PID 20692 | inspector/simple.js took 1520ms.
06:12:36 INFO - PID 20692 | Loading test 'debugger/simple.js'
06:12:36 INFO - PID 20692 | Executing test 'debugger/simple.js'
06:12:36 INFO - PID 20692 | Waiting for state change: has source http://127.0.0.1:38305/tests/devtools/addon/content/pages/simple.html
06:12:36 INFO - PID 20692 | Finished waiting for state change: has source http://127.0.0.1:38305/tests/devtools/addon/content/pages/simple.html
06:12:36 INFO - PID 20692 | Selecting source: simple.html
06:12:36 INFO - PID 20692 | Waiting for state change: selected source
06:12:36 INFO - PID 20692 | Finished waiting for state change: selected source
06:12:36 INFO - PID 20692 | Waiting until: text is visible
06:12:36 INFO - PID 20692 | Finished Waiting until: text is visible
06:12:36 INFO - PID 20692 | Waiting until: has file metadata
06:12:37 INFO - PID 20692 | Finished Waiting until: has file metadata
06:12:37 INFO - PID 20692 | 'simple.jsdebugger.open.settle.DAMP' took 0.26607299997704104ms.
06:12:37 INFO - PID 20692 | Garbage collect
06:12:37 INFO - PID 20692 | Reload page on 'simple.jsdebugger'
06:12:37 INFO - PID 20692 | Waiting for state change: count sources
06:12:37 INFO - PID 20692 | Finished waiting for state change: count sources
06:12:37 INFO - PID 20692 | Waiting until: text is visible
06:12:37 INFO - PID 20692 | Finished Waiting until: text is visible
06:12:37 INFO - PID 20692 | Waiting until: has file metadata
06:12:37 INFO - PID 20692 | Finished Waiting until: has file metadata
06:12:37 INFO - PID 20692 | Wait for pending paints on 'simple.jsdebugger'
06:12:37 INFO - PID 20692 | 'simple.jsdebugger.reload.settle.DAMP' took 0.141334000043571ms.
06:12:37 INFO - PID 20692 | Close toolbox on 'simple.jsdebugger'
06:12:37 INFO - PID 20692 | Garbage collect
06:12:38 INFO - PID 20692 | debugger/simple.js took 2076ms.
06:12:38 INFO - PID 20692 | Loading test 'styleeditor/simple.js'
06:12:38 INFO - PID 20692 | Executing test 'styleeditor/simple.js'
06:12:38 INFO - PID 20692 | 'simple.styleeditor.open.settle.DAMP' took 0.19154500000877306ms.
06:12:38 INFO - PID 20692 | Garbage collect
06:12:39 INFO - PID 20692 | Reload page on 'simple.styleeditor'
06:12:39 INFO - PID 20692 | Wait for pending paints on 'simple.styleeditor'
06:12:39 INFO - PID 20692 | 'simple.styleeditor.reload.settle.DAMP' took 21.35440200002631ms.
06:12:39 INFO - PID 20692 | Close toolbox on 'simple.styleeditor'
06:12:39 INFO - PID 20692 | Garbage collect
06:12:39 INFO - PID 20692 | styleeditor/simple.js took 1560ms.
06:12:39 INFO - PID 20692 | Loading test 'netmonitor/simple.js'
06:12:39 INFO - PID 20692 | Executing test 'netmonitor/simple.js'
06:12:40 INFO - PID 20692 | 'simple.netmonitor.open.settle.DAMP' took 0.10421600000699982ms.
06:12:40 INFO - PID 20692 | Garbage collect
06:12:40 INFO - PID 20692 | Reload page on 'simple.netmonitor'
06:12:40 INFO - PID 20692 | Wait for pending paints on 'simple.netmonitor'
06:12:40 INFO - PID 20692 | 'simple.netmonitor.reload.settle.DAMP' took 20.043761000037193ms.
06:12:40 INFO - PID 20692 | Close toolbox on 'simple.netmonitor'
06:12:40 INFO - PID 20692 | console.error: "Task cancelled"
06:12:41 INFO - PID 20692 | Garbage collect
06:12:41 INFO - PID 20692 | netmonitor/simple.js took 1764ms.
06:12:41 INFO - PID 20692 | Loading test 'webconsole/complicated.js'
06:12:41 INFO - PID 20692 | Executing test 'webconsole/complicated.js'
06:12:42 INFO - PID 20692 | 'complicated.webconsole.open.settle.DAMP' took 52.235097000026144ms.
06:12:42 INFO - PID 20692 | Garbage collect
06:12:43 INFO - PID 20692 | Reload page on 'complicated.webconsole'
06:12:44 INFO - PID 20692 | Wait for pending paints on 'complicated.webconsole'
06:12:44 INFO - PID 20692 | 'complicated.webconsole.reload.settle.DAMP' took 29.94796000001952ms.
06:12:44 INFO - PID 20692 | Close toolbox on 'complicated.webconsole'
06:12:44 INFO - PID 20692 | Garbage collect
06:12:45 INFO - PID 20692 | webconsole/complicated.js took 3997ms.
06:12:45 INFO - PID 20692 | Loading test 'inspector/complicated.js'
06:12:45 INFO - PID 20692 | Executing test 'inspector/complicated.js'
06:12:46 INFO - PID 20692 | 'complicated.inspector.open.settle.DAMP' took 23.441642999998294ms.
06:12:46 INFO - PID 20692 | Garbage collect
06:12:47 INFO - PID 20692 | Reload page on 'complicated.inspector'
06:12:47 INFO - PID 20692 | console.error: "Error while calling actor 'pagestyle's method 'getUsedFontFaces'" "node is null"
06:12:47 INFO - PID 20692 | console.error: "getUsedFontFaces@resource://devtools/server/actors/styles.js:302:11\nhandler@resource://devtools/shared/protocol.js:1206:21\nonPacket@resource://devtools/server/main.js:1275:15\nreceiveMessage@resource://devtools/shared/transport/child-transport.js:66:5\nMessageListener.receiveMessage*_addListener@resource://devtools/shared/transport/child-transport.js:40:5\nready@resource://devtools/shared/transport/child-transport.js:57:5\n_onConnection@resource://devtools/server/main.js:864:5\nconnectToParent@resource://devtools/server/main.js:285:12\nonConnect</<@resource://devtools/server/startup/frame.js:50:22\nonConnect<@resource://devtools/server/startup/frame.js:49:7\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14\nMessageListener.receiveMessage*@resource://devtools/server/startup/frame.js:72:5\n@resource://devtools/server/startup/frame.js:19:4\n"
06:12:47 INFO - PID 20692 | console.error: "Could not get the list of font families" "Protocol error (unknownError): node is null"
06:12:48 INFO - PID 20692 | Wait for pending paints on 'complicated.inspector'
06:12:48 INFO - PID 20692 | 'complicated.inspector.reload.settle.DAMP' took 30.64307699992787ms.
06:12:48 INFO - PID 20692 | Close toolbox on 'complicated.inspector'
06:12:48 INFO - PID 20692 | Garbage collect
06:12:49 INFO - PID 20692 | inspector/complicated.js took 4105ms.
06:12:49 INFO - PID 20692 | Loading test 'debugger/complicated.js'
06:12:49 INFO - PID 20692 | Executing test 'debugger/complicated.js'
06:12:50 INFO - PID 20692 | Waiting for state change: has source http://127.0.0.1:38305/tests/tp5n/bild.de/www.bild.de/index.html
06:12:50 INFO - PID 20692 | Finished waiting for state change: has source http://127.0.0.1:38305/tests/tp5n/bild.de/www.bild.de/index.html
06:12:50 INFO - PID 20692 | Selecting source: ga.js
06:12:50 INFO - PID 20692 | Waiting for state change: selected source
06:12:51 INFO - PID 20692 | Finished waiting for state change: selected source
06:12:51 INFO - PID 20692 | Waiting until: text is visible
06:12:51 INFO - PID 20692 | Finished Waiting until: text is visible
06:12:51 INFO - PID 20692 | Waiting until: has file metadata
06:12:52 INFO - PID 20692 | Finished Waiting until: has file metadata
06:12:52 INFO - PID 20692 | 'complicated.jsdebugger.open.settle.DAMP' took 0.18100600002799183ms.
06:12:52 INFO - PID 20692 | Garbage collect
06:12:52 INFO - PID 20692 | Reload page on 'complicated.jsdebugger'
06:12:52 INFO - PID 20692 | Waiting for state change: count sources
06:12:53 INFO - PID 20692 | Finished waiting for state change: count sources
06:12:53 INFO - PID 20692 | Waiting until: text is visible
06:12:54 INFO - PID 20692 | Finished Waiting until: text is visible
06:12:54 INFO - PID 20692 | Waiting until: has file metadata
06:12:55 INFO - PID 20692 | Finished Waiting until: has file metadata
06:12:55 INFO - PID 20692 | Wait for pending paints on 'complicated.jsdebugger'
06:12:55 INFO - PID 20692 | 'complicated.jsdebugger.reload.settle.DAMP' took 0.15815799997653812ms.
06:12:55 INFO - PID 20692 | Close toolbox on 'complicated.jsdebugger'
06:12:55 INFO - PID 20692 | Garbage collect
06:12:55 INFO - PID 20692 | debugger/complicated.js took 6257ms.
06:12:55 INFO - PID 20692 | Loading test 'styleeditor/complicated.js'
06:12:55 INFO - PID 20692 | Executing test 'styleeditor/complicated.js'
06:12:57 INFO - PID 20692 | 'complicated.styleeditor.open.settle.DAMP' took 0.10753500001737848ms.
06:12:57 INFO - PID 20692 | Garbage collect
06:12:58 INFO - PID 20692 | Reload page on 'complicated.styleeditor'
06:12:59 INFO - PID 20692 | Wait for pending paints on 'complicated.styleeditor'
06:12:59 INFO - PID 20692 | 'complicated.styleeditor.reload.settle.DAMP' took 0.12372299999697134ms.
06:12:59 INFO - PID 20692 | Close toolbox on 'complicated.styleeditor'
06:12:59 INFO - PID 20692 | Garbage collect
06:12:59 INFO - PID 20692 | styleeditor/complicated.js took 3904ms.
06:12:59 INFO - PID 20692 | Loading test 'netmonitor/complicated.js'
06:12:59 INFO - PID 20692 | Executing test 'netmonitor/complicated.js'
06:13:00 INFO - PID 20692 | 'complicated.netmonitor.open.settle.DAMP' took 26.429159999941476ms.
06:13:00 INFO - PID 20692 | Garbage collect
06:13:01 INFO - PID 20692 | Reload page on 'complicated.netmonitor'
06:13:03 INFO - PID 20692 | Wait for pending paints on 'complicated.netmonitor'
06:13:03 INFO - PID 20692 | 'complicated.netmonitor.reload.settle.DAMP' took 0.1793560000951402ms.
06:13:04 INFO - PID 20692 | Close toolbox on 'complicated.netmonitor'
06:13:05 INFO - PID 20692 | Garbage collect
06:13:06 INFO - PID 20692 | netmonitor/complicated.js took 6272ms.
06:13:06 INFO - PID 20692 | Loading test 'webconsole/custom.js'
06:13:06 INFO - PID 20692 | Executing test 'webconsole/custom.js'
06:13:06 INFO - PID 20692 | 'custom.webconsole.open.settle.DAMP' took 113.32127199997194ms.
06:13:06 INFO - PID 20692 | Garbage collect
06:13:08 INFO - PID 20692 | Reload page on 'custom.webconsole'
06:13:15 INFO - PID 20692 | Wait for pending paints on 'custom.webconsole'
06:13:15 INFO - PID 20692 | 'custom.webconsole.reload.settle.DAMP' took 27.571866999962367ms.
06:13:15 INFO - PID 20692 | Close toolbox on 'custom.webconsole'
06:13:15 INFO - PID 20692 | Garbage collect
06:13:16 INFO - PID 20692 | webconsole/custom.js took 10052ms.
06:13:16 INFO - PID 20692 | Loading test 'inspector/custom.js'
06:13:16 INFO - PID 20692 | Executing test 'inspector/custom.js'
06:13:17 INFO - PID 20692 | 'custom.inspector.open.settle.DAMP' took 97.13591399992583ms.
06:13:17 INFO - PID 20692 | Garbage collect
06:13:18 INFO - PID 20692 | Reload page on 'custom.inspector'
06:13:19 INFO - PID 20692 | Wait for pending paints on 'custom.inspector'
06:13:19 INFO - PID 20692 | 'custom.inspector.reload.settle.DAMP' took 82.35349100001622ms.
06:13:19 INFO - PID 20692 | Selecting .many-css-rules test node front
06:13:20 INFO - PID 20692 | Move the selection to a node with no rules
06:13:20 INFO - PID 20692 | Select expand-many-children node
06:13:21 INFO - PID 20692 | Expand all children of expand-many-children
06:13:21 INFO - PID 20692 | Collapse all children of expand-many-children
06:13:21 INFO - PID 20692 | Select expand-balanced node
06:13:21 INFO - PID 20692 | Expand all children of expand-balanced
06:13:22 INFO - PID 20692 | Collapse all children of expand-balanced
06:13:22 INFO - PID 20692 | Close toolbox on 'custom.inspector'
06:13:22 INFO - PID 20692 | Garbage collect
06:13:23 INFO - PID 20692 | inspector/custom.js took 7783ms.
06:13:23 INFO - PID 20692 | Loading test 'debugger/custom.js'
06:13:23 INFO - PID 20692 | Executing test 'debugger/custom.js'
06:13:24 INFO - PID 20692 | Waiting for state change: has source http://127.0.0.1:38305/tests/devtools/addon/content/pages/custom/debugger/static/js/App.js
06:13:24 INFO - PID 20692 | Finished waiting for state change: has source http://127.0.0.1:38305/tests/devtools/addon/content/pages/custom/debugger/static/js/App.js
06:13:24 INFO - PID 20692 | Selecting source: App.js
06:13:25 INFO - PID 20692 | Waiting for state change: selected source
06:13:25 INFO - PID 20692 | Finished waiting for state change: selected source
06:13:25 INFO - PID 20692 | Waiting until: text is visible
06:13:25 INFO - PID 20692 | Finished Waiting until: text is visible
06:13:25 INFO - PID 20692 | Waiting until: has file metadata
06:13:25 INFO - PID 20692 | Finished Waiting until: has file metadata
06:13:25 INFO - PID 20692 | 'custom.jsdebugger.open.settle.DAMP' took 0.2212950000539422ms.
06:13:25 INFO - PID 20692 | Garbage collect
06:13:25 INFO - PID 20692 | Reload page on 'custom.jsdebugger'
06:13:25 INFO - PID 20692 | Waiting for state change: count sources
06:13:26 INFO - PID 20692 | Finished waiting for state change: count sources
06:13:26 INFO - PID 20692 | Waiting until: text is visible
06:13:26 INFO - PID 20692 | Finished Waiting until: text is visible
06:13:26 INFO - PID 20692 | Waiting until: has file metadata
06:13:26 INFO - PID 20692 | Finished Waiting until: has file metadata
06:13:26 INFO - PID 20692 | Wait for pending paints on 'custom.jsdebugger'
06:13:26 INFO - PID 20692 | 'custom.jsdebugger.reload.settle.DAMP' took 0.2320649999892339ms.
06:13:26 INFO - PID 20692 | Waiting for debugger panel
06:13:26 INFO - PID 20692 | Creating context
06:13:26 INFO - PID 20692 | Pausing debugger
06:13:26 INFO - PID 20692 | add breakpoint
06:13:26 INFO - PID 20692 | Run function in content process: window.hitBreakpoint()
06:13:27 INFO - PID 20692 | remove all breakpoints
06:13:27 INFO - PID 20692 | Garbage collect
06:13:27 INFO - PID 20692 | add breakpoint
06:13:27 INFO - PID 20692 | Run function in content process: window.hitBreakpoint()
06:13:28 INFO - PID 20692 | remove all breakpoints
06:13:28 INFO - PID 20692 | Garbage collect
06:13:29 INFO - PID 20692 | add breakpoint
06:13:30 INFO - PID 20692 | Run function in content process: window.hitBreakpoint()
06:13:30 INFO - PID 20692 | remove all breakpoints
06:13:31 INFO - PID 20692 | Garbage collect
06:13:31 INFO - PID 20692 | add breakpoint
06:13:31 INFO - PID 20692 | Run function in content process: window.hitBreakpoint()
06:13:32 INFO - PID 20692 | remove all breakpoints
06:13:32 INFO - PID 20692 | Garbage collect
06:13:33 INFO - PID 20692 | Close toolbox on 'custom.jsdebugger'
06:13:33 INFO - PID 20692 | Garbage collect
06:13:33 INFO - PID 20692 | debugger/custom.js took 9989ms.
06:13:33 INFO - PID 20692 | Loading test 'webconsole/bulklog.js'
06:13:33 INFO - PID 20692 | Executing test 'webconsole/bulklog.js'
06:13:34 INFO - PID 20692 | Garbage collect
06:13:35 INFO - PID 20692 | webconsole/bulklog.js took 1294ms.
06:13:35 INFO - PID 20692 | Loading test 'webconsole/autocomplete.js'
06:13:35 INFO - PID 20692 | Executing test 'webconsole/autocomplete.js'
06:13:36 INFO - PID 20692 | Garbage collect
06:13:37 INFO - PID 20692 | webconsole/autocomplete.js took 2435ms.
06:13:37 INFO - PID 20692 | Loading test 'webconsole/streamlog.js'
06:13:37 INFO - PID 20692 | Executing test 'webconsole/streamlog.js'
06:13:39 INFO - PID 20692 | Garbage collect
06:13:40 INFO - PID 20692 | webconsole/streamlog.js took 3060ms.
06:13:40 INFO - PID 20692 | Loading test 'webconsole/objectexpand.js'
06:13:40 INFO - PID 20692 | Executing test 'webconsole/objectexpand.js'
06:13:41 INFO - PID 20692 | Close toolbox on 'console.objectexpanded'
06:13:41 INFO - PID 20692 | Garbage collect
06:13:42 INFO - PID 20692 | webconsole/objectexpand.js took 1974ms.
06:13:42 INFO - PID 20692 | Loading test 'webconsole/openwithcache.js'
06:13:42 INFO - PID 20692 | Executing test 'webconsole/openwithcache.js'
06:13:43 INFO - PID 20692 | 'console.openwithcache.open.settle.DAMP' took 253.99655600002734ms.
06:13:43 INFO - PID 20692 | Garbage collect
06:13:44 INFO - PID 20692 | Garbage collect
06:13:45 INFO - PID 20692 | webconsole/openwithcache.js took 2980ms.
06:13:45 INFO - PID 20692 | Loading test 'webconsole/typing.js'
06:13:45 INFO - PID 20692 | Executing test 'webconsole/typing.js'
06:18:45 INFO - PID 20692 | TEST-UNEXPECTED-FAIL | damp | webconsole/typing.js: Test timed out

Comment 1

[Intermittent Failures Robot]

1 failures in 2383 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2019-01-21&endday=2019-01-27&tree=all

Comment 2

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Comment 3

[Cosmin Sabou [:CosminS]]

Recent failure: https://treeherder.mozilla.org/logviewer.html#?job_id=290636434&repo=autoland

Comment 4

[Cosmin Sabou [:CosminS]]

Actually the above failure was a fixed by commit from https://hg.mozilla.org/integration/autoland/rev/9df351217706b66757eaafba30028a5aa04d961f

Comment 5

[Narcis Beleuzu [:NarcisB]]

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=291016019&repo=autoland&lineNumber=2279

Comment 6

[Intermittent Failures Robot]

5 failures in 5233 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5

Platform breakdown:

• linux64-shippable: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-02-24&endday=2020-03-01&tree=all

Comment 7

[Intermittent Failures Robot]

13 failures in 5483 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 11
• try: 1

Platform breakdown:

• linux64-shippable: 12
• linux64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-03-02&endday=2020-03-08&tree=all

Comment 8

[Florin Strugariu [:Bebe]]

:ochameau can you take a look over these intermittent failures ?

Comment 9

[Intermittent Failures Robot]

16 failures in 989 pushes (0.016 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 4
• mozilla-central: 2
• mozilla-beta: 10

Platform breakdown:

• linux64-shippable: 16

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-03-12&endday=2020-03-12&tree=all

Comment 10

[Intermittent Failures Robot]

45 failures in 5336 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

This is the #37 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 27
• mozilla-beta: 11
• mozilla-central: 6
• try: 1

Platform breakdown:

• linux64-shippable: 43
• macosx1014-64-shippable: 1
• linux64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-03-09&endday=2020-03-15&tree=all

Comment 11

[Henrik Skupin [:whimboo][⌚️UTC+2]]

There are no more failures since March 13th, so I assume it was temporarily and got fixed by a backout, or follow-up patch. Lets observe for the next week.

Comment 12

[Intermittent Failures Robot]

12 failures in 4955 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4
• mozilla-beta: 3
• mozilla-central: 5

Platform breakdown:

• linux64-shippable: 8
• linux64-shippable-qr: 2
• linux64-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-03-16&endday=2020-03-22&tree=all

Comment 13

[Henrik Skupin [:whimboo][⌚️UTC+2]]

Recent failures are about accessing dead objects:

[task 2020-03-22T21:51:42.304Z] 21:51:42    ERROR -  PID 3899 | Handler function threw an exception: TypeError: can't access dead object
[task 2020-03-22T21:51:42.304Z] 21:51:42     INFO -  PID 3899 | Stack: destroy@resource://devtools/server/actors/stylesheets.js:117:7
[task 2020-03-22T21:51:42.304Z] 21:51:42     INFO -  PID 3899 | destroy@resource://devtools/shared/protocol/Pool.js:166:17
[task 2020-03-22T21:51:42.304Z] 21:51:42     INFO -  PID 3899 | destroy@resource://devtools/shared/protocol/Actor.js:76:11
[task 2020-03-22T21:51:42.304Z] 21:51:42     INFO -  PID 3899 | destroy@resource://devtools/server/actors/stylesheets.js:326:38
[task 2020-03-22T21:51:42.304Z] 21:51:42     INFO -  PID 3899 | destroy@resource://devtools/shared/protocol/Pool.js:166:17
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | _detach@resource://devtools/server/actors/targets/browsing-context.js:962:35
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | exit@resource://devtools/server/actors/targets/browsing-context.js:558:10
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | frameTargetPrototype.exit@resource://devtools/server/actors/targets/frame.js:82:45
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | destroy@resource://devtools/server/actors/targets/browsing-context.js:539:10
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | removeActor@resource://devtools/server/actors/common.js:63:13
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | APDestroy@resource://devtools/server/actors/common.js:28:12
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | onClosed/<@resource://devtools/server/devtools-server-connection.js:477:37
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | onClosed@resource://devtools/server/devtools-server-connection.js:477:22
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | close@resource://devtools/shared/transport/child-transport.js:62:16
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | close@resource://devtools/server/devtools-server-connection.js:86:23
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | onDisconnect<@resource://devtools/server/startup/frame.js:145:12
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | MessageListener.receiveMessage*@resource://devtools/server/startup/frame.js:148:23
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | @resource://devtools/server/startup/frame.js:177:5
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | Line: 117, column: 7
[task 2020-03-22T21:51:42.305Z] 21:51:42     INFO -  PID 3899 | console.error: (new TypeError("can't access dead object", "resource://devtools/server/actors/stylesheets.js", 117))

Alexandre, can you please have a look? Thanks.

Comment 14

[Intermittent Failures Robot]

12 failures in 4773 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 6
• try: 1
• mozilla-beta: 4
• autoland: 1

Platform breakdown:

• linux64-shippable: 10
• linux64-qr: 1
• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-03-23&endday=2020-03-29&tree=all

Comment 15

[Intermittent Failures Robot]

9 failures in 5015 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 6
• autoland: 3

Platform breakdown:

• linux64-shippable: 7
• linux64-shippable-qr: 1
• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-03-30&endday=2020-04-05&tree=all

Comment 16

[Intermittent Failures Robot]

1 failures in 4823 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-04-06&endday=2020-04-12&tree=all

Comment 17

[Intermittent Failures Robot]

13 failures in 4528 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 8
• mozilla-central: 3
• mozilla-beta: 1
• try: 1

Platform breakdown:

• linux64-shippable: 11
• linux64-shippable-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-04-13&endday=2020-04-19&tree=all

Comment 18

[Intermittent Failures Robot]

17 failures in 5582 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4
• mozilla-central: 10
• mozilla-beta: 3

Platform breakdown:

• linux64-shippable: 16
• linux64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-04-20&endday=2020-04-26&tree=all

Comment 19

[Intermittent Failures Robot]

7 failures in 5423 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• mozilla-beta: 1
• autoland: 3
• try: 2

Platform breakdown:

• linux64-shippable: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-04-27&endday=2020-05-03&tree=all

Comment 20

[Intermittent Failures Robot]

19 failures in 5276 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 9
• mozilla-beta: 2
• autoland: 7
• try: 1

Platform breakdown:

• linux64-shippable: 11
• linux64-shippable-qr: 8

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-05-04&endday=2020-05-10&tree=all

Comment 21

[Intermittent Failures Robot]

17 failures in 5488 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 5
• autoland: 10
• mozilla-beta: 2

Platform breakdown:

• linux64-shippable: 12
• linux64-shippable-qr: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-05-11&endday=2020-05-17&tree=all

Comment 22

[Intermittent Failures Robot]

22 failures in 4727 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 18
• try: 1
• mozilla-central: 2
• mozilla-beta: 1

Platform breakdown:

• linux64-shippable: 16
• linux64-shippable-qr: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-05-18&endday=2020-05-24&tree=all

Comment 23

[Alexandre Poirot [:ochameau]]

Bug 1480201 may help debug this one failure.
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=304164042&repo=try&lineNumber=22488

| Loading test 'webconsole/typing.js'
| Executing test 'webconsole/typing.js'
| TEST-UNEXPECTED-FAIL | damp | webconsole/typing.js: Test timed out
| List of pending promises #37
|  - ID:1337 AllocationSite:file:///tmp/tmppRYCmr/profile/extensions/[e-mail]/content/tests/webconsole/typing.js:18:1fun:module.exports Dependents(1):1338 LifeTime:300012ms
|  - ID:1338 AllocationSite:file:///tmp/tmppRYCmr/profile/extensions/[e-mail]/content/damp.js:334:15fun:_runNextTest Dependents(0): LifeTime:299999ms
|  - ID:1341 AllocationSite:resource://devtools/shared/event-emitter.js:138:12fun:once Dependents(0): LifeTime:299753ms
|  - ID:1342 AllocationSite:resource://devtools/shared/event-emitter.js:138:12fun:once Dependents(0): LifeTime:299727ms
|  - ID:1343 AllocationSite:resource://devtools/shared/event-emitter.js:138:12fun:once Dependents(0): LifeTime:299723ms
|  - ID:1344 AllocationSite:resource://devtools/shared/event-emitter.js:138:12fun:once Dependents(0): LifeTime:299723ms
|  - ID:1346 AllocationSite:resource://devtools/shared/event-emitter.js:138:12fun:once Dependents(1):1347 LifeTime:299663ms
|  - ID:1349 AllocationSite:resource://devtools/client/webconsole/webconsole-connection-proxy.js:95:31fun:connect Dependents(1):1350 LifeTime:299566ms
|  - ID:1351 AllocationSite:resource://devtools/client/webconsole/webconsole-wrapper.js:317:37fun:setTimeoutIfNeeded Dependents(0): LifeTime:299561ms
|  - ID:1352 AllocationSite:resource://devtools/client/webconsole/webconsole-wrapper.js:317:37fun:setTimeoutIfNeeded Dependents(0): LifeTime:299510ms
|  - ID:1353 AllocationSite:resource://devtools/client/webconsole/webconsole-wrapper.js:317:37fun:setTimeoutIfNeeded Dependents(0): LifeTime:299460ms
|  - ID:1354 AllocationSite:resource://devtools/shared/event-emitter.js:138:12fun:once Dependents(1):1355 LifeTime:298245ms
|  - ID:1356 AllocationSite:resource://devtools/shared/event-emitter.js:138:12fun:once Dependents(0): LifeTime:298221ms
|  - ID:1357 AllocationSite:resource://devtools/client/webconsole/components/Input/JSTerm.js:969:1fun:updateAutocompletionPopup Dependents(0): LifeTime:297549ms
|  - ID:1358 AllocationSite:resource://devtools/client/shared/autocomplete-popup.js:153:1fun:openPopup Dependents(1):1359 LifeTime:297548ms
|  - ID:1360 AllocationSite:resource://devtools/client/shared/widgets/tooltip/HTMLTooltip.js:492:1fun:show Dependents(0): LifeTime:297548ms
|  - ID:1361 AllocationSite:resource://devtools/client/webconsole/components/Input/JSTerm.js:1037:19fun:updateAutocompletionPopup Dependents(0): LifeTime:297545ms
|  - ID:1359 AllocationSite:null Dependents(0): LifeTime:297545ms
|  - ID:1362 AllocationSite:resource://devtools/client/shared/widgets/tooltip/HTMLTooltip.js:526:13fun:show Dependents(0): LifeTime:297517ms
|  - ID:1363 AllocationSite:resource://devtools/shared/event-emitter.js:138:12fun:once Dependents(0): LifeTime:297492ms

(I removed all promises with stack not coming from DevTools)
It is a bit unfortunate as it doesn't highlight on what promise within webconsole/typing.js we were stuck on.
We only see that the module.exports on line 18 was still pending.
May be there is an issue with my script?
In any case, may be the other pending promises may help understanding what was stuck here?

Comment 24

[Intermittent Failures Robot]

20 failures in 5458 pushes (0.004 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 17
• mozilla-central: 2
• try: 1

Platform breakdown:

• linux64-shippable: 10
• linux64-shippable-qr: 10

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-05-25&endday=2020-05-31&tree=all

Comment 25

[Intermittent Failures Robot]

6 failures in 4949 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 3
• mozilla-beta: 1
• autoland: 2

Platform breakdown:

• linux64-shippable-qr: 3
• linux64-shippable: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-06-01&endday=2020-06-07&tree=all

Comment 26

[Intermittent Failures Robot]

1 failures in 5211 pushes (0.0 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• linux64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-06-08&endday=2020-06-14&tree=all

Comment 27

[Intermittent Failures Robot]

3 failures in 4035 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 3

Platform breakdown:

• linux64-shippable: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-06-15&endday=2020-06-21&tree=all

Comment 28

[Intermittent Failures Robot]

5 failures in 5252 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5

Platform breakdown:

• linux64-shippable: 4
• linux64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-06-22&endday=2020-06-28&tree=all

Comment 29

[Intermittent Failures Robot]

9 failures in 4516 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7
• try: 1
• mozilla-beta: 1

Platform breakdown:

• linux64-shippable: 8
• linux64-shippable-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-06-29&endday=2020-07-05&tree=all

Comment 30

[Atila Butkovits]

In the last 7 days there were 32 failures on linux64-shippable opt build type.

Recent log:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=309402593&repo=autoland&lineNumber=19586

Alexandre, could you take a look?

Comment 31

[Intermittent Failures Robot]

44 failures in 5265 pushes (0.008 failures/push) were associated with this bug in the last 7 days.

This is the #39 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 31
• mozilla-central: 10
• try: 3

Platform breakdown:

• linux64-shippable: 44

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-07-06&endday=2020-07-12&tree=all

Comment 32

[Alexandre Poirot [:ochameau]]

I'm on PTO, forwarding to Nicolas.

Comment 33

[Nicolas Chevobbe [:nchevobbe]]

I'm looking into this at the moment, hopefully I can get to the bottom of it

Comment 34

[Nicolas Chevobbe [:nchevobbe]]

Attachment: Bug 1521781 - Fix damp webconsole/typing.js intermittent. r=bomsy.

Wait for tick between each "key stroke", wait for the popup to be open if it
wasn't.

Depends on D83912

Comment 35

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/dd59ff70b764
Fix damp webconsole/typing.js intermittent. r=bomsy,perftest-reviewers,sparky.

Comment 36

[Atila Butkovits]

https://hg.mozilla.org/mozilla-central/rev/dd59ff70b764

Comment 37

[Intermittent Failures Robot]

36 failures in 4014 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

This is the #36 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 22
• mozilla-central: 7
• mozilla-beta: 3
• try: 4

Platform breakdown:

• linux64-shippable: 36

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1521781&startday=2020-07-13&endday=2020-07-19&tree=all