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]
03:26:58 INFO - 1499855218456 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}]
03:26:58 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499855218464 Marionette TRACE 352 <- [1,5,null,{"value":null}]
03:26:58 INFO - 1499855218466 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
03:26:58 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:26:58 INFO - 1499855218470 Marionette TRACE 352 <- [1,6,null,{}]
03:26:58 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 853ms
03:26:58 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
03:26:58 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:26:58 INFO - 1499855218495 Marionette DEBUG Closed connection 352
03:26:58 INFO - 1499855218497 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49793
03:26:58 INFO - 1499855218501 Marionette DEBUG Closed connection 353
03:26:58 INFO - 1499855218502 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49794
03:26:58 INFO - 1499855218504 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:26:58 INFO - 1499855218506 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:26:58 INFO - 1499855218529 Marionette DEBUG Register listener.js for window 15
03:26:58 INFO - 1499855218540 Marionette TRACE 354 <- [1,1,null,{"sessionId":"3ca11bf7-aae6-400c-91cd-bc3f787666fa","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:26:58 INFO - 1499855218550 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
03:26:58 INFO - 1499855218552 Marionette TRACE 354 <- [1,2,null,{}]
03:26:58 INFO - 1499855218554 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:26:58 INFO - 1499855218555 Marionette TRACE 354 <- [1,3,null,{}]
03:26:58 INFO - 1499855218557 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
03:26:58 INFO - 1499855218559 Marionette TRACE 354 <- [1,4,null,{}]
03:26:58 INFO - 1499855218562 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}]
03:26:58 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499855218569 Marionette TRACE 354 <- [1,5,null,{"value":null}]
03:26:58 INFO - 1499855218571 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
03:26:58 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:26:58 INFO - 1499855218575 Marionette TRACE 354 <- [1,6,null,{}]
03:26:58 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:26:58 INFO - 1499855218600 Marionette DEBUG Closed connection 354
03:26:58 INFO - 1499855218601 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49795
03:26:58 INFO - 1499855218605 Marionette DEBUG Closed connection 355
03:26:58 INFO - 1499855218607 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49796
03:26:58 INFO - 1499855218661 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:26:58 INFO - 1499855218673 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:26:58 INFO - 1499855218720 Marionette DEBUG Register listener.js for window 15
03:26:58 INFO - 1499855218744 Marionette TRACE 356 <- [1,1,null,{"sessionId":"8d781ed6-dcbc-4088-be8c-c161a769904c","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:26:58 INFO - 1499855218762 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
03:26:58 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:26:58 INFO - 1499855218769 Marionette TRACE 356 <- [1,2,null,{}]
03:26:58 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:26:58 INFO - 1499855218779 Marionette DEBUG Closed connection 356
03:26:58 INFO - 1499855218782 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49797
03:26:58 INFO - 1499855218787 Marionette DEBUG Closed connection 357
03:26:58 INFO - 1499855218789 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49798
03:26:58 INFO - 1499855218795 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:26:58 INFO - 1499855218801 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:26:58 INFO - 1499855218849 Marionette DEBUG Register listener.js for window 15
03:26:58 INFO - 1499855218881 Marionette TRACE 358 <- [1,1,null,{"sessionId":"2df182a8-79e8-4eac-9fc0-27fe95b83c45","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:26:58 INFO - 1499855218893 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49330/clicks.html"}]
03:26:58 INFO - 1499855218901 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/hidden.html"
03:26:58 INFO - 1499855218924 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/hidden.html"
03:26:58 INFO - 1499855218925 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/hidden.html"
03:26:58 INFO - ++DOMWINDOW == 28 (000000FF3F56B800) [pid = 2112] [serial = 40] [outer = 000000FF4A948800]
03:26:59 INFO - 1499855219010 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/clicks.html"
03:26:59 INFO - 1499855219017 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/clicks.html"
03:26:59 INFO - 1499855219046 Marionette TRACE 358 <- [1,2,null,{}]
03:26:59 INFO - 1499855219083 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
03:26:59 INFO - 1499855219096 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"7f9a5d23-1917-4d0c-a612-8ab0194115fd","ELEMENT":"7f9a5d23-1917-4d0c-a612-8ab0194115fd"}}]
03:26:59 INFO - 1499855219111 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"7f9a5d23-1917-4d0c-a612-8ab0194115fd"}]
03:26:59 INFO - 1499855219557 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/clicks.html"
03:26:59 INFO - [Parent 2112] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 446
03:26:59 INFO - 1499855219578 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/clicks.html"
03:26:59 INFO - 1499855219580 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/clicks.html"
03:26:59 INFO - ++DOMWINDOW == 29 (000000FF46FBA800) [pid = 2112] [serial = 41] [outer = 000000FF4A948800]
03:26:59 INFO - 1499855219672 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49330/test.html"
03:26:59 INFO - 1499855219678 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49330/test.html"
03:26:59 INFO - 1499855219701 Marionette TRACE 358 <- [1,4,null,{}]
03:26:59 INFO - 1499855219706 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
03:26:59 INFO - 1499855219719 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"d0e7b74e-df60-4fcd-8379-00b2333b9794","ELEMENT":"d0e7b74e-df60-4fcd-8379-00b2333b9794"}}]
03:26:59 INFO - 1499855219753 Marionette TRACE 358 -> [0,6,"getTitle",{}]
03:26:59 INFO - 1499855219756 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
03:26:59 INFO - 1499855219760 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}]
03:26:59 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499855219771 Marionette TRACE 358 <- [1,7,null,{"value":null}]
03:26:59 INFO - 1499855219786 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
03:26:59 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:26:59 INFO - 1499855219793 Marionette TRACE 358 <- [1,8,null,{}]
03:26:59 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1323ms
03:26:59 INFO - TEST-START | test_click.py TestClick.test_container_element
03:26:59 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:26:59 INFO - 1499855219801 Marionette DEBUG Closed connection 358
03:26:59 INFO - 1499855219804 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49801
03:26:59 INFO - 1499855219827 Marionette DEBUG Closed connection 359
03:26:59 INFO - 1499855219829 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49802
03:26:59 INFO - 1499855219887 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:26:59 INFO - 1499855219892 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:26:59 INFO - 1499855219940 Marionette DEBUG Register listener.js for window 15
03:26:59 INFO - 1499855219959 Marionette TRACE 360 <- [1,1,null,{"sessionId":"eba08769-5a7f-4f00-821f-bf981ed37a7a","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:26:59 INFO - 1499855219973 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
03:26:59 INFO - 1499855219975 Marionette TRACE 360 <- [1,2,null,{}]
03:27:00 INFO - 1499855220027 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:27:00 INFO - 1499855220030 Marionette TRACE 360 <- [1,3,null,{}]
03:27:00 INFO - 1499855220033 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
03:27:00 INFO - 1499855220035 Marionette TRACE 360 <- [1,4,null,{}]
03:27:00 INFO - 1499855220038 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}]
03:27:00 INFO - TEST-START: test_click.py TestClick.test_container_element1499855220049 Marionette TRACE 360 <- [1,5,null,{"value":null}]
03:27:00 INFO - 1499855220101 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
03:27:00 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:00 INFO - 1499855220107 Marionette TRACE 360 <- [1,6,null,{}]
03:27:00 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:00 INFO - 1499855220132 Marionette DEBUG Closed connection 360
03:27:00 INFO - 1499855220135 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49803
03:27:00 INFO - 1499855220140 Marionette DEBUG Closed connection 361
03:27:00 INFO - 1499855220142 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49804
03:27:00 INFO - 1499855220190 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:00 INFO - 1499855220194 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:00 INFO - 1499855220242 Marionette DEBUG Register listener.js for window 15
03:27:00 INFO - 1499855220257 Marionette TRACE 362 <- [1,1,null,{"sessionId":"f9abcedc-1dee-42e7-abd5-d509cd313d6d","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:00 INFO - 1499855220268 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
03:27:00 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:00 INFO - 1499855220274 Marionette TRACE 362 <- [1,2,null,{}]
03:27:00 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:00 INFO - 1499855220302 Marionette DEBUG Closed connection 362
03:27:00 INFO - 1499855220304 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49805
03:27:00 INFO - 1499855220524 Marionette DEBUG Closed connection 363
03:27:00 INFO - 1499855220528 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49806
03:27:00 INFO - 1499855220536 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:27:00 INFO - 1499855220549 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:00 INFO - 1499855220576 Marionette DEBUG Register listener.js for window 15
03:27:00 INFO - 1499855220613 Marionette TRACE 364 <- [1,1,null,{"sessionId":"be0b288b-b310-49b7-836f-418510c250aa","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:00 INFO - 1499855220633 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"}]
03:27:00 INFO - 1499855220640 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49330/test.html"
03:27:00 INFO - 1499855220650 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49330/test.html"
03:27:00 INFO - 1499855220651 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49330/test.html"
03:27:00 INFO - ++DOMWINDOW == 30 (000000FF4219F000) [pid = 2112] [serial = 42] [outer = 000000FF4A948800]
03:27:00 INFO - 1499855220729 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"
03:27:00 INFO - 1499855220737 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"
03:27:00 INFO - 1499855220761 Marionette TRACE 364 <- [1,2,null,{}]
03:27:00 INFO - 1499855220767 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:27:00 INFO - 1499855220779 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"cbc46566-6367-4982-a200-c3f6e90ad322","ELEMENT":"cbc46566-6367-4982-a200-c3f6e90ad322"}}]
03:27:00 INFO - 1499855220789 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"cbc46566-6367-4982-a200-c3f6e90ad322"}]
03:27:01 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
03:27:01 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
03:27:01 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
03:27:01 INFO - 1499855221252 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:27:01 INFO - 1499855221255 Marionette TRACE 364 <- [1,4,null,{}]
03:27:01 INFO - 1499855221263 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"cbc46566-6367-4982-a200-c3f6e90ad322","name":"selected"}]
03:27:01 INFO - 1499855221299 Marionette TRACE 364 <- [1,5,null,{"value":true}]
03:27:01 INFO - 1499855221309 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}]
03:27:01 INFO - TEST-END: test_click.py TestClick.test_container_element1499855221320 Marionette TRACE 364 <- [1,6,null,{"value":null}]
03:27:01 INFO - 1499855221323 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
03:27:01 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:01 INFO - 1499855221329 Marionette TRACE 364 <- [1,7,null,{}]
03:27:01 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1535ms
03:27:01 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
03:27:01 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:01 INFO - 1499855221337 Marionette DEBUG Closed connection 364
03:27:01 INFO - 1499855221339 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49807
03:27:01 INFO - 1499855221341 Marionette DEBUG Closed connection 365
03:27:01 INFO - 1499855221345 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49808
03:27:01 INFO - 1499855221348 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:01 INFO - 1499855221352 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:01 INFO - 1499855221377 Marionette DEBUG Register listener.js for window 15
03:27:01 INFO - 1499855221393 Marionette TRACE 366 <- [1,1,null,{"sessionId":"ac341f27-cd9a-4d33-9ace-25aa64b50264","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:01 INFO - 1499855221413 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
03:27:01 INFO - 1499855221415 Marionette TRACE 366 <- [1,2,null,{}]
03:27:01 INFO - 1499855221442 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:27:01 INFO - 1499855221444 Marionette TRACE 366 <- [1,3,null,{}]
03:27:01 INFO - 1499855221451 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
03:27:01 INFO - 1499855221456 Marionette TRACE 366 <- [1,4,null,{}]
03:27:01 INFO - 1499855221486 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}]
03:27:01 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499855221497 Marionette TRACE 366 <- [1,5,null,{"value":null}]
03:27:01 INFO - 1499855221502 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
03:27:01 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:01 INFO - 1499855221508 Marionette TRACE 366 <- [1,6,null,{}]
03:27:01 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:01 INFO - 1499855221537 Marionette DEBUG Closed connection 366
03:27:01 INFO - 1499855221539 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49809
03:27:01 INFO - 1499855221546 Marionette DEBUG Closed connection 367
03:27:01 INFO - 1499855221550 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49810
03:27:01 INFO - 1499855221556 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:01 INFO - 1499855221559 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:01 INFO - 1499855221583 Marionette DEBUG Register listener.js for window 15
03:27:01 INFO - 1499855221600 Marionette TRACE 368 <- [1,1,null,{"sessionId":"2af44d88-d62c-4864-a532-2c4fafe6e50c","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:01 INFO - 1499855221611 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
03:27:01 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:01 INFO - 1499855221616 Marionette TRACE 368 <- [1,2,null,{}]
03:27:01 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:01 INFO - 1499855221626 Marionette DEBUG Closed connection 368
03:27:01 INFO - 1499855221628 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49811
03:27:01 INFO - 1499855221642 Marionette DEBUG Closed connection 369
03:27:01 INFO - 1499855221644 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49812
03:27:01 INFO - 1499855221671 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:27:01 INFO - 1499855221674 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:01 INFO - 1499855221698 Marionette DEBUG Register listener.js for window 15
03:27:01 INFO - 1499855221713 Marionette TRACE 370 <- [1,1,null,{"sessionId":"7242d17f-c1fc-4f92-962c-a9aa81c57ad1","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:01 INFO - --DOMWINDOW == 29 (000000FF494C4000) [pid = 2112] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
03:27:01 INFO - --DOMWINDOW == 28 (000000FF466D4000) [pid = 2112] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
03:27:01 INFO - --DOMWINDOW == 27 (000000FF45EB2800) [pid = 2112] [serial = 24] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
03:27:01 INFO - --DOMWINDOW == 26 (000000FF4CDBA800) [pid = 2112] [serial = 22] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
03:27:01 INFO - --DOMWINDOW == 25 (000000FF3F759800) [pid = 2112] [serial = 23] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
03:27:01 INFO - --DOMWINDOW == 24 (000000FF51287800) [pid = 2112] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
03:27:01 INFO - --DOMWINDOW == 23 (000000FF494C3000) [pid = 2112] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49330/test_accessibility.html]
03:27:01 INFO - 1499855221754 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"}]
03:27:01 INFO - 1499855221760 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"
03:27:01 INFO - 1499855221769 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"
03:27:01 INFO - 1499855221770 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"
03:27:01 INFO - ++DOMWINDOW == 24 (000000FF3E4D4000) [pid = 2112] [serial = 43] [outer = 000000FF4A948800]
03:27:01 INFO - 1499855221825 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"
03:27:01 INFO - 1499855221839 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"
03:27:01 INFO - 1499855221850 Marionette TRACE 370 <- [1,2,null,{}]
03:27:01 INFO - 1499855221863 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
03:27:01 INFO - 1499855221871 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b4490374-27eb-4e18-9bf4-06e8a91d2d5e","ELEMENT":"b4490374-27eb-4e18-9bf4-06e8a91d2d5e"}}]
03:27:01 INFO - 1499855221873 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"b4490374-27eb-4e18-9bf4-06e8a91d2d5e"}]
03:27:02 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
03:27:02 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
03:27:02 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
03:27:03 INFO - 1499855223063 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:27:03 INFO - 1499855223080 Marionette TRACE 370 <- [1,4,null,{}]
03:27:03 INFO - 1499855223084 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"b4490374-27eb-4e18-9bf4-06e8a91d2d5e","name":"selected"}]
03:27:03 INFO - 1499855223114 Marionette TRACE 370 <- [1,5,null,{"value":true}]
03:27:03 INFO - 1499855223163 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}]
03:27:03 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499855223175 Marionette TRACE 370 <- [1,6,null,{"value":null}]
03:27:03 INFO - 1499855223227 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
03:27:03 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:03 INFO - 1499855223232 Marionette TRACE 370 <- [1,7,null,{}]
03:27:03 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1903ms
03:27:03 INFO - TEST-START | test_click.py TestClick.test_css_transforms
03:27:03 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:03 INFO - 1499855223289 Marionette DEBUG Closed connection 370
03:27:03 INFO - 1499855223291 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49813
03:27:03 INFO - 1499855223344 Marionette DEBUG Closed connection 371
03:27:03 INFO - 1499855223346 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49814
03:27:03 INFO - 1499855223399 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:03 INFO - 1499855223404 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:03 INFO - 1499855223427 Marionette DEBUG Register listener.js for window 15
03:27:03 INFO - 1499855223443 Marionette TRACE 372 <- [1,1,null,{"sessionId":"39e7288e-bbcc-483b-bd2d-4860b99e8cd3","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:03 INFO - 1499855223496 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
03:27:03 INFO - 1499855223498 Marionette TRACE 372 <- [1,2,null,{}]
03:27:03 INFO - 1499855223521 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:27:03 INFO - 1499855223523 Marionette TRACE 372 <- [1,3,null,{}]
03:27:03 INFO - 1499855223525 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
03:27:03 INFO - 1499855223526 Marionette TRACE 372 <- [1,4,null,{}]
03:27:03 INFO - 1499855223530 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}]
03:27:03 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499855223540 Marionette TRACE 372 <- [1,5,null,{"value":null}]
03:27:03 INFO - 1499855223544 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
03:27:03 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:03 INFO - 1499855223548 Marionette TRACE 372 <- [1,6,null,{}]
03:27:03 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:03 INFO - 1499855223572 Marionette DEBUG Closed connection 372
03:27:03 INFO - 1499855223574 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49815
03:27:03 INFO - 1499855223580 Marionette DEBUG Closed connection 373
03:27:03 INFO - 1499855223585 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49816
03:27:03 INFO - 1499855223588 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:03 INFO - 1499855223591 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:03 INFO - 1499855223614 Marionette DEBUG Register listener.js for window 15
03:27:03 INFO - 1499855223629 Marionette TRACE 374 <- [1,1,null,{"sessionId":"f12e65b8-7eab-48aa-952c-080a993c5921","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:03 INFO - 1499855223639 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
03:27:03 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:03 INFO - 1499855223644 Marionette TRACE 374 <- [1,2,null,{}]
03:27:03 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:03 INFO - 1499855223666 Marionette DEBUG Closed connection 374
03:27:03 INFO - 1499855223668 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49817
03:27:03 INFO - 1499855223672 Marionette DEBUG Closed connection 375
03:27:03 INFO - 1499855223674 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49818
03:27:03 INFO - 1499855223676 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:27:03 INFO - 1499855223679 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:03 INFO - 1499855223702 Marionette DEBUG Register listener.js for window 15
03:27:03 INFO - 1499855223714 Marionette TRACE 376 <- [1,1,null,{"sessionId":"0b664f9f-3a51-4bd7-912d-42aa8802737d","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:03 INFO - 1499855223724 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"}]
03:27:03 INFO - 1499855223729 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"
03:27:03 INFO - 1499855223736 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"
03:27:03 INFO - 1499855223737 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"
03:27:03 INFO - ++DOMWINDOW == 25 (000000FF45EB8800) [pid = 2112] [serial = 44] [outer = 000000FF4A948800]
03:27:03 INFO - 1499855223790 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"
03:27:03 INFO - 1499855223798 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"
03:27:03 INFO - 1499855223814 Marionette TRACE 376 <- [1,2,null,{}]
03:27:03 INFO - 1499855223821 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
03:27:03 INFO - 1499855223831 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"61155b83-a68a-4013-9da2-caffbae77716","ELEMENT":"61155b83-a68a-4013-9da2-caffbae77716"}}]
03:27:03 INFO - 1499855223835 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"61155b83-a68a-4013-9da2-caffbae77716"}]
03:27:04 INFO - 1499855224281 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:27:04 INFO - 1499855224283 Marionette TRACE 376 <- [1,4,null,{}]
03:27:04 INFO - 1499855224286 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}]
03:27:04 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499855224294 Marionette TRACE 376 <- [1,5,null,{"value":null}]
03:27:04 INFO - 1499855224296 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
03:27:04 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:04 INFO - 1499855224300 Marionette TRACE 376 <- [1,6,null,{}]
03:27:04 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1068ms
03:27:04 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
03:27:04 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:04 INFO - 1499855224306 Marionette DEBUG Closed connection 376
03:27:04 INFO - 1499855224308 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49819
03:27:04 INFO - 1499855224309 Marionette DEBUG Closed connection 377
03:27:04 INFO - 1499855224311 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49820
03:27:04 INFO - 1499855224313 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:04 INFO - 1499855224316 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:04 INFO - 1499855224339 Marionette DEBUG Register listener.js for window 15
03:27:04 INFO - 1499855224350 Marionette TRACE 378 <- [1,1,null,{"sessionId":"7de13994-caf2-48d1-be20-e8ea2a95336d","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:04 INFO - 1499855224358 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
03:27:04 INFO - 1499855224360 Marionette TRACE 378 <- [1,2,null,{}]
03:27:04 INFO - 1499855224362 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:27:04 INFO - 1499855224363 Marionette TRACE 378 <- [1,3,null,{}]
03:27:04 INFO - 1499855224365 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
03:27:04 INFO - 1499855224367 Marionette TRACE 378 <- [1,4,null,{}]
03:27:04 INFO - 1499855224369 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}]
03:27:04 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499855224406 Marionette TRACE 378 <- [1,5,null,{"value":null}]
03:27:04 INFO - 1499855224408 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
03:27:04 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:04 INFO - 1499855224413 Marionette TRACE 378 <- [1,6,null,{}]
03:27:04 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:04 INFO - 1499855224421 Marionette DEBUG Closed connection 378
03:27:04 INFO - 1499855224422 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49821
03:27:04 INFO - 1499855224424 Marionette DEBUG Closed connection 379
03:27:04 INFO - 1499855224425 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49822
03:27:04 INFO - 1499855224427 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:04 INFO - 1499855224430 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:04 INFO - 1499855224453 Marionette DEBUG Register listener.js for window 15
03:27:04 INFO - 1499855224465 Marionette TRACE 380 <- [1,1,null,{"sessionId":"1d248a26-f17f-476a-b836-55a7966826c7","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:04 INFO - 1499855224471 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
03:27:04 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:04 INFO - 1499855224475 Marionette TRACE 380 <- [1,2,null,{}]
03:27:04 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:04 INFO - 1499855224481 Marionette DEBUG Closed connection 380
03:27:04 INFO - 1499855224483 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49823
03:27:04 INFO - 1499855224484 Marionette DEBUG Closed connection 381
03:27:04 INFO - 1499855224486 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49824
03:27:04 INFO - 1499855224488 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:27:04 INFO - 1499855224491 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:04 INFO - 1499855224515 Marionette DEBUG Register listener.js for window 15
03:27:04 INFO - 1499855224527 Marionette TRACE 382 <- [1,1,null,{"sessionId":"7f5241ac-4638-4dae-a22d-2f7693761f2a","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:04 INFO - 1499855224534 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"}]
03:27:04 INFO - 1499855224538 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"
03:27:04 INFO - 1499855224547 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"
03:27:04 INFO - 1499855224548 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"
03:27:04 INFO - ++DOMWINDOW == 26 (000000FF46FAA000) [pid = 2112] [serial = 45] [outer = 000000FF4A948800]
03:27:04 INFO - 1499855224602 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"
03:27:04 INFO - 1499855224608 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"
03:27:04 INFO - 1499855224626 Marionette TRACE 382 <- [1,2,null,{}]
03:27:04 INFO - 1499855224631 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
03:27:04 INFO - 1499855224640 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"00ef5bf3-e5e9-4a43-b3bb-f717b3d691b4","ELEMENT":"00ef5bf3-e5e9-4a43-b3bb-f717b3d691b4"}}]
03:27:04 INFO - 1499855224642 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"00ef5bf3-e5e9-4a43-b3bb-f717b3d691b4"}]
03:27:05 INFO - 1499855225194 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:27:05 INFO - 1499855225196 Marionette TRACE 382 <- [1,4,null,{}]
03:27:05 INFO - 1499855225201 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}]
03:27:05 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499855225210 Marionette TRACE 382 <- [1,5,null,{"value":null}]
03:27:05 INFO - 1499855225212 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
03:27:05 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:05 INFO - 1499855225216 Marionette TRACE 382 <- [1,6,null,{}]
03:27:05 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 916ms
03:27:05 INFO - TEST-START | test_click.py TestClick.test_input_file
03:27:05 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:05 INFO - 1499855225223 Marionette DEBUG Closed connection 382
03:27:05 INFO - 1499855225224 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49825
03:27:05 INFO - 1499855225227 Marionette DEBUG Closed connection 383
03:27:05 INFO - 1499855225229 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49826
03:27:05 INFO - 1499855225231 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:05 INFO - 1499855225235 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:05 INFO - 1499855225263 Marionette DEBUG Register listener.js for window 15
03:27:05 INFO - 1499855225277 Marionette TRACE 384 <- [1,1,null,{"sessionId":"8dac4b0c-4160-4b11-8388-8548c910b55a","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:05 INFO - 1499855225284 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
03:27:05 INFO - 1499855225285 Marionette TRACE 384 <- [1,2,null,{}]
03:27:05 INFO - 1499855225287 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:27:05 INFO - 1499855225289 Marionette TRACE 384 <- [1,3,null,{}]
03:27:05 INFO - 1499855225291 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
03:27:05 INFO - 1499855225292 Marionette TRACE 384 <- [1,4,null,{}]
03:27:05 INFO - 1499855225295 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}]
03:27:05 INFO - TEST-START: test_click.py TestClick.test_input_file1499855225303 Marionette TRACE 384 <- [1,5,null,{"value":null}]
03:27:05 INFO - 1499855225304 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
03:27:05 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:05 INFO - 1499855225309 Marionette TRACE 384 <- [1,6,null,{}]
03:27:05 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:05 INFO - 1499855225356 Marionette DEBUG Closed connection 384
03:27:05 INFO - 1499855225357 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49827
03:27:06 INFO - 1499855225997 Marionette DEBUG Closed connection 385
03:27:06 INFO - 1499855225998 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49828
03:27:06 INFO - 1499855226053 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:06 INFO - 1499855226055 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:06 INFO - 1499855226080 Marionette DEBUG Register listener.js for window 15
03:27:06 INFO - 1499855226092 Marionette TRACE 386 <- [1,1,null,{"sessionId":"e12cf67b-8f77-4c2c-93dd-8b0f6f0a235c","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:06 INFO - 1499855226135 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
03:27:06 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:06 INFO - 1499855226139 Marionette TRACE 386 <- [1,2,null,{}]
03:27:06 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:06 INFO - 1499855226173 Marionette DEBUG Closed connection 386
03:27:06 INFO - 1499855226174 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49829
03:27:06 INFO - 1499855226185 Marionette DEBUG Closed connection 387
03:27:06 INFO - 1499855226187 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49830
03:27:06 INFO - 1499855226189 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:27:06 INFO - 1499855226192 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:06 INFO - 1499855226217 Marionette DEBUG Register listener.js for window 15
03:27:06 INFO - 1499855226239 Marionette TRACE 388 <- [1,1,null,{"sessionId":"a4107e7b-5333-4d53-a8e8-3e2c95294038","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:06 INFO - 1499855226249 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
03:27:06 INFO - 1499855226254 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"
03:27:06 INFO - 1499855226261 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"
03:27:06 INFO - 1499855226263 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"
03:27:06 INFO - ++DOMWINDOW == 27 (000000FF46FB4800) [pid = 2112] [serial = 46] [outer = 000000FF4A948800]
03:27:06 INFO - 1499855226311 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:27:06 INFO - 1499855226325 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:27:06 INFO - 1499855226341 Marionette TRACE 388 <- [1,2,null,{}]
03:27:06 INFO - 1499855226344 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
03:27:06 INFO - 1499855226352 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b22862b3-fe92-433b-b92a-d5a3aaa9a408","ELEMENT":"b22862b3-fe92-433b-b92a-d5a3aaa9a408"}}]
03:27:06 INFO - 1499855226360 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"b22862b3-fe92-433b-b92a-d5a3aaa9a408"}]
03:27:06 INFO - 1499855226531 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]
03:27:06 INFO - 1499855226548 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}]
03:27:06 INFO - TEST-END: test_click.py TestClick.test_input_file1499855226563 Marionette TRACE 388 <- [1,5,null,{"value":null}]
03:27:06 INFO - 1499855226569 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
03:27:06 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:06 INFO - 1499855226579 Marionette TRACE 388 <- [1,6,null,{}]
03:27:06 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1363ms
03:27:06 INFO - TEST-START | test_click.py TestClick.test_obscured_element
03:27:06 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:06 INFO - 1499855226617 Marionette DEBUG Closed connection 388
03:27:06 INFO - 1499855226620 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49831
03:27:06 INFO - 1499855226641 Marionette DEBUG Closed connection 389
03:27:06 INFO - 1499855226644 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49832
03:27:06 INFO - 1499855226649 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:06 INFO - 1499855226660 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:06 INFO - 1499855226712 Marionette DEBUG Register listener.js for window 15
03:27:06 INFO - 1499855226735 Marionette TRACE 390 <- [1,1,null,{"sessionId":"0c7a590e-7164-44dd-98d7-a973045326dc","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:06 INFO - 1499855226761 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
03:27:06 INFO - 1499855226764 Marionette TRACE 390 <- [1,2,null,{}]
03:27:06 INFO - 1499855226771 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:27:06 INFO - 1499855226775 Marionette TRACE 390 <- [1,3,null,{}]
03:27:06 INFO - 1499855226778 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
03:27:06 INFO - 1499855226782 Marionette TRACE 390 <- [1,4,null,{}]
03:27:06 INFO - 1499855226834 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}]
03:27:06 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499855226850 Marionette TRACE 390 <- [1,5,null,{"value":null}]
03:27:06 INFO - 1499855226855 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
03:27:06 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:06 INFO - 1499855226862 Marionette TRACE 390 <- [1,6,null,{}]
03:27:06 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:06 INFO - 1499855226873 Marionette DEBUG Closed connection 390
03:27:06 INFO - 1499855226876 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49833
03:27:06 INFO - 1499855226879 Marionette DEBUG Closed connection 391
03:27:06 INFO - 1499855226881 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49834
03:27:06 INFO - 1499855226884 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:06 INFO - 1499855226888 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:06 INFO - 1499855226940 Marionette DEBUG Register listener.js for window 15
03:27:06 INFO - 1499855226969 Marionette TRACE 392 <- [1,1,null,{"sessionId":"0aff45d9-11bb-471f-b05f-84c8ebaa0343","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:07 INFO - 1499855227002 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
03:27:07 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:07 INFO - 1499855227010 Marionette TRACE 392 <- [1,2,null,{}]
03:27:07 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:07 INFO - 1499855227034 Marionette DEBUG Closed connection 392
03:27:07 INFO - 1499855227037 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49835
03:27:07 INFO - 1499855227090 Marionette DEBUG Closed connection 393
03:27:07 INFO - 1499855227092 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49836
03:27:07 INFO - 1499855227103 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:27:07 INFO - 1499855227110 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:07 INFO - 1499855227164 Marionette DEBUG Register listener.js for window 15
03:27:07 INFO - 1499855227181 Marionette TRACE 394 <- [1,1,null,{"sessionId":"10eb2018-3b88-4928-86ca-12c786c15fc4","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:07 INFO - 1499855227193 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"}]
03:27:07 INFO - 1499855227199 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:27:07 INFO - 1499855227211 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:27:07 INFO - 1499855227212 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
03:27:07 INFO - ++DOMWINDOW == 28 (000000FF43A31000) [pid = 2112] [serial = 47] [outer = 000000FF4A948800]
03:27:07 INFO - 1499855227323 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"
03:27:07 INFO - 1499855227332 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"
03:27:07 INFO - 1499855227352 Marionette TRACE 394 <- [1,2,null,{}]
03:27:07 INFO - 1499855227355 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
03:27:07 INFO - 1499855227368 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2b09bf49-888c-46e8-b0d4-8185a8da2f03","ELEMENT":"2b09bf49-888c-46e8-b0d4-8185a8da2f03"}}]
03:27:07 INFO - 1499855227370 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
03:27:07 INFO - 1499855227381 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"34cf286b-62fe-4fcd-a9ba-da92b6062222","ELEMENT":"34cf286b-62fe-4fcd-a9ba-da92b6062222"}}]
03:27:07 INFO - 1499855227383 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"2b09bf49-888c-46e8-b0d4-8185a8da2f03"}]
03:27:08 INFO - 1499855228047 Marionette DEBUG Canceled page load listener because no navigation has been detected
03:27:08 INFO - 1499855228049 Marionette TRACE 394 <- [1,5,null,{}]
03:27:08 INFO - 1499855228053 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"34cf286b-62fe-4fcd-a9ba-da92b6062222"}]
03:27:08 INFO - 1499855228400 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]
03:27:08 INFO - 1499855228406 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
03:27:08 INFO - 1499855228418 Marionette TRACE 394 <- [1,7,null,{"value":false}]
03:27:08 INFO - 1499855228420 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}]
03:27:08 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499855228430 Marionette TRACE 394 <- [1,8,null,{"value":null}]
03:27:08 INFO - 1499855228433 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
03:27:08 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:08 INFO - 1499855228439 Marionette TRACE 394 <- [1,9,null,{}]
03:27:08 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 1858ms
03:27:08 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
03:27:08 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:08 INFO - 1499855228490 Marionette DEBUG Closed connection 394
03:27:08 INFO - 1499855228492 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49837
03:27:08 INFO - 1499855228508 Marionette DEBUG Closed connection 395
03:27:08 INFO - 1499855228510 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49838
03:27:08 INFO - 1499855228514 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:08 INFO - 1499855228517 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:08 INFO - 1499855228570 Marionette DEBUG Register listener.js for window 15
03:27:08 INFO - 1499855228586 Marionette TRACE 396 <- [1,1,null,{"sessionId":"54838329-541d-427d-be05-6b540937f873","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:08 INFO - 1499855228642 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
03:27:08 INFO - 1499855228645 Marionette TRACE 396 <- [1,2,null,{}]
03:27:08 INFO - 1499855228650 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
03:27:08 INFO - 1499855228652 Marionette TRACE 396 <- [1,3,null,{}]
03:27:08 INFO - 1499855228655 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
03:27:08 INFO - 1499855228657 Marionette TRACE 396 <- [1,4,null,{}]
03:27:08 INFO - 1499855228666 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}]
03:27:08 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499855228677 Marionette TRACE 396 <- [1,5,null,{"value":null}]
03:27:08 INFO - 1499855228681 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
03:27:08 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:08 INFO - 1499855228687 Marionette TRACE 396 <- [1,6,null,{}]
03:27:08 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:08 INFO - 1499855228710 Marionette DEBUG Closed connection 396
03:27:08 INFO - 1499855228713 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49839
03:27:08 INFO - 1499855228881 Marionette DEBUG Closed connection 397
03:27:08 INFO - 1499855228884 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49840
03:27:08 INFO - 1499855228892 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
03:27:08 INFO - 1499855228903 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:08 INFO - 1499855228931 Marionette DEBUG Register listener.js for window 15
03:27:08 INFO - 1499855228958 Marionette TRACE 398 <- [1,1,null,{"sessionId":"9174ad94-4c00-4efb-8760-a4d3eadc2d36","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:08 INFO - 1499855228984 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
03:27:08 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:08 INFO - 1499855228991 Marionette TRACE 398 <- [1,2,null,{}]
03:27:09 INFO - [Parent 2112] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
03:27:09 INFO - 1499855229009 Marionette DEBUG Closed connection 398
03:27:09 INFO - 1499855229011 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49841
03:27:09 INFO - 1499855229043 Marionette DEBUG Closed connection 399
03:27:09 INFO - 1499855229048 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49842
03:27:09 INFO - 1499855229075 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
03:27:09 INFO - 1499855229082 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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}
03:27:09 INFO - 1499855229109 Marionette DEBUG Register listener.js for window 15
03:27:09 INFO - 1499855229127 Marionette TRACE 400 <- [1,1,null,{"sessionId":"f5a388a6-9e35-47b6-aff2-dd548ff0ab67","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":2112,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-281.000\\AppData\\Local\\Temp\\tmpbkuioy.mozrunner","moz:accessibilityChecks":false}}]
03:27:09 INFO - 1499855229154 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"}]
03:27:09 INFO - 1499855229163 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"
03:27:09 INFO - 1499855229173 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"
03:27:09 INFO - 1499855229175 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"
03:27:09 INFO - ++DOMWINDOW == 29 (000000FF49D1B000) [pid = 2112] [serial = 48] [outer = 000000FF4A948800]
03:27:09 INFO - 1499855229253 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"
03:27:09 INFO - 1499855229260 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"
03:27:09 INFO - 1499855229279 Marionette TRACE 400 <- [1,2,null,{}]
03:27:09 INFO - 1499855229294 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
03:27:09 INFO - 1499855229306 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"52b415df-99a7-419e-bdfc-fbe009c0236a","ELEMENT":"52b415df-99a7-419e-bdfc-fbe009c0236a"}}]
03:27:09 INFO - 1499855229309 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"52b415df-99a7-419e-bdfc-fbe009c0236a"}]
03:27:09 INFO - 1499855229452 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
03:27:09 INFO - 1499855229455 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"52b415df-99a7-419e-bdfc-fbe009c0236a"}]
03:27:09 INFO - 1499855229623 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element