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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
07:06:19 INFO - 1500645979767 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}]
07:06:19 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500645979774 Marionette TRACE 352 <- [1,5,null,{"value":null}]
07:06:19 INFO - 1500645979777 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
07:06:19 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:19 INFO - 1500645979781 Marionette TRACE 352 <- [1,6,null,{}]
07:06:19 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 503ms
07:06:19 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
07:06:19 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:19 INFO - 1500645979785 Marionette DEBUG Closed connection 352
07:06:19 INFO - 1500645979787 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49798
07:06:19 INFO - 1500645979788 Marionette DEBUG Closed connection 353
07:06:19 INFO - 1500645979789 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49799
07:06:19 INFO - 1500645979791 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:19 INFO - 1500645979794 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:19 INFO - 1500645979815 Marionette DEBUG Register listener.js for window 6442450945
07:06:19 INFO - 1500645979827 Marionette TRACE 354 <- [1,1,null,{"sessionId":"7ce5721a-d983-4c85-9546-55d081129c32","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:19 INFO - 1500645979830 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
07:06:19 INFO - 1500645979831 Marionette TRACE 354 <- [1,2,null,{}]
07:06:19 INFO - 1500645979833 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:19 INFO - 1500645979834 Marionette TRACE 354 <- [1,3,null,{}]
07:06:19 INFO - 1500645979836 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:19 INFO - 1500645979838 Marionette TRACE 354 <- [1,4,null,{}]
07:06:19 INFO - 1500645979840 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}]
07:06:19 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500645979857 Marionette TRACE 354 <- [1,5,null,{"value":null}]
07:06:19 INFO - 1500645979859 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
07:06:19 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:19 INFO - 1500645979863 Marionette TRACE 354 <- [1,6,null,{}]
07:06:19 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:19 INFO - 1500645979868 Marionette DEBUG Closed connection 354
07:06:19 INFO - 1500645979869 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49800
07:06:19 INFO - 1500645979871 Marionette DEBUG Closed connection 355
07:06:19 INFO - 1500645979872 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49801
07:06:19 INFO - 1500645979875 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:19 INFO - 1500645979877 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:19 INFO - 1500645979982 Marionette DEBUG Register listener.js for window 6442450945
07:06:20 INFO - 1500645979994 Marionette TRACE 356 <- [1,1,null,{"sessionId":"575b05b5-7a7c-435f-b384-551b112b1b1d","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:20 INFO - 1500645979997 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
07:06:20 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:20 INFO - 1500645980001 Marionette TRACE 356 <- [1,2,null,{}]
07:06:20 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:20 INFO - 1500645980005 Marionette DEBUG Closed connection 356
07:06:20 INFO - 1500645980007 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49802
07:06:20 INFO - 1500645980013 Marionette DEBUG Closed connection 357
07:06:20 INFO - 1500645980014 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49803
07:06:20 INFO - 1500645980017 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:20 INFO - 1500645980020 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:20 INFO - 1500645980046 Marionette DEBUG Register listener.js for window 6442450945
07:06:20 INFO - 1500645980058 Marionette TRACE 358 <- [1,1,null,{"sessionId":"654559ae-c3d2-4319-8ab4-a6ddfab3ecf0","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:20 INFO - 1500645980061 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49339/clicks.html"}]
07:06:20 INFO - 1500645980111 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49339/hidden.html"
07:06:20 INFO - 1500645980135 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49339/hidden.html"
07:06:20 INFO - 1500645980136 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49339/hidden.html"
07:06:20 INFO - ++DOMWINDOW == 17 (000000F703309000) [pid = 3788] [serial = 24] [outer = 000000F777E2B000]
07:06:20 INFO - 1500645980160 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49339/clicks.html"
07:06:20 INFO - 1500645980171 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49339/clicks.html"
07:06:20 INFO - 1500645980175 Marionette TRACE 358 <- [1,2,null,{}]
07:06:20 INFO - 1500645980184 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
07:06:20 INFO - 1500645980199 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3075ebc4-72cc-4f54-93c2-2671b0749ea8","ELEMENT":"3075ebc4-72cc-4f54-93c2-2671b0749ea8"}}]
07:06:20 INFO - 1500645980201 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"3075ebc4-72cc-4f54-93c2-2671b0749ea8"}]
07:06:20 INFO - 1500645980435 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49339/clicks.html"
07:06:20 INFO - 1500645980452 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49339/clicks.html"
07:06:20 INFO - 1500645980452 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49339/clicks.html"
07:06:20 INFO - ++DOMWINDOW == 18 (000000F703BC1800) [pid = 3788] [serial = 25] [outer = 000000F777E2B000]
07:06:20 INFO - 1500645980481 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49339/test.html"
07:06:20 INFO - 1500645980485 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49339/test.html"
07:06:20 INFO - 1500645980494 Marionette TRACE 358 <- [1,4,null,{}]
07:06:20 INFO - 1500645980502 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
07:06:20 INFO - 1500645980513 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"23fcbf27-77ce-4661-8074-56e106d472c9","ELEMENT":"23fcbf27-77ce-4661-8074-56e106d472c9"}}]
07:06:20 INFO - 1500645980515 Marionette TRACE 358 -> [0,6,"getTitle",{}]
07:06:20 INFO - 1500645980517 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
07:06:20 INFO - 1500645980525 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}]
07:06:20 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link--DOMWINDOW == 17 (000000F7044B9000) [pid = 3788] [serial = 15] [outer = 0000000000000000] [url = http://127.0.0.1:49339/test_accessibility.html]
07:06:20 INFO - --DOMWINDOW == 16 (000000F7044AB800) [pid = 3788] [serial = 14] [outer = 0000000000000000] [url = http://127.0.0.1:49339/test_accessibility.html]
07:06:20 INFO - --DOMWINDOW == 15 (000000F7051C3800) [pid = 3788] [serial = 13] [outer = 0000000000000000] [url = http://127.0.0.1:49339/test_accessibility.html]
07:06:20 INFO - --DOMWINDOW == 14 (000000F7051AA000) [pid = 3788] [serial = 12] [outer = 0000000000000000] [url = http://127.0.0.1:49339/test_accessibility.html]
07:06:20 INFO - --DOMWINDOW == 13 (000000F702F5A800) [pid = 3788] [serial = 10] [outer = 0000000000000000] [url = http://127.0.0.1:49339/test_accessibility.html]
07:06:20 INFO - --DOMWINDOW == 12 (000000F704BC6000) [pid = 3788] [serial = 9] [outer = 0000000000000000] [url = http://127.0.0.1:49339/test_accessibility.html]
07:06:20 INFO - --DOMWINDOW == 11 (000000F702F59000) [pid = 3788] [serial = 11] [outer = 0000000000000000] [url = http://127.0.0.1:49339/test_accessibility.html]
07:06:20 INFO - 1500645980887 Marionette TRACE 358 <- [1,7,null,{"value":null}]
07:06:20 INFO - 1500645980892 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
07:06:20 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:20 INFO - 1500645980896 Marionette TRACE 358 <- [1,8,null,{}]
07:06:20 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1115ms
07:06:20 INFO - TEST-START | test_click.py TestClick.test_container_element
07:06:20 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:20 INFO - 1500645980902 Marionette DEBUG Closed connection 358
07:06:20 INFO - 1500645980904 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49806
07:06:20 INFO - 1500645980905 Marionette DEBUG Closed connection 359
07:06:20 INFO - 1500645980907 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49807
07:06:20 INFO - 1500645980909 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:20 INFO - 1500645980911 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:20 INFO - 1500645980933 Marionette DEBUG Register listener.js for window 6442450945
07:06:20 INFO - 1500645980944 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3e54d01b-bd05-4ed6-b1f9-bc3fdcd4beba","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:20 INFO - 1500645980947 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
07:06:20 INFO - 1500645980949 Marionette TRACE 360 <- [1,2,null,{}]
07:06:20 INFO - 1500645980950 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:20 INFO - 1500645980952 Marionette TRACE 360 <- [1,3,null,{}]
07:06:20 INFO - 1500645980954 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:20 INFO - 1500645980955 Marionette TRACE 360 <- [1,4,null,{}]
07:06:20 INFO - 1500645980958 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}]
07:06:20 INFO - TEST-START: test_click.py TestClick.test_container_element1500645980965 Marionette TRACE 360 <- [1,5,null,{"value":null}]
07:06:20 INFO - 1500645980967 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
07:06:20 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:20 INFO - 1500645980971 Marionette TRACE 360 <- [1,6,null,{}]
07:06:20 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:20 INFO - 1500645980975 Marionette DEBUG Closed connection 360
07:06:20 INFO - 1500645980976 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49808
07:06:20 INFO - 1500645980978 Marionette DEBUG Closed connection 361
07:06:20 INFO - 1500645980979 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49809
07:06:20 INFO - 1500645980981 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:20 INFO - 1500645980983 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:21 INFO - 1500645981007 Marionette DEBUG Register listener.js for window 6442450945
07:06:21 INFO - 1500645981019 Marionette TRACE 362 <- [1,1,null,{"sessionId":"1c884a44-b345-4690-9b1f-7f2ac37f120a","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:21 INFO - 1500645981021 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
07:06:21 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:21 INFO - 1500645981026 Marionette TRACE 362 <- [1,2,null,{}]
07:06:21 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:21 INFO - 1500645981030 Marionette DEBUG Closed connection 362
07:06:21 INFO - 1500645981031 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49810
07:06:21 INFO - 1500645981033 Marionette DEBUG Closed connection 363
07:06:21 INFO - 1500645981034 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49811
07:06:21 INFO - 1500645981037 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:21 INFO - 1500645981039 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:21 INFO - 1500645981062 Marionette DEBUG Register listener.js for window 6442450945
07:06:21 INFO - 1500645981086 Marionette TRACE 364 <- [1,1,null,{"sessionId":"a38b09c5-d994-4dc7-bf4f-9891278da028","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:21 INFO - 1500645981089 Marionette TRACE 364 -> [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"}]
07:06:21 INFO - 1500645981093 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49339/test.html"
07:06:21 INFO - 1500645981097 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49339/test.html"
07:06:21 INFO - 1500645981098 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49339/test.html"
07:06:21 INFO - ++DOMWINDOW == 12 (000000F703308800) [pid = 3788] [serial = 26] [outer = 000000F777E2B000]
07:06:21 INFO - 1500645981133 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"
07:06:21 INFO - 1500645981141 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"
07:06:21 INFO - 1500645981143 Marionette TRACE 364 <- [1,2,null,{}]
07:06:21 INFO - 1500645981149 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
07:06:21 INFO - 1500645981160 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9a0556f0-0528-4925-8877-4f45fea680bd","ELEMENT":"9a0556f0-0528-4925-8877-4f45fea680bd"}}]
07:06:21 INFO - 1500645981162 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"9a0556f0-0528-4925-8877-4f45fea680bd"}]
07:06:21 INFO - [Child 3788] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
07:06:21 INFO - [Child 3788] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
07:06:21 INFO - [Child 3788] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
07:06:21 INFO - 1500645981592 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:06:21 INFO - 1500645981594 Marionette TRACE 364 <- [1,4,null,{}]
07:06:21 INFO - 1500645981596 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"9a0556f0-0528-4925-8877-4f45fea680bd","name":"selected"}]
07:06:21 INFO - 1500645981623 Marionette TRACE 364 <- [1,5,null,{"value":true}]
07:06:21 INFO - 1500645981625 Marionette TRACE 364 -> [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}]
07:06:21 INFO - TEST-END: test_click.py TestClick.test_container_element1500645981632 Marionette TRACE 364 <- [1,6,null,{"value":null}]
07:06:21 INFO - 1500645981634 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
07:06:21 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:21 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 741ms
07:06:21 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
07:06:21 INFO - 1500645981638 Marionette TRACE 364 <- [1,7,null,{}]
07:06:21 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:21 INFO - 1500645981642 Marionette DEBUG Closed connection 364
07:06:21 INFO - 1500645981644 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49812
07:06:21 INFO - 1500645981645 Marionette DEBUG Closed connection 365
07:06:21 INFO - 1500645981647 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49813
07:06:21 INFO - 1500645981649 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:21 INFO - 1500645981651 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:21 INFO - 1500645981673 Marionette DEBUG Register listener.js for window 6442450945
07:06:21 INFO - 1500645981685 Marionette TRACE 366 <- [1,1,null,{"sessionId":"ce41b588-71ef-4f9a-8d65-43ee2367a62b","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:21 INFO - 1500645981687 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
07:06:21 INFO - 1500645981689 Marionette TRACE 366 <- [1,2,null,{}]
07:06:21 INFO - 1500645981691 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:21 INFO - 1500645981692 Marionette TRACE 366 <- [1,3,null,{}]
07:06:21 INFO - 1500645981694 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:21 INFO - 1500645981696 Marionette TRACE 366 <- [1,4,null,{}]
07:06:21 INFO - 1500645981698 Marionette TRACE 366 -> [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}]
07:06:21 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500645981705 Marionette TRACE 366 <- [1,5,null,{"value":null}]
07:06:21 INFO - 1500645981707 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
07:06:21 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:21 INFO - 1500645981711 Marionette TRACE 366 <- [1,6,null,{}]
07:06:21 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:21 INFO - 1500645981715 Marionette DEBUG Closed connection 366
07:06:21 INFO - 1500645981717 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49814
07:06:21 INFO - 1500645981718 Marionette DEBUG Closed connection 367
07:06:21 INFO - 1500645981720 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49815
07:06:21 INFO - 1500645981722 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:21 INFO - 1500645981724 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:21 INFO - 1500645981747 Marionette DEBUG Register listener.js for window 6442450945
07:06:21 INFO - 1500645981758 Marionette TRACE 368 <- [1,1,null,{"sessionId":"60e5ddbc-8388-4be3-82ac-17f5e1a3d432","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:21 INFO - 1500645981760 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
07:06:21 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:21 INFO - 1500645981765 Marionette TRACE 368 <- [1,2,null,{}]
07:06:21 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:21 INFO - 1500645981769 Marionette DEBUG Closed connection 368
07:06:21 INFO - 1500645981770 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49816
07:06:21 INFO - 1500645981771 Marionette DEBUG Closed connection 369
07:06:21 INFO - 1500645981773 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49817
07:06:21 INFO - 1500645981775 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:21 INFO - 1500645981777 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:21 INFO - 1500645981800 Marionette DEBUG Register listener.js for window 6442450945
07:06:21 INFO - 1500645981811 Marionette TRACE 370 <- [1,1,null,{"sessionId":"ac21a69d-5290-46dc-bc8c-0fb68bd19564","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:21 INFO - 1500645981814 Marionette TRACE 370 -> [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"}]
07:06:21 INFO - 1500645981818 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"
07:06:21 INFO - 1500645981822 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"
07:06:21 INFO - 1500645981822 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"
07:06:21 INFO - ++DOMWINDOW == 13 (000000F7044BA800) [pid = 3788] [serial = 27] [outer = 000000F777E2B000]
07:06:21 INFO - 1500645981844 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"
07:06:21 INFO - 1500645981851 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"
07:06:21 INFO - 1500645981870 Marionette TRACE 370 <- [1,2,null,{}]
07:06:21 INFO - 1500645981880 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
07:06:21 INFO - 1500645981892 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fe0f2616-0e85-4a0b-b6c1-36372596a4fe","ELEMENT":"fe0f2616-0e85-4a0b-b6c1-36372596a4fe"}}]
07:06:21 INFO - 1500645981895 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"fe0f2616-0e85-4a0b-b6c1-36372596a4fe"}]
07:06:22 INFO - [Child 3788] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
07:06:22 INFO - [Child 3788] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
07:06:22 INFO - [Child 3788] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
07:06:22 INFO - 1500645982329 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:06:22 INFO - 1500645982331 Marionette TRACE 370 <- [1,4,null,{}]
07:06:22 INFO - 1500645982333 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"fe0f2616-0e85-4a0b-b6c1-36372596a4fe","name":"selected"}]
07:06:22 INFO - 1500645982360 Marionette TRACE 370 <- [1,5,null,{"value":true}]
07:06:22 INFO - 1500645982363 Marionette TRACE 370 -> [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}]
07:06:22 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500645982370 Marionette TRACE 370 <- [1,6,null,{"value":null}]
07:06:22 INFO - 1500645982372 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
07:06:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 737ms
07:06:22 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:22 INFO - 1500645982376 Marionette TRACE 370 <- [1,7,null,{}]
07:06:22 INFO - TEST-START | test_click.py TestClick.test_css_transforms
07:06:22 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:22 INFO - 1500645982380 Marionette DEBUG Closed connection 370
07:06:22 INFO - 1500645982382 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49818
07:06:22 INFO - 1500645982383 Marionette DEBUG Closed connection 371
07:06:22 INFO - 1500645982385 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49819
07:06:22 INFO - 1500645982387 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:22 INFO - 1500645982389 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:22 INFO - 1500645982412 Marionette DEBUG Register listener.js for window 6442450945
07:06:22 INFO - 1500645982423 Marionette TRACE 372 <- [1,1,null,{"sessionId":"8be848c9-99d7-4777-902d-296227107d52","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:22 INFO - 1500645982425 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
07:06:22 INFO - 1500645982427 Marionette TRACE 372 <- [1,2,null,{}]
07:06:22 INFO - 1500645982429 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:22 INFO - 1500645982430 Marionette TRACE 372 <- [1,3,null,{}]
07:06:22 INFO - 1500645982432 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:22 INFO - 1500645982433 Marionette TRACE 372 <- [1,4,null,{}]
07:06:22 INFO - 1500645982436 Marionette TRACE 372 -> [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}]
07:06:22 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500645982443 Marionette TRACE 372 <- [1,5,null,{"value":null}]
07:06:22 INFO - 1500645982445 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
07:06:22 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:22 INFO - 1500645982450 Marionette TRACE 372 <- [1,6,null,{}]
07:06:22 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:22 INFO - 1500645982454 Marionette DEBUG Closed connection 372
07:06:22 INFO - 1500645982455 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49820
07:06:22 INFO - 1500645982457 Marionette DEBUG Closed connection 373
07:06:22 INFO - 1500645982458 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49821
07:06:22 INFO - 1500645982461 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:22 INFO - 1500645982463 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:22 INFO - 1500645982486 Marionette DEBUG Register listener.js for window 6442450945
07:06:22 INFO - 1500645982497 Marionette TRACE 374 <- [1,1,null,{"sessionId":"3b5c04d2-22ff-463e-8eb0-a9538008c575","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:22 INFO - 1500645982499 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
07:06:22 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:22 INFO - 1500645982504 Marionette TRACE 374 <- [1,2,null,{}]
07:06:22 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:22 INFO - 1500645982508 Marionette DEBUG Closed connection 374
07:06:22 INFO - 1500645982510 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49822
07:06:22 INFO - 1500645982511 Marionette DEBUG Closed connection 375
07:06:22 INFO - 1500645982513 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49823
07:06:22 INFO - 1500645982515 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:22 INFO - 1500645982517 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:22 INFO - 1500645982541 Marionette DEBUG Register listener.js for window 6442450945
07:06:22 INFO - 1500645982552 Marionette TRACE 376 <- [1,1,null,{"sessionId":"b3d2e3e1-3b7c-421f-885b-10c4dd45820e","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:22 INFO - 1500645982555 Marionette TRACE 376 -> [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"}]
07:06:22 INFO - 1500645982559 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"
07:06:22 INFO - 1500645982564 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"
07:06:22 INFO - 1500645982565 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"
07:06:22 INFO - ++DOMWINDOW == 14 (000000F705613800) [pid = 3788] [serial = 28] [outer = 000000F777E2B000]
07:06:22 INFO - 1500645982592 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"
07:06:22 INFO - 1500645982599 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"
07:06:22 INFO - 1500645982611 Marionette TRACE 376 <- [1,2,null,{}]
07:06:22 INFO - 1500645982626 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
07:06:22 INFO - 1500645982633 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9807aa0d-37ac-4760-973b-4257cc547510","ELEMENT":"9807aa0d-37ac-4760-973b-4257cc547510"}}]
07:06:22 INFO - 1500645982635 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"9807aa0d-37ac-4760-973b-4257cc547510"}]
07:06:23 INFO - 1500645983059 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:06:23 INFO - 1500645983061 Marionette TRACE 376 <- [1,4,null,{}]
07:06:23 INFO - 1500645983064 Marionette TRACE 376 -> [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}]
07:06:23 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500645983071 Marionette TRACE 376 <- [1,5,null,{"value":null}]
07:06:23 INFO - 1500645983073 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
07:06:23 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 701ms
07:06:23 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:23 INFO - 1500645983077 Marionette TRACE 376 <- [1,6,null,{}]
07:06:23 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
07:06:23 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:23 INFO - 1500645983082 Marionette DEBUG Closed connection 376
07:06:23 INFO - 1500645983083 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49824
07:06:23 INFO - 1500645983085 Marionette DEBUG Closed connection 377
07:06:23 INFO - 1500645983087 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49825
07:06:23 INFO - 1500645983090 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:23 INFO - 1500645983092 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:23 INFO - 1500645983115 Marionette DEBUG Register listener.js for window 6442450945
07:06:23 INFO - 1500645983126 Marionette TRACE 378 <- [1,1,null,{"sessionId":"6d8b8ed7-3fe2-4a02-99bd-73f782182f68","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:23 INFO - 1500645983129 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
07:06:23 INFO - 1500645983131 Marionette TRACE 378 <- [1,2,null,{}]
07:06:23 INFO - 1500645983133 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:23 INFO - 1500645983134 Marionette TRACE 378 <- [1,3,null,{}]
07:06:23 INFO - 1500645983136 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:23 INFO - 1500645983138 Marionette TRACE 378 <- [1,4,null,{}]
07:06:23 INFO - 1500645983140 Marionette TRACE 378 -> [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}]
07:06:23 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500645983147 Marionette TRACE 378 <- [1,5,null,{"value":null}]
07:06:23 INFO - 1500645983149 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
07:06:23 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:23 INFO - 1500645983153 Marionette TRACE 378 <- [1,6,null,{}]
07:06:23 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:23 INFO - 1500645983157 Marionette DEBUG Closed connection 378
07:06:23 INFO - 1500645983159 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49826
07:06:23 INFO - 1500645983160 Marionette DEBUG Closed connection 379
07:06:23 INFO - 1500645983162 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49827
07:06:23 INFO - 1500645983164 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:23 INFO - 1500645983166 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:23 INFO - 1500645983190 Marionette DEBUG Register listener.js for window 6442450945
07:06:23 INFO - 1500645983201 Marionette TRACE 380 <- [1,1,null,{"sessionId":"2fb88a26-e8d9-4d41-bfd7-3a78a1bfc2b9","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:23 INFO - 1500645983204 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
07:06:23 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:23 INFO - 1500645983209 Marionette TRACE 380 <- [1,2,null,{}]
07:06:23 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:23 INFO - 1500645983213 Marionette DEBUG Closed connection 380
07:06:23 INFO - 1500645983214 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49828
07:06:23 INFO - 1500645983216 Marionette DEBUG Closed connection 381
07:06:23 INFO - 1500645983217 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49829
07:06:23 INFO - 1500645983219 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:23 INFO - 1500645983221 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:23 INFO - 1500645983245 Marionette DEBUG Register listener.js for window 6442450945
07:06:23 INFO - 1500645983256 Marionette TRACE 382 <- [1,1,null,{"sessionId":"37b91d72-415f-4477-b347-e8f617350f50","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:23 INFO - 1500645983259 Marionette TRACE 382 -> [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"}]
07:06:23 INFO - 1500645983263 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"
07:06:23 INFO - 1500645983267 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"
07:06:23 INFO - 1500645983267 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"
07:06:23 INFO - ++DOMWINDOW == 15 (000000F70561D000) [pid = 3788] [serial = 29] [outer = 000000F777E2B000]
07:06:23 INFO - 1500645983295 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"
07:06:23 INFO - 1500645983302 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"
07:06:23 INFO - 1500645983308 Marionette TRACE 382 <- [1,2,null,{}]
07:06:23 INFO - 1500645983316 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
07:06:23 INFO - 1500645983327 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"05bb55b6-1cba-48bc-b1d5-92b547937cf9","ELEMENT":"05bb55b6-1cba-48bc-b1d5-92b547937cf9"}}]
07:06:23 INFO - 1500645983329 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"05bb55b6-1cba-48bc-b1d5-92b547937cf9"}]
07:06:23 INFO - 1500645983758 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:06:23 INFO - 1500645983760 Marionette TRACE 382 <- [1,4,null,{}]
07:06:23 INFO - 1500645983763 Marionette TRACE 382 -> [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}]
07:06:23 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500645983770 Marionette TRACE 382 <- [1,5,null,{"value":null}]
07:06:23 INFO - 1500645983772 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
07:06:23 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:23 INFO - 1500645983776 Marionette TRACE 382 <- [1,6,null,{}]
07:06:23 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 698ms
07:06:23 INFO - TEST-START | test_click.py TestClick.test_input_file
07:06:23 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:23 INFO - 1500645983780 Marionette DEBUG Closed connection 382
07:06:23 INFO - 1500645983782 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49830
07:06:23 INFO - 1500645983783 Marionette DEBUG Closed connection 383
07:06:23 INFO - 1500645983784 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49831
07:06:23 INFO - 1500645983787 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:23 INFO - 1500645983789 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:23 INFO - 1500645983812 Marionette DEBUG Register listener.js for window 6442450945
07:06:23 INFO - 1500645983823 Marionette TRACE 384 <- [1,1,null,{"sessionId":"e11955ef-b806-45fd-968e-901be67b400f","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:23 INFO - 1500645983826 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
07:06:23 INFO - 1500645983827 Marionette TRACE 384 <- [1,2,null,{}]
07:06:23 INFO - 1500645983829 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:23 INFO - 1500645983831 Marionette TRACE 384 <- [1,3,null,{}]
07:06:23 INFO - 1500645983832 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:23 INFO - 1500645983834 Marionette TRACE 384 <- [1,4,null,{}]
07:06:23 INFO - 1500645983837 Marionette TRACE 384 -> [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}]
07:06:23 INFO - TEST-START: test_click.py TestClick.test_input_file1500645983843 Marionette TRACE 384 <- [1,5,null,{"value":null}]
07:06:23 INFO - 1500645983845 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
07:06:23 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:23 INFO - 1500645983849 Marionette TRACE 384 <- [1,6,null,{}]
07:06:23 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:23 INFO - 1500645983853 Marionette DEBUG Closed connection 384
07:06:23 INFO - 1500645983855 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49832
07:06:23 INFO - 1500645983856 Marionette DEBUG Closed connection 385
07:06:23 INFO - 1500645983858 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49833
07:06:23 INFO - 1500645983860 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:23 INFO - 1500645983863 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:24 INFO - 1500645984009 Marionette DEBUG Register listener.js for window 6442450945
07:06:24 INFO - 1500645984020 Marionette TRACE 386 <- [1,1,null,{"sessionId":"4a4140b2-8144-4dcb-a716-d7d281d6e2c1","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:24 INFO - 1500645984023 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
07:06:24 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:24 INFO - 1500645984027 Marionette TRACE 386 <- [1,2,null,{}]
07:06:24 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:24 INFO - 1500645984031 Marionette DEBUG Closed connection 386
07:06:24 INFO - 1500645984032 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49834
07:06:24 INFO - 1500645984034 Marionette DEBUG Closed connection 387
07:06:24 INFO - 1500645984035 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49835
07:06:24 INFO - 1500645984038 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:24 INFO - 1500645984040 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:24 INFO - 1500645984063 Marionette DEBUG Register listener.js for window 6442450945
07:06:24 INFO - 1500645984074 Marionette TRACE 388 <- [1,1,null,{"sessionId":"39eaf484-6791-4d6b-a450-16ec54e63527","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:24 INFO - 1500645984077 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
07:06:24 INFO - 1500645984081 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"
07:06:24 INFO - 1500645984085 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"
07:06:24 INFO - 1500645984087 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"
07:06:24 INFO - ++DOMWINDOW == 16 (000000F703314000) [pid = 3788] [serial = 30] [outer = 000000F777E2B000]
07:06:24 INFO - 1500645984115 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:06:24 INFO - 1500645984119 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:06:24 INFO - 1500645984124 Marionette TRACE 388 <- [1,2,null,{}]
07:06:24 INFO - 1500645984132 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
07:06:24 INFO - 1500645984143 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e03322b7-67b9-47d1-bc26-e891dd5985db","ELEMENT":"e03322b7-67b9-47d1-bc26-e891dd5985db"}}]
07:06:24 INFO - 1500645984145 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"e03322b7-67b9-47d1-bc26-e891dd5985db"}]
07:06:24 INFO - 1500645984304 Marionette TRACE 388 <- [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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
07:06:24 INFO - 1500645984308 Marionette TRACE 388 -> [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}]
07:06:24 INFO - TEST-END: test_click.py TestClick.test_input_file1500645984315 Marionette TRACE 388 <- [1,5,null,{"value":null}]
07:06:24 INFO - 1500645984317 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
07:06:24 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:24 INFO - 1500645984321 Marionette TRACE 388 <- [1,6,null,{}]
07:06:24 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 545ms
07:06:24 INFO - TEST-START | test_click.py TestClick.test_obscured_element
07:06:24 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:24 INFO - 1500645984325 Marionette DEBUG Closed connection 388
07:06:24 INFO - 1500645984326 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49836
07:06:24 INFO - 1500645984328 Marionette DEBUG Closed connection 389
07:06:24 INFO - 1500645984329 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49837
07:06:24 INFO - 1500645984331 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:24 INFO - 1500645984333 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:24 INFO - 1500645984357 Marionette DEBUG Register listener.js for window 6442450945
07:06:24 INFO - 1500645984368 Marionette TRACE 390 <- [1,1,null,{"sessionId":"c344fd68-2f7c-4ae1-bb8b-741aee445481","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:24 INFO - 1500645984371 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
07:06:24 INFO - 1500645984372 Marionette TRACE 390 <- [1,2,null,{}]
07:06:24 INFO - 1500645984374 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:24 INFO - 1500645984376 Marionette TRACE 390 <- [1,3,null,{}]
07:06:24 INFO - 1500645984378 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:24 INFO - 1500645984379 Marionette TRACE 390 <- [1,4,null,{}]
07:06:24 INFO - 1500645984382 Marionette TRACE 390 -> [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}]
07:06:24 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500645984389 Marionette TRACE 390 <- [1,5,null,{"value":null}]
07:06:24 INFO - 1500645984390 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
07:06:24 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:24 INFO - 1500645984394 Marionette TRACE 390 <- [1,6,null,{}]
07:06:24 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:24 INFO - 1500645984399 Marionette DEBUG Closed connection 390
07:06:24 INFO - 1500645984400 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49838
07:06:24 INFO - 1500645984402 Marionette DEBUG Closed connection 391
07:06:24 INFO - 1500645984403 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49839
07:06:24 INFO - 1500645984406 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:24 INFO - 1500645984408 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:24 INFO - 1500645984433 Marionette DEBUG Register listener.js for window 6442450945
07:06:24 INFO - 1500645984458 Marionette TRACE 392 <- [1,1,null,{"sessionId":"f4aa4575-f200-4710-94d9-a340c6d271cb","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:24 INFO - 1500645984460 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
07:06:24 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:24 INFO - 1500645984465 Marionette TRACE 392 <- [1,2,null,{}]
07:06:24 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:24 INFO - 1500645984469 Marionette DEBUG Closed connection 392
07:06:24 INFO - 1500645984470 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49840
07:06:24 INFO - 1500645984472 Marionette DEBUG Closed connection 393
07:06:24 INFO - 1500645984473 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49841
07:06:24 INFO - 1500645984475 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:24 INFO - 1500645984478 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:24 INFO - 1500645984502 Marionette DEBUG Register listener.js for window 6442450945
07:06:24 INFO - 1500645984513 Marionette TRACE 394 <- [1,1,null,{"sessionId":"3e38c3b4-752f-4ec3-8e7d-183cbc98f288","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:24 INFO - 1500645984516 Marionette TRACE 394 -> [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"}]
07:06:24 INFO - 1500645984525 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:06:24 INFO - 1500645984530 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:06:24 INFO - 1500645984530 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
07:06:24 INFO - ++DOMWINDOW == 17 (000000F705658800) [pid = 3788] [serial = 31] [outer = 000000F777E2B000]
07:06:24 INFO - 1500645984557 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"
07:06:24 INFO - 1500645984561 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"
07:06:24 INFO - 1500645984567 Marionette TRACE 394 <- [1,2,null,{}]
07:06:24 INFO - 1500645984579 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
07:06:25 INFO - 1500645984990 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b6322491-001d-4c5d-abe9-d8b62225a233","ELEMENT":"b6322491-001d-4c5d-abe9-d8b62225a233"}}]
07:06:25 INFO - 1500645984993 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
07:06:25 INFO - 1500645985023 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b70b46dd-86d5-45f9-b7f9-81a8b08e6849","ELEMENT":"b70b46dd-86d5-45f9-b7f9-81a8b08e6849"}}]
07:06:25 INFO - 1500645985025 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"b6322491-001d-4c5d-abe9-d8b62225a233"}]
07:06:25 INFO - 1500645985462 Marionette DEBUG Canceled page load listener because no navigation has been detected
07:06:25 INFO - 1500645985464 Marionette TRACE 394 <- [1,5,null,{}]
07:06:25 INFO - 1500645985466 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"b70b46dd-86d5-45f9-b7f9-81a8b08e6849"}]
07:06:25 INFO - 1500645985593 Marionette TRACE 394 <- [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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
07:06:25 INFO - 1500645985596 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
07:06:25 INFO - 1500645985611 Marionette TRACE 394 <- [1,7,null,{"value":false}]
07:06:25 INFO - 1500645985613 Marionette TRACE 394 -> [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}]
07:06:25 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500645985620 Marionette TRACE 394 <- [1,8,null,{"value":null}]
07:06:25 INFO - 1500645985622 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
07:06:25 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1304ms
07:06:25 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
07:06:25 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:25 INFO - 1500645985626 Marionette TRACE 394 <- [1,9,null,{}]
07:06:25 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:25 INFO - 1500645985630 Marionette DEBUG Closed connection 394
07:06:25 INFO - 1500645985632 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49842
07:06:25 INFO - 1500645985634 Marionette DEBUG Closed connection 395
07:06:25 INFO - 1500645985635 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49843
07:06:25 INFO - 1500645985637 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:25 INFO - 1500645985640 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:25 INFO - 1500645985665 Marionette DEBUG Register listener.js for window 6442450945
07:06:25 INFO - 1500645985676 Marionette TRACE 396 <- [1,1,null,{"sessionId":"019b7d6d-4f17-4dec-8d43-2062bde61093","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:25 INFO - 1500645985679 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
07:06:25 INFO - 1500645985680 Marionette TRACE 396 <- [1,2,null,{}]
07:06:25 INFO - 1500645985682 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
07:06:25 INFO - 1500645985683 Marionette TRACE 396 <- [1,3,null,{}]
07:06:25 INFO - 1500645985685 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
07:06:25 INFO - 1500645985687 Marionette TRACE 396 <- [1,4,null,{}]
07:06:25 INFO - 1500645985689 Marionette TRACE 396 -> [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}]
07:06:25 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500645985696 Marionette TRACE 396 <- [1,5,null,{"value":null}]
07:06:25 INFO - 1500645985698 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
07:06:25 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:25 INFO - 1500645985702 Marionette TRACE 396 <- [1,6,null,{}]
07:06:25 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:25 INFO - 1500645985706 Marionette DEBUG Closed connection 396
07:06:25 INFO - 1500645985708 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49844
07:06:25 INFO - 1500645985710 Marionette DEBUG Closed connection 397
07:06:25 INFO - 1500645985712 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49845
07:06:25 INFO - 1500645985714 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
07:06:25 INFO - 1500645985716 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:25 INFO - 1500645985740 Marionette DEBUG Register listener.js for window 6442450945
07:06:25 INFO - 1500645985751 Marionette TRACE 398 <- [1,1,null,{"sessionId":"05c52d1a-8f7b-4f0f-b7a4-8009d13378a2","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:25 INFO - 1500645985754 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
07:06:25 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:25 INFO - 1500645985759 Marionette TRACE 398 <- [1,2,null,{}]
07:06:25 INFO - [Parent 3568] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
07:06:25 INFO - 1500645985763 Marionette DEBUG Closed connection 398
07:06:25 INFO - 1500645985765 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49846
07:06:25 INFO - 1500645985766 Marionette DEBUG Closed connection 399
07:06:25 INFO - 1500645985768 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49847
07:06:25 INFO - 1500645985770 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
07:06:25 INFO - 1500645985772 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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}
07:06:25 INFO - 1500645985796 Marionette DEBUG Register listener.js for window 6442450945
07:06:25 INFO - 1500645985807 Marionette TRACE 400 <- [1,1,null,{"sessionId":"c66561ab-0cce-486d-b520-a349aaa24d90","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":3568,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-243.000\\AppData\\Local\\Temp\\tmpqzcma5.mozrunner","moz:accessibilityChecks":false}}]
07:06:25 INFO - 1500645985810 Marionette TRACE 400 -> [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"}]
07:06:25 INFO - 1500645985814 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"
07:06:25 INFO - 1500645985819 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"
07:06:25 INFO - 1500645985820 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"
07:06:25 INFO - ++DOMWINDOW == 18 (000000F705B72800) [pid = 3788] [serial = 32] [outer = 000000F777E2B000]
07:06:25 INFO - 1500645985843 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"
07:06:25 INFO - 1500645985849 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"
07:06:25 INFO - 1500645985857 Marionette TRACE 400 <- [1,2,null,{}]
07:06:25 INFO - 1500645985865 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
07:06:25 INFO - 1500645985886 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a56142fa-064d-4b31-8ce0-2878ef3d45c7","ELEMENT":"a56142fa-064d-4b31-8ce0-2878ef3d45c7"}}]
07:06:25 INFO - 1500645985888 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"a56142fa-064d-4b31-8ce0-2878ef3d45c7"}]
07:06:26 INFO - 1500645986027 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
07:06:26 INFO - 1500645986029 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"a56142fa-064d-4b31-8ce0-2878ef3d45c7"}]
07:06:26 INFO - 1500645986164 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element