could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:09:33 INFO - 1500408573485 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
13:09:33 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500408573492 Marionette TRACE 352 <- [1,5,null,{"value":null}]
13:09:33 INFO - 1500408573494 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
13:09:33 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:33 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 478ms
13:09:33 INFO - 1500408573498 Marionette TRACE 352 <- [1,6,null,{}]
13:09:33 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
13:09:33 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:33 INFO - 1500408573502 Marionette DEBUG Closed connection 352
13:09:33 INFO - 1500408573504 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49797
13:09:33 INFO - 1500408573505 Marionette DEBUG Closed connection 353
13:09:33 INFO - 1500408573507 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49798
13:09:33 INFO - 1500408573509 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:33 INFO - 1500408573511 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:33 INFO - 1500408573533 Marionette DEBUG Register listener.js for window 6442450945
13:09:33 INFO - 1500408573544 Marionette TRACE 354 <- [1,1,null,{"sessionId":"7de30fc8-f98f-4997-8d9e-c9843347a228","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:33 INFO - 1500408573547 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
13:09:33 INFO - 1500408573549 Marionette TRACE 354 <- [1,2,null,{}]
13:09:33 INFO - 1500408573550 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:33 INFO - 1500408573552 Marionette TRACE 354 <- [1,3,null,{}]
13:09:33 INFO - 1500408573554 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:33 INFO - 1500408573556 Marionette TRACE 354 <- [1,4,null,{}]
13:09:33 INFO - 1500408573559 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
13:09:33 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500408573566 Marionette TRACE 354 <- [1,5,null,{"value":null}]
13:09:33 INFO - 1500408573568 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
13:09:33 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:33 INFO - 1500408573572 Marionette TRACE 354 <- [1,6,null,{}]
13:09:33 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:33 INFO - 1500408573576 Marionette DEBUG Closed connection 354
13:09:33 INFO - 1500408573577 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49799
13:09:33 INFO - 1500408573579 Marionette DEBUG Closed connection 355
13:09:33 INFO - 1500408573580 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49800
13:09:33 INFO - 1500408573582 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:33 INFO - 1500408573584 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:33 INFO - 1500408573605 Marionette DEBUG Register listener.js for window 6442450945
13:09:33 INFO - 1500408573616 Marionette TRACE 356 <- [1,1,null,{"sessionId":"ca8b0760-cfaf-43fe-a8d4-28c803d44567","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:33 INFO - 1500408573619 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
13:09:33 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:33 INFO - 1500408573624 Marionette TRACE 356 <- [1,2,null,{}]
13:09:33 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:33 INFO - 1500408573628 Marionette DEBUG Closed connection 356
13:09:33 INFO - 1500408573629 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49801
13:09:33 INFO - 1500408573633 Marionette DEBUG Closed connection 357
13:09:33 INFO - 1500408573634 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49802
13:09:33 INFO - 1500408573637 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:33 INFO - 1500408573639 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:33 INFO - 1500408573660 Marionette DEBUG Register listener.js for window 6442450945
13:09:33 INFO - 1500408573671 Marionette TRACE 358 <- [1,1,null,{"sessionId":"c86cb3fe-5a77-47fa-bf10-2e6c2f6e47d4","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:33 INFO - 1500408573674 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49338/clicks.html"}]
13:09:33 INFO - 1500408573678 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49338/hidden.html"
13:09:33 INFO - 1500408573698 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49338/hidden.html"
13:09:33 INFO - 1500408573699 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49338/hidden.html"
13:09:33 INFO - ++DOMWINDOW == 18 (0000004E43E70800) [pid = 732] [serial = 24] [outer = 0000004E3B9E7800]
13:09:33 INFO - 1500408573731 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49338/clicks.html"
13:09:33 INFO - 1500408573735 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49338/clicks.html"
13:09:33 INFO - 1500408573737 Marionette TRACE 358 <- [1,2,null,{}]
13:09:33 INFO - 1500408573748 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
13:09:33 INFO - 1500408573940 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1a295d5e-b091-40cf-85e9-82dd1f566092","ELEMENT":"1a295d5e-b091-40cf-85e9-82dd1f566092"}}]
13:09:33 INFO - 1500408573943 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"1a295d5e-b091-40cf-85e9-82dd1f566092"}]
13:09:34 INFO - 1500408574171 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49338/clicks.html"
13:09:34 INFO - 1500408574189 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49338/clicks.html"
13:09:34 INFO - 1500408574190 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49338/clicks.html"
13:09:34 INFO - ++DOMWINDOW == 19 (0000004E40DD8000) [pid = 732] [serial = 25] [outer = 0000004E3B9E7800]
13:09:34 INFO - 1500408574218 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49338/test.html"
13:09:34 INFO - 1500408574222 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49338/test.html"
13:09:34 INFO - 1500408574227 Marionette TRACE 358 <- [1,4,null,{}]
13:09:34 INFO - 1500408574234 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
13:09:34 INFO - 1500408574245 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"14cbfefd-c9cf-40d2-bfed-06b52956c0fb","ELEMENT":"14cbfefd-c9cf-40d2-bfed-06b52956c0fb"}}]
13:09:34 INFO - 1500408574247 Marionette TRACE 358 -> [0,6,"getTitle",{}]
13:09:34 INFO - 1500408574249 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
13:09:34 INFO - 1500408574251 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
13:09:34 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500408574261 Marionette TRACE 358 <- [1,7,null,{"value":null}]
13:09:34 INFO - 1500408574263 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
13:09:34 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 769ms
13:09:34 INFO - 1500408574268 Marionette TRACE 358 <- [1,8,null,{}]
13:09:34 INFO - TEST-START | test_click.py TestClick.test_container_element
13:09:34 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1500408574273 Marionette DEBUG Closed connection 358
13:09:34 INFO - 1500408574276 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49805
13:09:34 INFO - 1500408574278 Marionette DEBUG Closed connection 359
13:09:34 INFO - 1500408574279 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49806
13:09:34 INFO - 1500408574281 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:34 INFO - 1500408574283 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:34 INFO - 1500408574306 Marionette DEBUG Register listener.js for window 6442450945
13:09:34 INFO - 1500408574329 Marionette TRACE 360 <- [1,1,null,{"sessionId":"16e5c171-6ff6-4962-af08-1a792756af4c","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:34 INFO - 1500408574332 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
13:09:34 INFO - 1500408574334 Marionette TRACE 360 <- [1,2,null,{}]
13:09:34 INFO - 1500408574336 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:34 INFO - 1500408574337 Marionette TRACE 360 <- [1,3,null,{}]
13:09:34 INFO - 1500408574339 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:34 INFO - 1500408574340 Marionette TRACE 360 <- [1,4,null,{}]
13:09:34 INFO - 1500408574344 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
13:09:34 INFO - TEST-START: test_click.py TestClick.test_container_element1500408574352 Marionette TRACE 360 <- [1,5,null,{"value":null}]
13:09:34 INFO - 1500408574354 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
13:09:34 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1500408574359 Marionette TRACE 360 <- [1,6,null,{}]
13:09:34 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1500408574363 Marionette DEBUG Closed connection 360
13:09:34 INFO - 1500408574364 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49807
13:09:34 INFO - 1500408574366 Marionette DEBUG Closed connection 361
13:09:34 INFO - 1500408574368 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49808
13:09:34 INFO - 1500408574370 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:34 INFO - 1500408574372 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:34 INFO - 1500408574395 Marionette DEBUG Register listener.js for window 6442450945
13:09:34 INFO - 1500408574406 Marionette TRACE 362 <- [1,1,null,{"sessionId":"6485acf4-7fed-4cac-8ad4-2077cddf5893","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:34 INFO - 1500408574409 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
13:09:34 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1500408574415 Marionette TRACE 362 <- [1,2,null,{}]
13:09:34 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:34 INFO - 1500408574420 Marionette DEBUG Closed connection 362
13:09:34 INFO - 1500408574421 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49809
13:09:34 INFO - 1500408574423 Marionette DEBUG Closed connection 363
13:09:34 INFO - 1500408574424 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49810
13:09:34 INFO - 1500408574426 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:34 INFO - 1500408574428 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:34 INFO - 1500408574450 Marionette DEBUG Register listener.js for window 6442450945
13:09:34 INFO - 1500408574461 Marionette TRACE 364 <- [1,1,null,{"sessionId":"77f0667a-78c8-4735-b430-908150c419b8","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:34 INFO - 1500408574464 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
13:09:34 INFO - 1500408574469 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49338/test.html"
13:09:34 INFO - 1500408574473 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49338/test.html"
13:09:34 INFO - 1500408574473 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49338/test.html"
13:09:34 INFO - ++DOMWINDOW == 20 (0000004E481D5800) [pid = 732] [serial = 26] [outer = 0000004E3B9E7800]
13:09:34 INFO - 1500408574495 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:34 INFO - 1500408574503 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:34 INFO - 1500408574511 Marionette TRACE 364 <- [1,2,null,{}]
13:09:34 INFO - 1500408574518 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:09:34 INFO - 1500408574541 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"81282350-a300-446d-80a7-18f242389674","ELEMENT":"81282350-a300-446d-80a7-18f242389674"}}]
13:09:34 INFO - 1500408574543 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"81282350-a300-446d-80a7-18f242389674"}]
13:09:34 INFO - [Child 732] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
13:09:34 INFO - [Child 732] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
13:09:34 INFO - [Child 732] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
13:09:34 INFO - 1500408574978 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:34 INFO - 1500408574980 Marionette TRACE 364 <- [1,4,null,{}]
13:09:34 INFO - 1500408574983 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"81282350-a300-446d-80a7-18f242389674","name":"selected"}]
13:09:35 INFO - 1500408575009 Marionette TRACE 364 <- [1,5,null,{"value":true}]
13:09:35 INFO - 1500408575012 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
13:09:35 INFO - TEST-END: test_click.py TestClick.test_container_element1500408575019 Marionette TRACE 364 <- [1,6,null,{"value":null}]
13:09:35 INFO - 1500408575021 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
13:09:35 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 756ms
13:09:35 INFO - 1500408575025 Marionette TRACE 364 <- [1,7,null,{}]
13:09:35 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
13:09:35 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1500408575029 Marionette DEBUG Closed connection 364
13:09:35 INFO - 1500408575031 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49811
13:09:35 INFO - 1500408575032 Marionette DEBUG Closed connection 365
13:09:35 INFO - 1500408575033 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49812
13:09:35 INFO - 1500408575036 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:35 INFO - 1500408575038 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:35 INFO - 1500408575060 Marionette DEBUG Register listener.js for window 6442450945
13:09:35 INFO - 1500408575070 Marionette TRACE 366 <- [1,1,null,{"sessionId":"18acc214-6566-4c7d-bfcc-0e86c4e31032","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:35 INFO - 1500408575073 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
13:09:35 INFO - 1500408575075 Marionette TRACE 366 <- [1,2,null,{}]
13:09:35 INFO - 1500408575077 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:35 INFO - 1500408575078 Marionette TRACE 366 <- [1,3,null,{}]
13:09:35 INFO - 1500408575080 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:35 INFO - 1500408575082 Marionette TRACE 366 <- [1,4,null,{}]
13:09:35 INFO - 1500408575084 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
13:09:35 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500408575092 Marionette TRACE 366 <- [1,5,null,{"value":null}]
13:09:35 INFO - 1500408575095 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
13:09:35 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1500408575099 Marionette TRACE 366 <- [1,6,null,{}]
13:09:35 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1500408575103 Marionette DEBUG Closed connection 366
13:09:35 INFO - 1500408575105 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49813
13:09:35 INFO - 1500408575107 Marionette DEBUG Closed connection 367
13:09:35 INFO - 1500408575108 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49814
13:09:35 INFO - 1500408575110 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:35 INFO - 1500408575112 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:35 INFO - 1500408575134 Marionette DEBUG Register listener.js for window 6442450945
13:09:35 INFO - 1500408575145 Marionette TRACE 368 <- [1,1,null,{"sessionId":"18e8521d-ccab-4975-a4af-767fd7a0e33d","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:35 INFO - 1500408575147 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
13:09:35 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1500408575152 Marionette TRACE 368 <- [1,2,null,{}]
13:09:35 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1500408575155 Marionette DEBUG Closed connection 368
13:09:35 INFO - 1500408575157 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49815
13:09:35 INFO - 1500408575159 Marionette DEBUG Closed connection 369
13:09:35 INFO - 1500408575160 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49816
13:09:35 INFO - 1500408575162 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:35 INFO - 1500408575164 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:35 INFO - 1500408575190 Marionette DEBUG Register listener.js for window 6442450945
13:09:35 INFO - 1500408575201 Marionette TRACE 370 <- [1,1,null,{"sessionId":"ccfc961a-aa97-4f0a-afdf-5581decba942","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:35 INFO - 1500408575204 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
13:09:35 INFO - 1500408575218 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:35 INFO - 1500408575222 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:35 INFO - 1500408575223 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:35 INFO - ++DOMWINDOW == 21 (0000004E4662E800) [pid = 732] [serial = 27] [outer = 0000004E3B9E7800]
13:09:35 INFO - 1500408575245 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:35 INFO - 1500408575252 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:35 INFO - 1500408575272 Marionette TRACE 370 <- [1,2,null,{}]
13:09:35 INFO - 1500408575284 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
13:09:35 INFO - 1500408575294 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"40a22445-ac14-48e4-9a88-c0570c286883","ELEMENT":"40a22445-ac14-48e4-9a88-c0570c286883"}}]
13:09:35 INFO - 1500408575296 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"40a22445-ac14-48e4-9a88-c0570c286883"}]
13:09:35 INFO - [Child 732] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
13:09:35 INFO - [Child 732] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
13:09:35 INFO - [Child 732] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
13:09:35 INFO - 1500408575726 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:35 INFO - 1500408575728 Marionette TRACE 370 <- [1,4,null,{}]
13:09:35 INFO - 1500408575730 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"40a22445-ac14-48e4-9a88-c0570c286883","name":"selected"}]
13:09:35 INFO - 1500408575757 Marionette TRACE 370 <- [1,5,null,{"value":true}]
13:09:35 INFO - 1500408575760 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
13:09:35 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500408575767 Marionette TRACE 370 <- [1,6,null,{"value":null}]
13:09:35 INFO - 1500408575768 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
13:09:35 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1500408575773 Marionette TRACE 370 <- [1,7,null,{}]
13:09:35 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 747ms
13:09:35 INFO - TEST-START | test_click.py TestClick.test_css_transforms
13:09:35 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:35 INFO - 1500408575777 Marionette DEBUG Closed connection 370
13:09:35 INFO - 1500408575779 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49817
13:09:35 INFO - 1500408575780 Marionette DEBUG Closed connection 371
13:09:35 INFO - 1500408575782 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49818
13:09:35 INFO - 1500408575784 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:35 INFO - 1500408575786 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:35 INFO - 1500408575808 Marionette DEBUG Register listener.js for window 6442450945
13:09:35 INFO - 1500408575819 Marionette TRACE 372 <- [1,1,null,{"sessionId":"bf9ca4f6-2298-4a5c-8ef2-1a13c4c37753","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:35 INFO - 1500408575822 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
13:09:35 INFO - 1500408575824 Marionette TRACE 372 <- [1,2,null,{}]
13:09:35 INFO - 1500408575825 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:35 INFO - 1500408575827 Marionette TRACE 372 <- [1,3,null,{}]
13:09:35 INFO - 1500408575829 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:35 INFO - 1500408575831 Marionette TRACE 372 <- [1,4,null,{}]
13:09:35 INFO - 1500408575833 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
13:09:36 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500408576440 Marionette TRACE 372 <- [1,5,null,{"value":null}]
13:09:36 INFO - 1500408576444 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
13:09:36 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:36 INFO - 1500408576448 Marionette TRACE 372 <- [1,6,null,{}]
13:09:36 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:36 INFO - 1500408576455 Marionette DEBUG Closed connection 372
13:09:36 INFO - 1500408576456 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49819
13:09:36 INFO - 1500408576459 Marionette DEBUG Closed connection 373
13:09:36 INFO - 1500408576461 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49820
13:09:36 INFO - 1500408576463 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:36 INFO - 1500408576465 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:36 INFO - 1500408576527 Marionette DEBUG Register listener.js for window 6442450945
13:09:36 INFO - 1500408576538 Marionette TRACE 374 <- [1,1,null,{"sessionId":"e196e9c1-a5e3-4497-abee-e75bb966ec49","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:36 INFO - 1500408576541 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
13:09:36 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:36 INFO - 1500408576545 Marionette TRACE 374 <- [1,2,null,{}]
13:09:36 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:36 INFO - 1500408576549 Marionette DEBUG Closed connection 374
13:09:36 INFO - 1500408576550 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49821
13:09:36 INFO - 1500408576552 Marionette DEBUG Closed connection 375
13:09:36 INFO - 1500408576554 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49822
13:09:36 INFO - 1500408576556 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:36 INFO - 1500408576558 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:36 INFO - 1500408576585 Marionette DEBUG Register listener.js for window 6442450945
13:09:36 INFO - 1500408576596 Marionette TRACE 376 <- [1,1,null,{"sessionId":"14c71f28-3974-45bc-b184-da1632a97d0e","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:36 INFO - 1500408576599 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
13:09:36 INFO - 1500408576622 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:36 INFO - 1500408576627 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:36 INFO - 1500408576627 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:36 INFO - ++DOMWINDOW == 22 (0000004E47555800) [pid = 732] [serial = 28] [outer = 0000004E3B9E7800]
13:09:36 INFO - 1500408576650 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:09:36 INFO - 1500408576656 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:09:36 INFO - 1500408576666 Marionette TRACE 376 <- [1,2,null,{}]
13:09:36 INFO - 1500408576682 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
13:09:36 INFO - 1500408576691 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0ba1d23c-bcd4-40a4-86a7-2bc823cc9845","ELEMENT":"0ba1d23c-bcd4-40a4-86a7-2bc823cc9845"}}]
13:09:36 INFO - 1500408576693 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"0ba1d23c-bcd4-40a4-86a7-2bc823cc9845"}]
13:09:37 INFO - 1500408577114 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:37 INFO - 1500408577116 Marionette TRACE 376 <- [1,4,null,{}]
13:09:37 INFO - 1500408577119 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
13:09:37 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500408577126 Marionette TRACE 376 <- [1,5,null,{"value":null}]
13:09:37 INFO - 1500408577128 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
13:09:37 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1500408577132 Marionette TRACE 376 <- [1,6,null,{}]
13:09:37 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1359ms
13:09:37 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
13:09:37 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1500408577140 Marionette DEBUG Closed connection 376
13:09:37 INFO - 1500408577142 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49823
13:09:37 INFO - 1500408577145 Marionette DEBUG Closed connection 377
13:09:37 INFO - 1500408577146 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49824
13:09:37 INFO - 1500408577149 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:37 INFO - 1500408577151 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:37 INFO - 1500408577174 Marionette DEBUG Register listener.js for window 6442450945
13:09:37 INFO - 1500408577563 Marionette TRACE 378 <- [1,1,null,{"sessionId":"c7e1892c-b0e6-468b-8c1c-b381000ab6ad","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:37 INFO - 1500408577570 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
13:09:37 INFO - 1500408577572 Marionette TRACE 378 <- [1,2,null,{}]
13:09:37 INFO - 1500408577574 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:37 INFO - 1500408577576 Marionette TRACE 378 <- [1,3,null,{}]
13:09:37 INFO - 1500408577578 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:37 INFO - 1500408577579 Marionette TRACE 378 <- [1,4,null,{}]
13:09:37 INFO - 1500408577582 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
13:09:37 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500408577863 Marionette TRACE 378 <- [1,5,null,{"value":null}]
13:09:37 INFO - 1500408577865 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
13:09:37 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1500408577870 Marionette TRACE 378 <- [1,6,null,{}]
13:09:37 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1500408577874 Marionette DEBUG Closed connection 378
13:09:37 INFO - 1500408577875 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49825
13:09:37 INFO - 1500408577878 Marionette DEBUG Closed connection 379
13:09:37 INFO - 1500408577880 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49826
13:09:37 INFO - 1500408577883 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:37 INFO - 1500408577885 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:37 INFO - 1500408577924 Marionette DEBUG Register listener.js for window 6442450945
13:09:37 INFO - 1500408577935 Marionette TRACE 380 <- [1,1,null,{"sessionId":"83404e12-49c5-49a2-80f5-c6cc73b86a04","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:37 INFO - 1500408577938 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
13:09:37 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1500408577942 Marionette TRACE 380 <- [1,2,null,{}]
13:09:37 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:37 INFO - 1500408577946 Marionette DEBUG Closed connection 380
13:09:37 INFO - 1500408577948 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49827
13:09:37 INFO - 1500408577950 Marionette DEBUG Closed connection 381
13:09:37 INFO - 1500408577951 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49828
13:09:37 INFO - 1500408577954 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:37 INFO - 1500408577956 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:37 INFO - 1500408577994 Marionette DEBUG Register listener.js for window 6442450945
13:09:38 INFO - 1500408578004 Marionette TRACE 382 <- [1,1,null,{"sessionId":"d68f964d-a443-4380-9d1a-772ac04c3af7","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:38 INFO - 1500408578007 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
13:09:38 INFO - 1500408578054 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:09:38 INFO - 1500408578058 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:09:38 INFO - 1500408578058 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
13:09:38 INFO - ++DOMWINDOW == 23 (0000004E48A46000) [pid = 732] [serial = 29] [outer = 0000004E3B9E7800]
13:09:38 INFO - 1500408578080 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:38 INFO - 1500408578087 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:38 INFO - 1500408578095 Marionette TRACE 382 <- [1,2,null,{}]
13:09:38 INFO - 1500408578103 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
13:09:38 INFO - 1500408578131 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9f72c50e-bc1d-4d62-b0ef-877424f7d95f","ELEMENT":"9f72c50e-bc1d-4d62-b0ef-877424f7d95f"}}]
13:09:38 INFO - 1500408578133 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"9f72c50e-bc1d-4d62-b0ef-877424f7d95f"}]
13:09:38 INFO - 1500408578569 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:38 INFO - 1500408578571 Marionette TRACE 382 <- [1,4,null,{}]
13:09:38 INFO - 1500408578573 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
13:09:38 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500408578581 Marionette TRACE 382 <- [1,5,null,{"value":null}]
13:09:38 INFO - 1500408578583 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
13:09:38 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578588 Marionette TRACE 382 <- [1,6,null,{}]
13:09:38 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1455ms
13:09:38 INFO - TEST-START | test_click.py TestClick.test_input_file
13:09:38 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578593 Marionette DEBUG Closed connection 382
13:09:38 INFO - 1500408578594 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49829
13:09:38 INFO - 1500408578596 Marionette DEBUG Closed connection 383
13:09:38 INFO - 1500408578597 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49830
13:09:38 INFO - 1500408578599 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:38 INFO - 1500408578601 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:38 INFO - 1500408578684 Marionette DEBUG Register listener.js for window 6442450945
13:09:38 INFO - 1500408578695 Marionette TRACE 384 <- [1,1,null,{"sessionId":"4c5c6779-590c-4eb4-8cde-828df9b6642e","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:38 INFO - 1500408578698 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
13:09:38 INFO - 1500408578699 Marionette TRACE 384 <- [1,2,null,{}]
13:09:38 INFO - 1500408578701 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:38 INFO - 1500408578702 Marionette TRACE 384 <- [1,3,null,{}]
13:09:38 INFO - 1500408578705 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:38 INFO - 1500408578707 Marionette TRACE 384 <- [1,4,null,{}]
13:09:38 INFO - 1500408578710 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
13:09:38 INFO - TEST-START: test_click.py TestClick.test_input_file1500408578751 Marionette TRACE 384 <- [1,5,null,{"value":null}]
13:09:38 INFO - 1500408578753 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
13:09:38 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578757 Marionette TRACE 384 <- [1,6,null,{}]
13:09:38 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578762 Marionette DEBUG Closed connection 384
13:09:38 INFO - 1500408578764 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49831
13:09:38 INFO - 1500408578766 Marionette DEBUG Closed connection 385
13:09:38 INFO - 1500408578768 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49832
13:09:38 INFO - 1500408578770 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:38 INFO - 1500408578772 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:38 INFO - 1500408578797 Marionette DEBUG Register listener.js for window 6442450945
13:09:38 INFO - 1500408578808 Marionette TRACE 386 <- [1,1,null,{"sessionId":"fef7092c-b265-4a08-b1b1-2f523c062193","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:38 INFO - 1500408578811 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
13:09:38 INFO - --DOMWINDOW == 22 (0000004E454B8800) [pid = 732] [serial = 8] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
13:09:38 INFO - --DOMWINDOW == 21 (0000004E41089800) [pid = 732] [serial = 9] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
13:09:38 INFO - --DOMWINDOW == 20 (0000004E439C0000) [pid = 732] [serial = 10] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
13:09:38 INFO - --DOMWINDOW == 19 (0000004E45ABF000) [pid = 732] [serial = 12] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
13:09:38 INFO - --DOMWINDOW == 18 (0000004E4694E800) [pid = 732] [serial = 13] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
13:09:38 INFO - --DOMWINDOW == 17 (0000004E41073000) [pid = 732] [serial = 14] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
13:09:38 INFO - --DOMWINDOW == 16 (0000004E43CEE800) [pid = 732] [serial = 15] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
13:09:38 INFO - --DOMWINDOW == 15 (0000004E44557000) [pid = 732] [serial = 16] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
13:09:38 INFO - --DOMWINDOW == 14 (0000004E43CE5800) [pid = 732] [serial = 11] [outer = 0000000000000000] [url = http://127.0.0.1:49338/test_accessibility.html]
13:09:38 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578815 Marionette TRACE 386 <- [1,2,null,{}]
13:09:38 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:38 INFO - 1500408578820 Marionette DEBUG Closed connection 386
13:09:38 INFO - 1500408578821 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49833
13:09:38 INFO - 1500408578823 Marionette DEBUG Closed connection 387
13:09:38 INFO - 1500408578824 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49834
13:09:38 INFO - 1500408578826 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:38 INFO - 1500408578829 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:39 INFO - 1500408579217 Marionette DEBUG Register listener.js for window 6442450945
13:09:39 INFO - 1500408579240 Marionette TRACE 388 <- [1,1,null,{"sessionId":"e9f31a86-79f9-4719-bee9-4b405bf624fb","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:39 INFO - 1500408579245 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
13:09:39 INFO - 1500408579294 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:39 INFO - 1500408579298 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:39 INFO - 1500408579299 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
13:09:39 INFO - ++DOMWINDOW == 15 (0000004E3D19C800) [pid = 732] [serial = 30] [outer = 0000004E3B9E7800]
13:09:39 INFO - 1500408579326 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:39 INFO - 1500408579330 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:39 INFO - 1500408579333 Marionette TRACE 388 <- [1,2,null,{}]
13:09:39 INFO - 1500408579345 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
13:09:39 INFO - 1500408579380 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d13b308a-2f9d-419a-bb86-e023ff914066","ELEMENT":"d13b308a-2f9d-419a-bb86-e023ff914066"}}]
13:09:39 INFO - 1500408579382 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"d13b308a-2f9d-419a-bb86-e023ff914066"}]
13:09:39 INFO - 1500408579538 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:09:39 INFO - 1500408579541 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
13:09:39 INFO - TEST-END: test_click.py TestClick.test_input_file1500408579580 Marionette TRACE 388 <- [1,5,null,{"value":null}]
13:09:39 INFO - 1500408579582 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
13:09:39 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579587 Marionette TRACE 388 <- [1,6,null,{}]
13:09:39 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 998ms
13:09:39 INFO - TEST-START | test_click.py TestClick.test_obscured_element
13:09:39 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579592 Marionette DEBUG Closed connection 388
13:09:39 INFO - 1500408579593 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49835
13:09:39 INFO - 1500408579595 Marionette DEBUG Closed connection 389
13:09:39 INFO - 1500408579596 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49836
13:09:39 INFO - 1500408579599 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:39 INFO - 1500408579601 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:39 INFO - 1500408579637 Marionette DEBUG Register listener.js for window 6442450945
13:09:39 INFO - 1500408579647 Marionette TRACE 390 <- [1,1,null,{"sessionId":"d8aa846e-4de3-46aa-ac2e-d0b30c417c3f","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:39 INFO - 1500408579650 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
13:09:39 INFO - 1500408579652 Marionette TRACE 390 <- [1,2,null,{}]
13:09:39 INFO - 1500408579654 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:39 INFO - 1500408579655 Marionette TRACE 390 <- [1,3,null,{}]
13:09:39 INFO - 1500408579657 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:39 INFO - 1500408579658 Marionette TRACE 390 <- [1,4,null,{}]
13:09:39 INFO - 1500408579662 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
13:09:39 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500408579703 Marionette TRACE 390 <- [1,5,null,{"value":null}]
13:09:39 INFO - 1500408579705 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
13:09:39 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579709 Marionette TRACE 390 <- [1,6,null,{}]
13:09:39 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579713 Marionette DEBUG Closed connection 390
13:09:39 INFO - 1500408579714 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49837
13:09:39 INFO - 1500408579717 Marionette DEBUG Closed connection 391
13:09:39 INFO - 1500408579719 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49838
13:09:39 INFO - 1500408579723 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:39 INFO - 1500408579726 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:39 INFO - 1500408579758 Marionette DEBUG Register listener.js for window 6442450945
13:09:39 INFO - 1500408579768 Marionette TRACE 392 <- [1,1,null,{"sessionId":"9ccba3f5-1c0e-4a3c-bd06-7aeb051e9607","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:39 INFO - 1500408579771 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
13:09:39 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579776 Marionette TRACE 392 <- [1,2,null,{}]
13:09:39 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:39 INFO - 1500408579780 Marionette DEBUG Closed connection 392
13:09:39 INFO - 1500408579782 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49839
13:09:39 INFO - 1500408579784 Marionette DEBUG Closed connection 393
13:09:39 INFO - 1500408579785 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49840
13:09:39 INFO - 1500408579787 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:39 INFO - 1500408579790 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:39 INFO - 1500408579816 Marionette DEBUG Register listener.js for window 6442450945
13:09:39 INFO - 1500408579827 Marionette TRACE 394 <- [1,1,null,{"sessionId":"e02cf436-8e7b-46f1-bad3-7534367f5979","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:39 INFO - 1500408579830 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
13:09:39 INFO - 1500408579858 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:39 INFO - 1500408579862 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:39 INFO - 1500408579863 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
13:09:39 INFO - ++DOMWINDOW == 16 (0000004E439C0000) [pid = 732] [serial = 31] [outer = 0000004E3B9E7800]
13:09:39 INFO - 1500408579890 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:09:39 INFO - 1500408579894 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:09:39 INFO - 1500408579900 Marionette TRACE 394 <- [1,2,null,{}]
13:09:39 INFO - 1500408579912 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
13:09:39 INFO - 1500408579919 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b1365e34-a82f-4687-9f05-3c694a8ff7ae","ELEMENT":"b1365e34-a82f-4687-9f05-3c694a8ff7ae"}}]
13:09:39 INFO - 1500408579928 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
13:09:39 INFO - 1500408579933 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"4ceb5b09-e257-402e-bd61-92c330944ddc","ELEMENT":"4ceb5b09-e257-402e-bd61-92c330944ddc"}}]
13:09:39 INFO - 1500408579935 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"b1365e34-a82f-4687-9f05-3c694a8ff7ae"}]
13:09:40 INFO - 1500408580364 Marionette DEBUG Canceled page load listener because no navigation has been detected
13:09:40 INFO - 1500408580366 Marionette TRACE 394 <- [1,5,null,{}]
13:09:40 INFO - 1500408580368 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"4ceb5b09-e257-402e-bd61-92c330944ddc"}]
13:09:40 INFO - 1500408580493 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:397:12\nclickElement@chrome://marionette/content/listener.js:1317:5\n"},null]
13:09:40 INFO - 1500408580496 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":259}]
13:09:40 INFO - 1500408580511 Marionette TRACE 394 <- [1,7,null,{"value":false}]
13:09:40 INFO - 1500408580514 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
13:09:40 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500408580520 Marionette TRACE 394 <- [1,8,null,{"value":null}]
13:09:40 INFO - 1500408580522 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
13:09:40 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:40 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 939ms
13:09:40 INFO - 1500408580527 Marionette TRACE 394 <- [1,9,null,{}]
13:09:40 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
13:09:40 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:40 INFO - 1500408580531 Marionette DEBUG Closed connection 394
13:09:40 INFO - 1500408580532 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49841
13:09:40 INFO - 1500408580534 Marionette DEBUG Closed connection 395
13:09:40 INFO - 1500408580535 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49842
13:09:40 INFO - 1500408580537 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:40 INFO - 1500408580539 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:40 INFO - 1500408580562 Marionette DEBUG Register listener.js for window 6442450945
13:09:40 INFO - 1500408580573 Marionette TRACE 396 <- [1,1,null,{"sessionId":"b072b3a0-2165-40c1-a854-0b3e3788a4eb","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:40 INFO - 1500408580576 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
13:09:40 INFO - 1500408580577 Marionette TRACE 396 <- [1,2,null,{}]
13:09:40 INFO - 1500408580579 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
13:09:40 INFO - 1500408580580 Marionette TRACE 396 <- [1,3,null,{}]
13:09:40 INFO - 1500408580582 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
13:09:40 INFO - 1500408580583 Marionette TRACE 396 <- [1,4,null,{}]
13:09:40 INFO - 1500408580586 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
13:09:40 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500408580593 Marionette TRACE 396 <- [1,5,null,{"value":null}]
13:09:40 INFO - 1500408580595 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
13:09:40 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:40 INFO - 1500408580600 Marionette TRACE 396 <- [1,6,null,{}]
13:09:40 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:40 INFO - 1500408580604 Marionette DEBUG Closed connection 396
13:09:40 INFO - 1500408580605 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49843
13:09:40 INFO - 1500408580607 Marionette DEBUG Closed connection 397
13:09:40 INFO - 1500408580608 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49844
13:09:40 INFO - 1500408580610 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
13:09:40 INFO - 1500408580612 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:40 INFO - 1500408580635 Marionette DEBUG Register listener.js for window 6442450945
13:09:40 INFO - 1500408580646 Marionette TRACE 398 <- [1,1,null,{"sessionId":"a897eea2-c2c7-40f1-a60a-3e9bb0d7c2bd","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:40 INFO - 1500408580649 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
13:09:40 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:40 INFO - 1500408580653 Marionette TRACE 398 <- [1,2,null,{}]
13:09:40 INFO - [Parent 2684] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
13:09:40 INFO - 1500408580657 Marionette DEBUG Closed connection 398
13:09:40 INFO - 1500408580658 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49845
13:09:40 INFO - 1500408580660 Marionette DEBUG Closed connection 399
13:09:40 INFO - 1500408580661 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49846
13:09:40 INFO - 1500408580664 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
13:09:40 INFO - 1500408580666 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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}
13:09:40 INFO - 1500408580690 Marionette DEBUG Register listener.js for window 6442450945
13:09:40 INFO - 1500408580700 Marionette TRACE 400 <- [1,1,null,{"sessionId":"18331e1c-f569-4db0-9f55-1d9902ec98a1","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":2684,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-295.001\\AppData\\Local\\Temp\\tmpn_i2wp.mozrunner","moz:accessibilityChecks":false}}]
13:09:40 INFO - 1500408580703 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
13:09:40 INFO - 1500408580707 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:09:40 INFO - 1500408580712 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:09:40 INFO - 1500408580713 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
13:09:40 INFO - ++DOMWINDOW == 17 (0000004E46D30800) [pid = 732] [serial = 32] [outer = 0000004E3B9E7800]
13:09:40 INFO - 1500408580738 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
13:09:40 INFO - 1500408580743 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
13:09:40 INFO - 1500408580747 Marionette TRACE 400 <- [1,2,null,{}]
13:09:40 INFO - 1500408580762 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
13:09:40 INFO - 1500408580789 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5a8d3f2b-9ad3-44c7-93fa-baca6a5e0a8c","ELEMENT":"5a8d3f2b-9ad3-44c7-93fa-baca6a5e0a8c"}}]
13:09:40 INFO - 1500408580791 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"5a8d3f2b-9ad3-44c7-93fa-baca6a5e0a8c"}]
13:09:40 INFO - 1500408580923 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
13:09:40 INFO - 1500408580925 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"5a8d3f2b-9ad3-44c7-93fa-baca6a5e0a8c"}]
13:09:41 INFO - 1500408581056 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element