could not be scrolled into view","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementNotInteractableError@chrome://marionette/content/error.js:315:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:183:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:20:02 INFO - 1500898802892 Marionette TRACE 352 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises')","sandbox":"simpletest","line":328}]
05:20:02 INFO - TEST-END: test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises1500898802899 Marionette TRACE 352 <- [1,5,null,{"value":null}]
05:20:02 INFO - 1500898802901 Marionette TRACE 352 -> [0,6,"deleteSession",{}]
05:20:02 INFO - TEST-PASS | test_click.py TestClick.test_clicking_an_element_that_is_not_displayed_raises | took 746ms
05:20:02 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:02 INFO - TEST-START | test_click.py TestClick.test_clicking_on_a_multiline_link
05:20:02 INFO - 1500898802905 Marionette TRACE 352 <- [1,6,null,{}]
05:20:02 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:02 INFO - 1500898802910 Marionette DEBUG Closed connection 352
05:20:02 INFO - 1500898802911 Marionette DEBUG Accepted connection 353 from 127.0.0.1:49790
05:20:02 INFO - 1500898802913 Marionette DEBUG Closed connection 353
05:20:02 INFO - 1500898802914 Marionette DEBUG Accepted connection 354 from 127.0.0.1:49791
05:20:02 INFO - 1500898802916 Marionette TRACE 354 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:02 INFO - 1500898802919 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:02 INFO - 1500898802939 Marionette DEBUG Register listener.js for window 4294967297
05:20:02 INFO - 1500898802952 Marionette TRACE 354 <- [1,1,null,{"sessionId":"b5e81049-af1e-4e15-8a92-74633f82b371","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:02 INFO - 1500898802955 Marionette TRACE 354 -> [0,2,"setTimeouts",{"script":30000}]
05:20:02 INFO - 1500898802957 Marionette TRACE 354 <- [1,2,null,{}]
05:20:02 INFO - 1500898802967 Marionette TRACE 354 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:02 INFO - 1500898802969 Marionette TRACE 354 <- [1,3,null,{}]
05:20:02 INFO - 1500898802971 Marionette TRACE 354 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:02 INFO - 1500898802972 Marionette TRACE 354 <- [1,4,null,{}]
05:20:02 INFO - 1500898802975 Marionette TRACE 354 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":316}]
05:20:02 INFO - TEST-START: test_click.py TestClick.test_clicking_on_a_multiline_link1500898802983 Marionette TRACE 354 <- [1,5,null,{"value":null}]
05:20:02 INFO - 1500898802985 Marionette TRACE 354 -> [0,6,"deleteSession",{}]
05:20:02 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:02 INFO - 1500898802989 Marionette TRACE 354 <- [1,6,null,{}]
05:20:02 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:02 INFO - 1500898802993 Marionette DEBUG Closed connection 354
05:20:02 INFO - 1500898802995 Marionette DEBUG Accepted connection 355 from 127.0.0.1:49792
05:20:02 INFO - 1500898802996 Marionette DEBUG Closed connection 355
05:20:03 INFO - 1500898802998 Marionette DEBUG Accepted connection 356 from 127.0.0.1:49793
05:20:03 INFO - 1500898803000 Marionette TRACE 356 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:03 INFO - 1500898803002 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:03 INFO - 1500898803024 Marionette DEBUG Register listener.js for window 4294967297
05:20:03 INFO - 1500898803037 Marionette TRACE 356 <- [1,1,null,{"sessionId":"871e520a-5d48-470a-ac28-def13aba6bbb","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:03 INFO - 1500898803041 Marionette TRACE 356 -> [0,2,"deleteSession",{}]
05:20:03 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:03 INFO - 1500898803046 Marionette TRACE 356 <- [1,2,null,{}]
05:20:03 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:03 INFO - 1500898803050 Marionette DEBUG Closed connection 356
05:20:03 INFO - 1500898803051 Marionette DEBUG Accepted connection 357 from 127.0.0.1:49794
05:20:03 INFO - 1500898803053 Marionette DEBUG Closed connection 357
05:20:03 INFO - 1500898803054 Marionette DEBUG Accepted connection 358 from 127.0.0.1:49795
05:20:03 INFO - 1500898803057 Marionette TRACE 358 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:03 INFO - 1500898803059 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:03 INFO - 1500898803080 Marionette DEBUG Register listener.js for window 4294967297
05:20:03 INFO - 1500898803092 Marionette TRACE 358 <- [1,1,null,{"sessionId":"64a192a2-04de-410c-9e6d-f21eb8f3d8a7","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:03 INFO - 1500898803095 Marionette TRACE 358 -> [0,2,"get",{"url":"http://127.0.0.1:49331/clicks.html"}]
05:20:03 INFO - 1500898803099 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/hidden.html"
05:20:03 INFO - 1500898803120 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/hidden.html"
05:20:03 INFO - 1500898803121 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/hidden.html"
05:20:03 INFO - ++DOMWINDOW == 15 (000000F5DA27C000) [pid = 3056] [serial = 25] [outer = 000000F5CE645800]
05:20:03 INFO - 1500898803153 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49331/clicks.html"
05:20:03 INFO - 1500898803157 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49331/clicks.html"
05:20:03 INFO - 1500898803163 Marionette TRACE 358 <- [1,2,null,{}]
05:20:03 INFO - 1500898803178 Marionette TRACE 358 -> [0,3,"findElement",{"using":"id","value":"overflowLink"}]
05:20:03 INFO - 1500898803184 Marionette TRACE 358 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8ef146e8-070d-4fee-b144-11abd3babbde","ELEMENT":"8ef146e8-070d-4fee-b144-11abd3babbde"}}]
05:20:03 INFO - 1500898803190 Marionette TRACE 358 -> [0,4,"clickElement",{"id":"8ef146e8-070d-4fee-b144-11abd3babbde"}]
05:20:03 INFO - 1500898803412 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/clicks.html"
05:20:03 INFO - 1500898803434 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/clicks.html"
05:20:03 INFO - 1500898803435 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/clicks.html"
05:20:03 INFO - ++DOMWINDOW == 16 (000000F5DB9E3800) [pid = 3056] [serial = 26] [outer = 000000F5CE645800]
05:20:03 INFO - 1500898803463 Marionette DEBUG Received DOM event "DOMContentLoaded" for "http://127.0.0.1:49331/test.html"
05:20:03 INFO - 1500898803467 Marionette DEBUG Received DOM event "pageshow" for "http://127.0.0.1:49331/test.html"
05:20:03 INFO - 1500898803480 Marionette TRACE 358 <- [1,4,null,{}]
05:20:03 INFO - 1500898803497 Marionette TRACE 358 -> [0,5,"findElement",{"using":"id","value":"testDiv"}]
05:20:03 INFO - 1500898803504 Marionette TRACE 358 <- [1,5,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"8ad03897-6bdb-4bc8-90e7-4687a238a652","ELEMENT":"8ad03897-6bdb-4bc8-90e7-4687a238a652"}}]
05:20:03 INFO - 1500898803506 Marionette TRACE 358 -> [0,6,"getTitle",{}]
05:20:03 INFO - 1500898803508 Marionette TRACE 358 <- [1,6,null,{"value":"Marionette Test"}]
05:20:03 INFO - 1500898803510 Marionette TRACE 358 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link')","sandbox":"simpletest","line":328}]
05:20:03 INFO - TEST-END: test_click.py TestClick.test_clicking_on_a_multiline_link1500898803517 Marionette TRACE 358 <- [1,7,null,{"value":null}]
05:20:03 INFO - 1500898803519 Marionette TRACE 358 -> [0,8,"deleteSession",{}]
05:20:03 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:03 INFO - 1500898803524 Marionette TRACE 358 <- [1,8,null,{}]
05:20:03 INFO - TEST-PASS | test_click.py TestClick.test_clicking_on_a_multiline_link | took 618ms
05:20:03 INFO - TEST-START | test_click.py TestClick.test_container_element
05:20:03 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:03 INFO - 1500898803528 Marionette DEBUG Closed connection 358
05:20:03 INFO - 1500898803529 Marionette DEBUG Accepted connection 359 from 127.0.0.1:49798
05:20:03 INFO - 1500898803531 Marionette DEBUG Closed connection 359
05:20:03 INFO - 1500898803532 Marionette DEBUG Accepted connection 360 from 127.0.0.1:49799
05:20:03 INFO - 1500898803535 Marionette TRACE 360 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:03 INFO - 1500898803537 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:03 INFO - 1500898803558 Marionette DEBUG Register listener.js for window 4294967297
05:20:03 INFO - 1500898803569 Marionette TRACE 360 <- [1,1,null,{"sessionId":"d4f8ebd7-7905-4323-bf5e-ddb85ba558d2","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:03 INFO - 1500898803579 Marionette TRACE 360 -> [0,2,"setTimeouts",{"script":30000}]
05:20:03 INFO - 1500898803581 Marionette TRACE 360 <- [1,2,null,{}]
05:20:03 INFO - 1500898803583 Marionette TRACE 360 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:03 INFO - 1500898803585 Marionette TRACE 360 <- [1,3,null,{}]
05:20:03 INFO - 1500898803587 Marionette TRACE 360 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:03 INFO - 1500898803588 Marionette TRACE 360 <- [1,4,null,{}]
05:20:03 INFO - 1500898803590 Marionette TRACE 360 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":316}]
05:20:03 INFO - TEST-START: test_click.py TestClick.test_container_element1500898803598 Marionette TRACE 360 <- [1,5,null,{"value":null}]
05:20:03 INFO - 1500898803600 Marionette TRACE 360 -> [0,6,"deleteSession",{}]
05:20:03 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:03 INFO - 1500898803604 Marionette TRACE 360 <- [1,6,null,{}]
05:20:03 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:03 INFO - 1500898803608 Marionette DEBUG Closed connection 360
05:20:03 INFO - 1500898803610 Marionette DEBUG Accepted connection 361 from 127.0.0.1:49800
05:20:03 INFO - 1500898803612 Marionette DEBUG Closed connection 361
05:20:03 INFO - 1500898803613 Marionette DEBUG Accepted connection 362 from 127.0.0.1:49801
05:20:03 INFO - 1500898803615 Marionette TRACE 362 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:03 INFO - 1500898803617 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:03 INFO - 1500898803646 Marionette DEBUG Register listener.js for window 4294967297
05:20:03 INFO - 1500898803660 Marionette TRACE 362 <- [1,1,null,{"sessionId":"4ef08ab4-bb74-4df5-a429-851874ff7fec","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:03 INFO - 1500898803663 Marionette TRACE 362 -> [0,2,"deleteSession",{}]
05:20:03 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:03 INFO - 1500898803668 Marionette TRACE 362 <- [1,2,null,{}]
05:20:03 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:03 INFO - 1500898803671 Marionette DEBUG Closed connection 362
05:20:03 INFO - 1500898803673 Marionette DEBUG Accepted connection 363 from 127.0.0.1:49802
05:20:03 INFO - 1500898803679 Marionette DEBUG Closed connection 363
05:20:03 INFO - 1500898803680 Marionette DEBUG Accepted connection 364 from 127.0.0.1:49803
05:20:03 INFO - 1500898803683 Marionette TRACE 364 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:03 INFO - 1500898803686 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:03 INFO - 1500898803707 Marionette DEBUG Register listener.js for window 4294967297
05:20:03 INFO - 1500898803732 Marionette TRACE 364 <- [1,1,null,{"sessionId":"0ca633bf-fc56-489c-b4d0-4430cc2373f8","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:03 INFO - 1500898803738 Marionette TRACE 364 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:20:03 INFO - 1500898803743 Marionette DEBUG Received DOM event "beforeunload" for "http://127.0.0.1:49331/test.html"
05:20:03 INFO - 1500898803748 Marionette DEBUG Received DOM event "pagehide" for "http://127.0.0.1:49331/test.html"
05:20:03 INFO - 1500898803748 Marionette DEBUG Received DOM event "unload" for "http://127.0.0.1:49331/test.html"
05:20:03 INFO - ++DOMWINDOW == 17 (000000F5DBA45800) [pid = 3056] [serial = 27] [outer = 000000F5CE645800]
05:20:03 INFO - 1500898803770 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:03 INFO - 1500898803777 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:04 INFO - 1500898804142 Marionette TRACE 364 <- [1,2,null,{}]
05:20:04 INFO - 1500898804165 Marionette TRACE 364 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:20:04 INFO - 1500898804174 Marionette TRACE 364 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"23cfbf41-b3de-4424-995d-6436ad4238bf","ELEMENT":"23cfbf41-b3de-4424-995d-6436ad4238bf"}}]
05:20:04 INFO - 1500898804176 Marionette TRACE 364 -> [0,4,"clickElement",{"id":"23cfbf41-b3de-4424-995d-6436ad4238bf"}]
05:20:04 INFO - [Child 3056] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
05:20:04 INFO - [Child 3056] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
05:20:04 INFO - [Child 3056] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
05:20:04 INFO - 1500898804611 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:04 INFO - 1500898804613 Marionette TRACE 364 <- [1,4,null,{}]
05:20:04 INFO - 1500898804615 Marionette TRACE 364 -> [0,5,"getElementProperty",{"id":"23cfbf41-b3de-4424-995d-6436ad4238bf","name":"selected"}]
05:20:04 INFO - 1500898804643 Marionette TRACE 364 <- [1,5,null,{"value":true}]
05:20:04 INFO - 1500898804645 Marionette TRACE 364 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element')","sandbox":"simpletest","line":328}]
05:20:04 INFO - TEST-END: test_click.py TestClick.test_container_element1500898804653 Marionette TRACE 364 <- [1,6,null,{"value":null}]
05:20:04 INFO - 1500898804655 Marionette TRACE 364 -> [0,7,"deleteSession",{}]
05:20:04 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:04 INFO - TEST-PASS | test_click.py TestClick.test_container_element | took 1134ms
05:20:04 INFO - TEST-START | test_click.py TestClick.test_container_element_outside_view
05:20:04 INFO - 1500898804659 Marionette TRACE 364 <- [1,7,null,{}]
05:20:04 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:04 INFO - 1500898804663 Marionette DEBUG Closed connection 364
05:20:04 INFO - 1500898804665 Marionette DEBUG Accepted connection 365 from 127.0.0.1:49804
05:20:04 INFO - 1500898804666 Marionette DEBUG Closed connection 365
05:20:04 INFO - 1500898804668 Marionette DEBUG Accepted connection 366 from 127.0.0.1:49805
05:20:04 INFO - 1500898804675 Marionette TRACE 366 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:04 INFO - 1500898804677 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:04 INFO - 1500898804699 Marionette DEBUG Register listener.js for window 4294967297
05:20:04 INFO - 1500898804710 Marionette TRACE 366 <- [1,1,null,{"sessionId":"67141086-0a47-4fde-accd-be9ede147324","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:04 INFO - 1500898804714 Marionette TRACE 366 -> [0,2,"setTimeouts",{"script":30000}]
05:20:04 INFO - 1500898804715 Marionette TRACE 366 <- [1,2,null,{}]
05:20:04 INFO - 1500898804717 Marionette TRACE 366 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:04 INFO - 1500898804719 Marionette TRACE 366 <- [1,3,null,{}]
05:20:04 INFO - 1500898804720 Marionette TRACE 366 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:04 INFO - 1500898804722 Marionette TRACE 366 <- [1,4,null,{}]
05:20:04 INFO - 1500898804724 Marionette TRACE 366 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":316}]
05:20:04 INFO - TEST-START: test_click.py TestClick.test_container_element_outside_view1500898804731 Marionette TRACE 366 <- [1,5,null,{"value":null}]
05:20:04 INFO - 1500898804734 Marionette TRACE 366 -> [0,6,"deleteSession",{}]
05:20:04 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:04 INFO - 1500898804738 Marionette TRACE 366 <- [1,6,null,{}]
05:20:04 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:04 INFO - 1500898804742 Marionette DEBUG Closed connection 366
05:20:04 INFO - 1500898804743 Marionette DEBUG Accepted connection 367 from 127.0.0.1:49806
05:20:04 INFO - 1500898804745 Marionette DEBUG Closed connection 367
05:20:04 INFO - 1500898804747 Marionette DEBUG Accepted connection 368 from 127.0.0.1:49807
05:20:04 INFO - 1500898804757 Marionette TRACE 368 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:04 INFO - 1500898804759 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:04 INFO - 1500898804783 Marionette DEBUG Register listener.js for window 4294967297
05:20:04 INFO - 1500898804795 Marionette TRACE 368 <- [1,1,null,{"sessionId":"6487f688-e85b-44c7-abdc-3e8db8447533","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:04 INFO - 1500898804798 Marionette TRACE 368 -> [0,2,"deleteSession",{}]
05:20:04 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:04 INFO - 1500898804803 Marionette TRACE 368 <- [1,2,null,{}]
05:20:04 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:04 INFO - 1500898804807 Marionette DEBUG Closed connection 368
05:20:04 INFO - 1500898804808 Marionette DEBUG Accepted connection 369 from 127.0.0.1:49808
05:20:04 INFO - 1500898804810 Marionette DEBUG Closed connection 369
05:20:04 INFO - 1500898804812 Marionette DEBUG Accepted connection 370 from 127.0.0.1:49809
05:20:04 INFO - 1500898804814 Marionette TRACE 370 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:04 INFO - 1500898804816 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:04 INFO - 1500898804838 Marionette DEBUG Register listener.js for window 4294967297
05:20:04 INFO - 1500898804851 Marionette TRACE 370 <- [1,1,null,{"sessionId":"97160f89-7d24-450b-800a-1c6ac139a321","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:04 INFO - 1500898804854 Marionette TRACE 370 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:20:04 INFO - 1500898804858 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:04 INFO - 1500898804862 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:04 INFO - 1500898804863 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:04 INFO - ++DOMWINDOW == 18 (000000F5DA12F800) [pid = 3056] [serial = 28] [outer = 000000F5CE645800]
05:20:04 INFO - 1500898804889 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:04 INFO - 1500898804893 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:04 INFO - 1500898804906 Marionette TRACE 370 <- [1,2,null,{}]
05:20:04 INFO - 1500898804922 Marionette TRACE 370 -> [0,3,"findElement",{"using":"tag name","value":"option"}]
05:20:04 INFO - 1500898804935 Marionette TRACE 370 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"85485525-e2aa-4cb5-83d6-e4fdbff17240","ELEMENT":"85485525-e2aa-4cb5-83d6-e4fdbff17240"}}]
05:20:04 INFO - 1500898804937 Marionette TRACE 370 -> [0,4,"clickElement",{"id":"85485525-e2aa-4cb5-83d6-e4fdbff17240"}]
05:20:05 INFO - [Child 3056] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1905
05:20:05 INFO - [Child 3056] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1813
05:20:05 INFO - [Child 3056] WARNING: NS_ENSURE_TRUE(mouseEvent) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/layout/forms/nsListControlFrame.cpp, line 1619
05:20:05 INFO - 1500898805772 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:05 INFO - 1500898805774 Marionette TRACE 370 <- [1,4,null,{}]
05:20:05 INFO - 1500898805776 Marionette TRACE 370 -> [0,5,"getElementProperty",{"id":"85485525-e2aa-4cb5-83d6-e4fdbff17240","name":"selected"}]
05:20:05 INFO - 1500898805848 Marionette TRACE 370 <- [1,5,null,{"value":true}]
05:20:05 INFO - 1500898805851 Marionette TRACE 370 -> [0,6,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_container_element_outside_view')","sandbox":"simpletest","line":328}]
05:20:05 INFO - TEST-END: test_click.py TestClick.test_container_element_outside_view1500898805883 Marionette TRACE 370 <- [1,6,null,{"value":null}]
05:20:05 INFO - 1500898805885 Marionette TRACE 370 -> [0,7,"deleteSession",{}]
05:20:05 INFO - TEST-PASS | test_click.py TestClick.test_container_element_outside_view | took 1229ms
05:20:05 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:05 INFO - 1500898805889 Marionette TRACE 370 <- [1,7,null,{}]
05:20:05 INFO - TEST-START | test_click.py TestClick.test_css_transforms
05:20:05 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:05 INFO - 1500898805894 Marionette DEBUG Closed connection 370
05:20:05 INFO - 1500898805895 Marionette DEBUG Accepted connection 371 from 127.0.0.1:49810
05:20:05 INFO - 1500898805902 Marionette DEBUG Closed connection 371
05:20:05 INFO - 1500898805904 Marionette DEBUG Accepted connection 372 from 127.0.0.1:49811
05:20:05 INFO - 1500898805906 Marionette TRACE 372 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:05 INFO - 1500898805908 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:05 INFO - 1500898805951 Marionette DEBUG Register listener.js for window 4294967297
05:20:05 INFO - 1500898805962 Marionette TRACE 372 <- [1,1,null,{"sessionId":"8cba73fc-bbde-455a-8445-2043b729d21c","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:05 INFO - 1500898805965 Marionette TRACE 372 -> [0,2,"setTimeouts",{"script":30000}]
05:20:05 INFO - 1500898805967 Marionette TRACE 372 <- [1,2,null,{}]
05:20:05 INFO - 1500898805968 Marionette TRACE 372 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:05 INFO - 1500898805970 Marionette TRACE 372 <- [1,3,null,{}]
05:20:05 INFO - 1500898805972 Marionette TRACE 372 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:05 INFO - 1500898805973 Marionette TRACE 372 <- [1,4,null,{}]
05:20:05 INFO - 1500898805976 Marionette TRACE 372 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":316}]
05:20:06 INFO - TEST-START: test_click.py TestClick.test_css_transforms1500898806018 Marionette TRACE 372 <- [1,5,null,{"value":null}]
05:20:06 INFO - 1500898806020 Marionette TRACE 372 -> [0,6,"deleteSession",{}]
05:20:06 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:06 INFO - 1500898806025 Marionette TRACE 372 <- [1,6,null,{}]
05:20:06 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:06 INFO - 1500898806029 Marionette DEBUG Closed connection 372
05:20:06 INFO - 1500898806030 Marionette DEBUG Accepted connection 373 from 127.0.0.1:49812
05:20:06 INFO - 1500898806032 Marionette DEBUG Closed connection 373
05:20:06 INFO - 1500898806033 Marionette DEBUG Accepted connection 374 from 127.0.0.1:49813
05:20:06 INFO - 1500898806036 Marionette TRACE 374 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:06 INFO - 1500898806038 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:06 INFO - 1500898806073 Marionette DEBUG Register listener.js for window 4294967297
05:20:06 INFO - 1500898806084 Marionette TRACE 374 <- [1,1,null,{"sessionId":"62ddee00-7283-4168-a214-72db4cbb7c76","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:06 INFO - 1500898806087 Marionette TRACE 374 -> [0,2,"deleteSession",{}]
05:20:06 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:06 INFO - 1500898806091 Marionette TRACE 374 <- [1,2,null,{}]
05:20:06 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:06 INFO - 1500898806095 Marionette DEBUG Closed connection 374
05:20:06 INFO - 1500898806097 Marionette DEBUG Accepted connection 375 from 127.0.0.1:49814
05:20:06 INFO - 1500898806099 Marionette DEBUG Closed connection 375
05:20:06 INFO - 1500898806100 Marionette DEBUG Accepted connection 376 from 127.0.0.1:49815
05:20:06 INFO - 1500898806102 Marionette TRACE 376 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:06 INFO - 1500898806105 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:06 INFO - 1500898806141 Marionette DEBUG Register listener.js for window 4294967297
05:20:06 INFO - 1500898806152 Marionette TRACE 376 <- [1,1,null,{"sessionId":"6f891701-19f0-4eea-bfff-3b7791b97d02","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:06 INFO - 1500898806155 Marionette TRACE 376 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"}]
05:20:06 INFO - 1500898806177 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:06 INFO - 1500898806181 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:06 INFO - 1500898806182 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20style%3D%22margin-top%3A%20100vh%22%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efoo%3C/option%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:06 INFO - ++DOMWINDOW == 19 (000000F5DA351000) [pid = 3056] [serial = 29] [outer = 000000F5CE645800]
05:20:06 INFO - 1500898806208 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:20:06 INFO - 1500898806212 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:20:06 INFO - 1500898806226 Marionette TRACE 376 <- [1,2,null,{}]
05:20:06 INFO - 1500898806241 Marionette TRACE 376 -> [0,3,"findElement",{"using":"tag name","value":"div"}]
05:20:06 INFO - 1500898806259 Marionette TRACE 376 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"57b9137f-d3c5-4956-8135-8a0ebbfb768a","ELEMENT":"57b9137f-d3c5-4956-8135-8a0ebbfb768a"}}]
05:20:06 INFO - 1500898806261 Marionette TRACE 376 -> [0,4,"clickElement",{"id":"57b9137f-d3c5-4956-8135-8a0ebbfb768a"}]
05:20:06 INFO - 1500898806695 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:06 INFO - 1500898806697 Marionette TRACE 376 <- [1,4,null,{}]
05:20:06 INFO - 1500898806700 Marionette TRACE 376 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_css_transforms')","sandbox":"simpletest","line":328}]
05:20:06 INFO - TEST-END: test_click.py TestClick.test_css_transforms1500898806707 Marionette TRACE 376 <- [1,5,null,{"value":null}]
05:20:06 INFO - 1500898806709 Marionette TRACE 376 -> [0,6,"deleteSession",{}]
05:20:06 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:06 INFO - TEST-PASS | test_click.py TestClick.test_css_transforms | took 824ms
05:20:06 INFO - TEST-START | test_click.py TestClick.test_inclusive_descendant
05:20:06 INFO - 1500898806713 Marionette TRACE 376 <- [1,6,null,{}]
05:20:06 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:06 INFO - 1500898806717 Marionette DEBUG Closed connection 376
05:20:06 INFO - 1500898806719 Marionette DEBUG Accepted connection 377 from 127.0.0.1:49816
05:20:06 INFO - 1500898806720 Marionette DEBUG Closed connection 377
05:20:06 INFO - 1500898806722 Marionette DEBUG Accepted connection 378 from 127.0.0.1:49817
05:20:06 INFO - 1500898806724 Marionette TRACE 378 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:06 INFO - 1500898806726 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:06 INFO - 1500898806748 Marionette DEBUG Register listener.js for window 4294967297
05:20:06 INFO - 1500898806759 Marionette TRACE 378 <- [1,1,null,{"sessionId":"4f22c110-ad98-43bd-99e1-36cb36da9f7d","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:06 INFO - 1500898806761 Marionette TRACE 378 -> [0,2,"setTimeouts",{"script":30000}]
05:20:06 INFO - 1500898806763 Marionette TRACE 378 <- [1,2,null,{}]
05:20:06 INFO - 1500898806765 Marionette TRACE 378 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:06 INFO - 1500898806766 Marionette TRACE 378 <- [1,3,null,{}]
05:20:06 INFO - 1500898806768 Marionette TRACE 378 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:06 INFO - 1500898806770 Marionette TRACE 378 <- [1,4,null,{}]
05:20:06 INFO - 1500898806772 Marionette TRACE 378 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":316}]
05:20:06 INFO - TEST-START: test_click.py TestClick.test_inclusive_descendant1500898806779 Marionette TRACE 378 <- [1,5,null,{"value":null}]
05:20:06 INFO - 1500898806781 Marionette TRACE 378 -> [0,6,"deleteSession",{}]
05:20:06 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:06 INFO - 1500898806785 Marionette TRACE 378 <- [1,6,null,{}]
05:20:06 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:06 INFO - 1500898806789 Marionette DEBUG Closed connection 378
05:20:06 INFO - 1500898806791 Marionette DEBUG Accepted connection 379 from 127.0.0.1:49818
05:20:06 INFO - 1500898806792 Marionette DEBUG Closed connection 379
05:20:06 INFO - 1500898806794 Marionette DEBUG Accepted connection 380 from 127.0.0.1:49819
05:20:06 INFO - 1500898806796 Marionette TRACE 380 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:06 INFO - 1500898806799 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:06 INFO - 1500898806821 Marionette DEBUG Register listener.js for window 4294967297
05:20:06 INFO - 1500898806832 Marionette TRACE 380 <- [1,1,null,{"sessionId":"b575f5bb-0434-4fc5-b27e-91f41dfe50ee","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:06 INFO - 1500898806835 Marionette TRACE 380 -> [0,2,"deleteSession",{}]
05:20:06 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:06 INFO - 1500898806839 Marionette TRACE 380 <- [1,2,null,{}]
05:20:06 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:06 INFO - 1500898806843 Marionette DEBUG Closed connection 380
05:20:06 INFO - 1500898806844 Marionette DEBUG Accepted connection 381 from 127.0.0.1:49820
05:20:06 INFO - 1500898806845 Marionette DEBUG Closed connection 381
05:20:06 INFO - 1500898806847 Marionette DEBUG Accepted connection 382 from 127.0.0.1:49821
05:20:06 INFO - 1500898806849 Marionette TRACE 382 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:06 INFO - 1500898806851 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:06 INFO - 1500898806873 Marionette DEBUG Register listener.js for window 4294967297
05:20:06 INFO - 1500898806884 Marionette TRACE 382 <- [1,1,null,{"sessionId":"3bd40100-6309-4884-b665-57022d18d290","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:06 INFO - 1500898806887 Marionette TRACE 382 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"}]
05:20:06 INFO - 1500898806891 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:20:06 INFO - 1500898806896 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:20:06 INFO - 1500898806896 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cstyle%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20div%20%7B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20display%3A%20block%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20background-color%3A%20blue%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20width%3A%20200px%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20height%3A%20200px%3B%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%20transform%3A%20translateX%28-105px%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%7D%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/style%3E%0A%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cdiv%3E%3C/div%3E"
05:20:06 INFO - ++DOMWINDOW == 20 (000000F5DA381800) [pid = 3056] [serial = 30] [outer = 000000F5CE645800]
05:20:06 INFO - 1500898806922 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:06 INFO - 1500898806926 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:06 INFO - 1500898806935 Marionette TRACE 382 <- [1,2,null,{}]
05:20:06 INFO - 1500898806950 Marionette TRACE 382 -> [0,3,"findElement",{"using":"tag name","value":"select"}]
05:20:06 INFO - 1500898806957 Marionette TRACE 382 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"a6bb0557-7564-4975-81a0-7b30b163c870","ELEMENT":"a6bb0557-7564-4975-81a0-7b30b163c870"}}]
05:20:06 INFO - 1500898806960 Marionette TRACE 382 -> [0,4,"clickElement",{"id":"a6bb0557-7564-4975-81a0-7b30b163c870"}]
05:20:07 INFO - 1500898807388 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:07 INFO - 1500898807390 Marionette TRACE 382 <- [1,4,null,{}]
05:20:07 INFO - 1500898807393 Marionette TRACE 382 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_inclusive_descendant')","sandbox":"simpletest","line":328}]
05:20:07 INFO - TEST-END: test_click.py TestClick.test_inclusive_descendant1500898807400 Marionette TRACE 382 <- [1,5,null,{"value":null}]
05:20:07 INFO - 1500898807402 Marionette TRACE 382 -> [0,6,"deleteSession",{}]
05:20:07 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:07 INFO - TEST-PASS | test_click.py TestClick.test_inclusive_descendant | took 693ms
05:20:07 INFO - TEST-START | test_click.py TestClick.test_input_file
05:20:07 INFO - 1500898807406 Marionette TRACE 382 <- [1,6,null,{}]
05:20:07 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:07 INFO - 1500898807410 Marionette DEBUG Closed connection 382
05:20:07 INFO - 1500898807412 Marionette DEBUG Accepted connection 383 from 127.0.0.1:49822
05:20:07 INFO - 1500898807413 Marionette DEBUG Closed connection 383
05:20:07 INFO - 1500898807415 Marionette DEBUG Accepted connection 384 from 127.0.0.1:49823
05:20:07 INFO - 1500898807417 Marionette TRACE 384 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:07 INFO - 1500898807419 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:07 INFO - 1500898807441 Marionette DEBUG Register listener.js for window 4294967297
05:20:07 INFO - 1500898807453 Marionette TRACE 384 <- [1,1,null,{"sessionId":"e6031411-edf4-4127-b3a9-713ec73f8b6a","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:07 INFO - 1500898807455 Marionette TRACE 384 -> [0,2,"setTimeouts",{"script":30000}]
05:20:07 INFO - 1500898807457 Marionette TRACE 384 <- [1,2,null,{}]
05:20:07 INFO - 1500898807459 Marionette TRACE 384 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:07 INFO - 1500898807460 Marionette TRACE 384 <- [1,3,null,{}]
05:20:07 INFO - 1500898807463 Marionette TRACE 384 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:07 INFO - 1500898807465 Marionette TRACE 384 <- [1,4,null,{}]
05:20:07 INFO - 1500898807468 Marionette TRACE 384 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":316}]
05:20:07 INFO - TEST-START: test_click.py TestClick.test_input_file1500898807476 Marionette TRACE 384 <- [1,5,null,{"value":null}]
05:20:07 INFO - 1500898807478 Marionette TRACE 384 -> [0,6,"deleteSession",{}]
05:20:07 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:07 INFO - 1500898807482 Marionette TRACE 384 <- [1,6,null,{}]
05:20:07 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:07 INFO - 1500898807486 Marionette DEBUG Closed connection 384
05:20:07 INFO - 1500898807487 Marionette DEBUG Accepted connection 385 from 127.0.0.1:49824
05:20:07 INFO - 1500898807489 Marionette DEBUG Closed connection 385
05:20:07 INFO - 1500898807490 Marionette DEBUG Accepted connection 386 from 127.0.0.1:49825
05:20:07 INFO - 1500898807492 Marionette TRACE 386 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:07 INFO - 1500898807494 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:07 INFO - 1500898807517 Marionette DEBUG Register listener.js for window 4294967297
05:20:07 INFO - 1500898807527 Marionette TRACE 386 <- [1,1,null,{"sessionId":"ccf90a0e-2d68-421f-b906-85f6ad8a3470","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:07 INFO - 1500898807530 Marionette TRACE 386 -> [0,2,"deleteSession",{}]
05:20:07 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:07 INFO - 1500898807535 Marionette TRACE 386 <- [1,2,null,{}]
05:20:07 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:07 INFO - 1500898807540 Marionette DEBUG Closed connection 386
05:20:07 INFO - 1500898807542 Marionette DEBUG Accepted connection 387 from 127.0.0.1:49826
05:20:07 INFO - 1500898807544 Marionette DEBUG Closed connection 387
05:20:07 INFO - 1500898807546 Marionette DEBUG Accepted connection 388 from 127.0.0.1:49827
05:20:07 INFO - 1500898807548 Marionette TRACE 388 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:07 INFO - 1500898807551 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:07 INFO - 1500898807853 Marionette DEBUG Register listener.js for window 4294967297
05:20:07 INFO - 1500898807865 Marionette TRACE 388 <- [1,1,null,{"sessionId":"90b15336-c549-41dc-9396-04962c953c3d","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:07 INFO - 1500898807868 Marionette TRACE 388 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"}]
05:20:07 INFO - 1500898807916 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:07 INFO - 1500898807921 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:07 INFO - 1500898807922 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cselect%20multiple%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Efirst%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Esecond%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20%3Coption%3Ethird%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%3C/select%3E"
05:20:07 INFO - ++DOMWINDOW == 21 (000000F5DAE59800) [pid = 3056] [serial = 31] [outer = 000000F5CE645800]
05:20:07 INFO - 1500898807945 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:07 INFO - 1500898807954 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:07 INFO - 1500898807961 Marionette TRACE 388 <- [1,2,null,{}]
05:20:07 INFO - 1500898807970 Marionette TRACE 388 -> [0,3,"findElement",{"using":"tag name","value":"input"}]
05:20:07 INFO - 1500898807989 Marionette TRACE 388 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"6839ab55-4ecc-434b-a437-9036bf7c5663","ELEMENT":"6839ab55-4ecc-434b-a437-9036bf7c5663"}}]
05:20:07 INFO - 1500898807991 Marionette TRACE 388 -> [0,4,"clickElement",{"id":"6839ab55-4ecc-434b-a437-9036bf7c5663"}]
05:20:08 INFO - 1500898808144 Marionette TRACE 388 <- [1,4,{"error":"invalid argument","message":"Cannot click
elements","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nInvalidArgumentError@chrome://marionette/content/error.js:329:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:165:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:20:08 INFO - 1500898808147 Marionette TRACE 388 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_input_file')","sandbox":"simpletest","line":328}]
05:20:08 INFO - TEST-END: test_click.py TestClick.test_input_file1500898808179 Marionette TRACE 388 <- [1,5,null,{"value":null}]
05:20:08 INFO - 1500898808181 Marionette TRACE 388 -> [0,6,"deleteSession",{}]
05:20:08 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:08 INFO - 1500898808187 Marionette TRACE 388 <- [1,6,null,{}]
05:20:08 INFO - TEST-PASS | test_click.py TestClick.test_input_file | took 780ms
05:20:08 INFO - TEST-START | test_click.py TestClick.test_obscured_element
05:20:08 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:08 INFO - 1500898808192 Marionette DEBUG Closed connection 388
05:20:08 INFO - 1500898808193 Marionette DEBUG Accepted connection 389 from 127.0.0.1:49828
05:20:08 INFO - 1500898808195 Marionette DEBUG Closed connection 389
05:20:08 INFO - 1500898808196 Marionette DEBUG Accepted connection 390 from 127.0.0.1:49829
05:20:08 INFO - 1500898808199 Marionette TRACE 390 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:08 INFO - 1500898808201 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:08 INFO - 1500898808236 Marionette DEBUG Register listener.js for window 4294967297
05:20:08 INFO - 1500898808247 Marionette TRACE 390 <- [1,1,null,{"sessionId":"f663429d-9eef-4581-a07f-689510dd0cbf","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:08 INFO - 1500898808250 Marionette TRACE 390 -> [0,2,"setTimeouts",{"script":30000}]
05:20:08 INFO - 1500898808251 Marionette TRACE 390 <- [1,2,null,{}]
05:20:08 INFO - 1500898808253 Marionette TRACE 390 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:08 INFO - 1500898808255 Marionette TRACE 390 <- [1,3,null,{}]
05:20:08 INFO - 1500898808257 Marionette TRACE 390 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:08 INFO - 1500898808258 Marionette TRACE 390 <- [1,4,null,{}]
05:20:08 INFO - 1500898808261 Marionette TRACE 390 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":316}]
05:20:08 INFO - TEST-START: test_click.py TestClick.test_obscured_element1500898808277 Marionette TRACE 390 <- [1,5,null,{"value":null}]
05:20:08 INFO - 1500898808279 Marionette TRACE 390 -> [0,6,"deleteSession",{}]
05:20:08 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:08 INFO - 1500898808283 Marionette TRACE 390 <- [1,6,null,{}]
05:20:08 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:08 INFO - 1500898808287 Marionette DEBUG Closed connection 390
05:20:08 INFO - 1500898808289 Marionette DEBUG Accepted connection 391 from 127.0.0.1:49830
05:20:08 INFO - 1500898808290 Marionette DEBUG Closed connection 391
05:20:08 INFO - 1500898808292 Marionette DEBUG Accepted connection 392 from 127.0.0.1:49831
05:20:08 INFO - 1500898808294 Marionette TRACE 392 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:08 INFO - 1500898808296 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:08 INFO - 1500898808319 Marionette DEBUG Register listener.js for window 4294967297
05:20:08 INFO - 1500898808346 Marionette TRACE 392 <- [1,1,null,{"sessionId":"15a39f30-a1f0-4f89-9c21-7c491769e0fa","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:08 INFO - 1500898808349 Marionette TRACE 392 -> [0,2,"deleteSession",{}]
05:20:08 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:08 INFO - 1500898808353 Marionette TRACE 392 <- [1,2,null,{}]
05:20:08 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:08 INFO - 1500898808357 Marionette DEBUG Closed connection 392
05:20:08 INFO - 1500898808359 Marionette DEBUG Accepted connection 393 from 127.0.0.1:49832
05:20:08 INFO - 1500898808360 Marionette DEBUG Closed connection 393
05:20:08 INFO - 1500898808362 Marionette DEBUG Accepted connection 394 from 127.0.0.1:49833
05:20:08 INFO - 1500898808364 Marionette TRACE 394 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:08 INFO - 1500898808366 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:08 INFO - 1500898808389 Marionette DEBUG Register listener.js for window 4294967297
05:20:08 INFO - 1500898808400 Marionette TRACE 394 <- [1,1,null,{"sessionId":"f914ee31-db15-40db-bc3b-a8ab7f81d3ea","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:08 INFO - 1500898808403 Marionette TRACE 394 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"}]
05:20:08 INFO - 1500898808407 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:08 INFO - 1500898808412 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:08 INFO - 1500898808413 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%3Cinput%20type%3Dfile%3E"
05:20:08 INFO - ++DOMWINDOW == 22 (000000F5DCF9C800) [pid = 3056] [serial = 32] [outer = 000000F5CE645800]
05:20:08 INFO - 1500898808439 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:20:08 INFO - 1500898808453 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:20:08 INFO - 1500898808455 Marionette TRACE 394 <- [1,2,null,{}]
05:20:08 INFO - 1500898808462 Marionette TRACE 394 -> [0,3,"findElement",{"using":"id","value":"overlay"}]
05:20:08 INFO - 1500898808476 Marionette TRACE 394 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"2c5ff9ee-3b5e-4990-bc52-ecc205363f70","ELEMENT":"2c5ff9ee-3b5e-4990-bc52-ecc205363f70"}}]
05:20:08 INFO - 1500898808478 Marionette TRACE 394 -> [0,4,"findElement",{"using":"id","value":"obscured"}]
05:20:08 INFO - 1500898808484 Marionette TRACE 394 <- [1,4,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"b9cafce6-aa1f-4ac5-a11f-d2cec58f54b0","ELEMENT":"b9cafce6-aa1f-4ac5-a11f-d2cec58f54b0"}}]
05:20:08 INFO - 1500898808486 Marionette TRACE 394 -> [0,5,"clickElement",{"id":"2c5ff9ee-3b5e-4990-bc52-ecc205363f70"}]
05:20:08 INFO - 1500898808934 Marionette DEBUG Canceled page load listener because no navigation has been detected
05:20:08 INFO - 1500898808936 Marionette TRACE 394 <- [1,5,null,{}]
05:20:08 INFO - 1500898808938 Marionette TRACE 394 -> [0,6,"clickElement",{"id":"b9cafce6-aa1f-4ac5-a11f-d2cec58f54b0"}]
05:20:09 INFO - 1500898809065 Marionette TRACE 394 <- [1,6,{"error":"element click intercepted","message":"Element
is not clickable at point (12.449996948242188,10) because another element obscures it","stacktrace":"WebDriverError@chrome://marionette/content/error.js:226:5\nElementClickInterceptedError@chrome://marionette/content/error.js:308:5\nwebdriverClickElement@chrome://marionette/content/interaction.js:192:11\nTaskImpl_run@resource://gre/modules/Task.jsm:331:42\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nTaskImpl_handleResultValue@resource://gre/modules/Task.jsm:401:16\nTaskImpl_run@resource://gre/modules/Task.jsm:339:15\nTaskImpl@resource://gre/modules/Task.jsm:280:3\nasyncFunction@resource://gre/modules/Task.jsm:252:14\nTask_spawn@resource://gre/modules/Task.jsm:166:12\nnavigate@chrome://marionette/content/listener.js:447:12\nclickElement@chrome://marionette/content/listener.js:1367:5\n"},null]
05:20:09 INFO - 1500898809068 Marionette TRACE 394 -> [0,7,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"test_click.py","script":"return window.clicked","sandbox":null,"line":257}]
05:20:09 INFO - 1500898809086 Marionette TRACE 394 <- [1,7,null,{"value":false}]
05:20:09 INFO - 1500898809089 Marionette TRACE 394 -> [0,8,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-END: test_click.py TestClick.test_obscured_element')","sandbox":"simpletest","line":328}]
05:20:09 INFO - TEST-END: test_click.py TestClick.test_obscured_element1500898809096 Marionette TRACE 394 <- [1,8,null,{"value":null}]
05:20:09 INFO - 1500898809098 Marionette TRACE 394 -> [0,9,"deleteSession",{}]
05:20:09 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:09 INFO - TEST-PASS | test_click.py TestClick.test_obscured_element | took 915ms
05:20:09 INFO - TEST-START | test_click.py TestClick.test_pointer_events_none
05:20:09 INFO - 1500898809103 Marionette TRACE 394 <- [1,9,null,{}]
05:20:09 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:09 INFO - 1500898809107 Marionette DEBUG Closed connection 394
05:20:09 INFO - 1500898809108 Marionette DEBUG Accepted connection 395 from 127.0.0.1:49834
05:20:09 INFO - 1500898809110 Marionette DEBUG Closed connection 395
05:20:09 INFO - 1500898809111 Marionette DEBUG Accepted connection 396 from 127.0.0.1:49835
05:20:09 INFO - 1500898809113 Marionette TRACE 396 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:09 INFO - 1500898809115 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:09 INFO - 1500898809138 Marionette DEBUG Register listener.js for window 4294967297
05:20:09 INFO - 1500898809149 Marionette TRACE 396 <- [1,1,null,{"sessionId":"c2405eff-e4ed-4551-8b1d-4c9c3d35115b","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:09 INFO - 1500898809152 Marionette TRACE 396 -> [0,2,"setTimeouts",{"script":30000}]
05:20:09 INFO - 1500898809154 Marionette TRACE 396 <- [1,2,null,{}]
05:20:09 INFO - 1500898809156 Marionette TRACE 396 -> [0,3,"setTimeouts",{"pageLoad":300000}]
05:20:09 INFO - 1500898809157 Marionette TRACE 396 <- [1,3,null,{}]
05:20:09 INFO - 1500898809159 Marionette TRACE 396 -> [0,4,"setTimeouts",{"implicit":0}]
05:20:09 INFO - 1500898809161 Marionette TRACE 396 <- [1,4,null,{}]
05:20:09 INFO - 1500898809164 Marionette TRACE 396 -> [0,5,"executeScript",{"scriptTimeout":null,"newSandbox":true,"args":[],"filename":"testcases.py","script":"dump('TEST-START: test_click.py TestClick.test_pointer_events_none')","sandbox":"simpletest","line":316}]
05:20:09 INFO - TEST-START: test_click.py TestClick.test_pointer_events_none1500898809171 Marionette TRACE 396 <- [1,5,null,{"value":null}]
05:20:09 INFO - 1500898809173 Marionette TRACE 396 -> [0,6,"deleteSession",{}]
05:20:09 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:09 INFO - 1500898809177 Marionette TRACE 396 <- [1,6,null,{}]
05:20:09 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:09 INFO - 1500898809181 Marionette DEBUG Closed connection 396
05:20:09 INFO - 1500898809183 Marionette DEBUG Accepted connection 397 from 127.0.0.1:49836
05:20:09 INFO - 1500898809184 Marionette DEBUG Closed connection 397
05:20:09 INFO - 1500898809186 Marionette DEBUG Accepted connection 398 from 127.0.0.1:49837
05:20:09 INFO - 1500898809188 Marionette TRACE 398 -> [0,1,"newSession",{"sessionId":null,"capabilities":null}]
05:20:09 INFO - 1500898809190 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:09 INFO - 1500898809213 Marionette DEBUG Register listener.js for window 4294967297
05:20:09 INFO - 1500898809225 Marionette TRACE 398 <- [1,1,null,{"sessionId":"fbbacf0b-44d0-45bd-91a9-ea9727b84650","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:09 INFO - 1500898809227 Marionette TRACE 398 -> [0,2,"deleteSession",{}]
05:20:09 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:09 INFO - 1500898809232 Marionette TRACE 398 <- [1,2,null,{}]
05:20:09 INFO - [Parent 1408] WARNING: NS_ENSURE_TRUE(weak) failed: file c:/builds/moz2_slave/m-cen-w64-d-000000000000000000/build/src/dom/base/nsFrameMessageManager.cpp, line 343
05:20:09 INFO - 1500898809236 Marionette DEBUG Closed connection 398
05:20:09 INFO - 1500898809238 Marionette DEBUG Accepted connection 399 from 127.0.0.1:49838
05:20:09 INFO - 1500898809239 Marionette DEBUG Closed connection 399
05:20:09 INFO - 1500898809241 Marionette DEBUG Accepted connection 400 from 127.0.0.1:49839
05:20:09 INFO - 1500898809243 Marionette TRACE 400 -> [0,1,"newSession",{"sessionId":null,"capabilities":{"requiredCapabilities":{"specificationLevel":1}}}]
05:20:09 INFO - 1500898809246 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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}
05:20:09 INFO - 1500898809616 Marionette DEBUG Register listener.js for window 4294967297
05:20:09 INFO - 1500898809627 Marionette TRACE 400 <- [1,1,null,{"sessionId":"7467efd8-89ed-4a84-91b7-3eb1865cdca1","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":1408,"moz:profile":"C:\\Users\\cltbld.T-W864-IX-292.001\\AppData\\Local\\Temp\\tmpvhdgyb.mozrunner","moz:accessibilityChecks":false}}]
05:20:09 INFO - 1500898809630 Marionette TRACE 400 -> [0,2,"get",{"url":"data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"}]
05:20:09 INFO - 1500898809679 Marionette DEBUG Received DOM event "beforeunload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:20:09 INFO - 1500898809684 Marionette DEBUG Received DOM event "pagehide" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:20:09 INFO - 1500898809685 Marionette DEBUG Received DOM event "unload" for "data:text/html;charset=utf-8,%0A%3Cstyle%3E%0A%2A%20%7B%20margin%3A%200%3B%20padding%3A%200%3B%20%7D%0Abody%20%7B%20height%3A%20100vh%20%7D%0A%23overlay%20%7B%0A%20%20background-color%3A%20pink%3B%0A%20%20position%3A%20absolute%3B%0A%20%20width%3A%20100%25%3B%0A%20%20height%3A%20100%25%3B%0A%7D%0A%3C/style%3E%0A%0A%3Cdiv%20id%3Doverlay%3E%3C/div%3E%0A%3Ca%20id%3Dobscured%20href%3D%23%3Elink%3C/a%3E%0A%0A%3Cscript%3E%0Awindow.clicked%20%3D%20false%3B%0A%0Alet%20link%20%3D%20document.querySelector%28%22%23obscured%22%29%3B%0Alink.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%3C/script%3E%0A"
05:20:09 INFO - ++DOMWINDOW == 23 (000000F5DCB0F000) [pid = 3056] [serial = 33] [outer = 000000F5CE645800]
05:20:09 INFO - 1500898809710 Marionette DEBUG Received DOM event "DOMContentLoaded" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:20:09 INFO - 1500898809714 Marionette DEBUG Received DOM event "pageshow" for "data:text/html;charset=utf-8,%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cbutton%20style%3D%22pointer-events%3A%20none%22%3Eclick%20me%3C/button%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%3Cscript%3E%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20window.clicked%20%3D%20false%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20let%20button%20%3D%20document.querySelector%28%22button%22%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%20%20button.addEventListener%28%22click%22%2C%20%28%29%20%3D%3E%20window.clicked%20%3D%20true%29%3B%0A%20%20%20%20%20%20%20%20%20%20%20%20%3C/script%3E%0A%20%20%20%20%20%20%20%20"
05:20:09 INFO - 1500898809728 Marionette TRACE 400 <- [1,2,null,{}]
05:20:09 INFO - 1500898809744 Marionette TRACE 400 -> [0,3,"findElement",{"using":"tag name","value":"button"}]
05:20:09 INFO - 1500898809751 Marionette TRACE 400 <- [1,3,null,{"value":{"element-6066-11e4-a52e-4f735466cecf":"3a0b11bb-d011-4c84-b87a-5091e492af6f","ELEMENT":"3a0b11bb-d011-4c84-b87a-5091e492af6f"}}]
05:20:09 INFO - 1500898809753 Marionette TRACE 400 -> [0,4,"getElementValueOfCssProperty",{"propertyName":"pointer-events","id":"3a0b11bb-d011-4c84-b87a-5091e492af6f"}]
05:20:09 INFO - 1500898809879 Marionette TRACE 400 <- [1,4,null,{"value":"none"}]
05:20:09 INFO - 1500898809881 Marionette TRACE 400 -> [0,5,"clickElement",{"id":"3a0b11bb-d011-4c84-b87a-5091e492af6f"}]
05:20:10 INFO - 1500898810038 Marionette TRACE 400 <- [1,5,{"error":"element click intercepted","message":"Element