could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:23:41 INFO - 1499109821701 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
12:23:41 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499109821703 Marionette TRACE 352 <- [1,5,null,{"value":null}]
12:23:41 INFO - 1499109821704 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
12:23:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 116ms
12:23:41 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
12:23:41 INFO - 1499109821706 Marionette TRACE 352 <- [1,6,null,{}]
12:23:41 INFO - 1499109821707 Marionette DEBUG Closed connection 352
12:23:41 INFO - 1499109821708 Marionette DEBUG Accepted connection 353 from 127.0.0.1:50346
12:23:41 INFO - 1499109821709 Marionette DEBUG Closed connection 353
12:23:41 INFO - 1499109821710 Marionette DEBUG Accepted connection 354 from 127.0.0.1:50347
12:23:41 INFO - 1499109821710 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:41 INFO - 1499109821712 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:41 INFO - 1499109821714 Marionette DEBUG Register listener.js for window 4294967297
12:23:41 INFO - 1499109821717 Marionette TRACE 354 <- [1,1,null,{"sessionId":"17303182-2705-46d8-818b-f2441cafbb1e","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:41 INFO - 1499109821718 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
12:23:41 INFO - 1499109821720 Marionette TRACE 354 <- [1,2,null,{}]
12:23:41 INFO - 1499109821721 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:41 INFO - 1499109821721 Marionette TRACE 354 <- [1,3,null,{}]
12:23:41 INFO - 1499109821722 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:41 INFO - 1499109821723 Marionette TRACE 354 <- [1,4,null,{}]
12:23:41 INFO - 1499109821725 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
12:23:41 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499109821728 Marionette TRACE 354 <- [1,5,null,{"value":null}]
12:23:41 INFO - 1499109821729 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
12:23:41 INFO - 1499109821730 Marionette TRACE 354 <- [1,6,null,{}]
12:23:41 INFO - 1499109821731 Marionette DEBUG Closed connection 354
12:23:41 INFO - 1499109821732 Marionette DEBUG Accepted connection 355 from 127.0.0.1:50348
12:23:41 INFO - 1499109821733 Marionette DEBUG Closed connection 355
12:23:41 INFO - 1499109821734 Marionette DEBUG Accepted connection 356 from 127.0.0.1:50349
12:23:41 INFO - 1499109821734 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:41 INFO - 1499109821735 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:41 INFO - 1499109821737 Marionette DEBUG Register listener.js for window 4294967297
12:23:41 INFO - 1499109821740 Marionette TRACE 356 <- [1,1,null,{"sessionId":"eb93ec10-ce8a-4426-8cc6-8ec395772eb8","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:41 INFO - 1499109821741 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
12:23:41 INFO - 1499109821743 Marionette TRACE 356 <- [1,2,null,{}]
12:23:41 INFO - 1499109821744 Marionette DEBUG Closed connection 356
12:23:41 INFO - 1499109821745 Marionette DEBUG Accepted connection 357 from 127.0.0.1:50350
12:23:41 INFO - 1499109821745 Marionette DEBUG Closed connection 357
12:23:41 INFO - 1499109821746 Marionette DEBUG Accepted connection 358 from 127.0.0.1:50351
12:23:41 INFO - 1499109821747 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:41 INFO - 1499109821748 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:41 INFO - 1499109821750 Marionette DEBUG Register listener.js for window 4294967297
12:23:41 INFO - 1499109821761 Marionette TRACE 358 <- [1,1,null,{"sessionId":"78118245-3aa0-4766-b8ff-5bc715279f0a","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:41 INFO - 1499109821762 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49904/clicks.html"}]
12:23:41 INFO - 1499109821764 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49904/hidden.html"
12:23:41 INFO - 1499109821775 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49904/hidden.html"
12:23:41 INFO - 1499109821775 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49904/hidden.html"
12:23:41 INFO - 1499109821782 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49904/clicks.html"
12:23:41 INFO - 1499109821787 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49904/clicks.html"
12:23:41 INFO - 1499109821790 Marionette TRACE 358 <- [1,2,null,{}]
12:23:41 INFO - 1499109821794 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
12:23:41 INFO - 1499109821797 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e69314b1-b672-4ec2-bbd7-f0ab1fe15f81","ELEMENT":"e69314b1-b672-4ec2-bbd7-f0ab1fe15f81"}}]
12:23:41 INFO - 1499109821799 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"e69314b1-b672-4ec2-bbd7-f0ab1fe15f81"}]
12:23:41 INFO - 1499109821841 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49904/clicks.html"
12:23:41 INFO - 1499109821848 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49904/clicks.html"
12:23:41 INFO - 1499109821848 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49904/clicks.html"
12:23:41 INFO - 1499109821858 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49904/test.html"
12:23:41 INFO - 1499109821859 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49904/test.html"
12:23:41 INFO - 1499109821863 Marionette TRACE 358 <- [1,4,null,{}]
12:23:41 INFO - 1499109821868 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
12:23:41 INFO - 1499109821876 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f98a0228-440e-49a1-9d2d-2b6569a862e7","ELEMENT":"f98a0228-440e-49a1-9d2d-2b6569a862e7"}}]
12:23:41 INFO - 1499109821877 Marionette TRACE 358 -> [0,6,"getTitle",{}]
12:23:41 INFO - 1499109821879 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
12:23:41 INFO - 1499109821880 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
12:23:41 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499109821883 Marionette TRACE 358 <- [1,7,null,{"value":null}]
12:23:41 INFO - 1499109821884 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
12:23:41 INFO - 1499109821886 Marionette TRACE 358 <- [1,8,null,{}]
12:23:41 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 181ms
12:23:41 INFO - TEST-START | test_click.py TestClick.test_container_element
12:23:41 INFO - 1499109821888 Marionette DEBUG Closed connection 358
12:23:41 INFO - 1499109821889 Marionette DEBUG Accepted connection 359 from 127.0.0.1:50354
12:23:41 INFO - 1499109821890 Marionette DEBUG Closed connection 359
12:23:41 INFO - 1499109821890 Marionette DEBUG Accepted connection 360 from 127.0.0.1:50355
12:23:41 INFO - 1499109821892 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:41 INFO - 1499109821893 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:41 INFO - 1499109821895 Marionette DEBUG Register listener.js for window 4294967297
12:23:41 INFO - 1499109821898 Marionette TRACE 360 <- [1,1,null,{"sessionId":"2b7553f0-fb20-456a-9084-6bb4354a6612","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:41 INFO - 1499109821899 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
12:23:41 INFO - 1499109821900 Marionette TRACE 360 <- [1,2,null,{}]
12:23:41 INFO - 1499109821900 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:41 INFO - 1499109821901 Marionette TRACE 360 <- [1,3,null,{}]
12:23:41 INFO - 1499109821902 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:41 INFO - 1499109821902 Marionette TRACE 360 <- [1,4,null,{}]
12:23:41 INFO - 1499109821904 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
12:23:41 INFO - TEST-START: test_click.py TestClick.test_container_element1499109821906 Marionette TRACE 360 <- [1,5,null,{"value":null}]
12:23:41 INFO - 1499109821907 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
12:23:41 INFO - 1499109821909 Marionette TRACE 360 <- [1,6,null,{}]
12:23:41 INFO - 1499109821911 Marionette DEBUG Closed connection 360
12:23:41 INFO - 1499109821911 Marionette DEBUG Accepted connection 361 from 127.0.0.1:50356
12:23:41 INFO - 1499109821913 Marionette DEBUG Closed connection 361
12:23:41 INFO - 1499109821914 Marionette DEBUG Accepted connection 362 from 127.0.0.1:50357
12:23:41 INFO - 1499109821915 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:41 INFO - 1499109821916 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:41 INFO - 1499109821918 Marionette DEBUG Register listener.js for window 4294967297
12:23:41 INFO - 1499109821921 Marionette TRACE 362 <- [1,1,null,{"sessionId":"46fb1da7-a91d-43b6-8070-9dd370cfc3ef","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:41 INFO - 1499109821922 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
12:23:41 INFO - 1499109821924 Marionette TRACE 362 <- [1,2,null,{}]
12:23:41 INFO - 1499109821925 Marionette DEBUG Closed connection 362
12:23:41 INFO - 1499109821926 Marionette DEBUG Accepted connection 363 from 127.0.0.1:50358
12:23:41 INFO - 1499109821926 Marionette DEBUG Closed connection 363
12:23:41 INFO - 1499109821927 Marionette DEBUG Accepted connection 364 from 127.0.0.1:50359
12:23:41 INFO - 1499109821928 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:41 INFO - 1499109821928 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:41 INFO - 1499109821936 Marionette DEBUG Register listener.js for window 4294967297
12:23:41 INFO - 1499109821939 Marionette TRACE 364 <- [1,1,null,{"sessionId":"7bbf879a-2b69-409f-b0f9-6c66f301cf58","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:41 INFO - 1499109821940 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:23:41 INFO - 1499109821942 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49904/test.html"
12:23:41 INFO - 1499109821945 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49904/test.html"
12:23:41 INFO - 1499109821945 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49904/test.html"
12:23:41 INFO - 1499109821951 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"
12:23:41 INFO - 1499109821955 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"
12:23:41 INFO - 1499109821973 Marionette TRACE 364 <- [1,2,null,{}]
12:23:41 INFO - 1499109821982 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:23:41 INFO - 1499109821984 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fa623c21-5731-436e-a2f2-c4b483518b54","ELEMENT":"fa623c21-5731-436e-a2f2-c4b483518b54"}}]
12:23:42 INFO - 1499109821997 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"fa623c21-5731-436e-a2f2-c4b483518b54"}]
12:23:42 INFO - 1499109822240 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:23:42 INFO - 1499109822241 Marionette TRACE 364 <- [1,4,null,{}]
12:23:42 INFO - 1499109822242 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"fa623c21-5731-436e-a2f2-c4b483518b54","name":"selected"}]
12:23:42 INFO - 1499109822246 Marionette TRACE 364 <- [1,5,null,{"value":true}]
12:23:42 INFO - 1499109822248 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
12:23:42 INFO - TEST-END: test_click.py TestClick.test_container_element1499109822250 Marionette TRACE 364 <- [1,6,null,{"value":null}]
12:23:42 INFO - 1499109822251 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
12:23:42 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 367ms
12:23:42 INFO - 1499109822253 Marionette TRACE 364 <- [1,7,null,{}]
12:23:42 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
12:23:42 INFO - 1499109822255 Marionette DEBUG Closed connection 364
12:23:42 INFO - 1499109822257 Marionette DEBUG Accepted connection 365 from 127.0.0.1:50360
12:23:42 INFO - 1499109822257 Marionette DEBUG Closed connection 365
12:23:42 INFO - 1499109822258 Marionette DEBUG Accepted connection 366 from 127.0.0.1:50361
12:23:42 INFO - 1499109822259 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:42 INFO - 1499109822260 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:42 INFO - 1499109822262 Marionette DEBUG Register listener.js for window 4294967297
12:23:42 INFO - 1499109822265 Marionette TRACE 366 <- [1,1,null,{"sessionId":"eccbd8de-477d-420c-8e2c-7d1f7c7828bb","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:42 INFO - 1499109822266 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
12:23:42 INFO - 1499109822267 Marionette TRACE 366 <- [1,2,null,{}]
12:23:42 INFO - 1499109822267 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:42 INFO - 1499109822268 Marionette TRACE 366 <- [1,3,null,{}]
12:23:42 INFO - 1499109822269 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:42 INFO - 1499109822269 Marionette TRACE 366 <- [1,4,null,{}]
12:23:42 INFO - 1499109822271 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
12:23:42 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499109822274 Marionette TRACE 366 <- [1,5,null,{"value":null}]
12:23:42 INFO - 1499109822275 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
12:23:42 INFO - 1499109822276 Marionette TRACE 366 <- [1,6,null,{}]
12:23:42 INFO - 1499109822278 Marionette DEBUG Closed connection 366
12:23:42 INFO - 1499109822278 Marionette DEBUG Accepted connection 367 from 127.0.0.1:50362
12:23:42 INFO - 1499109822279 Marionette DEBUG Closed connection 367
12:23:42 INFO - 1499109822280 Marionette DEBUG Accepted connection 368 from 127.0.0.1:50363
12:23:42 INFO - 1499109822281 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:42 INFO - 1499109822281 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:42 INFO - 1499109822283 Marionette DEBUG Register listener.js for window 4294967297
12:23:42 INFO - 1499109822286 Marionette TRACE 368 <- [1,1,null,{"sessionId":"50c2efd8-f5ac-4d68-ada6-4faa8fbdd744","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:42 INFO - 1499109822287 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
12:23:42 INFO - 1499109822288 Marionette TRACE 368 <- [1,2,null,{}]
12:23:42 INFO - 1499109822290 Marionette DEBUG Closed connection 368
12:23:42 INFO - 1499109822290 Marionette DEBUG Accepted connection 369 from 127.0.0.1:50364
12:23:42 INFO - 1499109822291 Marionette DEBUG Closed connection 369
12:23:42 INFO - 1499109822292 Marionette DEBUG Accepted connection 370 from 127.0.0.1:50365
12:23:42 INFO - 1499109822293 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:42 INFO - 1499109822293 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:42 INFO - 1499109822295 Marionette DEBUG Register listener.js for window 4294967297
12:23:42 INFO - 1499109822298 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c796b48d-c2ef-4f96-bbb2-670650a91674","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:42 INFO - 1499109822299 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:23:42 INFO - 1499109822301 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"
12:23:42 INFO - 1499109822303 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"
12:23:42 INFO - 1499109822303 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"
12:23:42 INFO - 1499109822309 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"
12:23:42 INFO - 1499109822312 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"
12:23:42 INFO - 1499109822314 Marionette TRACE 370 <- [1,2,null,{}]
12:23:42 INFO - 1499109822317 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
12:23:42 INFO - 1499109822324 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"ce9e1fcd-e776-4bf0-907c-259d87bc198b","ELEMENT":"ce9e1fcd-e776-4bf0-907c-259d87bc198b"}}]
12:23:42 INFO - 1499109822326 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"ce9e1fcd-e776-4bf0-907c-259d87bc198b"}]
12:23:42 INFO - 1499109822568 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:23:42 INFO - 1499109822569 Marionette TRACE 370 <- [1,4,null,{}]
12:23:42 INFO - 1499109822570 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"ce9e1fcd-e776-4bf0-907c-259d87bc198b","name":"selected"}]
12:23:42 INFO - 1499109822574 Marionette TRACE 370 <- [1,5,null,{"value":true}]
12:23:42 INFO - 1499109822576 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
12:23:42 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499109822579 Marionette TRACE 370 <- [1,6,null,{"value":null}]
12:23:42 INFO - 1499109822580 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
12:23:42 INFO - 1499109822581 Marionette TRACE 370 <- [1,7,null,{}]
12:23:42 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 327ms
12:23:42 INFO - TEST-START | test_click.py TestClick.test_css_transforms
12:23:42 INFO - 1499109822583 Marionette DEBUG Closed connection 370
12:23:42 INFO - 1499109822584 Marionette DEBUG Accepted connection 371 from 127.0.0.1:50366
12:23:42 INFO - 1499109822585 Marionette DEBUG Closed connection 371
12:23:42 INFO - 1499109822586 Marionette DEBUG Accepted connection 372 from 127.0.0.1:50367
12:23:42 INFO - 1499109822587 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:42 INFO - 1499109822587 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:42 INFO - 1499109822589 Marionette DEBUG Register listener.js for window 4294967297
12:23:42 INFO - 1499109822592 Marionette TRACE 372 <- [1,1,null,{"sessionId":"63dedb27-d56c-4812-b497-8afb3563ea9a","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:42 INFO - 1499109822593 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
12:23:42 INFO - 1499109822594 Marionette TRACE 372 <- [1,2,null,{}]
12:23:42 INFO - 1499109822595 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:42 INFO - 1499109822595 Marionette TRACE 372 <- [1,3,null,{}]
12:23:42 INFO - 1499109822596 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:42 INFO - 1499109822596 Marionette TRACE 372 <- [1,4,null,{}]
12:23:42 INFO - 1499109822598 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
12:23:42 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499109822600 Marionette TRACE 372 <- [1,5,null,{"value":null}]
12:23:42 INFO - 1499109822601 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
12:23:42 INFO - 1499109822603 Marionette TRACE 372 <- [1,6,null,{}]
12:23:42 INFO - 1499109822604 Marionette DEBUG Closed connection 372
12:23:42 INFO - 1499109822605 Marionette DEBUG Accepted connection 373 from 127.0.0.1:50368
12:23:42 INFO - 1499109822605 Marionette DEBUG Closed connection 373
12:23:42 INFO - 1499109822606 Marionette DEBUG Accepted connection 374 from 127.0.0.1:50369
12:23:42 INFO - 1499109822607 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:42 INFO - 1499109822608 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:42 INFO - 1499109822610 Marionette DEBUG Register listener.js for window 4294967297
12:23:42 INFO - 1499109822613 Marionette TRACE 374 <- [1,1,null,{"sessionId":"0f3f48bd-26c9-43fa-a063-52a33356b213","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:42 INFO - 1499109822614 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
12:23:42 INFO - 1499109822615 Marionette TRACE 374 <- [1,2,null,{}]
12:23:42 INFO - 1499109822616 Marionette DEBUG Closed connection 374
12:23:42 INFO - 1499109822617 Marionette DEBUG Accepted connection 375 from 127.0.0.1:50370
12:23:42 INFO - 1499109822617 Marionette DEBUG Closed connection 375
12:23:42 INFO - 1499109822618 Marionette DEBUG Accepted connection 376 from 127.0.0.1:50371
12:23:42 INFO - 1499109822619 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:42 INFO - 1499109822620 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:42 INFO - 1499109822622 Marionette DEBUG Register listener.js for window 4294967297
12:23:42 INFO - 1499109822625 Marionette TRACE 376 <- [1,1,null,{"sessionId":"1c3f6759-a322-49f7-b50a-0bfd03e71fa7","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:42 INFO - 1499109822626 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
12:23:42 INFO - 1499109822628 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"
12:23:42 INFO - 1499109822630 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"
12:23:42 INFO - 1499109822631 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"
12:23:42 INFO - 1499109822638 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"
12:23:42 INFO - 1499109822640 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"
12:23:42 INFO - 1499109822646 Marionette TRACE 376 <- [1,2,null,{}]
12:23:42 INFO - 1499109822650 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
12:23:42 INFO - 1499109822656 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1012532a-fc4a-4911-9c57-0ff9b083ee1a","ELEMENT":"1012532a-fc4a-4911-9c57-0ff9b083ee1a"}}]
12:23:42 INFO - 1499109822658 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"1012532a-fc4a-4911-9c57-0ff9b083ee1a"}]
12:23:42 INFO - 1499109822895 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:23:42 INFO - 1499109822896 Marionette TRACE 376 <- [1,4,null,{}]
12:23:42 INFO - 1499109822898 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
12:23:42 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499109822900 Marionette TRACE 376 <- [1,5,null,{"value":null}]
12:23:42 INFO - 1499109822901 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
12:23:42 INFO - 1499109822902 Marionette TRACE 376 <- [1,6,null,{}]
12:23:42 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 320ms
12:23:42 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
12:23:42 INFO - 1499109822904 Marionette DEBUG Closed connection 376
12:23:42 INFO - 1499109822905 Marionette DEBUG Accepted connection 377 from 127.0.0.1:50372
12:23:42 INFO - 1499109822906 Marionette DEBUG Closed connection 377
12:23:42 INFO - 1499109822906 Marionette DEBUG Accepted connection 378 from 127.0.0.1:50373
12:23:42 INFO - 1499109822907 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:42 INFO - 1499109822908 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:42 INFO - 1499109822910 Marionette DEBUG Register listener.js for window 4294967297
12:23:42 INFO - 1499109822913 Marionette TRACE 378 <- [1,1,null,{"sessionId":"ca4194c2-923c-405a-b15c-a98670d9c0fa","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:42 INFO - 1499109822914 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
12:23:42 INFO - 1499109822914 Marionette TRACE 378 <- [1,2,null,{}]
12:23:42 INFO - 1499109822915 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:42 INFO - 1499109822916 Marionette TRACE 378 <- [1,3,null,{}]
12:23:42 INFO - 1499109822916 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:42 INFO - 1499109822917 Marionette TRACE 378 <- [1,4,null,{}]
12:23:42 INFO - 1499109822919 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
12:23:42 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499109822921 Marionette TRACE 378 <- [1,5,null,{"value":null}]
12:23:42 INFO - 1499109822922 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
12:23:42 INFO - 1499109822923 Marionette TRACE 378 <- [1,6,null,{}]
12:23:42 INFO - 1499109822924 Marionette DEBUG Closed connection 378
12:23:42 INFO - 1499109822925 Marionette DEBUG Accepted connection 379 from 127.0.0.1:50374
12:23:42 INFO - 1499109822926 Marionette DEBUG Closed connection 379
12:23:42 INFO - 1499109822927 Marionette DEBUG Accepted connection 380 from 127.0.0.1:50375
12:23:42 INFO - 1499109822928 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:42 INFO - 1499109822928 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:42 INFO - 1499109822931 Marionette DEBUG Register listener.js for window 4294967297
12:23:42 INFO - 1499109822933 Marionette TRACE 380 <- [1,1,null,{"sessionId":"4bff298c-c81d-4bbb-b237-7c659b7bfa96","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:42 INFO - 1499109822934 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
12:23:42 INFO - 1499109822935 Marionette TRACE 380 <- [1,2,null,{}]
12:23:42 INFO - 1499109822937 Marionette DEBUG Closed connection 380
12:23:42 INFO - 1499109822938 Marionette DEBUG Accepted connection 381 from 127.0.0.1:50376
12:23:42 INFO - 1499109822938 Marionette DEBUG Closed connection 381
12:23:42 INFO - 1499109822939 Marionette DEBUG Accepted connection 382 from 127.0.0.1:50377
12:23:42 INFO - 1499109822940 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:42 INFO - 1499109822941 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:42 INFO - 1499109822943 Marionette DEBUG Register listener.js for window 4294967297
12:23:42 INFO - 1499109822945 Marionette TRACE 382 <- [1,1,null,{"sessionId":"019cbb9a-f5ae-44cb-afc8-6597e5e5acfe","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:42 INFO - 1499109822946 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
12:23:42 INFO - 1499109822948 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"
12:23:42 INFO - 1499109822950 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"
12:23:42 INFO - 1499109822950 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"
12:23:42 INFO - 1499109822956 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"
12:23:42 INFO - 1499109822959 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"
12:23:42 INFO - 1499109822963 Marionette TRACE 382 <- [1,2,null,{}]
12:23:42 INFO - 1499109822966 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
12:23:42 INFO - 1499109822969 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9c6bfb11-0ff1-4362-b907-f793a94e3f57","ELEMENT":"9c6bfb11-0ff1-4362-b907-f793a94e3f57"}}]
12:23:42 INFO - 1499109822975 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"9c6bfb11-0ff1-4362-b907-f793a94e3f57"}]
12:23:43 INFO - 1499109823218 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:23:43 INFO - 1499109823219 Marionette TRACE 382 <- [1,4,null,{}]
12:23:43 INFO - 1499109823221 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
12:23:43 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499109823223 Marionette TRACE 382 <- [1,5,null,{"value":null}]
12:23:43 INFO - 1499109823224 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
12:23:43 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 323ms
12:23:43 INFO - TEST-START | test_click.py TestClick.test_input_file
12:23:43 INFO - 1499109823226 Marionette TRACE 382 <- [1,6,null,{}]
12:23:43 INFO - 1499109823227 Marionette DEBUG Closed connection 382
12:23:43 INFO - 1499109823228 Marionette DEBUG Accepted connection 383 from 127.0.0.1:50378
12:23:43 INFO - 1499109823229 Marionette DEBUG Closed connection 383
12:23:43 INFO - 1499109823229 Marionette DEBUG Accepted connection 384 from 127.0.0.1:50379
12:23:43 INFO - 1499109823230 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:43 INFO - 1499109823231 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:43 INFO - 1499109823233 Marionette DEBUG Register listener.js for window 4294967297
12:23:43 INFO - 1499109823236 Marionette TRACE 384 <- [1,1,null,{"sessionId":"23f6f98d-7515-45b2-94ca-2507e953d713","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:43 INFO - 1499109823237 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
12:23:43 INFO - 1499109823237 Marionette TRACE 384 <- [1,2,null,{}]
12:23:43 INFO - 1499109823238 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:43 INFO - 1499109823239 Marionette TRACE 384 <- [1,3,null,{}]
12:23:43 INFO - 1499109823240 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:43 INFO - 1499109823240 Marionette TRACE 384 <- [1,4,null,{}]
12:23:43 INFO - 1499109823242 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
12:23:43 INFO - TEST-START: test_click.py TestClick.test_input_file1499109823244 Marionette TRACE 384 <- [1,5,null,{"value":null}]
12:23:43 INFO - 1499109823245 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
12:23:43 INFO - 1499109823246 Marionette TRACE 384 <- [1,6,null,{}]
12:23:43 INFO - 1499109823248 Marionette DEBUG Closed connection 384
12:23:43 INFO - 1499109823248 Marionette DEBUG Accepted connection 385 from 127.0.0.1:50380
12:23:43 INFO - 1499109823249 Marionette DEBUG Closed connection 385
12:23:43 INFO - 1499109823250 Marionette DEBUG Accepted connection 386 from 127.0.0.1:50381
12:23:43 INFO - 1499109823251 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:43 INFO - 1499109823251 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:43 INFO - 1499109823253 Marionette DEBUG Register listener.js for window 4294967297
12:23:43 INFO - 1499109823256 Marionette TRACE 386 <- [1,1,null,{"sessionId":"e3f5c613-6e78-4373-8cd4-ae7b90bf44b5","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:43 INFO - 1499109823257 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
12:23:43 INFO - 1499109823258 Marionette TRACE 386 <- [1,2,null,{}]
12:23:43 INFO - 1499109823259 Marionette DEBUG Closed connection 386
12:23:43 INFO - 1499109823260 Marionette DEBUG Accepted connection 387 from 127.0.0.1:50382
12:23:43 INFO - 1499109823260 Marionette DEBUG Closed connection 387
12:23:43 INFO - 1499109823261 Marionette DEBUG Accepted connection 388 from 127.0.0.1:50383
12:23:43 INFO - 1499109823262 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:43 INFO - 1499109823263 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:43 INFO - 1499109823265 Marionette DEBUG Register listener.js for window 4294967297
12:23:43 INFO - 1499109823267 Marionette TRACE 388 <- [1,1,null,{"sessionId":"7e79b656-cf08-4f8a-8086-1b4c83377ff7","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:43 INFO - 1499109823268 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
12:23:43 INFO - 1499109823270 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"
12:23:43 INFO - 1499109823272 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"
12:23:43 INFO - 1499109823272 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"
12:23:43 INFO - 1499109823278 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:23:43 INFO - 1499109823282 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:23:43 INFO - 1499109823284 Marionette TRACE 388 <- [1,2,null,{}]
12:23:43 INFO - 1499109823297 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
12:23:43 INFO - 1499109823301 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1fdb2a64-7d8a-49e3-b0a1-9aed23f0f2be","ELEMENT":"1fdb2a64-7d8a-49e3-b0a1-9aed23f0f2be"}}]
12:23:43 INFO - 1499109823302 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"1fdb2a64-7d8a-49e3-b0a1-9aed23f0f2be"}]
12:23:43 INFO - 1499109823329 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:23:43 INFO - 1499109823331 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
12:23:43 INFO - TEST-END: test_click.py TestClick.test_input_file1499109823333 Marionette TRACE 388 <- [1,5,null,{"value":null}]
12:23:43 INFO - 1499109823334 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
12:23:43 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 110ms
12:23:43 INFO - 1499109823336 Marionette TRACE 388 <- [1,6,null,{}]
12:23:43 INFO - TEST-START | test_click.py TestClick.test_obscured_element
12:23:43 INFO - 1499109823337 Marionette DEBUG Closed connection 388
12:23:43 INFO - 1499109823339 Marionette DEBUG Accepted connection 389 from 127.0.0.1:50384
12:23:43 INFO - 1499109823339 Marionette DEBUG Closed connection 389
12:23:43 INFO - 1499109823340 Marionette DEBUG Accepted connection 390 from 127.0.0.1:50385
12:23:43 INFO - 1499109823341 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:43 INFO - 1499109823342 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:43 INFO - 1499109823344 Marionette DEBUG Register listener.js for window 4294967297
12:23:43 INFO - 1499109823347 Marionette TRACE 390 <- [1,1,null,{"sessionId":"83c9eba4-11be-4c6a-9cdd-f53c776d2237","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:43 INFO - 1499109823348 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
12:23:43 INFO - 1499109823348 Marionette TRACE 390 <- [1,2,null,{}]
12:23:43 INFO - 1499109823349 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:43 INFO - 1499109823349 Marionette TRACE 390 <- [1,3,null,{}]
12:23:43 INFO - 1499109823350 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:43 INFO - 1499109823351 Marionette TRACE 390 <- [1,4,null,{}]
12:23:43 INFO - 1499109823353 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
12:23:43 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499109823355 Marionette TRACE 390 <- [1,5,null,{"value":null}]
12:23:43 INFO - 1499109823356 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
12:23:43 INFO - 1499109823357 Marionette TRACE 390 <- [1,6,null,{}]
12:23:43 INFO - 1499109823359 Marionette DEBUG Closed connection 390
12:23:43 INFO - 1499109823359 Marionette DEBUG Accepted connection 391 from 127.0.0.1:50386
12:23:43 INFO - 1499109823360 Marionette DEBUG Closed connection 391
12:23:43 INFO - 1499109823361 Marionette DEBUG Accepted connection 392 from 127.0.0.1:50387
12:23:43 INFO - 1499109823362 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:43 INFO - 1499109823362 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:43 INFO - 1499109823364 Marionette DEBUG Register listener.js for window 4294967297
12:23:43 INFO - 1499109823367 Marionette TRACE 392 <- [1,1,null,{"sessionId":"ff308e0b-a735-4510-8813-9c7f1b4b6386","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:43 INFO - 1499109823368 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
12:23:43 INFO - 1499109823369 Marionette TRACE 392 <- [1,2,null,{}]
12:23:43 INFO - 1499109823370 Marionette DEBUG Closed connection 392
12:23:43 INFO - 1499109823371 Marionette DEBUG Accepted connection 393 from 127.0.0.1:50388
12:23:43 INFO - 1499109823372 Marionette DEBUG Closed connection 393
12:23:43 INFO - 1499109823373 Marionette DEBUG Accepted connection 394 from 127.0.0.1:50389
12:23:43 INFO - 1499109823373 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:43 INFO - 1499109823374 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:43 INFO - 1499109823376 Marionette DEBUG Register listener.js for window 4294967297
12:23:43 INFO - 1499109823379 Marionette TRACE 394 <- [1,1,null,{"sessionId":"401f8008-9d4b-4a3f-8f80-d878afcd03dc","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:43 INFO - 1499109823380 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
12:23:43 INFO - 1499109823381 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:23:43 INFO - 1499109823384 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:23:43 INFO - 1499109823385 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
12:23:43 INFO - 1499109823393 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"
12:23:43 INFO - 1499109823397 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"
12:23:43 INFO - 1499109823398 Marionette TRACE 394 <- [1,2,null,{}]
12:23:43 INFO - 1499109823400 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
12:23:43 INFO - 1499109823407 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"387e1d79-c36e-41ec-82a7-091ec95bb089","ELEMENT":"387e1d79-c36e-41ec-82a7-091ec95bb089"}}]
12:23:43 INFO - 1499109823410 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
12:23:43 INFO - 1499109823412 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a27c8c92-b314-46aa-9477-59f215e0fd9f","ELEMENT":"a27c8c92-b314-46aa-9477-59f215e0fd9f"}}]
12:23:43 INFO - 1499109823413 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"387e1d79-c36e-41ec-82a7-091ec95bb089"}]
12:23:43 INFO - 1499109823649 Marionette DEBUG Canceled page load listener because no navigation has been detected
12:23:43 INFO - 1499109823650 Marionette TRACE 394 <- [1,5,null,{}]
12:23:43 INFO - 1499109823651 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"a27c8c92-b314-46aa-9477-59f215e0fd9f"}]
12:23:43 INFO - 1499109823665 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (10.5,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
12:23:43 INFO - 1499109823667 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
12:23:43 INFO - 1499109823670 Marionette TRACE 394 <- [1,7,null,{"value":false}]
12:23:43 INFO - 1499109823672 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
12:23:43 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499109823674 Marionette TRACE 394 <- [1,8,null,{"value":null}]
12:23:43 INFO - 1499109823675 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
12:23:43 INFO - 1499109823676 Marionette TRACE 394 <- [1,9,null,{}]
12:23:43 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 340ms
12:23:43 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
12:23:43 INFO - 1499109823678 Marionette DEBUG Closed connection 394
12:23:43 INFO - 1499109823679 Marionette DEBUG Accepted connection 395 from 127.0.0.1:50390
12:23:43 INFO - 1499109823679 Marionette DEBUG Closed connection 395
12:23:43 INFO - 1499109823680 Marionette DEBUG Accepted connection 396 from 127.0.0.1:50391
12:23:43 INFO - 1499109823681 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:43 INFO - 1499109823682 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:43 INFO - 1499109823684 Marionette DEBUG Register listener.js for window 4294967297
12:23:43 INFO - 1499109823687 Marionette TRACE 396 <- [1,1,null,{"sessionId":"80bd27b7-4c3e-489c-ac15-d01668e0302e","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:43 INFO - 1499109823688 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
12:23:43 INFO - 1499109823689 Marionette TRACE 396 <- [1,2,null,{}]
12:23:43 INFO - 1499109823689 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
12:23:43 INFO - 1499109823690 Marionette TRACE 396 <- [1,3,null,{}]
12:23:43 INFO - 1499109823691 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
12:23:43 INFO - 1499109823691 Marionette TRACE 396 <- [1,4,null,{}]
12:23:43 INFO - 1499109823693 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
12:23:43 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499109823695 Marionette TRACE 396 <- [1,5,null,{"value":null}]
12:23:43 INFO - 1499109823696 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
12:23:43 INFO - 1499109823697 Marionette TRACE 396 <- [1,6,null,{}]
12:23:43 INFO - 1499109823699 Marionette DEBUG Closed connection 396
12:23:43 INFO - 1499109823699 Marionette DEBUG Accepted connection 397 from 127.0.0.1:50392
12:23:43 INFO - 1499109823700 Marionette DEBUG Closed connection 397
12:23:43 INFO - 1499109823701 Marionette DEBUG Accepted connection 398 from 127.0.0.1:50393
12:23:43 INFO - 1499109823702 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
12:23:43 INFO - 1499109823702 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:43 INFO - 1499109823705 Marionette DEBUG Register listener.js for window 4294967297
12:23:43 INFO - 1499109823707 Marionette TRACE 398 <- [1,1,null,{"sessionId":"58fff750-a33c-4e72-b2d5-def114f45611","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:43 INFO - 1499109823708 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
12:23:43 INFO - 1499109823709 Marionette TRACE 398 <- [1,2,null,{}]
12:23:43 INFO - 1499109823711 Marionette DEBUG Closed connection 398
12:23:43 INFO - 1499109823711 Marionette DEBUG Accepted connection 399 from 127.0.0.1:50394
12:23:43 INFO - 1499109823712 Marionette DEBUG Closed connection 399
12:23:43 INFO - 1499109823713 Marionette DEBUG Accepted connection 400 from 127.0.0.1:50395
12:23:43 INFO - 1499109823714 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
12:23:43 INFO - 1499109823714 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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}
12:23:43 INFO - 1499109823716 Marionette DEBUG Register listener.js for window 4294967297
12:23:43 INFO - 1499109823719 Marionette TRACE 400 <- [1,1,null,{"sessionId":"c880a720-74d1-4d0c-bbb3-18c75abfef46","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":3888,"moz:profile":"C:\\Users\\cltbld\\AppData\\Local\\Temp\\tmpyluooy.mozrunner","moz:accessibilityChecks":false}}]
12:23:43 INFO - 1499109823720 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
12:23:43 INFO - 1499109823721 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"
12:23:43 INFO - 1499109823723 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"
12:23:43 INFO - 1499109823724 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"
12:23:43 INFO - 1499109823730 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"
12:23:43 INFO - 1499109823732 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"
12:23:43 INFO - 1499109823735 Marionette TRACE 400 <- [1,2,null,{}]
12:23:43 INFO - 1499109823743 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
12:23:43 INFO - 1499109823747 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"310caf43-f5f3-4bed-953d-3d99a700e5b0","ELEMENT":"310caf43-f5f3-4bed-953d-3d99a700e5b0"}}]
12:23:43 INFO - 1499109823748 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"310caf43-f5f3-4bed-953d-3d99a700e5b0"}]
12:23:43 INFO - 1499109823773 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
12:23:43 INFO - 1499109823774 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"310caf43-f5f3-4bed-953d-3d99a700e5b0"}]
12:23:43 INFO - 1499109823788 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element