Closed
Bug 1430437
Opened 8 years ago
Closed 8 years ago
Intermittent TEST-UNEXPECTED-TIMEOUT | toolkit/components/extensions/test/mochitest/test_ext_exclude_include_globs.html | application timed out after 370 seconds with no output
Categories
(WebExtensions :: General, defect, P5)
WebExtensions
General
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: apavel [at] mozilla.com
https://treeherder.mozilla.org/logviewer.html#?job_id=155620273&repo=mozilla-inbound
https://queue.taskcluster.net/v1/task/NmFpSE5fSo2y_ReqVcu3xA/runs/0/artifacts/public/logs/live_backing.log
[task 2018-01-11T12:55:19.944Z] 12:55:19 INFO - GECKO(2256) | --DOMWINDOW == 16 (0xc2863c00) [pid = 2256] [serial = 323] [outer = (nil)] [url = moz-extension://dea78df6-5e20-4cd0-9df8-c0cef48dc377/_generated_background_page.html]
[task 2018-01-11T12:56:27.078Z] 12:56:27 INFO - GECKO(2256) | --DOMWINDOW == 15 (0xbfc7f800) [pid = 2256] [serial = 360] [outer = (nil)] [url = about:blank]
[task 2018-01-11T12:56:27.078Z] 12:56:27 INFO - GECKO(2256) | --DOMWINDOW == 14 (0xc28f5000) [pid = 2256] [serial = 362] [outer = (nil)] [url = about:blank]
[task 2018-01-11T12:56:58.040Z] 12:56:58 INFO - GECKO(2256) | Console message: [JavaScript Error: "getScreenshot error: Unix error 2 during operation open on file /tmp/tmpzsxFEP.mozrunner/thumbnails/7a2a34de5c4886295371451e2c33f235.png (No such file or directory)" {file: "resource://activity-stream/lib/Screenshots.jsm" line: 61}]
[task 2018-01-11T12:56:58.040Z] 12:56:58 INFO - GECKO(2256) | getScreenshotForURL@resource://activity-stream/lib/Screenshots.jsm:61:7
[task 2018-01-11T12:56:58.040Z] 12:56:58 INFO - GECKO(2256) | async*maybeCacheScreenshot@resource://activity-stream/lib/Screenshots.jsm:84:32
[task 2018-01-11T12:56:58.040Z] 12:56:58 INFO - GECKO(2256) | async*_fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:186:13
[task 2018-01-11T12:56:58.040Z] 12:56:58 INFO - GECKO(2256) | async*getLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:135:9
[task 2018-01-11T12:56:58.040Z] 12:56:58 INFO - GECKO(2256) | async*refresh@resource://activity-stream/lib/TopSitesFeed.jsm:154:25
[task 2018-01-11T12:56:58.043Z] 12:56:58 INFO - GECKO(2256) | async*onAction@resource://activity-stream/lib/TopSitesFeed.jsm:276:9
[task 2018-01-11T12:56:58.045Z] 12:56:58 INFO - GECKO(2256) | async*_middleware/</<@resource://activity-stream/lib/Store.jsm:51:11
[task 2018-01-11T12:56:58.047Z] 12:56:58 INFO - GECKO(2256) | Store/this[method]@resource://activity-stream/lib/Store.jsm:30:55
[task 2018-01-11T12:56:58.049Z] 12:56:58 INFO - GECKO(2256) | init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:19:41
[task 2018-01-11T12:56:58.050Z] 12:56:58 INFO - GECKO(2256) | notify@resource://gre/modules/Timer.jsm:44:7
[task 2018-01-11T12:56:58.052Z] 12:56:58 INFO - GECKO(2256) | Console message: [JavaScript Error: "getScreenshot error: Unix error 2 during operation open on file /tmp/tmpzsxFEP.mozrunner/thumbnails/182ccedb33a9e03fbf1079b209da1a31.png (No such file or directory)" {file: "resource://activity-stream/lib/Screenshots.jsm" line: 61}]
[task 2018-01-11T12:56:58.053Z] 12:56:58 INFO - GECKO(2256) | getScreenshotForURL@resource://activity-stream/lib/Screenshots.jsm:61:7
[task 2018-01-11T12:56:58.055Z] 12:56:58 INFO - GECKO(2256) | async*maybeCacheScreenshot@resource://activity-stream/lib/Screenshots.jsm:84:32
[task 2018-01-11T12:56:58.055Z] 12:56:58 INFO - GECKO(2256) | async*_fetchIcon@resource://activity-stream/lib/TopSitesFeed.jsm:186:13
[task 2018-01-11T12:56:58.056Z] 12:56:58 INFO - GECKO(2256) | async*getLinksWithDefaults@resource://activity-stream/lib/TopSitesFeed.jsm:135:9
[task 2018-01-11T12:56:58.057Z] 12:56:58 INFO - GECKO(2256) | async*refresh@resource://activity-stream/lib/TopSitesFeed.jsm:154:25
[task 2018-01-11T12:56:58.057Z] 12:56:58 INFO - GECKO(2256) | async*onAction@resource://activity-stream/lib/TopSitesFeed.jsm:276:9
[task 2018-01-11T12:56:58.058Z] 12:56:58 INFO - GECKO(2256) | async*_middleware/</<@resource://activity-stream/lib/Store.jsm:51:11
[task 2018-01-11T12:56:58.059Z] 12:56:58 INFO - GECKO(2256) | Store/this[method]@resource://activity-stream/lib/Store.jsm:30:55
[task 2018-01-11T12:56:58.059Z] 12:56:58 INFO - GECKO(2256) | init/this.intervalId<@resource://activity-stream/lib/SystemTickFeed.jsm:19:41
[task 2018-01-11T12:56:58.060Z] 12:56:58 INFO - GECKO(2256) | notify@resource://gre/modules/Timer.jsm:44:7
[task 2018-01-11T13:02:23.078Z] 13:02:23 INFO - GECKO(2256) | [Parent 2256, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80070057: file /builds/worker/workspace/build/src/netwerk/base/nsChannelClassifier.cpp, line 344
[task 2018-01-11T13:02:23.078Z] 13:02:23 INFO - GECKO(2256) | [Parent 2256, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80070057: file /builds/worker/workspace/build/src/netwerk/base/nsChannelClassifier.cpp, line 344
[task 2018-01-11T13:08:33.097Z] 13:08:33 INFO - Buffered messages logged at 12:55:04
[task 2018-01-11T13:08:33.097Z] 13:08:33 INFO - SpawnTask.js | Entering test test_contentscript
[task 2018-01-11T13:08:33.099Z] 13:08:33 INFO - Extension loaded
[task 2018-01-11T13:08:33.101Z] 13:08:33 INFO - Buffered messages logged at 12:55:05
[task 2018-01-11T13:08:33.103Z] 13:08:33 INFO - extension loaded
[task 2018-01-11T13:08:33.104Z] 13:08:33 INFO - Buffered messages finished
[task 2018-01-11T13:08:33.106Z] 13:08:33 ERROR - TEST-UNEXPECTED-TIMEOUT | toolkit/components/extensions/test/mochitest/test_ext_exclude_include_globs.html | application timed out after 370 seconds with no output
[task 2018-01-11T13:08:33.108Z] 13:08:33 ERROR - Force-terminating active process(es).
[task 2018-01-11T13:08:33.109Z] 13:08:33 INFO - Determining child pids from psutil...
[task 2018-01-11T13:08:33.112Z] 13:08:33 INFO - [2491]
[task 2018-01-11T13:08:33.114Z] 13:08:33 INFO - ==> process 2256 launched child process 2279
[task 2018-01-11T13:08:33.116Z] 13:08:33 INFO - ==> process 2256 launched child process 2308
[task 2018-01-11T13:08:33.117Z] 13:08:33 INFO - ==> process 2256 launched child process 2362
[task 2018-01-11T13:08:33.118Z] 13:08:33 INFO - ==> process 2256 launched child process 2405
[task 2018-01-11T13:08:33.119Z] 13:08:33 INFO - ==> process 2256 launched child process 2433
[task 2018-01-11T13:08:33.120Z] 13:08:33 INFO - ==> process 2256 launched child process 2462
[task 2018-01-11T13:08:33.121Z] 13:08:33 INFO - ==> process 2256 launched child process 2491
[task 2018-01-11T13:08:33.122Z] 13:08:33 INFO - ==> process 2256 launched child process 2522
[task 2018-01-11T13:08:33.123Z] 13:08:33 INFO - Found child pids: set([2433, 2308, 2405, 2279, 2362, 2491, 2522, 2462])
[task 2018-01-11T13:08:33.124Z] 13:08:33 INFO - Failed to get child procs
[task 2018-01-11T13:08:33.125Z] 13:08:33 INFO - Killing process: 2433
[task 2018-01-11T13:08:33.126Z] 13:08:33 INFO - TEST-INFO | started process screentopng
[task 2018-01-11T13:08:33.473Z] 13:08:33 INFO - TEST-INFO | screentopng: exit 0
[task 2018-01-11T13:08:33.474Z] 13:08:33 INFO - Can't trigger Breakpad, process no longer exists
[task 2018-01-11T13:08:33.475Z] 13:08:33 INFO - Killing process: 2308
[task 2018-01-11T13:08:33.475Z] 13:08:33 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-01-11T13:08:33.476Z] 13:08:33 INFO - Can't trigger Breakpad, process no longer exists
[task 2018-01-11T13:08:33.476Z] 13:08:33 INFO - Killing process: 2405
[task 2018-01-11T13:08:33.479Z] 13:08:33 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-01-11T13:08:33.480Z] 13:08:33 INFO - Can't trigger Breakpad, process no longer exists
[task 2018-01-11T13:08:33.480Z] 13:08:33 INFO - Killing process: 2279
[task 2018-01-11T13:08:33.481Z] 13:08:33 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-01-11T13:08:33.482Z] 13:08:33 INFO - Can't trigger Breakpad, process no longer exists
[task 2018-01-11T13:08:33.482Z] 13:08:33 INFO - Killing process: 2362
[task 2018-01-11T13:08:33.483Z] 13:08:33 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-01-11T13:08:33.483Z] 13:08:33 INFO - Can't trigger Breakpad, process no longer exists
[task 2018-01-11T13:08:33.484Z] 13:08:33 INFO - Killing process: 2491
[task 2018-01-11T13:08:33.484Z] 13:08:33 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-01-11T13:08:33.485Z] 13:08:33 INFO - Killing process: 2522
[task 2018-01-11T13:08:33.485Z] 13:08:33 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-01-11T13:08:33.485Z] 13:08:33 INFO - Can't trigger Breakpad, process no longer exists
[task 2018-01-11T13:08:33.486Z] 13:08:33 INFO - Killing process: 2462
[task 2018-01-11T13:08:33.486Z] 13:08:33 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-01-11T13:08:33.487Z] 13:08:33 INFO - Can't trigger Breakpad, process no longer exists
[task 2018-01-11T13:08:33.487Z] 13:08:33 INFO - Killing process: 2256
[task 2018-01-11T13:08:33.487Z] 13:08:33 INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-01-11T13:08:33.749Z] 13:08:33 INFO - psutil found pid 2256 dead
[task 2018-01-11T13:08:33.906Z] 13:08:33 WARNING - runtests.py | Failed to get app exit code - running/crashed?
[task 2018-01-11T13:08:33.907Z] 13:08:33 INFO - TEST-INFO | Main app process: exit 0
[task 2018-01-11T13:08:33.908Z] 13:08:33 INFO - runtests.py | Application ran for: 0:16:43.967615
[task 2018-01-11T13:08:33.909Z] 13:08:33 INFO - zombiecheck | Reading PID log: /tmp/tmpO8n3x2pidlog
[task 2018-01-11T13:08:33.910Z] 13:08:33 INFO - ==> process 2256 launched child process 2279
[task 2018-01-11T13:08:33.911Z] 13:08:33 INFO - ==> process 2256 launched child process 2308
[task 2018-01-11T13:08:33.912Z] 13:08:33 INFO - ==> process 2256 launched child process 2362
[task 2018-01-11T13:08:33.913Z] 13:08:33 INFO - ==> process 2256 launched child process 2405
[task 2018-01-11T13:08:33.914Z] 13:08:33 INFO - ==> process 2256 launched child process 2433
[task 2018-01-11T13:08:33.915Z] 13:08:33 INFO - ==> process 2256 launched child process 2462
[task 2018-01-11T13:08:33.916Z] 13:08:33 INFO - ==> process 2256 launched child process 2491
[task 2018-01-11T13:08:33.917Z] 13:08:33 INFO - ==> process 2256 launched child process 2522
[task 2018-01-11T13:08:33.918Z] 13:08:33 INFO - zombiecheck | Checking for orphan process with PID: 2433
[task 2018-01-11T13:08:33.918Z] 13:08:33 INFO - zombiecheck | Checking for orphan process with PID: 2308
[task 2018-01-11T13:08:33.919Z] 13:08:33 INFO - zombiecheck | Checking for orphan process with PID: 2405
[task 2018-01-11T13:08:33.920Z] 13:08:33 INFO - zombiecheck | Checking for orphan process with PID: 2279
[task 2018-01-11T13:08:33.921Z] 13:08:33 INFO - zombiecheck | Checking for orphan process with PID: 2362
[task 2018-01-11T13:08:33.921Z] 13:08:33 INFO - zombiecheck | Checking for orphan process with PID: 2491
[task 2018-01-11T13:08:33.922Z] 13:08:33 INFO - zombiecheck | Checking for orphan process with PID: 2522
[task 2018-01-11T13:08:33.923Z] 13:08:33 INFO - zombiecheck | Checking for orphan process with PID: 2462
[task 2018-01-11T13:08:33.924Z] 13:08:33 INFO - mozcrash Copy/paste: /usr/local/bin/linux64-minidump_stackwalk /tmp/tmpzsxFEP.mozrunner/minidumps/4905aebc-e5e1-420b-973a-056167743b0b.dmp /builds/worker/workspace/build/symbols
[task 2018-01-11T13:08:43.078Z] 13:08:43 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/4905aebc-e5e1-420b-973a-056167743b0b.dmp
[task 2018-01-11T13:08:43.079Z] 13:08:43 INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/4905aebc-e5e1-420b-973a-056167743b0b.extra
[task 2018-01-11T13:08:43.140Z] 13:08:43 INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ linux-gate.so + 0xcd9]
[task 2018-01-11T13:08:43.143Z] 13:08:43 INFO - Crash dump filename: /tmp/tmpzsxFEP.mozrunner/minidumps/4905aebc-e5e1-420b-973a-056167743b0b.dmp
[task 2018-01-11T13:08:43.144Z] 13:08:43 INFO - Operating system: Linux
[task 2018-01-11T13:08:43.145Z] 13:08:43 INFO - 0.0.0 Linux 4.4.0-98-generic #121~14.04.1-Ubuntu SMP Wed Oct 11 11:54:55 UTC 2017 x86_64
[task 2018-01-11T13:08:43.146Z] 13:08:43 INFO - CPU: x86
[task 2018-01-11T13:08:43.146Z] 13:08:43 INFO - GenuineIntel family 6 model 62 stepping 4
[task 2018-01-11T13:08:43.147Z] 13:08:43 INFO - 2 CPUs
[task 2018-01-11T13:08:43.148Z] 13:08:43 INFO -
[task 2018-01-11T13:08:43.149Z] 13:08:43 INFO - GPU: UNKNOWN
[task 2018-01-11T13:08:43.150Z] 13:08:43 INFO -
[task 2018-01-11T13:08:43.151Z] 13:08:43 INFO - Crash reason: SIGABRT
[task 2018-01-11T13:08:43.152Z] 13:08:43 INFO - Crash address: 0x3e4
[task 2018-01-11T13:08:43.153Z] 13:08:43 INFO - Process uptime: not available
[task 2018-01-11T13:08:43.155Z] 13:08:43 INFO -
[task 2018-01-11T13:08:43.156Z] 13:08:43 INFO - Thread 0 (crashed)
[task 2018-01-11T13:08:43.157Z] 13:08:43 INFO - 0 linux-gate.so + 0xcd9
[task 2018-01-11T13:08:43.157Z] 13:08:43 INFO - eip = 0xf77a9cd9 esp = 0xfff5e174 ebp = 0xfff5e1e8 ebx = 0xe50e9de0
[task 2018-01-11T13:08:43.158Z] 13:08:43 INFO - esi = 0xf71447b0 edi = 0xe50e9de0 eax = 0xfffffffc ecx = 0x00000004
[task 2018-01-11T13:08:43.159Z] 13:08:43 INFO - edx = 0xffffffff efl = 0x00200292
[task 2018-01-11T13:08:43.159Z] 13:08:43 INFO - Found by: given as instruction pointer in context
[task 2018-01-11T13:08:43.159Z] 13:08:43 INFO - 1 libglib-2.0.so.0.4800.2 + 0x4711c
[task 2018-01-11T13:08:43.160Z] 13:08:43 INFO - eip = 0xf5e4f11c esp = 0xfff5e1f0 ebp = 0x00000004
[task 2018-01-11T13:08:43.161Z] 13:08:43 INFO - Found by: previous frame's frame pointer
[task 2018-01-11T13:08:43.162Z] 13:08:43 INFO - 2 libglib-2.0.so.0.4800.2 + 0x8cfec
[task 2018-01-11T13:08:43.162Z] 13:08:43 INFO - eip = 0xf5e94fec esp = 0xfff5e200 ebp = 0x00000004
[task 2018-01-11T13:08:43.163Z] 13:08:43 INFO - Found by: stack scanning
[task 2018-01-11T13:08:43.164Z] 13:08:43 INFO - 3 libxul.so!IsFrameContentNodeInNamespace [nsNativeThemeGTK.cpp:32371cb3206a : 113 + 0x4]
[task 2018-01-11T13:08:43.165Z] 13:08:43 INFO - eip = 0xf13b7643 esp = 0xfff5e204 ebp = 0x00000004
[task 2018-01-11T13:08:43.165Z] 13:08:43 INFO - Found by: stack scanning
[task 2018-01-11T13:08:43.165Z] 13:08:43 INFO - 4 libglib-2.0.so.0.4800.2 + 0x1285b0
[task 2018-01-11T13:08:43.166Z] 13:08:43 INFO - eip = 0xf5f305b0 esp = 0xfff5e214 ebp = 0x00000004
[task 2018-01-11T13:08:43.167Z] 13:08:43 INFO - Found by: stack scanning
[task 2018-01-11T13:08:43.168Z] 13:08:43 INFO - 5 libglib-2.0.so.0.4800.2 + 0x46f8d
[task 2018-01-11T13:08:43.168Z] 13:08:43 INFO - eip = 0xf5e4ef8d esp = 0xfff5e22c ebp = 0xfff5e248
[task 2018-01-11T13:08:43.168Z] 13:08:43 INFO - Found by: stack scanning
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•8 years ago
|
||
Status: NEW → RESOLVED
Closed: 8 years ago
Resolution: --- → INCOMPLETE
|
||
Updated•7 years ago
|
Product: Toolkit → WebExtensions
You need to log in
before you can comment on or make changes to this bug.
Description
•