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]
08:02:33 INFO - 1498748553946 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}]
08:02:33 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498748553954 Marionette TRACE 359 <- [1,5,null,{"value":null}]
08:02:33 INFO - 1498748553956 Marionette TRACE 359 -> [0,6,"deleteSession",{}]
08:02:33 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:33 INFO - 1498748553961 Marionette TRACE 359 <- [1,6,null,{}]
08:02:33 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1436ms
08:02:33 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
08:02:33 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:33 INFO - 1498748553967 Marionette DEBUG Closed connection 359
08:02:33 INFO - 1498748553968 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49727
08:02:33 INFO - 1498748553970 Marionette DEBUG Closed connection 360
08:02:33 INFO - 1498748553971 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49728
08:02:33 INFO - 1498748553973 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:33 INFO - 1498748553976 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:33 INFO - 1498748553997 Marionette DEBUG Register listener.js for window 7
08:02:34 INFO - 1498748554009 Marionette TRACE 361 <- [1,1,null,{"sessionId":"d75c54df-79e8-4e00-84c5-f407640fe1f7","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:34 INFO - 1498748554035 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
08:02:34 INFO - 1498748554037 Marionette TRACE 361 <- [1,2,null,{}]
08:02:34 INFO - 1498748554039 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:02:34 INFO - 1498748554041 Marionette TRACE 361 <- [1,3,null,{}]
08:02:34 INFO - 1498748554043 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
08:02:34 INFO - 1498748554044 Marionette TRACE 361 <- [1,4,null,{}]
08:02:34 INFO - 1498748554047 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}]
08:02:34 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498748554056 Marionette TRACE 361 <- [1,5,null,{"value":null}]
08:02:34 INFO - 1498748554058 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
08:02:34 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:34 INFO - 1498748554062 Marionette TRACE 361 <- [1,6,null,{}]
08:02:34 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:34 INFO - 1498748554087 Marionette DEBUG Closed connection 361
08:02:34 INFO - 1498748554088 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49729
08:02:34 INFO - 1498748554092 Marionette DEBUG Closed connection 362
08:02:34 INFO - 1498748554093 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49730
08:02:34 INFO - 1498748554095 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:34 INFO - 1498748554098 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:34 INFO - 1498748554120 Marionette DEBUG Register listener.js for window 7
08:02:34 INFO - 1498748554132 Marionette TRACE 363 <- [1,1,null,{"sessionId":"63e1906c-18ce-4119-b303-33a5b5bc53d3","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:34 INFO - 1498748554139 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
08:02:34 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:34 INFO - 1498748554143 Marionette TRACE 363 <- [1,2,null,{}]
08:02:34 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:34 INFO - 1498748554166 Marionette DEBUG Closed connection 363
08:02:34 INFO - 1498748554168 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49731
08:02:34 INFO - 1498748554173 Marionette DEBUG Closed connection 364
08:02:34 INFO - 1498748554174 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49732
08:02:34 INFO - 1498748554177 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:02:34 INFO - 1498748554179 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:34 INFO - 1498748554201 Marionette DEBUG Register listener.js for window 7
08:02:34 INFO - 1498748554213 Marionette TRACE 365 <- [1,1,null,{"sessionId":"f82c6742-135c-47be-89f2-ade91a6a5d11","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:34 INFO - 1498748554220 Marionette TRACE 365 -> [0,2,"get",{"url":"http://127.0.0.1:49329/clicks.html"}]
08:02:34 INFO - 1498748554223 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/hidden.html"
08:02:34 INFO - 1498748554236 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/hidden.html"
08:02:34 INFO - 1498748554237 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/hidden.html"
08:02:34 INFO - ++DOMWINDOW == 22 (000000546716C800) [pid = 3336] [serial = 39] [outer = 000000545F198000]
08:02:34 INFO - 1498748554305 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/clicks.html"
08:02:34 INFO - 1498748554310 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/clicks.html"
08:02:34 INFO - 1498748554331 Marionette TRACE 365 <- [1,2,null,{}]
08:02:34 INFO - 1498748554334 Marionette TRACE 365 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
08:02:34 INFO - 1498748554345 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fccfa4eb-46ca-4100-bda5-1441196243a9","ELEMENT":"fccfa4eb-46ca-4100-bda5-1441196243a9"}}]
08:02:34 INFO - 1498748554347 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"fccfa4eb-46ca-4100-bda5-1441196243a9"}]
08:02:34 INFO - 1498748554612 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/clicks.html"
08:02:34 INFO - [3336] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 445
08:02:34 INFO - 1498748554634 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/clicks.html"
08:02:34 INFO - 1498748554636 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/clicks.html"
08:02:34 INFO - ++DOMWINDOW == 23 (0000005466FCC800) [pid = 3336] [serial = 40] [outer = 000000545F198000]
08:02:34 INFO - 1498748554703 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/test.html"
08:02:34 INFO - 1498748554708 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/test.html"
08:02:34 INFO - 1498748554722 Marionette TRACE 365 <- [1,4,null,{}]
08:02:34 INFO - 1498748554732 Marionette TRACE 365 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
08:02:34 INFO - 1498748554740 Marionette TRACE 365 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8840b6cd-67ff-4e2b-a430-4f1b1382d137","ELEMENT":"8840b6cd-67ff-4e2b-a430-4f1b1382d137"}}]
08:02:34 INFO - 1498748554744 Marionette TRACE 365 -> [0,6,"getTitle",{}]
08:02:34 INFO - 1498748554749 Marionette TRACE 365 <- [1,6,null,{"value":"Marionette Test"}]
08:02:34 INFO - 1498748554751 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}]
08:02:34 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498748554759 Marionette TRACE 365 <- [1,7,null,{"value":null}]
08:02:34 INFO - 1498748554761 Marionette TRACE 365 -> [0,8,"deleteSession",{}]
08:02:34 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:34 INFO - 1498748554765 Marionette TRACE 365 <- [1,8,null,{}]
08:02:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 805ms
08:02:34 INFO - TEST-START | test_click.py TestClick.test_container_element
08:02:34 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:34 INFO - 1498748554772 Marionette DEBUG Closed connection 365
08:02:34 INFO - 1498748554774 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49735
08:02:34 INFO - 1498748554802 Marionette DEBUG Closed connection 366
08:02:34 INFO - 1498748554804 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49736
08:02:34 INFO - 1498748554811 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:34 INFO - 1498748554813 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:34 INFO - 1498748554835 Marionette DEBUG Register listener.js for window 7
08:02:34 INFO - 1498748554847 Marionette TRACE 367 <- [1,1,null,{"sessionId":"bb11389e-245e-4ee3-b603-2600a6b0e715","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:34 INFO - 1498748554850 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
08:02:34 INFO - 1498748554852 Marionette TRACE 367 <- [1,2,null,{}]
08:02:34 INFO - 1498748554853 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:02:34 INFO - 1498748554856 Marionette TRACE 367 <- [1,3,null,{}]
08:02:34 INFO - 1498748554857 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
08:02:34 INFO - 1498748554859 Marionette TRACE 367 <- [1,4,null,{}]
08:02:34 INFO - 1498748554862 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}]
08:02:34 INFO - TEST-START: test_click.py TestClick.test_container_element1498748554870 Marionette TRACE 367 <- [1,5,null,{"value":null}]
08:02:34 INFO - 1498748554872 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
08:02:34 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:34 INFO - 1498748554877 Marionette TRACE 367 <- [1,6,null,{}]
08:02:34 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:34 INFO - 1498748554884 Marionette DEBUG Closed connection 367
08:02:34 INFO - 1498748554886 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49737
08:02:34 INFO - 1498748554912 Marionette DEBUG Closed connection 368
08:02:34 INFO - 1498748554913 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49738
08:02:34 INFO - 1498748554916 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:34 INFO - 1498748554919 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:34 INFO - 1498748554946 Marionette DEBUG Register listener.js for window 7
08:02:34 INFO - 1498748554960 Marionette TRACE 369 <- [1,1,null,{"sessionId":"0c2f6acc-2f9d-4676-848e-84d581e7aa5e","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:34 INFO - 1498748554966 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
08:02:34 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:34 INFO - 1498748554971 Marionette TRACE 369 <- [1,2,null,{}]
08:02:34 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:34 INFO - 1498748554993 Marionette DEBUG Closed connection 369
08:02:34 INFO - 1498748554994 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49739
08:02:35 INFO - 1498748554999 Marionette DEBUG Closed connection 370
08:02:35 INFO - 1498748555000 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49740
08:02:35 INFO - 1498748555003 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:02:35 INFO - 1498748555005 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:35 INFO - 1498748555027 Marionette DEBUG Register listener.js for window 7
08:02:35 INFO - 1498748555039 Marionette TRACE 371 <- [1,1,null,{"sessionId":"6d0665e8-ff35-446c-b40d-bae1ddac1a4f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:35 INFO - 1498748555046 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"}]
08:02:35 INFO - 1498748555050 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/test.html"
08:02:35 INFO - 1498748555059 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/test.html"
08:02:35 INFO - 1498748555060 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/test.html"
08:02:35 INFO - ++DOMWINDOW == 24 (00000054674B7800) [pid = 3336] [serial = 41] [outer = 000000545F198000]
08:02:35 INFO - 1498748555123 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"
08:02:35 INFO - 1498748555130 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"
08:02:35 INFO - 1498748555147 Marionette TRACE 371 <- [1,2,null,{}]
08:02:35 INFO - 1498748555154 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
08:02:35 INFO - 1498748555162 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6fa5b3b6-fa13-469e-a21c-42f92622fae4","ELEMENT":"6fa5b3b6-fa13-469e-a21c-42f92622fae4"}}]
08:02:35 INFO - 1498748555166 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"6fa5b3b6-fa13-469e-a21c-42f92622fae4"}]
08:02:35 INFO - [3336] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
08:02:35 INFO - [3336] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
08:02:35 INFO - [3336] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
08:02:35 INFO - 1498748555600 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:02:35 INFO - 1498748555602 Marionette TRACE 371 <- [1,4,null,{}]
08:02:35 INFO - 1498748555607 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"6fa5b3b6-fa13-469e-a21c-42f92622fae4","name":"selected"}]
08:02:35 INFO - 1498748555636 Marionette TRACE 371 <- [1,5,null,{"value":true}]
08:02:35 INFO - 1498748555654 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}]
08:02:35 INFO - TEST-END: test_click.py TestClick.test_container_element1498748555664 Marionette TRACE 371 <- [1,6,null,{"value":null}]
08:02:35 INFO - 1498748555666 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
08:02:35 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:35 INFO - 1498748555671 Marionette TRACE 371 <- [1,7,null,{}]
08:02:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 904ms
08:02:35 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
08:02:35 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:35 INFO - 1498748555704 Marionette DEBUG Closed connection 371
08:02:35 INFO - 1498748555706 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49741
08:02:35 INFO - 1498748555710 Marionette DEBUG Closed connection 372
08:02:35 INFO - 1498748555712 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49742
08:02:35 INFO - 1498748555714 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:35 INFO - 1498748555716 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:35 INFO - 1498748555740 Marionette DEBUG Register listener.js for window 7
08:02:35 INFO - 1498748555754 Marionette TRACE 373 <- [1,1,null,{"sessionId":"01859ff9-9233-4387-897e-da34185f6acd","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:35 INFO - 1498748555763 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
08:02:35 INFO - 1498748555765 Marionette TRACE 373 <- [1,2,null,{}]
08:02:35 INFO - 1498748555767 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:02:35 INFO - 1498748555768 Marionette TRACE 373 <- [1,3,null,{}]
08:02:35 INFO - 1498748555770 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
08:02:35 INFO - 1498748555772 Marionette TRACE 373 <- [1,4,null,{}]
08:02:35 INFO - 1498748555775 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}]
08:02:35 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498748555783 Marionette TRACE 373 <- [1,5,null,{"value":null}]
08:02:35 INFO - 1498748555785 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
08:02:35 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:35 INFO - 1498748555789 Marionette TRACE 373 <- [1,6,null,{}]
08:02:36 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:36 INFO - 1498748556497 Marionette DEBUG Closed connection 373
08:02:36 INFO - 1498748556499 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49743
08:02:36 INFO - 1498748556505 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49744
08:02:36 INFO - 1498748556507 Marionette DEBUG Closed connection 374
08:02:36 INFO - 1498748556508 Marionette DEBUG Closed connection 375
08:02:36 INFO - 1498748556510 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49745
08:02:36 INFO - 1498748556512 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:36 INFO - 1498748556514 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:36 INFO - 1498748556536 Marionette DEBUG Register listener.js for window 7
08:02:36 INFO - 1498748556548 Marionette TRACE 376 <- [1,1,null,{"sessionId":"6e8a36a4-4922-48d9-af7d-7c544b8c3b56","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:36 INFO - 1498748556550 Marionette TRACE 376 -> [0,2,"deleteSession",{}]
08:02:36 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:36 INFO - 1498748556554 Marionette TRACE 376 <- [1,2,null,{}]
08:02:36 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:36 INFO - 1498748556560 Marionette DEBUG Closed connection 376
08:02:36 INFO - 1498748556562 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49746
08:02:36 INFO - 1498748556563 Marionette DEBUG Closed connection 377
08:02:36 INFO - 1498748556564 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49747
08:02:36 INFO - 1498748556566 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:02:36 INFO - 1498748556569 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:36 INFO - 1498748556591 Marionette DEBUG Register listener.js for window 7
08:02:36 INFO - 1498748556658 Marionette TRACE 378 <- [1,1,null,{"sessionId":"8907014c-6521-463a-a137-a2fd744ef27d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:36 INFO - 1498748556703 Marionette TRACE 378 -> [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"}]
08:02:36 INFO - 1498748556707 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"
08:02:36 INFO - 1498748556713 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"
08:02:36 INFO - 1498748556714 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"
08:02:36 INFO - ++DOMWINDOW == 25 (000000546501E800) [pid = 3336] [serial = 42] [outer = 000000545F198000]
08:02:36 INFO - 1498748556757 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"
08:02:36 INFO - 1498748556772 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"
08:02:36 INFO - 1498748556796 Marionette TRACE 378 <- [1,2,null,{}]
08:02:36 INFO - 1498748556800 Marionette TRACE 378 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
08:02:36 INFO - 1498748556821 Marionette TRACE 378 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"769f065a-7b03-4fb6-91d1-2be080f95e30","ELEMENT":"769f065a-7b03-4fb6-91d1-2be080f95e30"}}]
08:02:36 INFO - 1498748556825 Marionette TRACE 378 -> [0,4,"clickElement",{"id":"769f065a-7b03-4fb6-91d1-2be080f95e30"}]
08:02:37 INFO - [3336] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
08:02:37 INFO - [3336] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
08:02:37 INFO - [3336] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
08:02:37 INFO - 1498748557478 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:02:37 INFO - 1498748557483 Marionette TRACE 378 <- [1,4,null,{}]
08:02:37 INFO - 1498748557488 Marionette TRACE 378 -> [0,5,"getElementProperty",{"id":"769f065a-7b03-4fb6-91d1-2be080f95e30","name":"selected"}]
08:02:37 INFO - 1498748557554 Marionette TRACE 378 <- [1,5,null,{"value":true}]
08:02:37 INFO - 1498748557566 Marionette TRACE 378 -> [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}]
08:02:37 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498748557582 Marionette TRACE 378 <- [1,6,null,{"value":null}]
08:02:37 INFO - 1498748557587 Marionette TRACE 378 -> [0,7,"deleteSession",{}]
08:02:37 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:37 INFO - 1498748557596 Marionette TRACE 378 <- [1,7,null,{}]
08:02:37 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1940ms
08:02:37 INFO - TEST-START | test_click.py TestClick.test_css_transforms
08:02:37 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:37 INFO - 1498748557638 Marionette DEBUG Closed connection 378
08:02:37 INFO - 1498748557642 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49748
08:02:37 INFO - 1498748557654 Marionette DEBUG Closed connection 379
08:02:37 INFO - 1498748557657 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49749
08:02:37 INFO - 1498748557661 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:37 INFO - 1498748557671 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:37 INFO - 1498748557720 Marionette DEBUG Register listener.js for window 7
08:02:37 INFO - 1498748557766 Marionette TRACE 380 <- [1,1,null,{"sessionId":"a70facab-88e4-4122-a6ef-62b9a97127eb","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:37 INFO - 1498748557781 Marionette TRACE 380 -> [0,2,"setTimeouts",{"script":30000}]
08:02:37 INFO - 1498748557785 Marionette TRACE 380 <- [1,2,null,{}]
08:02:37 INFO - 1498748557837 Marionette TRACE 380 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:02:37 INFO - 1498748557840 Marionette TRACE 380 <- [1,3,null,{}]
08:02:37 INFO - 1498748557843 Marionette TRACE 380 -> [0,4,"setTimeouts",{"implicit":0}]
08:02:37 INFO - 1498748557846 Marionette TRACE 380 <- [1,4,null,{}]
08:02:37 INFO - 1498748557852 Marionette TRACE 380 -> [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}]
08:02:37 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498748557865 Marionette TRACE 380 <- [1,5,null,{"value":null}]
08:02:37 INFO - 1498748557868 Marionette TRACE 380 -> [0,6,"deleteSession",{}]
08:02:37 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:37 INFO - 1498748557876 Marionette TRACE 380 <- [1,6,null,{}]
08:02:37 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:37 INFO - 1498748557891 Marionette DEBUG Closed connection 380
08:02:37 INFO - 1498748557895 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49750
08:02:37 INFO - 1498748557898 Marionette DEBUG Closed connection 381
08:02:37 INFO - 1498748557900 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49751
08:02:37 INFO - 1498748557909 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:37 INFO - 1498748557915 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:37 INFO - 1498748557963 Marionette DEBUG Register listener.js for window 7
08:02:37 INFO - 1498748557982 Marionette TRACE 382 <- [1,1,null,{"sessionId":"b90d4557-9a28-47f5-8e1a-2bdc12a391a1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:38 INFO - 1498748558020 Marionette TRACE 382 -> [0,2,"deleteSession",{}]
08:02:38 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:38 INFO - 1498748558027 Marionette TRACE 382 <- [1,2,null,{}]
08:02:38 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:38 INFO - 1498748558051 Marionette DEBUG Closed connection 382
08:02:38 INFO - 1498748558053 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49752
08:02:38 INFO - 1498748558058 Marionette DEBUG Closed connection 383
08:02:38 INFO - 1498748558060 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49753
08:02:38 INFO - 1498748558113 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:02:38 INFO - 1498748558117 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:38 INFO - 1498748558163 Marionette DEBUG Register listener.js for window 7
08:02:38 INFO - 1498748558181 Marionette TRACE 384 <- [1,1,null,{"sessionId":"3b763725-1634-4632-a37c-b62bce2b12e2","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:38 INFO - 1498748558188 Marionette TRACE 384 -> [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"}]
08:02:38 INFO - 1498748558193 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"
08:02:38 INFO - 1498748558205 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"
08:02:38 INFO - 1498748558206 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"
08:02:38 INFO - ++DOMWINDOW == 26 (000000545EB07800) [pid = 3336] [serial = 43] [outer = 000000545F198000]
08:02:38 INFO - 1498748558275 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"
08:02:38 INFO - 1498748558286 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"
08:02:38 INFO - 1498748558316 Marionette TRACE 384 <- [1,2,null,{}]
08:02:38 INFO - 1498748558321 Marionette TRACE 384 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
08:02:38 INFO - 1498748558331 Marionette TRACE 384 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"226239c7-5a47-48f9-bb25-daca767acef6","ELEMENT":"226239c7-5a47-48f9-bb25-daca767acef6"}}]
08:02:38 INFO - 1498748558339 Marionette TRACE 384 -> [0,4,"clickElement",{"id":"226239c7-5a47-48f9-bb25-daca767acef6"}]
08:02:38 INFO - 1498748558970 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:02:38 INFO - 1498748558972 Marionette TRACE 384 <- [1,4,null,{}]
08:02:38 INFO - 1498748558978 Marionette TRACE 384 -> [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}]
08:02:38 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498748558990 Marionette TRACE 384 <- [1,5,null,{"value":null}]
08:02:38 INFO - 1498748558994 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
08:02:39 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:39 INFO - 1498748558999 Marionette TRACE 384 <- [1,6,null,{}]
08:02:39 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1388ms
08:02:39 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
08:02:39 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:39 INFO - 1498748559006 Marionette DEBUG Closed connection 384
08:02:39 INFO - 1498748559009 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49754
08:02:39 INFO - 1498748559011 Marionette DEBUG Closed connection 385
08:02:39 INFO - 1498748559013 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49755
08:02:39 INFO - 1498748559016 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:39 INFO - 1498748559019 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:39 INFO - 1498748559068 Marionette DEBUG Register listener.js for window 7
08:02:39 INFO - 1498748559085 Marionette TRACE 386 <- [1,1,null,{"sessionId":"49e773aa-4a04-4d1f-8bb6-5c01283b4797","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:39 INFO - 1498748559088 Marionette TRACE 386 -> [0,2,"setTimeouts",{"script":30000}]
08:02:39 INFO - 1498748559091 Marionette TRACE 386 <- [1,2,null,{}]
08:02:39 INFO - 1498748559127 Marionette TRACE 386 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:02:39 INFO - 1498748559129 Marionette TRACE 386 <- [1,3,null,{}]
08:02:39 INFO - 1498748559132 Marionette TRACE 386 -> [0,4,"setTimeouts",{"implicit":0}]
08:02:39 INFO - 1498748559134 Marionette TRACE 386 <- [1,4,null,{}]
08:02:39 INFO - 1498748559169 Marionette TRACE 386 -> [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}]
08:02:39 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498748559180 Marionette TRACE 386 <- [1,5,null,{"value":null}]
08:02:39 INFO - 1498748559184 Marionette TRACE 386 -> [0,6,"deleteSession",{}]
08:02:39 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:39 INFO - 1498748559189 Marionette TRACE 386 <- [1,6,null,{}]
08:02:39 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:39 INFO - 1498748559213 Marionette DEBUG Closed connection 386
08:02:39 INFO - 1498748559215 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49756
08:02:39 INFO - 1498748559241 Marionette DEBUG Closed connection 387
08:02:39 INFO - 1498748559243 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49757
08:02:39 INFO - 1498748559447 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:39 INFO - 1498748559460 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:39 INFO - 1498748559486 Marionette DEBUG Register listener.js for window 7
08:02:39 INFO - 1498748559510 Marionette TRACE 388 <- [1,1,null,{"sessionId":"e37f43dc-d222-4229-bc50-722fe0242603","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:39 INFO - 1498748559521 Marionette TRACE 388 -> [0,2,"deleteSession",{}]
08:02:39 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:39 INFO - 1498748559527 Marionette TRACE 388 <- [1,2,null,{}]
08:02:39 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:39 INFO - 1498748559536 Marionette DEBUG Closed connection 388
08:02:39 INFO - 1498748559539 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49758
08:02:39 INFO - 1498748559541 Marionette DEBUG Closed connection 389
08:02:39 INFO - 1498748559544 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49759
08:02:39 INFO - 1498748559549 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:02:39 INFO - 1498748559554 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:39 INFO - 1498748559579 Marionette DEBUG Register listener.js for window 7
08:02:39 INFO - 1498748559602 Marionette TRACE 390 <- [1,1,null,{"sessionId":"94c25e57-14ec-4c0a-b2df-1a67caafdb02","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:39 INFO - 1498748559617 Marionette TRACE 390 -> [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"}]
08:02:39 INFO - 1498748559624 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"
08:02:39 INFO - 1498748559635 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"
08:02:39 INFO - 1498748559636 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"
08:02:39 INFO - ++DOMWINDOW == 27 (000000545EA23800) [pid = 3336] [serial = 44] [outer = 000000545F198000]
08:02:39 INFO - 1498748559688 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"
08:02:39 INFO - 1498748559706 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"
08:02:39 INFO - 1498748559725 Marionette TRACE 390 <- [1,2,null,{}]
08:02:39 INFO - 1498748559760 Marionette TRACE 390 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
08:02:39 INFO - 1498748559782 Marionette TRACE 390 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1d2eb834-da50-45fe-a72f-850b83df50a8","ELEMENT":"1d2eb834-da50-45fe-a72f-850b83df50a8"}}]
08:02:39 INFO - 1498748559799 Marionette TRACE 390 -> [0,4,"clickElement",{"id":"1d2eb834-da50-45fe-a72f-850b83df50a8"}]
08:02:40 INFO - --DOMWINDOW == 26 (000000545EB03800) [pid = 3336] [serial = 31] [outer = 0000000000000000] [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%20position%3A%20absolute%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%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%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]
08:02:40 INFO - --DOMWINDOW == 25 (0000005466878800) [pid = 3336] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
08:02:40 INFO - --DOMWINDOW == 24 (000000545A302800) [pid = 3336] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
08:02:40 INFO - 1498748560362 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:02:40 INFO - 1498748560365 Marionette TRACE 390 <- [1,4,null,{}]
08:02:40 INFO - 1498748560370 Marionette TRACE 390 -> [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}]
08:02:40 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498748560383 Marionette TRACE 390 <- [1,5,null,{"value":null}]
08:02:40 INFO - 1498748560386 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
08:02:40 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:40 INFO - 1498748560391 Marionette TRACE 390 <- [1,6,null,{}]
08:02:40 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1392ms
08:02:40 INFO - TEST-START | test_click.py TestClick.test_input_file
08:02:40 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:40 INFO - 1498748560400 Marionette DEBUG Closed connection 390
08:02:40 INFO - 1498748560402 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49760
08:02:40 INFO - 1498748560406 Marionette DEBUG Closed connection 391
08:02:40 INFO - 1498748560407 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49761
08:02:40 INFO - 1498748560410 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:40 INFO - 1498748560414 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:40 INFO - 1498748560437 Marionette DEBUG Register listener.js for window 7
08:02:40 INFO - 1498748560454 Marionette TRACE 392 <- [1,1,null,{"sessionId":"2752a1c5-1a48-46f4-ae2e-6bda215813a8","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:40 INFO - 1498748560482 Marionette TRACE 392 -> [0,2,"setTimeouts",{"script":30000}]
08:02:40 INFO - 1498748560484 Marionette TRACE 392 <- [1,2,null,{}]
08:02:40 INFO - 1498748560492 Marionette TRACE 392 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:02:40 INFO - 1498748560498 Marionette TRACE 392 <- [1,3,null,{}]
08:02:40 INFO - 1498748560501 Marionette TRACE 392 -> [0,4,"setTimeouts",{"implicit":0}]
08:02:40 INFO - 1498748560503 Marionette TRACE 392 <- [1,4,null,{}]
08:02:40 INFO - 1498748560506 Marionette TRACE 392 -> [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}]
08:02:40 INFO - TEST-START: test_click.py TestClick.test_input_file1498748560516 Marionette TRACE 392 <- [1,5,null,{"value":null}]
08:02:40 INFO - 1498748560518 Marionette TRACE 392 -> [0,6,"deleteSession",{}]
08:02:40 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:40 INFO - 1498748560523 Marionette TRACE 392 <- [1,6,null,{}]
08:02:40 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:40 INFO - 1498748560549 Marionette DEBUG Closed connection 392
08:02:40 INFO - 1498748560551 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49762
08:02:40 INFO - 1498748560556 Marionette DEBUG Closed connection 393
08:02:40 INFO - 1498748560558 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49763
08:02:41 INFO - 1498748561364 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:41 INFO - 1498748561367 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:41 INFO - 1498748561390 Marionette DEBUG Register listener.js for window 7
08:02:41 INFO - 1498748561408 Marionette TRACE 394 <- [1,1,null,{"sessionId":"130aa8c2-2e92-469f-951b-b7c0245705b9","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:41 INFO - 1498748561509 Marionette TRACE 394 -> [0,2,"deleteSession",{}]
08:02:41 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:41 INFO - 1498748561514 Marionette TRACE 394 <- [1,2,null,{}]
08:02:41 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:41 INFO - 1498748561570 Marionette DEBUG Closed connection 394
08:02:41 INFO - 1498748561573 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49764
08:02:41 INFO - 1498748561626 Marionette DEBUG Closed connection 395
08:02:41 INFO - 1498748561628 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49765
08:02:41 INFO - 1498748561666 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:02:41 INFO - 1498748561669 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:41 INFO - 1498748561694 Marionette DEBUG Register listener.js for window 7
08:02:41 INFO - 1498748561711 Marionette TRACE 396 <- [1,1,null,{"sessionId":"a8fdb920-9966-4ddc-ab9f-e61bb6740776","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:41 INFO - 1498748561733 Marionette TRACE 396 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
08:02:41 INFO - 1498748561740 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"
08:02:41 INFO - 1498748561749 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"
08:02:41 INFO - 1498748561751 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"
08:02:41 INFO - ++DOMWINDOW == 25 (000000545B086800) [pid = 3336] [serial = 45] [outer = 000000545F198000]
08:02:41 INFO - 1498748561808 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:02:41 INFO - 1498748561823 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:02:41 INFO - 1498748561842 Marionette TRACE 396 <- [1,2,null,{}]
08:02:41 INFO - 1498748561850 Marionette TRACE 396 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
08:02:41 INFO - 1498748561858 Marionette TRACE 396 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"991c1fb9-288c-4bd4-9ab9-a1add3eafa1f","ELEMENT":"991c1fb9-288c-4bd4-9ab9-a1add3eafa1f"}}]
08:02:41 INFO - 1498748561860 Marionette TRACE 396 -> [0,4,"clickElement",{"id":"991c1fb9-288c-4bd4-9ab9-a1add3eafa1f"}]
08:02:42 INFO - 1498748562019 Marionette TRACE 396 <- [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]
08:02:42 INFO - 1498748562035 Marionette TRACE 396 -> [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}]
08:02:42 INFO - TEST-END: test_click.py TestClick.test_input_file1498748562050 Marionette TRACE 396 <- [1,5,null,{"value":null}]
08:02:42 INFO - 1498748562054 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
08:02:42 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:42 INFO - 1498748562059 Marionette TRACE 396 <- [1,6,null,{}]
08:02:42 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1668ms
08:02:42 INFO - TEST-START | test_click.py TestClick.test_obscured_element
08:02:42 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:42 INFO - 1498748562066 Marionette DEBUG Closed connection 396
08:02:42 INFO - 1498748562068 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49766
08:02:42 INFO - 1498748562069 Marionette DEBUG Closed connection 397
08:02:42 INFO - 1498748562071 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49767
08:02:42 INFO - 1498748562073 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:42 INFO - 1498748562078 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:42 INFO - 1498748562103 Marionette DEBUG Register listener.js for window 7
08:02:42 INFO - 1498748562118 Marionette TRACE 398 <- [1,1,null,{"sessionId":"04dca028-9955-491d-a9d1-90e8b017d74f","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:42 INFO - 1498748562145 Marionette TRACE 398 -> [0,2,"setTimeouts",{"script":30000}]
08:02:42 INFO - 1498748562146 Marionette TRACE 398 <- [1,2,null,{}]
08:02:42 INFO - 1498748562149 Marionette TRACE 398 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:02:42 INFO - 1498748562150 Marionette TRACE 398 <- [1,3,null,{}]
08:02:42 INFO - 1498748562152 Marionette TRACE 398 -> [0,4,"setTimeouts",{"implicit":0}]
08:02:42 INFO - 1498748562154 Marionette TRACE 398 <- [1,4,null,{}]
08:02:42 INFO - 1498748562157 Marionette TRACE 398 -> [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}]
08:02:42 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498748562165 Marionette TRACE 398 <- [1,5,null,{"value":null}]
08:02:42 INFO - 1498748562168 Marionette TRACE 398 -> [0,6,"deleteSession",{}]
08:02:42 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:42 INFO - 1498748562173 Marionette TRACE 398 <- [1,6,null,{}]
08:02:42 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:42 INFO - 1498748562181 Marionette DEBUG Closed connection 398
08:02:42 INFO - 1498748562186 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49768
08:02:42 INFO - 1498748562210 Marionette DEBUG Closed connection 399
08:02:42 INFO - 1498748562212 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49769
08:02:42 INFO - 1498748562215 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:42 INFO - 1498748562217 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:42 INFO - 1498748562240 Marionette DEBUG Register listener.js for window 7
08:02:42 INFO - 1498748562252 Marionette TRACE 400 <- [1,1,null,{"sessionId":"a7973ad7-efb6-4bb9-b627-7cf558f87483","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:42 INFO - 1498748562259 Marionette TRACE 400 -> [0,2,"deleteSession",{}]
08:02:42 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:42 INFO - 1498748562263 Marionette TRACE 400 <- [1,2,null,{}]
08:02:42 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:42 INFO - 1498748562286 Marionette DEBUG Closed connection 400
08:02:42 INFO - 1498748562287 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49770
08:02:42 INFO - 1498748562292 Marionette DEBUG Closed connection 401
08:02:42 INFO - 1498748562293 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49771
08:02:42 INFO - 1498748562296 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:02:42 INFO - 1498748562298 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:42 INFO - 1498748562322 Marionette DEBUG Register listener.js for window 7
08:02:42 INFO - 1498748562335 Marionette TRACE 402 <- [1,1,null,{"sessionId":"55c4f0d7-766d-4b0d-92b3-99ceeda8b25d","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:42 INFO - 1498748562342 Marionette TRACE 402 -> [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"}]
08:02:42 INFO - 1498748562349 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:02:42 INFO - 1498748562356 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:02:42 INFO - 1498748562357 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:02:42 INFO - ++DOMWINDOW == 26 (0000005465010800) [pid = 3336] [serial = 46] [outer = 000000545F198000]
08:02:42 INFO - 1498748562417 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"
08:02:42 INFO - 1498748562425 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"
08:02:42 INFO - 1498748562441 Marionette TRACE 402 <- [1,2,null,{}]
08:02:42 INFO - 1498748562449 Marionette TRACE 402 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
08:02:42 INFO - 1498748562457 Marionette TRACE 402 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"65791604-970d-410d-9f78-a3c312cbc063","ELEMENT":"65791604-970d-410d-9f78-a3c312cbc063"}}]
08:02:42 INFO - 1498748562459 Marionette TRACE 402 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
08:02:42 INFO - 1498748562467 Marionette TRACE 402 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e72d3cae-16d7-4d39-987a-f12f3b298e96","ELEMENT":"e72d3cae-16d7-4d39-987a-f12f3b298e96"}}]
08:02:42 INFO - 1498748562468 Marionette TRACE 402 -> [0,5,"clickElement",{"id":"65791604-970d-410d-9f78-a3c312cbc063"}]
08:02:42 INFO - 1498748562932 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:02:42 INFO - 1498748562933 Marionette TRACE 402 <- [1,5,null,{}]
08:02:42 INFO - 1498748562935 Marionette TRACE 402 -> [0,6,"clickElement",{"id":"e72d3cae-16d7-4d39-987a-f12f3b298e96"}]
08:02:43 INFO - 1498748563103 Marionette TRACE 402 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js: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]
08:02:43 INFO - 1498748563110 Marionette TRACE 402 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
08:02:43 INFO - 1498748563118 Marionette TRACE 402 <- [1,7,null,{"value":false}]
08:02:43 INFO - 1498748563121 Marionette TRACE 402 -> [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}]
08:02:43 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498748563130 Marionette TRACE 402 <- [1,8,null,{"value":null}]
08:02:43 INFO - 1498748563132 Marionette TRACE 402 -> [0,9,"deleteSession",{}]
08:02:43 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:43 INFO - 1498748563136 Marionette TRACE 402 <- [1,9,null,{}]
08:02:43 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1076ms
08:02:43 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
08:02:43 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:43 INFO - 1498748563142 Marionette DEBUG Closed connection 402
08:02:43 INFO - 1498748563144 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49772
08:02:43 INFO - 1498748563145 Marionette DEBUG Closed connection 403
08:02:43 INFO - 1498748563147 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49773
08:02:43 INFO - 1498748563149 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:43 INFO - 1498748563151 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:43 INFO - 1498748563176 Marionette DEBUG Register listener.js for window 7
08:02:43 INFO - 1498748563189 Marionette TRACE 404 <- [1,1,null,{"sessionId":"f6b5a732-a2b6-4e59-bdc9-0f2351fe3e56","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:43 INFO - 1498748563192 Marionette TRACE 404 -> [0,2,"setTimeouts",{"script":30000}]
08:02:43 INFO - 1498748563193 Marionette TRACE 404 <- [1,2,null,{}]
08:02:43 INFO - 1498748563195 Marionette TRACE 404 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:02:43 INFO - 1498748563197 Marionette TRACE 404 <- [1,3,null,{}]
08:02:43 INFO - 1498748563199 Marionette TRACE 404 -> [0,4,"setTimeouts",{"implicit":0}]
08:02:43 INFO - 1498748563201 Marionette TRACE 404 <- [1,4,null,{}]
08:02:43 INFO - 1498748563204 Marionette TRACE 404 -> [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}]
08:02:43 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498748563212 Marionette TRACE 404 <- [1,5,null,{"value":null}]
08:02:43 INFO - 1498748563214 Marionette TRACE 404 -> [0,6,"deleteSession",{}]
08:02:43 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:43 INFO - 1498748563219 Marionette TRACE 404 <- [1,6,null,{}]
08:02:43 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:43 INFO - 1498748563226 Marionette DEBUG Closed connection 404
08:02:43 INFO - 1498748563227 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49774
08:02:43 INFO - 1498748563229 Marionette DEBUG Closed connection 405
08:02:43 INFO - 1498748563230 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49775
08:02:43 INFO - 1498748563233 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:02:43 INFO - 1498748563235 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:43 INFO - 1498748563259 Marionette DEBUG Register listener.js for window 7
08:02:43 INFO - 1498748563271 Marionette TRACE 406 <- [1,1,null,{"sessionId":"d69a296c-b769-4de9-baea-84ab75cc5b79","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":0,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:43 INFO - 1498748563274 Marionette TRACE 406 -> [0,2,"deleteSession",{}]
08:02:43 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:43 INFO - 1498748563278 Marionette TRACE 406 <- [1,2,null,{}]
08:02:43 INFO - [3336] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:02:43 INFO - 1498748563283 Marionette DEBUG Closed connection 406
08:02:43 INFO - 1498748563285 Marionette DEBUG Accepted connection 407 from 127.0.0.1:49776
08:02:43 INFO - 1498748563286 Marionette DEBUG Closed connection 407
08:02:43 INFO - 1498748563288 Marionette DEBUG Accepted connection 408 from 127.0.0.1:49777
08:02:43 INFO - 1498748563290 Marionette TRACE 408 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:02:43 INFO - 1498748563294 Marionette CONFIG Matched capabilities: {"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}
08:02:43 INFO - 1498748563323 Marionette DEBUG Register listener.js for window 7
08:02:43 INFO - 1498748563338 Marionette TRACE 408 <- [1,1,null,{"sessionId":"b1c070ad-66be-4e33-9068-3b39a5fc4aa1","capabilities":{"browserName":"firefox","browserVersion":"56.0a1","platformName":"windows_nt","platformVersion":"6.2","pageLoadStrategy":"normal","acceptInsecureCerts":false,"timeouts":{"implicit":0,"pageLoad":300000,"script":30000},"rotatable":false,"specificationLevel":1,"moz:processID":3336,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpkxuk2j.mozrunner","moz:accessibilityChecks":false}}]
08:02:43 INFO - 1498748563340 Marionette TRACE 408 -> [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"}]
08:02:43 INFO - 1498748563344 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"
08:02:43 INFO - 1498748563354 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"
08:02:43 INFO - 1498748563355 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"
08:02:43 INFO - ++DOMWINDOW == 27 (00000054676A4000) [pid = 3336] [serial = 47] [outer = 000000545F198000]
08:02:43 INFO - 1498748563487 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"
08:02:43 INFO - 1498748563494 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"
08:02:43 INFO - 1498748563511 Marionette TRACE 408 <- [1,2,null,{}]
08:02:43 INFO - 1498748563518 Marionette TRACE 408 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
08:02:43 INFO - 1498748563526 Marionette TRACE 408 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"64550f08-ef6c-40e8-b8f8-4a2813158b14","ELEMENT":"64550f08-ef6c-40e8-b8f8-4a2813158b14"}}]
08:02:43 INFO - 1498748563528 Marionette TRACE 408 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"64550f08-ef6c-40e8-b8f8-4a2813158b14"}]
08:02:43 INFO - 1498748563665 Marionette TRACE 408 <- [1,4,null,{"value":"none"}]
08:02:43 INFO - 1498748563667 Marionette TRACE 408 -> [0,5,"clickElement",{"id":"64550f08-ef6c-40e8-b8f8-4a2813158b14"}]
08:02:43 INFO - 1498748563849 Marionette TRACE 408 <- [1,5,{"error":"element click intercepted","message":"Element