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:1318:5\n"},null]
05:23:30 INFO - 1499257410191 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}]
05:23:30 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499257410193 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:23:30 INFO - 1499257410194 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:23:30 INFO - 1499257410195 Marionette TRACE 352 <- [1,6,null,{}]
05:23:30 INFO - 1499257410197 Marionette DEBUG Closed connection 352
05:23:30 INFO - 1499257410198 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49789
05:23:30 INFO - 1499257410200 Marionette DEBUG Closed connection 353
05:23:30 INFO - 1499257410200 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49790
05:23:30 INFO - 1499257410202 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:30 INFO - 1499257410202 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:30 INFO - 1499257410205 Marionette DEBUG Register listener.js for window 12
05:23:30 INFO - 1499257410207 Marionette TRACE 354 <- [1,1,null,{"sessionId":"9951a67a-79e0-482d-8238-b7eb44a73d7b","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:30 INFO - 1499257410210 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:23:30 INFO - 1499257410210 Marionette TRACE 354 <- [1,2,null,{}]
05:23:30 INFO - 1499257410211 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:23:30 INFO - 1499257410211 Marionette TRACE 354 <- [1,3,null,{}]
05:23:30 INFO - 1499257410212 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:23:30 INFO - 1499257410212 Marionette TRACE 354 <- [1,4,null,{}]
05:23:30 INFO - 1499257410214 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}]
05:23:30 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499257410216 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:23:30 INFO - 1499257410217 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:23:30 INFO - 1499257410218 Marionette TRACE 354 <- [1,6,null,{}]
05:23:30 INFO - 1499257410220 Marionette DEBUG Closed connection 354
05:23:30 INFO - 1499257410221 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49791
05:23:30 INFO - 1499257410221 Marionette DEBUG Closed connection 355
05:23:30 INFO - 1499257410222 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49792
05:23:30 INFO - 1499257410223 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:30 INFO - 1499257410224 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:30 INFO - 1499257410226 Marionette DEBUG Register listener.js for window 12
05:23:30 INFO - 1499257410229 Marionette TRACE 356 <- [1,1,null,{"sessionId":"d1938032-3a04-4851-882d-28fdc5cd9fb5","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:30 INFO - 1499257410230 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:23:30 INFO - 1499257410231 Marionette TRACE 356 <- [1,2,null,{}]
05:23:30 INFO - 1499257410233 Marionette DEBUG Closed connection 356
05:23:30 INFO - 1499257410233 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49793
05:23:30 INFO - 1499257410234 Marionette DEBUG Closed connection 357
05:23:30 INFO - 1499257410235 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49794
05:23:30 INFO - 1499257410236 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:23:30 INFO - 1499257410236 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:30 INFO - 1499257410238 Marionette DEBUG Register listener.js for window 12
05:23:30 INFO - 1499257410241 Marionette TRACE 358 <- [1,1,null,{"sessionId":"78eb0117-96c1-4df8-9432-bc383aca9a91","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:30 INFO - 1499257410242 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49359/clicks.html"}]
05:23:30 INFO - 1499257410243 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49359/hidden.html"
05:23:30 INFO - 1499257410248 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49359/hidden.html"
05:23:30 INFO - 1499257410248 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49359/hidden.html"
05:23:30 INFO - 1499257410264 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49359/clicks.html"
05:23:30 INFO - 1499257410269 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49359/clicks.html"
05:23:30 INFO - 1499257410272 Marionette TRACE 358 <- [1,2,null,{}]
05:23:30 INFO - 1499257410279 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:23:30 INFO - 1499257410281 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dfe5b5e9-ce58-4ed6-9197-2a2119694e3b","ELEMENT":"dfe5b5e9-ce58-4ed6-9197-2a2119694e3b"}}]
05:23:30 INFO - 1499257410283 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"dfe5b5e9-ce58-4ed6-9197-2a2119694e3b"}]
05:23:30 INFO - 1499257410329 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49359/clicks.html"
05:23:30 INFO - 1499257410334 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49359/clicks.html"
05:23:30 INFO - 1499257410334 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49359/clicks.html"
05:23:30 INFO - 1499257410352 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49359/test.html"
05:23:30 INFO - 1499257410355 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49359/test.html"
05:23:30 INFO - 1499257410364 Marionette TRACE 358 <- [1,4,null,{}]
05:23:30 INFO - 1499257410366 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:23:30 INFO - 1499257410369 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"21183950-5e87-4ed8-bc46-5b43d2a83dd7","ELEMENT":"21183950-5e87-4ed8-bc46-5b43d2a83dd7"}}]
05:23:30 INFO - 1499257410370 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:23:30 INFO - 1499257410371 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:23:30 INFO - 1499257410373 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}]
05:23:30 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 183ms
05:23:30 INFO - TEST-START | test_click.py TestClick.test_container_element
05:23:30 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499257410376 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:23:30 INFO - 1499257410377 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:23:30 INFO - 1499257410378 Marionette TRACE 358 <- [1,8,null,{}]
05:23:30 INFO - 1499257410380 Marionette DEBUG Closed connection 358
05:23:30 INFO - 1499257410381 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49797
05:23:30 INFO - 1499257410382 Marionette DEBUG Closed connection 359
05:23:30 INFO - 1499257410382 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49798
05:23:30 INFO - 1499257410383 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:30 INFO - 1499257410384 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:30 INFO - 1499257410386 Marionette DEBUG Register listener.js for window 12
05:23:30 INFO - 1499257410392 Marionette TRACE 360 <- [1,1,null,{"sessionId":"3d1f1359-1b65-47e3-b8bb-0fcb7dc2443e","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:30 INFO - 1499257410393 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:23:30 INFO - 1499257410393 Marionette TRACE 360 <- [1,2,null,{}]
05:23:30 INFO - 1499257410394 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:23:30 INFO - 1499257410395 Marionette TRACE 360 <- [1,3,null,{}]
05:23:30 INFO - 1499257410395 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:23:30 INFO - 1499257410396 Marionette TRACE 360 <- [1,4,null,{}]
05:23:30 INFO - 1499257410397 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}]
05:23:30 INFO - TEST-START: test_click.py TestClick.test_container_element1499257410400 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:23:30 INFO - 1499257410401 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:23:30 INFO - 1499257410402 Marionette TRACE 360 <- [1,6,null,{}]
05:23:30 INFO - 1499257410403 Marionette DEBUG Closed connection 360
05:23:30 INFO - 1499257410405 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49799
05:23:30 INFO - 1499257410406 Marionette DEBUG Closed connection 361
05:23:30 INFO - 1499257410407 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49800
05:23:30 INFO - 1499257410408 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:30 INFO - 1499257410408 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:30 INFO - 1499257410410 Marionette DEBUG Register listener.js for window 12
05:23:30 INFO - 1499257410413 Marionette TRACE 362 <- [1,1,null,{"sessionId":"98be2267-741e-48a1-9466-665d1064b303","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:30 INFO - 1499257410415 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:23:30 INFO - 1499257410416 Marionette TRACE 362 <- [1,2,null,{}]
05:23:30 INFO - 1499257410418 Marionette DEBUG Closed connection 362
05:23:30 INFO - 1499257410418 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49801
05:23:30 INFO - 1499257410419 Marionette DEBUG Closed connection 363
05:23:30 INFO - 1499257410420 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49802
05:23:30 INFO - 1499257410438 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:23:30 INFO - 1499257410439 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:30 INFO - 1499257410441 Marionette DEBUG Register listener.js for window 12
05:23:30 INFO - 1499257410443 Marionette TRACE 364 <- [1,1,null,{"sessionId":"ee424a5b-53c7-47da-b98b-494f1d41bec3","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:30 INFO - 1499257410452 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"}]
05:23:30 INFO - 1499257410453 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49359/test.html"
05:23:30 INFO - 1499257410459 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49359/test.html"
05:23:30 INFO - 1499257410460 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49359/test.html"
05:23:30 INFO - 1499257410484 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"
05:23:30 INFO - 1499257410490 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"
05:23:30 INFO - 1499257410500 Marionette TRACE 364 <- [1,2,null,{}]
05:23:30 INFO - 1499257410501 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:23:30 INFO - 1499257410504 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d16e4469-eba7-40a1-ae34-f3eed6d04132","ELEMENT":"d16e4469-eba7-40a1-ae34-f3eed6d04132"}}]
05:23:30 INFO - 1499257410516 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d16e4469-eba7-40a1-ae34-f3eed6d04132"}]
05:23:30 INFO - 1499257410753 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:23:30 INFO - 1499257410754 Marionette TRACE 364 <- [1,4,null,{}]
05:23:30 INFO - 1499257410755 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"d16e4469-eba7-40a1-ae34-f3eed6d04132","name":"selected"}]
05:23:30 INFO - 1499257410758 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:23:30 INFO - 1499257410760 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}]
05:23:30 INFO - TEST-END: test_click.py TestClick.test_container_element1499257410763 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:23:30 INFO - 1499257410764 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:23:30 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 388ms
05:23:30 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:23:30 INFO - 1499257410766 Marionette TRACE 364 <- [1,7,null,{}]
05:23:30 INFO - 1499257410767 Marionette DEBUG Closed connection 364
05:23:30 INFO - 1499257410768 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49803
05:23:30 INFO - 1499257410769 Marionette DEBUG Closed connection 365
05:23:30 INFO - 1499257410770 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49804
05:23:30 INFO - 1499257410771 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:30 INFO - 1499257410771 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:30 INFO - 1499257410773 Marionette DEBUG Register listener.js for window 12
05:23:30 INFO - 1499257410776 Marionette TRACE 366 <- [1,1,null,{"sessionId":"6dda1b83-7fcd-4abe-b057-4e5c1a7f2f14","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:30 INFO - 1499257410777 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:23:30 INFO - 1499257410778 Marionette TRACE 366 <- [1,2,null,{}]
05:23:30 INFO - 1499257410779 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:23:30 INFO - 1499257410779 Marionette TRACE 366 <- [1,3,null,{}]
05:23:30 INFO - 1499257410780 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:23:30 INFO - 1499257410780 Marionette TRACE 366 <- [1,4,null,{}]
05:23:30 INFO - 1499257410782 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}]
05:23:30 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499257410784 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:23:30 INFO - 1499257410785 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:23:30 INFO - 1499257410786 Marionette TRACE 366 <- [1,6,null,{}]
05:23:30 INFO - 1499257410788 Marionette DEBUG Closed connection 366
05:23:30 INFO - 1499257410789 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49805
05:23:30 INFO - 1499257410789 Marionette DEBUG Closed connection 367
05:23:30 INFO - 1499257410790 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49806
05:23:30 INFO - 1499257410791 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:30 INFO - 1499257410791 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:30 INFO - 1499257410793 Marionette DEBUG Register listener.js for window 12
05:23:30 INFO - 1499257410796 Marionette TRACE 368 <- [1,1,null,{"sessionId":"100635cf-cf4b-436b-9be8-da5162534ad5","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:30 INFO - 1499257410797 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:23:30 INFO - 1499257410798 Marionette TRACE 368 <- [1,2,null,{}]
05:23:30 INFO - 1499257410799 Marionette DEBUG Closed connection 368
05:23:30 INFO - 1499257410800 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49807
05:23:30 INFO - 1499257410801 Marionette DEBUG Closed connection 369
05:23:30 INFO - 1499257410802 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49808
05:23:30 INFO - 1499257410802 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:23:30 INFO - 1499257410803 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:30 INFO - 1499257410805 Marionette DEBUG Register listener.js for window 12
05:23:30 INFO - 1499257410807 Marionette TRACE 370 <- [1,1,null,{"sessionId":"07754e80-afef-468d-8e26-861bd82dbee3","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:30 INFO - 1499257410808 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"}]
05:23:30 INFO - 1499257410810 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"
05:23:30 INFO - 1499257410812 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"
05:23:30 INFO - 1499257410812 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"
05:23:30 INFO - 1499257410828 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"
05:23:30 INFO - 1499257410829 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"
05:23:30 INFO - 1499257410832 Marionette TRACE 370 <- [1,2,null,{}]
05:23:30 INFO - 1499257410833 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:23:30 INFO - 1499257410835 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"322047ba-0c0a-4a06-8cdc-bf1581f915be","ELEMENT":"322047ba-0c0a-4a06-8cdc-bf1581f915be"}}]
05:23:30 INFO - 1499257410836 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"322047ba-0c0a-4a06-8cdc-bf1581f915be"}]
05:23:31 INFO - 1499257411085 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:23:31 INFO - 1499257411085 Marionette TRACE 370 <- [1,4,null,{}]
05:23:31 INFO - 1499257411086 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"322047ba-0c0a-4a06-8cdc-bf1581f915be","name":"selected"}]
05:23:31 INFO - 1499257411093 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:23:31 INFO - 1499257411095 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}]
05:23:31 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499257411097 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:23:31 INFO - 1499257411098 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:23:31 INFO - 1499257411099 Marionette TRACE 370 <- [1,7,null,{}]
05:23:31 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 333ms
05:23:31 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:23:31 INFO - 1499257411101 Marionette DEBUG Closed connection 370
05:23:31 INFO - 1499257411102 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49809
05:23:31 INFO - 1499257411103 Marionette DEBUG Closed connection 371
05:23:31 INFO - 1499257411103 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49810
05:23:31 INFO - 1499257411104 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:31 INFO - 1499257411105 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:31 INFO - 1499257411107 Marionette DEBUG Register listener.js for window 12
05:23:31 INFO - 1499257411110 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ea541dd2-7e8f-4518-ae6f-040f268af774","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:31 INFO - 1499257411111 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:23:31 INFO - 1499257411111 Marionette TRACE 372 <- [1,2,null,{}]
05:23:31 INFO - 1499257411112 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:23:31 INFO - 1499257411112 Marionette TRACE 372 <- [1,3,null,{}]
05:23:31 INFO - 1499257411113 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:23:31 INFO - 1499257411114 Marionette TRACE 372 <- [1,4,null,{}]
05:23:31 INFO - 1499257411115 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}]
05:23:31 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499257411118 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:23:31 INFO - 1499257411118 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:23:31 INFO - 1499257411119 Marionette TRACE 372 <- [1,6,null,{}]
05:23:31 INFO - 1499257411121 Marionette DEBUG Closed connection 372
05:23:31 INFO - 1499257411122 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49811
05:23:31 INFO - 1499257411122 Marionette DEBUG Closed connection 373
05:23:31 INFO - 1499257411123 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49812
05:23:31 INFO - 1499257411124 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:31 INFO - 1499257411125 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:31 INFO - 1499257411126 Marionette DEBUG Register listener.js for window 12
05:23:31 INFO - 1499257411129 Marionette TRACE 374 <- [1,1,null,{"sessionId":"dc6f9fa3-43d5-48ba-bcfe-5dff0f4f7729","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:31 INFO - 1499257411130 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:23:31 INFO - 1499257411131 Marionette TRACE 374 <- [1,2,null,{}]
05:23:31 INFO - 1499257411133 Marionette DEBUG Closed connection 374
05:23:31 INFO - 1499257411134 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49813
05:23:31 INFO - 1499257411134 Marionette DEBUG Closed connection 375
05:23:31 INFO - 1499257411135 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49814
05:23:31 INFO - 1499257411136 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:23:31 INFO - 1499257411136 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:31 INFO - 1499257411138 Marionette DEBUG Register listener.js for window 12
05:23:31 INFO - 1499257411141 Marionette TRACE 376 <- [1,1,null,{"sessionId":"7cb0b89c-d640-404e-a65d-7adf7ce74a2a","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:31 INFO - 1499257411142 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"}]
05:23:31 INFO - 1499257411144 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"
05:23:31 INFO - 1499257411146 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"
05:23:31 INFO - 1499257411147 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"
05:23:31 INFO - 1499257411162 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"
05:23:31 INFO - 1499257411165 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"
05:23:31 INFO - 1499257411167 Marionette TRACE 376 <- [1,2,null,{}]
05:23:31 INFO - 1499257411168 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:23:31 INFO - 1499257411176 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bbf59506-617c-4cc5-954d-5f3a19545166","ELEMENT":"bbf59506-617c-4cc5-954d-5f3a19545166"}}]
05:23:31 INFO - 1499257411178 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"bbf59506-617c-4cc5-954d-5f3a19545166"}]
05:23:31 INFO - 1499257411412 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:23:31 INFO - 1499257411412 Marionette TRACE 376 <- [1,4,null,{}]
05:23:31 INFO - 1499257411414 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}]
05:23:31 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499257411417 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:23:31 INFO - 1499257411417 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:23:31 INFO - 1499257411419 Marionette TRACE 376 <- [1,6,null,{}]
05:23:31 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 320ms
05:23:31 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:23:31 INFO - 1499257411420 Marionette DEBUG Closed connection 376
05:23:31 INFO - 1499257411421 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49815
05:23:31 INFO - 1499257411422 Marionette DEBUG Closed connection 377
05:23:31 INFO - 1499257411423 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49816
05:23:31 INFO - 1499257411424 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:31 INFO - 1499257411424 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:31 INFO - 1499257411426 Marionette DEBUG Register listener.js for window 12
05:23:31 INFO - 1499257411429 Marionette TRACE 378 <- [1,1,null,{"sessionId":"166033d9-2d7c-43d3-b89a-e5e1688c6e0f","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:31 INFO - 1499257411430 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:23:31 INFO - 1499257411430 Marionette TRACE 378 <- [1,2,null,{}]
05:23:31 INFO - 1499257411431 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:23:31 INFO - 1499257411432 Marionette TRACE 378 <- [1,3,null,{}]
05:23:31 INFO - 1499257411432 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:23:31 INFO - 1499257411433 Marionette TRACE 378 <- [1,4,null,{}]
05:23:31 INFO - 1499257411434 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}]
05:23:31 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499257411437 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:23:31 INFO - 1499257411438 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:23:31 INFO - 1499257411439 Marionette TRACE 378 <- [1,6,null,{}]
05:23:31 INFO - 1499257411440 Marionette DEBUG Closed connection 378
05:23:31 INFO - 1499257411441 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49817
05:23:31 INFO - 1499257411441 Marionette DEBUG Closed connection 379
05:23:31 INFO - 1499257411442 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49818
05:23:31 INFO - 1499257411443 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:31 INFO - 1499257411444 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:31 INFO - 1499257411446 Marionette DEBUG Register listener.js for window 12
05:23:31 INFO - 1499257411448 Marionette TRACE 380 <- [1,1,null,{"sessionId":"78a4608e-6ccd-45df-a94a-7b23d48c6fd2","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:31 INFO - 1499257411449 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:23:31 INFO - 1499257411450 Marionette TRACE 380 <- [1,2,null,{}]
05:23:31 INFO - 1499257411452 Marionette DEBUG Closed connection 380
05:23:31 INFO - 1499257411453 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49819
05:23:31 INFO - 1499257411453 Marionette DEBUG Closed connection 381
05:23:31 INFO - 1499257411454 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49820
05:23:31 INFO - 1499257411455 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:23:31 INFO - 1499257411455 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:31 INFO - 1499257411457 Marionette DEBUG Register listener.js for window 12
05:23:31 INFO - 1499257411460 Marionette TRACE 382 <- [1,1,null,{"sessionId":"bf3619fa-365e-438d-91c7-5fae1ef1aac4","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:31 INFO - 1499257411461 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"}]
05:23:31 INFO - 1499257411462 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"
05:23:31 INFO - 1499257411464 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"
05:23:31 INFO - 1499257411464 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"
05:23:31 INFO - 1499257411479 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"
05:23:31 INFO - 1499257411482 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"
05:23:31 INFO - 1499257411484 Marionette TRACE 382 <- [1,2,null,{}]
05:23:31 INFO - 1499257411485 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:23:31 INFO - 1499257411493 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5ba17fb7-4022-431e-80c6-380c3e6b7eae","ELEMENT":"5ba17fb7-4022-431e-80c6-380c3e6b7eae"}}]
05:23:31 INFO - 1499257411494 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"5ba17fb7-4022-431e-80c6-380c3e6b7eae"}]
05:23:31 INFO - 1499257411740 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:23:31 INFO - 1499257411740 Marionette TRACE 382 <- [1,4,null,{}]
05:23:31 INFO - 1499257411742 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}]
05:23:31 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499257411745 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:23:31 INFO - 1499257411745 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:23:31 INFO - 1499257411747 Marionette TRACE 382 <- [1,6,null,{}]
05:23:31 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 328ms
05:23:31 INFO - TEST-START | test_click.py TestClick.test_input_file
05:23:31 INFO - 1499257411748 Marionette DEBUG Closed connection 382
05:23:31 INFO - 1499257411750 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49821
05:23:31 INFO - 1499257411751 Marionette DEBUG Closed connection 383
05:23:31 INFO - 1499257411752 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49822
05:23:31 INFO - 1499257411752 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:31 INFO - 1499257411753 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:31 INFO - 1499257411755 Marionette DEBUG Register listener.js for window 12
05:23:31 INFO - 1499257411758 Marionette TRACE 384 <- [1,1,null,{"sessionId":"5edf2138-36e9-4ba6-a62f-8f8eae32dff0","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:31 INFO - 1499257411759 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:23:31 INFO - 1499257411759 Marionette TRACE 384 <- [1,2,null,{}]
05:23:31 INFO - 1499257411760 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:23:31 INFO - 1499257411760 Marionette TRACE 384 <- [1,3,null,{}]
05:23:31 INFO - 1499257411761 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:23:31 INFO - 1499257411762 Marionette TRACE 384 <- [1,4,null,{}]
05:23:31 INFO - 1499257411763 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}]
05:23:31 INFO - TEST-START: test_click.py TestClick.test_input_file1499257411766 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:23:31 INFO - 1499257411766 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:23:31 INFO - 1499257411767 Marionette TRACE 384 <- [1,6,null,{}]
05:23:31 INFO - 1499257411769 Marionette DEBUG Closed connection 384
05:23:31 INFO - 1499257411770 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49823
05:23:31 INFO - 1499257411770 Marionette DEBUG Closed connection 385
05:23:31 INFO - 1499257411771 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49824
05:23:31 INFO - 1499257411772 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:31 INFO - 1499257411772 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:31 INFO - 1499257411774 Marionette DEBUG Register listener.js for window 12
05:23:31 INFO - 1499257411777 Marionette TRACE 386 <- [1,1,null,{"sessionId":"6675caad-db48-42d0-8aa5-5d90227a116c","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:31 INFO - 1499257411778 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:23:31 INFO - 1499257411779 Marionette TRACE 386 <- [1,2,null,{}]
05:23:31 INFO - 1499257411781 Marionette DEBUG Closed connection 386
05:23:31 INFO - 1499257411781 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49825
05:23:31 INFO - 1499257411782 Marionette DEBUG Closed connection 387
05:23:31 INFO - 1499257411783 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49826
05:23:31 INFO - 1499257411784 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:23:31 INFO - 1499257411784 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:31 INFO - 1499257411786 Marionette DEBUG Register listener.js for window 12
05:23:31 INFO - 1499257411789 Marionette TRACE 388 <- [1,1,null,{"sessionId":"0e25bf9e-b8f4-4eb0-85dc-94828d7dc392","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:31 INFO - 1499257411790 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:23:31 INFO - 1499257411791 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"
05:23:31 INFO - 1499257411793 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"
05:23:31 INFO - 1499257411793 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"
05:23:31 INFO - 1499257411803 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:23:31 INFO - 1499257411811 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:23:31 INFO - 1499257411814 Marionette TRACE 388 <- [1,2,null,{}]
05:23:31 INFO - 1499257411816 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:23:31 INFO - 1499257411818 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"70a7baed-9120-4c03-a700-4ba2831b0eef","ELEMENT":"70a7baed-9120-4c03-a700-4ba2831b0eef"}}]
05:23:31 INFO - 1499257411819 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"70a7baed-9120-4c03-a700-4ba2831b0eef"}]
05:23:31 INFO - 1499257411847 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:1318:5\n"},null]
05:23:31 INFO - 1499257411849 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}]
05:23:31 INFO - TEST-END: test_click.py TestClick.test_input_file1499257411852 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:23:31 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 107ms
05:23:31 INFO - 1499257411852 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:23:31 INFO - 1499257411854 Marionette TRACE 388 <- [1,6,null,{}]
05:23:31 INFO - 1499257411856 Marionette DEBUG Closed connection 388
05:23:31 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:23:31 INFO - 1499257411858 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49827
05:23:31 INFO - 1499257411859 Marionette DEBUG Closed connection 389
05:23:31 INFO - 1499257411859 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49828
05:23:31 INFO - 1499257411860 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:31 INFO - 1499257411861 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:31 INFO - 1499257411863 Marionette DEBUG Register listener.js for window 12
05:23:31 INFO - 1499257411866 Marionette TRACE 390 <- [1,1,null,{"sessionId":"0a90dc8e-650d-47dc-8e51-923512dc170b","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:31 INFO - 1499257411867 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:23:31 INFO - 1499257411867 Marionette TRACE 390 <- [1,2,null,{}]
05:23:31 INFO - 1499257411868 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:23:31 INFO - 1499257411868 Marionette TRACE 390 <- [1,3,null,{}]
05:23:31 INFO - 1499257411869 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:23:31 INFO - 1499257411870 Marionette TRACE 390 <- [1,4,null,{}]
05:23:31 INFO - 1499257411871 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}]
05:23:31 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499257411874 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:23:31 INFO - 1499257411875 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:23:31 INFO - 1499257411876 Marionette TRACE 390 <- [1,6,null,{}]
05:23:31 INFO - 1499257411877 Marionette DEBUG Closed connection 390
05:23:31 INFO - 1499257411878 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49829
05:23:31 INFO - 1499257411879 Marionette DEBUG Closed connection 391
05:23:31 INFO - 1499257411879 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49830
05:23:31 INFO - 1499257411880 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:31 INFO - 1499257411881 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:31 INFO - 1499257411883 Marionette DEBUG Register listener.js for window 12
05:23:31 INFO - 1499257411886 Marionette TRACE 392 <- [1,1,null,{"sessionId":"8eb25137-23fc-441a-8c97-1e3c84463604","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:31 INFO - 1499257411887 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:23:31 INFO - 1499257411888 Marionette TRACE 392 <- [1,2,null,{}]
05:23:31 INFO - 1499257411889 Marionette DEBUG Closed connection 392
05:23:31 INFO - 1499257411890 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49831
05:23:31 INFO - 1499257411890 Marionette DEBUG Closed connection 393
05:23:31 INFO - 1499257411891 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49832
05:23:31 INFO - 1499257411892 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:23:31 INFO - 1499257411893 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:31 INFO - 1499257411895 Marionette DEBUG Register listener.js for window 12
05:23:31 INFO - 1499257411897 Marionette TRACE 394 <- [1,1,null,{"sessionId":"50f937d6-d54e-4ff6-bd81-75b382de0d8f","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:31 INFO - 1499257411898 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"}]
05:23:31 INFO - 1499257411899 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:23:31 INFO - 1499257411901 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:23:31 INFO - 1499257411902 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:23:31 INFO - 1499257411918 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"
05:23:31 INFO - 1499257411921 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"
05:23:31 INFO - 1499257411928 Marionette TRACE 394 <- [1,2,null,{}]
05:23:31 INFO - 1499257411930 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:23:31 INFO - 1499257411932 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"47fa2285-2d3c-48eb-a982-234fdeadad95","ELEMENT":"47fa2285-2d3c-48eb-a982-234fdeadad95"}}]
05:23:31 INFO - 1499257411933 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:23:31 INFO - 1499257411935 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d0db4327-e189-4772-bcbe-37a8143fd45b","ELEMENT":"d0db4327-e189-4772-bcbe-37a8143fd45b"}}]
05:23:31 INFO - 1499257411936 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"47fa2285-2d3c-48eb-a982-234fdeadad95"}]
05:23:32 INFO - 1499257412177 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:23:32 INFO - 1499257412177 Marionette TRACE 394 <- [1,5,null,{}]
05:23:32 INFO - 1499257412178 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"d0db4327-e189-4772-bcbe-37a8143fd45b"}]
05:23:32 INFO - 1499257412192 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:1318:5\n"},null]
05:23:32 INFO - 1499257412194 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:23:32 INFO - 1499257412197 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:23:32 INFO - 1499257412198 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}]
05:23:32 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499257412201 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:23:32 INFO - 1499257412202 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:23:32 INFO - 1499257412203 Marionette TRACE 394 <- [1,9,null,{}]
05:23:32 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 348ms
05:23:32 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:23:32 INFO - 1499257412205 Marionette DEBUG Closed connection 394
05:23:32 INFO - 1499257412206 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49835
05:23:32 INFO - 1499257412206 Marionette DEBUG Closed connection 395
05:23:32 INFO - 1499257412207 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49836
05:23:32 INFO - 1499257412208 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:32 INFO - 1499257412209 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:32 INFO - 1499257412211 Marionette DEBUG Register listener.js for window 12
05:23:32 INFO - 1499257412213 Marionette TRACE 396 <- [1,1,null,{"sessionId":"416f4afb-09e4-45d0-8c1a-eeee928bba27","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:32 INFO - 1499257412214 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:23:32 INFO - 1499257412215 Marionette TRACE 396 <- [1,2,null,{}]
05:23:32 INFO - 1499257412216 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:23:32 INFO - 1499257412216 Marionette TRACE 396 <- [1,3,null,{}]
05:23:32 INFO - 1499257412217 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:23:32 INFO - 1499257412217 Marionette TRACE 396 <- [1,4,null,{}]
05:23:32 INFO - 1499257412219 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}]
05:23:32 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499257412221 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:23:32 INFO - 1499257412222 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:23:32 INFO - 1499257412223 Marionette TRACE 396 <- [1,6,null,{}]
05:23:32 INFO - 1499257412225 Marionette DEBUG Closed connection 396
05:23:32 INFO - 1499257412226 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49837
05:23:32 INFO - 1499257412226 Marionette DEBUG Closed connection 397
05:23:32 INFO - 1499257412227 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49838
05:23:32 INFO - 1499257412228 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:23:32 INFO - 1499257412228 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:32 INFO - 1499257412230 Marionette DEBUG Register listener.js for window 12
05:23:32 INFO - 1499257412233 Marionette TRACE 398 <- [1,1,null,{"sessionId":"b7840f19-756c-4655-8383-d88cfc200581","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:32 INFO - 1499257412234 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:23:32 INFO - 1499257412235 Marionette TRACE 398 <- [1,2,null,{}]
05:23:32 INFO - 1499257412236 Marionette DEBUG Closed connection 398
05:23:32 INFO - 1499257412237 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49839
05:23:32 INFO - 1499257412238 Marionette DEBUG Closed connection 399
05:23:32 INFO - 1499257412239 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49840
05:23:32 INFO - 1499257412240 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:23:32 INFO - 1499257412240 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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}
05:23:32 INFO - 1499257412242 Marionette DEBUG Register listener.js for window 12
05:23:32 INFO - 1499257412245 Marionette TRACE 400 <- [1,1,null,{"sessionId":"8df04d05-6d5a-4686-b57e-116860f6a8e2","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":2200,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpjc0wwl.mozrunner","moz:accessibilityChecks":false}}]
05:23:32 INFO - 1499257412246 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"}]
05:23:32 INFO - 1499257412247 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"
05:23:32 INFO - 1499257412249 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"
05:23:32 INFO - 1499257412249 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"
05:23:32 INFO - 1499257412265 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"
05:23:32 INFO - 1499257412267 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"
05:23:32 INFO - 1499257412270 Marionette TRACE 400 <- [1,2,null,{}]
05:23:32 INFO - 1499257412277 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:23:32 INFO - 1499257412279 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3bfb0d27-1150-4faf-b01d-b2a8e1dba567","ELEMENT":"3bfb0d27-1150-4faf-b01d-b2a8e1dba567"}}]
05:23:32 INFO - 1499257412280 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"3bfb0d27-1150-4faf-b01d-b2a8e1dba567"}]
05:23:32 INFO - 1499257412301 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:23:32 INFO - 1499257412302 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"3bfb0d27-1150-4faf-b01d-b2a8e1dba567"}]
05:23:32 INFO - 1499257412319 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element