<!--
求求了!帮帮忙!花费30秒不到的时间填写一下以下信息,真的求求了。不填写模板无法审核。标题也补上版本号
-->
问题描述:晚上19:04分连某个设备debug,凌晨2:49分log提示设备退出(可能是超时机制?),早上来公司发现设备显示被占用中(实际无人占用),点强制解除,提示操作成功,实际接触占用失败。重启设备,仍旧解除占用失败。
Server版本:2.4.2
Agent版本与部署方式:2.4.2 jar
手机型号与系统版本:android 11
截图:
相关日志:
2023-05-24T19:04:02.500+08:00 INFO 26211 --- [http-nio-7777-exec-13] o.c.s.agent.websockets.AndroidWSServer : AndroidWSServer-af92317d send: {"detail":"down 412 1797\n","type":"touch"}
2023-05-24T19:04:02.520+08:00 INFO 26211 --- [http-nio-7777-exec-1] o.c.s.agent.websockets.AndroidWSServer : AndroidWSServer-af92317d send: {"detail":"up\n","type":"touch"}
2023-05-24T19:04:02.758+08:00 INFO 26211 --- [http-nio-7777-exec-4] o.c.s.agent.websockets.AndroidWSServer : AndroidWSServer-af92317d send: {"detail":"down 412 1797\n","type":"touch"}
2023-05-24T19:04:03.027+08:00 INFO 26211 --- [http-nio-7777-exec-8] o.c.s.agent.websockets.AndroidWSServer : AndroidWSServer-af92317d send: {"detail":"up\n","type":"touch"}
2023-05-24T19:04:03.027+08:00 INFO 26211 --- [http-nio-7777-exec-8] o.c.s.agent.websockets.AndroidWSServer : AndroidWSServer-af92317d send: {"detail":"down 412 1797\n","type":"touch"}
2023-05-24T19:04:03.027+08:00 INFO 26211 --- [http-nio-7777-exec-8] o.c.s.agent.websockets.AndroidWSServer : AndroidWSServer-af92317d send: {"detail":"up\n","type":"touch"}
2023-05-24T19:04:16.367+08:00 INFO 26211 --- [http-nio-7777-exec-5] o.c.s.agent.websockets.AndroidWSServer : AndroidWSServer-af92317d send: {"detail":"down 83 187\n","type":"touch"}
2023-05-24T19:04:16.674+08:00 INFO 26211 --- [http-nio-7777-exec-10] o.c.s.agent.websockets.AndroidWSServer : AndroidWSServer-af92317d send: {"detail":"up\n","type":"touch"}
2023-05-25T02:49:15.065+08:00 INFO 26211 --- [ios-battery-thread] org.cloud.sonic.agent.tests.LogUtil : {"msg":"step","des":"测试终止异常!请检查设备连接状态","log":"org.cloud.sonic.driver.common.tool.SonicRespException: ConnectException: Connection refused","time":"02:49:15","rid":0,"udId":"af92317d","status":3,"cid":0}
2023-05-25T02:49:15.182+08:00 INFO 26211 --- [ios-battery-thread] o.c.s.agent.websockets.AndroidWSServer : AndroidWSServer-af92317d : quit.
2023-05-25T02:49:15.182+08:00 INFO 26211 --- [ios-battery-thread] o.c.s.agent.websockets.AndroidWSServer : android unlock udId:af92317d
2023-05-25T02:49:15.182+08:00 INFO 26211 --- [ios-battery-thread] o.c.s.agent.websockets.AndroidWSServer : AndroidWSServer-af92317d : quit.
2023-05-25T02:49:15.182+08:00 INFO 26211 --- [Thread-1875] o.c.s.a.t.handlers.AndroidTouchHandler : error: sleep interrupted
2023-05-25T02:49:15.182+08:00 INFO 26211 --- [Thread-1875] o.c.s.a.t.handlers.AndroidTouchHandler : touch thread closed.
2023-05-25T02:49:15.183+08:00 INFO 26211 --- [Thread-1875] o.c.s.a.t.handlers.AndroidTouchHandler : touch socket closed.
2023-05-25T02:49:15.183+08:00 INFO 26211 --- [Thread-1875] o.c.s.a.t.handlers.AndroidTouchHandler : touch output stream closed.
2023-05-25T02:49:15.183+08:00 INFO 26211 --- [Thread-1875] o.c.s.a.b.a.AndroidDeviceBridgeTool : cancel af92317d device sonictouchservice port forward to 51798
2023-05-25T02:49:15.183+08:00 INFO 26211 --- [Thread-1874] o.c.s.a.t.handlers.AndroidTouchHandler : af92317d device touch service launch err
2023-05-25T02:49:15.183+08:00 ERROR 26211 --- [Thread-1874] o.c.s.a.t.handlers.AndroidTouchHandler : executeRemoteCommand interrupted with immediate timeout via interruption.
2023-05-25T02:49:15.934+08:00 INFO 26211 --- [ios-battery-thread] o.c.s.a.w.AndroidTerminalWSServer : time up!
2023-05-25T02:49:15.935+08:00 ERROR 26211 --- [ios-battery-thread] o.a.t.websocket.pojo.PojoEndpointBase : Failed to call onClose method of POJO end point for POJO of type [org.cloud.sonic.agent.websockets.AndroidTerminalWSServer]
java.lang.reflect.InvocationTargetException: null
at jdk.internal.reflect.GeneratedMethodAccessor30.invoke(Unknown Source) ~[na:na]
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:na]
at java.base/java.lang.reflect.Method.invoke(Method.java:568) ~[na:na]
at org.apache.tomcat.websocket.pojo.PojoEndpointBase.onClose(PojoEndpointBase.java:107) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.WsSession.fireEndpointOnClose(WsSession.java:674) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.WsSession.doClose(WsSession.java:622) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.WsSession.doClose(WsSession.java:572) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.WsSession.close(WsSession.java:559) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.WsSession.close(WsSession.java:553) ~[sonic-agent-macosx-x86_64.jar:na]
at org.cloud.sonic.agent.websockets.AndroidTerminalWSServer.exit(AndroidTerminalWSServer.java:293) ~[sonic-agent-macosx-x86_64.jar:na]
at org.cloud.sonic.agent.websockets.AndroidTerminalWSServer.lambda$onOpen$2(AndroidTerminalWSServer.java:116) ~[sonic-agent-macosx-x86_64.jar:na]
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) ~[na:na]
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) ~[na:na]
at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) ~[na:na]
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) ~[na:na]
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) ~[na:na]
at java.base/java.lang.Thread.run(Thread.java:833) ~[na:na]
Caused by: java.lang.NullPointerException: Cannot invoke "java.util.concurrent.Future.isDone()" because "cmd" is null
at org.cloud.sonic.agent.websockets.AndroidTerminalWSServer.exit(AndroidTerminalWSServer.java:273) ~[sonic-agent-macosx-x86_64.jar:na]
at org.cloud.sonic.agent.websockets.AndroidTerminalWSServer.onClose(AndroidTerminalWSServer.java:257) ~[sonic-agent-macosx-x86_64.jar:na]
... 17 common frames omitted
2023-05-25T02:49:15.937+08:00 ERROR 26211 --- [ios-battery-thread] o.c.s.a.w.AndroidTerminalWSServer : null
2023-05-25T02:49:15.937+08:00 INFO 26211 --- [ios-battery-thread] o.c.s.a.w.AndroidTerminalWSServer : AndroidTerminalWSServer-af92317d : quit.
2023-05-25T02:49:15.978+08:00 INFO 26211 --- [Thread-1883] o.c.s.a.w.AndroidTerminalWSServer : manager socket closed.
2023-05-25T02:49:15.978+08:00 INFO 26211 --- [Thread-1883] o.c.s.a.b.a.AndroidDeviceBridgeTool : cancel af92317d device 2334 forward to 51822
2023-05-25T02:49:15.978+08:00 ERROR 26211 --- [Thread-1883] o.c.s.a.b.a.AndroidDeviceBridgeTool : null
2023-05-25T02:49:15.978+08:00 INFO 26211 --- [Thread-1883] o.c.s.a.w.AndroidTerminalWSServer : manager done.
2023-05-25T02:49:16.231+08:00 INFO 26211 --- [transport-connection-thread] o.c.s.a.w.AndroidScreenWSServer : time up!
2023-05-25T02:49:19.908+08:00 ERROR 26211 --- [transport-connection-thread] org.cloud.sonic.agent.tools.BytesTool : WebSocket send msg error...connection has been closed.
2023-05-25T02:49:19.908+08:00 INFO 26211 --- [transport-connection-thread] o.c.s.a.w.AndroidScreenWSServer : AndroidScreenWSServer-af92317d : quit.
2023-05-25T02:49:19.908+08:00 INFO 26211 --- [android-scrcpy-start-scrcpy-server-task-0-0-af92317d] o.c.s.a.t.a.scrcpy.ScrcpyLocalThread : af92317d scrcpy service stopped.
2023-05-25T02:49:19.908+08:00 ERROR 26211 --- [android-scrcpy-start-scrcpy-server-task-0-0-af92317d] o.c.s.a.t.a.scrcpy.ScrcpyLocalThread : executeRemoteCommand interrupted with immediate timeout via interruption.
2023-05-25T02:49:19.908+08:00 INFO 26211 --- [Thread-1877] o.c.s.a.t.h.AndroidMonitorHandler : af92317d rotation service stopped.
2023-05-25T02:49:19.908+08:00 ERROR 26211 --- [Thread-1877] o.c.s.a.t.h.AndroidMonitorHandler : executeRemoteCommand interrupted with immediate timeout via interruption.
2023-05-25T02:49:19.908+08:00 ERROR 26211 --- [http-nio-7777-exec-5] o.a.t.websocket.pojo.PojoEndpointBase : Failed to call onClose method of POJO end point for POJO of type [org.cloud.sonic.agent.websockets.AndroidScreenWSServer]
java.lang.reflect.InvocationTargetException: null
at jdk.internal.reflect.GeneratedMethodAccessor29.invoke(Unknown Source) ~[na:na]
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:na]
at java.base/java.lang.reflect.Method.invoke(Method.java:568) ~[na:na]
at org.apache.tomcat.websocket.pojo.PojoEndpointBase.onClose(PojoEndpointBase.java:107) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.WsSession.fireEndpointOnClose(WsSession.java:674) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.WsSession.onClose(WsSession.java:655) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.WsFrameBase.processDataControl(WsFrameBase.java:367) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.WsFrameBase.processData(WsFrameBase.java:296) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.WsFrameBase.processInputBuffer(WsFrameBase.java:133) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.server.WsFrameServer.onDataAvailable(WsFrameServer.java:85) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.server.WsFrameServer.doOnDataAvailable(WsFrameServer.java:185) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.server.WsFrameServer.notifyDataAvailable(WsFrameServer.java:164) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.websocket.server.WsHttpUpgradeHandler.upgradeDispatch(WsHttpUpgradeHandler.java:157) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.coyote.http11.upgrade.UpgradeProcessorInternal.dispatch(UpgradeProcessorInternal.java:60) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:57) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:894) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1741) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659) ~[sonic-agent-macosx-x86_64.jar:na]
at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) ~[sonic-agent-macosx-x86_64.jar:na]
at java.base/java.lang.Thread.run(Thread.java:833) ~[na:na]
Caused by: java.lang.NullPointerException: Cannot invoke "com.android.ddmlib.IDevice.getSerialNumber()" because "iDevice" is null
at org.cloud.sonic.agent.tests.handlers.AndroidMonitorHandler.stopMonitor(AndroidMonitorHandler.java:96) ~[sonic-agent-macosx-x86_64.jar:na]
at org.cloud.sonic.agent.websockets.AndroidScreenWSServer.exit(AndroidScreenWSServer.java:187) ~[sonic-agent-macosx-x86_64.jar:na]
at org.cloud.sonic.agent.websockets.AndroidScreenWSServer.onClose(AndroidScreenWSServer.java:105) ~[sonic-agent-macosx-x86_64.jar:na]
... 22 common frames omitted
2023-05-25T02:49:19.909+08:00 ERROR 26211 --- [http-nio-7777-exec-5] o.c.s.a.w.AndroidScreenWSServer : null
2023-05-25T02:49:20.957+08:00 INFO 26211 --- [android-scrcpy-input-socket-task-0-0-af92317d] o.c.s.a.t.a.s.ScrcpyInputSocketThread : scrcpy video socket closed.
2023-05-25T02:49:20.958+08:00 INFO 26211 --- [android-scrcpy-input-socket-task-0-0-af92317d] o.c.s.a.t.a.s.ScrcpyInputSocketThread : scrcpy input stream closed.
2023-05-25T02:49:20.958+08:00 INFO 26211 --- [android-scrcpy-input-socket-task-0-0-af92317d] o.c.s.a.b.a.AndroidDeviceBridgeTool : cancel af92317d device scrcpy port forward to 51807
2023-05-25T10:14:54.947+08:00 INFO 26211 --- [WebSocketConnectReadThread-57] o.c.s.agent.transport.TransportClient : Agent <- Server message: {"msg":"stopDebug","udId":"af92317d"}
2023-05-25T10:17:11.468+08:00 INFO 26211 --- [WebSocketConnectReadThread-57] o.c.s.agent.transport.TransportClient : Agent <- Server message: {"msg":"stopDebug","udId":"af92317d"}
2023-05-25T10:20:07.269+08:00 INFO 26211 --- [WebSocketConnectReadThread-57] o.c.s.agent.transport.TransportClient : Agent <- Server message: {"msg":"stopDebug","udId":"af92317d"}
2023-05-25T10:21:09.971+08:00 INFO 26211 --- [WebSocketConnectReadThread-57] o.c.s.agent.transport.TransportClient : Agent <- Server message: {"msg":"stopDebug","udId":"af92317d"}
2023-05-25T10:21:27.882+08:00 INFO 26211 --- [WebSocketConnectReadThread-57] o.c.s.agent.transport.TransportClient : Agent <- Server message: {"msg":"stopDebug","udId":"af92317d"}
2023-05-25T10:23:12.726+08:00 INFO 26211 --- [WebSocketConnectReadThread-57] o.c.s.agent.transport.TransportClient : Agent <- Server message: {"msg":"stopDebug","udId":"af92317d"}