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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:53:51 INFO - 1500584031237 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}]
13:53:51 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500584031245 Marionette TRACE 352 <- [1,5,null,{"value":null}]
13:53:51 INFO - 1500584031246 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
13:53:51 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:51 INFO - 1500584031250 Marionette TRACE 352 <- [1,6,null,{}]
13:53:51 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 698ms
13:53:51 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:53:51 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:51 INFO - 1500584031256 Marionette DEBUG Closed connection 352
13:53:51 INFO - 1500584031258 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49782
13:53:51 INFO - 1500584031260 Marionette DEBUG Closed connection 353
13:53:51 INFO - 1500584031261 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49783
13:53:51 INFO - 1500584031263 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:51 INFO - 1500584031267 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:51 INFO - 1500584031289 Marionette DEBUG Register listener.js for window 15
13:53:51 INFO - 1500584031300 Marionette TRACE 354 <- [1,1,null,{"sessionId":"1fdfc271-b1aa-4703-89cc-dbaacae93f91","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:51 INFO - 1500584031328 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
13:53:51 INFO - 1500584031330 Marionette TRACE 354 <- [1,2,null,{}]
13:53:51 INFO - 1500584031332 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:53:51 INFO - 1500584031333 Marionette TRACE 354 <- [1,3,null,{}]
13:53:51 INFO - 1500584031335 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
13:53:51 INFO - 1500584031337 Marionette TRACE 354 <- [1,4,null,{}]
13:53:51 INFO - 1500584031339 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}]
13:53:51 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500584031346 Marionette TRACE 354 <- [1,5,null,{"value":null}]
13:53:51 INFO - 1500584031348 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
13:53:51 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:51 INFO - 1500584031353 Marionette TRACE 354 <- [1,6,null,{}]
13:53:51 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:51 INFO - 1500584031359 Marionette DEBUG Closed connection 354
13:53:51 INFO - 1500584031360 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49784
13:53:51 INFO - 1500584031362 Marionette DEBUG Closed connection 355
13:53:51 INFO - 1500584031363 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49785
13:53:51 INFO - 1500584031365 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:51 INFO - 1500584031367 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:51 INFO - 1500584031388 Marionette DEBUG Register listener.js for window 15
13:53:51 INFO - 1500584031399 Marionette TRACE 356 <- [1,1,null,{"sessionId":"3b79edf0-72e9-4717-a37b-95d5ae905501","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:51 INFO - 1500584031405 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
13:53:51 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:51 INFO - 1500584031409 Marionette TRACE 356 <- [1,2,null,{}]
13:53:51 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:51 INFO - 1500584031415 Marionette DEBUG Closed connection 356
13:53:51 INFO - 1500584031416 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49786
13:53:51 INFO - 1500584031418 Marionette DEBUG Closed connection 357
13:53:51 INFO - 1500584031419 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49787
13:53:51 INFO - 1500584031421 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:53:51 INFO - 1500584031424 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:51 INFO - 1500584031445 Marionette DEBUG Register listener.js for window 15
13:53:51 INFO - 1500584031467 Marionette TRACE 358 <- [1,1,null,{"sessionId":"5c00988c-3c39-46ae-bc65-0bb245f5ed02","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:51 INFO - 1500584031474 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49332/clicks.html"}]
13:53:51 INFO - 1500584031478 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/hidden.html"
13:53:51 INFO - 1500584031489 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/hidden.html"
13:53:51 INFO - 1500584031490 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/hidden.html"
13:53:51 INFO - ++DOMWINDOW == 22 (000000FD741EE000) [pid = 3176] [serial = 40] [outer = 000000FD6F6E2800]
13:53:51 INFO - 1500584031595 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/clicks.html"
13:53:51 INFO - 1500584031599 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/clicks.html"
13:53:51 INFO - 1500584031619 Marionette TRACE 358 <- [1,2,null,{}]
13:53:51 INFO - 1500584031622 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:53:51 INFO - 1500584031632 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f7ee63a1-3e74-497c-9779-a1cc7dd3ac2c","ELEMENT":"f7ee63a1-3e74-497c-9779-a1cc7dd3ac2c"}}]
13:53:51 INFO - 1500584031640 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"f7ee63a1-3e74-497c-9779-a1cc7dd3ac2c"}]
13:53:51 INFO - 1500584031863 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/clicks.html"
13:53:51 INFO - [Parent 3176] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
13:53:51 INFO - 1500584031881 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/clicks.html"
13:53:51 INFO - 1500584031883 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/clicks.html"
13:53:51 INFO - ++DOMWINDOW == 23 (000000FD778A5800) [pid = 3176] [serial = 41] [outer = 000000FD6F6E2800]
13:53:51 INFO - 1500584031943 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49332/test.html"
13:53:51 INFO - 1500584031947 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49332/test.html"
13:53:51 INFO - 1500584031977 Marionette TRACE 358 <- [1,4,null,{}]
13:53:51 INFO - 1500584031988 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:53:52 INFO - 1500584031996 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"554d9bc7-acd3-40f8-80bc-dbcbb0e4690d","ELEMENT":"554d9bc7-acd3-40f8-80bc-dbcbb0e4690d"}}]
13:53:52 INFO - 1500584031998 Marionette TRACE 358 -> [0,6,"getTitle",{}]
13:53:52 INFO - 1500584032000 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
13:53:52 INFO - 1500584032004 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}]
13:53:52 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500584032012 Marionette TRACE 358 <- [1,7,null,{"value":null}]
13:53:52 INFO - 1500584032013 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
13:53:52 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:52 INFO - 1500584032018 Marionette TRACE 358 <- [1,8,null,{}]
13:53:52 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 768ms
13:53:52 INFO - TEST-START | test_click.py TestClick.test_container_element
13:53:52 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:52 INFO - 1500584032024 Marionette DEBUG Closed connection 358
13:53:52 INFO - 1500584032026 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49790
13:53:52 INFO - 1500584032028 Marionette DEBUG Closed connection 359
13:53:52 INFO - 1500584032029 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49791
13:53:52 INFO - 1500584032032 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:52 INFO - 1500584032034 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:52 INFO - 1500584032056 Marionette DEBUG Register listener.js for window 15
13:53:52 INFO - 1500584032067 Marionette TRACE 360 <- [1,1,null,{"sessionId":"800f4cb6-8516-4ea7-8e18-dfc95af57a7e","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:52 INFO - 1500584032096 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
13:53:52 INFO - 1500584032098 Marionette TRACE 360 <- [1,2,null,{}]
13:53:52 INFO - 1500584032100 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:53:52 INFO - 1500584032101 Marionette TRACE 360 <- [1,3,null,{}]
13:53:52 INFO - 1500584032104 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
13:53:52 INFO - 1500584032106 Marionette TRACE 360 <- [1,4,null,{}]
13:53:52 INFO - 1500584032108 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}]
13:53:52 INFO - TEST-START: test_click.py TestClick.test_container_element1500584032115 Marionette TRACE 360 <- [1,5,null,{"value":null}]
13:53:52 INFO - 1500584032117 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
13:53:52 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:52 INFO - 1500584032121 Marionette TRACE 360 <- [1,6,null,{}]
13:53:52 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:52 INFO - 1500584032146 Marionette DEBUG Closed connection 360
13:53:52 INFO - 1500584032148 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49792
13:53:52 INFO - 1500584032152 Marionette DEBUG Closed connection 361
13:53:52 INFO - 1500584032153 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49793
13:53:52 INFO - 1500584032155 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:52 INFO - 1500584032157 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:52 INFO - 1500584032179 Marionette DEBUG Register listener.js for window 15
13:53:52 INFO - 1500584032211 Marionette TRACE 362 <- [1,1,null,{"sessionId":"c9bef562-af39-4dda-b22a-db7034389c01","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:52 INFO - 1500584032222 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
13:53:52 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:52 INFO - 1500584032226 Marionette TRACE 362 <- [1,2,null,{}]
13:53:52 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:52 INFO - 1500584032248 Marionette DEBUG Closed connection 362
13:53:52 INFO - 1500584032249 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49794
13:53:52 INFO - 1500584032254 Marionette DEBUG Closed connection 363
13:53:52 INFO - 1500584032256 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49795
13:53:52 INFO - 1500584032258 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:53:52 INFO - 1500584032261 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:52 INFO - 1500584032282 Marionette DEBUG Register listener.js for window 15
13:53:52 INFO - 1500584032293 Marionette TRACE 364 <- [1,1,null,{"sessionId":"57539ca5-3b01-41b5-9565-dd921fe77706","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:52 INFO - 1500584032303 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"}]
13:53:52 INFO - 1500584032307 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49332/test.html"
13:53:52 INFO - 1500584032312 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49332/test.html"
13:53:52 INFO - 1500584032313 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49332/test.html"
13:53:52 INFO - ++DOMWINDOW == 24 (000000FD6EF17800) [pid = 3176] [serial = 42] [outer = 000000FD6F6E2800]
13:53:52 INFO - 1500584032368 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"
13:53:52 INFO - 1500584032372 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"
13:53:52 INFO - 1500584032380 Marionette TRACE 364 <- [1,2,null,{}]
13:53:52 INFO - 1500584032390 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:53:52 INFO - 1500584032404 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d3871581-22ad-465f-88ef-a6e3c8c78926","ELEMENT":"d3871581-22ad-465f-88ef-a6e3c8c78926"}}]
13:53:52 INFO - 1500584032414 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"d3871581-22ad-465f-88ef-a6e3c8c78926"}]
13:53:52 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
13:53:52 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
13:53:52 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
13:53:53 INFO - 1500584033414 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:53:53 INFO - 1500584033429 Marionette TRACE 364 <- [1,4,null,{}]
13:53:53 INFO - 1500584033435 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"d3871581-22ad-465f-88ef-a6e3c8c78926","name":"selected"}]
13:53:53 INFO - 1500584033459 Marionette TRACE 364 <- [1,5,null,{"value":true}]
13:53:53 INFO - 1500584033503 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}]
13:53:53 INFO - TEST-END: test_click.py TestClick.test_container_element1500584033511 Marionette TRACE 364 <- [1,6,null,{"value":null}]
13:53:53 INFO - 1500584033562 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
13:53:53 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:53 INFO - 1500584033567 Marionette TRACE 364 <- [1,7,null,{}]
13:53:53 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1549ms
13:53:53 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:53:53 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:53 INFO - 1500584033601 Marionette DEBUG Closed connection 364
13:53:53 INFO - 1500584033603 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49796
13:53:53 INFO - 1500584033606 Marionette DEBUG Closed connection 365
13:53:53 INFO - 1500584033608 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49797
13:53:53 INFO - 1500584033620 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:53 INFO - 1500584033623 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:53 INFO - 1500584033644 Marionette DEBUG Register listener.js for window 15
13:53:53 INFO - 1500584033655 Marionette TRACE 366 <- [1,1,null,{"sessionId":"73f9c82b-9934-4af2-825e-4e71ac2e2095","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:53 INFO - 1500584033666 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
13:53:53 INFO - 1500584033667 Marionette TRACE 366 <- [1,2,null,{}]
13:53:53 INFO - 1500584033669 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:53:53 INFO - 1500584033671 Marionette TRACE 366 <- [1,3,null,{}]
13:53:53 INFO - 1500584033673 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
13:53:53 INFO - 1500584033674 Marionette TRACE 366 <- [1,4,null,{}]
13:53:53 INFO - 1500584033677 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}]
13:53:53 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500584033684 Marionette TRACE 366 <- [1,5,null,{"value":null}]
13:53:53 INFO - 1500584033686 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
13:53:53 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:53 INFO - 1500584033690 Marionette TRACE 366 <- [1,6,null,{}]
13:53:53 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:53 INFO - 1500584033699 Marionette DEBUG Closed connection 366
13:53:53 INFO - 1500584033700 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49798
13:53:53 INFO - 1500584033702 Marionette DEBUG Closed connection 367
13:53:53 INFO - 1500584033703 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49799
13:53:53 INFO - 1500584033706 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:53 INFO - 1500584033708 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:53 INFO - 1500584033730 Marionette DEBUG Register listener.js for window 15
13:53:53 INFO - 1500584033741 Marionette TRACE 368 <- [1,1,null,{"sessionId":"e7507b76-459d-44ca-80e3-6d480aed2ddf","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:53 INFO - 1500584033748 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
13:53:53 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:53 INFO - 1500584033753 Marionette TRACE 368 <- [1,2,null,{}]
13:53:53 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:53 INFO - 1500584033759 Marionette DEBUG Closed connection 368
13:53:53 INFO - 1500584033760 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49800
13:53:53 INFO - 1500584033762 Marionette DEBUG Closed connection 369
13:53:53 INFO - 1500584033763 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49801
13:53:53 INFO - 1500584033765 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:53:53 INFO - 1500584033768 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:53 INFO - 1500584033789 Marionette DEBUG Register listener.js for window 15
13:53:53 INFO - 1500584033800 Marionette TRACE 370 <- [1,1,null,{"sessionId":"03a2b548-256f-4798-a830-74312fdd2d44","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:53 INFO - 1500584033807 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"}]
13:53:53 INFO - 1500584033811 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"
13:53:53 INFO - 1500584033821 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"
13:53:53 INFO - 1500584033822 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"
13:53:53 INFO - ++DOMWINDOW == 25 (000000FD778AE800) [pid = 3176] [serial = 43] [outer = 000000FD6F6E2800]
13:53:53 INFO - 1500584033910 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"
13:53:53 INFO - 1500584033921 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"
13:53:53 INFO - 1500584033992 Marionette TRACE 370 <- [1,2,null,{}]
13:53:54 INFO - 1500584033998 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:53:54 INFO - 1500584034004 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9f2d5c26-6500-4d99-9538-703ac260a48e","ELEMENT":"9f2d5c26-6500-4d99-9538-703ac260a48e"}}]
13:53:54 INFO - 1500584034006 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"9f2d5c26-6500-4d99-9538-703ac260a48e"}]
13:53:54 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
13:53:54 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
13:53:54 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
13:53:54 INFO - 1500584034416 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:53:54 INFO - 1500584034417 Marionette TRACE 370 <- [1,4,null,{}]
13:53:54 INFO - 1500584034420 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"9f2d5c26-6500-4d99-9538-703ac260a48e","name":"selected"}]
13:53:54 INFO - 1500584034445 Marionette TRACE 370 <- [1,5,null,{"value":true}]
13:53:54 INFO - 1500584034454 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}]
13:53:54 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500584034462 Marionette TRACE 370 <- [1,6,null,{"value":null}]
13:53:54 INFO - 1500584034464 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
13:53:54 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:54 INFO - 1500584034468 Marionette TRACE 370 <- [1,7,null,{}]
13:53:54 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 900ms
13:53:54 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:53:54 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:54 INFO - 1500584034474 Marionette DEBUG Closed connection 370
13:53:54 INFO - 1500584034476 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49802
13:53:54 INFO - 1500584034477 Marionette DEBUG Closed connection 371
13:53:54 INFO - 1500584034479 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49803
13:53:54 INFO - 1500584034481 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:54 INFO - 1500584034483 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:54 INFO - 1500584034505 Marionette DEBUG Register listener.js for window 15
13:53:54 INFO - 1500584034516 Marionette TRACE 372 <- [1,1,null,{"sessionId":"ea9c2786-eb4f-4751-b8ab-bd85168ab7a2","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:54 INFO - 1500584034544 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
13:53:54 INFO - 1500584034546 Marionette TRACE 372 <- [1,2,null,{}]
13:53:54 INFO - 1500584034549 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:53:54 INFO - 1500584034550 Marionette TRACE 372 <- [1,3,null,{}]
13:53:54 INFO - 1500584034552 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
13:53:54 INFO - 1500584034554 Marionette TRACE 372 <- [1,4,null,{}]
13:53:54 INFO - 1500584034556 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}]
13:53:54 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500584034564 Marionette TRACE 372 <- [1,5,null,{"value":null}]
13:53:54 INFO - 1500584034617 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
13:53:54 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:54 INFO - 1500584034627 Marionette TRACE 372 <- [1,6,null,{}]
13:53:54 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:54 INFO - 1500584034654 Marionette DEBUG Closed connection 372
13:53:54 INFO - 1500584034657 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49804
13:53:54 INFO - 1500584034663 Marionette DEBUG Closed connection 373
13:53:54 INFO - 1500584034666 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49805
13:53:54 INFO - 1500584034720 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:54 INFO - 1500584034730 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:54 INFO - 1500584034774 Marionette DEBUG Register listener.js for window 15
13:53:54 INFO - 1500584034796 Marionette TRACE 374 <- [1,1,null,{"sessionId":"4c231f36-872d-4e8b-948b-f1a1b2d9ba83","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:54 INFO - 1500584034835 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
13:53:54 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:54 INFO - 1500584034844 Marionette TRACE 374 <- [1,2,null,{}]
13:53:54 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:54 INFO - 1500584034898 Marionette DEBUG Closed connection 374
13:53:54 INFO - 1500584034901 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49806
13:53:54 INFO - 1500584034923 Marionette DEBUG Closed connection 375
13:53:54 INFO - 1500584034926 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49807
13:53:54 INFO - 1500584034931 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:53:54 INFO - 1500584034935 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:54 INFO - 1500584034980 Marionette DEBUG Register listener.js for window 15
13:53:55 INFO - 1500584035000 Marionette TRACE 376 <- [1,1,null,{"sessionId":"faec42d8-2418-4b1e-a0de-aa9f59d0280e","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:55 INFO - 1500584035029 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"}]
13:53:55 INFO - 1500584035037 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"
13:53:55 INFO - 1500584035046 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"
13:53:55 INFO - 1500584035047 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"
13:53:55 INFO - ++DOMWINDOW == 26 (000000FD637DF000) [pid = 3176] [serial = 44] [outer = 000000FD6F6E2800]
13:53:55 INFO - 1500584035127 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"
13:53:55 INFO - 1500584035136 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"
13:53:55 INFO - 1500584035157 Marionette TRACE 376 <- [1,2,null,{}]
13:53:55 INFO - 1500584035163 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:53:55 INFO - 1500584035176 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f8de12e0-6474-4719-b023-13d7c307cc4a","ELEMENT":"f8de12e0-6474-4719-b023-13d7c307cc4a"}}]
13:53:55 INFO - 1500584035188 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"f8de12e0-6474-4719-b023-13d7c307cc4a"}]
13:53:55 INFO - 1500584035728 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:53:55 INFO - 1500584035731 Marionette TRACE 376 <- [1,4,null,{}]
13:53:55 INFO - 1500584035737 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}]
13:53:55 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500584035751 Marionette TRACE 376 <- [1,5,null,{"value":null}]
13:53:55 INFO - 1500584035755 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
13:53:55 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:55 INFO - 1500584035763 Marionette TRACE 376 <- [1,6,null,{}]
13:53:55 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1296ms
13:53:55 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:53:55 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:55 INFO - 1500584035779 Marionette DEBUG Closed connection 376
13:53:55 INFO - 1500584035781 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49808
13:53:55 INFO - 1500584035784 Marionette DEBUG Closed connection 377
13:53:55 INFO - 1500584035786 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49809
13:53:55 INFO - 1500584035789 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:55 INFO - 1500584035793 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:55 INFO - 1500584035838 Marionette DEBUG Register listener.js for window 15
13:53:55 INFO - 1500584035854 Marionette TRACE 378 <- [1,1,null,{"sessionId":"897fd061-8165-4614-9c92-81d714782c46","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:55 INFO - 1500584035903 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
13:53:55 INFO - 1500584035909 Marionette TRACE 378 <- [1,2,null,{}]
13:53:55 INFO - 1500584035912 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:53:55 INFO - 1500584035914 Marionette TRACE 378 <- [1,3,null,{}]
13:53:55 INFO - 1500584035917 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
13:53:55 INFO - 1500584035919 Marionette TRACE 378 <- [1,4,null,{}]
13:53:55 INFO - 1500584035963 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}]
13:53:55 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500584035976 Marionette TRACE 378 <- [1,5,null,{"value":null}]
13:53:55 INFO - 1500584035981 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
13:53:55 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:55 INFO - 1500584035986 Marionette TRACE 378 <- [1,6,null,{}]
13:53:56 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:56 INFO - 1500584036011 Marionette DEBUG Closed connection 378
13:53:56 INFO - 1500584036013 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49810
13:53:56 INFO - 1500584036217 Marionette DEBUG Closed connection 379
13:53:56 INFO - 1500584036221 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49811
13:53:56 INFO - 1500584036247 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:56 INFO - 1500584036258 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:56 INFO - 1500584036285 Marionette DEBUG Register listener.js for window 15
13:53:56 INFO - 1500584036306 Marionette TRACE 380 <- [1,1,null,{"sessionId":"cc3a9ebc-2ff6-4962-844d-9e8958de5e44","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:56 INFO - 1500584036326 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
13:53:56 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:56 INFO - 1500584036333 Marionette TRACE 380 <- [1,2,null,{}]
13:53:56 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:56 INFO - 1500584036358 Marionette DEBUG Closed connection 380
13:53:56 INFO - 1500584036360 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49812
13:53:56 INFO - 1500584036378 Marionette DEBUG Closed connection 381
13:53:56 INFO - 1500584036384 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49813
13:53:56 INFO - 1500584036390 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:53:56 INFO - 1500584036398 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:56 INFO - 1500584036424 Marionette DEBUG Register listener.js for window 15
13:53:56 INFO - 1500584036442 Marionette TRACE 382 <- [1,1,null,{"sessionId":"5482d584-d374-4f40-97d1-78987313741b","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:56 INFO - 1500584036481 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"}]
13:53:56 INFO - 1500584036488 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"
13:53:56 INFO - 1500584036497 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"
13:53:56 INFO - 1500584036498 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"
13:53:56 INFO - ++DOMWINDOW == 27 (000000FD6EF02800) [pid = 3176] [serial = 45] [outer = 000000FD6F6E2800]
13:53:56 INFO - 1500584036558 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"
13:53:56 INFO - 1500584036568 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"
13:53:56 INFO - 1500584036588 Marionette TRACE 382 <- [1,2,null,{}]
13:53:56 INFO - 1500584036604 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:53:56 INFO - 1500584036616 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4fe0fc81-3241-44ba-aa4a-f84c2beefb86","ELEMENT":"4fe0fc81-3241-44ba-aa4a-f84c2beefb86"}}]
13:53:56 INFO - 1500584036619 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"4fe0fc81-3241-44ba-aa4a-f84c2beefb86"}]
13:53:57 INFO - 1500584037063 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:53:57 INFO - 1500584037065 Marionette TRACE 382 <- [1,4,null,{}]
13:53:57 INFO - 1500584037070 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}]
13:53:57 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500584037081 Marionette TRACE 382 <- [1,5,null,{"value":null}]
13:53:57 INFO - 1500584037085 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
13:53:57 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:57 INFO - 1500584037091 Marionette TRACE 382 <- [1,6,null,{}]
13:53:57 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1329ms
13:53:57 INFO - TEST-START | test_click.py TestClick.test_input_file
13:53:57 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:57 INFO - 1500584037100 Marionette DEBUG Closed connection 382
13:53:57 INFO - 1500584037102 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49814
13:53:57 INFO - 1500584037107 Marionette DEBUG Closed connection 383
13:53:57 INFO - 1500584037110 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49815
13:53:57 INFO - 1500584037132 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:57 INFO - 1500584037136 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:57 INFO - 1500584037159 Marionette DEBUG Register listener.js for window 15
13:53:57 INFO - 1500584037174 Marionette TRACE 384 <- [1,1,null,{"sessionId":"1851d74e-ba57-46bd-890f-72ce9ae1534a","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:57 INFO - 1500584037184 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
13:53:57 INFO - 1500584037187 Marionette TRACE 384 <- [1,2,null,{}]
13:53:57 INFO - 1500584037198 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:53:57 INFO - 1500584037203 Marionette TRACE 384 <- [1,3,null,{}]
13:53:57 INFO - 1500584037205 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
13:53:57 INFO - 1500584037207 Marionette TRACE 384 <- [1,4,null,{}]
13:53:57 INFO - 1500584037210 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}]
13:53:57 INFO - TEST-START: test_click.py TestClick.test_input_file1500584037221 Marionette TRACE 384 <- [1,5,null,{"value":null}]
13:53:57 INFO - 1500584037223 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
13:53:57 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:57 INFO - 1500584037228 Marionette TRACE 384 <- [1,6,null,{}]
13:53:57 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:57 INFO - 1500584037234 Marionette DEBUG Closed connection 384
13:53:57 INFO - 1500584037236 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49816
13:53:57 INFO - 1500584037241 Marionette DEBUG Closed connection 385
13:53:57 INFO - 1500584037243 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49817
13:53:57 INFO - 1500584037268 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:57 INFO - 1500584037271 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:57 INFO - 1500584037295 Marionette DEBUG Register listener.js for window 15
13:53:57 INFO - 1500584037308 Marionette TRACE 386 <- [1,1,null,{"sessionId":"b548d445-ab4f-44bc-8885-e07236433fe4","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:57 INFO - 1500584037319 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
13:53:57 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:57 INFO - 1500584037324 Marionette TRACE 386 <- [1,2,null,{}]
13:53:57 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:57 INFO - 1500584037330 Marionette DEBUG Closed connection 386
13:53:57 INFO - 1500584037332 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49818
13:53:57 INFO - 1500584037334 Marionette DEBUG Closed connection 387
13:53:57 INFO - 1500584037336 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49819
13:53:57 INFO - 1500584037339 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:53:57 INFO - 1500584037345 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:57 INFO - 1500584037370 Marionette DEBUG Register listener.js for window 15
13:53:57 INFO - 1500584037387 Marionette TRACE 388 <- [1,1,null,{"sessionId":"1ef0d407-9e03-4156-9315-993b57fcd0b4","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:57 INFO - --DOMWINDOW == 26 (000000FD75811800) [pid = 3176] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
13:53:57 INFO - --DOMWINDOW == 25 (000000FD63646800) [pid = 3176] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
13:53:57 INFO - --DOMWINDOW == 24 (000000FD6756E800) [pid = 3176] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49332/test_accessibility.html]
13:53:57 INFO - 1500584037426 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:53:57 INFO - 1500584037432 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"
13:53:57 INFO - 1500584037438 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"
13:53:57 INFO - 1500584037440 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"
13:53:57 INFO - ++DOMWINDOW == 25 (000000FD6486F000) [pid = 3176] [serial = 46] [outer = 000000FD6F6E2800]
13:53:57 INFO - 1500584037527 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:53:57 INFO - 1500584037544 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:53:57 INFO - 1500584037561 Marionette TRACE 388 <- [1,2,null,{}]
13:53:57 INFO - 1500584037570 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:53:57 INFO - 1500584037579 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bf90e026-8418-40e4-bc87-8bc012ccd72b","ELEMENT":"bf90e026-8418-40e4-bc87-8bc012ccd72b"}}]
13:53:57 INFO - 1500584037581 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"bf90e026-8418-40e4-bc87-8bc012ccd72b"}]
13:53:57 INFO - 1500584037729 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:53:57 INFO - 1500584037733 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}]
13:53:57 INFO - TEST-END: test_click.py TestClick.test_input_file1500584037742 Marionette TRACE 388 <- [1,5,null,{"value":null}]
13:53:57 INFO - 1500584037744 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
13:53:57 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:57 INFO - 1500584037748 Marionette TRACE 388 <- [1,6,null,{}]
13:53:57 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 654ms
13:53:57 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:53:57 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:57 INFO - 1500584037868 Marionette DEBUG Closed connection 388
13:53:57 INFO - 1500584037870 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49820
13:53:57 INFO - 1500584037906 Marionette DEBUG Closed connection 389
13:53:57 INFO - 1500584037911 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49821
13:53:57 INFO - 1500584037915 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:57 INFO - 1500584037917 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:57 INFO - 1500584037941 Marionette DEBUG Register listener.js for window 15
13:53:57 INFO - 1500584037954 Marionette TRACE 390 <- [1,1,null,{"sessionId":"1a5548ea-c6eb-4da9-9504-23b925ba32ae","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:57 INFO - 1500584037962 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
13:53:57 INFO - 1500584037964 Marionette TRACE 390 <- [1,2,null,{}]
13:53:57 INFO - 1500584037970 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:53:57 INFO - 1500584037972 Marionette TRACE 390 <- [1,3,null,{}]
13:53:57 INFO - 1500584037974 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
13:53:57 INFO - 1500584037976 Marionette TRACE 390 <- [1,4,null,{}]
13:53:57 INFO - 1500584037980 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}]
13:53:57 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500584037989 Marionette TRACE 390 <- [1,5,null,{"value":null}]
13:53:57 INFO - 1500584037991 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
13:53:57 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:57 INFO - 1500584037996 Marionette TRACE 390 <- [1,6,null,{}]
13:53:58 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:58 INFO - 1500584038003 Marionette DEBUG Closed connection 390
13:53:58 INFO - 1500584038004 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49822
13:53:58 INFO - 1500584038006 Marionette DEBUG Closed connection 391
13:53:58 INFO - 1500584038007 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49823
13:53:58 INFO - 1500584038010 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:58 INFO - 1500584038012 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:58 INFO - 1500584038035 Marionette DEBUG Register listener.js for window 15
13:53:58 INFO - 1500584038046 Marionette TRACE 392 <- [1,1,null,{"sessionId":"e127340f-37d8-45fb-95ec-bbef2954961d","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:58 INFO - 1500584038077 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
13:53:58 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:58 INFO - 1500584038082 Marionette TRACE 392 <- [1,2,null,{}]
13:53:58 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:58 INFO - 1500584038106 Marionette DEBUG Closed connection 392
13:53:58 INFO - 1500584038107 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49824
13:53:58 INFO - 1500584038111 Marionette DEBUG Closed connection 393
13:53:58 INFO - 1500584038113 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49825
13:53:58 INFO - 1500584038894 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:53:58 INFO - 1500584038897 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:58 INFO - 1500584038920 Marionette DEBUG Register listener.js for window 15
13:53:58 INFO - 1500584038934 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f21442a0-578c-4310-847d-bf769580b686","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:58 INFO - 1500584038987 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"}]
13:53:58 INFO - 1500584038994 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:53:59 INFO - 1500584039000 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:53:59 INFO - 1500584039001 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:53:59 INFO - ++DOMWINDOW == 26 (000000FD6ED5D800) [pid = 3176] [serial = 47] [outer = 000000FD6F6E2800]
13:53:59 INFO - 1500584039121 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"
13:53:59 INFO - 1500584039128 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"
13:53:59 INFO - 1500584039146 Marionette TRACE 394 <- [1,2,null,{}]
13:53:59 INFO - 1500584039202 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:53:59 INFO - 1500584039211 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"eee2c127-22d2-4950-bf2c-bbd4b1e19502","ELEMENT":"eee2c127-22d2-4950-bf2c-bbd4b1e19502"}}]
13:53:59 INFO - 1500584039213 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:53:59 INFO - 1500584039221 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"73a2cec3-21d3-4a41-b00e-cf06b60c0310","ELEMENT":"73a2cec3-21d3-4a41-b00e-cf06b60c0310"}}]
13:53:59 INFO - 1500584039273 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"eee2c127-22d2-4950-bf2c-bbd4b1e19502"}]
13:53:59 INFO - 1500584039749 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:53:59 INFO - 1500584039750 Marionette TRACE 394 <- [1,5,null,{}]
13:53:59 INFO - 1500584039753 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"73a2cec3-21d3-4a41-b00e-cf06b60c0310"}]
13:53:59 INFO - 1500584039864 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:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:53:59 INFO - 1500584039866 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
13:53:59 INFO - 1500584039874 Marionette TRACE 394 <- [1,7,null,{"value":false}]
13:53:59 INFO - 1500584039877 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}]
13:53:59 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500584039886 Marionette TRACE 394 <- [1,8,null,{"value":null}]
13:53:59 INFO - 1500584039888 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
13:53:59 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:59 INFO - 1500584039893 Marionette TRACE 394 <- [1,9,null,{}]
13:53:59 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 2144ms
13:53:59 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:53:59 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:53:59 INFO - 1500584039899 Marionette DEBUG Closed connection 394
13:53:59 INFO - 1500584039901 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49826
13:53:59 INFO - 1500584039902 Marionette DEBUG Closed connection 395
13:53:59 INFO - 1500584039903 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49827
13:53:59 INFO - 1500584039906 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:53:59 INFO - 1500584039908 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:53:59 INFO - 1500584039932 Marionette DEBUG Register listener.js for window 15
13:53:59 INFO - 1500584039944 Marionette TRACE 396 <- [1,1,null,{"sessionId":"50eb22b2-d5ce-4f0a-ac04-c059157c9867","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:53:59 INFO - 1500584039950 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
13:53:59 INFO - 1500584039952 Marionette TRACE 396 <- [1,2,null,{}]
13:53:59 INFO - 1500584039954 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:53:59 INFO - 1500584039955 Marionette TRACE 396 <- [1,3,null,{}]
13:53:59 INFO - 1500584039957 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
13:53:59 INFO - 1500584039959 Marionette TRACE 396 <- [1,4,null,{}]
13:53:59 INFO - 1500584039961 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}]
13:54:00 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500584040022 Marionette TRACE 396 <- [1,5,null,{"value":null}]
13:54:00 INFO - 1500584040038 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
13:54:00 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:54:00 INFO - 1500584040042 Marionette TRACE 396 <- [1,6,null,{}]
13:54:00 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:54:00 INFO - 1500584040065 Marionette DEBUG Closed connection 396
13:54:00 INFO - 1500584040067 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49828
13:54:00 INFO - 1500584040071 Marionette DEBUG Closed connection 397
13:54:00 INFO - 1500584040073 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49829
13:54:00 INFO - 1500584040075 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:54:00 INFO - 1500584040078 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:54:00 INFO - 1500584040101 Marionette DEBUG Register listener.js for window 15
13:54:00 INFO - 1500584040113 Marionette TRACE 398 <- [1,1,null,{"sessionId":"adae6f59-410c-438e-be2f-f17611aed309","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:54:00 INFO - 1500584040123 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
13:54:00 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:54:00 INFO - 1500584040127 Marionette TRACE 398 <- [1,2,null,{}]
13:54:00 INFO - [Parent 3176] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:54:00 INFO - 1500584040134 Marionette DEBUG Closed connection 398
13:54:00 INFO - 1500584040135 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49830
13:54:00 INFO - 1500584040156 Marionette DEBUG Closed connection 399
13:54:00 INFO - 1500584040158 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49831
13:54:00 INFO - 1500584040161 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:54:00 INFO - 1500584040165 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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}
13:54:00 INFO - 1500584040193 Marionette DEBUG Register listener.js for window 15
13:54:00 INFO - 1500584040207 Marionette TRACE 400 <- [1,1,null,{"sessionId":"c905e399-6abd-4a7b-8554-9027982b5fca","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":3176,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-051.001\\AppData\\Local\\Temp\\tmpcofk4t.mozrunner","moz:accessibilityChecks":false}}]
13:54:00 INFO - 1500584040216 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"}]
13:54:00 INFO - 1500584040221 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"
13:54:00 INFO - 1500584040226 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"
13:54:00 INFO - 1500584040227 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"
13:54:00 INFO - ++DOMWINDOW == 27 (000000FD73516800) [pid = 3176] [serial = 48] [outer = 000000FD6F6E2800]
13:54:00 INFO - 1500584040287 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"
13:54:00 INFO - 1500584040292 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"
13:54:00 INFO - 1500584040301 Marionette TRACE 400 <- [1,2,null,{}]
13:54:00 INFO - 1500584040311 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:54:00 INFO - 1500584040320 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5db9dc85-e531-4514-9b38-a01d8b00c586","ELEMENT":"5db9dc85-e531-4514-9b38-a01d8b00c586"}}]
13:54:00 INFO - 1500584040329 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5db9dc85-e531-4514-9b38-a01d8b00c586"}]
13:54:00 INFO - 1500584040453 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
13:54:00 INFO - 1500584040455 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"5db9dc85-e531-4514-9b38-a01d8b00c586"}]
13:54:00 INFO - 1500584040574 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element