could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
14:27:53 INFO - 1501018073763 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}]
14:27:53 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501018073770 Marionette TRACE 352 <- [1,5,null,{"value":null}]
14:27:53 INFO - 1501018073772 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
14:27:53 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:53 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 830ms
14:27:53 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
14:27:53 INFO - 1501018073776 Marionette TRACE 352 <- [1,6,null,{}]
14:27:53 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:53 INFO - 1501018073780 Marionette DEBUG Closed connection 352
14:27:53 INFO - 1501018073782 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49806
14:27:53 INFO - 1501018073784 Marionette DEBUG Closed connection 353
14:27:53 INFO - 1501018073785 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49807
14:27:53 INFO - 1501018073787 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:53 INFO - 1501018073792 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:53 INFO - 1501018073816 Marionette DEBUG Register listener.js for window 4294967297
14:27:53 INFO - 1501018073831 Marionette TRACE 354 <- [1,1,null,{"sessionId":"15ac4558-b411-4789-a8dd-e8fe8e1a516f","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:53 INFO - 1501018073834 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
14:27:53 INFO - 1501018073835 Marionette TRACE 354 <- [1,2,null,{}]
14:27:53 INFO - 1501018073837 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:27:53 INFO - 1501018073839 Marionette TRACE 354 <- [1,3,null,{}]
14:27:53 INFO - 1501018073841 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
14:27:53 INFO - 1501018073843 Marionette TRACE 354 <- [1,4,null,{}]
14:27:53 INFO - 1501018073846 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}]
14:27:53 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501018073853 Marionette TRACE 354 <- [1,5,null,{"value":null}]
14:27:53 INFO - 1501018073855 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
14:27:53 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:53 INFO - 1501018073859 Marionette TRACE 354 <- [1,6,null,{}]
14:27:53 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:53 INFO - 1501018073864 Marionette DEBUG Closed connection 354
14:27:53 INFO - 1501018073866 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49808
14:27:53 INFO - 1501018073870 Marionette DEBUG Closed connection 355
14:27:53 INFO - 1501018073871 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49809
14:27:53 INFO - 1501018073874 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:53 INFO - 1501018073876 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:53 INFO - 1501018073897 Marionette DEBUG Register listener.js for window 4294967297
14:27:53 INFO - 1501018073908 Marionette TRACE 356 <- [1,1,null,{"sessionId":"aab1da91-c712-4e3d-af1a-7bffe487d604","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:53 INFO - 1501018073911 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
14:27:53 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:53 INFO - 1501018073915 Marionette TRACE 356 <- [1,2,null,{}]
14:27:53 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:53 INFO - 1501018073920 Marionette DEBUG Closed connection 356
14:27:53 INFO - 1501018073921 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49810
14:27:53 INFO - 1501018073922 Marionette DEBUG Closed connection 357
14:27:53 INFO - 1501018073924 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49811
14:27:53 INFO - 1501018073926 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:27:53 INFO - 1501018073929 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:53 INFO - 1501018073949 Marionette DEBUG Register listener.js for window 4294967297
14:27:53 INFO - 1501018073960 Marionette TRACE 358 <- [1,1,null,{"sessionId":"d8e505d7-1125-4f76-98e8-c227cc79c0b0","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:53 INFO - 1501018073963 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49348/clicks.html"}]
14:27:53 INFO - 1501018073968 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49348/hidden.html"
14:27:53 INFO - 1501018073987 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49348/hidden.html"
14:27:53 INFO - 1501018073987 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49348/hidden.html"
14:27:53 INFO - ++DOMWINDOW == 15 (000000CF79EDB800) [pid = 1524] [serial = 25] [outer = 000000CF7A6DD000]
14:27:54 INFO - 1501018074023 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49348/clicks.html"
14:27:54 INFO - 1501018074027 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49348/clicks.html"
14:27:54 INFO - 1501018074038 Marionette TRACE 358 <- [1,2,null,{}]
14:27:54 INFO - 1501018074055 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
14:27:54 INFO - 1501018074062 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e3674b68-5798-40a4-9f03-86614ed5fe4c","ELEMENT":"e3674b68-5798-40a4-9f03-86614ed5fe4c"}}]
14:27:54 INFO - 1501018074064 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"e3674b68-5798-40a4-9f03-86614ed5fe4c"}]
14:27:54 INFO - 1501018074291 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49348/clicks.html"
14:27:54 INFO - 1501018074312 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49348/clicks.html"
14:27:54 INFO - 1501018074313 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49348/clicks.html"
14:27:54 INFO - ++DOMWINDOW == 16 (000000CF062BB000) [pid = 1524] [serial = 26] [outer = 000000CF7A6DD000]
14:27:54 INFO - 1501018074335 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49348/test.html"
14:27:54 INFO - 1501018074343 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49348/test.html"
14:27:54 INFO - 1501018074353 Marionette TRACE 358 <- [1,4,null,{}]
14:27:54 INFO - 1501018074371 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
14:27:54 INFO - 1501018074377 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"45d9e5d4-470f-4642-a4e9-f51a9a9dd081","ELEMENT":"45d9e5d4-470f-4642-a4e9-f51a9a9dd081"}}]
14:27:54 INFO - 1501018074379 Marionette TRACE 358 -> [0,6,"getTitle",{}]
14:27:54 INFO - 1501018074380 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
14:27:54 INFO - 1501018074383 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}]
14:27:54 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501018074390 Marionette TRACE 358 <- [1,7,null,{"value":null}]
14:27:54 INFO - 1501018074392 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
14:27:54 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:54 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 619ms
14:27:54 INFO - 1501018074396 Marionette TRACE 358 <- [1,8,null,{}]
14:27:54 INFO - TEST-START | test_click.py TestClick.test_container_element
14:27:54 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:54 INFO - 1501018074400 Marionette DEBUG Closed connection 358
14:27:54 INFO - 1501018074402 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49814
14:27:54 INFO - 1501018074403 Marionette DEBUG Closed connection 359
14:27:54 INFO - 1501018074405 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49815
14:27:54 INFO - 1501018074407 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:54 INFO - 1501018074409 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:54 INFO - 1501018074429 Marionette DEBUG Register listener.js for window 4294967297
14:27:54 INFO - 1501018074441 Marionette TRACE 360 <- [1,1,null,{"sessionId":"e6cc8322-8150-42aa-a53c-28e8b19e9c02","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:54 INFO - 1501018074444 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
14:27:54 INFO - 1501018074446 Marionette TRACE 360 <- [1,2,null,{}]
14:27:54 INFO - 1501018074447 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:27:54 INFO - 1501018074449 Marionette TRACE 360 <- [1,3,null,{}]
14:27:54 INFO - 1501018074451 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
14:27:54 INFO - 1501018074453 Marionette TRACE 360 <- [1,4,null,{}]
14:27:54 INFO - 1501018074455 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}]
14:27:54 INFO - TEST-START: test_click.py TestClick.test_container_element1501018074464 Marionette TRACE 360 <- [1,5,null,{"value":null}]
14:27:54 INFO - 1501018074466 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
14:27:54 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:54 INFO - 1501018074471 Marionette TRACE 360 <- [1,6,null,{}]
14:27:54 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:54 INFO - 1501018074475 Marionette DEBUG Closed connection 360
14:27:54 INFO - 1501018074476 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49816
14:27:54 INFO - 1501018074478 Marionette DEBUG Closed connection 361
14:27:54 INFO - 1501018074479 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49817
14:27:54 INFO - 1501018074481 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:54 INFO - 1501018074484 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:54 INFO - 1501018074503 Marionette DEBUG Register listener.js for window 4294967297
14:27:54 INFO - 1501018074526 Marionette TRACE 362 <- [1,1,null,{"sessionId":"070bcfab-6f0d-43af-b641-d8e5c0cebc63","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:54 INFO - 1501018074529 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
14:27:54 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:54 INFO - 1501018074534 Marionette TRACE 362 <- [1,2,null,{}]
14:27:54 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:54 INFO - 1501018074538 Marionette DEBUG Closed connection 362
14:27:54 INFO - 1501018074540 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49818
14:27:54 INFO - 1501018074541 Marionette DEBUG Closed connection 363
14:27:54 INFO - 1501018074543 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49819
14:27:54 INFO - 1501018074547 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:27:54 INFO - 1501018074550 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:54 INFO - 1501018074571 Marionette DEBUG Register listener.js for window 4294967297
14:27:54 INFO - 1501018074582 Marionette TRACE 364 <- [1,1,null,{"sessionId":"e4cc6b75-7d3c-4084-a21e-44352147b39c","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:54 INFO - 1501018074585 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"}]
14:27:54 INFO - 1501018074589 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49348/test.html"
14:27:54 INFO - 1501018074594 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49348/test.html"
14:27:54 INFO - 1501018074595 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49348/test.html"
14:27:54 INFO - ++DOMWINDOW == 17 (000000CF075BA800) [pid = 1524] [serial = 27] [outer = 000000CF7A6DD000]
14:27:54 INFO - 1501018074620 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"
14:27:54 INFO - 1501018074625 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"
14:27:54 INFO - 1501018074639 Marionette TRACE 364 <- [1,2,null,{}]
14:27:54 INFO - 1501018074655 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:27:54 INFO - 1501018074661 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6f5e1ff1-4fb0-439f-bb0b-c42b809f99b9","ELEMENT":"6f5e1ff1-4fb0-439f-bb0b-c42b809f99b9"}}]
14:27:54 INFO - 1501018074663 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"6f5e1ff1-4fb0-439f-bb0b-c42b809f99b9"}]
14:27:54 INFO - [Child 1524] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
14:27:54 INFO - [Child 1524] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
14:27:54 INFO - [Child 1524] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
14:27:55 INFO - 1501018075082 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:27:55 INFO - 1501018075084 Marionette TRACE 364 <- [1,4,null,{}]
14:27:55 INFO - 1501018075086 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"6f5e1ff1-4fb0-439f-bb0b-c42b809f99b9","name":"selected"}]
14:27:55 INFO - 1501018075112 Marionette TRACE 364 <- [1,5,null,{"value":true}]
14:27:55 INFO - 1501018075115 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}]
14:27:55 INFO - TEST-END: test_click.py TestClick.test_container_element1501018075122 Marionette TRACE 364 <- [1,6,null,{"value":null}]
14:27:55 INFO - 1501018075124 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
14:27:55 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 732ms
14:27:55 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
14:27:55 INFO - 1501018075129 Marionette TRACE 364 <- [1,7,null,{}]
14:27:55 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:55 INFO - 1501018075133 Marionette DEBUG Closed connection 364
14:27:55 INFO - 1501018075134 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49820
14:27:55 INFO - 1501018075136 Marionette DEBUG Closed connection 365
14:27:55 INFO - 1501018075137 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49821
14:27:55 INFO - 1501018075139 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:55 INFO - 1501018075142 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:55 INFO - 1501018075161 Marionette DEBUG Register listener.js for window 4294967297
14:27:55 INFO - 1501018075173 Marionette TRACE 366 <- [1,1,null,{"sessionId":"64bfd13a-b7a1-4f5f-bd94-ffc486ea967d","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:55 INFO - 1501018075176 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
14:27:55 INFO - 1501018075177 Marionette TRACE 366 <- [1,2,null,{}]
14:27:55 INFO - 1501018075179 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:27:55 INFO - 1501018075181 Marionette TRACE 366 <- [1,3,null,{}]
14:27:55 INFO - 1501018075183 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
14:27:55 INFO - 1501018075184 Marionette TRACE 366 <- [1,4,null,{}]
14:27:55 INFO - 1501018075187 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}]
14:27:55 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501018075194 Marionette TRACE 366 <- [1,5,null,{"value":null}]
14:27:55 INFO - 1501018075196 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
14:27:55 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:55 INFO - 1501018075201 Marionette TRACE 366 <- [1,6,null,{}]
14:27:55 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:55 INFO - 1501018075205 Marionette DEBUG Closed connection 366
14:27:55 INFO - 1501018075206 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49822
14:27:55 INFO - 1501018075208 Marionette DEBUG Closed connection 367
14:27:55 INFO - 1501018075210 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49823
14:27:55 INFO - 1501018075212 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:55 INFO - 1501018075214 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:55 INFO - 1501018075234 Marionette DEBUG Register listener.js for window 4294967297
14:27:55 INFO - 1501018075245 Marionette TRACE 368 <- [1,1,null,{"sessionId":"d365dcfc-6587-4ead-96d8-c2609568e76b","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:55 INFO - 1501018075251 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
14:27:55 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:55 INFO - 1501018075255 Marionette TRACE 368 <- [1,2,null,{}]
14:27:55 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:55 INFO - 1501018075260 Marionette DEBUG Closed connection 368
14:27:55 INFO - 1501018075261 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49824
14:27:55 INFO - 1501018075263 Marionette DEBUG Closed connection 369
14:27:55 INFO - 1501018075264 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49825
14:27:55 INFO - 1501018075267 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:27:55 INFO - 1501018075272 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:55 INFO - 1501018075292 Marionette DEBUG Register listener.js for window 4294967297
14:27:55 INFO - 1501018075303 Marionette TRACE 370 <- [1,1,null,{"sessionId":"b9d5810f-9e97-4cd8-8f37-2e12e858bf3f","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:55 INFO - 1501018075306 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"}]
14:27:55 INFO - 1501018075311 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"
14:27:55 INFO - 1501018075315 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"
14:27:55 INFO - 1501018075316 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"
14:27:55 INFO - ++DOMWINDOW == 18 (000000CF07651000) [pid = 1524] [serial = 28] [outer = 000000CF7A6DD000]
14:27:55 INFO - 1501018075340 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"
14:27:55 INFO - 1501018075344 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"
14:27:55 INFO - 1501018075355 Marionette TRACE 370 <- [1,2,null,{}]
14:27:55 INFO - 1501018075371 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
14:27:55 INFO - 1501018075377 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eb1c4e90-c5b6-419c-864b-764002387ed9","ELEMENT":"eb1c4e90-c5b6-419c-864b-764002387ed9"}}]
14:27:55 INFO - 1501018075379 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"eb1c4e90-c5b6-419c-864b-764002387ed9"}]
14:27:55 INFO - [Child 1524] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
14:27:55 INFO - [Child 1524] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
14:27:55 INFO - [Child 1524] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
14:27:55 INFO - 1501018075813 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:27:55 INFO - 1501018075815 Marionette TRACE 370 <- [1,4,null,{}]
14:27:55 INFO - 1501018075817 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"eb1c4e90-c5b6-419c-864b-764002387ed9","name":"selected"}]
14:27:55 INFO - 1501018075844 Marionette TRACE 370 <- [1,5,null,{"value":true}]
14:27:55 INFO - 1501018075847 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}]
14:27:55 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501018075854 Marionette TRACE 370 <- [1,6,null,{"value":null}]
14:27:55 INFO - 1501018075856 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
14:27:55 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 731ms
14:27:55 INFO - 1501018075860 Marionette TRACE 370 <- [1,7,null,{}]
14:27:55 INFO - TEST-START | test_click.py TestClick.test_css_transforms
14:27:55 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:55 INFO - 1501018075864 Marionette DEBUG Closed connection 370
14:27:55 INFO - 1501018075866 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49826
14:27:55 INFO - 1501018075867 Marionette DEBUG Closed connection 371
14:27:55 INFO - 1501018075869 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49827
14:27:55 INFO - 1501018075871 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:55 INFO - 1501018075873 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:55 INFO - 1501018075894 Marionette DEBUG Register listener.js for window 4294967297
14:27:55 INFO - 1501018075905 Marionette TRACE 372 <- [1,1,null,{"sessionId":"9b706b15-b7dc-4d2e-944f-275ec438f6ae","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:55 INFO - 1501018075908 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
14:27:55 INFO - 1501018075910 Marionette TRACE 372 <- [1,2,null,{}]
14:27:55 INFO - 1501018075912 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:27:55 INFO - 1501018075913 Marionette TRACE 372 <- [1,3,null,{}]
14:27:55 INFO - 1501018075915 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
14:27:55 INFO - 1501018075917 Marionette TRACE 372 <- [1,4,null,{}]
14:27:55 INFO - 1501018075919 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}]
14:27:55 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501018075927 Marionette TRACE 372 <- [1,5,null,{"value":null}]
14:27:55 INFO - 1501018075929 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
14:27:55 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:55 INFO - 1501018075933 Marionette TRACE 372 <- [1,6,null,{}]
14:27:55 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:55 INFO - 1501018075937 Marionette DEBUG Closed connection 372
14:27:55 INFO - 1501018075938 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49828
14:27:55 INFO - 1501018075940 Marionette DEBUG Closed connection 373
14:27:55 INFO - 1501018075941 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49829
14:27:55 INFO - 1501018075944 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:55 INFO - 1501018075946 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:55 INFO - 1501018075966 Marionette DEBUG Register listener.js for window 4294967297
14:27:55 INFO - 1501018075978 Marionette TRACE 374 <- [1,1,null,{"sessionId":"af45ba1f-0135-40d2-852d-61ee7d6a9303","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:55 INFO - 1501018075981 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
14:27:55 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:55 INFO - 1501018075986 Marionette TRACE 374 <- [1,2,null,{}]
14:27:55 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:55 INFO - 1501018075992 Marionette DEBUG Closed connection 374
14:27:55 INFO - 1501018075994 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49830
14:27:55 INFO - 1501018075995 Marionette DEBUG Closed connection 375
14:27:55 INFO - 1501018075997 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49831
14:27:56 INFO - 1501018075999 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:27:56 INFO - 1501018076002 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:56 INFO - 1501018076022 Marionette DEBUG Register listener.js for window 4294967297
14:27:56 INFO - 1501018076034 Marionette TRACE 376 <- [1,1,null,{"sessionId":"8b6b9dc1-d409-4c09-bd4e-d243156f6835","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:56 INFO - 1501018076037 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"}]
14:27:56 INFO - 1501018076041 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"
14:27:56 INFO - 1501018076046 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"
14:27:56 INFO - 1501018076047 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"
14:27:56 INFO - ++DOMWINDOW == 19 (000000CF075D9800) [pid = 1524] [serial = 29] [outer = 000000CF7A6DD000]
14:27:56 INFO - 1501018076071 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"
14:27:56 INFO - 1501018076076 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"
14:27:56 INFO - 1501018076091 Marionette TRACE 376 <- [1,2,null,{}]
14:27:56 INFO - 1501018076106 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
14:27:56 INFO - 1501018076113 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"95482964-1d31-4953-8d9d-1de225d7da02","ELEMENT":"95482964-1d31-4953-8d9d-1de225d7da02"}}]
14:27:56 INFO - 1501018076115 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"95482964-1d31-4953-8d9d-1de225d7da02"}]
14:27:56 INFO - 1501018076538 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:27:56 INFO - 1501018076540 Marionette TRACE 376 <- [1,4,null,{}]
14:27:56 INFO - 1501018076542 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}]
14:27:56 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501018076729 Marionette TRACE 376 <- [1,5,null,{"value":null}]
14:27:56 INFO - 1501018076731 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
14:27:56 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 875ms
14:27:56 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:56 INFO - 1501018076736 Marionette TRACE 376 <- [1,6,null,{}]
14:27:56 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
14:27:56 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:56 INFO - 1501018076740 Marionette DEBUG Closed connection 376
14:27:56 INFO - 1501018076742 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49832
14:27:56 INFO - 1501018076743 Marionette DEBUG Closed connection 377
14:27:56 INFO - 1501018076744 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49833
14:27:56 INFO - 1501018076747 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:56 INFO - 1501018076749 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:56 INFO - 1501018076769 Marionette DEBUG Register listener.js for window 4294967297
14:27:56 INFO - 1501018076780 Marionette TRACE 378 <- [1,1,null,{"sessionId":"7102de9b-ef55-4a67-86ba-9e84165012fb","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:56 INFO - 1501018076783 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
14:27:56 INFO - 1501018076784 Marionette TRACE 378 <- [1,2,null,{}]
14:27:56 INFO - 1501018076786 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:27:56 INFO - 1501018076788 Marionette TRACE 378 <- [1,3,null,{}]
14:27:56 INFO - 1501018076790 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
14:27:56 INFO - 1501018076791 Marionette TRACE 378 <- [1,4,null,{}]
14:27:56 INFO - 1501018076794 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}]
14:27:56 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501018076801 Marionette TRACE 378 <- [1,5,null,{"value":null}]
14:27:56 INFO - 1501018076802 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
14:27:56 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:56 INFO - 1501018076806 Marionette TRACE 378 <- [1,6,null,{}]
14:27:56 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:56 INFO - 1501018076810 Marionette DEBUG Closed connection 378
14:27:56 INFO - 1501018076812 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49834
14:27:56 INFO - 1501018076814 Marionette DEBUG Closed connection 379
14:27:56 INFO - 1501018076815 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49835
14:27:56 INFO - 1501018076817 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:56 INFO - 1501018076820 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:56 INFO - 1501018076841 Marionette DEBUG Register listener.js for window 4294967297
14:27:56 INFO - 1501018076853 Marionette TRACE 380 <- [1,1,null,{"sessionId":"7cd8033e-9777-45d8-857e-11816195bbca","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:56 INFO - 1501018076855 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
14:27:56 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:56 INFO - 1501018076860 Marionette TRACE 380 <- [1,2,null,{}]
14:27:56 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:56 INFO - 1501018076864 Marionette DEBUG Closed connection 380
14:27:56 INFO - 1501018076865 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49836
14:27:56 INFO - 1501018076867 Marionette DEBUG Closed connection 381
14:27:56 INFO - 1501018076868 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49837
14:27:56 INFO - 1501018076870 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:27:56 INFO - 1501018076873 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:56 INFO - 1501018076895 Marionette DEBUG Register listener.js for window 4294967297
14:27:56 INFO - 1501018076906 Marionette TRACE 382 <- [1,1,null,{"sessionId":"e5212f73-501d-4312-9d24-c7edd8abc6cf","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:56 INFO - 1501018076909 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"}]
14:27:56 INFO - 1501018076913 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"
14:27:56 INFO - 1501018076918 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"
14:27:56 INFO - 1501018076918 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"
14:27:56 INFO - ++DOMWINDOW == 20 (000000CF07616000) [pid = 1524] [serial = 30] [outer = 000000CF7A6DD000]
14:27:56 INFO - 1501018076943 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"
14:27:56 INFO - 1501018076947 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"
14:27:56 INFO - 1501018076989 Marionette TRACE 382 <- [1,2,null,{}]
14:27:57 INFO - 1501018077017 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
14:27:57 INFO - 1501018077024 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d88965d1-b20c-4b09-9b1c-10cbb5b28ce1","ELEMENT":"d88965d1-b20c-4b09-9b1c-10cbb5b28ce1"}}]
14:27:57 INFO - 1501018077026 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"d88965d1-b20c-4b09-9b1c-10cbb5b28ce1"}]
14:27:57 INFO - 1501018077923 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:27:57 INFO - 1501018077926 Marionette TRACE 382 <- [1,4,null,{}]
14:27:57 INFO - 1501018077932 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}]
14:27:57 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501018077990 Marionette TRACE 382 <- [1,5,null,{"value":null}]
14:27:57 INFO - 1501018077995 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
14:27:58 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:58 INFO - 1501018078005 Marionette TRACE 382 <- [1,6,null,{}]
14:27:58 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1269ms
14:27:58 INFO - TEST-START | test_click.py TestClick.test_input_file
14:27:58 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:58 INFO - 1501018078014 Marionette DEBUG Closed connection 382
14:27:58 INFO - 1501018078017 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49838
14:27:58 INFO - 1501018078020 Marionette DEBUG Closed connection 383
14:27:58 INFO - 1501018078023 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49839
14:27:58 INFO - 1501018078028 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:58 INFO - 1501018078040 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:58 INFO - 1501018078096 Marionette DEBUG Register listener.js for window 4294967297
14:27:58 INFO - 1501018078115 Marionette TRACE 384 <- [1,1,null,{"sessionId":"ca8d12fe-dca7-4738-b63d-21443a09e146","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:58 INFO - 1501018078122 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
14:27:58 INFO - 1501018078125 Marionette TRACE 384 <- [1,2,null,{}]
14:27:58 INFO - 1501018078128 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:27:58 INFO - 1501018078133 Marionette TRACE 384 <- [1,3,null,{}]
14:27:58 INFO - 1501018078136 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
14:27:58 INFO - 1501018078139 Marionette TRACE 384 <- [1,4,null,{}]
14:27:58 INFO - 1501018078143 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}]
14:27:58 INFO - TEST-START: test_click.py TestClick.test_input_file1501018078164 Marionette TRACE 384 <- [1,5,null,{"value":null}]
14:27:58 INFO - 1501018078167 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
14:27:58 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:58 INFO - 1501018078175 Marionette TRACE 384 <- [1,6,null,{}]
14:27:58 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:58 INFO - 1501018078182 Marionette DEBUG Closed connection 384
14:27:58 INFO - 1501018078185 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49840
14:27:58 INFO - 1501018078187 Marionette DEBUG Closed connection 385
14:27:58 INFO - 1501018078191 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49841
14:27:58 INFO - 1501018078195 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:58 INFO - 1501018078198 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:58 INFO - 1501018078238 Marionette DEBUG Register listener.js for window 4294967297
14:27:58 INFO - 1501018078255 Marionette TRACE 386 <- [1,1,null,{"sessionId":"1fb9a41b-7f70-49db-a8de-320f67b90e08","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:58 INFO - 1501018078258 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
14:27:58 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:58 INFO - 1501018078265 Marionette TRACE 386 <- [1,2,null,{}]
14:27:58 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:58 INFO - 1501018078271 Marionette DEBUG Closed connection 386
14:27:58 INFO - 1501018078273 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49842
14:27:58 INFO - 1501018078275 Marionette DEBUG Closed connection 387
14:27:58 INFO - 1501018078277 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49843
14:27:58 INFO - 1501018078285 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:27:58 INFO - 1501018078291 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:58 INFO - 1501018078312 Marionette DEBUG Register listener.js for window 4294967297
14:27:58 INFO - 1501018078326 Marionette TRACE 388 <- [1,1,null,{"sessionId":"55d5a6e6-c9d4-4231-898a-a4adbfd3b35e","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:58 INFO - 1501018078330 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
14:27:58 INFO - 1501018078335 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"
14:27:58 INFO - 1501018078339 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"
14:27:58 INFO - 1501018078341 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"
14:27:58 INFO - ++DOMWINDOW == 21 (000000CF0444C000) [pid = 1524] [serial = 31] [outer = 000000CF7A6DD000]
14:27:58 INFO - 1501018078363 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:27:58 INFO - 1501018078372 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:27:58 INFO - 1501018078404 Marionette TRACE 388 <- [1,2,null,{}]
14:27:58 INFO - 1501018078417 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
14:27:58 INFO - 1501018078429 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5f0d05e9-5bef-44b5-a5b9-b03429435e2d","ELEMENT":"5f0d05e9-5bef-44b5-a5b9-b03429435e2d"}}]
14:27:58 INFO - 1501018078431 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"5f0d05e9-5bef-44b5-a5b9-b03429435e2d"}]
14:27:58 INFO - 1501018078587 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
14:27:58 INFO - 1501018078590 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}]
14:27:58 INFO - TEST-END: test_click.py TestClick.test_input_file1501018078599 Marionette TRACE 388 <- [1,5,null,{"value":null}]
14:27:58 INFO - 1501018078601 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
14:27:58 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:58 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 599ms
14:27:58 INFO - TEST-START | test_click.py TestClick.test_obscured_element
14:27:58 INFO - 1501018078606 Marionette TRACE 388 <- [1,6,null,{}]
14:27:58 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:58 INFO - 1501018078611 Marionette DEBUG Closed connection 388
14:27:58 INFO - 1501018078612 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49844
14:27:58 INFO - 1501018078614 Marionette DEBUG Closed connection 389
14:27:58 INFO - 1501018078616 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49845
14:27:58 INFO - 1501018078619 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:58 INFO - 1501018078622 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:58 INFO - 1501018078643 Marionette DEBUG Register listener.js for window 4294967297
14:27:58 INFO - 1501018078657 Marionette TRACE 390 <- [1,1,null,{"sessionId":"1ce528f1-1a39-4a66-955d-cac5b299f8b9","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:58 INFO - 1501018078660 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
14:27:58 INFO - 1501018078663 Marionette TRACE 390 <- [1,2,null,{}]
14:27:58 INFO - 1501018078665 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:27:58 INFO - 1501018078667 Marionette TRACE 390 <- [1,3,null,{}]
14:27:58 INFO - 1501018078669 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
14:27:58 INFO - 1501018078671 Marionette TRACE 390 <- [1,4,null,{}]
14:27:58 INFO - 1501018078673 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}]
14:27:58 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501018078685 Marionette TRACE 390 <- [1,5,null,{"value":null}]
14:27:58 INFO - 1501018078687 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
14:27:58 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:58 INFO - 1501018078692 Marionette TRACE 390 <- [1,6,null,{}]
14:27:58 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:58 INFO - 1501018078696 Marionette DEBUG Closed connection 390
14:27:58 INFO - 1501018078698 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49846
14:27:58 INFO - 1501018078700 Marionette DEBUG Closed connection 391
14:27:58 INFO - 1501018078702 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49847
14:27:58 INFO - 1501018078704 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:27:58 INFO - 1501018078708 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:58 INFO - 1501018078729 Marionette DEBUG Register listener.js for window 4294967297
14:27:58 INFO - 1501018078747 Marionette TRACE 392 <- [1,1,null,{"sessionId":"8c75477d-0c98-40a2-a045-ae18580bf034","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:58 INFO - 1501018078750 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
14:27:58 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:58 INFO - 1501018078756 Marionette TRACE 392 <- [1,2,null,{}]
14:27:58 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:27:58 INFO - 1501018078765 Marionette DEBUG Closed connection 392
14:27:58 INFO - 1501018078771 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49848
14:27:58 INFO - 1501018078773 Marionette DEBUG Closed connection 393
14:27:58 INFO - 1501018078775 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49849
14:27:58 INFO - 1501018078778 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:27:58 INFO - 1501018078781 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:27:58 INFO - 1501018078803 Marionette DEBUG Register listener.js for window 4294967297
14:27:58 INFO - 1501018078817 Marionette TRACE 394 <- [1,1,null,{"sessionId":"e35e6480-da61-4ed7-a047-4dc9007c0911","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:27:58 INFO - 1501018078820 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"}]
14:27:58 INFO - 1501018078832 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:27:58 INFO - 1501018078838 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:27:58 INFO - 1501018078838 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
14:27:58 INFO - ++DOMWINDOW == 22 (000000CF03B7A800) [pid = 1524] [serial = 32] [outer = 000000CF7A6DD000]
14:27:58 INFO - 1501018078878 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"
14:27:58 INFO - 1501018078882 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"
14:27:58 INFO - 1501018078900 Marionette TRACE 394 <- [1,2,null,{}]
14:27:58 INFO - 1501018078909 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
14:27:58 INFO - 1501018078916 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e89dbe6a-759c-4a56-b92a-795e774a88ff","ELEMENT":"e89dbe6a-759c-4a56-b92a-795e774a88ff"}}]
14:27:58 INFO - 1501018078925 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
14:27:58 INFO - 1501018078933 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c47254c3-4f7d-4886-9b76-66cb27f5b6e8","ELEMENT":"c47254c3-4f7d-4886-9b76-66cb27f5b6e8"}}]
14:27:58 INFO - 1501018078935 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"e89dbe6a-759c-4a56-b92a-795e774a88ff"}]
14:27:59 INFO - 1501018079358 Marionette DEBUG Canceled page load listener because no navigation has been detected
14:27:59 INFO - 1501018079360 Marionette TRACE 394 <- [1,5,null,{}]
14:27:59 INFO - 1501018079402 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"c47254c3-4f7d-4886-9b76-66cb27f5b6e8"}]
14:27:59 INFO - 1501018079526 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:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
14:27:59 INFO - 1501018079529 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
14:27:59 INFO - 1501018079544 Marionette TRACE 394 <- [1,7,null,{"value":false}]
14:27:59 INFO - 1501018079547 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}]
14:28:00 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501018080069 Marionette TRACE 394 <- [1,8,null,{"value":null}]
14:28:00 INFO - 1501018080076 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
14:28:00 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:28:00 INFO - 1501018080081 Marionette TRACE 394 <- [1,9,null,{}]
14:28:00 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1475ms
14:28:00 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
14:28:00 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:28:00 INFO - 1501018080087 Marionette DEBUG Closed connection 394
14:28:00 INFO - 1501018080089 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49850
14:28:00 INFO - 1501018080092 Marionette DEBUG Closed connection 395
14:28:00 INFO - 1501018080093 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49851
14:28:00 INFO - 1501018080095 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:28:00 INFO - 1501018080098 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:28:00 INFO - 1501018080120 Marionette DEBUG Register listener.js for window 4294967297
14:28:00 INFO - 1501018080132 Marionette TRACE 396 <- [1,1,null,{"sessionId":"0511111f-da0a-406d-8788-2160344b5d70","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:28:00 INFO - 1501018080135 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
14:28:00 INFO - 1501018080137 Marionette TRACE 396 <- [1,2,null,{}]
14:28:00 INFO - 1501018080139 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
14:28:00 INFO - 1501018080141 Marionette TRACE 396 <- [1,3,null,{}]
14:28:00 INFO - 1501018080144 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
14:28:00 INFO - 1501018080146 Marionette TRACE 396 <- [1,4,null,{}]
14:28:00 INFO - 1501018080149 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}]
14:28:00 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501018080184 Marionette TRACE 396 <- [1,5,null,{"value":null}]
14:28:00 INFO - 1501018080186 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
14:28:00 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:28:00 INFO - 1501018080191 Marionette TRACE 396 <- [1,6,null,{}]
14:28:00 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:28:00 INFO - 1501018080195 Marionette DEBUG Closed connection 396
14:28:00 INFO - 1501018080197 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49852
14:28:00 INFO - 1501018080198 Marionette DEBUG Closed connection 397
14:28:00 INFO - 1501018080200 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49853
14:28:00 INFO - 1501018080202 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
14:28:00 INFO - 1501018080205 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:28:00 INFO - 1501018080226 Marionette DEBUG Register listener.js for window 4294967297
14:28:00 INFO - 1501018080238 Marionette TRACE 398 <- [1,1,null,{"sessionId":"2ba421e1-926b-4b68-8f1b-109d5a2ea403","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:28:00 INFO - 1501018080241 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
14:28:00 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:28:00 INFO - 1501018080245 Marionette TRACE 398 <- [1,2,null,{}]
14:28:00 INFO - [Parent 4008] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
14:28:00 INFO - 1501018080249 Marionette DEBUG Closed connection 398
14:28:00 INFO - 1501018080250 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49854
14:28:00 INFO - 1501018080252 Marionette DEBUG Closed connection 399
14:28:00 INFO - 1501018080254 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49855
14:28:00 INFO - 1501018080256 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
14:28:00 INFO - 1501018080259 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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}
14:28:00 INFO - 1501018080280 Marionette DEBUG Register listener.js for window 4294967297
14:28:00 INFO - 1501018080292 Marionette TRACE 400 <- [1,1,null,{"sessionId":"a67918c7-91be-405d-a8bb-4be251f1a926","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":4008,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-336.000\\AppData\\Local\\Temp\\tmpcq7gls.mozrunner","moz:accessibilityChecks":false}}]
14:28:00 INFO - 1501018080295 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"}]
14:28:00 INFO - 1501018080299 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"
14:28:00 INFO - 1501018080304 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"
14:28:00 INFO - 1501018080305 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"
14:28:00 INFO - ++DOMWINDOW == 23 (000000CF02231800) [pid = 1524] [serial = 33] [outer = 000000CF7A6DD000]
14:28:00 INFO - 1501018080330 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"
14:28:00 INFO - 1501018080334 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"
14:28:00 INFO - 1501018080353 Marionette TRACE 400 <- [1,2,null,{}]
14:28:00 INFO - 1501018080369 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
14:28:00 INFO - 1501018080376 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f702e29b-67e4-4ad8-97f1-e3f6d9187ad3","ELEMENT":"f702e29b-67e4-4ad8-97f1-e3f6d9187ad3"}}]
14:28:00 INFO - 1501018080379 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f702e29b-67e4-4ad8-97f1-e3f6d9187ad3"}]
14:28:00 INFO - 1501018080511 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
14:28:00 INFO - 1501018080513 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"f702e29b-67e4-4ad8-97f1-e3f6d9187ad3"}]
14:28:00 INFO - 1501018080641 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element