Tencent / mars

Mars is a cross-platform network component developed by WeChat.
Other
17.26k stars 3.63k forks source link

app为厂商白名单运行在后台的时候,几分钟后就无法建立连接了。但是微信可以 #586

Open byron-xie opened 5 years ago

byron-xie commented 5 years ago

O [nioEventLoopGroup-3-1] (NetMsgHeaderHandler.java:143) - client resp, cmdId=3, seq=555, resp.len=41 com.tencent.mars.proxy.NetMsgHeader$InvalidHeaderException: invalid client version in header, clientVersion: 32 packlen: 1869309869 at com.tencent.mars.proxy.NetMsgHeader.decode(NetMsgHeader.java:76) at com.tencent.mars.proxy.NetMsgHeaderHandler.channelRead(NetMsgHeaderHandler.java:87) at io.netty.channel.DefaultChannelHandlerContext.invokeChannelRead(DefaultChannelHandlerContext.java:368) at io.netty.channel.DefaultChannelHandlerContext.fireChannelRead(DefaultChannelHandlerContext.java:353) at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:780) at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:100) at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:497) at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:465) at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:359) at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:101) at java.lang.Thread.run(Thread.java:748) INFO [Timer-17] (NetMsgHeaderHandler.java:196) - check longlink alive per 15 minutes, com.tencent.mars.proxy.NetMsgHeaderHandler$ContextTimeoutChecker@55d0b39 java.io.IOException: 远程主机强迫关闭了一个现有的连接。 at sun.nio.ch.SocketDispatcher.read0(Native Method) at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:43) at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:223) at sun.nio.ch.IOUtil.read(IOUtil.java:192) at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:380) at io.netty.buffer.UnpooledUnsafeDirectByteBuf.setBytes(UnpooledUnsafeDirectByteBuf.java:401) at io.netty.buffer.AbstractByteBuf.writeBytes(AbstractByteBuf.java:867) at io.netty.channel.socket.nio.NioSocketChannel.doReadBytes(NioSocketChannel.java:227) at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:87) at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:497) at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:465) at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:359) at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:101) at java.lang.Thread.run(Thread.java:748)

Building 85% > :server:nettyRun

byron-xie commented 5 years ago

只有在不充电,熄屏,待机的情况下才会出此问题

byron-xie commented 5 years ago

也给微信加了白名单

garryyan commented 5 years ago

分析下客户端的日志,看为什么关闭了 socket

byron-xie commented 5 years ago

