Appium is taking a lot of time around 15 sec to locate an element on ios

Lamlouma · August 6, 2015, 7:36am

Appium is taking a lot of time around 15 sec to locate an element on ios even when trying to locate the element by id or name.
Please can someone advise how can we make this faster?

Telmo_Cardoso · August 6, 2015, 8:02am

The screen where you want to search is available at the time of command and then it still takes 15s? Post the logs from the moment of command until the response please.

Lamlouma · August 6, 2015, 8:44am

Yes the screen is available and it still takes to 15 sec to find the element. Please find the logs below:
info: [debug] [INST] 2015-08-06 08:32:06 +0000 Debug: Lookup returned [object UIAStaticText] with the name “Please enter your Passcode” (id: 1).

info: [debug] [INST] 2015-08-06 08:32:06 +0000 Debug: responding with:
info: [debug] [INST] 2015-08-06 08:32:06 +0000 Debug: Running system command #6: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:[{“ELEMENT”:“1”}]}…

info: [debug] Socket data received (40 bytes)

info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:[{“ELEMENT”:“1”}]}
info: [debug] Responding to client with success: {“status”:0,“value”:[{“ELEMENT”:“1”}],“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: <-- POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/elements 200 1241.161 ms - 89 {“status”:0,“value”:[{“ELEMENT”:“1”}],“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}

info: --> POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/timeouts/implicit_wait {“ms”:1000}
info: [debug] Set iOS implicit wait to 1000ms
info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: <-- POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/timeouts/implicit_wait 200 1.231 ms - 76 {“status”:0,“value”:null,“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: --> GET /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element/1/displayed {}
info: [debug] Pushing command to appium work queue: “au.getElement(‘1’).isDisplayed()”
info: [debug] Sending command to instruments: au.getElement(‘1’).isDisplayed()

info: [debug] [INST] 2015-08-06 08:32:07 +0000 Debug: Got new command 6 from instruments: au.getElement(‘1’).isDisplayed()

info: [debug] [INST] 2015-08-06 08:32:07 +0000 Debug: evaluating au.getElement(‘1’).isDisplayed()

info: [debug] [INST] 2015-08-06 08:32:07 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-08-06 08:32:07 +0000 Debug: responding with:
info: [debug] [INST] 2015-08-06 08:32:07 +0000 Debug: Running system command #7: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:true}…

info: [debug] Socket data received (27 bytes)

info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:true}
info: [debug] Responding to client with success: {“status”:0,“value”:true,“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}

info: <-- GET /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element/1/displayed 200 954.190 ms - 76 {“status”:0,“value”:true,“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: --> POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element {“using”:“accessibility id”,“value”:“0”}
info: [debug] Waiting up to 1000ms for condition
info: [debug] Pushing command to appium work queue: “au.getElementByAccessibilityId(‘0’)”
info: [debug] Sending command to instruments: au.getElementByAccessibilityId(‘0’)

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:08 iPhone CLTM[20] : CLTM: _subscription 0x0, _subscribedChannels 0x0

info: [debug] [INST] 2015-08-06 08:32:08 +0000 Debug: Got new command 7 from instruments: au.getElementByAccessibilityId(‘0’)

info: [debug] [INST] 2015-08-06 08:32:08 +0000 Debug: evaluating au.getElementByAccessibilityId(‘0’)

info: [debug] [INST] 2015-08-06 08:32:08 +0000 Debug: evaluation finished

info: [debug] [INST] 2015-08-06 08:32:08 +0000 Debug: Lookup returned [object UIAKey] with the name “0” (id: 2).

info: [debug] [INST] 2015-08-06 08:32:08 +0000 Debug: responding with:
info: [debug] [INST] 2015-08-06 08:32:08 +0000 Debug: Running system command #8: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:{“ELEMENT”:“2”}}…

info: [debug] Socket data received (38 bytes)

info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:{“ELEMENT”:“2”}}
info: [debug] Responding to client with success: {“status”:0,“value”:{“ELEMENT”:“2”},“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}

info: <-- POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element 200 1146.634 ms - 87 {“status”:0,“value”:{“ELEMENT”:“2”},“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: --> POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element/2/click {“id”:“2”}
info: [debug] Pushing command to appium work queue: “au.tapById(‘2’)”
info: [debug] Sending command to instruments: au.tapById(‘2’)

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:09 iPhone MobileGestaltHelper[94] : libMobileGestalt MobileGestalt.c:273: server_access_check denied access to question UniqueDeviceID for pid 616

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:09 iPhone ScriptAgent[616] : libMobileGestalt MobileGestaltSupport.m:170: pid 616 (ScriptAgent) does not have sandbox access for re6Zb+zwFKJNlkQTUeT+/w and IS NOT appropriately entitled

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:09 iPhone ScriptAgent[616] : libMobileGestalt MobileGestalt.c:534: no access to UniqueDeviceID (see rdar://problem/11744455)
info: [debug] [INST] 2015-08-06 08:32:09 +0000 Debug: Got new command 8 from instruments: au.tapById(‘2’)
info: [debug] [INST] 2015-08-06 08:32:09 +0000 Debug: evaluating au.tapById(‘2’)
info: [debug] [INST] 2015-08-06 08:32:09 +0000 Debug: UIAKey.tap()

info: [debug] Socket data received (25 bytes)

info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:""}
info: [debug] Responding to client with success: {“status”:0,“value”:"",“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}

info: <-- POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element/2/click 200 1079.820 ms - 74 {“status”:0,“value”:"",“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: --> POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element {“using”:“accessibility id”,“value”:“0”}
info: [debug] Waiting up to 1000ms for condition
info: [debug] Pushing command to appium work queue: “au.getElementByAccessibilityId(‘0’)”
info: [debug] Sending command to instruments: au.getElementByAccessibilityId(‘0’)
info: [debug] [INST] 2015-08-06 08:32:09 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-08-06 08:32:09 +0000 Debug: responding with:
info: [debug] [INST] 2015-08-06 08:32:09 +0000 Debug: Running system command #9: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:""}…

info: [debug] [INST] 2015-08-06 08:32:10 +0000 Debug: Got new command 9 from instruments: au.getElementByAccessibilityId(‘0’)

info: [debug] [INST] 2015-08-06 08:32:10 +0000 Debug: evaluating au.getElementByAccessibilityId(‘0’)

info: [debug] [INST] 2015-08-06 08:32:11 +0000 Debug: evaluation finished

info: [debug] [INST] 2015-08-06 08:32:11 +0000 Debug: Lookup returned [object UIAKey] with the name “0” (id: 3).

info: [debug] [INST] 2015-08-06 08:32:11 +0000 Debug: responding with:
info: [debug] [INST] 2015-08-06 08:32:11 +0000 Debug: Running system command #10: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:{“ELEMENT”:“3”}}…

info: [debug] Socket data received (38 bytes)

info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:{“ELEMENT”:“3”}}
info: [debug] Responding to client with success: {“status”:0,“value”:{“ELEMENT”:“3”},“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}

info: <-- POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element 200 1295.688 ms - 87 {“status”:0,“value”:{“ELEMENT”:“3”},“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: --> POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element/3/click {“id”:“3”}
info: [debug] Pushing command to appium work queue: “au.tapById(‘3’)”
info: [debug] Sending command to instruments: au.tapById(‘3’)

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:12 iPhone MobileGestaltHelper[94] : libMobileGestalt MobileGestalt.c:273: server_access_check denied access to question UniqueDeviceID for pid 616

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:12 iPhone ScriptAgent[616] : libMobileGestalt MobileGestaltSupport.m:170: pid 616 (ScriptAgent) does not have sandbox access for re6Zb+zwFKJNlkQTUeT+/w and IS NOT appropriately entitled

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:12 iPhone ScriptAgent[616] : libMobileGestalt MobileGestalt.c:534: no access to UniqueDeviceID (see rdar://problem/11744455)

info: [debug] [INST] 2015-08-06 08:32:12 +0000 Debug: Got new command 10 from instruments: au.tapById(‘3’)

info: [debug] [INST] 2015-08-06 08:32:12 +0000 Debug: evaluating au.tapById(‘3’)

info: [debug] [INST] 2015-08-06 08:32:12 +0000 Debug: UIAKey.tap()

info: [debug] Socket data received (25 bytes)

info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:""}
info: [debug] Responding to client with success: {“status”:0,“value”:"",“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}

info: <-- POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element/3/click 200 958.831 ms - 74 {“status”:0,“value”:"",“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: --> POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element {“using”:“accessibility id”,“value”:“0”}
info: [debug] Waiting up to 1000ms for condition
info: [debug] Pushing command to appium work queue: “au.getElementByAccessibilityId(‘0’)”
info: [debug] Sending command to instruments: au.getElementByAccessibilityId(‘0’)
info: [debug] [INST] 2015-08-06 08:32:12 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-08-06 08:32:12 +0000 Debug: responding with:
info: [debug] [INST] 2015-08-06 08:32:12 +0000 Debug: Running system command #11: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:""}…

info: [debug] [INST] 2015-08-06 08:32:13 +0000 Debug: Got new command 11 from instruments: au.getElementByAccessibilityId(‘0’)

info: [debug] [INST] 2015-08-06 08:32:13 +0000 Debug: evaluating au.getElementByAccessibilityId(‘0’)

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:13 iPhone CLTM[20] : CLTM: _subscription 0x0, _subscribedChannels 0x0

info: [debug] [INST] 2015-08-06 08:32:13 +0000 Debug: evaluation finished

info: [debug] [INST] 2015-08-06 08:32:13 +0000 Debug: Lookup returned [object UIAKey] with the name “0” (id: 4).

info: [debug] [INST] 2015-08-06 08:32:13 +0000 Debug: responding with:
info: [debug] [INST] 2015-08-06 08:32:13 +0000 Debug: Running system command #12: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:{“ELEMENT”:“4”}}…

info: [debug] Socket data received (38 bytes)

info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:{“ELEMENT”:“4”}}
info: [debug] Responding to client with success: {“status”:0,“value”:{“ELEMENT”:“4”},“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}

info: <-- POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element 200 1231.592 ms - 87 {“status”:0,“value”:{“ELEMENT”:“4”},“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: --> POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element/4/click {“id”:“4”}
info: [debug] Pushing command to appium work queue: “au.tapById(‘4’)”
info: [debug] Sending command to instruments: au.tapById(‘4’)

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:14 iPhone MobileGestaltHelper[94] : libMobileGestalt MobileGestalt.c:273: server_access_check denied access to question UniqueDeviceID for pid 616

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:14 iPhone ScriptAgent[616] : libMobileGestalt MobileGestaltSupport.m:170: pid 616 (ScriptAgent) does not have sandbox access for re6Zb+zwFKJNlkQTUeT+/w and IS NOT appropriately entitled

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:14 iPhone ScriptAgent[616] : libMobileGestalt MobileGestalt.c:534: no access to UniqueDeviceID (see rdar://problem/11744455)
info: [debug] [INST] 2015-08-06 08:32:14 +0000 Debug: Got new command 12 from instruments: au.tapById(‘4’)
info: [debug] [INST] 2015-08-06 08:32:14 +0000 Debug: evaluating au.tapById(‘4’)
info: [debug] [INST] 2015-08-06 08:32:14 +0000 Debug: UIAKey.tap()

info: [debug] Socket data received (25 bytes)

info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:""}
info: [debug] Responding to client with success: {“status”:0,“value”:"",“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: <-- POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element/4/click 200 1035.598 ms - 74 {“status”:0,“value”:"",“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}

info: --> POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element {“using”:“accessibility id”,“value”:“0”}
info: [debug] Waiting up to 1000ms for condition
info: [debug] Pushing command to appium work queue: “au.getElementByAccessibilityId(‘0’)”
info: [debug] Sending command to instruments: au.getElementByAccessibilityId(‘0’)
info: [debug] [INST] 2015-08-06 08:32:14 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-08-06 08:32:14 +0000 Debug: responding with:
info: [debug] [INST] 2015-08-06 08:32:14 +0000 Debug: Running system command #13: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:""}…

info: [debug] [INST] 2015-08-06 08:32:15 +0000 Debug: Got new command 13 from instruments: au.getElementByAccessibilityId(‘0’)

info: [debug] [INST] 2015-08-06 08:32:15 +0000 Debug: evaluating au.getElementByAccessibilityId(‘0’)

info: [debug] [INST] 2015-08-06 08:32:15 +0000 Debug: evaluation finished

info: [debug] [INST] 2015-08-06 08:32:15 +0000 Debug: Lookup returned [object UIAKey] with the name “0” (id: 5).

info: [debug] [INST] 2015-08-06 08:32:15 +0000 Debug: responding with:
info: [debug] [INST] 2015-08-06 08:32:15 +0000 Debug: Running system command #14: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:{“ELEMENT”:“5”}}…

info: [debug] Socket data received (38 bytes)

info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:{“ELEMENT”:“5”}}
info: [debug] Responding to client with success: {“status”:0,“value”:{“ELEMENT”:“5”},“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: <-- POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element 200 1170.690 ms - 87 {“status”:0,“value”:{“ELEMENT”:“5”},“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: --> POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element/5/click {“id”:“5”}
info: [debug] Pushing command to appium work queue: “au.tapById(‘5’)”
info: [debug] Sending command to instruments: au.tapById(‘5’)

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:16 iPhone MobileGestaltHelper[94] : libMobileGestalt MobileGestalt.c:273: server_access_check denied access to question UniqueDeviceID for pid 616

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:16 iPhone ScriptAgent[616] : libMobileGestalt MobileGestaltSupport.m:170: pid 616 (ScriptAgent) does not have sandbox access for re6Zb+zwFKJNlkQTUeT+/w and IS NOT appropriately entitled

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:16 iPhone ScriptAgent[616] : libMobileGestalt MobileGestalt.c:534: no access to UniqueDeviceID (see rdar://problem/11744455)

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:16 iPhone PrimeFirst[615] : Warning: sync SCNetworkReachability (by-name) query on main thread

info: [debug] [INST] 2015-08-06 08:32:16 +0000 Debug: Got new command 14 from instruments: au.tapById(‘5’)
info: [debug] [INST] 2015-08-06 08:32:16 +0000 Debug: evaluating au.tapById(‘5’)
info: [debug] [INST] 2015-08-06 08:32:16 +0000 Debug: UIAKey.tap()

info: [debug] Socket data received (25 bytes)

info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:""}
info: [debug] Responding to client with success: {“status”:0,“value”:"",“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}

info: <-- POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/element/5/click 200 1094.549 ms - 74 {“status”:0,“value”:"",“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: --> POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/timeouts/implicit_wait {“ms”:0}
info: [debug] Set iOS implicit wait to 0ms
info: [debug] Responding to client with success: {“status”:0,“value”:null,“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: <-- POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/timeouts/implicit_wait 200 1.068 ms - 76 {“status”:0,“value”:null,“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: --> POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/elements {“using”:“id”,“value”:“expiredAccountsAlert”}
info: [debug] Waiting up to 0ms for condition
info: [debug] Pushing command to appium work queue: “au.getElementsByAccessibilityId(‘expiredAccountsAlert’)”
info: [debug] Sending command to instruments: au.getElementsByAccessibilityId(‘expiredAccountsAlert’)
info: [debug] [INST] 2015-08-06 08:32:16 +0000 Debug: evaluation finished
info: [debug] [INST] 2015-08-06 08:32:16 +0000 Debug: responding with:

info: [debug] [INST] 2015-08-06 08:32:16 +0000 Debug: Running system command #15: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:""}…

info: [debug] [INST] 2015-08-06 08:32:17 +0000 Debug: Got new command 15 from instruments: au.getElementsByAccessibilityId(‘expiredAccountsAlert’)

info: [debug] [INST] 2015-08-06 08:32:17 +0000 Debug: evaluating au.getElementsByAccessibilityId(‘expiredAccountsAlert’)

info: [debug] [INST] 2015-08-06 08:32:17 +0000 Debug: evaluation finished

info: [debug] [INST] 2015-08-06 08:32:17 +0000 Debug: responding with:

info: [debug] [INST] 2015-08-06 08:32:17 +0000 Debug: Running system command #16: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:[]}…

info: [debug] Socket data received (25 bytes)

info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:[]}
info: [debug] Id selector, ‘expiredAccountsAlert’, not found in Localizable.strings.
info: [debug] Pushing command to appium work queue: “au.getElementsById(‘expiredAccountsAlert’)”
info: [debug] Sending command to instruments: au.getElementsById(‘expiredAccountsAlert’)

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:18 iPhone PrimeFirst[615] : Warning: sync SCNetworkReachability (by-name) query on main thread

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:18 iPhone PrimeFirst[615] : Warning: sync SCNetworkReachability (by-name) query on main thread
info: [IOS_SYSLOG_ROW ] Aug 6 11:32:18 iPhone PrimeFirst[615] : Warning: sync SCNetworkReachability (by-name) query on main thread
info: [IOS_SYSLOG_ROW ] Aug 6 11:32:18 iPhone PrimeFirst[615] : Warning: sync SCNetworkReachability (by-name) query on main thread
info: [IOS_SYSLOG_ROW ] Aug 6 11:32:18 iPhone PrimeFirst[615] : Warning: sync SCNetworkReachability (by-name) query on main thread
info: [IOS_SYSLOG_ROW ] Aug 6 11:32:18 iPhone PrimeFirst[615] : Warning: sync SCNetworkReachability (by-name) query on main thread
info: [IOS_SYSLOG_ROW ] Aug 6 11:32:18 iPhone PrimeFirst[615] : Warning: sync SCNetworkReachability (by-name) query on main thread
info: [IOS_SYSLOG_ROW ] Aug 6 11:32:18 iPhone PrimeFirst[615] : Warning: sync SCNetworkReachability (by-name) query on main thread
info: [IOS_SYSLOG_ROW ] Aug 6 11:32:18 iPhone PrimeFirst[615] : Warning: sync SCNetworkReachability (by-name) query on main thread

info: [IOS_SYSLOG_ROW ] Aug 6 11:32:18 iPhone CLTM[20] : CLTM: _subscription 0x0, _subscribedChannels 0x0

info: [debug] [INST] 2015-08-06 08:32:18 +0000 Debug: Got new command 16 from instruments: au.getElementsById(‘expiredAccountsAlert’)

info: [debug] [INST] 2015-08-06 08:32:18 +0000 Debug: evaluating au.getElementsById(‘expiredAccountsAlert’)

info: [debug] [INST] 2015-08-06 08:32:19 +0000 Debug: evaluation finished

info: [debug] [INST] 2015-08-06 08:32:19 +0000 Debug: responding with:
info: [debug] [INST] 2015-08-06 08:32:19 +0000 Debug: Running system command #17: /Applications/Appium.app/Contents/Resources/node/bin/node /Applications/Appium.app/Contents/Resources/node_modules/appium/node_modules/appium-uiauto/bin/command-proxy-client.js /tmp/instruments_sock 2,{“status”:0,“value”:[]}…

info: [debug] Socket data received (25 bytes)

info: [debug] Socket data being routed.
info: [debug] Got result from instruments: {“status”:0,“value”:[]}
info: [debug] Condition unmet after 2250ms. Timing out.
info: [debug] Responding to client with success: {“status”:0,“value”:[],“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}

info: <-- POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/elements 200 2251.683 ms - 74 {“status”:0,“value”:[],“sessionId”:“1e6bece7-466d-4f2c-a2ba-cd84ac12d18f”}
info: --> POST /wd/hub/session/1e6bece7-466d-4f2c-a2ba-cd84ac12d18f/elements {“using”:“name”,“value”:“expiredAccountsAlert”}
info: [debug] Waiting up to 0ms for condition
info: [debug] Pushing command to appium work queue: “au.getElementsByName(‘expiredAccountsAlert’)”
info: [debug] Sending command to instruments: au.getElementsByName(‘expiredAccountsAlert’)

info: [debug] [INST] 2015-08-06 08:32:20 +0000 Debug: Got new command 17 from instruments: au.getElementsByName(‘expiredAccountsAlert’)

info: [debug] [INST] 2015-08-06 08:32:20 +0000 Debug: evaluating au.getElementsByName(‘expiredAccountsAlert’)

info: [debug] [INST] 2015-08-06 08:32:20 +0000 Debug: evaluation finished

info: [debug] [INST] 2015-08-06 08:32:20 +0000 Debug: responding with:

Lamlouma · August 6, 2015, 8:52am