could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nElementNotInteractableError@chrome://marionette/content/error.js:311:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:176: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
21:03:22 INFO - 1498795402739 Marionette TRACE 358 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
21:03:22 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1498795402752 Marionette TRACE 358 <- [1,5,null,{"value":null}]
21:03:22 INFO - 1498795402757 Marionette TRACE 358 -> [0,6,"deleteSession",{}]
21:03:22 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:22 INFO - 1498795402761 Marionette TRACE 358 <- [1,6,null,{}]
21:03:22 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 1043ms
21:03:22 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
21:03:22 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:22 INFO - 1498795402769 Marionette DEBUG Closed connection 358
21:03:22 INFO - 1498795402771 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49731
21:03:22 INFO - 1498795402791 Marionette DEBUG Closed connection 359
21:03:22 INFO - 1498795402792 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49732
21:03:22 INFO - 1498795402795 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:22 INFO - 1498795402797 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:22 INFO - 1498795402820 Marionette DEBUG Register listener.js for window 7
21:03:22 INFO - 1498795402832 Marionette TRACE 360 <- [1,1,null,{"sessionId":"a1f9d7d8-b899-4d60-9f09-3f7b45f28939","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:22 INFO - 1498795402936 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
21:03:22 INFO - 1498795402938 Marionette TRACE 360 <- [1,2,null,{}]
21:03:22 INFO - 1498795402944 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:03:22 INFO - 1498795402946 Marionette TRACE 360 <- [1,3,null,{}]
21:03:22 INFO - 1498795402949 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
21:03:22 INFO - 1498795402951 Marionette TRACE 360 <- [1,4,null,{}]
21:03:22 INFO - 1498795402954 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
21:03:22 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1498795402961 Marionette TRACE 360 <- [1,5,null,{"value":null}]
21:03:22 INFO - 1498795402963 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
21:03:22 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:22 INFO - 1498795402968 Marionette TRACE 360 <- [1,6,null,{}]
21:03:22 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:22 INFO - 1498795402974 Marionette DEBUG Closed connection 360
21:03:22 INFO - 1498795402976 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49733
21:03:22 INFO - 1498795402978 Marionette DEBUG Closed connection 361
21:03:22 INFO - 1498795402979 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49734
21:03:22 INFO - 1498795402981 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:22 INFO - 1498795402984 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:23 INFO - 1498795403006 Marionette DEBUG Register listener.js for window 7
21:03:23 INFO - 1498795403019 Marionette TRACE 362 <- [1,1,null,{"sessionId":"ca3b4479-2d1d-4303-8320-54166799a840","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:23 INFO - 1498795403023 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
21:03:23 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:23 INFO - 1498795403028 Marionette TRACE 362 <- [1,2,null,{}]
21:03:23 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:23 INFO - 1498795403072 Marionette DEBUG Closed connection 362
21:03:23 INFO - 1498795403074 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49735
21:03:23 INFO - 1498795403078 Marionette DEBUG Closed connection 363
21:03:23 INFO - 1498795403080 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49736
21:03:23 INFO - 1498795403082 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:03:23 INFO - 1498795403085 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:23 INFO - 1498795403108 Marionette DEBUG Register listener.js for window 7
21:03:23 INFO - 1498795403120 Marionette TRACE 364 <- [1,1,null,{"sessionId":"4b082105-680e-41a8-b733-0aeb1fbb49a7","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:23 INFO - 1498795403131 Marionette TRACE 364 -> [0,2,"get",{"url":"http://127.0.0.1:49334/clicks.html"}]
21:03:23 INFO - 1498795403136 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/hidden.html"
21:03:23 INFO - 1498795403149 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/hidden.html"
21:03:23 INFO - 1498795403150 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/hidden.html"
21:03:23 INFO - ++DOMWINDOW == 24 (000000C03241D000) [pid = 872] [serial = 39] [outer = 000000C02B193800]
21:03:23 INFO - 1498795403212 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49334/clicks.html"
21:03:23 INFO - 1498795403217 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49334/clicks.html"
21:03:23 INFO - 1498795403234 Marionette TRACE 364 <- [1,2,null,{}]
21:03:23 INFO - 1498795403241 Marionette TRACE 364 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
21:03:23 INFO - 1498795403252 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"47f6bf30-28f9-438d-8f69-c9f2fd006c55","ELEMENT":"47f6bf30-28f9-438d-8f69-c9f2fd006c55"}}]
21:03:23 INFO - 1498795403254 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"47f6bf30-28f9-438d-8f69-c9f2fd006c55"}]
21:03:23 INFO - 1498795403488 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/clicks.html"
21:03:23 INFO - [872] 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
21:03:23 INFO - 1498795403498 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/clicks.html"
21:03:23 INFO - 1498795403500 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/clicks.html"
21:03:23 INFO - ++DOMWINDOW == 25 (000000C035ED5800) [pid = 872] [serial = 40] [outer = 000000C02B193800]
21:03:23 INFO - 1498795403567 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49334/test.html"
21:03:23 INFO - 1498795403572 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49334/test.html"
21:03:23 INFO - 1498795403587 Marionette TRACE 364 <- [1,4,null,{}]
21:03:23 INFO - 1498795403595 Marionette TRACE 364 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
21:03:23 INFO - 1498795403608 Marionette TRACE 364 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"19c9c246-748c-40f6-9925-1235ca2216d7","ELEMENT":"19c9c246-748c-40f6-9925-1235ca2216d7"}}]
21:03:23 INFO - 1498795403610 Marionette TRACE 364 -> [0,6,"getTitle",{}]
21:03:23 INFO - 1498795403615 Marionette TRACE 364 <- [1,6,null,{"value":"Marionette Test"}]
21:03:23 INFO - 1498795403620 Marionette TRACE 364 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
21:03:23 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1498795403628 Marionette TRACE 364 <- [1,7,null,{"value":null}]
21:03:23 INFO - 1498795403630 Marionette TRACE 364 -> [0,8,"deleteSession",{}]
21:03:23 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:23 INFO - 1498795403634 Marionette TRACE 364 <- [1,8,null,{}]
21:03:23 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 873ms
21:03:23 INFO - TEST-START | test_click.py TestClick.test_container_element
21:03:23 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:23 INFO - 1498795403659 Marionette DEBUG Closed connection 364
21:03:23 INFO - 1498795403661 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49739
21:03:23 INFO - 1498795403669 Marionette DEBUG Closed connection 365
21:03:23 INFO - 1498795403671 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49740
21:03:23 INFO - 1498795403673 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:23 INFO - 1498795403678 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:23 INFO - 1498795403700 Marionette DEBUG Register listener.js for window 7
21:03:23 INFO - 1498795403712 Marionette TRACE 366 <- [1,1,null,{"sessionId":"74af34f0-48a5-4b36-816c-9e33994065b4","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:23 INFO - 1498795403721 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
21:03:23 INFO - 1498795403723 Marionette TRACE 366 <- [1,2,null,{}]
21:03:23 INFO - 1498795403725 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:03:23 INFO - 1498795403726 Marionette TRACE 366 <- [1,3,null,{}]
21:03:23 INFO - 1498795403728 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
21:03:23 INFO - 1498795403730 Marionette TRACE 366 <- [1,4,null,{}]
21:03:23 INFO - 1498795403733 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
21:03:23 INFO - TEST-START: test_click.py TestClick.test_container_element1498795403740 Marionette TRACE 366 <- [1,5,null,{"value":null}]
21:03:23 INFO - 1498795403742 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
21:03:23 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:23 INFO - 1498795403746 Marionette TRACE 366 <- [1,6,null,{}]
21:03:23 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:23 INFO - 1498795403752 Marionette DEBUG Closed connection 366
21:03:23 INFO - 1498795403754 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49741
21:03:23 INFO - 1498795403755 Marionette DEBUG Closed connection 367
21:03:23 INFO - 1498795403756 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49742
21:03:23 INFO - 1498795403759 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:23 INFO - 1498795403761 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:23 INFO - 1498795403783 Marionette DEBUG Register listener.js for window 7
21:03:23 INFO - 1498795403794 Marionette TRACE 368 <- [1,1,null,{"sessionId":"2cebf1a4-35f7-49da-b30e-61b6d7258222","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:23 INFO - 1498795403821 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
21:03:23 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:23 INFO - 1498795403826 Marionette TRACE 368 <- [1,2,null,{}]
21:03:23 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:23 INFO - 1498795403832 Marionette DEBUG Closed connection 368
21:03:23 INFO - 1498795403834 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49743
21:03:23 INFO - 1498795403838 Marionette DEBUG Closed connection 369
21:03:23 INFO - 1498795403839 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49744
21:03:23 INFO - 1498795403841 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:03:23 INFO - 1498795403844 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:23 INFO - 1498795403867 Marionette DEBUG Register listener.js for window 7
21:03:23 INFO - 1498795403880 Marionette TRACE 370 <- [1,1,null,{"sessionId":"9bd931d4-3368-4bba-917f-d276b57c78d4","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:23 INFO - 1498795403882 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:03:23 INFO - 1498795403886 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49334/test.html"
21:03:23 INFO - 1498795403897 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49334/test.html"
21:03:23 INFO - 1498795403898 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49334/test.html"
21:03:23 INFO - ++DOMWINDOW == 26 (000000C03641F800) [pid = 872] [serial = 41] [outer = 000000C02B193800]
21:03:23 INFO - 1498795403987 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:24 INFO - 1498795404010 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:24 INFO - 1498795404027 Marionette TRACE 370 <- [1,2,null,{}]
21:03:24 INFO - 1498795404031 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:03:24 INFO - 1498795404039 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a44c8ef3-4205-4e89-9433-b4e3174e14b9","ELEMENT":"a44c8ef3-4205-4e89-9433-b4e3174e14b9"}}]
21:03:24 INFO - 1498795404068 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"a44c8ef3-4205-4e89-9433-b4e3174e14b9"}]
21:03:24 INFO - [872] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
21:03:24 INFO - [872] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
21:03:24 INFO - [872] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
21:03:25 INFO - 1498795405269 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:03:25 INFO - 1498795405272 Marionette TRACE 370 <- [1,4,null,{}]
21:03:25 INFO - 1498795405323 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"a44c8ef3-4205-4e89-9433-b4e3174e14b9","name":"selected"}]
21:03:25 INFO - 1498795405345 Marionette TRACE 370 <- [1,5,null,{"value":true}]
21:03:25 INFO - 1498795405348 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
21:03:25 INFO - TEST-END: test_click.py TestClick.test_container_element1498795405356 Marionette TRACE 370 <- [1,6,null,{"value":null}]
21:03:25 INFO - 1498795405358 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
21:03:25 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:25 INFO - 1498795405365 Marionette TRACE 370 <- [1,7,null,{}]
21:03:25 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1731ms
21:03:25 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
21:03:25 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:25 INFO - 1498795405373 Marionette DEBUG Closed connection 370
21:03:25 INFO - 1498795405374 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49745
21:03:25 INFO - 1498795405423 Marionette DEBUG Closed connection 371
21:03:25 INFO - 1498795405424 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49746
21:03:25 INFO - 1498795405429 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:25 INFO - 1498795405431 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:25 INFO - 1498795405454 Marionette DEBUG Register listener.js for window 7
21:03:25 INFO - 1498795405467 Marionette TRACE 372 <- [1,1,null,{"sessionId":"933d0036-3c5f-4b39-b1e7-2f9a6eeffe06","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:25 INFO - 1498795405470 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
21:03:25 INFO - 1498795405471 Marionette TRACE 372 <- [1,2,null,{}]
21:03:25 INFO - 1498795405473 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:03:25 INFO - 1498795405474 Marionette TRACE 372 <- [1,3,null,{}]
21:03:25 INFO - 1498795405476 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
21:03:25 INFO - 1498795405478 Marionette TRACE 372 <- [1,4,null,{}]
21:03:25 INFO - 1498795405480 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
21:03:25 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1498795405529 Marionette TRACE 372 <- [1,5,null,{"value":null}]
21:03:25 INFO - 1498795405539 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
21:03:25 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:25 INFO - 1498795405544 Marionette TRACE 372 <- [1,6,null,{}]
21:03:25 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:25 INFO - 1498795405550 Marionette DEBUG Closed connection 372
21:03:25 INFO - 1498795405552 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49747
21:03:25 INFO - 1498795405572 Marionette DEBUG Closed connection 373
21:03:25 INFO - 1498795405574 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49748
21:03:25 INFO - 1498795405577 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:25 INFO - 1498795405579 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:25 INFO - 1498795405603 Marionette DEBUG Register listener.js for window 7
21:03:25 INFO - 1498795405627 Marionette TRACE 374 <- [1,1,null,{"sessionId":"61283cb5-538a-489c-8a7c-52fc515ecf45","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:25 INFO - 1498795405634 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
21:03:25 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:25 INFO - 1498795405638 Marionette TRACE 374 <- [1,2,null,{}]
21:03:25 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:25 INFO - 1498795405661 Marionette DEBUG Closed connection 374
21:03:25 INFO - 1498795405662 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49749
21:03:25 INFO - 1498795405666 Marionette DEBUG Closed connection 375
21:03:25 INFO - 1498795405668 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49750
21:03:25 INFO - 1498795405671 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:03:25 INFO - 1498795405674 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:25 INFO - 1498795405698 Marionette DEBUG Register listener.js for window 7
21:03:25 INFO - 1498795405710 Marionette TRACE 376 <- [1,1,null,{"sessionId":"fed72db8-f77e-4122-9f05-777888253b3d","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:25 INFO - 1498795405718 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:03:25 INFO - 1498795405722 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:25 INFO - 1498795405728 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:25 INFO - 1498795405729 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:25 INFO - ++DOMWINDOW == 27 (000000C01FF75800) [pid = 872] [serial = 42] [outer = 000000C02B193800]
21:03:25 INFO - 1498795405777 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:25 INFO - 1498795405782 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:25 INFO - 1498795405799 Marionette TRACE 376 <- [1,2,null,{}]
21:03:25 INFO - 1498795405806 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
21:03:25 INFO - 1498795405813 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6ebad955-42a9-4dbe-a0f8-4f74145af28f","ELEMENT":"6ebad955-42a9-4dbe-a0f8-4f74145af28f"}}]
21:03:25 INFO - 1498795405815 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"6ebad955-42a9-4dbe-a0f8-4f74145af28f"}]
21:03:26 INFO - [872] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1908
21:03:26 INFO - [872] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1816
21:03:26 INFO - [872] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1622
21:03:26 INFO - 1498795406222 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:03:26 INFO - 1498795406224 Marionette TRACE 376 <- [1,4,null,{}]
21:03:26 INFO - 1498795406227 Marionette TRACE 376 -> [0,5,"getElementProperty",{"id":"6ebad955-42a9-4dbe-a0f8-4f74145af28f","name":"selected"}]
21:03:26 INFO - 1498795406249 Marionette TRACE 376 <- [1,5,null,{"value":true}]
21:03:26 INFO - 1498795406252 Marionette TRACE 376 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
21:03:26 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1498795406259 Marionette TRACE 376 <- [1,6,null,{"value":null}]
21:03:26 INFO - 1498795406261 Marionette TRACE 376 -> [0,7,"deleteSession",{}]
21:03:26 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:26 INFO - 1498795406265 Marionette TRACE 376 <- [1,7,null,{}]
21:03:26 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 900ms
21:03:26 INFO - TEST-START | test_click.py TestClick.test_css_transforms
21:03:26 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:26 INFO - 1498795406272 Marionette DEBUG Closed connection 376
21:03:26 INFO - 1498795406274 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49751
21:03:26 INFO - 1498795406275 Marionette DEBUG Closed connection 377
21:03:26 INFO - 1498795406277 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49752
21:03:26 INFO - 1498795406279 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:26 INFO - 1498795406281 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:26 INFO - 1498795406305 Marionette DEBUG Register listener.js for window 7
21:03:26 INFO - 1498795406317 Marionette TRACE 378 <- [1,1,null,{"sessionId":"16d75b8a-f48a-4c16-82c5-b5203921a056","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:26 INFO - 1498795406376 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
21:03:26 INFO - 1498795406381 Marionette TRACE 378 <- [1,2,null,{}]
21:03:26 INFO - 1498795406386 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:03:26 INFO - 1498795406391 Marionette TRACE 378 <- [1,3,null,{}]
21:03:26 INFO - 1498795406396 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
21:03:26 INFO - 1498795406402 Marionette TRACE 378 <- [1,4,null,{}]
21:03:26 INFO - 1498795406407 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
21:03:26 INFO - TEST-START: test_click.py TestClick.test_css_transforms1498795406422 Marionette TRACE 378 <- [1,5,null,{"value":null}]
21:03:26 INFO - 1498795406425 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
21:03:26 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:26 INFO - 1498795406435 Marionette TRACE 378 <- [1,6,null,{}]
21:03:26 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:26 INFO - 1498795406448 Marionette DEBUG Closed connection 378
21:03:26 INFO - 1498795406452 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49753
21:03:26 INFO - 1498795406460 Marionette DEBUG Closed connection 379
21:03:26 INFO - 1498795406463 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49754
21:03:26 INFO - 1498795406501 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:26 INFO - 1498795406511 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:26 INFO - 1498795406563 Marionette DEBUG Register listener.js for window 7
21:03:26 INFO - 1498795406587 Marionette TRACE 380 <- [1,1,null,{"sessionId":"d3955d97-8c86-413d-9e36-dfa812eefb65","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:26 INFO - 1498795406595 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
21:03:26 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:26 INFO - 1498795406603 Marionette TRACE 380 <- [1,2,null,{}]
21:03:26 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:26 INFO - 1498795406639 Marionette DEBUG Closed connection 380
21:03:26 INFO - 1498795406643 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49755
21:03:26 INFO - 1498795406648 Marionette DEBUG Closed connection 381
21:03:26 INFO - 1498795406650 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49756
21:03:26 INFO - 1498795406655 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:03:26 INFO - 1498795406660 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:26 INFO - 1498795406710 Marionette DEBUG Register listener.js for window 7
21:03:26 INFO - 1498795406731 Marionette TRACE 382 <- [1,1,null,{"sessionId":"ae302beb-0227-401a-b165-294af25c1372","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:26 INFO - 1498795406771 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
21:03:26 INFO - 1498795406777 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:26 INFO - 1498795406788 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:26 INFO - 1498795406789 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:26 INFO - ++DOMWINDOW == 28 (000000C02285B800) [pid = 872] [serial = 43] [outer = 000000C02B193800]
21:03:26 INFO - 1498795406859 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:03:26 INFO - 1498795406868 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:03:26 INFO - 1498795406889 Marionette TRACE 382 <- [1,2,null,{}]
21:03:26 INFO - 1498795406902 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
21:03:26 INFO - 1498795406912 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"9c9e5bbf-4f33-4824-8408-dd2c39ce8efa","ELEMENT":"9c9e5bbf-4f33-4824-8408-dd2c39ce8efa"}}]
21:03:26 INFO - 1498795406920 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"9c9e5bbf-4f33-4824-8408-dd2c39ce8efa"}]
21:03:27 INFO - 1498795407473 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:03:27 INFO - 1498795407477 Marionette TRACE 382 <- [1,4,null,{}]
21:03:27 INFO - 1498795407481 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
21:03:27 INFO - TEST-END: test_click.py TestClick.test_css_transforms1498795407493 Marionette TRACE 382 <- [1,5,null,{"value":null}]
21:03:27 INFO - 1498795407497 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
21:03:27 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:27 INFO - 1498795407503 Marionette TRACE 382 <- [1,6,null,{}]
21:03:27 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 1237ms
21:03:27 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
21:03:27 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:27 INFO - 1498795407528 Marionette DEBUG Closed connection 382
21:03:27 INFO - 1498795407530 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49757
21:03:27 INFO - 1498795407552 Marionette DEBUG Closed connection 383
21:03:27 INFO - 1498795407555 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49758
21:03:27 INFO - 1498795407561 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:27 INFO - 1498795407565 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:27 INFO - 1498795407614 Marionette DEBUG Register listener.js for window 7
21:03:27 INFO - 1498795407630 Marionette TRACE 384 <- [1,1,null,{"sessionId":"aaee4740-5631-46b0-b55a-dbea898c6b40","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:27 INFO - 1498795407639 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
21:03:27 INFO - 1498795407642 Marionette TRACE 384 <- [1,2,null,{}]
21:03:27 INFO - 1498795407644 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:03:27 INFO - 1498795407646 Marionette TRACE 384 <- [1,3,null,{}]
21:03:27 INFO - 1498795407657 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
21:03:27 INFO - 1498795407659 Marionette TRACE 384 <- [1,4,null,{}]
21:03:27 INFO - 1498795407662 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
21:03:27 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1498795407673 Marionette TRACE 384 <- [1,5,null,{"value":null}]
21:03:27 INFO - 1498795407675 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
21:03:27 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:27 INFO - 1498795407680 Marionette TRACE 384 <- [1,6,null,{}]
21:03:27 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:27 INFO - 1498795407687 Marionette DEBUG Closed connection 384
21:03:27 INFO - 1498795407690 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49759
21:03:27 INFO - 1498795407692 Marionette DEBUG Closed connection 385
21:03:27 INFO - 1498795407694 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49760
21:03:27 INFO - 1498795407697 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:27 INFO - 1498795407700 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:27 INFO - 1498795407752 Marionette DEBUG Register listener.js for window 7
21:03:27 INFO - 1498795407770 Marionette TRACE 386 <- [1,1,null,{"sessionId":"cf2e0d31-9657-4afb-a0a4-519465808948","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:27 INFO - 1498795407774 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
21:03:27 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:27 INFO - 1498795407779 Marionette TRACE 386 <- [1,2,null,{}]
21:03:27 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:27 INFO - 1498795407820 Marionette DEBUG Closed connection 386
21:03:27 INFO - 1498795407822 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49761
21:03:27 INFO - 1498795407872 Marionette DEBUG Closed connection 387
21:03:27 INFO - 1498795407874 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49762
21:03:27 INFO - 1498795407878 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:03:27 INFO - 1498795407881 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:27 INFO - 1498795407932 Marionette DEBUG Register listener.js for window 7
21:03:27 INFO - 1498795407990 Marionette TRACE 388 <- [1,1,null,{"sessionId":"66d8fba8-6f2e-44c3-9d37-9decab1b801e","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:28 INFO - 1498795408001 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
21:03:28 INFO - 1498795408006 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:03:28 INFO - 1498795408016 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:03:28 INFO - 1498795408017 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
21:03:28 INFO - ++DOMWINDOW == 29 (000000C032419800) [pid = 872] [serial = 44] [outer = 000000C02B193800]
21:03:28 INFO - 1498795408081 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:28 INFO - 1498795408098 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:28 INFO - 1498795408127 Marionette TRACE 388 <- [1,2,null,{}]
21:03:28 INFO - 1498795408130 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
21:03:28 INFO - 1498795408141 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"fdda6004-7e90-4dbe-882b-fa4b1af7e1bf","ELEMENT":"fdda6004-7e90-4dbe-882b-fa4b1af7e1bf"}}]
21:03:28 INFO - 1498795408143 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"fdda6004-7e90-4dbe-882b-fa4b1af7e1bf"}]
21:03:28 INFO - 1498795408688 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:03:28 INFO - 1498795408690 Marionette TRACE 388 <- [1,4,null,{}]
21:03:28 INFO - 1498795408693 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
21:03:28 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1498795408704 Marionette TRACE 388 <- [1,5,null,{"value":null}]
21:03:28 INFO - 1498795408752 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
21:03:28 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:28 INFO - 1498795408758 Marionette TRACE 388 <- [1,6,null,{}]
21:03:28 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 1255ms
21:03:28 INFO - TEST-START | test_click.py TestClick.test_input_file
21:03:28 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:28 INFO - 1498795408788 Marionette DEBUG Closed connection 388
21:03:28 INFO - 1498795408797 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49763
21:03:28 INFO - 1498795408802 Marionette DEBUG Closed connection 389
21:03:28 INFO - 1498795408804 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49764
21:03:28 INFO - 1498795408807 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:28 INFO - 1498795408812 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:28 INFO - 1498795408864 Marionette DEBUG Register listener.js for window 7
21:03:28 INFO - 1498795408880 Marionette TRACE 390 <- [1,1,null,{"sessionId":"477f839b-796f-4bbc-aba2-0060a2499998","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:28 INFO - 1498795408896 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
21:03:28 INFO - 1498795408899 Marionette TRACE 390 <- [1,2,null,{}]
21:03:28 INFO - 1498795408905 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:03:28 INFO - 1498795408907 Marionette TRACE 390 <- [1,3,null,{}]
21:03:28 INFO - 1498795408910 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
21:03:28 INFO - 1498795408912 Marionette TRACE 390 <- [1,4,null,{}]
21:03:28 INFO - 1498795408915 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
21:03:28 INFO - TEST-START: test_click.py TestClick.test_input_file1498795408931 Marionette TRACE 390 <- [1,5,null,{"value":null}]
21:03:28 INFO - 1498795408934 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
21:03:28 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:28 INFO - 1498795408939 Marionette TRACE 390 <- [1,6,null,{}]
21:03:28 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:28 INFO - 1498795408945 Marionette DEBUG Closed connection 390
21:03:28 INFO - 1498795408947 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49765
21:03:28 INFO - 1498795408949 Marionette DEBUG Closed connection 391
21:03:28 INFO - 1498795408951 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49766
21:03:28 INFO - 1498795408953 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:28 INFO - 1498795408956 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:29 INFO - 1498795409008 Marionette DEBUG Register listener.js for window 7
21:03:29 INFO - 1498795409052 Marionette TRACE 392 <- [1,1,null,{"sessionId":"1477d76c-65ae-48ab-82e3-be581ba78604","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:29 INFO - 1498795409083 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
21:03:29 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:29 INFO - 1498795409088 Marionette TRACE 392 <- [1,2,null,{}]
21:03:29 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:29 INFO - 1498795409103 Marionette DEBUG Closed connection 392
21:03:29 INFO - 1498795409105 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49767
21:03:29 INFO - 1498795409127 Marionette DEBUG Closed connection 393
21:03:29 INFO - 1498795409129 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49768
21:03:29 INFO - 1498795409132 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:03:29 INFO - 1498795409135 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:29 INFO - 1498795409187 Marionette DEBUG Register listener.js for window 7
21:03:29 INFO - 1498795409201 Marionette TRACE 394 <- [1,1,null,{"sessionId":"68c214d8-5e92-48f4-8146-e4e1fef3d5b3","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:29 INFO - 1498795409426 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
21:03:29 INFO - 1498795409436 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:29 INFO - 1498795409446 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:29 INFO - 1498795409449 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
21:03:29 INFO - ++DOMWINDOW == 30 (000000C01FF8E000) [pid = 872] [serial = 45] [outer = 000000C02B193800]
21:03:29 INFO - 1498795409510 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:03:29 INFO - 1498795409517 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:03:29 INFO - 1498795409538 Marionette TRACE 394 <- [1,2,null,{}]
21:03:29 INFO - 1498795409561 Marionette TRACE 394 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
21:03:29 INFO - 1498795409575 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"40b0f7ea-f714-4e24-be53-a940e2bfc33e","ELEMENT":"40b0f7ea-f714-4e24-be53-a940e2bfc33e"}}]
21:03:29 INFO - 1498795409580 Marionette TRACE 394 -> [0,4,"clickElement",{"id":"40b0f7ea-f714-4e24-be53-a940e2bfc33e"}]
21:03:29 INFO - 1498795409741 Marionette TRACE 394 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:222:5\nInvalidArgumentError@chrome://marionette/content/error.js:325:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:158: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
21:03:29 INFO - 1498795409763 Marionette TRACE 394 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
21:03:29 INFO - TEST-END: test_click.py TestClick.test_input_file1498795409774 Marionette TRACE 394 <- [1,5,null,{"value":null}]
21:03:29 INFO - 1498795409778 Marionette TRACE 394 -> [0,6,"deleteSession",{}]
21:03:29 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:29 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 1029ms
21:03:29 INFO - TEST-START | test_click.py TestClick.test_obscured_element
21:03:29 INFO - 1498795409787 Marionette TRACE 394 <- [1,6,null,{}]
21:03:29 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:29 INFO - 1498795409849 Marionette DEBUG Closed connection 394
21:03:29 INFO - 1498795409852 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49769
21:03:29 INFO - 1498795409855 Marionette DEBUG Closed connection 395
21:03:29 INFO - 1498795409857 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49770
21:03:29 INFO - 1498795409881 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:29 INFO - 1498795409889 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:29 INFO - 1498795409916 Marionette DEBUG Register listener.js for window 7
21:03:29 INFO - 1498795409936 Marionette TRACE 396 <- [1,1,null,{"sessionId":"e5233bbd-d496-4d3b-a66a-20431afc420b","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:29 INFO - 1498795409944 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
21:03:29 INFO - 1498795409947 Marionette TRACE 396 <- [1,2,null,{}]
21:03:29 INFO - 1498795409962 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:03:29 INFO - 1498795409964 Marionette TRACE 396 <- [1,3,null,{}]
21:03:29 INFO - 1498795409970 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
21:03:29 INFO - 1498795409972 Marionette TRACE 396 <- [1,4,null,{}]
21:03:29 INFO - 1498795409976 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
21:03:29 INFO - TEST-START: test_click.py TestClick.test_obscured_element1498795409988 Marionette TRACE 396 <- [1,5,null,{"value":null}]
21:03:29 INFO - 1498795409998 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
21:03:30 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:30 INFO - 1498795410007 Marionette TRACE 396 <- [1,6,null,{}]
21:03:30 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:30 INFO - 1498795410014 Marionette DEBUG Closed connection 396
21:03:30 INFO - 1498795410018 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49771
21:03:30 INFO - 1498795410020 Marionette DEBUG Closed connection 397
21:03:30 INFO - 1498795410022 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49772
21:03:30 INFO - 1498795410025 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:30 INFO - 1498795410028 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:30 INFO - 1498795410053 Marionette DEBUG Register listener.js for window 7
21:03:30 INFO - 1498795410071 Marionette TRACE 398 <- [1,1,null,{"sessionId":"196d2374-0ddd-4d0b-b39b-70eee36f4bca","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:30 INFO - 1498795410100 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
21:03:30 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:30 INFO - 1498795410106 Marionette TRACE 398 <- [1,2,null,{}]
21:03:30 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:30 INFO - 1498795410130 Marionette DEBUG Closed connection 398
21:03:30 INFO - 1498795410132 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49773
21:03:30 INFO - 1498795410137 Marionette DEBUG Closed connection 399
21:03:30 INFO - 1498795410139 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49774
21:03:30 INFO - 1498795410149 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:03:30 INFO - 1498795410153 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:30 INFO - 1498795410177 Marionette DEBUG Register listener.js for window 7
21:03:30 INFO - 1498795410193 Marionette TRACE 400 <- [1,1,null,{"sessionId":"4d027dfc-b5fc-4563-a0e9-66221391c753","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:30 INFO - --DOMWINDOW == 29 (000000C02AA0F000) [pid = 872] [serial = 29] [outer = 0000000000000000] [url = http://127.0.0.1:49334/test_accessibility.html]
21:03:30 INFO - --DOMWINDOW == 28 (000000C027020800) [pid = 872] [serial = 28] [outer = 0000000000000000] [url = http://127.0.0.1:49334/test_accessibility.html]
21:03:30 INFO - --DOMWINDOW == 27 (000000C033CDA000) [pid = 872] [serial = 27] [outer = 0000000000000000] [url = http://127.0.0.1:49334/test_accessibility.html]
21:03:30 INFO - 1498795410209 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
21:03:30 INFO - 1498795410214 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:03:30 INFO - 1498795410230 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:03:30 INFO - 1498795410231 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
21:03:30 INFO - ++DOMWINDOW == 28 (000000C02631A000) [pid = 872] [serial = 46] [outer = 000000C02B193800]
21:03:30 INFO - 1498795410300 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:03:30 INFO - 1498795410307 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:03:30 INFO - 1498795410324 Marionette TRACE 400 <- [1,2,null,{}]
21:03:30 INFO - 1498795410327 Marionette TRACE 400 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
21:03:30 INFO - 1498795410336 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"49ac5e5b-96b1-488b-ae61-6704d9d99ab6","ELEMENT":"49ac5e5b-96b1-488b-ae61-6704d9d99ab6"}}]
21:03:30 INFO - 1498795410338 Marionette TRACE 400 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
21:03:30 INFO - 1498795410347 Marionette TRACE 400 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"c642382c-a456-47ef-90ef-b21bf223adb8","ELEMENT":"c642382c-a456-47ef-90ef-b21bf223adb8"}}]
21:03:30 INFO - 1498795410349 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"49ac5e5b-96b1-488b-ae61-6704d9d99ab6"}]
21:03:31 INFO - 1498795411602 Marionette DEBUG Canceled page load listener because no navigation has been detected
21:03:31 INFO - 1498795411605 Marionette TRACE 400 <- [1,5,null,{}]
21:03:31 INFO - 1498795411656 Marionette TRACE 400 -> [0,6,"clickElement",{"id":"c642382c-a456-47ef-90ef-b21bf223adb8"}]
21:03:31 INFO - 1498795411818 Marionette TRACE 400 <- [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:222:5\nElementClickInterceptedError@chrome://marionette/content/error.js:304:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:185: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:357:12\nclickElement@chrome://marionette/content/listener.js:1226:5\n"},null]
21:03:31 INFO - 1498795411875 Marionette TRACE 400 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
21:03:31 INFO - 1498795411887 Marionette TRACE 400 <- [1,7,null,{"value":false}]
21:03:31 INFO - 1498795411889 Marionette TRACE 400 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
21:03:31 INFO - TEST-END: test_click.py TestClick.test_obscured_element1498795411898 Marionette TRACE 400 <- [1,8,null,{"value":null}]
21:03:31 INFO - 1498795411900 Marionette TRACE 400 -> [0,9,"deleteSession",{}]
21:03:31 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:31 INFO - 1498795411905 Marionette TRACE 400 <- [1,9,null,{}]
21:03:31 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 2116ms
21:03:31 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
21:03:31 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:31 INFO - 1498795411911 Marionette DEBUG Closed connection 400
21:03:31 INFO - 1498795411913 Marionette DEBUG Accepted connection 401 from 127.0.0.1:49775
21:03:31 INFO - 1498795411967 Marionette DEBUG Closed connection 401
21:03:31 INFO - 1498795411969 Marionette DEBUG Accepted connection 402 from 127.0.0.1:49776
21:03:32 INFO - 1498795412023 Marionette TRACE 402 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:32 INFO - 1498795412026 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:32 INFO - 1498795412051 Marionette DEBUG Register listener.js for window 7
21:03:32 INFO - 1498795412067 Marionette TRACE 402 <- [1,1,null,{"sessionId":"2a40c481-1216-4c2f-a969-40c098693e69","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:32 INFO - 1498795412120 Marionette TRACE 402 -> [0,2,"setTimeouts",{"script":30000}]
21:03:32 INFO - 1498795412122 Marionette TRACE 402 <- [1,2,null,{}]
21:03:32 INFO - 1498795412175 Marionette TRACE 402 -> [0,3,"setTimeouts",{"pageLoad":300000}]
21:03:32 INFO - 1498795412177 Marionette TRACE 402 <- [1,3,null,{}]
21:03:32 INFO - 1498795412216 Marionette TRACE 402 -> [0,4,"setTimeouts",{"implicit":0}]
21:03:32 INFO - 1498795412217 Marionette TRACE 402 <- [1,4,null,{}]
21:03:32 INFO - 1498795412242 Marionette TRACE 402 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
21:03:32 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1498795412250 Marionette TRACE 402 <- [1,5,null,{"value":null}]
21:03:32 INFO - 1498795412252 Marionette TRACE 402 -> [0,6,"deleteSession",{}]
21:03:32 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:32 INFO - 1498795412256 Marionette TRACE 402 <- [1,6,null,{}]
21:03:32 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:32 INFO - 1498795412262 Marionette DEBUG Closed connection 402
21:03:32 INFO - 1498795412264 Marionette DEBUG Accepted connection 403 from 127.0.0.1:49777
21:03:32 INFO - 1498795412267 Marionette DEBUG Closed connection 403
21:03:32 INFO - 1498795412269 Marionette DEBUG Accepted connection 404 from 127.0.0.1:49778
21:03:32 INFO - 1498795412294 Marionette TRACE 404 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
21:03:32 INFO - 1498795412296 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:32 INFO - 1498795412321 Marionette DEBUG Register listener.js for window 7
21:03:32 INFO - 1498795412338 Marionette TRACE 404 <- [1,1,null,{"sessionId":"224c9d15-3571-4771-ac0d-c492dd41dadf","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:32 INFO - 1498795412344 Marionette TRACE 404 -> [0,2,"deleteSession",{}]
21:03:32 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:32 INFO - 1498795412350 Marionette TRACE 404 <- [1,2,null,{}]
21:03:32 INFO - [872] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/pine-w64-d-0000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
21:03:32 INFO - 1498795412358 Marionette DEBUG Closed connection 404
21:03:32 INFO - 1498795412363 Marionette DEBUG Accepted connection 405 from 127.0.0.1:49779
21:03:32 INFO - 1498795412365 Marionette DEBUG Closed connection 405
21:03:32 INFO - 1498795412367 Marionette DEBUG Accepted connection 406 from 127.0.0.1:49780
21:03:32 INFO - 1498795412369 Marionette TRACE 406 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
21:03:32 INFO - 1498795412374 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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}
21:03:32 INFO - 1498795412399 Marionette DEBUG Register listener.js for window 7
21:03:32 INFO - 1498795412414 Marionette TRACE 406 <- [1,1,null,{"sessionId":"26e5ff99-d5a8-4682-990d-b179d4e00f16","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":872,"moz:profile":"c:\\users\\cltbld~1.001\\appdata\\local\\temp\\tmpuhhxgl.mozrunner","moz:accessibilityChecks":false}}]
21:03:32 INFO - 1498795412417 Marionette TRACE 406 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
21:03:32 INFO - 1498795412421 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:03:32 INFO - 1498795412428 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:03:32 INFO - 1498795412429 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
21:03:32 INFO - ++DOMWINDOW == 29 (000000C02E317000) [pid = 872] [serial = 47] [outer = 000000C02B193800]
21:03:32 INFO - 1498795412488 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
21:03:32 INFO - 1498795412495 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
21:03:32 INFO - 1498795412529 Marionette TRACE 406 <- [1,2,null,{}]
21:03:32 INFO - 1498795412540 Marionette TRACE 406 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
21:03:32 INFO - 1498795412548 Marionette TRACE 406 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2ef4f58b-f104-4bee-8148-71b59ca68ebd","ELEMENT":"2ef4f58b-f104-4bee-8148-71b59ca68ebd"}}]
21:03:32 INFO - 1498795412550 Marionette TRACE 406 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"2ef4f58b-f104-4bee-8148-71b59ca68ebd"}]
21:03:32 INFO - 1498795412676 Marionette TRACE 406 <- [1,4,null,{"value":"none"}]
21:03:32 INFO - 1498795412687 Marionette TRACE 406 -> [0,5,"clickElement",{"id":"2ef4f58b-f104-4bee-8148-71b59ca68ebd"}]
21:03:32 INFO - 1498795412805 Marionette TRACE 406 <- [1,5,{"error":"element click intercepted","message":"Element