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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
19:39:51 INFO - 1498876791765 Marionette TRACE 360 -> [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}]
19:39:51 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498876791777 Marionette TRACE 360 <- [1,5,null,{"value":null}]
19:39:51 INFO - 1498876791779 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
19:39:51 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:51 INFO - 1498876791784 Marionette TRACE 360 <- [1,6,null,{}]
19:39:51 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 832ms
19:39:51 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
19:39:51 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:51 INFO - 1498876791790 Marionette DEBUG Closed connection 360
19:39:51 INFO - 1498876791792 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49731
19:39:51 INFO - 1498876791815 Marionette DEBUG Closed connection 361
19:39:51 INFO - 1498876791817 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49732
19:39:51 INFO - 1498876791823 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:51 INFO - 1498876791825 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:51 INFO - 1498876791849 Marionette DEBUG Register listener.js for window 7
19:39:51 INFO - 1498876791865 Marionette TRACE 362 <- [1,1,null,{"sessionId":"072b53a2-e2c8-4e89-8837-32bebc9da76c","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:51 INFO - 1498876791872 Marionette TRACE 362 -> [0,2,"setTimeouts",{"script":30000}]
19:39:51 INFO - 1498876791874 Marionette TRACE 362 <- [1,2,null,{}]
19:39:52 INFO - 1498876792694 Marionette TRACE 362 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:39:52 INFO - 1498876792696 Marionette TRACE 362 <- [1,3,null,{}]
19:39:52 INFO - 1498876792743 Marionette TRACE 362 -> [0,4,"setTimeouts",{"implicit":0}]
19:39:52 INFO - 1498876792745 Marionette TRACE 362 <- [1,4,null,{}]
19:39:52 INFO - 1498876792748 Marionette TRACE 362 -> [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}]
19:39:52 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498876792756 Marionette TRACE 362 <- [1,5,null,{"value":null}]
19:39:52 INFO - 1498876792757 Marionette TRACE 362 -> [0,6,"deleteSession",{}]
19:39:52 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:52 INFO - 1498876792762 Marionette TRACE 362 <- [1,6,null,{}]
19:39:52 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:52 INFO - 1498876792770 Marionette DEBUG Closed connection 362
19:39:52 INFO - 1498876792776 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49733
19:39:52 INFO - 1498876792778 Marionette DEBUG Closed connection 363
19:39:52 INFO - 1498876792780 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49734
19:39:52 INFO - 1498876792784 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:52 INFO - 1498876792788 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:52 INFO - 1498876792812 Marionette DEBUG Register listener.js for window 7
19:39:52 INFO - 1498876792876 Marionette TRACE 364 <- [1,1,null,{"sessionId":"1c17cf7e-e027-44ec-b0ea-d60cfcda7121","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:52 INFO - 1498876792929 Marionette TRACE 364 -> [0,2,"deleteSession",{}]
19:39:52 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:52 INFO - 1498876792933 Marionette TRACE 364 <- [1,2,null,{}]
19:39:52 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:52 INFO - 1498876792991 Marionette DEBUG Closed connection 364
19:39:52 INFO - 1498876792992 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49735
19:39:53 INFO - 1498876793044 Marionette DEBUG Closed connection 365
19:39:53 INFO - 1498876793046 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49736
19:39:53 INFO - 1498876793086 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:39:53 INFO - 1498876793089 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:53 INFO - 1498876793113 Marionette DEBUG Register listener.js for window 7
19:39:53 INFO - 1498876793125 Marionette TRACE 366 <- [1,1,null,{"sessionId":"b43d519e-a576-4969-8a33-eb65d250fd89","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:53 INFO - 1498876793147 Marionette TRACE 366 -> [0,2,"get",{"url":"http://127.0.0.1:49329/clicks.html"}]
19:39:53 INFO - 1498876793151 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/hidden.html"
19:39:53 INFO - 1498876793165 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/hidden.html"
19:39:53 INFO - 1498876793166 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/hidden.html"
19:39:53 INFO - ++DOMWINDOW == 25 (0000005AD0DC0000) [pid = 3536] [serial = 39] [outer = 0000005AD0E93800]
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - 1498876793231 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/clicks.html"
19:39:53 INFO - 1498876793236 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/clicks.html"
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - 1498876793260 Marionette TRACE 366 <- [1,2,null,{}]
19:39:53 INFO - 1498876793262 Marionette TRACE 366 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
19:39:53 INFO - 1498876793274 Marionette TRACE 366 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"024e2e1a-9562-4673-8caf-f6dadef05982","ELEMENT":"024e2e1a-9562-4673-8caf-f6dadef05982"}}]
19:39:53 INFO - 1498876793276 Marionette TRACE 366 -> [0,4,"clickElement",{"id":"024e2e1a-9562-4673-8caf-f6dadef05982"}]
19:39:53 INFO - 1498876793549 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/clicks.html"
19:39:53 INFO - [3536] WARNING: Cannot switch to prerendered document in the parent process: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/xpfe/appshell/nsContentTreeOwner.cpp, line 445
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - 1498876793568 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/clicks.html"
19:39:53 INFO - 1498876793570 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/clicks.html"
19:39:53 INFO - ++DOMWINDOW == 26 (0000005AD0DC5000) [pid = 3536] [serial = 40] [outer = 0000005AD0E93800]
19:39:53 INFO - 1498876793625 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49329/test.html"
19:39:53 INFO - 1498876793631 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49329/test.html"
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:53 INFO - 1498876793647 Marionette TRACE 366 <- [1,4,null,{}]
19:39:53 INFO - 1498876793652 Marionette TRACE 366 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
19:39:53 INFO - 1498876793661 Marionette TRACE 366 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"58832253-e96d-46b2-821c-a2bddf2eacd8","ELEMENT":"58832253-e96d-46b2-821c-a2bddf2eacd8"}}]
19:39:53 INFO - 1498876793662 Marionette TRACE 366 -> [0,6,"getTitle",{}]
19:39:53 INFO - 1498876793667 Marionette TRACE 366 <- [1,6,null,{"value":"Marionette Test"}]
19:39:53 INFO - 1498876793678 Marionette TRACE 366 -> [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}]
19:39:53 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498876793686 Marionette TRACE 366 <- [1,7,null,{"value":null}]
19:39:53 INFO - 1498876793688 Marionette TRACE 366 -> [0,8,"deleteSession",{}]
19:39:53 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:53 INFO - 1498876793692 Marionette TRACE 366 <- [1,8,null,{}]
19:39:53 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 1908ms
19:39:53 INFO - TEST-START | test_click.py TestClick.test_container_element
19:39:53 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:53 INFO - 1498876793699 Marionette DEBUG Closed connection 366
19:39:53 INFO - 1498876793701 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49739
19:39:53 INFO - 1498876793702 Marionette DEBUG Closed connection 367
19:39:53 INFO - 1498876793704 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49740
19:39:53 INFO - 1498876793706 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:53 INFO - 1498876793708 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:53 INFO - 1498876793731 Marionette DEBUG Register listener.js for window 7
19:39:53 INFO - 1498876793744 Marionette TRACE 368 <- [1,1,null,{"sessionId":"003683f0-1c88-4ce1-a53d-0711f199be28","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:53 INFO - 1498876793772 Marionette TRACE 368 -> [0,2,"setTimeouts",{"script":30000}]
19:39:53 INFO - 1498876793773 Marionette TRACE 368 <- [1,2,null,{}]
19:39:53 INFO - 1498876793775 Marionette TRACE 368 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:39:53 INFO - 1498876793777 Marionette TRACE 368 <- [1,3,null,{}]
19:39:53 INFO - 1498876793779 Marionette TRACE 368 -> [0,4,"setTimeouts",{"implicit":0}]
19:39:53 INFO - 1498876793781 Marionette TRACE 368 <- [1,4,null,{}]
19:39:53 INFO - 1498876793784 Marionette TRACE 368 -> [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}]
19:39:53 INFO - TEST-START: test_click.py TestClick.test_container_element1498876793793 Marionette TRACE 368 <- [1,5,null,{"value":null}]
19:39:53 INFO - 1498876793795 Marionette TRACE 368 -> [0,6,"deleteSession",{}]
19:39:53 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:53 INFO - 1498876793799 Marionette TRACE 368 <- [1,6,null,{}]
19:39:53 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:53 INFO - 1498876793805 Marionette DEBUG Closed connection 368
19:39:53 INFO - 1498876793807 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49741
19:39:53 INFO - 1498876793808 Marionette DEBUG Closed connection 369
19:39:53 INFO - 1498876793811 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49742
19:39:53 INFO - 1498876793835 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:53 INFO - 1498876793837 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:53 INFO - 1498876793860 Marionette DEBUG Register listener.js for window 7
19:39:53 INFO - 1498876793873 Marionette TRACE 370 <- [1,1,null,{"sessionId":"91e9f1be-b773-486e-8d73-d1c9a674d897","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:53 INFO - 1498876793878 Marionette TRACE 370 -> [0,2,"deleteSession",{}]
19:39:53 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:53 INFO - 1498876793883 Marionette TRACE 370 <- [1,2,null,{}]
19:39:53 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:53 INFO - 1498876793906 Marionette DEBUG Closed connection 370
19:39:53 INFO - 1498876793908 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49743
19:39:53 INFO - 1498876793911 Marionette DEBUG Closed connection 371
19:39:53 INFO - 1498876793913 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49744
19:39:53 INFO - 1498876793915 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:39:53 INFO - 1498876793918 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:53 INFO - 1498876793941 Marionette DEBUG Register listener.js for window 7
19:39:53 INFO - 1498876793955 Marionette TRACE 372 <- [1,1,null,{"sessionId":"2521ca63-90ff-43ae-a4f8-0e900cf1f0e6","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:53 INFO - 1498876793963 Marionette TRACE 372 -> [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"}]
19:39:53 INFO - 1498876793967 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49329/test.html"
19:39:53 INFO - 1498876793976 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49329/test.html"
19:39:53 INFO - 1498876793977 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49329/test.html"
19:39:53 INFO - ++DOMWINDOW == 27 (0000005AD25B8000) [pid = 3536] [serial = 41] [outer = 0000005AD0E93800]
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - 1498876794042 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"
19:39:54 INFO - 1498876794050 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"
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - 1498876794068 Marionette TRACE 372 <- [1,2,null,{}]
19:39:54 INFO - 1498876794074 Marionette TRACE 372 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:39:54 INFO - 1498876794081 Marionette TRACE 372 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"09a8629a-8058-47ba-8a81-2618228e285d","ELEMENT":"09a8629a-8058-47ba-8a81-2618228e285d"}}]
19:39:54 INFO - 1498876794084 Marionette TRACE 372 -> [0,4,"clickElement",{"id":"09a8629a-8058-47ba-8a81-2618228e285d"}]
19:39:54 INFO - [3536] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
19:39:54 INFO - [3536] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
19:39:54 INFO - [3536] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
19:39:54 INFO - 1498876794559 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:39:54 INFO - 1498876794561 Marionette TRACE 372 <- [1,4,null,{}]
19:39:54 INFO - 1498876794563 Marionette TRACE 372 -> [0,5,"getElementProperty",{"id":"09a8629a-8058-47ba-8a81-2618228e285d","name":"selected"}]
19:39:54 INFO - 1498876794593 Marionette TRACE 372 <- [1,5,null,{"value":true}]
19:39:54 INFO - 1498876794595 Marionette TRACE 372 -> [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}]
19:39:54 INFO - TEST-END: test_click.py TestClick.test_container_element1498876794603 Marionette TRACE 372 <- [1,6,null,{"value":null}]
19:39:54 INFO - 1498876794605 Marionette TRACE 372 -> [0,7,"deleteSession",{}]
19:39:54 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:54 INFO - 1498876794609 Marionette TRACE 372 <- [1,7,null,{}]
19:39:54 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 916ms
19:39:54 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
19:39:54 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:54 INFO - 1498876794615 Marionette DEBUG Closed connection 372
19:39:54 INFO - 1498876794617 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49745
19:39:54 INFO - 1498876794618 Marionette DEBUG Closed connection 373
19:39:54 INFO - 1498876794620 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49746
19:39:54 INFO - 1498876794622 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:54 INFO - 1498876794626 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:54 INFO - 1498876794654 Marionette DEBUG Register listener.js for window 7
19:39:54 INFO - 1498876794704 Marionette TRACE 374 <- [1,1,null,{"sessionId":"442c3709-3df3-4e17-b145-fb536f9b55e0","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:54 INFO - 1498876794707 Marionette TRACE 374 -> [0,2,"setTimeouts",{"script":30000}]
19:39:54 INFO - 1498876794709 Marionette TRACE 374 <- [1,2,null,{}]
19:39:54 INFO - 1498876794711 Marionette TRACE 374 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:39:54 INFO - 1498876794713 Marionette TRACE 374 <- [1,3,null,{}]
19:39:54 INFO - 1498876794716 Marionette TRACE 374 -> [0,4,"setTimeouts",{"implicit":0}]
19:39:54 INFO - 1498876794717 Marionette TRACE 374 <- [1,4,null,{}]
19:39:54 INFO - 1498876794720 Marionette TRACE 374 -> [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}]
19:39:54 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498876794729 Marionette TRACE 374 <- [1,5,null,{"value":null}]
19:39:54 INFO - 1498876794731 Marionette TRACE 374 -> [0,6,"deleteSession",{}]
19:39:54 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:54 INFO - 1498876794735 Marionette TRACE 374 <- [1,6,null,{}]
19:39:54 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:54 INFO - 1498876794742 Marionette DEBUG Closed connection 374
19:39:54 INFO - 1498876794743 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49747
19:39:54 INFO - 1498876794745 Marionette DEBUG Closed connection 375
19:39:54 INFO - 1498876794746 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49748
19:39:54 INFO - 1498876794749 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:54 INFO - 1498876794751 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:54 INFO - 1498876794774 Marionette DEBUG Register listener.js for window 7
19:39:54 INFO - 1498876794786 Marionette TRACE 376 <- [1,1,null,{"sessionId":"2183c8bd-4b59-40e8-9fec-8383351e18e3","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:54 INFO - 1498876794789 Marionette TRACE 376 -> [0,2,"deleteSession",{}]
19:39:54 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:54 INFO - 1498876794793 Marionette TRACE 376 <- [1,2,null,{}]
19:39:54 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:54 INFO - 1498876794799 Marionette DEBUG Closed connection 376
19:39:54 INFO - 1498876794801 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49749
19:39:54 INFO - 1498876794802 Marionette DEBUG Closed connection 377
19:39:54 INFO - 1498876794804 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49750
19:39:54 INFO - 1498876794806 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:39:54 INFO - 1498876794808 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:54 INFO - 1498876794832 Marionette DEBUG Register listener.js for window 7
19:39:54 INFO - 1498876794857 Marionette TRACE 378 <- [1,1,null,{"sessionId":"4a75944b-7f84-4807-bd9f-482186d2e8cd","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:54 INFO - 1498876794860 Marionette TRACE 378 -> [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"}]
19:39:54 INFO - 1498876794864 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"
19:39:54 INFO - 1498876794870 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"
19:39:54 INFO - 1498876794871 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"
19:39:54 INFO - ++DOMWINDOW == 28 (0000005ACCB17800) [pid = 3536] [serial = 42] [outer = 0000005AD0E93800]
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - 1498876794916 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"
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - 1498876794932 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"
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:54 INFO - 1498876794948 Marionette TRACE 378 <- [1,2,null,{}]
19:39:54 INFO - 1498876794951 Marionette TRACE 378 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
19:39:54 INFO - 1498876794958 Marionette TRACE 378 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"64806b38-50f0-4c98-b3d2-9a40a8ba838b","ELEMENT":"64806b38-50f0-4c98-b3d2-9a40a8ba838b"}}]
19:39:54 INFO - 1498876794961 Marionette TRACE 378 -> [0,4,"clickElement",{"id":"64806b38-50f0-4c98-b3d2-9a40a8ba838b"}]
19:39:55 INFO - [3536] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
19:39:55 INFO - [3536] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
19:39:55 INFO - [3536] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
19:39:55 INFO - 1498876795475 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:39:55 INFO - 1498876795477 Marionette TRACE 378 <- [1,4,null,{}]
19:39:55 INFO - 1498876795479 Marionette TRACE 378 -> [0,5,"getElementProperty",{"id":"64806b38-50f0-4c98-b3d2-9a40a8ba838b","name":"selected"}]
19:39:55 INFO - 1498876795507 Marionette TRACE 378 <- [1,5,null,{"value":true}]
19:39:55 INFO - 1498876795510 Marionette TRACE 378 -> [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}]
19:39:55 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498876795520 Marionette TRACE 378 <- [1,6,null,{"value":null}]
19:39:55 INFO - 1498876795522 Marionette TRACE 378 -> [0,7,"deleteSession",{}]
19:39:55 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:55 INFO - 1498876795526 Marionette TRACE 378 <- [1,7,null,{}]
19:39:55 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 917ms
19:39:55 INFO - TEST-START | test_click.py TestClick.test_css_transforms
19:39:55 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:55 INFO - 1498876795559 Marionette DEBUG Closed connection 378
19:39:55 INFO - 1498876795561 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49751
19:39:55 INFO - 1498876795563 Marionette DEBUG Closed connection 379
19:39:55 INFO - 1498876795564 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49752
19:39:55 INFO - 1498876795566 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:55 INFO - 1498876795569 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:55 INFO - 1498876795593 Marionette DEBUG Register listener.js for window 7
19:39:55 INFO - 1498876795606 Marionette TRACE 380 <- [1,1,null,{"sessionId":"12ac64d4-f0f6-4fdc-b2de-b3e17d71307a","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:55 INFO - 1498876795615 Marionette TRACE 380 -> [0,2,"setTimeouts",{"script":30000}]
19:39:55 INFO - 1498876795617 Marionette TRACE 380 <- [1,2,null,{}]
19:39:55 INFO - 1498876795618 Marionette TRACE 380 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:39:55 INFO - 1498876795620 Marionette TRACE 380 <- [1,3,null,{}]
19:39:55 INFO - 1498876795622 Marionette TRACE 380 -> [0,4,"setTimeouts",{"implicit":0}]
19:39:55 INFO - 1498876795623 Marionette TRACE 380 <- [1,4,null,{}]
19:39:55 INFO - 1498876795626 Marionette TRACE 380 -> [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}]
19:39:55 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498876795634 Marionette TRACE 380 <- [1,5,null,{"value":null}]
19:39:55 INFO - 1498876795635 Marionette TRACE 380 -> [0,6,"deleteSession",{}]
19:39:55 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:55 INFO - 1498876795640 Marionette TRACE 380 <- [1,6,null,{}]
19:39:55 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:55 INFO - 1498876795646 Marionette DEBUG Closed connection 380
19:39:55 INFO - 1498876795648 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49753
19:39:55 INFO - 1498876795649 Marionette DEBUG Closed connection 381
19:39:55 INFO - 1498876795651 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49754
19:39:55 INFO - 1498876795653 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:55 INFO - 1498876795655 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:55 INFO - 1498876795678 Marionette DEBUG Register listener.js for window 7
19:39:55 INFO - 1498876795690 Marionette TRACE 382 <- [1,1,null,{"sessionId":"9efb60b4-309a-4179-be5b-2b58eb916a35","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:55 INFO - 1498876795693 Marionette TRACE 382 -> [0,2,"deleteSession",{}]
19:39:55 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:55 INFO - 1498876795697 Marionette TRACE 382 <- [1,2,null,{}]
19:39:55 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:55 INFO - 1498876795703 Marionette DEBUG Closed connection 382
19:39:55 INFO - 1498876795705 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49755
19:39:55 INFO - 1498876795706 Marionette DEBUG Closed connection 383
19:39:55 INFO - 1498876795708 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49756
19:39:55 INFO - 1498876795710 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:39:55 INFO - 1498876795713 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:55 INFO - 1498876795736 Marionette DEBUG Register listener.js for window 7
19:39:55 INFO - 1498876795747 Marionette TRACE 384 <- [1,1,null,{"sessionId":"a40fe92b-8a2d-4f88-9e03-52978bf94d88","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:55 INFO - 1498876795750 Marionette TRACE 384 -> [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"}]
19:39:55 INFO - 1498876795754 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"
19:39:55 INFO - 1498876795760 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"
19:39:55 INFO - 1498876795761 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"
19:39:55 INFO - ++DOMWINDOW == 29 (0000005AD7DD2000) [pid = 3536] [serial = 43] [outer = 0000005AD0E93800]
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:55 INFO - 1498876795807 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"
19:39:55 INFO - 1498876795815 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"
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:55 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:55 INFO - 1498876795884 Marionette TRACE 384 <- [1,2,null,{}]
19:39:55 INFO - 1498876795901 Marionette TRACE 384 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
19:39:55 INFO - 1498876795908 Marionette TRACE 384 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"e0c71f8d-a28b-4354-b3de-0b6b59e4967e","ELEMENT":"e0c71f8d-a28b-4354-b3de-0b6b59e4967e"}}]
19:39:55 INFO - 1498876795910 Marionette TRACE 384 -> [0,4,"clickElement",{"id":"e0c71f8d-a28b-4354-b3de-0b6b59e4967e"}]
19:39:56 INFO - 1498876796353 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:39:56 INFO - 1498876796356 Marionette TRACE 384 <- [1,4,null,{}]
19:39:56 INFO - 1498876796361 Marionette TRACE 384 -> [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}]
19:39:56 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498876796376 Marionette TRACE 384 <- [1,5,null,{"value":null}]
19:39:56 INFO - 1498876796430 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
19:39:56 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:56 INFO - 1498876796440 Marionette TRACE 384 <- [1,6,null,{}]
19:39:56 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 915ms
19:39:56 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
19:39:56 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:56 INFO - 1498876796473 Marionette DEBUG Closed connection 384
19:39:56 INFO - 1498876796476 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49757
19:39:56 INFO - 1498876796481 Marionette DEBUG Closed connection 385
19:39:56 INFO - 1498876796484 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49758
19:39:56 INFO - 1498876796491 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:56 INFO - 1498876796501 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:56 INFO - 1498876796550 Marionette DEBUG Register listener.js for window 7
19:39:56 INFO - 1498876796585 Marionette TRACE 386 <- [1,1,null,{"sessionId":"2f22ef79-0ec0-4489-abbc-d971428b4f55","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:56 INFO - 1498876796591 Marionette TRACE 386 -> [0,2,"setTimeouts",{"script":30000}]
19:39:56 INFO - 1498876796596 Marionette TRACE 386 <- [1,2,null,{}]
19:39:56 INFO - 1498876796600 Marionette TRACE 386 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:39:56 INFO - 1498876796604 Marionette TRACE 386 <- [1,3,null,{}]
19:39:56 INFO - 1498876796606 Marionette TRACE 386 -> [0,4,"setTimeouts",{"implicit":0}]
19:39:56 INFO - 1498876796609 Marionette TRACE 386 <- [1,4,null,{}]
19:39:56 INFO - 1498876796612 Marionette TRACE 386 -> [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}]
19:39:56 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498876796626 Marionette TRACE 386 <- [1,5,null,{"value":null}]
19:39:56 INFO - 1498876796636 Marionette TRACE 386 -> [0,6,"deleteSession",{}]
19:39:56 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:56 INFO - 1498876796643 Marionette TRACE 386 <- [1,6,null,{}]
19:39:56 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:56 INFO - 1498876796652 Marionette DEBUG Closed connection 386
19:39:56 INFO - 1498876796656 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49759
19:39:56 INFO - 1498876796695 Marionette DEBUG Closed connection 387
19:39:56 INFO - 1498876796697 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49760
19:39:56 INFO - 1498876796721 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:56 INFO - 1498876796726 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:56 INFO - 1498876796775 Marionette DEBUG Register listener.js for window 7
19:39:56 INFO - 1498876796795 Marionette TRACE 388 <- [1,1,null,{"sessionId":"28ecd796-ecb8-4205-95c6-58dccdde690b","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:56 INFO - 1498876796802 Marionette TRACE 388 -> [0,2,"deleteSession",{}]
19:39:56 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:56 INFO - 1498876796810 Marionette TRACE 388 <- [1,2,null,{}]
19:39:56 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:56 INFO - 1498876796824 Marionette DEBUG Closed connection 388
19:39:56 INFO - 1498876796827 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49761
19:39:56 INFO - 1498876796850 Marionette DEBUG Closed connection 389
19:39:56 INFO - 1498876796853 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49762
19:39:56 INFO - 1498876796861 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:39:56 INFO - 1498876796868 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:56 INFO - 1498876796916 Marionette DEBUG Register listener.js for window 7
19:39:56 INFO - 1498876796934 Marionette TRACE 390 <- [1,1,null,{"sessionId":"27aab5e8-b81f-45c1-9c57-2bbf4078bd98","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:56 INFO - 1498876796954 Marionette TRACE 390 -> [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"}]
19:39:56 INFO - 1498876796960 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"
19:39:56 INFO - 1498876796971 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"
19:39:56 INFO - 1498876796972 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"
19:39:56 INFO - ++DOMWINDOW == 30 (0000005AD0B69000) [pid = 3536] [serial = 44] [outer = 0000005AD0E93800]
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:57 INFO - 1498876797042 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"
19:39:57 INFO - 1498876797050 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"
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:57 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:57 INFO - 1498876797073 Marionette TRACE 390 <- [1,2,null,{}]
19:39:57 INFO - 1498876797076 Marionette TRACE 390 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
19:39:57 INFO - 1498876797088 Marionette TRACE 390 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b70d05d5-f55a-488a-bc65-0f7b659239b7","ELEMENT":"b70d05d5-f55a-488a-bc65-0f7b659239b7"}}]
19:39:57 INFO - 1498876797091 Marionette TRACE 390 -> [0,4,"clickElement",{"id":"b70d05d5-f55a-488a-bc65-0f7b659239b7"}]
19:39:57 INFO - 1498876797744 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:39:57 INFO - 1498876797747 Marionette TRACE 390 <- [1,4,null,{}]
19:39:57 INFO - 1498876797750 Marionette TRACE 390 -> [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}]
19:39:57 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498876797760 Marionette TRACE 390 <- [1,5,null,{"value":null}]
19:39:57 INFO - 1498876797763 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
19:39:57 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:57 INFO - 1498876797768 Marionette TRACE 390 <- [1,6,null,{}]
19:39:57 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1324ms
19:39:57 INFO - TEST-START | test_click.py TestClick.test_input_file
19:39:57 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:57 INFO - 1498876797775 Marionette DEBUG Closed connection 390
19:39:57 INFO - 1498876797778 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49763
19:39:57 INFO - 1498876797825 Marionette DEBUG Closed connection 391
19:39:57 INFO - 1498876797828 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49764
19:39:57 INFO - 1498876797859 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:57 INFO - 1498876797862 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:57 INFO - 1498876797916 Marionette DEBUG Register listener.js for window 7
19:39:57 INFO - 1498876797935 Marionette TRACE 392 <- [1,1,null,{"sessionId":"5c3fff38-caaf-48b0-aa8c-7948c7a275c9","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:57 INFO - 1498876797942 Marionette TRACE 392 -> [0,2,"setTimeouts",{"script":30000}]
19:39:57 INFO - 1498876797945 Marionette TRACE 392 <- [1,2,null,{}]
19:39:57 INFO - 1498876797947 Marionette TRACE 392 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:39:57 INFO - 1498876797949 Marionette TRACE 392 <- [1,3,null,{}]
19:39:57 INFO - 1498876797952 Marionette TRACE 392 -> [0,4,"setTimeouts",{"implicit":0}]
19:39:57 INFO - 1498876797955 Marionette TRACE 392 <- [1,4,null,{}]
19:39:57 INFO - 1498876797994 Marionette TRACE 392 -> [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}]
19:39:58 INFO - TEST-START: test_click.py TestClick.test_input_file1498876798010 Marionette TRACE 392 <- [1,5,null,{"value":null}]
19:39:58 INFO - 1498876798016 Marionette TRACE 392 -> [0,6,"deleteSession",{}]
19:39:58 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:58 INFO - 1498876798022 Marionette TRACE 392 <- [1,6,null,{}]
19:39:58 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:58 INFO - 1498876798049 Marionette DEBUG Closed connection 392
19:39:58 INFO - 1498876798051 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49765
19:39:58 INFO - 1498876798053 Marionette DEBUG Closed connection 393
19:39:58 INFO - 1498876798056 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49766
19:39:58 INFO - 1498876798058 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:58 INFO - 1498876798061 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:58 INFO - 1498876798112 Marionette DEBUG Register listener.js for window 7
19:39:58 INFO - 1498876798144 Marionette TRACE 394 <- [1,1,null,{"sessionId":"de468fe3-17de-473a-bd79-9d8de800a822","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:58 INFO - 1498876798155 Marionette TRACE 394 -> [0,2,"deleteSession",{}]
19:39:58 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:58 INFO - 1498876798161 Marionette TRACE 394 <- [1,2,null,{}]
19:39:58 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:58 INFO - 1498876798190 Marionette DEBUG Closed connection 394
19:39:58 INFO - 1498876798196 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49767
19:39:58 INFO - 1498876798248 Marionette DEBUG Closed connection 395
19:39:58 INFO - 1498876798249 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49768
19:39:58 INFO - 1498876798255 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:39:58 INFO - 1498876798261 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:58 INFO - 1498876798310 Marionette DEBUG Register listener.js for window 7
19:39:58 INFO - 1498876798327 Marionette TRACE 396 <- [1,1,null,{"sessionId":"6342e612-af0f-4642-8e23-e974789f088c","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:58 INFO - 1498876798334 Marionette TRACE 396 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
19:39:58 INFO - 1498876798339 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"
19:39:58 INFO - 1498876798348 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"
19:39:58 INFO - 1498876798350 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"
19:39:58 INFO - ++DOMWINDOW == 31 (0000005ADA77D800) [pid = 3536] [serial = 45] [outer = 0000005AD0E93800]
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:58 INFO - 1498876798413 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:58 INFO - 1498876798431 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:58 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:58 INFO - 1498876798459 Marionette TRACE 396 <- [1,2,null,{}]
19:39:58 INFO - 1498876798461 Marionette TRACE 396 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
19:39:58 INFO - 1498876798473 Marionette TRACE 396 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"36ce369f-6d63-456d-91fd-1408b1f8b746","ELEMENT":"36ce369f-6d63-456d-91fd-1408b1f8b746"}}]
19:39:58 INFO - 1498876798475 Marionette TRACE 396 -> [0,4,"clickElement",{"id":"36ce369f-6d63-456d-91fd-1408b1f8b746"}]
19:39:58 INFO - 1498876798911 Marionette TRACE 396 <- [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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
19:39:58 INFO - 1498876798934 Marionette TRACE 396 -> [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}]
19:39:58 INFO - TEST-END: test_click.py TestClick.test_input_file1498876798947 Marionette TRACE 396 <- [1,5,null,{"value":null}]
19:39:58 INFO - 1498876798953 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
19:39:58 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:58 INFO - 1498876798963 Marionette TRACE 396 <- [1,6,null,{}]
19:39:58 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1196ms
19:39:58 INFO - TEST-START | test_click.py TestClick.test_obscured_element
19:39:59 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:59 INFO - 1498876799022 Marionette DEBUG Closed connection 396
19:39:59 INFO - 1498876799025 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49769
19:39:59 INFO - 1498876799051 Marionette DEBUG Closed connection 397
19:39:59 INFO - 1498876799053 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49770
19:39:59 INFO - 1498876799059 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:59 INFO - 1498876799068 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:59 INFO - 1498876799097 Marionette DEBUG Register listener.js for window 7
19:39:59 INFO - 1498876799119 Marionette TRACE 398 <- [1,1,null,{"sessionId":"5607ace6-b41f-4952-8607-59943d08f440","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:59 INFO - 1498876799133 Marionette TRACE 398 -> [0,2,"setTimeouts",{"script":30000}]
19:39:59 INFO - 1498876799138 Marionette TRACE 398 <- [1,2,null,{}]
19:39:59 INFO - 1498876799141 Marionette TRACE 398 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:39:59 INFO - 1498876799145 Marionette TRACE 398 <- [1,3,null,{}]
19:39:59 INFO - 1498876799151 Marionette TRACE 398 -> [0,4,"setTimeouts",{"implicit":0}]
19:39:59 INFO - 1498876799154 Marionette TRACE 398 <- [1,4,null,{}]
19:39:59 INFO - 1498876799158 Marionette TRACE 398 -> [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}]
19:39:59 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498876799171 Marionette TRACE 398 <- [1,5,null,{"value":null}]
19:39:59 INFO - 1498876799173 Marionette TRACE 398 -> [0,6,"deleteSession",{}]
19:39:59 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:59 INFO - 1498876799179 Marionette TRACE 398 <- [1,6,null,{}]
19:39:59 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:59 INFO - 1498876799188 Marionette DEBUG Closed connection 398
19:39:59 INFO - 1498876799192 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49771
19:39:59 INFO - 1498876799194 Marionette DEBUG Closed connection 399
19:39:59 INFO - 1498876799196 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49772
19:39:59 INFO - 1498876799199 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:39:59 INFO - 1498876799203 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:59 INFO - 1498876799232 Marionette DEBUG Register listener.js for window 7
19:39:59 INFO - 1498876799261 Marionette TRACE 400 <- [1,1,null,{"sessionId":"0445ea1c-938e-46db-b779-b4f475fb9650","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:59 INFO - 1498876799291 Marionette TRACE 400 -> [0,2,"deleteSession",{}]
19:39:59 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:59 INFO - 1498876799298 Marionette TRACE 400 <- [1,2,null,{}]
19:39:59 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:39:59 INFO - 1498876799322 Marionette DEBUG Closed connection 400
19:39:59 INFO - 1498876799324 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49773
19:39:59 INFO - 1498876799341 Marionette DEBUG Closed connection 401
19:39:59 INFO - 1498876799343 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49774
19:39:59 INFO - 1498876799375 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:39:59 INFO - 1498876799381 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:39:59 INFO - 1498876799407 Marionette DEBUG Register listener.js for window 7
19:39:59 INFO - 1498876799424 Marionette TRACE 402 <- [1,1,null,{"sessionId":"6be908a7-beb5-47c5-81a4-18011e1aa966","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:39:59 INFO - 1498876799434 Marionette TRACE 402 -> [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"}]
19:39:59 INFO - 1498876799439 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:39:59 INFO - 1498876799449 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:39:59 INFO - 1498876799450 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
19:39:59 INFO - ++DOMWINDOW == 32 (0000005ACCC52000) [pid = 3536] [serial = 46] [outer = 0000005AD0E93800]
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:59 INFO - 1498876799521 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"
19:39:59 INFO - 1498876799528 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"
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:59 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:39:59 INFO - 1498876799547 Marionette TRACE 402 <- [1,2,null,{}]
19:39:59 INFO - 1498876799554 Marionette TRACE 402 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
19:39:59 INFO - 1498876799563 Marionette TRACE 402 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"5877d04a-3674-430a-bdd8-4490ae1a609d","ELEMENT":"5877d04a-3674-430a-bdd8-4490ae1a609d"}}]
19:39:59 INFO - 1498876799566 Marionette TRACE 402 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
19:39:59 INFO - 1498876799574 Marionette TRACE 402 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2198fd73-2782-4fd7-ae59-06bdbbc9215b","ELEMENT":"2198fd73-2782-4fd7-ae59-06bdbbc9215b"}}]
19:39:59 INFO - 1498876799576 Marionette TRACE 402 -> [0,5,"clickElement",{"id":"5877d04a-3674-430a-bdd8-4490ae1a609d"}]
19:39:59 INFO - --DOMWINDOW == 31 (0000005AD075D800) [pid = 3536] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
19:39:59 INFO - --DOMWINDOW == 30 (0000005AD0E9D800) [pid = 3536] [serial = 26] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
19:39:59 INFO - --DOMWINDOW == 29 (0000005ACCE3C000) [pid = 3536] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
19:39:59 INFO - --DOMWINDOW == 28 (0000005AD074F800) [pid = 3536] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
19:39:59 INFO - --DOMWINDOW == 27 (0000005AD70C4000) [pid = 3536] [serial = 30] [outer = 0000000000000000] [url = http://127.0.0.1:49329/test_accessibility.html]
19:39:59 INFO - --DOMWINDOW == 26 (0000005ADB515800) [pid = 3536] [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%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]
19:39:59 INFO - --DOMWINDOW == 25 (0000005AD49CC000) [pid = 3536] [serial = 31] [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]
19:39:59 INFO - --DOMWINDOW == 24 (0000005ADAF66000) [pid = 3536] [serial = 32] [outer = 0000000000000000] [url = data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20position%3A%20absolute%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20/%2A%20move%20centre%20point%20off%20viewport%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]
19:40:00 INFO - 1498876800816 Marionette DEBUG Canceled page load listener because no navigation has been detected
19:40:00 INFO - 1498876800837 Marionette TRACE 402 <- [1,5,null,{}]
19:40:00 INFO - 1498876800843 Marionette TRACE 402 -> [0,6,"clickElement",{"id":"2198fd73-2782-4fd7-ae59-06bdbbc9215b"}]
19:40:01 INFO - 1498876801049 Marionette TRACE 402 <- [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:393:12\nclickElement@chrome://marionette/content/listener.js:1330:5\n"},null]
19:40:01 INFO - 1498876801104 Marionette TRACE 402 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
19:40:01 INFO - 1498876801115 Marionette TRACE 402 <- [1,7,null,{"value":false}]
19:40:01 INFO - 1498876801169 Marionette TRACE 402 -> [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}]
19:40:01 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498876801178 Marionette TRACE 402 <- [1,8,null,{"value":null}]
19:40:01 INFO - 1498876801231 Marionette TRACE 402 -> [0,9,"deleteSession",{}]
19:40:01 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:40:01 INFO - 1498876801236 Marionette TRACE 402 <- [1,9,null,{}]
19:40:01 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 2271ms
19:40:01 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
19:40:01 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:40:01 INFO - 1498876801273 Marionette DEBUG Closed connection 402
19:40:01 INFO - 1498876801275 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49775
19:40:01 INFO - 1498876801293 Marionette DEBUG Closed connection 403
19:40:01 INFO - 1498876801295 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49776
19:40:01 INFO - 1498876801298 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:40:01 INFO - 1498876801301 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:40:01 INFO - 1498876801327 Marionette DEBUG Register listener.js for window 7
19:40:01 INFO - 1498876801342 Marionette TRACE 404 <- [1,1,null,{"sessionId":"d8741bc8-f4de-4439-be45-929638298d0d","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:40:01 INFO - 1498876801351 Marionette TRACE 404 -> [0,2,"setTimeouts",{"script":30000}]
19:40:01 INFO - 1498876801353 Marionette TRACE 404 <- [1,2,null,{}]
19:40:01 INFO - 1498876801357 Marionette TRACE 404 -> [0,3,"setTimeouts",{"pageLoad":300000}]
19:40:01 INFO - 1498876801359 Marionette TRACE 404 <- [1,3,null,{}]
19:40:01 INFO - 1498876801361 Marionette TRACE 404 -> [0,4,"setTimeouts",{"implicit":0}]
19:40:01 INFO - 1498876801363 Marionette TRACE 404 <- [1,4,null,{}]
19:40:01 INFO - 1498876801366 Marionette TRACE 404 -> [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}]
19:40:01 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498876801375 Marionette TRACE 404 <- [1,5,null,{"value":null}]
19:40:01 INFO - 1498876801377 Marionette TRACE 404 -> [0,6,"deleteSession",{}]
19:40:01 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:40:01 INFO - 1498876801381 Marionette TRACE 404 <- [1,6,null,{}]
19:40:01 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:40:01 INFO - 1498876801408 Marionette DEBUG Closed connection 404
19:40:01 INFO - 1498876801410 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49777
19:40:01 INFO - 1498876801414 Marionette DEBUG Closed connection 405
19:40:01 INFO - 1498876801416 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49778
19:40:01 INFO - 1498876801418 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
19:40:01 INFO - 1498876801421 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:40:01 INFO - 1498876801446 Marionette DEBUG Register listener.js for window 7
19:40:01 INFO - 1498876801462 Marionette TRACE 406 <- [1,1,null,{"sessionId":"c5910093-f4d3-4499-b872-903f991d4279","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:40:01 INFO - 1498876801470 Marionette TRACE 406 -> [0,2,"deleteSession",{}]
19:40:01 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:40:01 INFO - 1498876801475 Marionette TRACE 406 <- [1,2,null,{}]
19:40:01 INFO - [3536] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
19:40:01 INFO - 1498876801500 Marionette DEBUG Closed connection 406
19:40:01 INFO - 1498876801505 Marionette DEBUG Accepted connection 407 from 127.0.0.1:49779
19:40:01 INFO - 1498876801510 Marionette DEBUG Closed connection 407
19:40:01 INFO - 1498876801512 Marionette DEBUG Accepted connection 408 from 127.0.0.1:49780
19:40:01 INFO - 1498876801514 Marionette TRACE 408 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
19:40:01 INFO - 1498876801519 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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}
19:40:01 INFO - 1498876801544 Marionette DEBUG Register listener.js for window 7
19:40:01 INFO - 1498876801558 Marionette TRACE 408 <- [1,1,null,{"sessionId":"4e6da238-81a6-4b23-b329-f506d1caa3db","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":3536,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-291.001\\AppData\\Local\\Temp\\tmp0s5k6o.mozrunner","moz:accessibilityChecks":false}}]
19:40:01 INFO - 1498876801565 Marionette TRACE 408 -> [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"}]
19:40:01 INFO - 1498876801571 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"
19:40:01 INFO - 1498876801580 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"
19:40:01 INFO - 1498876801580 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"
19:40:01 INFO - ++DOMWINDOW == 25 (0000005AD1683800) [pid = 3536] [serial = 47] [outer = 0000005AD0E93800]
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:40:01 INFO - 1498876801641 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"
19:40:01 INFO - 1498876801647 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"
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/VectorImage.cpp, line 1032
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:40:01 INFO - [3536] WARNING: 'obs', file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/image/RasterImage.cpp, line 1402
19:40:01 INFO - 1498876801666 Marionette TRACE 408 <- [1,2,null,{}]
19:40:01 INFO - 1498876801673 Marionette TRACE 408 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
19:40:01 INFO - 1498876801681 Marionette TRACE 408 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"f5810e82-eb9e-4175-8088-ba15aeb29487","ELEMENT":"f5810e82-eb9e-4175-8088-ba15aeb29487"}}]
19:40:01 INFO - 1498876801683 Marionette TRACE 408 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"f5810e82-eb9e-4175-8088-ba15aeb29487"}]
19:40:01 INFO - 1498876801810 Marionette TRACE 408 <- [1,4,null,{"value":"none"}]
19:40:01 INFO - 1498876801813 Marionette TRACE 408 -> [0,5,"clickElement",{"id":"f5810e82-eb9e-4175-8088-ba15aeb29487"}]
19:40:01 INFO - 1498876801970 Marionette TRACE 408 <- [1,5,{"error":"element click intercepted","message":"Element