could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
15:21:44 INFO - 1501021304722 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
15:21:44 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1501021304731 Marionette TRACE 352 <- [1,5,null,{"value":null}]
15:21:44 INFO - 1501021304733 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
15:21:44 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:44 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 682ms
15:21:44 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
15:21:44 INFO - 1501021304737 Marionette TRACE 352 <- [1,6,null,{}]
15:21:44 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:44 INFO - 1501021304741 Marionette DEBUG Closed connection 352
15:21:44 INFO - 1501021304743 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49798
15:21:44 INFO - --DOMWINDOW == 17 (000000E4C06E1000) [pid = 2460] [serial = 8] [outer = 0000000000000000] [url = http://127.0.0.1:49336/test_accessibility.html]
15:21:44 INFO - --DOMWINDOW == 16 (000000E4BCABC800) [pid = 2460] [serial = 9] [outer = 0000000000000000] [url = http://127.0.0.1:49336/test_accessibility.html]
15:21:44 INFO - --DOMWINDOW == 15 (000000E4BEF56800) [pid = 2460] [serial = 10] [outer = 0000000000000000] [url = http://127.0.0.1:49336/test_accessibility.html]
15:21:44 INFO - --DOMWINDOW == 14 (000000E4C1118800) [pid = 2460] [serial = 11] [outer = 0000000000000000] [url = http://127.0.0.1:49336/test_accessibility.html]
15:21:44 INFO - 1501021304749 Marionette DEBUG Closed connection 353
15:21:44 INFO - 1501021304751 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49799
15:21:44 INFO - 1501021304753 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:44 INFO - 1501021304755 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:44 INFO - 1501021304775 Marionette DEBUG Register listener.js for window 4294967297
15:21:44 INFO - 1501021304786 Marionette TRACE 354 <- [1,1,null,{"sessionId":"ae5edb45-4961-4e44-b9e3-3c2bf43f0d54","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:44 INFO - 1501021304789 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
15:21:44 INFO - 1501021304791 Marionette TRACE 354 <- [1,2,null,{}]
15:21:44 INFO - 1501021304793 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:44 INFO - 1501021304794 Marionette TRACE 354 <- [1,3,null,{}]
15:21:44 INFO - 1501021304796 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:44 INFO - 1501021304797 Marionette TRACE 354 <- [1,4,null,{}]
15:21:44 INFO - 1501021304800 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
15:21:44 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1501021304807 Marionette TRACE 354 <- [1,5,null,{"value":null}]
15:21:44 INFO - 1501021304809 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
15:21:44 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:44 INFO - 1501021304813 Marionette TRACE 354 <- [1,6,null,{}]
15:21:44 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:44 INFO - 1501021304817 Marionette DEBUG Closed connection 354
15:21:44 INFO - 1501021304819 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49800
15:21:44 INFO - 1501021304820 Marionette DEBUG Closed connection 355
15:21:44 INFO - 1501021304822 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49801
15:21:44 INFO - 1501021304824 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:44 INFO - 1501021304826 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:44 INFO - 1501021304846 Marionette DEBUG Register listener.js for window 4294967297
15:21:44 INFO - 1501021304864 Marionette TRACE 356 <- [1,1,null,{"sessionId":"c2692787-b31e-469f-a105-2a47a10da216","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:44 INFO - 1501021304867 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
15:21:44 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:44 INFO - 1501021304872 Marionette TRACE 356 <- [1,2,null,{}]
15:21:44 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:44 INFO - 1501021304876 Marionette DEBUG Closed connection 356
15:21:44 INFO - 1501021304878 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49802
15:21:44 INFO - 1501021304879 Marionette DEBUG Closed connection 357
15:21:44 INFO - 1501021304881 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49803
15:21:44 INFO - 1501021304884 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:44 INFO - 1501021304886 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:44 INFO - 1501021304906 Marionette DEBUG Register listener.js for window 4294967297
15:21:44 INFO - 1501021304920 Marionette TRACE 358 <- [1,1,null,{"sessionId":"a7bc39b5-8017-4f36-994d-ff86df0ec715","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:44 INFO - 1501021304922 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49336/clicks.html"}]
15:21:44 INFO - 1501021304927 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/hidden.html"
15:21:44 INFO - 1501021304946 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/hidden.html"
15:21:44 INFO - 1501021304947 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/hidden.html"
15:21:44 INFO - ++DOMWINDOW == 15 (000000E4BFF79800) [pid = 2460] [serial = 25] [outer = 000000E4AE7D5800]
15:21:44 INFO - 1501021304978 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49336/clicks.html"
15:21:44 INFO - 1501021304982 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49336/clicks.html"
15:21:44 INFO - 1501021304987 Marionette TRACE 358 <- [1,2,null,{}]
15:21:45 INFO - 1501021305003 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
15:21:45 INFO - 1501021305012 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"10e77aac-7118-4ad2-907a-f85ab136af3c","ELEMENT":"10e77aac-7118-4ad2-907a-f85ab136af3c"}}]
15:21:45 INFO - 1501021305014 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"10e77aac-7118-4ad2-907a-f85ab136af3c"}]
15:21:45 INFO - 1501021305233 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/clicks.html"
15:21:45 INFO - 1501021305248 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/clicks.html"
15:21:45 INFO - 1501021305249 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/clicks.html"
15:21:45 INFO - ++DOMWINDOW == 16 (000000E4C0F96000) [pid = 2460] [serial = 26] [outer = 000000E4AE7D5800]
15:21:45 INFO - 1501021305279 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49336/test.html"
15:21:45 INFO - 1501021305283 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49336/test.html"
15:21:45 INFO - 1501021305295 Marionette TRACE 358 <- [1,4,null,{}]
15:21:45 INFO - 1501021305310 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
15:21:45 INFO - 1501021305316 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"53d82467-23a0-4f46-bbfb-f445cc40d9f0","ELEMENT":"53d82467-23a0-4f46-bbfb-f445cc40d9f0"}}]
15:21:45 INFO - 1501021305318 Marionette TRACE 358 -> [0,6,"getTitle",{}]
15:21:45 INFO - 1501021305319 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
15:21:45 INFO - 1501021305322 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
15:21:45 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1501021305329 Marionette TRACE 358 <- [1,7,null,{"value":null}]
15:21:45 INFO - 1501021305331 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
15:21:45 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:45 INFO - 1501021305335 Marionette TRACE 358 <- [1,8,null,{}]
15:21:45 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 599ms
15:21:45 INFO - TEST-START | test_click.py TestClick.test_container_element
15:21:45 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:45 INFO - 1501021305339 Marionette DEBUG Closed connection 358
15:21:45 INFO - 1501021305341 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49806
15:21:45 INFO - 1501021305343 Marionette DEBUG Closed connection 359
15:21:45 INFO - 1501021305344 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49807
15:21:45 INFO - 1501021305346 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:45 INFO - 1501021305348 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:45 INFO - 1501021305368 Marionette DEBUG Register listener.js for window 4294967297
15:21:45 INFO - 1501021305392 Marionette TRACE 360 <- [1,1,null,{"sessionId":"65f5dbfa-6ad8-4845-87f1-ce08e4a79c18","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:45 INFO - 1501021305395 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
15:21:45 INFO - 1501021305397 Marionette TRACE 360 <- [1,2,null,{}]
15:21:45 INFO - 1501021305399 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:45 INFO - 1501021305400 Marionette TRACE 360 <- [1,3,null,{}]
15:21:45 INFO - 1501021305402 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:45 INFO - 1501021305403 Marionette TRACE 360 <- [1,4,null,{}]
15:21:45 INFO - 1501021305406 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
15:21:45 INFO - TEST-START: test_click.py TestClick.test_container_element1501021305413 Marionette TRACE 360 <- [1,5,null,{"value":null}]
15:21:45 INFO - 1501021305415 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
15:21:45 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:45 INFO - 1501021305420 Marionette TRACE 360 <- [1,6,null,{}]
15:21:45 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:45 INFO - 1501021305424 Marionette DEBUG Closed connection 360
15:21:45 INFO - 1501021305425 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49808
15:21:45 INFO - 1501021305427 Marionette DEBUG Closed connection 361
15:21:45 INFO - 1501021305428 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49809
15:21:45 INFO - 1501021305430 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:45 INFO - 1501021305432 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:45 INFO - 1501021305453 Marionette DEBUG Register listener.js for window 4294967297
15:21:45 INFO - 1501021305465 Marionette TRACE 362 <- [1,1,null,{"sessionId":"13aad7a1-bc54-483c-802a-b861994901a0","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:45 INFO - 1501021305468 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
15:21:45 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:45 INFO - 1501021305472 Marionette TRACE 362 <- [1,2,null,{}]
15:21:45 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:45 INFO - 1501021305476 Marionette DEBUG Closed connection 362
15:21:45 INFO - 1501021305478 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49810
15:21:45 INFO - 1501021305479 Marionette DEBUG Closed connection 363
15:21:45 INFO - 1501021305481 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49811
15:21:45 INFO - 1501021305483 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:45 INFO - 1501021305485 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:45 INFO - 1501021305505 Marionette DEBUG Register listener.js for window 4294967297
15:21:45 INFO - 1501021305516 Marionette TRACE 364 <- [1,1,null,{"sessionId":"2c5fa463-8b53-4581-8d8b-5a39ea7193f9","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:45 INFO - 1501021305519 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
15:21:45 INFO - 1501021305523 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49336/test.html"
15:21:45 INFO - 1501021305528 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49336/test.html"
15:21:45 INFO - 1501021305529 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49336/test.html"
15:21:45 INFO - ++DOMWINDOW == 17 (000000E4BA764800) [pid = 2460] [serial = 27] [outer = 000000E4AE7D5800]
15:21:45 INFO - 1501021305551 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:45 INFO - 1501021305559 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:45 INFO - 1501021305577 Marionette TRACE 364 <- [1,2,null,{}]
15:21:45 INFO - 1501021305614 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:21:45 INFO - 1501021305621 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fecfb5b6-1a46-45f8-8474-5ca285a5b05d","ELEMENT":"fecfb5b6-1a46-45f8-8474-5ca285a5b05d"}}]
15:21:45 INFO - 1501021305623 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"fecfb5b6-1a46-45f8-8474-5ca285a5b05d"}]
15:21:45 INFO - [Child 2460] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
15:21:45 INFO - [Child 2460] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
15:21:45 INFO - [Child 2460] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
15:21:46 INFO - 1501021306046 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:21:46 INFO - 1501021306144 Marionette TRACE 364 <- [1,4,null,{}]
15:21:46 INFO - 1501021306149 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"fecfb5b6-1a46-45f8-8474-5ca285a5b05d","name":"selected"}]
15:21:46 INFO - 1501021306176 Marionette TRACE 364 <- [1,5,null,{"value":true}]
15:21:46 INFO - 1501021306179 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
15:21:46 INFO - TEST-END: test_click.py TestClick.test_container_element1501021306186 Marionette TRACE 364 <- [1,6,null,{"value":null}]
15:21:46 INFO - 1501021306188 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
15:21:46 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:46 INFO - 1501021306192 Marionette TRACE 364 <- [1,7,null,{}]
15:21:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 857ms
15:21:46 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
15:21:46 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:46 INFO - 1501021306196 Marionette DEBUG Closed connection 364
15:21:46 INFO - 1501021306197 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49812
15:21:46 INFO - 1501021306199 Marionette DEBUG Closed connection 365
15:21:46 INFO - 1501021306200 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49813
15:21:46 INFO - 1501021306203 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:46 INFO - 1501021306205 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:46 INFO - 1501021306225 Marionette DEBUG Register listener.js for window 4294967297
15:21:46 INFO - 1501021306236 Marionette TRACE 366 <- [1,1,null,{"sessionId":"bc01030f-3b2e-4f28-8ecf-93232bd6007d","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:46 INFO - 1501021306239 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
15:21:46 INFO - 1501021306241 Marionette TRACE 366 <- [1,2,null,{}]
15:21:46 INFO - 1501021306242 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:46 INFO - 1501021306244 Marionette TRACE 366 <- [1,3,null,{}]
15:21:46 INFO - 1501021306246 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:46 INFO - 1501021306247 Marionette TRACE 366 <- [1,4,null,{}]
15:21:46 INFO - 1501021306250 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
15:21:46 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1501021306257 Marionette TRACE 366 <- [1,5,null,{"value":null}]
15:21:46 INFO - 1501021306260 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
15:21:46 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:46 INFO - 1501021306265 Marionette TRACE 366 <- [1,6,null,{}]
15:21:46 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:46 INFO - 1501021306269 Marionette DEBUG Closed connection 366
15:21:46 INFO - 1501021306270 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49814
15:21:46 INFO - 1501021306272 Marionette DEBUG Closed connection 367
15:21:46 INFO - 1501021306273 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49815
15:21:46 INFO - 1501021306276 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:46 INFO - 1501021306278 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:46 INFO - 1501021306298 Marionette DEBUG Register listener.js for window 4294967297
15:21:46 INFO - 1501021306309 Marionette TRACE 368 <- [1,1,null,{"sessionId":"2f451974-0d3f-4844-bade-ff14f3056354","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:46 INFO - 1501021306312 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
15:21:46 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:46 INFO - 1501021306316 Marionette TRACE 368 <- [1,2,null,{}]
15:21:46 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:46 INFO - 1501021306320 Marionette DEBUG Closed connection 368
15:21:46 INFO - 1501021306322 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49816
15:21:46 INFO - 1501021306323 Marionette DEBUG Closed connection 369
15:21:46 INFO - 1501021306324 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49817
15:21:46 INFO - 1501021306326 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:46 INFO - 1501021306329 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:46 INFO - 1501021306349 Marionette DEBUG Register listener.js for window 4294967297
15:21:46 INFO - 1501021306360 Marionette TRACE 370 <- [1,1,null,{"sessionId":"cd95d3ca-899a-44f1-bdd5-927f0c6b3632","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:46 INFO - 1501021306363 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
15:21:46 INFO - 1501021306367 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:46 INFO - 1501021306372 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:46 INFO - 1501021306373 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:46 INFO - ++DOMWINDOW == 18 (000000E4C020E800) [pid = 2460] [serial = 28] [outer = 000000E4AE7D5800]
15:21:46 INFO - 1501021306398 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:46 INFO - 1501021306402 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:46 INFO - 1501021306413 Marionette TRACE 370 <- [1,2,null,{}]
15:21:46 INFO - 1501021306428 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
15:21:46 INFO - 1501021306435 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"539a102b-2cc8-4e4e-aeac-f6e4c2bda7a4","ELEMENT":"539a102b-2cc8-4e4e-aeac-f6e4c2bda7a4"}}]
15:21:46 INFO - 1501021306437 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"539a102b-2cc8-4e4e-aeac-f6e4c2bda7a4"}]
15:21:46 INFO - [Child 2460] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
15:21:46 INFO - [Child 2460] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
15:21:46 INFO - [Child 2460] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
15:21:46 INFO - 1501021306872 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:21:46 INFO - 1501021306874 Marionette TRACE 370 <- [1,4,null,{}]
15:21:46 INFO - 1501021306876 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"539a102b-2cc8-4e4e-aeac-f6e4c2bda7a4","name":"selected"}]
15:21:46 INFO - 1501021306903 Marionette TRACE 370 <- [1,5,null,{"value":true}]
15:21:46 INFO - 1501021306905 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
15:21:46 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1501021306912 Marionette TRACE 370 <- [1,6,null,{"value":null}]
15:21:46 INFO - 1501021306914 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
15:21:46 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:46 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 724ms
15:21:46 INFO - TEST-START | test_click.py TestClick.test_css_transforms
15:21:46 INFO - 1501021306918 Marionette TRACE 370 <- [1,7,null,{}]
15:21:46 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:46 INFO - 1501021306922 Marionette DEBUG Closed connection 370
15:21:46 INFO - 1501021306924 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49818
15:21:46 INFO - 1501021306925 Marionette DEBUG Closed connection 371
15:21:46 INFO - 1501021306927 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49819
15:21:46 INFO - 1501021306929 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:46 INFO - 1501021306931 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:46 INFO - 1501021306951 Marionette DEBUG Register listener.js for window 4294967297
15:21:46 INFO - 1501021306962 Marionette TRACE 372 <- [1,1,null,{"sessionId":"6f1aaf81-df0a-48a2-9da3-f44e35868960","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:46 INFO - 1501021306964 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
15:21:46 INFO - 1501021306966 Marionette TRACE 372 <- [1,2,null,{}]
15:21:46 INFO - 1501021306968 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:46 INFO - 1501021306969 Marionette TRACE 372 <- [1,3,null,{}]
15:21:46 INFO - 1501021306971 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:46 INFO - 1501021306973 Marionette TRACE 372 <- [1,4,null,{}]
15:21:46 INFO - 1501021306975 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
15:21:46 INFO - TEST-START: test_click.py TestClick.test_css_transforms1501021306982 Marionette TRACE 372 <- [1,5,null,{"value":null}]
15:21:46 INFO - 1501021306984 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
15:21:46 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:46 INFO - 1501021306988 Marionette TRACE 372 <- [1,6,null,{}]
15:21:46 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:46 INFO - 1501021306992 Marionette DEBUG Closed connection 372
15:21:46 INFO - 1501021306993 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49820
15:21:46 INFO - 1501021306995 Marionette DEBUG Closed connection 373
15:21:46 INFO - 1501021306996 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49821
15:21:47 INFO - 1501021306999 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:47 INFO - 1501021307001 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:47 INFO - 1501021307021 Marionette DEBUG Register listener.js for window 4294967297
15:21:47 INFO - 1501021307032 Marionette TRACE 374 <- [1,1,null,{"sessionId":"d5d4d352-eea5-4275-ba92-b8e81df56d22","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:47 INFO - 1501021307035 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
15:21:47 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:47 INFO - 1501021307040 Marionette TRACE 374 <- [1,2,null,{}]
15:21:47 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:47 INFO - 1501021307043 Marionette DEBUG Closed connection 374
15:21:47 INFO - 1501021307045 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49822
15:21:47 INFO - 1501021307046 Marionette DEBUG Closed connection 375
15:21:47 INFO - 1501021307048 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49823
15:21:47 INFO - 1501021307050 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:47 INFO - 1501021307052 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:47 INFO - 1501021307073 Marionette DEBUG Register listener.js for window 4294967297
15:21:47 INFO - 1501021307084 Marionette TRACE 376 <- [1,1,null,{"sessionId":"92db0f1d-fe6d-4819-9f32-2cd3cc147041","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:47 INFO - 1501021307087 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
15:21:47 INFO - 1501021307205 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:47 INFO - 1501021307210 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:47 INFO - 1501021307211 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:47 INFO - ++DOMWINDOW == 19 (000000E4BA755000) [pid = 2460] [serial = 29] [outer = 000000E4AE7D5800]
15:21:47 INFO - 1501021307233 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
15:21:47 INFO - 1501021307239 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
15:21:47 INFO - 1501021307251 Marionette TRACE 376 <- [1,2,null,{}]
15:21:47 INFO - 1501021307267 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
15:21:47 INFO - 1501021307275 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"53170f59-03ea-4029-adec-0e4286d8f00f","ELEMENT":"53170f59-03ea-4029-adec-0e4286d8f00f"}}]
15:21:47 INFO - 1501021307277 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"53170f59-03ea-4029-adec-0e4286d8f00f"}]
15:21:47 INFO - 1501021307698 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:21:47 INFO - 1501021307700 Marionette TRACE 376 <- [1,4,null,{}]
15:21:47 INFO - 1501021307703 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
15:21:47 INFO - TEST-END: test_click.py TestClick.test_css_transforms1501021307710 Marionette TRACE 376 <- [1,5,null,{"value":null}]
15:21:47 INFO - 1501021307712 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
15:21:47 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:47 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 797ms
15:21:47 INFO - 1501021307716 Marionette TRACE 376 <- [1,6,null,{}]
15:21:47 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
15:21:47 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:47 INFO - 1501021307720 Marionette DEBUG Closed connection 376
15:21:47 INFO - 1501021307721 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49824
15:21:47 INFO - 1501021307723 Marionette DEBUG Closed connection 377
15:21:47 INFO - 1501021307724 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49825
15:21:47 INFO - 1501021307726 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:47 INFO - 1501021307728 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:47 INFO - 1501021307749 Marionette DEBUG Register listener.js for window 4294967297
15:21:47 INFO - 1501021307760 Marionette TRACE 378 <- [1,1,null,{"sessionId":"db84c8f5-6652-45ae-8a83-584085066f01","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:47 INFO - 1501021307763 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
15:21:47 INFO - 1501021307765 Marionette TRACE 378 <- [1,2,null,{}]
15:21:47 INFO - 1501021307766 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:47 INFO - 1501021307768 Marionette TRACE 378 <- [1,3,null,{}]
15:21:47 INFO - 1501021307770 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:47 INFO - 1501021307771 Marionette TRACE 378 <- [1,4,null,{}]
15:21:47 INFO - 1501021307774 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
15:21:47 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1501021307787 Marionette TRACE 378 <- [1,5,null,{"value":null}]
15:21:47 INFO - 1501021307789 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
15:21:47 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:47 INFO - 1501021307793 Marionette TRACE 378 <- [1,6,null,{}]
15:21:47 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:47 INFO - 1501021307797 Marionette DEBUG Closed connection 378
15:21:47 INFO - 1501021307798 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49826
15:21:47 INFO - 1501021307800 Marionette DEBUG Closed connection 379
15:21:47 INFO - 1501021307801 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49827
15:21:47 INFO - 1501021307803 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:47 INFO - 1501021307806 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:47 INFO - 1501021307826 Marionette DEBUG Register listener.js for window 4294967297
15:21:47 INFO - 1501021307838 Marionette TRACE 380 <- [1,1,null,{"sessionId":"8888a1d5-5fc4-4846-b324-7b6b7a5a5c46","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:47 INFO - 1501021307841 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
15:21:47 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:47 INFO - 1501021307845 Marionette TRACE 380 <- [1,2,null,{}]
15:21:47 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:47 INFO - 1501021307849 Marionette DEBUG Closed connection 380
15:21:47 INFO - 1501021307850 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49828
15:21:47 INFO - 1501021307852 Marionette DEBUG Closed connection 381
15:21:47 INFO - 1501021307853 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49829
15:21:47 INFO - 1501021307855 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:47 INFO - 1501021307858 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:47 INFO - 1501021307878 Marionette DEBUG Register listener.js for window 4294967297
15:21:47 INFO - 1501021307889 Marionette TRACE 382 <- [1,1,null,{"sessionId":"0ab555a2-80f8-4b97-99ff-a416ac3e0f88","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:47 INFO - 1501021307892 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
15:21:47 INFO - 1501021307907 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
15:21:47 INFO - 1501021307911 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
15:21:47 INFO - 1501021307912 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
15:21:47 INFO - ++DOMWINDOW == 20 (000000E4C021B800) [pid = 2460] [serial = 30] [outer = 000000E4AE7D5800]
15:21:47 INFO - 1501021307938 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:47 INFO - 1501021307942 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:47 INFO - 1501021307951 Marionette TRACE 382 <- [1,2,null,{}]
15:21:47 INFO - 1501021307966 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
15:21:47 INFO - 1501021307972 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"efbc016d-64b5-4091-89cb-a7a6bec0f8e7","ELEMENT":"efbc016d-64b5-4091-89cb-a7a6bec0f8e7"}}]
15:21:47 INFO - 1501021307974 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"efbc016d-64b5-4091-89cb-a7a6bec0f8e7"}]
15:21:48 INFO - 1501021308408 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:21:48 INFO - 1501021308410 Marionette TRACE 382 <- [1,4,null,{}]
15:21:48 INFO - 1501021308413 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
15:21:48 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1501021308420 Marionette TRACE 382 <- [1,5,null,{"value":null}]
15:21:48 INFO - 1501021308422 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
15:21:48 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:48 INFO - 1501021308426 Marionette TRACE 382 <- [1,6,null,{}]
15:21:48 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 709ms
15:21:48 INFO - TEST-START | test_click.py TestClick.test_input_file
15:21:48 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:48 INFO - 1501021308430 Marionette DEBUG Closed connection 382
15:21:48 INFO - 1501021308431 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49830
15:21:48 INFO - 1501021308433 Marionette DEBUG Closed connection 383
15:21:48 INFO - 1501021308434 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49831
15:21:48 INFO - 1501021308436 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:48 INFO - 1501021308438 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:48 INFO - 1501021308459 Marionette DEBUG Register listener.js for window 4294967297
15:21:48 INFO - 1501021308470 Marionette TRACE 384 <- [1,1,null,{"sessionId":"8bd253f7-7f0d-42ef-b89e-8d0625124584","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:48 INFO - 1501021308473 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
15:21:48 INFO - 1501021308474 Marionette TRACE 384 <- [1,2,null,{}]
15:21:48 INFO - 1501021308476 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:48 INFO - 1501021308478 Marionette TRACE 384 <- [1,3,null,{}]
15:21:48 INFO - 1501021308479 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:48 INFO - 1501021308481 Marionette TRACE 384 <- [1,4,null,{}]
15:21:48 INFO - 1501021308484 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
15:21:48 INFO - TEST-START: test_click.py TestClick.test_input_file1501021308500 Marionette TRACE 384 <- [1,5,null,{"value":null}]
15:21:48 INFO - 1501021308502 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
15:21:48 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:48 INFO - 1501021308506 Marionette TRACE 384 <- [1,6,null,{}]
15:21:48 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:48 INFO - 1501021308511 Marionette DEBUG Closed connection 384
15:21:48 INFO - 1501021308513 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49832
15:21:48 INFO - 1501021308515 Marionette DEBUG Closed connection 385
15:21:48 INFO - 1501021308516 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49833
15:21:48 INFO - 1501021308518 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:48 INFO - 1501021308521 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:48 INFO - 1501021308990 Marionette DEBUG Register listener.js for window 4294967297
15:21:49 INFO - 1501021309001 Marionette TRACE 386 <- [1,1,null,{"sessionId":"08be076b-11c0-41d5-aed7-59ca764b8f9a","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:49 INFO - 1501021309004 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
15:21:49 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:49 INFO - 1501021309008 Marionette TRACE 386 <- [1,2,null,{}]
15:21:49 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:49 INFO - 1501021309012 Marionette DEBUG Closed connection 386
15:21:49 INFO - 1501021309014 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49834
15:21:49 INFO - 1501021309017 Marionette DEBUG Closed connection 387
15:21:49 INFO - 1501021309018 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49835
15:21:49 INFO - 1501021309021 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:49 INFO - 1501021309023 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:49 INFO - 1501021309056 Marionette DEBUG Register listener.js for window 4294967297
15:21:49 INFO - 1501021309080 Marionette TRACE 388 <- [1,1,null,{"sessionId":"6b2bdc56-16e3-4011-923a-6ae22acb5791","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:49 INFO - 1501021309083 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
15:21:49 INFO - 1501021309124 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:49 INFO - 1501021309129 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:49 INFO - 1501021309130 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
15:21:49 INFO - ++DOMWINDOW == 21 (000000E4BFC4E000) [pid = 2460] [serial = 31] [outer = 000000E4AE7D5800]
15:21:49 INFO - 1501021309158 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:21:49 INFO - 1501021309162 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:21:49 INFO - 1501021309166 Marionette TRACE 388 <- [1,2,null,{}]
15:21:49 INFO - 1501021309180 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
15:21:49 INFO - 1501021309219 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2e62d290-ad19-40ed-a167-3eeb16554c5a","ELEMENT":"2e62d290-ad19-40ed-a167-3eeb16554c5a"}}]
15:21:49 INFO - 1501021309221 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"2e62d290-ad19-40ed-a167-3eeb16554c5a"}]
15:21:49 INFO - 1501021309370 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
15:21:49 INFO - 1501021309373 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
15:21:49 INFO - TEST-END: test_click.py TestClick.test_input_file1501021309418 Marionette TRACE 388 <- [1,5,null,{"value":null}]
15:21:49 INFO - 1501021309420 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
15:21:49 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:49 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 998ms
15:21:49 INFO - 1501021309425 Marionette TRACE 388 <- [1,6,null,{}]
15:21:49 INFO - TEST-START | test_click.py TestClick.test_obscured_element
15:21:49 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:49 INFO - 1501021309429 Marionette DEBUG Closed connection 388
15:21:49 INFO - 1501021309430 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49836
15:21:49 INFO - 1501021309432 Marionette DEBUG Closed connection 389
15:21:49 INFO - 1501021309433 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49837
15:21:49 INFO - 1501021309435 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:49 INFO - 1501021309437 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:49 INFO - 1501021309474 Marionette DEBUG Register listener.js for window 4294967297
15:21:49 INFO - 1501021309485 Marionette TRACE 390 <- [1,1,null,{"sessionId":"cbc447a9-ca3b-4ade-a012-44bec2ab5a7a","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:49 INFO - 1501021309489 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
15:21:49 INFO - 1501021309490 Marionette TRACE 390 <- [1,2,null,{}]
15:21:49 INFO - 1501021309492 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:49 INFO - 1501021309493 Marionette TRACE 390 <- [1,3,null,{}]
15:21:49 INFO - 1501021309495 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:49 INFO - 1501021309497 Marionette TRACE 390 <- [1,4,null,{}]
15:21:49 INFO - 1501021309499 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
15:21:49 INFO - TEST-START: test_click.py TestClick.test_obscured_element1501021309541 Marionette TRACE 390 <- [1,5,null,{"value":null}]
15:21:49 INFO - 1501021309543 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
15:21:49 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:49 INFO - 1501021309547 Marionette TRACE 390 <- [1,6,null,{}]
15:21:49 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:49 INFO - 1501021309552 Marionette DEBUG Closed connection 390
15:21:49 INFO - 1501021309553 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49838
15:21:49 INFO - 1501021309555 Marionette DEBUG Closed connection 391
15:21:49 INFO - 1501021309556 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49839
15:21:49 INFO - 1501021309558 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:49 INFO - 1501021309560 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:49 INFO - 1501021309595 Marionette DEBUG Register listener.js for window 4294967297
15:21:49 INFO - 1501021309605 Marionette TRACE 392 <- [1,1,null,{"sessionId":"c5189a90-d35d-4528-816f-1c8f4d8747b5","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:49 INFO - 1501021309608 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
15:21:49 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:49 INFO - 1501021309612 Marionette TRACE 392 <- [1,2,null,{}]
15:21:49 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:49 INFO - 1501021309617 Marionette DEBUG Closed connection 392
15:21:49 INFO - 1501021309618 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49840
15:21:49 INFO - 1501021309620 Marionette DEBUG Closed connection 393
15:21:49 INFO - 1501021309621 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49841
15:21:49 INFO - 1501021309624 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:49 INFO - 1501021309626 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:49 INFO - 1501021309650 Marionette DEBUG Register listener.js for window 4294967297
15:21:49 INFO - 1501021309661 Marionette TRACE 394 <- [1,1,null,{"sessionId":"0b9a9c26-b0b1-4853-9d42-1e6ea95eb025","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:49 INFO - 1501021309664 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
15:21:49 INFO - 1501021309668 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:21:49 INFO - 1501021309674 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:21:49 INFO - 1501021309674 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
15:21:49 INFO - ++DOMWINDOW == 22 (000000E4BFC5F000) [pid = 2460] [serial = 32] [outer = 000000E4AE7D5800]
15:21:49 INFO - 1501021309697 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
15:21:49 INFO - 1501021309704 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
15:21:49 INFO - 1501021309716 Marionette TRACE 394 <- [1,2,null,{}]
15:21:49 INFO - 1501021309731 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
15:21:49 INFO - 1501021309738 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b6e0ea2c-acda-4551-9673-45e8aeabfb3a","ELEMENT":"b6e0ea2c-acda-4551-9673-45e8aeabfb3a"}}]
15:21:49 INFO - 1501021309740 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
15:21:49 INFO - 1501021309746 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7c43058e-a024-4b3e-a0ed-e41071205503","ELEMENT":"7c43058e-a024-4b3e-a0ed-e41071205503"}}]
15:21:49 INFO - 1501021309748 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"b6e0ea2c-acda-4551-9673-45e8aeabfb3a"}]
15:21:50 INFO - 1501021310191 Marionette DEBUG Canceled page load listener because no navigation has been detected
15:21:50 INFO - 1501021310193 Marionette TRACE 394 <- [1,5,null,{}]
15:21:50 INFO - 1501021310195 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"7c43058e-a024-4b3e-a0ed-e41071205503"}]
15:21:50 INFO - 1501021310323 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
15:21:50 INFO - 1501021310326 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
15:21:50 INFO - 1501021310341 Marionette TRACE 394 <- [1,7,null,{"value":false}]
15:21:50 INFO - 1501021310343 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
15:21:50 INFO - TEST-END: test_click.py TestClick.test_obscured_element1501021310350 Marionette TRACE 394 <- [1,8,null,{"value":null}]
15:21:50 INFO - 1501021310352 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
15:21:50 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 930ms
15:21:50 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:50 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
15:21:50 INFO - 1501021310356 Marionette TRACE 394 <- [1,9,null,{}]
15:21:50 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:50 INFO - 1501021310360 Marionette DEBUG Closed connection 394
15:21:50 INFO - 1501021310362 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49842
15:21:50 INFO - 1501021310363 Marionette DEBUG Closed connection 395
15:21:50 INFO - 1501021310365 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49843
15:21:50 INFO - 1501021310367 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:50 INFO - 1501021310369 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:50 INFO - 1501021310391 Marionette DEBUG Register listener.js for window 4294967297
15:21:50 INFO - 1501021310402 Marionette TRACE 396 <- [1,1,null,{"sessionId":"79816ffe-d5f5-4d7c-8f3b-ee2c23a46801","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:50 INFO - 1501021310405 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
15:21:50 INFO - 1501021310406 Marionette TRACE 396 <- [1,2,null,{}]
15:21:50 INFO - 1501021310408 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
15:21:50 INFO - 1501021310410 Marionette TRACE 396 <- [1,3,null,{}]
15:21:50 INFO - 1501021310412 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
15:21:50 INFO - 1501021310414 Marionette TRACE 396 <- [1,4,null,{}]
15:21:50 INFO - 1501021310417 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
15:21:50 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1501021310424 Marionette TRACE 396 <- [1,5,null,{"value":null}]
15:21:50 INFO - 1501021310426 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
15:21:50 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:50 INFO - 1501021310431 Marionette TRACE 396 <- [1,6,null,{}]
15:21:50 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:50 INFO - 1501021310436 Marionette DEBUG Closed connection 396
15:21:50 INFO - 1501021310438 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49844
15:21:50 INFO - 1501021310440 Marionette DEBUG Closed connection 397
15:21:50 INFO - 1501021310441 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49845
15:21:50 INFO - 1501021310443 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
15:21:50 INFO - 1501021310446 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:50 INFO - 1501021310468 Marionette DEBUG Register listener.js for window 4294967297
15:21:50 INFO - 1501021310479 Marionette TRACE 398 <- [1,1,null,{"sessionId":"14f406d4-6ec9-4b59-96bc-d1856551ab61","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:50 INFO - 1501021310482 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
15:21:50 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:50 INFO - 1501021310486 Marionette TRACE 398 <- [1,2,null,{}]
15:21:50 INFO - [Parent 3700] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
15:21:50 INFO - 1501021310491 Marionette DEBUG Closed connection 398
15:21:50 INFO - 1501021310492 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49846
15:21:50 INFO - 1501021310495 Marionette DEBUG Closed connection 399
15:21:50 INFO - 1501021310497 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49847
15:21:50 INFO - 1501021310500 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
15:21:50 INFO - 1501021310502 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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}
15:21:50 INFO - 1501021310524 Marionette DEBUG Register listener.js for window 4294967297
15:21:50 INFO - 1501021310535 Marionette TRACE 400 <- [1,1,null,{"sessionId":"f83f3ba0-5f00-438e-9f63-13ec14bfe9bb","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":3700,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-168.001\\AppData\\Local\\Temp\\tmpbbwvbm.mozrunner","moz:accessibilityChecks":false}}]
15:21:50 INFO - 1501021310539 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
15:21:50 INFO - 1501021310543 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
15:21:50 INFO - 1501021310548 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
15:21:50 INFO - 1501021310548 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
15:21:50 INFO - ++DOMWINDOW == 23 (000000E4C139F800) [pid = 2460] [serial = 33] [outer = 000000E4AE7D5800]
15:21:50 INFO - 1501021310574 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
15:21:50 INFO - 1501021310578 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
15:21:50 INFO - 1501021310589 Marionette TRACE 400 <- [1,2,null,{}]
15:21:50 INFO - 1501021310604 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
15:21:50 INFO - 1501021310616 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9ad2808e-f4e8-4fd1-b70e-60ee608edd3b","ELEMENT":"9ad2808e-f4e8-4fd1-b70e-60ee608edd3b"}}]
15:21:50 INFO - 1501021310619 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"9ad2808e-f4e8-4fd1-b70e-60ee608edd3b"}]
15:21:50 INFO - 1501021310742 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
15:21:50 INFO - 1501021310744 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"9ad2808e-f4e8-4fd1-b70e-60ee608edd3b"}]
15:21:50 INFO - 1501021310873 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element