A session is either terminated or not started while running multiple test cases

[daeilkang]

Hi,
I was running multiple test cases(12 test cases total) and observed first 3~4 test cases were ok.
but, after these test runs,
suddenly, I got errors below.
when running locally, no issue like this.
somehow, session was terminated suddenly.

logs from client side:
selenium.common.exceptions.InvalidSessionIdException: Message: A session is either terminated or not started
Stacktrace:
NoSuchDriverError: A session is either terminated or not started
at asyncHandler (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/@appium/base-driver/lib/protocol/protocol.js:309:15)
at /Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/@appium/base-driver/lib/protocol/protocol.js:503:15
at Layer.handle [as handle_request] (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/layer.js:95:5)
at next (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/route.js:144:13)
at Route.dispatch (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/route.js:114:3)
at Layer.handle [as handle_request] (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/layer.js:95:5)
at /Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:284:15
at param (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:365:14)
at param (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:376:14)
at Function.process_params (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:421:3)
at next (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:280:10)
at /Users/cbx/.appium/node_modules/appium-dashboard/lib/plugin/debugger.js:58:16
at Generator.next ()
at /Users/cbx/.appium/node_modules/appium-dashboard/lib/plugin/debugger.js:8:71
at new Promise ()
at __awaiter (/Users/cbx/.appium/node_modules/appium-dashboard/lib/plugin/debugger.js:4:12)
at handler (/Users/cbx/.appium/node_modules/appium-dashboard/lib/plugin/debugger.js:46:12)
at Layer.handle [as handle_request] (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/layer.js:95:5)
at trim_prefix (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:328:13)
at /Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:286:9
at Function.process_params (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:346:12)
at next (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:280:10)
at /Users/cbx/.appium/node_modules/appium-device-farm/lib/wd-command-proxy.js:69:20
at Generator.next ()
at /Users/cbx/.appium/node_modules/appium-device-farm/lib/wd-command-proxy.js:8:71
at new Promise ()
at __awaiter (/Users/cbx/.appium/node_modules/appium-device-farm/lib/wd-command-proxy.js:4:12)
at handler (/Users/cbx/.appium/node_modules/appium-device-farm/lib/wd-command-proxy.js:55:12)
at Layer.handle [as handle_request] (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/layer.js:95:5)
at trim_prefix (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:328:13)
at /Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:286:9
at Function.process_params (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:346:12)
at next (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:280:10)
at logger (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/morgan/index.js:144:5)
at Layer.handle [as handle_request] (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/layer.js:95:5)
at trim_prefix (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:328:13)
at /Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:286:9
at Function.process_params (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:346:12)
at next (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/express/lib/router/index.js:280:10)
at jsonParser (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/body-parser/lib/types/json.js:113:7)
........
../libraries/helpers.py:138: in close_ad
window_size = self.driver.get_window_size()
/Users/cbx/.pyenv/versions/3.10.7/lib/python3.10/site-packages/selenium/webdriver/remote/webdriver.py:1401: in get_window_size
size = self.get_window_rect()
/Users/cbx/.pyenv/versions/3.10.7/lib/python3.10/site-packages/selenium/webdriver/remote/webdriver.py:1451: in get_window_rect
return self.execute(Command.GET_WINDOW_RECT)['value']
/Users/cbx/.pyenv/versions/3.10.7/lib/python3.10/site-packages/selenium/webdriver/remote/webdriver.py:430: in execute
self.error_handler.check_response(response)
/Users/cbx/.pyenv/versions/3.10.7/lib/python3.10/site-packages/appium/webdriver/errorhandler.py:30: in check_response
raise wde
/Users/cbx/.pyenv/versions/3.10.7/lib/python3.10/site-packages/appium/webdriver/errorhandler.py:26: in check_response
super().check_response(response)

---

self = <appium.webdriver.errorhandler.MobileErrorHandler object at 0x10a467f10>
response = {'status': 404, 'value': '{"value":{"error":"invalid session id","message":"A session is either terminated or not star...r (/Users/cbx/.nvm/versions/node/v19.8.1/lib/node_modules/appium/node_modules/body-parser/lib/types/json.js:113:7)"}}'}

