2022-07-13 17:31:18.711 - INFO 17292 --- [main] org.cloud.sonic.agent.AgentApplication : Starting AgentApplication v1.4.1-release using Java 17.0.2 on LY202102250052 with PID 17292 (D:\sonic-agent\sonic-agent-windows-x86_64.jar started by shirly in D:\sonic-agent)
2022-07-13 17:31:18.719 - INFO 17292 --- [main] org.cloud.sonic.agent.AgentApplication : The following profiles are active: sonic-agent
2022-07-13 17:31:20.167 - INFO 17292 --- [main] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port(s): 7777 (http)
2022-07-13 17:31:20.180 - INFO 17292 --- [main] o.apache.catalina.core.StandardService : Starting service [Tomcat]
2022-07-13 17:31:20.181 - INFO 17292 --- [main] org.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/9.0.53]
2022-07-13 17:31:20.248 - INFO 17292 --- [main] o.a.c.c.C.[Tomcat].[localhost].[/] : Initializing Spring embedded WebApplicationContext
2022-07-13 17:31:20.249 - INFO 17292 --- [main] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 1454 ms
===================== Checking the Environment =====================
→ Check ANDROID_HOME Path Pass √
→ Check ADB env Pass √
→ Check Node env (Next version deprecated) Pass √
→ Check npm env (Next version deprecated) Pass √
→ Check JAVA_HOME Path Pass √
→ Check Appium env (Next version deprecated) Pass √
→ Check adbkit env (Next version deprecated) Pass √
→ Check local resource Pass √
=========================== Check results ===========================
· JAVA_HOME: D:\software\jdk-17.0.2
· java version: 17.0.2
· ANDROID_HOME: C:\Users\shirly\AppData\Local\Android\Sdk
· ADB path: C:\Users\shirly\AppData\Local\Android\Sdk\platform-tools\adb.exe
· ADB version: Android Debug Bridge version 1.0.41
Version 31.0.2-7242960
Installed as C:\Users\shirly\AppData\Local\Android\Sdk\platform-tools\adb.exe
· chromeDriver path: /Applications/Google Chrome.app/Contents/MacOS/chromedriver
· chromeDriver version: null· Node path: D:\software\nodejs\node.exe
· Node version: v14.15.4
· npm path: D:\software\nodejs\npm
D:\software\nodejs\npm.cmd
· npm version: 6.14.10
· adbkit path: C:\Users\shirly\AppData\Roaming\npm\adbkit
C:\Users\shirly\AppData\Roaming\npm\adbkit.cmd
· adbkit version: 2.11.1
· Appium path: C:\Users\shirly\AppData\Roaming\npm\appium
C:\Users\shirly\AppData\Roaming\npm\appium.cmd
· Appium version: 1.22.3
· System: windows 10
========================== Check Completed ==========================
2022-07-13 17:31:24.232 - INFO 17292 --- [main] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port(s): 7777 (http) with context path ''
2022-07-13 17:31:24.398 - INFO 17292 --- [main] o.c.s.a.b.a.AndroidDeviceBridgeTool : Android devices listening...
2022-07-13 17:31:24.420 - INFO 17292 --- [Device List Monitor] o.c.s.a.b.a.AndroidDeviceStatusListener : Android device: WTK7N16A10002895 ONLINE！
2022-07-13 17:31:25.409 - INFO 17292 --- [main] o.c.s.a.b.a.AndroidDeviceBridgeTool : Enable Android Module
2022-07-13 17:31:25.547 - INFO 17292 --- [main] o.cloud.sonic.agent.bridge.ios.SibTool : iOS devices listening...
2022-07-13 17:31:25.547 - INFO 17292 --- [main] o.cloud.sonic.agent.bridge.ios.SibTool : Enable iOS Module
2022-07-13 17:31:25.557 - INFO 17292 --- [main] o.c.s.agent.tests.handlers.StepHandlers : Registered 5 condition handler(s)
2022-07-13 17:31:25.569 - INFO 17292 --- [main] org.cloud.sonic.agent.AgentApplication : Started AgentApplication in 7.538 seconds (JVM running for 7.993)
2022-07-13 17:31:25.695 - INFO 17292 --- [Thread-1] org.cloud.sonic.agent.tools.LaunchTool : Generating ca file...
2022-07-13 17:31:25.791 - INFO 17292 --- [pool-4-thread-1] o.cloud.sonic.agent.bridge.ios.SibTool : iOS Devices: c3841315830d6f136233e093d507c6f3c75e2654 ONLINE!
2022-07-13 17:31:25.792 - INFO 17292 --- [pool-4-thread-1] o.cloud.sonic.agent.bridge.ios.SibTool : {"deviceId":1,"connectionSpeed":0,"connectionType":"USB","locationId":0,"productId":4776,"serialNumber":"c3841315830d6f136233e093d507c6f3c75e2654","status":"online","deviceDetail":{"generationName":"iPhone 8","deviceName":"“shirly”的 iPhone","deviceColor":"2","deviceClass":"iPhone","productVersion":"14.4.2","productType":"iPhone10,1","productName":"iPhone OS","modelNumber":"MQ6M2","serialNumber":"F4HWP5KCJC6F","simStatus":"kCTSIMSupportSIMStatusNotInserted","cpuArchitecture":"arm64","protocolVersion":"2","regionInfo":"CH/A","telephonyCapability":true,"timeZone":"Asia/Shanghai","uniqueDeviceID":"c3841315830d6f136233e093d507c6f3c75e2654","wifiAddress":"5c:1d:d9:5f:65:44","wirelessBoardSerialNumber":"857C380021","bluetoothAddress":"5c:1d:d9:51:62:4c","buildVersion":"18D70"}}
2022-07-13 17:31:30.736 - INFO 17292 --- [Thread-1] org.cloud.sonic.agent.tools.LaunchTool : init sonic-go-mitmproxy-ca Successful!
2022-07-13 17:31:55.631 - INFO 17292 --- [WebSocketConnectReadThread-43] o.c.s.agent.transport.TransportClient : Connected and auth...
2022-07-13 17:31:55.647 - INFO 17292 --- [WebSocketConnectReadThread-43] o.c.s.agent.transport.TransportClient : Agent <- Server message: {"msg":"auth","result":"pass","highTemp":45,"id":2,"highTempTime":15}
2022-07-13 17:31:55.672 - INFO 17292 --- [pool-2-thread-2] o.c.s.agent.transport.TransportClient : server auth successful!
2022-07-13 17:32:25.972 - INFO 17292 --- [WebSocketConnectReadThread-43] o.c.s.agent.transport.TransportClient : Agent <- Server message: {"msg":"pong"}
2022-07-13 17:32:30.240 - INFO 17292 --- [http-nio-7777-exec-1] o.a.c.c.C.[Tomcat].[localhost].[/] : Initializing Spring DispatcherServlet 'dispatcherServlet'
2022-07-13 17:32:30.240 - INFO 17292 --- [http-nio-7777-exec-1] o.s.web.servlet.DispatcherServlet : Initializing Servlet 'dispatcherServlet'
2022-07-13 17:32:30.240 - INFO 17292 --- [http-nio-7777-exec-1] o.s.web.servlet.DispatcherServlet : Completed initialization in 0 ms
2022-07-13 17:32:30.308 - INFO 17292 --- [http-nio-7777-exec-1] o.c.s.agent.websockets.AndroidWSServer : android lock udId：WTK7N16A10002895
2022-07-13 17:32:30.317 - INFO 17292 --- [pool-3-thread-1] o.c.s.a.w.AndroidTerminalWSServer : WTK7N16A10002895开启terminal
2022-07-13 17:32:30.318 - INFO 17292 --- [pool-3-thread-2] o.c.s.a.w.AndroidTerminalWSServer : WTK7N16A10002895开启logcat
2022-07-13 17:32:30.892 - INFO 17292 --- [http-nio-7777-exec-1] o.c.s.agent.websockets.AndroidWSServer : 未安装Sonic插件或版本不是最新，正在安装...
2022-07-13 17:32:36.140 - INFO 17292 --- [http-nio-7777-exec-1] o.c.s.agent.websockets.AndroidWSServer : Sonic插件安装完毕
2022-07-13 17:32:36.878 - INFO 17292 --- [http-nio-7777-exec-2] o.c.s.a.w.AndroidScreenWSServer : 1 send: {"detail":"scrcpy","type":"switch"}
2022-07-13 17:32:37.127 - INFO 17292 --- [Thread-4] o.c.s.agent.websockets.AndroidWSServer : starting：start()
2022-07-13 17:32:37.200 - INFO 17292 --- [pool-3-thread-2] o.c.s.a.b.a.AndroidDeviceBridgeTool : WTK7N16A10002895 device sonictouchservice port forward to 17637
2022-07-13 17:32:37.708 - INFO 17292 --- [Thread-6] o.c.s.a.w.AndroidScreenWSServer : WTK7N16A10002895 rotation: 0
2022-07-13 17:32:38.191 - INFO 17292 --- [android-scrcpy-start-scrcpy-server-task-0-0-WTK7N16A10002895] o.c.s.a.t.a.scrcpy.ScrcpyLocalThread : [server] INFO: Device: HUAWEI FRD-AL00 (Android 8.0.0)
2022-07-13 17:32:38.230 - INFO 17292 --- [android-scrcpy-input-socket-task-0-0-WTK7N16A10002895] o.c.s.a.b.a.AndroidDeviceBridgeTool : WTK7N16A10002895 device scrcpy port forward to 17653
2022-07-13 17:32:39.483 - INFO 17292 --- [http-nio-7777-exec-4] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"d 0 873 652 50\n","type":"touch"}
2022-07-13 17:32:39.584 - INFO 17292 --- [http-nio-7777-exec-5] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 805 658 50\n","type":"touch"}
2022-07-13 17:32:39.616 - INFO 17292 --- [http-nio-7777-exec-6] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 690 669 50\n","type":"touch"}
2022-07-13 17:32:39.648 - INFO 17292 --- [http-nio-7777-exec-7] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 518 682 50\n","type":"touch"}
2022-07-13 17:32:39.683 - INFO 17292 --- [http-nio-7777-exec-8] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 394 690 50\n","type":"touch"}
2022-07-13 17:32:39.715 - INFO 17292 --- [http-nio-7777-exec-9] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 317 701 50\n","type":"touch"}
2022-07-13 17:32:39.750 - INFO 17292 --- [http-nio-7777-exec-10] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 284 701 50\n","type":"touch"}
2022-07-13 17:32:39.760 - INFO 17292 --- [http-nio-7777-exec-1] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"u 0\n","type":"touch"}
2022-07-13 17:32:40.526 - INFO 17292 --- [http-nio-7777-exec-3] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"d 0 784 913 50\n","type":"touch"}
2022-07-13 17:32:40.572 - INFO 17292 --- [http-nio-7777-exec-2] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 784 913 50\n","type":"touch"}
2022-07-13 17:32:40.598 - INFO 17292 --- [http-nio-7777-exec-4] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 746 921 50\n","type":"touch"}
2022-07-13 17:32:40.632 - INFO 17292 --- [http-nio-7777-exec-5] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 644 932 50\n","type":"touch"}
2022-07-13 17:32:40.666 - INFO 17292 --- [http-nio-7777-exec-6] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 505 937 50\n","type":"touch"}
2022-07-13 17:32:40.699 - INFO 17292 --- [http-nio-7777-exec-7] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 397 943 50\n","type":"touch"}
2022-07-13 17:32:40.709 - INFO 17292 --- [Device List Monitor] o.c.s.a.b.a.AndroidDeviceStatusListener : Android device: WTK7N16A10002895 OFFLINE！
2022-07-13 17:32:40.718 - INFO 17292 --- [Device List Monitor] o.c.s.a.b.a.AndroidDeviceBridgeTool : Send shell command wm size to device WTK7N16A10002895 failed.
2022-07-13 17:32:40.719 -ERROR 17292 --- [Device List Monitor] o.c.s.a.b.a.AndroidDeviceBridgeTool : device 'WTK7N16A10002895' not found
2022-07-13 17:32:40.719 - INFO 17292 --- [Device List Monitor] o.c.s.a.b.a.AndroidDeviceBridgeTool : Get screen size failed, ignore when plug in moment...
2022-07-13 17:32:40.721 - INFO 17292 --- [android-scrcpy-input-socket-task-0-0-WTK7N16A10002895] o.c.s.a.t.a.s.ScrcpyInputSocketThread : scrcpy video socket closed.
2022-07-13 17:32:40.726 - INFO 17292 --- [android-scrcpy-input-socket-task-0-0-WTK7N16A10002895] o.c.s.a.t.a.s.ScrcpyInputSocketThread : scrcpy input stream closed.
2022-07-13 17:32:40.734 - INFO 17292 --- [android-scrcpy-input-socket-task-0-0-WTK7N16A10002895] o.c.s.a.b.a.AndroidDeviceBridgeTool : cancel WTK7N16A10002895 device scrcpy port forward to 17653
2022-07-13 17:32:40.734 - INFO 17292 --- [http-nio-7777-exec-8] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 317 948 50\n","type":"touch"}
2022-07-13 17:32:40.743 -ERROR 17292 --- [android-scrcpy-input-socket-task-0-0-WTK7N16A10002895] o.c.s.a.b.a.AndroidDeviceBridgeTool :
2022-07-13 17:32:40.767 - INFO 17292 --- [http-nio-7777-exec-9] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 263 951 50\n","type":"touch"}
2022-07-13 17:32:40.783 - INFO 17292 --- [http-nio-7777-exec-10] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"m 0 236 951 50\n","type":"touch"}
2022-07-13 17:32:40.784 - INFO 17292 --- [http-nio-7777-exec-10] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"u 0\n","type":"touch"}
2022-07-13 17:32:41.228 - INFO 17292 --- [pool-3-thread-2] o.c.s.agent.websockets.AndroidWSServer : touch socket closed.
2022-07-13 17:32:41.229 - INFO 17292 --- [pool-3-thread-2] o.c.s.agent.websockets.AndroidWSServer : touch output stream closed.
2022-07-13 17:32:41.235 - INFO 17292 --- [pool-3-thread-2] o.c.s.a.b.a.AndroidDeviceBridgeTool : cancel WTK7N16A10002895 device sonictouchservice port forward to 17637
2022-07-13 17:32:41.247 -ERROR 17292 --- [pool-3-thread-2] o.c.s.a.b.a.AndroidDeviceBridgeTool :
2022-07-13 17:32:41.626 - INFO 17292 --- [Device List Monitor] o.c.s.a.b.a.AndroidDeviceStatusListener : Android device: WTK7N16A10002895 ONLINE！
2022-07-13 17:32:41.631 - INFO 17292 --- [http-nio-7777-exec-1] o.c.s.agent.websockets.AndroidWSServer : 0 send: {"detail":"d 0 891 1002 50\n","type":"touch"}
java.net.SocketException: Socket closed
2022-07-13 17:32:41.635 - INFO 17292 --- [Device List Monitor] o.c.s.a.b.a.AndroidDeviceBridgeTool : Send shell command wm size to device WTK7N16A10002895 failed.
at java.base/sun.nio.ch.NioSocketImpl.ensureOpenAndConnected(NioSocketImpl.java:165)
2022-07-13 17:32:41.642 -ERROR 17292 --- [Device List Monitor] o.c.s.a.b.a.AndroidDeviceBridgeTool : device offline
at java.base/sun.nio.ch.NioSocketImpl.beginWrite(NioSocketImpl.java:366)
2022-07-13 17:32:41.650 - INFO 17292 --- [Device List Monitor] o.c.s.a.b.a.AndroidDeviceBridgeTool : Get screen size failed, ignore when plug in moment...
at java.base/sun.nio.ch.NioSocketImpl.implWrite(NioSocketImpl.java:411)
at java.base/sun.nio.ch.NioSocketImpl.write(NioSocketImpl.java:440)
at java.base/sun.nio.ch.NioSocketImpl$2.write(NioSocketImpl.java:826)
at java.base/java.net.Socket$SocketOutputStream.write(Socket.java:1035)
at java.base/java.io.OutputStream.write(OutputStream.java:127)
at org.cloud.sonic.agent.websockets.AndroidWSServer.onMessage(AndroidWSServer.java:447)
at jdk.internal.reflect.GeneratedMethodAccessor28.invoke(Unknown Source)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:568)
at org.apache.tomcat.websocket.pojo.PojoMessageHandlerWholeBase.onMessage(PojoMessageHandlerWholeBase.java:104)
at org.apache.tomcat.websocket.WsFrameBase.sendMessageText(WsFrameBase.java:415)
at org.apache.tomcat.websocket.server.WsFrameServer.sendMessageText(WsFrameServer.java:129)
at org.apache.tomcat.websocket.WsFrameBase.processDataText(WsFrameBase.java:515)
at org.apache.tomcat.websocket.WsFrameBase.processData(WsFrameBase.java:301)
at org.apache.tomcat.websocket.WsFrameBase.processInputBuffer(WsFrameBase.java:133)
at org.apache.tomcat.websocket.server.WsFrameServer.onDataAvailable(WsFrameServer.java:85)
at org.apache.tomcat.websocket.server.WsFrameServer.doOnDataAvailable(WsFrameServer.java:183)
at org.apache.tomcat.websocket.server.WsFrameServer.notifyDataAvailable(WsFrameServer.java:162)
at org.apache.tomcat.websocket.server.WsHttpUpgradeHandler.upgradeDispatch(WsHttpUpgradeHandler.java:156)
at org.apache.coyote.http11.upgrade.UpgradeProcessorInternal.dispatch(UpgradeProcessorInternal.java:60)
at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:59)
at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:893)
at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1726)
at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
at java.base/java.lang.Thread.run(Thread.java:833)
