Closed
Bug 1711022
Opened 4 years ago
Closed 4 years ago
Intermittent browser/components/extensions/test/browser/browser_ext_menus_events_after_context_destroy.js | Test timed out -
Categories
(WebExtensions :: Untriaged, defect, P5)
WebExtensions
Untriaged
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=339592680&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/dgOfip3-Sve4DEPQVWsvPA/runs/0/artifacts/public/logs/live_backing.log
[task 2021-05-13T10:03:56.146Z] 10:03:56 INFO - TEST-START | browser/components/extensions/test/browser/browser_ext_menus_events_after_context_destroy.js
[task 2021-05-13T10:04:02.119Z] 10:04:02 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f075b0e0800 == 9 [pid = 1551] [id = 183] [url = chrome://browser/content/browser.xhtml]
[task 2021-05-13T10:04:02.121Z] 10:04:02 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f0761f3d000 == 8 [pid = 1551] [id = 189] [url = chrome://browser/content/browser.xhtml]
[task 2021-05-13T10:04:02.124Z] 10:04:02 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f075b4c1c00 == 7 [pid = 1551] [id = 185] [url = chrome://browser/content/browser.xhtml]
[task 2021-05-13T10:04:02.126Z] 10:04:02 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f075d114800 == 6 [pid = 1551] [id = 187] [url = chrome://browser/content/browser.xhtml]
[task 2021-05-13T10:04:02.126Z] 10:04:02 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f075aa6d000 == 5 [pid = 1551] [id = 181] [url = chrome://browser/content/browser.xhtml]
[task 2021-05-13T10:04:03.329Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 37 (7f075b59f580) [pid = 1551] [serial = 464] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:03.330Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 36 (7f0762134ac0) [pid = 1551] [serial = 460] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:03.331Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 35 (7f075d1e6740) [pid = 1551] [serial = 448] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:03.332Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 34 (7f0771ff7040) [pid = 1551] [serial = 444] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:03.333Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 33 (7f0771a22c80) [pid = 1551] [serial = 440] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:03.333Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 32 (7f075b59f740) [pid = 1551] [serial = 456] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:03.334Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 31 (7f0766a46c80) [pid = 1551] [serial = 452] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:03.335Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 30 (7f0771a22200) [pid = 1551] [serial = 438] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2021-05-13T10:04:03.336Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 29 (7f0762134c80) [pid = 1551] [serial = 458] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2021-05-13T10:04:03.337Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 28 (7f0766a6c740) [pid = 1551] [serial = 446] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2021-05-13T10:04:03.337Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 27 (7f075d1e6580) [pid = 1551] [serial = 454] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2021-05-13T10:04:03.338Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 26 (7f0766a6c900) [pid = 1551] [serial = 462] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2021-05-13T10:04:03.339Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 25 (7f0771a22e40) [pid = 1551] [serial = 442] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2021-05-13T10:04:03.341Z] 10:04:03 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 24 (7f0766a45e40) [pid = 1551] [serial = 450] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2021-05-13T10:04:06.682Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 23 (7f075b0ebc00) [pid = 1551] [serial = 451] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.683Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 22 (7f075ba2e800) [pid = 1551] [serial = 443] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.684Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 21 (7f0761fe8000) [pid = 1551] [serial = 463] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.685Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 20 (7f075b4c2400) [pid = 1551] [serial = 455] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.686Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 19 (7f075aa6f400) [pid = 1551] [serial = 447] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.687Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 18 (7f075d133800) [pid = 1551] [serial = 459] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.688Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 17 (7f075d114c00) [pid = 1551] [serial = 439] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.689Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (7f076377a000) [pid = 1551] [serial = 453] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.689Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (7f075ba2f400) [pid = 1551] [serial = 457] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.690Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (7f075d132000) [pid = 1551] [serial = 441] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.693Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (7f075d131400) [pid = 1551] [serial = 445] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.693Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (7f075aa6a400) [pid = 1551] [serial = 449] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.693Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (7f075d134c00) [pid = 1551] [serial = 461] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:06.694Z] 10:04:06 INFO - GECKO(1551) | [Parent 1551: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f075b0e3c00) [pid = 1551] [serial = 465] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:23.956Z] 10:04:23 INFO - GECKO(1551) | [Child 1634: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7fc9399e1800 == 4 [pid = 1634] [id = 331] [url = moz-extension://d601d611-0606-4a4d-aab2-e5e92b9916a6/_generated_background_page.html]
[task 2021-05-13T10:04:23.957Z] 10:04:23 INFO - GECKO(1551) | [Child 1634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (7fc9399ea400) [pid = 1634] [serial = 916] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:23.958Z] 10:04:23 INFO - GECKO(1551) | [Child 1634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 13 (7fc93ac3fc00) [pid = 1634] [serial = 917] [outer = 0] [url = moz-extension://f5c25422-0f31-4362-9e58-558cf77858ed/_generated_background_page.html]
[task 2021-05-13T10:04:23.959Z] 10:04:23 INFO - GECKO(1551) | [Child 1634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 12 (7fc9399e0400) [pid = 1634] [serial = 919] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:23.959Z] 10:04:23 INFO - GECKO(1551) | [Child 1634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 11 (7fc9399eac00) [pid = 1634] [serial = 920] [outer = 0] [url = moz-extension://c392ef32-6a34-49df-a678-8b252d75745c/_generated_background_page.html]
[task 2021-05-13T10:04:25.316Z] 10:04:25 INFO - GECKO(1551) | [Child 1634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7fc9458b9200) [pid = 1634] [serial = 921] [outer = 0] [url = moz-extension://d601d611-0606-4a4d-aab2-e5e92b9916a6/_generated_background_page.html]
[task 2021-05-13T10:04:35.745Z] 10:04:35 INFO - GECKO(1551) | [Child 1634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (7fc93ac3c800) [pid = 1634] [serial = 923] [outer = 0] [url = moz-extension://d601d611-0606-4a4d-aab2-e5e92b9916a6/_generated_background_page.html]
[task 2021-05-13T10:04:35.746Z] 10:04:35 INFO - GECKO(1551) | [Child 1634: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (7fc9399e3800) [pid = 1634] [serial = 922] [outer = 0] [url = about:blank]
[task 2021-05-13T10:04:53.260Z] 10:04:53 INFO - GECKO(1551) | 1620900293258 addons.xpi ERROR System addon update list error Error: got node name: html, expected: updates
[task 2021-05-13T10:06:56.157Z] 10:06:56 INFO - TEST-INFO | started process screentopng
[task 2021-05-13T10:06:56.306Z] 10:06:56 INFO - TEST-INFO | screentopng: exit 0
[task 2021-05-13T10:06:56.308Z] 10:06:56 INFO - Buffered messages logged at 10:03:56
[task 2021-05-13T10:06:56.308Z] 10:06:56 INFO - Entering test bound test_subscribed_events_fired_after_context_destroy
[task 2021-05-13T10:06:56.309Z] 10:06:56 INFO - Extension loaded
[task 2021-05-13T10:06:56.310Z] 10:06:56 INFO - Console message: Warning: attempting to write 16620 bytes to preference extensions.webextensions.uuids. This is bad for general performance and memory usage. Such an amount of data should rather be written to an external file. This preference will not be sent to any content processes.
[task 2021-05-13T10:06:56.310Z] 10:06:56 INFO - Loading extension page in a tab
[task 2021-05-13T10:06:56.311Z] 10:06:56 INFO - Buffered messages logged at 10:04:53
[task 2021-05-13T10:06:56.313Z] 10:06:56 INFO - Console message: [JavaScript Error: "1620900293258 addons.xpi ERROR System addon update list error Error: got node name: html, expected: updates" {file: "resource://gre/modules/Log.jsm" line: 723}]
[task 2021-05-13T10:06:56.313Z] 10:06:56 INFO - append@resource://gre/modules/Log.jsm:723:12
[task 2021-05-13T10:06:56.314Z] 10:06:56 INFO - log@resource://gre/modules/Log.jsm:379:16
[task 2021-05-13T10:06:56.314Z] 10:06:56 INFO - error@resource://gre/modules/Log.jsm:387:10
[task 2021-05-13T10:06:56.314Z] 10:06:56 INFO - updateSystemAddons/res<@resource://gre/modules/addons/XPIInstall.jsm:4024:25
[task 2021-05-13T10:06:56.315Z] 10:06:56 INFO -
[task 2021-05-13T10:06:56.315Z] 10:06:56 INFO - Buffered messages finished
[task 2021-05-13T10:06:56.316Z] 10:06:56 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_menus_events_after_context_destroy.js | Test timed out -
[task 2021-05-13T10:06:56.317Z] 10:06:56 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:06:56.318Z] 10:06:56 INFO - TEST-UNEXPECTED-FAIL | browser/components/extensions/test/browser/browser_ext_menus_events_after_context_destroy.js | Extension left running at test shutdown -
[task 2021-05-13T10:06:56.318Z] 10:06:56 INFO - Stack trace:
[task 2021-05-13T10:06:56.318Z] 10:06:56 INFO - chrome://mochikit/content/browser-test.js:test_ok:1334
[task 2021-05-13T10:06:56.318Z] 10:06:56 INFO - chrome://mochikit/content/tests/SimpleTest/ExtensionTestUtils.js:ExtensionTestUtils.loadExtension/<:117
[task 2021-05-13T10:06:56.319Z] 10:06:56 INFO - chrome://mochikit/content/browser-test.js:nextTest:564
[task 2021-05-13T10:07:04.165Z] 10:07:04 INFO - Console message: [JavaScript Error: "Timeout while waiting for extension child to shutdown: "Generated extension" (ID: {25efa39c-7eb3-41d6-bb26-3bcd0e7ca35b}, moz-extension://928e6acd-97eb-4af8-9481-29f15d7faa23/)" {file: "resource://gre/modules/Extension.jsm" line: 2789}]
[task 2021-05-13T10:07:04.165Z] 10:07:04 INFO - shutdown@resource://gre/modules/Extension.jsm:2789:10
[task 2021-05-13T10:07:04.165Z] 10:07:04 INFO -
[task 2021-05-13T10:13:14.179Z] 10:13:14 INFO - Buffered messages finished
[task 2021-05-13T10:13:14.179Z] 10:13:14 ERROR - TEST-UNEXPECTED-TIMEOUT | browser/components/extensions/test/browser/browser_ext_menus_events_after_context_destroy.js | application timed out after 370 seconds with no output
[task 2021-05-13T10:13:14.179Z] 10:13:14 ERROR - Force-terminating active process(es).
[task 2021-05-13T10:13:14.179Z] 10:13:14 INFO - Determining child pids from psutil...
[task 2021-05-13T10:13:14.196Z] 10:13:14 INFO - [1612, 1634, 1693, 1697, 1705, 1707, 1791, 1813, 1852, 1871, 1900, 1949, 1968, 2002, 2033, 2057, 2058, 2110, 2123, 2156, 2182, 2215, 2244, 2253, 2296, 2328, 2357, 2386, 2423, 2428, 2477, 2506, 2535, 2562, 2599, 2607, 2651, 2681, 2686, 2742, 2773, 2794, 2829, 2856, 2882, 2907, 2932, 2958, 2990, 3027, 3058, 3061, 3103, 3110, 3153, 3179, 3181, 3232, 3236, 3278, 3303, 3334, 3349, 3380, 3405, 3439, 3464, 3489, 3515, 3540, 3565, 3590, 3615, 3646, 3671, 3693, 3718, 3749, 3762, 3799, 3824, 3849, 3874, 3899, 3928, 3957, 3982, 4013, 4052, 4071, 4092, 4129, 4149, 4197, 4216, 4245, 4274, 4303, 4332, 4361, 4392, 4406, 4448, 4477, 4506, 4535, 4564, 4595, 4611, 4659, 4694]
[task 2021-05-13T10:13:14.197Z] 10:13:14 INFO - ==> process 1551 launched child process 1571
[task 2021-05-13T10:13:14.197Z] 10:13:14 INFO - ==> process 1551 launched child process 1612
[task 2021-05-13T10:13:14.197Z] 10:13:14 INFO - ==> process 1551 launched child process 1634
[task 2021-05-13T10:13:14.197Z] 10:13:14 INFO - ==> process 1551 launched child process 1693
[task 2021-05-13T10:13:14.197Z] 10:13:14 INFO - ==> process 1551 launched child process 1697
[task 2021-05-13T10:13:14.197Z] 10:13:14 INFO - ==> process 1551 launched child process 1705
[task 2021-05-13T10:13:14.197Z] 10:13:14 INFO - ==> process 1551 launched child process 1707
[task 2021-05-13T10:13:14.198Z] 10:13:14 INFO - ==> process 1551 launched child process 1791
[task 2021-05-13T10:13:14.198Z] 10:13:14 INFO - ==> process 1551 launched child process 1813
[task 2021-05-13T10:13:14.198Z] 10:13:14 INFO - ==> process 1551 launched child process 1852
[task 2021-05-13T10:13:14.198Z] 10:13:14 INFO - ==> process 1551 launched child process 1871
[task 2021-05-13T10:13:14.198Z] 10:13:14 INFO - ==> process 1551 launched child process 1900
[task 2021-05-13T10:13:14.198Z] 10:13:14 INFO - ==> process 1551 launched child process 1949
[task 2021-05-13T10:13:14.198Z] 10:13:14 INFO - ==> process 1551 launched child process 1968
[task 2021-05-13T10:13:14.198Z] 10:13:14 INFO - ==> process 1551 launched child process 2002
[task 2021-05-13T10:13:14.198Z] 10:13:14 INFO - ==> process 1551 launched child process 2033
[task 2021-05-13T10:13:14.198Z] 10:13:14 INFO - ==> process 1551 launched child process 2057
[task 2021-05-13T10:13:14.198Z] 10:13:14 INFO - ==> process 1551 launched child process 2058
[task 2021-05-13T10:13:14.198Z] 10:13:14 INFO - ==> process 1551 launched child process 2110
[task 2021-05-13T10:13:14.199Z] 10:13:14 INFO - ==> process 1551 launched child process 2123
[task 2021-05-13T10:13:14.199Z] 10:13:14 INFO - ==> process 1551 launched child process 2156
[task 2021-05-13T10:13:14.199Z] 10:13:14 INFO - ==> process 1551 launched child process 2182
[task 2021-05-13T10:13:14.199Z] 10:13:14 INFO - ==> process 1551 launched child process 2215
[task 2021-05-13T10:13:14.199Z] 10:13:14 INFO - ==> process 1551 launched child process 2244
[task 2021-05-13T10:13:14.199Z] 10:13:14 INFO - ==> process 1551 launched child process 2253
[task 2021-05-13T10:13:14.200Z] 10:13:14 INFO - ==> process 1551 launched child process 2296
[task 2021-05-13T10:13:14.200Z] 10:13:14 INFO - ==> process 1551 launched child process 2328
[task 2021-05-13T10:13:14.201Z] 10:13:14 INFO - ==> process 1551 launched child process 2357
[task 2021-05-13T10:13:14.202Z] 10:13:14 INFO - ==> process 1551 launched child process 2386
[task 2021-05-13T10:13:14.202Z] 10:13:14 INFO - ==> process 1551 launched child process 2423
[task 2021-05-13T10:13:14.203Z] 10:13:14 INFO - ==> process 1551 launched child process 2428
[task 2021-05-13T10:13:14.204Z] 10:13:14 INFO - ==> process 1551 launched child process 2477
[task 2021-05-13T10:13:14.204Z] 10:13:14 INFO - ==> process 1551 launched child process 2506
[task 2021-05-13T10:13:14.205Z] 10:13:14 INFO - ==> process 1551 launched child process 2535
[task 2021-05-13T10:13:14.206Z] 10:13:14 INFO - ==> process 1551 launched child process 2562
[task 2021-05-13T10:13:14.206Z] 10:13:14 INFO - ==> process 1551 launched child process 2599
[task 2021-05-13T10:13:14.207Z] 10:13:14 INFO - ==> process 1551 launched child process 2607
[task 2021-05-13T10:13:14.208Z] 10:13:14 INFO - ==> process 1551 launched child process 2651
[task 2021-05-13T10:13:14.209Z] 10:13:14 INFO - ==> process 1551 launched child process 2681
[task 2021-05-13T10:13:14.210Z] 10:13:14 INFO - ==> process 1551 launched child process 2686
[task 2021-05-13T10:13:14.212Z] 10:13:14 INFO - ==> process 1551 launched child process 2742
[task 2021-05-13T10:13:14.213Z] 10:13:14 INFO - ==> process 1551 launched child process 2773
[task 2021-05-13T10:13:14.214Z] 10:13:14 INFO - ==> process 1551 launched child process 2794
[task 2021-05-13T10:13:14.215Z] 10:13:14 INFO - ==> process 1551 launched child process 2829
[task 2021-05-13T10:13:14.216Z] 10:13:14 INFO - ==> process 1551 launched child process 2856
[task 2021-05-13T10:13:14.217Z] 10:13:14 INFO - ==> process 1551 launched child process 2882
[task 2021-05-13T10:13:14.218Z] 10:13:14 INFO - ==> process 1551 launched child process 2907
[task 2021-05-13T10:13:14.219Z] 10:13:14 INFO - ==> process 1551 launched child process 2932
[task 2021-05-13T10:13:14.220Z] 10:13:14 INFO - ==> process 1551 launched child process 2958
[task 2021-05-13T10:13:14.221Z] 10:13:14 INFO - ==> process 1551 launched child process 2990
[task 2021-05-13T10:13:14.222Z] 10:13:14 INFO - ==> process 1551 launched child process 3027
[task 2021-05-13T10:13:14.223Z] 10:13:14 INFO - ==> process 1551 launched child process 3058
[task 2021-05-13T10:13:14.224Z] 10:13:14 INFO - ==> process 1551 launched child process 3061
[task 2021-05-13T10:13:14.225Z] 10:13:14 INFO - ==> process 1551 launched child process 3103
[task 2021-05-13T10:13:14.226Z] 10:13:14 INFO - ==> process 1551 launched child process 3110
[task 2021-05-13T10:13:14.227Z] 10:13:14 INFO - ==> process 1551 launched child process 3153
[task 2021-05-13T10:13:14.228Z] 10:13:14 INFO - ==> process 1551 launched child process 3179
[task 2021-05-13T10:13:14.229Z] 10:13:14 INFO - ==> process 1551 launched child process 3181
[task 2021-05-13T10:13:14.230Z] 10:13:14 INFO - ==> process 1551 launched child process 3232
[task 2021-05-13T10:13:14.231Z] 10:13:14 INFO - ==> process 1551 launched child process 3236
[task 2021-05-13T10:13:14.232Z] 10:13:14 INFO - ==> process 1551 launched child process 3278
[task 2021-05-13T10:13:14.233Z] 10:13:14 INFO - ==> process 1551 launched child process 3303
[task 2021-05-13T10:13:14.234Z] 10:13:14 INFO - ==> process 1551 launched child process 3334
[task 2021-05-13T10:13:14.235Z] 10:13:14 INFO - ==> process 1551 launched child process 3349
[task 2021-05-13T10:13:14.236Z] 10:13:14 INFO - ==> process 1551 launched child process 3380
[task 2021-05-13T10:13:14.237Z] 10:13:14 INFO - ==> process 1551 launched child process 3405
[task 2021-05-13T10:13:14.238Z] 10:13:14 INFO - ==> process 1551 launched child process 3439
[task 2021-05-13T10:13:14.239Z] 10:13:14 INFO - ==> process 1551 launched child process 3464
[task 2021-05-13T10:13:14.240Z] 10:13:14 INFO - ==> process 1551 launched child process 3489
[task 2021-05-13T10:13:14.240Z] 10:13:14 INFO - ==> process 1551 launched child process 3515
[task 2021-05-13T10:13:14.241Z] 10:13:14 INFO - ==> process 1551 launched child process 3540
[task 2021-05-13T10:13:14.242Z] 10:13:14 INFO - ==> process 1551 launched child process 3565
[task 2021-05-13T10:13:14.243Z] 10:13:14 INFO - ==> process 1551 launched child process 3590
[task 2021-05-13T10:13:14.244Z] 10:13:14 INFO - ==> process 1551 launched child process 3615
[task 2021-05-13T10:13:14.244Z] 10:13:14 INFO - ==> process 1551 launched child process 3646
[task 2021-05-13T10:13:14.244Z] 10:13:14 INFO - ==> process 1551 launched child process 3671
[task 2021-05-13T10:13:14.245Z] 10:13:14 INFO - ==> process 1551 launched child process 3693
[task 2021-05-13T10:13:14.245Z] 10:13:14 INFO - ==> process 1551 launched child process 3718
[task 2021-05-13T10:13:14.246Z] 10:13:14 INFO - ==> process 1551 launched child process 3749
[task 2021-05-13T10:13:14.246Z] 10:13:14 INFO - ==> process 1551 launched child process 3762
[task 2021-05-13T10:13:14.247Z] 10:13:14 INFO - ==> process 1551 launched child process 3799
[task 2021-05-13T10:13:14.247Z] 10:13:14 INFO - ==> process 1551 launched child process 3824
[task 2021-05-13T10:13:14.248Z] 10:13:14 INFO - ==> process 1551 launched child process 3849
[task 2021-05-13T10:13:14.248Z] 10:13:14 INFO - ==> process 1551 launched child process 3874
[task 2021-05-13T10:13:14.248Z] 10:13:14 INFO - ==> process 1551 launched child process 3899
[task 2021-05-13T10:13:14.249Z] 10:13:14 INFO - ==> process 1551 launched child process 3928
[task 2021-05-13T10:13:14.249Z] 10:13:14 INFO - ==> process 1551 launched child process 3957
[task 2021-05-13T10:13:14.250Z] 10:13:14 INFO - ==> process 1551 launched child process 3982
[task 2021-05-13T10:13:14.250Z] 10:13:14 INFO - ==> process 1551 launched child process 4013
[task 2021-05-13T10:13:14.251Z] 10:13:14 INFO - ==> process 1551 launched child process 4052
[task 2021-05-13T10:13:14.251Z] 10:13:14 INFO - ==> process 1551 launched child process 4071
[task 2021-05-13T10:13:14.251Z] 10:13:14 INFO - ==> process 1551 launched child process 4092
[task 2021-05-13T10:13:14.252Z] 10:13:14 INFO - ==> process 1551 launched child process 4129
[task 2021-05-13T10:13:14.252Z] 10:13:14 INFO - ==> process 1551 launched child process 4149
[task 2021-05-13T10:13:14.253Z] 10:13:14 INFO - ==> process 1551 launched child process 4197
[task 2021-05-13T10:13:14.253Z] 10:13:14 INFO - ==> process 1551 launched child process 4216
[task 2021-05-13T10:13:14.254Z] 10:13:14 INFO - ==> process 1551 launched child process 4245
[task 2021-05-13T10:13:14.254Z] 10:13:14 INFO - ==> process 1551 launched child process 4274
[task 2021-05-13T10:13:14.255Z] 10:13:14 INFO - ==> process 1551 launched child process 4303
[task 2021-05-13T10:13:14.255Z] 10:13:14 INFO - ==> process 1551 launched child process 4332
[task 2021-05-13T10:13:14.255Z] 10:13:14 INFO - ==> process 1551 launched child process 4361
[task 2021-05-13T10:13:14.256Z] 10:13:14 INFO - ==> process 1551 launched child process 4392
[task 2021-05-13T10:13:14.256Z] 10:13:14 INFO - ==> process 1551 launched child process 4406
[task 2021-05-13T10:13:14.257Z] 10:13:14 INFO - ==> process 1551 launched child process 4448
[task 2021-05-13T10:13:14.257Z] 10:13:14 INFO - ==> process 1551 launched child process 4477
[task 2021-05-13T10:13:14.258Z] 10:13:14 INFO - ==> process 1551 launched child process 4506
[task 2021-05-13T10:13:14.258Z] 10:13:14 INFO - ==> process 1551 launched child process 4535
[task 2021-05-13T10:13:14.258Z] 10:13:14 INFO - ==> process 1551 launched child process 4564
[task 2021-05-13T10:13:14.259Z] 10:13:14 INFO - ==> process 1551 launched child process 4595
[task 2021-05-13T10:13:14.259Z] 10:13:14 INFO - ==> process 1551 launched child process 4611
[task 2021-05-13T10:13:14.260Z] 10:13:14 INFO - ==> process 1551 launched child process 4659
[task 2021-05-13T10:13:14.260Z] 10:13:14 INFO - ==> process 1551 launched child process 4694
[task 2021-05-13T10:13:14.261Z] 10:13:14 INFO - Found child pids: {2562, 4611, 3590, 2057, 2058, 3103, 3615, 4129, 1571, 3110, 2599, 2607, 4659, 4149, 2110, 3646, 2123, 1612, 3153, 4694, 3671, 2651, 1634, 4197, 3179, 2156, 3181, 3693, 4216, 2681, 2686, 2182, 3718, 4245, 1693, 3232, 1697, 3236, 3749, 2215, 1705, 1707, 3762, 4274, 2742, 2244, 2253, 3278, 4303, 2773, 3799, 3303, 2794, 4332, 3824, 2296, 1791, 3334, 3849, 4361, 2829, 1813, 3349, 2328, 3874, 2856, 4392, 3380, 2357, 4406, 3899, 1852, 2882, 3405, 1871, 2386, 3928, 2907, 4448, 1900, 3439, 2932, 3957, 2423, 2428, 4477, 3464, 2958, 3982, 4506, 1949, 3489, 2477, 2990, 4013, 1968, 4535, 3515, 2506, 2002, 3027, 3540, 4052, 4564, 2535, 4071, 3565, 2033, 3058, 4595, 3061, 4092}
[task 2021-05-13T10:13:14.262Z] 10:13:14 INFO - Failed to get child procs
[task 2021-05-13T10:13:14.262Z] 10:13:14 INFO - Killing process: 2562
[task 2021-05-13T10:13:14.263Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.263Z] 10:13:14 INFO - Killing process: 4611
[task 2021-05-13T10:13:14.263Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.264Z] 10:13:14 INFO - Killing process: 3590
[task 2021-05-13T10:13:14.264Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.265Z] 10:13:14 INFO - Killing process: 2057
[task 2021-05-13T10:13:14.265Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.266Z] 10:13:14 INFO - Killing process: 2058
[task 2021-05-13T10:13:14.266Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.266Z] 10:13:14 INFO - Killing process: 3103
[task 2021-05-13T10:13:14.267Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.267Z] 10:13:14 INFO - Killing process: 3615
[task 2021-05-13T10:13:14.268Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.268Z] 10:13:14 INFO - Killing process: 4129
[task 2021-05-13T10:13:14.269Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.269Z] 10:13:14 INFO - Killing process: 1571
[task 2021-05-13T10:13:14.269Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.270Z] 10:13:14 INFO - Can't trigger Breakpad, process no longer exists
[task 2021-05-13T10:13:14.270Z] 10:13:14 INFO - Killing process: 3110
[task 2021-05-13T10:13:14.271Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.271Z] 10:13:14 INFO - Killing process: 2599
[task 2021-05-13T10:13:14.272Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.272Z] 10:13:14 INFO - Killing process: 2607
[task 2021-05-13T10:13:14.272Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.273Z] 10:13:14 INFO - Killing process: 4659
[task 2021-05-13T10:13:14.273Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.274Z] 10:13:14 INFO - Killing process: 4149
[task 2021-05-13T10:13:14.274Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.275Z] 10:13:14 INFO - Killing process: 2110
[task 2021-05-13T10:13:14.275Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.275Z] 10:13:14 INFO - Killing process: 3646
[task 2021-05-13T10:13:14.276Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.276Z] 10:13:14 INFO - Killing process: 2123
[task 2021-05-13T10:13:14.277Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.277Z] 10:13:14 INFO - Killing process: 1612
[task 2021-05-13T10:13:14.278Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.278Z] 10:13:14 INFO - Killing process: 3153
[task 2021-05-13T10:13:14.278Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.279Z] 10:13:14 INFO - Killing process: 4694
[task 2021-05-13T10:13:14.279Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.280Z] 10:13:14 INFO - Killing process: 3671
[task 2021-05-13T10:13:14.280Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.281Z] 10:13:14 INFO - Killing process: 2651
[task 2021-05-13T10:13:14.281Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.281Z] 10:13:14 INFO - Killing process: 1634
[task 2021-05-13T10:13:14.282Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.282Z] 10:13:14 INFO - Killing process: 4197
[task 2021-05-13T10:13:14.283Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.283Z] 10:13:14 INFO - Killing process: 3179
[task 2021-05-13T10:13:14.284Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.284Z] 10:13:14 INFO - Killing process: 2156
[task 2021-05-13T10:13:14.284Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.285Z] 10:13:14 INFO - Killing process: 3181
[task 2021-05-13T10:13:14.285Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.286Z] 10:13:14 INFO - Killing process: 3693
[task 2021-05-13T10:13:14.286Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.287Z] 10:13:14 INFO - Killing process: 4216
[task 2021-05-13T10:13:14.287Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.287Z] 10:13:14 INFO - Killing process: 2681
[task 2021-05-13T10:13:14.288Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.288Z] 10:13:14 INFO - Killing process: 2686
[task 2021-05-13T10:13:14.289Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.289Z] 10:13:14 INFO - Killing process: 2182
[task 2021-05-13T10:13:14.290Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.290Z] 10:13:14 INFO - Killing process: 3718
[task 2021-05-13T10:13:14.290Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.291Z] 10:13:14 INFO - Killing process: 4245
[task 2021-05-13T10:13:14.291Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.292Z] 10:13:14 INFO - Killing process: 1693
[task 2021-05-13T10:13:14.292Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.293Z] 10:13:14 INFO - Killing process: 3232
[task 2021-05-13T10:13:14.293Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.293Z] 10:13:14 INFO - Killing process: 1697
[task 2021-05-13T10:13:14.294Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.294Z] 10:13:14 INFO - Killing process: 3236
[task 2021-05-13T10:13:14.295Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.295Z] 10:13:14 INFO - Killing process: 3749
[task 2021-05-13T10:13:14.295Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.296Z] 10:13:14 INFO - Killing process: 2215
[task 2021-05-13T10:13:14.296Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.297Z] 10:13:14 INFO - Killing process: 1705
[task 2021-05-13T10:13:14.297Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.298Z] 10:13:14 INFO - Killing process: 1707
[task 2021-05-13T10:13:14.298Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.299Z] 10:13:14 INFO - Killing process: 3762
[task 2021-05-13T10:13:14.299Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.300Z] 10:13:14 INFO - Killing process: 4274
[task 2021-05-13T10:13:14.301Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.301Z] 10:13:14 INFO - Killing process: 2742
[task 2021-05-13T10:13:14.302Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.303Z] 10:13:14 INFO - Killing process: 2244
[task 2021-05-13T10:13:14.303Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.304Z] 10:13:14 INFO - Killing process: 2253
[task 2021-05-13T10:13:14.305Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.305Z] 10:13:14 INFO - Killing process: 3278
[task 2021-05-13T10:13:14.306Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.307Z] 10:13:14 INFO - Killing process: 4303
[task 2021-05-13T10:13:14.308Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.308Z] 10:13:14 INFO - Killing process: 2773
[task 2021-05-13T10:13:14.309Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.310Z] 10:13:14 INFO - Killing process: 3799
[task 2021-05-13T10:13:14.310Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.311Z] 10:13:14 INFO - Killing process: 3303
[task 2021-05-13T10:13:14.312Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.313Z] 10:13:14 INFO - Killing process: 2794
[task 2021-05-13T10:13:14.313Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.314Z] 10:13:14 INFO - Killing process: 4332
[task 2021-05-13T10:13:14.315Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.315Z] 10:13:14 INFO - Killing process: 3824
[task 2021-05-13T10:13:14.316Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.317Z] 10:13:14 INFO - Killing process: 2296
[task 2021-05-13T10:13:14.318Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.318Z] 10:13:14 INFO - Killing process: 1791
[task 2021-05-13T10:13:14.319Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.320Z] 10:13:14 INFO - Killing process: 3334
[task 2021-05-13T10:13:14.321Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.322Z] 10:13:14 INFO - Killing process: 3849
[task 2021-05-13T10:13:14.323Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.323Z] 10:13:14 INFO - Killing process: 4361
[task 2021-05-13T10:13:14.324Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.325Z] 10:13:14 INFO - Killing process: 2829
[task 2021-05-13T10:13:14.326Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.326Z] 10:13:14 INFO - Killing process: 1813
[task 2021-05-13T10:13:14.327Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.328Z] 10:13:14 INFO - Killing process: 3349
[task 2021-05-13T10:13:14.328Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.329Z] 10:13:14 INFO - Killing process: 2328
[task 2021-05-13T10:13:14.330Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.330Z] 10:13:14 INFO - Killing process: 3874
[task 2021-05-13T10:13:14.331Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.332Z] 10:13:14 INFO - Killing process: 2856
[task 2021-05-13T10:13:14.333Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.333Z] 10:13:14 INFO - Killing process: 4392
[task 2021-05-13T10:13:14.334Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.335Z] 10:13:14 INFO - Killing process: 3380
[task 2021-05-13T10:13:14.335Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.336Z] 10:13:14 INFO - Killing process: 2357
[task 2021-05-13T10:13:14.337Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.338Z] 10:13:14 INFO - Killing process: 4406
[task 2021-05-13T10:13:14.338Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.339Z] 10:13:14 INFO - Killing process: 3899
[task 2021-05-13T10:13:14.340Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.340Z] 10:13:14 INFO - Killing process: 1852
[task 2021-05-13T10:13:14.341Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.342Z] 10:13:14 INFO - Killing process: 2882
[task 2021-05-13T10:13:14.343Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.343Z] 10:13:14 INFO - Killing process: 3405
[task 2021-05-13T10:13:14.344Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.345Z] 10:13:14 INFO - Killing process: 1871
[task 2021-05-13T10:13:14.345Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.346Z] 10:13:14 INFO - Killing process: 2386
[task 2021-05-13T10:13:14.347Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.347Z] 10:13:14 INFO - Killing process: 3928
[task 2021-05-13T10:13:14.348Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.349Z] 10:13:14 INFO - Killing process: 2907
[task 2021-05-13T10:13:14.350Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.350Z] 10:13:14 INFO - Killing process: 4448
[task 2021-05-13T10:13:14.351Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.352Z] 10:13:14 INFO - Killing process: 1900
[task 2021-05-13T10:13:14.352Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.353Z] 10:13:14 INFO - Killing process: 3439
[task 2021-05-13T10:13:14.354Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.355Z] 10:13:14 INFO - Killing process: 2932
[task 2021-05-13T10:13:14.355Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.356Z] 10:13:14 INFO - Killing process: 3957
[task 2021-05-13T10:13:14.357Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.357Z] 10:13:14 INFO - Killing process: 2423
[task 2021-05-13T10:13:14.358Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.359Z] 10:13:14 INFO - Killing process: 2428
[task 2021-05-13T10:13:14.360Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.360Z] 10:13:14 INFO - Killing process: 4477
[task 2021-05-13T10:13:14.361Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.362Z] 10:13:14 INFO - Killing process: 3464
[task 2021-05-13T10:13:14.362Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.363Z] 10:13:14 INFO - Killing process: 2958
[task 2021-05-13T10:13:14.364Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.365Z] 10:13:14 INFO - Killing process: 3982
[task 2021-05-13T10:13:14.365Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.366Z] 10:13:14 INFO - Killing process: 4506
[task 2021-05-13T10:13:14.367Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.367Z] 10:13:14 INFO - Killing process: 1949
[task 2021-05-13T10:13:14.368Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.369Z] 10:13:14 INFO - Killing process: 3489
[task 2021-05-13T10:13:14.369Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.370Z] 10:13:14 INFO - Killing process: 2477
[task 2021-05-13T10:13:14.371Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.372Z] 10:13:14 INFO - Killing process: 2990
[task 2021-05-13T10:13:14.372Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.373Z] 10:13:14 INFO - Killing process: 4013
[task 2021-05-13T10:13:14.374Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.374Z] 10:13:14 INFO - Killing process: 1968
[task 2021-05-13T10:13:14.375Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.376Z] 10:13:14 INFO - Killing process: 4535
[task 2021-05-13T10:13:14.377Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.377Z] 10:13:14 INFO - Killing process: 3515
[task 2021-05-13T10:13:14.378Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.379Z] 10:13:14 INFO - Killing process: 2506
[task 2021-05-13T10:13:14.379Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.380Z] 10:13:14 INFO - Killing process: 2002
[task 2021-05-13T10:13:14.381Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.381Z] 10:13:14 INFO - Killing process: 3027
[task 2021-05-13T10:13:14.382Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.383Z] 10:13:14 INFO - Killing process: 3540
[task 2021-05-13T10:13:14.384Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.384Z] 10:13:14 INFO - Killing process: 4052
[task 2021-05-13T10:13:14.385Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.385Z] 10:13:14 INFO - Killing process: 4564
[task 2021-05-13T10:13:14.386Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.386Z] 10:13:14 INFO - Killing process: 2535
[task 2021-05-13T10:13:14.387Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.387Z] 10:13:14 INFO - Killing process: 4071
[task 2021-05-13T10:13:14.388Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.388Z] 10:13:14 INFO - Killing process: 3565
[task 2021-05-13T10:13:14.389Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.389Z] 10:13:14 INFO - Killing process: 2033
[task 2021-05-13T10:13:14.390Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.390Z] 10:13:14 INFO - Killing process: 3058
[task 2021-05-13T10:13:14.391Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.392Z] 10:13:14 INFO - Killing process: 4595
[task 2021-05-13T10:13:14.392Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.393Z] 10:13:14 INFO - Killing process: 3061
[task 2021-05-13T10:13:14.393Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.394Z] 10:13:14 INFO - Killing process: 4092
[task 2021-05-13T10:13:14.394Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.395Z] 10:13:14 INFO - Killing process: 1551
[task 2021-05-13T10:13:14.395Z] 10:13:14 INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-05-13T10:13:14.491Z] 10:13:14 INFO - psutil found pid 1551 dead
[task 2021-05-13T10:29:54.521Z] 10:29:54 INFO - Automation Error: mozprocess timed out after 1000 seconds running ['/builds/worker/workspace/build/venv/bin/python', '-u', '/builds/worker/workspace/build/tests/mochitest/runtests.py', 'browser/components/extensions/test/browser/browser.ini', '--setpref=fission.autostart=true', '--setpref=dom.serviceWorkers.parent_intercept=true', '--setpref=media.peerconnection.mtransport_process=false', '--setpref=network.process.enabled=false', '--appname=/builds/worker/workspace/build/application/firefox/firefox', '--utility-path=tests/bin', '--extra-profile-file=tests/bin/plugins', '--symbols-path=/builds/worker/workspace/build/symbols', '--certificate-path=tests/certs', '--setpref=webgl.force-enabled=true', '--quiet', '--log-raw=/builds/worker/workspace/build/blobber_upload_dir/mochitest-browser-chrome_raw.log', '--log-errorsummary=/builds/worker/workspace/build/blobber_upload_dir/mochitest-browser-chrome_errorsummary.log', '--use-test-media-devices', '--screenshot-on-fail', '--cleanup-crashes', '--marionette-startup-timeout=180', '--sandbox-read-whitelist=/builds/worker/workspace/build', '--log-raw=-', '--flavor=browser', '--chunk-by-runtime']
[task 2021-05-13T10:29:54.523Z] 10:29:54 ERROR - timed out after 1000 seconds of no output
[task 2021-05-13T10:29:54.524Z] 10:29:54 ERROR - Return code: -15
[task 2021-05-13T10:29:54.525Z] 10:29:54 ERROR - Got 2 unexpected statuses
[task 2021-05-13T10:29:54.525Z] 10:29:54 ERROR - No suite end message was emitted by this harness.
[task 2021-05-13T10:29:54.526Z] 10:29:54 INFO - TinderboxPrint: mochitest-mochitest-browser-chrome<br/>88/<em class="testfail">2</em>/0
[task 2021-05-13T10:29:54.527Z] 10:29:54 ERROR - # TBPL FAILURE #
[task 2021-05-13T10:29:54.528Z] 10:29:54 WARNING - setting return code to 2
[task 2021-05-13T10:29:54.529Z] 10:29:54 ERROR - The mochitest suite: mochitest-browser-chrome ran with return status: FAILURE
[task 2021-05-13T10:29:54.530Z] 10:29:54 INFO - Running post-action listener: _package_coverage_data
[task 2021-05-13T10:29:54.531Z] 10:29:54 INFO - Running post-action listener: _resource_record_post_action
[task 2021-05-13T10:29:54.532Z] 10:29:54 INFO - Running post-action listener: process_java_coverage_data
[task 2021-05-13T10:29:54.533Z] 10:29:54 INFO - [mozharness: 2021-05-13 10:29:54.523877Z] Finished run-tests step (success)
[task 2021-05-13T10:29:54.533Z] 10:29:54 INFO - Running post-run listener: _resource_record_post_run
[task 2021-05-13T10:29:54.641Z] 10:29:54 INFO - Validating Perfherder data against /builds/worker/workspace/mozharness/external_tools/performance-artifact-schema.json
[task 2021-05-13T10:29:54.644Z] 10:29:54 INFO - PERFHERDER_DATA: {"framework": {"name": "job_resource_usage"}, "suites": [{"name": "mochitest.mochitest-browser-chrome.overall", "extraOptions": ["e10s", "taskcluster-m5.large"], "subtests": [{"name": "cpu_percent", "value": 17.99617094861653}, {"name": "io_write_bytes", "value": 1148137472}, {"name": "io.read_bytes", "value": 15220736}, {"name": "io_write_time", "value": 109784}, {"name": "io_read_time", "value": 556}]}, {"name": "mochitest.mochitest-browser-chrome.start-pulseaudio", "subtests": [{"name": "time", "value": 0.020429611206054688}]}, {"name": "mochitest.mochitest-browser-chrome.install", "subtests": [{"name": "time", "value": 13.540356397628784}, {"name": "cpu_percent", "value": 50.333333333333336}]}, {"name": "mochitest.mochitest-browser-chrome.stage-files", "subtests": [{"name": "time", "value": 0.0005629062652587891}]}, {"name": "mochitest.mochitest-browser-chrome.run-tests", "subtests": [{"name": "time", "value": 2014.2263522148132}, {"name": "cpu_percent", "value": 17.769751243781027}]}]}
[task 2021-05-13T10:29:54.644Z] 10:29:54 INFO - Total resource usage - Wall time: 2026s; CPU: 18%; Read bytes: 15220736; Write bytes: 1148137472; Read time: 556; Write time: 109784
[task 2021-05-13T10:29:54.645Z] 10:29:54 INFO - TinderboxPrint: CPU usage<br/>17.8%
[task 2021-05-13T10:29:54.646Z] 10:29:54 INFO - TinderboxPrint: I/O read bytes / time<br/>15,220,736 / 556
[task 2021-05-13T10:29:54.646Z] 10:29:54 INFO - TinderboxPrint: I/O write bytes / time<br/>1,148,137,472 / 109,784
[task 2021-05-13T10:29:54.646Z] 10:29:54 INFO - TinderboxPrint: CPU idle<br/>3,317.2 (82.0%)
[task 2021-05-13T10:29:54.646Z] 10:29:54 INFO - TinderboxPrint: CPU system<br/>71.9 (1.8%)
[task 2021-05-13T10:29:54.646Z] 10:29:54 INFO - TinderboxPrint: CPU user<br/>653.1 (16.1%)
[task 2021-05-13T10:29:54.646Z] 10:29:54 INFO - TinderboxPrint: Swap in / out<br/>0 / 0
[task 2021-05-13T10:29:54.647Z] 10:29:54 INFO - start-pulseaudio - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-05-13T10:29:54.648Z] 10:29:54 INFO - install - Wall time: 14s; CPU: 50%; Read bytes: 0; Write bytes: 24576; Read time: 0; Write time: 8
[task 2021-05-13T10:29:54.649Z] 10:29:54 INFO - stage-files - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2021-05-13T10:29:54.658Z] 10:29:54 INFO - run-tests - Wall time: 2014s; CPU: 18%; Read bytes: 14737408; Write bytes: 1148112896; Read time: 524; Write time: 109776
[task 2021-05-13T10:29:54.868Z] 10:29:54 WARNING - returning nonzero exit status 2```
|
||
Comment 1•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
| Comment hidden (Intermittent Failures Robot) |
You need to log in
before you can comment on or make changes to this bug.
Description
•