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]
18:31:05 INFO - 1499304665335 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}]
18:31:05 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499304665344 Marionette TRACE 352 <- [1,5,null,{"value":null}]
18:31:05 INFO - 1499304665348 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
18:31:05 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:05 INFO - 1499304665353 Marionette TRACE 352 <- [1,6,null,{}]
18:31:05 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 802ms
18:31:05 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
18:31:05 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:05 INFO - 1499304665360 Marionette DEBUG Closed connection 352
18:31:05 INFO - 1499304665362 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49776
18:31:05 INFO - 1499304665364 Marionette DEBUG Closed connection 353
18:31:05 INFO - 1499304665366 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49777
18:31:05 INFO - 1499304665369 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:05 INFO - 1499304665372 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:05 INFO - 1499304665396 Marionette DEBUG Register listener.js for window 12
18:31:05 INFO - 1499304665413 Marionette TRACE 354 <- [1,1,null,{"sessionId":"853ae868-e6c1-4191-b2f8-51031234c7ad","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:05 INFO - 1499304665431 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
18:31:05 INFO - 1499304665433 Marionette TRACE 354 <- [1,2,null,{}]
18:31:05 INFO - 1499304665461 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:31:05 INFO - 1499304665463 Marionette TRACE 354 <- [1,3,null,{}]
18:31:05 INFO - 1499304665466 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
18:31:05 INFO - 1499304665468 Marionette TRACE 354 <- [1,4,null,{}]
18:31:05 INFO - 1499304665472 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}]
18:31:05 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499304665487 Marionette TRACE 354 <- [1,5,null,{"value":null}]
18:31:05 INFO - 1499304665491 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
18:31:05 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:05 INFO - 1499304665495 Marionette TRACE 354 <- [1,6,null,{}]
18:31:05 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:05 INFO - 1499304665504 Marionette DEBUG Closed connection 354
18:31:05 INFO - 1499304665507 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49778
18:31:05 INFO - 1499304665527 Marionette DEBUG Closed connection 355
18:31:05 INFO - 1499304665529 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49779
18:31:05 INFO - 1499304665532 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:05 INFO - 1499304665536 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:05 INFO - 1499304665557 Marionette DEBUG Register listener.js for window 12
18:31:05 INFO - 1499304665594 Marionette TRACE 356 <- [1,1,null,{"sessionId":"c9ccc367-0700-4318-a466-2362b23a2cf6","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:05 INFO - 1499304665602 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
18:31:05 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:05 INFO - 1499304665608 Marionette TRACE 356 <- [1,2,null,{}]
18:31:05 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:05 INFO - 1499304665623 Marionette DEBUG Closed connection 356
18:31:05 INFO - 1499304665628 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49780
18:31:05 INFO - --DOMWINDOW == 27 (00000013E8CC5800) [pid = 2732] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49326/test_accessibility.html]
18:31:05 INFO - --DOMWINDOW == 26 (00000013E835A000) [pid = 2732] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49326/test_accessibility.html]
18:31:05 INFO - --DOMWINDOW == 25 (00000013DE982000) [pid = 2732] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49326/test_accessibility.html]
18:31:05 INFO - --DOMWINDOW == 24 (00000013DE97A000) [pid = 2732] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49326/test_accessibility.html]
18:31:05 INFO - --DOMWINDOW == 23 (00000013E0908000) [pid = 2732] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49326/test_accessibility.html]
18:31:05 INFO - 1499304665655 Marionette DEBUG Closed connection 357
18:31:05 INFO - 1499304665657 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49781
18:31:05 INFO - 1499304665661 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:31:05 INFO - 1499304665663 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:05 INFO - 1499304665685 Marionette DEBUG Register listener.js for window 12
18:31:05 INFO - 1499304665697 Marionette TRACE 358 <- [1,1,null,{"sessionId":"305127d0-9882-4b30-a79b-434e6afaa3d1","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:05 INFO - 1499304665700 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49326/clicks.html"}]
18:31:05 INFO - 1499304665704 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/hidden.html"
18:31:05 INFO - 1499304665717 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/hidden.html"
18:31:05 INFO - 1499304665718 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/hidden.html"
18:31:05 INFO - ++DOMWINDOW == 24 (00000013D8FBB800) [pid = 2732] [serial = 44] [outer = 00000013E4614800]
18:31:05 INFO - 1499304665788 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49326/clicks.html"
18:31:05 INFO - 1499304665793 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49326/clicks.html"
18:31:05 INFO - 1499304665815 Marionette TRACE 358 <- [1,2,null,{}]
18:31:05 INFO - 1499304665818 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
18:31:05 INFO - 1499304665829 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3f19dcc7-ffc5-4661-a1d0-c55f4eb0b043","ELEMENT":"3f19dcc7-ffc5-4661-a1d0-c55f4eb0b043"}}]
18:31:05 INFO - 1499304665831 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"3f19dcc7-ffc5-4661-a1d0-c55f4eb0b043"}]
18:31:06 INFO - 1499304666081 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/clicks.html"
18:31:06 INFO - [2732] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 445
18:31:06 INFO - 1499304666102 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/clicks.html"
18:31:06 INFO - 1499304666103 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/clicks.html"
18:31:06 INFO - ++DOMWINDOW == 25 (00000013E13A6000) [pid = 2732] [serial = 45] [outer = 00000013E4614800]
18:31:06 INFO - 1499304666172 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49326/test.html"
18:31:06 INFO - 1499304666177 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49326/test.html"
18:31:06 INFO - 1499304666193 Marionette TRACE 358 <- [1,4,null,{}]
18:31:06 INFO - 1499304666298 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
18:31:06 INFO - 1499304666306 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"327238b8-eb07-4591-9bf8-b2d95ecf31fe","ELEMENT":"327238b8-eb07-4591-9bf8-b2d95ecf31fe"}}]
18:31:06 INFO - 1499304666308 Marionette TRACE 358 -> [0,6,"getTitle",{}]
18:31:06 INFO - 1499304666310 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
18:31:06 INFO - 1499304666312 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}]
18:31:06 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499304666320 Marionette TRACE 358 <- [1,7,null,{"value":null}]
18:31:06 INFO - 1499304666322 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
18:31:06 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:06 INFO - 1499304666326 Marionette TRACE 358 <- [1,8,null,{}]
18:31:06 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 972ms
18:31:06 INFO - TEST-START | test_click.py TestClick.test_container_element
18:31:06 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:06 INFO - 1499304666332 Marionette DEBUG Closed connection 358
18:31:06 INFO - 1499304666333 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49784
18:31:06 INFO - 1499304666394 Marionette DEBUG Closed connection 359
18:31:06 INFO - 1499304666396 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49785
18:31:06 INFO - 1499304666398 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:06 INFO - 1499304666400 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:06 INFO - 1499304666421 Marionette DEBUG Register listener.js for window 12
18:31:06 INFO - 1499304666434 Marionette TRACE 360 <- [1,1,null,{"sessionId":"580aca04-bdd2-4959-b589-acf18e534965","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:06 INFO - 1499304666438 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
18:31:06 INFO - 1499304666440 Marionette TRACE 360 <- [1,2,null,{}]
18:31:06 INFO - 1499304666442 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:31:06 INFO - 1499304666443 Marionette TRACE 360 <- [1,3,null,{}]
18:31:06 INFO - 1499304666447 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
18:31:06 INFO - 1499304666448 Marionette TRACE 360 <- [1,4,null,{}]
18:31:06 INFO - 1499304666456 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}]
18:31:06 INFO - TEST-START: test_click.py TestClick.test_container_element1499304666465 Marionette TRACE 360 <- [1,5,null,{"value":null}]
18:31:06 INFO - 1499304666472 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
18:31:06 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:06 INFO - 1499304666476 Marionette TRACE 360 <- [1,6,null,{}]
18:31:06 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:06 INFO - 1499304666498 Marionette DEBUG Closed connection 360
18:31:06 INFO - 1499304666500 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49786
18:31:07 INFO - 1499304667276 Marionette DEBUG Closed connection 361
18:31:07 INFO - 1499304667278 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49787
18:31:07 INFO - 1499304667296 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:07 INFO - 1499304667299 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:07 INFO - 1499304667320 Marionette DEBUG Register listener.js for window 12
18:31:07 INFO - 1499304667332 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f83e153b-5f95-49a1-b080-2abbb9a9567a","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:07 INFO - 1499304667335 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
18:31:07 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:07 INFO - 1499304667340 Marionette TRACE 362 <- [1,2,null,{}]
18:31:07 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:07 INFO - 1499304667396 Marionette DEBUG Closed connection 362
18:31:07 INFO - 1499304667398 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49788
18:31:07 INFO - 1499304667402 Marionette DEBUG Closed connection 363
18:31:07 INFO - 1499304667403 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49789
18:31:07 INFO - 1499304667451 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:31:07 INFO - 1499304667454 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:07 INFO - 1499304667475 Marionette DEBUG Register listener.js for window 12
18:31:07 INFO - 1499304667488 Marionette TRACE 364 <- [1,1,null,{"sessionId":"3be6f406-a76e-4a2c-9b5c-3cde18bc0bb0","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:07 INFO - 1499304667541 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"}]
18:31:07 INFO - 1499304667545 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49326/test.html"
18:31:07 INFO - 1499304667554 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49326/test.html"
18:31:07 INFO - 1499304667555 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49326/test.html"
18:31:07 INFO - ++DOMWINDOW == 26 (00000013E85C1800) [pid = 2732] [serial = 46] [outer = 00000013E4614800]
18:31:07 INFO - 1499304667671 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"
18:31:07 INFO - 1499304667678 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"
18:31:07 INFO - 1499304667695 Marionette TRACE 364 <- [1,2,null,{}]
18:31:07 INFO - 1499304667749 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:31:07 INFO - 1499304667756 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"dc83bf1c-3d70-4b44-b2c3-3e26dc4400ae","ELEMENT":"dc83bf1c-3d70-4b44-b2c3-3e26dc4400ae"}}]
18:31:07 INFO - 1499304667758 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"dc83bf1c-3d70-4b44-b2c3-3e26dc4400ae"}]
18:31:08 INFO - [2732] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
18:31:08 INFO - [2732] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
18:31:08 INFO - [2732] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
18:31:08 INFO - 1499304668231 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:31:08 INFO - 1499304668233 Marionette TRACE 364 <- [1,4,null,{}]
18:31:08 INFO - 1499304668236 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"dc83bf1c-3d70-4b44-b2c3-3e26dc4400ae","name":"selected"}]
18:31:08 INFO - 1499304668264 Marionette TRACE 364 <- [1,5,null,{"value":true}]
18:31:08 INFO - 1499304668266 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}]
18:31:08 INFO - TEST-END: test_click.py TestClick.test_container_element1499304668275 Marionette TRACE 364 <- [1,6,null,{"value":null}]
18:31:08 INFO - 1499304668277 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
18:31:08 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:08 INFO - 1499304668281 Marionette TRACE 364 <- [1,7,null,{}]
18:31:08 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1955ms
18:31:08 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
18:31:08 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:08 INFO - 1499304668287 Marionette DEBUG Closed connection 364
18:31:08 INFO - 1499304668288 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49790
18:31:08 INFO - 1499304668290 Marionette DEBUG Closed connection 365
18:31:08 INFO - 1499304668291 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49791
18:31:08 INFO - 1499304668293 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:08 INFO - 1499304668297 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:08 INFO - 1499304668323 Marionette DEBUG Register listener.js for window 12
18:31:08 INFO - 1499304668337 Marionette TRACE 366 <- [1,1,null,{"sessionId":"3d447649-3939-4723-b6f1-5b9de91247eb","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:08 INFO - 1499304668340 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
18:31:08 INFO - 1499304668342 Marionette TRACE 366 <- [1,2,null,{}]
18:31:08 INFO - 1499304668344 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:31:08 INFO - 1499304668345 Marionette TRACE 366 <- [1,3,null,{}]
18:31:08 INFO - 1499304668347 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
18:31:08 INFO - 1499304668349 Marionette TRACE 366 <- [1,4,null,{}]
18:31:08 INFO - 1499304668351 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}]
18:31:08 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499304668360 Marionette TRACE 366 <- [1,5,null,{"value":null}]
18:31:08 INFO - 1499304668362 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
18:31:08 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:08 INFO - 1499304668366 Marionette TRACE 366 <- [1,6,null,{}]
18:31:08 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:08 INFO - 1499304668372 Marionette DEBUG Closed connection 366
18:31:08 INFO - 1499304668374 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49792
18:31:08 INFO - 1499304668376 Marionette DEBUG Closed connection 367
18:31:08 INFO - 1499304668377 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49793
18:31:08 INFO - 1499304668379 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:08 INFO - 1499304668382 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:08 INFO - 1499304668405 Marionette DEBUG Register listener.js for window 12
18:31:08 INFO - 1499304668418 Marionette TRACE 368 <- [1,1,null,{"sessionId":"98424f94-cadf-43e5-8b57-f225f96d3816","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:08 INFO - 1499304668421 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
18:31:08 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:08 INFO - 1499304668425 Marionette TRACE 368 <- [1,2,null,{}]
18:31:08 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:08 INFO - 1499304668431 Marionette DEBUG Closed connection 368
18:31:08 INFO - 1499304668433 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49794
18:31:08 INFO - 1499304668434 Marionette DEBUG Closed connection 369
18:31:08 INFO - 1499304668436 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49795
18:31:08 INFO - 1499304668438 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:31:08 INFO - 1499304668441 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:08 INFO - 1499304668462 Marionette DEBUG Register listener.js for window 12
18:31:08 INFO - 1499304668473 Marionette TRACE 370 <- [1,1,null,{"sessionId":"f229cb17-166b-4de6-b077-58c1edc335d4","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:08 INFO - 1499304668476 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"}]
18:31:08 INFO - 1499304668480 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"
18:31:08 INFO - 1499304668486 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"
18:31:08 INFO - 1499304668487 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"
18:31:08 INFO - ++DOMWINDOW == 27 (00000013E3F8C000) [pid = 2732] [serial = 47] [outer = 00000013E4614800]
18:31:08 INFO - 1499304668628 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"
18:31:08 INFO - 1499304668633 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"
18:31:08 INFO - 1499304668644 Marionette TRACE 370 <- [1,2,null,{}]
18:31:08 INFO - 1499304668655 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
18:31:08 INFO - 1499304668662 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"684b87cb-7e8d-4b2e-8b36-830a8994404b","ELEMENT":"684b87cb-7e8d-4b2e-8b36-830a8994404b"}}]
18:31:08 INFO - 1499304668669 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"684b87cb-7e8d-4b2e-8b36-830a8994404b"}]
18:31:08 INFO - [2732] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
18:31:08 INFO - [2732] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
18:31:08 INFO - [2732] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
18:31:09 INFO - 1499304669109 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:31:09 INFO - 1499304669110 Marionette TRACE 370 <- [1,4,null,{}]
18:31:09 INFO - 1499304669113 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"684b87cb-7e8d-4b2e-8b36-830a8994404b","name":"selected"}]
18:31:09 INFO - 1499304669140 Marionette TRACE 370 <- [1,5,null,{"value":true}]
18:31:09 INFO - 1499304669143 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}]
18:31:09 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499304669150 Marionette TRACE 370 <- [1,6,null,{"value":null}]
18:31:09 INFO - 1499304669152 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
18:31:09 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:09 INFO - 1499304669156 Marionette TRACE 370 <- [1,7,null,{}]
18:31:09 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 874ms
18:31:09 INFO - TEST-START | test_click.py TestClick.test_css_transforms
18:31:09 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:09 INFO - 1499304669162 Marionette DEBUG Closed connection 370
18:31:09 INFO - 1499304669164 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49796
18:31:09 INFO - 1499304669165 Marionette DEBUG Closed connection 371
18:31:09 INFO - 1499304669167 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49797
18:31:09 INFO - 1499304669169 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:09 INFO - 1499304669171 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:09 INFO - 1499304669193 Marionette DEBUG Register listener.js for window 12
18:31:09 INFO - 1499304669205 Marionette TRACE 372 <- [1,1,null,{"sessionId":"6212ea66-ce6e-4c2d-89b3-9b12b68ff13a","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:09 INFO - 1499304669209 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
18:31:09 INFO - 1499304669211 Marionette TRACE 372 <- [1,2,null,{}]
18:31:09 INFO - 1499304669244 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:31:09 INFO - 1499304669246 Marionette TRACE 372 <- [1,3,null,{}]
18:31:09 INFO - 1499304669248 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
18:31:09 INFO - 1499304669249 Marionette TRACE 372 <- [1,4,null,{}]
18:31:09 INFO - 1499304669252 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}]
18:31:09 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499304669259 Marionette TRACE 372 <- [1,5,null,{"value":null}]
18:31:09 INFO - 1499304669261 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
18:31:09 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:09 INFO - 1499304669265 Marionette TRACE 372 <- [1,6,null,{}]
18:31:09 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:09 INFO - 1499304669271 Marionette DEBUG Closed connection 372
18:31:09 INFO - 1499304669273 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49798
18:31:09 INFO - 1499304669274 Marionette DEBUG Closed connection 373
18:31:09 INFO - 1499304669276 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49799
18:31:09 INFO - 1499304669278 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:09 INFO - 1499304669280 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:09 INFO - 1499304669302 Marionette DEBUG Register listener.js for window 12
18:31:09 INFO - 1499304669314 Marionette TRACE 374 <- [1,1,null,{"sessionId":"03aa3963-a600-44f0-836a-1507df1841eb","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:09 INFO - 1499304669316 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
18:31:09 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:09 INFO - 1499304669320 Marionette TRACE 374 <- [1,2,null,{}]
18:31:09 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:09 INFO - 1499304669326 Marionette DEBUG Closed connection 374
18:31:09 INFO - 1499304669328 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49800
18:31:09 INFO - 1499304669329 Marionette DEBUG Closed connection 375
18:31:09 INFO - 1499304669331 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49801
18:31:09 INFO - 1499304669333 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:31:09 INFO - 1499304669335 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:09 INFO - 1499304669358 Marionette DEBUG Register listener.js for window 12
18:31:09 INFO - 1499304669370 Marionette TRACE 376 <- [1,1,null,{"sessionId":"9689163c-ef0e-4ac4-8e6d-f41506f1baef","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:09 INFO - 1499304669372 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"}]
18:31:09 INFO - 1499304669376 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"
18:31:09 INFO - 1499304669382 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"
18:31:09 INFO - 1499304669383 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"
18:31:09 INFO - ++DOMWINDOW == 28 (00000013E455C800) [pid = 2732] [serial = 48] [outer = 00000013E4614800]
18:31:09 INFO - 1499304669430 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"
18:31:09 INFO - 1499304669438 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"
18:31:09 INFO - 1499304669453 Marionette TRACE 376 <- [1,2,null,{}]
18:31:09 INFO - 1499304669509 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
18:31:09 INFO - 1499304669515 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"bee9596f-c16b-4903-8501-ce63d8d8a7cc","ELEMENT":"bee9596f-c16b-4903-8501-ce63d8d8a7cc"}}]
18:31:09 INFO - 1499304669517 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"bee9596f-c16b-4903-8501-ce63d8d8a7cc"}]
18:31:09 INFO - 1499304669967 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:31:09 INFO - 1499304669969 Marionette TRACE 376 <- [1,4,null,{}]
18:31:09 INFO - 1499304669973 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}]
18:31:09 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499304669984 Marionette TRACE 376 <- [1,5,null,{"value":null}]
18:31:09 INFO - 1499304669986 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
18:31:09 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:09 INFO - 1499304669990 Marionette TRACE 376 <- [1,6,null,{}]
18:31:09 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 833ms
18:31:09 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
18:31:10 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:10 INFO - 1499304670016 Marionette DEBUG Closed connection 376
18:31:10 INFO - 1499304670017 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49802
18:31:10 INFO - 1499304670019 Marionette DEBUG Closed connection 377
18:31:10 INFO - 1499304670020 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49803
18:31:10 INFO - 1499304670023 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:10 INFO - 1499304670025 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:10 INFO - 1499304670047 Marionette DEBUG Register listener.js for window 12
18:31:10 INFO - 1499304670058 Marionette TRACE 378 <- [1,1,null,{"sessionId":"3f3fe8fa-624b-41f5-a477-195b41e460a1","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:10 INFO - 1499304670066 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
18:31:10 INFO - 1499304670068 Marionette TRACE 378 <- [1,2,null,{}]
18:31:10 INFO - 1499304670070 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:31:10 INFO - 1499304670071 Marionette TRACE 378 <- [1,3,null,{}]
18:31:10 INFO - 1499304670073 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
18:31:10 INFO - 1499304670075 Marionette TRACE 378 <- [1,4,null,{}]
18:31:10 INFO - 1499304670077 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}]
18:31:10 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499304670084 Marionette TRACE 378 <- [1,5,null,{"value":null}]
18:31:10 INFO - 1499304670086 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
18:31:10 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:10 INFO - 1499304670090 Marionette TRACE 378 <- [1,6,null,{}]
18:31:10 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:10 INFO - 1499304670096 Marionette DEBUG Closed connection 378
18:31:10 INFO - 1499304670097 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49804
18:31:10 INFO - 1499304670099 Marionette DEBUG Closed connection 379
18:31:10 INFO - 1499304670103 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49805
18:31:10 INFO - 1499304670106 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:10 INFO - 1499304670108 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:10 INFO - 1499304670131 Marionette DEBUG Register listener.js for window 12
18:31:10 INFO - 1499304670143 Marionette TRACE 380 <- [1,1,null,{"sessionId":"22d330a5-bf8b-42c3-b0b2-d70f60cb145c","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:10 INFO - 1499304670146 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
18:31:10 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:10 INFO - 1499304670150 Marionette TRACE 380 <- [1,2,null,{}]
18:31:10 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:10 INFO - 1499304670156 Marionette DEBUG Closed connection 380
18:31:10 INFO - 1499304670158 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49806
18:31:10 INFO - 1499304670159 Marionette DEBUG Closed connection 381
18:31:10 INFO - 1499304670161 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49807
18:31:10 INFO - 1499304670163 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:31:10 INFO - 1499304670166 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:10 INFO - 1499304670189 Marionette DEBUG Register listener.js for window 12
18:31:10 INFO - 1499304670200 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ea960f2b-beee-4b0c-8680-6012b052d29d","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:10 INFO - 1499304670203 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"}]
18:31:10 INFO - 1499304670207 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"
18:31:10 INFO - 1499304670213 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"
18:31:10 INFO - 1499304670214 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"
18:31:10 INFO - ++DOMWINDOW == 29 (00000013E4DA8800) [pid = 2732] [serial = 49] [outer = 00000013E4614800]
18:31:10 INFO - 1499304670265 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"
18:31:10 INFO - 1499304670270 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"
18:31:10 INFO - 1499304670338 Marionette TRACE 382 <- [1,2,null,{}]
18:31:10 INFO - 1499304670343 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
18:31:10 INFO - 1499304670350 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"84c43bac-ac3e-480d-823e-3c578e89b817","ELEMENT":"84c43bac-ac3e-480d-823e-3c578e89b817"}}]
18:31:10 INFO - 1499304670352 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"84c43bac-ac3e-480d-823e-3c578e89b817"}]
18:31:10 INFO - 1499304670799 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:31:10 INFO - 1499304670803 Marionette TRACE 382 <- [1,4,null,{}]
18:31:10 INFO - 1499304670811 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}]
18:31:10 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499304670825 Marionette TRACE 382 <- [1,5,null,{"value":null}]
18:31:10 INFO - 1499304670831 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
18:31:10 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:10 INFO - 1499304670841 Marionette TRACE 382 <- [1,6,null,{}]
18:31:10 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 852ms
18:31:10 INFO - TEST-START | test_click.py TestClick.test_input_file
18:31:10 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:10 INFO - 1499304670851 Marionette DEBUG Closed connection 382
18:31:10 INFO - 1499304670854 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49808
18:31:10 INFO - 1499304670857 Marionette DEBUG Closed connection 383
18:31:10 INFO - 1499304670859 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49809
18:31:10 INFO - 1499304670865 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:10 INFO - 1499304670876 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:10 INFO - 1499304670919 Marionette DEBUG Register listener.js for window 12
18:31:10 INFO - 1499304670955 Marionette TRACE 384 <- [1,1,null,{"sessionId":"75386ee5-6461-41f7-9292-f090a8d3cd50","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:10 INFO - 1499304670983 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
18:31:10 INFO - 1499304670989 Marionette TRACE 384 <- [1,2,null,{}]
18:31:11 INFO - 1499304670992 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:31:11 INFO - 1499304670996 Marionette TRACE 384 <- [1,3,null,{}]
18:31:11 INFO - 1499304670999 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
18:31:11 INFO - 1499304671002 Marionette TRACE 384 <- [1,4,null,{}]
18:31:11 INFO - 1499304671005 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}]
18:31:11 INFO - TEST-START: test_click.py TestClick.test_input_file1499304671019 Marionette TRACE 384 <- [1,5,null,{"value":null}]
18:31:11 INFO - 1499304671024 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
18:31:11 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:11 INFO - 1499304671030 Marionette TRACE 384 <- [1,6,null,{}]
18:31:11 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:11 INFO - 1499304671040 Marionette DEBUG Closed connection 384
18:31:11 INFO - 1499304671044 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49810
18:31:11 INFO - 1499304671047 Marionette DEBUG Closed connection 385
18:31:11 INFO - 1499304671049 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49811
18:31:11 INFO - 1499304671052 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:11 INFO - 1499304671056 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:11 INFO - 1499304671100 Marionette DEBUG Register listener.js for window 12
18:31:11 INFO - 1499304671130 Marionette TRACE 386 <- [1,1,null,{"sessionId":"82ae3153-e9c6-4cd8-a0a8-a4279269c35a","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:11 INFO - 1499304671157 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
18:31:11 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:11 INFO - 1499304671165 Marionette TRACE 386 <- [1,2,null,{}]
18:31:11 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:11 INFO - 1499304671189 Marionette DEBUG Closed connection 386
18:31:11 INFO - 1499304671191 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49812
18:31:11 INFO - 1499304671211 Marionette DEBUG Closed connection 387
18:31:11 INFO - 1499304671213 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49813
18:31:11 INFO - 1499304671249 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:31:11 INFO - 1499304671255 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:11 INFO - 1499304671298 Marionette DEBUG Register listener.js for window 12
18:31:11 INFO - 1499304671315 Marionette TRACE 388 <- [1,1,null,{"sessionId":"cffe9e80-f796-4b31-9c4c-a7364633ed5b","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:11 INFO - 1499304671326 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
18:31:11 INFO - 1499304671332 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"
18:31:11 INFO - 1499304671343 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"
18:31:11 INFO - 1499304671345 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"
18:31:11 INFO - ++DOMWINDOW == 30 (00000013E3D6A800) [pid = 2732] [serial = 50] [outer = 00000013E4614800]
18:31:11 INFO - 1499304671417 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:31:11 INFO - 1499304671437 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:31:11 INFO - 1499304671454 Marionette TRACE 388 <- [1,2,null,{}]
18:31:11 INFO - 1499304671462 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
18:31:11 INFO - 1499304671474 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d4141af5-1b68-4b8c-9ed9-b8c3b7782dc8","ELEMENT":"d4141af5-1b68-4b8c-9ed9-b8c3b7782dc8"}}]
18:31:11 INFO - 1499304671476 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"d4141af5-1b68-4b8c-9ed9-b8c3b7782dc8"}]
18:31:11 INFO - 1499304671732 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]
18:31:11 INFO - 1499304671748 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}]
18:31:11 INFO - TEST-END: test_click.py TestClick.test_input_file1499304671759 Marionette TRACE 388 <- [1,5,null,{"value":null}]
18:31:11 INFO - 1499304671762 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
18:31:11 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:11 INFO - 1499304671767 Marionette TRACE 388 <- [1,6,null,{}]
18:31:11 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 925ms
18:31:11 INFO - TEST-START | test_click.py TestClick.test_obscured_element
18:31:11 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:11 INFO - 1499304671824 Marionette DEBUG Closed connection 388
18:31:11 INFO - 1499304671826 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49814
18:31:11 INFO - 1499304671852 Marionette DEBUG Closed connection 389
18:31:11 INFO - 1499304671855 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49815
18:31:11 INFO - 1499304671858 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:11 INFO - 1499304671861 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:11 INFO - 1499304671905 Marionette DEBUG Register listener.js for window 12
18:31:11 INFO - 1499304671922 Marionette TRACE 390 <- [1,1,null,{"sessionId":"318e40e9-d6b1-46b8-a934-3bcc71153e9d","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:11 INFO - 1499304671957 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
18:31:11 INFO - 1499304671959 Marionette TRACE 390 <- [1,2,null,{}]
18:31:11 INFO - 1499304671967 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:31:11 INFO - 1499304671970 Marionette TRACE 390 <- [1,3,null,{}]
18:31:11 INFO - 1499304671972 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
18:31:11 INFO - 1499304671975 Marionette TRACE 390 <- [1,4,null,{}]
18:31:11 INFO - 1499304671986 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}]
18:31:12 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499304671998 Marionette TRACE 390 <- [1,5,null,{"value":null}]
18:31:12 INFO - 1499304672000 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
18:31:12 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:12 INFO - 1499304672005 Marionette TRACE 390 <- [1,6,null,{}]
18:31:12 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:12 INFO - 1499304672013 Marionette DEBUG Closed connection 390
18:31:12 INFO - 1499304672015 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49816
18:31:12 INFO - 1499304672017 Marionette DEBUG Closed connection 391
18:31:12 INFO - 1499304672019 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49817
18:31:12 INFO - 1499304672045 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:12 INFO - 1499304672048 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:12 INFO - 1499304672093 Marionette DEBUG Register listener.js for window 12
18:31:12 INFO - 1499304672113 Marionette TRACE 392 <- [1,1,null,{"sessionId":"0245bc3c-3918-4f84-bb6f-a9087e73b477","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:12 INFO - 1499304672131 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
18:31:12 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:12 INFO - 1499304672138 Marionette TRACE 392 <- [1,2,null,{}]
18:31:12 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:12 INFO - 1499304672165 Marionette DEBUG Closed connection 392
18:31:12 INFO - 1499304672171 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49818
18:31:12 INFO - 1499304672173 Marionette DEBUG Closed connection 393
18:31:12 INFO - 1499304672175 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49819
18:31:12 INFO - 1499304672178 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:31:12 INFO - 1499304672183 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:12 INFO - 1499304672228 Marionette DEBUG Register listener.js for window 12
18:31:12 INFO - 1499304672256 Marionette TRACE 394 <- [1,1,null,{"sessionId":"ac28efc1-284f-4b3e-aab5-417b2ee63c7d","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:12 INFO - 1499304672266 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"}]
18:31:12 INFO - 1499304672272 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:31:12 INFO - 1499304672282 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:31:12 INFO - 1499304672283 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
18:31:12 INFO - ++DOMWINDOW == 31 (00000013EAD60000) [pid = 2732] [serial = 51] [outer = 00000013E4614800]
18:31:12 INFO - 1499304672393 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"
18:31:12 INFO - 1499304672401 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"
18:31:12 INFO - 1499304672421 Marionette TRACE 394 <- [1,2,null,{}]
18:31:12 INFO - 1499304672428 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
18:31:12 INFO - 1499304672437 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4e7b76bf-ce18-4172-b7c2-c937752fcea3","ELEMENT":"4e7b76bf-ce18-4172-b7c2-c937752fcea3"}}]
18:31:12 INFO - 1499304672439 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
18:31:12 INFO - 1499304672450 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b4373a16-5c83-4794-bf98-fb8d5679508a","ELEMENT":"b4373a16-5c83-4794-bf98-fb8d5679508a"}}]
18:31:12 INFO - 1499304672452 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"4e7b76bf-ce18-4172-b7c2-c937752fcea3"}]
18:31:13 INFO - 1499304673058 Marionette DEBUG Canceled page load listener because no navigation has been detected
18:31:13 INFO - 1499304673060 Marionette TRACE 394 <- [1,5,null,{}]
18:31:13 INFO - 1499304673064 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"b4373a16-5c83-4794-bf98-fb8d5679508a"}]
18:31:13 INFO - 1499304673339 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]
18:31:13 INFO - 1499304673367 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
18:31:13 INFO - 1499304673377 Marionette TRACE 394 <- [1,7,null,{"value":false}]
18:31:13 INFO - 1499304673380 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}]
18:31:13 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499304673390 Marionette TRACE 394 <- [1,8,null,{"value":null}]
18:31:13 INFO - 1499304673407 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
18:31:13 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:13 INFO - 1499304673412 Marionette TRACE 394 <- [1,9,null,{}]
18:31:13 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1645ms
18:31:13 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
18:31:13 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:13 INFO - 1499304673442 Marionette DEBUG Closed connection 394
18:31:13 INFO - 1499304673444 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49820
18:31:13 INFO - 1499304673469 Marionette DEBUG Closed connection 395
18:31:13 INFO - 1499304673471 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49821
18:31:13 INFO - 1499304673474 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:13 INFO - 1499304673477 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:13 INFO - 1499304673522 Marionette DEBUG Register listener.js for window 12
18:31:13 INFO - 1499304673537 Marionette TRACE 396 <- [1,1,null,{"sessionId":"cfcb3dc3-75d0-4de6-8226-ada1e468c516","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:13 INFO - 1499304673572 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
18:31:13 INFO - 1499304673574 Marionette TRACE 396 <- [1,2,null,{}]
18:31:13 INFO - 1499304673580 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
18:31:13 INFO - 1499304673582 Marionette TRACE 396 <- [1,3,null,{}]
18:31:13 INFO - 1499304673585 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
18:31:13 INFO - 1499304673587 Marionette TRACE 396 <- [1,4,null,{}]
18:31:13 INFO - 1499304673595 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}]
18:31:13 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499304673604 Marionette TRACE 396 <- [1,5,null,{"value":null}]
18:31:13 INFO - 1499304673608 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
18:31:13 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:13 INFO - 1499304673613 Marionette TRACE 396 <- [1,6,null,{}]
18:31:13 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:13 INFO - 1499304673637 Marionette DEBUG Closed connection 396
18:31:13 INFO - 1499304673639 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49822
18:31:13 INFO - 1499304673839 Marionette DEBUG Closed connection 397
18:31:13 INFO - 1499304673842 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49823
18:31:13 INFO - 1499304673850 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
18:31:13 INFO - 1499304673861 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:13 INFO - 1499304673888 Marionette DEBUG Register listener.js for window 12
18:31:13 INFO - 1499304673910 Marionette TRACE 398 <- [1,1,null,{"sessionId":"20a7d072-b197-4d2b-a086-bbd0b8e16be5","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:13 INFO - 1499304673919 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
18:31:13 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:13 INFO - 1499304673925 Marionette TRACE 398 <- [1,2,null,{}]
18:31:13 INFO - [2732] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
18:31:13 INFO - 1499304673983 Marionette DEBUG Closed connection 398
18:31:13 INFO - 1499304673985 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49824
18:31:14 INFO - 1499304674012 Marionette DEBUG Closed connection 399
18:31:14 INFO - 1499304674016 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49825
18:31:14 INFO - 1499304674022 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
18:31:14 INFO - 1499304674028 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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}
18:31:14 INFO - 1499304674054 Marionette DEBUG Register listener.js for window 12
18:31:14 INFO - 1499304674075 Marionette TRACE 400 <- [1,1,null,{"sessionId":"6666069e-40be-4f7c-a0a1-d753b406d9eb","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":2732,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-250.001\\AppData\\Local\\Temp\\tmpd3_6jd.mozrunner","moz:accessibilityChecks":false}}]
18:31:14 INFO - 1499304674107 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"}]
18:31:14 INFO - 1499304674114 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"
18:31:14 INFO - 1499304674125 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"
18:31:14 INFO - 1499304674126 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"
18:31:14 INFO - ++DOMWINDOW == 32 (00000013DF053800) [pid = 2732] [serial = 52] [outer = 00000013E4614800]
18:31:14 INFO - 1499304674190 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"
18:31:14 INFO - 1499304674197 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"
18:31:14 INFO - 1499304674215 Marionette TRACE 400 <- [1,2,null,{}]
18:31:14 INFO - 1499304674218 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
18:31:14 INFO - 1499304674236 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"485ebefb-c933-49c0-a756-d56a2141e5a7","ELEMENT":"485ebefb-c933-49c0-a756-d56a2141e5a7"}}]
18:31:14 INFO - 1499304674246 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"485ebefb-c933-49c0-a756-d56a2141e5a7"}]
18:31:14 INFO - 1499304674383 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
18:31:14 INFO - 1499304674396 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"485ebefb-c933-49c0-a756-d56a2141e5a7"}]
18:31:14 INFO - 1499304674553 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element