appium logs:
device-farm] Received click request on driver - [object Object]
[AppiumDriver@8287] Executing default handling behavior for command 'click'
[debug] [AndroidUiautomator2Driver@182c (a832eeab)] Matched '/element/00000000-0000-3601-ffff-ffff000000d9/click' to command name 'click'
[debug] [AndroidUiautomator2Driver@182c (a832eeab)] Proxying [POST /element/00000000-0000-3601-ffff-ffff000000d9/click] to [POST http://127.0.0.1:63466/session/dbe37f7a-e635-4c83-8ee6-5f9e1cfb1780/element/00000000-0000-3601-ffff-ffff000000d9/click] with body: {"element":"00000000-0000-3601-ffff-ffff000000d9"}
[debug] [AndroidUiautomator2Driver@182c (a832eeab)] Got response with status 200: {"sessionId":"dbe37f7a-e635-4c83-8ee6-5f9e1cfb1780","value":null}
[debug] [AndroidUiautomator2Driver@182c (a832eeab)] Responding to client with driver.click() result: null
[HTTP] <-- POST /wd/hub/session/a832eeab-b4e4-4e9b-99e4-af2cc92ad395/element/00000000-0000-3601-ffff-ffff000000d9/click 200 62 ms - 14
[HTTP]
[HTTP] --> POST /wd/hub/wd-internal/session/a832eeab-b4e4-4e9b-99e4-af2cc92ad395/appium/stop_recording_screen
[HTTP] {}
[debug] [HTTP] No route found for /wd/hub/wd-internal/session/a832eeab-b4e4-4e9b-99e4-af2cc92ad395/appium/stop_recording_screen
[HTTP] <-- POST /wd/hub/wd-internal/session/a832eeab-b4e4-4e9b-99e4-af2cc92ad395/appium/stop_recording_screen 404 4 ms - 262
[HTTP]
[HTTP] --> DELETE /wd/hub/wd-internal/session/a832eeab-b4e4-4e9b-99e4-af2cc92ad395
[HTTP] {}
[debug] [HTTP] No route found for /wd/hub/wd-internal/session/a832eeab-b4e4-4e9b-99e4-af2cc92ad395
[HTTP] <-- DELETE /wd/hub/wd-internal/session/a832eeab-b4e4-4e9b-99e4-af2cc92ad395 404 1 ms - 262
[HTTP]
[HTTP] --> POST /wd/hub/session
[HTTP] {"capabilities":{"firstMatch":[{"appium:udid":"19231FDF600420","appium:systemPort":63976,"appium:chromeDriverPort":63977,"appium:adbRemoteHost":null,"appium:adbPort":5037}],"alwaysMatch":{"platformName":"Android","appium:automationName":"uiautomator2","appium:autoGrantPermissions":"true","appium:newCommandTimeout":300,"appium:app":"/Users/cbx/workspace/automation/test.apk","appium:deviceType":"real","appium:clearDeviceLogsOnStart":true,"appium:nativeWebScreenshot":true,"appium:mjpegServerPort":63975}}}
[debug] [AppiumDriver@8287] Calling AppiumDriver.createSession() with args: [null,null,{"firstMatch":[{"appium:udid":"19231FDF600420","appium:systemPort":63976,"appium:chromeDriverPort":63977,"appium:adbRemoteHost":null,"appium:adbPort":5037}],"alwaysMatch":{"platformName":"Android","appium:automationName":"uiautomator2","appium:autoGrantPermissions":"true","appium:newCommandTimeout":300,"appium:app":"/Users/cbx/workspace/automation/test.apk","appium:deviceType":"real","appium:clearDeviceLogsOnStart":true,"appium:nativeWebScreenshot":true,"appium:mjpegServerPort":63975}}]
[AppiumDriver@8287] Plugins which can handle cmd 'createSession': device-farm
[AppiumDriver@8287] Plugin device-farm is now handling cmd 'createSession'
{
'appium:udid': '19231FDF600420',
'appium:systemPort': 63976,
'appium:chromeDriverPort': 63977,
'appium:adbRemoteHost': null,
'appium:adbPort': 5037,
platformName: 'Android',
'appium:automationName': 'uiautomator2',
'appium:autoGrantPermissions': 'true',
'appium:newCommandTimeout': 300,
'appium:app': '/Users/cbx/workspace/automation/test.apk',
'appium:deviceType': 'real',
'appium:clearDeviceLogsOnStart': true,
'appium:nativeWebScreenshot': true,
'appium:mjpegServerPort': 63975
}
[device-farm] {"platform":"android","name":"","busy":false,"userBlocked":false}
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[device-farm] Waiting for free device
[AppiumDriver@8287] Command 'createSession' was not handled by the following behaviours or plugins, even though they were registered to handle it: ["default"]. The command was handled by these: ["device-farm"].
[debug] [AppiumDriver@8287] Encountered internal error running command: Error: No device found for filters: {"platform":"android","name":"","busy":false,"userBlocked":false}
[debug] [AppiumDriver@8287] at /Users/cbx/.appium/node_modules/appium-device-farm/lib/device-utils.js:112:19
[debug] [AppiumDriver@8287] at Generator.throw ()
[debug] [AppiumDriver@8287] at rejected (/Users/cbx/.appium/node_modules/appium-device-farm/lib/device-utils.js:6:65)
[HTTP] <-- POST /wd/hub/session 500 180029 ms - 768
[HTTP]

[saikrishna321]

Please provide full server logs.

Does this happen only when the plugin is active?

[daeilkang]

attached appium logs.
Yes. I don't see the issue when I tested in local.
appium_logs.txt