could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
13:09:30 INFO - 1501013370045 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
13:09:30 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501013370054 Marionette TRACE 352 <- [1,5,null,{"value":null}]
13:09:30 INFO - 1501013370056 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
13:09:30 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:30 INFO - 1501013370060 Marionette TRACE 352 <- [1,6,null,{}]
13:09:30 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 833ms
13:09:30 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:09:30 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:30 INFO - 1501013370067 Marionette DEBUG Closed connection 352
13:09:30 INFO - 1501013370069 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49779
13:09:30 INFO - 1501013370071 Marionette DEBUG Closed connection 353
13:09:30 INFO - 1501013370073 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49780
13:09:30 INFO - 1501013370076 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:30 INFO - 1501013370081 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:30 INFO - 1501013370101 Marionette DEBUG Register listener.js for window 12
13:09:30 INFO - 1501013370116 Marionette TRACE 354 <- [1,1,null,{"sessionId":"4aeca63a-25bd-4b47-ab43-0688322fa228","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:30 INFO - 1501013370169 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
13:09:30 INFO - 1501013370171 Marionette TRACE 354 <- [1,2,null,{}]
13:09:30 INFO - 1501013370176 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:30 INFO - 1501013370178 Marionette TRACE 354 <- [1,3,null,{}]
13:09:30 INFO - 1501013370180 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:30 INFO - 1501013370182 Marionette TRACE 354 <- [1,4,null,{}]
13:09:30 INFO - 1501013370186 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
13:09:30 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501013370198 Marionette TRACE 354 <- [1,5,null,{"value":null}]
13:09:30 INFO - 1501013370200 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
13:09:30 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:30 INFO - 1501013370204 Marionette TRACE 354 <- [1,6,null,{}]
13:09:30 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:30 INFO - 1501013370211 Marionette DEBUG Closed connection 354
13:09:30 INFO - 1501013370212 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49781
13:09:30 INFO - 1501013370214 Marionette DEBUG Closed connection 355
13:09:30 INFO - 1501013370216 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49782
13:09:30 INFO - 1501013370218 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:30 INFO - 1501013370220 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:30 INFO - 1501013370241 Marionette DEBUG Register listener.js for window 12
13:09:30 INFO - 1501013370255 Marionette TRACE 356 <- [1,1,null,{"sessionId":"e3ed7b58-9aaa-4a0d-ba48-1a1da0e244c1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:30 INFO - 1501013370283 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
13:09:30 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:30 INFO - 1501013370288 Marionette TRACE 356 <- [1,2,null,{}]
13:09:30 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:30 INFO - 1501013370908 Marionette DEBUG Closed connection 356
13:09:30 INFO - 1501013370913 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49783
13:09:30 INFO - 1501013370934 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49784
13:09:30 INFO - 1501013370935 Marionette DEBUG Closed connection 357
13:09:30 INFO - 1501013370982 Marionette DEBUG Closed connection 358
13:09:30 INFO - 1501013370984 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49785
13:09:31 INFO - 1501013371035 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:31 INFO - 1501013371038 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:31 INFO - 1501013371058 Marionette DEBUG Register listener.js for window 12
13:09:31 INFO - 1501013371070 Marionette TRACE 359 <- [1,1,null,{"sessionId":"49422e9b-32e3-4f3b-a009-54d2290c0efd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:31 INFO - 1501013371100 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49330/clicks.html"}]
13:09:31 INFO - 1501013371105 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/hidden.html"
13:09:31 INFO - 1501013371123 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/hidden.html"
13:09:31 INFO - 1501013371124 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/hidden.html"
13:09:31 INFO - ++DOMWINDOW == 19 (000000CB8E284000) [pid = 2412] [serial = 41] [outer = 000000CB8ED10800]
13:09:31 INFO - 1501013371204 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/clicks.html"
13:09:31 INFO - 1501013371209 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/clicks.html"
13:09:31 INFO - 1501013371231 Marionette TRACE 359 <- [1,2,null,{}]
13:09:31 INFO - 1501013371239 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:09:31 INFO - 1501013371252 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3b27bee5-7941-4b3a-a1ec-62d657294154","ELEMENT":"3b27bee5-7941-4b3a-a1ec-62d657294154"}}]
13:09:31 INFO - 1501013371254 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"3b27bee5-7941-4b3a-a1ec-62d657294154"}]
13:09:31 INFO - 1501013371499 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/clicks.html"
13:09:31 INFO - [Parent 2412] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
13:09:31 INFO - 1501013371518 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/clicks.html"
13:09:31 INFO - 1501013371520 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/clicks.html"
13:09:31 INFO - ++DOMWINDOW == 20 (000000CB97059000) [pid = 2412] [serial = 42] [outer = 000000CB8ED10800]
13:09:31 INFO - 1501013371610 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/test.html"
13:09:31 INFO - 1501013371614 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/test.html"
13:09:31 INFO - 1501013371631 Marionette TRACE 359 <- [1,4,null,{}]
13:09:31 INFO - 1501013371634 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:09:31 INFO - 1501013371643 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8fbf22fa-86e3-42b8-a46b-003d250fa747","ELEMENT":"8fbf22fa-86e3-42b8-a46b-003d250fa747"}}]
13:09:31 INFO - 1501013371645 Marionette TRACE 359 -> [0,6,"getTitle",{}]
13:09:31 INFO - 1501013371646 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
13:09:31 INFO - 1501013371656 Marionette TRACE 359 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
13:09:31 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501013371664 Marionette TRACE 359 <- [1,7,null,{"value":null}]
13:09:31 INFO - 1501013371666 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
13:09:31 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:31 INFO - 1501013371671 Marionette TRACE 359 <- [1,8,null,{}]
13:09:31 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1610ms
13:09:31 INFO - TEST-START | test_click.py TestClick.test_container_element
13:09:31 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:31 INFO - 1501013371677 Marionette DEBUG Closed connection 359
13:09:31 INFO - 1501013371679 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49788
13:09:31 INFO - 1501013371680 Marionette DEBUG Closed connection 360
13:09:31 INFO - 1501013371682 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49789
13:09:31 INFO - 1501013371684 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:31 INFO - 1501013371686 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:31 INFO - 1501013371707 Marionette DEBUG Register listener.js for window 12
13:09:31 INFO - 1501013371719 Marionette TRACE 361 <- [1,1,null,{"sessionId":"e4fefd7d-e944-43c4-b12d-d006a3950374","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:31 INFO - 1501013371748 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
13:09:31 INFO - 1501013371750 Marionette TRACE 361 <- [1,2,null,{}]
13:09:31 INFO - 1501013371752 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:31 INFO - 1501013371754 Marionette TRACE 361 <- [1,3,null,{}]
13:09:31 INFO - 1501013371756 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:31 INFO - 1501013371758 Marionette TRACE 361 <- [1,4,null,{}]
13:09:31 INFO - 1501013371761 Marionette TRACE 361 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
13:09:31 INFO - TEST-START: test_click.py TestClick.test_container_element1501013371771 Marionette TRACE 361 <- [1,5,null,{"value":null}]
13:09:31 INFO - 1501013371773 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
13:09:31 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:31 INFO - 1501013371778 Marionette TRACE 361 <- [1,6,null,{}]
13:09:31 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:31 INFO - 1501013371802 Marionette DEBUG Closed connection 361
13:09:31 INFO - 1501013371803 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49790
13:09:31 INFO - 1501013371807 Marionette DEBUG Closed connection 362
13:09:31 INFO - 1501013371808 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49791
13:09:31 INFO - 1501013371810 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:31 INFO - 1501013371813 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:31 INFO - 1501013371833 Marionette DEBUG Register listener.js for window 12
13:09:31 INFO - 1501013371845 Marionette TRACE 363 <- [1,1,null,{"sessionId":"301298c9-18ad-43ac-aa97-2aee163adaf7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:31 INFO - 1501013371855 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
13:09:31 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:31 INFO - 1501013371860 Marionette TRACE 363 <- [1,2,null,{}]
13:09:31 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:31 INFO - 1501013371882 Marionette DEBUG Closed connection 363
13:09:31 INFO - 1501013371883 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49792
13:09:31 INFO - 1501013371887 Marionette DEBUG Closed connection 364
13:09:31 INFO - 1501013371889 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49793
13:09:31 INFO - 1501013371892 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:31 INFO - 1501013371895 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:31 INFO - 1501013371916 Marionette DEBUG Register listener.js for window 12
13:09:31 INFO - 1501013371929 Marionette TRACE 365 <- [1,1,null,{"sessionId":"03d0dcfa-b407-458e-bed2-1eed642ec810","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:31 INFO - 1501013371939 Marionette TRACE 365 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
13:09:31 INFO - 1501013371943 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/test.html"
13:09:31 INFO - 1501013371953 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/test.html"
13:09:31 INFO - 1501013371954 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/test.html"
13:09:31 INFO - ++DOMWINDOW == 21 (000000CB975D3800) [pid = 2412] [serial = 43] [outer = 000000CB8ED10800]
13:09:32 INFO - 1501013372021 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:32 INFO - 1501013372029 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:32 INFO - 1501013372047 Marionette TRACE 365 <- [1,2,null,{}]
13:09:32 INFO - 1501013372054 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:09:32 INFO - 1501013372062 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"138431bf-6a46-4873-b232-374910dd18bd","ELEMENT":"138431bf-6a46-4873-b232-374910dd18bd"}}]
13:09:32 INFO - 1501013372068 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"138431bf-6a46-4873-b232-374910dd18bd"}]
13:09:32 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
13:09:32 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
13:09:32 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
13:09:32 INFO - 1501013372507 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:32 INFO - 1501013372509 Marionette TRACE 365 <- [1,4,null,{}]
13:09:32 INFO - 1501013372511 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"138431bf-6a46-4873-b232-374910dd18bd","name":"selected"}]
13:09:32 INFO - 1501013372539 Marionette TRACE 365 <- [1,5,null,{"value":true}]
13:09:32 INFO - 1501013372543 Marionette TRACE 365 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
13:09:32 INFO - TEST-END: test_click.py TestClick.test_container_element1501013372565 Marionette TRACE 365 <- [1,6,null,{"value":null}]
13:09:32 INFO - 1501013372568 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
13:09:32 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:32 INFO - 1501013372573 Marionette TRACE 365 <- [1,7,null,{}]
13:09:32 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 901ms
13:09:32 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:09:32 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:32 INFO - 1501013372579 Marionette DEBUG Closed connection 365
13:09:32 INFO - 1501013372580 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49794
13:09:32 INFO - 1501013372582 Marionette DEBUG Closed connection 366
13:09:32 INFO - 1501013372583 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49795
13:09:32 INFO - 1501013372586 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:32 INFO - 1501013372589 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:32 INFO - 1501013372614 Marionette DEBUG Register listener.js for window 12
13:09:32 INFO - 1501013372628 Marionette TRACE 367 <- [1,1,null,{"sessionId":"4a933197-e0c3-4d80-8684-0a858b2dcba3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:32 INFO - 1501013372664 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
13:09:32 INFO - 1501013372666 Marionette TRACE 367 <- [1,2,null,{}]
13:09:32 INFO - 1501013372668 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:32 INFO - 1501013372670 Marionette TRACE 367 <- [1,3,null,{}]
13:09:32 INFO - 1501013372672 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:32 INFO - 1501013372673 Marionette TRACE 367 <- [1,4,null,{}]
13:09:32 INFO - 1501013372676 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
13:09:32 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501013372684 Marionette TRACE 367 <- [1,5,null,{"value":null}]
13:09:32 INFO - 1501013372686 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
13:09:32 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:32 INFO - 1501013372691 Marionette TRACE 367 <- [1,6,null,{}]
13:09:32 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:32 INFO - 1501013372714 Marionette DEBUG Closed connection 367
13:09:32 INFO - 1501013372716 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49796
13:09:32 INFO - 1501013372719 Marionette DEBUG Closed connection 368
13:09:32 INFO - 1501013372721 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49797
13:09:32 INFO - 1501013372723 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:32 INFO - 1501013372725 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:32 INFO - 1501013372746 Marionette DEBUG Register listener.js for window 12
13:09:32 INFO - 1501013372757 Marionette TRACE 369 <- [1,1,null,{"sessionId":"5f2b3bad-f5f4-4749-89e3-a2058ae54bc3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:32 INFO - 1501013372768 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
13:09:32 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:32 INFO - 1501013372772 Marionette TRACE 369 <- [1,2,null,{}]
13:09:32 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:32 INFO - 1501013372793 Marionette DEBUG Closed connection 369
13:09:32 INFO - 1501013372794 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49798
13:09:33 INFO - 1501013373408 Marionette DEBUG Closed connection 370
13:09:33 INFO - 1501013373410 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49799
13:09:33 INFO - 1501013373449 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:33 INFO - 1501013373451 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:33 INFO - 1501013373480 Marionette DEBUG Register listener.js for window 12
13:09:33 INFO - 1501013373492 Marionette TRACE 371 <- [1,1,null,{"sessionId":"0687a0e8-1858-4c42-a31c-d206e1dd7786","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:33 INFO - 1501013373501 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
13:09:33 INFO - 1501013373505 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:33 INFO - 1501013373511 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:33 INFO - 1501013373512 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:33 INFO - ++DOMWINDOW == 22 (000000CB92A36800) [pid = 2412] [serial = 44] [outer = 000000CB8ED10800]
13:09:33 INFO - 1501013373559 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:33 INFO - 1501013373566 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:33 INFO - 1501013373586 Marionette TRACE 371 <- [1,2,null,{}]
13:09:33 INFO - 1501013373593 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:09:33 INFO - 1501013373601 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"68343f6a-7b34-40c4-9016-72c023e327c5","ELEMENT":"68343f6a-7b34-40c4-9016-72c023e327c5"}}]
13:09:33 INFO - 1501013373604 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"68343f6a-7b34-40c4-9016-72c023e327c5"}]
13:09:33 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
13:09:33 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
13:09:33 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
13:09:34 INFO - 1501013374045 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:34 INFO - 1501013374047 Marionette TRACE 371 <- [1,4,null,{}]
13:09:34 INFO - 1501013374049 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"68343f6a-7b34-40c4-9016-72c023e327c5","name":"selected"}]
13:09:34 INFO - 1501013374077 Marionette TRACE 371 <- [1,5,null,{"value":true}]
13:09:34 INFO - 1501013374079 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
13:09:34 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501013374087 Marionette TRACE 371 <- [1,6,null,{"value":null}]
13:09:34 INFO - 1501013374089 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
13:09:34 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1501013374093 Marionette TRACE 371 <- [1,7,null,{}]
13:09:34 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1519ms
13:09:34 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:09:34 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1501013374099 Marionette DEBUG Closed connection 371
13:09:34 INFO - 1501013374100 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49800
13:09:34 INFO - 1501013374102 Marionette DEBUG Closed connection 372
13:09:34 INFO - 1501013374103 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49801
13:09:34 INFO - 1501013374105 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:34 INFO - 1501013374107 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:34 INFO - 1501013374128 Marionette DEBUG Register listener.js for window 12
13:09:34 INFO - 1501013374139 Marionette TRACE 373 <- [1,1,null,{"sessionId":"1c698356-093d-4e96-bbba-744524f3f34a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:34 INFO - 1501013374147 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
13:09:34 INFO - 1501013374149 Marionette TRACE 373 <- [1,2,null,{}]
13:09:34 INFO - 1501013374151 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:34 INFO - 1501013374153 Marionette TRACE 373 <- [1,3,null,{}]
13:09:34 INFO - 1501013374155 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:34 INFO - 1501013374156 Marionette TRACE 373 <- [1,4,null,{}]
13:09:34 INFO - 1501013374159 Marionette TRACE 373 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
13:09:34 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501013374166 Marionette TRACE 373 <- [1,5,null,{"value":null}]
13:09:34 INFO - 1501013374168 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
13:09:34 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1501013374173 Marionette TRACE 373 <- [1,6,null,{}]
13:09:34 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1501013374179 Marionette DEBUG Closed connection 373
13:09:34 INFO - 1501013374180 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49802
13:09:34 INFO - 1501013374182 Marionette DEBUG Closed connection 374
13:09:34 INFO - 1501013374183 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49803
13:09:34 INFO - 1501013374185 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:34 INFO - 1501013374187 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:34 INFO - 1501013374208 Marionette DEBUG Register listener.js for window 12
13:09:34 INFO - 1501013374219 Marionette TRACE 375 <- [1,1,null,{"sessionId":"74a6b6bc-add9-46b8-936b-de44ab054c81","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:34 INFO - 1501013374225 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
13:09:34 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1501013374229 Marionette TRACE 375 <- [1,2,null,{}]
13:09:34 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1501013374235 Marionette DEBUG Closed connection 375
13:09:34 INFO - 1501013374237 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49804
13:09:34 INFO - 1501013374238 Marionette DEBUG Closed connection 376
13:09:34 INFO - 1501013374240 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49805
13:09:34 INFO - 1501013374242 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:34 INFO - 1501013374244 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:34 INFO - 1501013374265 Marionette DEBUG Register listener.js for window 12
13:09:34 INFO - 1501013374276 Marionette TRACE 377 <- [1,1,null,{"sessionId":"8e46cda1-7c1d-4ed5-9200-16266a70f101","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:34 INFO - 1501013374282 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
13:09:34 INFO - 1501013374286 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:34 INFO - 1501013374292 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:34 INFO - 1501013374293 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:34 INFO - ++DOMWINDOW == 23 (000000CB97C6A000) [pid = 2412] [serial = 45] [outer = 000000CB8ED10800]
13:09:34 INFO - 1501013374343 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:09:34 INFO - 1501013374350 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:09:34 INFO - 1501013374365 Marionette TRACE 377 <- [1,2,null,{}]
13:09:34 INFO - 1501013374369 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:09:34 INFO - 1501013374375 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"94735302-6b02-4527-bc73-b3da8f682802","ELEMENT":"94735302-6b02-4527-bc73-b3da8f682802"}}]
13:09:34 INFO - 1501013374377 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"94735302-6b02-4527-bc73-b3da8f682802"}]
13:09:34 INFO - 1501013374849 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:34 INFO - 1501013374852 Marionette TRACE 377 <- [1,4,null,{}]
13:09:34 INFO - 1501013374858 Marionette TRACE 377 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
13:09:34 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501013374873 Marionette TRACE 377 <- [1,5,null,{"value":null}]
13:09:34 INFO - 1501013374879 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
13:09:34 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1501013374889 Marionette TRACE 377 <- [1,6,null,{}]
13:09:34 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 797ms
13:09:34 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:09:34 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1501013374948 Marionette DEBUG Closed connection 377
13:09:34 INFO - 1501013374951 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49806
13:09:34 INFO - 1501013374971 Marionette DEBUG Closed connection 378
13:09:34 INFO - 1501013374974 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49807
13:09:34 INFO - 1501013374981 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:34 INFO - 1501013374992 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:35 INFO - 1501013375032 Marionette DEBUG Register listener.js for window 12
13:09:35 INFO - 1501013375055 Marionette TRACE 379 <- [1,1,null,{"sessionId":"75a615cd-da26-418d-bdda-9c904cb296b0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:35 INFO - 1501013375074 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
13:09:35 INFO - 1501013375077 Marionette TRACE 379 <- [1,2,null,{}]
13:09:35 INFO - 1501013375091 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:35 INFO - 1501013375095 Marionette TRACE 379 <- [1,3,null,{}]
13:09:35 INFO - 1501013375098 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:35 INFO - 1501013375101 Marionette TRACE 379 <- [1,4,null,{}]
13:09:35 INFO - 1501013375105 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
13:09:35 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501013375118 Marionette TRACE 379 <- [1,5,null,{"value":null}]
13:09:35 INFO - 1501013375126 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
13:09:35 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1501013375132 Marionette TRACE 379 <- [1,6,null,{}]
13:09:35 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1501013375144 Marionette DEBUG Closed connection 379
13:09:35 INFO - 1501013375147 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49808
13:09:35 INFO - 1501013375167 Marionette DEBUG Closed connection 380
13:09:35 INFO - 1501013375169 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49809
13:09:35 INFO - 1501013375172 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:35 INFO - 1501013375175 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:35 INFO - 1501013375215 Marionette DEBUG Register listener.js for window 12
13:09:35 INFO - 1501013375243 Marionette TRACE 381 <- [1,1,null,{"sessionId":"885e57ed-5f55-43c4-9e11-34ebce1f47e2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:35 INFO - 1501013375256 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
13:09:35 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1501013375264 Marionette TRACE 381 <- [1,2,null,{}]
13:09:35 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1501013375287 Marionette DEBUG Closed connection 381
13:09:35 INFO - 1501013375289 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49810
13:09:35 INFO - 1501013375341 Marionette DEBUG Closed connection 382
13:09:35 INFO - 1501013375343 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49811
13:09:35 INFO - 1501013375354 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:35 INFO - 1501013375360 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:35 INFO - 1501013375399 Marionette DEBUG Register listener.js for window 12
13:09:35 INFO - 1501013375416 Marionette TRACE 383 <- [1,1,null,{"sessionId":"bcb1df7d-32f6-4829-a594-5078d529a810","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:35 INFO - 1501013375427 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
13:09:35 INFO - 1501013375433 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:09:35 INFO - 1501013375443 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:09:35 INFO - 1501013375444 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:09:35 INFO - ++DOMWINDOW == 24 (000000CB94CD0800) [pid = 2412] [serial = 46] [outer = 000000CB8ED10800]
13:09:35 INFO - 1501013375512 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:35 INFO - 1501013375519 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:35 INFO - 1501013375553 Marionette TRACE 383 <- [1,2,null,{}]
13:09:35 INFO - 1501013375558 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:09:35 INFO - 1501013375569 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b94c65b2-a17d-4308-b9da-f5146095f954","ELEMENT":"b94c65b2-a17d-4308-b9da-f5146095f954"}}]
13:09:35 INFO - 1501013375571 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"b94c65b2-a17d-4308-b9da-f5146095f954"}]
13:09:36 INFO - 1501013376166 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:36 INFO - 1501013376169 Marionette TRACE 383 <- [1,4,null,{}]
13:09:36 INFO - 1501013376173 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
13:09:36 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501013376184 Marionette TRACE 383 <- [1,5,null,{"value":null}]
13:09:36 INFO - 1501013376187 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
13:09:36 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:36 INFO - 1501013376193 Marionette TRACE 383 <- [1,6,null,{}]
13:09:36 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1303ms
13:09:36 INFO - TEST-START | test_click.py TestClick.test_input_file
13:09:36 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:36 INFO - 1501013376368 Marionette DEBUG Closed connection 383
13:09:36 INFO - 1501013376371 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49812
13:09:36 INFO - 1501013376376 Marionette DEBUG Closed connection 384
13:09:36 INFO - 1501013376379 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49813
13:09:36 INFO - 1501013376402 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:36 INFO - 1501013376413 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:36 INFO - 1501013376437 Marionette DEBUG Register listener.js for window 12
13:09:36 INFO - 1501013376479 Marionette TRACE 385 <- [1,1,null,{"sessionId":"d2b9bbdd-d951-4c1f-a439-8292767a86e9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:36 INFO - 1501013376511 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
13:09:36 INFO - 1501013376514 Marionette TRACE 385 <- [1,2,null,{}]
13:09:36 INFO - 1501013376520 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:36 INFO - 1501013376524 Marionette TRACE 385 <- [1,3,null,{}]
13:09:36 INFO - 1501013376528 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:36 INFO - 1501013376532 Marionette TRACE 385 <- [1,4,null,{}]
13:09:36 INFO - 1501013376588 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
13:09:36 INFO - TEST-START: test_click.py TestClick.test_input_file1501013376600 Marionette TRACE 385 <- [1,5,null,{"value":null}]
13:09:36 INFO - 1501013376607 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
13:09:36 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:36 INFO - 1501013376614 Marionette TRACE 385 <- [1,6,null,{}]
13:09:36 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:36 INFO - 1501013376637 Marionette DEBUG Closed connection 385
13:09:36 INFO - 1501013376641 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49814
13:09:36 INFO - 1501013376655 Marionette DEBUG Closed connection 386
13:09:36 INFO - 1501013376657 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49815
13:09:36 INFO - 1501013376664 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:36 INFO - 1501013376668 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:36 INFO - 1501013376691 Marionette DEBUG Register listener.js for window 12
13:09:36 INFO - 1501013376708 Marionette TRACE 387 <- [1,1,null,{"sessionId":"4d6aad0b-9a57-42cc-a15a-6cc07d4212af","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:36 INFO - 1501013376717 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
13:09:36 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:36 INFO - 1501013376723 Marionette TRACE 387 <- [1,2,null,{}]
13:09:36 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:36 INFO - 1501013376749 Marionette DEBUG Closed connection 387
13:09:36 INFO - 1501013376751 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49816
13:09:36 INFO - 1501013376755 Marionette DEBUG Closed connection 388
13:09:36 INFO - 1501013376757 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49817
13:09:36 INFO - 1501013376763 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:36 INFO - 1501013376767 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:36 INFO - 1501013376789 Marionette DEBUG Register listener.js for window 12
13:09:36 INFO - 1501013376804 Marionette TRACE 389 <- [1,1,null,{"sessionId":"89b7cf8e-3791-49f6-b19c-b8bf347fb633","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:36 INFO - 1501013376815 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:09:36 INFO - 1501013376820 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:36 INFO - 1501013376829 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:36 INFO - 1501013376831 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:36 INFO - ++DOMWINDOW == 25 (000000CB84B4B800) [pid = 2412] [serial = 47] [outer = 000000CB8ED10800]
13:09:36 INFO - 1501013376893 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:36 INFO - 1501013376899 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:36 INFO - 1501013376922 Marionette TRACE 389 <- [1,2,null,{}]
13:09:36 INFO - 1501013376933 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:09:36 INFO - 1501013376946 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c1178fcc-97ce-4bba-b063-8f7ab58312e2","ELEMENT":"c1178fcc-97ce-4bba-b063-8f7ab58312e2"}}]
13:09:36 INFO - 1501013376948 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"c1178fcc-97ce-4bba-b063-8f7ab58312e2"}]
13:09:37 INFO - 1501013377109 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
13:09:37 INFO - --DOMWINDOW == 24 (000000CB99185800) [pid = 2412] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
13:09:37 INFO - --DOMWINDOW == 23 (000000CB94FDC800) [pid = 2412] [serial = 35] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20vertically%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
13:09:37 INFO - --DOMWINDOW == 22 (000000CB8F1B7800) [pid = 2412] [serial = 34] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20horizontally%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
13:09:37 INFO - --DOMWINDOW == 21 (000000CB88821800) [pid = 2412] [serial = 33] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
13:09:37 INFO - 1501013377132 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
13:09:37 INFO - TEST-END: test_click.py TestClick.test_input_file1501013377144 Marionette TRACE 389 <- [1,5,null,{"value":null}]
13:09:37 INFO - 1501013377148 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
13:09:37 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1501013377153 Marionette TRACE 389 <- [1,6,null,{}]
13:09:37 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 959ms
13:09:37 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:09:37 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1501013377159 Marionette DEBUG Closed connection 389
13:09:37 INFO - 1501013377161 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49818
13:09:37 INFO - 1501013377163 Marionette DEBUG Closed connection 390
13:09:37 INFO - 1501013377165 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49819
13:09:37 INFO - 1501013377168 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:37 INFO - 1501013377170 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:37 INFO - 1501013377193 Marionette DEBUG Register listener.js for window 12
13:09:37 INFO - 1501013377206 Marionette TRACE 391 <- [1,1,null,{"sessionId":"c93696d5-5aa8-4de5-be88-f9f7b6a96620","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:37 INFO - 1501013377214 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
13:09:37 INFO - 1501013377216 Marionette TRACE 391 <- [1,2,null,{}]
13:09:37 INFO - 1501013377218 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:37 INFO - 1501013377220 Marionette TRACE 391 <- [1,3,null,{}]
13:09:37 INFO - 1501013377222 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:37 INFO - 1501013377224 Marionette TRACE 391 <- [1,4,null,{}]
13:09:37 INFO - 1501013377226 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
13:09:37 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501013377235 Marionette TRACE 391 <- [1,5,null,{"value":null}]
13:09:37 INFO - 1501013377237 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
13:09:37 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1501013377241 Marionette TRACE 391 <- [1,6,null,{}]
13:09:37 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1501013377248 Marionette DEBUG Closed connection 391
13:09:37 INFO - 1501013377250 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49820
13:09:37 INFO - 1501013377252 Marionette DEBUG Closed connection 392
13:09:37 INFO - 1501013377254 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49821
13:09:37 INFO - 1501013377256 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:37 INFO - 1501013377261 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:37 INFO - 1501013377284 Marionette DEBUG Register listener.js for window 12
13:09:37 INFO - 1501013377301 Marionette TRACE 393 <- [1,1,null,{"sessionId":"55a19d28-97ab-403e-89c9-698b8b1692fc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:37 INFO - 1501013377307 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
13:09:37 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1501013377311 Marionette TRACE 393 <- [1,2,null,{}]
13:09:37 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1501013377320 Marionette DEBUG Closed connection 393
13:09:37 INFO - 1501013377325 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49822
13:09:37 INFO - 1501013377328 Marionette DEBUG Closed connection 394
13:09:37 INFO - 1501013377329 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49823
13:09:37 INFO - 1501013377331 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:37 INFO - 1501013377334 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:37 INFO - 1501013377357 Marionette DEBUG Register listener.js for window 12
13:09:37 INFO - 1501013377371 Marionette TRACE 395 <- [1,1,null,{"sessionId":"9f5465cf-7ffd-481d-bc2e-89691761ee96","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:37 INFO - 1501013377377 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
13:09:37 INFO - 1501013377381 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:37 INFO - 1501013377389 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:37 INFO - 1501013377390 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:37 INFO - ++DOMWINDOW == 22 (000000CB8EBEF000) [pid = 2412] [serial = 48] [outer = 000000CB8ED10800]
13:09:37 INFO - 1501013377457 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:09:37 INFO - 1501013377463 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:09:37 INFO - 1501013377474 Marionette TRACE 395 <- [1,2,null,{}]
13:09:37 INFO - 1501013377485 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:09:37 INFO - 1501013377493 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6f3b4b56-4738-49f8-9988-f08e53ccf599","ELEMENT":"6f3b4b56-4738-49f8-9988-f08e53ccf599"}}]
13:09:37 INFO - 1501013377495 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:09:37 INFO - 1501013377503 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7c027ca2-3f5f-40a8-86e1-004767dafe04","ELEMENT":"7c027ca2-3f5f-40a8-86e1-004767dafe04"}}]
13:09:37 INFO - 1501013377505 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"6f3b4b56-4738-49f8-9988-f08e53ccf599"}]
13:09:37 INFO - 1501013377957 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:37 INFO - 1501013377959 Marionette TRACE 395 <- [1,5,null,{}]
13:09:37 INFO - 1501013377961 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"7c027ca2-3f5f-40a8-86e1-004767dafe04"}]
13:09:38 INFO - 1501013378101 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
13:09:38 INFO - 1501013378105 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
13:09:38 INFO - 1501013378114 Marionette TRACE 395 <- [1,7,null,{"value":false}]
13:09:38 INFO - 1501013378118 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
13:09:38 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501013378127 Marionette TRACE 395 <- [1,8,null,{"value":null}]
13:09:38 INFO - 1501013378129 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
13:09:38 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1501013378133 Marionette TRACE 395 <- [1,9,null,{}]
13:09:38 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 979ms
13:09:38 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:09:38 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1501013378140 Marionette DEBUG Closed connection 395
13:09:38 INFO - 1501013378141 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49824
13:09:38 INFO - 1501013378143 Marionette DEBUG Closed connection 396
13:09:38 INFO - 1501013378144 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49825
13:09:38 INFO - 1501013378147 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:38 INFO - 1501013378149 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:38 INFO - 1501013378171 Marionette DEBUG Register listener.js for window 12
13:09:38 INFO - 1501013378183 Marionette TRACE 397 <- [1,1,null,{"sessionId":"1f2e338c-20bf-41c4-bbf5-6209f303acc6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:38 INFO - 1501013378189 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
13:09:38 INFO - 1501013378191 Marionette TRACE 397 <- [1,2,null,{}]
13:09:38 INFO - 1501013378193 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:38 INFO - 1501013378194 Marionette TRACE 397 <- [1,3,null,{}]
13:09:38 INFO - 1501013378196 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:38 INFO - 1501013378198 Marionette TRACE 397 <- [1,4,null,{}]
13:09:38 INFO - 1501013378200 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
13:09:38 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501013378209 Marionette TRACE 397 <- [1,5,null,{"value":null}]
13:09:38 INFO - 1501013378211 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
13:09:38 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1501013378215 Marionette TRACE 397 <- [1,6,null,{}]
13:09:38 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1501013378221 Marionette DEBUG Closed connection 397
13:09:38 INFO - 1501013378222 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49826
13:09:38 INFO - 1501013378224 Marionette DEBUG Closed connection 398
13:09:38 INFO - 1501013378225 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49827
13:09:38 INFO - 1501013378228 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:38 INFO - 1501013378231 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:38 INFO - 1501013378254 Marionette DEBUG Register listener.js for window 12
13:09:38 INFO - 1501013378266 Marionette TRACE 399 <- [1,1,null,{"sessionId":"4cadffa6-345e-448b-bfd1-5a3f007364a6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:38 INFO - 1501013378272 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
13:09:38 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1501013378276 Marionette TRACE 399 <- [1,2,null,{}]
13:09:38 INFO - [Parent 2412] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1501013378282 Marionette DEBUG Closed connection 399
13:09:38 INFO - 1501013378284 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49828
13:09:38 INFO - 1501013378285 Marionette DEBUG Closed connection 400
13:09:38 INFO - 1501013378287 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49829
13:09:38 INFO - 1501013378289 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:38 INFO - 1501013378292 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}
13:09:38 INFO - 1501013378315 Marionette DEBUG Register listener.js for window 12
13:09:38 INFO - 1501013378326 Marionette TRACE 401 <- [1,1,null,{"sessionId":"1d934e7a-c08d-4d70-a8ef-454d3b80c2a6","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":2412,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-079.001\\AppData\\Local\\Temp\\tmpaxdalf.mozrunner","moz:accessibilityChecks":false}}]
13:09:38 INFO - 1501013378333 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
13:09:38 INFO - 1501013378337 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:09:38 INFO - 1501013378344 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:09:38 INFO - 1501013378345 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:09:38 INFO - ++DOMWINDOW == 23 (000000CB97C63800) [pid = 2412] [serial = 49] [outer = 000000CB8ED10800]
13:09:38 INFO - 1501013378405 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
13:09:38 INFO - 1501013378410 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
13:09:38 INFO - 1501013378418 Marionette TRACE 401 <- [1,2,null,{}]
13:09:38 INFO - 1501013378430 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:09:38 INFO - 1501013378438 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b955b5be-c95d-414e-a77d-6ad1be10dd08","ELEMENT":"b955b5be-c95d-414e-a77d-6ad1be10dd08"}}]
13:09:38 INFO - 1501013378440 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b955b5be-c95d-414e-a77d-6ad1be10dd08"}]
13:09:38 INFO - 1501013378567 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
13:09:38 INFO - 1501013378569 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"b955b5be-c95d-414e-a77d-6ad1be10dd08"}]
13:09:38 INFO - 1501013378712 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element