could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
03:48:21 INFO - 1500806901589 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}]
03:48:21 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500806901597 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:48:21 INFO - 1500806901599 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:48:21 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:21 INFO - 1500806901604 Marionette TRACE 352 <- [1,6,null,{}]
03:48:21 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 851ms
03:48:21 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:48:21 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:21 INFO - 1500806901608 Marionette DEBUG Closed connection 352
03:48:21 INFO - 1500806901610 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49568
03:48:21 INFO - 1500806901611 Marionette DEBUG Closed connection 353
03:48:21 INFO - 1500806901613 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49569
03:48:21 INFO - 1500806901615 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:21 INFO - 1500806901617 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:21 INFO - 1500806901638 Marionette DEBUG Register listener.js for window 4294967297
03:48:21 INFO - 1500806901650 Marionette TRACE 354 <- [1,1,null,{"sessionId":"3acfd4a7-5722-42c7-897f-5c7a70e51a94","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:21 INFO - 1500806901653 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:48:21 INFO - 1500806901654 Marionette TRACE 354 <- [1,2,null,{}]
03:48:21 INFO - 1500806901656 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:48:21 INFO - 1500806901658 Marionette TRACE 354 <- [1,3,null,{}]
03:48:21 INFO - 1500806901660 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:48:21 INFO - 1500806901662 Marionette TRACE 354 <- [1,4,null,{}]
03:48:21 INFO - 1500806901665 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}]
03:48:21 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500806901672 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:48:21 INFO - 1500806901674 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:48:21 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:21 INFO - 1500806901678 Marionette TRACE 354 <- [1,6,null,{}]
03:48:21 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:21 INFO - 1500806901682 Marionette DEBUG Closed connection 354
03:48:21 INFO - 1500806901686 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49570
03:48:21 INFO - 1500806901690 Marionette DEBUG Closed connection 355
03:48:21 INFO - 1500806901692 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49571
03:48:21 INFO - 1500806901694 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:21 INFO - 1500806901698 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:21 INFO - 1500806901723 Marionette DEBUG Register listener.js for window 4294967297
03:48:21 INFO - 1500806901737 Marionette TRACE 356 <- [1,1,null,{"sessionId":"ddc5d299-3dc8-4cff-8a5b-3dac54de873d","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:21 INFO - 1500806901740 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:48:21 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:21 INFO - 1500806901745 Marionette TRACE 356 <- [1,2,null,{}]
03:48:21 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:21 INFO - 1500806901749 Marionette DEBUG Closed connection 356
03:48:21 INFO - 1500806901751 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49572
03:48:21 INFO - 1500806901753 Marionette DEBUG Closed connection 357
03:48:21 INFO - 1500806901755 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49573
03:48:21 INFO - 1500806901757 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:48:21 INFO - 1500806901760 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:21 INFO - 1500806901781 Marionette DEBUG Register listener.js for window 4294967297
03:48:21 INFO - 1500806901792 Marionette TRACE 358 <- [1,1,null,{"sessionId":"5d7dc13c-f30f-42e3-b8c6-09f6673c90d0","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:21 INFO - 1500806901795 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:65481/clicks.html"}]
03:48:21 INFO - 1500806901800 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:65481/hidden.html"
03:48:21 INFO - 1500806901819 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:65481/hidden.html"
03:48:21 INFO - 1500806901820 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:65481/hidden.html"
03:48:21 INFO - ++DOMWINDOW == 15 (0000004BDE25D800) [pid = 1568] [serial = 25] [outer = 0000004BD254F800]
03:48:21 INFO - 1500806901852 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:65481/clicks.html"
03:48:21 INFO - 1500806901856 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:65481/clicks.html"
03:48:21 INFO - 1500806901864 Marionette TRACE 358 <- [1,2,null,{}]
03:48:21 INFO - 1500806901884 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:48:21 INFO - 1500806901891 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"286c376d-be49-48ff-b244-5b2a1431f76e","ELEMENT":"286c376d-be49-48ff-b244-5b2a1431f76e"}}]
03:48:21 INFO - 1500806901894 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"286c376d-be49-48ff-b244-5b2a1431f76e"}]
03:48:22 INFO - 1500806902112 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:65481/clicks.html"
03:48:22 INFO - 1500806902135 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:65481/clicks.html"
03:48:22 INFO - 1500806902136 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:65481/clicks.html"
03:48:22 INFO - ++DOMWINDOW == 16 (0000004BE1059000) [pid = 1568] [serial = 26] [outer = 0000004BD254F800]
03:48:22 INFO - 1500806902173 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:65481/test.html"
03:48:22 INFO - 1500806902177 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:65481/test.html"
03:48:22 INFO - 1500806902191 Marionette TRACE 358 <- [1,4,null,{}]
03:48:22 INFO - 1500806902207 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:48:22 INFO - 1500806902214 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f88f8498-eb8f-4de9-b042-c53b9cb00550","ELEMENT":"f88f8498-eb8f-4de9-b042-c53b9cb00550"}}]
03:48:22 INFO - 1500806902216 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:48:22 INFO - 1500806902217 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:48:22 INFO - 1500806902220 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}]
03:48:22 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500806902228 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:48:22 INFO - 1500806902230 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:48:22 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 629ms
03:48:22 INFO - TEST-START | test_click.py TestClick.test_container_element
03:48:22 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:22 INFO - 1500806902234 Marionette TRACE 358 <- [1,8,null,{}]
03:48:22 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:22 INFO - 1500806902238 Marionette DEBUG Closed connection 358
03:48:22 INFO - 1500806902240 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49576
03:48:22 INFO - 1500806902241 Marionette DEBUG Closed connection 359
03:48:22 INFO - 1500806902243 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49577
03:48:22 INFO - 1500806902245 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:22 INFO - 1500806902247 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:22 INFO - 1500806902268 Marionette DEBUG Register listener.js for window 4294967297
03:48:22 INFO - 1500806902290 Marionette TRACE 360 <- [1,1,null,{"sessionId":"5d513f9a-bdfe-4433-a597-9db2246c495e","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:22 INFO - 1500806902293 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:48:22 INFO - 1500806902295 Marionette TRACE 360 <- [1,2,null,{}]
03:48:22 INFO - 1500806902297 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:48:22 INFO - 1500806902299 Marionette TRACE 360 <- [1,3,null,{}]
03:48:22 INFO - 1500806902300 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:48:22 INFO - 1500806902302 Marionette TRACE 360 <- [1,4,null,{}]
03:48:22 INFO - 1500806902305 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}]
03:48:22 INFO - TEST-START: test_click.py TestClick.test_container_element1500806902314 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:48:22 INFO - 1500806902316 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:48:22 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:22 INFO - 1500806902321 Marionette TRACE 360 <- [1,6,null,{}]
03:48:22 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:22 INFO - 1500806902325 Marionette DEBUG Closed connection 360
03:48:22 INFO - 1500806902326 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49578
03:48:22 INFO - 1500806902328 Marionette DEBUG Closed connection 361
03:48:22 INFO - 1500806902330 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49579
03:48:22 INFO - 1500806902332 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:22 INFO - 1500806902334 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:22 INFO - 1500806902355 Marionette DEBUG Register listener.js for window 4294967297
03:48:22 INFO - 1500806902366 Marionette TRACE 362 <- [1,1,null,{"sessionId":"fcc4763a-7d0d-452f-b68f-3e1999bd7a88","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:22 INFO - 1500806902369 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:48:22 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:22 INFO - 1500806902373 Marionette TRACE 362 <- [1,2,null,{}]
03:48:22 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:22 INFO - 1500806902377 Marionette DEBUG Closed connection 362
03:48:22 INFO - 1500806902378 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49580
03:48:22 INFO - 1500806902380 Marionette DEBUG Closed connection 363
03:48:22 INFO - 1500806902381 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49581
03:48:22 INFO - 1500806902384 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:48:22 INFO - 1500806902386 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:22 INFO - 1500806902407 Marionette DEBUG Register listener.js for window 4294967297
03:48:22 INFO - 1500806902418 Marionette TRACE 364 <- [1,1,null,{"sessionId":"af68e306-3559-4bff-9329-e3343142d97b","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:22 INFO - 1500806902421 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"}]
03:48:22 INFO - 1500806902425 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:65481/test.html"
03:48:22 INFO - 1500806902430 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:65481/test.html"
03:48:22 INFO - 1500806902430 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:65481/test.html"
03:48:22 INFO - ++DOMWINDOW == 17 (0000004BE1069800) [pid = 1568] [serial = 27] [outer = 0000004BD254F800]
03:48:22 INFO - 1500806902453 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"
03:48:22 INFO - 1500806902461 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"
03:48:22 INFO - 1500806902475 Marionette TRACE 364 <- [1,2,null,{}]
03:48:22 INFO - 1500806902490 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:48:22 INFO - 1500806902497 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7527767c-f56d-4e7f-8d3c-b6dadda1dcbb","ELEMENT":"7527767c-f56d-4e7f-8d3c-b6dadda1dcbb"}}]
03:48:22 INFO - 1500806902500 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"7527767c-f56d-4e7f-8d3c-b6dadda1dcbb"}]
03:48:22 INFO - [Child 1568] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
03:48:22 INFO - [Child 1568] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
03:48:22 INFO - [Child 1568] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
03:48:22 INFO - 1500806902944 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:48:22 INFO - 1500806902946 Marionette TRACE 364 <- [1,4,null,{}]
03:48:22 INFO - 1500806902948 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"7527767c-f56d-4e7f-8d3c-b6dadda1dcbb","name":"selected"}]
03:48:22 INFO - 1500806902975 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:48:22 INFO - 1500806902977 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}]
03:48:22 INFO - TEST-END: test_click.py TestClick.test_container_element1500806902985 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:48:22 INFO - 1500806902987 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:48:22 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:22 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 758ms
03:48:22 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:48:22 INFO - 1500806902991 Marionette TRACE 364 <- [1,7,null,{}]
03:48:22 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:22 INFO - 1500806902995 Marionette DEBUG Closed connection 364
03:48:23 INFO - 1500806902997 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49582
03:48:23 INFO - 1500806902998 Marionette DEBUG Closed connection 365
03:48:23 INFO - 1500806903000 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49583
03:48:23 INFO - 1500806903002 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:23 INFO - 1500806903004 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:23 INFO - 1500806903025 Marionette DEBUG Register listener.js for window 4294967297
03:48:23 INFO - 1500806903037 Marionette TRACE 366 <- [1,1,null,{"sessionId":"9abe6a53-2ee3-4287-a6be-a51e2bd3440c","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:23 INFO - 1500806903040 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:48:23 INFO - 1500806903041 Marionette TRACE 366 <- [1,2,null,{}]
03:48:23 INFO - 1500806903043 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:48:23 INFO - 1500806903045 Marionette TRACE 366 <- [1,3,null,{}]
03:48:23 INFO - 1500806903046 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:48:23 INFO - 1500806903048 Marionette TRACE 366 <- [1,4,null,{}]
03:48:23 INFO - 1500806903051 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}]
03:48:23 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500806903064 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:48:23 INFO - 1500806903066 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:48:23 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:23 INFO - 1500806903070 Marionette TRACE 366 <- [1,6,null,{}]
03:48:23 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:23 INFO - 1500806903074 Marionette DEBUG Closed connection 366
03:48:23 INFO - 1500806903076 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49584
03:48:23 INFO - 1500806903077 Marionette DEBUG Closed connection 367
03:48:23 INFO - 1500806903079 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49585
03:48:23 INFO - 1500806903081 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:23 INFO - 1500806903083 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:23 INFO - 1500806903104 Marionette DEBUG Register listener.js for window 4294967297
03:48:23 INFO - 1500806903115 Marionette TRACE 368 <- [1,1,null,{"sessionId":"3d705590-cba2-4954-a778-e522ecd18741","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:23 INFO - 1500806903118 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:48:23 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:23 INFO - 1500806903122 Marionette TRACE 368 <- [1,2,null,{}]
03:48:23 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:23 INFO - 1500806903126 Marionette DEBUG Closed connection 368
03:48:23 INFO - 1500806903127 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49586
03:48:23 INFO - 1500806903129 Marionette DEBUG Closed connection 369
03:48:23 INFO - 1500806903130 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49587
03:48:23 INFO - 1500806903133 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:48:23 INFO - 1500806903135 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:23 INFO - 1500806903156 Marionette DEBUG Register listener.js for window 4294967297
03:48:23 INFO - 1500806903170 Marionette TRACE 370 <- [1,1,null,{"sessionId":"5bcba86b-9735-4a79-8031-115a92a4624a","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:23 INFO - 1500806903176 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"}]
03:48:23 INFO - 1500806903189 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"
03:48:23 INFO - 1500806903193 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"
03:48:23 INFO - 1500806903194 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"
03:48:23 INFO - ++DOMWINDOW == 18 (0000004BDE263000) [pid = 1568] [serial = 28] [outer = 0000004BD254F800]
03:48:23 INFO - 1500806903219 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"
03:48:23 INFO - 1500806903223 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"
03:48:23 INFO - 1500806903237 Marionette TRACE 370 <- [1,2,null,{}]
03:48:23 INFO - 1500806903253 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:48:23 INFO - 1500806903260 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bbfbdf92-7494-4bb3-9324-9b31a34ae408","ELEMENT":"bbfbdf92-7494-4bb3-9324-9b31a34ae408"}}]
03:48:23 INFO - 1500806903262 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"bbfbdf92-7494-4bb3-9324-9b31a34ae408"}]
03:48:23 INFO - [Child 1568] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
03:48:23 INFO - [Child 1568] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
03:48:23 INFO - [Child 1568] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
03:48:23 INFO - 1500806903686 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:48:23 INFO - 1500806903688 Marionette TRACE 370 <- [1,4,null,{}]
03:48:23 INFO - 1500806903691 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"bbfbdf92-7494-4bb3-9324-9b31a34ae408","name":"selected"}]
03:48:23 INFO - 1500806903717 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:48:23 INFO - 1500806903720 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}]
03:48:23 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500806903727 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:48:23 INFO - 1500806903729 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:48:23 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:23 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 742ms
03:48:23 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:48:23 INFO - 1500806903733 Marionette TRACE 370 <- [1,7,null,{}]
03:48:23 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:23 INFO - 1500806903737 Marionette DEBUG Closed connection 370
03:48:23 INFO - 1500806903739 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49588
03:48:23 INFO - 1500806903741 Marionette DEBUG Closed connection 371
03:48:23 INFO - 1500806903742 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49589
03:48:23 INFO - 1500806903744 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:23 INFO - 1500806903749 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:24 INFO - 1500806904196 Marionette DEBUG Register listener.js for window 4294967297
03:48:24 INFO - 1500806904207 Marionette TRACE 372 <- [1,1,null,{"sessionId":"c5177e17-e643-4119-b284-e11b7a671ab7","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:24 INFO - 1500806904210 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:48:24 INFO - 1500806904212 Marionette TRACE 372 <- [1,2,null,{}]
03:48:24 INFO - 1500806904214 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:48:24 INFO - 1500806904215 Marionette TRACE 372 <- [1,3,null,{}]
03:48:24 INFO - 1500806904219 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:48:24 INFO - 1500806904220 Marionette TRACE 372 <- [1,4,null,{}]
03:48:24 INFO - 1500806904223 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}]
03:48:24 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500806904273 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:48:24 INFO - 1500806904275 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:48:24 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:24 INFO - 1500806904280 Marionette TRACE 372 <- [1,6,null,{}]
03:48:24 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:24 INFO - 1500806904284 Marionette DEBUG Closed connection 372
03:48:24 INFO - 1500806904286 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49590
03:48:24 INFO - 1500806904287 Marionette DEBUG Closed connection 373
03:48:24 INFO - 1500806904289 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49591
03:48:24 INFO - 1500806904291 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:24 INFO - 1500806904293 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:24 INFO - 1500806904329 Marionette DEBUG Register listener.js for window 4294967297
03:48:24 INFO - 1500806904340 Marionette TRACE 374 <- [1,1,null,{"sessionId":"e78fea4b-76e9-4f59-a8ed-f4ddb7e99ab6","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:24 INFO - 1500806904343 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:48:24 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:24 INFO - 1500806904347 Marionette TRACE 374 <- [1,2,null,{}]
03:48:24 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:24 INFO - 1500806904352 Marionette DEBUG Closed connection 374
03:48:24 INFO - 1500806904354 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49592
03:48:24 INFO - 1500806904355 Marionette DEBUG Closed connection 375
03:48:24 INFO - 1500806904357 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49593
03:48:24 INFO - 1500806904359 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:48:24 INFO - 1500806904362 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:24 INFO - 1500806904397 Marionette DEBUG Register listener.js for window 4294967297
03:48:24 INFO - 1500806904408 Marionette TRACE 376 <- [1,1,null,{"sessionId":"9877dd8e-ec36-4aa5-9dbd-9419e50575bd","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:24 INFO - 1500806904411 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"}]
03:48:24 INFO - 1500806904457 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"
03:48:24 INFO - 1500806904462 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"
03:48:24 INFO - 1500806904463 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"
03:48:24 INFO - ++DOMWINDOW == 19 (0000004BDE28E000) [pid = 1568] [serial = 29] [outer = 0000004BD254F800]
03:48:24 INFO - 1500806904485 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"
03:48:24 INFO - 1500806904490 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"
03:48:24 INFO - 1500806904507 Marionette TRACE 376 <- [1,2,null,{}]
03:48:24 INFO - 1500806904525 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:48:24 INFO - 1500806904542 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9b333fb3-6a07-4d41-8969-2209e0c0055d","ELEMENT":"9b333fb3-6a07-4d41-8969-2209e0c0055d"}}]
03:48:24 INFO - 1500806904544 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"9b333fb3-6a07-4d41-8969-2209e0c0055d"}]
03:48:25 INFO - 1500806905021 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:48:25 INFO - 1500806905023 Marionette TRACE 376 <- [1,4,null,{}]
03:48:25 INFO - 1500806905026 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}]
03:48:25 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500806905036 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:48:25 INFO - 1500806905038 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:48:25 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:25 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1309ms
03:48:25 INFO - 1500806905043 Marionette TRACE 376 <- [1,6,null,{}]
03:48:25 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:48:25 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:25 INFO - 1500806905048 Marionette DEBUG Closed connection 376
03:48:25 INFO - 1500806905050 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49594
03:48:25 INFO - 1500806905052 Marionette DEBUG Closed connection 377
03:48:25 INFO - 1500806905053 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49595
03:48:25 INFO - 1500806905056 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:25 INFO - 1500806905058 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:25 INFO - 1500806905079 Marionette DEBUG Register listener.js for window 4294967297
03:48:25 INFO - 1500806905090 Marionette TRACE 378 <- [1,1,null,{"sessionId":"c4b26b12-6b03-458f-b579-2b70c2f3d639","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:25 INFO - 1500806905093 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:48:25 INFO - 1500806905095 Marionette TRACE 378 <- [1,2,null,{}]
03:48:25 INFO - 1500806905096 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:48:25 INFO - 1500806905098 Marionette TRACE 378 <- [1,3,null,{}]
03:48:25 INFO - 1500806905100 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:48:25 INFO - 1500806905101 Marionette TRACE 378 <- [1,4,null,{}]
03:48:25 INFO - 1500806905104 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}]
03:48:25 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500806905111 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:48:25 INFO - 1500806905113 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:48:25 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:25 INFO - 1500806905117 Marionette TRACE 378 <- [1,6,null,{}]
03:48:25 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:25 INFO - 1500806905121 Marionette DEBUG Closed connection 378
03:48:25 INFO - 1500806905123 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49596
03:48:25 INFO - 1500806905124 Marionette DEBUG Closed connection 379
03:48:25 INFO - 1500806905126 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49597
03:48:25 INFO - 1500806905128 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:25 INFO - 1500806905131 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:25 INFO - 1500806905152 Marionette DEBUG Register listener.js for window 4294967297
03:48:25 INFO - 1500806905163 Marionette TRACE 380 <- [1,1,null,{"sessionId":"3202ac70-c08d-40db-8f9a-579c34d19ebf","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:25 INFO - 1500806905166 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:48:25 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:25 INFO - 1500806905170 Marionette TRACE 380 <- [1,2,null,{}]
03:48:25 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:25 INFO - 1500806905174 Marionette DEBUG Closed connection 380
03:48:25 INFO - 1500806905176 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49598
03:48:25 INFO - 1500806905177 Marionette DEBUG Closed connection 381
03:48:25 INFO - 1500806905179 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49599
03:48:25 INFO - 1500806905181 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:48:25 INFO - 1500806905183 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:25 INFO - 1500806905204 Marionette DEBUG Register listener.js for window 4294967297
03:48:25 INFO - 1500806905215 Marionette TRACE 382 <- [1,1,null,{"sessionId":"a90973e3-59e0-40fd-90bb-1afa62030e8e","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:25 INFO - 1500806905218 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"}]
03:48:25 INFO - 1500806905232 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"
03:48:25 INFO - 1500806905237 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"
03:48:25 INFO - 1500806905237 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"
03:48:25 INFO - ++DOMWINDOW == 20 (0000004BDE29E800) [pid = 1568] [serial = 30] [outer = 0000004BD254F800]
03:48:25 INFO - 1500806905263 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"
03:48:25 INFO - 1500806905267 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"
03:48:25 INFO - 1500806905280 Marionette TRACE 382 <- [1,2,null,{}]
03:48:25 INFO - 1500806905295 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:48:25 INFO - 1500806905317 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"02d85b57-50b3-4687-944a-bef2e09ef2c5","ELEMENT":"02d85b57-50b3-4687-944a-bef2e09ef2c5"}}]
03:48:25 INFO - 1500806905322 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"02d85b57-50b3-4687-944a-bef2e09ef2c5"}]
03:48:25 INFO - 1500806905752 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:48:25 INFO - 1500806905767 Marionette TRACE 382 <- [1,4,null,{}]
03:48:25 INFO - 1500806905772 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}]
03:48:25 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500806905783 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:48:25 INFO - 1500806905789 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:48:25 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:25 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 756ms
03:48:25 INFO - TEST-START | test_click.py TestClick.test_input_file
03:48:25 INFO - 1500806905800 Marionette TRACE 382 <- [1,6,null,{}]
03:48:25 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:25 INFO - 1500806905851 Marionette DEBUG Closed connection 382
03:48:25 INFO - 1500806905855 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49600
03:48:25 INFO - 1500806905866 Marionette DEBUG Closed connection 383
03:48:25 INFO - 1500806905869 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49601
03:48:25 INFO - 1500806905873 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:25 INFO - 1500806905886 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:25 INFO - 1500806905920 Marionette DEBUG Register listener.js for window 4294967297
03:48:25 INFO - 1500806905972 Marionette TRACE 384 <- [1,1,null,{"sessionId":"d1a94559-45e4-4b6d-a5a5-eb3f551f7dc3","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:25 INFO - 1500806905977 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:48:25 INFO - 1500806905981 Marionette TRACE 384 <- [1,2,null,{}]
03:48:25 INFO - 1500806905989 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:48:25 INFO - 1500806905994 Marionette TRACE 384 <- [1,3,null,{}]
03:48:26 INFO - 1500806906001 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:48:26 INFO - 1500806906006 Marionette TRACE 384 <- [1,4,null,{}]
03:48:26 INFO - 1500806906017 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}]
03:48:26 INFO - TEST-START: test_click.py TestClick.test_input_file1500806906339 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:48:26 INFO - 1500806906343 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:48:26 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:26 INFO - 1500806906351 Marionette TRACE 384 <- [1,6,null,{}]
03:48:26 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:26 INFO - 1500806906357 Marionette DEBUG Closed connection 384
03:48:26 INFO - 1500806906360 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49602
03:48:26 INFO - 1500806906362 Marionette DEBUG Closed connection 385
03:48:26 INFO - 1500806906364 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49603
03:48:26 INFO - 1500806906368 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:26 INFO - 1500806906373 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:26 INFO - 1500806906436 Marionette DEBUG Register listener.js for window 4294967297
03:48:26 INFO - 1500806906454 Marionette TRACE 386 <- [1,1,null,{"sessionId":"914701de-b579-4fd6-804a-b420de599249","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:26 INFO - 1500806906459 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:48:26 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:26 INFO - 1500806906466 Marionette TRACE 386 <- [1,2,null,{}]
03:48:26 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:26 INFO - 1500806906472 Marionette DEBUG Closed connection 386
03:48:26 INFO - 1500806906474 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49604
03:48:26 INFO - 1500806906476 Marionette DEBUG Closed connection 387
03:48:26 INFO - 1500806906478 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49605
03:48:26 INFO - 1500806906481 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:48:26 INFO - 1500806906485 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:26 INFO - 1500806906525 Marionette DEBUG Register listener.js for window 4294967297
03:48:26 INFO - 1500806906539 Marionette TRACE 388 <- [1,1,null,{"sessionId":"10f40e79-93ef-4507-a7b2-5da7164e9423","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:26 INFO - 1500806906543 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:48:26 INFO - 1500806906549 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"
03:48:26 INFO - 1500806906554 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"
03:48:26 INFO - 1500806906555 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"
03:48:26 INFO - ++DOMWINDOW == 21 (0000004BE104E000) [pid = 1568] [serial = 31] [outer = 0000004BD254F800]
03:48:26 INFO - 1500806906583 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:48:26 INFO - 1500806906587 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:48:26 INFO - 1500806906616 Marionette TRACE 388 <- [1,2,null,{}]
03:48:26 INFO - 1500806906639 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:48:26 INFO - 1500806906651 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4d761bdf-874b-4d75-ad42-01154d5f1b35","ELEMENT":"4d761bdf-874b-4d75-ad42-01154d5f1b35"}}]
03:48:26 INFO - 1500806906654 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"4d761bdf-874b-4d75-ad42-01154d5f1b35"}]
03:48:26 INFO - 1500806906808 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
03:48:26 INFO - 1500806906811 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}]
03:48:26 INFO - TEST-END: test_click.py TestClick.test_input_file1500806906821 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:48:26 INFO - 1500806906824 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:48:26 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:26 INFO - 1500806906829 Marionette TRACE 388 <- [1,6,null,{}]
03:48:26 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1029ms
03:48:26 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:48:26 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:26 INFO - 1500806906833 Marionette DEBUG Closed connection 388
03:48:26 INFO - 1500806906835 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49606
03:48:26 INFO - 1500806906837 Marionette DEBUG Closed connection 389
03:48:26 INFO - 1500806906839 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49607
03:48:26 INFO - 1500806906842 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:26 INFO - 1500806906844 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:26 INFO - 1500806906867 Marionette DEBUG Register listener.js for window 4294967297
03:48:26 INFO - 1500806906881 Marionette TRACE 390 <- [1,1,null,{"sessionId":"84a0f3ad-bd3c-4f31-ab49-169cca8f931c","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:26 INFO - 1500806906885 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:48:26 INFO - 1500806906887 Marionette TRACE 390 <- [1,2,null,{}]
03:48:26 INFO - 1500806906889 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:48:26 INFO - 1500806906891 Marionette TRACE 390 <- [1,3,null,{}]
03:48:26 INFO - 1500806906893 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:48:26 INFO - 1500806906895 Marionette TRACE 390 <- [1,4,null,{}]
03:48:26 INFO - 1500806906897 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}]
03:48:26 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500806906905 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:48:26 INFO - 1500806906907 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:48:26 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:26 INFO - 1500806906912 Marionette TRACE 390 <- [1,6,null,{}]
03:48:26 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:26 INFO - 1500806906917 Marionette DEBUG Closed connection 390
03:48:26 INFO - 1500806906919 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49608
03:48:26 INFO - 1500806906921 Marionette DEBUG Closed connection 391
03:48:26 INFO - 1500806906922 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49609
03:48:26 INFO - 1500806906925 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:26 INFO - 1500806906928 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:26 INFO - 1500806906951 Marionette DEBUG Register listener.js for window 4294967297
03:48:26 INFO - 1500806906969 Marionette TRACE 392 <- [1,1,null,{"sessionId":"686a08fc-cb57-4e16-878c-dfc3474bcf67","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:26 INFO - 1500806906974 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:48:26 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:26 INFO - 1500806906979 Marionette TRACE 392 <- [1,2,null,{}]
03:48:26 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:26 INFO - 1500806906987 Marionette DEBUG Closed connection 392
03:48:26 INFO - 1500806906994 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49610
03:48:26 INFO - 1500806906996 Marionette DEBUG Closed connection 393
03:48:27 INFO - 1500806906998 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49611
03:48:27 INFO - 1500806907000 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:48:27 INFO - 1500806907003 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:27 INFO - 1500806907033 Marionette DEBUG Register listener.js for window 4294967297
03:48:27 INFO - 1500806907048 Marionette TRACE 394 <- [1,1,null,{"sessionId":"d72f5cc8-4259-4c4d-834a-98dbfd69a3c4","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:27 INFO - 1500806907051 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"}]
03:48:27 INFO - 1500806907056 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:48:27 INFO - 1500806907062 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:48:27 INFO - 1500806907062 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:48:27 INFO - ++DOMWINDOW == 22 (0000004BDD628000) [pid = 1568] [serial = 32] [outer = 0000004BD254F800]
03:48:27 INFO - 1500806907103 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"
03:48:27 INFO - 1500806907107 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"
03:48:27 INFO - 1500806907125 Marionette TRACE 394 <- [1,2,null,{}]
03:48:27 INFO - 1500806907134 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:48:27 INFO - 1500806907141 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bbe3cefc-b935-4752-8eac-dcfed382f8c1","ELEMENT":"bbe3cefc-b935-4752-8eac-dcfed382f8c1"}}]
03:48:27 INFO - 1500806907150 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:48:27 INFO - 1500806907157 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"787d2ff2-4707-414c-9af3-2fd10b16ae12","ELEMENT":"787d2ff2-4707-414c-9af3-2fd10b16ae12"}}]
03:48:27 INFO - 1500806907159 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"bbe3cefc-b935-4752-8eac-dcfed382f8c1"}]
03:48:27 INFO - 1500806907581 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:48:27 INFO - 1500806907584 Marionette TRACE 394 <- [1,5,null,{}]
03:48:27 INFO - 1500806907586 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"787d2ff2-4707-414c-9af3-2fd10b16ae12"}]
03:48:27 INFO - 1500806907717 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
03:48:27 INFO - 1500806907720 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:48:27 INFO - 1500806907728 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:48:27 INFO - 1500806907730 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}]
03:48:27 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500806907737 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:48:27 INFO - 1500806907739 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:48:27 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:27 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 914ms
03:48:27 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:48:27 INFO - 1500806907744 Marionette TRACE 394 <- [1,9,null,{}]
03:48:27 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:27 INFO - 1500806907752 Marionette DEBUG Closed connection 394
03:48:27 INFO - 1500806907754 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49612
03:48:27 INFO - 1500806907757 Marionette DEBUG Closed connection 395
03:48:27 INFO - 1500806907758 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49613
03:48:27 INFO - 1500806907761 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:27 INFO - 1500806907763 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:27 INFO - 1500806907785 Marionette DEBUG Register listener.js for window 4294967297
03:48:27 INFO - 1500806907797 Marionette TRACE 396 <- [1,1,null,{"sessionId":"6639e1d0-994d-4686-8279-d8dfc1116438","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:27 INFO - 1500806907800 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:48:27 INFO - 1500806907802 Marionette TRACE 396 <- [1,2,null,{}]
03:48:27 INFO - 1500806907804 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:48:27 INFO - 1500806907806 Marionette TRACE 396 <- [1,3,null,{}]
03:48:27 INFO - 1500806907808 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:48:27 INFO - 1500806907810 Marionette TRACE 396 <- [1,4,null,{}]
03:48:27 INFO - 1500806907813 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}]
03:48:27 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500806907820 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:48:27 INFO - 1500806907822 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:48:27 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:27 INFO - 1500806907827 Marionette TRACE 396 <- [1,6,null,{}]
03:48:27 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:27 INFO - 1500806907831 Marionette DEBUG Closed connection 396
03:48:27 INFO - 1500806907832 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49614
03:48:27 INFO - 1500806907834 Marionette DEBUG Closed connection 397
03:48:27 INFO - 1500806907835 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49615
03:48:27 INFO - 1500806907838 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:48:27 INFO - 1500806907840 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:27 INFO - 1500806907862 Marionette DEBUG Register listener.js for window 4294967297
03:48:27 INFO - 1500806907876 Marionette TRACE 398 <- [1,1,null,{"sessionId":"2531ad80-7a3e-4398-bafe-ad8a129306c9","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:27 INFO - 1500806907879 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:48:27 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:27 INFO - 1500806907884 Marionette TRACE 398 <- [1,2,null,{}]
03:48:27 INFO - [Parent 3744] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:48:27 INFO - 1500806907888 Marionette DEBUG Closed connection 398
03:48:27 INFO - 1500806907889 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49616
03:48:27 INFO - 1500806907894 Marionette DEBUG Closed connection 399
03:48:27 INFO - 1500806907896 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49617
03:48:27 INFO - 1500806907898 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:48:27 INFO - 1500806907901 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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}
03:48:27 INFO - 1500806907923 Marionette DEBUG Register listener.js for window 4294967297
03:48:27 INFO - 1500806907935 Marionette TRACE 400 <- [1,1,null,{"sessionId":"89139f5d-e44e-4e45-80a9-2d8f92751d5a","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":3744,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-318.001\\AppData\\Local\\Temp\\tmpz3jhwl.mozrunner","moz:accessibilityChecks":false}}]
03:48:27 INFO - 1500806907939 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"}]
03:48:27 INFO - 1500806907944 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"
03:48:27 INFO - 1500806907949 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"
03:48:27 INFO - 1500806907949 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"
03:48:27 INFO - ++DOMWINDOW == 23 (0000004BE105E000) [pid = 1568] [serial = 33] [outer = 0000004BD254F800]
03:48:27 INFO - 1500806907972 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"
03:48:27 INFO - 1500806907978 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"
03:48:28 INFO - 1500806908317 Marionette TRACE 400 <- [1,2,null,{}]
03:48:28 INFO - 1500806908337 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:48:28 INFO - 1500806908344 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"293b6f66-1b0d-436f-befb-70a4f951cb7f","ELEMENT":"293b6f66-1b0d-436f-befb-70a4f951cb7f"}}]
03:48:28 INFO - 1500806908346 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"293b6f66-1b0d-436f-befb-70a4f951cb7f"}]
03:48:28 INFO - 1500806908470 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:48:28 INFO - 1500806908473 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"293b6f66-1b0d-436f-befb-70a4f951cb7f"}]
03:48:28 INFO - 1500806908599 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element