OSX 10.10.1
Appium 1.4.0
python bindings Appium_Python_Client-0.11
xcode 6.3.1
(I found this in Appium 1.3.4 and failed to log it. I believe I was running Xcode 5.x at that point but not sure)
This is from the UICatalog main page.
-get the table, it works fine
-get the first staictext child of the table, it works fine
-get all statictext children of the table, it fails
-if you try to get the first one again, it works (not shown here)
t=dr.find_element_by_xpath('//UIATableView')
t.find_element_by_xpath('//UIAStaticText')
->appium.webdriver.webelement.WebElement
t.find_elements_by_xpath('//UIAStaticText')
->StaleElementReferenceException: Message: An element command failed because the referenced element is no longer attached to the DOM.
Here are the commands inline with the log (is there an attachment option for discuss?)
# t=dr.find_element_by_xpath('//UIATableView')
2015-05-18 13:50:39,213 [DEBUG] selenium.webdriver.remote.remote_connection:POST http://127.0.0.1:4723/wd/hub/session/afdd4a33-8a79-4825-a990-27d41c61c104/element {"using": "xpath", "sessionId": "afdd4a33-8a79-4825-a990-27d41c61c104", "value": "//UIATableView"} [remote_connection.py:363]
2015-05-18 13:50:39,217 [DEBUG] my_proj.exec_logger.AppiumServer:info: --> POST /wd/hub/session/afdd4a33-8a79-4825-a990-27d41c61c104/element {"using":"xpath","sessionId":"afdd4a33-8a79-4825-a990-27d41c61c104","value":"//UIATableView"} [service.py:47]
2015-05-18 13:50:39,218 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Waiting up to 0ms for condition [service.py:47]
2015-05-18 13:50:39,219 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Pushing command to appium work queue: "au.mainApp().getTreeForXML()" [service.py:47]
2015-05-18 13:50:39,221 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Sending command to instruments: au.mainApp().getTreeForXML() [service.py:47]
2015-05-18 13:50:39,262 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:39 +0000 Debug: Got new command 20 from instruments: au.mainApp().getTreeForXML() [service.py:47]
2015-05-18 13:50:39,269 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:39 +0000 Debug: evaluating au.mainApp().getTreeForXML() [service.py:47]
2015-05-18 13:50:39,403 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:39 +0000 Debug: evaluation finished [service.py:47]
2015-05-18 13:50:39,418 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:39 +0000 Debug: responding with:,"valid":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":0,"width":320,"height":568},">":[{"UIANavigationBar":{"@":{"name":"UICatalog","label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/0","x":0,"y":20,"width":320,"height":44},">":[{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/0/0","x":0,"y":0,"width":320,"height":64},">":[{"UIAImage":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/0/0/0","x":0,"y":64,"width":320,"height":0.5},">":[]}}]}},{"UIAButton":{"@":{"name":"Back","label":"Back","value":null,"dom":null,"enabled":true,"valid":true,"visible":false,"hint":null,"path":"/0/0/0/1","x":8,"y":31.5,"width":21,"height":21},">":[]}},{"UIAStaticText":{"@":{"name":"UICatalog","label":"UICatalog","value":"UIC [service.py:47]
2015-05-18 13:50:39,422 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] width":320,"height":44},">":[{"UIAStaticText":{"@":{"name":"Alerts","label":"Alerts","value":"Alerts","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1/10/0","x":16,"y":509,"width":44,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Various uses of UIAlertView, UIActionSheet","label":"Various uses of UIAlertView, UIActionSheet","value":"Various uses of UIAlertView, UIActionSheet","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1/10/1","x":16,"y":529.5,"width":239,"height":14.5},">":[]}}]}},{"UIATableCell":{"@":{"name":"Transitions","label":null,"value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1/11","x":0,"y":548,"width":320,"height":44},">":[{"UIAStaticText":{"@":{"name":"Transitions","label":"Transitions","value":"Transitions","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1/11/0","x":16,"y":553,"width":82.5,"height":20.5},">":[]}},{" [service.py:47]
2015-05-18 13:50:39,423 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:39 +0000 Debug: Running system command #21: /Users/my_user/Documents/my_proj/.buildout/parts/buildout-node/node-v0.10.24-darwin-x64/bin/node /Users/my_user/Documents/my_proj/.buildout/parts/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"UIAApplication":{"@":{"name":"UICatalog","... [service.py:47]
2015-05-18 13:50:39,483 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (8192 bytes) [service.py:47]
2015-05-18 13:50:39,485 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (3670 bytes) [service.py:47]
2015-05-18 13:50:39,485 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data being routed. [service.py:47]
2015-05-18 13:50:39,486 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got result from instruments: {"status":0,"value":{"UIAApplication":{"@":{"name":"UICatalog","label":"UICatalog","value":null,"dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":20,"width":320,"height":548},">":[{"UIAWindow":{"@":{"name":null,"label":null,"value":null,"dom":null,"enabled":true [service.py:47]
2015-05-18 13:50:39,493 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Pushing command to appium work queue: "au.getElementByIndexPath('/0/0/1')" [service.py:47]
2015-05-18 13:50:39,494 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Sending command to instruments: au.getElementByIndexPath('/0/0/1') [service.py:47]
2015-05-18 13:50:39,549 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:39 +0000 Debug: Got new command 21 from instruments: au.getElementByIndexPath('/0/0/1') [service.py:47]
2015-05-18 13:50:39,557 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:39 +0000 Debug: evaluating au.getElementByIndexPath('/0/0/1') [service.py:47]
2015-05-18 13:50:39,563 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:39 +0000 Debug: evaluation finished [service.py:47]
2015-05-18 13:50:39,571 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:39 +0000 Debug: responding with: [service.py:47]
2015-05-18 13:50:39,579 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:39 +0000 Debug: Running system command #22: /Users/my_user/Documents/my_proj/.buildout/parts/buildout-node/node-v0.10.24-darwin-x64/bin/node /Users/my_user/Documents/my_proj/.buildout/parts/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"ELEMENT":"4"}}... [service.py:47]
2015-05-18 13:50:39,638 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (38 bytes) [service.py:47]
2015-05-18 13:50:39,639 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data being routed. [service.py:47]
2015-05-18 13:50:39,640 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got result from instruments: {"status":0,"value":{"ELEMENT":"4"}} [service.py:47]
2015-05-18 13:50:39,642 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"4"},"sessionId":"afdd4a33-8a79-4825-a990-27d41c61c104"} [service.py:47]
2015-05-18 13:50:39,643 [DEBUG] selenium.webdriver.remote.remote_connection:Finished Request [remote_connection.py:458]
2015-05-18 13:50:39,644 [DEBUG] my_proj.exec_logger.AppiumServer:info: <-- POST /wd/hub/session/afdd4a33-8a79-4825-a990-27d41c61c104/element 200 426.110 ms - 87 {"status":0,"value":{"ELEMENT":"4"},"sessionId":"afdd4a33-8a79-4825-a990-27d41c61c104"} [service.py:47]
# t.find_element_by_xpath('//UIAStaticText')
2015-05-18 13:50:48,934 [DEBUG] selenium.webdriver.remote.remote_connection:POST http://127.0.0.1:4723/wd/hub/session/afdd4a33-8a79-4825-a990-27d41c61c104/element/4/element {"using": "xpath", "sessionId": "afdd4a33-8a79-4825-a990-27d41c61c104", "id": "4", "value": "//UIAStaticText"} [remote_connection.py:363]
2015-05-18 13:50:48,940 [DEBUG] my_proj.exec_logger.AppiumServer:info: --> POST /wd/hub/session/afdd4a33-8a79-4825-a990-27d41c61c104/element/4/element {"using":"xpath","sessionId":"afdd4a33-8a79-4825-a990-27d41c61c104","id":"4","value":"//UIAStaticText"} [service.py:47]
2015-05-18 13:50:48,941 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Waiting up to 0ms for condition [service.py:47]
2015-05-18 13:50:48,942 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Pushing command to appium work queue: "au.getElement('4').getTreeForXML()" [service.py:47]
2015-05-18 13:50:48,942 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Sending command to instruments: au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:49,093 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:49 +0000 Debug: Got new command 22 from instruments: au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:49,100 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:49 +0000 Debug: evaluating au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:49,165 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:49 +0000 Debug: evaluation finished [service.py:47]
2015-05-18 13:50:49,181 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:49 +0000 Debug: responding with:":true,"valid":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":64,"width":320,"height":44},">":[{"UIAStaticText":{"@":{"name":"Buttons","label":"Buttons","value":"Buttons","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/0","x":16,"y":69,"width":60,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Various uses of UIButton","label":"Various uses of UIButton","value":"Various uses of UIButton","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1","x":16,"y":89.5,"width":138,"height":14.5},">":[]}}]}},{"UIATableCell":{"@":{"name":"Controls","label":null,"value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1","x":0,"y":108,"width":320,"height":44},">":[{"UIAStaticText":{"@":{"name":"Controls","label":"Controls","value":"Controls","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1/0","x":16,"y":113, [service.py:47]
2015-05-18 13:50:49,182 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:49 +0000 Debug: Running system command #23: /Users/my_user/Documents/my_proj/.buildout/parts/buildout-node/node-v0.10.24-darwin-x64/bin/node /Users/my_user/Documents/my_proj/.buildout/parts/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"UIATableView":{"@":{"name":null,"label":nu... [service.py:47]
2015-05-18 13:50:49,253 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (8192 bytes) [service.py:47]
2015-05-18 13:50:49,254 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (27 bytes) [service.py:47]
2015-05-18 13:50:49,254 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data being routed. [service.py:47]
2015-05-18 13:50:49,255 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got result from instruments: {"status":0,"value":{"UIATableView":{"@":{"name":null,"label":null,"value":"rows 1 to 12 of 12","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":64,"width":320,"height":504},">":[{"UIATableCell":{"@":{"name":"Buttons","label":null,"value":"","dom":null,"enabled [service.py:47]
2015-05-18 13:50:49,262 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Pushing command to appium work queue: "au.getElementByIndexPath('/0/0/0')" [service.py:47]
2015-05-18 13:50:49,263 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Sending command to instruments: au.getElementByIndexPath('/0/0/0') [service.py:47]
2015-05-18 13:50:49,372 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:49 +0000 Debug: Got new command 23 from instruments: au.getElementByIndexPath('/0/0/0') [service.py:47]
2015-05-18 13:50:49,379 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:49 +0000 Debug: evaluating au.getElementByIndexPath('/0/0/0') [service.py:47]
2015-05-18 13:50:49,386 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:49 +0000 Debug: evaluation finished [service.py:47]
2015-05-18 13:50:49,393 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:49 +0000 Debug: Lookup returned [object UIANavigationBar] with the name "UICatalog" (id: 5). [service.py:47]
2015-05-18 13:50:49,404 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:49 +0000 Debug: responding with: [service.py:47]
2015-05-18 13:50:49,411 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:49 +0000 Debug: Running system command #24: /Users/my_user/Documents/my_proj/.buildout/parts/buildout-node/node-v0.10.24-darwin-x64/bin/node /Users/my_user/Documents/my_proj/.buildout/parts/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"ELEMENT":"5"}}... [service.py:47]
2015-05-18 13:50:49,414 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (38 bytes) [service.py:47]
2015-05-18 13:50:49,415 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data being routed. [service.py:47]
2015-05-18 13:50:49,415 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got result from instruments: {"status":0,"value":{"ELEMENT":"5"}} [service.py:47]
2015-05-18 13:50:49,416 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Responding to client with success: {"status":0,"value":{"ELEMENT":"5"},"sessionId":"afdd4a33-8a79-4825-a990-27d41c61c104"} [service.py:47]
2015-05-18 13:50:49,417 [DEBUG] selenium.webdriver.remote.remote_connection:Finished Request [remote_connection.py:458]
2015-05-18 13:50:49,418 [DEBUG] my_proj.exec_logger.AppiumServer:info: <-- POST /wd/hub/session/afdd4a33-8a79-4825-a990-27d41c61c104/element/4/element 200 476.755 ms - 87 {"status":0,"value":{"ELEMENT":"5"},"sessionId":"afdd4a33-8a79-4825-a990-27d41c61c104"} [service.py:47]
# t.find_elements_by_xpath('//UIAStaticText')
# ---------------------------------------------------------------------------
# StaleElementReferenceException Traceback (most recent call last)
# <ipython-input-10-e66435a178da> in <module>()
# ----> 1 t.find_elements_by_xpath('//UIAStaticText')
#
# /Users/my_user/Documents/my_proj/.buildout/eggs/selenium-2.44.0-py2.7.egg/selenium/webdriver/remote/webelement.pyc in find_elements_by_xpath(self, xpath)
# 227 myelement.find_elements_by_xpath("//a")
# 228 """
# --> 229 return self.find_elements(by=By.XPATH, value=xpath)
# 230
# 231 def find_element_by_class_name(self, name):
#
# /Users/my_user/Documents/my_proj/.buildout/eggs/selenium-2.44.0-py2.7.egg/selenium/webdriver/remote/webelement.pyc in find_elements(self, by, value)
# 397
# 398 return self._execute(Command.FIND_CHILD_ELEMENTS,
# --> 399 {"using": by, "value": value})['value']
# 400
# 401 def __hash__(self):
#
# /Users/my_user/Documents/my_proj/.buildout/eggs/selenium-2.44.0-py2.7.egg/selenium/webdriver/remote/webelement.pyc in _execute(self, command, params)
# 383 params = {}
# 384 params['id'] = self._id
# --> 385 return self._parent.execute(command, params)
# 386
# 387 def find_element(self, by=By.ID, value=None):
#
# /Users/my_user/Documents/my_proj/.buildout/eggs/selenium-2.44.0-py2.7.egg/selenium/webdriver/remote/webdriver.pyc in execute(self, driver_command, params)
# 171 response = self.command_executor.execute(driver_command, params)
# 172 if response:
# --> 173 self.error_handler.check_response(response)
# 174 response['value'] = self._unwrap_value(
# 175 response.get('value', None))
#
# /Users/my_user/Documents/my_proj/.buildout/eggs/Appium_Python_Client-0.11-py2.7.egg/appium/webdriver/errorhandler.pyc in check_response(self, response)
# 27 raise NoSuchContextException(wde.msg, wde.screen, wde.stacktrace)
# 28 else:
# ---> 29 raise wde
# 30
#
# StaleElementReferenceException: Message: An element command failed because the referenced element is no longer attached to the DOM.
2015-05-18 13:50:55,278 [DEBUG] selenium.webdriver.remote.remote_connection:POST http://127.0.0.1:4723/wd/hub/session/afdd4a33-8a79-4825-a990-27d41c61c104/element/4/elements {"using": "xpath", "sessionId": "afdd4a33-8a79-4825-a990-27d41c61c104", "id": "4", "value": "//UIAStaticText"} [remote_connection.py:363]
2015-05-18 13:50:55,282 [DEBUG] my_proj.exec_logger.AppiumServer:info: --> POST /wd/hub/session/afdd4a33-8a79-4825-a990-27d41c61c104/element/4/elements {"using":"xpath","sessionId":"afdd4a33-8a79-4825-a990-27d41c61c104","id":"4","value":"//UIAStaticText"} [service.py:47]
2015-05-18 13:50:55,282 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Waiting up to 0ms for condition [service.py:47]
2015-05-18 13:50:55,283 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Pushing command to appium work queue: "au.getElement('4').getTreeForXML()" [service.py:47]
2015-05-18 13:50:55,284 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Sending command to instruments: au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:55,322 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:55 +0000 Debug: Got new command 24 from instruments: au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:55,327 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:55 +0000 Debug: evaluating au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:55,459 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:55 +0000 Debug: evaluation finished [service.py:47]
2015-05-18 13:50:55,474 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:55 +0000 Debug: responding with:":true,"valid":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":64,"width":320,"height":44},">":[{"UIAStaticText":{"@":{"name":"Buttons","label":"Buttons","value":"Buttons","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/0","x":16,"y":69,"width":60,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Various uses of UIButton","label":"Various uses of UIButton","value":"Various uses of UIButton","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1","x":16,"y":89.5,"width":138,"height":14.5},">":[]}}]}},{"UIATableCell":{"@":{"name":"Controls","label":null,"value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1","x":0,"y":108,"width":320,"height":44},">":[{"UIAStaticText":{"@":{"name":"Controls","label":"Controls","value":"Controls","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1/0","x":16,"y":113, [service.py:47]
2015-05-18 13:50:55,475 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:55 +0000 Debug: Running system command #25: /Users/my_user/Documents/my_proj/.buildout/parts/buildout-node/node-v0.10.24-darwin-x64/bin/node /Users/my_user/Documents/my_proj/.buildout/parts/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"UIATableView":{"@":{"name":null,"label":nu... [service.py:47]
2015-05-18 13:50:55,551 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (8192 bytes) [service.py:47]
2015-05-18 13:50:55,552 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (27 bytes) [service.py:47]
2015-05-18 13:50:55,553 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data being routed. [service.py:47]
2015-05-18 13:50:55,554 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got result from instruments: {"status":0,"value":{"UIATableView":{"@":{"name":null,"label":null,"value":"rows 1 to 12 of 12","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":64,"width":320,"height":504},">":[{"UIATableCell":{"@":{"name":"Buttons","label":null,"value":"","dom":null,"enabled [service.py:47]
2015-05-18 13:50:55,559 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Pushing command to appium work queue: "au.getElementsByIndexPaths([\"/0/0/0\",\"/0/0/1\",\"/0/1/0\",\"/0/1/1\",\"/0/2/0\",\"/0/2/1\",\"/0/3/0\",\"/0/3/1\",\"/0/4/0\",\"/0/4/1\",\"/0/5/0\",\"/0/5/1\",\"/0/6/0\",\"/0/6/1\",\"/0/7/0\",\"/0/7/1\",\"/0/8/0\",\"/0/8/1\",\"/0/9/0\",\"/0/9/1\",\"/0/10/0\",\"/0/10/1\",\"/0/11/0\",\"/0/11/1\"])" [service.py:47]
2015-05-18 13:50:55,560 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Sending command to instruments: au.getElementsByIndexPaths(["/0/0/0","/0/0/1","/0/1/0","/0/1/1","/0/2/0","/0/2/1","/0/3/0","/0/3/1","/0/4/0","/0/4/1","/0/5/0","/0/5/1","/0/6/0","/0/6/1","/0/7/0","/0/7/1","/0/8/0","/0/8/1","/0/9/0","/0/9/1","/0/10/0","/0/10/1","/0/11/0","/0/11/1"]) [service.py:47]
2015-05-18 13:50:55,605 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:55 +0000 Debug: Got new command 25 from instruments: au.getElementsByIndexPaths(["/0/0/0","/0/0/1","/0/1/0","/0/1/1","/0/2/0","/0/2/1","/0/3/0","/0/3/1","/0/4/0","/0/4/1","/0/5/0","/0/5/1","/0/6/0","/0/6/1","/0/7/0","/0/7/1","/0/8/0","/0/8/1","/0/9/0","/0/9/1","/0/10/0","/0/10/1","/0/11/0","/0/11/1"]) [service.py:47]
2015-05-18 13:50:55,614 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:55 +0000 Debug: evaluating au.getElementsByIndexPaths(["/0/0/0","/0/0/1","/0/1/0","/0/1/1","/0/2/0","/0/2/1","/0/3/0","/0/3/1","/0/4/0","/0/4/1","/0/5/0","/0/5/1","/0/6/0","/0/6/1","/0/7/0","/0/7/1","/0/8/0","/0/8/1","/0/9/0","/0/9/1","/0/10/0","/0/10/1","/0/11/0","/0/11/1"]) [service.py:47]
2015-05-18 13:50:55,784 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:55 +0000 Error: VerboseError: Could not find element with path /0/1/0 [service.py:47]
2015-05-18 13:50:55,814 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (65 bytes) [service.py:47]
2015-05-18 13:50:55,815 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data being routed. [service.py:47]
2015-05-18 13:50:55,815 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got result from instruments: {"status":10,"value":"Could not find element with path /0/1/0"} [service.py:47]
2015-05-18 13:50:55,816 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got a warning from uiauto that some index paths could not be resolved, trying again [service.py:47]
2015-05-18 13:50:55,947 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:55 +0000 Error: Error during eval: _handleIndexPathError@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:1886:43 [service.py:47]
2015-05-18 13:50:55,948 [DEBUG] my_proj.exec_logger.AppiumServer:getElementsByIndexPaths@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:1913:43 [service.py:47]
2015-05-18 13:50:55,948 [DEBUG] my_proj.exec_logger.AppiumServer:eval code [service.py:47]
2015-05-18 13:50:55,949 [DEBUG] my_proj.exec_logger.AppiumServer:eval@[native code] [service.py:47]
2015-05-18 13:50:55,950 [DEBUG] my_proj.exec_logger.AppiumServer:startProcessing@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:2702:30 [service.py:47]
2015-05-18 13:50:55,951 [DEBUG] my_proj.exec_logger.AppiumServer:bootstrap@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:2770:31 [service.py:47]
2015-05-18 13:50:55,951 [DEBUG] my_proj.exec_logger.AppiumServer:global code@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:2779:10 [service.py:47]
2015-05-18 13:50:55,956 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:55 +0000 Debug: responding with: [service.py:47]
2015-05-18 13:50:55,965 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:55 +0000 Debug: Running system command #26: /Users/my_user/Documents/my_proj/.buildout/parts/buildout-node/node-v0.10.24-darwin-x64/bin/node /Users/my_user/Documents/my_proj/.buildout/parts/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":10,"value":"Could not find element with path /0/1/0"}... [service.py:47]
2015-05-18 13:50:56,119 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Pushing command to appium work queue: "au.getElement('4').getTreeForXML()" [service.py:47]
2015-05-18 13:50:56,120 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Sending command to instruments: au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:56,163 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Debug: Got new command 26 from instruments: au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:56,172 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Debug: evaluating au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:56,305 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Debug: evaluation finished [service.py:47]
2015-05-18 13:50:56,322 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Debug: responding with:":true,"valid":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":64,"width":320,"height":44},">":[{"UIAStaticText":{"@":{"name":"Buttons","label":"Buttons","value":"Buttons","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/0","x":16,"y":69,"width":60,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Various uses of UIButton","label":"Various uses of UIButton","value":"Various uses of UIButton","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1","x":16,"y":89.5,"width":138,"height":14.5},">":[]}}]}},{"UIATableCell":{"@":{"name":"Controls","label":null,"value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1","x":0,"y":108,"width":320,"height":44},">":[{"UIAStaticText":{"@":{"name":"Controls","label":"Controls","value":"Controls","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1/0","x":16,"y":113, [service.py:47]
2015-05-18 13:50:56,323 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Debug: Running system command #27: /Users/my_user/Documents/my_proj/.buildout/parts/buildout-node/node-v0.10.24-darwin-x64/bin/node /Users/my_user/Documents/my_proj/.buildout/parts/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"UIATableView":{"@":{"name":null,"label":nu... [service.py:47]
2015-05-18 13:50:56,381 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (8192 bytes) [service.py:47]
2015-05-18 13:50:56,381 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (27 bytes) [service.py:47]
2015-05-18 13:50:56,382 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data being routed. [service.py:47]
2015-05-18 13:50:56,383 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got result from instruments: {"status":0,"value":{"UIATableView":{"@":{"name":null,"label":null,"value":"rows 1 to 12 of 12","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":64,"width":320,"height":504},">":[{"UIATableCell":{"@":{"name":"Buttons","label":null,"value":"","dom":null,"enabled [service.py:47]
2015-05-18 13:50:56,389 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Pushing command to appium work queue: "au.getElementsByIndexPaths([\"/0/0/0\",\"/0/0/1\",\"/0/1/0\",\"/0/1/1\",\"/0/2/0\",\"/0/2/1\",\"/0/3/0\",\"/0/3/1\",\"/0/4/0\",\"/0/4/1\",\"/0/5/0\",\"/0/5/1\",\"/0/6/0\",\"/0/6/1\",\"/0/7/0\",\"/0/7/1\",\"/0/8/0\",\"/0/8/1\",\"/0/9/0\",\"/0/9/1\",\"/0/10/0\",\"/0/10/1\",\"/0/11/0\",\"/0/11/1\"])" [service.py:47]
2015-05-18 13:50:56,390 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Sending command to instruments: au.getElementsByIndexPaths(["/0/0/0","/0/0/1","/0/1/0","/0/1/1","/0/2/0","/0/2/1","/0/3/0","/0/3/1","/0/4/0","/0/4/1","/0/5/0","/0/5/1","/0/6/0","/0/6/1","/0/7/0","/0/7/1","/0/8/0","/0/8/1","/0/9/0","/0/9/1","/0/10/0","/0/10/1","/0/11/0","/0/11/1"]) [service.py:47]
2015-05-18 13:50:56,454 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Debug: Got new command 27 from instruments: au.getElementsByIndexPaths(["/0/0/0","/0/0/1","/0/1/0","/0/1/1","/0/2/0","/0/2/1","/0/3/0","/0/3/1","/0/4/0","/0/4/1","/0/5/0","/0/5/1","/0/6/0","/0/6/1","/0/7/0","/0/7/1","/0/8/0","/0/8/1","/0/9/0","/0/9/1","/0/10/0","/0/10/1","/0/11/0","/0/11/1"]) [service.py:47]
2015-05-18 13:50:56,461 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Debug: evaluating au.getElementsByIndexPaths(["/0/0/0","/0/0/1","/0/1/0","/0/1/1","/0/2/0","/0/2/1","/0/3/0","/0/3/1","/0/4/0","/0/4/1","/0/5/0","/0/5/1","/0/6/0","/0/6/1","/0/7/0","/0/7/1","/0/8/0","/0/8/1","/0/9/0","/0/9/1","/0/10/0","/0/10/1","/0/11/0","/0/11/1"]) [service.py:47]
2015-05-18 13:50:56,619 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Error: VerboseError: Could not find element with path /0/1/0 [service.py:47]
2015-05-18 13:50:56,630 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (65 bytes) [service.py:47]
2015-05-18 13:50:56,631 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data being routed. [service.py:47]
2015-05-18 13:50:56,631 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got result from instruments: {"status":10,"value":"Could not find element with path /0/1/0"} [service.py:47]
2015-05-18 13:50:56,632 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got a warning from uiauto that some index paths could not be resolved, trying again [service.py:47]
2015-05-18 13:50:56,805 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Error: Error during eval: _handleIndexPathError@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:1886:43 [service.py:47]
2015-05-18 13:50:56,805 [DEBUG] my_proj.exec_logger.AppiumServer:getElementsByIndexPaths@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:1913:43 [service.py:47]
2015-05-18 13:50:56,806 [DEBUG] my_proj.exec_logger.AppiumServer:eval code [service.py:47]
2015-05-18 13:50:56,806 [DEBUG] my_proj.exec_logger.AppiumServer:eval@[native code] [service.py:47]
2015-05-18 13:50:56,807 [DEBUG] my_proj.exec_logger.AppiumServer:startProcessing@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:2702:30 [service.py:47]
2015-05-18 13:50:56,808 [DEBUG] my_proj.exec_logger.AppiumServer:bootstrap@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:2770:31 [service.py:47]
2015-05-18 13:50:56,808 [DEBUG] my_proj.exec_logger.AppiumServer:global code@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:2779:10 [service.py:47]
2015-05-18 13:50:56,814 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Debug: responding with: [service.py:47]
2015-05-18 13:50:56,824 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Debug: Running system command #28: /Users/my_user/Documents/my_proj/.buildout/parts/buildout-node/node-v0.10.24-darwin-x64/bin/node /Users/my_user/Documents/my_proj/.buildout/parts/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":10,"value":"Could not find element with path /0/1/0"}... [service.py:47]
2015-05-18 13:50:56,935 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Pushing command to appium work queue: "au.getElement('4').getTreeForXML()" [service.py:47]
2015-05-18 13:50:56,935 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Sending command to instruments: au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:57,006 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Debug: Got new command 28 from instruments: au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:57,019 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:56 +0000 Debug: evaluating au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:57,150 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Debug: evaluation finished [service.py:47]
2015-05-18 13:50:57,168 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Debug: responding with:":true,"valid":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":64,"width":320,"height":44},">":[{"UIAStaticText":{"@":{"name":"Buttons","label":"Buttons","value":"Buttons","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/0","x":16,"y":69,"width":60,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Various uses of UIButton","label":"Various uses of UIButton","value":"Various uses of UIButton","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1","x":16,"y":89.5,"width":138,"height":14.5},">":[]}}]}},{"UIATableCell":{"@":{"name":"Controls","label":null,"value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1","x":0,"y":108,"width":320,"height":44},">":[{"UIAStaticText":{"@":{"name":"Controls","label":"Controls","value":"Controls","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1/0","x":16,"y":113, [service.py:47]
2015-05-18 13:50:57,170 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Debug: Running system command #29: /Users/my_user/Documents/my_proj/.buildout/parts/buildout-node/node-v0.10.24-darwin-x64/bin/node /Users/my_user/Documents/my_proj/.buildout/parts/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"UIATableView":{"@":{"name":null,"label":nu... [service.py:47]
2015-05-18 13:50:57,229 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (8192 bytes) [service.py:47]
2015-05-18 13:50:57,230 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (27 bytes) [service.py:47]
2015-05-18 13:50:57,234 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data being routed. [service.py:47]
2015-05-18 13:50:57,235 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got result from instruments: {"status":0,"value":{"UIATableView":{"@":{"name":null,"label":null,"value":"rows 1 to 12 of 12","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":64,"width":320,"height":504},">":[{"UIATableCell":{"@":{"name":"Buttons","label":null,"value":"","dom":null,"enabled [service.py:47]
2015-05-18 13:50:57,242 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Pushing command to appium work queue: "au.getElementsByIndexPaths([\"/0/0/0\",\"/0/0/1\",\"/0/1/0\",\"/0/1/1\",\"/0/2/0\",\"/0/2/1\",\"/0/3/0\",\"/0/3/1\",\"/0/4/0\",\"/0/4/1\",\"/0/5/0\",\"/0/5/1\",\"/0/6/0\",\"/0/6/1\",\"/0/7/0\",\"/0/7/1\",\"/0/8/0\",\"/0/8/1\",\"/0/9/0\",\"/0/9/1\",\"/0/10/0\",\"/0/10/1\",\"/0/11/0\",\"/0/11/1\"])" [service.py:47]
2015-05-18 13:50:57,243 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Sending command to instruments: au.getElementsByIndexPaths(["/0/0/0","/0/0/1","/0/1/0","/0/1/1","/0/2/0","/0/2/1","/0/3/0","/0/3/1","/0/4/0","/0/4/1","/0/5/0","/0/5/1","/0/6/0","/0/6/1","/0/7/0","/0/7/1","/0/8/0","/0/8/1","/0/9/0","/0/9/1","/0/10/0","/0/10/1","/0/11/0","/0/11/1"]) [service.py:47]
2015-05-18 13:50:57,295 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Debug: Got new command 29 from instruments: au.getElementsByIndexPaths(["/0/0/0","/0/0/1","/0/1/0","/0/1/1","/0/2/0","/0/2/1","/0/3/0","/0/3/1","/0/4/0","/0/4/1","/0/5/0","/0/5/1","/0/6/0","/0/6/1","/0/7/0","/0/7/1","/0/8/0","/0/8/1","/0/9/0","/0/9/1","/0/10/0","/0/10/1","/0/11/0","/0/11/1"]) [service.py:47]
2015-05-18 13:50:57,305 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Debug: evaluating au.getElementsByIndexPaths(["/0/0/0","/0/0/1","/0/1/0","/0/1/1","/0/2/0","/0/2/1","/0/3/0","/0/3/1","/0/4/0","/0/4/1","/0/5/0","/0/5/1","/0/6/0","/0/6/1","/0/7/0","/0/7/1","/0/8/0","/0/8/1","/0/9/0","/0/9/1","/0/10/0","/0/10/1","/0/11/0","/0/11/1"]) [service.py:47]
2015-05-18 13:50:57,481 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Error: VerboseError: Could not find element with path /0/1/0 [service.py:47]
2015-05-18 13:50:57,501 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (65 bytes) [service.py:47]
2015-05-18 13:50:57,502 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data being routed. [service.py:47]
2015-05-18 13:50:57,502 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got result from instruments: {"status":10,"value":"Could not find element with path /0/1/0"} [service.py:47]
2015-05-18 13:50:57,504 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got a warning from uiauto that some index paths could not be resolved, trying again [service.py:47]
2015-05-18 13:50:57,625 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Error: Error during eval: _handleIndexPathError@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:1886:43 [service.py:47]
2015-05-18 13:50:57,625 [DEBUG] my_proj.exec_logger.AppiumServer:getElementsByIndexPaths@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:1913:43 [service.py:47]
2015-05-18 13:50:57,626 [DEBUG] my_proj.exec_logger.AppiumServer:eval code [service.py:47]
2015-05-18 13:50:57,626 [DEBUG] my_proj.exec_logger.AppiumServer:eval@[native code] [service.py:47]
2015-05-18 13:50:57,626 [DEBUG] my_proj.exec_logger.AppiumServer:startProcessing@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:2702:30 [service.py:47]
2015-05-18 13:50:57,627 [DEBUG] my_proj.exec_logger.AppiumServer:bootstrap@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:2770:31 [service.py:47]
2015-05-18 13:50:57,627 [DEBUG] my_proj.exec_logger.AppiumServer:global code@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:2779:10 [service.py:47]
2015-05-18 13:50:57,632 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Debug: responding with: [service.py:47]
2015-05-18 13:50:57,639 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Debug: Running system command #30: /Users/my_user/Documents/my_proj/.buildout/parts/buildout-node/node-v0.10.24-darwin-x64/bin/node /Users/my_user/Documents/my_proj/.buildout/parts/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":10,"value":"Could not find element with path /0/1/0"}... [service.py:47]
2015-05-18 13:50:57,804 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Pushing command to appium work queue: "au.getElement('4').getTreeForXML()" [service.py:47]
2015-05-18 13:50:57,805 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Sending command to instruments: au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:57,836 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Debug: Got new command 30 from instruments: au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:57,846 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Debug: evaluating au.getElement('4').getTreeForXML() [service.py:47]
2015-05-18 13:50:57,981 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Debug: evaluation finished [service.py:47]
2015-05-18 13:50:57,997 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Debug: responding with:":true,"valid":true,"visible":true,"hint":null,"path":"/0/0","x":0,"y":64,"width":320,"height":44},">":[{"UIAStaticText":{"@":{"name":"Buttons","label":"Buttons","value":"Buttons","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/0","x":16,"y":69,"width":60,"height":20.5},">":[]}},{"UIAStaticText":{"@":{"name":"Various uses of UIButton","label":"Various uses of UIButton","value":"Various uses of UIButton","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/0/1","x":16,"y":89.5,"width":138,"height":14.5},">":[]}}]}},{"UIATableCell":{"@":{"name":"Controls","label":null,"value":"","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1","x":0,"y":108,"width":320,"height":44},">":[{"UIAStaticText":{"@":{"name":"Controls","label":"Controls","value":"Controls","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0/1/0","x":16,"y":113, [service.py:47]
2015-05-18 13:50:58,001 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:57 +0000 Debug: Running system command #31: /Users/my_user/Documents/my_proj/.buildout/parts/buildout-node/node-v0.10.24-darwin-x64/bin/node /Users/my_user/Documents/my_proj/.buildout/parts/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":0,"value":{"UIATableView":{"@":{"name":null,"label":nu... [service.py:47]
2015-05-18 13:50:58,059 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (8192 bytes) [service.py:47]
2015-05-18 13:50:58,059 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (27 bytes) [service.py:47]
2015-05-18 13:50:58,060 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data being routed. [service.py:47]
2015-05-18 13:50:58,060 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got result from instruments: {"status":0,"value":{"UIATableView":{"@":{"name":null,"label":null,"value":"rows 1 to 12 of 12","dom":null,"enabled":true,"valid":true,"visible":true,"hint":null,"path":"/0","x":0,"y":64,"width":320,"height":504},">":[{"UIATableCell":{"@":{"name":"Buttons","label":null,"value":"","dom":null,"enabled [service.py:47]
2015-05-18 13:50:58,064 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Pushing command to appium work queue: "au.getElementsByIndexPaths([\"/0/0/0\",\"/0/0/1\",\"/0/1/0\",\"/0/1/1\",\"/0/2/0\",\"/0/2/1\",\"/0/3/0\",\"/0/3/1\",\"/0/4/0\",\"/0/4/1\",\"/0/5/0\",\"/0/5/1\",\"/0/6/0\",\"/0/6/1\",\"/0/7/0\",\"/0/7/1\",\"/0/8/0\",\"/0/8/1\",\"/0/9/0\",\"/0/9/1\",\"/0/10/0\",\"/0/10/1\",\"/0/11/0\",\"/0/11/1\"])" [service.py:47]
2015-05-18 13:50:58,065 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Sending command to instruments: au.getElementsByIndexPaths(["/0/0/0","/0/0/1","/0/1/0","/0/1/1","/0/2/0","/0/2/1","/0/3/0","/0/3/1","/0/4/0","/0/4/1","/0/5/0","/0/5/1","/0/6/0","/0/6/1","/0/7/0","/0/7/1","/0/8/0","/0/8/1","/0/9/0","/0/9/1","/0/10/0","/0/10/1","/0/11/0","/0/11/1"]) [service.py:47]
2015-05-18 13:50:58,129 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:58 +0000 Debug: Got new command 31 from instruments: au.getElementsByIndexPaths(["/0/0/0","/0/0/1","/0/1/0","/0/1/1","/0/2/0","/0/2/1","/0/3/0","/0/3/1","/0/4/0","/0/4/1","/0/5/0","/0/5/1","/0/6/0","/0/6/1","/0/7/0","/0/7/1","/0/8/0","/0/8/1","/0/9/0","/0/9/1","/0/10/0","/0/10/1","/0/11/0","/0/11/1"]) [service.py:47]
2015-05-18 13:50:58,141 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:58 +0000 Debug: evaluating au.getElementsByIndexPaths(["/0/0/0","/0/0/1","/0/1/0","/0/1/1","/0/2/0","/0/2/1","/0/3/0","/0/3/1","/0/4/0","/0/4/1","/0/5/0","/0/5/1","/0/6/0","/0/6/1","/0/7/0","/0/7/1","/0/8/0","/0/8/1","/0/9/0","/0/9/1","/0/10/0","/0/10/1","/0/11/0","/0/11/1"]) [service.py:47]
2015-05-18 13:50:58,297 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:58 +0000 Error: VerboseError: Could not find element with path /0/1/0 [service.py:47]
2015-05-18 13:50:58,299 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data received (65 bytes) [service.py:47]
2015-05-18 13:50:58,299 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Socket data being routed. [service.py:47]
2015-05-18 13:50:58,300 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Got result from instruments: {"status":10,"value":"Could not find element with path /0/1/0"} [service.py:47]
2015-05-18 13:50:58,303 [DEBUG] selenium.webdriver.remote.remote_connection:Finished Request [remote_connection.py:458]
2015-05-18 13:50:58,303 [INFO] my_proj.exec_logger.AppiumServer:
'Fail' or 'Error' found:
******************************************************
******************************************************
******************************************************
******************************************************
[service.py:41]
2015-05-18 13:50:58,305 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] Responding to client with error: {"status":10,"value":{"message":"An element command failed because the referenced element is no longer attached to the DOM.","origValue":"Could not find element with path /0/1/0"},"sessionId":"afdd4a33-8a79-4825-a990-27d41c61c104"} [service.py:47]
2015-05-18 13:50:58,312 [DEBUG] my_proj.exec_logger.AppiumServer:info: <-- POST /wd/hub/session/afdd4a33-8a79-4825-a990-27d41c61c104/element/4/elements 500 3020.433 ms - 231 [service.py:47]
2015-05-18 13:50:58,448 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:58 +0000 Error: Error during eval: _handleIndexPathError@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:1886:43 [service.py:47]
2015-05-18 13:50:58,449 [DEBUG] my_proj.exec_logger.AppiumServer:getElementsByIndexPaths@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:1913:43 [service.py:47]
2015-05-18 13:50:58,449 [DEBUG] my_proj.exec_logger.AppiumServer:eval code [service.py:47]
2015-05-18 13:50:58,450 [DEBUG] my_proj.exec_logger.AppiumServer:eval@[native code] [service.py:47]
2015-05-18 13:50:58,450 [DEBUG] my_proj.exec_logger.AppiumServer:startProcessing@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:2702:30 [service.py:47]
2015-05-18 13:50:58,451 [DEBUG] my_proj.exec_logger.AppiumServer:bootstrap@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:2770:31 [service.py:47]
2015-05-18 13:50:58,451 [DEBUG] my_proj.exec_logger.AppiumServer:global code@file:///Users/my_user/Documents/my_proj/.buildout/parts/appium/D26C4B94-6984-431B-A156-A9AA82EBDCCE/bootstrap-69f09e2860fabc53.js:2779:10 [service.py:47]
2015-05-18 13:50:58,456 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:58 +0000 Debug: responding with: [service.py:47]
2015-05-18 13:50:58,464 [DEBUG] my_proj.exec_logger.AppiumServer:info: [debug] [INST] 2015-05-18 18:50:58 +0000 Debug: Running system command #32: /Users/my_user/Documents/my_proj/.buildout/parts/buildout-node/node-v0.10.24-darwin-x64/bin/node /Users/my_user/Documents/my_proj/.buildout/parts/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{"status":10,"value":"Could not find element with path /0/1/0"}... [service.py:47]