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]
21:43:11 INFO - 1499834591337 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
21:43:11 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499834591345 Marionette TRACE 352 <- [1,5,null,{"value":null}]
21:43:11 INFO - 1499834591346 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
21:43:11 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:11 INFO - 1499834591351 Marionette TRACE 352 <- [1,6,null,{}]
21:43:11 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 581ms
21:43:11 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:43:11 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:11 INFO - 1499834591357 Marionette DEBUG Closed connection 352
21:43:11 INFO - 1499834591358 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49778
21:43:11 INFO - 1499834591360 Marionette DEBUG Closed connection 353
21:43:11 INFO - 1499834591362 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49779
21:43:11 INFO - 1499834591364 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:11 INFO - 1499834591366 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:11 INFO - 1499834591389 Marionette DEBUG Register listener.js for window 12
21:43:11 INFO - 1499834591400 Marionette TRACE 354 <- [1,1,null,{"sessionId":"6e887189-1386-48c9-b73b-61028e2d5e96","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:11 INFO - 1499834591407 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
21:43:11 INFO - 1499834591408 Marionette TRACE 354 <- [1,2,null,{}]
21:43:11 INFO - 1499834591410 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:11 INFO - 1499834591412 Marionette TRACE 354 <- [1,3,null,{}]
21:43:11 INFO - 1499834591413 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:11 INFO - 1499834591415 Marionette TRACE 354 <- [1,4,null,{}]
21:43:11 INFO - 1499834591417 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
21:43:11 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499834591426 Marionette TRACE 354 <- [1,5,null,{"value":null}]
21:43:11 INFO - 1499834591428 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
21:43:11 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:11 INFO - 1499834591432 Marionette TRACE 354 <- [1,6,null,{}]
21:43:11 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:11 INFO - 1499834591438 Marionette DEBUG Closed connection 354
21:43:11 INFO - 1499834591440 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49780
21:43:11 INFO - 1499834591441 Marionette DEBUG Closed connection 355
21:43:11 INFO - 1499834591444 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49781
21:43:11 INFO - 1499834591446 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:11 INFO - 1499834591450 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:11 INFO - 1499834591472 Marionette DEBUG Register listener.js for window 12
21:43:11 INFO - 1499834591483 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f5179dd5-d67b-4fe6-bfeb-ea510e66620b","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:11 INFO - 1499834591490 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
21:43:11 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:11 INFO - 1499834591494 Marionette TRACE 356 <- [1,2,null,{}]
21:43:11 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:11 INFO - 1499834591500 Marionette DEBUG Closed connection 356
21:43:11 INFO - 1499834591501 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49782
21:43:11 INFO - 1499834591503 Marionette DEBUG Closed connection 357
21:43:11 INFO - 1499834591504 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49783
21:43:11 INFO - 1499834591506 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:11 INFO - 1499834591509 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:11 INFO - 1499834591531 Marionette DEBUG Register listener.js for window 12
21:43:11 INFO - 1499834591543 Marionette TRACE 358 <- [1,1,null,{"sessionId":"4bf18d27-4607-47fc-a129-1ca36c3e1b62","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:11 INFO - 1499834591549 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49330/clicks.html"}]
21:43:11 INFO - 1499834591553 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/hidden.html"
21:43:11 INFO - 1499834591572 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/hidden.html"
21:43:11 INFO - 1499834591573 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/hidden.html"
21:43:11 INFO - ++DOMWINDOW == 19 (00000005F201B800) [pid = 2280] [serial = 41] [outer = 00000005F5CEA800]
21:43:11 INFO - 1499834591636 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/clicks.html"
21:43:11 INFO - 1499834591642 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/clicks.html"
21:43:11 INFO - 1499834591665 Marionette TRACE 358 <- [1,2,null,{}]
21:43:11 INFO - 1499834591668 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:43:11 INFO - 1499834591678 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a3a00cd1-592d-40ed-98af-debb3c7d17d8","ELEMENT":"a3a00cd1-592d-40ed-98af-debb3c7d17d8"}}]
21:43:11 INFO - 1499834591681 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"a3a00cd1-592d-40ed-98af-debb3c7d17d8"}]
21:43:11 INFO - 1499834591886 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/clicks.html"
21:43:11 INFO - [Parent 2280] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
21:43:11 INFO - 1499834591906 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/clicks.html"
21:43:11 INFO - 1499834591907 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/clicks.html"
21:43:11 INFO - ++DOMWINDOW == 20 (00000005FC622000) [pid = 2280] [serial = 42] [outer = 00000005F5CEA800]
21:43:11 INFO - 1499834591968 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/test.html"
21:43:11 INFO - 1499834591973 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/test.html"
21:43:12 INFO - 1499834591990 Marionette TRACE 358 <- [1,4,null,{}]
21:43:12 INFO - 1499834591995 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:43:12 INFO - 1499834592003 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"968a300f-dfd9-4c4d-a89a-7554f642b97c","ELEMENT":"968a300f-dfd9-4c4d-a89a-7554f642b97c"}}]
21:43:12 INFO - 1499834592005 Marionette TRACE 358 -> [0,6,"getTitle",{}]
21:43:12 INFO - 1499834592007 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
21:43:12 INFO - 1499834592009 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
21:43:12 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499834592017 Marionette TRACE 358 <- [1,7,null,{"value":null}]
21:43:12 INFO - 1499834592019 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:12 INFO - 1499834592023 Marionette TRACE 358 <- [1,8,null,{}]
21:43:12 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 673ms
21:43:12 INFO - TEST-START | test_click.py TestClick.test_container_element
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:12 INFO - 1499834592030 Marionette DEBUG Closed connection 358
21:43:12 INFO - 1499834592032 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49786
21:43:12 INFO - 1499834592034 Marionette DEBUG Closed connection 359
21:43:12 INFO - 1499834592036 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49787
21:43:12 INFO - 1499834592039 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:12 INFO - 1499834592041 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:12 INFO - 1499834592064 Marionette DEBUG Register listener.js for window 12
21:43:12 INFO - 1499834592076 Marionette TRACE 360 <- [1,1,null,{"sessionId":"dee5b183-ca59-4821-810b-dbe2bfde1a05","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:12 INFO - 1499834592083 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
21:43:12 INFO - 1499834592084 Marionette TRACE 360 <- [1,2,null,{}]
21:43:12 INFO - 1499834592086 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:12 INFO - 1499834592088 Marionette TRACE 360 <- [1,3,null,{}]
21:43:12 INFO - 1499834592090 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:12 INFO - 1499834592092 Marionette TRACE 360 <- [1,4,null,{}]
21:43:12 INFO - 1499834592094 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
21:43:12 INFO - TEST-START: test_click.py TestClick.test_container_element1499834592102 Marionette TRACE 360 <- [1,5,null,{"value":null}]
21:43:12 INFO - 1499834592104 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:12 INFO - 1499834592109 Marionette TRACE 360 <- [1,6,null,{}]
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:12 INFO - 1499834592115 Marionette DEBUG Closed connection 360
21:43:12 INFO - 1499834592116 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49788
21:43:12 INFO - 1499834592118 Marionette DEBUG Closed connection 361
21:43:12 INFO - 1499834592119 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49789
21:43:12 INFO - 1499834592122 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:12 INFO - 1499834592125 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:12 INFO - 1499834592153 Marionette DEBUG Register listener.js for window 12
21:43:12 INFO - 1499834592168 Marionette TRACE 362 <- [1,1,null,{"sessionId":"e47e40ab-3007-4107-9810-8dffcd0e1b29","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:12 INFO - 1499834592175 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:12 INFO - 1499834592179 Marionette TRACE 362 <- [1,2,null,{}]
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:12 INFO - 1499834592185 Marionette DEBUG Closed connection 362
21:43:12 INFO - 1499834592186 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49790
21:43:12 INFO - 1499834592188 Marionette DEBUG Closed connection 363
21:43:12 INFO - 1499834592190 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49791
21:43:12 INFO - 1499834592192 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:12 INFO - 1499834592205 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:12 INFO - 1499834592228 Marionette DEBUG Register listener.js for window 12
21:43:12 INFO - 1499834592240 Marionette TRACE 364 <- [1,1,null,{"sessionId":"6086d869-9a1f-40e5-bb4c-f515fc2139fe","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:12 INFO - 1499834592246 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:43:12 INFO - 1499834592250 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/test.html"
21:43:12 INFO - 1499834592259 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/test.html"
21:43:12 INFO - 1499834592260 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/test.html"
21:43:12 INFO - ++DOMWINDOW == 21 (00000005FC626000) [pid = 2280] [serial = 43] [outer = 00000005F5CEA800]
21:43:12 INFO - 1499834592340 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"
21:43:12 INFO - 1499834592358 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"
21:43:12 INFO - 1499834592373 Marionette TRACE 364 <- [1,2,null,{}]
21:43:12 INFO - 1499834592376 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:43:12 INFO - 1499834592383 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a4e9307f-fc82-4662-9222-e0ccdabb231b","ELEMENT":"a4e9307f-fc82-4662-9222-e0ccdabb231b"}}]
21:43:12 INFO - 1499834592385 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"a4e9307f-fc82-4662-9222-e0ccdabb231b"}]
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
21:43:12 INFO - 1499834592788 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:12 INFO - 1499834592790 Marionette TRACE 364 <- [1,4,null,{}]
21:43:12 INFO - 1499834592792 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"a4e9307f-fc82-4662-9222-e0ccdabb231b","name":"selected"}]
21:43:12 INFO - 1499834592815 Marionette TRACE 364 <- [1,5,null,{"value":true}]
21:43:12 INFO - 1499834592818 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
21:43:12 INFO - TEST-END: test_click.py TestClick.test_container_element1499834592826 Marionette TRACE 364 <- [1,6,null,{"value":null}]
21:43:12 INFO - 1499834592828 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:12 INFO - 1499834592832 Marionette TRACE 364 <- [1,7,null,{}]
21:43:12 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 808ms
21:43:12 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:12 INFO - 1499834592838 Marionette DEBUG Closed connection 364
21:43:12 INFO - 1499834592840 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49792
21:43:12 INFO - 1499834592841 Marionette DEBUG Closed connection 365
21:43:12 INFO - 1499834592842 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49793
21:43:12 INFO - 1499834592845 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:12 INFO - 1499834592847 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:12 INFO - 1499834592870 Marionette DEBUG Register listener.js for window 12
21:43:12 INFO - 1499834592881 Marionette TRACE 366 <- [1,1,null,{"sessionId":"3804de0b-9dba-4958-abdb-e9e280733c5f","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:12 INFO - 1499834592889 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
21:43:12 INFO - 1499834592891 Marionette TRACE 366 <- [1,2,null,{}]
21:43:12 INFO - 1499834592893 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:12 INFO - 1499834592894 Marionette TRACE 366 <- [1,3,null,{}]
21:43:12 INFO - 1499834592896 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:12 INFO - 1499834592898 Marionette TRACE 366 <- [1,4,null,{}]
21:43:12 INFO - 1499834592900 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
21:43:12 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499834592908 Marionette TRACE 366 <- [1,5,null,{"value":null}]
21:43:12 INFO - 1499834592910 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:12 INFO - 1499834592914 Marionette TRACE 366 <- [1,6,null,{}]
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:12 INFO - 1499834592920 Marionette DEBUG Closed connection 366
21:43:12 INFO - 1499834592922 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49794
21:43:12 INFO - 1499834592923 Marionette DEBUG Closed connection 367
21:43:12 INFO - 1499834592925 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49795
21:43:12 INFO - 1499834592927 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:12 INFO - 1499834592929 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:12 INFO - 1499834592952 Marionette DEBUG Register listener.js for window 12
21:43:12 INFO - 1499834592963 Marionette TRACE 368 <- [1,1,null,{"sessionId":"12c33e23-ffe8-4073-b1d7-e41c95ef3d37","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:12 INFO - 1499834592969 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:12 INFO - 1499834592974 Marionette TRACE 368 <- [1,2,null,{}]
21:43:12 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:12 INFO - 1499834592979 Marionette DEBUG Closed connection 368
21:43:12 INFO - 1499834592981 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49796
21:43:12 INFO - 1499834592982 Marionette DEBUG Closed connection 369
21:43:12 INFO - 1499834592984 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49797
21:43:12 INFO - 1499834592986 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:12 INFO - 1499834592988 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:13 INFO - 1499834593011 Marionette DEBUG Register listener.js for window 12
21:43:13 INFO - 1499834593022 Marionette TRACE 370 <- [1,1,null,{"sessionId":"ca1f7a2d-6749-4c9a-a136-597ec180177b","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:13 INFO - 1499834593028 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:43:13 INFO - 1499834593032 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"
21:43:13 INFO - 1499834593038 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"
21:43:13 INFO - 1499834593039 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"
21:43:13 INFO - ++DOMWINDOW == 22 (00000005FCC5A000) [pid = 2280] [serial = 44] [outer = 00000005F5CEA800]
21:43:13 INFO - 1499834593083 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"
21:43:13 INFO - 1499834593094 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"
21:43:13 INFO - 1499834593111 Marionette TRACE 370 <- [1,2,null,{}]
21:43:13 INFO - 1499834593114 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:43:13 INFO - 1499834593121 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"59857ebe-7124-4470-a31a-7c16cdfd6731","ELEMENT":"59857ebe-7124-4470-a31a-7c16cdfd6731"}}]
21:43:13 INFO - 1499834593124 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"59857ebe-7124-4470-a31a-7c16cdfd6731"}]
21:43:13 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
21:43:13 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
21:43:13 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
21:43:13 INFO - 1499834593544 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:13 INFO - 1499834593546 Marionette TRACE 370 <- [1,4,null,{}]
21:43:13 INFO - 1499834593548 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"59857ebe-7124-4470-a31a-7c16cdfd6731","name":"selected"}]
21:43:13 INFO - 1499834593571 Marionette TRACE 370 <- [1,5,null,{"value":true}]
21:43:13 INFO - 1499834593574 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
21:43:13 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499834593585 Marionette TRACE 370 <- [1,6,null,{"value":null}]
21:43:13 INFO - 1499834593587 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
21:43:13 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:13 INFO - 1499834593591 Marionette TRACE 370 <- [1,7,null,{}]
21:43:13 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 759ms
21:43:13 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:43:13 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:13 INFO - 1499834593597 Marionette DEBUG Closed connection 370
21:43:13 INFO - 1499834593599 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49798
21:43:13 INFO - 1499834593601 Marionette DEBUG Closed connection 371
21:43:13 INFO - 1499834593602 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49799
21:43:13 INFO - 1499834593604 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:13 INFO - 1499834593606 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:13 INFO - 1499834593630 Marionette DEBUG Register listener.js for window 12
21:43:13 INFO - 1499834593640 Marionette TRACE 372 <- [1,1,null,{"sessionId":"4c916435-cd4f-4686-ab28-40d8b1898182","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:13 INFO - 1499834593648 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
21:43:13 INFO - 1499834593650 Marionette TRACE 372 <- [1,2,null,{}]
21:43:13 INFO - 1499834593652 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:13 INFO - 1499834593653 Marionette TRACE 372 <- [1,3,null,{}]
21:43:13 INFO - 1499834593655 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:13 INFO - 1499834593656 Marionette TRACE 372 <- [1,4,null,{}]
21:43:13 INFO - 1499834593659 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
21:43:13 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499834593666 Marionette TRACE 372 <- [1,5,null,{"value":null}]
21:43:13 INFO - 1499834593668 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
21:43:13 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:13 INFO - 1499834593672 Marionette TRACE 372 <- [1,6,null,{}]
21:43:13 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:13 INFO - 1499834593678 Marionette DEBUG Closed connection 372
21:43:13 INFO - 1499834593680 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49800
21:43:13 INFO - 1499834593681 Marionette DEBUG Closed connection 373
21:43:13 INFO - 1499834593683 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49801
21:43:13 INFO - 1499834593685 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:13 INFO - 1499834593687 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:13 INFO - 1499834593710 Marionette DEBUG Register listener.js for window 12
21:43:13 INFO - 1499834593721 Marionette TRACE 374 <- [1,1,null,{"sessionId":"0429c784-6633-4104-b97f-54f41058d396","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:13 INFO - 1499834593727 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
21:43:13 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:13 INFO - 1499834593731 Marionette TRACE 374 <- [1,2,null,{}]
21:43:13 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:13 INFO - 1499834593737 Marionette DEBUG Closed connection 374
21:43:13 INFO - 1499834593738 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49802
21:43:13 INFO - 1499834593740 Marionette DEBUG Closed connection 375
21:43:13 INFO - 1499834593741 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49803
21:43:13 INFO - 1499834593744 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:13 INFO - 1499834593746 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:13 INFO - 1499834593771 Marionette DEBUG Register listener.js for window 12
21:43:13 INFO - 1499834593785 Marionette TRACE 376 <- [1,1,null,{"sessionId":"01118815-c80d-4793-9e89-e0e39cda6b16","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:13 INFO - 1499834593795 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
21:43:13 INFO - 1499834593799 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"
21:43:13 INFO - 1499834593806 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"
21:43:13 INFO - 1499834593807 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"
21:43:13 INFO - ++DOMWINDOW == 23 (00000005FD226800) [pid = 2280] [serial = 45] [outer = 00000005F5CEA800]
21:43:13 INFO - 1499834593856 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"
21:43:13 INFO - 1499834593864 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"
21:43:13 INFO - 1499834593881 Marionette TRACE 376 <- [1,2,null,{}]
21:43:13 INFO - 1499834593884 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:43:13 INFO - 1499834593892 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0b26f91f-d121-41ed-946e-f2c1d6e7174f","ELEMENT":"0b26f91f-d121-41ed-946e-f2c1d6e7174f"}}]
21:43:13 INFO - 1499834593894 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"0b26f91f-d121-41ed-946e-f2c1d6e7174f"}]
21:43:14 INFO - 1499834594307 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:14 INFO - 1499834594309 Marionette TRACE 376 <- [1,4,null,{}]
21:43:14 INFO - 1499834594847 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
21:43:14 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499834594856 Marionette TRACE 376 <- [1,5,null,{"value":null}]
21:43:14 INFO - 1499834594861 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
21:43:14 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:14 INFO - 1499834594865 Marionette TRACE 376 <- [1,6,null,{}]
21:43:14 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1274ms
21:43:14 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:43:14 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:14 INFO - 1499834594872 Marionette DEBUG Closed connection 376
21:43:14 INFO - 1499834594874 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49804
21:43:14 INFO - 1499834594875 Marionette DEBUG Closed connection 377
21:43:14 INFO - 1499834594877 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49805
21:43:14 INFO - 1499834594879 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:14 INFO - 1499834594883 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:14 INFO - 1499834594907 Marionette DEBUG Register listener.js for window 12
21:43:14 INFO - 1499834594918 Marionette TRACE 378 <- [1,1,null,{"sessionId":"bea36c06-4488-4261-b694-43dc4d46f2ae","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:14 INFO - 1499834594932 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
21:43:14 INFO - 1499834594934 Marionette TRACE 378 <- [1,2,null,{}]
21:43:14 INFO - 1499834594957 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:14 INFO - 1499834594959 Marionette TRACE 378 <- [1,3,null,{}]
21:43:14 INFO - 1499834594962 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:14 INFO - 1499834594963 Marionette TRACE 378 <- [1,4,null,{}]
21:43:14 INFO - 1499834594966 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
21:43:14 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499834594973 Marionette TRACE 378 <- [1,5,null,{"value":null}]
21:43:15 INFO - 1499834595070 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
21:43:15 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:15 INFO - 1499834595074 Marionette TRACE 378 <- [1,6,null,{}]
21:43:15 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:15 INFO - 1499834595097 Marionette DEBUG Closed connection 378
21:43:15 INFO - 1499834595099 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49806
21:43:15 INFO - 1499834595102 Marionette DEBUG Closed connection 379
21:43:15 INFO - 1499834595103 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49807
21:43:15 INFO - 1499834595108 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:15 INFO - 1499834595110 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:15 INFO - 1499834595134 Marionette DEBUG Register listener.js for window 12
21:43:15 INFO - 1499834595145 Marionette TRACE 380 <- [1,1,null,{"sessionId":"96d890a8-31c3-45ff-ad09-1655c799ace7","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:15 INFO - 1499834595153 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
21:43:15 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:15 INFO - 1499834595157 Marionette TRACE 380 <- [1,2,null,{}]
21:43:15 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:15 INFO - 1499834595181 Marionette DEBUG Closed connection 380
21:43:15 INFO - 1499834595183 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49808
21:43:15 INFO - 1499834595188 Marionette DEBUG Closed connection 381
21:43:15 INFO - 1499834595189 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49809
21:43:15 INFO - 1499834595192 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:15 INFO - 1499834595194 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:15 INFO - 1499834595218 Marionette DEBUG Register listener.js for window 12
21:43:15 INFO - 1499834595239 Marionette TRACE 382 <- [1,1,null,{"sessionId":"2dc9fd4f-5630-4605-ada1-b3f40418c91d","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:15 INFO - 1499834595249 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:43:15 INFO - 1499834595253 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"
21:43:15 INFO - 1499834595259 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"
21:43:15 INFO - 1499834595261 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"
21:43:15 INFO - ++DOMWINDOW == 24 (00000005FAB30000) [pid = 2280] [serial = 46] [outer = 00000005F5CEA800]
21:43:15 INFO - 1499834595314 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"
21:43:15 INFO - 1499834595318 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"
21:43:15 INFO - 1499834595326 Marionette TRACE 382 <- [1,2,null,{}]
21:43:15 INFO - 1499834595337 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:43:15 INFO - 1499834595345 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c505762e-e17c-41f7-98a2-56c89bcd565e","ELEMENT":"c505762e-e17c-41f7-98a2-56c89bcd565e"}}]
21:43:15 INFO - 1499834595352 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"c505762e-e17c-41f7-98a2-56c89bcd565e"}]
21:43:15 INFO - 1499834595789 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:15 INFO - 1499834595791 Marionette TRACE 382 <- [1,4,null,{}]
21:43:15 INFO - 1499834595794 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
21:43:15 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499834595801 Marionette TRACE 382 <- [1,5,null,{"value":null}]
21:43:15 INFO - 1499834595803 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
21:43:15 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:15 INFO - 1499834595807 Marionette TRACE 382 <- [1,6,null,{}]
21:43:15 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 942ms
21:43:15 INFO - TEST-START | test_click.py TestClick.test_input_file
21:43:15 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:15 INFO - 1499834595813 Marionette DEBUG Closed connection 382
21:43:15 INFO - 1499834595815 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49810
21:43:15 INFO - 1499834595816 Marionette DEBUG Closed connection 383
21:43:15 INFO - 1499834595818 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49811
21:43:15 INFO - 1499834595820 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:15 INFO - 1499834595822 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:15 INFO - 1499834595846 Marionette DEBUG Register listener.js for window 12
21:43:15 INFO - 1499834595856 Marionette TRACE 384 <- [1,1,null,{"sessionId":"aa6e5d61-3e9b-4424-ac2e-451561a16901","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:15 INFO - 1499834595864 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
21:43:15 INFO - 1499834595866 Marionette TRACE 384 <- [1,2,null,{}]
21:43:15 INFO - 1499834595894 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:15 INFO - 1499834595895 Marionette TRACE 384 <- [1,3,null,{}]
21:43:15 INFO - 1499834595897 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:15 INFO - 1499834595898 Marionette TRACE 384 <- [1,4,null,{}]
21:43:15 INFO - 1499834595901 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
21:43:15 INFO - TEST-START: test_click.py TestClick.test_input_file1499834595909 Marionette TRACE 384 <- [1,5,null,{"value":null}]
21:43:15 INFO - 1499834595910 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
21:43:15 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:15 INFO - 1499834595915 Marionette TRACE 384 <- [1,6,null,{}]
21:43:15 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:15 INFO - 1499834595921 Marionette DEBUG Closed connection 384
21:43:15 INFO - 1499834595922 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49812
21:43:15 INFO - 1499834595924 Marionette DEBUG Closed connection 385
21:43:15 INFO - 1499834595925 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49813
21:43:15 INFO - 1499834595927 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:15 INFO - 1499834595929 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:15 INFO - 1499834595953 Marionette DEBUG Register listener.js for window 12
21:43:15 INFO - 1499834595964 Marionette TRACE 386 <- [1,1,null,{"sessionId":"05cd5de0-32c1-48af-8e35-b7c7c23bbcf0","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:15 INFO - 1499834595970 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
21:43:15 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:15 INFO - 1499834595974 Marionette TRACE 386 <- [1,2,null,{}]
21:43:15 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:15 INFO - 1499834595980 Marionette DEBUG Closed connection 386
21:43:15 INFO - 1499834595981 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49814
21:43:15 INFO - 1499834595983 Marionette DEBUG Closed connection 387
21:43:15 INFO - 1499834595984 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49815
21:43:15 INFO - 1499834595986 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:15 INFO - 1499834595989 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:16 INFO - 1499834596012 Marionette DEBUG Register listener.js for window 12
21:43:16 INFO - 1499834596023 Marionette TRACE 388 <- [1,1,null,{"sessionId":"4ede4ea6-1fd4-4f85-b1ea-50418d2180b1","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:16 INFO - 1499834596030 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:43:16 INFO - 1499834596033 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"
21:43:16 INFO - 1499834596039 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"
21:43:16 INFO - 1499834596041 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"
21:43:16 INFO - ++DOMWINDOW == 25 (00000005FE36B000) [pid = 2280] [serial = 47] [outer = 00000005F5CEA800]
21:43:16 INFO - 1499834596088 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:16 INFO - 1499834596102 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:16 INFO - 1499834596117 Marionette TRACE 388 <- [1,2,null,{}]
21:43:16 INFO - 1499834596120 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:43:16 INFO - 1499834596130 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"61e2ce80-932f-435d-990a-a4b13a3e934c","ELEMENT":"61e2ce80-932f-435d-990a-a4b13a3e934c"}}]
21:43:16 INFO - 1499834596183 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"61e2ce80-932f-435d-990a-a4b13a3e934c"}]
21:43:16 INFO - 1499834596348 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
21:43:16 INFO - 1499834596364 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
21:43:16 INFO - TEST-END: test_click.py TestClick.test_input_file1499834596371 Marionette TRACE 388 <- [1,5,null,{"value":null}]
21:43:16 INFO - 1499834596375 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
21:43:16 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:16 INFO - 1499834596380 Marionette TRACE 388 <- [1,6,null,{}]
21:43:16 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 571ms
21:43:16 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:43:16 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:16 INFO - 1499834596402 Marionette DEBUG Closed connection 388
21:43:16 INFO - 1499834596403 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49816
21:43:16 INFO - 1499834596408 Marionette DEBUG Closed connection 389
21:43:16 INFO - 1499834596409 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49817
21:43:16 INFO - 1499834596412 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:16 INFO - 1499834596414 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:16 INFO - 1499834596438 Marionette DEBUG Register listener.js for window 12
21:43:16 INFO - 1499834596449 Marionette TRACE 390 <- [1,1,null,{"sessionId":"1013f64d-5695-4c98-9922-ddead5827732","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:16 INFO - 1499834596459 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
21:43:16 INFO - 1499834596461 Marionette TRACE 390 <- [1,2,null,{}]
21:43:16 INFO - 1499834596463 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:16 INFO - 1499834596465 Marionette TRACE 390 <- [1,3,null,{}]
21:43:16 INFO - 1499834596466 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:16 INFO - 1499834596468 Marionette TRACE 390 <- [1,4,null,{}]
21:43:16 INFO - 1499834596470 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
21:43:16 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499834596478 Marionette TRACE 390 <- [1,5,null,{"value":null}]
21:43:16 INFO - 1499834596480 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
21:43:16 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:16 INFO - 1499834596484 Marionette TRACE 390 <- [1,6,null,{}]
21:43:16 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:16 INFO - 1499834596508 Marionette DEBUG Closed connection 390
21:43:16 INFO - 1499834596510 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49818
21:43:16 INFO - 1499834596514 Marionette DEBUG Closed connection 391
21:43:16 INFO - 1499834596515 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49819
21:43:16 INFO - 1499834596518 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:16 INFO - 1499834596520 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:16 INFO - 1499834596544 Marionette DEBUG Register listener.js for window 12
21:43:16 INFO - 1499834596554 Marionette TRACE 392 <- [1,1,null,{"sessionId":"bdcd0bf5-243b-4795-86eb-2b205100e6d3","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:16 INFO - 1499834596562 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
21:43:16 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:16 INFO - 1499834596569 Marionette TRACE 392 <- [1,2,null,{}]
21:43:16 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:16 INFO - 1499834596597 Marionette DEBUG Closed connection 392
21:43:16 INFO - 1499834596598 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49820
21:43:16 INFO - 1499834596600 Marionette DEBUG Closed connection 393
21:43:16 INFO - 1499834596601 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49821
21:43:16 INFO - 1499834596603 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:16 INFO - 1499834596606 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:16 INFO - 1499834596630 Marionette DEBUG Register listener.js for window 12
21:43:16 INFO - 1499834596641 Marionette TRACE 394 <- [1,1,null,{"sessionId":"387de76a-7789-49a8-95de-e7ec0897c265","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:16 INFO - 1499834596653 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
21:43:16 INFO - 1499834596657 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:16 INFO - 1499834596668 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:16 INFO - 1499834596669 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:43:16 INFO - ++DOMWINDOW == 26 (00000005FF07F800) [pid = 2280] [serial = 48] [outer = 00000005F5CEA800]
21:43:16 INFO - 1499834596758 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"
21:43:16 INFO - 1499834596770 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"
21:43:16 INFO - 1499834596788 Marionette TRACE 394 <- [1,2,null,{}]
21:43:16 INFO - 1499834596797 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:43:16 INFO - 1499834596810 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7a025ccc-d6ef-45ad-bb1c-0c0d535475e2","ELEMENT":"7a025ccc-d6ef-45ad-bb1c-0c0d535475e2"}}]
21:43:16 INFO - 1499834596811 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:43:16 INFO - 1499834596818 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"27a81689-ad23-46fa-af1b-b774c62ac756","ELEMENT":"27a81689-ad23-46fa-af1b-b774c62ac756"}}]
21:43:16 INFO - 1499834596820 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"7a025ccc-d6ef-45ad-bb1c-0c0d535475e2"}]
21:43:17 INFO - 1499834597927 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:43:17 INFO - 1499834597940 Marionette TRACE 394 <- [1,5,null,{}]
21:43:18 INFO - 1499834597992 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"27a81689-ad23-46fa-af1b-b774c62ac756"}]
21:43:18 INFO - 1499834598156 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
21:43:18 INFO - 1499834598200 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:43:18 INFO - 1499834598208 Marionette TRACE 394 <- [1,7,null,{"value":false}]
21:43:18 INFO - 1499834598222 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
21:43:18 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499834598229 Marionette TRACE 394 <- [1,8,null,{"value":null}]
21:43:18 INFO - 1499834598234 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
21:43:18 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:18 INFO - 1499834598238 Marionette TRACE 394 <- [1,9,null,{}]
21:43:18 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1857ms
21:43:18 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:43:18 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:18 INFO - 1499834598245 Marionette DEBUG Closed connection 394
21:43:18 INFO - 1499834598246 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49822
21:43:18 INFO - 1499834598247 Marionette DEBUG Closed connection 395
21:43:18 INFO - 1499834598249 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49823
21:43:18 INFO - 1499834598251 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:18 INFO - 1499834598253 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:18 INFO - 1499834598278 Marionette DEBUG Register listener.js for window 12
21:43:18 INFO - 1499834598288 Marionette TRACE 396 <- [1,1,null,{"sessionId":"9249292e-fb48-40f4-866e-6c6b50c1e8fd","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:18 INFO - 1499834598316 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
21:43:18 INFO - 1499834598318 Marionette TRACE 396 <- [1,2,null,{}]
21:43:18 INFO - 1499834598371 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:43:18 INFO - 1499834598376 Marionette TRACE 396 <- [1,3,null,{}]
21:43:18 INFO - 1499834598380 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
21:43:18 INFO - 1499834598385 Marionette TRACE 396 <- [1,4,null,{}]
21:43:18 INFO - 1499834598440 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
21:43:18 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499834598454 Marionette TRACE 396 <- [1,5,null,{"value":null}]
21:43:18 INFO - 1499834598459 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
21:43:18 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:18 INFO - 1499834598468 Marionette TRACE 396 <- [1,6,null,{}]
21:43:18 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:18 INFO - 1499834598498 Marionette DEBUG Closed connection 396
21:43:18 INFO - 1499834598501 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49824
21:43:18 INFO - 1499834598525 Marionette DEBUG Closed connection 397
21:43:18 INFO - 1499834598530 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49825
21:43:18 INFO - 1499834598534 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:43:18 INFO - 1499834598543 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:18 INFO - 1499834598593 Marionette DEBUG Register listener.js for window 12
21:43:18 INFO - 1499834598616 Marionette TRACE 398 <- [1,1,null,{"sessionId":"cabc407a-1c4b-45a4-85d8-31c1fdbd3558","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:18 INFO - 1499834598629 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
21:43:18 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:18 INFO - 1499834598636 Marionette TRACE 398 <- [1,2,null,{}]
21:43:18 INFO - [Parent 2280] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/oak-w64-d-00000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:43:18 INFO - 1499834598676 Marionette DEBUG Closed connection 398
21:43:18 INFO - 1499834598679 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49826
21:43:18 INFO - 1499834598684 Marionette DEBUG Closed connection 399
21:43:18 INFO - 1499834598686 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49827
21:43:18 INFO - 1499834598739 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:43:18 INFO - 1499834598745 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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}
21:43:18 INFO - 1499834598795 Marionette DEBUG Register listener.js for window 12
21:43:18 INFO - 1499834598814 Marionette TRACE 400 <- [1,1,null,{"sessionId":"48bfeda6-0aba-4095-aeec-422af63df837","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":2280,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-014.001\\AppData\\Local\\Temp\\tmpykbyxr.mozrunner","moz:accessibilityChecks":false}}]
21:43:18 INFO - 1499834598825 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
21:43:18 INFO - 1499834598831 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"
21:43:18 INFO - 1499834598842 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"
21:43:18 INFO - 1499834598843 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"
21:43:18 INFO - ++DOMWINDOW == 27 (00000005F200E000) [pid = 2280] [serial = 49] [outer = 00000005F5CEA800]
21:43:18 INFO - 1499834598913 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"
21:43:18 INFO - 1499834598921 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"
21:43:18 INFO - 1499834598953 Marionette TRACE 400 <- [1,2,null,{}]
21:43:18 INFO - 1499834598965 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:43:18 INFO - 1499834598976 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"86f29b3c-23bb-4aea-a6ff-b5ee407a600f","ELEMENT":"86f29b3c-23bb-4aea-a6ff-b5ee407a600f"}}]
21:43:18 INFO - 1499834598979 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"86f29b3c-23bb-4aea-a6ff-b5ee407a600f"}]
21:43:19 INFO - 1499834599171 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
21:43:19 INFO - 1499834599179 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"86f29b3c-23bb-4aea-a6ff-b5ee407a600f"}]
21:43:19 INFO - 1499834599417 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element