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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
13:14:45 INFO - 1499458485721 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:14:45 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499458485732 Marionette TRACE 352 <- [1,5,null,{"value":null}]
13:14:45 INFO - 1499458485735 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
13:14:45 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:45 INFO - 1499458485740 Marionette TRACE 352 <- [1,6,null,{}]
13:14:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1011ms
13:14:45 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:14:45 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:45 INFO - 1499458485750 Marionette DEBUG Closed connection 352
13:14:45 INFO - 1499458485753 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49780
13:14:45 INFO - 1499458485755 Marionette DEBUG Closed connection 353
13:14:45 INFO - 1499458485758 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49781
13:14:45 INFO - 1499458485782 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:45 INFO - 1499458485790 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:45 INFO - 1499458485814 Marionette DEBUG Register listener.js for window 12
13:14:45 INFO - 1499458485843 Marionette TRACE 354 <- [1,1,null,{"sessionId":"0338a25b-77fa-42bc-95a9-babff2a774ac","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:45 INFO - 1499458485858 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
13:14:45 INFO - 1499458485861 Marionette TRACE 354 <- [1,2,null,{}]
13:14:45 INFO - 1499458485863 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:14:45 INFO - 1499458485865 Marionette TRACE 354 <- [1,3,null,{}]
13:14:45 INFO - 1499458485869 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
13:14:45 INFO - 1499458485871 Marionette TRACE 354 <- [1,4,null,{}]
13:14:45 INFO - 1499458485915 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:14:45 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499458485929 Marionette TRACE 354 <- [1,5,null,{"value":null}]
13:14:45 INFO - 1499458485940 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
13:14:45 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:45 INFO - 1499458485948 Marionette TRACE 354 <- [1,6,null,{}]
13:14:45 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:45 INFO - 1499458485955 Marionette DEBUG Closed connection 354
13:14:45 INFO - 1499458485958 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49782
13:14:45 INFO - 1499458485960 Marionette DEBUG Closed connection 355
13:14:45 INFO - 1499458485962 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49783
13:14:45 INFO - 1499458485965 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:45 INFO - 1499458485968 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:45 INFO - 1499458485990 Marionette DEBUG Register listener.js for window 12
13:14:46 INFO - 1499458486005 Marionette TRACE 356 <- [1,1,null,{"sessionId":"8dc58ead-83d6-46a1-8755-6a429d04cf58","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:46 INFO - 1499458486038 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
13:14:46 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:46 INFO - 1499458486046 Marionette TRACE 356 <- [1,2,null,{}]
13:14:46 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:46 INFO - 1499458486052 Marionette DEBUG Closed connection 356
13:14:46 INFO - 1499458486054 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49784
13:14:46 INFO - 1499458486076 Marionette DEBUG Closed connection 357
13:14:46 INFO - 1499458486078 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49785
13:14:46 INFO - 1499458486085 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:14:46 INFO - 1499458486089 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:46 INFO - 1499458486110 Marionette DEBUG Register listener.js for window 12
13:14:46 INFO - 1499458486126 Marionette TRACE 358 <- [1,1,null,{"sessionId":"9394fa3b-6612-4293-90d8-96e2adc60530","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:46 INFO - 1499458486133 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
13:14:46 INFO - 1499458486137 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
13:14:46 INFO - 1499458486157 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
13:14:46 INFO - 1499458486158 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
13:14:46 INFO - ++DOMWINDOW == 28 (000000CF980EE800) [pid = 3592] [serial = 44] [outer = 000000CFA4540800]
13:14:46 INFO - 1499458486231 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
13:14:46 INFO - 1499458486237 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
13:14:46 INFO - 1499458486259 Marionette TRACE 358 <- [1,2,null,{}]
13:14:46 INFO - --DOMWINDOW == 27 (000000CF98B18800) [pid = 3592] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:14:46 INFO - --DOMWINDOW == 26 (000000CFA80B9000) [pid = 3592] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:14:46 INFO - --DOMWINDOW == 25 (000000CF9DE15000) [pid = 3592] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:14:46 INFO - --DOMWINDOW == 24 (000000CFA4407000) [pid = 3592] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:14:46 INFO - --DOMWINDOW == 23 (000000CFA5A16800) [pid = 3592] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:14:46 INFO - --DOMWINDOW == 22 (000000CFA609C000) [pid = 3592] [serial = 33] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:14:46 INFO - --DOMWINDOW == 21 (000000CFA8E23800) [pid = 3592] [serial = 34] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:14:46 INFO - 1499458486277 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:14:46 INFO - 1499458486307 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"700f50d7-436a-4bd9-aba9-f9ad2a3a9f5c","ELEMENT":"700f50d7-436a-4bd9-aba9-f9ad2a3a9f5c"}}]
13:14:46 INFO - 1499458486324 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"700f50d7-436a-4bd9-aba9-f9ad2a3a9f5c"}]
13:14:46 INFO - 1499458486564 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
13:14:46 INFO - [3592] 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:14:46 INFO - 1499458486580 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
13:14:46 INFO - 1499458486582 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
13:14:46 INFO - ++DOMWINDOW == 22 (000000CFA897A000) [pid = 3592] [serial = 45] [outer = 000000CFA4540800]
13:14:46 INFO - 1499458486654 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
13:14:46 INFO - 1499458486660 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
13:14:46 INFO - 1499458486678 Marionette TRACE 358 <- [1,4,null,{}]
13:14:46 INFO - 1499458486773 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:14:46 INFO - 1499458486782 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ab773535-d5df-494b-a0f0-77ceffec4292","ELEMENT":"ab773535-d5df-494b-a0f0-77ceffec4292"}}]
13:14:46 INFO - 1499458486784 Marionette TRACE 358 -> [0,6,"getTitle",{}]
13:14:46 INFO - 1499458486786 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
13:14:46 INFO - 1499458486788 Marionette TRACE 358 -> [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:14:46 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499458486797 Marionette TRACE 358 <- [1,7,null,{"value":null}]
13:14:46 INFO - 1499458486798 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
13:14:46 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:46 INFO - 1499458486803 Marionette TRACE 358 <- [1,8,null,{}]
13:14:46 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1061ms
13:14:46 INFO - TEST-START | test_click.py TestClick.test_container_element
13:14:46 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:46 INFO - 1499458486809 Marionette DEBUG Closed connection 358
13:14:46 INFO - 1499458486811 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49788
13:14:46 INFO - 1499458486813 Marionette DEBUG Closed connection 359
13:14:46 INFO - 1499458486815 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49789
13:14:46 INFO - 1499458486819 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:46 INFO - 1499458486822 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:46 INFO - 1499458486845 Marionette DEBUG Register listener.js for window 12
13:14:46 INFO - 1499458486857 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d3473fc3-3ba4-42f5-8fee-eae96afb1073","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:46 INFO - 1499458486919 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
13:14:46 INFO - 1499458486921 Marionette TRACE 360 <- [1,2,null,{}]
13:14:46 INFO - 1499458486925 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:14:46 INFO - 1499458486926 Marionette TRACE 360 <- [1,3,null,{}]
13:14:46 INFO - 1499458486928 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
13:14:46 INFO - 1499458486930 Marionette TRACE 360 <- [1,4,null,{}]
13:14:46 INFO - 1499458486936 Marionette TRACE 360 -> [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:14:46 INFO - TEST-START: test_click.py TestClick.test_container_element1499458486948 Marionette TRACE 360 <- [1,5,null,{"value":null}]
13:14:46 INFO - 1499458486951 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
13:14:46 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:46 INFO - 1499458486955 Marionette TRACE 360 <- [1,6,null,{}]
13:14:47 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:47 INFO - 1499458487660 Marionette DEBUG Closed connection 360
13:14:47 INFO - 1499458487662 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49790
13:14:47 INFO - 1499458487664 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49791
13:14:47 INFO - 1499458487683 Marionette DEBUG Closed connection 361
13:14:47 INFO - 1499458487691 Marionette DEBUG Closed connection 362
13:14:47 INFO - 1499458487696 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49792
13:14:47 INFO - 1499458487703 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:47 INFO - 1499458487708 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:47 INFO - 1499458487730 Marionette DEBUG Register listener.js for window 12
13:14:47 INFO - 1499458487790 Marionette TRACE 363 <- [1,1,null,{"sessionId":"a8b62b8e-361e-404c-8102-1895cea89e8d","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:47 INFO - 1499458487847 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
13:14:47 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:47 INFO - 1499458487852 Marionette TRACE 363 <- [1,2,null,{}]
13:14:47 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:47 INFO - 1499458487908 Marionette DEBUG Closed connection 363
13:14:47 INFO - 1499458487910 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49793
13:14:47 INFO - 1499458487927 Marionette DEBUG Closed connection 364
13:14:47 INFO - 1499458487929 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49794
13:14:47 INFO - 1499458487942 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:14:47 INFO - 1499458487945 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:47 INFO - 1499458487967 Marionette DEBUG Register listener.js for window 12
13:14:47 INFO - 1499458487981 Marionette TRACE 365 <- [1,1,null,{"sessionId":"e0b556ee-902d-44d6-8cf1-08deefb49dd1","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:47 INFO - 1499458487992 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:14:47 INFO - 1499458487996 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
13:14:48 INFO - 1499458488003 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
13:14:48 INFO - 1499458488003 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
13:14:48 INFO - ++DOMWINDOW == 23 (000000CF9E2D8000) [pid = 3592] [serial = 46] [outer = 000000CFA4540800]
13:14:48 INFO - 1499458488058 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:14:48 INFO - 1499458488074 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:14:48 INFO - 1499458488093 Marionette TRACE 365 <- [1,2,null,{}]
13:14:48 INFO - 1499458488101 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:14:48 INFO - 1499458488109 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6a1a8b5a-c71d-4852-a333-763adbac50d5","ELEMENT":"6a1a8b5a-c71d-4852-a333-763adbac50d5"}}]
13:14:48 INFO - 1499458488111 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"6a1a8b5a-c71d-4852-a333-763adbac50d5"}]
13:14:48 INFO - [3592] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
13:14:48 INFO - [3592] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
13:14:48 INFO - [3592] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
13:14:48 INFO - 1499458488556 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:14:48 INFO - 1499458488558 Marionette TRACE 365 <- [1,4,null,{}]
13:14:48 INFO - 1499458488560 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"6a1a8b5a-c71d-4852-a333-763adbac50d5","name":"selected"}]
13:14:48 INFO - 1499458488588 Marionette TRACE 365 <- [1,5,null,{"value":true}]
13:14:48 INFO - 1499458488591 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:14:48 INFO - TEST-END: test_click.py TestClick.test_container_element1499458488598 Marionette TRACE 365 <- [1,6,null,{"value":null}]
13:14:48 INFO - 1499458488600 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
13:14:48 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:48 INFO - 1499458488604 Marionette TRACE 365 <- [1,7,null,{}]
13:14:48 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1802ms
13:14:48 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:14:48 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:48 INFO - 1499458488610 Marionette DEBUG Closed connection 365
13:14:48 INFO - 1499458488612 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49795
13:14:48 INFO - 1499458488614 Marionette DEBUG Closed connection 366
13:14:48 INFO - 1499458488615 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49796
13:14:48 INFO - 1499458488617 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:48 INFO - 1499458488620 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:48 INFO - 1499458488642 Marionette DEBUG Register listener.js for window 12
13:14:48 INFO - 1499458488653 Marionette TRACE 367 <- [1,1,null,{"sessionId":"3443845f-bdab-4859-870e-df2c59cd7423","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:48 INFO - 1499458488693 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
13:14:48 INFO - 1499458488695 Marionette TRACE 367 <- [1,2,null,{}]
13:14:48 INFO - 1499458488697 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:14:48 INFO - 1499458488699 Marionette TRACE 367 <- [1,3,null,{}]
13:14:48 INFO - 1499458488700 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
13:14:48 INFO - 1499458488702 Marionette TRACE 367 <- [1,4,null,{}]
13:14:48 INFO - 1499458488705 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:14:48 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499458488714 Marionette TRACE 367 <- [1,5,null,{"value":null}]
13:14:48 INFO - 1499458488716 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
13:14:48 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:48 INFO - 1499458488721 Marionette TRACE 367 <- [1,6,null,{}]
13:14:48 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:48 INFO - 1499458488727 Marionette DEBUG Closed connection 367
13:14:48 INFO - 1499458488729 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49797
13:14:48 INFO - 1499458488730 Marionette DEBUG Closed connection 368
13:14:48 INFO - 1499458488732 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49798
13:14:48 INFO - 1499458488734 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:48 INFO - 1499458488737 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:48 INFO - 1499458488760 Marionette DEBUG Register listener.js for window 12
13:14:48 INFO - 1499458488772 Marionette TRACE 369 <- [1,1,null,{"sessionId":"9cbac73d-48f4-4f39-89b1-b71af5f343c2","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:48 INFO - 1499458488779 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
13:14:48 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:48 INFO - 1499458488783 Marionette TRACE 369 <- [1,2,null,{}]
13:14:48 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:48 INFO - 1499458488789 Marionette DEBUG Closed connection 369
13:14:48 INFO - 1499458488790 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49799
13:14:48 INFO - 1499458488792 Marionette DEBUG Closed connection 370
13:14:48 INFO - 1499458488794 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49800
13:14:48 INFO - 1499458488796 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:14:48 INFO - 1499458488798 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:48 INFO - 1499458488821 Marionette DEBUG Register listener.js for window 12
13:14:48 INFO - 1499458488832 Marionette TRACE 371 <- [1,1,null,{"sessionId":"1e8fc98a-4db4-403b-927f-ed8bb8c04d8d","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:48 INFO - 1499458488838 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:14:48 INFO - 1499458488842 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:14:48 INFO - 1499458488849 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:14:48 INFO - 1499458488850 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:14:48 INFO - ++DOMWINDOW == 24 (000000CFA8B1D000) [pid = 3592] [serial = 47] [outer = 000000CFA4540800]
13:14:48 INFO - 1499458488902 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:14:48 INFO - 1499458488908 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:14:48 INFO - 1499458488926 Marionette TRACE 371 <- [1,2,null,{}]
13:14:48 INFO - 1499458488930 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:14:48 INFO - 1499458488937 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"21b73498-8416-4d34-a97e-265c4ac969d4","ELEMENT":"21b73498-8416-4d34-a97e-265c4ac969d4"}}]
13:14:48 INFO - 1499458488939 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"21b73498-8416-4d34-a97e-265c4ac969d4"}]
13:14:49 INFO - [3592] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
13:14:49 INFO - [3592] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
13:14:49 INFO - [3592] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
13:14:49 INFO - 1499458489467 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:14:49 INFO - 1499458489469 Marionette TRACE 371 <- [1,4,null,{}]
13:14:49 INFO - 1499458489471 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"21b73498-8416-4d34-a97e-265c4ac969d4","name":"selected"}]
13:14:49 INFO - 1499458489499 Marionette TRACE 371 <- [1,5,null,{"value":true}]
13:14:49 INFO - 1499458489501 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:14:49 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499458489509 Marionette TRACE 371 <- [1,6,null,{"value":null}]
13:14:49 INFO - 1499458489511 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
13:14:49 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:49 INFO - 1499458489515 Marionette TRACE 371 <- [1,7,null,{}]
13:14:49 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 910ms
13:14:49 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:14:49 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:49 INFO - 1499458489521 Marionette DEBUG Closed connection 371
13:14:49 INFO - 1499458489523 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49801
13:14:49 INFO - 1499458489524 Marionette DEBUG Closed connection 372
13:14:49 INFO - 1499458489526 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49802
13:14:49 INFO - 1499458489528 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:49 INFO - 1499458489530 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:49 INFO - 1499458489552 Marionette DEBUG Register listener.js for window 12
13:14:49 INFO - 1499458489564 Marionette TRACE 373 <- [1,1,null,{"sessionId":"c6fb76a7-436b-4baf-9841-72a84dbe5956","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:49 INFO - 1499458489605 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
13:14:49 INFO - 1499458489607 Marionette TRACE 373 <- [1,2,null,{}]
13:14:49 INFO - 1499458489609 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:14:49 INFO - 1499458489611 Marionette TRACE 373 <- [1,3,null,{}]
13:14:49 INFO - 1499458489614 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
13:14:49 INFO - 1499458489615 Marionette TRACE 373 <- [1,4,null,{}]
13:14:49 INFO - 1499458489618 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:14:49 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499458489625 Marionette TRACE 373 <- [1,5,null,{"value":null}]
13:14:49 INFO - 1499458489627 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
13:14:49 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:49 INFO - 1499458489632 Marionette TRACE 373 <- [1,6,null,{}]
13:14:49 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:49 INFO - 1499458489638 Marionette DEBUG Closed connection 373
13:14:49 INFO - 1499458489639 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49803
13:14:49 INFO - 1499458489641 Marionette DEBUG Closed connection 374
13:14:49 INFO - 1499458489642 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49804
13:14:49 INFO - 1499458489644 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:49 INFO - 1499458489647 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:49 INFO - 1499458489675 Marionette DEBUG Register listener.js for window 12
13:14:49 INFO - 1499458489689 Marionette TRACE 375 <- [1,1,null,{"sessionId":"d8c05852-cf03-4164-b944-c8e1562e976d","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:49 INFO - 1499458489696 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
13:14:49 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:49 INFO - 1499458489699 Marionette TRACE 375 <- [1,2,null,{}]
13:14:49 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:49 INFO - 1499458489705 Marionette DEBUG Closed connection 375
13:14:49 INFO - 1499458489707 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49805
13:14:49 INFO - 1499458489708 Marionette DEBUG Closed connection 376
13:14:49 INFO - 1499458489710 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49806
13:14:49 INFO - 1499458489712 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:14:49 INFO - 1499458489714 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:49 INFO - 1499458489737 Marionette DEBUG Register listener.js for window 12
13:14:49 INFO - 1499458489757 Marionette TRACE 377 <- [1,1,null,{"sessionId":"3a9b9104-d37a-4868-a15d-e8c97b218f03","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:49 INFO - 1499458489763 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:14:49 INFO - 1499458489767 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:14:49 INFO - 1499458489776 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:14:49 INFO - 1499458489777 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:14:49 INFO - ++DOMWINDOW == 25 (000000CFAA850000) [pid = 3592] [serial = 48] [outer = 000000CFA4540800]
13:14:49 INFO - 1499458489837 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:14:49 INFO - 1499458489845 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:14:49 INFO - 1499458489861 Marionette TRACE 377 <- [1,2,null,{}]
13:14:49 INFO - 1499458489918 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:14:49 INFO - 1499458489925 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c1b07358-7b5c-460c-96ed-687f77f78746","ELEMENT":"c1b07358-7b5c-460c-96ed-687f77f78746"}}]
13:14:49 INFO - 1499458489927 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"c1b07358-7b5c-460c-96ed-687f77f78746"}]
13:14:50 INFO - 1499458490354 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:14:50 INFO - 1499458490355 Marionette TRACE 377 <- [1,4,null,{}]
13:14:50 INFO - 1499458490358 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:14:50 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499458490367 Marionette TRACE 377 <- [1,5,null,{"value":null}]
13:14:50 INFO - 1499458490369 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
13:14:50 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:50 INFO - 1499458490374 Marionette TRACE 377 <- [1,6,null,{}]
13:14:50 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 858ms
13:14:50 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:14:50 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:50 INFO - 1499458490380 Marionette DEBUG Closed connection 377
13:14:50 INFO - 1499458490381 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49807
13:14:50 INFO - 1499458490383 Marionette DEBUG Closed connection 378
13:14:50 INFO - 1499458490384 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49808
13:14:50 INFO - 1499458490386 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:50 INFO - 1499458490388 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:50 INFO - 1499458490411 Marionette DEBUG Register listener.js for window 12
13:14:50 INFO - 1499458490421 Marionette TRACE 379 <- [1,1,null,{"sessionId":"a02f5f4f-e470-4e98-b69c-863ac9888402","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:50 INFO - 1499458490454 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
13:14:50 INFO - 1499458490455 Marionette TRACE 379 <- [1,2,null,{}]
13:14:50 INFO - 1499458490457 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:14:50 INFO - 1499458490459 Marionette TRACE 379 <- [1,3,null,{}]
13:14:50 INFO - 1499458490461 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
13:14:50 INFO - 1499458490462 Marionette TRACE 379 <- [1,4,null,{}]
13:14:50 INFO - 1499458490465 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:14:50 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499458490473 Marionette TRACE 379 <- [1,5,null,{"value":null}]
13:14:50 INFO - 1499458490475 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
13:14:50 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:50 INFO - 1499458490479 Marionette TRACE 379 <- [1,6,null,{}]
13:14:50 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:50 INFO - 1499458490505 Marionette DEBUG Closed connection 379
13:14:50 INFO - 1499458490506 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49809
13:14:50 INFO - 1499458490511 Marionette DEBUG Closed connection 380
13:14:50 INFO - 1499458490512 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49810
13:14:50 INFO - 1499458490515 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:50 INFO - 1499458490517 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:50 INFO - 1499458490541 Marionette DEBUG Register listener.js for window 12
13:14:50 INFO - 1499458490552 Marionette TRACE 381 <- [1,1,null,{"sessionId":"5932d2a0-1d5d-4c46-881c-7182396dd3e3","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:50 INFO - 1499458490558 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
13:14:50 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:50 INFO - 1499458490562 Marionette TRACE 381 <- [1,2,null,{}]
13:14:50 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:50 INFO - 1499458490568 Marionette DEBUG Closed connection 381
13:14:50 INFO - 1499458490569 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49811
13:14:50 INFO - 1499458490571 Marionette DEBUG Closed connection 382
13:14:50 INFO - 1499458490573 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49812
13:14:50 INFO - 1499458490575 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:14:50 INFO - 1499458490577 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:50 INFO - 1499458490601 Marionette DEBUG Register listener.js for window 12
13:14:50 INFO - 1499458490612 Marionette TRACE 383 <- [1,1,null,{"sessionId":"d41b9ada-008b-4491-b4b2-0787b2942333","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:50 INFO - 1499458490646 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:14:50 INFO - 1499458490650 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:14:50 INFO - 1499458490657 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:14:50 INFO - 1499458490657 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:14:50 INFO - ++DOMWINDOW == 26 (000000CFACF65800) [pid = 3592] [serial = 49] [outer = 000000CFA4540800]
13:14:50 INFO - 1499458490705 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:14:50 INFO - 1499458490715 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:14:50 INFO - 1499458490730 Marionette TRACE 383 <- [1,2,null,{}]
13:14:50 INFO - 1499458490736 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:14:50 INFO - 1499458490743 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cb23cd08-8874-4e5b-8b38-7bb2da1938e6","ELEMENT":"cb23cd08-8874-4e5b-8b38-7bb2da1938e6"}}]
13:14:50 INFO - 1499458490749 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"cb23cd08-8874-4e5b-8b38-7bb2da1938e6"}]
13:14:51 INFO - 1499458491817 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:14:51 INFO - 1499458491861 Marionette TRACE 383 <- [1,4,null,{}]
13:14:51 INFO - 1499458491873 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:14:51 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499458491887 Marionette TRACE 383 <- [1,5,null,{"value":null}]
13:14:51 INFO - 1499458491893 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
13:14:51 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:51 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1530ms
13:14:51 INFO - TEST-START | test_click.py TestClick.test_input_file
13:14:51 INFO - 1499458491903 Marionette TRACE 383 <- [1,6,null,{}]
13:14:51 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:51 INFO - 1499458491912 Marionette DEBUG Closed connection 383
13:14:51 INFO - 1499458491915 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49813
13:14:51 INFO - 1499458491918 Marionette DEBUG Closed connection 384
13:14:51 INFO - 1499458491920 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49814
13:14:51 INFO - 1499458491928 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:51 INFO - 1499458491939 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:51 INFO - 1499458491985 Marionette DEBUG Register listener.js for window 12
13:14:52 INFO - 1499458492018 Marionette TRACE 385 <- [1,1,null,{"sessionId":"62d7c0b9-d0f5-4d0d-b646-e900964e460b","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:52 INFO - 1499458492034 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
13:14:52 INFO - 1499458492037 Marionette TRACE 385 <- [1,2,null,{}]
13:14:52 INFO - 1499458492040 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:14:52 INFO - 1499458492045 Marionette TRACE 385 <- [1,3,null,{}]
13:14:52 INFO - 1499458492071 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
13:14:52 INFO - 1499458492074 Marionette TRACE 385 <- [1,4,null,{}]
13:14:52 INFO - 1499458492078 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:14:52 INFO - TEST-START: test_click.py TestClick.test_input_file1499458492091 Marionette TRACE 385 <- [1,5,null,{"value":null}]
13:14:52 INFO - 1499458492094 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
13:14:52 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:52 INFO - 1499458492100 Marionette TRACE 385 <- [1,6,null,{}]
13:14:52 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:52 INFO - 1499458492111 Marionette DEBUG Closed connection 385
13:14:52 INFO - 1499458492114 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49815
13:14:52 INFO - 1499458492148 Marionette DEBUG Closed connection 386
13:14:52 INFO - 1499458492150 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49816
13:14:52 INFO - 1499458492154 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:52 INFO - 1499458492157 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:52 INFO - 1499458492203 Marionette DEBUG Register listener.js for window 12
13:14:52 INFO - 1499458492220 Marionette TRACE 387 <- [1,1,null,{"sessionId":"b74f2d51-ef47-4c2b-a8b0-1d285f236650","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:52 INFO - 1499458492253 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
13:14:52 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:52 INFO - 1499458492261 Marionette TRACE 387 <- [1,2,null,{}]
13:14:52 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:52 INFO - 1499458492289 Marionette DEBUG Closed connection 387
13:14:52 INFO - 1499458492291 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49817
13:14:52 INFO - 1499458492296 Marionette DEBUG Closed connection 388
13:14:52 INFO - 1499458492298 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49818
13:14:52 INFO - 1499458492356 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:14:52 INFO - 1499458492362 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:52 INFO - 1499458492407 Marionette DEBUG Register listener.js for window 12
13:14:52 INFO - 1499458492423 Marionette TRACE 389 <- [1,1,null,{"sessionId":"c5dcc304-ab6e-4d7a-bc6b-72625a4a6eee","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:52 INFO - 1499458492435 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:14:52 INFO - 1499458492441 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:14:52 INFO - 1499458492451 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:14:52 INFO - 1499458492454 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:14:52 INFO - ++DOMWINDOW == 27 (000000CFA3F6D800) [pid = 3592] [serial = 50] [outer = 000000CFA4540800]
13:14:52 INFO - 1499458492523 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:14:52 INFO - 1499458492531 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:14:52 INFO - 1499458492562 Marionette TRACE 389 <- [1,2,null,{}]
13:14:52 INFO - 1499458492570 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:14:52 INFO - 1499458492582 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"24ceac52-efc5-49b6-851e-621fd00d7b52","ELEMENT":"24ceac52-efc5-49b6-851e-621fd00d7b52"}}]
13:14:52 INFO - 1499458492584 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"24ceac52-efc5-49b6-851e-621fd00d7b52"}]
13:14:52 INFO - 1499458492840 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
13:14:52 INFO - 1499458492857 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:14:52 INFO - TEST-END: test_click.py TestClick.test_input_file1499458492867 Marionette TRACE 389 <- [1,5,null,{"value":null}]
13:14:52 INFO - 1499458492871 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
13:14:52 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:52 INFO - 1499458492876 Marionette TRACE 389 <- [1,6,null,{}]
13:14:52 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 972ms
13:14:52 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:14:52 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:52 INFO - 1499458492933 Marionette DEBUG Closed connection 389
13:14:52 INFO - 1499458492935 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49819
13:14:52 INFO - 1499458492956 Marionette DEBUG Closed connection 390
13:14:52 INFO - 1499458492959 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49820
13:14:52 INFO - 1499458492962 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:52 INFO - 1499458492965 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:53 INFO - 1499458493011 Marionette DEBUG Register listener.js for window 12
13:14:53 INFO - 1499458493025 Marionette TRACE 391 <- [1,1,null,{"sessionId":"ab33b362-d862-423a-97d1-1b535d3e75c2","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:53 INFO - 1499458493061 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
13:14:53 INFO - 1499458493063 Marionette TRACE 391 <- [1,2,null,{}]
13:14:53 INFO - 1499458493067 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:14:53 INFO - 1499458493069 Marionette TRACE 391 <- [1,3,null,{}]
13:14:53 INFO - 1499458493076 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
13:14:53 INFO - 1499458493078 Marionette TRACE 391 <- [1,4,null,{}]
13:14:53 INFO - 1499458493094 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:14:53 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499458493105 Marionette TRACE 391 <- [1,5,null,{"value":null}]
13:14:53 INFO - 1499458493107 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
13:14:53 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:53 INFO - 1499458493112 Marionette TRACE 391 <- [1,6,null,{}]
13:14:53 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:53 INFO - 1499458493119 Marionette DEBUG Closed connection 391
13:14:53 INFO - 1499458493121 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49821
13:14:53 INFO - 1499458493123 Marionette DEBUG Closed connection 392
13:14:53 INFO - 1499458493125 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49822
13:14:53 INFO - 1499458493128 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:53 INFO - 1499458493134 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:53 INFO - 1499458493180 Marionette DEBUG Register listener.js for window 12
13:14:53 INFO - 1499458493197 Marionette TRACE 393 <- [1,1,null,{"sessionId":"7c7e4dc5-0bbb-483c-ac3e-23fb8428fb6b","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:53 INFO - 1499458493240 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
13:14:53 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:53 INFO - 1499458493246 Marionette TRACE 393 <- [1,2,null,{}]
13:14:53 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:53 INFO - 1499458493255 Marionette DEBUG Closed connection 393
13:14:53 INFO - 1499458493261 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49823
13:14:53 INFO - 1499458493263 Marionette DEBUG Closed connection 394
13:14:53 INFO - 1499458493265 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49824
13:14:53 INFO - 1499458493268 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:14:53 INFO - 1499458493271 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:53 INFO - 1499458493318 Marionette DEBUG Register listener.js for window 12
13:14:53 INFO - 1499458493333 Marionette TRACE 395 <- [1,1,null,{"sessionId":"dd25387e-b0f1-4e09-9c1f-07214d535067","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:53 INFO - 1499458493374 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:14:53 INFO - 1499458493379 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:14:53 INFO - 1499458493389 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:14:53 INFO - 1499458493390 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:14:53 INFO - ++DOMWINDOW == 28 (000000CFAB879000) [pid = 3592] [serial = 51] [outer = 000000CFA4540800]
13:14:53 INFO - 1499458493491 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:14:53 INFO - 1499458493499 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:14:53 INFO - 1499458493519 Marionette TRACE 395 <- [1,2,null,{}]
13:14:53 INFO - 1499458493526 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:14:53 INFO - 1499458493535 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"446d5c01-1cde-4f64-8630-7b8ebf6ec9e8","ELEMENT":"446d5c01-1cde-4f64-8630-7b8ebf6ec9e8"}}]
13:14:53 INFO - 1499458493537 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:14:53 INFO - 1499458493558 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0c4a2100-902b-48ac-8fd4-e713937cfc7c","ELEMENT":"0c4a2100-902b-48ac-8fd4-e713937cfc7c"}}]
13:14:53 INFO - 1499458493561 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"446d5c01-1cde-4f64-8630-7b8ebf6ec9e8"}]
13:14:54 INFO - 1499458494164 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:14:54 INFO - 1499458494167 Marionette TRACE 395 <- [1,5,null,{}]
13:14:54 INFO - 1499458494169 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"0c4a2100-902b-48ac-8fd4-e713937cfc7c"}]
13:14:54 INFO - 1499458494447 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
13:14:54 INFO - 1499458494477 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
13:14:54 INFO - 1499458494487 Marionette TRACE 395 <- [1,7,null,{"value":false}]
13:14:54 INFO - 1499458494489 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:14:54 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499458494514 Marionette TRACE 395 <- [1,8,null,{"value":null}]
13:14:54 INFO - 1499458494520 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
13:14:54 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:54 INFO - 1499458494525 Marionette TRACE 395 <- [1,9,null,{}]
13:14:54 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1649ms
13:14:54 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:14:54 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:54 INFO - 1499458494532 Marionette DEBUG Closed connection 395
13:14:54 INFO - 1499458494534 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49825
13:14:54 INFO - 1499458494535 Marionette DEBUG Closed connection 396
13:14:54 INFO - 1499458494537 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49826
13:14:54 INFO - 1499458494540 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:54 INFO - 1499458494543 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:54 INFO - 1499458494589 Marionette DEBUG Register listener.js for window 12
13:14:54 INFO - 1499458494789 Marionette TRACE 397 <- [1,1,null,{"sessionId":"c5f0a7ef-8457-46a4-ab02-39b9c5004aea","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:54 INFO - 1499458494828 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
13:14:54 INFO - 1499458494832 Marionette TRACE 397 <- [1,2,null,{}]
13:14:54 INFO - 1499458494885 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:14:54 INFO - 1499458494888 Marionette TRACE 397 <- [1,3,null,{}]
13:14:54 INFO - 1499458494896 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
13:14:54 INFO - 1499458494899 Marionette TRACE 397 <- [1,4,null,{}]
13:14:54 INFO - 1499458494909 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:14:54 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499458494929 Marionette TRACE 397 <- [1,5,null,{"value":null}]
13:14:54 INFO - 1499458494968 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
13:14:54 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:54 INFO - 1499458494978 Marionette TRACE 397 <- [1,6,null,{}]
13:14:54 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:54 INFO - 1499458494992 Marionette DEBUG Closed connection 397
13:14:54 INFO - 1499458494995 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49827
13:14:55 INFO - 1499458495003 Marionette DEBUG Closed connection 398
13:14:55 INFO - 1499458495005 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49828
13:14:55 INFO - 1499458495039 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:14:55 INFO - 1499458495042 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:55 INFO - 1499458495066 Marionette DEBUG Register listener.js for window 12
13:14:55 INFO - 1499458495085 Marionette TRACE 399 <- [1,1,null,{"sessionId":"36038a9d-87da-48db-8057-00536139bd39","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:55 INFO - 1499458495105 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
13:14:55 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:55 INFO - 1499458495111 Marionette TRACE 399 <- [1,2,null,{}]
13:14:55 INFO - [3592] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:14:55 INFO - 1499458495133 Marionette DEBUG Closed connection 399
13:14:55 INFO - 1499458495135 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49829
13:14:55 INFO - 1499458495139 Marionette DEBUG Closed connection 400
13:14:55 INFO - 1499458495141 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49830
13:14:55 INFO - 1499458495167 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:14:55 INFO - 1499458495171 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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}
13:14:55 INFO - 1499458495195 Marionette DEBUG Register listener.js for window 12
13:14:55 INFO - 1499458495209 Marionette TRACE 401 <- [1,1,null,{"sessionId":"85f4e577-813a-46e6-9e33-8692fe2207a3","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":3592,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-253.001\\AppData\\Local\\Temp\\tmpx1thqk.mozrunner","moz:accessibilityChecks":false}}]
13:14:55 INFO - 1499458495222 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:14:55 INFO - 1499458495228 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:14:55 INFO - 1499458495238 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:14:55 INFO - 1499458495239 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:14:55 INFO - ++DOMWINDOW == 29 (000000CFA061A800) [pid = 3592] [serial = 52] [outer = 000000CFA4540800]
13:14:55 INFO - 1499458495300 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:14:55 INFO - 1499458495307 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:14:55 INFO - 1499458495325 Marionette TRACE 401 <- [1,2,null,{}]
13:14:55 INFO - 1499458495327 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:14:55 INFO - 1499458495338 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"11d93ee3-cdfe-426e-8227-8939af225f18","ELEMENT":"11d93ee3-cdfe-426e-8227-8939af225f18"}}]
13:14:55 INFO - 1499458495345 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"11d93ee3-cdfe-426e-8227-8939af225f18"}]
13:14:55 INFO - 1499458495476 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
13:14:55 INFO - --DOMWINDOW == 28 (000000CFA96BF000) [pid = 3592] [serial = 35] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
13:14:55 INFO - --DOMWINDOW == 27 (000000CFA7737800) [pid = 3592] [serial = 39] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%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.clicks%20%3D%200%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.clicks%2B%2B%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:14:55 INFO - --DOMWINDOW == 26 (000000CFA5A0C800) [pid = 3592] [serial = 38] [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:14:55 INFO - --DOMWINDOW == 25 (000000CFA3F56000) [pid = 3592] [serial = 37] [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:14:55 INFO - --DOMWINDOW == 24 (000000CF98C10800) [pid = 3592] [serial = 36] [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:14:55 INFO - 1499458495499 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"11d93ee3-cdfe-426e-8227-8939af225f18"}]
13:14:55 INFO - 1499458495640 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element