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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:02:48 INFO - --DOMWINDOW == 24 (000000039ED19000) [pid = 3236] [serial = 25] [outer = 0000000000000000] [url = http://127.0.0.1:49346/test_accessibility.html]
05:02:48 INFO - --DOMWINDOW == 23 (00000003944E2800) [pid = 3236] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49346/test_accessibility.html]
05:02:48 INFO - --DOMWINDOW == 22 (00000003A0011000) [pid = 3236] [serial = 31] [outer = 0000000000000000] [url = http://127.0.0.1:49346/test_accessibility.html]
05:02:48 INFO - --DOMWINDOW == 21 (000000039E68F000) [pid = 3236] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49346/test_accessibility.html]
05:02:48 INFO - --DOMWINDOW == 20 (000000039CAC2800) [pid = 3236] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49346/test_accessibility.html]
05:02:48 INFO - --DOMWINDOW == 19 (00000003998E3000) [pid = 3236] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49346/test_accessibility.html]
05:02:48 INFO - --DOMWINDOW == 18 (000000038CDD0000) [pid = 3236] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49346/test_accessibility.html]
05:02:48 INFO - 1499688168105 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}]
05:02:48 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1499688168114 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:02:48 INFO - 1499688168116 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:02:48 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:48 INFO - 1499688168120 Marionette TRACE 352 <- [1,6,null,{}]
05:02:48 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 777ms
05:02:48 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:02:48 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:48 INFO - 1499688168127 Marionette DEBUG Closed connection 352
05:02:48 INFO - 1499688168129 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49796
05:02:48 INFO - 1499688168130 Marionette DEBUG Closed connection 353
05:02:48 INFO - 1499688168132 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49797
05:02:48 INFO - 1499688168134 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:48 INFO - 1499688168139 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:48 INFO - 1499688168163 Marionette DEBUG Register listener.js for window 12
05:02:48 INFO - 1499688168177 Marionette TRACE 354 <- [1,1,null,{"sessionId":"97ba0e6c-992d-4cf4-8337-8c60d781b62e","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:48 INFO - 1499688168186 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:02:48 INFO - 1499688168188 Marionette TRACE 354 <- [1,2,null,{}]
05:02:48 INFO - 1499688168190 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:48 INFO - 1499688168191 Marionette TRACE 354 <- [1,3,null,{}]
05:02:48 INFO - 1499688168215 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:48 INFO - 1499688168217 Marionette TRACE 354 <- [1,4,null,{}]
05:02:48 INFO - 1499688168318 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}]
05:02:48 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1499688168331 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:02:48 INFO - 1499688168335 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:02:48 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:48 INFO - 1499688168340 Marionette TRACE 354 <- [1,6,null,{}]
05:02:48 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:48 INFO - 1499688168350 Marionette DEBUG Closed connection 354
05:02:48 INFO - 1499688168355 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49798
05:02:48 INFO - 1499688168375 Marionette DEBUG Closed connection 355
05:02:48 INFO - 1499688168376 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49799
05:02:48 INFO - 1499688168380 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:48 INFO - 1499688168383 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:48 INFO - 1499688168407 Marionette DEBUG Register listener.js for window 12
05:02:48 INFO - 1499688168422 Marionette TRACE 356 <- [1,1,null,{"sessionId":"f9575655-849d-4207-b677-1f2e334e720d","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:48 INFO - 1499688168430 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:02:48 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:48 INFO - 1499688168434 Marionette TRACE 356 <- [1,2,null,{}]
05:02:48 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:48 INFO - 1499688168459 Marionette DEBUG Closed connection 356
05:02:48 INFO - 1499688168461 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49800
05:02:48 INFO - 1499688168462 Marionette DEBUG Closed connection 357
05:02:48 INFO - 1499688168464 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49801
05:02:48 INFO - 1499688168466 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:48 INFO - 1499688168469 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:48 INFO - 1499688168491 Marionette DEBUG Register listener.js for window 12
05:02:48 INFO - 1499688168503 Marionette TRACE 358 <- [1,1,null,{"sessionId":"f7291553-09bf-4404-9376-89af0fbd3b0c","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:48 INFO - 1499688168515 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49346/clicks.html"}]
05:02:48 INFO - 1499688168520 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49346/hidden.html"
05:02:48 INFO - 1499688168533 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49346/hidden.html"
05:02:48 INFO - 1499688168534 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49346/hidden.html"
05:02:48 INFO - ++DOMWINDOW == 19 (0000000392FD8800) [pid = 3236] [serial = 41] [outer = 0000000397A87000]
05:02:48 INFO - 1499688168597 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49346/clicks.html"
05:02:48 INFO - 1499688168603 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49346/clicks.html"
05:02:48 INFO - 1499688168626 Marionette TRACE 358 <- [1,2,null,{}]
05:02:48 INFO - 1499688168629 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:02:48 INFO - 1499688168640 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"0b1fa3a8-1210-4151-8e65-4c6d6c6a02c7","ELEMENT":"0b1fa3a8-1210-4151-8e65-4c6d6c6a02c7"}}]
05:02:48 INFO - 1499688168648 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"0b1fa3a8-1210-4151-8e65-4c6d6c6a02c7"}]
05:02:48 INFO - 1499688168900 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49346/clicks.html"
05:02:48 INFO - [Parent 3236] 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
05:02:48 INFO - 1499688168922 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49346/clicks.html"
05:02:48 INFO - 1499688168923 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49346/clicks.html"
05:02:48 INFO - ++DOMWINDOW == 20 (00000003A0016000) [pid = 3236] [serial = 42] [outer = 0000000397A87000]
05:02:49 INFO - 1499688169009 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49346/test.html"
05:02:49 INFO - 1499688169014 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49346/test.html"
05:02:49 INFO - 1499688169030 Marionette TRACE 358 <- [1,4,null,{}]
05:02:49 INFO - 1499688169728 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:02:49 INFO - 1499688169737 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a4493062-ce54-45fb-9f51-af79e54f58e9","ELEMENT":"a4493062-ce54-45fb-9f51-af79e54f58e9"}}]
05:02:49 INFO - 1499688169739 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:02:49 INFO - 1499688169741 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:02:49 INFO - 1499688169744 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}]
05:02:49 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1499688169753 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:02:49 INFO - 1499688169755 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:02:49 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:49 INFO - 1499688169760 Marionette TRACE 358 <- [1,8,null,{}]
05:02:49 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1639ms
05:02:49 INFO - TEST-START | test_click.py TestClick.test_container_element
05:02:49 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:49 INFO - 1499688169784 Marionette DEBUG Closed connection 358
05:02:49 INFO - 1499688169786 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49804
05:02:49 INFO - 1499688169788 Marionette DEBUG Closed connection 359
05:02:49 INFO - 1499688169789 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49805
05:02:49 INFO - 1499688169791 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:49 INFO - 1499688169795 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:49 INFO - 1499688169817 Marionette DEBUG Register listener.js for window 12
05:02:49 INFO - 1499688169874 Marionette TRACE 360 <- [1,1,null,{"sessionId":"a460c122-f4cd-4179-a998-fa68b020faf0","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:49 INFO - 1499688169936 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:02:49 INFO - 1499688169938 Marionette TRACE 360 <- [1,2,null,{}]
05:02:49 INFO - 1499688169973 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:49 INFO - 1499688169975 Marionette TRACE 360 <- [1,3,null,{}]
05:02:49 INFO - 1499688169985 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:49 INFO - 1499688169987 Marionette TRACE 360 <- [1,4,null,{}]
05:02:49 INFO - 1499688169990 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}]
05:02:50 INFO - TEST-START: test_click.py TestClick.test_container_element1499688170000 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:02:50 INFO - 1499688170003 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:50 INFO - 1499688170007 Marionette TRACE 360 <- [1,6,null,{}]
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:50 INFO - 1499688170029 Marionette DEBUG Closed connection 360
05:02:50 INFO - 1499688170030 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49806
05:02:50 INFO - 1499688170034 Marionette DEBUG Closed connection 361
05:02:50 INFO - 1499688170036 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49807
05:02:50 INFO - 1499688170038 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:50 INFO - 1499688170040 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:50 INFO - 1499688170063 Marionette DEBUG Register listener.js for window 12
05:02:50 INFO - 1499688170075 Marionette TRACE 362 <- [1,1,null,{"sessionId":"d68af70f-f9bd-49d1-a396-921bfa53dfc8","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:50 INFO - 1499688170085 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:50 INFO - 1499688170089 Marionette TRACE 362 <- [1,2,null,{}]
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:50 INFO - 1499688170097 Marionette DEBUG Closed connection 362
05:02:50 INFO - 1499688170098 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49808
05:02:50 INFO - 1499688170100 Marionette DEBUG Closed connection 363
05:02:50 INFO - 1499688170101 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49809
05:02:50 INFO - 1499688170103 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:50 INFO - 1499688170106 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:50 INFO - 1499688170129 Marionette DEBUG Register listener.js for window 12
05:02:50 INFO - 1499688170140 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1ac5fff6-c1fc-4acb-b5bd-5cb073fb8aa7","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:50 INFO - 1499688170147 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"}]
05:02:50 INFO - 1499688170151 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49346/test.html"
05:02:50 INFO - 1499688170160 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49346/test.html"
05:02:50 INFO - 1499688170161 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49346/test.html"
05:02:50 INFO - ++DOMWINDOW == 21 (00000003A1931800) [pid = 3236] [serial = 43] [outer = 0000000397A87000]
05:02:50 INFO - 1499688170217 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"
05:02:50 INFO - 1499688170235 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"
05:02:50 INFO - 1499688170250 Marionette TRACE 364 <- [1,2,null,{}]
05:02:50 INFO - 1499688170278 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:02:50 INFO - 1499688170285 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e206138b-fecb-4462-8832-448a6da247d3","ELEMENT":"e206138b-fecb-4462-8832-448a6da247d3"}}]
05:02:50 INFO - 1499688170287 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"e206138b-fecb-4462-8832-448a6da247d3"}]
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
05:02:50 INFO - 1499688170728 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:02:50 INFO - 1499688170730 Marionette TRACE 364 <- [1,4,null,{}]
05:02:50 INFO - 1499688170732 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"e206138b-fecb-4462-8832-448a6da247d3","name":"selected"}]
05:02:50 INFO - 1499688170755 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:02:50 INFO - 1499688170757 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}]
05:02:50 INFO - TEST-END: test_click.py TestClick.test_container_element1499688170766 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:02:50 INFO - 1499688170768 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:50 INFO - 1499688170772 Marionette TRACE 364 <- [1,7,null,{}]
05:02:50 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1012ms
05:02:50 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:50 INFO - 1499688170778 Marionette DEBUG Closed connection 364
05:02:50 INFO - 1499688170779 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49810
05:02:50 INFO - 1499688170781 Marionette DEBUG Closed connection 365
05:02:50 INFO - 1499688170782 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49811
05:02:50 INFO - 1499688170784 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:50 INFO - 1499688170787 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:50 INFO - 1499688170815 Marionette DEBUG Register listener.js for window 12
05:02:50 INFO - 1499688170858 Marionette TRACE 366 <- [1,1,null,{"sessionId":"e2b5d683-1e8b-46e3-91fa-921d76987085","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:50 INFO - 1499688170867 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:02:50 INFO - 1499688170869 Marionette TRACE 366 <- [1,2,null,{}]
05:02:50 INFO - 1499688170871 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:50 INFO - 1499688170873 Marionette TRACE 366 <- [1,3,null,{}]
05:02:50 INFO - 1499688170875 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:50 INFO - 1499688170876 Marionette TRACE 366 <- [1,4,null,{}]
05:02:50 INFO - 1499688170879 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}]
05:02:50 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1499688170886 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:02:50 INFO - 1499688170889 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:50 INFO - 1499688170893 Marionette TRACE 366 <- [1,6,null,{}]
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:50 INFO - 1499688170899 Marionette DEBUG Closed connection 366
05:02:50 INFO - 1499688170901 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49812
05:02:50 INFO - 1499688170902 Marionette DEBUG Closed connection 367
05:02:50 INFO - 1499688170904 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49813
05:02:50 INFO - 1499688170906 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:50 INFO - 1499688170908 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:50 INFO - 1499688170932 Marionette DEBUG Register listener.js for window 12
05:02:50 INFO - 1499688170943 Marionette TRACE 368 <- [1,1,null,{"sessionId":"dd37f9bd-a7db-4596-9304-bf55f95a87cb","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:50 INFO - 1499688170950 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:50 INFO - 1499688170954 Marionette TRACE 368 <- [1,2,null,{}]
05:02:50 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:50 INFO - 1499688170960 Marionette DEBUG Closed connection 368
05:02:50 INFO - 1499688170962 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49814
05:02:50 INFO - 1499688170963 Marionette DEBUG Closed connection 369
05:02:50 INFO - 1499688170965 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49815
05:02:50 INFO - 1499688170967 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:50 INFO - 1499688170969 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:50 INFO - 1499688170992 Marionette DEBUG Register listener.js for window 12
05:02:51 INFO - 1499688171003 Marionette TRACE 370 <- [1,1,null,{"sessionId":"44c75e34-3da2-424b-9a9a-0f7049f81326","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:51 INFO - 1499688171010 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"}]
05:02:51 INFO - 1499688171013 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"
05:02:51 INFO - 1499688171019 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"
05:02:51 INFO - 1499688171020 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"
05:02:51 INFO - ++DOMWINDOW == 22 (00000003A0EB4000) [pid = 3236] [serial = 44] [outer = 0000000397A87000]
05:02:51 INFO - 1499688171064 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"
05:02:51 INFO - 1499688171079 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"
05:02:51 INFO - 1499688171094 Marionette TRACE 370 <- [1,2,null,{}]
05:02:51 INFO - 1499688171097 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:02:51 INFO - 1499688171105 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6e969e66-e984-4ec8-9576-0d841fd66843","ELEMENT":"6e969e66-e984-4ec8-9576-0d841fd66843"}}]
05:02:51 INFO - 1499688171107 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"6e969e66-e984-4ec8-9576-0d841fd66843"}]
05:02:51 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
05:02:51 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
05:02:51 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
05:02:51 INFO - 1499688171604 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:02:51 INFO - 1499688171606 Marionette TRACE 370 <- [1,4,null,{}]
05:02:51 INFO - 1499688171608 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"6e969e66-e984-4ec8-9576-0d841fd66843","name":"selected"}]
05:02:51 INFO - 1499688171630 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:02:51 INFO - 1499688171633 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}]
05:02:51 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1499688171640 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:02:51 INFO - 1499688171642 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:02:51 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:51 INFO - 1499688171646 Marionette TRACE 370 <- [1,7,null,{}]
05:02:51 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 874ms
05:02:51 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:02:51 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:51 INFO - 1499688171652 Marionette DEBUG Closed connection 370
05:02:51 INFO - 1499688171653 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49816
05:02:51 INFO - 1499688171655 Marionette DEBUG Closed connection 371
05:02:51 INFO - 1499688171657 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49817
05:02:51 INFO - 1499688171659 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:51 INFO - 1499688171661 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:51 INFO - 1499688171684 Marionette DEBUG Register listener.js for window 12
05:02:51 INFO - 1499688171733 Marionette TRACE 372 <- [1,1,null,{"sessionId":"02c4940b-a69b-4d06-8ca2-005da016f4c1","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:51 INFO - 1499688171745 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:02:51 INFO - 1499688171746 Marionette TRACE 372 <- [1,2,null,{}]
05:02:51 INFO - 1499688171748 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:51 INFO - 1499688171750 Marionette TRACE 372 <- [1,3,null,{}]
05:02:51 INFO - 1499688171751 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:51 INFO - 1499688171753 Marionette TRACE 372 <- [1,4,null,{}]
05:02:51 INFO - 1499688171755 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}]
05:02:51 INFO - TEST-START: test_click.py TestClick.test_css_transforms1499688171763 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:02:51 INFO - 1499688171765 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:02:51 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:51 INFO - 1499688171769 Marionette TRACE 372 <- [1,6,null,{}]
05:02:51 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:51 INFO - 1499688171775 Marionette DEBUG Closed connection 372
05:02:51 INFO - 1499688171776 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49818
05:02:51 INFO - 1499688171778 Marionette DEBUG Closed connection 373
05:02:51 INFO - 1499688171779 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49819
05:02:51 INFO - 1499688171781 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:51 INFO - 1499688171783 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:51 INFO - 1499688171807 Marionette DEBUG Register listener.js for window 12
05:02:51 INFO - 1499688171818 Marionette TRACE 374 <- [1,1,null,{"sessionId":"43ff9e9d-fa34-4efc-b246-9d4ff3675e04","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:51 INFO - 1499688171824 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:02:51 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:51 INFO - 1499688171828 Marionette TRACE 374 <- [1,2,null,{}]
05:02:51 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:51 INFO - 1499688171833 Marionette DEBUG Closed connection 374
05:02:51 INFO - 1499688171835 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49820
05:02:51 INFO - 1499688171836 Marionette DEBUG Closed connection 375
05:02:51 INFO - 1499688171838 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49821
05:02:51 INFO - 1499688171840 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:51 INFO - 1499688171843 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:51 INFO - 1499688171866 Marionette DEBUG Register listener.js for window 12
05:02:51 INFO - 1499688171878 Marionette TRACE 376 <- [1,1,null,{"sessionId":"38c48abb-c4eb-4564-a995-338f2b1ff6d8","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:51 INFO - 1499688171884 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"}]
05:02:51 INFO - 1499688171888 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"
05:02:51 INFO - 1499688171894 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"
05:02:51 INFO - 1499688171894 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"
05:02:51 INFO - ++DOMWINDOW == 23 (00000003A1396800) [pid = 3236] [serial = 45] [outer = 0000000397A87000]
05:02:51 INFO - 1499688171942 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"
05:02:51 INFO - 1499688171950 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"
05:02:51 INFO - 1499688171966 Marionette TRACE 376 <- [1,2,null,{}]
05:02:52 INFO - 1499688172019 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:02:52 INFO - 1499688172026 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6dffee0c-b05e-4a2c-a4ab-87a4cbc15e3c","ELEMENT":"6dffee0c-b05e-4a2c-a4ab-87a4cbc15e3c"}}]
05:02:52 INFO - 1499688172028 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"6dffee0c-b05e-4a2c-a4ab-87a4cbc15e3c"}]
05:02:52 INFO - 1499688172432 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:02:52 INFO - 1499688172433 Marionette TRACE 376 <- [1,4,null,{}]
05:02:52 INFO - 1499688172439 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}]
05:02:52 INFO - TEST-END: test_click.py TestClick.test_css_transforms1499688172450 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:02:52 INFO - 1499688172453 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:02:52 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:52 INFO - 1499688172457 Marionette TRACE 376 <- [1,6,null,{}]
05:02:52 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 812ms
05:02:52 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:02:52 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:52 INFO - 1499688172463 Marionette DEBUG Closed connection 376
05:02:52 INFO - 1499688172464 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49822
05:02:52 INFO - 1499688172466 Marionette DEBUG Closed connection 377
05:02:52 INFO - 1499688172467 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49823
05:02:52 INFO - 1499688172469 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:52 INFO - 1499688172472 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:52 INFO - 1499688172495 Marionette DEBUG Register listener.js for window 12
05:02:52 INFO - 1499688172506 Marionette TRACE 378 <- [1,1,null,{"sessionId":"cf6f1230-29e0-453d-a968-e164261d366a","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:53 INFO - 1499688173187 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:02:53 INFO - 1499688173188 Marionette TRACE 378 <- [1,2,null,{}]
05:02:53 INFO - 1499688173199 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:53 INFO - 1499688173200 Marionette TRACE 378 <- [1,3,null,{}]
05:02:53 INFO - 1499688173203 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:53 INFO - 1499688173204 Marionette TRACE 378 <- [1,4,null,{}]
05:02:53 INFO - 1499688173258 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}]
05:02:53 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1499688173270 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:02:53 INFO - 1499688173324 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:02:53 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:53 INFO - 1499688173335 Marionette TRACE 378 <- [1,6,null,{}]
05:02:53 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:53 INFO - 1499688173394 Marionette DEBUG Closed connection 378
05:02:53 INFO - 1499688173397 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49824
05:02:53 INFO - 1499688173420 Marionette DEBUG Closed connection 379
05:02:53 INFO - 1499688173422 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49825
05:02:53 INFO - 1499688173428 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:53 INFO - 1499688173438 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:53 INFO - 1499688173484 Marionette DEBUG Register listener.js for window 12
05:02:53 INFO - 1499688173507 Marionette TRACE 380 <- [1,1,null,{"sessionId":"601ad8b8-95cb-4c39-b318-6e86c46fd68a","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:53 INFO - 1499688173527 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:02:53 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:53 INFO - 1499688173535 Marionette TRACE 380 <- [1,2,null,{}]
05:02:53 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:53 INFO - 1499688173563 Marionette DEBUG Closed connection 380
05:02:53 INFO - 1499688173566 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49826
05:02:53 INFO - 1499688173569 Marionette DEBUG Closed connection 381
05:02:53 INFO - 1499688173572 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49827
05:02:53 INFO - 1499688173578 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:53 INFO - 1499688173582 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:53 INFO - 1499688173627 Marionette DEBUG Register listener.js for window 12
05:02:53 INFO - 1499688173645 Marionette TRACE 382 <- [1,1,null,{"sessionId":"f70256ec-57ef-439e-b329-33ae4d74ab09","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:53 INFO - 1499688173669 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"}]
05:02:53 INFO - 1499688173677 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"
05:02:53 INFO - 1499688173688 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"
05:02:53 INFO - 1499688173689 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"
05:02:53 INFO - ++DOMWINDOW == 24 (0000000392FD8000) [pid = 3236] [serial = 46] [outer = 0000000397A87000]
05:02:53 INFO - 1499688173749 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"
05:02:53 INFO - 1499688173766 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"
05:02:53 INFO - 1499688173786 Marionette TRACE 382 <- [1,2,null,{}]
05:02:53 INFO - 1499688173798 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:02:53 INFO - 1499688173810 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6bb4c638-3459-46cf-994f-500a3220c105","ELEMENT":"6bb4c638-3459-46cf-994f-500a3220c105"}}]
05:02:53 INFO - 1499688173814 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"6bb4c638-3459-46cf-994f-500a3220c105"}]
05:02:54 INFO - 1499688174378 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:02:54 INFO - 1499688174381 Marionette TRACE 382 <- [1,4,null,{}]
05:02:54 INFO - 1499688174409 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}]
05:02:54 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1499688174421 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:02:54 INFO - 1499688174426 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:02:54 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:54 INFO - 1499688174433 Marionette TRACE 382 <- [1,6,null,{}]
05:02:54 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1975ms
05:02:54 INFO - TEST-START | test_click.py TestClick.test_input_file
05:02:54 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:54 INFO - 1499688174455 Marionette DEBUG Closed connection 382
05:02:54 INFO - 1499688174457 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49828
05:02:54 INFO - 1499688174633 Marionette DEBUG Closed connection 383
05:02:54 INFO - 1499688174636 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49829
05:02:54 INFO - 1499688174642 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:54 INFO - 1499688174652 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:54 INFO - 1499688174679 Marionette DEBUG Register listener.js for window 12
05:02:54 INFO - 1499688174729 Marionette TRACE 384 <- [1,1,null,{"sessionId":"b01c9b41-c9da-46e5-92b9-f4153f014402","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:54 INFO - 1499688174751 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:02:54 INFO - 1499688174754 Marionette TRACE 384 <- [1,2,null,{}]
05:02:54 INFO - 1499688174774 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:54 INFO - 1499688174777 Marionette TRACE 384 <- [1,3,null,{}]
05:02:54 INFO - 1499688174785 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:54 INFO - 1499688174789 Marionette TRACE 384 <- [1,4,null,{}]
05:02:54 INFO - 1499688174847 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}]
05:02:54 INFO - TEST-START: test_click.py TestClick.test_input_file1499688174860 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:02:54 INFO - 1499688174866 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:02:54 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:54 INFO - 1499688174873 Marionette TRACE 384 <- [1,6,null,{}]
05:02:54 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:54 INFO - 1499688174883 Marionette DEBUG Closed connection 384
05:02:54 INFO - 1499688174886 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49830
05:02:54 INFO - 1499688174906 Marionette DEBUG Closed connection 385
05:02:54 INFO - 1499688174911 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49831
05:02:54 INFO - 1499688174914 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:54 INFO - 1499688174919 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:54 INFO - 1499688174944 Marionette DEBUG Register listener.js for window 12
05:02:54 INFO - 1499688174960 Marionette TRACE 386 <- [1,1,null,{"sessionId":"67281743-290e-4b3b-bd58-ccf8dfeb4b44","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:54 INFO - 1499688174970 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:02:54 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:54 INFO - 1499688174976 Marionette TRACE 386 <- [1,2,null,{}]
05:02:54 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:54 INFO - 1499688174984 Marionette DEBUG Closed connection 386
05:02:54 INFO - 1499688174986 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49832
05:02:55 INFO - 1499688175009 Marionette DEBUG Closed connection 387
05:02:55 INFO - 1499688175012 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49833
05:02:55 INFO - 1499688175019 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:55 INFO - 1499688175023 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:55 INFO - 1499688175048 Marionette DEBUG Register listener.js for window 12
05:02:55 INFO - 1499688175063 Marionette TRACE 388 <- [1,1,null,{"sessionId":"4e4be918-8b68-4a44-aa44-a4ff94046324","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:55 INFO - --DOMWINDOW == 23 (00000003A01A7000) [pid = 3236] [serial = 32] [outer = 0000000000000000] [url = http://127.0.0.1:49346/test_accessibility.html]
05:02:55 INFO - --DOMWINDOW == 22 (000000039CAAC800) [pid = 3236] [serial = 35] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20vertically%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
05:02:55 INFO - --DOMWINDOW == 21 (000000039AC1E800) [pid = 3236] [serial = 34] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20horizontally%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
05:02:55 INFO - --DOMWINDOW == 20 (000000038DBEF800) [pid = 3236] [serial = 33] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%20%2A/%0A%20%20%20%20%20%20%20%20%20%20%20%20%20left%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20top%3A%20-105px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E]
05:02:55 INFO - 1499688175079 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:02:55 INFO - 1499688175084 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"
05:02:55 INFO - 1499688175094 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"
05:02:55 INFO - 1499688175096 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"
05:02:55 INFO - ++DOMWINDOW == 21 (000000038DA8F000) [pid = 3236] [serial = 47] [outer = 0000000397A87000]
05:02:55 INFO - 1499688175147 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:02:55 INFO - 1499688175163 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:02:55 INFO - 1499688175181 Marionette TRACE 388 <- [1,2,null,{}]
05:02:55 INFO - 1499688175197 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:02:55 INFO - 1499688175209 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a75d2819-d878-40e8-b7ae-624c806dbf5a","ELEMENT":"a75d2819-d878-40e8-b7ae-624c806dbf5a"}}]
05:02:55 INFO - 1499688175211 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"a75d2819-d878-40e8-b7ae-624c806dbf5a"}]
05:02:55 INFO - 1499688175358 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:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:02:55 INFO - 1499688175377 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}]
05:02:55 INFO - TEST-END: test_click.py TestClick.test_input_file1499688175388 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:02:55 INFO - 1499688175390 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:02:55 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:55 INFO - 1499688175395 Marionette TRACE 388 <- [1,6,null,{}]
05:02:55 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 962ms
05:02:55 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:02:55 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:55 INFO - 1499688175403 Marionette DEBUG Closed connection 388
05:02:55 INFO - 1499688175405 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49834
05:02:55 INFO - 1499688175426 Marionette DEBUG Closed connection 389
05:02:55 INFO - 1499688175428 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49835
05:02:55 INFO - 1499688175536 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:55 INFO - 1499688175538 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:55 INFO - 1499688175564 Marionette DEBUG Register listener.js for window 12
05:02:55 INFO - 1499688175577 Marionette TRACE 390 <- [1,1,null,{"sessionId":"e74c9fcf-5bdd-4b6f-802c-0ee2aff25169","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:55 INFO - 1499688175589 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:02:55 INFO - 1499688175591 Marionette TRACE 390 <- [1,2,null,{}]
05:02:55 INFO - 1499688175594 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:55 INFO - 1499688175596 Marionette TRACE 390 <- [1,3,null,{}]
05:02:55 INFO - 1499688175598 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:55 INFO - 1499688175600 Marionette TRACE 390 <- [1,4,null,{}]
05:02:55 INFO - 1499688175603 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}]
05:02:55 INFO - TEST-START: test_click.py TestClick.test_obscured_element1499688175612 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:02:55 INFO - 1499688175615 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:02:55 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:55 INFO - 1499688175620 Marionette TRACE 390 <- [1,6,null,{}]
05:02:55 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:55 INFO - 1499688175642 Marionette DEBUG Closed connection 390
05:02:55 INFO - 1499688175644 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49836
05:02:55 INFO - 1499688175648 Marionette DEBUG Closed connection 391
05:02:55 INFO - 1499688175650 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49837
05:02:55 INFO - 1499688175654 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:55 INFO - 1499688175659 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:55 INFO - 1499688175684 Marionette DEBUG Register listener.js for window 12
05:02:55 INFO - 1499688175700 Marionette TRACE 392 <- [1,1,null,{"sessionId":"0bd0a89f-d3f0-43ce-b5a1-88cbc14dadd7","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:55 INFO - 1499688175715 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:02:55 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:55 INFO - 1499688175719 Marionette TRACE 392 <- [1,2,null,{}]
05:02:56 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:56 INFO - 1499688176434 Marionette DEBUG Closed connection 392
05:02:56 INFO - 1499688176436 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49838
05:02:56 INFO - 1499688176460 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49839
05:02:56 INFO - 1499688176464 Marionette DEBUG Closed connection 393
05:02:56 INFO - 1499688176508 Marionette DEBUG Closed connection 394
05:02:56 INFO - 1499688176510 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49840
05:02:56 INFO - 1499688176513 Marionette TRACE 395 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:56 INFO - 1499688176515 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:56 INFO - 1499688176541 Marionette DEBUG Register listener.js for window 12
05:02:56 INFO - 1499688176555 Marionette TRACE 395 <- [1,1,null,{"sessionId":"dcecfd69-16b6-4481-a615-91fb52e834a8","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:56 INFO - 1499688176562 Marionette TRACE 395 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
05:02:56 INFO - 1499688176566 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:02:56 INFO - 1499688176575 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:02:56 INFO - 1499688176576 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:02:56 INFO - ++DOMWINDOW == 22 (000000039B87F800) [pid = 3236] [serial = 48] [outer = 0000000397A87000]
05:02:56 INFO - 1499688176690 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"
05:02:56 INFO - 1499688176696 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"
05:02:56 INFO - 1499688176713 Marionette TRACE 395 <- [1,2,null,{}]
05:02:56 INFO - 1499688176717 Marionette TRACE 395 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:02:56 INFO - 1499688176776 Marionette TRACE 395 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"55a94a17-865b-4eb4-991c-942c4e618c43","ELEMENT":"55a94a17-865b-4eb4-991c-942c4e618c43"}}]
05:02:56 INFO - 1499688176828 Marionette TRACE 395 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:02:56 INFO - 1499688176836 Marionette TRACE 395 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b68a9ae7-6d31-4e1c-b267-56531a297dc7","ELEMENT":"b68a9ae7-6d31-4e1c-b267-56531a297dc7"}}]
05:02:56 INFO - 1499688176837 Marionette TRACE 395 -> [0,5,"clickElement",{"id":"55a94a17-865b-4eb4-991c-942c4e618c43"}]
05:02:57 INFO - 1499688177298 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:02:57 INFO - 1499688177300 Marionette TRACE 395 <- [1,5,null,{}]
05:02:57 INFO - 1499688177302 Marionette TRACE 395 -> [0,6,"clickElement",{"id":"b68a9ae7-6d31-4e1c-b267-56531a297dc7"}]
05:02:57 INFO - 1499688177419 Marionette TRACE 395 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:392:12\nclickElement@chrome://marionette/content/listener.js:1312:5\n"},null]
05:02:57 INFO - 1499688177423 Marionette TRACE 395 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:02:57 INFO - 1499688177431 Marionette TRACE 395 <- [1,7,null,{"value":false}]
05:02:57 INFO - 1499688177434 Marionette TRACE 395 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
05:02:57 INFO - TEST-END: test_click.py TestClick.test_obscured_element1499688177441 Marionette TRACE 395 <- [1,8,null,{"value":null}]
05:02:57 INFO - 1499688177443 Marionette TRACE 395 -> [0,9,"deleteSession",{}]
05:02:57 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:57 INFO - 1499688177448 Marionette TRACE 395 <- [1,9,null,{}]
05:02:57 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 2053ms
05:02:57 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:02:57 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:57 INFO - 1499688177455 Marionette DEBUG Closed connection 395
05:02:57 INFO - 1499688177456 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49841
05:02:57 INFO - 1499688177458 Marionette DEBUG Closed connection 396
05:02:57 INFO - 1499688177459 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49842
05:02:57 INFO - 1499688177462 Marionette TRACE 397 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:57 INFO - 1499688177464 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:57 INFO - 1499688177489 Marionette DEBUG Register listener.js for window 12
05:02:57 INFO - 1499688177501 Marionette TRACE 397 <- [1,1,null,{"sessionId":"e591207c-00ed-4e5f-9ae7-afa6ed07c6cf","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:57 INFO - 1499688177507 Marionette TRACE 397 -> [0,2,"setTimeouts",{"script":30000}]
05:02:57 INFO - 1499688177509 Marionette TRACE 397 <- [1,2,null,{}]
05:02:57 INFO - 1499688177511 Marionette TRACE 397 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:02:57 INFO - 1499688177512 Marionette TRACE 397 <- [1,3,null,{}]
05:02:57 INFO - 1499688177514 Marionette TRACE 397 -> [0,4,"setTimeouts",{"implicit":0}]
05:02:57 INFO - 1499688177516 Marionette TRACE 397 <- [1,4,null,{}]
05:02:57 INFO - 1499688177518 Marionette TRACE 397 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
05:02:57 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1499688177527 Marionette TRACE 397 <- [1,5,null,{"value":null}]
05:02:57 INFO - 1499688177529 Marionette TRACE 397 -> [0,6,"deleteSession",{}]
05:02:57 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:57 INFO - 1499688177534 Marionette TRACE 397 <- [1,6,null,{}]
05:02:57 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:57 INFO - 1499688177626 Marionette DEBUG Closed connection 397
05:02:57 INFO - 1499688177627 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49843
05:02:57 INFO - 1499688177641 Marionette DEBUG Closed connection 398
05:02:57 INFO - 1499688177648 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49844
05:02:57 INFO - 1499688177658 Marionette TRACE 399 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:02:57 INFO - 1499688177662 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:57 INFO - 1499688177687 Marionette DEBUG Register listener.js for window 12
05:02:57 INFO - 1499688177699 Marionette TRACE 399 <- [1,1,null,{"sessionId":"ab1a05bb-9575-48b3-bc75-aaec566e2461","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:57 INFO - 1499688177712 Marionette TRACE 399 -> [0,2,"deleteSession",{}]
05:02:57 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:57 INFO - 1499688177717 Marionette TRACE 399 <- [1,2,null,{}]
05:02:57 INFO - [Parent 3236] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:02:57 INFO - 1499688177730 Marionette DEBUG Closed connection 399
05:02:57 INFO - 1499688177732 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49845
05:02:57 INFO - 1499688177761 Marionette DEBUG Closed connection 400
05:02:57 INFO - 1499688177762 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49846
05:02:57 INFO - 1499688177776 Marionette TRACE 401 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:02:57 INFO - 1499688177779 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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}
05:02:57 INFO - 1499688177804 Marionette DEBUG Register listener.js for window 12
05:02:57 INFO - 1499688177817 Marionette TRACE 401 <- [1,1,null,{"sessionId":"c96a8fea-ad24-4b88-a338-cb019d5527c3","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":3236,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-091.000\\AppData\\Local\\Temp\\tmph5qsk4.mozrunner","moz:accessibilityChecks":false}}]
05:02:57 INFO - 1499688177827 Marionette TRACE 401 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
05:02:57 INFO - 1499688177831 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"
05:02:57 INFO - 1499688177838 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"
05:02:57 INFO - 1499688177839 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"
05:02:57 INFO - ++DOMWINDOW == 23 (00000003A0EAD800) [pid = 3236] [serial = 49] [outer = 0000000397A87000]
05:02:57 INFO - 1499688177896 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"
05:02:57 INFO - 1499688177902 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"
05:02:57 INFO - 1499688177918 Marionette TRACE 401 <- [1,2,null,{}]
05:02:57 INFO - 1499688177925 Marionette TRACE 401 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:02:57 INFO - 1499688177933 Marionette TRACE 401 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"1b6a79d1-8d5a-4406-a711-91a8690a00e2","ELEMENT":"1b6a79d1-8d5a-4406-a711-91a8690a00e2"}}]
05:02:57 INFO - 1499688177935 Marionette TRACE 401 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"1b6a79d1-8d5a-4406-a711-91a8690a00e2"}]
05:02:58 INFO - 1499688178063 Marionette TRACE 401 <- [1,4,null,{"value":"none"}]
05:02:58 INFO - 1499688178073 Marionette TRACE 401 -> [0,5,"clickElement",{"id":"1b6a79d1-8d5a-4406-a711-91a8690a00e2"}]
05:02:58 INFO - 1499688178195 Marionette TRACE 401 <- [1,5,{"error":"element click intercepted","message":"Element