could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
17:09:34 INFO - 1499299774794 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}]
17:09:34 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499299774801 Marionette TRACE 352 <- [1,5,null,{"value":null}]
17:09:34 INFO - 1499299774802 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
17:09:34 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:34 INFO - 1499299774806 Marionette TRACE 352 <- [1,6,null,{}]
17:09:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 489ms
17:09:34 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
17:09:34 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:34 INFO - 1499299774813 Marionette DEBUG Closed connection 352
17:09:34 INFO - 1499299774814 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49779
17:09:34 INFO - 1499299774828 Marionette DEBUG Closed connection 353
17:09:34 INFO - 1499299774829 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49780
17:09:34 INFO - 1499299774831 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:34 INFO - 1499299774833 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:34 INFO - 1499299774855 Marionette DEBUG Register listener.js for window 12
17:09:34 INFO - 1499299774866 Marionette TRACE 354 <- [1,1,null,{"sessionId":"1f5831e4-bac3-48fe-ba45-8bc89e38452f","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:34 INFO - 1499299774869 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
17:09:34 INFO - 1499299774870 Marionette TRACE 354 <- [1,2,null,{}]
17:09:34 INFO - 1499299774872 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:09:34 INFO - 1499299774873 Marionette TRACE 354 <- [1,3,null,{}]
17:09:34 INFO - 1499299774875 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
17:09:34 INFO - 1499299774877 Marionette TRACE 354 <- [1,4,null,{}]
17:09:34 INFO - 1499299774879 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}]
17:09:34 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499299774886 Marionette TRACE 354 <- [1,5,null,{"value":null}]
17:09:34 INFO - 1499299774888 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
17:09:34 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:34 INFO - 1499299774892 Marionette TRACE 354 <- [1,6,null,{}]
17:09:34 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:34 INFO - 1499299774898 Marionette DEBUG Closed connection 354
17:09:34 INFO - 1499299774899 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49781
17:09:34 INFO - 1499299774901 Marionette DEBUG Closed connection 355
17:09:34 INFO - 1499299774902 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49782
17:09:34 INFO - 1499299774904 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:34 INFO - 1499299774906 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:34 INFO - 1499299774929 Marionette DEBUG Register listener.js for window 12
17:09:34 INFO - 1499299774940 Marionette TRACE 356 <- [1,1,null,{"sessionId":"ac9412fe-e063-4e8e-95e8-57206a71279d","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:35 INFO - 1499299775522 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
17:09:35 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:35 INFO - 1499299775527 Marionette TRACE 356 <- [1,2,null,{}]
17:09:35 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:35 INFO - 1499299775551 Marionette DEBUG Closed connection 356
17:09:35 INFO - 1499299775553 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49783
17:09:35 INFO - 1499299775561 Marionette DEBUG Closed connection 357
17:09:35 INFO - 1499299775562 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49784
17:09:35 INFO - 1499299775565 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:09:35 INFO - 1499299775567 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:35 INFO - 1499299775589 Marionette DEBUG Register listener.js for window 12
17:09:35 INFO - 1499299775601 Marionette TRACE 358 <- [1,1,null,{"sessionId":"2c270394-8ba1-46c7-8987-fe63c941a0c2","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:35 INFO - 1499299775614 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49333/clicks.html"}]
17:09:35 INFO - 1499299775618 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/hidden.html"
17:09:35 INFO - 1499299775638 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/hidden.html"
17:09:35 INFO - 1499299775638 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/hidden.html"
17:09:35 INFO - ++DOMWINDOW == 28 (0000006685AE0000) [pid = 2868] [serial = 44] [outer = 0000006682A2A800]
17:09:35 INFO - 1499299775694 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/clicks.html"
17:09:35 INFO - 1499299775698 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/clicks.html"
17:09:35 INFO - 1499299775716 Marionette TRACE 358 <- [1,2,null,{}]
17:09:35 INFO - 1499299775723 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
17:09:35 INFO - 1499299775735 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a1e37f39-1347-4797-9c77-5e7b722b76b3","ELEMENT":"a1e37f39-1347-4797-9c77-5e7b722b76b3"}}]
17:09:35 INFO - 1499299775737 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"a1e37f39-1347-4797-9c77-5e7b722b76b3"}]
17:09:35 INFO - 1499299775953 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/clicks.html"
17:09:35 INFO - [2868] 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 445
17:09:35 INFO - 1499299775972 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/clicks.html"
17:09:35 INFO - 1499299775973 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/clicks.html"
17:09:35 INFO - ++DOMWINDOW == 29 (000000668BE2C000) [pid = 2868] [serial = 45] [outer = 0000006682A2A800]
17:09:36 INFO - 1499299776029 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49333/test.html"
17:09:36 INFO - 1499299776033 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49333/test.html"
17:09:36 INFO - 1499299776050 Marionette TRACE 358 <- [1,4,null,{}]
17:09:36 INFO - 1499299776142 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
17:09:36 INFO - 1499299776153 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"12a3877d-1f42-424c-b97d-860a1cb0d4c4","ELEMENT":"12a3877d-1f42-424c-b97d-860a1cb0d4c4"}}]
17:09:36 INFO - 1499299776156 Marionette TRACE 358 -> [0,6,"getTitle",{}]
17:09:36 INFO - 1499299776157 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
17:09:36 INFO - 1499299776160 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}]
17:09:36 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499299776167 Marionette TRACE 358 <- [1,7,null,{"value":null}]
17:09:36 INFO - 1499299776169 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
17:09:36 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:36 INFO - 1499299776174 Marionette TRACE 358 <- [1,8,null,{}]
17:09:36 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1368ms
17:09:36 INFO - TEST-START | test_click.py TestClick.test_container_element
17:09:36 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:36 INFO - 1499299776228 Marionette DEBUG Closed connection 358
17:09:36 INFO - 1499299776230 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49787
17:09:36 INFO - 1499299776233 Marionette DEBUG Closed connection 359
17:09:36 INFO - 1499299776234 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49788
17:09:36 INFO - 1499299776237 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:36 INFO - 1499299776241 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:36 INFO - 1499299776264 Marionette DEBUG Register listener.js for window 12
17:09:36 INFO - 1499299776275 Marionette TRACE 360 <- [1,1,null,{"sessionId":"f3d245c1-b4d5-4f25-9ed6-7113f65bba98","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:36 INFO - 1499299776280 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
17:09:36 INFO - 1499299776282 Marionette TRACE 360 <- [1,2,null,{}]
17:09:36 INFO - 1499299776290 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:09:36 INFO - 1499299776291 Marionette TRACE 360 <- [1,3,null,{}]
17:09:36 INFO - 1499299776293 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
17:09:36 INFO - 1499299776295 Marionette TRACE 360 <- [1,4,null,{}]
17:09:36 INFO - 1499299776297 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}]
17:09:36 INFO - TEST-START: test_click.py TestClick.test_container_element1499299776305 Marionette TRACE 360 <- [1,5,null,{"value":null}]
17:09:36 INFO - 1499299776307 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
17:09:36 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:36 INFO - 1499299776311 Marionette TRACE 360 <- [1,6,null,{}]
17:09:36 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:36 INFO - 1499299776317 Marionette DEBUG Closed connection 360
17:09:36 INFO - 1499299776318 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49789
17:09:36 INFO - 1499299776320 Marionette DEBUG Closed connection 361
17:09:36 INFO - 1499299776321 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49790
17:09:36 INFO - 1499299776323 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:36 INFO - 1499299776325 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:36 INFO - 1499299776348 Marionette DEBUG Register listener.js for window 12
17:09:36 INFO - 1499299776359 Marionette TRACE 362 <- [1,1,null,{"sessionId":"763d4778-1073-4d6d-b57e-64206e518ceb","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:36 INFO - 1499299776386 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
17:09:36 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:36 INFO - 1499299776390 Marionette TRACE 362 <- [1,2,null,{}]
17:09:36 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:36 INFO - 1499299776399 Marionette DEBUG Closed connection 362
17:09:36 INFO - 1499299776400 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49791
17:09:36 INFO - 1499299776402 Marionette DEBUG Closed connection 363
17:09:36 INFO - 1499299776403 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49792
17:09:36 INFO - 1499299776405 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:09:36 INFO - 1499299776407 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:36 INFO - 1499299776430 Marionette DEBUG Register listener.js for window 12
17:09:36 INFO - 1499299776441 Marionette TRACE 364 <- [1,1,null,{"sessionId":"a9c3fe2c-821f-4014-984e-f53e46eef998","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:36 INFO - 1499299776465 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"}]
17:09:36 INFO - 1499299776469 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49333/test.html"
17:09:36 INFO - 1499299776479 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49333/test.html"
17:09:36 INFO - 1499299776480 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49333/test.html"
17:09:36 INFO - ++DOMWINDOW == 30 (00000066F7D1A800) [pid = 2868] [serial = 46] [outer = 0000006682A2A800]
17:09:36 INFO - 1499299776565 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"
17:09:36 INFO - 1499299776587 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"
17:09:36 INFO - 1499299776605 Marionette TRACE 364 <- [1,2,null,{}]
17:09:36 INFO - 1499299776608 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:09:36 INFO - 1499299776616 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"51ab311f-d4bd-423c-83da-ef4d8de63084","ELEMENT":"51ab311f-d4bd-423c-83da-ef4d8de63084"}}]
17:09:36 INFO - 1499299776618 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"51ab311f-d4bd-423c-83da-ef4d8de63084"}]
17:09:36 INFO - [2868] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
17:09:36 INFO - [2868] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
17:09:36 INFO - [2868] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
17:09:37 INFO - 1499299777063 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:09:37 INFO - 1499299777065 Marionette TRACE 364 <- [1,4,null,{}]
17:09:37 INFO - 1499299777067 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"51ab311f-d4bd-423c-83da-ef4d8de63084","name":"selected"}]
17:09:37 INFO - 1499299777089 Marionette TRACE 364 <- [1,5,null,{"value":true}]
17:09:37 INFO - 1499299777092 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}]
17:09:37 INFO - TEST-END: test_click.py TestClick.test_container_element1499299777099 Marionette TRACE 364 <- [1,6,null,{"value":null}]
17:09:37 INFO - 1499299777101 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
17:09:37 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:37 INFO - 1499299777105 Marionette TRACE 364 <- [1,7,null,{}]
17:09:37 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 930ms
17:09:37 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
17:09:37 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:37 INFO - 1499299777111 Marionette DEBUG Closed connection 364
17:09:37 INFO - 1499299777112 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49793
17:09:37 INFO - 1499299777114 Marionette DEBUG Closed connection 365
17:09:37 INFO - 1499299777115 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49794
17:09:37 INFO - 1499299777117 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:37 INFO - 1499299777119 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:37 INFO - 1499299777141 Marionette DEBUG Register listener.js for window 12
17:09:37 INFO - 1499299777183 Marionette TRACE 366 <- [1,1,null,{"sessionId":"a791710d-d67e-4b4c-ab9d-8753f3f34bd8","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:37 INFO - 1499299777188 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
17:09:37 INFO - 1499299777190 Marionette TRACE 366 <- [1,2,null,{}]
17:09:37 INFO - 1499299777191 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:09:37 INFO - 1499299777193 Marionette TRACE 366 <- [1,3,null,{}]
17:09:37 INFO - 1499299777195 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
17:09:37 INFO - 1499299777196 Marionette TRACE 366 <- [1,4,null,{}]
17:09:37 INFO - 1499299777198 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}]
17:09:37 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499299777208 Marionette TRACE 366 <- [1,5,null,{"value":null}]
17:09:37 INFO - 1499299777210 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
17:09:37 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:37 INFO - 1499299777214 Marionette TRACE 366 <- [1,6,null,{}]
17:09:37 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:37 INFO - 1499299777221 Marionette DEBUG Closed connection 366
17:09:37 INFO - 1499299777223 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49795
17:09:37 INFO - 1499299777225 Marionette DEBUG Closed connection 367
17:09:37 INFO - 1499299777226 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49796
17:09:37 INFO - 1499299777228 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:37 INFO - 1499299777230 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:37 INFO - 1499299777263 Marionette DEBUG Register listener.js for window 12
17:09:37 INFO - 1499299777276 Marionette TRACE 368 <- [1,1,null,{"sessionId":"4951e70a-486b-4be3-92dd-153ed8ea118b","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:37 INFO - 1499299777279 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
17:09:37 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:37 INFO - 1499299777283 Marionette TRACE 368 <- [1,2,null,{}]
17:09:37 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:37 INFO - 1499299777289 Marionette DEBUG Closed connection 368
17:09:37 INFO - 1499299777290 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49797
17:09:37 INFO - 1499299777292 Marionette DEBUG Closed connection 369
17:09:37 INFO - 1499299777293 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49798
17:09:37 INFO - 1499299777296 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:09:37 INFO - 1499299777298 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:37 INFO - 1499299777321 Marionette DEBUG Register listener.js for window 12
17:09:37 INFO - 1499299777332 Marionette TRACE 370 <- [1,1,null,{"sessionId":"c54076b3-b98f-4345-93b3-8ac83eb802d1","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:37 INFO - 1499299777335 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"}]
17:09:37 INFO - 1499299777339 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"
17:09:37 INFO - 1499299777345 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"
17:09:37 INFO - 1499299777346 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"
17:09:37 INFO - ++DOMWINDOW == 31 (000000668D788000) [pid = 2868] [serial = 47] [outer = 0000006682A2A800]
17:09:37 INFO - 1499299777388 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"
17:09:37 INFO - 1499299777397 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"
17:09:37 INFO - 1499299777412 Marionette TRACE 370 <- [1,2,null,{}]
17:09:37 INFO - 1499299777466 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
17:09:37 INFO - 1499299777472 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5468cd52-c96f-4923-a106-baebfdcc1995","ELEMENT":"5468cd52-c96f-4923-a106-baebfdcc1995"}}]
17:09:37 INFO - 1499299777474 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"5468cd52-c96f-4923-a106-baebfdcc1995"}]
17:09:37 INFO - [2868] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
17:09:37 INFO - [2868] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
17:09:37 INFO - [2868] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
17:09:37 INFO - 1499299777879 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:09:37 INFO - 1499299777881 Marionette TRACE 370 <- [1,4,null,{}]
17:09:37 INFO - 1499299777883 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"5468cd52-c96f-4923-a106-baebfdcc1995","name":"selected"}]
17:09:37 INFO - 1499299777905 Marionette TRACE 370 <- [1,5,null,{"value":true}]
17:09:37 INFO - 1499299777908 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}]
17:09:37 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499299777915 Marionette TRACE 370 <- [1,6,null,{"value":null}]
17:09:37 INFO - 1499299777917 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
17:09:37 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:37 INFO - 1499299777921 Marionette TRACE 370 <- [1,7,null,{}]
17:09:37 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 815ms
17:09:37 INFO - TEST-START | test_click.py TestClick.test_css_transforms
17:09:37 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:37 INFO - 1499299777927 Marionette DEBUG Closed connection 370
17:09:37 INFO - 1499299777928 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49799
17:09:37 INFO - 1499299777930 Marionette DEBUG Closed connection 371
17:09:37 INFO - 1499299777932 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49800
17:09:37 INFO - 1499299777934 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:37 INFO - 1499299777936 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:37 INFO - 1499299777959 Marionette DEBUG Register listener.js for window 12
17:09:37 INFO - 1499299777970 Marionette TRACE 372 <- [1,1,null,{"sessionId":"9399033f-05c9-445d-a970-35d8867c8f23","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:38 INFO - 1499299778004 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
17:09:38 INFO - 1499299778006 Marionette TRACE 372 <- [1,2,null,{}]
17:09:38 INFO - 1499299778008 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:09:38 INFO - 1499299778010 Marionette TRACE 372 <- [1,3,null,{}]
17:09:38 INFO - 1499299778012 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
17:09:38 INFO - 1499299778013 Marionette TRACE 372 <- [1,4,null,{}]
17:09:38 INFO - 1499299778016 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}]
17:09:38 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499299778023 Marionette TRACE 372 <- [1,5,null,{"value":null}]
17:09:38 INFO - 1499299778025 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
17:09:38 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:38 INFO - 1499299778029 Marionette TRACE 372 <- [1,6,null,{}]
17:09:38 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:38 INFO - 1499299778054 Marionette DEBUG Closed connection 372
17:09:38 INFO - 1499299778056 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49801
17:09:38 INFO - 1499299778060 Marionette DEBUG Closed connection 373
17:09:38 INFO - 1499299778062 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49802
17:09:38 INFO - 1499299778070 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:38 INFO - 1499299778072 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:38 INFO - 1499299778094 Marionette DEBUG Register listener.js for window 12
17:09:38 INFO - 1499299778105 Marionette TRACE 374 <- [1,1,null,{"sessionId":"eaf8ebfc-0ef4-497f-a2c1-cd07a990452f","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:38 INFO - 1499299778108 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
17:09:38 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:38 INFO - 1499299778112 Marionette TRACE 374 <- [1,2,null,{}]
17:09:38 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:38 INFO - 1499299778118 Marionette DEBUG Closed connection 374
17:09:38 INFO - 1499299778119 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49803
17:09:38 INFO - 1499299778121 Marionette DEBUG Closed connection 375
17:09:38 INFO - 1499299778122 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49804
17:09:38 INFO - 1499299778124 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:09:38 INFO - 1499299778127 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:38 INFO - 1499299778149 Marionette DEBUG Register listener.js for window 12
17:09:38 INFO - 1499299778160 Marionette TRACE 376 <- [1,1,null,{"sessionId":"4bab9a7f-a880-41b1-8570-07c1295f0b83","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:38 INFO - 1499299778209 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"}]
17:09:38 INFO - 1499299778214 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"
17:09:38 INFO - 1499299778220 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"
17:09:38 INFO - 1499299778222 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"
17:09:38 INFO - ++DOMWINDOW == 32 (00000066F7D20800) [pid = 2868] [serial = 48] [outer = 0000006682A2A800]
17:09:38 INFO - 1499299778271 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"
17:09:38 INFO - 1499299778276 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"
17:09:38 INFO - 1499299778291 Marionette TRACE 376 <- [1,2,null,{}]
17:09:38 INFO - 1499299778294 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
17:09:38 INFO - 1499299778301 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4940a285-e4c2-4274-940e-6e472bec6503","ELEMENT":"4940a285-e4c2-4274-940e-6e472bec6503"}}]
17:09:39 INFO - 1499299779204 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"4940a285-e4c2-4274-940e-6e472bec6503"}]
17:09:39 INFO - 1499299779656 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:09:39 INFO - 1499299779661 Marionette TRACE 376 <- [1,4,null,{}]
17:09:39 INFO - 1499299779666 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}]
17:09:39 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499299779674 Marionette TRACE 376 <- [1,5,null,{"value":null}]
17:09:39 INFO - 1499299779677 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
17:09:39 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:39 INFO - 1499299779681 Marionette TRACE 376 <- [1,6,null,{}]
17:09:39 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1760ms
17:09:39 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
17:09:39 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:39 INFO - 1499299779723 Marionette DEBUG Closed connection 376
17:09:39 INFO - 1499299779724 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49805
17:09:39 INFO - 1499299779743 Marionette DEBUG Closed connection 377
17:09:39 INFO - 1499299779745 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49806
17:09:39 INFO - 1499299779747 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:39 INFO - 1499299779749 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:39 INFO - 1499299779772 Marionette DEBUG Register listener.js for window 12
17:09:39 INFO - 1499299779783 Marionette TRACE 378 <- [1,1,null,{"sessionId":"041b3b41-d7da-4150-9091-f731b20a67da","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:39 INFO - 1499299779893 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
17:09:39 INFO - 1499299779895 Marionette TRACE 378 <- [1,2,null,{}]
17:09:39 INFO - 1499299779897 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:09:39 INFO - 1499299779898 Marionette TRACE 378 <- [1,3,null,{}]
17:09:39 INFO - 1499299779900 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
17:09:39 INFO - 1499299779901 Marionette TRACE 378 <- [1,4,null,{}]
17:09:39 INFO - 1499299779904 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}]
17:09:39 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499299779911 Marionette TRACE 378 <- [1,5,null,{"value":null}]
17:09:39 INFO - 1499299779913 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
17:09:39 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:39 INFO - 1499299779917 Marionette TRACE 378 <- [1,6,null,{}]
17:09:39 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:39 INFO - 1499299779923 Marionette DEBUG Closed connection 378
17:09:39 INFO - 1499299779924 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49807
17:09:39 INFO - 1499299779926 Marionette DEBUG Closed connection 379
17:09:39 INFO - 1499299779927 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49808
17:09:39 INFO - 1499299779953 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:39 INFO - 1499299779955 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:39 INFO - 1499299779978 Marionette DEBUG Register listener.js for window 12
17:09:39 INFO - 1499299779989 Marionette TRACE 380 <- [1,1,null,{"sessionId":"b23734be-f549-46c2-87b7-3975997668f7","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:39 INFO - 1499299779995 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
17:09:39 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:40 INFO - 1499299779999 Marionette TRACE 380 <- [1,2,null,{}]
17:09:40 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:40 INFO - 1499299780005 Marionette DEBUG Closed connection 380
17:09:40 INFO - 1499299780006 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49809
17:09:40 INFO - 1499299780008 Marionette DEBUG Closed connection 381
17:09:40 INFO - 1499299780009 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49810
17:09:40 INFO - 1499299780011 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:09:40 INFO - 1499299780014 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:40 INFO - 1499299780036 Marionette DEBUG Register listener.js for window 12
17:09:40 INFO - 1499299780058 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d4451562-2c76-46db-931f-a6d8ddae0ff8","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:40 INFO - 1499299780083 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"}]
17:09:40 INFO - 1499299780086 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"
17:09:40 INFO - 1499299780092 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"
17:09:40 INFO - 1499299780093 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"
17:09:40 INFO - ++DOMWINDOW == 33 (0000006682BAA800) [pid = 2868] [serial = 49] [outer = 0000006682A2A800]
17:09:40 INFO - 1499299780142 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"
17:09:40 INFO - 1499299780147 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"
17:09:40 INFO - 1499299780162 Marionette TRACE 382 <- [1,2,null,{}]
17:09:40 INFO - 1499299780170 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
17:09:40 INFO - 1499299780176 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6645e85f-adcf-42c1-8980-61c156d6fec8","ELEMENT":"6645e85f-adcf-42c1-8980-61c156d6fec8"}}]
17:09:40 INFO - 1499299780178 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"6645e85f-adcf-42c1-8980-61c156d6fec8"}]
17:09:40 INFO - 1499299780601 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:09:40 INFO - 1499299780603 Marionette TRACE 382 <- [1,4,null,{}]
17:09:40 INFO - 1499299780606 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}]
17:09:40 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499299780613 Marionette TRACE 382 <- [1,5,null,{"value":null}]
17:09:40 INFO - 1499299780618 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
17:09:40 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:40 INFO - 1499299780623 Marionette TRACE 382 <- [1,6,null,{}]
17:09:40 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 941ms
17:09:40 INFO - TEST-START | test_click.py TestClick.test_input_file
17:09:40 INFO - --DOMWINDOW == 32 (00000066F7DD5800) [pid = 2868] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
17:09:40 INFO - --DOMWINDOW == 31 (000000668D274000) [pid = 2868] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
17:09:40 INFO - --DOMWINDOW == 30 (0000006681FE5000) [pid = 2868] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
17:09:40 INFO - --DOMWINDOW == 29 (000000668301D800) [pid = 2868] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
17:09:40 INFO - --DOMWINDOW == 28 (00000066F6E9A000) [pid = 2868] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
17:09:40 INFO - --DOMWINDOW == 27 (00000066FC14D800) [pid = 2868] [serial = 33] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
17:09:40 INFO - --DOMWINDOW == 26 (00000066FC894000) [pid = 2868] [serial = 34] [outer = 0000000000000000] [url = http://127.0.0.1:49333/test_accessibility.html]
17:09:40 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:40 INFO - 1499299780648 Marionette DEBUG Closed connection 382
17:09:40 INFO - 1499299780649 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49811
17:09:40 INFO - 1499299780653 Marionette DEBUG Closed connection 383
17:09:40 INFO - 1499299780654 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49812
17:09:40 INFO - 1499299780657 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:40 INFO - 1499299780659 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:40 INFO - 1499299780681 Marionette DEBUG Register listener.js for window 12
17:09:40 INFO - 1499299780693 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4d1aca7a-d2fc-419e-923d-13a36fab07f8","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:40 INFO - 1499299780695 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
17:09:40 INFO - 1499299780697 Marionette TRACE 384 <- [1,2,null,{}]
17:09:40 INFO - 1499299780701 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:09:40 INFO - 1499299780702 Marionette TRACE 384 <- [1,3,null,{}]
17:09:40 INFO - 1499299780704 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
17:09:40 INFO - 1499299780705 Marionette TRACE 384 <- [1,4,null,{}]
17:09:40 INFO - 1499299780708 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}]
17:09:40 INFO - TEST-START: test_click.py TestClick.test_input_file1499299780715 Marionette TRACE 384 <- [1,5,null,{"value":null}]
17:09:40 INFO - 1499299780717 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
17:09:40 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:40 INFO - 1499299780721 Marionette TRACE 384 <- [1,6,null,{}]
17:09:40 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:40 INFO - 1499299780726 Marionette DEBUG Closed connection 384
17:09:40 INFO - 1499299780728 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49813
17:09:40 INFO - 1499299780729 Marionette DEBUG Closed connection 385
17:09:40 INFO - 1499299780731 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49814
17:09:40 INFO - 1499299780733 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:40 INFO - 1499299780735 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:40 INFO - 1499299780758 Marionette DEBUG Register listener.js for window 12
17:09:40 INFO - 1499299780769 Marionette TRACE 386 <- [1,1,null,{"sessionId":"d88ea9f6-4110-46c6-b69c-88f3c8dfb00e","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:40 INFO - 1499299780771 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
17:09:40 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:40 INFO - 1499299780775 Marionette TRACE 386 <- [1,2,null,{}]
17:09:40 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:40 INFO - 1499299780781 Marionette DEBUG Closed connection 386
17:09:40 INFO - 1499299780782 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49815
17:09:40 INFO - 1499299780784 Marionette DEBUG Closed connection 387
17:09:40 INFO - 1499299780785 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49816
17:09:40 INFO - 1499299780787 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:09:40 INFO - 1499299780789 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:40 INFO - 1499299780812 Marionette DEBUG Register listener.js for window 12
17:09:40 INFO - 1499299780824 Marionette TRACE 388 <- [1,1,null,{"sessionId":"233ddba3-16a9-47df-88d3-0808a371ef60","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:40 INFO - 1499299780827 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
17:09:40 INFO - 1499299780830 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"
17:09:40 INFO - 1499299780835 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"
17:09:40 INFO - 1499299780837 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"
17:09:40 INFO - ++DOMWINDOW == 27 (0000006682BAF000) [pid = 2868] [serial = 50] [outer = 0000006682A2A800]
17:09:40 INFO - 1499299780890 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:09:40 INFO - 1499299780899 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:09:40 INFO - 1499299780913 Marionette TRACE 388 <- [1,2,null,{}]
17:09:40 INFO - 1499299780917 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
17:09:40 INFO - 1499299780923 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"84fde4fd-8789-4024-a08d-bf36f976bf81","ELEMENT":"84fde4fd-8789-4024-a08d-bf36f976bf81"}}]
17:09:40 INFO - 1499299780925 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"84fde4fd-8789-4024-a08d-bf36f976bf81"}]
17:09:41 INFO - 1499299781064 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
17:09:41 INFO - 1499299781067 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}]
17:09:41 INFO - TEST-END: test_click.py TestClick.test_input_file1499299781075 Marionette TRACE 388 <- [1,5,null,{"value":null}]
17:09:41 INFO - 1499299781076 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
17:09:41 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:41 INFO - 1499299781080 Marionette TRACE 388 <- [1,6,null,{}]
17:09:41 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 457ms
17:09:41 INFO - TEST-START | test_click.py TestClick.test_obscured_element
17:09:41 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:41 INFO - 1499299781086 Marionette DEBUG Closed connection 388
17:09:41 INFO - 1499299781089 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49817
17:09:41 INFO - 1499299781103 Marionette DEBUG Closed connection 389
17:09:41 INFO - 1499299781104 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49818
17:09:41 INFO - 1499299781106 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:41 INFO - 1499299781108 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:41 INFO - 1499299781131 Marionette DEBUG Register listener.js for window 12
17:09:41 INFO - 1499299781143 Marionette TRACE 390 <- [1,1,null,{"sessionId":"418b598c-b742-4356-abf1-fe4a02681b8c","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:41 INFO - 1499299781145 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
17:09:41 INFO - 1499299781147 Marionette TRACE 390 <- [1,2,null,{}]
17:09:41 INFO - 1499299781149 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:09:41 INFO - 1499299781150 Marionette TRACE 390 <- [1,3,null,{}]
17:09:41 INFO - 1499299781152 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
17:09:41 INFO - 1499299781153 Marionette TRACE 390 <- [1,4,null,{}]
17:09:41 INFO - 1499299781156 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}]
17:09:41 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499299781163 Marionette TRACE 390 <- [1,5,null,{"value":null}]
17:09:41 INFO - 1499299781165 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
17:09:41 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:41 INFO - 1499299781169 Marionette TRACE 390 <- [1,6,null,{}]
17:09:41 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:41 INFO - 1499299781175 Marionette DEBUG Closed connection 390
17:09:41 INFO - 1499299781176 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49819
17:09:41 INFO - 1499299781178 Marionette DEBUG Closed connection 391
17:09:41 INFO - 1499299781179 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49820
17:09:41 INFO - 1499299781181 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:41 INFO - 1499299781183 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:41 INFO - 1499299781207 Marionette DEBUG Register listener.js for window 12
17:09:41 INFO - 1499299781219 Marionette TRACE 392 <- [1,1,null,{"sessionId":"32f5022a-f9ce-423a-9c42-494cc9f895b7","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:41 INFO - 1499299781221 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
17:09:41 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:41 INFO - 1499299781225 Marionette TRACE 392 <- [1,2,null,{}]
17:09:41 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:41 INFO - 1499299781231 Marionette DEBUG Closed connection 392
17:09:41 INFO - 1499299781232 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49821
17:09:41 INFO - 1499299781234 Marionette DEBUG Closed connection 393
17:09:41 INFO - 1499299781235 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49822
17:09:41 INFO - 1499299781238 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:09:41 INFO - 1499299781240 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:41 INFO - 1499299781264 Marionette DEBUG Register listener.js for window 12
17:09:41 INFO - 1499299781275 Marionette TRACE 394 <- [1,1,null,{"sessionId":"21b5bbc4-bf94-49e9-ad76-e495c7f25c0f","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:41 INFO - 1499299781278 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"}]
17:09:41 INFO - 1499299781282 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:09:41 INFO - 1499299781288 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:09:41 INFO - 1499299781288 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
17:09:41 INFO - ++DOMWINDOW == 28 (0000006689B4A000) [pid = 2868] [serial = 51] [outer = 0000006682A2A800]
17:09:41 INFO - 1499299781339 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"
17:09:41 INFO - 1499299781344 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"
17:09:41 INFO - 1499299781356 Marionette TRACE 394 <- [1,2,null,{}]
17:09:41 INFO - 1499299781359 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
17:09:41 INFO - 1499299781367 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fb3e0752-8b33-4d3f-a16a-74ca05ca8e6d","ELEMENT":"fb3e0752-8b33-4d3f-a16a-74ca05ca8e6d"}}]
17:09:41 INFO - 1499299781369 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
17:09:41 INFO - 1499299781374 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9d1382de-ef24-4286-9c5d-a21fe329d1e5","ELEMENT":"9d1382de-ef24-4286-9c5d-a21fe329d1e5"}}]
17:09:41 INFO - 1499299781376 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"fb3e0752-8b33-4d3f-a16a-74ca05ca8e6d"}]
17:09:41 INFO - 1499299781788 Marionette DEBUG Canceled page load listener because no navigation has been detected
17:09:41 INFO - 1499299781789 Marionette TRACE 394 <- [1,5,null,{}]
17:09:41 INFO - 1499299781792 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"9d1382de-ef24-4286-9c5d-a21fe329d1e5"}]
17:09:41 INFO - 1499299781898 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:393:12\nclickElement@chrome://marionette/content/listener.js:1318:5\n"},null]
17:09:41 INFO - 1499299781901 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
17:09:41 INFO - 1499299781908 Marionette TRACE 394 <- [1,7,null,{"value":false}]
17:09:41 INFO - 1499299781910 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}]
17:09:41 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499299781917 Marionette TRACE 394 <- [1,8,null,{"value":null}]
17:09:41 INFO - 1499299781919 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
17:09:41 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:41 INFO - 1499299781923 Marionette TRACE 394 <- [1,9,null,{}]
17:09:41 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 842ms
17:09:41 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
17:09:41 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:41 INFO - 1499299781929 Marionette DEBUG Closed connection 394
17:09:41 INFO - 1499299781930 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49823
17:09:41 INFO - 1499299781932 Marionette DEBUG Closed connection 395
17:09:41 INFO - 1499299781933 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49824
17:09:41 INFO - 1499299781936 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:41 INFO - 1499299781938 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:41 INFO - 1499299781962 Marionette DEBUG Register listener.js for window 12
17:09:41 INFO - 1499299781974 Marionette TRACE 396 <- [1,1,null,{"sessionId":"02464d2c-a7cc-4c5f-abf5-f8f92c71ba7a","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:41 INFO - 1499299781976 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
17:09:41 INFO - 1499299781978 Marionette TRACE 396 <- [1,2,null,{}]
17:09:41 INFO - 1499299781980 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
17:09:41 INFO - 1499299781981 Marionette TRACE 396 <- [1,3,null,{}]
17:09:41 INFO - 1499299781983 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
17:09:41 INFO - 1499299781984 Marionette TRACE 396 <- [1,4,null,{}]
17:09:41 INFO - 1499299781987 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}]
17:09:41 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499299781993 Marionette TRACE 396 <- [1,5,null,{"value":null}]
17:09:41 INFO - 1499299781995 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
17:09:41 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:42 INFO - 1499299781999 Marionette TRACE 396 <- [1,6,null,{}]
17:09:42 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:42 INFO - 1499299782006 Marionette DEBUG Closed connection 396
17:09:42 INFO - 1499299782007 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49825
17:09:42 INFO - 1499299782009 Marionette DEBUG Closed connection 397
17:09:42 INFO - 1499299782010 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49826
17:09:42 INFO - 1499299782013 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
17:09:42 INFO - 1499299782015 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:42 INFO - 1499299782038 Marionette DEBUG Register listener.js for window 12
17:09:42 INFO - 1499299782049 Marionette TRACE 398 <- [1,1,null,{"sessionId":"5378b5dd-286a-4c2a-b112-2618dcbbd1d0","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:42 INFO - 1499299782052 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
17:09:42 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:42 INFO - 1499299782056 Marionette TRACE 398 <- [1,2,null,{}]
17:09:42 INFO - [2868] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
17:09:42 INFO - 1499299782061 Marionette DEBUG Closed connection 398
17:09:42 INFO - 1499299782063 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49827
17:09:42 INFO - 1499299782064 Marionette DEBUG Closed connection 399
17:09:42 INFO - 1499299782066 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49828
17:09:42 INFO - 1499299782068 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
17:09:42 INFO - 1499299782070 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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}
17:09:42 INFO - 1499299782093 Marionette DEBUG Register listener.js for window 12
17:09:42 INFO - 1499299782104 Marionette TRACE 400 <- [1,1,null,{"sessionId":"393a5f96-e797-43a4-9656-a87d2a4ee763","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":2868,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmpttzwru.mozrunner","moz:accessibilityChecks":false}}]
17:09:42 INFO - 1499299782107 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"}]
17:09:42 INFO - 1499299782111 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"
17:09:42 INFO - 1499299782116 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"
17:09:42 INFO - 1499299782117 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"
17:09:42 INFO - ++DOMWINDOW == 29 (00000066F4830000) [pid = 2868] [serial = 52] [outer = 0000006682A2A800]
17:09:42 INFO - 1499299782169 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"
17:09:42 INFO - 1499299782174 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"
17:09:42 INFO - 1499299782180 Marionette TRACE 400 <- [1,2,null,{}]
17:09:42 INFO - 1499299782190 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
17:09:42 INFO - 1499299782196 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"576a7746-c79d-4e5e-a84b-f1c6c7783438","ELEMENT":"576a7746-c79d-4e5e-a84b-f1c6c7783438"}}]
17:09:42 INFO - 1499299782199 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"576a7746-c79d-4e5e-a84b-f1c6c7783438"}]
17:09:42 INFO - 1499299782329 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
17:09:42 INFO - 1499299782332 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"576a7746-c79d-4e5e-a84b-f1c6c7783438"}]
17:09:42 INFO - 1499299782443 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element