Filed by: btara [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=328383303&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/bEOvytD0REmu9ET-BfTUHQ/runs/0/artifacts/public/logs/live_backing.log
...
[task 2021-01-31T21:27:41.708Z] 21:27:41 INFO - PID 9940 | Executing test 'toolbox/panels-in-background.js'
[task 2021-01-31T21:27:41.727Z] 21:27:41 INFO - PID 9940 | Wait for a pageshow event for browsing context 629
[task 2021-01-31T21:27:41.910Z] 21:27:41 INFO - PID 9940 | Received pageshow event for 629
[task 2021-01-31T21:27:42.070Z] 21:27:42 INFO - PID 9940 | Open toolbox on 'webconsole'
[task 2021-01-31T21:27:42.070Z] 21:27:42 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:27:42.092Z] 21:27:42 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:27:42.092Z] 21:27:42 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:27:42.094Z] 21:27:42 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:27:43.271Z] 21:27:43 INFO - PID 9940 | Reload page on 'panelsInBackground'
[task 2021-01-31T21:27:43.273Z] 21:27:43 INFO - PID 9940 | Wait for a pageshow event for browsing context 629
[task 2021-01-31T21:27:43.625Z] 21:27:43 INFO - PID 9940 | console.error: "Task cancelled"
[task 2021-01-31T21:27:43.644Z] 21:27:43 ERROR - PID 9940 | Handler function threw an exception: TypeError: timings.REQUEST_HEADER is undefined
[task 2021-01-31T21:27:43.644Z] 21:27:43 INFO - PID 9940 | Stack: _getBlockedTiming@resource://devtools/server/actors/network-monitor/network-observer.js:1249:7
[task 2021-01-31T21:27:43.644Z] 21:27:43 INFO - PID 9940 | _setupHarTimings@resource://devtools/server/actors/network-monitor/network-observer.js:1442:31
[task 2021-01-31T21:27:43.644Z] 21:27:43 INFO - PID 9940 | _onTransactionClose@resource://devtools/server/actors/network-monitor/network-observer.js:1233:27
[task 2021-01-31T21:27:43.644Z] 21:27:43 INFO - PID 9940 | _dispatchActivity@resource://devtools/server/actors/network-monitor/network-observer.js:634:14
[task 2021-01-31T21:27:43.644Z] 21:27:43 INFO - PID 9940 | NetworkObserver.prototype.observeActivity<@resource://devtools/server/actors/network-monitor/network-observer.js:740:12
[task 2021-01-31T21:27:43.645Z] 21:27:43 INFO - PID 9940 | exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:103:22
[task 2021-01-31T21:27:43.645Z] 21:27:43 INFO - PID 9940 | Line: 1249, column: 7
[task 2021-01-31T21:27:43.645Z] 21:27:43 INFO - PID 9940 | console.error: (new TypeError("timings.REQUEST_HEADER is undefined", "resource://devtools/server/actors/network-monitor/network-observer.js", 1249))
[task 2021-01-31T21:27:44.553Z] 21:27:44 INFO - PID 9940 | Received pageshow event for 629
[task 2021-01-31T21:27:44.553Z] 21:27:44 INFO - PID 9940 | Wait for pending paints on 'panelsInBackground.reload'
[task 2021-01-31T21:27:44.553Z] 21:27:44 INFO - PID 9940 | 'panelsInBackground.reload.settle.DAMP' took 0.08260273712221533ms.
[task 2021-01-31T21:27:44.612Z] 21:27:44 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:27:46.483Z] 21:27:46 INFO - PID 9940 | toolbox/panels-in-background.js took 4768ms.
[task 2021-01-31T21:27:46.483Z] 21:27:46 INFO - PID 9940 | Loading test 'toolbox/screenshot.js'
[task 2021-01-31T21:27:46.483Z] 21:27:46 INFO - PID 9940 | Executing test 'toolbox/screenshot.js'
[task 2021-01-31T21:27:46.488Z] 21:27:46 INFO - PID 9940 | Wait for a pageshow event for browsing context 636
[task 2021-01-31T21:27:46.518Z] 21:27:46 INFO - PID 9940 | [Parent 6104, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2021-01-31T21:27:47.353Z] 21:27:47 INFO - PID 9940 | Received pageshow event for 636
[task 2021-01-31T21:27:47.453Z] 21:27:47 INFO - PID 9940 | Open toolbox on 'webconsole'
[task 2021-01-31T21:27:47.453Z] 21:27:47 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:27:47.504Z] 21:27:47 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:27:47.504Z] 21:27:47 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:27:47.506Z] 21:27:47 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:27:49.457Z] 21:27:49 INFO - PID 9940 | console.warn: "IGNORED REDUX ACTION:" "AUTOCOMPLETE_CLEAR"
[task 2021-01-31T21:27:49.457Z] 21:27:49 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:27:49.943Z] 21:27:49 INFO - PID 9940 | toolbox/screenshot.js took 3466ms.
[task 2021-01-31T21:27:49.943Z] 21:27:49 INFO - PID 9940 | Loading test 'server/protocol.js'
[task 2021-01-31T21:27:49.943Z] 21:27:49 INFO - PID 9940 | Executing test 'server/protocol.js'
[task 2021-01-31T21:27:49.952Z] 21:27:49 INFO - PID 9940 | Wait for a pageshow event for browsing context 641
[task 2021-01-31T21:27:49.982Z] 21:27:49 INFO - PID 9940 | Received pageshow event for 641
[task 2021-01-31T21:27:50.080Z] 21:27:50 INFO - PID 9940 | Open toolbox on 'options'
[task 2021-01-31T21:27:50.080Z] 21:27:50 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:27:50.090Z] 21:27:50 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:27:50.090Z] 21:27:50 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:27:50.092Z] 21:27:50 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:27:52.161Z] 21:27:52 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:27:52.645Z] 21:27:52 INFO - PID 9940 | server/protocol.js took 2693ms.
[task 2021-01-31T21:27:52.645Z] 21:27:52 INFO - PID 9940 | DAMP_RESULTS_JSON=[{"name":"simple.webconsole.open.DAMP","value":275.3743149860529},{"name":"simple.webconsole.reload.DAMP","value":173.9040203980403},{"name":"simple.webconsole.close.DAMP","value":7.734619933587965},{"name":"simple.inspector.open.DAMP","value":230.24318314081756},{"name":"simple.inspector.reload.DAMP","value":128.7612832096056},{"name":"simple.inspector.close.DAMP","value":6.547120253497269},{"name":"simple.jsdebugger.open.DAMP","value":485.0279125571251},{"name":"simple.jsdebugger.reload.DAMP","value":247.72902206215076},{"name":"simple.jsdebugger.close.DAMP","value":22.191122936434112},{"name":"simple.styleeditor.open.DAMP","value":203.09487197425915},{"name":"simple.styleeditor.reload.DAMP","value":24.679786393418908},{"name":"simple.styleeditor.close.DAMP","value":5.258585820673034},{"name":"simple.netmonitor.open.DAMP","value":153.91449934034608},{"name":"simple.netmonitor.reload.DAMP","value":22.635197982075624},{"name":"simple.netmonitor.requestsFinished.DAMP","value":179.94323621829972},{"name":"simple.netmonitor.exportHar","value":3.7642272121156566},{"name":"simple.netmonitor.close.DAMP","value":9.203719853889197},{"name":"simple.accessibility.open.DAMP","value":213.49940351949772},{"name":"simple.accessibility.reload.DAMP","value":72.2053736152011},{"name":"simple.accessibility.close.DAMP","value":7.561222452262882},{"name":"complicated.webconsole.open.DAMP","value":298.2392304975074},{"name":"complicated.webconsole.reload.DAMP","value":1558.2033563335426},{"name":"complicated.webconsole.close.DAMP","value":16.51884076272836},{"name":"complicated.inspector.open.DAMP","value":325.2168748517288},{"name":"complicated.inspector.reload.DAMP","value":704.4416037900955},{"name":"complicated.inspector.close.DAMP","value":21.54975705576362},{"name":"complicated.jsdebugger.open.DAMP","value":944.5182673222735},{"name":"complicated.jsdebugger.reload.DAMP","value":1114.6611325108097},{"name":"complicated.jsdebugger.close.DAMP","value":35.827059905743226},{"name":"complicated.styleeditor.open.DAMP","value":507.1889981345739},{"name":"complicated.styleeditor.reload.DAMP","value":1928.9333153564367},{"name":"complicated.styleeditor.close.DAMP","value":21.763773238461},{"name":"complicated.netmonitor.open.DAMP","value":247.75940075470135},{"name":"complicated.netmonitor.reload.DAMP","value":2598.9346977575915},{"name":"complicated.netmonitor.requestsFinished.DAMP","value":3720.925287189544},{"name":"complicated.netmonitor.exportHar","value":534.3526693143067},{"name":"complicated.netmonitor.responsePanel.html","value":291.4193846778362},{"name":"complicated.netmonitor.responsePanel.editor","value":2772.184040263761},{"name":"complicated.netmonitor.close.DAMP","value":47.36618441919563},{"name":"custom.webconsole.open.DAMP","value":2429.3161210164544},{"name":"custom.webconsole.reload.DAMP","value":9039.2752121815},{"name":"custom.webconsole.close.DAMP","value":194.80899823701475},{"name":"custom.inspector.open.DAMP","value":346.4553356419201},{"name":"custom.inspector.reload.DAMP","value":479.74918805260677},{"name":"custom.inspector.manyrules.selectnode","value":674.6950610731146},{"name":"custom.inspector.manyrules.deselectnode","value":156.37756277550943},{"name":"custom.inspector.manycssvariables.selectnode","value":366.6656995547237},{"name":"custom.inspector.expandall.manychildren","value":157.4486676895758},{"name":"custom.inspector.collapseall.manychildren","value":1.2383583899936639},{"name":"custom.inspector.expandall.balanced","value":707.9798681428656},{"name":"custom.inspector.collapseall.balanced","value":8.716295437887311},{"name":"custom.inspector.close.DAMP","value":44.452901933167595},{"name":"custom.jsdebugger.open.DAMP","value":893.1926128576742},{"name":"custom.jsdebugger.reload.DAMP","value":799.679146392853},{"name":"custom.jsdebugger.pause.DAMP","value":177.78669037797954},{"name":"custom.jsdebugger.stepIn.DAMP","value":618.0616687458823},{"name":"custom.jsdebugger.stepOver.DAMP","value":291.8651663916535},{"name":"custom.jsdebugger.stepOut.DAMP","value":392.0694545658771},{"name":"custom.jsdebugger.project-search.DAMP","value":1670.7164763447945},{"name":"custom.jsdebugger.preview.DAMP","value":544.3998245544499},{"name":"custom.jsdebugger.close.DAMP","value":45.275857302127406},{"name":"console.bulklog","value":1491.357603291748},{"name":"console.autocomplete","value":722.2882323525846},{"name":"console.autocomplete.longInput","value":1039.7981352944626},{"name":"console.streamlog","value":16},{"name":"console.objectexpand","value":396.4180449433625},{"name":"console.objectexpand-many-instances","value":847.1480722330743},{"name":"console.objectexpanded.close.DAMP","value":52.99511722254101},{"name":"console.openwithcache.open.DAMP","value":444.32148848765064},{"name":"console.typing","value":377.74880234571174},{"name":"inspector.mutations","value":849.227476674132},{"name":"inspector.layout.open","value":297.8371394876158},{"name":"panelsInBackground.reload.DAMP","value":1283.9844556667376},{"name":"screenshot.DAMP","value":1608.8033355121734},{"name":"server.protocoljs.DAMP","value":1931.4847842004383}]
[task 2021-01-31T21:27:52.646Z] 21:27:52 INFO - PID 9940 |
[task 2021-01-31T21:27:52.646Z] 21:27:52 INFO - PID 9940 | simple.webconsole.open.DAMP: 275.4
[task 2021-01-31T21:27:52.646Z] 21:27:52 INFO - PID 9940 | simple.webconsole.reload.DAMP: 173.9
[task 2021-01-31T21:27:52.647Z] 21:27:52 INFO - PID 9940 | simple.webconsole.close.DAMP: 7.7
[task 2021-01-31T21:27:52.647Z] 21:27:52 INFO - PID 9940 | simple.inspector.open.DAMP: 230.2
[task 2021-01-31T21:27:52.647Z] 21:27:52 INFO - PID 9940 | simple.inspector.reload.DAMP: 128.8
[task 2021-01-31T21:27:52.647Z] 21:27:52 INFO - PID 9940 | simple.inspector.close.DAMP: 6.5
[task 2021-01-31T21:27:52.648Z] 21:27:52 INFO - PID 9940 | simple.jsdebugger.open.DAMP: 485.0
[task 2021-01-31T21:27:52.648Z] 21:27:52 INFO - PID 9940 | simple.jsdebugger.reload.DAMP: 247.7
[task 2021-01-31T21:27:52.648Z] 21:27:52 INFO - PID 9940 | simple.jsdebugger.close.DAMP: 22.2
[task 2021-01-31T21:27:52.649Z] 21:27:52 INFO - PID 9940 | simple.styleeditor.open.DAMP: 203.1
[task 2021-01-31T21:27:52.650Z] 21:27:52 INFO - PID 9940 | simple.styleeditor.reload.DAMP: 24.7
[task 2021-01-31T21:27:52.650Z] 21:27:52 INFO - PID 9940 | simple.styleeditor.close.DAMP: 5.3
[task 2021-01-31T21:27:52.651Z] 21:27:52 INFO - PID 9940 | simple.netmonitor.open.DAMP: 153.9
[task 2021-01-31T21:27:52.651Z] 21:27:52 INFO - PID 9940 | simple.netmonitor.reload.DAMP: 22.6
[task 2021-01-31T21:27:52.651Z] 21:27:52 INFO - PID 9940 | simple.netmonitor.requestsFinished.DAMP: 179.9
[task 2021-01-31T21:27:52.651Z] 21:27:52 INFO - PID 9940 | simple.netmonitor.exportHar: 3.8
[task 2021-01-31T21:27:52.651Z] 21:27:52 INFO - PID 9940 | simple.netmonitor.close.DAMP: 9.2
[task 2021-01-31T21:27:52.651Z] 21:27:52 INFO - PID 9940 | simple.accessibility.open.DAMP: 213.5
[task 2021-01-31T21:27:52.651Z] 21:27:52 INFO - PID 9940 | simple.accessibility.reload.DAMP: 72.2
[task 2021-01-31T21:27:52.651Z] 21:27:52 INFO - PID 9940 | simple.accessibility.close.DAMP: 7.6
[task 2021-01-31T21:27:52.652Z] 21:27:52 INFO - PID 9940 | complicated.webconsole.open.DAMP: 298.2
[task 2021-01-31T21:27:52.652Z] 21:27:52 INFO - PID 9940 | complicated.webconsole.reload.DAMP: 1558.2
[task 2021-01-31T21:27:52.652Z] 21:27:52 INFO - PID 9940 | complicated.webconsole.close.DAMP: 16.5
[task 2021-01-31T21:27:52.652Z] 21:27:52 INFO - PID 9940 | complicated.inspector.open.DAMP: 325.2
[task 2021-01-31T21:27:52.652Z] 21:27:52 INFO - PID 9940 | complicated.inspector.reload.DAMP: 704.4
[task 2021-01-31T21:27:52.652Z] 21:27:52 INFO - PID 9940 | complicated.inspector.close.DAMP: 21.5
[task 2021-01-31T21:27:52.652Z] 21:27:52 INFO - PID 9940 | complicated.jsdebugger.open.DAMP: 944.5
[task 2021-01-31T21:27:52.652Z] 21:27:52 INFO - PID 9940 | complicated.jsdebugger.reload.DAMP: 1114.7
[task 2021-01-31T21:27:52.653Z] 21:27:52 INFO - PID 9940 | complicated.jsdebugger.close.DAMP: 35.8
[task 2021-01-31T21:27:52.653Z] 21:27:52 INFO - PID 9940 | complicated.styleeditor.open.DAMP: 507.2
[task 2021-01-31T21:27:52.653Z] 21:27:52 INFO - PID 9940 | complicated.styleeditor.reload.DAMP: 1928.9
[task 2021-01-31T21:27:52.653Z] 21:27:52 INFO - PID 9940 | complicated.styleeditor.close.DAMP: 21.8
[task 2021-01-31T21:27:52.653Z] 21:27:52 INFO - PID 9940 | complicated.netmonitor.open.DAMP: 247.8
[task 2021-01-31T21:27:52.653Z] 21:27:52 INFO - PID 9940 | complicated.netmonitor.reload.DAMP: 2598.9
[task 2021-01-31T21:27:52.653Z] 21:27:52 INFO - PID 9940 | complicated.netmonitor.requestsFinished.DAMP: 3720.9
[task 2021-01-31T21:27:52.653Z] 21:27:52 INFO - PID 9940 | complicated.netmonitor.exportHar: 534.4
[task 2021-01-31T21:27:52.653Z] 21:27:52 INFO - PID 9940 | complicated.netmonitor.responsePanel.html: 291.4
[task 2021-01-31T21:27:52.653Z] 21:27:52 INFO - PID 9940 | complicated.netmonitor.responsePanel.editor: 2772.2
[task 2021-01-31T21:27:52.654Z] 21:27:52 INFO - PID 9940 | complicated.netmonitor.close.DAMP: 47.4
[task 2021-01-31T21:27:52.654Z] 21:27:52 INFO - PID 9940 | custom.webconsole.open.DAMP: 2429.3
[task 2021-01-31T21:27:52.654Z] 21:27:52 INFO - PID 9940 | custom.webconsole.reload.DAMP: 9039.3
[task 2021-01-31T21:27:52.654Z] 21:27:52 INFO - PID 9940 | custom.webconsole.close.DAMP: 194.8
[task 2021-01-31T21:27:52.654Z] 21:27:52 INFO - PID 9940 | custom.inspector.open.DAMP: 346.5
[task 2021-01-31T21:27:52.654Z] 21:27:52 INFO - PID 9940 | custom.inspector.reload.DAMP: 479.7
[task 2021-01-31T21:27:52.654Z] 21:27:52 INFO - PID 9940 | custom.inspector.manyrules.selectnode: 674.7
[task 2021-01-31T21:27:52.655Z] 21:27:52 INFO - PID 9940 | custom.inspector.manyrules.deselectnode: 156.4
[task 2021-01-31T21:27:52.655Z] 21:27:52 INFO - PID 9940 | custom.inspector.manycssvariables.selectnode: 366.7
[task 2021-01-31T21:27:52.655Z] 21:27:52 INFO - PID 9940 | custom.inspector.expandall.manychildren: 157.4
[task 2021-01-31T21:27:52.655Z] 21:27:52 INFO - PID 9940 | custom.inspector.collapseall.manychildren: 1.2
[task 2021-01-31T21:27:52.655Z] 21:27:52 INFO - PID 9940 | custom.inspector.expandall.balanced: 708.0
[task 2021-01-31T21:27:52.655Z] 21:27:52 INFO - PID 9940 | custom.inspector.collapseall.balanced: 8.7
[task 2021-01-31T21:27:52.655Z] 21:27:52 INFO - PID 9940 | custom.inspector.close.DAMP: 44.5
[task 2021-01-31T21:27:52.655Z] 21:27:52 INFO - PID 9940 | custom.jsdebugger.open.DAMP: 893.2
[task 2021-01-31T21:27:52.655Z] 21:27:52 INFO - PID 9940 | custom.jsdebugger.reload.DAMP: 799.7
[task 2021-01-31T21:27:52.656Z] 21:27:52 INFO - PID 9940 | custom.jsdebugger.pause.DAMP: 177.8
[task 2021-01-31T21:27:52.656Z] 21:27:52 INFO - PID 9940 | custom.jsdebugger.stepIn.DAMP: 618.1
[task 2021-01-31T21:27:52.656Z] 21:27:52 INFO - PID 9940 | custom.jsdebugger.stepOver.DAMP: 291.9
[task 2021-01-31T21:27:52.656Z] 21:27:52 INFO - PID 9940 | custom.jsdebugger.stepOut.DAMP: 392.1
[task 2021-01-31T21:27:52.656Z] 21:27:52 INFO - PID 9940 | custom.jsdebugger.project-search.DAMP: 1670.7
[task 2021-01-31T21:27:52.656Z] 21:27:52 INFO - PID 9940 | custom.jsdebugger.preview.DAMP: 544.4
[task 2021-01-31T21:27:52.656Z] 21:27:52 INFO - PID 9940 | custom.jsdebugger.close.DAMP: 45.3
[task 2021-01-31T21:27:52.656Z] 21:27:52 INFO - PID 9940 | console.bulklog: 1491.4
[task 2021-01-31T21:27:52.656Z] 21:27:52 INFO - PID 9940 | console.autocomplete: 722.3
[task 2021-01-31T21:27:52.657Z] 21:27:52 INFO - PID 9940 | console.autocomplete.longInput: 1039.8
[task 2021-01-31T21:27:52.657Z] 21:27:52 INFO - PID 9940 | console.streamlog: 16.0
[task 2021-01-31T21:27:52.657Z] 21:27:52 INFO - PID 9940 | console.objectexpand: 396.4
[task 2021-01-31T21:27:52.657Z] 21:27:52 INFO - PID 9940 | console.objectexpand-many-instances: 847.1
[task 2021-01-31T21:27:52.657Z] 21:27:52 INFO - PID 9940 | console.objectexpanded.close.DAMP: 53.0
[task 2021-01-31T21:27:52.657Z] 21:27:52 INFO - PID 9940 | console.openwithcache.open.DAMP: 444.3
[task 2021-01-31T21:27:52.657Z] 21:27:52 INFO - PID 9940 | console.typing: 377.7
[task 2021-01-31T21:27:52.657Z] 21:27:52 INFO - PID 9940 | inspector.mutations: 849.2
[task 2021-01-31T21:27:52.657Z] 21:27:52 INFO - PID 9940 | inspector.layout.open: 297.8
[task 2021-01-31T21:27:52.658Z] 21:27:52 INFO - PID 9940 | panelsInBackground.reload.DAMP: 1284.0
[task 2021-01-31T21:27:52.658Z] 21:27:52 INFO - PID 9940 | screenshot.DAMP: 1608.8
[task 2021-01-31T21:27:52.658Z] 21:27:52 INFO - PID 9940 | server.protocoljs.DAMP: 1931.5
[task 2021-01-31T21:27:52.658Z] 21:27:52 INFO - PID 9940 | [DampLoad helper] Unregister DampLoad actors
[task 2021-01-31T21:27:52.658Z] 21:27:52 INFO - PID 9940 | Cycle 1(4): loaded ext+damp:damp.html (next: ext+damp:damp.html)
[task 2021-01-31T21:27:53.264Z] 21:27:53 INFO - PID 9940 | [damp-api] Expose damp test path as a char preference
[task 2021-01-31T21:27:53.264Z] 21:27:53 INFO - PID 9940 | [damp-api] Retrieve the main DevTools loader
[task 2021-01-31T21:27:53.264Z] 21:27:53 INFO - PID 9940 | [damp-api] Retrieve the DAMP runner and start the test
[task 2021-01-31T21:27:53.265Z] 21:27:53 INFO - PID 9940 | [DampLoad helper] Register DampLoad actors
[task 2021-01-31T21:27:54.760Z] 21:27:54 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:27:55.201Z] 21:27:55 INFO - PID 9940 | Loading test 'webconsole/simple.js'
[task 2021-01-31T21:27:55.201Z] 21:27:55 INFO - PID 9940 | Executing test 'webconsole/simple.js'
[task 2021-01-31T21:27:55.201Z] 21:27:55 INFO - PID 9940 | Wait for a pageshow event for browsing context 646
[task 2021-01-31T21:27:55.240Z] 21:27:55 INFO - PID 9940 | Received pageshow event for 646
[task 2021-01-31T21:27:55.339Z] 21:27:55 INFO - PID 9940 | Open toolbox on 'webconsole'
[task 2021-01-31T21:27:55.340Z] 21:27:55 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:27:55.385Z] 21:27:55 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:27:55.385Z] 21:27:55 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:27:55.390Z] 21:27:55 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:27:55.611Z] 21:27:55 INFO - PID 9940 | Wait for pending paints on 'simple.webconsole.open'
[task 2021-01-31T21:27:55.611Z] 21:27:55 INFO - PID 9940 | 'simple.webconsole.open.settle.DAMP' took 0.059733384288847446ms.
[task 2021-01-31T21:27:55.611Z] 21:27:55 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:27:56.145Z] 21:27:56 INFO - PID 9940 | Reload page on 'simple.webconsole'
[task 2021-01-31T21:27:56.303Z] 21:27:56 INFO - PID 9940 | Wait for pending paints on 'simple.webconsole.reload'
[task 2021-01-31T21:27:56.323Z] 21:27:56 INFO - PID 9940 | 'simple.webconsole.reload.settle.DAMP' took 20.82954310788773ms.
[task 2021-01-31T21:27:56.323Z] 21:27:56 INFO - PID 9940 | Close toolbox on 'simple.webconsole'
[task 2021-01-31T21:27:56.341Z] 21:27:56 INFO - PID 9940 | console.warn: "IGNORED REDUX ACTION:" "AUTOCOMPLETE_CLEAR"
[task 2021-01-31T21:27:56.344Z] 21:27:56 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:27:56.868Z] 21:27:56 INFO - PID 9940 | webconsole/simple.js took 1665ms.
[task 2021-01-31T21:27:56.868Z] 21:27:56 INFO - PID 9940 | Loading test 'inspector/simple.js'
[task 2021-01-31T21:27:56.868Z] 21:27:56 INFO - PID 9940 | Executing test 'inspector/simple.js'
[task 2021-01-31T21:27:56.873Z] 21:27:56 INFO - PID 9940 | Wait for a pageshow event for browsing context 651
[task 2021-01-31T21:27:56.895Z] 21:27:56 INFO - PID 9940 | Received pageshow event for 651
[task 2021-01-31T21:27:56.995Z] 21:27:56 INFO - PID 9940 | Open toolbox on 'inspector'
[task 2021-01-31T21:27:56.995Z] 21:27:56 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:27:57.000Z] 21:27:57 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:27:57.000Z] 21:27:57 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:27:57.005Z] 21:27:57 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:27:57.236Z] 21:27:57 INFO - PID 9940 | Wait for pending paints on 'simple.inspector.open'
[task 2021-01-31T21:27:57.257Z] 21:27:57 INFO - PID 9940 | 'simple.inspector.open.settle.DAMP' took 24.362346122507006ms.
[task 2021-01-31T21:27:57.257Z] 21:27:57 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:27:57.799Z] 21:27:57 INFO - PID 9940 | Reload page on 'simple.inspector'
[task 2021-01-31T21:27:57.918Z] 21:27:57 INFO - PID 9940 | Wait for pending paints on 'simple.inspector.reload'
[task 2021-01-31T21:27:57.939Z] 21:27:57 INFO - PID 9940 | 'simple.inspector.reload.settle.DAMP' took 21.501629014732316ms.
[task 2021-01-31T21:27:57.939Z] 21:27:57 INFO - PID 9940 | Close toolbox on 'simple.inspector'
[task 2021-01-31T21:27:57.957Z] 21:27:57 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:27:58.521Z] 21:27:58 INFO - PID 9940 | inspector/simple.js took 1655ms.
[task 2021-01-31T21:27:58.521Z] 21:27:58 INFO - PID 9940 | Loading test 'debugger/simple.js'
[task 2021-01-31T21:27:58.521Z] 21:27:58 INFO - PID 9940 | Executing test 'debugger/simple.js'
[task 2021-01-31T21:27:58.521Z] 21:27:58 INFO - PID 9940 | Wait for a pageshow event for browsing context 657
[task 2021-01-31T21:27:58.546Z] 21:27:58 INFO - PID 9940 | Received pageshow event for 657
[task 2021-01-31T21:27:58.648Z] 21:27:58 INFO - PID 9940 | Open toolbox on 'jsdebugger'
[task 2021-01-31T21:27:58.648Z] 21:27:58 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:27:58.653Z] 21:27:58 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:27:58.653Z] 21:27:58 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:27:58.653Z] 21:27:58 INFO - PID 9940 | Open toolbox - Wait for custom onLoad callback
[task 2021-01-31T21:27:58.934Z] 21:27:58 INFO - PID 9940 | Waiting for state change: has source http://damp.top.com/tests/devtools/addon/content/pages/simple.html
[task 2021-01-31T21:27:58.934Z] 21:27:58 INFO - PID 9940 | Finished waiting for state change: has source http://damp.top.com/tests/devtools/addon/content/pages/simple.html
[task 2021-01-31T21:27:58.934Z] 21:27:58 INFO - PID 9940 | Selecting source: simple.html
[task 2021-01-31T21:27:58.973Z] 21:27:58 INFO - PID 9940 | Waiting for state change: selected source
[task 2021-01-31T21:27:59.033Z] 21:27:59 INFO - PID 9940 | Finished waiting for state change: selected source
[task 2021-01-31T21:27:59.033Z] 21:27:59 INFO - PID 9940 | Waiting until: text is visible
[task 2021-01-31T21:27:59.090Z] 21:27:59 INFO - PID 9940 | Finished Waiting until: text is visible
[task 2021-01-31T21:27:59.090Z] 21:27:59 INFO - PID 9940 | Waiting until: has file metadata
[task 2021-01-31T21:27:59.132Z] 21:27:59 INFO - PID 9940 | Finished Waiting until: has file metadata
[task 2021-01-31T21:27:59.132Z] 21:27:59 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:27:59.132Z] 21:27:59 INFO - PID 9940 | Wait for pending paints on 'simple.jsdebugger.open'
[task 2021-01-31T21:27:59.132Z] 21:27:59 INFO - PID 9940 | 'simple.jsdebugger.open.settle.DAMP' took 0.05188271088991314ms.
[task 2021-01-31T21:27:59.132Z] 21:27:59 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:27:59.774Z] 21:27:59 INFO - PID 9940 | Reload page on 'simple.jsdebugger'
[task 2021-01-31T21:27:59.776Z] 21:27:59 INFO - PID 9940 | Waiting for state change: count sources
[task 2021-01-31T21:27:59.898Z] 21:27:59 INFO - PID 9940 | Finished waiting for state change: count sources
[task 2021-01-31T21:27:59.917Z] 21:27:59 INFO - PID 9940 | Waiting until: text is visible
[task 2021-01-31T21:27:59.959Z] 21:27:59 INFO - PID 9940 | Finished Waiting until: text is visible
[task 2021-01-31T21:27:59.959Z] 21:27:59 INFO - PID 9940 | Waiting until: has file metadata
[task 2021-01-31T21:28:00.018Z] 21:28:00 INFO - PID 9940 | Finished Waiting until: has file metadata
[task 2021-01-31T21:28:00.018Z] 21:28:00 INFO - PID 9940 | Wait for pending paints on 'simple.jsdebugger.reload'
[task 2021-01-31T21:28:00.018Z] 21:28:00 INFO - PID 9940 | 'simple.jsdebugger.reload.settle.DAMP' took 0.07133872748818249ms.
[task 2021-01-31T21:28:00.018Z] 21:28:00 INFO - PID 9940 | Close toolbox on 'simple.jsdebugger'
[task 2021-01-31T21:28:00.043Z] 21:28:00 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:00.685Z] 21:28:00 INFO - PID 9940 | debugger/simple.js took 2170ms.
[task 2021-01-31T21:28:00.685Z] 21:28:00 INFO - PID 9940 | Loading test 'styleeditor/simple.js'
[task 2021-01-31T21:28:00.685Z] 21:28:00 INFO - PID 9940 | Executing test 'styleeditor/simple.js'
[task 2021-01-31T21:28:00.704Z] 21:28:00 INFO - PID 9940 | Wait for a pageshow event for browsing context 662
[task 2021-01-31T21:28:00.706Z] 21:28:00 INFO - PID 9940 | [Parent 6104, IPC I/O Parent] WARNING: file /builds/worker/checkouts/gecko/ipc/chromium/src/base/process_util_win.cc:167
[task 2021-01-31T21:28:00.746Z] 21:28:00 INFO - PID 9940 | Received pageshow event for 662
[task 2021-01-31T21:28:00.846Z] 21:28:00 INFO - PID 9940 | Open toolbox on 'styleeditor'
[task 2021-01-31T21:28:00.846Z] 21:28:00 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:28:00.886Z] 21:28:00 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:28:00.886Z] 21:28:00 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:28:00.891Z] 21:28:00 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:28:01.052Z] 21:28:01 INFO - PID 9940 | Wait for pending paints on 'simple.styleeditor.open'
[task 2021-01-31T21:28:01.052Z] 21:28:01 INFO - PID 9940 | 'simple.styleeditor.open.settle.DAMP' took 0.04505603853613138ms.
[task 2021-01-31T21:28:01.052Z] 21:28:01 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:01.734Z] 21:28:01 INFO - PID 9940 | Reload page on 'simple.styleeditor'
[task 2021-01-31T21:28:01.734Z] 21:28:01 INFO - PID 9940 | Wait for a pageshow event for browsing context 662
[task 2021-01-31T21:28:01.753Z] 21:28:01 INFO - PID 9940 | Received pageshow event for 662
[task 2021-01-31T21:28:01.753Z] 21:28:01 INFO - PID 9940 | Wait for pending paints on 'simple.styleeditor.reload'
[task 2021-01-31T21:28:01.753Z] 21:28:01 INFO - PID 9940 | 'simple.styleeditor.reload.settle.DAMP' took 0.10171742015518248ms.
[task 2021-01-31T21:28:01.753Z] 21:28:01 INFO - PID 9940 | Close toolbox on 'simple.styleeditor'
[task 2021-01-31T21:28:01.772Z] 21:28:01 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:02.435Z] 21:28:02 INFO - PID 9940 | styleeditor/simple.js took 1739ms.
[task 2021-01-31T21:28:02.435Z] 21:28:02 INFO - PID 9940 | Loading test 'netmonitor/simple.js'
[task 2021-01-31T21:28:02.435Z] 21:28:02 INFO - PID 9940 | Executing test 'netmonitor/simple.js'
[task 2021-01-31T21:28:02.435Z] 21:28:02 INFO - PID 9940 | Wait for a pageshow event for browsing context 667
[task 2021-01-31T21:28:02.454Z] 21:28:02 INFO - PID 9940 | Received pageshow event for 667
[task 2021-01-31T21:28:02.553Z] 21:28:02 INFO - PID 9940 | Open toolbox on 'netmonitor'
[task 2021-01-31T21:28:02.553Z] 21:28:02 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:28:02.558Z] 21:28:02 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:28:02.558Z] 21:28:02 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:28:02.558Z] 21:28:02 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:28:02.698Z] 21:28:02 INFO - PID 9940 | Wait for pending paints on 'simple.netmonitor.open'
[task 2021-01-31T21:28:02.728Z] 21:28:02 INFO - PID 9940 | 'simple.netmonitor.open.settle.DAMP' took 24.099860565154813ms.
[task 2021-01-31T21:28:02.728Z] 21:28:02 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:03.411Z] 21:28:03 INFO - PID 9940 | Reload page on 'simple.netmonitor'
[task 2021-01-31T21:28:03.411Z] 21:28:03 INFO - PID 9940 | Wait for a pageshow event for browsing context 667
[task 2021-01-31T21:28:03.429Z] 21:28:03 INFO - PID 9940 | Received pageshow event for 667
[task 2021-01-31T21:28:03.429Z] 21:28:03 INFO - PID 9940 | Wait for pending paints on 'simple.netmonitor.reload'
[task 2021-01-31T21:28:03.429Z] 21:28:03 INFO - PID 9940 | 'simple.netmonitor.reload.settle.DAMP' took 0.07918940088711679ms.
[task 2021-01-31T21:28:03.590Z] 21:28:03 INFO - PID 9940 | Waiting for 0 requests
[task 2021-01-31T21:28:03.592Z] 21:28:03 INFO - PID 9940 | Close toolbox on 'simple.netmonitor'
[task 2021-01-31T21:28:03.611Z] 21:28:03 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:04.335Z] 21:28:04 INFO - PID 9940 | netmonitor/simple.js took 1904ms.
[task 2021-01-31T21:28:04.335Z] 21:28:04 INFO - PID 9940 | Loading test 'accessibility/simple.js'
[task 2021-01-31T21:28:04.335Z] 21:28:04 INFO - PID 9940 | Executing test 'accessibility/simple.js'
[task 2021-01-31T21:28:04.337Z] 21:28:04 INFO - PID 9940 | Wait for a pageshow event for browsing context 672
[task 2021-01-31T21:28:04.359Z] 21:28:04 INFO - PID 9940 | Received pageshow event for 672
[task 2021-01-31T21:28:04.461Z] 21:28:04 INFO - PID 9940 | Open toolbox on 'accessibility'
[task 2021-01-31T21:28:04.461Z] 21:28:04 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:28:04.466Z] 21:28:04 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:28:04.466Z] 21:28:04 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:28:04.466Z] 21:28:04 INFO - PID 9940 | Open toolbox - Wait for custom onLoad callback
[task 2021-01-31T21:28:04.686Z] 21:28:04 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:28:04.686Z] 21:28:04 INFO - PID 9940 | Wait for pending paints on 'simple.accessibility.open'
[task 2021-01-31T21:28:04.705Z] 21:28:04 INFO - PID 9940 | 'simple.accessibility.open.settle.DAMP' took 10.303155458765104ms.
[task 2021-01-31T21:28:04.705Z] 21:28:04 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:05.430Z] 21:28:05 INFO - PID 9940 | Reload page on 'simple.accessibility'
[task 2021-01-31T21:28:05.496Z] 21:28:05 INFO - PID 9940 | Wait for pending paints on 'simple.accessibility.reload'
[task 2021-01-31T21:28:05.526Z] 21:28:05 INFO - PID 9940 | 'simple.accessibility.reload.settle.DAMP' took 29.677251991350204ms.
[task 2021-01-31T21:28:05.526Z] 21:28:05 INFO - PID 9940 | Close toolbox on 'simple.accessibility'
[task 2021-01-31T21:28:05.544Z] 21:28:05 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:06.321Z] 21:28:06 INFO - PID 9940 | accessibility/simple.js took 1989ms.
[task 2021-01-31T21:28:06.321Z] 21:28:06 INFO - PID 9940 | Loading test 'webconsole/complicated.js'
[task 2021-01-31T21:28:06.321Z] 21:28:06 INFO - PID 9940 | Executing test 'webconsole/complicated.js'
[task 2021-01-31T21:28:06.331Z] 21:28:06 INFO - PID 9940 | Wait for a pageshow event for browsing context 677
[task 2021-01-31T21:28:07.188Z] 21:28:07 INFO - PID 9940 | Received pageshow event for 677
[task 2021-01-31T21:28:07.288Z] 21:28:07 INFO - PID 9940 | Open toolbox on 'webconsole'
[task 2021-01-31T21:28:07.288Z] 21:28:07 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:28:07.348Z] 21:28:07 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:28:07.348Z] 21:28:07 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:28:07.348Z] 21:28:07 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:28:07.628Z] 21:28:07 INFO - PID 9940 | Wait for pending paints on 'complicated.webconsole.open'
[task 2021-01-31T21:28:07.647Z] 21:28:07 INFO - PID 9940 | 'complicated.webconsole.open.settle.DAMP' took 23.70527889521327ms.
[task 2021-01-31T21:28:07.647Z] 21:28:07 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:08.555Z] 21:28:08 INFO - PID 9940 | Reload page on 'complicated.webconsole'
[task 2021-01-31T21:28:10.158Z] 21:28:10 INFO - PID 9940 | Wait for pending paints on 'complicated.webconsole.reload'
[task 2021-01-31T21:28:10.177Z] 21:28:10 INFO - PID 9940 | 'complicated.webconsole.reload.settle.DAMP' took 17.32438811683096ms.
[task 2021-01-31T21:28:10.177Z] 21:28:10 INFO - PID 9940 | Close toolbox on 'complicated.webconsole'
[task 2021-01-31T21:28:10.199Z] 21:28:10 INFO - PID 9940 | console.warn: "IGNORED REDUX ACTION:" "AUTOCOMPLETE_CLEAR"
[task 2021-01-31T21:28:10.204Z] 21:28:10 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:11.109Z] 21:28:11 INFO - PID 9940 | webconsole/complicated.js took 4790ms.
[task 2021-01-31T21:28:11.110Z] 21:28:11 INFO - PID 9940 | Loading test 'inspector/complicated.js'
[task 2021-01-31T21:28:11.110Z] 21:28:11 INFO - PID 9940 | Executing test 'inspector/complicated.js'
[task 2021-01-31T21:28:11.115Z] 21:28:11 INFO - PID 9940 | Wait for a pageshow event for browsing context 682
[task 2021-01-31T21:28:11.971Z] 21:28:11 INFO - PID 9940 | Received pageshow event for 682
[task 2021-01-31T21:28:12.072Z] 21:28:12 INFO - PID 9940 | Open toolbox on 'inspector'
[task 2021-01-31T21:28:12.072Z] 21:28:12 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:28:12.077Z] 21:28:12 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:28:12.077Z] 21:28:12 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:28:12.077Z] 21:28:12 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:28:12.339Z] 21:28:12 INFO - PID 9940 | Wait for pending paints on 'complicated.inspector.open'
[task 2021-01-31T21:28:12.358Z] 21:28:12 INFO - PID 9940 | 'complicated.inspector.open.settle.DAMP' took 22.447464488563128ms.
[task 2021-01-31T21:28:12.358Z] 21:28:12 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:13.224Z] 21:28:13 INFO - PID 9940 | Reload page on 'complicated.inspector'
[task 2021-01-31T21:28:13.243Z] 21:28:13 INFO - PID 9940 | console.error: "Error while calling actor 'pagestyle's method 'getUsedFontFaces'" "node is null"
[task 2021-01-31T21:28:13.245Z] 21:28:13 INFO - PID 9940 | console.error: "getUsedFontFaces@resource://devtools/server/actors/page-style.js:368:24\nhandler@resource://devtools/shared/protocol/Actor.js:172:37\nonPacket@resource://devtools/server/devtools-server-connection.js:379:58\nreceiveMessage@resource://devtools/shared/transport/child-transport.js:68:16\n"
[task 2021-01-31T21:28:13.255Z] 21:28:13 INFO - PID 9940 | console.error: "Could not get the list of font families" (new Error("Protocol error (TypeError): node is null from: server0.conn112.child3/pagestyle37 (resource://devtools/server/actors/page-style.js:368:24)", "resource://devtools/shared/protocol/Front.js", 361))
[task 2021-01-31T21:28:13.960Z] 21:28:13 INFO - PID 9940 | Wait for pending paints on 'complicated.inspector.reload'
[task 2021-01-31T21:28:13.960Z] 21:28:13 INFO - PID 9940 | 'complicated.inspector.reload.settle.DAMP' took 0.05495471367612481ms.
[task 2021-01-31T21:28:13.960Z] 21:28:13 INFO - PID 9940 | Close toolbox on 'complicated.inspector'
[task 2021-01-31T21:28:14.025Z] 21:28:14 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:14.982Z] 21:28:14 INFO - PID 9940 | inspector/complicated.js took 3872ms.
[task 2021-01-31T21:28:14.982Z] 21:28:14 INFO - PID 9940 | Loading test 'debugger/complicated.js'
[task 2021-01-31T21:28:14.982Z] 21:28:14 INFO - PID 9940 | Executing test 'debugger/complicated.js'
[task 2021-01-31T21:28:14.992Z] 21:28:14 INFO - PID 9940 | Wait for a pageshow event for browsing context 688
[task 2021-01-31T21:28:16.084Z] 21:28:16 INFO - PID 9940 | Received pageshow event for 688
[task 2021-01-31T21:28:16.185Z] 21:28:16 INFO - PID 9940 | Open toolbox on 'jsdebugger'
[task 2021-01-31T21:28:16.185Z] 21:28:16 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:28:16.227Z] 21:28:16 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:28:16.227Z] 21:28:16 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:28:16.232Z] 21:28:16 INFO - PID 9940 | Open toolbox - Wait for custom onLoad callback
[task 2021-01-31T21:28:16.617Z] 21:28:16 INFO - PID 9940 | Waiting for state change: has source http://www.bild.de-talos/fis/tp5n/bild.de/www.bild.de/index.html
[task 2021-01-31T21:28:16.617Z] 21:28:16 INFO - PID 9940 | Finished waiting for state change: has source http://www.bild.de-talos/fis/tp5n/bild.de/www.bild.de/index.html
[task 2021-01-31T21:28:16.618Z] 21:28:16 INFO - PID 9940 | Selecting source: ga.js
[task 2021-01-31T21:28:16.663Z] 21:28:16 INFO - PID 9940 | Waiting for state change: selected source
[task 2021-01-31T21:28:17.115Z] 21:28:17 INFO - PID 9940 | Finished waiting for state change: selected source
[task 2021-01-31T21:28:17.115Z] 21:28:17 INFO - PID 9940 | Waiting until: text is visible
[task 2021-01-31T21:28:17.165Z] 21:28:17 INFO - PID 9940 | Finished Waiting until: text is visible
[task 2021-01-31T21:28:17.165Z] 21:28:17 INFO - PID 9940 | Waiting until: has file metadata
[task 2021-01-31T21:28:17.217Z] 21:28:17 INFO - PID 9940 | Finished Waiting until: has file metadata
[task 2021-01-31T21:28:17.217Z] 21:28:17 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:28:17.217Z] 21:28:17 INFO - PID 9940 | Wait for pending paints on 'complicated.jsdebugger.open'
[task 2021-01-31T21:28:17.217Z] 21:28:17 INFO - PID 9940 | 'complicated.jsdebugger.open.settle.DAMP' took 0.07918940100353211ms.
[task 2021-01-31T21:28:17.217Z] 21:28:17 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:18.202Z] 21:28:18 INFO - PID 9940 | Reload page on 'complicated.jsdebugger'
[task 2021-01-31T21:28:18.224Z] 21:28:18 INFO - PID 9940 | Waiting for state change: count sources
[task 2021-01-31T21:28:18.464Z] 21:28:18 INFO - PID 9940 | Finished waiting for state change: count sources
[task 2021-01-31T21:28:18.466Z] 21:28:18 INFO - PID 9940 | Waiting until: text is visible
[task 2021-01-31T21:28:18.948Z] 21:28:18 INFO - PID 9940 | Finished Waiting until: text is visible
[task 2021-01-31T21:28:18.948Z] 21:28:18 INFO - PID 9940 | Waiting until: has file metadata
[task 2021-01-31T21:28:19.289Z] 21:28:19 INFO - PID 9940 | Finished Waiting until: has file metadata
[task 2021-01-31T21:28:19.289Z] 21:28:19 INFO - PID 9940 | Wait for pending paints on 'complicated.jsdebugger.reload'
[task 2021-01-31T21:28:19.289Z] 21:28:19 INFO - PID 9940 | 'complicated.jsdebugger.reload.settle.DAMP' took 0.08499207254499197ms.
[task 2021-01-31T21:28:19.289Z] 21:28:19 INFO - PID 9940 | Close toolbox on 'complicated.jsdebugger'
[task 2021-01-31T21:28:19.328Z] 21:28:19 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:20.399Z] 21:28:20 INFO - PID 9940 | debugger/complicated.js took 5413ms.
[task 2021-01-31T21:28:20.399Z] 21:28:20 INFO - PID 9940 | Loading test 'styleeditor/complicated.js'
[task 2021-01-31T21:28:20.399Z] 21:28:20 INFO - PID 9940 | Executing test 'styleeditor/complicated.js'
[task 2021-01-31T21:28:20.404Z] 21:28:20 INFO - PID 9940 | Wait for a pageshow event for browsing context 693
[task 2021-01-31T21:28:21.250Z] 21:28:21 INFO - PID 9940 | Received pageshow event for 693
[task 2021-01-31T21:28:21.352Z] 21:28:21 INFO - PID 9940 | Open toolbox on 'styleeditor'
[task 2021-01-31T21:28:21.352Z] 21:28:21 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:28:21.352Z] 21:28:21 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:28:21.353Z] 21:28:21 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:28:21.355Z] 21:28:21 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:28:21.841Z] 21:28:21 INFO - PID 9940 | Wait for pending paints on 'complicated.styleeditor.open'
[task 2021-01-31T21:28:21.841Z] 21:28:21 INFO - PID 9940 | 'complicated.styleeditor.open.settle.DAMP' took 0.04573870578315109ms.
[task 2021-01-31T21:28:21.841Z] 21:28:21 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:22.930Z] 21:28:22 INFO - PID 9940 | Reload page on 'complicated.styleeditor'
[task 2021-01-31T21:28:22.930Z] 21:28:22 INFO - PID 9940 | Wait for a pageshow event for browsing context 693
[task 2021-01-31T21:28:24.747Z] 21:28:24 INFO - PID 9940 | Received pageshow event for 693
[task 2021-01-31T21:28:24.747Z] 21:28:24 INFO - PID 9940 | Wait for pending paints on 'complicated.styleeditor.reload'
[task 2021-01-31T21:28:24.747Z] 21:28:24 INFO - PID 9940 | 'complicated.styleeditor.reload.settle.DAMP' took 0.04334937036037445ms.
[task 2021-01-31T21:28:24.747Z] 21:28:24 INFO - PID 9940 | Close toolbox on 'complicated.styleeditor'
[task 2021-01-31T21:28:24.766Z] 21:28:24 INFO - PID 9940 | console.error: (new TypeError("this.browserRequire is not a function", "resource://devtools/client/framework/toolbox.js", 1258))
[task 2021-01-31T21:28:24.766Z] 21:28:24 INFO - PID 9940 | console.error: (new TypeError("this._seenSheets is null", "resource://devtools/client/styleeditor/StyleEditorUI.jsm", 317))
[task 2021-01-31T21:28:24.766Z] 21:28:24 INFO - PID 9940 | console.error: (new TypeError("this._seenSheets is null", "resource://devtools/client/styleeditor/StyleEditorUI.jsm", 317))
[task 2021-01-31T21:28:24.785Z] 21:28:24 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:25.945Z] 21:28:25 INFO - PID 9940 | styleeditor/complicated.js took 5547ms.
[task 2021-01-31T21:28:25.945Z] 21:28:25 INFO - PID 9940 | Loading test 'netmonitor/complicated.js'
[task 2021-01-31T21:28:25.945Z] 21:28:25 INFO - PID 9940 | Executing test 'netmonitor/complicated.js'
[task 2021-01-31T21:28:25.955Z] 21:28:25 INFO - PID 9940 | Wait for a pageshow event for browsing context 701
[task 2021-01-31T21:28:26.789Z] 21:28:26 INFO - PID 9940 | Received pageshow event for 701
[task 2021-01-31T21:28:26.888Z] 21:28:26 INFO - PID 9940 | Open toolbox on 'netmonitor'
[task 2021-01-31T21:28:26.888Z] 21:28:26 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:28:26.948Z] 21:28:26 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:28:26.948Z] 21:28:26 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:28:26.948Z] 21:28:26 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:28:27.128Z] 21:28:27 INFO - PID 9940 | Wait for pending paints on 'complicated.netmonitor.open'
[task 2021-01-31T21:28:27.128Z] 21:28:27 INFO - PID 9940 | 'complicated.netmonitor.open.settle.DAMP' took 0.040618701255880296ms.
[task 2021-01-31T21:28:27.128Z] 21:28:27 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:28.362Z] 21:28:28 INFO - PID 9940 | Reload page on 'complicated.netmonitor'
[task 2021-01-31T21:28:28.362Z] 21:28:28 INFO - PID 9940 | Wait for a pageshow event for browsing context 701
[task 2021-01-31T21:28:28.602Z] 21:28:28 INFO - PID 9940 | Waiting for 279 requests
...
...
...
[task 2021-01-31T21:28:30.666Z] 21:28:30 INFO - PID 9940 | Waiting for 29 requests
[task 2021-01-31T21:28:30.746Z] 21:28:30 INFO - PID 9940 | Received pageshow event for 701
[task 2021-01-31T21:28:30.746Z] 21:28:30 INFO - PID 9940 | Wait for pending paints on 'complicated.netmonitor.reload'
[task 2021-01-31T21:28:30.746Z] 21:28:30 INFO - PID 9940 | 'complicated.netmonitor.reload.settle.DAMP' took 0.05802671634592116ms.
[task 2021-01-31T21:28:30.791Z] 21:28:30 INFO - PID 9940 | Waiting for 28 requests
[task 2021-01-31T21:28:30.791Z] 21:28:30 INFO - PID 9940 | Waiting for 27 requests
[task 2021-01-31T21:28:30.791Z] 21:28:30 INFO - PID 9940 | Waiting for 26 requests
[task 2021-01-31T21:28:30.793Z] 21:28:30 INFO - PID 9940 | Waiting for 25 requests
[task 2021-01-31T21:28:30.793Z] 21:28:30 INFO - PID 9940 | Waiting for 24 requests
[task 2021-01-31T21:28:30.793Z] 21:28:30 INFO - PID 9940 | Waiting for 23 requests
[task 2021-01-31T21:28:30.793Z] 21:28:30 INFO - PID 9940 | Waiting for 22 requests
[task 2021-01-31T21:28:30.793Z] 21:28:30 INFO - PID 9940 | Waiting for 21 requests
[task 2021-01-31T21:28:30.793Z] 21:28:30 INFO - PID 9940 | Waiting for 20 requests
[task 2021-01-31T21:28:30.794Z] 21:28:30 INFO - PID 9940 | Waiting for 19 requests
[task 2021-01-31T21:28:30.794Z] 21:28:30 INFO - PID 9940 | Waiting for 18 requests
[task 2021-01-31T21:28:30.794Z] 21:28:30 INFO - PID 9940 | Waiting for 17 requests
[task 2021-01-31T21:28:30.794Z] 21:28:30 INFO - PID 9940 | Waiting for 16 requests
[task 2021-01-31T21:28:30.794Z] 21:28:30 INFO - PID 9940 | Waiting for 15 requests
[task 2021-01-31T21:28:30.794Z] 21:28:30 INFO - PID 9940 | Waiting for 14 requests
[task 2021-01-31T21:28:30.794Z] 21:28:30 INFO - PID 9940 | Waiting for 13 requests
[task 2021-01-31T21:28:30.794Z] 21:28:30 INFO - PID 9940 | Waiting for 12 requests
[task 2021-01-31T21:28:30.794Z] 21:28:30 INFO - PID 9940 | Waiting for 11 requests
[task 2021-01-31T21:28:30.794Z] 21:28:30 INFO - PID 9940 | Waiting for 10 requests
[task 2021-01-31T21:28:30.896Z] 21:28:30 INFO - PID 9940 | Waiting for 9 requests
[task 2021-01-31T21:28:30.897Z] 21:28:30 INFO - PID 9940 | Waiting for 8 requests
[task 2021-01-31T21:28:30.897Z] 21:28:30 INFO - PID 9940 | Waiting for 7 requests
[task 2021-01-31T21:28:30.897Z] 21:28:30 INFO - PID 9940 | Waiting for 6 requests
[task 2021-01-31T21:28:30.897Z] 21:28:30 INFO - PID 9940 | Waiting for 5 requests
[task 2021-01-31T21:28:30.897Z] 21:28:30 INFO - PID 9940 | Waiting for 4 requests
[task 2021-01-31T21:28:30.898Z] 21:28:30 INFO - PID 9940 | Waiting for 3 requests
[task 2021-01-31T21:28:30.898Z] 21:28:30 INFO - PID 9940 | Waiting for 2 requests
[task 2021-01-31T21:28:36.362Z] 21:28:36 INFO - PID 9940 | Close toolbox on 'complicated.netmonitor'
[task 2021-01-31T21:28:37.404Z] 21:28:37 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:38.669Z] 21:28:38 INFO - PID 9940 | netmonitor/complicated.js took 12719ms.
[task 2021-01-31T21:28:38.669Z] 21:28:38 INFO - PID 9940 | Loading test 'webconsole/custom.js'
[task 2021-01-31T21:28:38.670Z] 21:28:38 INFO - PID 9940 | Executing test 'webconsole/custom.js'
[task 2021-01-31T21:28:38.672Z] 21:28:38 INFO - PID 9940 | Wait for a pageshow event for browsing context 713
[task 2021-01-31T21:28:38.742Z] 21:28:38 INFO - PID 9940 | Received pageshow event for 713
[task 2021-01-31T21:28:38.842Z] 21:28:38 INFO - PID 9940 | Open toolbox on 'webconsole'
[task 2021-01-31T21:28:38.842Z] 21:28:38 INFO - PID 9940 | Open toolbox - Wait for tab target
[task 2021-01-31T21:28:38.888Z] 21:28:38 INFO - PID 9940 | Open toolbox - Call showToolbox
[task 2021-01-31T21:28:38.888Z] 21:28:38 INFO - PID 9940 | Open toolbox - Wait for "toolbox-created"
[task 2021-01-31T21:28:38.890Z] 21:28:38 INFO - PID 9940 | Open toolbox - Wait for showToolbox to resolve
[task 2021-01-31T21:28:41.191Z] 21:28:41 INFO - PID 9940 | Wait for pending paints on 'custom.webconsole.open'
[task 2021-01-31T21:28:41.210Z] 21:28:41 INFO - PID 9940 | 'custom.webconsole.open.settle.DAMP' took 26.399425194133073ms.
[task 2021-01-31T21:28:41.210Z] 21:28:41 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:28:44.068Z] 21:28:44 INFO - PID 9940 | Reload page on 'custom.webconsole'
[task 2021-01-31T21:29:00.632Z] 21:29:00 INFO - PID 9940 | Wait for pending paints on 'custom.webconsole.reload'
[task 2021-01-31T21:29:00.651Z] 21:29:00 INFO - PID 9940 | 'custom.webconsole.reload.settle.DAMP' took 14.14145206741523ms.
[task 2021-01-31T21:29:00.651Z] 21:29:00 INFO - PID 9940 | Close toolbox on 'custom.webconsole'
[task 2021-01-31T21:29:01.053Z] 21:29:01 INFO - PID 9940 | console.warn: "IGNORED REDUX ACTION:" "AUTOCOMPLETE_CLEAR"
[task 2021-01-31T21:29:01.093Z] 21:29:01 INFO - PID 9940 | Garbage collect
[task 2021-01-31T21:29:04.448Z] 21:29:04 INFO - PID 9940 | webconsole/custom.js took 25781ms.
[task 2021-01-31T21:29:04.448Z] 21:29:04 INFO - PID 9940 | Loading test 'inspector/custom.js'
[task 2021-01-31T21:29:04.448Z] 21:29:04 INFO - PID 9940 | Executing test 'inspector/custom.js'
[task 2021-01-31T21:29:04.453Z] 21:29:04 INFO - PID 9940 | Wait for a pageshow event for browsing context 718
[task 2021-01-31T21:34:04.459Z] 21:34:04 INFO - PID 9940 | TEST-UNEXPECTED-FAIL | damp | inspector/custom.js: Test timed out
[task 2021-01-31T21:34:04.464Z] 21:34:04 INFO - PID 9940 | [DampLoad helper] Unregister DampLoad actors
[task 2021-01-31T21:34:04.503Z] 21:34:04 INFO - Launcher process psutil.Process(pid=9940L, name='firefox.exe', started='21:18:21') detected. Terminating parent process psutil.Process(pid=6104, name='firefox.exe', started='21:18:21') instead.
[task 2021-01-31T21:34:04.503Z] 21:34:04 INFO - Terminating psutil.Process(pid=6104, name='firefox.exe', started='21:18:21')
[task 2021-01-31T21:34:04.513Z] 21:34:04 INFO - PID 9940 | Exiting due to channel error.
[task 2021-01-31T21:34:04.523Z] 21:34:04 INFO - PID 9940 | Exiting due to channel error.
[task 2021-01-31T21:34:04.744Z] 21:34:04 INFO - mozcrash checking c:\users\task_1612114300\appdata\local\temp\tmpofhes3\profile\minidumps for minidumps...
[task 2021-01-31T21:34:04.744Z] 21:34:04 INFO - TEST-UNEXPECTED-ERROR | damp | unexpected error
[task 2021-01-31T21:34:04.745Z] 21:34:04 ERROR - Traceback (most recent call last):
[task 2021-01-31T21:34:04.745Z] 21:34:04 INFO - File "C:\Users\task_1612114300\build\tests\talos\talos\run_tests.py", line 337, in run_tests
[task 2021-01-31T21:34:04.746Z] 21:34:04 INFO - talos_results.add(mytest.runTest(browser_config, test))
[task 2021-01-31T21:34:04.746Z] 21:34:04 INFO - File "C:\Users\task_1612114300\build\tests\talos\talos\ttest.py", line 65, in runTest
[task 2021-01-31T21:34:04.746Z] 21:34:04 INFO - return self._runTest(browser_config, test_config, setup)
[task 2021-01-31T21:34:04.746Z] 21:34:04 INFO - File "C:\Users\task_1612114300\build\tests\talos\talos\ttest.py", line 216, in _runTest
[task 2021-01-31T21:34:04.746Z] 21:34:04 INFO - debugger_args=browser_config["debugger_args"],
[task 2021-01-31T21:34:04.746Z] 21:34:04 INFO - File "C:\Users\task_1612114300\build\tests\talos\talos\talos_process.py", line 191, in run_browser
[task 2021-01-31T21:34:04.746Z] 21:34:04 INFO - raise TalosError("unexpected error")
[task 2021-01-31T21:34:04.746Z] 21:34:04 INFO - TalosError: unexpected error
[task 2021-01-31T21:34:04.746Z] 21:34:04 INFO - TEST-INFO took 3108217ms
[task 2021-01-31T21:34:04.747Z] 21:34:04 INFO - SUITE-END | took 3108s
[task 2021-01-31T21:34:04.836Z] 21:34:04 ERROR - Return code: 2
[task 2021-01-31T21:34:04.836Z] 21:34:04 WARNING - setting return code to 2
Description
•