could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
21:40:38 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 122ms
21:40:38 INFO - 1500698438287 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}]
21:40:38 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500698438289 Marionette TRACE 352 <- [1,5,null,{"value":null}]
21:40:38 INFO - 1500698438290 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
21:40:38 INFO - 1500698438291 Marionette TRACE 352 <- [1,6,null,{}]
21:40:38 INFO - 1500698438293 Marionette DEBUG Closed connection 352
21:40:38 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:40:38 INFO - 1500698438295 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50368
21:40:38 INFO - 1500698438296 Marionette DEBUG Closed connection 353
21:40:38 INFO - 1500698438297 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50369
21:40:38 INFO - 1500698438298 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438298 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438300 Marionette DEBUG Register listener.js for window 15
21:40:38 INFO - 1500698438303 Marionette TRACE 354 <- [1,1,null,{"sessionId":"7c654113-1f1c-4a9c-b405-dc7b1d859aaf","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438305 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
21:40:38 INFO - 1500698438305 Marionette TRACE 354 <- [1,2,null,{}]
21:40:38 INFO - 1500698438306 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:38 INFO - 1500698438306 Marionette TRACE 354 <- [1,3,null,{}]
21:40:38 INFO - 1500698438307 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:38 INFO - 1500698438307 Marionette TRACE 354 <- [1,4,null,{}]
21:40:38 INFO - 1500698438309 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}]
21:40:38 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500698438312 Marionette TRACE 354 <- [1,5,null,{"value":null}]
21:40:38 INFO - 1500698438312 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
21:40:38 INFO - 1500698438314 Marionette TRACE 354 <- [1,6,null,{}]
21:40:38 INFO - 1500698438315 Marionette DEBUG Closed connection 354
21:40:38 INFO - 1500698438316 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50370
21:40:38 INFO - 1500698438317 Marionette DEBUG Closed connection 355
21:40:38 INFO - 1500698438317 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50371
21:40:38 INFO - 1500698438318 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438326 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438328 Marionette DEBUG Register listener.js for window 15
21:40:38 INFO - 1500698438331 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f7b5e71f-e989-4bd5-a349-63feae613f16","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438333 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
21:40:38 INFO - 1500698438334 Marionette TRACE 356 <- [1,2,null,{}]
21:40:38 INFO - 1500698438336 Marionette DEBUG Closed connection 356
21:40:38 INFO - 1500698438338 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50372
21:40:38 INFO - 1500698438339 Marionette DEBUG Closed connection 357
21:40:38 INFO - 1500698438340 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50373
21:40:38 INFO - 1500698438341 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:38 INFO - 1500698438342 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438344 Marionette DEBUG Register listener.js for window 15
21:40:38 INFO - 1500698438346 Marionette TRACE 358 <- [1,1,null,{"sessionId":"6bfbb11d-517c-433e-b301-b928e996664f","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438348 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49934/clicks.html"}]
21:40:38 INFO - 1500698438349 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49934/hidden.html"
21:40:38 INFO - 1500698438354 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49934/hidden.html"
21:40:38 INFO - 1500698438355 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49934/hidden.html"
21:40:38 INFO - 1500698438370 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49934/clicks.html"
21:40:38 INFO - 1500698438374 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49934/clicks.html"
21:40:38 INFO - 1500698438378 Marionette TRACE 358 <- [1,2,null,{}]
21:40:38 INFO - 1500698438385 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:40:38 INFO - 1500698438387 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5abe4607-2d77-47e4-8c5d-c854a03837f9","ELEMENT":"5abe4607-2d77-47e4-8c5d-c854a03837f9"}}]
21:40:38 INFO - 1500698438388 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"5abe4607-2d77-47e4-8c5d-c854a03837f9"}]
21:40:38 INFO - 1500698438426 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49934/clicks.html"
21:40:38 INFO - 1500698438434 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49934/clicks.html"
21:40:38 INFO - 1500698438435 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49934/clicks.html"
21:40:38 INFO - 1500698438452 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49934/test.html"
21:40:38 INFO - 1500698438453 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49934/test.html"
21:40:38 INFO - 1500698438456 Marionette TRACE 358 <- [1,4,null,{}]
21:40:38 INFO - 1500698438458 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:40:38 INFO - 1500698438469 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3ec631dd-df4e-4798-9639-06d4cd147ef0","ELEMENT":"3ec631dd-df4e-4798-9639-06d4cd147ef0"}}]
21:40:38 INFO - 1500698438471 Marionette TRACE 358 -> [0,6,"getTitle",{}]
21:40:38 INFO - 1500698438471 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
21:40:38 INFO - 1500698438473 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}]
21:40:38 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500698438475 Marionette TRACE 358 <- [1,7,null,{"value":null}]
21:40:38 INFO - 1500698438476 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
21:40:38 INFO - 1500698438477 Marionette TRACE 358 <- [1,8,null,{}]
21:40:38 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 184ms
21:40:38 INFO - TEST-START | test_click.py TestClick.test_container_element
21:40:38 INFO - 1500698438479 Marionette DEBUG Closed connection 358
21:40:38 INFO - 1500698438480 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50376
21:40:38 INFO - 1500698438481 Marionette DEBUG Closed connection 359
21:40:38 INFO - 1500698438482 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50377
21:40:38 INFO - 1500698438483 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438483 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438485 Marionette DEBUG Register listener.js for window 15
21:40:38 INFO - 1500698438488 Marionette TRACE 360 <- [1,1,null,{"sessionId":"4da2a2ea-54a8-462d-a9c5-242e55f7ac8e","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438491 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
21:40:38 INFO - 1500698438491 Marionette TRACE 360 <- [1,2,null,{}]
21:40:38 INFO - 1500698438492 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:38 INFO - 1500698438492 Marionette TRACE 360 <- [1,3,null,{}]
21:40:38 INFO - 1500698438493 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:38 INFO - 1500698438494 Marionette TRACE 360 <- [1,4,null,{}]
21:40:38 INFO - 1500698438496 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}]
21:40:38 INFO - TEST-START: test_click.py TestClick.test_container_element1500698438498 Marionette TRACE 360 <- [1,5,null,{"value":null}]
21:40:38 INFO - 1500698438499 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
21:40:38 INFO - 1500698438500 Marionette TRACE 360 <- [1,6,null,{}]
21:40:38 INFO - 1500698438502 Marionette DEBUG Closed connection 360
21:40:38 INFO - 1500698438503 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50378
21:40:38 INFO - 1500698438503 Marionette DEBUG Closed connection 361
21:40:38 INFO - 1500698438504 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50379
21:40:38 INFO - 1500698438505 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438506 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438508 Marionette DEBUG Register listener.js for window 15
21:40:38 INFO - 1500698438510 Marionette TRACE 362 <- [1,1,null,{"sessionId":"fdc4e3ad-e1b0-4568-906e-1063cd26e6a4","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438512 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
21:40:38 INFO - 1500698438513 Marionette TRACE 362 <- [1,2,null,{}]
21:40:38 INFO - 1500698438515 Marionette DEBUG Closed connection 362
21:40:38 INFO - 1500698438515 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50380
21:40:38 INFO - 1500698438516 Marionette DEBUG Closed connection 363
21:40:38 INFO - 1500698438517 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50381
21:40:38 INFO - 1500698438518 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:38 INFO - 1500698438518 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438520 Marionette DEBUG Register listener.js for window 15
21:40:38 INFO - 1500698438523 Marionette TRACE 364 <- [1,1,null,{"sessionId":"dc4c1b6a-7b89-450f-9608-c4726fe9c1dc","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438524 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"}]
21:40:38 INFO - 1500698438526 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49934/test.html"
21:40:38 INFO - 1500698438529 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49934/test.html"
21:40:38 INFO - 1500698438529 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49934/test.html"
21:40:38 INFO - 1500698438557 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"
21:40:38 INFO - 1500698438561 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"
21:40:38 INFO - 1500698438572 Marionette TRACE 364 <- [1,2,null,{}]
21:40:38 INFO - 1500698438573 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:40:38 INFO - 1500698438575 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"28036e27-23c0-4c9a-973d-398084fc1b9f","ELEMENT":"28036e27-23c0-4c9a-973d-398084fc1b9f"}}]
21:40:38 INFO - 1500698438576 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"28036e27-23c0-4c9a-973d-398084fc1b9f"}]
21:40:38 INFO - 1500698438815 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:40:38 INFO - 1500698438816 Marionette TRACE 364 <- [1,4,null,{}]
21:40:38 INFO - 1500698438817 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"28036e27-23c0-4c9a-973d-398084fc1b9f","name":"selected"}]
21:40:38 INFO - 1500698438822 Marionette TRACE 364 <- [1,5,null,{"value":true}]
21:40:38 INFO - 1500698438823 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}]
21:40:38 INFO - TEST-END: test_click.py TestClick.test_container_element1500698438826 Marionette TRACE 364 <- [1,6,null,{"value":null}]
21:40:38 INFO - 1500698438827 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
21:40:38 INFO - 1500698438828 Marionette TRACE 364 <- [1,7,null,{}]
21:40:38 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 351ms
21:40:38 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:40:38 INFO - 1500698438830 Marionette DEBUG Closed connection 364
21:40:38 INFO - 1500698438831 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50382
21:40:38 INFO - 1500698438832 Marionette DEBUG Closed connection 365
21:40:38 INFO - 1500698438832 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50383
21:40:38 INFO - 1500698438833 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438834 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438836 Marionette DEBUG Register listener.js for window 15
21:40:38 INFO - 1500698438839 Marionette TRACE 366 <- [1,1,null,{"sessionId":"e88dd400-ee87-4ae9-9772-982e90920bdc","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438841 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
21:40:38 INFO - 1500698438841 Marionette TRACE 366 <- [1,2,null,{}]
21:40:38 INFO - 1500698438842 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:38 INFO - 1500698438842 Marionette TRACE 366 <- [1,3,null,{}]
21:40:38 INFO - 1500698438843 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:38 INFO - 1500698438843 Marionette TRACE 366 <- [1,4,null,{}]
21:40:38 INFO - 1500698438845 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}]
21:40:38 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500698438848 Marionette TRACE 366 <- [1,5,null,{"value":null}]
21:40:38 INFO - 1500698438848 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
21:40:38 INFO - 1500698438850 Marionette TRACE 366 <- [1,6,null,{}]
21:40:38 INFO - 1500698438851 Marionette DEBUG Closed connection 366
21:40:38 INFO - 1500698438852 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50384
21:40:38 INFO - 1500698438853 Marionette DEBUG Closed connection 367
21:40:38 INFO - 1500698438854 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50385
21:40:38 INFO - 1500698438855 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:38 INFO - 1500698438855 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438857 Marionette DEBUG Register listener.js for window 15
21:40:38 INFO - 1500698438867 Marionette TRACE 368 <- [1,1,null,{"sessionId":"34828a7c-89b2-4ef0-a566-8a8ff4b35895","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438869 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
21:40:38 INFO - 1500698438870 Marionette TRACE 368 <- [1,2,null,{}]
21:40:38 INFO - 1500698438872 Marionette DEBUG Closed connection 368
21:40:38 INFO - 1500698438873 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50386
21:40:38 INFO - 1500698438873 Marionette DEBUG Closed connection 369
21:40:38 INFO - 1500698438874 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50387
21:40:38 INFO - 1500698438875 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:38 INFO - 1500698438876 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:38 INFO - 1500698438878 Marionette DEBUG Register listener.js for window 15
21:40:38 INFO - 1500698438881 Marionette TRACE 370 <- [1,1,null,{"sessionId":"8800ae1b-cbee-48a6-91d6-6f5f73e379ec","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:38 INFO - 1500698438883 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"}]
21:40:38 INFO - 1500698438885 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"
21:40:38 INFO - 1500698438886 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"
21:40:38 INFO - 1500698438886 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"
21:40:38 INFO - 1500698438904 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"
21:40:38 INFO - 1500698438906 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"
21:40:38 INFO - 1500698438910 Marionette TRACE 370 <- [1,2,null,{}]
21:40:38 INFO - 1500698438916 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:40:38 INFO - 1500698438919 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb8e0b26-b3cc-420f-974f-e8a05fcd8e49","ELEMENT":"fb8e0b26-b3cc-420f-974f-e8a05fcd8e49"}}]
21:40:38 INFO - 1500698438921 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"fb8e0b26-b3cc-420f-974f-e8a05fcd8e49"}]
21:40:39 INFO - 1500698439159 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:40:39 INFO - 1500698439159 Marionette TRACE 370 <- [1,4,null,{}]
21:40:39 INFO - 1500698439161 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"fb8e0b26-b3cc-420f-974f-e8a05fcd8e49","name":"selected"}]
21:40:39 INFO - 1500698439164 Marionette TRACE 370 <- [1,5,null,{"value":true}]
21:40:39 INFO - 1500698439166 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}]
21:40:39 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500698439168 Marionette TRACE 370 <- [1,6,null,{"value":null}]
21:40:39 INFO - 1500698439169 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
21:40:39 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 342ms
21:40:39 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:40:39 INFO - 1500698439170 Marionette TRACE 370 <- [1,7,null,{}]
21:40:39 INFO - 1500698439172 Marionette DEBUG Closed connection 370
21:40:39 INFO - 1500698439173 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50388
21:40:39 INFO - 1500698439174 Marionette DEBUG Closed connection 371
21:40:39 INFO - 1500698439175 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50389
21:40:39 INFO - 1500698439176 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:39 INFO - 1500698439176 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439178 Marionette DEBUG Register listener.js for window 15
21:40:39 INFO - 1500698439181 Marionette TRACE 372 <- [1,1,null,{"sessionId":"e41d1aeb-ae70-4efc-b1e6-773f4aa4896a","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439183 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
21:40:39 INFO - 1500698439183 Marionette TRACE 372 <- [1,2,null,{}]
21:40:39 INFO - 1500698439185 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:39 INFO - 1500698439186 Marionette TRACE 372 <- [1,3,null,{}]
21:40:39 INFO - 1500698439186 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:39 INFO - 1500698439187 Marionette TRACE 372 <- [1,4,null,{}]
21:40:39 INFO - 1500698439189 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}]
21:40:39 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500698439191 Marionette TRACE 372 <- [1,5,null,{"value":null}]
21:40:39 INFO - 1500698439192 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
21:40:39 INFO - 1500698439193 Marionette TRACE 372 <- [1,6,null,{}]
21:40:39 INFO - 1500698439194 Marionette DEBUG Closed connection 372
21:40:39 INFO - 1500698439195 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50390
21:40:39 INFO - 1500698439196 Marionette DEBUG Closed connection 373
21:40:39 INFO - 1500698439196 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50391
21:40:39 INFO - 1500698439197 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:39 INFO - 1500698439198 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439200 Marionette DEBUG Register listener.js for window 15
21:40:39 INFO - 1500698439202 Marionette TRACE 374 <- [1,1,null,{"sessionId":"972fcd5e-10b7-463f-9747-3604f290bec3","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439204 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
21:40:39 INFO - 1500698439205 Marionette TRACE 374 <- [1,2,null,{}]
21:40:39 INFO - 1500698439206 Marionette DEBUG Closed connection 374
21:40:39 INFO - 1500698439207 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50392
21:40:39 INFO - 1500698439208 Marionette DEBUG Closed connection 375
21:40:39 INFO - 1500698439209 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50393
21:40:39 INFO - 1500698439209 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:39 INFO - 1500698439210 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439212 Marionette DEBUG Register listener.js for window 15
21:40:39 INFO - 1500698439214 Marionette TRACE 376 <- [1,1,null,{"sessionId":"1242b00b-ca2f-472b-9d0b-e509199ae311","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439216 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"}]
21:40:39 INFO - 1500698439218 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"
21:40:39 INFO - 1500698439220 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"
21:40:39 INFO - 1500698439220 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"
21:40:39 INFO - 1500698439240 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"
21:40:39 INFO - 1500698439242 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"
21:40:39 INFO - 1500698439245 Marionette TRACE 376 <- [1,2,null,{}]
21:40:39 INFO - 1500698439252 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:40:39 INFO - 1500698439255 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"205312a3-7f0c-44ba-913e-f197af8bbf93","ELEMENT":"205312a3-7f0c-44ba-913e-f197af8bbf93"}}]
21:40:39 INFO - 1500698439260 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"205312a3-7f0c-44ba-913e-f197af8bbf93"}]
21:40:39 INFO - 1500698439495 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:40:39 INFO - 1500698439496 Marionette TRACE 376 <- [1,4,null,{}]
21:40:39 INFO - 1500698439497 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}]
21:40:39 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500698439500 Marionette TRACE 376 <- [1,5,null,{"value":null}]
21:40:39 INFO - 1500698439501 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
21:40:39 INFO - 1500698439502 Marionette TRACE 376 <- [1,6,null,{}]
21:40:39 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 331ms
21:40:39 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:40:39 INFO - 1500698439504 Marionette DEBUG Closed connection 376
21:40:39 INFO - 1500698439505 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50394
21:40:39 INFO - 1500698439506 Marionette DEBUG Closed connection 377
21:40:39 INFO - 1500698439506 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50395
21:40:39 INFO - 1500698439507 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:39 INFO - 1500698439508 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439510 Marionette DEBUG Register listener.js for window 15
21:40:39 INFO - 1500698439513 Marionette TRACE 378 <- [1,1,null,{"sessionId":"c2218088-c254-4946-b7b6-dff9db214588","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439514 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
21:40:39 INFO - 1500698439515 Marionette TRACE 378 <- [1,2,null,{}]
21:40:39 INFO - 1500698439516 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:39 INFO - 1500698439516 Marionette TRACE 378 <- [1,3,null,{}]
21:40:39 INFO - 1500698439517 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:39 INFO - 1500698439517 Marionette TRACE 378 <- [1,4,null,{}]
21:40:39 INFO - 1500698439519 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}]
21:40:39 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500698439522 Marionette TRACE 378 <- [1,5,null,{"value":null}]
21:40:39 INFO - 1500698439523 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
21:40:39 INFO - 1500698439524 Marionette TRACE 378 <- [1,6,null,{}]
21:40:39 INFO - 1500698439526 Marionette DEBUG Closed connection 378
21:40:39 INFO - 1500698439526 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50396
21:40:39 INFO - 1500698439527 Marionette DEBUG Closed connection 379
21:40:39 INFO - 1500698439528 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50397
21:40:39 INFO - 1500698439529 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:39 INFO - 1500698439529 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439531 Marionette DEBUG Register listener.js for window 15
21:40:39 INFO - 1500698439534 Marionette TRACE 380 <- [1,1,null,{"sessionId":"75909325-c8b3-4ef7-8980-ee014dc8abae","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439543 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
21:40:39 INFO - 1500698439544 Marionette TRACE 380 <- [1,2,null,{}]
21:40:39 INFO - 1500698439546 Marionette DEBUG Closed connection 380
21:40:39 INFO - 1500698439547 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50398
21:40:39 INFO - 1500698439547 Marionette DEBUG Closed connection 381
21:40:39 INFO - 1500698439548 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50399
21:40:39 INFO - 1500698439549 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:39 INFO - 1500698439550 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439552 Marionette DEBUG Register listener.js for window 15
21:40:39 INFO - 1500698439554 Marionette TRACE 382 <- [1,1,null,{"sessionId":"10a7f03c-23a8-4349-bc28-307398142fc3","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439556 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"}]
21:40:39 INFO - 1500698439558 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"
21:40:39 INFO - 1500698439559 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"
21:40:39 INFO - 1500698439559 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"
21:40:39 INFO - 1500698439575 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"
21:40:39 INFO - 1500698439577 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"
21:40:39 INFO - 1500698439586 Marionette TRACE 382 <- [1,2,null,{}]
21:40:39 INFO - 1500698439587 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:40:39 INFO - 1500698439590 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"838853c6-e4d0-4ea3-b2f4-ed3c7ed3aa05","ELEMENT":"838853c6-e4d0-4ea3-b2f4-ed3c7ed3aa05"}}]
21:40:39 INFO - 1500698439590 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"838853c6-e4d0-4ea3-b2f4-ed3c7ed3aa05"}]
21:40:39 INFO - 1500698439829 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:40:39 INFO - 1500698439829 Marionette TRACE 382 <- [1,4,null,{}]
21:40:39 INFO - 1500698439831 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}]
21:40:39 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500698439834 Marionette TRACE 382 <- [1,5,null,{"value":null}]
21:40:39 INFO - 1500698439835 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
21:40:39 INFO - 1500698439836 Marionette TRACE 382 <- [1,6,null,{}]
21:40:39 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 333ms
21:40:39 INFO - TEST-START | test_click.py TestClick.test_input_file
21:40:39 INFO - 1500698439838 Marionette DEBUG Closed connection 382
21:40:39 INFO - 1500698439839 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50400
21:40:39 INFO - 1500698439840 Marionette DEBUG Closed connection 383
21:40:39 INFO - 1500698439840 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50401
21:40:39 INFO - 1500698439841 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:39 INFO - 1500698439842 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439844 Marionette DEBUG Register listener.js for window 15
21:40:39 INFO - 1500698439846 Marionette TRACE 384 <- [1,1,null,{"sessionId":"b2c68c0f-6525-46e8-a95b-31ad737ea2b5","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439848 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
21:40:39 INFO - 1500698439848 Marionette TRACE 384 <- [1,2,null,{}]
21:40:39 INFO - 1500698439849 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:39 INFO - 1500698439850 Marionette TRACE 384 <- [1,3,null,{}]
21:40:39 INFO - 1500698439850 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:39 INFO - 1500698439851 Marionette TRACE 384 <- [1,4,null,{}]
21:40:39 INFO - 1500698439852 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}]
21:40:39 INFO - TEST-START: test_click.py TestClick.test_input_file1500698439855 Marionette TRACE 384 <- [1,5,null,{"value":null}]
21:40:39 INFO - 1500698439855 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
21:40:39 INFO - 1500698439856 Marionette TRACE 384 <- [1,6,null,{}]
21:40:39 INFO - 1500698439858 Marionette DEBUG Closed connection 384
21:40:39 INFO - 1500698439859 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50402
21:40:39 INFO - 1500698439859 Marionette DEBUG Closed connection 385
21:40:39 INFO - 1500698439860 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50403
21:40:39 INFO - 1500698439861 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:39 INFO - 1500698439862 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439864 Marionette DEBUG Register listener.js for window 15
21:40:39 INFO - 1500698439866 Marionette TRACE 386 <- [1,1,null,{"sessionId":"d7a6c703-705a-41b0-b2b3-dcb41fa4c9ad","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439868 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
21:40:39 INFO - 1500698439869 Marionette TRACE 386 <- [1,2,null,{}]
21:40:39 INFO - 1500698439871 Marionette DEBUG Closed connection 386
21:40:39 INFO - 1500698439871 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50404
21:40:39 INFO - 1500698439872 Marionette DEBUG Closed connection 387
21:40:39 INFO - 1500698439873 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50405
21:40:39 INFO - 1500698439874 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:39 INFO - 1500698439874 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439876 Marionette DEBUG Register listener.js for window 15
21:40:39 INFO - 1500698439879 Marionette TRACE 388 <- [1,1,null,{"sessionId":"b8de3777-3c5f-47c2-9050-5df2adaee3dc","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439881 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:40:39 INFO - 1500698439882 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"
21:40:39 INFO - 1500698439883 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"
21:40:39 INFO - 1500698439884 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"
21:40:39 INFO - 1500698439894 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:40:39 INFO - 1500698439902 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:40:39 INFO - 1500698439906 Marionette TRACE 388 <- [1,2,null,{}]
21:40:39 INFO - 1500698439907 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:40:39 INFO - 1500698439910 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a5c8c5c7-4d95-4c97-9a2c-75291559f792","ELEMENT":"a5c8c5c7-4d95-4c97-9a2c-75291559f792"}}]
21:40:39 INFO - 1500698439917 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"a5c8c5c7-4d95-4c97-9a2c-75291559f792"}]
21:40:39 INFO - 1500698439942 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
21:40:39 INFO - 1500698439944 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}]
21:40:39 INFO - TEST-END: test_click.py TestClick.test_input_file1500698439946 Marionette TRACE 388 <- [1,5,null,{"value":null}]
21:40:39 INFO - 1500698439947 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
21:40:39 INFO - 1500698439948 Marionette TRACE 388 <- [1,6,null,{}]
21:40:39 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 112ms
21:40:39 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:40:39 INFO - 1500698439950 Marionette DEBUG Closed connection 388
21:40:39 INFO - 1500698439951 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50406
21:40:39 INFO - 1500698439952 Marionette DEBUG Closed connection 389
21:40:39 INFO - 1500698439953 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50407
21:40:39 INFO - 1500698439954 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:39 INFO - 1500698439954 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439956 Marionette DEBUG Register listener.js for window 15
21:40:39 INFO - 1500698439959 Marionette TRACE 390 <- [1,1,null,{"sessionId":"9dd9ff18-524d-485d-974b-70467aa9ff6d","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439961 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
21:40:39 INFO - 1500698439961 Marionette TRACE 390 <- [1,2,null,{}]
21:40:39 INFO - 1500698439962 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:39 INFO - 1500698439963 Marionette TRACE 390 <- [1,3,null,{}]
21:40:39 INFO - 1500698439964 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:39 INFO - 1500698439964 Marionette TRACE 390 <- [1,4,null,{}]
21:40:39 INFO - 1500698439966 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}]
21:40:39 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500698439968 Marionette TRACE 390 <- [1,5,null,{"value":null}]
21:40:39 INFO - 1500698439969 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
21:40:39 INFO - 1500698439970 Marionette TRACE 390 <- [1,6,null,{}]
21:40:39 INFO - 1500698439971 Marionette DEBUG Closed connection 390
21:40:39 INFO - 1500698439972 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50408
21:40:39 INFO - 1500698439973 Marionette DEBUG Closed connection 391
21:40:39 INFO - 1500698439973 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50409
21:40:39 INFO - 1500698439974 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:39 INFO - 1500698439975 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439977 Marionette DEBUG Register listener.js for window 15
21:40:39 INFO - 1500698439980 Marionette TRACE 392 <- [1,1,null,{"sessionId":"f9ee32f1-50ee-45ae-a3ef-0d3fb05b2ec1","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:39 INFO - 1500698439982 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
21:40:39 INFO - 1500698439983 Marionette TRACE 392 <- [1,2,null,{}]
21:40:39 INFO - 1500698439985 Marionette DEBUG Closed connection 392
21:40:39 INFO - 1500698439985 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50410
21:40:39 INFO - 1500698439986 Marionette DEBUG Closed connection 393
21:40:39 INFO - 1500698439987 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50411
21:40:39 INFO - 1500698439988 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:39 INFO - 1500698439988 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:39 INFO - 1500698439990 Marionette DEBUG Register listener.js for window 15
21:40:40 INFO - 1500698440000 Marionette TRACE 394 <- [1,1,null,{"sessionId":"38f03482-2b66-406a-a484-d8d0c654a4eb","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:40 INFO - 1500698440002 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"}]
21:40:40 INFO - 1500698440003 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:40:40 INFO - 1500698440005 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:40:40 INFO - 1500698440006 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:40:40 INFO - 1500698440024 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"
21:40:40 INFO - 1500698440026 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"
21:40:40 INFO - 1500698440029 Marionette TRACE 394 <- [1,2,null,{}]
21:40:40 INFO - 1500698440037 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:40:40 INFO - 1500698440039 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b4c92905-9b72-40ea-ba2c-ba580e6b2b06","ELEMENT":"b4c92905-9b72-40ea-ba2c-ba580e6b2b06"}}]
21:40:40 INFO - 1500698440040 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:40:40 INFO - 1500698440042 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b3f6d78d-1bd6-429e-a34b-c7f78aa37525","ELEMENT":"b3f6d78d-1bd6-429e-a34b-c7f78aa37525"}}]
21:40:40 INFO - 1500698440043 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"b4c92905-9b72-40ea-ba2c-ba580e6b2b06"}]
21:40:40 INFO - 1500698440278 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:40:40 INFO - 1500698440278 Marionette TRACE 394 <- [1,5,null,{}]
21:40:40 INFO - 1500698440279 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"b3f6d78d-1bd6-429e-a34b-c7f78aa37525"}]
21:40:40 INFO - 1500698440293 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
21:40:40 INFO - 1500698440295 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:40:40 INFO - 1500698440297 Marionette TRACE 394 <- [1,7,null,{"value":false}]
21:40:40 INFO - 1500698440299 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}]
21:40:40 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500698440301 Marionette TRACE 394 <- [1,8,null,{"value":null}]
21:40:40 INFO - 1500698440302 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
21:40:40 INFO - 1500698440303 Marionette TRACE 394 <- [1,9,null,{}]
21:40:40 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 354ms
21:40:40 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:40:40 INFO - 1500698440305 Marionette DEBUG Closed connection 394
21:40:40 INFO - 1500698440306 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50412
21:40:40 INFO - 1500698440307 Marionette DEBUG Closed connection 395
21:40:40 INFO - 1500698440307 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50413
21:40:40 INFO - 1500698440308 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:40 INFO - 1500698440309 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:40 INFO - 1500698440311 Marionette DEBUG Register listener.js for window 15
21:40:40 INFO - 1500698440314 Marionette TRACE 396 <- [1,1,null,{"sessionId":"68bfdea6-93a6-4c0f-afa8-1200e7016f50","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:40 INFO - 1500698440315 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
21:40:40 INFO - 1500698440316 Marionette TRACE 396 <- [1,2,null,{}]
21:40:40 INFO - 1500698440316 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:40:40 INFO - 1500698440317 Marionette TRACE 396 <- [1,3,null,{}]
21:40:40 INFO - 1500698440318 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
21:40:40 INFO - 1500698440318 Marionette TRACE 396 <- [1,4,null,{}]
21:40:40 INFO - 1500698440320 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}]
21:40:40 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500698440322 Marionette TRACE 396 <- [1,5,null,{"value":null}]
21:40:40 INFO - 1500698440323 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
21:40:40 INFO - 1500698440324 Marionette TRACE 396 <- [1,6,null,{}]
21:40:40 INFO - 1500698440326 Marionette DEBUG Closed connection 396
21:40:40 INFO - 1500698440326 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50414
21:40:40 INFO - 1500698440327 Marionette DEBUG Closed connection 397
21:40:40 INFO - 1500698440328 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50415
21:40:40 INFO - 1500698440329 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:40:40 INFO - 1500698440329 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:40 INFO - 1500698440331 Marionette DEBUG Register listener.js for window 15
21:40:40 INFO - 1500698440334 Marionette TRACE 398 <- [1,1,null,{"sessionId":"0641f7cb-8ac0-4621-960d-964d21fcadf4","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:40 INFO - 1500698440335 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
21:40:40 INFO - 1500698440336 Marionette TRACE 398 <- [1,2,null,{}]
21:40:40 INFO - 1500698440338 Marionette DEBUG Closed connection 398
21:40:40 INFO - 1500698440339 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50416
21:40:40 INFO - 1500698440339 Marionette DEBUG Closed connection 399
21:40:40 INFO - 1500698440340 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50417
21:40:40 INFO - 1500698440341 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:40:40 INFO - 1500698440341 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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}
21:40:40 INFO - 1500698440343 Marionette DEBUG Register listener.js for window 15
21:40:40 INFO - 1500698440346 Marionette TRACE 400 <- [1,1,null,{"sessionId":"f2bd131f-0d20-4894-9681-f38880e51cd2","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":3180,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpxe1jw_.mozrunner","moz:accessibilityChecks":false}}]
21:40:40 INFO - 1500698440349 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"}]
21:40:40 INFO - 1500698440350 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"
21:40:40 INFO - 1500698440352 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"
21:40:40 INFO - 1500698440352 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"
21:40:40 INFO - 1500698440371 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"
21:40:40 INFO - 1500698440372 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"
21:40:40 INFO - 1500698440375 Marionette TRACE 400 <- [1,2,null,{}]
21:40:40 INFO - 1500698440376 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:40:40 INFO - 1500698440385 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"323ed6c2-9de5-41e6-8728-073c722c3fb1","ELEMENT":"323ed6c2-9de5-41e6-8728-073c722c3fb1"}}]
21:40:40 INFO - 1500698440386 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"323ed6c2-9de5-41e6-8728-073c722c3fb1"}]
21:40:40 INFO - 1500698440411 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
21:40:40 INFO - 1500698440413 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"323ed6c2-9de5-41e6-8728-073c722c3fb1"}]
21:40:40 INFO - 1500698440432 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element