could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nElementNotInteractableError@chrome://marionette/content/error.js:311:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:176: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
15:20:04 INFO - 1498774804155 Marionette TRACE 359 -> [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}]
15:20:04 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498774804158 Marionette TRACE 359 <- [1,5,null,{"value":null}]
15:20:04 INFO - 1498774804158 Marionette TRACE 359 -> [0,6,"deleteSession",{}]
15:20:04 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 144ms
15:20:04 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
15:20:04 INFO - 1498774804160 Marionette TRACE 359 <- [1,6,null,{}]
15:20:04 INFO - 1498774804161 Marionette DEBUG Closed connection 359
15:20:04 INFO - 1498774804162 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49732
15:20:04 INFO - 1498774804163 Marionette DEBUG Closed connection 360
15:20:04 INFO - 1498774804164 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49733
15:20:04 INFO - 1498774804165 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:04 INFO - 1498774804165 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:04 INFO - 1498774804168 Marionette DEBUG Register listener.js for window 7
15:20:04 INFO - 1498774804170 Marionette TRACE 361 <- [1,1,null,{"sessionId":"2f836ac3-071d-48c3-9452-aaaff66dde8a","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:04 INFO - 1498774804172 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
15:20:04 INFO - 1498774804172 Marionette TRACE 361 <- [1,2,null,{}]
15:20:04 INFO - 1498774804173 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:20:04 INFO - 1498774804173 Marionette TRACE 361 <- [1,3,null,{}]
15:20:04 INFO - 1498774804174 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
15:20:04 INFO - 1498774804175 Marionette TRACE 361 <- [1,4,null,{}]
15:20:04 INFO - 1498774804176 Marionette TRACE 361 -> [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}]
15:20:04 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498774804179 Marionette TRACE 361 <- [1,5,null,{"value":null}]
15:20:04 INFO - 1498774804180 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
15:20:04 INFO - 1498774804181 Marionette TRACE 361 <- [1,6,null,{}]
15:20:04 INFO - 1498774804183 Marionette DEBUG Closed connection 361
15:20:04 INFO - 1498774804183 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49734
15:20:04 INFO - 1498774804184 Marionette DEBUG Closed connection 362
15:20:04 INFO - 1498774804185 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49735
15:20:04 INFO - 1498774804186 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:04 INFO - 1498774804186 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:04 INFO - 1498774804188 Marionette DEBUG Register listener.js for window 7
15:20:04 INFO - 1498774804191 Marionette TRACE 363 <- [1,1,null,{"sessionId":"2e830716-279e-48ef-9cc3-da3d37e7ae8b","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:04 INFO - 1498774804192 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
15:20:04 INFO - 1498774804193 Marionette TRACE 363 <- [1,2,null,{}]
15:20:04 INFO - 1498774804195 Marionette DEBUG Closed connection 363
15:20:04 INFO - 1498774804196 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49736
15:20:04 INFO - 1498774804196 Marionette DEBUG Closed connection 364
15:20:04 INFO - 1498774804197 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49737
15:20:04 INFO - 1498774804198 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:20:04 INFO - 1498774804199 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:04 INFO - 1498774804201 Marionette DEBUG Register listener.js for window 7
15:20:04 INFO - 1498774804204 Marionette TRACE 365 <- [1,1,null,{"sessionId":"95cd6f37-490d-4d5b-891b-30adc84dc3a7","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:04 INFO - 1498774804205 Marionette TRACE 365 -> [0,2,"get",{"url":"http://127.0.0.1:49335/clicks.html"}]
15:20:04 INFO - 1498774804207 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/hidden.html"
15:20:04 INFO - 1498774804225 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/hidden.html"
15:20:04 INFO - 1498774804226 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/hidden.html"
15:20:04 INFO - 1498774804246 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/clicks.html"
15:20:04 INFO - 1498774804248 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/clicks.html"
15:20:04 INFO - 1498774804251 Marionette TRACE 365 <- [1,2,null,{}]
15:20:04 INFO - 1498774804264 Marionette TRACE 365 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
15:20:04 INFO - 1498774804267 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"17342060-73d5-4d50-89f6-9e45367f30eb","ELEMENT":"17342060-73d5-4d50-89f6-9e45367f30eb"}}]
15:20:04 INFO - 1498774804268 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"17342060-73d5-4d50-89f6-9e45367f30eb"}]
15:20:04 INFO - 1498774804313 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/clicks.html"
15:20:04 INFO - 1498774804326 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/clicks.html"
15:20:04 INFO - 1498774804326 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/clicks.html"
15:20:04 INFO - 1498774804348 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49335/test.html"
15:20:04 INFO - 1498774804350 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49335/test.html"
15:20:04 INFO - 1498774804352 Marionette TRACE 365 <- [1,4,null,{}]
15:20:04 INFO - 1498774804364 Marionette TRACE 365 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
15:20:04 INFO - 1498774804367 Marionette TRACE 365 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ac86806b-5206-487b-ac3f-fa158d6a66c1","ELEMENT":"ac86806b-5206-487b-ac3f-fa158d6a66c1"}}]
15:20:04 INFO - 1498774804368 Marionette TRACE 365 -> [0,6,"getTitle",{}]
15:20:04 INFO - 1498774804369 Marionette TRACE 365 <- [1,6,null,{"value":"Marionette Test"}]
15:20:04 INFO - 1498774804371 Marionette TRACE 365 -> [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}]
15:20:04 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498774804374 Marionette TRACE 365 <- [1,7,null,{"value":null}]
15:20:04 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 221ms
15:20:04 INFO - 1498774804380 Marionette TRACE 365 -> [0,8,"deleteSession",{}]
15:20:04 INFO - 1498774804381 Marionette TRACE 365 <- [1,8,null,{}]
15:20:04 INFO - TEST-START | test_click.py TestClick.test_container_element
15:20:04 INFO - 1498774804383 Marionette DEBUG Closed connection 365
15:20:04 INFO - 1498774804385 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49740
15:20:04 INFO - 1498774804385 Marionette DEBUG Closed connection 366
15:20:04 INFO - 1498774804386 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49741
15:20:04 INFO - 1498774804387 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:04 INFO - 1498774804388 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:04 INFO - 1498774804390 Marionette DEBUG Register listener.js for window 7
15:20:04 INFO - 1498774804396 Marionette TRACE 367 <- [1,1,null,{"sessionId":"3cf09e9e-9973-47ee-ba14-df34d9f773bf","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:04 INFO - 1498774804397 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
15:20:04 INFO - 1498774804398 Marionette TRACE 367 <- [1,2,null,{}]
15:20:04 INFO - 1498774804399 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:20:04 INFO - 1498774804399 Marionette TRACE 367 <- [1,3,null,{}]
15:20:04 INFO - 1498774804400 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
15:20:04 INFO - 1498774804400 Marionette TRACE 367 <- [1,4,null,{}]
15:20:04 INFO - 1498774804402 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')","sandbox":"simpletest","line":316}]
15:20:04 INFO - TEST-START: test_click.py TestClick.test_container_element1498774804405 Marionette TRACE 367 <- [1,5,null,{"value":null}]
15:20:04 INFO - 1498774804405 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
15:20:04 INFO - 1498774804407 Marionette TRACE 367 <- [1,6,null,{}]
15:20:04 INFO - 1498774804409 Marionette DEBUG Closed connection 367
15:20:04 INFO - 1498774804410 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49742
15:20:04 INFO - 1498774804411 Marionette DEBUG Closed connection 368
15:20:04 INFO - 1498774804412 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49743
15:20:04 INFO - 1498774804412 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:04 INFO - 1498774804413 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:04 INFO - 1498774804415 Marionette DEBUG Register listener.js for window 7
15:20:04 INFO - 1498774804418 Marionette TRACE 369 <- [1,1,null,{"sessionId":"7b3f8bb9-7e41-4bd6-9e60-8b39a05dfe78","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:04 INFO - 1498774804419 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
15:20:04 INFO - 1498774804420 Marionette TRACE 369 <- [1,2,null,{}]
15:20:04 INFO - 1498774804422 Marionette DEBUG Closed connection 369
15:20:04 INFO - 1498774804423 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49744
15:20:04 INFO - 1498774804424 Marionette DEBUG Closed connection 370
15:20:04 INFO - 1498774804425 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49745
15:20:04 INFO - 1498774804426 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:20:04 INFO - 1498774804427 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:04 INFO - 1498774804429 Marionette DEBUG Register listener.js for window 7
15:20:04 INFO - 1498774804432 Marionette TRACE 371 <- [1,1,null,{"sessionId":"2d786344-d121-4357-9380-bee1d3c19344","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:04 INFO - 1498774804433 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%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"}]
15:20:04 INFO - 1498774804434 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49335/test.html"
15:20:04 INFO - 1498774804436 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49335/test.html"
15:20:04 INFO - 1498774804436 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49335/test.html"
15:20:04 INFO - 1498774804456 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"
15:20:04 INFO - 1498774804463 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"
15:20:04 INFO - 1498774804466 Marionette TRACE 371 <- [1,2,null,{}]
15:20:04 INFO - 1498774804467 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:20:04 INFO - 1498774804469 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2f75fd8e-39b7-4bb1-b2d1-d6ddf2da9b62","ELEMENT":"2f75fd8e-39b7-4bb1-b2d1-d6ddf2da9b62"}}]
15:20:04 INFO - 1498774804470 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"2f75fd8e-39b7-4bb1-b2d1-d6ddf2da9b62"}]
15:20:04 INFO - 1498774804712 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:20:04 INFO - 1498774804713 Marionette TRACE 371 <- [1,4,null,{}]
15:20:04 INFO - 1498774804714 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"2f75fd8e-39b7-4bb1-b2d1-d6ddf2da9b62","name":"selected"}]
15:20:04 INFO - 1498774804718 Marionette TRACE 371 <- [1,5,null,{"value":true}]
15:20:04 INFO - 1498774804719 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')","sandbox":"simpletest","line":328}]
15:20:04 INFO - TEST-END: test_click.py TestClick.test_container_element1498774804722 Marionette TRACE 371 <- [1,6,null,{"value":null}]
15:20:04 INFO - 1498774804723 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
15:20:04 INFO - 1498774804724 Marionette TRACE 371 <- [1,7,null,{}]
15:20:04 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 342ms
15:20:04 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
15:20:04 INFO - 1498774804726 Marionette DEBUG Closed connection 371
15:20:04 INFO - 1498774804727 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49746
15:20:04 INFO - 1498774804728 Marionette DEBUG Closed connection 372
15:20:04 INFO - 1498774804728 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49747
15:20:04 INFO - 1498774804729 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:04 INFO - 1498774804730 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:04 INFO - 1498774804732 Marionette DEBUG Register listener.js for window 7
15:20:04 INFO - 1498774804735 Marionette TRACE 373 <- [1,1,null,{"sessionId":"4a71642e-76ba-4446-81eb-e233e4650346","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:04 INFO - 1498774804736 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
15:20:04 INFO - 1498774804736 Marionette TRACE 373 <- [1,2,null,{}]
15:20:04 INFO - 1498774804737 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:20:04 INFO - 1498774804737 Marionette TRACE 373 <- [1,3,null,{}]
15:20:04 INFO - 1498774804738 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
15:20:04 INFO - 1498774804739 Marionette TRACE 373 <- [1,4,null,{}]
15:20:04 INFO - 1498774804740 Marionette TRACE 373 -> [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}]
15:20:04 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498774804743 Marionette TRACE 373 <- [1,5,null,{"value":null}]
15:20:04 INFO - 1498774804744 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
15:20:04 INFO - 1498774804745 Marionette TRACE 373 <- [1,6,null,{}]
15:20:04 INFO - 1498774804747 Marionette DEBUG Closed connection 373
15:20:04 INFO - 1498774804747 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49748
15:20:04 INFO - 1498774804748 Marionette DEBUG Closed connection 374
15:20:04 INFO - 1498774804749 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49749
15:20:04 INFO - 1498774804750 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:04 INFO - 1498774804750 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:04 INFO - 1498774804752 Marionette DEBUG Register listener.js for window 7
15:20:04 INFO - 1498774804755 Marionette TRACE 375 <- [1,1,null,{"sessionId":"c588bcb4-65d4-4b5a-8d67-16e12d0a4662","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:04 INFO - 1498774804756 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
15:20:04 INFO - 1498774804757 Marionette TRACE 375 <- [1,2,null,{}]
15:20:04 INFO - 1498774804759 Marionette DEBUG Closed connection 375
15:20:04 INFO - 1498774804760 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49750
15:20:04 INFO - 1498774804760 Marionette DEBUG Closed connection 376
15:20:04 INFO - 1498774804761 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49751
15:20:04 INFO - 1498774804762 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:20:04 INFO - 1498774804763 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:04 INFO - 1498774804765 Marionette DEBUG Register listener.js for window 7
15:20:04 INFO - 1498774804767 Marionette TRACE 377 <- [1,1,null,{"sessionId":"874dc5f4-f3df-4ba8-8536-c8129d2fd9fc","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:04 INFO - 1498774804768 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%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"}]
15:20:04 INFO - 1498774804770 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"
15:20:04 INFO - 1498774804772 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"
15:20:04 INFO - 1498774804772 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"
15:20:04 INFO - 1498774804787 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"
15:20:04 INFO - 1498774804790 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"
15:20:04 INFO - 1498774804798 Marionette TRACE 377 <- [1,2,null,{}]
15:20:04 INFO - 1498774804800 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:20:04 INFO - 1498774804802 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"84d85562-5cb2-4aeb-b25c-6e53fa005f1b","ELEMENT":"84d85562-5cb2-4aeb-b25c-6e53fa005f1b"}}]
15:20:04 INFO - 1498774804803 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"84d85562-5cb2-4aeb-b25c-6e53fa005f1b"}]
15:20:05 INFO - 1498774805050 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:20:05 INFO - 1498774805051 Marionette TRACE 377 <- [1,4,null,{}]
15:20:05 INFO - 1498774805052 Marionette TRACE 377 -> [0,5,"getElementProperty",{"id":"84d85562-5cb2-4aeb-b25c-6e53fa005f1b","name":"selected"}]
15:20:05 INFO - 1498774805056 Marionette TRACE 377 <- [1,5,null,{"value":true}]
15:20:05 INFO - 1498774805060 Marionette TRACE 377 -> [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}]
15:20:05 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498774805063 Marionette TRACE 377 <- [1,6,null,{"value":null}]
15:20:05 INFO - 1498774805064 Marionette TRACE 377 -> [0,7,"deleteSession",{}]
15:20:05 INFO - 1498774805065 Marionette TRACE 377 <- [1,7,null,{}]
15:20:05 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 341ms
15:20:05 INFO - TEST-START | test_click.py TestClick.test_css_transforms
15:20:05 INFO - 1498774805067 Marionette DEBUG Closed connection 377
15:20:05 INFO - 1498774805068 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49752
15:20:05 INFO - 1498774805069 Marionette DEBUG Closed connection 378
15:20:05 INFO - 1498774805069 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49753
15:20:05 INFO - 1498774805070 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:05 INFO - 1498774805071 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:05 INFO - 1498774805073 Marionette DEBUG Register listener.js for window 7
15:20:05 INFO - 1498774805076 Marionette TRACE 379 <- [1,1,null,{"sessionId":"0b1c2275-abfd-4452-b8d6-4c58779d5ab7","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:05 INFO - 1498774805077 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
15:20:05 INFO - 1498774805078 Marionette TRACE 379 <- [1,2,null,{}]
15:20:05 INFO - 1498774805078 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:20:05 INFO - 1498774805079 Marionette TRACE 379 <- [1,3,null,{}]
15:20:05 INFO - 1498774805080 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
15:20:05 INFO - 1498774805080 Marionette TRACE 379 <- [1,4,null,{}]
15:20:05 INFO - 1498774805082 Marionette TRACE 379 -> [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}]
15:20:05 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498774805085 Marionette TRACE 379 <- [1,5,null,{"value":null}]
15:20:05 INFO - 1498774805086 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
15:20:05 INFO - 1498774805087 Marionette TRACE 379 <- [1,6,null,{}]
15:20:05 INFO - 1498774805088 Marionette DEBUG Closed connection 379
15:20:05 INFO - 1498774805089 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49754
15:20:05 INFO - 1498774805090 Marionette DEBUG Closed connection 380
15:20:05 INFO - 1498774805091 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49755
15:20:05 INFO - 1498774805092 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:05 INFO - 1498774805092 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:05 INFO - 1498774805094 Marionette DEBUG Register listener.js for window 7
15:20:05 INFO - 1498774805097 Marionette TRACE 381 <- [1,1,null,{"sessionId":"900a54a0-58b4-4a6b-b700-b94111f2bf45","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:05 INFO - 1498774805098 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
15:20:05 INFO - 1498774805099 Marionette TRACE 381 <- [1,2,null,{}]
15:20:05 INFO - 1498774805101 Marionette DEBUG Closed connection 381
15:20:05 INFO - 1498774805101 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49756
15:20:05 INFO - 1498774805102 Marionette DEBUG Closed connection 382
15:20:05 INFO - 1498774805103 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49757
15:20:05 INFO - 1498774805104 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:20:05 INFO - 1498774805104 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:05 INFO - 1498774805106 Marionette DEBUG Register listener.js for window 7
15:20:05 INFO - 1498774805109 Marionette TRACE 383 <- [1,1,null,{"sessionId":"e80662ef-6870-4fcf-bb0b-d6b149dbd8e2","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:05 INFO - 1498774805110 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%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"}]
15:20:05 INFO - 1498774805111 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"
15:20:05 INFO - 1498774805113 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"
15:20:05 INFO - 1498774805113 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"
15:20:05 INFO - 1498774805130 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"
15:20:05 INFO - 1498774805132 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"
15:20:05 INFO - 1498774805136 Marionette TRACE 383 <- [1,2,null,{}]
15:20:05 INFO - 1498774805137 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
15:20:05 INFO - 1498774805139 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0d339ea6-6158-4cf5-8ffb-400ab0d39eee","ELEMENT":"0d339ea6-6158-4cf5-8ffb-400ab0d39eee"}}]
15:20:05 INFO - 1498774805146 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"0d339ea6-6158-4cf5-8ffb-400ab0d39eee"}]
15:20:05 INFO - 1498774805383 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:20:05 INFO - 1498774805384 Marionette TRACE 383 <- [1,4,null,{}]
15:20:05 INFO - 1498774805386 Marionette TRACE 383 -> [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}]
15:20:05 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498774805391 Marionette TRACE 383 <- [1,5,null,{"value":null}]
15:20:05 INFO - 1498774805393 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
15:20:05 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 332ms
15:20:05 INFO - 1498774805396 Marionette TRACE 383 <- [1,6,null,{}]
15:20:05 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
15:20:05 INFO - 1498774805400 Marionette DEBUG Closed connection 383
15:20:05 INFO - 1498774805401 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49758
15:20:05 INFO - 1498774805441 Marionette DEBUG Closed connection 384
15:20:05 INFO - 1498774805442 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49759
15:20:05 INFO - 1498774805444 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:05 INFO - 1498774805446 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:05 INFO - 1498774805449 Marionette DEBUG Register listener.js for window 7
15:20:05 INFO - 1498774805454 Marionette TRACE 385 <- [1,1,null,{"sessionId":"a2ef3e50-12fe-49ee-a180-ebae78a0e0a5","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:05 INFO - 1498774805456 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
15:20:05 INFO - 1498774805457 Marionette TRACE 385 <- [1,2,null,{}]
15:20:05 INFO - 1498774805527 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:20:05 INFO - 1498774805528 Marionette TRACE 385 <- [1,3,null,{}]
15:20:05 INFO - 1498774805530 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
15:20:05 INFO - 1498774805531 Marionette TRACE 385 <- [1,4,null,{}]
15:20:05 INFO - 1498774805533 Marionette TRACE 385 -> [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}]
15:20:05 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498774805537 Marionette TRACE 385 <- [1,5,null,{"value":null}]
15:20:05 INFO - 1498774805538 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
15:20:05 INFO - 1498774805540 Marionette TRACE 385 <- [1,6,null,{}]
15:20:05 INFO - 1498774805544 Marionette DEBUG Closed connection 385
15:20:05 INFO - 1498774805545 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49760
15:20:05 INFO - 1498774805557 Marionette DEBUG Closed connection 386
15:20:05 INFO - 1498774805558 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49761
15:20:05 INFO - 1498774805562 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:05 INFO - 1498774805564 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:05 INFO - 1498774805566 Marionette DEBUG Register listener.js for window 7
15:20:05 INFO - 1498774805572 Marionette TRACE 387 <- [1,1,null,{"sessionId":"89aa0529-8e37-4482-b852-764057e5fb39","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:05 INFO - 1498774805573 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
15:20:05 INFO - 1498774805575 Marionette TRACE 387 <- [1,2,null,{}]
15:20:05 INFO - 1498774805577 Marionette DEBUG Closed connection 387
15:20:05 INFO - 1498774805579 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49762
15:20:05 INFO - 1498774805579 Marionette DEBUG Closed connection 388
15:20:05 INFO - 1498774805580 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49763
15:20:05 INFO - 1498774805581 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:20:05 INFO - 1498774805583 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:05 INFO - 1498774805585 Marionette DEBUG Register listener.js for window 7
15:20:05 INFO - 1498774805590 Marionette TRACE 389 <- [1,1,null,{"sessionId":"3222794a-4b57-417b-adae-6f674c3f3378","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:05 INFO - 1498774805592 Marionette TRACE 389 -> [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"}]
15:20:05 INFO - 1498774805594 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"
15:20:05 INFO - 1498774805598 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"
15:20:05 INFO - 1498774805598 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"
15:20:05 INFO - 1498774805619 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"
15:20:05 INFO - 1498774805624 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"
15:20:05 INFO - 1498774805636 Marionette TRACE 389 <- [1,2,null,{}]
15:20:05 INFO - 1498774805637 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
15:20:05 INFO - 1498774805641 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"56407e74-946b-43e7-ba1d-e1858dd38bf0","ELEMENT":"56407e74-946b-43e7-ba1d-e1858dd38bf0"}}]
15:20:05 INFO - 1498774805642 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"56407e74-946b-43e7-ba1d-e1858dd38bf0"}]
15:20:05 INFO - 1498774805888 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:20:05 INFO - 1498774805889 Marionette TRACE 389 <- [1,4,null,{}]
15:20:05 INFO - 1498774805899 Marionette TRACE 389 -> [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}]
15:20:05 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498774805910 Marionette TRACE 389 <- [1,5,null,{"value":null}]
15:20:05 INFO - 1498774805912 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
15:20:05 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 517ms
15:20:05 INFO - TEST-START | test_click.py TestClick.test_input_file
15:20:05 INFO - 1498774805914 Marionette TRACE 389 <- [1,6,null,{}]
15:20:05 INFO - 1498774805916 Marionette DEBUG Closed connection 389
15:20:05 INFO - 1498774805917 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49764
15:20:05 INFO - 1498774805918 Marionette DEBUG Closed connection 390
15:20:05 INFO - 1498774805919 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49765
15:20:05 INFO - 1498774805920 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:05 INFO - 1498774805921 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:05 INFO - 1498774805923 Marionette DEBUG Register listener.js for window 7
15:20:05 INFO - 1498774805927 Marionette TRACE 391 <- [1,1,null,{"sessionId":"24174269-d8d9-4012-ab6e-9e7892989001","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:05 INFO - 1498774805928 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
15:20:05 INFO - 1498774805928 Marionette TRACE 391 <- [1,2,null,{}]
15:20:05 INFO - 1498774805929 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:20:05 INFO - 1498774805930 Marionette TRACE 391 <- [1,3,null,{}]
15:20:05 INFO - 1498774805930 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
15:20:05 INFO - 1498774805931 Marionette TRACE 391 <- [1,4,null,{}]
15:20:05 INFO - 1498774805933 Marionette TRACE 391 -> [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}]
15:20:05 INFO - TEST-START: test_click.py TestClick.test_input_file1498774805936 Marionette TRACE 391 <- [1,5,null,{"value":null}]
15:20:05 INFO - 1498774805937 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
15:20:05 INFO - 1498774805938 Marionette TRACE 391 <- [1,6,null,{}]
15:20:05 INFO - 1498774805940 Marionette DEBUG Closed connection 391
15:20:05 INFO - 1498774805941 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49766
15:20:05 INFO - 1498774805942 Marionette DEBUG Closed connection 392
15:20:05 INFO - 1498774805943 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49767
15:20:05 INFO - 1498774805944 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:05 INFO - 1498774805944 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:05 INFO - 1498774805947 Marionette DEBUG Register listener.js for window 7
15:20:05 INFO - 1498774805951 Marionette TRACE 393 <- [1,1,null,{"sessionId":"b499a1d7-8be6-4c77-b8bc-24bc6f6f3dec","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:05 INFO - 1498774805952 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
15:20:05 INFO - 1498774805953 Marionette TRACE 393 <- [1,2,null,{}]
15:20:05 INFO - 1498774805955 Marionette DEBUG Closed connection 393
15:20:05 INFO - 1498774805956 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49768
15:20:05 INFO - 1498774805957 Marionette DEBUG Closed connection 394
15:20:05 INFO - 1498774805958 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49769
15:20:05 INFO - 1498774805959 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:20:05 INFO - 1498774805959 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:05 INFO - 1498774805962 Marionette DEBUG Register listener.js for window 7
15:20:05 INFO - 1498774805966 Marionette TRACE 395 <- [1,1,null,{"sessionId":"f4047c70-0c3c-4363-9613-e23afaf6c52c","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:05 INFO - 1498774805968 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
15:20:05 INFO - 1498774805969 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"
15:20:05 INFO - 1498774805990 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"
15:20:05 INFO - 1498774805990 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"
15:20:06 INFO - 1498774806013 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:20:06 INFO - 1498774806015 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:20:06 INFO - 1498774806019 Marionette TRACE 395 <- [1,2,null,{}]
15:20:06 INFO - 1498774806021 Marionette TRACE 395 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
15:20:06 INFO - 1498774806030 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cdaa6936-fc96-48a9-ab8b-7b32c54d5fe4","ELEMENT":"cdaa6936-fc96-48a9-ab8b-7b32c54d5fe4"}}]
15:20:06 INFO - 1498774806032 Marionette TRACE 395 -> [0,4,"clickElement",{"id":"cdaa6936-fc96-48a9-ab8b-7b32c54d5fe4"}]
15:20:06 INFO - 1498774806060 Marionette TRACE 395 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nInvalidArgumentError@chrome://marionette/content/error.js:325:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:158: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
15:20:06 INFO - 1498774806063 Marionette TRACE 395 -> [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}]
15:20:06 INFO - TEST-END: test_click.py TestClick.test_input_file1498774806065 Marionette TRACE 395 <- [1,5,null,{"value":null}]
15:20:06 INFO - 1498774806066 Marionette TRACE 395 -> [0,6,"deleteSession",{}]
15:20:06 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 154ms
15:20:06 INFO - TEST-START | test_click.py TestClick.test_obscured_element
15:20:06 INFO - 1498774806068 Marionette TRACE 395 <- [1,6,null,{}]
15:20:06 INFO - 1498774806071 Marionette DEBUG Closed connection 395
15:20:06 INFO - 1498774806072 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49770
15:20:06 INFO - 1498774806073 Marionette DEBUG Closed connection 396
15:20:06 INFO - 1498774806074 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49771
15:20:06 INFO - 1498774806075 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:06 INFO - 1498774806077 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:06 INFO - 1498774806079 Marionette DEBUG Register listener.js for window 7
15:20:06 INFO - 1498774806083 Marionette TRACE 397 <- [1,1,null,{"sessionId":"9f8c03d6-03ed-44c3-9fa6-9a5f34916204","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:06 INFO - 1498774806084 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
15:20:06 INFO - 1498774806085 Marionette TRACE 397 <- [1,2,null,{}]
15:20:06 INFO - 1498774806086 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:20:06 INFO - 1498774806086 Marionette TRACE 397 <- [1,3,null,{}]
15:20:06 INFO - 1498774806087 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
15:20:06 INFO - 1498774806088 Marionette TRACE 397 <- [1,4,null,{}]
15:20:06 INFO - 1498774806089 Marionette TRACE 397 -> [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}]
15:20:06 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498774806093 Marionette TRACE 397 <- [1,5,null,{"value":null}]
15:20:06 INFO - 1498774806094 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
15:20:06 INFO - 1498774806096 Marionette TRACE 397 <- [1,6,null,{}]
15:20:06 INFO - 1498774806098 Marionette DEBUG Closed connection 397
15:20:06 INFO - 1498774806098 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49772
15:20:06 INFO - 1498774806099 Marionette DEBUG Closed connection 398
15:20:06 INFO - 1498774806100 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49773
15:20:06 INFO - 1498774806101 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:06 INFO - 1498774806101 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:06 INFO - 1498774806103 Marionette DEBUG Register listener.js for window 7
15:20:06 INFO - 1498774806107 Marionette TRACE 399 <- [1,1,null,{"sessionId":"b5a78745-0616-435d-be25-b1ce85777627","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:06 INFO - 1498774806143 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
15:20:06 INFO - 1498774806144 Marionette TRACE 399 <- [1,2,null,{}]
15:20:06 INFO - 1498774806147 Marionette DEBUG Closed connection 399
15:20:06 INFO - 1498774806147 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49774
15:20:06 INFO - 1498774806149 Marionette DEBUG Closed connection 400
15:20:06 INFO - 1498774806149 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49775
15:20:06 INFO - 1498774806155 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:20:06 INFO - 1498774806155 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:06 INFO - 1498774806157 Marionette DEBUG Register listener.js for window 7
15:20:06 INFO - 1498774806160 Marionette TRACE 401 <- [1,1,null,{"sessionId":"ff2ac230-af9a-4f02-97b1-209647b1ae75","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:06 INFO - 1498774806162 Marionette TRACE 401 -> [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"}]
15:20:06 INFO - 1498774806163 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:20:06 INFO - 1498774806166 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:20:06 INFO - 1498774806166 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:20:06 INFO - 1498774806187 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"
15:20:06 INFO - 1498774806190 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"
15:20:06 INFO - 1498774806200 Marionette TRACE 401 <- [1,2,null,{}]
15:20:06 INFO - 1498774806201 Marionette TRACE 401 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
15:20:06 INFO - 1498774806204 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4f85cad6-1257-47f4-9f26-0598983261cc","ELEMENT":"4f85cad6-1257-47f4-9f26-0598983261cc"}}]
15:20:06 INFO - 1498774806205 Marionette TRACE 401 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
15:20:06 INFO - 1498774806208 Marionette TRACE 401 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f1d47572-c3ca-49ac-8c75-193027ccbc7e","ELEMENT":"f1d47572-c3ca-49ac-8c75-193027ccbc7e"}}]
15:20:06 INFO - 1498774806211 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"4f85cad6-1257-47f4-9f26-0598983261cc"}]
15:20:06 INFO - 1498774806450 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:20:06 INFO - 1498774806451 Marionette TRACE 401 <- [1,5,null,{}]
15:20:06 INFO - 1498774806452 Marionette TRACE 401 -> [0,6,"clickElement",{"id":"f1d47572-c3ca-49ac-8c75-193027ccbc7e"}]
15:20:06 INFO - 1498774806468 Marionette TRACE 401 <- [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:222:5\nElementClickInterceptedError@chrome://marionette/content/error.js:304:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:185: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
15:20:06 INFO - 1498774806469 Marionette TRACE 401 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
15:20:06 INFO - 1498774806473 Marionette TRACE 401 <- [1,7,null,{"value":false}]
15:20:06 INFO - 1498774806474 Marionette TRACE 401 -> [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}]
15:20:06 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498774806477 Marionette TRACE 401 <- [1,8,null,{"value":null}]
15:20:06 INFO - 1498774806478 Marionette TRACE 401 -> [0,9,"deleteSession",{}]
15:20:06 INFO - 1498774806479 Marionette TRACE 401 <- [1,9,null,{}]
15:20:06 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 411ms
15:20:06 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
15:20:06 INFO - 1498774806481 Marionette DEBUG Closed connection 401
15:20:06 INFO - 1498774806482 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49776
15:20:06 INFO - 1498774806483 Marionette DEBUG Closed connection 402
15:20:06 INFO - 1498774806484 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49777
15:20:06 INFO - 1498774806484 Marionette TRACE 403 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:06 INFO - 1498774806485 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:06 INFO - 1498774806487 Marionette DEBUG Register listener.js for window 7
15:20:06 INFO - 1498774806491 Marionette TRACE 403 <- [1,1,null,{"sessionId":"3e4638ad-0620-458d-b726-6cce3cb4577c","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:06 INFO - 1498774806492 Marionette TRACE 403 -> [0,2,"setTimeouts",{"script":30000}]
15:20:06 INFO - 1498774806492 Marionette TRACE 403 <- [1,2,null,{}]
15:20:06 INFO - 1498774806493 Marionette TRACE 403 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:20:06 INFO - 1498774806494 Marionette TRACE 403 <- [1,3,null,{}]
15:20:06 INFO - 1498774806495 Marionette TRACE 403 -> [0,4,"setTimeouts",{"implicit":0}]
15:20:06 INFO - 1498774806495 Marionette TRACE 403 <- [1,4,null,{}]
15:20:06 INFO - 1498774806497 Marionette TRACE 403 -> [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}]
15:20:06 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498774806500 Marionette TRACE 403 <- [1,5,null,{"value":null}]
15:20:06 INFO - 1498774806500 Marionette TRACE 403 -> [0,6,"deleteSession",{}]
15:20:06 INFO - 1498774806502 Marionette TRACE 403 <- [1,6,null,{}]
15:20:06 INFO - 1498774806503 Marionette DEBUG Closed connection 403
15:20:06 INFO - 1498774806504 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49778
15:20:06 INFO - 1498774806505 Marionette DEBUG Closed connection 404
15:20:06 INFO - 1498774806506 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49779
15:20:06 INFO - 1498774806506 Marionette TRACE 405 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:20:06 INFO - 1498774806514 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:06 INFO - 1498774806516 Marionette DEBUG Register listener.js for window 7
15:20:06 INFO - 1498774806520 Marionette TRACE 405 <- [1,1,null,{"sessionId":"98b183a3-7d0e-45b9-8f47-e65a02a11288","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:06 INFO - 1498774806521 Marionette TRACE 405 -> [0,2,"deleteSession",{}]
15:20:06 INFO - 1498774806522 Marionette TRACE 405 <- [1,2,null,{}]
15:20:06 INFO - 1498774806524 Marionette DEBUG Closed connection 405
15:20:06 INFO - 1498774806524 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49780
15:20:06 INFO - 1498774806525 Marionette DEBUG Closed connection 406
15:20:06 INFO - 1498774806526 Marionette DEBUG Accepted connection 407 from 127.0.0.1:49781
15:20:06 INFO - 1498774806527 Marionette TRACE 407 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:20:06 INFO - 1498774806528 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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}
15:20:06 INFO - 1498774806531 Marionette DEBUG Register listener.js for window 7
15:20:06 INFO - 1498774806535 Marionette TRACE 407 <- [1,1,null,{"sessionId":"54a8fb07-16b2-49a1-b0fa-15dc49584298","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":896,"moz:profile":"c:\\users\\cltbld\\appdata\\local\\temp\\tmpza13n3.mozrunner","moz:accessibilityChecks":false}}]
15:20:06 INFO - 1498774806536 Marionette TRACE 407 -> [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"}]
15:20:06 INFO - 1498774806537 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"
15:20:06 INFO - 1498774806540 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"
15:20:06 INFO - 1498774806541 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"
15:20:06 INFO - 1498774806560 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"
15:20:06 INFO - 1498774806562 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"
15:20:06 INFO - 1498774806566 Marionette TRACE 407 <- [1,2,null,{}]
15:20:06 INFO - 1498774806568 Marionette TRACE 407 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
15:20:06 INFO - 1498774806570 Marionette TRACE 407 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5664d836-ea07-481f-a2a4-e56b9c2e10a8","ELEMENT":"5664d836-ea07-481f-a2a4-e56b9c2e10a8"}}]
15:20:06 INFO - 1498774806571 Marionette TRACE 407 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5664d836-ea07-481f-a2a4-e56b9c2e10a8"}]
15:20:06 INFO - 1498774806600 Marionette TRACE 407 <- [1,4,null,{"value":"none"}]
15:20:06 INFO - 1498774806602 Marionette TRACE 407 -> [0,5,"clickElement",{"id":"5664d836-ea07-481f-a2a4-e56b9c2e10a8"}]
15:20:06 INFO - 1498774806623 Marionette TRACE 407 <- [1,5,{"error":"element click intercepted","message":"Element