yejianfengblue/appium-server.log

## appium-server.log
2021-03-22 21:31:52:612 [Appium] Welcome to Appium v1.20.2
2021-03-22 21:31:52:613 [Appium] Non-default server args:
2021-03-22 21:31:52:613 [Appium]   port: 4000
2021-03-22 21:31:52:613 [Appium]   logFile: /home/k/appium-m4.log
2021-03-22 21:31:52:614 [Appium]   logTimestamp: true
2021-03-22 21:31:52:614 [Appium]   localTimezone: true
2021-03-22 21:31:52:632 [Appium] Appium REST http interface listener started on 0.0.0.0:4000


2021-03-22 21:31:59:421 [HTTP] Request idempotency key: dee40678-80de-4f8c-9339-15bde812eb38
2021-03-22 21:31:59:431 [HTTP] --> POST /wd/hub/session
2021-03-22 21:31:59:432 [HTTP] {"capabilities":{"firstMatch":[{"platformName":"Android","appium:automationName":"uiautomator2","appium:udid":"m4:5555","appium:mjpegScreenshotUrl":"http://m4:7810","appium:newCommandTimeout":600,"appium:noReset":true}]},"desiredCapabilities":{"platformName":"Android","automationName":"uiautomator2","udid":"m4:5555","mjpegScreenshotUrl":"http://m4:7810","newCommandTimeout":600,"noReset":true}}
2021-03-22 21:31:59:433 [W3C] Calling AppiumDriver.createSession() with args: [{"platformName":"Android","automationName":"uiautomator2","udid":"m4:5555","mjpegScreenshotUrl":"http://m4:7810","newCommandTimeout":600,"noReset":true},null,{"firstMatch":[{"platformName":"Android","appium:automationName":"uiautomator2","appium:udid":"m4:5555","appium:mjpegScreenshotUrl":"http://m4:7810","appium:newCommandTimeout":600,"appium:noReset":true}]}]
2021-03-22 21:31:59:434 [BaseDriver] Event 'newSessionRequested' logged at 1616419919434 (21:31:59 GMT+0800 (China Standard Time))
2021-03-22 21:31:59:597 [Appium] Appium v1.20.2 creating new AndroidUiautomator2Driver (v1.61.2) session
2021-03-22 21:31:59:599 [BaseDriver] W3C capabilities and MJSONWP desired capabilities were provided
2021-03-22 21:31:59:599 [BaseDriver] Creating session with W3C capabilities: {
2021-03-22 21:31:59:599 [BaseDriver]   "alwaysMatch": {
2021-03-22 21:31:59:599 [BaseDriver]     "platformName": "Android",
2021-03-22 21:31:59:599 [BaseDriver]     "appium:automationName": "uiautomator2",
2021-03-22 21:31:59:599 [BaseDriver]     "appium:udid": "m4:5555",
2021-03-22 21:31:59:599 [BaseDriver]     "appium:mjpegScreenshotUrl": "http://m4:7810",
2021-03-22 21:31:59:599 [BaseDriver]     "appium:newCommandTimeout": 600,
2021-03-22 21:31:59:599 [BaseDriver]     "appium:noReset": true
2021-03-22 21:31:59:600 [BaseDriver]   },
2021-03-22 21:31:59:600 [BaseDriver]   "firstMatch": [
2021-03-22 21:31:59:600 [BaseDriver]     {}
2021-03-22 21:31:59:600 [BaseDriver]   ]
2021-03-22 21:31:59:600 [BaseDriver] }
2021-03-22 21:31:59:606 [BaseDriver] Session created with session id: 3cf1dfce-e90d-4c9b-b350-895e58b9ee18
2021-03-22 21:31:59:607 [UiAutomator2] Neither 'app' nor 'appPackage' was set. Starting UiAutomator2 without the target application
2021-03-22 21:31:59:612 [ADB] Found 1 'build-tools' folders under '/home/k/software/android-sdk' (newest first):
2021-03-22 21:31:59:612 [ADB]     /home/k/software/android-sdk/build-tools/25.0.0
2021-03-22 21:31:59:612 [ADB] Using 'adb' from '/home/k/software/android-sdk/platform-tools/adb'
2021-03-22 21:31:59:613 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 start-server'
2021-03-22 21:31:59:622 [AndroidDriver] Retrieving device list
2021-03-22 21:31:59:622 [ADB] Trying to find a connected android device
2021-03-22 21:31:59:623 [ADB] Getting connected devices
2021-03-22 21:31:59:631 [ADB] Connected devices: [{"udid":"m4:5555","state":"device"},{"udid":"n2:5555","state":"device"},{"udid":"r2:5555","state":"device"},{"udid":"r3:5555","state":"device"},{"udid":"r5:5555","state":"device"}]
2021-03-22 21:31:59:631 [AndroidDriver] Using device: m4:5555
2021-03-22 21:31:59:632 [ADB] Using 'adb' from '/home/k/software/android-sdk/platform-tools/adb'
2021-03-22 21:31:59:632 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 start-server'
2021-03-22 21:31:59:640 [ADB] Setting device id to m4:5555
2021-03-22 21:31:59:640 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell getprop ro.build.version.sdk'
2021-03-22 21:31:59:738 [ADB] Current device property 'ro.build.version.sdk': 23
2021-03-22 21:31:59:738 [ADB] Device API level: 23
2021-03-22 21:31:59:738 [AndroidDriver] No app sent in, not parsing package/activity
2021-03-22 21:31:59:739 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 wait-for-device'
2021-03-22 21:31:59:749 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell echo ping'
2021-03-22 21:31:59:783 [AndroidDriver] Pushing settings apk to device...
2021-03-22 21:31:59:784 [ADB] Getting install status for io.appium.settings
2021-03-22 21:31:59:785 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell dumpsys package io.appium.settings'
2021-03-22 21:31:59:868 [ADB] 'io.appium.settings' is installed
2021-03-22 21:31:59:868 [ADB] Getting package info for 'io.appium.settings'
2021-03-22 21:31:59:869 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell dumpsys package io.appium.settings'
2021-03-22 21:31:59:982 [ADB] The version name of the installed 'io.appium.settings' is greater or equal to the application version name ('3.2.1' >= '3.2.1')
2021-03-22 21:31:59:983 [ADB] There is no need to install/upgrade '/home/k/.npm-global/lib/node_modules/appium/node_modules/io.appium.settings/apks/settings_apk-debug.apk'
2021-03-22 21:31:59:984 [ADB] Getting IDs of all 'io.appium.settings' processes
2021-03-22 21:31:59:984 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4\:5555 shell 'pgrep --help; echo $?''
2021-03-22 21:32:00:052 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell pgrep -f \(\[\[:blank:\]\]\|\^\)io\.appium\.settings\(\[\[:blank:\]\]\|\$\)'
2021-03-22 21:32:00:133 [AndroidDriver] io.appium.settings is already running. There is no need to reset its permissions.
2021-03-22 21:32:00:134 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell appops set io.appium.settings android:mock_location allow'
2021-03-22 21:32:01:309 [Logcat] Starting logs capture with command: /home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4\:5555 logcat -v threadtime
2021-03-22 21:32:01:395 [UiAutomator2] Forwarding UiAutomator2 Server port 6790 to local port 8204
2021-03-22 21:32:01:396 [ADB] Forwarding system: 8204 to device: 6790
2021-03-22 21:32:01:396 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 forward tcp:8204 tcp:6790'
2021-03-22 21:32:01:411 [ADB] Getting install status for io.appium.uiautomator2.server
2021-03-22 21:32:01:412 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell dumpsys package io.appium.uiautomator2.server'
2021-03-22 21:32:01:500 [ADB] 'io.appium.uiautomator2.server' is installed
2021-03-22 21:32:01:500 [ADB] Getting package info for 'io.appium.uiautomator2.server'
2021-03-22 21:32:01:500 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell dumpsys package io.appium.uiautomator2.server'
2021-03-22 21:32:01:549 [ADB] The version name of the installed 'io.appium.uiautomator2.server' is greater or equal to the application version name ('4.17.4' >= '4.17.4')
2021-03-22 21:32:01:550 [UiAutomator2] io.appium.uiautomator2.server installation state: sameVersionInstalled
2021-03-22 21:32:01:551 [ADB] Checking app cert for /home/k/.npm-global/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.17.4.apk
2021-03-22 21:32:01:552 [ADB] Using 'apksigner.jar' from '/home/k/software/android-sdk/apksigner.jar'
2021-03-22 21:32:01:552 [ADB] Starting apksigner: /lib/jvm/java-11-openjdk/bin/java -Xmx1024M -Xss1m -jar /home/k/software/android-sdk/apksigner.jar verify --print-certs /home/k/.npm-global/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.17.4.apk
2021-03-22 21:32:01:803 [ADB] apksigner stdout: Signer #1 certificate DN: EMAILADDRESS=android@android.com, CN=Android, OU=Android, O=Android, L=Mountain View, ST=California, C=US
2021-03-22 21:32:01:803 [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc
2021-03-22 21:32:01:803 [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81
2021-03-22 21:32:01:803 [ADB]
2021-03-22 21:32:01:803 [ADB] sha256 hash did match for 'appium-uiautomator2-server-v4.17.4.apk'
2021-03-22 21:32:01:804 [ADB] '/home/k/.npm-global/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.17.4.apk' is signed with the default certificate
2021-03-22 21:32:01:804 [ADB] Getting install status for io.appium.uiautomator2.server.test
2021-03-22 21:32:01:804 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell dumpsys package io.appium.uiautomator2.server.test'
2021-03-22 21:32:01:878 [ADB] 'io.appium.uiautomator2.server.test' is installed
2021-03-22 21:32:01:878 [ADB] Checking app cert for /home/k/.npm-global/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk
2021-03-22 21:32:01:879 [ADB] Starting apksigner: /lib/jvm/java-11-openjdk/bin/java -Xmx1024M -Xss1m -jar /home/k/software/android-sdk/apksigner.jar verify --print-certs /home/k/.npm-global/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk
2021-03-22 21:32:02:114 [ADB] apksigner stdout: Signer #1 certificate DN: EMAILADDRESS=android@android.com, CN=Android, OU=Android, O=Android, L=Mountain View, ST=California, C=US
2021-03-22 21:32:02:115 [ADB] Signer #1 certificate SHA-256 digest: a40da80a59d170caa950cf15c18c454d47a39b26989d8b640ecd745ba71bf5dc
2021-03-22 21:32:02:115 [ADB] Signer #1 certificate SHA-1 digest: 61ed377e85d386a8dfee6b864bd85b0bfaa5af81
2021-03-22 21:32:02:115 [ADB]
2021-03-22 21:32:02:115 [ADB] sha256 hash did match for 'appium-uiautomator2-server-debug-androidTest.apk'
2021-03-22 21:32:02:115 [ADB] '/home/k/.npm-global/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk' is signed with the default certificate
2021-03-22 21:32:02:115 [UiAutomator2] Server packages are not going to be (re)installed
2021-03-22 21:32:02:116 [UiAutomator2] Waiting up to 30000ms for services to be available
2021-03-22 21:32:02:117 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell pm list instrumentation'
2021-03-22 21:32:03:104 [UiAutomator2] Instrumentation target 'io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner' is available
2021-03-22 21:32:03:106 [ADB] Adding packages ["io.appium.settings","io.appium.uiautomator2.server","io.appium.uiautomator2.server.test"] to Doze whitelist
2021-03-22 21:32:03:106 [ADB] Got the following command chunks to execute: [["dumpsys","deviceidle","whitelist","+io.appium.settings",";","dumpsys","deviceidle","whitelist","+io.appium.uiautomator2.server",";","dumpsys","deviceidle","whitelist","+io.appium.uiautomator2.server.test",";"]]
2021-03-22 21:32:03:106 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell dumpsys deviceidle whitelist +io.appium.settings ; dumpsys deviceidle whitelist +io.appium.uiautomator2.server ; dumpsys deviceidle whitelist +io.appium.uiautomator2.server.test ;'
2021-03-22 21:32:03:266 [UiAutomator2] No app capability. Assuming it is already on the device
2021-03-22 21:32:03:267 [UiAutomator2] Performing shallow cleanup of automation leftovers
2021-03-22 21:32:03:285 [UiAutomator2] No obsolete sessions have been detected (socket hang up)
2021-03-22 21:32:03:285 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell am force-stop io.appium.uiautomator2.server.test'
2021-03-22 21:32:04:453 [UiAutomator2] Starting UIAutomator2 server 4.17.4
2021-03-22 21:32:04:453 [UiAutomator2] Using UIAutomator2 server from '/home/k/.npm-global/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-v4.17.4.apk' and test from '/home/k/.npm-global/lib/node_modules/appium/node_modules/appium-uiautomator2-server/apks/appium-uiautomator2-server-debug-androidTest.apk'
2021-03-22 21:32:04:454 [UiAutomator2] Waiting up to 30000ms for UiAutomator2 to be online...
2021-03-22 21:32:04:454 [ADB] Creating ADB subprocess with args: ["-P",5037,"-s","m4:5555","shell","am","instrument","-w","io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner"]
2021-03-22 21:32:04:467 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-03-22 21:32:04:469 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8204/wd/hub/status] with no body
2021-03-22 21:32:04:476 [WD Proxy] socket hang up
2021-03-22 21:32:05:479 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-03-22 21:32:05:480 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8204/wd/hub/status] with no body
2021-03-22 21:32:05:486 [WD Proxy] socket hang up
2021-03-22 21:32:06:037 [Instrumentation] io.appium.uiautomator2.server.test.AppiumUiAutomator2Server:
2021-03-22 21:32:06:486 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-03-22 21:32:06:487 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8204/wd/hub/status] with no body
2021-03-22 21:32:06:494 [WD Proxy] socket hang up
2021-03-22 21:32:07:495 [WD Proxy] Matched '/status' to command name 'getStatus'
2021-03-22 21:32:07:496 [WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8204/wd/hub/status] with no body
2021-03-22 21:32:07:687 [WD Proxy] Got response with status 200: {"sessionId":"None","value":{"message":"UiAutomator2 Server is ready to accept commands","ready":true}}
2021-03-22 21:32:07:687 [UiAutomator2] The initialization of the instrumentation process took 3234ms
2021-03-22 21:32:07:688 [WD Proxy] Matched '/session' to command name 'createSession'
2021-03-22 21:32:07:688 [WD Proxy] Proxying [POST /session] to [POST http://127.0.0.1:8204/wd/hub/session] with body: {"capabilities":{"firstMatch":[{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"Android","automationName":"uiautomator2","udid":"m4:5555","mjpegScreenshotUrl":"http://m4:7810","newCommandTimeout":600,"noReset":true},"platformName":"Android","automationName":"uiautomator2","udid":"m4:5555","mjpegScreenshotUrl":"http://m4:7810","newCommandTimeout":600,"noReset":true,"deviceName":"m4:5555","deviceUDID":"m4:5555"}],"alwaysMatch":{}}}
2021-03-22 21:32:07:731 [WD Proxy] Got response with status 200: {"sessionId":"f4d693f9-4782-44eb-82c3-5ede6a20cf3c","value":{"capabilities":{"firstMatch":[{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"Android","automationName":"uiautomator2","udid":"m4:5555","mjpegScreenshotUrl":"http://m4:7810","newCommandTimeout":600,"noReset":true},"platformName":"Android","automationName":"uiautomator2","udid":"m4:5555","mjpegScreenshotUrl":"http://m4:7810","newCommandTimeout":600,"noReset":true,"deviceName":"m4:5555","deviceUDID":"m4:5555"}],"alwaysMatch":{}},"sessionId":"f4d693f9-4782-44eb-82c3-5ede6a20cf3c"}}
2021-03-22 21:32:07:731 [WD Proxy] Determined the downstream protocol as 'W3C'
2021-03-22 21:32:07:742 [WD Proxy] Proxying [GET /appium/device/info] to [GET http://127.0.0.1:8204/wd/hub/session/f4d693f9-4782-44eb-82c3-5ede6a20cf3c/appium/device/info] with no body
2021-03-22 21:32:07:798 [WD Proxy] Got response with status 200: {"sessionId":"f4d693f9-4782-44eb-82c3-5ede6a20cf3c","value":{"androidId":"4cea0ae73854c9e9","apiVersion":"23","bluetooth":{"state":"OFF"},"brand":"Xiaomi","carrierName":"","displayDensity":480,"locale":"zh_CN","manufacturer":"Xiaomi","model":"MI 4LTE","networks":[{"capabilities":{"SSID":null,"linkDownBandwidthKbps":1048576,"linkUpstreamBandwidthKbps":1048576,"networkCapabilities":"NET_CAPABILITY_NOT_RESTRICTED,NET_CAPABILITY_NOT_VPN,NET_CAPABILITY_INTERNET,NET_CAPABILITY_TRUSTED,NET_CAPABILITY_VALIDATED","signalStrength":-54,"transportTypes":"TRANSPORT_WIFI"},"detailedState":"CONNECTED","extraInfo":"\"Blue5_5G\"","isAvailable":true,"isConnected":true,"isFailover":false,"isRoaming":false,"state":"CONNECTED","subtype":0,"subtypeName":"","type":1,"typeName":"WIFI"}],"platformVersion":"6.0.1","realDisplaySize":"1080x1920","timeZone":"Asia/Shanghai"}}
2021-03-22 21:32:07:799 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 shell dumpsys window'
2021-03-22 21:32:07:893 [AndroidDriver] Screen already unlocked, doing nothing
2021-03-22 21:32:07:896 [WD Proxy] Proxying [GET /appium/device/pixel_ratio] to [GET http://127.0.0.1:8204/wd/hub/session/f4d693f9-4782-44eb-82c3-5ede6a20cf3c/appium/device/pixel_ratio] with no body
2021-03-22 21:32:07:907 [WD Proxy] Got response with status 200: {"sessionId":"f4d693f9-4782-44eb-82c3-5ede6a20cf3c","value":3}
2021-03-22 21:32:07:909 [WD Proxy] Matched '/appium/device/system_bars' to command name 'getSystemBars'
2021-03-22 21:32:07:910 [WD Proxy] Proxying [GET /appium/device/system_bars] to [GET http://127.0.0.1:8204/wd/hub/session/f4d693f9-4782-44eb-82c3-5ede6a20cf3c/appium/device/system_bars] with no body
2021-03-22 21:32:07:970 [WD Proxy] Got response with status 200: {"sessionId":"f4d693f9-4782-44eb-82c3-5ede6a20cf3c","value":{"statusBar":60}}
2021-03-22 21:32:07:970 [WD Proxy] Matched '/window/current/size' to command name 'getWindowSize'
2021-03-22 21:32:07:971 [WD Proxy] Proxying [GET /window/current/size] to [GET http://127.0.0.1:8204/wd/hub/session/f4d693f9-4782-44eb-82c3-5ede6a20cf3c/window/current/size] with no body
2021-03-22 21:32:07:982 [WD Proxy] Got response with status 200: {"sessionId":"f4d693f9-4782-44eb-82c3-5ede6a20cf3c","value":{"height":1920,"width":1080}}
2021-03-22 21:32:07:982 [UiAutomator2] Starting MJPEG stream reading URL: 'http://m4:7810'
2021-03-22 21:32:07:983 [Support] Loading local package 'mjpeg-consumer'
2021-03-22 21:32:08:354 [Appium] New AndroidUiautomator2Driver session created successfully, session 3cf1dfce-e90d-4c9b-b350-895e58b9ee18 added to master session list
2021-03-22 21:32:08:355 [BaseDriver] Event 'newSessionStarted' logged at 1616419928354 (21:32:08 GMT+0800 (China Standard Time))
2021-03-22 21:32:08:356 [W3C (3cf1dfce)] Cached the protocol value 'W3C' for the new session 3cf1dfce-e90d-4c9b-b350-895e58b9ee18
2021-03-22 21:32:08:357 [W3C (3cf1dfce)] Responding to client with driver.createSession() result: {"capabilities":{"platform":"LINUX","webStorageEnabled":false,"takesScreenshot":true,"javascriptEnabled":true,"databaseEnabled":false,"networkConnectionEnabled":true,"locationContextEnabled":false,"warnings":{},"desired":{"platformName":"Android","automationName":"uiautomator2","udid":"m4:5555","mjpegScreenshotUrl":"http://m4:7810","newCommandTimeout":600,"noReset":true},"platformName":"Android","automationName":"uiautomator2","udid":"m4:5555","mjpegScreenshotUrl":"http://m4:7810","newCommandTimeout":600,"noReset":true,"deviceName":"m4:5555","deviceUDID":"m4:5555","deviceApiLevel":23,"platformVersion":"6.0.1","deviceScreenSize":"1080x1920","deviceScreenDensity":480,"deviceModel":"MI 4LTE","deviceManufacturer":"Xiaomi","pixelRatio":3,"statBarHeight":60,"viewportRect":{"left":0,"top":60,"width":1080,"height":1860}}}
2021-03-22 21:32:08:362 [HTTP] <-- POST /wd/hub/session 200 8929 ms - 886
2021-03-22 21:32:08:363 [HTTP]


2021-03-22 21:32:08:370 [HTTP] --> GET /wd/hub/session/3cf1dfce-e90d-4c9b-b350-895e58b9ee18/screenshot
2021-03-22 21:32:08:370 [HTTP] {}
2021-03-22 21:32:08:374 [W3C (3cf1dfce)] Calling AppiumDriver.getScreenshot() with args: ["3cf1dfce-e90d-4c9b-b350-895e58b9ee18"]
2021-03-22 21:32:08:588 [W3C (3cf1dfce)] Responding to client with driver.getScreenshot() result: "iVBORw0KGgoAAAANSUhEUgAAAhwAAAPACAYAAAB+daCcAAPC3UlEQVR4AezBB5idVaHw+/9ab9m9TS+Z1EkvEJReAoRuQyJHBMsRCQIqgiJVDooHKaKACCJ+REAFGyooqAchgEQgDUiB9DYzyeype2b3t6z13XePfE84l4P3Prn3OcfH+f1Ew4mXad5GElBIAlpQo5CMkewvXjUI+IZPwDE8Aq7pEVCMkYyRShIwlCQgUbyNlgSUkAS0oEYhGSPYnxLUCE2NQBOQKAJCewQkioBAExCaMSpCQAtqtFAElFAEtKBGCUVAC0VAC2paiyae55GzwDRNnGgEKSW66uE4DhnfJGArTcAxPAJFWxEIuRqlFAqNlBJMg4CjfDzPwwrZBISmRvJ2inHj/nGFfEVAC/5GElBIAlpQo5GMkexPaMUBkVUOhCcsDoi2CAhNjUQTEFoRcKpFDMPAMgRSSizTwDAMlOtRrVYpIKlUKtTXZ4hGo/Rns1SrVerq0sRiMQqjearVKlKAaZo4ToVqtUoyHqepqYm+gSECWjBGCwJaUKOQjJEEtOBvJAElPAISRUBoTUBq/sYgoDAJaAwCCpOA8B0ChmFgmiZSSrTWOI6D67oIITBNE9u2EUKgtUYpRUAIge/383aSgBKSgGaMEpIaLdhftDiZgDZKBJQ5RECbOQK+USbgC2o0IWq0SUB4aQ6E1hFqhENAiDIBgyoBqamRKkRA+hFqtE2gahc5EKYvORC+9Hg3Sin2ZzLugBiGgdYaKTVv0VojhMAwDCxpEbCUJqANQcCyFAHtVNFao9G8RQiBEAIpJePGjfvnZZompmliCI3nebhOlYApJEIIQnYIy7JwHIdCoQBa09TUhBCaXbt20d7ahmVZSAGGYRAKWViWhe/7ZLNZhGHx38nzPAzDQCmF4zhorRFCYFkW4XAYIQSu61KtVlFKIYRASonWGq01psm4fyAm/4fk7RQBoSUBKRRjFGMkAcFbFPsTWlIjFAGhqZGaGoniHQnF/oSm...
2021-03-22 21:32:08:591 [HTTP] <-- GET /wd/hub/session/3cf1dfce-e90d-4c9b-b350-895e58b9ee18/screenshot 200 220 ms - 328748
2021-03-22 21:32:08:591 [HTTP]

2021-03-22 21:39:21:853 [HTTP] --> GET /wd/hub/session/3cf1dfce-e90d-4c9b-b350-895e58b9ee18/appium/settings
2021-03-22 21:39:21:853 [HTTP] {}
2021-03-22 21:39:21:870 [W3C (3cf1dfce)] Calling AppiumDriver.getSettings() with args: ["3cf1dfce-e90d-4c9b-b350-895e58b9ee18"]
2021-03-22 21:39:21:890 [WD Proxy] Matched '/appium/settings' to command name 'getSettings'
2021-03-22 21:39:21:891 [WD Proxy] Proxying [GET /appium/settings] to [GET http://127.0.0.1:8204/wd/hub/session/f4d693f9-4782-44eb-82c3-5ede6a20cf3c/appium/settings] with no body
2021-03-22 21:39:21:947 [WD Proxy] Got response with status 200: {"sessionId":"f4d693f9-4782-44eb-82c3-5ede6a20cf3c","value":{"keyInjectionDelay":0,"mjpegScalingFactor":50,"trackScrollEvents":true,"normalizeTagNames":false,"useResourcesForOrientationDetection":false,"mjpegServerScreenshotQuality":50,"wakeLockTimeout":85965217,"waitForSelectorTimeout":10000,"mjpegServerPort":7810,"ignoreUnimportantViews":false,"simpleBoundsCalculation":false,"shutdownOnPowerDisconnect":true,"scrollAcknowledgmentTimeout":200,"mjpegServerFramerate":10,"elementResponseAttributes":"","enableMultiWindows":false,"actionAcknowledgmentTimeout":3000,"serverPort":6790,"waitForIdleTimeout":10000,"allowInvisibleElements":false,"enableNotificationListener":true,"mjpegBilinearFiltering":false,"shouldUseCompactResponses":true}}
2021-03-22 21:39:21:948 [W3C (3cf1dfce)] Responding to client with driver.getSettings() result: {"imageMatchThreshold":0.4,"fixImageFindScreenshotDims":true,"fixImageTemplateSize":false,"fixImageTemplateScale":false,"defaultImageTemplateScale":1,"checkForImageElementStaleness":true,"autoUpdateImageElementPosition":false,"imageElementTapStrategy":"w3cActions","getMatchedImageResult":false,"ignoreUnimportantViews":false,"allowInvisibleElements":false,"keyInjectionDelay":0,"mjpegScalingFactor":50,"trackScrollEvents":true,"normalizeTagNames":false,"useResourcesForOrientationDetection":false,"mjpegServerScreenshotQuality":50,"wakeLockTimeout":85965217,"waitForSelectorTimeout":10000,"mjpegServerPort":7810,"simpleBoundsCalculation":false,"shutdownOnPowerDisconnect":true,"scrollAcknowledgmentTimeout":200,"mjpegServerFramerate":10,"elementResponseAttributes":"","enableMultiWindows":false,"actionAcknowledgmentTimeout":3000,"serverPort":6790,"waitForIdleTimeout":10000,"enableNotificationListener":true,"mjpegBilinearFiltering":false,"shouldUseCompactResponses":true}
2021-03-22 21:39:21:950 [HTTP] <-- GET /wd/hub/session/3cf1dfce-e90d-4c9b-b350-895e58b9ee18/appium/settings 200 97 ms - 984
2021-03-22 21:39:21:950 [HTTP]

2021-03-22 21:42:04:132 [HTTP] --> DELETE /wd/hub/session/3cf1dfce-e90d-4c9b-b350-895e58b9ee18
2021-03-22 21:42:04:132 [HTTP] {}
2021-03-22 21:42:04:132 [W3C (3cf1dfce)] Calling AppiumDriver.deleteSession() with args: ["3cf1dfce-e90d-4c9b-b350-895e58b9ee18"]
2021-03-22 21:42:04:133 [BaseDriver] Event 'quitSessionRequested' logged at 1616420524133 (21:42:04 GMT+0800 (China Standard Time))
2021-03-22 21:42:04:133 [Appium] Removing session 3cf1dfce-e90d-4c9b-b350-895e58b9ee18 from our master session list
2021-03-22 21:42:04:134 [UiAutomator2] Deleting UiAutomator2 session
2021-03-22 21:42:04:135 [UiAutomator2] Deleting UiAutomator2 server session
2021-03-22 21:42:04:136 [WD Proxy] Matched '/' to command name 'deleteSession'
2021-03-22 21:42:04:136 [WD Proxy] Proxying [DELETE /] to [DELETE http://127.0.0.1:8204/wd/hub/session/f4d693f9-4782-44eb-82c3-5ede6a20cf3c] with no body
2021-03-22 21:42:04:163 [WD Proxy] Got response with status 200: {"sessionId":"f4d693f9-4782-44eb-82c3-5ede6a20cf3c","value":null}
2021-03-22 21:42:04:163 [Logcat] Stopping logcat capture
2021-03-22 21:42:04:165 [ADB] Removing forwarded port socket connection: 8204
2021-03-22 21:42:04:165 [ADB] Running '/home/k/software/android-sdk/platform-tools/adb -P 5037 -s m4:5555 forward --remove tcp:8204'
2021-03-22 21:42:04:176 [UiAutomator2] Closing MJPEG stream
2021-03-22 21:42:04:177 [BaseDriver] Event 'quitSessionFinished' logged at 1616420524177 (21:42:04 GMT+0800 (China Standard Time))
2021-03-22 21:42:04:177 [W3C (3cf1dfce)] Received response: null
2021-03-22 21:42:04:177 [W3C (3cf1dfce)] But deleting session, so not returning
2021-03-22 21:42:04:177 [W3C (3cf1dfce)] Responding to client with driver.deleteSession() result: null
2021-03-22 21:42:04:178 [HTTP] <-- DELETE /wd/hub/session/3cf1dfce-e90d-4c9b-b350-895e58b9ee18 200 46 ms - 14
2021-03-22 21:42:04:178 [HTTP]
2021-03-22 21:42:04:730 [Instrumentation] .
2021-03-22 21:42:05:549 [Instrumentation] Time: 598.696
2021-03-22 21:42:05:549 [Instrumentation]
2021-03-22 21:42:05:549 [Instrumentation] OK (1 test)
2021-03-22 21:42:05:802 [Instrumentation] The process has exited with code 0