Android 1.22-rc.3 is MUCH slower than 1.21

Still getting details on when this specifically started happening, but I noticed a test that usually takes in the 2 minute range now takes > 9 minutes (sometimes as much as 11 minutes). It still passes, but is MUCH longer than before

1.23-rc.3 search for elements

[2021-09-10T09:18:12-0400] [ALL] [HTTP] --> POST /wd/hub/session/b52b1588-3f66-441c-a0db-ea42a5003287/elements
[2021-09-10T09:18:12-0400] [ALL] [HTTP] {"using":"-android uiautomator","value":"new UiSelector().className(\"android.widget.TextView\").resourceId(\"com.tripadvisor.tripadvisor.prerelease:id/txtHeading\")"}
[2021-09-10T09:18:12-0400] [ALL] [W3C (b52b1588)] Calling AppiumDriver.findElements() with args: ["-android uiautomator","new UiSelector().className(\"android.widget.TextView\").resourceId(\"com.tripadvisor.tripadvisor.prerelease:id/txtHeading\")","b52b1588-3f66-441c-a0db-ea42a5003287"]
[2021-09-10T09:18:12-0400] [ALL] [BaseDriver] Valid locator strategies for this request: xpath, id, class name, accessibility id, css selector, -android uiautomator
[2021-09-10T09:18:12-0400] [ALL] [BaseDriver] Waiting up to 0 ms for condition
[2021-09-10T09:18:12-0400] [ALL] [WD Proxy] Matched '/elements' to command name 'findElements'
[2021-09-10T09:18:12-0400] [ALL] [WD Proxy] Proxying [POST /elements] to [POST http://127.0.0.1:7833/wd/hub/session/29d63bd0-1dad-4c6e-8c46-a9a3818822ae/elements] with body: {"strategy":"-android uiautomator","selector":"new UiSelector().className(\"android.widget.TextView\").resourceId(\"com.tripadvisor.tripadvisor.prerelease:id/txtHeading\")","context":"","multiple":true}
[2021-09-10T09:18:12-0400] [ALL] [HTTP] --> GET /wd/hub/status
[2021-09-10T09:18:12-0400] [ALL] [HTTP] {}
[2021-09-10T09:18:12-0400] [ALL] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[2021-09-10T09:18:12-0400] [ALL] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.22.0-rc.3"}}
[2021-09-10T09:18:12-0400] [ALL] [HTTP] <-- GET /wd/hub/status 200 1 ms - 73
[2021-09-10T09:18:12-0400] [ALL] [HTTP] 
[2021-09-10T09:18:12-0400] [ALL] [HTTP] --> GET /wd/hub/status
[2021-09-10T09:18:12-0400] [ALL] [HTTP] {}
[2021-09-10T09:18:12-0400] [ALL] [GENERIC] Calling AppiumDriver.getStatus() with args: []
[2021-09-10T09:18:12-0400] [ALL] [GENERIC] Responding to client with driver.getStatus() result: {"build":{"version":"1.22.0-rc.3"}}
[2021-09-10T09:18:12-0400] [ALL] [HTTP] <-- GET /wd/hub/status 200 1 ms - 73
[2021-09-10T09:18:12-0400] [ALL] [HTTP] 
[2021-09-10T09:18:12-0400] [ALL] [WD Proxy] Got response with status 200: {"sessionId":"29d63bd0-1dad-4c6e-8c46-a9a3818822ae","value":[{"ELEMENT":"00000000-0000-0315-ffff-ffff000000dd","element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000dd"},{"ELEMENT":"00000000-0000-0315-ffff-ffff000000da","element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000da"},{"ELEMENT":"00000000-0000-0315-ffff-ffff000000d8","element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000d8"},{"ELEMENT":"00000000-0000-0315-ffff-ffff000000e6","element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000e6"},{"ELEMENT":"00000000-0000-0315-ffff-ffff000000e0","element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000e0"},{"ELEMENT":"00000000-0000-0315-ffff-ffff000000e3","element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000e3"},{"ELEMENT":"00000000-0000-0315-ffff-ffff000000ed","element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000ed"}]}
[2021-09-10T09:18:12-0400] [ALL] [W3C (b52b1588)] Responding to client with driver.findElements() result: [{"element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000dd","ELEMENT":"00000000-0000-0315-ffff-ffff000000dd"},{"element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000da","ELEMENT":"00000000-0000-0315-ffff-ffff000000da"},{"element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000d8","ELEMENT":"00000000-0000-0315-ffff-ffff000000d8"},{"element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000e6","ELEMENT":"00000000-0000-0315-ffff-ffff000000e6"},{"element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000e0","ELEMENT":"00000000-0000-0315-ffff-ffff000000e0"},{"element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000e3","ELEMENT":"00000000-0000-0315-ffff-ffff000000e3"},{"element-6066-11e4-a52e-4f735466cecf":"00000000-0000-0315-ffff-ffff000000ed","ELEMENT":"00000000-0000-0315-ffff-ffff000000ed"}]
[2021-09-10T09:18:12-0400] [ALL] [HTTP] <-- POST /wd/hub/session/b52b1588-3f66-441c-a0db-ea42a5003287/elements 200 11837 ms - 907

1.19.1 (similar results from 1.21.0)

2021-09-09 17:30:18:908 - [HTTP] --> POST /wd/hub/session/c018fb28-bdff-4c11-b0ad-eebd60c9ee26/elements
2021-09-09 17:30:18:908 - [HTTP] {"using":"-android uiautomator","value":"new UiSelector().className(\"android.widget.TextView\").resourceId(\"com.tripadvisor.tripadvisor.prerelease:id/txtHeading\")"}
2021-09-09 17:30:18:908 - [debug] [W3C (c018fb28)] Calling AppiumDriver.findElements() with args: ["-android uiautomator","new UiSelector().className(\"android.widget.TextView\").resourceId(\"com.tripadvisor.tripadv
isor.prerelease:id/txtHeading\")","c018fb28-bdff-4c11-b0ad-eebd60c9ee26"]
2021-09-09 17:30:18:908 - [debug] [BaseDriver] Valid locator strategies for this request: xpath, id, class name, accessibility id, css selector, -android uiautomator
2021-09-09 17:30:18:909 - [debug] [BaseDriver] Waiting up to 0 ms for condition
2021-09-09 17:30:18:909 - [debug] [WD Proxy] Matched '/elements' to command name 'findElements'
2021-09-09 17:30:18:909 - [debug] [WD Proxy] Proxying [POST /elements] to [POST http://127.0.0.1:7823/wd/hub/session/91d4b2bf-6da7-47aa-986a-e2e7e9e9c02e/elements] with body: {"strategy":"-android uiautomator","sele
ctor":"new UiSelector().className(\"android.widget.TextView\").resourceId(\"com.tripadvisor.tripadvisor.prerelease:id/txtHeading\")","context":"","multiple":true}
2021-09-09 17:30:19:361 - [debug] [WD Proxy] Got response with status 200: {"sessionId":"91d4b2bf-6da7-47aa-986a-e2e7e9e9c02e","value":[{"ELEMENT":"e04ba2d8-c822-4956-9c34-4c438628006b","element-6066-11e4-a52e-4f735
466cecf":"e04ba2d8-c822-4956-9c34-4c438628006b"},{"ELEMENT":"ebcdf5bc-1852-40d9-a4a8-b1c143873017","element-6066-11e4-a52e-4f735466cecf":"ebcdf5bc-1852-40d9-a4a8-b1c143873017"},{"ELEMENT":"89524192-2ecd-4e78-a375-19
8dcf9e389c","element-6066-11e4-a52e-4f735466cecf":"89524192-2ecd-4e78-a375-198dcf9e389c"},{"ELEMENT":"2e2845ff-1f92-4e69-a4f8-c14b53c430d4","element-6066-11e4-a52e-4f735466cecf":"2e2845ff-1f92-4e69-a4f8-c14b53c430d4
"},{"ELEMENT":"122bf7af-639f-4a4d-b876-25499cd0a983","element-6066-11e4-a52e-4f735466cecf":"122bf7af-639f-4a4d-b876-25499cd0a983"},{"ELEMENT":"97099711-0c05-422c-9790-a97997ace478","element-6066-11e4-a52e-4f735466ce
cf":"97099711-0c05-422c-9790-a97997ace478"},{"ELEMENT":"8dd33a0b-b0cc-46d8-8bf5-446ce5d31691","element-6066-11e4-a52e-4f735466cecf":"8dd33a0b-b0cc-46d8-8bf5-446ce5d31691"}]}
2021-09-09 17:30:19:361 - [debug] [W3C (c018fb28)] Responding to client with driver.findElements() result: [{"element-6066-11e4-a52e-4f735466cecf":"e04ba2d8-c822-4956-9c34-4c438628006b","ELEMENT":"e04ba2d8-c822-4956
-9c34-4c438628006b"},{"element-6066-11e4-a52e-4f735466cecf":"ebcdf5bc-1852-40d9-a4a8-b1c143873017","ELEMENT":"ebcdf5bc-1852-40d9-a4a8-b1c143873017"},{"element-6066-11e4-a52e-4f735466cecf":"89524192-2ecd-4e78-a375-19
8dcf9e389c","ELEMENT":"89524192-2ecd-4e78-a375-198dcf9e389c"},{"element-6066-11e4-a52e-4f735466cecf":"2e2845ff-1f92-4e69-a4f8-c14b53c430d4","ELEMENT":"2e2845ff-1f92-4e69-a4f8-c14b53c430d4"},{"element-6066-11e4-a52e-
4f735466cecf":"122bf7af-639f-4a4d-b876-25499cd0a983","ELEMENT":"122bf7af-639f-4a4d-b876-25499cd0a983"},{"element-6066-11e4-a52e-4f735466cecf":"97099711-0c05-422c-9790-a97997ace478","ELEMENT":"97099711-0c05-422c-9790
-a97997ace478"},{"element-6066-11e4-a52e-4f735466cecf":"8dd33a0b-b0cc-46d8-8bf5-446ce5d31691","ELEMENT":"8dd33a0b-b0cc-46d8-8bf5-446ce5d31691"}]
2021-09-09 17:30:19:362 - [HTTP] <-- POST /wd/hub/session/c018fb28-bdff-4c11-b0ad-eebd60c9ee26/elements 200 454 ms - 907

Requested capabilities (On Selenium Grid with custom capability matcher)

Requesting capabilities:
    appActivity :: com.android.settings.Settings
    appPackage :: com.android.settings
    autoGrantPermissions :: true
    automationName :: UIAutomator2
    browserName :: 
    chromeOptions :: {w3c=false}
    deviceName :: *
    disableAndroidWatchers :: true
    language :: en
    locale :: US
    newCommandTimeout :: 45
    platformName :: Android
    platformVersion :: *
    resetKeyboard :: true
    tripadvisor:node.defaultDevice :: true
    tripadvisor:node.platformType :: Phone
    tripadvisor:testng.hostname :: bedouglas-mac
    tripadvisor:testng.session.hash :: 118be589-e915-4a72-b374-cac779c48c07
    tripadvisor:testng.suite :: Default Suite
    tripadvisor:testng.test :: testOverViewTabCuratedGeo
    unicodeKeyboard :: true
    unlockKey :: 1234
    unlockType :: pin

The time difference occurs between [email protected] and [email protected]

Since you are helping to test the beta, you should file a bug here: https://github.com/appium/appium/issues

Hopefully this can get fixed before release.

1 Like