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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
21:43:25 INFO - 1500007405278 Marionette TRACE 353 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
21:43:25 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500007405280 Marionette TRACE 353 <- [1,5,null,{"value":null}]
21:43:25 INFO - 1500007405281 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
21:43:25 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 137ms
21:43:25 INFO - 1500007405283 Marionette TRACE 353 <- [1,6,null,{}]
21:43:25 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:43:25 INFO - 1500007405285 Marionette DEBUG Closed connection 353
21:43:25 INFO - 1500007405286 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50291
21:43:25 INFO - 1500007405287 Marionette DEBUG Closed connection 354
21:43:25 INFO - 1500007405288 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50292
21:43:25 INFO - 1500007405289 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:25 INFO - 1500007405289 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:25 INFO - 1500007405292 Marionette DEBUG Register listener.js for window 15
21:43:25 INFO - 1500007405294 Marionette TRACE 355 <- [1,1,null,{"sessionId":"0556de48-4f38-41af-a794-08bd9777dbd2","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:25 INFO - 1500007405296 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
21:43:25 INFO - 1500007405297 Marionette TRACE 355 <- [1,2,null,{}]
21:43:25 INFO - 1500007405298 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:25 INFO - 1500007405298 Marionette TRACE 355 <- [1,3,null,{}]
21:43:25 INFO - 1500007405299 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:25 INFO - 1500007405299 Marionette TRACE 355 <- [1,4,null,{}]
21:43:25 INFO - 1500007405301 Marionette TRACE 355 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
21:43:25 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500007405303 Marionette TRACE 355 <- [1,5,null,{"value":null}]
21:43:25 INFO - 1500007405304 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
21:43:25 INFO - 1500007405305 Marionette TRACE 355 <- [1,6,null,{}]
21:43:25 INFO - 1500007405307 Marionette DEBUG Closed connection 355
21:43:25 INFO - 1500007405308 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50293
21:43:25 INFO - 1500007405308 Marionette DEBUG Closed connection 356
21:43:25 INFO - 1500007405309 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50294
21:43:25 INFO - 1500007405310 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:25 INFO - 1500007405311 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:25 INFO - 1500007405312 Marionette DEBUG Register listener.js for window 15
21:43:25 INFO - 1500007405315 Marionette TRACE 357 <- [1,1,null,{"sessionId":"bcab137b-9511-4635-8f17-5c6588e2d3a6","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:25 INFO - 1500007405317 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
21:43:25 INFO - 1500007405318 Marionette TRACE 357 <- [1,2,null,{}]
21:43:25 INFO - 1500007405320 Marionette DEBUG Closed connection 357
21:43:25 INFO - 1500007405320 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50295
21:43:25 INFO - 1500007405321 Marionette DEBUG Closed connection 358
21:43:25 INFO - 1500007405322 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50296
21:43:25 INFO - 1500007405323 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:25 INFO - 1500007405323 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:25 INFO - 1500007405325 Marionette DEBUG Register listener.js for window 15
21:43:25 INFO - 1500007405327 Marionette TRACE 359 <- [1,1,null,{"sessionId":"7bb2fac4-a75c-4854-8116-6c1328ebac47","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:25 INFO - 1500007405330 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49855/clicks.html"}]
21:43:25 INFO - 1500007405331 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49855/hidden.html"
21:43:25 INFO - 1500007405336 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49855/hidden.html"
21:43:25 INFO - 1500007405337 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49855/hidden.html"
21:43:25 INFO - 1500007405355 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49855/clicks.html"
21:43:25 INFO - 1500007405357 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49855/clicks.html"
21:43:25 INFO - 1500007405360 Marionette TRACE 359 <- [1,2,null,{}]
21:43:25 INFO - 1500007405361 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:43:25 INFO - 1500007405371 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a692d468-c810-4d52-a99a-101c77e529d6","ELEMENT":"a692d468-c810-4d52-a99a-101c77e529d6"}}]
21:43:25 INFO - 1500007405372 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"a692d468-c810-4d52-a99a-101c77e529d6"}]
21:43:25 INFO - 1500007405411 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49855/clicks.html"
21:43:25 INFO - 1500007405419 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49855/clicks.html"
21:43:25 INFO - 1500007405420 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49855/clicks.html"
21:43:25 INFO - 1500007405438 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49855/test.html"
21:43:25 INFO - 1500007405440 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49855/test.html"
21:43:25 INFO - 1500007405442 Marionette TRACE 359 <- [1,4,null,{}]
21:43:25 INFO - 1500007405443 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:43:25 INFO - 1500007405445 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5a5d0c14-5031-4a40-9f92-1ebfd5bdcf89","ELEMENT":"5a5d0c14-5031-4a40-9f92-1ebfd5bdcf89"}}]
21:43:25 INFO - 1500007405446 Marionette TRACE 359 -> [0,6,"getTitle",{}]
21:43:25 INFO - 1500007405447 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
21:43:25 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 175ms
21:43:25 INFO - TEST-START | test_click.py TestClick.test_container_element
21:43:25 INFO - 1500007405455 Marionette TRACE 359 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
21:43:25 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500007405457 Marionette TRACE 359 <- [1,7,null,{"value":null}]
21:43:25 INFO - 1500007405458 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
21:43:25 INFO - 1500007405459 Marionette TRACE 359 <- [1,8,null,{}]
21:43:25 INFO - 1500007405461 Marionette DEBUG Closed connection 359
21:43:25 INFO - 1500007405462 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50299
21:43:25 INFO - 1500007405463 Marionette DEBUG Closed connection 360
21:43:25 INFO - 1500007405464 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50300
21:43:25 INFO - 1500007405464 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:25 INFO - 1500007405466 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:25 INFO - 1500007405468 Marionette DEBUG Register listener.js for window 15
21:43:25 INFO - 1500007405478 Marionette TRACE 361 <- [1,1,null,{"sessionId":"7949b4a1-fa4e-4fe1-a71b-eb4f7c559bf8","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:25 INFO - 1500007405480 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
21:43:25 INFO - 1500007405481 Marionette TRACE 361 <- [1,2,null,{}]
21:43:25 INFO - 1500007405481 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:25 INFO - 1500007405482 Marionette TRACE 361 <- [1,3,null,{}]
21:43:25 INFO - 1500007405483 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:25 INFO - 1500007405483 Marionette TRACE 361 <- [1,4,null,{}]
21:43:25 INFO - 1500007405485 Marionette TRACE 361 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
21:43:25 INFO - TEST-START: test_click.py TestClick.test_container_element1500007405487 Marionette TRACE 361 <- [1,5,null,{"value":null}]
21:43:25 INFO - 1500007405488 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
21:43:25 INFO - 1500007405489 Marionette TRACE 361 <- [1,6,null,{}]
21:43:25 INFO - 1500007405491 Marionette DEBUG Closed connection 361
21:43:25 INFO - 1500007405492 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50301
21:43:25 INFO - 1500007405493 Marionette DEBUG Closed connection 362
21:43:25 INFO - 1500007405493 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50302
21:43:25 INFO - 1500007405494 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:25 INFO - 1500007405495 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:25 INFO - 1500007405497 Marionette DEBUG Register listener.js for window 15
21:43:25 INFO - 1500007405499 Marionette TRACE 363 <- [1,1,null,{"sessionId":"48fed8f6-f7d5-4b7c-9f62-837d402928d2","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:25 INFO - 1500007405501 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
21:43:25 INFO - 1500007405502 Marionette TRACE 363 <- [1,2,null,{}]
21:43:25 INFO - 1500007405504 Marionette DEBUG Closed connection 363
21:43:25 INFO - 1500007405505 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50303
21:43:25 INFO - 1500007405505 Marionette DEBUG Closed connection 364
21:43:25 INFO - 1500007405506 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50304
21:43:25 INFO - 1500007405507 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:25 INFO - 1500007405507 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:25 INFO - 1500007405509 Marionette DEBUG Register listener.js for window 15
21:43:25 INFO - 1500007405512 Marionette TRACE 365 <- [1,1,null,{"sessionId":"cff67c41-6559-400a-838f-e1e6bad225a0","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:25 INFO - 1500007405513 Marionette TRACE 365 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:43:25 INFO - 1500007405515 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49855/test.html"
21:43:25 INFO - 1500007405517 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49855/test.html"
21:43:25 INFO - 1500007405517 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49855/test.html"
21:43:25 INFO - 1500007405527 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:25 INFO - 1500007405532 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:25 INFO - 1500007405542 Marionette TRACE 365 <- [1,2,null,{}]
21:43:25 INFO - 1500007405543 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:43:25 INFO - 1500007405545 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c12a66c6-4e8c-4cbb-9f1f-2d59947c3c30","ELEMENT":"c12a66c6-4e8c-4cbb-9f1f-2d59947c3c30"}}]
21:43:25 INFO - 1500007405546 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"c12a66c6-4e8c-4cbb-9f1f-2d59947c3c30"}]
21:43:25 INFO - 1500007405783 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:25 INFO - 1500007405783 Marionette TRACE 365 <- [1,4,null,{}]
21:43:25 INFO - 1500007405784 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"c12a66c6-4e8c-4cbb-9f1f-2d59947c3c30","name":"selected"}]
21:43:25 INFO - 1500007405788 Marionette TRACE 365 <- [1,5,null,{"value":true}]
21:43:25 INFO - 1500007405790 Marionette TRACE 365 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
21:43:25 INFO - TEST-END: test_click.py TestClick.test_container_element1500007405792 Marionette TRACE 365 <- [1,6,null,{"value":null}]
21:43:25 INFO - 1500007405793 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
21:43:25 INFO - 1500007405794 Marionette TRACE 365 <- [1,7,null,{}]
21:43:25 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 334ms
21:43:25 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:43:25 INFO - 1500007405796 Marionette DEBUG Closed connection 365
21:43:25 INFO - 1500007405797 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50305
21:43:25 INFO - 1500007405798 Marionette DEBUG Closed connection 366
21:43:25 INFO - 1500007405799 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50306
21:43:25 INFO - 1500007405799 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:25 INFO - 1500007405800 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:25 INFO - 1500007405802 Marionette DEBUG Register listener.js for window 15
21:43:25 INFO - 1500007405804 Marionette TRACE 367 <- [1,1,null,{"sessionId":"4325dff8-fcd2-4aa2-a62e-4ff8d801bd9c","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:25 INFO - 1500007405806 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
21:43:25 INFO - 1500007405807 Marionette TRACE 367 <- [1,2,null,{}]
21:43:25 INFO - 1500007405807 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:25 INFO - 1500007405808 Marionette TRACE 367 <- [1,3,null,{}]
21:43:25 INFO - 1500007405809 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:25 INFO - 1500007405809 Marionette TRACE 367 <- [1,4,null,{}]
21:43:25 INFO - 1500007405811 Marionette TRACE 367 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
21:43:25 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500007405813 Marionette TRACE 367 <- [1,5,null,{"value":null}]
21:43:25 INFO - 1500007405814 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
21:43:25 INFO - 1500007405815 Marionette TRACE 367 <- [1,6,null,{}]
21:43:25 INFO - 1500007405817 Marionette DEBUG Closed connection 367
21:43:25 INFO - 1500007405817 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50307
21:43:25 INFO - 1500007405818 Marionette DEBUG Closed connection 368
21:43:25 INFO - 1500007405819 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50308
21:43:25 INFO - 1500007405820 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:25 INFO - 1500007405820 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:25 INFO - 1500007405822 Marionette DEBUG Register listener.js for window 15
21:43:25 INFO - 1500007405825 Marionette TRACE 369 <- [1,1,null,{"sessionId":"8a815dc4-6e32-4e3b-9db0-a21f0d56830a","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:25 INFO - 1500007405826 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
21:43:25 INFO - 1500007405827 Marionette TRACE 369 <- [1,2,null,{}]
21:43:25 INFO - 1500007405829 Marionette DEBUG Closed connection 369
21:43:25 INFO - 1500007405830 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50309
21:43:25 INFO - 1500007405831 Marionette DEBUG Closed connection 370
21:43:25 INFO - 1500007405832 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50310
21:43:25 INFO - 1500007405833 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:25 INFO - 1500007405834 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:25 INFO - 1500007405835 Marionette DEBUG Register listener.js for window 15
21:43:25 INFO - 1500007405838 Marionette TRACE 371 <- [1,1,null,{"sessionId":"a9b0ce86-3733-41ea-a6ed-a0f0893362d7","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:25 INFO - 1500007405840 Marionette TRACE 371 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:43:25 INFO - 1500007405841 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:25 INFO - 1500007405843 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:25 INFO - 1500007405843 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:25 INFO - 1500007405859 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:25 INFO - 1500007405862 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:25 INFO - 1500007405865 Marionette TRACE 371 <- [1,2,null,{}]
21:43:25 INFO - 1500007405871 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:43:25 INFO - 1500007405875 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"58d1d933-ac51-4d9c-a8a9-169f3bd45193","ELEMENT":"58d1d933-ac51-4d9c-a8a9-169f3bd45193"}}]
21:43:25 INFO - 1500007405878 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"58d1d933-ac51-4d9c-a8a9-169f3bd45193"}]
21:43:26 INFO - 1500007406117 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:26 INFO - 1500007406117 Marionette TRACE 371 <- [1,4,null,{}]
21:43:26 INFO - 1500007406118 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"58d1d933-ac51-4d9c-a8a9-169f3bd45193","name":"selected"}]
21:43:26 INFO - 1500007406122 Marionette TRACE 371 <- [1,5,null,{"value":true}]
21:43:26 INFO - 1500007406124 Marionette TRACE 371 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
21:43:26 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500007406126 Marionette TRACE 371 <- [1,6,null,{"value":null}]
21:43:26 INFO - 1500007406127 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
21:43:26 INFO - 1500007406128 Marionette TRACE 371 <- [1,7,null,{}]
21:43:26 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 333ms
21:43:26 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:43:26 INFO - 1500007406130 Marionette DEBUG Closed connection 371
21:43:26 INFO - 1500007406131 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50311
21:43:26 INFO - 1500007406132 Marionette DEBUG Closed connection 372
21:43:26 INFO - 1500007406132 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50312
21:43:26 INFO - 1500007406133 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:26 INFO - 1500007406134 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:26 INFO - 1500007406136 Marionette DEBUG Register listener.js for window 15
21:43:26 INFO - 1500007406138 Marionette TRACE 373 <- [1,1,null,{"sessionId":"ed4f5fb9-b14c-472f-93eb-266e4ffddfda","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:26 INFO - 1500007406140 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
21:43:26 INFO - 1500007406141 Marionette TRACE 373 <- [1,2,null,{}]
21:43:26 INFO - 1500007406141 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:26 INFO - 1500007406142 Marionette TRACE 373 <- [1,3,null,{}]
21:43:26 INFO - 1500007406143 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:26 INFO - 1500007406143 Marionette TRACE 373 <- [1,4,null,{}]
21:43:26 INFO - 1500007406145 Marionette TRACE 373 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
21:43:26 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500007406147 Marionette TRACE 373 <- [1,5,null,{"value":null}]
21:43:26 INFO - 1500007406148 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
21:43:26 INFO - 1500007406150 Marionette TRACE 373 <- [1,6,null,{}]
21:43:26 INFO - 1500007406152 Marionette DEBUG Closed connection 373
21:43:26 INFO - 1500007406152 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50313
21:43:26 INFO - 1500007406153 Marionette DEBUG Closed connection 374
21:43:26 INFO - 1500007406154 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50314
21:43:26 INFO - 1500007406155 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:26 INFO - 1500007406155 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:26 INFO - 1500007406157 Marionette DEBUG Register listener.js for window 15
21:43:26 INFO - 1500007406166 Marionette TRACE 375 <- [1,1,null,{"sessionId":"9be65e61-7519-4e59-9ce8-063614142a80","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:26 INFO - 1500007406168 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
21:43:26 INFO - 1500007406169 Marionette TRACE 375 <- [1,2,null,{}]
21:43:26 INFO - 1500007406170 Marionette DEBUG Closed connection 375
21:43:26 INFO - 1500007406171 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50315
21:43:26 INFO - 1500007406172 Marionette DEBUG Closed connection 376
21:43:26 INFO - 1500007406173 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50316
21:43:26 INFO - 1500007406173 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:26 INFO - 1500007406174 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:26 INFO - 1500007406176 Marionette DEBUG Register listener.js for window 15
21:43:26 INFO - 1500007406178 Marionette TRACE 377 <- [1,1,null,{"sessionId":"176c0326-e321-43ab-a4b3-752d0e0e93bb","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:26 INFO - 1500007406180 Marionette TRACE 377 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
21:43:26 INFO - 1500007406181 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:26 INFO - 1500007406185 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:26 INFO - 1500007406185 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:26 INFO - 1500007406217 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:43:26 INFO - 1500007406221 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:43:26 INFO - 1500007406225 Marionette TRACE 377 <- [1,2,null,{}]
21:43:26 INFO - 1500007406226 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:43:26 INFO - 1500007406228 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3486c808-d7ce-420a-9017-65bd6adf88b0","ELEMENT":"3486c808-d7ce-420a-9017-65bd6adf88b0"}}]
21:43:26 INFO - 1500007406229 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"3486c808-d7ce-420a-9017-65bd6adf88b0"}]
21:43:26 INFO - 1500007406472 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:26 INFO - 1500007406472 Marionette TRACE 377 <- [1,4,null,{}]
21:43:26 INFO - 1500007406474 Marionette TRACE 377 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
21:43:26 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500007406477 Marionette TRACE 377 <- [1,5,null,{"value":null}]
21:43:26 INFO - 1500007406477 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
21:43:26 INFO - 1500007406479 Marionette TRACE 377 <- [1,6,null,{}]
21:43:26 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 350ms
21:43:26 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:43:26 INFO - 1500007406480 Marionette DEBUG Closed connection 377
21:43:26 INFO - 1500007406482 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50317
21:43:26 INFO - 1500007406483 Marionette DEBUG Closed connection 378
21:43:26 INFO - 1500007406483 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50318
21:43:26 INFO - 1500007406484 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:26 INFO - 1500007406485 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:26 INFO - 1500007406487 Marionette DEBUG Register listener.js for window 15
21:43:26 INFO - 1500007406489 Marionette TRACE 379 <- [1,1,null,{"sessionId":"f56828b0-c03e-412a-8d4c-b26012951807","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:26 INFO - 1500007406491 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
21:43:26 INFO - 1500007406492 Marionette TRACE 379 <- [1,2,null,{}]
21:43:26 INFO - 1500007406492 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:26 INFO - 1500007406493 Marionette TRACE 379 <- [1,3,null,{}]
21:43:26 INFO - 1500007406494 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:26 INFO - 1500007406494 Marionette TRACE 379 <- [1,4,null,{}]
21:43:26 INFO - 1500007406496 Marionette TRACE 379 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
21:43:26 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500007406498 Marionette TRACE 379 <- [1,5,null,{"value":null}]
21:43:26 INFO - 1500007406499 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
21:43:26 INFO - 1500007406500 Marionette TRACE 379 <- [1,6,null,{}]
21:43:26 INFO - 1500007406502 Marionette DEBUG Closed connection 379
21:43:26 INFO - 1500007406502 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50319
21:43:26 INFO - 1500007406503 Marionette DEBUG Closed connection 380
21:43:26 INFO - 1500007406504 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50320
21:43:26 INFO - 1500007406504 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:26 INFO - 1500007406505 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:26 INFO - 1500007406507 Marionette DEBUG Register listener.js for window 15
21:43:26 INFO - 1500007406509 Marionette TRACE 381 <- [1,1,null,{"sessionId":"aea6331a-519c-4fd4-861b-0b42459d73b3","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:26 INFO - 1500007406511 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
21:43:26 INFO - 1500007406512 Marionette TRACE 381 <- [1,2,null,{}]
21:43:26 INFO - 1500007406514 Marionette DEBUG Closed connection 381
21:43:26 INFO - 1500007406514 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50321
21:43:26 INFO - 1500007406515 Marionette DEBUG Closed connection 382
21:43:26 INFO - 1500007406516 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50322
21:43:26 INFO - 1500007406517 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:26 INFO - 1500007406517 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:26 INFO - 1500007406519 Marionette DEBUG Register listener.js for window 15
21:43:26 INFO - 1500007406521 Marionette TRACE 383 <- [1,1,null,{"sessionId":"d9977320-4748-4bd2-a677-df3c865c3133","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:26 INFO - 1500007406524 Marionette TRACE 383 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:43:26 INFO - 1500007406525 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:43:26 INFO - 1500007406527 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:43:26 INFO - 1500007406527 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:43:26 INFO - 1500007406543 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:26 INFO - 1500007406547 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:26 INFO - 1500007406557 Marionette TRACE 383 <- [1,2,null,{}]
21:43:26 INFO - 1500007406558 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:43:26 INFO - 1500007406560 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b5ee6e44-855b-4804-b3b7-bef0ab9c516f","ELEMENT":"b5ee6e44-855b-4804-b3b7-bef0ab9c516f"}}]
21:43:26 INFO - 1500007406561 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"b5ee6e44-855b-4804-b3b7-bef0ab9c516f"}]
21:43:26 INFO - 1500007406798 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:26 INFO - 1500007406798 Marionette TRACE 383 <- [1,4,null,{}]
21:43:26 INFO - 1500007406800 Marionette TRACE 383 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
21:43:26 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500007406802 Marionette TRACE 383 <- [1,5,null,{"value":null}]
21:43:26 INFO - 1500007406803 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
21:43:26 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 326ms
21:43:26 INFO - TEST-START | test_click.py TestClick.test_input_file
21:43:26 INFO - 1500007406805 Marionette TRACE 383 <- [1,6,null,{}]
21:43:26 INFO - 1500007406806 Marionette DEBUG Closed connection 383
21:43:26 INFO - 1500007406807 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50323
21:43:26 INFO - 1500007406808 Marionette DEBUG Closed connection 384
21:43:26 INFO - 1500007406809 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50324
21:43:26 INFO - 1500007406810 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:26 INFO - 1500007406810 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:26 INFO - 1500007406812 Marionette DEBUG Register listener.js for window 15
21:43:26 INFO - 1500007406815 Marionette TRACE 385 <- [1,1,null,{"sessionId":"23bc3664-ddf9-47c4-ab4a-0aeb05f3455b","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:26 INFO - 1500007406817 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
21:43:26 INFO - 1500007406817 Marionette TRACE 385 <- [1,2,null,{}]
21:43:26 INFO - 1500007406818 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:26 INFO - 1500007406818 Marionette TRACE 385 <- [1,3,null,{}]
21:43:26 INFO - 1500007406819 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:26 INFO - 1500007406820 Marionette TRACE 385 <- [1,4,null,{}]
21:43:26 INFO - 1500007406821 Marionette TRACE 385 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
21:43:26 INFO - TEST-START: test_click.py TestClick.test_input_file1500007406824 Marionette TRACE 385 <- [1,5,null,{"value":null}]
21:43:26 INFO - 1500007406824 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
21:43:26 INFO - 1500007406825 Marionette TRACE 385 <- [1,6,null,{}]
21:43:26 INFO - 1500007406827 Marionette DEBUG Closed connection 385
21:43:26 INFO - 1500007406828 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50325
21:43:26 INFO - 1500007406828 Marionette DEBUG Closed connection 386
21:43:26 INFO - 1500007406829 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50326
21:43:26 INFO - 1500007406830 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:26 INFO - 1500007406831 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:26 INFO - 1500007406832 Marionette DEBUG Register listener.js for window 15
21:43:26 INFO - 1500007406841 Marionette TRACE 387 <- [1,1,null,{"sessionId":"000eb6cc-7d89-4fa3-96c1-70435f49edda","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:26 INFO - 1500007406843 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
21:43:26 INFO - 1500007406844 Marionette TRACE 387 <- [1,2,null,{}]
21:43:26 INFO - 1500007406845 Marionette DEBUG Closed connection 387
21:43:26 INFO - 1500007406846 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50327
21:43:26 INFO - 1500007406847 Marionette DEBUG Closed connection 388
21:43:26 INFO - 1500007406848 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50328
21:43:26 INFO - 1500007406849 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:26 INFO - 1500007406849 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:26 INFO - 1500007406851 Marionette DEBUG Register listener.js for window 15
21:43:26 INFO - 1500007406853 Marionette TRACE 389 <- [1,1,null,{"sessionId":"c2d94356-a6ba-48a7-baa2-2e9c4dbdba47","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:26 INFO - 1500007406855 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:43:26 INFO - 1500007406857 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:26 INFO - 1500007406859 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:26 INFO - 1500007406860 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:43:26 INFO - 1500007406878 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:26 INFO - 1500007406881 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:26 INFO - 1500007406890 Marionette TRACE 389 <- [1,2,null,{}]
21:43:26 INFO - 1500007406891 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:43:26 INFO - 1500007406894 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e74739b9-4b21-4ecb-a6e9-74951ca7b908","ELEMENT":"e74739b9-4b21-4ecb-a6e9-74951ca7b908"}}]
21:43:26 INFO - 1500007406894 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"e74739b9-4b21-4ecb-a6e9-74951ca7b908"}]
21:43:26 INFO - 1500007406919 Marionette TRACE 389 <- [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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
21:43:26 INFO - 1500007406922 Marionette TRACE 389 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
21:43:26 INFO - TEST-END: test_click.py TestClick.test_input_file1500007406924 Marionette TRACE 389 <- [1,5,null,{"value":null}]
21:43:26 INFO - 1500007406925 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
21:43:26 INFO - 1500007406926 Marionette TRACE 389 <- [1,6,null,{}]
21:43:26 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 121ms
21:43:26 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:43:26 INFO - 1500007406928 Marionette DEBUG Closed connection 389
21:43:26 INFO - 1500007406929 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50329
21:43:26 INFO - 1500007406931 Marionette DEBUG Closed connection 390
21:43:26 INFO - 1500007406932 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50330
21:43:26 INFO - 1500007406933 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:26 INFO - 1500007406933 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:26 INFO - 1500007406935 Marionette DEBUG Register listener.js for window 15
21:43:26 INFO - 1500007406938 Marionette TRACE 391 <- [1,1,null,{"sessionId":"425017d2-c2bf-4860-8d86-115668b3e1a2","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:26 INFO - 1500007406940 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
21:43:26 INFO - 1500007406941 Marionette TRACE 391 <- [1,2,null,{}]
21:43:26 INFO - 1500007406942 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:26 INFO - 1500007406942 Marionette TRACE 391 <- [1,3,null,{}]
21:43:26 INFO - 1500007406943 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:26 INFO - 1500007406943 Marionette TRACE 391 <- [1,4,null,{}]
21:43:26 INFO - 1500007406945 Marionette TRACE 391 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
21:43:26 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500007406947 Marionette TRACE 391 <- [1,5,null,{"value":null}]
21:43:26 INFO - 1500007406948 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
21:43:26 INFO - 1500007406949 Marionette TRACE 391 <- [1,6,null,{}]
21:43:26 INFO - 1500007406951 Marionette DEBUG Closed connection 391
21:43:26 INFO - 1500007406951 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50331
21:43:26 INFO - 1500007406952 Marionette DEBUG Closed connection 392
21:43:26 INFO - 1500007406953 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50332
21:43:26 INFO - 1500007406954 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:26 INFO - 1500007406954 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:26 INFO - 1500007406956 Marionette DEBUG Register listener.js for window 15
21:43:26 INFO - 1500007406959 Marionette TRACE 393 <- [1,1,null,{"sessionId":"50814e53-4316-4f0b-b9fb-a3440e5197f3","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:26 INFO - 1500007406960 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
21:43:26 INFO - 1500007406961 Marionette TRACE 393 <- [1,2,null,{}]
21:43:26 INFO - 1500007406963 Marionette DEBUG Closed connection 393
21:43:26 INFO - 1500007406964 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50333
21:43:26 INFO - 1500007406964 Marionette DEBUG Closed connection 394
21:43:26 INFO - 1500007406965 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50334
21:43:26 INFO - 1500007406966 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:26 INFO - 1500007406966 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:26 INFO - 1500007406968 Marionette DEBUG Register listener.js for window 15
21:43:26 INFO - 1500007406971 Marionette TRACE 395 <- [1,1,null,{"sessionId":"3c675a0b-ebc4-46bf-b0a5-472cecdc1cfd","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:26 INFO - 1500007406973 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
21:43:26 INFO - 1500007406974 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:26 INFO - 1500007406976 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:26 INFO - 1500007406976 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:26 INFO - 1500007406994 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:43:26 INFO - 1500007406997 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:43:27 INFO - 1500007407006 Marionette TRACE 395 <- [1,2,null,{}]
21:43:27 INFO - 1500007407007 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:43:27 INFO - 1500007407009 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d462ee6f-2d51-4b22-8c12-3898e724ae48","ELEMENT":"d462ee6f-2d51-4b22-8c12-3898e724ae48"}}]
21:43:27 INFO - 1500007407010 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:43:27 INFO - 1500007407012 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4a9775df-ba7e-4bc3-bc1e-3c28cb49a965","ELEMENT":"4a9775df-ba7e-4bc3-bc1e-3c28cb49a965"}}]
21:43:27 INFO - 1500007407013 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"d462ee6f-2d51-4b22-8c12-3898e724ae48"}]
21:43:27 INFO - 1500007407248 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:27 INFO - 1500007407248 Marionette TRACE 395 <- [1,5,null,{}]
21:43:27 INFO - 1500007407249 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"4a9775df-ba7e-4bc3-bc1e-3c28cb49a965"}]
21:43:27 INFO - 1500007407263 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
21:43:27 INFO - 1500007407264 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:43:27 INFO - 1500007407267 Marionette TRACE 395 <- [1,7,null,{"value":false}]
21:43:27 INFO - 1500007407268 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
21:43:27 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500007407270 Marionette TRACE 395 <- [1,8,null,{"value":null}]
21:43:27 INFO - 1500007407271 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
21:43:27 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 345ms
21:43:27 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:43:27 INFO - 1500007407273 Marionette TRACE 395 <- [1,9,null,{}]
21:43:27 INFO - 1500007407274 Marionette DEBUG Closed connection 395
21:43:27 INFO - 1500007407275 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50335
21:43:27 INFO - 1500007407276 Marionette DEBUG Closed connection 396
21:43:27 INFO - 1500007407277 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50336
21:43:27 INFO - 1500007407278 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:27 INFO - 1500007407278 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:27 INFO - 1500007407280 Marionette DEBUG Register listener.js for window 15
21:43:27 INFO - 1500007407283 Marionette TRACE 397 <- [1,1,null,{"sessionId":"3c60ec44-5afe-430a-a01a-8614e5324004","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:27 INFO - 1500007407284 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
21:43:27 INFO - 1500007407285 Marionette TRACE 397 <- [1,2,null,{}]
21:43:27 INFO - 1500007407286 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:27 INFO - 1500007407286 Marionette TRACE 397 <- [1,3,null,{}]
21:43:27 INFO - 1500007407287 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:27 INFO - 1500007407287 Marionette TRACE 397 <- [1,4,null,{}]
21:43:27 INFO - 1500007407289 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
21:43:27 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500007407291 Marionette TRACE 397 <- [1,5,null,{"value":null}]
21:43:27 INFO - 1500007407292 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
21:43:27 INFO - 1500007407293 Marionette TRACE 397 <- [1,6,null,{}]
21:43:27 INFO - 1500007407295 Marionette DEBUG Closed connection 397
21:43:27 INFO - 1500007407295 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50337
21:43:27 INFO - 1500007407296 Marionette DEBUG Closed connection 398
21:43:27 INFO - 1500007407297 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50338
21:43:27 INFO - 1500007407298 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:27 INFO - 1500007407298 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:27 INFO - 1500007407300 Marionette DEBUG Register listener.js for window 15
21:43:27 INFO - 1500007407302 Marionette TRACE 399 <- [1,1,null,{"sessionId":"35144280-358a-4079-8c91-80c94fcd765c","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:27 INFO - 1500007407304 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
21:43:27 INFO - 1500007407305 Marionette TRACE 399 <- [1,2,null,{}]
21:43:27 INFO - 1500007407307 Marionette DEBUG Closed connection 399
21:43:27 INFO - 1500007407307 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50341
21:43:27 INFO - 1500007407308 Marionette DEBUG Closed connection 400
21:43:27 INFO - 1500007407309 Marionette DEBUG Accepted connection 401 from 127.0.0.1:50342
21:43:27 INFO - 1500007407310 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:27 INFO - 1500007407310 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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}
21:43:27 INFO - 1500007407312 Marionette DEBUG Register listener.js for window 15
21:43:27 INFO - 1500007407323 Marionette TRACE 401 <- [1,1,null,{"sessionId":"cbbbd237-de46-4967-988c-d59c7416a276","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":4004,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpj9zisl.mozrunner","moz:accessibilityChecks":false}}]
21:43:27 INFO - 1500007407325 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
21:43:27 INFO - 1500007407326 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:43:27 INFO - 1500007407328 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:43:27 INFO - 1500007407328 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:43:27 INFO - 1500007407344 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
21:43:27 INFO - 1500007407346 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
21:43:27 INFO - 1500007407349 Marionette TRACE 401 <- [1,2,null,{}]
21:43:27 INFO - 1500007407356 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:43:27 INFO - 1500007407358 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"10882041-69dd-4919-bdbe-b585e15bdbda","ELEMENT":"10882041-69dd-4919-bdbe-b585e15bdbda"}}]
21:43:27 INFO - 1500007407360 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"10882041-69dd-4919-bdbe-b585e15bdbda"}]
21:43:27 INFO - 1500007407382 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
21:43:27 INFO - 1500007407383 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"10882041-69dd-4919-bdbe-b585e15bdbda"}]
21:43:27 INFO - 1500007407396 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element