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]
18:48:18 INFO - 1499737698009 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}]
18:48:18 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499737698011 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:48:18 INFO - 1499737698012 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:48:18 INFO - 1499737698013 Marionette TRACE 352 <- [1,6,null,{}]
18:48:18 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 134ms
18:48:18 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:48:18 INFO - 1499737698015 Marionette DEBUG Closed connection 352
18:48:18 INFO - 1499737698016 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50397
18:48:18 INFO - 1499737698017 Marionette DEBUG Closed connection 353
18:48:18 INFO - 1499737698018 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50398
18:48:18 INFO - 1499737698019 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:18 INFO - 1499737698020 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:18 INFO - 1499737698022 Marionette DEBUG Register listener.js for window 12
18:48:18 INFO - 1499737698025 Marionette TRACE 354 <- [1,1,null,{"sessionId":"86db0588-8fc2-488d-a766-f123d4da24c0","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:18 INFO - 1499737698027 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:48:18 INFO - 1499737698027 Marionette TRACE 354 <- [1,2,null,{}]
18:48:18 INFO - 1499737698028 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:48:18 INFO - 1499737698029 Marionette TRACE 354 <- [1,3,null,{}]
18:48:18 INFO - 1499737698029 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:48:18 INFO - 1499737698030 Marionette TRACE 354 <- [1,4,null,{}]
18:48:18 INFO - 1499737698032 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}]
18:48:18 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499737698035 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:48:18 INFO - 1499737698036 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:48:18 INFO - 1499737698037 Marionette TRACE 354 <- [1,6,null,{}]
18:48:18 INFO - 1499737698039 Marionette DEBUG Closed connection 354
18:48:18 INFO - 1499737698040 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50399
18:48:18 INFO - 1499737698040 Marionette DEBUG Closed connection 355
18:48:18 INFO - 1499737698041 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50400
18:48:18 INFO - 1499737698042 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:18 INFO - 1499737698051 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:18 INFO - 1499737698053 Marionette DEBUG Register listener.js for window 12
18:48:18 INFO - 1499737698056 Marionette TRACE 356 <- [1,1,null,{"sessionId":"b57b77e1-cd50-4284-a916-b97bf240f86b","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:18 INFO - 1499737698058 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:48:18 INFO - 1499737698059 Marionette TRACE 356 <- [1,2,null,{}]
18:48:18 INFO - 1499737698061 Marionette DEBUG Closed connection 356
18:48:18 INFO - 1499737698062 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50401
18:48:18 INFO - 1499737698063 Marionette DEBUG Closed connection 357
18:48:18 INFO - 1499737698063 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50402
18:48:18 INFO - 1499737698064 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:48:18 INFO - 1499737698065 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:18 INFO - 1499737698067 Marionette DEBUG Register listener.js for window 12
18:48:18 INFO - 1499737698070 Marionette TRACE 358 <- [1,1,null,{"sessionId":"382325a0-75ee-4051-afe3-27206f638e62","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:18 INFO - 1499737698072 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49964/clicks.html"}]
18:48:18 INFO - 1499737698074 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49964/hidden.html"
18:48:18 INFO - 1499737698079 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49964/hidden.html"
18:48:18 INFO - 1499737698079 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49964/hidden.html"
18:48:18 INFO - 1499737698096 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49964/clicks.html"
18:48:18 INFO - 1499737698102 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49964/clicks.html"
18:48:18 INFO - 1499737698113 Marionette TRACE 358 <- [1,2,null,{}]
18:48:18 INFO - 1499737698115 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:48:18 INFO - 1499737698119 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bdde5954-c79f-4ab3-b63d-adad5b457f90","ELEMENT":"bdde5954-c79f-4ab3-b63d-adad5b457f90"}}]
18:48:18 INFO - 1499737698120 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"bdde5954-c79f-4ab3-b63d-adad5b457f90"}]
18:48:18 INFO - 1499737698158 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49964/clicks.html"
18:48:18 INFO - 1499737698163 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49964/clicks.html"
18:48:18 INFO - 1499737698164 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49964/clicks.html"
18:48:18 INFO - 1499737698187 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49964/test.html"
18:48:18 INFO - 1499737698188 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49964/test.html"
18:48:18 INFO - 1499737698191 Marionette TRACE 358 <- [1,4,null,{}]
18:48:18 INFO - 1499737698193 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:48:18 INFO - 1499737698197 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3d32e85a-f111-4581-b3ac-040d39defdcc","ELEMENT":"3d32e85a-f111-4581-b3ac-040d39defdcc"}}]
18:48:18 INFO - 1499737698198 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:48:18 INFO - 1499737698198 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:48:18 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 198ms
18:48:18 INFO - TEST-START | test_click.py TestClick.test_container_element
18:48:18 INFO - 1499737698206 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}]
18:48:18 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499737698209 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:48:18 INFO - 1499737698210 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:48:18 INFO - 1499737698211 Marionette TRACE 358 <- [1,8,null,{}]
18:48:18 INFO - 1499737698213 Marionette DEBUG Closed connection 358
18:48:18 INFO - 1499737698214 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50405
18:48:18 INFO - 1499737698215 Marionette DEBUG Closed connection 359
18:48:18 INFO - 1499737698216 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50406
18:48:18 INFO - 1499737698217 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:18 INFO - 1499737698218 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:18 INFO - 1499737698220 Marionette DEBUG Register listener.js for window 12
18:48:18 INFO - 1499737698224 Marionette TRACE 360 <- [1,1,null,{"sessionId":"8e014331-d921-4d9a-8264-c2ff06189169","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:18 INFO - 1499737698226 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:48:18 INFO - 1499737698226 Marionette TRACE 360 <- [1,2,null,{}]
18:48:18 INFO - 1499737698227 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:48:18 INFO - 1499737698227 Marionette TRACE 360 <- [1,3,null,{}]
18:48:18 INFO - 1499737698228 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:48:18 INFO - 1499737698229 Marionette TRACE 360 <- [1,4,null,{}]
18:48:18 INFO - 1499737698230 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}]
18:48:18 INFO - TEST-START: test_click.py TestClick.test_container_element1499737698236 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:48:18 INFO - 1499737698237 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:48:18 INFO - 1499737698238 Marionette TRACE 360 <- [1,6,null,{}]
18:48:18 INFO - 1499737698240 Marionette DEBUG Closed connection 360
18:48:18 INFO - 1499737698241 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50407
18:48:18 INFO - 1499737698241 Marionette DEBUG Closed connection 361
18:48:18 INFO - 1499737698242 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50408
18:48:18 INFO - 1499737698243 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:18 INFO - 1499737698244 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:18 INFO - 1499737698246 Marionette DEBUG Register listener.js for window 12
18:48:18 INFO - 1499737698248 Marionette TRACE 362 <- [1,1,null,{"sessionId":"3ae95728-937d-41d8-a7c2-18791e1dfe65","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:18 INFO - 1499737698251 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:48:18 INFO - 1499737698253 Marionette TRACE 362 <- [1,2,null,{}]
18:48:18 INFO - 1499737698254 Marionette DEBUG Closed connection 362
18:48:18 INFO - 1499737698255 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50409
18:48:18 INFO - 1499737698256 Marionette DEBUG Closed connection 363
18:48:18 INFO - 1499737698256 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50410
18:48:18 INFO - 1499737698257 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:48:18 INFO - 1499737698258 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:18 INFO - 1499737698260 Marionette DEBUG Register listener.js for window 12
18:48:18 INFO - 1499737698263 Marionette TRACE 364 <- [1,1,null,{"sessionId":"3aaccb31-da10-458c-8c16-b7fe2bacbdce","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:18 INFO - 1499737698266 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"}]
18:48:18 INFO - 1499737698267 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49964/test.html"
18:48:18 INFO - 1499737698270 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49964/test.html"
18:48:18 INFO - 1499737698271 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49964/test.html"
18:48:18 INFO - 1499737698298 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"
18:48:18 INFO - 1499737698306 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"
18:48:18 INFO - 1499737698311 Marionette TRACE 364 <- [1,2,null,{}]
18:48:18 INFO - 1499737698312 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:48:18 INFO - 1499737698315 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2e413c10-cf9b-490d-97e8-cd6cf27c69f6","ELEMENT":"2e413c10-cf9b-490d-97e8-cd6cf27c69f6"}}]
18:48:18 INFO - 1499737698323 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"2e413c10-cf9b-490d-97e8-cd6cf27c69f6"}]
18:48:18 INFO - 1499737698563 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:48:18 INFO - 1499737698564 Marionette TRACE 364 <- [1,4,null,{}]
18:48:18 INFO - 1499737698565 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"2e413c10-cf9b-490d-97e8-cd6cf27c69f6","name":"selected"}]
18:48:18 INFO - 1499737698570 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:48:18 INFO - 1499737698572 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}]
18:48:18 INFO - TEST-END: test_click.py TestClick.test_container_element1499737698574 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:48:18 INFO - 1499737698575 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:48:18 INFO - 1499737698577 Marionette TRACE 364 <- [1,7,null,{}]
18:48:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 365ms
18:48:18 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:48:18 INFO - 1499737698578 Marionette DEBUG Closed connection 364
18:48:18 INFO - 1499737698579 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50411
18:48:18 INFO - 1499737698580 Marionette DEBUG Closed connection 365
18:48:18 INFO - 1499737698581 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50412
18:48:18 INFO - 1499737698582 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:18 INFO - 1499737698583 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:18 INFO - 1499737698585 Marionette DEBUG Register listener.js for window 12
18:48:18 INFO - 1499737698588 Marionette TRACE 366 <- [1,1,null,{"sessionId":"4c336b3d-51c4-420b-ab3a-f18e6084f0c5","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:18 INFO - 1499737698590 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:48:18 INFO - 1499737698590 Marionette TRACE 366 <- [1,2,null,{}]
18:48:18 INFO - 1499737698591 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:48:18 INFO - 1499737698592 Marionette TRACE 366 <- [1,3,null,{}]
18:48:18 INFO - 1499737698592 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:48:18 INFO - 1499737698593 Marionette TRACE 366 <- [1,4,null,{}]
18:48:18 INFO - 1499737698595 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}]
18:48:18 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499737698597 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:48:18 INFO - 1499737698598 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:48:18 INFO - 1499737698599 Marionette TRACE 366 <- [1,6,null,{}]
18:48:18 INFO - 1499737698601 Marionette DEBUG Closed connection 366
18:48:18 INFO - 1499737698602 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50413
18:48:18 INFO - 1499737698603 Marionette DEBUG Closed connection 367
18:48:18 INFO - 1499737698604 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50414
18:48:18 INFO - 1499737698605 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:18 INFO - 1499737698605 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:18 INFO - 1499737698607 Marionette DEBUG Register listener.js for window 12
18:48:18 INFO - 1499737698618 Marionette TRACE 368 <- [1,1,null,{"sessionId":"f5b4036e-3c05-429e-831a-3ee784a26985","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:18 INFO - 1499737698620 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:48:18 INFO - 1499737698621 Marionette TRACE 368 <- [1,2,null,{}]
18:48:18 INFO - 1499737698623 Marionette DEBUG Closed connection 368
18:48:18 INFO - 1499737698624 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50415
18:48:18 INFO - 1499737698624 Marionette DEBUG Closed connection 369
18:48:18 INFO - 1499737698625 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50416
18:48:18 INFO - 1499737698626 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:48:18 INFO - 1499737698627 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:18 INFO - 1499737698629 Marionette DEBUG Register listener.js for window 12
18:48:18 INFO - 1499737698632 Marionette TRACE 370 <- [1,1,null,{"sessionId":"7604f6a0-0541-4c37-bce7-05b432d8b90d","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:18 INFO - 1499737698634 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"}]
18:48:18 INFO - 1499737698635 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"
18:48:18 INFO - 1499737698638 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"
18:48:18 INFO - 1499737698638 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"
18:48:18 INFO - 1499737698649 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"
18:48:18 INFO - 1499737698658 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"
18:48:18 INFO - 1499737698661 Marionette TRACE 370 <- [1,2,null,{}]
18:48:18 INFO - 1499737698662 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:48:18 INFO - 1499737698670 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"24407fa1-23dd-40bf-bb1b-77d28c0c058b","ELEMENT":"24407fa1-23dd-40bf-bb1b-77d28c0c058b"}}]
18:48:18 INFO - 1499737698672 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"24407fa1-23dd-40bf-bb1b-77d28c0c058b"}]
18:48:18 INFO - 1499737698912 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:48:18 INFO - 1499737698912 Marionette TRACE 370 <- [1,4,null,{}]
18:48:18 INFO - 1499737698914 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"24407fa1-23dd-40bf-bb1b-77d28c0c058b","name":"selected"}]
18:48:18 INFO - 1499737698921 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:48:18 INFO - 1499737698923 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}]
18:48:18 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499737698926 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:48:18 INFO - 1499737698927 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:48:18 INFO - 1499737698928 Marionette TRACE 370 <- [1,7,null,{}]
18:48:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 351ms
18:48:18 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:48:18 INFO - 1499737698930 Marionette DEBUG Closed connection 370
18:48:18 INFO - 1499737698931 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50417
18:48:18 INFO - 1499737698932 Marionette DEBUG Closed connection 371
18:48:18 INFO - 1499737698933 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50418
18:48:18 INFO - 1499737698934 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:18 INFO - 1499737698934 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:18 INFO - 1499737698936 Marionette DEBUG Register listener.js for window 12
18:48:18 INFO - 1499737698939 Marionette TRACE 372 <- [1,1,null,{"sessionId":"e956484f-a136-4f80-95b2-6979f473d47d","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:18 INFO - 1499737698943 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:48:18 INFO - 1499737698943 Marionette TRACE 372 <- [1,2,null,{}]
18:48:18 INFO - 1499737698944 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:48:18 INFO - 1499737698944 Marionette TRACE 372 <- [1,3,null,{}]
18:48:18 INFO - 1499737698945 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:48:18 INFO - 1499737698945 Marionette TRACE 372 <- [1,4,null,{}]
18:48:18 INFO - 1499737698947 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}]
18:48:18 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499737698950 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:48:18 INFO - 1499737698951 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:48:18 INFO - 1499737698952 Marionette TRACE 372 <- [1,6,null,{}]
18:48:18 INFO - 1499737698954 Marionette DEBUG Closed connection 372
18:48:18 INFO - 1499737698955 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50419
18:48:18 INFO - 1499737698955 Marionette DEBUG Closed connection 373
18:48:18 INFO - 1499737698956 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50420
18:48:19 INFO - 1499737698957 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:19 INFO - 1499737698958 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:19 INFO - 1499737698960 Marionette DEBUG Register listener.js for window 12
18:48:19 INFO - 1499737698963 Marionette TRACE 374 <- [1,1,null,{"sessionId":"ded41734-dc6f-4f14-87ed-d1c9de999191","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:19 INFO - 1499737698965 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:48:19 INFO - 1499737698966 Marionette TRACE 374 <- [1,2,null,{}]
18:48:19 INFO - 1499737698968 Marionette DEBUG Closed connection 374
18:48:19 INFO - 1499737698968 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50421
18:48:19 INFO - 1499737698969 Marionette DEBUG Closed connection 375
18:48:19 INFO - 1499737698970 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50422
18:48:19 INFO - 1499737698971 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:48:19 INFO - 1499737698971 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:19 INFO - 1499737698973 Marionette DEBUG Register listener.js for window 12
18:48:19 INFO - 1499737698976 Marionette TRACE 376 <- [1,1,null,{"sessionId":"49a3a4ef-c0a6-4014-9fae-abf31ee3b562","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:19 INFO - 1499737698979 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"}]
18:48:19 INFO - 1499737698981 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"
18:48:19 INFO - 1499737698983 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"
18:48:19 INFO - 1499737698984 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"
18:48:19 INFO - 1499737699003 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"
18:48:19 INFO - 1499737699005 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"
18:48:19 INFO - 1499737699008 Marionette TRACE 376 <- [1,2,null,{}]
18:48:19 INFO - 1499737699011 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:48:19 INFO - 1499737699014 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"01dd81dc-43f2-4e93-b9e2-44316186fd00","ELEMENT":"01dd81dc-43f2-4e93-b9e2-44316186fd00"}}]
18:48:19 INFO - 1499737699014 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"01dd81dc-43f2-4e93-b9e2-44316186fd00"}]
18:48:19 INFO - 1499737699258 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:48:19 INFO - 1499737699259 Marionette TRACE 376 <- [1,4,null,{}]
18:48:19 INFO - 1499737699261 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}]
18:48:19 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499737699264 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:48:19 INFO - 1499737699265 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:48:19 INFO - 1499737699266 Marionette TRACE 376 <- [1,6,null,{}]
18:48:19 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 338ms
18:48:19 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:48:19 INFO - 1499737699270 Marionette DEBUG Closed connection 376
18:48:19 INFO - 1499737699270 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50423
18:48:19 INFO - 1499737699271 Marionette DEBUG Closed connection 377
18:48:19 INFO - 1499737699272 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50424
18:48:19 INFO - 1499737699273 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:19 INFO - 1499737699273 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:19 INFO - 1499737699275 Marionette DEBUG Register listener.js for window 12
18:48:19 INFO - 1499737699278 Marionette TRACE 378 <- [1,1,null,{"sessionId":"fab8115f-f09b-4394-8ebe-648653129177","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:19 INFO - 1499737699280 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:48:19 INFO - 1499737699281 Marionette TRACE 378 <- [1,2,null,{}]
18:48:19 INFO - 1499737699281 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:48:19 INFO - 1499737699282 Marionette TRACE 378 <- [1,3,null,{}]
18:48:19 INFO - 1499737699283 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:48:19 INFO - 1499737699283 Marionette TRACE 378 <- [1,4,null,{}]
18:48:19 INFO - 1499737699285 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}]
18:48:19 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499737699287 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:48:19 INFO - 1499737699293 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:48:19 INFO - 1499737699294 Marionette TRACE 378 <- [1,6,null,{}]
18:48:19 INFO - 1499737699345 Marionette DEBUG Closed connection 378
18:48:19 INFO - 1499737699346 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50425
18:48:19 INFO - 1499737699362 Marionette DEBUG Closed connection 379
18:48:19 INFO - 1499737699363 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50426
18:48:19 INFO - 1499737699365 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:19 INFO - 1499737699366 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:19 INFO - 1499737699368 Marionette DEBUG Register listener.js for window 12
18:48:19 INFO - 1499737699371 Marionette TRACE 380 <- [1,1,null,{"sessionId":"656758b0-4e3b-49f7-9868-e03fd7823bd4","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:19 INFO - 1499737699380 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:48:19 INFO - 1499737699382 Marionette TRACE 380 <- [1,2,null,{}]
18:48:19 INFO - 1499737699383 Marionette DEBUG Closed connection 380
18:48:19 INFO - 1499737699384 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50427
18:48:19 INFO - 1499737699385 Marionette DEBUG Closed connection 381
18:48:19 INFO - 1499737699386 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50428
18:48:19 INFO - 1499737699387 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:48:19 INFO - 1499737699387 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:19 INFO - 1499737699389 Marionette DEBUG Register listener.js for window 12
18:48:19 INFO - 1499737699392 Marionette TRACE 382 <- [1,1,null,{"sessionId":"c3c2520a-0cb0-4c44-924e-04e91c9cbc55","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:19 INFO - 1499737699394 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"}]
18:48:19 INFO - 1499737699396 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"
18:48:19 INFO - 1499737699397 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"
18:48:19 INFO - 1499737699398 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"
18:48:19 INFO - 1499737699413 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"
18:48:19 INFO - 1499737699417 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"
18:48:19 INFO - 1499737699426 Marionette TRACE 382 <- [1,2,null,{}]
18:48:19 INFO - 1499737699443 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:48:19 INFO - 1499737699446 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"020533bd-3a12-457f-aa0a-1203937d414d","ELEMENT":"020533bd-3a12-457f-aa0a-1203937d414d"}}]
18:48:19 INFO - 1499737699447 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"020533bd-3a12-457f-aa0a-1203937d414d"}]
18:48:19 INFO - 1499737699685 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:48:19 INFO - 1499737699686 Marionette TRACE 382 <- [1,4,null,{}]
18:48:19 INFO - 1499737699688 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}]
18:48:19 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499737699691 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:48:19 INFO - 1499737699692 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:48:19 INFO - 1499737699693 Marionette TRACE 382 <- [1,6,null,{}]
18:48:19 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 426ms
18:48:19 INFO - TEST-START | test_click.py TestClick.test_input_file
18:48:19 INFO - 1499737699695 Marionette DEBUG Closed connection 382
18:48:19 INFO - 1499737699696 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50429
18:48:19 INFO - 1499737699697 Marionette DEBUG Closed connection 383
18:48:19 INFO - 1499737699698 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50430
18:48:19 INFO - 1499737699699 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:19 INFO - 1499737699699 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:19 INFO - 1499737699701 Marionette DEBUG Register listener.js for window 12
18:48:19 INFO - 1499737699704 Marionette TRACE 384 <- [1,1,null,{"sessionId":"f7831668-5211-449f-bd66-d8d1f23eb239","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:19 INFO - 1499737699706 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:48:19 INFO - 1499737699707 Marionette TRACE 384 <- [1,2,null,{}]
18:48:19 INFO - 1499737699708 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:48:19 INFO - 1499737699708 Marionette TRACE 384 <- [1,3,null,{}]
18:48:19 INFO - 1499737699709 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:48:19 INFO - 1499737699709 Marionette TRACE 384 <- [1,4,null,{}]
18:48:19 INFO - 1499737699711 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}]
18:48:19 INFO - TEST-START: test_click.py TestClick.test_input_file1499737699714 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:48:19 INFO - 1499737699715 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:48:19 INFO - 1499737699716 Marionette TRACE 384 <- [1,6,null,{}]
18:48:19 INFO - 1499737699718 Marionette DEBUG Closed connection 384
18:48:19 INFO - 1499737699719 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50431
18:48:19 INFO - 1499737699719 Marionette DEBUG Closed connection 385
18:48:19 INFO - 1499737699720 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50432
18:48:19 INFO - 1499737699721 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:19 INFO - 1499737699722 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:19 INFO - 1499737699724 Marionette DEBUG Register listener.js for window 12
18:48:19 INFO - 1499737699727 Marionette TRACE 386 <- [1,1,null,{"sessionId":"300b594a-dfd0-45c2-be25-421b5655304c","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:19 INFO - 1499737699728 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:48:19 INFO - 1499737699730 Marionette TRACE 386 <- [1,2,null,{}]
18:48:19 INFO - 1499737699731 Marionette DEBUG Closed connection 386
18:48:19 INFO - 1499737699732 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50433
18:48:19 INFO - 1499737699733 Marionette DEBUG Closed connection 387
18:48:19 INFO - 1499737699734 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50434
18:48:19 INFO - 1499737699735 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:48:19 INFO - 1499737699735 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:19 INFO - 1499737699737 Marionette DEBUG Register listener.js for window 12
18:48:19 INFO - 1499737699741 Marionette TRACE 388 <- [1,1,null,{"sessionId":"30dfc186-dc51-41b2-ac51-26867b628488","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:19 INFO - 1499737699743 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:48:19 INFO - 1499737699745 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"
18:48:19 INFO - 1499737699747 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"
18:48:19 INFO - 1499737699747 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"
18:48:19 INFO - 1499737699763 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:48:19 INFO - 1499737699767 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:48:19 INFO - 1499737699777 Marionette TRACE 388 <- [1,2,null,{}]
18:48:19 INFO - 1499737699779 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:48:19 INFO - 1499737699781 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f840e500-0c51-4fb6-a08c-16fdf683c5ea","ELEMENT":"f840e500-0c51-4fb6-a08c-16fdf683c5ea"}}]
18:48:19 INFO - 1499737699783 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"f840e500-0c51-4fb6-a08c-16fdf683c5ea"}]
18:48:19 INFO - 1499737699808 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]
18:48:19 INFO - 1499737699810 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}]
18:48:19 INFO - TEST-END: test_click.py TestClick.test_input_file1499737699813 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:48:19 INFO - 1499737699814 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:48:19 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 122ms
18:48:19 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:48:19 INFO - 1499737699815 Marionette TRACE 388 <- [1,6,null,{}]
18:48:19 INFO - 1499737699817 Marionette DEBUG Closed connection 388
18:48:19 INFO - 1499737699818 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50435
18:48:19 INFO - 1499737699819 Marionette DEBUG Closed connection 389
18:48:19 INFO - 1499737699820 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50436
18:48:19 INFO - 1499737699821 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:19 INFO - 1499737699822 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:19 INFO - 1499737699824 Marionette DEBUG Register listener.js for window 12
18:48:19 INFO - 1499737699827 Marionette TRACE 390 <- [1,1,null,{"sessionId":"1ad352db-09c6-43b6-90d0-6a239f48ef7c","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:19 INFO - 1499737699829 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:48:19 INFO - 1499737699830 Marionette TRACE 390 <- [1,2,null,{}]
18:48:19 INFO - 1499737699831 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:48:19 INFO - 1499737699831 Marionette TRACE 390 <- [1,3,null,{}]
18:48:19 INFO - 1499737699832 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:48:19 INFO - 1499737699833 Marionette TRACE 390 <- [1,4,null,{}]
18:48:19 INFO - 1499737699834 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}]
18:48:19 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499737699837 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:48:19 INFO - 1499737699838 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:48:19 INFO - 1499737699840 Marionette TRACE 390 <- [1,6,null,{}]
18:48:19 INFO - 1499737699842 Marionette DEBUG Closed connection 390
18:48:19 INFO - 1499737699842 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50437
18:48:19 INFO - 1499737699843 Marionette DEBUG Closed connection 391
18:48:19 INFO - 1499737699844 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50438
18:48:19 INFO - 1499737699845 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:19 INFO - 1499737699846 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:19 INFO - 1499737699848 Marionette DEBUG Register listener.js for window 12
18:48:19 INFO - 1499737699850 Marionette TRACE 392 <- [1,1,null,{"sessionId":"8dc65f82-5c8f-4394-b748-ce10cffbabd0","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:19 INFO - 1499737699852 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:48:19 INFO - 1499737699853 Marionette TRACE 392 <- [1,2,null,{}]
18:48:19 INFO - 1499737699855 Marionette DEBUG Closed connection 392
18:48:19 INFO - 1499737699856 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50439
18:48:19 INFO - 1499737699857 Marionette DEBUG Closed connection 393
18:48:19 INFO - 1499737699858 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50440
18:48:19 INFO - 1499737699859 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:48:19 INFO - 1499737699859 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:19 INFO - 1499737699861 Marionette DEBUG Register listener.js for window 12
18:48:19 INFO - 1499737699872 Marionette TRACE 394 <- [1,1,null,{"sessionId":"6c87b1e9-de59-49d4-bb81-147bb9112dfe","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:19 INFO - 1499737699874 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"}]
18:48:19 INFO - 1499737699876 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:48:19 INFO - 1499737699878 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:48:19 INFO - 1499737699878 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:48:19 INFO - 1499737699897 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"
18:48:19 INFO - 1499737699900 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"
18:48:19 INFO - 1499737699911 Marionette TRACE 394 <- [1,2,null,{}]
18:48:19 INFO - 1499737699912 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:48:19 INFO - 1499737699914 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a69fa463-fe49-40c7-ac95-5de83ad67b77","ELEMENT":"a69fa463-fe49-40c7-ac95-5de83ad67b77"}}]
18:48:19 INFO - 1499737699917 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:48:19 INFO - 1499737699919 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d41f7609-bfd4-4309-adec-0ea06c8c6d1f","ELEMENT":"d41f7609-bfd4-4309-adec-0ea06c8c6d1f"}}]
18:48:19 INFO - 1499737699920 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"a69fa463-fe49-40c7-ac95-5de83ad67b77"}]
18:48:20 INFO - 1499737700156 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:48:20 INFO - 1499737700157 Marionette TRACE 394 <- [1,5,null,{}]
18:48:20 INFO - 1499737700158 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"d41f7609-bfd4-4309-adec-0ea06c8c6d1f"}]
18:48:20 INFO - 1499737700173 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]
18:48:20 INFO - 1499737700175 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:48:20 INFO - 1499737700177 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:48:20 INFO - 1499737700179 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}]
18:48:20 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499737700181 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:48:20 INFO - 1499737700182 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:48:20 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 367ms
18:48:20 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:48:20 INFO - 1499737700183 Marionette TRACE 394 <- [1,9,null,{}]
18:48:20 INFO - 1499737700185 Marionette DEBUG Closed connection 394
18:48:20 INFO - 1499737700186 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50441
18:48:20 INFO - 1499737700187 Marionette DEBUG Closed connection 395
18:48:20 INFO - 1499737700188 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50442
18:48:20 INFO - 1499737700189 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:20 INFO - 1499737700189 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:20 INFO - 1499737700191 Marionette DEBUG Register listener.js for window 12
18:48:20 INFO - 1499737700194 Marionette TRACE 396 <- [1,1,null,{"sessionId":"b5f326e0-1c48-475b-b2c1-53d2a69f2a7b","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:20 INFO - 1499737700196 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:48:20 INFO - 1499737700197 Marionette TRACE 396 <- [1,2,null,{}]
18:48:20 INFO - 1499737700198 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:48:20 INFO - 1499737700198 Marionette TRACE 396 <- [1,3,null,{}]
18:48:20 INFO - 1499737700199 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:48:20 INFO - 1499737700199 Marionette TRACE 396 <- [1,4,null,{}]
18:48:20 INFO - 1499737700201 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}]
18:48:20 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499737700204 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:48:20 INFO - 1499737700205 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:48:20 INFO - 1499737700206 Marionette TRACE 396 <- [1,6,null,{}]
18:48:20 INFO - 1499737700208 Marionette DEBUG Closed connection 396
18:48:20 INFO - 1499737700208 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50443
18:48:20 INFO - 1499737700209 Marionette DEBUG Closed connection 397
18:48:20 INFO - 1499737700210 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50444
18:48:20 INFO - 1499737700211 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:48:20 INFO - 1499737700211 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:20 INFO - 1499737700214 Marionette DEBUG Register listener.js for window 12
18:48:20 INFO - 1499737700217 Marionette TRACE 398 <- [1,1,null,{"sessionId":"df27b96d-8b27-458c-ab49-44e0b34f7980","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:20 INFO - 1499737700219 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:48:20 INFO - 1499737700220 Marionette TRACE 398 <- [1,2,null,{}]
18:48:20 INFO - 1499737700222 Marionette DEBUG Closed connection 398
18:48:20 INFO - 1499737700222 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50445
18:48:20 INFO - 1499737700223 Marionette DEBUG Closed connection 399
18:48:20 INFO - 1499737700224 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50446
18:48:20 INFO - 1499737700225 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:48:20 INFO - 1499737700226 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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}
18:48:20 INFO - 1499737700228 Marionette DEBUG Register listener.js for window 12
18:48:20 INFO - 1499737700230 Marionette TRACE 400 <- [1,1,null,{"sessionId":"56ef603f-3d27-40a3-8b6a-3fa3ff6af6e2","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":1420,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmppjm3eg.mozrunner","moz:accessibilityChecks":false}}]
18:48:20 INFO - 1499737700233 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"}]
18:48:20 INFO - 1499737700235 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"
18:48:20 INFO - 1499737700237 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"
18:48:20 INFO - 1499737700237 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"
18:48:20 INFO - 1499737700256 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"
18:48:20 INFO - 1499737700258 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"
18:48:20 INFO - 1499737700261 Marionette TRACE 400 <- [1,2,null,{}]
18:48:20 INFO - 1499737700262 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:48:20 INFO - 1499737700264 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8267a767-a41d-4c2d-8112-834d87cdf498","ELEMENT":"8267a767-a41d-4c2d-8112-834d87cdf498"}}]
18:48:20 INFO - 1499737700272 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"8267a767-a41d-4c2d-8112-834d87cdf498"}]
18:48:20 INFO - 1499737700295 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:48:20 INFO - 1499737700296 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"8267a767-a41d-4c2d-8112-834d87cdf498"}]
18:48:20 INFO - 1499737700311 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element