分析下客户端的日志,看为什么关闭了 socket 稍等哈 从日志上来看就是心跳超时了 socket io和mqtt 都存在这个问题,(非充电情况下待机息屏必现

byron-xie commented 5 years ago

分析下客户端的日志,看为什么关闭了 socket 怎么过滤有效的日志 我看下

byron-xie commented 5 years ago

分析下客户端的日志,看为什么关闭了 socket

从目前分析的日志来看 白名单后 手机息屏后(非充电情况下待机息屏必现),无法正常心跳,然后属于阻塞状态,等再次亮屏的时候,就会立刻断了链接,重新建立连接,

byron-xie commented 5 years ago

server端: INFO [nioEventLoopGroup-3-5] (NetMsgHeaderHandler.java:143) - client resp, cmdId=3, seq=9, resp.len=55 INFO [Timer-1] (NetMsgHeaderHandler.java:196) - check longlink alive per 15 minutes, com.tencent.mars.proxy.NetMsgHeaderHandler$ContextTimeoutChecker@6b4c9999 INFO [Timer-2] (NetMsgHeaderHandler.java:196) **- check longlink alive per 15 minutes,** com.tencent.mars.proxy.NetMsgHeaderHandler$ContextTimeoutChecker@78fd5be

Building 85% > :server:nettyRun

手机这时候亮屏 后: rHandler$ContextTimeoutChecker@78fd5be java.io.IOException: 远程主机强迫关闭了一个现有的连接。 at sun.nio.ch.SocketDispatcher.read0(Native Method) at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:43) at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:223) at sun.nio.ch.IOUtil.read(IOUtil.java:192) at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:380) at io.netty.buffer.UnpooledUnsafeDirectByteBuf.setBytes(UnpooledUnsafeDirectByteBuf.java:401) at io.netty.buffer.AbstractByteBuf.writeBytes(AbstractByteBuf.java:867) at io.netty.channel.socket.nio.NioSocketChannel.doReadBytes(NioSocketChannel.java:227) at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:87) at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:497) at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:465) at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:359) at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:101) at java.lang.Thread.run(Thread.java:748) INFO [nioEventLoopGroup-3-6] (NetMsgHeaderHandler.java:76) - client connected! io.netty.channel.DefaultChannelHandlerContext@109c8c6a DEBUG [nioEventLoopGr

byron-xie commented 5 years ago

分析下客户端的日志,看为什么关闭了 socket

客户端 主要日志,非充电待机息屏必现 2019-04-17 11:18:46.670 8470-8500/com.tencent.mars.sample:marsservice E/mars::stn: [longlink.cc, __RunReadWrite, 570]:task socket close sock:58, excptoin:103(Software caused connection abort), nread:0, nwrite:0, net_type:"CloudMinds"task socket close getsocktcpinfo:tcpi_state=0x7, tcpi_ca_state=0x0, tcpi_retransmits=0x0, tcpi_probes=0x0, tcpi_backoff=0x0, tcpi_options=0x6, tcpi_rto=0x35B60, tcpi_snd_mss=0x578, tcpi_rcv_mss=0x218, tcpi_unacked=0x0, tcpi_sacked=0x0, tcpi_lost=0x0, tcpi_retrans=0x0, tcpi_fackets=0x0, tcpi_last_data_sent=0xC38C, tcpi_last_ack_sent=0x0, tcpi_last_data_recv=0xC36E, tcpi_last_ack_recv=0xC36E, tcpi_pmtu=0x5DC, tcpi_rcv_ssthresh=0x14820, tcpi_rtt=0x3E59, tcpi_rttvar=0x2CC0, tcpi_snd_ssthresh=0x578, tcpi_snd_cwnd=0xA, tcpi_advmss=0x578, tcpi_reordering=0x3, tcpi_rcv_rtt=0x0, tcpi_rcv_space=0x14820, tcpi_total_retrans=0x

byron-xie commented 5 years ago

分析下客户端的日志,看为什么关闭了 socket

然后找个是全部日志 后台就连不上了

2019-04-17 11:15:21.106 8470-8491/com.tencent.mars.sample:marsservice I/mars::baseevent: [active_logic.cc, OnForeground, 72]:OnForeground:true, change:false, 2019-04-17 11:15:34.508 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout 2019-04-17 11:15:34.509 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x1F56, _timeout= 500 2019-04-17 11:15:34.511 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2, 2019-04-17 11:15:41.166 8470-8483/com.tencent.mars.sample:marsservice I/Mars.Sample.MarsServiceStub: [, , 0]:now start task with id 3 2019-04-17 11:15:41.166 8470-8483/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_Java2C.cc, Java_com_tencent_mars_stn_StnLogic_startTask, 159]:-> Java_com_tencent_mars_stn_StnLogic_startTask 2019-04-17 11:15:41.167 8470-8483/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_Java2C.cc, Java_com_tencent_mars_stn_StnLogic_startTask, 159]:<- Java_com_tencent_mars_stn_StnLogic_startTask +0, 2019-04-17 11:15:41.167 8470-8483/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_Java2C.cc, Java_com_tencent_mars_stn_StnLogic_hasTask, 250]:-> Java_com_tencent_mars_stn_StnLogic_hasTask 2019-04-17 11:15:41.167 8470-8493/com.tencent.mars.sample:marsservice I/mars::stn: [net_core.cc, operator, 298]:task start long short taskid:3, cmdid:3, need_authed:true, cgi:/mars/sendmessage, channel_select:2, limit_flow:true, host:10.11.99.160, send_only:false, cmdid:3, server_process_cost:0, retrycount:-1, channel_strategy:0, total_timetout:0, network_status_sensitive:false, priority:3, report_arg: 2019-04-17 11:15:41.167 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [longlink_task_manager.cc, StartTask, 83]:-> StartTask 2019-04-17 11:15:41.167 8470-8493/com.tencent.mars.sample:marsservice D/mars::stn: [longlink_task_manager.cc, StartTask, 84]:taskid=3 2019-04-17 11:15:41.168 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 198]:-> operator() 2019-04-17 11:15:41.168 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 198]:<- operator() +0, 2019-04-17 11:15:41.168 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 119]:-> operator() 2019-04-17 11:15:41.171 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 119]:<- operator() +2, 2019-04-17 11:15:41.171 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [anti_avalanche.cc, Check, 45]:-> Check 2019-04-17 11:15:41.171 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [frequency_limit.cc, Check, 46]:-> Check 2019-04-17 11:15:41.171 8470-8493/com.tencent.mars.sample:marsservice D/mars::stn: [frequency_limit.cc, Check, 78]:InsertRecord Task Info: ptr=0xE1BDC288, cmdid=3, need_authed=true, cgi:/mars/sendmessage, channel_select=2, limit_flow=true 2019-04-17 11:15:41.171 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [frequency_limit.cc, Check, 46]:<- Check +0, 2019-04-17 11:15:41.171 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:15:41.172 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:15:41.172 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [anti_avalanche.cc, Check, 45]:<- Check +0, 2019-04-17 11:15:41.172 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:15:41.172 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:15:41.172 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:15:41.172 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:15:41.172 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:15:41.172 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:15:41.172 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:15:41.173 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:15:41.173 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:15:41.173 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:15:41.173 8470-8493/com.tencent.mars.sample:marsservice I/mars::stn: [longlink_task_manager.cc, RunOnStartTask, 377]:task add into longlink readwrite suc cgi:/mars/sendmessage, cmdid:3, taskid:3, size:68, timeout(firstpkg:12, rw:17, task:40), retry:1, curtime:80487902, start_send_time:80487901, 2019-04-17 11:15:41.173 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout 2019-04-17 11:15:41.173 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [socketbreaker.cc, Clear, 129]:Ret=-1 2019-04-17 11:15:41.173 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x1F56, _timeout= 30000 2019-04-17 11:15:41.174 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, stopAlarm, 64]:-> stopAlarm 2019-04-17 11:15:41.175 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +1, 2019-04-17 11:15:41.175 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [longlink_task_manager.cc, StartTask, 83]:<- StartTask +7, 2019-04-17 11:15:41.175 8470-8500/com.tencent.mars.sample:marsservice D/mars::comm: [platform_comm.cc, stopAlarm, 72]:id= 6, ret= true 2019-04-17 11:15:41.175 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [longlink_task_manager.cc, HasTask, 118]:-> HasTask 2019-04-17 11:15:41.175 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, stopAlarm, 64]:<- stopAlarm +1, 2019-04-17 11:15:41.175 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [longlink_task_manager.cc, HasTask, 118]:<- HasTask +0, 2019-04-17 11:15:41.175 8470-8500/com.tencent.mars.sample:marsservice I/mars::comm: [alarm.cc, Cancel, 94]:alarm cancel id:3480218736, seq:6, after:210000 2019-04-17 11:15:41.175 8470-8483/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_Java2C.cc, Java_com_tencent_mars_stn_StnLogic_hasTask, 250]:<- Java_com_tencent_mars_stn_StnLogic_hasTask +8, 2019-04-17 11:15:41.176 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm 2019-04-17 11:15:41.176 8470-8483/com.tencent.mars.sample:marsservice I/Mars.Sample.MarsServiceStub: [, , 0]:stn task started with id 3 2019-04-17 11:15:41.177 8470-8500/com.tencent.mars.sample:marsservice D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 7, after= 210000, ret= true 2019-04-17 11:15:41.178 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +1, 2019-04-17 11:15:41.178 8470-8500/com.tencent.mars.sample:marsservice I/mars::comm: [alarm.cc, Start, 68]:alarm id:3480218736, after:210000, seq:7, po.reg.q:18446744072900589936,po.reg.s:0,po.s:66 2019-04-17 11:15:41.178 8470-8500/com.tencent.mars.sample:marsservice I/Mars.Sample.MarsServiceProxy: [, , 0]:no push message listener set for cmdid = 10002, just ignored 2019-04-17 11:15:41.180 8470-8500/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, RunReadWrite, 662]:task socket send sock:58, all send:88, count:1, sub send taskid:3, cmdid:3, /mars/sendmessage, len(S:88, 88/88), 2019-04-17 11:15:41.180 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [longlink_task_manager.cc, OnSend, 609]:-> OnSend 2019-04-17 11:15:41.180 8470-8493/com.tencent.mars.sample:marsservice D/mars::stn: [longlink_task_manager.cc, OnSend, 617]:taskid:3, starttime:80487 2019-04-17 11:15:41.180 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [longlink_task_manager.cc, OnSend, 609]:<- OnSend +0, 2019-04-17 11:15:46.337 8470-8478/com.tencent.mars.sample:marsservice I/zygote: Debugger is no longer active 2019-04-17 11:18:46.669 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [socketbreaker.cc, Clear, 129]:Ret=-1 2019-04-17 11:18:46.670 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:46.670 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:46.670 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:-> getCurWifiInfo 2019-04-17 11:18:46.670 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:<- getCurWifiInfo +0, 2019-04-17 11:18:46.670 8470-8500/com.tencent.mars.sample:marsservice E/mars::stn: [longlink.cc, RunReadWrite, 570]:task socket close sock:58, excptoin:103(Software caused connection abort), nread:0, nwrite:0, net_type:"CloudMinds"task socket close getsocktcpinfo:tcpi_state=0x7, tcpi_ca_state=0x0, tcpi_retransmits=0x0, tcpi_probes=0x0, tcpi_backoff=0x0, tcpi_options=0x6, tcpi_rto=0x35B60, tcpi_snd_mss=0x578, tcpi_rcv_mss=0x218, tcpi_unacked=0x0, tcpi_sacked=0x0, tcpi_lost=0x0, tcpi_retrans=0x0, tcpi_fackets=0x0, tcpi_last_data_sent=0xC38C, tcpi_last_ack_sent=0x0, tcpi_last_data_recv=0xC36E, tcpi_last_ack_recv=0xC36E, tcpi_pmtu=0x5DC, tcpi_rcv_ssthresh=0x14820, tcpi_rtt=0x3E59, tcpi_rttvar=0x2CC0, tcpi_snd_ssthresh=0x578, tcpi_snd_cwnd=0xA, tcpi_advmss=0x578, tcpi_reordering=0x3, tcpi_rcv_rtt=0x0, tcpi_rcv_space=0x14820, tcpi_total_retrans=0x 2019-04-17 11:18:46.671 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, stopAlarm, 64]:-> stopAlarm 2019-04-17 11:18:46.684 8470-8500/com.tencent.mars.sample:marsservice D/mars::comm: [platform_comm.cc, stopAlarm, 72]:id= 8, ret= true 2019-04-17 11:18:46.684 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, stopAlarm, 64]:<- stopAlarm +13, 2019-04-17 11:18:46.685 8470-8500/com.tencent.mars.sample:marsservice I/mars::comm: [alarm.cc, Cancel, 94]:alarm cancel id:3480218736, seq:8, after:210000 2019-04-17 11:18:46.685 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:46.685 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:46.685 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getSignal, 387]:-> getSignal 2019-04-17 11:18:46.688 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getSignal, 397]:Signal Strength= 90, wifi:true 2019-04-17 11:18:46.688 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getSignal, 387]:<- getSignal +2, 2019-04-17 11:18:46.688 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:46.688 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:46.688 8470-8500/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, ConnectStatus, 349]:connect status from:2 to:3, nettype:1 2019-04-17 11:18:46.689 8470-8500/com.tencent.mars.sample:marsservice I/mars::stn: [smart_heartbeat.cc, OnLongLinkDisconnect, 82]:-> OnLongLinkDisconnect 2019-04-17 11:18:46.689 8470-8500/com.tencent.mars.sample:marsservice I/mars::stn: [smart_heartbeat.cc, OnLongLinkDisconnect, 87]:"CloudMinds" not stable last heart:210000 2019-04-17 11:18:46.690 8470-8500/com.tencent.mars.sample:marsservice I/mars::stn: [smart_heartbeat.cc, OnLongLinkDisconnect, 82]:<- OnLongLinkDisconnect +1, 2019-04-17 11:18:46.691 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm 2019-04-17 11:18:46.692 8470-8500/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, Run, 418]:longlink lifetime:217126 2019-04-17 11:18:46.693 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout 2019-04-17 11:18:46.693 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x1F46, _timeout= 1000 2019-04-17 11:18:46.695 8470-8500/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2, 2019-04-17 11:18:46.696 8470-8500/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, Run, 379]:<- Run +217199, 2019-04-17 11:18:46.709 8470-8493/com.tencent.mars.sample:marsservice D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 9, after= 500, ret= true 2019-04-17 11:18:46.710 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +18, 2019-04-17 11:18:46.710 8470-8493/com.tencent.mars.sample:marsservice I/mars::comm: [alarm.cc, Start, 68]:alarm id:3784130592, after:500, seq:9, po.reg.q:18446744072900589936,po.reg.s:0,po.s:77 2019-04-17 11:18:46.710 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:46.711 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:46.711 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [timing_sync.cc, OnLongLinkStatuChanged, 96]:-> OnLongLinkStatuChanged 2019-04-17 11:18:46.711 8470-8493/com.tencent.mars.sample:marsservice V/mars::app: [com_tencent_mars_app_AppLogic_C2Java.cc, GetAccountInfo, 72]:-> GetAccountInfo 2019-04-17 11:18:46.711 8470-8493/com.tencent.mars.sample:marsservice V/mars::app: [com_tencent_mars_app_AppLogic_C2Java.cc, GetAccountInfo, 72]:<- GetAccountInfo +0, 2019-04-17 11:18:46.712 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:46.712 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:46.712 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm 2019-04-17 11:18:46.721 8470-8493/com.tencent.mars.sample:marsservice D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 10, after= 240000, ret= true 2019-04-17 11:18:46.722 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +9, 2019-04-17 11:18:46.722 8470-8493/com.tencent.mars.sample:marsservice I/mars::comm: [alarm.cc, Start, 68]:alarm id:3739787200, after:240000, seq:10, po.reg.q:18446744072900589936,po.reg.s:0,po.s:81 2019-04-17 11:18:46.722 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [timing_sync.cc, OnLongLinkStatuChanged, 96]:<- OnLongLinkStatuChanged +11, 2019-04-17 11:18:46.723 8470-8493/com.tencent.mars.sample:marsservice W/mars::stn: [longlink_task_manager.cc, OnResponse, 538]:task error, taskid:0, cmdid:0, error_type:4, error_code:103 2019-04-17 11:18:46.724 8470-8493/com.tencent.mars.sample:marsservice D/mars::stn: [net_check_logic.cc, UpdateLongLinkInfo, 118]:shortlink:_continueFailCount=0, _isTaskSucc=0, records=0xfffffffe 2019-04-17 11:18:46.724 8470-8493/com.tencent.mars.sample:marsservice I/mars::stn: [net_source.cc, ReportLongIP, 240]:_is_success=false, ip=10.11.99.160, port=8081 2019-04-17 11:18:46.724 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:46.724 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:46.724 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:46.724 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:46.725 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:-> getCurWifiInfo 2019-04-17 11:18:46.725 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:<- getCurWifiInfo +0, 2019-04-17 11:18:47.194 8470-8470/com.tencent.mars.sample:marsservice I/MicroMsg.Alarm: [, , 0]:onReceive id=9, curId=9 2019-04-17 11:18:47.195 8470-8470/com.tencent.mars.sample:marsservice I/MicroMsg.Alarm: [, , 0]:onReceive find alarm id:9, pid:8470, delta miss time:3 2019-04-17 11:18:47.197 8470-8470/com.tencent.mars.sample:marsservice D/mars::comm: [OnAlarm.inl, Java_com_tencent_mars_comm_Alarm_onAlarm, 13]:BroadcastMessage seq:9 2019-04-17 11:18:47.198 8470-8493/com.tencent.mars.sample:marsservice I/mars::comm: [alarm.cc, OnAlarm, 135]:OnAlarm id:3784130592, seq:9, elapsed:507, after:500, miss:7, android alarm:false, runing 2019-04-17 11:18:47.200 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [socketbreaker.cc, Clear, 129]:Ret=-1 2019-04-17 11:18:47.200 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [socketbreaker.cc, Clear, 129]:Ret=-1 2019-04-17 11:18:47.200 8470-8493/com.tencent.mars.sample:marsservice I/mars::stn: [longlink_connect_monitor.cc, IntervalConnect, 173]:made interval connect interval:30000, posttime:217701, newone:true, connectstatus:0, ret:false 2019-04-17 11:18:47.200 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:47.201 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:47.201 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, Run, 379]:-> __Run LongLink Rebuild span:0, net:1 2019-04-17 11:18:47.201 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:47.201 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:47.201 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:-> getCurWifiInfo 2019-04-17 11:18:47.201 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:<- getCurWifiInfo +0, 2019-04-17 11:18:47.202 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout 2019-04-17 11:18:47.202 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x1F46, _timeout= 40000 2019-04-17 11:18:47.203 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +1, 2019-04-17 11:18:47.204 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:47.204 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:47.204 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, ConnectStatus, 349]:connect status from:0 to:1, nettype:1 2019-04-17 11:18:47.204 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [net_source.cc, GetLongLinkItems, 181]:-> GetLongLinkItems 2019-04-17 11:18:47.204 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:47.205 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [dns.cc, GetHostByName, 185]:-> GetHostByName 2019-04-17 11:18:47.205 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:47.205 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [timing_sync.cc, OnLongLinkStatuChanged, 96]:-> OnLongLinkStatuChanged 2019-04-17 11:18:47.205 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [timing_sync.cc, OnLongLinkStatuChanged, 96]:<- OnLongLinkStatuChanged +0, 2019-04-17 11:18:47.205 8470-8493/com.tencent.mars.sample:marsservice I/mars::stn: [net_core.cc, ConnStatusCallBack, 706]:reportNetConnectInfo all_connstatus:3, longlink_connstatus:3 2019-04-17 11:18:47.205 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 333]:-> operator() 2019-04-17 11:18:47.205 8470-9256/com.tencent.mars.sample:marsservice V/mars::comm: [dns.cc, GetIP, 59]:-> GetIP 2019-04-17 11:18:47.205 8470-9256/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 87]:-> operator() 2019-04-17 11:18:47.206 8470-8493/com.tencent.mars.sample:marsservice D/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 339]:all_connstatus = 3, longlink_connstatus = 3 2019-04-17 11:18:47.206 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 333]:<- operator() +0, 2019-04-17 11:18:47.206 8470-9256/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 87]:<- operator() +0, 2019-04-17 11:18:47.206 8470-9256/com.tencent.mars.sample:marsservice V/mars::comm: [dns.cc, GetIP, 59]:<- GetIP +1, 2019-04-17 11:18:47.207 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [dns.cc, GetHostByName, 266]:dns get ip status:4 host:10.11.99.160, func:true 2019-04-17 11:18:47.207 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [dns.cc, GetHostByName, 185]:<- GetHostByName +2, 2019-04-17 11:18:47.207 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [dns.cc, GetHostByName, 185]:-> GetHostByName 2019-04-17 11:18:47.207 8470-9257/com.tencent.mars.sample:marsservice V/mars::comm: [dns.cc, GetIP, 59]:-> GetIP 2019-04-17 11:18:47.211 8470-9257/com.tencent.mars.sample:marsservice V/mars::comm: [dns.cc, GetIP, 59]:<- GetIP +3, 2019-04-17 11:18:47.212 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [dns.cc, GetHostByName, 185]:<- GetHostByName +4, 2019-04-17 11:18:47.212 8470-9255/com.tencent.mars.sample:marsservice D/mars::stn: [net_source.cc, MakeIPPorts, 371]:link host:10.11.99.160, new dns ret:false, size:0 dns ret:true, size:1, 2019-04-17 11:18:47.212 8470-9255/com.tencent.mars.sample:marsservice D/mars::stn: [net_source.cc, MakeIPPorts, 400]:link host:10.11.99.160, backup ips size:1 2019-04-17 11:18:47.213 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [net_source.cc, GetLongLinkItems, 181]:<- GetLongLinkItems +8, 2019-04-17 11:18:47.213 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, RunConnect, 443]:task socket dns ip:10.11.99.160:8081:10.11.99.160:DNSIP|10.11.99.160:8081:10.11.99.160:BackupIP proxytype:0 useproxy:false 2019-04-17 11:18:47.213 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:47.213 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:47.213 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:-> getCurWifiInfo 2019-04-17 11:18:47.213 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:<- getCurWifiInfo +0, 2019-04-17 11:18:47.220 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [local_routetable.cc, get_local_route_table, 20]:popen cmd=ip route list table all 2019-04-17 11:18:47.277 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, RunConnect, 449]:ipstack log:defaultgateway6:failed defaultgateway: failed dns server0:AF_INET, 10.11.35.35 dns server1:AF_INET, 114.114.114.114 interface name:wlan0, AF_INET, ip:10.11.100.196 interface name:dummy0, AF_INET6, ip:fe80::a440:80ff:fe6a:d2c3 interface name:rmnet_data0, AF_INET6, ip:fe80::872e:6e54:86fa:cedf interface name:wlan0, AF_INET6, ip:fe80::993e:1352:39ba:21e6 have_ipv4:1 have_ipv6:0default dev dummy0 table 1003 proto static scope link 10.11.100.0/24 dev wlan0 proto kernel scope link src 10.11.100.196 default via 10.11.100.254 dev wlan0 table 1023 proto static 10.11.100.0/24 dev wlan0 table 1023 proto static scope link broadcast 10.11.100.0 dev wlan0 table local proto kernel scope link src 10.11.100.196 local 10.11.100.196 dev wlan0 table local proto kernel scope host src 10.11.100.196 broadcast 10.11.100.255 dev wlan0 table local proto kernel scope link src 10.11.100.196 broadcast 127.0.0.0 dev lo table local proto kernel scope link src 127.0.0.1 local 127.0.0.0/8 dev lo table local proto kernel scope host src 127.0.0.1 local 127.0.0.1 dev lo table local proto kernel scope host src 127.0.0.1 broadcast 127.255.255.255 dev lo table local proto kernel scope link src 127.0.0.1 fe80::/64 dev dummy0 table 1003 proto kernel metric 256 pref medium default dev dummy0 table 1003 proto static metric 1024 pref medium fe80::/64 dev rmnet_data0 table 1006 proto kernel metric 256 mtu 2000 pref medium default via fe80::1453:ac01:85d6:61a9 dev rmnet_data0 table 1006 proto ra metric 1024 expires 44180sec hoplimit 255 pref medium fe80::/64 dev wlan0 table 1023 proto kernel metric 256 pref medium fe80::/64 dev wlan0 table 1023 proto static metric 1024 pref medium local ::1 dev lo table local proto none metric 0 pref medium local fe80::872e:6e54:86fa:cedf dev lo table local proto none metric 0 pref medium local fe80::993e:1352:39ba:21e6 dev lo table local proto none metric 0 pref medium local fe80::a440:80ff:f 2019-04-17 11:18:47.278 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [complexconnect.cc, ConnectImpatient, 455]:_vecaddr size:2, m_timeout:10000, m_interval:4000, m_error_interval:4000, m_max_connect:3, @0xD0213C48 2019-04-17 11:18:47.279 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [complexconnect.cc, ConnectImpatient, 461]:complex.conn [::ffff:10.11.99.160]:8081 2019-04-17 11:18:47.279 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [complexconnect.cc, ConnectImpatient, 461]:complex.conn [::ffff:10.11.99.160]:8081 2019-04-17 11:18:47.279 8470-9255/com.tencent.mars.sample:marsservice D/mars::comm: [complexconnect.cc, ConnectImpatient, 478]:curtime:80674013, laststart_connecttime:80670013, @0xD0213C48 2019-04-17 11:18:47.280 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:47.280 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:47.282 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [complexconnect.cc, ConnectImpatient, 519]:index:0, @0xD0213C48, addr:(10.11.99.160:8081), socket:53, connect 2019-04-17 11:18:47.282 8470-9255/com.tencent.mars.sample:marsservice D/mars::comm: [complexconnect.cc, ConnectImpatient, 523]:timeout:4000, @0xD0213C48 2019-04-17 11:18:47.367 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [complexconnect.cc, ConnectImpatient, 557]:index:0, @0xD0213C48, sock:53, (10.11.99.160:8081), connected Rtt:87, 2019-04-17 11:18:47.367 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [complexconnect.cc, ConnectImpatient, 589]:index:0, sock:53, suc ConnectImpatient:10.11.99.160:8081, RTT:(87, 87), @0xD0213C48 2019-04-17 11:18:47.367 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [complexconnect.cc, ConnectImpatient, 625]:retsocket:53, connrtt:87, conntotalrtt:87, totalcost:88, @0xD0213C48 2019-04-17 11:18:47.368 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:47.368 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:47.368 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, RunConnect, 543]:task socket connect suc sock:53, host:10.11.99.160, ip:10.11.99.160, port:8081, local_ip:10.11.100.196, local_port:39242, iptype:DNSIP, costtime:88, rtt:87, totalcost:87, index:0, net:1 2019-04-17 11:18:47.368 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:47.368 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:47.368 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, ConnectStatus, 349]:connect status from:1 to:2, nettype:1 2019-04-17 11:18:47.369 8470-9255/com.tencent.mars.sample:marsservice D/mars::stn: [smart_heartbeat.cc, OnLongLinkEstablished, 76]:-> OnLongLinkEstablished 2019-04-17 11:18:47.369 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [smart_heartbeat.cc, LoadINI, 266]:-> LoadINI 2019-04-17 11:18:47.369 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:47.369 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:47.369 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:-> getCurWifiInfo 2019-04-17 11:18:47.369 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:<- getCurWifiInfo +0, 2019-04-17 11:18:47.369 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [smart_heartbeat.cc, LoadINI, 266]:<- LoadINI +0, 2019-04-17 11:18:47.369 8470-9255/com.tencent.mars.sample:marsservice D/mars::stn: [smart_heartbeat.cc, OnLongLinkEstablished, 76]:<- OnLongLinkEstablished +0, 2019-04-17 11:18:47.370 8470-8493/com.tencent.mars.sample:marsservice D/mars::stn: [net_check_logic.cc, UpdateLongLinkInfo, 118]:shortlink:_continueFailCount=0, _isTaskSucc=1, records=0xfffffffd 2019-04-17 11:18:47.370 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout 2019-04-17 11:18:47.370 8470-8493/com.tencent.mars.sample:marsservice I/mars::stn: [zombie_task_manager.cc, RedoTasks, 117]:-> RedoTasks 2019-04-17 11:18:47.370 8470-8493/com.tencent.mars.sample:marsservice I/mars::stn: [zombie_task_manager.cc, RedoTasks, 117]:<- RedoTasks +0, 2019-04-17 11:18:47.371 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x1F46, _timeout= 1000 2019-04-17 11:18:47.371 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:47.371 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:47.371 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:47.371 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:47.371 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:-> getCurWifiInfo 2019-04-17 11:18:47.371 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:<- getCurWifiInfo +0, 2019-04-17 11:18:47.372 8470-8493/com.tencent.mars.sample:marsservice I/mars::stn: [longlink_connect_monitor.cc, OnLongLinkStatuChanged, 224]:cancel auto connect 2019-04-17 11:18:47.372 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +1, 2019-04-17 11:18:47.372 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo 2019-04-17 11:18:47.372 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [alarm.h, Alarm, 56]:handler:(18446744072900589936,94) 2019-04-17 11:18:47.372 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, 2019-04-17 11:18:47.372 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [alarm.h, Alarm, 56]:handler:(18446744072900589936,96) 2019-04-17 11:18:47.373 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout 2019-04-17 11:18:47.373 8470-9255/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 212]:-> operator() 2019-04-17 11:18:47.373 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x1F56, _timeout= 500 2019-04-17 11:18:47.373 8470-9255/com.tencent.mars.sample:marsservice W/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 234]:getLongLinkIdentifyCheckBuffer uin == 0, not ready 2019-04-17 11:18:47.373 8470-9255/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 212]:<- operator() +0, 2019-04-17 11:18:47.373 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm 2019-04-17 11:18:47.374 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +1, 2019-04-17 11:18:47.374 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [timing_sync.cc, OnLongLinkStatuChanged, 96]:-> OnLongLinkStatuChanged 2019-04-17 11:18:47.376 8470-9255/com.tencent.mars.sample:marsservice D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 11, after= 8000, ret= true 2019-04-17 11:18:47.376 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +2, 2019-04-17 11:18:47.376 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [alarm.cc, Start, 68]:alarm id:3491839208, after:8000, seq:11, po.reg.q:18446744072900589936,po.reg.s:0,po.s:98 2019-04-17 11:18:47.377 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout 2019-04-17 11:18:47.377 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x1F46, _timeout= 8000 2019-04-17 11:18:47.377 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, stopAlarm, 64]:-> stopAlarm 2019-04-17 11:18:47.378 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +0, 2019-04-17 11:18:47.378 8470-9255/com.tencent.mars.sample:marsservice V/mars::stn: [smart_heartbeat.cc, OnHeartbeatStart, 69]:-> OnHeartbeatStart 2019-04-17 11:18:47.378 8470-9255/com.tencent.mars.sample:marsservice V/mars::stn: [smart_heartbeat.cc, OnHeartbeatStart, 69]:<- OnHeartbeatStart +0, 2019-04-17 11:18:47.378 8470-8493/com.tencent.mars.sample:marsservice D/mars::comm: [platform_comm.cc, stopAlarm, 72]:id= 10, ret= true 2019-04-17 11:18:47.379 8470-8493/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, stopAlarm, 64]:<- stopAlarm +1, 2019-04-17 11:18:47.379 8470-8493/com.tencent.mars.sample:marsservice I/mars::comm: [alarm.cc, Cancel, 94]:alarm cancel id:3739787200, seq:10, after:240000 2019-04-17 11:18:47.379 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [timing_sync.cc, OnLongLinkStatuChanged, 96]:<- OnLongLinkStatuChanged +4, 2019-04-17 11:18:47.379 8470-8493/com.tencent.mars.sample:marsservice I/mars::stn: [zombie_task_manager.cc, RedoTasks, 117]:-> RedoTasks 2019-04-17 11:18:47.379 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm 2019-04-17 11:18:47.379 8470-8493/com.tencent.mars.sample:marsservice I/mars::stn: [zombie_task_manager.cc, RedoTasks, 117]:<- RedoTasks +0, 2019-04-17 11:18:47.380 8470-8493/com.tencent.mars.sample:marsservice I/mars::stn: [net_core.cc, __ConnStatusCallBack, 706]:reportNetConnectInfo all_connstatus:4, longlink_connstatus:4 2019-04-17 11:18:47.380 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 333]:-> operator() 2019-04-17 11:18:47.380 8470-8493/com.tencent.mars.sample:marsservice D/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 339]:all_connstatus = 4, longlink_connstatus = 4 2019-04-17 11:18:47.380 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [com_tencent_mars_stn_StnLogic_C2Java.cc, operator, 333]:<- operator() +0, 2019-04-17 11:18:47.383 8470-9255/com.tencent.mars.sample:marsservice D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 12, after= 210000, ret= true 2019-04-17 11:18:47.384 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +4, 2019-04-17 11:18:47.384 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [alarm.cc, Start, 68]:alarm id:3491839088, after:210000, seq:12, po.reg.q:18446744072900589936,po.reg.s:0,po.s:99 2019-04-17 11:18:47.384 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, RunReadWrite, 581]:start noop taskid:4294967295, cmdid:6, last:(0,0), next:210000 2019-04-17 11:18:47.385 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, stopAlarm, 64]:-> stopAlarm 2019-04-17 11:18:47.388 8470-9255/com.tencent.mars.sample:marsservice D/mars::comm: [platform_comm.cc, stopAlarm, 72]:id= 12, ret= true 2019-04-17 11:18:47.388 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, stopAlarm, 64]:<- stopAlarm +3, 2019-04-17 11:18:47.388 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [alarm.cc, Cancel, 94]:alarm cancel id:3491839088, seq:12, after:210000 2019-04-17 11:18:47.389 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm 2019-04-17 11:18:47.391 8470-9255/com.tencent.mars.sample:marsservice D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 13, after= 210000, ret= true 2019-04-17 11:18:47.392 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +2, 2019-04-17 11:18:47.392 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [alarm.cc, Start, 68]:alarm id:3491839088, after:210000, seq:13, po.reg.q:18446744072900589936,po.reg.s:0,po.s:100 2019-04-17 11:18:47.393 8470-9255/com.tencent.mars.sample:marsservice I/Mars.Sample.MarsServiceProxy: [, , 0]:no push message listener set for cmdid = 10002, just ignored 2019-04-17 11:18:47.396 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, RunReadWrite, 662]:task socket send sock:53, all send:20, count:1, sub send taskid:4294967295, cmdid:6, , len(S:20, 20/20), 2019-04-17 11:18:47.397 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [longlink_task_manager.cc, OnSend, 609]:-> OnSend 2019-04-17 11:18:47.397 8470-8493/com.tencent.mars.sample:marsservice V/mars::stn: [longlink_task_manager.cc, OnSend, 609]:<- OnSend +0, 2019-04-17 11:18:47.415 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [socketbreaker.cc, Clear, 129]:Ret=-1 2019-04-17 11:18:47.417 8470-9255/com.tencent.mars.sample:marsservice I/Mars.Sample.MarsServiceProxy: [, , 0]:no push message listener set for cmdid = 10002, just ignored 2019-04-17 11:18:47.420 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, RunReadWrite, 751]:task socket recv sock:53, recv len:20, buff len:20 2019-04-17 11:18:47.420 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, RunReadWrite, 770]:task socket recv sock:53, pack recv finish taskid:4294967295, cmdid:6, , packlen:(20/20) 2019-04-17 11:18:47.420 8470-9255/com.tencent.mars.sample:marsservice I/mars::stn: [longlink.cc, __NoopResp, 316]:end noop 2019-04-17 11:18:47.420 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, stopAlarm, 64]:-> stopAlarm 2019-04-17 11:18:47.423 8470-9255/com.tencent.mars.sample:marsservice D/mars::comm: [platform_comm.cc, stopAlarm, 72]:id= 11, ret= true 2019-04-17 11:18:47.424 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, stopAlarm, 64]:<- stopAlarm +3, 2019-04-17 11:18:47.424 8470-9255/com.tencent.mars.sample:marsservice I/mars::comm: [alarm.cc, Cancel, 94]:alarm cancel id:3491839208, seq:11, after:8000 2019-04-17 11:18:47.424 8470-9255/com.tencent.mars.sample:marsservice D/mars::stn: [smart_heartbeat.cc, OnHeartResult, 105]:heart result:true, timeout:false 2019-04-17 11:18:47.425 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout 2019-04-17 11:18:47.425 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x1F46, _timeout= 500 2019-04-17 11:18:47.426 8470-9255/com.tencent.mars.sample:marsservice V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +1,

byron-xie commented 5 years ago

分析下客户端的日志,看为什么关闭了 socket

你们可以测下 测试条件:应用白名单加上,手机未充电,应用后台,锁屏,息屏,2分钟内必断

手机系统,8.0以及以上

garryyan commented 5 years ago

锁屏之后 wifi 是否被关闭了? 看看 wifi 的设置中是否有休眠关闭 wifi 的设置?

byron-xie commented 5 years ago

没有的。你可以实现下 任何手机加白名单后 不充电然后过几十秒就自动断了

byron-xie commented 5 years ago

实验下

garryyan commented 5 years ago

微信中不会出现这种情况,如果换个 app 出现这种情况,极大可能是手机厂商的限制。