when i run my automation on Android7, sometimes Appium responding time suddenly slow down from milliseconds to seconds.
below is part of my Appium log …
we can see, after a [click] action, Appium slow down …
the [click] action was trying to close a application popup dialog, and back to application
please help, thanks!
============================================================================================
22096 2017-04-25 20:54:58:766 - info: --> POST /wd/hub/session/e317dda0-8268-480b-8e5c-f794b42781c7/element {“using”:“id”,“value”:“message”}
22097 2017-04-25 20:54:58:766 - info: [debug] Waiting up to 5000ms for condition
22098 2017-04-25 20:54:58:766 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“message”,“context”:"",“multiple”:false}]
22099 2017-04-25 20:54:58:766 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“message”,“context”:"",“multiple”:false}}
22100 2017-04-25 20:54:58:766 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
22101 2017-04-25 20:54:58:766 - info: [debug] [BOOTSTRAP] [debug] Got command action: find
22102 2017-04-25 20:54:58:766 - info: [debug] [BOOTSTRAP] [debug] Finding message using ID with the contextId: multiple: false
22103 2017-04-25 20:54:58:766 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=myApplication:id/message]
22104 2017-04-25 20:54:58:766 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=android:id/message]
22105 2017-04-25 20:54:58:781 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:{“ELEMENT”:“228”}}
22106 2017-04-25 20:54:58:781 - info: [debug] Responding to client with success: {“status”:0,“value”:{“ELEMENT”:“228”},“sessionId”:“e317dda0-8268-480b-8e5c-f794b42781c7”}
22107 2017-04-25 20:54:58:781 - info: <-- POST /wd/hub/session/e317dda0-8268-480b-8e5c-f794b42781c7/element 200 22.599 ms - 89 {“status”:0,“value”:{“ELEMENT”:“228”},“sessionId”:“e317dda0-8268-480b-8e5c-f794b42781c7”}
22108 2017-04-25 20:54:58:781 - info: --> GET /wd/hub/session/e317dda0-8268-480b-8e5c-f794b42781c7/element/228/text {}
22109 2017-04-25 20:54:58:781 - info: [debug] Pushing command to appium work queue: [“element:getText”,{“elementId”:“228”}]
22110 2017-04-25 20:54:58:781 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“element:getText”,“params”:{“elementId”:“228”}}
22111 2017-04-25 20:54:58:781 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
22112 2017-04-25 20:54:58:781 - info: [debug] [BOOTSTRAP] [debug] Got command action: getText
22113 2017-04-25 20:54:58:797 - info: [debug] Responding to client with success: {“status”:0,“value”:“Show information about the new update?”,“sessionId”:“e317dda0-8268-480b-8e5c-f794b42781c7”}
22114 2017-04-25 20:54:58:797 - info: <-- GET /wd/hub/session/e317dda0-8268-480b-8e5c-f794b42781c7/element/228/text 200 12.409 ms - 112 {“status”:0,“value”:“Show information about the new update?”,“sessionId”:“e317dda0-8268-480b-8e5c-f794b42781c7”}
22115 2017-04-25 20:54:58:797 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:“Show information about the new update?”}
22116 2017-04-25 20:54:58:797 - info: --> POST /wd/hub/session/e317dda0-8268-480b-8e5c-f794b42781c7/element {“using”:“id”,“value”:“button2”}
22117 2017-04-25 20:54:58:797 - info: [debug] Waiting up to 5000ms for condition
22118 2017-04-25 20:54:58:797 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“button2”,“context”:"",“multiple”:false}]
22119 2017-04-25 20:54:58:797 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“button2”,“context”:"",“multiple”:false}}
22120 2017-04-25 20:54:58:797 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
22121 2017-04-25 20:54:58:797 - info: [debug] [BOOTSTRAP] [debug] Got command action: find
22122 2017-04-25 20:54:58:797 - info: [debug] [BOOTSTRAP] [debug] Finding button2 using ID with the contextId: multiple: false
22123 2017-04-25 20:54:58:797 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=myApplication:id/button2]
22124 2017-04-25 20:54:58:844 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=android:id/button2]
22125 2017-04-25 20:54:58:859 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:{“ELEMENT”:“229”}}
22126 2017-04-25 20:54:58:859 - info: [debug] Responding to client with success: {“status”:0,“value”:{“ELEMENT”:“229”},“sessionId”:“e317dda0-8268-480b-8e5c-f794b42781c7”}
22127 2017-04-25 20:54:58:859 - info: <-- POST /wd/hub/session/e317dda0-8268-480b-8e5c-f794b42781c7/element 200 59.194 ms - 89 {“status”:0,“value”:{“ELEMENT”:“229”},“sessionId”:“e317dda0-8268-480b-8e5c-f794b42781c7”}
22128 2017-04-25 20:54:58:859 - info: --> POST /wd/hub/session/e317dda0-8268-480b-8e5c-f794b42781c7/element/229/click {}
22129 2017-04-25 20:54:58:859 - info: [debug] Pushing command to appium work queue: [“element:click”,{“elementId”:“229”}]
22130 2017-04-25 20:54:58:859 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“element:click”,“params”:{“elementId”:“229”}}
22131 2017-04-25 20:54:58:859 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
22132 2017-04-25 20:54:58:859 - info: [debug] [BOOTSTRAP] [debug] Got command action: click
22133 2017-04-25 20:55:02:010 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:0,“value”:true}
22134 2017-04-25 20:55:02:010 - info: [debug] Responding to client with success: {“status”:0,“value”:true,“sessionId”:“e317dda0-8268-480b-8e5c-f794b42781c7”}
22135 2017-04-25 20:55:02:010 - info: <-- POST /wd/hub/session/e317dda0-8268-480b-8e5c-f794b42781c7/element/229/click 200 3154.171 ms - 76 {“status”:0,“value”:true,“sessionId”:“e317dda0-8268-480b-8e5c-f794b42781c7”}
22136 2017-04-25 20:55:07:018 - info: --> POST /wd/hub/session/e317dda0-8268-480b-8e5c-f794b42781c7/element {“using”:“id”,“value”:“meeting_list_btn”}
22137 2017-04-25 20:55:07:018 - info: [debug] Waiting up to 5000ms for condition
22138 2017-04-25 20:55:07:018 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“meeting_list_btn”,“context”:"",“multiple”:false}]
22139 2017-04-25 20:55:07:018 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“meeting_list_btn”,“context”:"",“multiple”:false}}
22140 2017-04-25 20:55:07:018 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
22141 2017-04-25 20:55:07:018 - info: [debug] [BOOTSTRAP] [debug] Got command action: find
22142 2017-04-25 20:55:07:018 - info: [debug] [BOOTSTRAP] [debug] Finding meeting_list_btn using ID with the contextId: multiple: false
22143 2017-04-25 20:55:07:034 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=myApplication:id/meeting_list_btn]
22144 2017-04-25 20:55:14:771 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=android:id/meeting_list_btn]
22145 2017-04-25 20:55:17:049 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[DESCRIPTION=meeting_list_btn, INSTANCE=0]
22146 2017-04-25 20:55:18:578 - info: [debug] [BOOTSTRAP] [debug] Failed to locate element. Clearing Accessibility cache and retrying.
22147 2017-04-25 20:55:18:578 - info: [debug] [BOOTSTRAP] [debug] Finding meeting_list_btn using ID with the contextId: multiple: false
22148 2017-04-25 20:55:18:578 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=myApplication:id/meeting_list_btn]
22149 2017-04-25 20:55:20:106 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=android:id/meeting_list_btn]
22150 2017-04-25 20:55:29:264 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[DESCRIPTION=meeting_list_btn, INSTANCE=0]
22151 2017-04-25 20:55:36:143 - info: [debug] [BOOTSTRAP] [debug] Returning result: {“status”:7,“value”:“No element found”}
22152 2017-04-25 20:55:36:143 - info: [debug] Condition unmet after 29125ms. Timing out.
22153 2017-04-25 20:55:36:143 - info: [debug] Responding to client with error: {“status”:7,“value”:{“message”:“An element could not be located on the page using the given search parameters.”,“origValue”:“No element found”},“sessionId”:“e317dda0-8268-480b-8e5c-f794b42781c7”}
22154 2017-04-25 20:55:36:143 - info: <-- POST /wd/hub/session/e317dda0-8268-480b-8e5c-f794b42781c7/element 500 29126.085 ms - 195
22155 2017-04-25 20:55:36:159 - info: --> POST /wd/hub/session/e317dda0-8268-480b-8e5c-f794b42781c7/element {“using”:“id”,“value”:“navigation_back_btn”}
22156 2017-04-25 20:55:36:159 - info: [debug] Waiting up to 5000ms for condition
22157 2017-04-25 20:55:36:159 - info: [debug] Pushing command to appium work queue: [“find”,{“strategy”:“id”,“selector”:“navigation_back_btn”,“context”:"",“multiple”:false}]
22158 2017-04-25 20:55:36:159 - info: [debug] [BOOTSTRAP] [debug] Got data from client: {“cmd”:“action”,“action”:“find”,“params”:{“strategy”:“id”,“selector”:“navigation_back_btn”,“context”:"",“multiple”:false}}
22159 2017-04-25 20:55:36:159 - info: [debug] [BOOTSTRAP] [debug] Got command of type ACTION
22160 2017-04-25 20:55:36:159 - info: [debug] [BOOTSTRAP] [debug] Got command action: find
22161 2017-04-25 20:55:36:174 - info: [debug] [BOOTSTRAP] [debug] Finding navigation_back_btn using ID with the contextId: multiple: false
22162 2017-04-25 20:55:36:174 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=myApplication:id/navigation_back_btn]
22163 2017-04-25 20:55:37:656 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=android:id/navigation_back_btn]
22164 2017-04-25 20:55:39:185 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[DESCRIPTION=navigation_back_btn, INSTANCE=0]
22165 2017-04-25 20:55:41:463 - info: [debug] [BOOTSTRAP] [debug] Failed to locate element. Clearing Accessibility cache and retrying.
22166 2017-04-25 20:55:41:478 - info: [debug] [BOOTSTRAP] [debug] Finding navigation_back_btn using ID with the contextId: multiple: false
22167 2017-04-25 20:55:41:478 - info: [debug] [BOOTSTRAP] [debug] Using: UiSelector[INSTANCE=0, RESOURCE_ID=myApplication:id/navigation_back_btn]