could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
08:35:01 INFO - 1499960101359 Marionette TRACE 353 -> [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}]
08:35:01 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499960101367 Marionette TRACE 353 <- [1,5,null,{"value":null}]
08:35:01 INFO - 1499960101368 Marionette TRACE 353 -> [0,6,"deleteSession",{}]
08:35:01 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:01 INFO - 1499960101373 Marionette TRACE 353 <- [1,6,null,{}]
08:35:01 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1906ms
08:35:01 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
08:35:01 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:01 INFO - 1499960101378 Marionette DEBUG Closed connection 353
08:35:01 INFO - 1499960101380 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49798
08:35:01 INFO - 1499960101381 Marionette DEBUG Closed connection 354
08:35:01 INFO - 1499960101382 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49799
08:35:01 INFO - 1499960101385 Marionette TRACE 355 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:01 INFO - 1499960101387 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:01 INFO - 1499960101408 Marionette DEBUG Register listener.js for window 15
08:35:01 INFO - 1499960101419 Marionette TRACE 355 <- [1,1,null,{"sessionId":"afbbee0d-9618-4a44-97e7-a9fabfdf0973","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:01 INFO - 1499960101470 Marionette TRACE 355 -> [0,2,"setTimeouts",{"script":30000}]
08:35:01 INFO - 1499960101472 Marionette TRACE 355 <- [1,2,null,{}]
08:35:01 INFO - 1499960101479 Marionette TRACE 355 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:35:01 INFO - 1499960101481 Marionette TRACE 355 <- [1,3,null,{}]
08:35:01 INFO - 1499960101483 Marionette TRACE 355 -> [0,4,"setTimeouts",{"implicit":0}]
08:35:01 INFO - 1499960101485 Marionette TRACE 355 <- [1,4,null,{}]
08:35:01 INFO - 1499960101490 Marionette TRACE 355 -> [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}]
08:35:01 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499960101497 Marionette TRACE 355 <- [1,5,null,{"value":null}]
08:35:01 INFO - 1499960101499 Marionette TRACE 355 -> [0,6,"deleteSession",{}]
08:35:01 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:01 INFO - 1499960101507 Marionette TRACE 355 <- [1,6,null,{}]
08:35:01 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:01 INFO - 1499960101532 Marionette DEBUG Closed connection 355
08:35:01 INFO - 1499960101533 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49800
08:35:01 INFO - 1499960101535 Marionette DEBUG Closed connection 356
08:35:01 INFO - 1499960101536 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49801
08:35:01 INFO - 1499960101539 Marionette TRACE 357 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:01 INFO - 1499960101541 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:01 INFO - 1499960101562 Marionette DEBUG Register listener.js for window 15
08:35:01 INFO - 1499960101574 Marionette TRACE 357 <- [1,1,null,{"sessionId":"0fbf4465-61fc-49ae-a707-c48d281c7dd6","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:01 INFO - 1499960101587 Marionette TRACE 357 -> [0,2,"deleteSession",{}]
08:35:01 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:01 INFO - 1499960101591 Marionette TRACE 357 <- [1,2,null,{}]
08:35:01 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:01 INFO - 1499960101613 Marionette DEBUG Closed connection 357
08:35:01 INFO - 1499960101614 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49802
08:35:01 INFO - 1499960101618 Marionette DEBUG Closed connection 358
08:35:01 INFO - 1499960101620 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49803
08:35:01 INFO - 1499960101622 Marionette TRACE 359 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:35:01 INFO - 1499960101624 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:01 INFO - 1499960101646 Marionette DEBUG Register listener.js for window 15
08:35:01 INFO - 1499960101657 Marionette TRACE 359 <- [1,1,null,{"sessionId":"4bec7ee1-a24a-4bc6-9bf0-5dbbaf140e52","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:01 INFO - 1499960101666 Marionette TRACE 359 -> [0,2,"get",{"url":"http://127.0.0.1:49337/clicks.html"}]
08:35:01 INFO - 1499960101670 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49337/hidden.html"
08:35:01 INFO - 1499960101682 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49337/hidden.html"
08:35:01 INFO - 1499960101683 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49337/hidden.html"
08:35:01 INFO - ++DOMWINDOW == 28 (00000088F2928800) [pid = 1764] [serial = 40] [outer = 00000088EAC86800]
08:35:01 INFO - 1499960101751 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49337/clicks.html"
08:35:01 INFO - 1499960101756 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49337/clicks.html"
08:35:01 INFO - 1499960101775 Marionette TRACE 359 <- [1,2,null,{}]
08:35:01 INFO - 1499960101782 Marionette TRACE 359 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
08:35:01 INFO - 1499960101788 Marionette TRACE 359 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f46885f9-0e51-4b54-a77c-c8d8b3076eb3","ELEMENT":"f46885f9-0e51-4b54-a77c-c8d8b3076eb3"}}]
08:35:01 INFO - 1499960101790 Marionette TRACE 359 -> [0,4,"clickElement",{"id":"f46885f9-0e51-4b54-a77c-c8d8b3076eb3"}]
08:35:02 INFO - 1499960102042 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49337/clicks.html"
08:35:02 INFO - [Parent 1764] 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 446
08:35:02 INFO - 1499960102066 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49337/clicks.html"
08:35:02 INFO - 1499960102068 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49337/clicks.html"
08:35:02 INFO - ++DOMWINDOW == 29 (00000088F2967000) [pid = 1764] [serial = 41] [outer = 00000088EAC86800]
08:35:02 INFO - 1499960102138 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49337/test.html"
08:35:02 INFO - 1499960102149 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49337/test.html"
08:35:02 INFO - 1499960102166 Marionette TRACE 359 <- [1,4,null,{}]
08:35:02 INFO - 1499960102177 Marionette TRACE 359 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
08:35:02 INFO - 1499960102183 Marionette TRACE 359 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"01ed42d1-686e-40bb-a7ae-d206e79abb7d","ELEMENT":"01ed42d1-686e-40bb-a7ae-d206e79abb7d"}}]
08:35:02 INFO - 1499960102185 Marionette TRACE 359 -> [0,6,"getTitle",{}]
08:35:02 INFO - 1499960102187 Marionette TRACE 359 <- [1,6,null,{"value":"Marionette Test"}]
08:35:02 INFO - 1499960102189 Marionette TRACE 359 -> [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}]
08:35:02 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499960102196 Marionette TRACE 359 <- [1,7,null,{"value":null}]
08:35:02 INFO - 1499960102198 Marionette TRACE 359 -> [0,8,"deleteSession",{}]
08:35:02 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:02 INFO - 1499960102202 Marionette TRACE 359 <- [1,8,null,{}]
08:35:02 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 829ms
08:35:02 INFO - TEST-START | test_click.py TestClick.test_container_element
08:35:02 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:02 INFO - 1499960102208 Marionette DEBUG Closed connection 359
08:35:02 INFO - 1499960102210 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49806
08:35:02 INFO - 1499960102211 Marionette DEBUG Closed connection 360
08:35:02 INFO - 1499960102213 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49807
08:35:02 INFO - 1499960102215 Marionette TRACE 361 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:02 INFO - 1499960102217 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:02 INFO - 1499960102240 Marionette DEBUG Register listener.js for window 15
08:35:02 INFO - 1499960102251 Marionette TRACE 361 <- [1,1,null,{"sessionId":"a4f43701-293f-4ab2-9446-371b4b61b7cc","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:02 INFO - 1499960102257 Marionette TRACE 361 -> [0,2,"setTimeouts",{"script":30000}]
08:35:02 INFO - 1499960102258 Marionette TRACE 361 <- [1,2,null,{}]
08:35:02 INFO - 1499960102286 Marionette TRACE 361 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:35:02 INFO - 1499960102287 Marionette TRACE 361 <- [1,3,null,{}]
08:35:02 INFO - 1499960102290 Marionette TRACE 361 -> [0,4,"setTimeouts",{"implicit":0}]
08:35:02 INFO - 1499960102291 Marionette TRACE 361 <- [1,4,null,{}]
08:35:02 INFO - 1499960102294 Marionette TRACE 361 -> [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}]
08:35:02 INFO - TEST-START: test_click.py TestClick.test_container_element1499960102301 Marionette TRACE 361 <- [1,5,null,{"value":null}]
08:35:02 INFO - 1499960102303 Marionette TRACE 361 -> [0,6,"deleteSession",{}]
08:35:02 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:02 INFO - 1499960102307 Marionette TRACE 361 <- [1,6,null,{}]
08:35:02 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:02 INFO - 1499960102313 Marionette DEBUG Closed connection 361
08:35:02 INFO - 1499960102315 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49808
08:35:02 INFO - 1499960102337 Marionette DEBUG Closed connection 362
08:35:02 INFO - 1499960102338 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49809
08:35:02 INFO - 1499960102341 Marionette TRACE 363 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:02 INFO - 1499960102343 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:02 INFO - 1499960102365 Marionette DEBUG Register listener.js for window 15
08:35:02 INFO - 1499960102376 Marionette TRACE 363 <- [1,1,null,{"sessionId":"e2fb488d-e9bb-49ac-834a-969437d86288","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:02 INFO - 1499960102385 Marionette TRACE 363 -> [0,2,"deleteSession",{}]
08:35:02 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:02 INFO - 1499960102389 Marionette TRACE 363 <- [1,2,null,{}]
08:35:02 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:02 INFO - 1499960102411 Marionette DEBUG Closed connection 363
08:35:02 INFO - 1499960102412 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49810
08:35:02 INFO - 1499960102417 Marionette DEBUG Closed connection 364
08:35:02 INFO - 1499960102418 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49811
08:35:02 INFO - 1499960102421 Marionette TRACE 365 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:35:02 INFO - 1499960102423 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:02 INFO - 1499960102445 Marionette DEBUG Register listener.js for window 15
08:35:02 INFO - 1499960102456 Marionette TRACE 365 <- [1,1,null,{"sessionId":"e80d5759-5f4d-4ddf-89a2-a69d1c7309e5","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:02 INFO - 1499960102465 Marionette TRACE 365 -> [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"}]
08:35:02 INFO - 1499960102469 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49337/test.html"
08:35:02 INFO - 1499960102476 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49337/test.html"
08:35:02 INFO - 1499960102477 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49337/test.html"
08:35:02 INFO - ++DOMWINDOW == 30 (00000088E726C000) [pid = 1764] [serial = 42] [outer = 00000088EAC86800]
08:35:02 INFO - 1499960102546 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"
08:35:02 INFO - 1499960102551 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"
08:35:02 INFO - 1499960102568 Marionette TRACE 365 <- [1,2,null,{}]
08:35:02 INFO - 1499960102576 Marionette TRACE 365 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
08:35:02 INFO - 1499960102583 Marionette TRACE 365 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"42b9f411-a631-4dae-bbb2-1df1cfe65b7a","ELEMENT":"42b9f411-a631-4dae-bbb2-1df1cfe65b7a"}}]
08:35:02 INFO - 1499960102585 Marionette TRACE 365 -> [0,4,"clickElement",{"id":"42b9f411-a631-4dae-bbb2-1df1cfe65b7a"}]
08:35:02 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
08:35:02 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
08:35:02 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
08:35:03 INFO - 1499960103031 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:35:03 INFO - 1499960103033 Marionette TRACE 365 <- [1,4,null,{}]
08:35:03 INFO - 1499960103035 Marionette TRACE 365 -> [0,5,"getElementProperty",{"id":"42b9f411-a631-4dae-bbb2-1df1cfe65b7a","name":"selected"}]
08:35:03 INFO - 1499960103071 Marionette TRACE 365 <- [1,5,null,{"value":true}]
08:35:03 INFO - 1499960103075 Marionette TRACE 365 -> [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}]
08:35:03 INFO - TEST-END: test_click.py TestClick.test_container_element1499960103082 Marionette TRACE 365 <- [1,6,null,{"value":null}]
08:35:03 INFO - 1499960103084 Marionette TRACE 365 -> [0,7,"deleteSession",{}]
08:35:03 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:03 INFO - 1499960103088 Marionette TRACE 365 <- [1,7,null,{}]
08:35:03 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 886ms
08:35:03 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
08:35:03 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:03 INFO - 1499960103094 Marionette DEBUG Closed connection 365
08:35:03 INFO - 1499960103096 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49812
08:35:03 INFO - 1499960103097 Marionette DEBUG Closed connection 366
08:35:03 INFO - 1499960103099 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49813
08:35:03 INFO - 1499960103129 Marionette TRACE 367 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:03 INFO - 1499960103131 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:03 INFO - 1499960103153 Marionette DEBUG Register listener.js for window 15
08:35:03 INFO - 1499960103167 Marionette TRACE 367 <- [1,1,null,{"sessionId":"e3f21e0f-508a-478c-b0d0-5bd9e4e1ff31","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:03 INFO - 1499960103176 Marionette TRACE 367 -> [0,2,"setTimeouts",{"script":30000}]
08:35:03 INFO - 1499960103177 Marionette TRACE 367 <- [1,2,null,{}]
08:35:03 INFO - 1499960103180 Marionette TRACE 367 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:35:03 INFO - 1499960103181 Marionette TRACE 367 <- [1,3,null,{}]
08:35:03 INFO - 1499960103183 Marionette TRACE 367 -> [0,4,"setTimeouts",{"implicit":0}]
08:35:03 INFO - 1499960103185 Marionette TRACE 367 <- [1,4,null,{}]
08:35:03 INFO - 1499960103187 Marionette TRACE 367 -> [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}]
08:35:03 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499960103194 Marionette TRACE 367 <- [1,5,null,{"value":null}]
08:35:03 INFO - 1499960103196 Marionette TRACE 367 -> [0,6,"deleteSession",{}]
08:35:03 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:03 INFO - 1499960103200 Marionette TRACE 367 <- [1,6,null,{}]
08:35:03 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:03 INFO - 1499960103207 Marionette DEBUG Closed connection 367
08:35:03 INFO - 1499960103208 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49814
08:35:03 INFO - 1499960103210 Marionette DEBUG Closed connection 368
08:35:03 INFO - 1499960103211 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49815
08:35:03 INFO - 1499960103214 Marionette TRACE 369 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:03 INFO - 1499960103216 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:03 INFO - 1499960103238 Marionette DEBUG Register listener.js for window 15
08:35:03 INFO - 1499960103249 Marionette TRACE 369 <- [1,1,null,{"sessionId":"42870971-a424-44d2-8939-f1ae2a66826a","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:03 INFO - 1499960103278 Marionette TRACE 369 -> [0,2,"deleteSession",{}]
08:35:03 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:03 INFO - 1499960103283 Marionette TRACE 369 <- [1,2,null,{}]
08:35:03 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:03 INFO - 1499960103305 Marionette DEBUG Closed connection 369
08:35:03 INFO - 1499960103306 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49816
08:35:03 INFO - 1499960103310 Marionette DEBUG Closed connection 370
08:35:03 INFO - 1499960103311 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49817
08:35:03 INFO - 1499960103313 Marionette TRACE 371 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:35:03 INFO - 1499960103316 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:03 INFO - 1499960103338 Marionette DEBUG Register listener.js for window 15
08:35:03 INFO - 1499960103349 Marionette TRACE 371 <- [1,1,null,{"sessionId":"ff08be52-d53e-4c63-a050-c9283cc04a46","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:03 INFO - 1499960103359 Marionette TRACE 371 -> [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"}]
08:35:03 INFO - 1499960103364 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"
08:35:03 INFO - 1499960103370 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"
08:35:03 INFO - 1499960103372 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"
08:35:03 INFO - ++DOMWINDOW == 31 (00000088F2937000) [pid = 1764] [serial = 43] [outer = 00000088EAC86800]
08:35:03 INFO - 1499960103414 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"
08:35:03 INFO - 1499960103424 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"
08:35:03 INFO - 1499960103439 Marionette TRACE 371 <- [1,2,null,{}]
08:35:03 INFO - 1499960103461 Marionette TRACE 371 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
08:35:03 INFO - 1499960103468 Marionette TRACE 371 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cbfb9ee7-4464-4e20-aeae-16a0cc0e76b9","ELEMENT":"cbfb9ee7-4464-4e20-aeae-16a0cc0e76b9"}}]
08:35:03 INFO - 1499960103470 Marionette TRACE 371 -> [0,4,"clickElement",{"id":"cbfb9ee7-4464-4e20-aeae-16a0cc0e76b9"}]
08:35:03 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
08:35:03 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
08:35:03 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
08:35:04 INFO - 1499960104395 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:35:04 INFO - 1499960104409 Marionette TRACE 371 <- [1,4,null,{}]
08:35:04 INFO - 1499960104450 Marionette TRACE 371 -> [0,5,"getElementProperty",{"id":"cbfb9ee7-4464-4e20-aeae-16a0cc0e76b9","name":"selected"}]
08:35:04 INFO - 1499960104476 Marionette TRACE 371 <- [1,5,null,{"value":true}]
08:35:04 INFO - 1499960104479 Marionette TRACE 371 -> [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}]
08:35:04 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499960104486 Marionette TRACE 371 <- [1,6,null,{"value":null}]
08:35:04 INFO - 1499960104488 Marionette TRACE 371 -> [0,7,"deleteSession",{}]
08:35:04 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:04 INFO - 1499960104492 Marionette TRACE 371 <- [1,7,null,{}]
08:35:04 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1403ms
08:35:04 INFO - TEST-START | test_click.py TestClick.test_css_transforms
08:35:04 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:04 INFO - 1499960104499 Marionette DEBUG Closed connection 371
08:35:04 INFO - 1499960104500 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49818
08:35:04 INFO - 1499960104502 Marionette DEBUG Closed connection 372
08:35:04 INFO - 1499960104503 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49819
08:35:04 INFO - 1499960104505 Marionette TRACE 373 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:04 INFO - 1499960104507 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:04 INFO - 1499960104530 Marionette DEBUG Register listener.js for window 15
08:35:04 INFO - 1499960104582 Marionette TRACE 373 <- [1,1,null,{"sessionId":"7a87cfe8-c2da-41ff-8de0-e7f2ee3f2bd5","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:04 INFO - 1499960104827 Marionette TRACE 373 -> [0,2,"setTimeouts",{"script":30000}]
08:35:04 INFO - 1499960104832 Marionette TRACE 373 <- [1,2,null,{}]
08:35:04 INFO - 1499960104839 Marionette TRACE 373 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:35:04 INFO - 1499960104842 Marionette TRACE 373 <- [1,3,null,{}]
08:35:04 INFO - 1499960104847 Marionette TRACE 373 -> [0,4,"setTimeouts",{"implicit":0}]
08:35:04 INFO - 1499960104851 Marionette TRACE 373 <- [1,4,null,{}]
08:35:04 INFO - 1499960104856 Marionette TRACE 373 -> [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}]
08:35:04 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499960104869 Marionette TRACE 373 <- [1,5,null,{"value":null}]
08:35:04 INFO - 1499960104872 Marionette TRACE 373 -> [0,6,"deleteSession",{}]
08:35:04 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:04 INFO - 1499960104880 Marionette TRACE 373 <- [1,6,null,{}]
08:35:04 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:04 INFO - 1499960104891 Marionette DEBUG Closed connection 373
08:35:04 INFO - 1499960104895 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49820
08:35:04 INFO - 1499960104899 Marionette DEBUG Closed connection 374
08:35:04 INFO - 1499960104901 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49821
08:35:04 INFO - 1499960104905 Marionette TRACE 375 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:04 INFO - 1499960104914 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:04 INFO - 1499960104963 Marionette DEBUG Register listener.js for window 15
08:35:04 INFO - 1499960104995 Marionette TRACE 375 <- [1,1,null,{"sessionId":"f1050025-001e-421d-86f2-77cde280d5d5","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:05 INFO - 1499960105032 Marionette TRACE 375 -> [0,2,"deleteSession",{}]
08:35:05 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:05 INFO - 1499960105040 Marionette TRACE 375 <- [1,2,null,{}]
08:35:05 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:05 INFO - 1499960105098 Marionette DEBUG Closed connection 375
08:35:05 INFO - 1499960105100 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49822
08:35:05 INFO - 1499960105104 Marionette DEBUG Closed connection 376
08:35:05 INFO - 1499960105106 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49823
08:35:05 INFO - 1499960105128 Marionette TRACE 377 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:35:05 INFO - 1499960105133 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:05 INFO - 1499960105182 Marionette DEBUG Register listener.js for window 15
08:35:05 INFO - 1499960105199 Marionette TRACE 377 <- [1,1,null,{"sessionId":"e05034f4-28d2-47f9-a9a7-c22b1383eb5a","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:05 INFO - 1499960105211 Marionette TRACE 377 -> [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"}]
08:35:05 INFO - 1499960105217 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"
08:35:05 INFO - 1499960105229 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"
08:35:05 INFO - 1499960105230 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"
08:35:05 INFO - ++DOMWINDOW == 32 (00000088DEFA1000) [pid = 1764] [serial = 44] [outer = 00000088EAC86800]
08:35:05 INFO - 1499960105310 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"
08:35:05 INFO - 1499960105319 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"
08:35:05 INFO - 1499960105342 Marionette TRACE 377 <- [1,2,null,{}]
08:35:05 INFO - 1499960105349 Marionette TRACE 377 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
08:35:05 INFO - 1499960105361 Marionette TRACE 377 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"284cae70-8f70-40c8-bb62-ba3e4bd377b2","ELEMENT":"284cae70-8f70-40c8-bb62-ba3e4bd377b2"}}]
08:35:05 INFO - 1499960105369 Marionette TRACE 377 -> [0,4,"clickElement",{"id":"284cae70-8f70-40c8-bb62-ba3e4bd377b2"}]
08:35:05 INFO - 1499960105954 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:35:05 INFO - 1499960105958 Marionette TRACE 377 <- [1,4,null,{}]
08:35:05 INFO - 1499960105962 Marionette TRACE 377 -> [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}]
08:35:05 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499960105974 Marionette TRACE 377 <- [1,5,null,{"value":null}]
08:35:05 INFO - 1499960105978 Marionette TRACE 377 -> [0,6,"deleteSession",{}]
08:35:05 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:05 INFO - 1499960105984 Marionette TRACE 377 <- [1,6,null,{}]
08:35:05 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1492ms
08:35:05 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
08:35:06 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:06 INFO - 1499960106041 Marionette DEBUG Closed connection 377
08:35:06 INFO - 1499960106043 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49824
08:35:06 INFO - 1499960106065 Marionette DEBUG Closed connection 378
08:35:06 INFO - 1499960106068 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49825
08:35:06 INFO - 1499960106070 Marionette TRACE 379 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:06 INFO - 1499960106074 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:06 INFO - 1499960106125 Marionette DEBUG Register listener.js for window 15
08:35:06 INFO - 1499960106144 Marionette TRACE 379 <- [1,1,null,{"sessionId":"7bb9e306-45aa-4009-9c14-519ea13cd779","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:06 INFO - 1499960106152 Marionette TRACE 379 -> [0,2,"setTimeouts",{"script":30000}]
08:35:06 INFO - 1499960106155 Marionette TRACE 379 <- [1,2,null,{}]
08:35:06 INFO - 1499960106162 Marionette TRACE 379 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:35:06 INFO - 1499960106164 Marionette TRACE 379 <- [1,3,null,{}]
08:35:06 INFO - 1499960106166 Marionette TRACE 379 -> [0,4,"setTimeouts",{"implicit":0}]
08:35:06 INFO - 1499960106168 Marionette TRACE 379 <- [1,4,null,{}]
08:35:06 INFO - 1499960106182 Marionette TRACE 379 -> [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}]
08:35:06 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499960106192 Marionette TRACE 379 <- [1,5,null,{"value":null}]
08:35:06 INFO - 1499960106195 Marionette TRACE 379 -> [0,6,"deleteSession",{}]
08:35:06 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:06 INFO - 1499960106200 Marionette TRACE 379 <- [1,6,null,{}]
08:35:06 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:06 INFO - 1499960106226 Marionette DEBUG Closed connection 379
08:35:06 INFO - 1499960106228 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49826
08:35:06 INFO - 1499960106254 Marionette DEBUG Closed connection 380
08:35:06 INFO - 1499960106257 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49827
08:35:06 INFO - 1499960106263 Marionette TRACE 381 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:06 INFO - 1499960106266 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:06 INFO - 1499960106316 Marionette DEBUG Register listener.js for window 15
08:35:06 INFO - 1499960106342 Marionette TRACE 381 <- [1,1,null,{"sessionId":"de6e4d59-45da-4f36-a31d-fd25d9638084","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:06 INFO - 1499960106353 Marionette TRACE 381 -> [0,2,"deleteSession",{}]
08:35:06 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:06 INFO - 1499960106359 Marionette TRACE 381 <- [1,2,null,{}]
08:35:06 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:06 INFO - 1499960106383 Marionette DEBUG Closed connection 381
08:35:06 INFO - 1499960106385 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49828
08:35:06 INFO - 1499960106436 Marionette DEBUG Closed connection 382
08:35:06 INFO - 1499960106438 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49829
08:35:06 INFO - 1499960106444 Marionette TRACE 383 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:35:06 INFO - 1499960106451 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:06 INFO - 1499960106506 Marionette DEBUG Register listener.js for window 15
08:35:06 INFO - 1499960106524 Marionette TRACE 383 <- [1,1,null,{"sessionId":"72395ee1-3047-404a-846c-8554740a35ff","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:06 INFO - 1499960106535 Marionette TRACE 383 -> [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"}]
08:35:06 INFO - 1499960106541 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"
08:35:06 INFO - 1499960106551 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"
08:35:06 INFO - 1499960106552 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"
08:35:06 INFO - ++DOMWINDOW == 33 (00000088EFE1A800) [pid = 1764] [serial = 45] [outer = 00000088EAC86800]
08:35:06 INFO - 1499960106622 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"
08:35:06 INFO - 1499960106628 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"
08:35:06 INFO - 1499960106661 Marionette TRACE 383 <- [1,2,null,{}]
08:35:06 INFO - 1499960106664 Marionette TRACE 383 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
08:35:06 INFO - 1499960106674 Marionette TRACE 383 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e1c55183-ebfe-4727-93bb-fcdf5d694497","ELEMENT":"e1c55183-ebfe-4727-93bb-fcdf5d694497"}}]
08:35:06 INFO - 1499960106676 Marionette TRACE 383 -> [0,4,"clickElement",{"id":"e1c55183-ebfe-4727-93bb-fcdf5d694497"}]
08:35:07 INFO - 1499960107352 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:35:07 INFO - 1499960107356 Marionette TRACE 383 <- [1,4,null,{}]
08:35:07 INFO - 1499960107363 Marionette TRACE 383 -> [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}]
08:35:07 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499960107377 Marionette TRACE 383 <- [1,5,null,{"value":null}]
08:35:07 INFO - 1499960107382 Marionette TRACE 383 -> [0,6,"deleteSession",{}]
08:35:07 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:07 INFO - 1499960107394 Marionette TRACE 383 <- [1,6,null,{}]
08:35:07 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1411ms
08:35:07 INFO - TEST-START | test_click.py TestClick.test_input_file
08:35:07 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:07 INFO - 1499960107453 Marionette DEBUG Closed connection 383
08:35:07 INFO - 1499960107456 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49830
08:35:07 INFO - 1499960107482 Marionette DEBUG Closed connection 384
08:35:07 INFO - 1499960107484 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49831
08:35:07 INFO - 1499960107493 Marionette TRACE 385 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:07 INFO - 1499960107503 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:07 INFO - 1499960107530 Marionette DEBUG Register listener.js for window 15
08:35:07 INFO - 1499960107551 Marionette TRACE 385 <- [1,1,null,{"sessionId":"6977328b-5dfe-4e5c-b155-6852b4e15db7","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:07 INFO - 1499960107601 Marionette TRACE 385 -> [0,2,"setTimeouts",{"script":30000}]
08:35:07 INFO - 1499960107604 Marionette TRACE 385 <- [1,2,null,{}]
08:35:07 INFO - 1499960107611 Marionette TRACE 385 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:35:07 INFO - 1499960107615 Marionette TRACE 385 <- [1,3,null,{}]
08:35:07 INFO - 1499960107618 Marionette TRACE 385 -> [0,4,"setTimeouts",{"implicit":0}]
08:35:07 INFO - 1499960107620 Marionette TRACE 385 <- [1,4,null,{}]
08:35:07 INFO - 1499960107636 Marionette TRACE 385 -> [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}]
08:35:07 INFO - TEST-START: test_click.py TestClick.test_input_file1499960107648 Marionette TRACE 385 <- [1,5,null,{"value":null}]
08:35:07 INFO - 1499960107653 Marionette TRACE 385 -> [0,6,"deleteSession",{}]
08:35:07 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:07 INFO - 1499960107660 Marionette TRACE 385 <- [1,6,null,{}]
08:35:07 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:07 INFO - 1499960107670 Marionette DEBUG Closed connection 385
08:35:07 INFO - 1499960107673 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49832
08:35:07 INFO - 1499960107695 Marionette DEBUG Closed connection 386
08:35:07 INFO - 1499960107697 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49833
08:35:07 INFO - 1499960107732 Marionette TRACE 387 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:07 INFO - 1499960107735 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:07 INFO - 1499960107761 Marionette DEBUG Register listener.js for window 15
08:35:07 INFO - 1499960107778 Marionette TRACE 387 <- [1,1,null,{"sessionId":"e199f774-d072-48af-a825-a26da58f4269","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:07 INFO - 1499960107792 Marionette TRACE 387 -> [0,2,"deleteSession",{}]
08:35:07 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:07 INFO - 1499960107799 Marionette TRACE 387 <- [1,2,null,{}]
08:35:07 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:07 INFO - 1499960107809 Marionette DEBUG Closed connection 387
08:35:07 INFO - 1499960107811 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49834
08:35:07 INFO - 1499960107834 Marionette DEBUG Closed connection 388
08:35:07 INFO - 1499960107836 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49835
08:35:07 INFO - 1499960107843 Marionette TRACE 389 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:35:07 INFO - 1499960107849 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:07 INFO - 1499960107874 Marionette DEBUG Register listener.js for window 15
08:35:07 INFO - 1499960107889 Marionette TRACE 389 <- [1,1,null,{"sessionId":"9054f0eb-0b5a-4d1f-a1ba-4000e675f48a","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:07 INFO - 1499960107903 Marionette TRACE 389 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
08:35:07 INFO - 1499960107909 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"
08:35:07 INFO - 1499960107919 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"
08:35:07 INFO - 1499960107922 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"
08:35:07 INFO - ++DOMWINDOW == 34 (00000088DE104800) [pid = 1764] [serial = 46] [outer = 00000088EAC86800]
08:35:07 INFO - 1499960107982 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:35:07 INFO - 1499960107989 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:35:08 INFO - 1499960108008 Marionette TRACE 389 <- [1,2,null,{}]
08:35:08 INFO - --DOMWINDOW == 33 (00000088EA603800) [pid = 1764] [serial = 22] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
08:35:08 INFO - --DOMWINDOW == 32 (00000088EE92E800) [pid = 1764] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
08:35:08 INFO - --DOMWINDOW == 31 (00000088E2436000) [pid = 1764] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
08:35:08 INFO - --DOMWINDOW == 30 (00000088E3D1D800) [pid = 1764] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
08:35:08 INFO - --DOMWINDOW == 29 (00000088EFE17000) [pid = 1764] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
08:35:08 INFO - --DOMWINDOW == 28 (00000088F2639000) [pid = 1764] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
08:35:08 INFO - --DOMWINDOW == 27 (00000088F2E73000) [pid = 1764] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
08:35:08 INFO - --DOMWINDOW == 26 (00000088F34AD000) [pid = 1764] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
08:35:08 INFO - --DOMWINDOW == 25 (00000088E027C000) [pid = 1764] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
08:35:08 INFO - --DOMWINDOW == 24 (00000088EA61E000) [pid = 1764] [serial = 34] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20vertically%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
08:35:08 INFO - --DOMWINDOW == 23 (00000088E8D23000) [pid = 1764] [serial = 33] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20horizontally%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
08:35:08 INFO - --DOMWINDOW == 22 (00000088E4D53000) [pid = 1764] [serial = 32] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
08:35:08 INFO - --DOMWINDOW == 21 (00000088E4B07800) [pid = 1764] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49337/test_accessibility.html]
08:35:08 INFO - 1499960108026 Marionette TRACE 389 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
08:35:08 INFO - 1499960108037 Marionette TRACE 389 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2b896074-1faa-4952-ac09-fc90640428d1","ELEMENT":"2b896074-1faa-4952-ac09-fc90640428d1"}}]
08:35:08 INFO - 1499960108039 Marionette TRACE 389 -> [0,4,"clickElement",{"id":"2b896074-1faa-4952-ac09-fc90640428d1"}]
08:35:08 INFO - 1499960108191 Marionette TRACE 389 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
08:35:08 INFO - 1499960108207 Marionette TRACE 389 -> [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}]
08:35:08 INFO - TEST-END: test_click.py TestClick.test_input_file1499960108216 Marionette TRACE 389 <- [1,5,null,{"value":null}]
08:35:08 INFO - 1499960108219 Marionette TRACE 389 -> [0,6,"deleteSession",{}]
08:35:08 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:08 INFO - 1499960108224 Marionette TRACE 389 <- [1,6,null,{}]
08:35:08 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 829ms
08:35:08 INFO - TEST-START | test_click.py TestClick.test_obscured_element
08:35:08 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:08 INFO - 1499960108231 Marionette DEBUG Closed connection 389
08:35:08 INFO - 1499960108233 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49836
08:35:08 INFO - 1499960108235 Marionette DEBUG Closed connection 390
08:35:08 INFO - 1499960108237 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49837
08:35:08 INFO - 1499960108239 Marionette TRACE 391 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:08 INFO - 1499960108242 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:08 INFO - 1499960108267 Marionette DEBUG Register listener.js for window 15
08:35:08 INFO - 1499960108281 Marionette TRACE 391 <- [1,1,null,{"sessionId":"7d05617b-bac7-4795-8a2b-cd91ad92567c","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:08 INFO - 1499960108289 Marionette TRACE 391 -> [0,2,"setTimeouts",{"script":30000}]
08:35:08 INFO - 1499960108291 Marionette TRACE 391 <- [1,2,null,{}]
08:35:08 INFO - 1499960108293 Marionette TRACE 391 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:35:08 INFO - 1499960108294 Marionette TRACE 391 <- [1,3,null,{}]
08:35:08 INFO - 1499960108296 Marionette TRACE 391 -> [0,4,"setTimeouts",{"implicit":0}]
08:35:08 INFO - 1499960108298 Marionette TRACE 391 <- [1,4,null,{}]
08:35:08 INFO - 1499960108301 Marionette TRACE 391 -> [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}]
08:35:08 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499960108310 Marionette TRACE 391 <- [1,5,null,{"value":null}]
08:35:08 INFO - 1499960108312 Marionette TRACE 391 -> [0,6,"deleteSession",{}]
08:35:08 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:08 INFO - 1499960108317 Marionette TRACE 391 <- [1,6,null,{}]
08:35:08 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:08 INFO - 1499960108323 Marionette DEBUG Closed connection 391
08:35:08 INFO - 1499960108325 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49838
08:35:08 INFO - 1499960108326 Marionette DEBUG Closed connection 392
08:35:08 INFO - 1499960108328 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49839
08:35:08 INFO - 1499960108331 Marionette TRACE 393 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:08 INFO - 1499960108336 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:08 INFO - 1499960108360 Marionette DEBUG Register listener.js for window 15
08:35:08 INFO - 1499960108376 Marionette TRACE 393 <- [1,1,null,{"sessionId":"40d9bf79-a33a-4e0c-abb6-16815fd1062b","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:08 INFO - 1499960108382 Marionette TRACE 393 -> [0,2,"deleteSession",{}]
08:35:08 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:08 INFO - 1499960108387 Marionette TRACE 393 <- [1,2,null,{}]
08:35:08 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:08 INFO - 1499960108396 Marionette DEBUG Closed connection 393
08:35:08 INFO - 1499960108401 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49840
08:35:08 INFO - 1499960108404 Marionette DEBUG Closed connection 394
08:35:08 INFO - 1499960108405 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49841
08:35:08 INFO - 1499960108408 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:35:08 INFO - 1499960108410 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:08 INFO - 1499960108434 Marionette DEBUG Register listener.js for window 15
08:35:08 INFO - 1499960108448 Marionette TRACE 395 <- [1,1,null,{"sessionId":"f24e0a2c-0926-4aa8-998a-36563922dbe8","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:08 INFO - 1499960108455 Marionette TRACE 395 -> [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"}]
08:35:08 INFO - 1499960108459 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:35:08 INFO - 1499960108468 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:35:08 INFO - 1499960108468 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
08:35:08 INFO - ++DOMWINDOW == 22 (00000088E4061800) [pid = 1764] [serial = 47] [outer = 00000088EAC86800]
08:35:08 INFO - 1499960108531 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"
08:35:08 INFO - 1499960108538 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"
08:35:08 INFO - 1499960108556 Marionette TRACE 395 <- [1,2,null,{}]
08:35:08 INFO - 1499960108559 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
08:35:08 INFO - 1499960108566 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"43bcebb9-d81d-4d00-a6e3-f85e752cda66","ELEMENT":"43bcebb9-d81d-4d00-a6e3-f85e752cda66"}}]
08:35:08 INFO - 1499960108569 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
08:35:08 INFO - 1499960108578 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"86e868e9-962c-4d5f-8c3f-cb2a2fd17782","ELEMENT":"86e868e9-962c-4d5f-8c3f-cb2a2fd17782"}}]
08:35:08 INFO - 1499960108580 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"43bcebb9-d81d-4d00-a6e3-f85e752cda66"}]
08:35:09 INFO - 1499960109009 Marionette DEBUG Canceled page load listener because no navigation has been detected
08:35:09 INFO - 1499960109011 Marionette TRACE 395 <- [1,5,null,{}]
08:35:09 INFO - 1499960109013 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"86e868e9-962c-4d5f-8c3f-cb2a2fd17782"}]
08:35:09 INFO - 1499960109133 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
08:35:09 INFO - 1499960109138 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
08:35:09 INFO - 1499960109146 Marionette TRACE 395 <- [1,7,null,{"value":false}]
08:35:09 INFO - 1499960109149 Marionette TRACE 395 -> [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}]
08:35:09 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499960109157 Marionette TRACE 395 <- [1,8,null,{"value":null}]
08:35:09 INFO - 1499960109159 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
08:35:09 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:09 INFO - 1499960109164 Marionette TRACE 395 <- [1,9,null,{}]
08:35:09 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 938ms
08:35:09 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
08:35:09 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:09 INFO - 1499960109170 Marionette DEBUG Closed connection 395
08:35:09 INFO - 1499960109171 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49842
08:35:09 INFO - 1499960109173 Marionette DEBUG Closed connection 396
08:35:09 INFO - 1499960109174 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49843
08:35:09 INFO - 1499960109176 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:09 INFO - 1499960109179 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:09 INFO - 1499960109202 Marionette DEBUG Register listener.js for window 15
08:35:09 INFO - 1499960109214 Marionette TRACE 397 <- [1,1,null,{"sessionId":"5e19cd78-3ac9-427a-9096-8545736ced4b","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:09 INFO - 1499960109220 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
08:35:09 INFO - 1499960109222 Marionette TRACE 397 <- [1,2,null,{}]
08:35:09 INFO - 1499960109224 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
08:35:09 INFO - 1499960109225 Marionette TRACE 397 <- [1,3,null,{}]
08:35:09 INFO - 1499960109227 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
08:35:09 INFO - 1499960109229 Marionette TRACE 397 <- [1,4,null,{}]
08:35:09 INFO - 1499960109231 Marionette TRACE 397 -> [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}]
08:35:09 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499960109239 Marionette TRACE 397 <- [1,5,null,{"value":null}]
08:35:09 INFO - 1499960109241 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
08:35:09 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:09 INFO - 1499960109245 Marionette TRACE 397 <- [1,6,null,{}]
08:35:09 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:09 INFO - 1499960109251 Marionette DEBUG Closed connection 397
08:35:09 INFO - 1499960109252 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49844
08:35:09 INFO - 1499960109254 Marionette DEBUG Closed connection 398
08:35:09 INFO - 1499960109257 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49845
08:35:09 INFO - 1499960109259 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
08:35:09 INFO - 1499960109262 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:09 INFO - 1499960109286 Marionette DEBUG Register listener.js for window 15
08:35:09 INFO - 1499960109298 Marionette TRACE 399 <- [1,1,null,{"sessionId":"c892ec1c-97ee-4a8d-9dd1-00cca7ca97a6","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:09 INFO - 1499960109304 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
08:35:09 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:09 INFO - 1499960109308 Marionette TRACE 399 <- [1,2,null,{}]
08:35:09 INFO - [Parent 1764] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
08:35:09 INFO - 1499960109314 Marionette DEBUG Closed connection 399
08:35:09 INFO - 1499960109315 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49846
08:35:09 INFO - 1499960109317 Marionette DEBUG Closed connection 400
08:35:09 INFO - 1499960109318 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49847
08:35:09 INFO - 1499960109320 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
08:35:09 INFO - 1499960109323 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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}
08:35:09 INFO - 1499960109347 Marionette DEBUG Register listener.js for window 15
08:35:09 INFO - 1499960109358 Marionette TRACE 401 <- [1,1,null,{"sessionId":"1f55e7f7-710a-45a8-a8aa-68ec5b0e28bb","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":1764,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-287.001\\AppData\\Local\\Temp\\tmpg5_mmo.mozrunner","moz:accessibilityChecks":false}}]
08:35:09 INFO - 1499960109365 Marionette TRACE 401 -> [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"}]
08:35:09 INFO - 1499960109369 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"
08:35:09 INFO - 1499960109376 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"
08:35:09 INFO - 1499960109377 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"
08:35:09 INFO - ++DOMWINDOW == 23 (00000088E725E800) [pid = 1764] [serial = 48] [outer = 00000088EAC86800]
08:35:09 INFO - 1499960109436 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"
08:35:09 INFO - 1499960109442 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"
08:35:09 INFO - 1499960109458 Marionette TRACE 401 <- [1,2,null,{}]
08:35:09 INFO - 1499960109461 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
08:35:09 INFO - 1499960109469 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1239b466-2d0d-48ca-8511-d30a2ff0cafa","ELEMENT":"1239b466-2d0d-48ca-8511-d30a2ff0cafa"}}]
08:35:09 INFO - 1499960109472 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"1239b466-2d0d-48ca-8511-d30a2ff0cafa"}]
08:35:09 INFO - 1499960109595 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
08:35:09 INFO - 1499960109597 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"1239b466-2d0d-48ca-8511-d30a2ff0cafa"}]
08:35:09 INFO - 1499960109723 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element