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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
17:33:17 INFO - 1499128397362 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}]
17:33:17 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499128397366 Marionette TRACE 352 <- [1,5,null,{"value":null}]
17:33:17 INFO - 1499128397367 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
17:33:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 150ms
17:33:17 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
17:33:17 INFO - 1499128397369 Marionette TRACE 352 <- [1,6,null,{}]
17:33:17 INFO - 1499128397370 Marionette DEBUG Closed connection 352
17:33:17 INFO - 1499128397371 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49806
17:33:17 INFO - 1499128397372 Marionette DEBUG Closed connection 353
17:33:17 INFO - 1499128397373 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49807
17:33:17 INFO - 1499128397374 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:17 INFO - 1499128397375 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:17 INFO - 1499128397378 Marionette DEBUG Register listener.js for window 4294967297
17:33:17 INFO - 1499128397382 Marionette TRACE 354 <- [1,1,null,{"sessionId":"5a26e4a3-6296-4567-9cc4-1178951e6cea","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:17 INFO - 1499128397383 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
17:33:17 INFO - 1499128397384 Marionette TRACE 354 <- [1,2,null,{}]
17:33:17 INFO - 1499128397385 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:17 INFO - 1499128397385 Marionette TRACE 354 <- [1,3,null,{}]
17:33:17 INFO - 1499128397386 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:17 INFO - 1499128397387 Marionette TRACE 354 <- [1,4,null,{}]
17:33:17 INFO - 1499128397389 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}]
17:33:17 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499128397392 Marionette TRACE 354 <- [1,5,null,{"value":null}]
17:33:17 INFO - 1499128397393 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
17:33:17 INFO - 1499128397395 Marionette TRACE 354 <- [1,6,null,{}]
17:33:17 INFO - 1499128397397 Marionette DEBUG Closed connection 354
17:33:17 INFO - 1499128397397 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49808
17:33:17 INFO - 1499128397398 Marionette DEBUG Closed connection 355
17:33:17 INFO - 1499128397399 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49809
17:33:17 INFO - 1499128397400 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:17 INFO - 1499128397401 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:17 INFO - 1499128397404 Marionette DEBUG Register listener.js for window 4294967297
17:33:17 INFO - 1499128397408 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d5e8aa59-d237-48d4-9c80-31ea42117707","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:17 INFO - 1499128397409 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
17:33:17 INFO - 1499128397410 Marionette TRACE 356 <- [1,2,null,{}]
17:33:17 INFO - 1499128397412 Marionette DEBUG Closed connection 356
17:33:17 INFO - 1499128397413 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49810
17:33:17 INFO - 1499128397414 Marionette DEBUG Closed connection 357
17:33:17 INFO - 1499128397415 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49811
17:33:17 INFO - 1499128397416 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:17 INFO - 1499128397417 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:17 INFO - 1499128397420 Marionette DEBUG Register listener.js for window 4294967297
17:33:17 INFO - 1499128397423 Marionette TRACE 358 <- [1,1,null,{"sessionId":"8295dd5b-891c-48cb-b9ca-481ab2861571","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:17 INFO - 1499128397425 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49364/clicks.html"}]
17:33:17 INFO - 1499128397427 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49364/hidden.html"
17:33:17 INFO - 1499128397436 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49364/hidden.html"
17:33:17 INFO - 1499128397437 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49364/hidden.html"
17:33:17 INFO - 1499128397445 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49364/clicks.html"
17:33:17 INFO - 1499128397451 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49364/clicks.html"
17:33:17 INFO - 1499128397464 Marionette TRACE 358 <- [1,2,null,{}]
17:33:17 INFO - 1499128397478 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
17:33:17 INFO - 1499128397481 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d46811d5-0482-418c-8e56-2bda801f988d","ELEMENT":"d46811d5-0482-418c-8e56-2bda801f988d"}}]
17:33:17 INFO - 1499128397482 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"d46811d5-0482-418c-8e56-2bda801f988d"}]
17:33:17 INFO - 1499128397526 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49364/clicks.html"
17:33:17 INFO - 1499128397536 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49364/clicks.html"
17:33:17 INFO - 1499128397537 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49364/clicks.html"
17:33:17 INFO - 1499128397547 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49364/test.html"
17:33:17 INFO - 1499128397554 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49364/test.html"
17:33:17 INFO - 1499128397557 Marionette TRACE 358 <- [1,4,null,{}]
17:33:17 INFO - 1499128397563 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
17:33:17 INFO - 1499128397566 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"923ae9ce-b704-447a-984c-3cb3911a6864","ELEMENT":"923ae9ce-b704-447a-984c-3cb3911a6864"}}]
17:33:17 INFO - 1499128397567 Marionette TRACE 358 -> [0,6,"getTitle",{}]
17:33:17 INFO - 1499128397574 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
17:33:17 INFO - 1499128397576 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}]
17:33:17 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499128397579 Marionette TRACE 358 <- [1,7,null,{"value":null}]
17:33:17 INFO - 1499128397580 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
17:33:17 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 212ms
17:33:17 INFO - TEST-START | test_click.py TestClick.test_container_element
17:33:17 INFO - 1499128397582 Marionette TRACE 358 <- [1,8,null,{}]
17:33:17 INFO - 1499128397584 Marionette DEBUG Closed connection 358
17:33:17 INFO - 1499128397585 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49814
17:33:17 INFO - 1499128397586 Marionette DEBUG Closed connection 359
17:33:17 INFO - 1499128397586 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49815
17:33:17 INFO - 1499128397588 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:17 INFO - 1499128397588 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:17 INFO - 1499128397591 Marionette DEBUG Register listener.js for window 4294967297
17:33:17 INFO - 1499128397595 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3edc2d13-0cba-420a-a141-7d987d6b80d0","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:17 INFO - 1499128397596 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
17:33:17 INFO - 1499128397597 Marionette TRACE 360 <- [1,2,null,{}]
17:33:17 INFO - 1499128397598 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:17 INFO - 1499128397599 Marionette TRACE 360 <- [1,3,null,{}]
17:33:17 INFO - 1499128397600 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:17 INFO - 1499128397600 Marionette TRACE 360 <- [1,4,null,{}]
17:33:17 INFO - 1499128397602 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}]
17:33:17 INFO - TEST-START: test_click.py TestClick.test_container_element1499128397605 Marionette TRACE 360 <- [1,5,null,{"value":null}]
17:33:17 INFO - 1499128397606 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
17:33:17 INFO - 1499128397608 Marionette TRACE 360 <- [1,6,null,{}]
17:33:17 INFO - 1499128397609 Marionette DEBUG Closed connection 360
17:33:17 INFO - 1499128397610 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49816
17:33:17 INFO - 1499128397611 Marionette DEBUG Closed connection 361
17:33:17 INFO - 1499128397612 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49817
17:33:17 INFO - 1499128397613 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:17 INFO - 1499128397614 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:17 INFO - 1499128397617 Marionette DEBUG Register listener.js for window 4294967297
17:33:17 INFO - 1499128397621 Marionette TRACE 362 <- [1,1,null,{"sessionId":"9b4e7188-5114-42a7-8db3-166a6e0a3103","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:17 INFO - 1499128397622 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
17:33:17 INFO - 1499128397624 Marionette TRACE 362 <- [1,2,null,{}]
17:33:17 INFO - 1499128397626 Marionette DEBUG Closed connection 362
17:33:17 INFO - 1499128397626 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49818
17:33:17 INFO - 1499128397627 Marionette DEBUG Closed connection 363
17:33:17 INFO - 1499128397628 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49819
17:33:17 INFO - 1499128397629 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:17 INFO - 1499128397630 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:17 INFO - 1499128397633 Marionette DEBUG Register listener.js for window 4294967297
17:33:17 INFO - 1499128397637 Marionette TRACE 364 <- [1,1,null,{"sessionId":"6b4ecd85-b84b-494e-b9ab-0d7d2bbf485d","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:17 INFO - 1499128397638 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"}]
17:33:17 INFO - 1499128397641 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49364/test.html"
17:33:17 INFO - 1499128397644 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49364/test.html"
17:33:17 INFO - 1499128397644 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49364/test.html"
17:33:17 INFO - 1499128397657 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"
17:33:17 INFO - 1499128397658 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"
17:33:17 INFO - 1499128397673 Marionette TRACE 364 <- [1,2,null,{}]
17:33:17 INFO - 1499128397678 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:33:17 INFO - 1499128397683 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7ecdc415-fe64-4680-ba1c-eb2ecb604684","ELEMENT":"7ecdc415-fe64-4680-ba1c-eb2ecb604684"}}]
17:33:17 INFO - 1499128397689 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"7ecdc415-fe64-4680-ba1c-eb2ecb604684"}]
17:33:17 INFO - 1499128397940 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:33:17 INFO - 1499128397941 Marionette TRACE 364 <- [1,4,null,{}]
17:33:17 INFO - 1499128397942 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"7ecdc415-fe64-4680-ba1c-eb2ecb604684","name":"selected"}]
17:33:17 INFO - 1499128397948 Marionette TRACE 364 <- [1,5,null,{"value":true}]
17:33:17 INFO - 1499128397949 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}]
17:33:17 INFO - TEST-END: test_click.py TestClick.test_container_element1499128397953 Marionette TRACE 364 <- [1,6,null,{"value":null}]
17:33:17 INFO - 1499128397954 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
17:33:17 INFO - 1499128397955 Marionette TRACE 364 <- [1,7,null,{}]
17:33:17 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 373ms
17:33:17 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
17:33:17 INFO - 1499128397957 Marionette DEBUG Closed connection 364
17:33:17 INFO - 1499128397958 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49820
17:33:17 INFO - 1499128397959 Marionette DEBUG Closed connection 365
17:33:17 INFO - 1499128397960 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49821
17:33:17 INFO - 1499128397961 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:17 INFO - 1499128397962 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:17 INFO - 1499128397965 Marionette DEBUG Register listener.js for window 4294967297
17:33:17 INFO - 1499128397968 Marionette TRACE 366 <- [1,1,null,{"sessionId":"3b5f1ccf-3561-4839-8751-c25d25e711f2","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:17 INFO - 1499128397970 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
17:33:17 INFO - 1499128397970 Marionette TRACE 366 <- [1,2,null,{}]
17:33:17 INFO - 1499128397971 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:17 INFO - 1499128397972 Marionette TRACE 366 <- [1,3,null,{}]
17:33:17 INFO - 1499128397973 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:17 INFO - 1499128397973 Marionette TRACE 366 <- [1,4,null,{}]
17:33:17 INFO - 1499128397975 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}]
17:33:17 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499128397978 Marionette TRACE 366 <- [1,5,null,{"value":null}]
17:33:17 INFO - 1499128397979 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
17:33:17 INFO - 1499128397981 Marionette TRACE 366 <- [1,6,null,{}]
17:33:17 INFO - 1499128397983 Marionette DEBUG Closed connection 366
17:33:17 INFO - 1499128397983 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49822
17:33:17 INFO - 1499128397984 Marionette DEBUG Closed connection 367
17:33:18 INFO - 1499128397985 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49823
17:33:18 INFO - 1499128397986 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:18 INFO - 1499128397987 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:18 INFO - 1499128397990 Marionette DEBUG Register listener.js for window 4294967297
17:33:18 INFO - 1499128397994 Marionette TRACE 368 <- [1,1,null,{"sessionId":"73a6f5ea-1c40-4e9e-9217-c63ebfb4f39e","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:18 INFO - 1499128397995 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
17:33:18 INFO - 1499128397997 Marionette TRACE 368 <- [1,2,null,{}]
17:33:18 INFO - 1499128397998 Marionette DEBUG Closed connection 368
17:33:18 INFO - 1499128397999 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49824
17:33:18 INFO - 1499128398000 Marionette DEBUG Closed connection 369
17:33:18 INFO - 1499128398001 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49825
17:33:18 INFO - 1499128398002 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:18 INFO - 1499128398003 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:18 INFO - 1499128398006 Marionette DEBUG Register listener.js for window 4294967297
17:33:18 INFO - 1499128398010 Marionette TRACE 370 <- [1,1,null,{"sessionId":"9576c153-58a1-466c-8bbf-bbb1452cdf9a","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:18 INFO - 1499128398011 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"}]
17:33:18 INFO - 1499128398013 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"
17:33:18 INFO - 1499128398015 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"
17:33:18 INFO - 1499128398016 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"
17:33:18 INFO - 1499128398023 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"
17:33:18 INFO - 1499128398028 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"
17:33:18 INFO - 1499128398033 Marionette TRACE 370 <- [1,2,null,{}]
17:33:18 INFO - 1499128398044 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:33:18 INFO - 1499128398048 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d8aae781-505a-4e1f-9376-239dc71297c7","ELEMENT":"d8aae781-505a-4e1f-9376-239dc71297c7"}}]
17:33:18 INFO - 1499128398049 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"d8aae781-505a-4e1f-9376-239dc71297c7"}]
17:33:18 INFO - 1499128398304 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:33:18 INFO - 1499128398305 Marionette TRACE 370 <- [1,4,null,{}]
17:33:18 INFO - 1499128398306 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"d8aae781-505a-4e1f-9376-239dc71297c7","name":"selected"}]
17:33:18 INFO - 1499128398311 Marionette TRACE 370 <- [1,5,null,{"value":true}]
17:33:18 INFO - 1499128398313 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}]
17:33:18 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499128398316 Marionette TRACE 370 <- [1,6,null,{"value":null}]
17:33:18 INFO - 1499128398317 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
17:33:18 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 363ms
17:33:18 INFO - TEST-START | test_click.py TestClick.test_css_transforms
17:33:18 INFO - 1499128398319 Marionette TRACE 370 <- [1,7,null,{}]
17:33:18 INFO - 1499128398321 Marionette DEBUG Closed connection 370
17:33:18 INFO - 1499128398321 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49826
17:33:18 INFO - 1499128398322 Marionette DEBUG Closed connection 371
17:33:18 INFO - 1499128398323 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49827
17:33:18 INFO - 1499128398324 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:18 INFO - 1499128398325 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:18 INFO - 1499128398328 Marionette DEBUG Register listener.js for window 4294967297
17:33:18 INFO - 1499128398331 Marionette TRACE 372 <- [1,1,null,{"sessionId":"748351b9-109b-434b-831b-90330c61556f","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:18 INFO - 1499128398333 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
17:33:18 INFO - 1499128398333 Marionette TRACE 372 <- [1,2,null,{}]
17:33:18 INFO - 1499128398334 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:18 INFO - 1499128398335 Marionette TRACE 372 <- [1,3,null,{}]
17:33:18 INFO - 1499128398336 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:18 INFO - 1499128398336 Marionette TRACE 372 <- [1,4,null,{}]
17:33:18 INFO - 1499128398338 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}]
17:33:18 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499128398342 Marionette TRACE 372 <- [1,5,null,{"value":null}]
17:33:18 INFO - 1499128398343 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
17:33:18 INFO - 1499128398344 Marionette TRACE 372 <- [1,6,null,{}]
17:33:18 INFO - 1499128398346 Marionette DEBUG Closed connection 372
17:33:18 INFO - 1499128398347 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49828
17:33:18 INFO - 1499128398348 Marionette DEBUG Closed connection 373
17:33:18 INFO - 1499128398349 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49829
17:33:18 INFO - 1499128398350 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:18 INFO - 1499128398350 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:18 INFO - 1499128398353 Marionette DEBUG Register listener.js for window 4294967297
17:33:18 INFO - 1499128398357 Marionette TRACE 374 <- [1,1,null,{"sessionId":"a63033be-9636-43ef-be2a-a13b9d5b228e","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:18 INFO - 1499128398359 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
17:33:18 INFO - 1499128398360 Marionette TRACE 374 <- [1,2,null,{}]
17:33:18 INFO - 1499128398362 Marionette DEBUG Closed connection 374
17:33:18 INFO - 1499128398363 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49830
17:33:18 INFO - 1499128398364 Marionette DEBUG Closed connection 375
17:33:18 INFO - 1499128398365 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49831
17:33:18 INFO - 1499128398366 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:18 INFO - 1499128398367 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:18 INFO - 1499128398370 Marionette DEBUG Register listener.js for window 4294967297
17:33:18 INFO - 1499128398374 Marionette TRACE 376 <- [1,1,null,{"sessionId":"12e111f5-d9e1-4c2d-aaf6-0b233ceb3e12","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:18 INFO - 1499128398375 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"}]
17:33:18 INFO - 1499128398377 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"
17:33:18 INFO - 1499128398379 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"
17:33:18 INFO - 1499128398380 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"
17:33:18 INFO - 1499128398389 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"
17:33:18 INFO - 1499128398391 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"
17:33:18 INFO - 1499128398405 Marionette TRACE 376 <- [1,2,null,{}]
17:33:18 INFO - 1499128398409 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
17:33:18 INFO - 1499128398414 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"801b63f7-1312-4698-8a2a-83ab3815414d","ELEMENT":"801b63f7-1312-4698-8a2a-83ab3815414d"}}]
17:33:18 INFO - 1499128398416 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"801b63f7-1312-4698-8a2a-83ab3815414d"}]
17:33:18 INFO - 1499128398658 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:33:18 INFO - 1499128398659 Marionette TRACE 376 <- [1,4,null,{}]
17:33:18 INFO - 1499128398661 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}]
17:33:18 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499128398664 Marionette TRACE 376 <- [1,5,null,{"value":null}]
17:33:18 INFO - 1499128398666 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
17:33:18 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 348ms
17:33:18 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
17:33:18 INFO - 1499128398667 Marionette TRACE 376 <- [1,6,null,{}]
17:33:18 INFO - 1499128398669 Marionette DEBUG Closed connection 376
17:33:18 INFO - 1499128398670 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49832
17:33:18 INFO - 1499128398671 Marionette DEBUG Closed connection 377
17:33:18 INFO - 1499128398672 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49833
17:33:18 INFO - 1499128398673 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:18 INFO - 1499128398673 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:18 INFO - 1499128398677 Marionette DEBUG Register listener.js for window 4294967297
17:33:18 INFO - 1499128398680 Marionette TRACE 378 <- [1,1,null,{"sessionId":"6bd9b5f6-c12d-4e26-b066-2e0e16c63da6","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:18 INFO - 1499128398682 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
17:33:18 INFO - 1499128398682 Marionette TRACE 378 <- [1,2,null,{}]
17:33:18 INFO - 1499128398683 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:18 INFO - 1499128398684 Marionette TRACE 378 <- [1,3,null,{}]
17:33:18 INFO - 1499128398685 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:18 INFO - 1499128398685 Marionette TRACE 378 <- [1,4,null,{}]
17:33:18 INFO - 1499128398687 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}]
17:33:18 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499128398691 Marionette TRACE 378 <- [1,5,null,{"value":null}]
17:33:18 INFO - 1499128398692 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
17:33:18 INFO - 1499128398694 Marionette TRACE 378 <- [1,6,null,{}]
17:33:18 INFO - 1499128398696 Marionette DEBUG Closed connection 378
17:33:18 INFO - 1499128398696 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49834
17:33:18 INFO - 1499128398697 Marionette DEBUG Closed connection 379
17:33:18 INFO - 1499128398698 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49835
17:33:18 INFO - 1499128398699 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:18 INFO - 1499128398700 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:18 INFO - 1499128398703 Marionette DEBUG Register listener.js for window 4294967297
17:33:18 INFO - 1499128398707 Marionette TRACE 380 <- [1,1,null,{"sessionId":"81863116-09c5-4cf5-8a5e-7746fe6cd26d","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:18 INFO - 1499128398708 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
17:33:18 INFO - 1499128398710 Marionette TRACE 380 <- [1,2,null,{}]
17:33:18 INFO - 1499128398711 Marionette DEBUG Closed connection 380
17:33:18 INFO - 1499128398712 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49836
17:33:18 INFO - 1499128398713 Marionette DEBUG Closed connection 381
17:33:18 INFO - 1499128398714 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49837
17:33:18 INFO - 1499128398715 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:18 INFO - 1499128398716 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:18 INFO - 1499128398719 Marionette DEBUG Register listener.js for window 4294967297
17:33:18 INFO - 1499128398722 Marionette TRACE 382 <- [1,1,null,{"sessionId":"9c5eca66-ec8a-4973-ab2c-842dc87f1b9d","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:18 INFO - 1499128398724 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"}]
17:33:18 INFO - 1499128398726 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"
17:33:18 INFO - 1499128398728 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"
17:33:18 INFO - 1499128398728 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"
17:33:18 INFO - 1499128398738 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"
17:33:18 INFO - 1499128398740 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"
17:33:18 INFO - 1499128398748 Marionette TRACE 382 <- [1,2,null,{}]
17:33:18 INFO - 1499128398757 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
17:33:18 INFO - 1499128398763 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"68f67526-c77d-4d86-8843-4254e53a2efe","ELEMENT":"68f67526-c77d-4d86-8843-4254e53a2efe"}}]
17:33:18 INFO - 1499128398764 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"68f67526-c77d-4d86-8843-4254e53a2efe"}]
17:33:19 INFO - 1499128399015 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:33:19 INFO - 1499128399016 Marionette TRACE 382 <- [1,4,null,{}]
17:33:19 INFO - 1499128399018 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}]
17:33:19 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499128399022 Marionette TRACE 382 <- [1,5,null,{"value":null}]
17:33:19 INFO - 1499128399023 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
17:33:19 INFO - 1499128399024 Marionette TRACE 382 <- [1,6,null,{}]
17:33:19 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 357ms
17:33:19 INFO - TEST-START | test_click.py TestClick.test_input_file
17:33:19 INFO - 1499128399026 Marionette DEBUG Closed connection 382
17:33:19 INFO - 1499128399027 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49840
17:33:19 INFO - 1499128399028 Marionette DEBUG Closed connection 383
17:33:19 INFO - 1499128399029 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49841
17:33:19 INFO - 1499128399030 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:19 INFO - 1499128399030 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:19 INFO - 1499128399034 Marionette DEBUG Register listener.js for window 4294967297
17:33:19 INFO - 1499128399047 Marionette TRACE 384 <- [1,1,null,{"sessionId":"e16ffb51-b936-475d-9959-47d156f0491f","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:19 INFO - 1499128399049 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
17:33:19 INFO - 1499128399049 Marionette TRACE 384 <- [1,2,null,{}]
17:33:19 INFO - 1499128399050 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:19 INFO - 1499128399051 Marionette TRACE 384 <- [1,3,null,{}]
17:33:19 INFO - 1499128399052 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:19 INFO - 1499128399052 Marionette TRACE 384 <- [1,4,null,{}]
17:33:19 INFO - 1499128399054 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}]
17:33:19 INFO - TEST-START: test_click.py TestClick.test_input_file1499128399057 Marionette TRACE 384 <- [1,5,null,{"value":null}]
17:33:19 INFO - 1499128399058 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
17:33:19 INFO - 1499128399060 Marionette TRACE 384 <- [1,6,null,{}]
17:33:19 INFO - 1499128399062 Marionette DEBUG Closed connection 384
17:33:19 INFO - 1499128399063 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49842
17:33:19 INFO - 1499128399064 Marionette DEBUG Closed connection 385
17:33:19 INFO - 1499128399065 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49843
17:33:19 INFO - 1499128399066 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:19 INFO - 1499128399066 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:19 INFO - 1499128399069 Marionette DEBUG Register listener.js for window 4294967297
17:33:19 INFO - 1499128399073 Marionette TRACE 386 <- [1,1,null,{"sessionId":"f763b66e-7b89-481d-ad0f-83b62715b83e","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:19 INFO - 1499128399074 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
17:33:19 INFO - 1499128399076 Marionette TRACE 386 <- [1,2,null,{}]
17:33:19 INFO - 1499128399078 Marionette DEBUG Closed connection 386
17:33:19 INFO - 1499128399078 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49844
17:33:19 INFO - 1499128399079 Marionette DEBUG Closed connection 387
17:33:19 INFO - 1499128399080 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49845
17:33:19 INFO - 1499128399081 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:19 INFO - 1499128399082 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:19 INFO - 1499128399085 Marionette DEBUG Register listener.js for window 4294967297
17:33:19 INFO - 1499128399089 Marionette TRACE 388 <- [1,1,null,{"sessionId":"d39f59f7-6fe6-4f32-8b9b-d346941af36d","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:19 INFO - 1499128399090 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
17:33:19 INFO - 1499128399092 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"
17:33:19 INFO - 1499128399095 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"
17:33:19 INFO - 1499128399095 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"
17:33:19 INFO - 1499128399111 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:33:19 INFO - 1499128399112 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:33:19 INFO - 1499128399120 Marionette TRACE 388 <- [1,2,null,{}]
17:33:19 INFO - 1499128399124 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
17:33:19 INFO - 1499128399128 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"daa935ea-6478-4e73-a0a8-ddedc988c4d2","ELEMENT":"daa935ea-6478-4e73-a0a8-ddedc988c4d2"}}]
17:33:19 INFO - 1499128399130 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"daa935ea-6478-4e73-a0a8-ddedc988c4d2"}]
17:33:19 INFO - 1499128399160 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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
17:33:19 INFO - 1499128399162 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}]
17:33:19 INFO - TEST-END: test_click.py TestClick.test_input_file1499128399166 Marionette TRACE 388 <- [1,5,null,{"value":null}]
17:33:19 INFO - 1499128399167 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
17:33:19 INFO - 1499128399168 Marionette TRACE 388 <- [1,6,null,{}]
17:33:19 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 144ms
17:33:19 INFO - TEST-START | test_click.py TestClick.test_obscured_element
17:33:19 INFO - 1499128399170 Marionette DEBUG Closed connection 388
17:33:19 INFO - 1499128399171 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49846
17:33:19 INFO - 1499128399172 Marionette DEBUG Closed connection 389
17:33:19 INFO - 1499128399173 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49847
17:33:19 INFO - 1499128399174 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:19 INFO - 1499128399175 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:19 INFO - 1499128399178 Marionette DEBUG Register listener.js for window 4294967297
17:33:19 INFO - 1499128399181 Marionette TRACE 390 <- [1,1,null,{"sessionId":"636acb40-0dc5-4b04-8661-c64291df65b4","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:19 INFO - 1499128399183 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
17:33:19 INFO - 1499128399183 Marionette TRACE 390 <- [1,2,null,{}]
17:33:19 INFO - 1499128399184 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:19 INFO - 1499128399185 Marionette TRACE 390 <- [1,3,null,{}]
17:33:19 INFO - 1499128399186 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:19 INFO - 1499128399186 Marionette TRACE 390 <- [1,4,null,{}]
17:33:19 INFO - 1499128399188 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}]
17:33:19 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499128399191 Marionette TRACE 390 <- [1,5,null,{"value":null}]
17:33:19 INFO - 1499128399192 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
17:33:19 INFO - 1499128399194 Marionette TRACE 390 <- [1,6,null,{}]
17:33:19 INFO - 1499128399196 Marionette DEBUG Closed connection 390
17:33:19 INFO - 1499128399196 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49848
17:33:19 INFO - 1499128399197 Marionette DEBUG Closed connection 391
17:33:19 INFO - 1499128399198 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49849
17:33:19 INFO - 1499128399199 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:19 INFO - 1499128399200 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:19 INFO - 1499128399203 Marionette DEBUG Register listener.js for window 4294967297
17:33:19 INFO - 1499128399206 Marionette TRACE 392 <- [1,1,null,{"sessionId":"3341145d-f68a-4dc6-8aaa-07bbda9b06d7","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:19 INFO - 1499128399208 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
17:33:19 INFO - 1499128399209 Marionette TRACE 392 <- [1,2,null,{}]
17:33:19 INFO - 1499128399211 Marionette DEBUG Closed connection 392
17:33:19 INFO - 1499128399212 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49850
17:33:19 INFO - 1499128399213 Marionette DEBUG Closed connection 393
17:33:19 INFO - 1499128399214 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49851
17:33:19 INFO - 1499128399215 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:19 INFO - 1499128399216 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:19 INFO - 1499128399219 Marionette DEBUG Register listener.js for window 4294967297
17:33:19 INFO - 1499128399222 Marionette TRACE 394 <- [1,1,null,{"sessionId":"d5b743a1-b37c-4932-8e2b-9e7b619f01c7","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:19 INFO - 1499128399224 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"}]
17:33:19 INFO - 1499128399226 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:33:19 INFO - 1499128399228 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:33:19 INFO - 1499128399228 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:33:19 INFO - 1499128399238 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"
17:33:19 INFO - 1499128399240 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"
17:33:19 INFO - 1499128399249 Marionette TRACE 394 <- [1,2,null,{}]
17:33:19 INFO - 1499128399259 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
17:33:19 INFO - 1499128399264 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e8a05d4d-6712-4144-a7fc-d868139fb1dc","ELEMENT":"e8a05d4d-6712-4144-a7fc-d868139fb1dc"}}]
17:33:19 INFO - 1499128399265 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
17:33:19 INFO - 1499128399269 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4a07ea06-2a30-4646-ae82-85d8868e2b8e","ELEMENT":"4a07ea06-2a30-4646-ae82-85d8868e2b8e"}}]
17:33:19 INFO - 1499128399270 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"e8a05d4d-6712-4144-a7fc-d868139fb1dc"}]
17:33:19 INFO - 1499128399511 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:33:19 INFO - 1499128399512 Marionette TRACE 394 <- [1,5,null,{}]
17:33:19 INFO - 1499128399513 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"4a07ea06-2a30-4646-ae82-85d8868e2b8e"}]
17:33:19 INFO - 1499128399532 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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
17:33:19 INFO - 1499128399535 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
17:33:19 INFO - 1499128399538 Marionette TRACE 394 <- [1,7,null,{"value":false}]
17:33:19 INFO - 1499128399540 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}]
17:33:19 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499128399544 Marionette TRACE 394 <- [1,8,null,{"value":null}]
17:33:19 INFO - 1499128399545 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
17:33:19 INFO - 1499128399547 Marionette TRACE 394 <- [1,9,null,{}]
17:33:19 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 378ms
17:33:19 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
17:33:19 INFO - 1499128399548 Marionette DEBUG Closed connection 394
17:33:19 INFO - 1499128399549 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49852
17:33:19 INFO - 1499128399550 Marionette DEBUG Closed connection 395
17:33:19 INFO - 1499128399551 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49853
17:33:19 INFO - 1499128399552 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:19 INFO - 1499128399553 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:19 INFO - 1499128399557 Marionette DEBUG Register listener.js for window 4294967297
17:33:19 INFO - 1499128399560 Marionette TRACE 396 <- [1,1,null,{"sessionId":"1d96f3de-4c35-4283-8a68-86c5d03b9f52","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:19 INFO - 1499128399561 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
17:33:19 INFO - 1499128399562 Marionette TRACE 396 <- [1,2,null,{}]
17:33:19 INFO - 1499128399563 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:33:19 INFO - 1499128399563 Marionette TRACE 396 <- [1,3,null,{}]
17:33:19 INFO - 1499128399564 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
17:33:19 INFO - 1499128399565 Marionette TRACE 396 <- [1,4,null,{}]
17:33:19 INFO - 1499128399567 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}]
17:33:19 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499128399570 Marionette TRACE 396 <- [1,5,null,{"value":null}]
17:33:19 INFO - 1499128399571 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
17:33:19 INFO - 1499128399573 Marionette TRACE 396 <- [1,6,null,{}]
17:33:19 INFO - 1499128399574 Marionette DEBUG Closed connection 396
17:33:19 INFO - 1499128399575 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49854
17:33:19 INFO - 1499128399576 Marionette DEBUG Closed connection 397
17:33:19 INFO - 1499128399577 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49855
17:33:19 INFO - 1499128399578 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:33:19 INFO - 1499128399579 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:19 INFO - 1499128399582 Marionette DEBUG Register listener.js for window 4294967297
17:33:19 INFO - 1499128399586 Marionette TRACE 398 <- [1,1,null,{"sessionId":"531076be-63fa-43f6-b84f-cb8db7ac1450","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:19 INFO - 1499128399587 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
17:33:19 INFO - 1499128399588 Marionette TRACE 398 <- [1,2,null,{}]
17:33:19 INFO - 1499128399590 Marionette DEBUG Closed connection 398
17:33:19 INFO - 1499128399591 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49856
17:33:19 INFO - 1499128399592 Marionette DEBUG Closed connection 399
17:33:19 INFO - 1499128399593 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49857
17:33:19 INFO - 1499128399594 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:33:19 INFO - 1499128399594 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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}
17:33:19 INFO - 1499128399597 Marionette DEBUG Register listener.js for window 4294967297
17:33:19 INFO - 1499128399601 Marionette TRACE 400 <- [1,1,null,{"sessionId":"990db99e-5c3b-4b6e-bde2-f571e3e35142","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":3528,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmprfrxku.mozrunner","moz:accessibilityChecks":false}}]
17:33:19 INFO - 1499128399602 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"}]
17:33:19 INFO - 1499128399605 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"
17:33:19 INFO - 1499128399607 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"
17:33:19 INFO - 1499128399607 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"
17:33:19 INFO - 1499128399615 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"
17:33:19 INFO - 1499128399618 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"
17:33:19 INFO - 1499128399628 Marionette TRACE 400 <- [1,2,null,{}]
17:33:19 INFO - 1499128399633 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
17:33:19 INFO - 1499128399640 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9ee837b7-19e1-4663-be80-fb800eab7445","ELEMENT":"9ee837b7-19e1-4663-be80-fb800eab7445"}}]
17:33:19 INFO - 1499128399651 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"9ee837b7-19e1-4663-be80-fb800eab7445"}]
17:33:19 INFO - 1499128399682 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
17:33:19 INFO - 1499128399684 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"9ee837b7-19e1-4663-be80-fb800eab7445"}]
17:33:19 INFO - 1499128399705 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element