Flutter commands doesn't work if perform closeApp() or resetApp() and try to launchApp() on same session.

[ahashmi24]

• Appium version: 1.16.0
• Device: iOS Simulator
• App: Flutter app included plugin (enableFlutterDriverExtension())

Appium Logs:

Last login: Tue Jan 21 14:05:20 on ttys000
Ahmers-MacBook-Pro:~ vikasvipul$ appium
[Appium] Welcome to Appium v1.16.0
[Appium] Appium REST http interface listener started on 0.0.0.0:4723
[HTTP] --> POST /wd/hub/session
[HTTP] {"desiredCapabilities":{"app":"/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app","includeSafariInWebviews":true,"orientation":"PORTRAIT","noReset":true,"platformVersion":"13.1","automationName":"Flutter","bundleId":"com.example.datePicket","platformName":"iOS","udid":"A59562F8-1EF1-435E-8C5E-4B829ABA6F37","deviceName":"iPhone 11 Pro Max","wdaLocalPort":"8101"},"capabilities":{"alwaysMatch":{"appium:app":"/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app","appium:automationName":"Flutter","appium:bundleId":"com.example.datePicket","appium:deviceName":"iPhone 11 Pro Max","includeSafariInWebviews":true,"appium:noReset":true,"appium:orientation":"PORTRAIT","platformName":"ios","appium:platformVersion":"13.1","appium:udid":"A59562F8-1EF1-435E-8C5E-4B829ABA6F37","appium:wdaLocalPort":"8101"},"firstMatch":[{}]}}
[debug] [W3C] Calling AppiumDriver.createSession() with args: [{"app":"/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app","includeSafariInWebviews":true,"orientation":"PORTRAIT","noReset":true,"platformVersion":"13.1","automationName":"Flutter","bundleId":"com.example.datePicket","platformName":"iOS","udid":"A59562F8-1EF1-435E-8C5E-4B829ABA6F37","deviceName":"iPhone 11 Pro Max","wdaLocalPort":"8101"},null,{"alwaysMatch":{"appium:app":"/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app","appium:automationName":"Flutter","appium:bundleId":"com.example.datePicket","appium:deviceName":"iPhone 11 Pro Max","includeSafariInWebviews":true,"appium:noReset":true,"appium:orientation":"PORTRAIT","platformName":"ios","appium:platformVersion":"13.1","appium:udid":"A59562F8-1EF1-435E-8C5E-4B829ABA6F37","appium:wdaLocalPort":"8101"},"firstMatch":[{}]}]
[debug] [BaseDriver] Event 'newSessionRequested' logged at 1579596067297 (14:11:07 GMT+0530 (India Standard Time))
[BaseDriver] The capabilities ["includeSafariInWebviews"] are not standard capabilities and should have an extension prefix
[Appium] Appium v1.16.0 creating new FlutterDriver (v0.0.20) session
[debug] [BaseDriver] Creating session with MJSONWP desired capabilities: {
[debug] [BaseDriver]   "app": "/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app",
[debug] [BaseDriver]   "includeSafariInWebviews": true,
[debug] [BaseDriver]   "orientation": "PORTRAIT",
[debug] [BaseDriver]   "noReset": true,
[debug] [BaseDriver]   "platformVersion": "13.1",
[debug] [BaseDriver]   "automationName": "Flutter",
[debug] [BaseDriver]   "bundleId": "com.example.datePicket",
[debug] [BaseDriver]   "platformName": "iOS",
[debug] [BaseDriver]   "udid": "A59562F8-1EF1-435E-8C5E-4B829ABA6F37",
[debug] [BaseDriver]   "deviceName": "iPhone 11 Pro Max",
[debug] [BaseDriver]   "wdaLocalPort": "8101"
[debug] [BaseDriver] }
[BaseDriver] The following capabilities were provided, but are not recognized by Appium:
[BaseDriver]   includeSafariInWebviews
[BaseDriver]   bundleId
[BaseDriver]   wdaLocalPort
[BaseDriver] Session created with session id: ce478cdf-1e4d-4a78-b5c6-8733102b47a8
[FlutterDriver] Starting an IOS proxy session
[debug] [BaseDriver] Creating session with MJSONWP desired capabilities: {
[debug] [BaseDriver]   "app": "/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app",
[debug] [BaseDriver]   "includeSafariInWebviews": true,
[debug] [BaseDriver]   "orientation": "PORTRAIT",
[debug] [BaseDriver]   "noReset": true,
[debug] [BaseDriver]   "platformVersion": "13.1",
[debug] [BaseDriver]   "automationName": "Flutter",
[debug] [BaseDriver]   "bundleId": "com.example.datePicket",
[debug] [BaseDriver]   "platformName": "iOS",
[debug] [BaseDriver]   "udid": "A59562F8-1EF1-435E-8C5E-4B829ABA6F37",
[debug] [BaseDriver]   "deviceName": "iPhone 11 Pro Max",
[debug] [BaseDriver]   "wdaLocalPort": "8101",
[debug] [BaseDriver]   "newCommandTimeout": 0
[debug] [BaseDriver] }
[BaseDriver] Capability 'wdaLocalPort' changed from string ('8101') to integer (8101). This may cause unexpected behavior
[BaseDriver] Session created with session id: 0495b586-3d05-40fa-bb37-3be715fa6e46
[debug] [XCUITest] Current user: 'vikasvipul'
[debug] [XCUITest] Available devices: 
[iOSSim] Constructing iOS simulator for Xcode version 11.1 with udid 'A59562F8-1EF1-435E-8C5E-4B829ABA6F37'
[XCUITest] Determining device to run tests on: udid: 'A59562F8-1EF1-435E-8C5E-4B829ABA6F37', real device: false
[debug] [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1579596068293 (14:11:08 GMT+0530 (India Standard Time))
[BaseDriver] Using local app '/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app'
[debug] [BaseDriver] Event 'appConfigured' logged at 1579596068296 (14:11:08 GMT+0530 (India Standard Time))
[debug] [XCUITest] Checking whether app '/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app' is actually present on file system
[debug] [XCUITest] App is present
[debug] [BaseDriver] Event 'resetStarted' logged at 1579596068296 (14:11:08 GMT+0530 (India Standard Time))
[debug] [XCUITest] Reset: noReset is on. Leaving simulator as is
[debug] [BaseDriver] Event 'resetComplete' logged at 1579596068297 (14:11:08 GMT+0530 (India Standard Time))
[debug] [IOSSimulatorLog] Starting log capture for iOS Simulator with udid 'A59562F8-1EF1-435E-8C5E-4B829ABA6F37', using 'xcrun simctl spawn A59562F8-1EF1-435E-8C5E-4B829ABA6F37 log stream --style compact'
[debug] [BaseDriver] Event 'logCaptureStarted' logged at 1579596068558 (14:11:08 GMT+0530 (India Standard Time))
[XCUITest] Setting up simulator
[debug] [iOS] No reason to set locale
[debug] [iOS] No iOS / app preferences to set
[debug] [iOS] Setting did not need to be updated
[debug] [iOSSim] Setting preferences of A59562F8-1EF1-435E-8C5E-4B829ABA6F37 Simulator to {"SimulatorWindowOrientation":"Portrait","SimulatorWindowRotationAngle":0,"ConnectHardwareKeyboard":false}
[debug] [iOSSim] Setting common Simulator preferences to {"RotateWindowWhenSignaledByGuest":true,"ConnectHardwareKeyboard":false}
[debug] [iOSSim] Updated A59562F8-1EF1-435E-8C5E-4B829ABA6F37 Simulator preferences at '/Users/vikasvipul/Library/Preferences/com.apple.iphonesimulator.plist' with {"DevicePreferences":{"A59562F8-1EF1-435E-8C5E-4B829ABA6F37":{"SimulatorWindowOrientation":"Portrait","SimulatorWindowRotationAngle":0,"ConnectHardwareKeyboard":false}},"RotateWindowWhenSignaledByGuest":true,"ConnectHardwareKeyboard":false}
[debug] [iOSSim] Got Simulator UI client PID: 27170
[iOSSim] Both Simulator with UDID A59562F8-1EF1-435E-8C5E-4B829ABA6F37 and the UI client are currently running
[debug] [IDB] Connecting idb service to 'A59562F8-1EF1-435E-8C5E-4B829ABA6F37'
[XCUITest] idb will not be used for Simulator interaction. Original error: 'idb' has not been found in PATH. Is it installed? Read https://www.fbidb.io for more details
[debug] [BaseDriver] Event 'simStarted' logged at 1579596068715 (14:11:08 GMT+0530 (India Standard Time))
[debug] [XCUITest] Verifying application platform
[debug] [XCUITest] CFBundleSupportedPlatforms: ["iPhoneSimulator"]
[debug] [XCUITest] App 'com.example.datePicket' is already installed. No need to reinstall.
[debug] [BaseDriver] Event 'appInstalled' logged at 1579596068817 (14:11:08 GMT+0530 (India Standard Time))
[XCUITest] Using WDA path: '/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent'
[XCUITest] Using WDA agent: '/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj'
[debug] [XCUITest] No obsolete cached processes from previous WDA sessions listening on port 8101 have been found
[DevCon Factory] Requesting connection for device A59562F8-1EF1-435E-8C5E-4B829ABA6F37 on local port 8101, device port 8101
[debug] [DevCon Factory] Cached connections count: 0
[DevCon Factory] Successfully requested the connection for A59562F8-1EF1-435E-8C5E-4B829ABA6F37:8101
[debug] [XCUITest] Starting WebDriverAgent initialization with the synchronization key 'XCUITestDriver'
[debug] [WD Proxy] Matched '/status' to command name 'getStatus'
[debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:8101/status] with no body
[debug] [WD Proxy] Got response with status 200: {
[debug] [WD Proxy]   "value" : {
[debug] [WD Proxy]     "message" : "WebDriverAgent is ready to accept commands",
[debug] [WD Proxy]     "state" : "success",
[debug] [WD Proxy]     "os" : {
[debug] [WD Proxy]       "name" : "iOS",
[debug] [WD Proxy]       "version" : "13.1",
[debug] [WD Proxy]       "sdkVersion" : "13.0"
[debug] [WD Proxy]     },
[debug] [WD Proxy]     "ios" : {
[debug] [WD Proxy]       "simulatorVersion" : "13.1",
[debug] [WD Proxy]       "ip" : "172.31.7.152"
[debug] [WD Proxy]     },
[debug] [WD Proxy]     "ready" : true,
[debug] [WD Proxy]     "build" : {
[debug] [WD Proxy]       "upgradedAt" : "1577356260834",
[debug] [WD Proxy]       "time" : "Jan 18 2020 14:09:48",
[debug] [WD Proxy]       "productBundleIdentifier" : "com.facebook.WebDriverAgentRunner"
[debug] [WD Proxy]     }
[debug] [WD Proxy]   },
[debug] [WD Proxy]   "sessionId" : "C1EE82BC-8992-4EA3-9C06-12F3AE47499E"
[debug] [WD Proxy] }
[debug] [XCUITest] Upgrade timestamp of the currently bundled WDA: 1577356260834
[debug] [XCUITest] Upgrade timestamp of the WDA on the device: 1577356260834
[XCUITest] Will reuse previously cached WDA instance at 'http://localhost:8101/' with 'com.facebook.WebDriverAgentRunner'. Set the wdaLocalPort capability to a value different from 8101 if this is an undesired behavior.
[debug] [XCUITest] Trying to start WebDriverAgent 2 times with 10000ms interval
[debug] [XCUITest] These values can be customized by changing wdaStartupRetries/wdaStartupRetryInterval capabilities
[debug] [BaseDriver] Event 'wdaStartAttempted' logged at 1579596068906 (14:11:08 GMT+0530 (India Standard Time))
[XCUITest] Using provided WebdriverAgent at 'http://localhost:8101/'
[debug] [WD Proxy] Matched '/status' to command name 'getStatus'
[debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:8101/status] with no body
[debug] [WD Proxy] Got response with status 200: {
[debug] [WD Proxy]   "value" : {
[debug] [WD Proxy]     "message" : "WebDriverAgent is ready to accept commands",
[debug] [WD Proxy]     "state" : "success",
[debug] [WD Proxy]     "os" : {
[debug] [WD Proxy]       "name" : "iOS",
[debug] [WD Proxy]       "version" : "13.1",
[debug] [WD Proxy]       "sdkVersion" : "13.0"
[debug] [WD Proxy]     },
[debug] [WD Proxy]     "ios" : {
[debug] [WD Proxy]       "simulatorVersion" : "13.1",
[debug] [WD Proxy]       "ip" : "172.31.7.152"
[debug] [WD Proxy]     },
[debug] [WD Proxy]     "ready" : true,
[debug] [WD Proxy]     "build" : {
[debug] [WD Proxy]       "upgradedAt" : "1577356260834",
[debug] [WD Proxy]       "time" : "Jan 18 2020 14:09:48",
[debug] [WD Proxy]       "productBundleIdentifier" : "com.facebook.WebDriverAgentRunner"
[debug] [WD Proxy]     }
[debug] [WD Proxy]   },
[debug] [WD Proxy]   "sessionId" : "C1EE82BC-8992-4EA3-9C06-12F3AE47499E"
[debug] [WD Proxy] }
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1579596068911 (14:11:08 GMT+0530 (India Standard Time))
[debug] [XCUITest] Sending createSession command to WDA
[debug] [WD Proxy] Matched '/session' to command name 'createSession'
[debug] [WD Proxy] Proxying [POST /session] to [POST http://localhost:8101/session] with body: {"capabilities":{"firstMatch":[{"bundleId":"com.example.datePicket","arguments":[],"environment":{},"eventloopIdleDelaySec":0,"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}],"alwaysMatch":{}}}
[debug] [WD Proxy] Got response with status 200: {"value":{"sessionId":"F1B09D96-0D5D-411D-930F-C1DD260658C3","capabilities":{"device":"iphone","browserName":"date_picket","sdkVersion":"13.1","CFBundleIdentifier":"com.example.datePicket"}},"sessionId":"F1B09D96-0D5D-411D-930F-C1DD260658C3"}
[WD Proxy] Determined the downstream protocol as 'W3C'
[debug] [BaseDriver] Event 'wdaSessionStarted' logged at 1579596071005 (14:11:11 GMT+0530 (India Standard Time))
[debug] [BaseDriver] Event 'wdaStarted' logged at 1579596071005 (14:11:11 GMT+0530 (India Standard Time))
[debug] [XCUITest] Setting initial orientation to 'PORTRAIT'
[debug] [WD Proxy] Matched '/orientation' to command name 'setOrientation'
[debug] [WD Proxy] Proxying [POST /orientation] to [POST http://localhost:8101/session/F1B09D96-0D5D-411D-930F-C1DD260658C3/orientation] with body: {"orientation":"PORTRAIT"}
[debug] [WD Proxy] Got response with status 200: {"value":null,"sessionId":"F1B09D96-0D5D-411D-930F-C1DD260658C3"}
[debug] [BaseDriver] Event 'orientationSet' logged at 1579596072529 (14:11:12 GMT+0530 (India Standard Time))
[FlutterDriver] Attempt #1
[FlutterDriver] Connecting to Dart Observatory: ws://127.0.0.1:64816/Cc2vt6adD0k=/ws
[FlutterDriver] Connected to ws://127.0.0.1:64816/Cc2vt6adD0k=/ws
[Appium] New FlutterDriver session created successfully, session ce478cdf-1e4d-4a78-b5c6-8733102b47a8 added to master session list
[debug] [BaseDriver] Event 'newSessionStarted' logged at 1579596072668 (14:11:12 GMT+0530 (India Standard Time))
[debug] [MJSONWP (ce478cdf)] Cached the protocol value 'MJSONWP' for the new session ce478cdf-1e4d-4a78-b5c6-8733102b47a8
[debug] [MJSONWP (ce478cdf)] Responding to client with driver.createSession() result: {"shell":null,"allowCors":false,"reboot":false,"ipa":null,"address":"0.0.0.0","port":4723,"basePath":"/wd/hub","callbackAddress":null,"callbackPort":null,"bootstrapPort":4724,"backendRetries":3,"sessionOverride":false,"launch":false,"logFile":null,"loglevel":"debug","logTimestamp":false,"localTimezone":false,"logNoColors":false,"webhook":null,"safari":false,"defaultDevice":false,"forceIphone":false,"forceIpad":false,"automationTraceTemplatePath":null,"instrumentsPath":null,"nodeconfig":null,"robotAddress":"0.0.0.0","robotPort":-1,"chromeDriverPort":null,"chromedriverExecutable":null,"showConfig":false,"noPermsCheck":false,"enforceStrictCaps":false,"isolateSimDevice":false,"tmpDir":"/var/folders/w6/qbbv2xsj11ldc85wdsxdbfvr0000gn/T","traceDir":null,"debugLogSpacing":false,"suppressKillServer":false,"longStacktrace":false,"webkitDebugProxyPort":27753,"wdaLocalPort":"8101","defaultCapabilities":{},"relaxedSecurityEnabled":false,"allowInsecure":[],"denyInsecure":[],"defaultCommandTimeout":60,"keepArtifacts":fal...
[HTTP] <-- POST /wd/hub/session 200 5375 ms - 2223
[HTTP] 
[HTTP] --> GET /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8
[HTTP] {}
[debug] [MJSONWP (ce478cdf)] Calling AppiumDriver.getSession() with args: ["ce478cdf-1e4d-4a78-b5c6-8733102b47a8"]
[debug] [FlutterDriver] Executing Flutter driver command 'getSession'
[debug] [MJSONWP (ce478cdf)] Responding to client with driver.getSession() result: {"app":"/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app","includeSafariInWebviews":true,"orientation":"PORTRAIT","noReset":true,"platformVersion":"13.1","automationName":"Flutter","bundleId":"com.example.datePicket","platformName":"iOS","udid":"A59562F8-1EF1-435E-8C5E-4B829ABA6F37","deviceName":"iPhone 11 Pro Max","wdaLocalPort":"8101"}
[HTTP] <-- GET /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8 200 2 ms - 468
[HTTP] 
[HTTP] --> GET /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8
[HTTP] {}
[debug] [MJSONWP (ce478cdf)] Calling AppiumDriver.getSession() with args: ["ce478cdf-1e4d-4a78-b5c6-8733102b47a8"]
[debug] [FlutterDriver] Executing Flutter driver command 'getSession'
[debug] [MJSONWP (ce478cdf)] Responding to client with driver.getSession() result: {"app":"/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app","includeSafariInWebviews":true,"orientation":"PORTRAIT","noReset":true,"platformVersion":"13.1","automationName":"Flutter","bundleId":"com.example.datePicket","platformName":"iOS","udid":"A59562F8-1EF1-435E-8C5E-4B829ABA6F37","deviceName":"iPhone 11 Pro Max","wdaLocalPort":"8101"}
[HTTP] <-- GET /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8 200 1 ms - 468
[HTTP] 
[HTTP] --> POST /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8/context
[HTTP] {"name":"NATIVE_APP"}
[debug] [MJSONWP (ce478cdf)] Calling AppiumDriver.setContext() with args: ["NATIVE_APP","ce478cdf-1e4d-4a78-b5c6-8733102b47a8"]
[debug] [FlutterDriver] Executing Flutter driver command 'setContext'
[debug] [MJSONWP (ce478cdf)] Responding to client with driver.setContext() result: "NATIVE_APP"
[HTTP] <-- POST /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8/context 200 4 ms - 84
[HTTP] 
[HTTP] --> POST /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8/appium/app/close
[HTTP] {}
[debug] [MJSONWP (ce478cdf)] Calling AppiumDriver.closeApp() with args: ["ce478cdf-1e4d-4a78-b5c6-8733102b47a8"]
[debug] [FlutterDriver] Executing proxied driver command 'closeApp'
[debug] [XCUITest] Executing command 'closeApp'
[debug] [WD Proxy] Matched '/session/0495b586-3d05-40fa-bb37-3be715fa6e46' to command name 'deleteSession'
[debug] [WD Proxy] Proxying [DELETE /session/0495b586-3d05-40fa-bb37-3be715fa6e46] to [DELETE http://localhost:8101/session/F1B09D96-0D5D-411D-930F-C1DD260658C3] with no body
[FlutterDriver] Connection to ws://127.0.0.1:64816/Cc2vt6adD0k=/ws closed
[debug] [WD Proxy] Got response with status 200: {
[debug] [WD Proxy]   "value" : null,
[debug] [WD Proxy]   "sessionId" : "7672E9AB-3A47-4888-B46A-8A1E112ED5E3"
[debug] [WD Proxy] }
[DevCon Factory] Releasing connections for A59562F8-1EF1-435E-8C5E-4B829ABA6F37 device on any port number
[DevCon Factory] Found cached connections to release: ["A59562F8-1EF1-435E-8C5E-4B829ABA6F37:8101"]
[debug] [DevCon Factory] Cached connections count: 0
[iOS] Successfully closed the '/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app' app.
[debug] [MJSONWP (ce478cdf)] Responding to client with driver.closeApp() result: null
[HTTP] <-- POST /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8/appium/app/close 200 1049 ms - 76
[HTTP] 
[HTTP] --> POST /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8/appium/app/launch
[HTTP] {}
[debug] [MJSONWP (ce478cdf)] Calling AppiumDriver.launchApp() with args: ["ce478cdf-1e4d-4a78-b5c6-8733102b47a8"]
[debug] [FlutterDriver] Executing proxied driver command 'launchApp'
[debug] [XCUITest] Executing command 'launchApp'
[debug] [XCUITest] Current user: 'vikasvipul'
[debug] [XCUITest] Available devices: 
[iOSSim] Constructing iOS simulator for Xcode version 11.1 with udid 'A59562F8-1EF1-435E-8C5E-4B829ABA6F37'
[XCUITest] Determining device to run tests on: udid: 'A59562F8-1EF1-435E-8C5E-4B829ABA6F37', real device: false
[debug] [BaseDriver] Event 'xcodeDetailsRetrieved' logged at 1579596074015 (14:11:14 GMT+0530 (India Standard Time))
[BaseDriver] Using local app '/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app'
[debug] [BaseDriver] Event 'appConfigured' logged at 1579596074016 (14:11:14 GMT+0530 (India Standard Time))
[debug] [XCUITest] Checking whether app '/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app' is actually present on file system
[debug] [XCUITest] App is present
[debug] [BaseDriver] Event 'resetStarted' logged at 1579596074017 (14:11:14 GMT+0530 (India Standard Time))
[debug] [XCUITest] Reset: noReset is on. Leaving simulator as is
[debug] [BaseDriver] Event 'resetComplete' logged at 1579596074017 (14:11:14 GMT+0530 (India Standard Time))
[XCUITest] Trying to start iOS log capture but it has already started!
[debug] [BaseDriver] Event 'logCaptureStarted' logged at 1579596074017 (14:11:14 GMT+0530 (India Standard Time))
[XCUITest] Setting up simulator
[debug] [iOS] No reason to set locale
[debug] [iOS] No iOS / app preferences to set
[debug] [iOS] Setting did not need to be updated
[debug] [iOSSim] Setting preferences of A59562F8-1EF1-435E-8C5E-4B829ABA6F37 Simulator to {"SimulatorWindowOrientation":"Portrait","SimulatorWindowRotationAngle":0,"ConnectHardwareKeyboard":false}
[debug] [iOSSim] Setting common Simulator preferences to {"RotateWindowWhenSignaledByGuest":true,"ConnectHardwareKeyboard":false}
[debug] [iOSSim] Updated A59562F8-1EF1-435E-8C5E-4B829ABA6F37 Simulator preferences at '/Users/vikasvipul/Library/Preferences/com.apple.iphonesimulator.plist' with {"DevicePreferences":{"A59562F8-1EF1-435E-8C5E-4B829ABA6F37":{"SimulatorWindowOrientation":"Portrait","SimulatorWindowRotationAngle":0,"ConnectHardwareKeyboard":false}},"RotateWindowWhenSignaledByGuest":true,"ConnectHardwareKeyboard":false}
[debug] [iOSSim] Got Simulator UI client PID: 27170
[iOSSim] Both Simulator with UDID A59562F8-1EF1-435E-8C5E-4B829ABA6F37 and the UI client are currently running
[debug] [IDB] Connecting idb service to 'A59562F8-1EF1-435E-8C5E-4B829ABA6F37'
[XCUITest] idb will not be used for Simulator interaction. Original error: 'idb' has not been found in PATH. Is it installed? Read https://www.fbidb.io for more details
[debug] [BaseDriver] Event 'simStarted' logged at 1579596074163 (14:11:14 GMT+0530 (India Standard Time))
[debug] [XCUITest] Verifying application platform
[debug] [XCUITest] CFBundleSupportedPlatforms: ["iPhoneSimulator"]
[debug] [XCUITest] App 'com.example.datePicket' is already installed. No need to reinstall.
[debug] [BaseDriver] Event 'appInstalled' logged at 1579596074256 (14:11:14 GMT+0530 (India Standard Time))
[XCUITest] Using WDA path: '/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent'
[XCUITest] Using WDA agent: '/usr/local/lib/node_modules/appium/node_modules/appium-webdriveragent/WebDriverAgent.xcodeproj'
[debug] [XCUITest] No obsolete cached processes from previous WDA sessions listening on port 8101 have been found
[DevCon Factory] Requesting connection for device A59562F8-1EF1-435E-8C5E-4B829ABA6F37 on local port 8101, device port 8101
[debug] [DevCon Factory] Cached connections count: 0
[DevCon Factory] Successfully requested the connection for A59562F8-1EF1-435E-8C5E-4B829ABA6F37:8101
[debug] [XCUITest] Starting WebDriverAgent initialization with the synchronization key 'XCUITestDriver'
[debug] [WD Proxy] Matched '/status' to command name 'getStatus'
[debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:8101/status] with no body
[debug] [WD Proxy] Got response with status 200: {
[debug] [WD Proxy]   "value" : {
[debug] [WD Proxy]     "message" : "WebDriverAgent is ready to accept commands",
[debug] [WD Proxy]     "state" : "success",
[debug] [WD Proxy]     "os" : {
[debug] [WD Proxy]       "name" : "iOS",
[debug] [WD Proxy]       "version" : "13.1",
[debug] [WD Proxy]       "sdkVersion" : "13.0"
[debug] [WD Proxy]     },
[debug] [WD Proxy]     "ios" : {
[debug] [WD Proxy]       "simulatorVersion" : "13.1",
[debug] [WD Proxy]       "ip" : "172.31.7.152"
[debug] [WD Proxy]     },
[debug] [WD Proxy]     "ready" : true,
[debug] [WD Proxy]     "build" : {
[debug] [WD Proxy]       "upgradedAt" : "1577356260834",
[debug] [WD Proxy]       "time" : "Jan 18 2020 14:09:48",
[debug] [WD Proxy]       "productBundleIdentifier" : "com.facebook.WebDriverAgentRunner"
[debug] [WD Proxy]     }
[debug] [WD Proxy]   },
[debug] [WD Proxy]   "sessionId" : "7672E9AB-3A47-4888-B46A-8A1E112ED5E3"
[debug] [WD Proxy] }
[debug] [XCUITest] Upgrade timestamp of the currently bundled WDA: 1577356260834
[debug] [XCUITest] Upgrade timestamp of the WDA on the device: 1577356260834
[XCUITest] Will reuse previously cached WDA instance at 'http://localhost:8101/' with 'com.facebook.WebDriverAgentRunner'. Set the wdaLocalPort capability to a value different from 8101 if this is an undesired behavior.
[debug] [XCUITest] Trying to start WebDriverAgent 2 times with 10000ms interval
[debug] [XCUITest] These values can be customized by changing wdaStartupRetries/wdaStartupRetryInterval capabilities
[debug] [BaseDriver] Event 'wdaStartAttempted' logged at 1579596074332 (14:11:14 GMT+0530 (India Standard Time))
[XCUITest] Using provided WebdriverAgent at 'http://localhost:8101/'
[debug] [WD Proxy] Matched '/status' to command name 'getStatus'
[debug] [WD Proxy] Proxying [GET /status] to [GET http://localhost:8101/status] with no body
[debug] [WD Proxy] Got response with status 200: {
[debug] [WD Proxy]   "value" : {
[debug] [WD Proxy]     "message" : "WebDriverAgent is ready to accept commands",
[debug] [WD Proxy]     "state" : "success",
[debug] [WD Proxy]     "os" : {
[debug] [WD Proxy]       "name" : "iOS",
[debug] [WD Proxy]       "version" : "13.1",
[debug] [WD Proxy]       "sdkVersion" : "13.0"
[debug] [WD Proxy]     },
[debug] [WD Proxy]     "ios" : {
[debug] [WD Proxy]       "simulatorVersion" : "13.1",
[debug] [WD Proxy]       "ip" : "172.31.7.152"
[debug] [WD Proxy]     },
[debug] [WD Proxy]     "ready" : true,
[debug] [WD Proxy]     "build" : {
[debug] [WD Proxy]       "upgradedAt" : "1577356260834",
[debug] [WD Proxy]       "time" : "Jan 18 2020 14:09:48",
[debug] [WD Proxy]       "productBundleIdentifier" : "com.facebook.WebDriverAgentRunner"
[debug] [WD Proxy]     }
[debug] [WD Proxy]   },
[debug] [WD Proxy]   "sessionId" : "7672E9AB-3A47-4888-B46A-8A1E112ED5E3"
[debug] [WD Proxy] }
[debug] [BaseDriver] Event 'wdaSessionAttempted' logged at 1579596074336 (14:11:14 GMT+0530 (India Standard Time))
[debug] [XCUITest] Sending createSession command to WDA
[debug] [WD Proxy] Matched '/session' to command name 'createSession'
[debug] [WD Proxy] Proxying [POST /session] to [POST http://localhost:8101/session] with body: {"capabilities":{"firstMatch":[{"bundleId":"com.example.datePicket","arguments":[],"environment":{},"eventloopIdleDelaySec":0,"shouldWaitForQuiescence":true,"shouldUseTestManagerForVisibilityDetection":false,"maxTypingFrequency":60,"shouldUseSingletonTestManager":true}],"alwaysMatch":{}}}
[debug] [WD Proxy] Got response with status 200: {"value":{"sessionId":"DF558BD9-062E-47B0-B35C-4408EB5AFF43","capabilities":{"device":"iphone","browserName":"date_picket","sdkVersion":"13.1","CFBundleIdentifier":"com.example.datePicket"}},"sessionId":"DF558BD9-062E-47B0-B35C-4408EB5AFF43"}
[WD Proxy] Determined the downstream protocol as 'W3C'
[debug] [BaseDriver] Event 'wdaSessionStarted' logged at 1579596076445 (14:11:16 GMT+0530 (India Standard Time))
[debug] [BaseDriver] Event 'wdaStarted' logged at 1579596076445 (14:11:16 GMT+0530 (India Standard Time))
[debug] [XCUITest] Setting initial orientation to 'PORTRAIT'
[debug] [WD Proxy] Matched '/orientation' to command name 'setOrientation'
[debug] [WD Proxy] Proxying [POST /orientation] to [POST http://localhost:8101/session/DF558BD9-062E-47B0-B35C-4408EB5AFF43/orientation] with body: {"orientation":"PORTRAIT"}
[debug] [WD Proxy] Got response with status 200: {"value":null,"sessionId":"DF558BD9-062E-47B0-B35C-4408EB5AFF43"}
[debug] [BaseDriver] Event 'orientationSet' logged at 1579596077972 (14:11:17 GMT+0530 (India Standard Time))
[iOS] Successfully launched the '/Users/vikasvipul/Documents/work_flutter_poc/automationFlutterApp/flutter.automation.test/apps/Runner.app' app.
[debug] [MJSONWP (ce478cdf)] Responding to client with driver.launchApp() result: null
[HTTP] <-- POST /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8/appium/app/launch 200 4191 ms - 76
[HTTP] 
[HTTP] --> GET /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8/contexts
[HTTP] {}
[debug] [MJSONWP (ce478cdf)] Calling AppiumDriver.getContexts() with args: ["ce478cdf-1e4d-4a78-b5c6-8733102b47a8"]
[debug] [FlutterDriver] Executing Flutter driver command 'getContexts'
[debug] [iOS] Getting list of available contexts
[debug] [iOS] Retrieving contexts and views
[debug] [XCUITest] Selecting by url: false 
[RemoteDebugger] Remote Debugger version 5.7.0
[debug] [RemoteDebugger] useNewSafari --> false
[debug] [RemoteDebugger] Checking which communication style to use (non-Safari on platform version '13.1')
[debug] [RemoteDebugger] Platform version equal or higher than '12.2': true
[RemoteDebugger] Setting communication protocol: using full Web Inspector protocol communication
[debug] [RemoteDebugger] Connecting to remote debugger through unix domain socket: '/private/tmp/com.apple.launchd.dfwnNolFqa/com.apple.webinspectord_sim.socket'
[debug] [RemoteDebugger] Debugger socket connected
[debug] [RemoteDebugger] Sending connection key request
[debug] [RemoteDebugger] Sending '_rpc_reportIdentifier:' message (id: 0)
[debug] [RemoteDebugger] Received no apps from remote debugger. Unable to connect.
[debug] [RemoteDebugger] Connected to application
[debug] [RemoteDebugger] Selecting application
[debug] [RemoteDebugger] No applications currently connected.
[debug] [XCUITest] No web frames found.
[debug] [MJSONWP (ce478cdf)] Responding to client with driver.getContexts() result: ["NATIVE_APP","FLUTTER"]
[HTTP] <-- GET /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8/contexts 200 195 ms - 96
[HTTP] 
[HTTP] --> POST /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8/context
[HTTP] {"name":"FLUTTER"}
[debug] [MJSONWP (ce478cdf)] Calling AppiumDriver.setContext() with args: ["FLUTTER","ce478cdf-1e4d-4a78-b5c6-8733102b47a8"]
[debug] [FlutterDriver] Executing Flutter driver command 'setContext'
[debug] [MJSONWP (ce478cdf)] Responding to client with driver.setContext() result: "FLUTTER"
[HTTP] <-- POST /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8/context 200 2 ms - 81
[HTTP] 
[HTTP] --> POST /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8/element/eyJmaW5kZXJUeXBlIjoiQnlWYWx1ZUtleSIsImtleVZhbHVlVHlwZSI6IlN0cmluZyIsImtleVZhbHVlU3RyaW5nIjoiYnV0dG9uMSJ9/click
[HTTP] {"id":"eyJmaW5kZXJUeXBlIjoiQnlWYWx1ZUtleSIsImtleVZhbHVlVHlwZSI6IlN0cmluZyIsImtleVZhbHVlU3RyaW5nIjoiYnV0dG9uMSJ9"}
[debug] [MJSONWP (ce478cdf)] Calling AppiumDriver.click() with args: ["eyJmaW5kZXJUeXBlIjoiQnlWYWx1ZUtleSIsImtleVZhbHVlVHlwZSI6IlN0cmluZyIsImtleVZhbHVlU3RyaW5nIjoiYnV0dG9uMSJ9","ce478cdf-1e4d-4a78-b5c6-8733102b47a8"]
[debug] [FlutterDriver] Executing Flutter driver command 'click'
[debug] [FlutterDriver] >>> {"command":"tap","finderType":"ByValueKey","keyValueType":"String","keyValueString":"button1"}
[FlutterDriver] {}
[debug] [MJSONWP (ce478cdf)] Encountered internal error running command: Error: {}
[debug] [MJSONWP (ce478cdf)]     at Object.wrappedLogger.errorAndThrow (/usr/local/lib/node_modules/appium/node_modules/appium-support/lib/logging.js:74:35)
[debug] [MJSONWP (ce478cdf)]     at Client.socket.call (/usr/local/lib/node_modules/appium/node_modules/appium-flutter-driver/lib/sessions/observatory.ts:73:19)
[debug] [MJSONWP (ce478cdf)]     at runMicrotasks (<anonymous>)
[debug] [MJSONWP (ce478cdf)]     at processTicksAndRejections (internal/process/task_queues.js:93:5)
[HTTP] <-- POST /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8/element/eyJmaW5kZXJUeXBlIjoiQnlWYWx1ZUtleSIsImtleVZhbHVlVHlwZSI6IlN0cmluZyIsImtleVZhbHVlU3RyaW5nIjoiYnV0dG9uMSJ9/click 500 10 ms - 173
[HTTP] 
[HTTP] --> DELETE /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8
[HTTP] {}
[debug] [MJSONWP (ce478cdf)] Calling AppiumDriver.deleteSession() with args: ["ce478cdf-1e4d-4a78-b5c6-8733102b47a8"]
[debug] [BaseDriver] Event 'quitSessionRequested' logged at 1579596108252 (14:11:48 GMT+0530 (India Standard Time))
[Appium] Removing session ce478cdf-1e4d-4a78-b5c6-8733102b47a8 from our master session list
[debug] [FlutterDriver] Deleting Flutter Driver session
[debug] [WD Proxy] Matched '/session/0495b586-3d05-40fa-bb37-3be715fa6e46' to command name 'deleteSession'
[debug] [WD Proxy] Proxying [DELETE /session/0495b586-3d05-40fa-bb37-3be715fa6e46] to [DELETE http://localhost:8101/session/DF558BD9-062E-47B0-B35C-4408EB5AFF43] with no body
[debug] [WD Proxy] Got response with status 200: {
[debug] [WD Proxy]   "value" : null,
[debug] [WD Proxy]   "sessionId" : "D05CEB71-16A2-4355-89B2-797D249A9EB1"
[debug] [WD Proxy] }
[DevCon Factory] Releasing connections for A59562F8-1EF1-435E-8C5E-4B829ABA6F37 device on any port number
[DevCon Factory] Found cached connections to release: ["A59562F8-1EF1-435E-8C5E-4B829ABA6F37:8101"]
[debug] [DevCon Factory] Cached connections count: 0
[debug] [XCUITest] Not clearing log files. Use `clearSystemFiles` capability to turn on.
[debug] [IOSSimulatorLog] Stopping iOS log capture
[debug] [BaseDriver] Event 'quitSessionFinished' logged at 1579596109295 (14:11:49 GMT+0530 (India Standard Time))
[debug] [MJSONWP (ce478cdf)] Received response: null
[debug] [MJSONWP (ce478cdf)] But deleting session, so not returning
[debug] [MJSONWP (ce478cdf)] Responding to client with driver.deleteSession() result: null
[HTTP] <-- DELETE /wd/hub/session/ce478cdf-1e4d-4a78-b5c6-8733102b47a8 200 1044 ms - 76
[HTTP] 

[shibupanda]

@ahashmi24 Could you please raised it in appium main repository. It seems from XCUITest driver.

[XCUITest] idb will not be used for Simulator interaction. Original error: 'idb' has not been found in PATH. Is it installed? Read https://www.fbidb.io for more details

[ahashmi24]

@shibupanda
It's only throwing an exception when interacting with FlutterElement. However, I am able to interact with native elements upon relaunching the app in same session.

It seems that it fails to set "Flutter" automation environment when we relaunching the app.

Exception:
org.openqa.selenium.WebDriverException: An unknown server-side error occurred while processing the command. Original error: {} (WARNING: The server did not provide any stacktrace information)
Command duration or timeout: 0 milliseconds
Build info: version: '3.13.0', revision: '2f0d292', time: '2018-06-25T15:24:21.231Z'
System info: host: 'Ahmers-MacBook-Pro.local', ip: 'fe80:0:0:0:1004:9e88:c8e9:1623%en0', os.name: 'Mac OS X', os.arch: 'x86_64', os.version: '10.14.4', java.version: '1.8.0_191'
Driver info: io.appium.java_client.ios.IOSDriver
Capabilities {address: 0.0.0.0, allowCors: false, allowInsecure: [], androidCoverage: false, app: /Users/vikasvipul/Documents..., appWaitActivity: false, appWaitPackage: false, automationName: Flutter, backendRetries: 3, basePath: /wd/hub, bootstrapPort: 4724, bundleId: com.example.datePicket, debugLogSpacing: false, defaultCapabilities: {}, defaultCommandTimeout: 60, defaultDevice: false, denyInsecure: [], deviceName: iPhone 11 Pro Max, deviceReadyTimeout: 5, dontStopAppOnReset: false, enforceStrictCaps: false, fastReset: false, forceIpad: false, forceIphone: false, fullReset: false, includeSafariInWebviews: true, intentAction: android.intent.action.MAIN, intentCategory: android.intent.category.LAU..., intentFlags: 0x10200000, isolateSimDevice: false, javascriptEnabled: true, keepArtifacts: false, keepKeyChains: false, keyAlias: androiddebugkey, keyPassword: android, keystorePassword: android, keystorePath: /Users/vikasvipul/.android/..., launch: false, launchTimeout: 90000, localTimezone: false, localizableStringsDir: en.lproj, logNoColors: false, logTimestamp: false, loglevel: debug, longStacktrace: false, nativeInstrumentsLib: false, noPermsCheck: false, noReset: true, orientation: PORTRAIT, platform: IOS, platformName: iOS, platformVersion: 13.1, port: 4723, reboot: false, relaxedSecurityEnabled: false, robotAddress: 0.0.0.0, robotPort: -1, safari: false, sessionOverride: false, showConfig: false, showIOSLog: false, skipUninstall: true, suppressKillServer: false, tmpDir: /var/folders/w6/qbbv2xsj11l..., udid: A59562F8-1EF1-435E-8C5E-4B8..., useKeystore: false, wdaLocalPort: 8101, webkitDebugProxyPort: 27753}
Session ID: 33e58f69-3c80-47a4-b9c8-3f936968a245
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
at org.openqa.selenium.remote.ErrorHandler.createThrowable(ErrorHandler.java:214)
at org.openqa.selenium.remote.ErrorHandler.throwIfResponseFailed(ErrorHandler.java:166)
at org.openqa.selenium.remote.http.JsonHttpResponseCodec.reconstructValue(JsonHttpResponseCodec.java:40)
at org.openqa.selenium.remote.http.AbstractHttpResponseCodec.decode(AbstractHttpResponseCodec.java:80)
at org.openqa.selenium.remote.http.AbstractHttpResponseCodec.decode(AbstractHttpResponseCodec.java:44)
at org.openqa.selenium.remote.HttpCommandExecutor.execute(HttpCommandExecutor.java:158)
at io.appium.java_client.remote.AppiumCommandExecutor.execute(AppiumCommandExecutor.java:231)
at org.openqa.selenium.remote.RemoteWebDriver.execute(RemoteWebDriver.java:548)
at io.appium.java_client.DefaultGenericMobileDriver.execute(DefaultGenericMobileDriver.java:42)
at io.appium.java_client.AppiumDriver.execute(AppiumDriver.java:1)
at io.appium.java_client.ios.IOSDriver.execute(IOSDriver.java:1)
at org.openqa.selenium.remote.RemoteWebElement.execute(RemoteWebElement.java:276)
at io.appium.java_client.DefaultGenericMobileElement.execute(DefaultGenericMobileElement.java:45)
at io.appium.java_client.MobileElement.execute(MobileElement.java:1)
at org.openqa.selenium.remote.RemoteWebElement.click(RemoteWebElement.java:83)
at base.FlutterTest.basicTest(FlutterTest.java:87)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:85)
at org.testng.internal.Invoker.invokeMethod(Invoker.java:639)
at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:821)
at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:1131)
at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:124)
at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:108)
at org.testng.TestRunner.privateRun(TestRunner.java:773)
at org.testng.TestRunner.run(TestRunner.java:623)
at org.testng.SuiteRunner.runTest(SuiteRunner.java:357)
at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:352)
at org.testng.SuiteRunner.privateRun(SuiteRunner.java:310)
at org.testng.SuiteRunner.run(SuiteRunner.java:259)
at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:86)
at org.testng.TestNG.runSuitesSequentially(TestNG.java:1185)
at org.testng.TestNG.runSuitesLocally(TestNG.java:1110)
at org.testng.TestNG.run(TestNG.java:1018)
at org.testng.remote.AbstractRemoteTestNG.run(AbstractRemoteTestNG.java:114)
at org.testng.remote.RemoteTestNG.initAndRun(RemoteTestNG.java:251)
at org.testng.remote.RemoteTestNG.main(RemoteTestNG.java:77)

[shibupanda]

CC @truongsinh

[truongsinh]

Thanks for reporting. It seems to be a coner case that we have not covered. Will work on the case in the coming time.

[ravijha-adi]

currently any possible workaround for this ?

[KazuCocoa]

Appium will drop appium/appium#15807 closeApp, resetApp and launchApp since they remain some legacy reasons. Instead, activate and terminate app remain.

The idea about this is to call observatory after "activate" app.

[KazuCocoa]

#190