Closed Bug 1504578 Opened 7 years ago Closed 7 years ago

Intermittent svg/smil/motion/animateMotion-by-1.svg | application timed out after 370 seconds with no output

Categories

(Core :: SVG, defect, P5)

Product:
Core
Component:
SVG
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure)

Filed by: apavel [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=209705741&repo=mozilla-inbound https://queue.taskcluster.net/v1/task/Aa0cx41iRDKDfM91TAv-8w/runs/0/artifacts/public/logs/live_backing.log https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/Aa0cx41iRDKDfM91TAv-8w/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1 1:31:28 INFO - REFTEST TEST-START | file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/table-dom/insertTbodyRebuild1.html == file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/table-dom/insertTbodyRebuild1-ref.html 21:31:28 INFO - REFTEST TEST-LOAD | file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/table-dom/insertTbodyRebuild1.html | 43 / 44 (97%) 21:31:28 INFO - REFTEST TEST-LOAD | file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/table-dom/insertTbodyRebuild1-ref.html | 43 / 44 (97%) 21:31:28 INFO - REFTEST TEST-PASS | file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/table-dom/insertTbodyRebuild1.html == file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/table-dom/insertTbodyRebuild1-ref.html | image comparison, max difference: 0, number of differing pixels: 0 21:31:28 INFO - REFTEST TEST-END | file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/table-dom/insertTbodyRebuild1.html == file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/table-dom/insertTbodyRebuild1-ref.html 21:31:28 INFO - REFTEST INFO | Slowest test took 6752ms (file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/table-dom/deleteColGroup1.html) 21:31:28 INFO - REFTEST INFO | Total canvas count = 2 21:31:32 INFO - [[Parent 5616, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:31:32 INFO - [Child 5572, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:31:32 INFO - JavaScript error: resource://reftest/reftest.jsm, line 1552: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString] 21:31:32 INFO - [Parent 5616, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:31:32 INFO - [Child 1124, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:31:32 INFO - JavaScript error: resource://reftest/reftest.jsm, line 1552: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString] 21:31:32 INFO - [Child 1124, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:31:32 INFO - JavaScript error: jar:file:///Z:/task_1541362056/build/application/firefox/browser/omni.ja!/components/nsBrowserGlue.js, line 1387: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIAppShellService.hiddenDOMWindow] 21:31:32 INFO - JavaScript error: resource://reftest/reftest.jsm, line 1552: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString] 21:31:32 INFO - JavaScript error: resource://gre/modules/JSONFile.jsm, line 159: Error: Data is not ready. 21:31:33 INFO - 1541367093661 Marionette DEBUG Received observer notification xpcom-will-shutdown 21:31:33 INFO - 1541367093661 Marionette INFO Stopped listening on port 2828 21:31:33 INFO - 1541367093661 Marionette DEBUG Remote service is inactive 21:31:33 INFO - ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 21:31:33 INFO - [ 21:31:33 INFO - ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv 21:31:34 INFO - REFTEST INFO | Process mode: e10s 21:31:34 WARNING - leakcheck | refcount logging is off, so leaks can't be detected! 21:31:34 INFO - REFTEST INFO | Running tests in file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/svg/smil/motion/reftest.list 21:31:34 INFO - REFTEST INFO | Running with e10s: True 21:31:34 INFO - REFTEST INFO | Application command: Z:\task_1541362056\build\application\firefox\firefox.exe -marionette --wait-for-browser -profile c:\users\task_1541362056\appdata\local\temp\tmpgdiusj.mozrunner 21:31:35 INFO - 1541367095575 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid host permission: resource://pdf.js/ 21:31:35 INFO - 1541367095575 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid host permission: about:reader* 21:31:35 INFO - 1541367095933 Marionette DEBUG Received observer notification profile-after-change 21:31:36 INFO - 1541367096076 Marionette DEBUG Received observer notification toplevel-window-ready 21:31:36 INFO - 1541367096094 Marionette DEBUG Received observer notification command-line-startup 21:31:36 INFO - 1541367096096 Marionette DEBUG Received observer notification nsPref:changed 21:31:36 INFO - 1541367096096 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false) 21:31:36 INFO - 1541367096984 Marionette DEBUG Received observer notification toplevel-window-ready 21:31:44 INFO - [Child 5592, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:31:44 INFO - 1541367104898 Marionette DEBUG Received observer notification sessionstore-windows-restored 21:31:44 INFO - 1541367104899 Marionette DEBUG Waiting for delayed startup... 21:31:45 INFO - 1541367105210 Marionette DEBUG Waiting for startup tests... 21:31:45 INFO - 1541367105399 Marionette INFO Listening on port 2828 21:31:45 INFO - 1541367105401 Marionette DEBUG Remote service is active 21:31:45 INFO - 1541367105897 Marionette DEBUG Accepted connection 0 from 127.0.0.1:51479 21:31:45 INFO - 1541367105927 Marionette DEBUG Closed connection 0 21:31:45 INFO - 1541367105931 Marionette DEBUG Accepted connection 1 from 127.0.0.1:51480 21:31:45 INFO - 1541367105965 Marionette TRACE 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}] 21:31:46 INFO - 1541367106088 Marionette DEBUG [6442450945] Frame script loaded 21:31:46 INFO - 1541367106207 Marionette DEBUG [6442450945] Frame script registered 21:31:46 INFO - 1541367106238 Marionette TRACE 1 <- [1,1,null,{"sessionId":"a25c5510-c68b-4bdf-93ac-ac015baecabd","capabilities":{"browserName":"firefox","browserVersion":"65.0a ... p\\tmpgdiusj.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}] 21:31:46 INFO - 1541367106277 Marionette TRACE 1 -> [0,2,"Addon:Install",{"path":"Z:\\task_1541362056\\build\\tests\\reftest\\reftest","temporary":true}] 21:31:46 INFO - 1541367106563 Marionette TRACE 1 <- [1,2,null,{"value":"reftest@mozilla.org"}] 21:31:46 INFO - 1541367106728 Marionette TRACE 1 -> [0,3,"WebDriver:DeleteSession",{}] 21:31:46 INFO - 1541367106734 Marionette TRACE 1 <- [1,3,null,{"value":null}] 21:31:46 INFO - 1541367106814 Marionette DEBUG Closed connection 1 21:31:46 INFO - [Child 5592, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/co[Parent 2176, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:31:46 INFO - REFTEST TEST-START | file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/svg/smil/motion/animateMotion-by-1.svg == file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/svg/smil/motion/lime.svg 21:31:46 INFO - REFTEST TEST-LOAD | file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/svg/smil/motion/animateMotion-by-1.svg | 0 / 16 (0%) 21:31:46 INFO - ###!!! [Parent][MessageChannel] Error: (msgtype=0x190095,name=PBrowser::Msg_UpdateNativeWindowHandle) Closed channel: cannot send/recv 21:31:46 INFO - ###!!! [Parent][MessageChannel] Error: (msgtype=0x190084,name=PBrowser::Msg_Destroy) Closed channel: cannot send/recv 21:31:46 INFO - JavaScript error: resource://reftest/reftest.jsm, line 1552: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPropertyBag2.getPropertyAsAString] 21:31:46 INFO - [Child 3564, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:31:46 INFO - [Child 3564, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:31:46 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x190001,name=PBrowser::Msg_AsyncMessage) Channel closing: too late to send/recv, messages will be lost 21:31:46 INFO - JavaScript error: resource://gre/modules/WebProgressChild.jsm, line 91: NS_ERROR_UNEXPECTED: 21:31:46 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x190001,name=PBrowser::Msg_AsyncMessage) Closed channel: cannot send/recv 21:31:46 INFO - JavaScript error: resource://gre/modules/WebProgressChild.jsm, line 91: NS_ERROR_UNEXPECTED: 21:31:46 INFO - ###!!! [Child][MessageChannel] Error: (msgtype=0x300112,name=PContent::Msg_DetachBrowsingContext) Closed channel: cannot send/recv 21:37:56 ERROR - REFTEST ERROR | file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/svg/smil/motion/animateMotion-by-1.svg | application timed out after 370 seconds with no output 21:37:56 ERROR - REFTEST ERROR | Force-terminating active process(es). 21:37:56 INFO - REFTEST TEST-INFO | started process screenshot 21:37:57 INFO - REFTEST TEST-INFO | screenshot: exit 0 21:37:57 INFO - TEST-INFO | crashinject: exit 0 21:41:02 INFO - WARNING | IO Completion Port failed to signal process shutdown 21:41:02 INFO - Parent process 4872 exited with children alive: 21:41:02 INFO - PIDS: 2176, 6040 21:41:02 INFO - Attempting to kill them, but no guarantee of success 21:41:02 ERROR - TEST-UNEXPECTED-FAIL | file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/svg/smil/motion/animateMotion-by-1.svg | application terminated with exit code 3221225477 21:41:02 INFO - REFTEST INFO | Process mode: e10s 21:41:02 WARNING - leakcheck | refcount logging is off, so leaks can't be detected! 21:41:02 INFO - REFTEST INFO | Running tests in file:///Z:/task_1541362056/build/tests/reftest/tests/layout/reftests/webcomponents/reftest.list 21:41:02 INFO - REFTEST INFO | Running with e10s: True 21:41:02 INFO - REFTEST INFO | Application command: Z:\task_1541362056\build\application\firefox\firefox.exe -marionette --wait-for-browser -profile c:\users\task_1541362056\appdata\local\temp\tmp76zzas.mozrunner 21:41:05 INFO - 1541367665456 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid host permission: resource://pdf.js/ 21:41:05 INFO - 1541367665457 addons.webextension.screenshots@mozilla.org WARN Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid host permission: about:reader* 21:41:05 INFO - 1541367665853 Marionette DEBUG Received observer notification profile-after-change 21:41:06 INFO - 1541367665999 Marionette DEBUG Received observer notification toplevel-window-ready 21:41:06 INFO - 1541367666025 Marionette DEBUG Received observer notification command-line-startup 21:41:06 INFO - 1541367666027 Marionette DEBUG Received observer notification nsPref:changed 21:41:06 INFO - 1541367666027 Marionette DEBUG Init aborted (running=false, enabled=true, finalUIStartup=false) 21:41:06 INFO - 1541367666892 Marionette DEBUG Received observer notification toplevel-window-ready 21:41:13 INFO - console.error: "Could not write session state file " (new Error("_initWorker called too early! Please read the session file from disk first.", "resource:///modules/sessionstore/SessionFile.jsm", 334)) "_initWorker/<@resource:///modules/sessionstore/SessionFile.jsm:334:15\n_initWorker@resource:///modules/sessionstore/SessionFile.jsm:327:12\n_postToWorker@resource:///modules/sessionstore/SessionFile.jsm:351:11\nasync*write@resource:///modules/sessionstore/SessionFile.jsm:390:19\nwrite@resource:///modules/sessionstore/SessionFile.jsm:63:12\n_writeState@resource:///modules/sessionstore/SessionSaver.jsm:356:12\n_saveState@resource:///modules/sessionstore/SessionSaver.jsm:294:12\n_saveStateAsync@resource:///modules/sessionstore/SessionSaver.jsm:340:5\nsaveStateAsyncWhenIdle@resource:///modules/sessionstore/SessionSaver.jsm:191:9\nrequestIdleCallback handler*runDelayed/this._timeoutID<@resource:///modules/sessionstore/SessionSaver.jsm:194:30\nnotify@resource://gre/modules/Timer.jsm:42:7\n" 21:41:13 INFO - [Parent 2248, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:41:13 INFO - [Child 4696, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:41:13 INFO - [Child 4696, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 21:41:13 INFO - 1541367673485 Marionette DEBUG Received observer notification sessionstore-windows-restored 21:41:13 INFO - 1541367673487 Marionette DEBUG Waiting for delayed startup... 21:41:13 INFO - 1541367673833 Marionette DEBUG Waiting for startup tests... 21:41:13 INFO - 1541367673950 Marionette INFO Listening on port 2828 21:41:13 INFO - 1541367673952 Marionette DEBUG Remote service is active 21:41:14 INFO - 1541367674167 Marionette DEBUG Accepted connection 0 from 127.0.0.1:51638 21:41:14 INFO - 1541367674238 Marionette DEBUG Accepted connection 1 from 127.0.0.1:51639 21:41:14 INFO - 1541367674249 Marionette DEBUG Closed connection 1 21:41:14 INFO - 1541367674250 Marionette DEBUG Closed connection 0 21:41:14 INFO - 1541367674251 Marionette DEBUG Accepted connection 2 from 127.0.0.1:51640 21:41:14 INFO - 1541367674323 Marionette TRACE 2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}] 21:41:14 INFO - 1541367674423 Marionette DEBUG [6442450945] Frame script loaded 21:41:14 INFO - 1541367674461 Marionette DEBUG [6442450945] Frame script registered 21:41:14 INFO - 1541367674488 Marionette TRACE 2 <- [1,1,null,{"sessionId":"5eb2345c-18bc-49d0-8b3a-00d5cb546595","capabilities":{"browserName":"firefox","browserVersion":"65.0a ... p\\tmp76zzas.mozrunner","moz:shutdownTimeout":60000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}] 21:41:14 INFO - 1541367674533 Marionette TRACE 2 -> [0,2,"Addon:Install",{"path":"Z:\\task_1541362056\\build\\tests\\reftest\\reftest","temporary":true}] 21:41:14 INFO - 1541367674789 Marionette TRACE 2 <- [1,2,null,{"value":"reftest@mozilla.org"}] 21:41:15 INFO - 1541367675059 Marionette TRACE 2 -> [0,3,"WebDriver:DeleteSession",{}] 21:41:15 INFO - 1541367675033 Marionette TRACE 2 <- [1,3,null,{"value":null}] 21:41:15 INFO - 1541367675041 Marionette DEBUG Closed connection 2
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.