Closed Bug 1641986 Opened 5 years ago Closed 5 years ago

Intermittent devtools/client/debugger/test/mochitest/browser_dbg-search-file.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. -

Categories

(DevTools :: Debugger, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=304293927&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FtxianMeR7OhkwUc3Qm2AA/runs/0/artifacts/public/logs/live_backing.log


INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-search-file.js | Search field is still visible - 
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - Buffered messages logged at 18:18:20
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-search-file.js | Search field focused - 
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - Leaving test bound 
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - Buffered messages logged at 18:18:33
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - Removing tab.
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - Buffered messages logged at 18:18:34
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - Tab removed and finished closing
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - Console message: [JavaScript Warning: "Empty string passed to getElementById()." {file: "chrome://global/content/elements/browser-custom-element.js" line: 411}]
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - Buffered messages logged at 18:18:36
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-search-file.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - Buffered messages finished
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-search-file.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. - 
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - GECKO(1263) | MEMORY STAT | vsize 20977748MB | residentFast 3275MB
[task 2020-05-29T18:18:37.280Z] 18:18:37     INFO - TEST-OK | devtools/client/debugger/test/mochitest/browser_dbg-search-file.js | took 658544ms
[task 2020-05-29T18:18:38.161Z] 18:18:38     INFO - checking window state
[task 2020-05-29T18:18:38.852Z] 18:18:38     INFO - TEST-START | devtools/client/debugger/test/mochitest/browser_dbg-search-project.js
[task 2020-05-29T18:18:53.423Z] 18:18:53     INFO - GECKO(1263) | [ACTION] SET_ORIENTATION  - {"type":"SET_ORIENTATION","orientation":"horizontal"}
[task 2020-05-29T18:18:54.492Z] 18:18:54     INFO - GECKO(1263) | [ACTION] UPDATE_EVENT_LISTENERS  - {"type":"UPDATE_EVENT_LISTENERS","active":[]}
[task 2020-05-29T18:18:59.350Z] 18:18:59     INFO - GECKO(1263) | [ACTION] RECEIVE_EVENT_LISTENER_TYPES  - {"type":"RECEIVE_EVENT_LISTENER_TYPES","categories":[{"name":"Animation","events":[{"id":"animationframe.request","name":"Request Animation Frame"},{"id":"animationframe.cancel","name":"Cancel Animation Frame"},{"id":"animationframe.fire","name":"Animation Frame fired"}]},{"name":"Clipboard","events":[{"id":"event.clipboard.copy","name":"copy"},{"id":"event.clipboard.cut","name":"cut"},{"id":"event.clipboard.paste","name":"paste"},{"id":"event.clipboard.beforecopy","name":"beforecopy"},{"id":"event.clipboard.beforecut","name":"beforecut"},{"id":"event.clipboard.beforepaste","name":"beforepaste"}]},{"name":"Control","events":[{"id":"event.control.resize","name":"resize"},{"id":"event.control.scroll","name":"scroll"},{"id":"event.control.zoom","name":"zoom"},{"id":"event.control.focus","name":"focus"},{"id":"event.control.blur","name":"blur"},{"id":"event.control.select","name":"select"},{"id":"event.control.change","name":"change"},{"id":"event.control.submit","name":"submit"},{"id":"event.control.reset","name":"reset"}]},{"name":"DOM Mutation","events":[{"id":"event.dom-mutation.DOMActivate","name":"DOMActivate"},{"id":"event.dom-mutation.DOMFocusIn","name":"DOMFocusIn"},{"id":"event.dom-mutation.DOMFocusOut","name":"DOMFocusOut"},{"id":"event.dom-mutation.DOMAttrModified","name":"DOMAttrModified"},{"id":"event.dom-mutation.DOMCharacterDataModified","name":"DOMCharacterDataModified"},{"id":"event.dom-mutation.DOMNodeInserted","name":"DOMNodeInserted"},{"id":"event.dom-mutation.DOMNodeInsertedIntoDocument","name":"DOMNodeInsertedIntoDocument"},{"id":"event.dom-mutation.DOMNodeRemoved","name":"DOMNodeRemoved"},{"id":"event.dom-mutation.DOMNodeRemovedIntoDocument","name":"DOMNodeRemovedIntoDocument"},{"id":"event.dom-mutation.DOMSubtreeModified","name":"DOMSubtreeModified"},{"id":"event.dom-mutation.DOMContentLoaded","name":"DOMContentLoaded"}]},{"name":"Device","events":[{"id":"event.device.deviceorientation","name":"deviceorientation"},{"id":"event.device.devicemotion","name":"devicemotion"}]},{"name":"Drag and Drop","events":[{"id":"event.drag-and-drop.drag","name":"drag"},{"id":"event.drag-and-drop.dragstart","name":"dragstart"},{"id":"event.drag-and-drop.dragend","name":"dragend"},{"id":"event.drag-and-drop.dragenter","name":"dragenter"},{"id":"event.drag-and-drop.dragover","name":"dragover"},{"id":"event.drag-and-drop.dragleave","name":"dragleave"},{"id":"event.drag-and-drop.drop","name":"drop"}]},{"name":"Keyboard","events":[{"id":"event.keyboard.input","name":"input"},{"id":"event.keyboard.keydown","name":"keydown"},{"id":"event.keyboard.keyup","name":"keyup"},{"id":"event.keyboard.keypress","name":"keypress"}]},{"name":"Load","events":[{"id":"event.load.load","name":"load"},{"id":"event.load.abort","name":"abort"},{"id":"event.load.error","name":"error"},{"id":"event.load.hashchange","name":"hashchange"},{"id":"event.load.popstate","name":"popstate"}]},{"name":"Media","events":[{"id":"event.media.play","name":"play"},{"id":"event.media.pause","name":"pause"},{"id":"event.media.playing","name":"playing"},{"id":"event.media.canplay","name":"canplay"},{"id":"event.media.canplaythrough","name":"canplaythrough"},{"id":"event.media.seeking","name":"seeking"},{"id":"event.media.seeked","name":"seeked"},{"id":"event.media.timeupdate","name":"timeupdate"},{"id":"event.media.ended","name":"ended"},{"id":"event.media.ratechange","name":"ratechange"},{"id":"event.media.durationchange","name":"durationchange"},{"id":"event.media.volumechange","name":"volumechange"},{"id":"event.media.loadstart","name":"loadstart"},{"id":"event.media.progress","name":"progress"},{"id":"event.media.suspend","name":"suspend"},{"id":"event.media.abort","name":"abort"},{"id":"event.media.error","name":"error"},{"id":"event.media.emptied","name":"emptied"},{"id":"event.media.stalled","name":"stalled"},{"id":"event.media.loadedmetadata","name":"loadedmetadata"},{"id":"event.media.loadeddata","name":"loadeddata"},{"id":"event.media.waiting","name":"waiting"}]},{"name":"Mouse","events":[{"id":"event.mouse.auxclick","name":"auxclick"},{"id":"event.mouse.click","name":"click"},{"id":"event.mouse.dblclick","name":"dblclick"},{"id":"event.mouse.mousedown","name":"mousedown"},{"id":"event.mouse.mouseup","name":"mouseup"},{"id":"event.mouse.mouseover","name":"mouseover"},{"id":"event.mouse.mousemove","name":"mousemove"},{"id":"event.mouse.mouseout","name":"mouseout"},{"id":"event.mouse.mouseenter","name":"mouseenter"},{"id":"event.mouse.mouseleave","name":"mouseleave"},{"id":"event.mouse.mousewheel","name":"mousewheel"},{"id":"event.mouse.wheel","name":"wheel"},{"id":"event.mouse.contextmenu","name":"contextmenu"}]},{"name":"Pointer","events":[{"id":"event.pointer.pointerover","name":"pointerover"},{"id":"event.pointer.pointerout","name":"pointerout"},{"id":"event.pointer.pointerenter","name":"pointerenter"},{"id":"event.pointer.pointerleave","name":"pointerleave"},{"id":"event.pointer.pointerdown","name":"pointerdown"},{"id":"event.pointer.pointerup","name":"pointerup"},{"id":"event.pointer.pointermove","name":"pointermove"},{"id":"event.pointer.pointercancel","name":"pointercancel"},{"id":"event.pointer.gotpointercapture","name":"gotpointercapture"},{"id":"event.pointer.lostpointercapture","name":"lostpointercapture"}]},{"name":"Timer","events":[{"id":"timer.timeout.set","name":"setTimeout"},{"id":"timer.timeout.clear","name":"clearTimeout"},{"id":"timer.timeout.fire","name":"setTimeout fired"},{"id":"timer.interval.set","name":"setInterval"},{"id":"timer.interval.clear","name":"clearInterval"},{"id":"timer.interval.fire","name":"setInterval fired"}]},{"name":"Touch","events":[{"id":"event.touch.touchstart","name":"touchstart"},{"id":"event.touch.touchmove","name":"touchmove"},{"id":"event.touch.touchend","name":"touchend"},{"id":"event.touch.touchcancel","name":"touchcancel"}]},{"name":"WebSocket","events":[{"id":"event.websocket.open","name":"open"},{"id":"event.websocket.message","name":"message"},{"id":"event.websocket.error","name":"error"},{"id":"event.websocket.close","name":"close"}]},{"name":"Worker","events":[{"id":"event.worker.message","name":"message"},{"id":"event.worker.messageerror","name":"messageerror"},{"id":"event.serviceworker.fetch","name":"fetch"}]},{"name":"XHR","events":[{"id":"event.xhr.readystatechange","name":"readystatechange"},{"id":"event.xhr.load","name":"load"},{"id":"event.xhr.loadstart","name":"loadstart"},{"id":"event.xhr.loadend","name":"loadend"},{"id":"event.xhr.abort","name":"abort"},{"id":"event.xhr.error","name":"error"},{"id":"event.xhr.progress","name":"progress"},{"id":"event.xhr.timeout","name":"timeout"}]}]}
[task 2020-05-29T18:19:32.374Z] 18:19:32     INFO - GECKO(1263) | [ACTION] REMOVE_SOURCE_ACTORS  - {"type":"REMOVE_SOURCE_ACTORS","items":[]}
[task 2020-05-29T18:19:32.377Z] 18:19:32     INFO - GECKO(1263) | [ACTION] REMOVE_THREADS  - {"type":"REMOVE_THREADS","cx":{"navigateCounter":0},"threads":[""]}
[task 2020-05-29T18:19:32.392Z] 18:19:32     INFO - GECKO(1263) | [ACTION] INSERT_THREADS  - {"type":"INSERT_THREADS","cx":{"navigateCounter":0},"threads":[{"actor":"server0.conn112.child2/thread22","url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-script-switching.html","type":"mainThread","name":"Debugger test page"}]}
[task 2020-05-29T18:19:35.072Z] 18:19:35     INFO - GECKO(1263) | [ACTION] ADD_SOURCES  - {"type":"ADD_SOURCES","cx":{"navigateCounter":0},"sources":[{"id":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/script-switching-01.js","url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/script-switching-01.js","relativeUrl":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/script-switching-01.js","isPrettyPrinted":false,"extensionName":null,"isBlackBoxed":false,"isWasm":false,"isExtension":false,"isOriginal":false},{"id":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/script-switching-02.js","url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/script-switching-02.js","relativeUrl":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/script-switching-02.js","isPrettyPrinted":false,"extensionName":null,"isBlackBoxed":false,"isWasm":false,"isExtension":false,"isOriginal":false}]}
[task 2020-05-29T18:19:35.661Z] 18:19:35     INFO - GECKO(1263) | [ACTION] INSERT_SOURCE_ACTORS  - {"type":"INSERT_SOURCE_ACTORS","items":[{"id":"server0.conn112.child2/source24","actor":"server0.conn112.child2/source24","thread":"server0.conn112.child2/thread22","source":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/script-switching-01.js","isBlackBoxed":false,"sourceMapBaseURL":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/script-switching-01.js","sourceMapURL":null,"url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/script-switching-01.js","introductionType":"scriptElement"},{"id":"server0.conn112.child2/source25","actor":"server0.conn112.child2/source25","thread":"server0.conn112.child2/thread22","source":"sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/script-switching-02.js","isBlackBoxed":false,"sourceMapBaseURL":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/script-switching-02.js","sourceMapURL":null,"url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/script-switching-02.js","introductionType":"scriptElement"}]}
[task 2020-05-29T18:19:35.683Z] 18:19:35     INFO - GECKO(1263) | [ACTION] CONNECT  - {"type":"CONNECT","mainThread":{"url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-script-switching.html","actor":"server0.conn112.child2/thread22","type":"mainThread","name":"Main Thread"},"traits":{"reconfigure":false,"frames":true,"logInPage":true,"watchpoints":true},"isWebExtension":false}
[task 2020-05-29T18:19:37.059Z] 18:19:37     INFO - GECKO(1263) | [ACTION] SET_EXPANDED_STATE  - {"type":"SET_EXPANDED_STATE","expanded":{}}
[task 2020-05-29T18:19:38.211Z] 18:19:38     INFO - GECKO(1263) | [ACTION] SET_EXPANDED_STATE  - {"type":"SET_EXPANDED_STATE","expanded":{}}
[task 2020-05-29T18:19:39.034Z] 18:19:38     INFO - GECKO(1263) | [ACTION] EVALUATE_EXPRESSIONS  - {"type":"EVALUATE_EXPRESSIONS","cx":{"navigateCounter":0,"thread":"server0.conn112.child2/thread22","isPaused":false,"pauseCounter":0},"inputs":[],"results":[]}
[task 2020-05-29T18:19:39.040Z] 18:19:39     INFO - GECKO(1263) | [ACTION] INSERT_THREADS  - {"type":"INSERT_THREADS","cx":{"navigateCounter":0},"threads":[{"actor":"server0.conn112.child2/thread22","url":"http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-script-switching.html","type":"mainThread","name":"Debugger test page"}]}
[task 2020-05-29T18:19:44.186Z] 18:19:44     INFO - GECKO(1263) | [ACTION] ADD_SOURCES  - {"type":"ADD_SOURCES","cx":{"navigateCounter":0},"sources":[]}
[task 2020-05-29T18:19:44.531Z] 18:19:44     INFO - GECKO(1263) | [ACTION] INSERT_SOURCE_ACTORS  - {"type":"INSERT_SOURCE_ACTORS","items":[]}
[task 2020-05-29T18:19:47.319Z] 18:19:47     INFO - GECKO(1263) | [ACTION] ADD_SOURCES  - {"type":"ADD_SOURCES","cx":{"navigateCounter":0},"sources":[]}
[task 2020-05-29T18:19:47.963Z] 18:19:47     INFO - GECKO(1263) | [ACTION] INSERT_SOURCE_ACTORS  - {"type":"INSERT_SOURCE_ACTORS","items":[]}
[task 2020-05-29T18:20:24.422Z] 18:20:24     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - Buffered messages logged at 18:18:38
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - Entering test bound 
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - Buffered messages logged at 18:18:39
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/debugger/test/mochitest/examples/doc-script-switching.html
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - Buffered messages logged at 18:18:44
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - Tab added and finished loading
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - Opening the toolbox
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - Buffered messages logged at 18:18:51
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 78}]
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - Buffered messages logged at 18:18:54
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 78}]
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - Buffered messages finished
[task 2020-05-29T18:20:24.432Z] 18:20:24     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-search-project.js | Test timed out - 
[task 2020-05-29T18:20:30.606Z] 18:20:30     INFO - GECKO(1263) | [ACTION] ADD_SOURCES  - {"type":"ADD_SOURCES","cx":{"navigateCounter":0},"sources":[]}
[task 2020-05-29T18:20:30.612Z] 18:20:30     INFO - GECKO(1263) | [ACTION] INSERT_SOURCE_ACTORS  - {"type":"INSERT_SOURCE_ACTORS","items":[]}
[task 2020-05-29T18:26:41.761Z] 18:26:41     INFO - Buffered messages finished
[task 2020-05-29T18:26:43.278Z] 18:26:41    ERROR - TEST-UNEXPECTED-TIMEOUT | devtools/client/debugger/test/mochitest/browser_dbg-search-project.js | application timed out after 370 seconds with no output
[task 2020-05-29T18:26:44.731Z] 18:26:43    ERROR - Force-terminating active process(es).
[task 2020-05-29T18:26:45.545Z] 18:26:44     INFO - Determining child pids from psutil...
[task 2020-05-29T18:26:47.248Z] 18:26:46     INFO - [1584, 1605, 1669, 1427, 1441]
[task 2020-05-29T18:26:47.435Z] 18:26:47     INFO - ==> process 1263 launched child process 1279
[task 2020-05-29T18:26:47.444Z] 18:26:47     INFO - ==> process 1263 launched child process 1427
[task 2020-05-29T18:26:47.444Z] 18:26:47     INFO - ==> process 1263 launched child process 1441
[task 2020-05-29T18:26:47.444Z] 18:26:47     INFO - ==> process 1263 launched child process 1584
[task 2020-05-29T18:26:47.444Z] 18:26:47     INFO - ==> process 1263 launched child process 1605
[task 2020-05-29T18:26:47.444Z] 18:26:47     INFO - ==> process 1263 launched child process 1669
[task 2020-05-29T18:26:47.444Z] 18:26:47     INFO - Found child pids: set([1441, 1605, 1584, 1669, 1427, 1279])
[task 2020-05-29T18:26:47.444Z] 18:26:47     INFO - Failed to get child procs
[task 2020-05-29T18:26:47.444Z] 18:26:47     INFO - Killing process: 1441
[task 2020-05-29T18:26:47.444Z] 18:26:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-29T18:26:47.444Z] 18:26:47     INFO - Can't trigger Breakpad, just killing process
[task 2020-05-29T18:27:17.392Z] 18:27:17     INFO - failed to kill pid 1441 after 30s
[task 2020-05-29T18:27:17.393Z] 18:27:17     INFO - Killing process: 1605
[task 2020-05-29T18:27:17.393Z] 18:27:17     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-29T18:27:17.393Z] 18:27:17     INFO - Can't trigger Breakpad, just killing process
[task 2020-05-29T18:27:47.391Z] 18:27:47     INFO - failed to kill pid 1605 after 30s
[task 2020-05-29T18:27:47.391Z] 18:27:47     INFO - Killing process: 1584
[task 2020-05-29T18:27:47.391Z] 18:27:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-29T18:27:47.391Z] 18:27:47     INFO - Can't trigger Breakpad, just killing process
[task 2020-05-29T18:28:17.400Z] 18:28:17     INFO - failed to kill pid 1584 after 30s
[task 2020-05-29T18:28:17.400Z] 18:28:17     INFO - Killing process: 1669
[task 2020-05-29T18:28:17.400Z] 18:28:17     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-29T18:28:17.401Z] 18:28:17     INFO - Can't trigger Breakpad, just killing process
[task 2020-05-29T18:28:47.414Z] 18:28:47     INFO - failed to kill pid 1669 after 30s
[task 2020-05-29T18:28:47.414Z] 18:28:47     INFO - Killing process: 1427
[task 2020-05-29T18:28:47.414Z] 18:28:47     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-29T18:28:47.414Z] 18:28:47     INFO - Can't trigger Breakpad, just killing process
[task 2020-05-29T18:29:17.415Z] 18:29:17     INFO - failed to kill pid 1427 after 30s
[task 2020-05-29T18:29:17.416Z] 18:29:17     INFO - Killing process: 1279
[task 2020-05-29T18:29:17.416Z] 18:29:17     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-29T18:29:17.416Z] 18:29:17     INFO - Can't trigger Breakpad, just killing process
[task 2020-05-29T18:29:17.416Z] 18:29:17     INFO - Error: Failed to kill process 1279: psutil.NoSuchProcess no process found with pid 1279
[task 2020-05-29T18:29:17.416Z] 18:29:17     INFO - Killing process: 1263
[task 2020-05-29T18:29:17.416Z] 18:29:17     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-29T18:29:17.416Z] 18:29:17     INFO - Can't trigger Breakpad, just killing process
[task 2020-05-29T18:29:47.426Z] 18:29:47     INFO - psutil found pid 1263 dead
[task 2020-05-29T18:29:47.427Z] 18:29:47     INFO - failed to kill pid 1605 after 30s
[task 2020-05-29T18:29:47.427Z] 18:29:47     INFO - failed to kill pid 1584 after 30s
[task 2020-05-29T18:29:47.427Z] 18:29:47     INFO - failed to kill pid 1669 after 30s
[task 2020-05-29T18:29:47.427Z] 18:29:47     INFO - failed to kill pid 1427 after 30s
[task 2020-05-29T18:29:47.427Z] 18:29:47     INFO - failed to kill pid 1441 after 30s
[task 2020-05-29T18:29:47.427Z] 18:29:47     INFO - failed to kill pid 1987 after 30s
[task 2020-05-29T18:29:47.428Z] 18:29:47     INFO - psutil found pid 1263 dead
[task 2020-05-29T18:29:47.448Z] 18:29:47  WARNING - runtests.py | Failed to get app exit code - running/crashed?
[task 2020-05-29T18:29:47.448Z] 18:29:47     INFO - TEST-INFO | Main app process: exit 0
[task 2020-05-29T18:29:47.448Z] 18:29:47     INFO - runtests.py | Application ran for: 0:35:21.061788
[task 2020-05-29T18:29:47.448Z] 18:29:47     INFO - zombiecheck | Reading PID log: /tmp/tmpxRraEdpidlog
[task 2020-05-29T18:29:47.448Z] 18:29:47     INFO - ==> process 1263 launched child process 1279
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - ==> process 1263 launched child process 1427
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - ==> process 1263 launched child process 1441
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - ==> process 1263 launched child process 1584
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - ==> process 1263 launched child process 1605
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - ==> process 1263 launched child process 1669
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - ==> process 1263 launched child process 1987
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - zombiecheck | Checking for orphan process with PID: 1441
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - zombiecheck | Checking for orphan process with PID: 1987
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - zombiecheck | Checking for orphan process with PID: 1669
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - zombiecheck | Checking for orphan process with PID: 1584
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - zombiecheck | Checking for orphan process with PID: 1427
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - zombiecheck | Checking for orphan process with PID: 1279
[task 2020-05-29T18:29:47.449Z] 18:29:47     INFO - zombiecheck | Checking for orphan process with PID: 1605
[task 2020-05-29T18:29:47.450Z] 18:29:47     INFO - Stopping web server
[task 2020-05-29T18:29:47.465Z] 18:29:47     INFO - Stopping web socket server
[task 2020-05-29T18:29:47.480Z] 18:29:47     INFO - Stopping ssltunnel
[task 2020-05-29T18:29:47.501Z] 18:29:47  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2020-05-29T18:29:47.501Z] 18:29:47     INFO - runtests.py | Running tests: end.
[task 2020-05-29T18:29:47.562Z] 18:29:47     INFO - Buffered messages finished
[task 2020-05-29T18:29:47.562Z] 18:29:47     INFO -  TEST-INFO | checking window state
[task 2020-05-29T18:29:47.562Z] 18:29:47     INFO -  Browser Chrome Test Summary
[task 2020-05-29T18:29:47.562Z] 18:29:47     INFO -  	Passed: 0
[task 2020-05-29T18:29:47.562Z] 18:29:47     INFO -  	Failed: 0
[task 2020-05-29T18:29:47.562Z] 18:29:47     INFO -  	Todo: 0
[task 2020-05-29T18:29:47.562Z] 18:29:47     INFO -  	Mode: e10s
[task 2020-05-29T18:29:47.562Z] 18:29:47     INFO -  *** End BrowserChrome Test Results ***
[task 2020-05-29T18:29:47.562Z] 18:29:47     INFO - Buffered messages finished
[task 2020-05-29T18:29:47.562Z] 18:29:47     INFO - SUITE-END | took 2122s
[task 2020-05-29T18:29:47.606Z] 18:29:47    ERROR - Return code: 1
[task 2020-05-29T18:29:47.608Z] 18:29:47    ERROR - Got 2 unexpected statuses
[task 2020-05-29T18:29:47.608Z] 18:29:47     INFO - TinderboxPrint: mochitest-mochitest-devtools-chrome<br/>124/<em class="testfail">2</em>/0
[task 2020-05-29T18:29:47.608Z] 18:29:47    ERROR - # TBPL FAILURE #
[task 2020-05-29T18:29:47.608Z] 18:29:47  WARNING - setting return code to 2
[task 2020-05-29T18:29:47.608Z] 18:29:47    ERROR - The mochitest suite: mochitest-devtools-chrome ran with return status: FAILURE
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.