could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
21:47:34 INFO - --DOMWINDOW == 26 (0000002128C99000) [pid = 2760] [serial = 34] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
21:47:34 INFO - --DOMWINDOW == 25 (0000002127CDB800) [pid = 2760] [serial = 33] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
21:47:34 INFO - --DOMWINDOW == 24 (000000212529E000) [pid = 2760] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
21:47:34 INFO - --DOMWINDOW == 23 (000000212228B000) [pid = 2760] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
21:47:34 INFO - --DOMWINDOW == 22 (00000021145A9000) [pid = 2760] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
21:47:34 INFO - --DOMWINDOW == 21 (0000002127436000) [pid = 2760] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
21:47:34 INFO - --DOMWINDOW == 20 (000000211D171000) [pid = 2760] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
21:47:34 INFO - 1499230054219 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}]
21:47:34 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499230054228 Marionette TRACE 352 <- [1,5,null,{"value":null}]
21:47:34 INFO - 1499230054230 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
21:47:34 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:34 INFO - 1499230054234 Marionette TRACE 352 <- [1,6,null,{}]
21:47:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 743ms
21:47:34 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:47:34 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:34 INFO - 1499230054240 Marionette DEBUG Closed connection 352
21:47:34 INFO - 1499230054242 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49781
21:47:34 INFO - 1499230054244 Marionette DEBUG Closed connection 353
21:47:34 INFO - 1499230054247 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49782
21:47:34 INFO - 1499230054249 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:34 INFO - 1499230054252 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:34 INFO - 1499230054274 Marionette DEBUG Register listener.js for window 12
21:47:34 INFO - 1499230054291 Marionette TRACE 354 <- [1,1,null,{"sessionId":"81d9a23c-cb48-4c9e-b3ac-c025865d9f94","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:34 INFO - 1499230054315 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
21:47:34 INFO - 1499230054318 Marionette TRACE 354 <- [1,2,null,{}]
21:47:34 INFO - 1499230054424 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:47:34 INFO - 1499230054426 Marionette TRACE 354 <- [1,3,null,{}]
21:47:34 INFO - 1499230054429 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
21:47:34 INFO - 1499230054430 Marionette TRACE 354 <- [1,4,null,{}]
21:47:34 INFO - 1499230054433 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}]
21:47:34 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499230054443 Marionette TRACE 354 <- [1,5,null,{"value":null}]
21:47:34 INFO - 1499230054447 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
21:47:34 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:34 INFO - 1499230054452 Marionette TRACE 354 <- [1,6,null,{}]
21:47:34 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:34 INFO - 1499230054460 Marionette DEBUG Closed connection 354
21:47:34 INFO - 1499230054462 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49783
21:47:34 INFO - 1499230054480 Marionette DEBUG Closed connection 355
21:47:34 INFO - 1499230054482 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49784
21:47:34 INFO - 1499230054485 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:34 INFO - 1499230054487 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:34 INFO - 1499230054509 Marionette DEBUG Register listener.js for window 12
21:47:34 INFO - 1499230054546 Marionette TRACE 356 <- [1,1,null,{"sessionId":"137a141d-965c-4c2d-ad77-e6c67eb22614","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:34 INFO - 1499230054551 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
21:47:34 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:34 INFO - 1499230054556 Marionette TRACE 356 <- [1,2,null,{}]
21:47:34 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:34 INFO - 1499230054584 Marionette DEBUG Closed connection 356
21:47:34 INFO - 1499230054589 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49785
21:47:34 INFO - 1499230054591 Marionette DEBUG Closed connection 357
21:47:34 INFO - 1499230054593 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49786
21:47:34 INFO - 1499230054595 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:47:34 INFO - 1499230054600 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:34 INFO - 1499230054622 Marionette DEBUG Register listener.js for window 12
21:47:34 INFO - 1499230054638 Marionette TRACE 358 <- [1,1,null,{"sessionId":"fe83906e-be14-40f0-833e-b7e0bd37ee5c","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:34 INFO - 1499230054646 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49330/clicks.html"}]
21:47:34 INFO - 1499230054652 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/hidden.html"
21:47:34 INFO - 1499230054672 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/hidden.html"
21:47:34 INFO - 1499230054672 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/hidden.html"
21:47:34 INFO - ++DOMWINDOW == 21 (000000211D18B800) [pid = 2760] [serial = 44] [outer = 000000212074D800]
21:47:34 INFO - 1499230054746 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/clicks.html"
21:47:34 INFO - 1499230054752 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/clicks.html"
21:47:34 INFO - 1499230054768 Marionette TRACE 358 <- [1,2,null,{}]
21:47:34 INFO - 1499230054774 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:47:34 INFO - 1499230054782 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f616b482-2671-4d91-aa6d-e371d7007d1e","ELEMENT":"f616b482-2671-4d91-aa6d-e371d7007d1e"}}]
21:47:35 INFO - 1499230055472 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"f616b482-2671-4d91-aa6d-e371d7007d1e"}]
21:47:35 INFO - 1499230055726 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/clicks.html"
21:47:35 INFO - [2760] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 445
21:47:35 INFO - 1499230055744 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/clicks.html"
21:47:35 INFO - 1499230055745 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/clicks.html"
21:47:35 INFO - ++DOMWINDOW == 22 (0000002115FB5800) [pid = 2760] [serial = 45] [outer = 000000212074D800]
21:47:35 INFO - 1499230055862 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/test.html"
21:47:35 INFO - 1499230055867 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/test.html"
21:47:35 INFO - 1499230055883 Marionette TRACE 358 <- [1,4,null,{}]
21:47:35 INFO - 1499230055939 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:47:35 INFO - 1499230055948 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cbae8c0c-7468-49dd-a610-3da177cdd27d","ELEMENT":"cbae8c0c-7468-49dd-a610-3da177cdd27d"}}]
21:47:35 INFO - 1499230055977 Marionette TRACE 358 -> [0,6,"getTitle",{}]
21:47:35 INFO - 1499230055979 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
21:47:36 INFO - 1499230055993 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}]
21:47:36 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499230056001 Marionette TRACE 358 <- [1,7,null,{"value":null}]
21:47:36 INFO - 1499230056005 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:36 INFO - 1499230056009 Marionette TRACE 358 <- [1,8,null,{}]
21:47:36 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1775ms
21:47:36 INFO - TEST-START | test_click.py TestClick.test_container_element
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:36 INFO - 1499230056030 Marionette DEBUG Closed connection 358
21:47:36 INFO - 1499230056031 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49789
21:47:36 INFO - 1499230056036 Marionette DEBUG Closed connection 359
21:47:36 INFO - 1499230056037 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49790
21:47:36 INFO - 1499230056040 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:36 INFO - 1499230056042 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:36 INFO - 1499230056064 Marionette DEBUG Register listener.js for window 12
21:47:36 INFO - 1499230056077 Marionette TRACE 360 <- [1,1,null,{"sessionId":"2bdba078-510d-4b0c-ada1-75bfc95a111c","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:36 INFO - 1499230056083 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
21:47:36 INFO - 1499230056085 Marionette TRACE 360 <- [1,2,null,{}]
21:47:36 INFO - 1499230056087 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:47:36 INFO - 1499230056089 Marionette TRACE 360 <- [1,3,null,{}]
21:47:36 INFO - 1499230056090 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
21:47:36 INFO - 1499230056092 Marionette TRACE 360 <- [1,4,null,{}]
21:47:36 INFO - 1499230056094 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}]
21:47:36 INFO - TEST-START: test_click.py TestClick.test_container_element1499230056104 Marionette TRACE 360 <- [1,5,null,{"value":null}]
21:47:36 INFO - 1499230056105 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:36 INFO - 1499230056110 Marionette TRACE 360 <- [1,6,null,{}]
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:36 INFO - 1499230056116 Marionette DEBUG Closed connection 360
21:47:36 INFO - 1499230056117 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49791
21:47:36 INFO - 1499230056119 Marionette DEBUG Closed connection 361
21:47:36 INFO - 1499230056120 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49792
21:47:36 INFO - 1499230056123 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:36 INFO - 1499230056125 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:36 INFO - 1499230056148 Marionette DEBUG Register listener.js for window 12
21:47:36 INFO - 1499230056159 Marionette TRACE 362 <- [1,1,null,{"sessionId":"6c9f5b25-c132-44f8-aa8e-f913f90bb038","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:36 INFO - 1499230056184 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:36 INFO - 1499230056188 Marionette TRACE 362 <- [1,2,null,{}]
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:36 INFO - 1499230056209 Marionette DEBUG Closed connection 362
21:47:36 INFO - 1499230056210 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49793
21:47:36 INFO - 1499230056215 Marionette DEBUG Closed connection 363
21:47:36 INFO - 1499230056217 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49794
21:47:36 INFO - 1499230056219 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:47:36 INFO - 1499230056222 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:36 INFO - 1499230056245 Marionette DEBUG Register listener.js for window 12
21:47:36 INFO - 1499230056257 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0219c7e8-d847-4c86-a174-6ba65faf4176","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:36 INFO - 1499230056263 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"}]
21:47:36 INFO - 1499230056267 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/test.html"
21:47:36 INFO - 1499230056276 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/test.html"
21:47:36 INFO - 1499230056277 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/test.html"
21:47:36 INFO - ++DOMWINDOW == 23 (0000002123888800) [pid = 2760] [serial = 46] [outer = 000000212074D800]
21:47:36 INFO - 1499230056336 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:36 INFO - 1499230056343 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:36 INFO - 1499230056359 Marionette TRACE 364 <- [1,2,null,{}]
21:47:36 INFO - 1499230056363 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:47:36 INFO - 1499230056370 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"06548768-d761-420c-ad8f-84c18e63029d","ELEMENT":"06548768-d761-420c-ad8f-84c18e63029d"}}]
21:47:36 INFO - 1499230056377 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"06548768-d761-420c-ad8f-84c18e63029d"}]
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
21:47:36 INFO - 1499230056804 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:47:36 INFO - 1499230056805 Marionette TRACE 364 <- [1,4,null,{}]
21:47:36 INFO - 1499230056808 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"06548768-d761-420c-ad8f-84c18e63029d","name":"selected"}]
21:47:36 INFO - 1499230056830 Marionette TRACE 364 <- [1,5,null,{"value":true}]
21:47:36 INFO - 1499230056832 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}]
21:47:36 INFO - TEST-END: test_click.py TestClick.test_container_element1499230056840 Marionette TRACE 364 <- [1,6,null,{"value":null}]
21:47:36 INFO - 1499230056842 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:36 INFO - 1499230056846 Marionette TRACE 364 <- [1,7,null,{}]
21:47:36 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 835ms
21:47:36 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:36 INFO - 1499230056852 Marionette DEBUG Closed connection 364
21:47:36 INFO - 1499230056853 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49795
21:47:36 INFO - 1499230056855 Marionette DEBUG Closed connection 365
21:47:36 INFO - 1499230056856 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49796
21:47:36 INFO - 1499230056858 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:36 INFO - 1499230056861 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:36 INFO - 1499230056883 Marionette DEBUG Register listener.js for window 12
21:47:36 INFO - 1499230056926 Marionette TRACE 366 <- [1,1,null,{"sessionId":"4b1a1da7-d103-41ec-a8aa-021c48ecf097","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:36 INFO - 1499230056932 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
21:47:36 INFO - 1499230056933 Marionette TRACE 366 <- [1,2,null,{}]
21:47:36 INFO - 1499230056935 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:47:36 INFO - 1499230056938 Marionette TRACE 366 <- [1,3,null,{}]
21:47:36 INFO - 1499230056940 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
21:47:36 INFO - 1499230056941 Marionette TRACE 366 <- [1,4,null,{}]
21:47:36 INFO - 1499230056944 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}]
21:47:36 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499230056952 Marionette TRACE 366 <- [1,5,null,{"value":null}]
21:47:36 INFO - 1499230056954 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:36 INFO - 1499230056958 Marionette TRACE 366 <- [1,6,null,{}]
21:47:36 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:36 INFO - 1499230056965 Marionette DEBUG Closed connection 366
21:47:36 INFO - 1499230056966 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49797
21:47:36 INFO - 1499230056968 Marionette DEBUG Closed connection 367
21:47:36 INFO - 1499230056970 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49798
21:47:36 INFO - 1499230056972 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:36 INFO - 1499230056976 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:37 INFO - 1499230057004 Marionette DEBUG Register listener.js for window 12
21:47:37 INFO - 1499230057019 Marionette TRACE 368 <- [1,1,null,{"sessionId":"40b50be8-a6d1-4e3e-ae68-6968d0bf240a","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:37 INFO - 1499230057022 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
21:47:37 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:37 INFO - 1499230057026 Marionette TRACE 368 <- [1,2,null,{}]
21:47:37 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:37 INFO - 1499230057031 Marionette DEBUG Closed connection 368
21:47:37 INFO - 1499230057033 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49799
21:47:37 INFO - 1499230057034 Marionette DEBUG Closed connection 369
21:47:37 INFO - 1499230057036 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49800
21:47:37 INFO - 1499230057038 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:47:37 INFO - 1499230057040 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:37 INFO - 1499230057063 Marionette DEBUG Register listener.js for window 12
21:47:37 INFO - 1499230057075 Marionette TRACE 370 <- [1,1,null,{"sessionId":"1aa5a52b-1418-4be7-9da8-b01ff3cb0891","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:37 INFO - 1499230057078 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"}]
21:47:37 INFO - 1499230057082 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:37 INFO - 1499230057093 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:37 INFO - 1499230057094 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:37 INFO - ++DOMWINDOW == 24 (0000002127CEA000) [pid = 2760] [serial = 47] [outer = 000000212074D800]
21:47:37 INFO - 1499230057153 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:37 INFO - 1499230057161 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:37 INFO - 1499230057178 Marionette TRACE 370 <- [1,2,null,{}]
21:47:37 INFO - 1499230057229 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:47:37 INFO - 1499230057236 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"61c45f0e-d36e-41d3-9d2c-acc73ffb3041","ELEMENT":"61c45f0e-d36e-41d3-9d2c-acc73ffb3041"}}]
21:47:37 INFO - 1499230057238 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"61c45f0e-d36e-41d3-9d2c-acc73ffb3041"}]
21:47:37 INFO - [2760] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
21:47:37 INFO - [2760] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
21:47:37 INFO - [2760] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
21:47:37 INFO - 1499230057658 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:47:37 INFO - 1499230057660 Marionette TRACE 370 <- [1,4,null,{}]
21:47:37 INFO - 1499230057664 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"61c45f0e-d36e-41d3-9d2c-acc73ffb3041","name":"selected"}]
21:47:37 INFO - 1499230057687 Marionette TRACE 370 <- [1,5,null,{"value":true}]
21:47:37 INFO - 1499230057696 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}]
21:47:37 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499230057703 Marionette TRACE 370 <- [1,6,null,{"value":null}]
21:47:37 INFO - 1499230057705 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
21:47:37 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:37 INFO - 1499230057710 Marionette TRACE 370 <- [1,7,null,{}]
21:47:37 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 863ms
21:47:37 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:47:37 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:37 INFO - 1499230057715 Marionette DEBUG Closed connection 370
21:47:37 INFO - 1499230057717 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49801
21:47:37 INFO - 1499230057718 Marionette DEBUG Closed connection 371
21:47:37 INFO - 1499230057720 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49802
21:47:37 INFO - 1499230057722 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:37 INFO - 1499230057724 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:37 INFO - 1499230057746 Marionette DEBUG Register listener.js for window 12
21:47:37 INFO - 1499230057758 Marionette TRACE 372 <- [1,1,null,{"sessionId":"5239b807-e4c8-46de-9d6e-e3856fea0979","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:37 INFO - 1499230057782 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
21:47:37 INFO - 1499230057784 Marionette TRACE 372 <- [1,2,null,{}]
21:47:37 INFO - 1499230057786 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:47:37 INFO - 1499230057788 Marionette TRACE 372 <- [1,3,null,{}]
21:47:37 INFO - 1499230057789 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
21:47:37 INFO - 1499230057791 Marionette TRACE 372 <- [1,4,null,{}]
21:47:37 INFO - 1499230057793 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}]
21:47:37 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499230057801 Marionette TRACE 372 <- [1,5,null,{"value":null}]
21:47:37 INFO - 1499230057803 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
21:47:37 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:37 INFO - 1499230057807 Marionette TRACE 372 <- [1,6,null,{}]
21:47:37 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:37 INFO - 1499230057813 Marionette DEBUG Closed connection 372
21:47:37 INFO - 1499230057815 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49803
21:47:37 INFO - 1499230057816 Marionette DEBUG Closed connection 373
21:47:37 INFO - 1499230057818 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49804
21:47:37 INFO - 1499230057820 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:37 INFO - 1499230057822 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:37 INFO - 1499230057846 Marionette DEBUG Register listener.js for window 12
21:47:37 INFO - 1499230057858 Marionette TRACE 374 <- [1,1,null,{"sessionId":"259df422-c317-4ff9-8bcb-03fa721fe816","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:37 INFO - 1499230057861 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
21:47:37 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:37 INFO - 1499230057865 Marionette TRACE 374 <- [1,2,null,{}]
21:47:37 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:37 INFO - 1499230057871 Marionette DEBUG Closed connection 374
21:47:37 INFO - 1499230057872 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49805
21:47:37 INFO - 1499230057874 Marionette DEBUG Closed connection 375
21:47:37 INFO - 1499230057875 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49806
21:47:37 INFO - 1499230057877 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:47:37 INFO - 1499230057879 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:37 INFO - 1499230057902 Marionette DEBUG Register listener.js for window 12
21:47:37 INFO - 1499230057913 Marionette TRACE 376 <- [1,1,null,{"sessionId":"92479d0d-95a5-4352-8e23-f29a0091cd09","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:37 INFO - 1499230057915 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"}]
21:47:37 INFO - 1499230057919 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:37 INFO - 1499230057925 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:37 INFO - 1499230057926 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:37 INFO - ++DOMWINDOW == 25 (000000212503C800) [pid = 2760] [serial = 48] [outer = 000000212074D800]
21:47:38 INFO - 1499230058023 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:47:38 INFO - 1499230058029 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:47:38 INFO - 1499230058046 Marionette TRACE 376 <- [1,2,null,{}]
21:47:38 INFO - 1499230058053 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:47:38 INFO - 1499230058060 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"90c93c9f-0b5d-4de4-865b-a2c1a72c8df4","ELEMENT":"90c93c9f-0b5d-4de4-865b-a2c1a72c8df4"}}]
21:47:38 INFO - 1499230058062 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"90c93c9f-0b5d-4de4-865b-a2c1a72c8df4"}]
21:47:39 INFO - 1499230059024 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:47:39 INFO - 1499230059027 Marionette TRACE 376 <- [1,4,null,{}]
21:47:39 INFO - 1499230059033 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}]
21:47:39 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499230059044 Marionette TRACE 376 <- [1,5,null,{"value":null}]
21:47:39 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1351ms
21:47:39 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:47:39 INFO - 1499230059057 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
21:47:39 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:39 INFO - 1499230059061 Marionette TRACE 376 <- [1,6,null,{}]
21:47:39 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:39 INFO - 1499230059067 Marionette DEBUG Closed connection 376
21:47:39 INFO - 1499230059069 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49807
21:47:39 INFO - 1499230059070 Marionette DEBUG Closed connection 377
21:47:39 INFO - 1499230059071 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49808
21:47:39 INFO - 1499230059073 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:39 INFO - 1499230059075 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:39 INFO - 1499230059098 Marionette DEBUG Register listener.js for window 12
21:47:39 INFO - 1499230059109 Marionette TRACE 378 <- [1,1,null,{"sessionId":"a79f97b4-7797-41ef-8718-3233cba84e35","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:39 INFO - 1499230059133 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
21:47:39 INFO - 1499230059134 Marionette TRACE 378 <- [1,2,null,{}]
21:47:39 INFO - 1499230059136 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:47:39 INFO - 1499230059138 Marionette TRACE 378 <- [1,3,null,{}]
21:47:39 INFO - 1499230059140 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
21:47:39 INFO - 1499230059141 Marionette TRACE 378 <- [1,4,null,{}]
21:47:39 INFO - 1499230059144 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}]
21:47:39 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499230059172 Marionette TRACE 378 <- [1,5,null,{"value":null}]
21:47:39 INFO - 1499230059179 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
21:47:39 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:39 INFO - 1499230059189 Marionette TRACE 378 <- [1,6,null,{}]
21:47:39 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:39 INFO - 1499230059215 Marionette DEBUG Closed connection 378
21:47:39 INFO - 1499230059219 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49809
21:47:39 INFO - 1499230059273 Marionette DEBUG Closed connection 379
21:47:39 INFO - 1499230059276 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49810
21:47:39 INFO - 1499230059283 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:39 INFO - 1499230059293 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:39 INFO - 1499230059342 Marionette DEBUG Register listener.js for window 12
21:47:39 INFO - 1499230059367 Marionette TRACE 380 <- [1,1,null,{"sessionId":"cf70c5ef-6772-4c9f-9f05-b83c587addfa","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:39 INFO - 1499230059378 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
21:47:39 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:39 INFO - 1499230059385 Marionette TRACE 380 <- [1,2,null,{}]
21:47:39 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:39 INFO - 1499230059445 Marionette DEBUG Closed connection 380
21:47:39 INFO - 1499230059450 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49811
21:47:39 INFO - 1499230059470 Marionette DEBUG Closed connection 381
21:47:39 INFO - 1499230059473 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49812
21:47:39 INFO - 1499230059477 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:47:39 INFO - 1499230059482 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:39 INFO - 1499230059529 Marionette DEBUG Register listener.js for window 12
21:47:39 INFO - 1499230059552 Marionette TRACE 382 <- [1,1,null,{"sessionId":"0a92033a-4a32-4c4c-a9ec-75b508c01e0b","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:39 INFO - 1499230059576 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"}]
21:47:39 INFO - 1499230059584 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:47:39 INFO - 1499230059595 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:47:39 INFO - 1499230059596 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:47:39 INFO - ++DOMWINDOW == 26 (000000211AF40800) [pid = 2760] [serial = 49] [outer = 000000212074D800]
21:47:39 INFO - 1499230059660 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:39 INFO - 1499230059677 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:39 INFO - 1499230059700 Marionette TRACE 382 <- [1,2,null,{}]
21:47:39 INFO - 1499230059710 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:47:39 INFO - 1499230059722 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f19c5a99-bee0-4a6d-964b-991f632cb4f7","ELEMENT":"f19c5a99-bee0-4a6d-964b-991f632cb4f7"}}]
21:47:39 INFO - 1499230059727 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"f19c5a99-bee0-4a6d-964b-991f632cb4f7"}]
21:47:40 INFO - 1499230060278 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:47:40 INFO - 1499230060280 Marionette TRACE 382 <- [1,4,null,{}]
21:47:40 INFO - 1499230060284 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}]
21:47:40 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499230060296 Marionette TRACE 382 <- [1,5,null,{"value":null}]
21:47:40 INFO - 1499230060334 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
21:47:40 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:40 INFO - 1499230060341 Marionette TRACE 382 <- [1,6,null,{}]
21:47:40 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1280ms
21:47:40 INFO - TEST-START | test_click.py TestClick.test_input_file
21:47:40 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:40 INFO - 1499230060366 Marionette DEBUG Closed connection 382
21:47:40 INFO - 1499230060368 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49813
21:47:40 INFO - 1499230060373 Marionette DEBUG Closed connection 383
21:47:40 INFO - 1499230060376 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49814
21:47:40 INFO - 1499230060380 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:40 INFO - 1499230060384 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:40 INFO - 1499230060432 Marionette DEBUG Register listener.js for window 12
21:47:40 INFO - 1499230060450 Marionette TRACE 384 <- [1,1,null,{"sessionId":"2fbe4df3-4259-4685-ab24-dd714a006cfd","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:40 INFO - 1499230060464 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
21:47:40 INFO - 1499230060469 Marionette TRACE 384 <- [1,2,null,{}]
21:47:40 INFO - 1499230060471 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:47:40 INFO - 1499230060473 Marionette TRACE 384 <- [1,3,null,{}]
21:47:40 INFO - 1499230060476 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
21:47:40 INFO - 1499230060478 Marionette TRACE 384 <- [1,4,null,{}]
21:47:40 INFO - 1499230060481 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}]
21:47:40 INFO - TEST-START: test_click.py TestClick.test_input_file1499230060492 Marionette TRACE 384 <- [1,5,null,{"value":null}]
21:47:40 INFO - 1499230060500 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
21:47:40 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:40 INFO - 1499230060505 Marionette TRACE 384 <- [1,6,null,{}]
21:47:40 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:40 INFO - 1499230060527 Marionette DEBUG Closed connection 384
21:47:40 INFO - 1499230060529 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49815
21:47:40 INFO - 1499230060531 Marionette DEBUG Closed connection 385
21:47:40 INFO - 1499230060534 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49816
21:47:40 INFO - 1499230060537 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:40 INFO - 1499230060540 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:40 INFO - 1499230060588 Marionette DEBUG Register listener.js for window 12
21:47:40 INFO - 1499230060641 Marionette TRACE 386 <- [1,1,null,{"sessionId":"ac766b9c-c581-4e00-85e7-cf92dd1a3bdc","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:40 INFO - 1499230060653 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
21:47:40 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:40 INFO - 1499230060659 Marionette TRACE 386 <- [1,2,null,{}]
21:47:40 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:40 INFO - 1499230060688 Marionette DEBUG Closed connection 386
21:47:40 INFO - 1499230060690 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49817
21:47:40 INFO - 1499230060711 Marionette DEBUG Closed connection 387
21:47:40 INFO - 1499230060713 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49818
21:47:40 INFO - 1499230060716 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:47:40 INFO - 1499230060719 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:40 INFO - 1499230060767 Marionette DEBUG Register listener.js for window 12
21:47:40 INFO - 1499230060787 Marionette TRACE 388 <- [1,1,null,{"sessionId":"a9c87d8d-5265-4cfc-94db-a63dec2f6296","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:40 INFO - 1499230060801 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:47:40 INFO - 1499230060808 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:40 INFO - 1499230060817 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:40 INFO - 1499230060819 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:47:40 INFO - ++DOMWINDOW == 27 (000000211ED6B800) [pid = 2760] [serial = 50] [outer = 000000212074D800]
21:47:40 INFO - 1499230060889 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:47:40 INFO - 1499230060895 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:47:40 INFO - 1499230060916 Marionette TRACE 388 <- [1,2,null,{}]
21:47:40 INFO - 1499230060926 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:47:40 INFO - 1499230060936 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8d2088fd-42d5-4ed9-b487-2398d9a7f0cd","ELEMENT":"8d2088fd-42d5-4ed9-b487-2398d9a7f0cd"}}]
21:47:40 INFO - 1499230060938 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"8d2088fd-42d5-4ed9-b487-2398d9a7f0cd"}]
21:47:41 INFO - 1499230061187 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
21:47:41 INFO - 1499230061202 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}]
21:47:41 INFO - TEST-END: test_click.py TestClick.test_input_file1499230061213 Marionette TRACE 388 <- [1,5,null,{"value":null}]
21:47:41 INFO - 1499230061216 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
21:47:41 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:41 INFO - 1499230061222 Marionette TRACE 388 <- [1,6,null,{}]
21:47:41 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 880ms
21:47:41 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:47:41 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:41 INFO - 1499230061411 Marionette DEBUG Closed connection 388
21:47:41 INFO - 1499230061415 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49819
21:47:41 INFO - 1499230061437 Marionette DEBUG Closed connection 389
21:47:41 INFO - 1499230061440 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49820
21:47:41 INFO - 1499230061445 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:41 INFO - 1499230061455 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:41 INFO - 1499230061483 Marionette DEBUG Register listener.js for window 12
21:47:41 INFO - 1499230061505 Marionette TRACE 390 <- [1,1,null,{"sessionId":"bc8506d7-f193-4519-91b3-08f77ce86137","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:41 INFO - 1499230061542 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
21:47:41 INFO - 1499230061546 Marionette TRACE 390 <- [1,2,null,{}]
21:47:41 INFO - 1499230061552 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:47:41 INFO - 1499230061556 Marionette TRACE 390 <- [1,3,null,{}]
21:47:41 INFO - 1499230061561 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
21:47:41 INFO - 1499230061565 Marionette TRACE 390 <- [1,4,null,{}]
21:47:41 INFO - 1499230061576 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}]
21:47:41 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499230061588 Marionette TRACE 390 <- [1,5,null,{"value":null}]
21:47:41 INFO - 1499230061591 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
21:47:41 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:41 INFO - 1499230061598 Marionette TRACE 390 <- [1,6,null,{}]
21:47:41 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:41 INFO - 1499230061605 Marionette DEBUG Closed connection 390
21:47:41 INFO - 1499230061608 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49821
21:47:41 INFO - 1499230061650 Marionette DEBUG Closed connection 391
21:47:41 INFO - 1499230061652 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49822
21:47:41 INFO - 1499230061658 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:41 INFO - 1499230061662 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:41 INFO - 1499230061687 Marionette DEBUG Register listener.js for window 12
21:47:41 INFO - 1499230061707 Marionette TRACE 392 <- [1,1,null,{"sessionId":"f639121c-e8f8-450c-92f7-8ff0d9ba54bd","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:41 INFO - 1499230061711 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
21:47:41 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:41 INFO - 1499230061716 Marionette TRACE 392 <- [1,2,null,{}]
21:47:41 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:41 INFO - 1499230061724 Marionette DEBUG Closed connection 392
21:47:41 INFO - 1499230061726 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49823
21:47:41 INFO - 1499230061728 Marionette DEBUG Closed connection 393
21:47:41 INFO - 1499230061730 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49824
21:47:41 INFO - 1499230061733 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:47:41 INFO - 1499230061736 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:41 INFO - 1499230061760 Marionette DEBUG Register listener.js for window 12
21:47:41 INFO - 1499230061776 Marionette TRACE 394 <- [1,1,null,{"sessionId":"81d651a1-6508-466d-9d69-0282dea52d82","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:41 INFO - 1499230061845 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"}]
21:47:41 INFO - 1499230061854 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:47:41 INFO - 1499230061863 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:47:41 INFO - 1499230061864 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:47:41 INFO - ++DOMWINDOW == 28 (000000211526E800) [pid = 2760] [serial = 51] [outer = 000000212074D800]
21:47:41 INFO - 1499230061928 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:47:41 INFO - 1499230061935 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:47:41 INFO - 1499230061952 Marionette TRACE 394 <- [1,2,null,{}]
21:47:41 INFO - 1499230061966 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:47:41 INFO - 1499230061979 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"718da171-1b5f-4950-b6c2-4390e9c26cda","ELEMENT":"718da171-1b5f-4950-b6c2-4390e9c26cda"}}]
21:47:41 INFO - 1499230061981 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:47:41 INFO - 1499230061990 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6facda29-47cf-4edc-8d64-a15c3891bb43","ELEMENT":"6facda29-47cf-4edc-8d64-a15c3891bb43"}}]
21:47:41 INFO - 1499230061993 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"718da171-1b5f-4950-b6c2-4390e9c26cda"}]
21:47:42 INFO - --DOMWINDOW == 27 (0000002120FAF800) [pid = 2760] [serial = 37] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20horizontally%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
21:47:42 INFO - --DOMWINDOW == 26 (000000211A265000) [pid = 2760] [serial = 36] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
21:47:42 INFO - --DOMWINDOW == 25 (0000002129918800) [pid = 2760] [serial = 35] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
21:47:42 INFO - --DOMWINDOW == 24 (000000212510D800) [pid = 2760] [serial = 38] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20vertically%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
21:47:42 INFO - --DOMWINDOW == 23 (0000002125984000) [pid = 2760] [serial = 39] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%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.clicks%20%3D%200%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.clicks%2B%2B%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20]
21:47:42 INFO - 1499230062406 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:47:42 INFO - 1499230062408 Marionette TRACE 394 <- [1,5,null,{}]
21:47:42 INFO - 1499230062412 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"6facda29-47cf-4edc-8d64-a15c3891bb43"}]
21:47:42 INFO - 1499230062521 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:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
21:47:42 INFO - 1499230062524 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:47:42 INFO - 1499230062533 Marionette TRACE 394 <- [1,7,null,{"value":false}]
21:47:42 INFO - 1499230062537 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}]
21:47:42 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499230062547 Marionette TRACE 394 <- [1,8,null,{"value":null}]
21:47:42 INFO - 1499230062549 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
21:47:42 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:42 INFO - 1499230062553 Marionette TRACE 394 <- [1,9,null,{}]
21:47:42 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1332ms
21:47:42 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:47:42 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:42 INFO - 1499230062560 Marionette DEBUG Closed connection 394
21:47:42 INFO - 1499230062562 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49825
21:47:42 INFO - 1499230062564 Marionette DEBUG Closed connection 395
21:47:42 INFO - 1499230062566 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49826
21:47:42 INFO - 1499230062568 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:42 INFO - 1499230062571 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:42 INFO - 1499230062596 Marionette DEBUG Register listener.js for window 12
21:47:42 INFO - 1499230062611 Marionette TRACE 396 <- [1,1,null,{"sessionId":"2def1b70-1993-4f28-982d-9a683a120a2a","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:42 INFO - 1499230062616 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
21:47:42 INFO - 1499230062618 Marionette TRACE 396 <- [1,2,null,{}]
21:47:42 INFO - 1499230062668 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:47:42 INFO - 1499230062671 Marionette TRACE 396 <- [1,3,null,{}]
21:47:42 INFO - 1499230062676 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
21:47:42 INFO - 1499230062677 Marionette TRACE 396 <- [1,4,null,{}]
21:47:42 INFO - 1499230062680 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}]
21:47:42 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499230062688 Marionette TRACE 396 <- [1,5,null,{"value":null}]
21:47:42 INFO - 1499230062690 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
21:47:42 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:42 INFO - 1499230062695 Marionette TRACE 396 <- [1,6,null,{}]
21:47:42 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:42 INFO - 1499230062701 Marionette DEBUG Closed connection 396
21:47:42 INFO - 1499230062702 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49827
21:47:42 INFO - 1499230062704 Marionette DEBUG Closed connection 397
21:47:42 INFO - 1499230062706 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49828
21:47:42 INFO - 1499230062708 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:47:42 INFO - 1499230062711 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:42 INFO - 1499230062735 Marionette DEBUG Register listener.js for window 12
21:47:42 INFO - 1499230062752 Marionette TRACE 398 <- [1,1,null,{"sessionId":"3cb51f8e-aee1-4535-b596-9a63ca9cfcf3","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:43 INFO - 1499230063554 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
21:47:43 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:43 INFO - 1499230063559 Marionette TRACE 398 <- [1,2,null,{}]
21:47:43 INFO - [2760] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:47:43 INFO - 1499230063593 Marionette DEBUG Closed connection 398
21:47:43 INFO - 1499230063604 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49829
21:47:43 INFO - 1499230063658 Marionette DEBUG Closed connection 399
21:47:43 INFO - 1499230063660 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49830
21:47:43 INFO - 1499230063719 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:47:43 INFO - 1499230063723 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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}
21:47:43 INFO - 1499230063747 Marionette DEBUG Register listener.js for window 12
21:47:43 INFO - 1499230063761 Marionette TRACE 400 <- [1,1,null,{"sessionId":"1c1b2f5e-fc19-4f91-9bdc-2f33c3b65fc0","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":2760,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpplvphh.mozrunner","moz:accessibilityChecks":false}}]
21:47:43 INFO - 1499230063824 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"}]
21:47:43 INFO - 1499230063828 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:47:43 INFO - 1499230063839 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:47:43 INFO - 1499230063840 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:47:43 INFO - ++DOMWINDOW == 24 (000000212588D000) [pid = 2760] [serial = 52] [outer = 000000212074D800]
21:47:43 INFO - 1499230063952 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
21:47:43 INFO - 1499230063957 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
21:47:43 INFO - 1499230063979 Marionette TRACE 400 <- [1,2,null,{}]
21:47:44 INFO - 1499230063991 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:47:44 INFO - 1499230064003 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b54ff135-916c-4a8a-8f50-887801f491e8","ELEMENT":"b54ff135-916c-4a8a-8f50-887801f491e8"}}]
21:47:44 INFO - 1499230064005 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"b54ff135-916c-4a8a-8f50-887801f491e8"}]
21:47:44 INFO - 1499230064155 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
21:47:44 INFO - 1499230064157 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"b54ff135-916c-4a8a-8f50-887801f491e8"}]
21:47:44 INFO - 1499230064277 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element