could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
10:10:50 INFO - 1499533850001 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}]
10:10:50 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499533850005 Marionette TRACE 352 <- [1,5,null,{"value":null}]
10:10:50 INFO - 1499533850006 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
10:10:50 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 167ms
10:10:50 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
10:10:50 INFO - 1499533850007 Marionette TRACE 352 <- [1,6,null,{}]
10:10:50 INFO - 1499533850009 Marionette DEBUG Closed connection 352
10:10:50 INFO - 1499533850010 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49808
10:10:50 INFO - 1499533850011 Marionette DEBUG Closed connection 353
10:10:50 INFO - 1499533850012 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49809
10:10:50 INFO - 1499533850013 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:50 INFO - 1499533850014 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:50 INFO - 1499533850017 Marionette DEBUG Register listener.js for window 4294967297
10:10:50 INFO - 1499533850021 Marionette TRACE 354 <- [1,1,null,{"sessionId":"efa466d7-b00e-4e36-9ebd-8c2c699737bd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:50 INFO - 1499533850023 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
10:10:50 INFO - 1499533850023 Marionette TRACE 354 <- [1,2,null,{}]
10:10:50 INFO - 1499533850024 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:10:50 INFO - 1499533850025 Marionette TRACE 354 <- [1,3,null,{}]
10:10:50 INFO - 1499533850026 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
10:10:50 INFO - 1499533850027 Marionette TRACE 354 <- [1,4,null,{}]
10:10:50 INFO - 1499533850029 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}]
10:10:50 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499533850032 Marionette TRACE 354 <- [1,5,null,{"value":null}]
10:10:50 INFO - 1499533850033 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
10:10:50 INFO - 1499533850035 Marionette TRACE 354 <- [1,6,null,{}]
10:10:50 INFO - 1499533850037 Marionette DEBUG Closed connection 354
10:10:50 INFO - 1499533850038 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49810
10:10:50 INFO - 1499533850039 Marionette DEBUG Closed connection 355
10:10:50 INFO - 1499533850040 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49811
10:10:50 INFO - 1499533850041 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:50 INFO - 1499533850042 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:50 INFO - 1499533850045 Marionette DEBUG Register listener.js for window 4294967297
10:10:50 INFO - 1499533850049 Marionette TRACE 356 <- [1,1,null,{"sessionId":"eab709ed-ebc1-43f9-929e-7b62ec576f7f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:50 INFO - 1499533850050 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
10:10:50 INFO - 1499533850052 Marionette TRACE 356 <- [1,2,null,{}]
10:10:50 INFO - 1499533850054 Marionette DEBUG Closed connection 356
10:10:50 INFO - 1499533850055 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49812
10:10:50 INFO - 1499533850056 Marionette DEBUG Closed connection 357
10:10:50 INFO - 1499533850057 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49813
10:10:50 INFO - 1499533850058 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:10:50 INFO - 1499533850059 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:50 INFO - 1499533850062 Marionette DEBUG Register listener.js for window 4294967297
10:10:50 INFO - 1499533850065 Marionette TRACE 358 <- [1,1,null,{"sessionId":"62efc831-d2e5-4d41-901b-78e3c685879c","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:50 INFO - 1499533850067 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49365/clicks.html"}]
10:10:50 INFO - 1499533850069 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49365/hidden.html"
10:10:50 INFO - 1499533850078 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49365/hidden.html"
10:10:50 INFO - 1499533850079 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49365/hidden.html"
10:10:50 INFO - 1499533850087 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49365/clicks.html"
10:10:50 INFO - 1499533850093 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49365/clicks.html"
10:10:50 INFO - 1499533850101 Marionette TRACE 358 <- [1,2,null,{}]
10:10:50 INFO - 1499533850107 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
10:10:50 INFO - 1499533850110 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"99f0c876-f1a4-47a4-af05-be348bf33200","ELEMENT":"99f0c876-f1a4-47a4-af05-be348bf33200"}}]
10:10:50 INFO - 1499533850111 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"99f0c876-f1a4-47a4-af05-be348bf33200"}]
10:10:50 INFO - 1499533850159 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49365/clicks.html"
10:10:50 INFO - 1499533850170 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49365/clicks.html"
10:10:50 INFO - 1499533850170 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49365/clicks.html"
10:10:50 INFO - 1499533850178 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49365/test.html"
10:10:50 INFO - 1499533850183 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49365/test.html"
10:10:50 INFO - 1499533850188 Marionette TRACE 358 <- [1,4,null,{}]
10:10:50 INFO - 1499533850194 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
10:10:50 INFO - 1499533850201 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d1a7adf8-1787-44af-a7de-f1b172e45cf1","ELEMENT":"d1a7adf8-1787-44af-a7de-f1b172e45cf1"}}]
10:10:50 INFO - 1499533850202 Marionette TRACE 358 -> [0,6,"getTitle",{}]
10:10:50 INFO - 1499533850203 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
10:10:50 INFO - 1499533850206 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}]
10:10:50 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 205ms
10:10:50 INFO - TEST-START | test_click.py TestClick.test_container_element
10:10:50 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499533850210 Marionette TRACE 358 <- [1,7,null,{"value":null}]
10:10:50 INFO - 1499533850211 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
10:10:50 INFO - 1499533850213 Marionette TRACE 358 <- [1,8,null,{}]
10:10:50 INFO - 1499533850215 Marionette DEBUG Closed connection 358
10:10:50 INFO - 1499533850217 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49816
10:10:50 INFO - 1499533850217 Marionette DEBUG Closed connection 359
10:10:50 INFO - 1499533850218 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49817
10:10:50 INFO - 1499533850219 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:50 INFO - 1499533850220 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:50 INFO - 1499533850223 Marionette DEBUG Register listener.js for window 4294967297
10:10:50 INFO - 1499533850227 Marionette TRACE 360 <- [1,1,null,{"sessionId":"45015fa2-29e1-418d-9f8d-a9c3acb08881","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:50 INFO - 1499533850228 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
10:10:50 INFO - 1499533850229 Marionette TRACE 360 <- [1,2,null,{}]
10:10:50 INFO - 1499533850230 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:10:50 INFO - 1499533850231 Marionette TRACE 360 <- [1,3,null,{}]
10:10:50 INFO - 1499533850232 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
10:10:50 INFO - 1499533850232 Marionette TRACE 360 <- [1,4,null,{}]
10:10:50 INFO - 1499533850234 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}]
10:10:50 INFO - TEST-START: test_click.py TestClick.test_container_element1499533850238 Marionette TRACE 360 <- [1,5,null,{"value":null}]
10:10:50 INFO - 1499533850239 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
10:10:50 INFO - 1499533850241 Marionette TRACE 360 <- [1,6,null,{}]
10:10:50 INFO - 1499533850243 Marionette DEBUG Closed connection 360
10:10:50 INFO - 1499533850244 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49818
10:10:50 INFO - 1499533850245 Marionette DEBUG Closed connection 361
10:10:50 INFO - 1499533850246 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49819
10:10:50 INFO - 1499533850247 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:50 INFO - 1499533850248 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:50 INFO - 1499533850251 Marionette DEBUG Register listener.js for window 4294967297
10:10:50 INFO - 1499533850255 Marionette TRACE 362 <- [1,1,null,{"sessionId":"4f91db99-0a81-407f-80be-1a5ccfbee909","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:50 INFO - 1499533850258 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
10:10:50 INFO - 1499533850260 Marionette TRACE 362 <- [1,2,null,{}]
10:10:50 INFO - 1499533850262 Marionette DEBUG Closed connection 362
10:10:50 INFO - 1499533850263 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49820
10:10:50 INFO - 1499533850264 Marionette DEBUG Closed connection 363
10:10:50 INFO - 1499533850264 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49821
10:10:50 INFO - 1499533850266 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:10:50 INFO - 1499533850267 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:50 INFO - 1499533850270 Marionette DEBUG Register listener.js for window 4294967297
10:10:50 INFO - 1499533850273 Marionette TRACE 364 <- [1,1,null,{"sessionId":"e427a238-45ea-4da3-acbc-b3eca95123cc","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:50 INFO - 1499533850275 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"}]
10:10:50 INFO - 1499533850277 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49365/test.html"
10:10:50 INFO - 1499533850280 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49365/test.html"
10:10:50 INFO - 1499533850280 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49365/test.html"
10:10:50 INFO - 1499533850287 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"
10:10:50 INFO - 1499533850292 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"
10:10:50 INFO - 1499533850305 Marionette TRACE 364 <- [1,2,null,{}]
10:10:50 INFO - 1499533850311 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:10:50 INFO - 1499533850319 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8a0f7896-0adc-40dc-9f00-5ce4dadf1674","ELEMENT":"8a0f7896-0adc-40dc-9f00-5ce4dadf1674"}}]
10:10:50 INFO - 1499533850320 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"8a0f7896-0adc-40dc-9f00-5ce4dadf1674"}]
10:10:50 INFO - 1499533850574 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:10:50 INFO - 1499533850575 Marionette TRACE 364 <- [1,4,null,{}]
10:10:50 INFO - 1499533850576 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"8a0f7896-0adc-40dc-9f00-5ce4dadf1674","name":"selected"}]
10:10:50 INFO - 1499533850582 Marionette TRACE 364 <- [1,5,null,{"value":true}]
10:10:50 INFO - 1499533850584 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}]
10:10:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 377ms
10:10:50 INFO - TEST-END: test_click.py TestClick.test_container_element1499533850588 Marionette TRACE 364 <- [1,6,null,{"value":null}]
10:10:50 INFO - 1499533850589 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
10:10:50 INFO - 1499533850591 Marionette TRACE 364 <- [1,7,null,{}]
10:10:50 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
10:10:50 INFO - 1499533850593 Marionette DEBUG Closed connection 364
10:10:50 INFO - 1499533850593 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49822
10:10:50 INFO - 1499533850594 Marionette DEBUG Closed connection 365
10:10:50 INFO - 1499533850595 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49823
10:10:50 INFO - 1499533850596 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:50 INFO - 1499533850597 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:50 INFO - 1499533850600 Marionette DEBUG Register listener.js for window 4294967297
10:10:50 INFO - 1499533850604 Marionette TRACE 366 <- [1,1,null,{"sessionId":"2f0927e2-6d66-4d32-aa72-6ad5cf029017","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:50 INFO - 1499533850606 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
10:10:50 INFO - 1499533850606 Marionette TRACE 366 <- [1,2,null,{}]
10:10:50 INFO - 1499533850607 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:10:50 INFO - 1499533850608 Marionette TRACE 366 <- [1,3,null,{}]
10:10:50 INFO - 1499533850609 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
10:10:50 INFO - 1499533850609 Marionette TRACE 366 <- [1,4,null,{}]
10:10:50 INFO - 1499533850611 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}]
10:10:50 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499533850615 Marionette TRACE 366 <- [1,5,null,{"value":null}]
10:10:50 INFO - 1499533850616 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
10:10:50 INFO - 1499533850617 Marionette TRACE 366 <- [1,6,null,{}]
10:10:50 INFO - 1499533850619 Marionette DEBUG Closed connection 366
10:10:50 INFO - 1499533850620 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49824
10:10:50 INFO - 1499533850621 Marionette DEBUG Closed connection 367
10:10:50 INFO - 1499533850622 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49825
10:10:50 INFO - 1499533850623 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:50 INFO - 1499533850624 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:50 INFO - 1499533850627 Marionette DEBUG Register listener.js for window 4294967297
10:10:50 INFO - 1499533850640 Marionette TRACE 368 <- [1,1,null,{"sessionId":"6654d2b0-9901-4d7c-9e55-b3893a835a21","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:50 INFO - 1499533850641 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
10:10:50 INFO - 1499533850643 Marionette TRACE 368 <- [1,2,null,{}]
10:10:50 INFO - 1499533850645 Marionette DEBUG Closed connection 368
10:10:50 INFO - 1499533850646 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49826
10:10:50 INFO - 1499533850646 Marionette DEBUG Closed connection 369
10:10:50 INFO - 1499533850647 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49827
10:10:50 INFO - 1499533850648 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:10:50 INFO - 1499533850649 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:50 INFO - 1499533850652 Marionette DEBUG Register listener.js for window 4294967297
10:10:50 INFO - 1499533850656 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c7a28434-9935-4544-8eab-a973305bbb27","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:50 INFO - 1499533850658 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"}]
10:10:50 INFO - 1499533850673 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"
10:10:50 INFO - 1499533850681 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"
10:10:50 INFO - 1499533850681 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"
10:10:50 INFO - 1499533850689 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"
10:10:50 INFO - 1499533850693 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"
10:10:50 INFO - 1499533850702 Marionette TRACE 370 <- [1,2,null,{}]
10:10:50 INFO - 1499533850707 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
10:10:50 INFO - 1499533850721 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"20589f40-9c11-4760-adca-c203834cfc43","ELEMENT":"20589f40-9c11-4760-adca-c203834cfc43"}}]
10:10:50 INFO - 1499533850722 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"20589f40-9c11-4760-adca-c203834cfc43"}]
10:10:50 INFO - 1499533850971 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:10:50 INFO - 1499533850972 Marionette TRACE 370 <- [1,4,null,{}]
10:10:50 INFO - 1499533850973 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"20589f40-9c11-4760-adca-c203834cfc43","name":"selected"}]
10:10:50 INFO - 1499533850978 Marionette TRACE 370 <- [1,5,null,{"value":true}]
10:10:50 INFO - 1499533850980 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}]
10:10:50 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499533850983 Marionette TRACE 370 <- [1,6,null,{"value":null}]
10:10:50 INFO - 1499533850984 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
10:10:50 INFO - 1499533850986 Marionette TRACE 370 <- [1,7,null,{}]
10:10:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 395ms
10:10:50 INFO - TEST-START | test_click.py TestClick.test_css_transforms
10:10:50 INFO - 1499533850988 Marionette DEBUG Closed connection 370
10:10:50 INFO - 1499533850989 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49828
10:10:50 INFO - 1499533850990 Marionette DEBUG Closed connection 371
10:10:50 INFO - 1499533850990 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49829
10:10:50 INFO - 1499533850992 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:50 INFO - 1499533850992 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:50 INFO - 1499533850996 Marionette DEBUG Register listener.js for window 4294967297
10:10:51 INFO - 1499533850999 Marionette TRACE 372 <- [1,1,null,{"sessionId":"8b47fe49-db4e-4940-b5ea-886b7eae9550","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:51 INFO - 1499533851001 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
10:10:51 INFO - 1499533851001 Marionette TRACE 372 <- [1,2,null,{}]
10:10:51 INFO - 1499533851002 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:10:51 INFO - 1499533851003 Marionette TRACE 372 <- [1,3,null,{}]
10:10:51 INFO - 1499533851004 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
10:10:51 INFO - 1499533851004 Marionette TRACE 372 <- [1,4,null,{}]
10:10:51 INFO - 1499533851006 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}]
10:10:51 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499533851010 Marionette TRACE 372 <- [1,5,null,{"value":null}]
10:10:51 INFO - 1499533851011 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
10:10:51 INFO - 1499533851012 Marionette TRACE 372 <- [1,6,null,{}]
10:10:51 INFO - 1499533851014 Marionette DEBUG Closed connection 372
10:10:51 INFO - 1499533851015 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49830
10:10:51 INFO - 1499533851016 Marionette DEBUG Closed connection 373
10:10:51 INFO - 1499533851017 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49831
10:10:51 INFO - 1499533851018 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:51 INFO - 1499533851019 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:51 INFO - 1499533851022 Marionette DEBUG Register listener.js for window 4294967297
10:10:51 INFO - 1499533851026 Marionette TRACE 374 <- [1,1,null,{"sessionId":"e79b174b-ffd6-4f43-aa01-0e5e8008add0","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:51 INFO - 1499533851028 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
10:10:51 INFO - 1499533851029 Marionette TRACE 374 <- [1,2,null,{}]
10:10:51 INFO - 1499533851031 Marionette DEBUG Closed connection 374
10:10:51 INFO - 1499533851032 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49832
10:10:51 INFO - 1499533851033 Marionette DEBUG Closed connection 375
10:10:51 INFO - 1499533851034 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49833
10:10:51 INFO - 1499533851035 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:10:51 INFO - 1499533851036 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:51 INFO - 1499533851040 Marionette DEBUG Register listener.js for window 4294967297
10:10:51 INFO - 1499533851043 Marionette TRACE 376 <- [1,1,null,{"sessionId":"1b97002a-7bc7-45bf-a038-1b6f10e76193","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:51 INFO - 1499533851045 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"}]
10:10:51 INFO - 1499533851047 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"
10:10:51 INFO - 1499533851049 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"
10:10:51 INFO - 1499533851050 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"
10:10:51 INFO - 1499533851057 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"
10:10:51 INFO - 1499533851060 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"
10:10:51 INFO - 1499533851064 Marionette TRACE 376 <- [1,2,null,{}]
10:10:51 INFO - 1499533851076 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
10:10:51 INFO - 1499533851079 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4eafd880-085e-4560-ab13-4b136d6db00b","ELEMENT":"4eafd880-085e-4560-ab13-4b136d6db00b"}}]
10:10:51 INFO - 1499533851086 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"4eafd880-085e-4560-ab13-4b136d6db00b"}]
10:10:51 INFO - 1499533851335 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:10:51 INFO - 1499533851336 Marionette TRACE 376 <- [1,4,null,{}]
10:10:51 INFO - 1499533851338 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}]
10:10:51 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499533851341 Marionette TRACE 376 <- [1,5,null,{"value":null}]
10:10:51 INFO - 1499533851343 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
10:10:51 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 358ms
10:10:51 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
10:10:51 INFO - 1499533851344 Marionette TRACE 376 <- [1,6,null,{}]
10:10:51 INFO - 1499533851346 Marionette DEBUG Closed connection 376
10:10:51 INFO - 1499533851347 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49834
10:10:51 INFO - 1499533851348 Marionette DEBUG Closed connection 377
10:10:51 INFO - 1499533851349 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49835
10:10:51 INFO - 1499533851350 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:51 INFO - 1499533851351 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:51 INFO - 1499533851354 Marionette DEBUG Register listener.js for window 4294967297
10:10:51 INFO - 1499533851358 Marionette TRACE 378 <- [1,1,null,{"sessionId":"c58760e2-88d6-4a50-87c5-ddb4e4346c68","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:51 INFO - 1499533851359 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
10:10:51 INFO - 1499533851359 Marionette TRACE 378 <- [1,2,null,{}]
10:10:51 INFO - 1499533851360 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:10:51 INFO - 1499533851361 Marionette TRACE 378 <- [1,3,null,{}]
10:10:51 INFO - 1499533851362 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
10:10:51 INFO - 1499533851362 Marionette TRACE 378 <- [1,4,null,{}]
10:10:51 INFO - 1499533851364 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}]
10:10:51 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499533851367 Marionette TRACE 378 <- [1,5,null,{"value":null}]
10:10:51 INFO - 1499533851368 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
10:10:51 INFO - 1499533851370 Marionette TRACE 378 <- [1,6,null,{}]
10:10:51 INFO - 1499533851372 Marionette DEBUG Closed connection 378
10:10:51 INFO - 1499533851373 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49836
10:10:51 INFO - 1499533851374 Marionette DEBUG Closed connection 379
10:10:51 INFO - 1499533851374 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49837
10:10:51 INFO - 1499533851375 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:51 INFO - 1499533851376 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:51 INFO - 1499533851379 Marionette DEBUG Register listener.js for window 4294967297
10:10:51 INFO - 1499533851383 Marionette TRACE 380 <- [1,1,null,{"sessionId":"691b0d93-ee73-4a8a-b9c8-8a3c2a228e95","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:51 INFO - 1499533851384 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
10:10:51 INFO - 1499533851386 Marionette TRACE 380 <- [1,2,null,{}]
10:10:51 INFO - 1499533851388 Marionette DEBUG Closed connection 380
10:10:51 INFO - 1499533851388 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49838
10:10:51 INFO - 1499533851389 Marionette DEBUG Closed connection 381
10:10:51 INFO - 1499533851390 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49839
10:10:51 INFO - 1499533851391 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:10:51 INFO - 1499533851392 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:51 INFO - 1499533851395 Marionette DEBUG Register listener.js for window 4294967297
10:10:51 INFO - 1499533851399 Marionette TRACE 382 <- [1,1,null,{"sessionId":"1ed6b5e9-11a1-42e9-9986-f933ef9355bf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:51 INFO - 1499533851400 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"}]
10:10:51 INFO - 1499533851402 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"
10:10:51 INFO - 1499533851405 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"
10:10:51 INFO - 1499533851405 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"
10:10:51 INFO - 1499533851412 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"
10:10:51 INFO - 1499533851418 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"
10:10:51 INFO - 1499533851425 Marionette TRACE 382 <- [1,2,null,{}]
10:10:51 INFO - 1499533851430 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
10:10:51 INFO - 1499533851437 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4f2bf202-af33-427e-a2f0-d937a9450f4c","ELEMENT":"4f2bf202-af33-427e-a2f0-d937a9450f4c"}}]
10:10:51 INFO - 1499533851438 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"4f2bf202-af33-427e-a2f0-d937a9450f4c"}]
10:10:51 INFO - 1499533851692 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:10:51 INFO - 1499533851694 Marionette TRACE 382 <- [1,4,null,{}]
10:10:51 INFO - 1499533851696 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}]
10:10:51 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499533851699 Marionette TRACE 382 <- [1,5,null,{"value":null}]
10:10:51 INFO - 1499533851700 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
10:10:51 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 357ms
10:10:51 INFO - TEST-START | test_click.py TestClick.test_input_file
10:10:51 INFO - 1499533851702 Marionette TRACE 382 <- [1,6,null,{}]
10:10:51 INFO - 1499533851704 Marionette DEBUG Closed connection 382
10:10:51 INFO - 1499533851705 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49840
10:10:51 INFO - 1499533851705 Marionette DEBUG Closed connection 383
10:10:51 INFO - 1499533851706 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49841
10:10:51 INFO - 1499533851707 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:51 INFO - 1499533851708 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:51 INFO - 1499533851711 Marionette DEBUG Register listener.js for window 4294967297
10:10:51 INFO - 1499533851715 Marionette TRACE 384 <- [1,1,null,{"sessionId":"07664d48-190a-4582-93dc-e630d37971cf","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:51 INFO - 1499533851716 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
10:10:51 INFO - 1499533851717 Marionette TRACE 384 <- [1,2,null,{}]
10:10:51 INFO - 1499533851718 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:10:51 INFO - 1499533851719 Marionette TRACE 384 <- [1,3,null,{}]
10:10:51 INFO - 1499533851719 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
10:10:51 INFO - 1499533851720 Marionette TRACE 384 <- [1,4,null,{}]
10:10:51 INFO - 1499533851722 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}]
10:10:51 INFO - TEST-START: test_click.py TestClick.test_input_file1499533851725 Marionette TRACE 384 <- [1,5,null,{"value":null}]
10:10:51 INFO - 1499533851726 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
10:10:51 INFO - 1499533851728 Marionette TRACE 384 <- [1,6,null,{}]
10:10:51 INFO - 1499533851730 Marionette DEBUG Closed connection 384
10:10:51 INFO - 1499533851731 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49842
10:10:51 INFO - 1499533851732 Marionette DEBUG Closed connection 385
10:10:51 INFO - 1499533851732 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49843
10:10:51 INFO - 1499533851733 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:51 INFO - 1499533851734 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:51 INFO - 1499533851737 Marionette DEBUG Register listener.js for window 4294967297
10:10:51 INFO - 1499533851741 Marionette TRACE 386 <- [1,1,null,{"sessionId":"c0aa5d02-64e3-4b5a-843c-b4cacf8dadb3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:51 INFO - 1499533851742 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
10:10:51 INFO - 1499533851744 Marionette TRACE 386 <- [1,2,null,{}]
10:10:51 INFO - 1499533851746 Marionette DEBUG Closed connection 386
10:10:51 INFO - 1499533851747 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49844
10:10:51 INFO - 1499533851748 Marionette DEBUG Closed connection 387
10:10:51 INFO - 1499533851749 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49845
10:10:51 INFO - 1499533851750 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:10:51 INFO - 1499533851751 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:51 INFO - 1499533851754 Marionette DEBUG Register listener.js for window 4294967297
10:10:51 INFO - 1499533851758 Marionette TRACE 388 <- [1,1,null,{"sessionId":"e58010c2-d2a0-42fa-81d5-0dbe07d57337","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:51 INFO - 1499533851759 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
10:10:51 INFO - 1499533851761 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"
10:10:51 INFO - 1499533851763 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"
10:10:51 INFO - 1499533851764 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"
10:10:51 INFO - 1499533851771 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:10:51 INFO - 1499533851776 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:10:51 INFO - 1499533851778 Marionette TRACE 388 <- [1,2,null,{}]
10:10:51 INFO - 1499533851793 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
10:10:51 INFO - 1499533851796 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a503cc7e-f4f8-4024-a639-50ba0820aede","ELEMENT":"a503cc7e-f4f8-4024-a639-50ba0820aede"}}]
10:10:51 INFO - 1499533851798 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"a503cc7e-f4f8-4024-a639-50ba0820aede"}]
10:10:51 INFO - 1499533851829 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
10:10:51 INFO - 1499533851832 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}]
10:10:51 INFO - TEST-END: test_click.py TestClick.test_input_file1499533851836 Marionette TRACE 388 <- [1,5,null,{"value":null}]
10:10:51 INFO - 1499533851838 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
10:10:51 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 140ms
10:10:51 INFO - TEST-START | test_click.py TestClick.test_obscured_element
10:10:51 INFO - 1499533851841 Marionette TRACE 388 <- [1,6,null,{}]
10:10:51 INFO - 1499533851845 Marionette DEBUG Closed connection 388
10:10:51 INFO - 1499533851846 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49846
10:10:51 INFO - 1499533851847 Marionette DEBUG Closed connection 389
10:10:51 INFO - 1499533851848 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49847
10:10:51 INFO - 1499533851850 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:51 INFO - 1499533851853 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:51 INFO - 1499533851856 Marionette DEBUG Register listener.js for window 4294967297
10:10:51 INFO - 1499533851862 Marionette TRACE 390 <- [1,1,null,{"sessionId":"3e7415fb-7810-4337-b7aa-75040445d886","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:51 INFO - 1499533851867 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
10:10:51 INFO - 1499533851868 Marionette TRACE 390 <- [1,2,null,{}]
10:10:51 INFO - 1499533851870 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:10:51 INFO - 1499533851871 Marionette TRACE 390 <- [1,3,null,{}]
10:10:51 INFO - 1499533851872 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
10:10:51 INFO - 1499533851874 Marionette TRACE 390 <- [1,4,null,{}]
10:10:51 INFO - 1499533851876 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}]
10:10:51 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499533851880 Marionette TRACE 390 <- [1,5,null,{"value":null}]
10:10:51 INFO - 1499533851881 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
10:10:51 INFO - 1499533851884 Marionette TRACE 390 <- [1,6,null,{}]
10:10:51 INFO - 1499533851886 Marionette DEBUG Closed connection 390
10:10:51 INFO - 1499533851888 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49848
10:10:51 INFO - 1499533851889 Marionette DEBUG Closed connection 391
10:10:51 INFO - 1499533851890 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49849
10:10:51 INFO - 1499533851891 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:51 INFO - 1499533851892 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:51 INFO - 1499533851896 Marionette DEBUG Register listener.js for window 4294967297
10:10:51 INFO - 1499533851902 Marionette TRACE 392 <- [1,1,null,{"sessionId":"ee60672a-1a6e-48a6-8070-81d1a538845f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:51 INFO - 1499533851903 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
10:10:51 INFO - 1499533851906 Marionette TRACE 392 <- [1,2,null,{}]
10:10:51 INFO - 1499533851909 Marionette DEBUG Closed connection 392
10:10:51 INFO - 1499533851910 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49850
10:10:51 INFO - 1499533851911 Marionette DEBUG Closed connection 393
10:10:51 INFO - 1499533851912 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49851
10:10:51 INFO - 1499533851915 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:10:51 INFO - 1499533851917 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:51 INFO - 1499533851920 Marionette DEBUG Register listener.js for window 4294967297
10:10:51 INFO - 1499533851925 Marionette TRACE 394 <- [1,1,null,{"sessionId":"3f78f06a-9cd9-44ec-b273-fd9fc21e692f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:51 INFO - 1499533851927 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"}]
10:10:51 INFO - 1499533851929 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:10:51 INFO - 1499533851932 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:10:51 INFO - 1499533851932 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
10:10:51 INFO - 1499533851940 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"
10:10:51 INFO - 1499533851943 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"
10:10:51 INFO - 1499533851956 Marionette TRACE 394 <- [1,2,null,{}]
10:10:51 INFO - 1499533851978 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
10:10:51 INFO - 1499533851983 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e654be0c-5a59-4e1d-9bc3-a0715e8744eb","ELEMENT":"e654be0c-5a59-4e1d-9bc3-a0715e8744eb"}}]
10:10:51 INFO - 1499533851984 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
10:10:52 INFO - 1499533851995 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"457fab31-ad69-482b-8ff0-6d1085a05518","ELEMENT":"457fab31-ad69-482b-8ff0-6d1085a05518"}}]
10:10:52 INFO - 1499533851997 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"e654be0c-5a59-4e1d-9bc3-a0715e8744eb"}]
10:10:52 INFO - 1499533852239 Marionette DEBUG Canceled page load listener because no navigation has been detected
10:10:52 INFO - 1499533852240 Marionette TRACE 394 <- [1,5,null,{}]
10:10:52 INFO - 1499533852242 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"457fab31-ad69-482b-8ff0-6d1085a05518"}]
10:10:52 INFO - 1499533852261 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
10:10:52 INFO - 1499533852266 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
10:10:52 INFO - 1499533852270 Marionette TRACE 394 <- [1,7,null,{"value":false}]
10:10:52 INFO - 1499533852273 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}]
10:10:52 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499533852278 Marionette TRACE 394 <- [1,8,null,{"value":null}]
10:10:52 INFO - 1499533852280 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
10:10:52 INFO - 1499533852283 Marionette TRACE 394 <- [1,9,null,{}]
10:10:52 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 441ms
10:10:52 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
10:10:52 INFO - 1499533852285 Marionette DEBUG Closed connection 394
10:10:52 INFO - 1499533852287 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49852
10:10:52 INFO - 1499533852289 Marionette DEBUG Closed connection 395
10:10:52 INFO - 1499533852290 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49853
10:10:52 INFO - 1499533852292 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:52 INFO - 1499533852294 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:52 INFO - 1499533852298 Marionette DEBUG Register listener.js for window 4294967297
10:10:52 INFO - 1499533852304 Marionette TRACE 396 <- [1,1,null,{"sessionId":"ac813c8a-c237-4b8e-b96b-99334882b27a","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:52 INFO - 1499533852306 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
10:10:52 INFO - 1499533852307 Marionette TRACE 396 <- [1,2,null,{}]
10:10:52 INFO - 1499533852309 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
10:10:52 INFO - 1499533852310 Marionette TRACE 396 <- [1,3,null,{}]
10:10:52 INFO - 1499533852311 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
10:10:52 INFO - 1499533852312 Marionette TRACE 396 <- [1,4,null,{}]
10:10:52 INFO - 1499533852315 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}]
10:10:52 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499533852319 Marionette TRACE 396 <- [1,5,null,{"value":null}]
10:10:52 INFO - 1499533852321 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
10:10:52 INFO - 1499533852323 Marionette TRACE 396 <- [1,6,null,{}]
10:10:52 INFO - 1499533852325 Marionette DEBUG Closed connection 396
10:10:52 INFO - 1499533852326 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49854
10:10:52 INFO - 1499533852328 Marionette DEBUG Closed connection 397
10:10:52 INFO - 1499533852329 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49855
10:10:52 INFO - 1499533852332 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
10:10:52 INFO - 1499533852334 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:52 INFO - 1499533852337 Marionette DEBUG Register listener.js for window 4294967297
10:10:52 INFO - 1499533852343 Marionette TRACE 398 <- [1,1,null,{"sessionId":"10b6cb3d-1d32-43d8-8829-12a6243367e9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:52 INFO - 1499533852344 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
10:10:52 INFO - 1499533852347 Marionette TRACE 398 <- [1,2,null,{}]
10:10:52 INFO - 1499533852351 Marionette DEBUG Closed connection 398
10:10:52 INFO - 1499533852352 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49856
10:10:52 INFO - 1499533852353 Marionette DEBUG Closed connection 399
10:10:52 INFO - 1499533852354 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49857
10:10:52 INFO - 1499533852355 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
10:10:52 INFO - 1499533852356 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}
10:10:52 INFO - 1499533852360 Marionette DEBUG Register listener.js for window 4294967297
10:10:52 INFO - 1499533852364 Marionette TRACE 400 <- [1,1,null,{"sessionId":"d860d86b-b281-433a-8d73-0cf4adf7abea","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.1","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":1152,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmp1psidz.mozrunner","moz:accessibilityChecks":false}}]
10:10:52 INFO - 1499533852366 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"}]
10:10:52 INFO - 1499533852368 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"
10:10:52 INFO - 1499533852371 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"
10:10:52 INFO - 1499533852371 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"
10:10:52 INFO - 1499533852379 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"
10:10:52 INFO - 1499533852382 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"
10:10:52 INFO - 1499533852396 Marionette TRACE 400 <- [1,2,null,{}]
10:10:52 INFO - 1499533852405 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
10:10:52 INFO - 1499533852409 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fa0ba243-4f82-4362-9133-13fc523e36a9","ELEMENT":"fa0ba243-4f82-4362-9133-13fc523e36a9"}}]
10:10:52 INFO - 1499533852410 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"fa0ba243-4f82-4362-9133-13fc523e36a9"}]
10:10:52 INFO - 1499533852435 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
10:10:52 INFO - 1499533852437 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"fa0ba243-4f82-4362-9133-13fc523e36a9"}]
10:10:52 INFO - 1499533852457 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element