search

Tencent / mars

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

发送消息频繁重连，帮忙看看是什么问题。不胜感激 #712

Open dulcan1988 opened 4 years ago

dulcan1988 commented 4 years ago

D/mars::stn: [longlink_task_manager.cc, StartTask, 83]:taskid=45 I/mars::proto: [stn_callback.cc, Req2Buf, 690]:PROTO -> TASK after package:taskid:45 cmdid:11 channel_id:0 channel_select:2 cgi:/im send_only:0 need_authed:1 limit_flow:0 limit_frequency:0 network_status_sensitive:0 channel_strategy:0 priority:3 retry_count:-1 server_process_cost:-1 total_timetout:-1 type:48topic:MS body size:0 V/mars::stn: [anti_avalanche.cc, Check, 45]:-> Check [frequency_limit.cc, Check, 46]:-> Check [frequency_limit.cc, Check, 46]:<- Check +0, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, V/mars::stn: [anti_avalanche.cc, Check, 45]:<- Check +0, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, I/mars::stn: [longlink_task_manager.cc, RunOnStartTask, 367]:task add into longlink readwrite suc cgi:/im, cmdid:11, taskid:45, size:2, timeout(firstpkg:12, rw:17, task:40), retry:1 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [socketbreaker.cc, Clear, 122]:Ret=-1 W/InputMethodManager: startInputReason = 3 V/mars::comm: [platform_comm.cc, stopAlarm, 64]:-> stopAlarm D/mars::comm: [platform_comm.cc, stopAlarm, 72]:id= 95, ret= true V/mars::comm: [platform_comm.cc, stopAlarm, 64]:<- stopAlarm +6, I/mars::comm: [alarm.cc, Cancel, 90]:alarm cancel id:532137632776, seq:95, after:270000 V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 96, after= 270000, ret= true V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +4, I/mars::comm: [alarm.cc, Start, 67]:alarm id:532137632776, after:270000, seq:96, po.reg.q:531980240112,po.reg.s:0,po.s:865 D/mars::proto: [stn_callback.cc, TrafficData, 205]:send:72, recv:0 I/mars::stn: [longlink.cc, __RunReadWrite, 650]:task socket send sock:46, all send:72, count:1, sub send taskid:45, cmdid:11, /im, len(S:72, 72/72), V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +15, V/mars::stn: [longlink_task_manager.cc, StartTask, 82]:<- StartTask +19, V/mars::stn: [longlink_task_manager.cc, OnSend, 588]:-> OnSend D/mars::stn: [longlink_task_manager.cc, OnSend, 596]:taskid:45, starttime:22901 V/mars::stn: [longlink_task_manager.cc, OnSend, 588]:<- OnSend +3,

W/IInputConnectionWrapper: getTextBeforeCursor on inactive InputConnection

W/IInputConnectionWrapper: getSelectedText on inactive InputConnection W/IInputConnectionWrapper: getTextAfterCursor on inactive InputConnection

V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +3,

V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +5,

V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +11,

V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +4, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +3, D/JIGUANG-JCore-IMPL: [JCommonInterface] onEvent action:waked V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +3, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +4, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2, E/mars::stn: [longlink_task_manager.cc, RunOnTimeout, 219]:task first-pkg timeout taskid:45, nStartSendTime=22901, nfirstpkgtimeout=12 D/mars::stn: [dynamic_timeout.cc, StatusSwitch, 185]:task_status:-1, good_count:0, good_time:0, dyntime_status:3, dyntime_failed_normal_count_NORMAL:1, cgi: V/mars::stn: [longlink_task_manager.cc, SingleRespHandle, 379]:-> SingleRespHandle W/mars::stn: [longlink_task_manager.cc, SingleRespHandle, 424]:task end retry long cmdid:11, err(6, -500, -1), svr(1.1.1.1:1111, BackupIP, 1.1.1.1), cli(, 1.1.1.1, n:"LT-QA", sig:0), cost(s:2, r:0, c:342, rw:12056), all:12078, retry:1, cgi:/im, taskid:45, tid:13572 V/mars::stn: [longlink_task_manager.cc, SingleRespHandle, 379]:<- SingleRespHandle +0, I/mars::stn: [longlink.cc, Disconnect, 240]:_scene:10007 V/mars::comm: [dns.cc, Cancel, 283]:-> Cancel [dns.cc, Cancel, 283]:<- Cancel +0, [dns.cc, Cancel, 283]:-> Cancel [dns.cc, Cancel, 283]:<- Cancel +0, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +1, V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:-> getCurWifiInfo V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:<- getCurWifiInfo +0, W/mars::stn: [longlink.cc, RunReadWrite, 564]:task socket close sock:46, user disconnect:10007, nread:0, nwrite:72, net_type:"LT-QA", info nwrite:72 taskid:45, cmdid:11, cgi:/im ; task socket close getsocktcpinfo:tcpi_state=0x1， tcpi_ca_state=0x4， tcpi_retransmits=0x3， tcpi_probes=0x0， tcpi_backoff=0x3， tcpi_options=0x7， tcpi_rto=0x7DBB80， tcpi_snd_mss=0x4A4， tcpi_rcv_mss=0x218， tcpi_unacked=0x1， tcpi_sacked=0x0， tcpi_lost=0x1， tcpi_retrans=0x1， tcpi_fackets=0x0， tcpi_last_data_sent=0x1022， tcpi_last_ack_sent=0x0， tcpi_last_data_recv=0x10680， tcpi_last_ack_recv=0x1068A， tcpi_pmtu=0x5DC， tcpi_rcv_ssthresh=0x14820， tcpi_rtt=0x53333， tcpi_rttvar=0x1F496， tcpi_snd_ssthresh=0x7， tcpi_snd_cwnd=0x1， tcpi_advmss=0x56C， tcpi_reordering=0x3， tcpi_rcv_rtt=0x0， tcpi_rcv_space=0x14820， tcpi_total_retrans=0x V/mars::comm: [platform_comm.cc, stopAlarm, 64]:-> stopAlarm D/mars::comm: [platform_comm.cc, stopAlarm, 72]:id= 96, ret= true V/mars::comm: [platform_comm.cc, stopAlarm, 64]:<- stopAlarm +14, I/mars::comm: [alarm.cc, Cancel, 90]:alarm cancel id:532137632776, seq:96, after:270000 V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getSignal, 387]:-> getSignal V/mars::comm: [platform_comm.cc, getSignal, 397]:Signal Strength= 90, wifi:true [platform_comm.cc, getSignal, 387]:<- getSignal +5, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, I/mars::stn: [longlink.cc, ConnectStatus, 345]:connect status from:2 to:3, nettype:1 [smart_heartbeat.cc, OnLongLinkDisconnect, 81]:-> OnLongLinkDisconnect I/mars::stn: [smart_heartbeat.cc, OnLongLinkDisconnect, 94]:"LT-QA" not stable last heart:270000 [smart_heartbeat.cc, OnLongLinkDisconnect, 81]:<- OnLongLinkDisconnect +0, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x24C6, _timeout= 1000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +3, I/mars::stn: [longlink.cc, Run, 374]:<- Run +67970, I/mars::stn: [longlink.cc, Disconnect, 240]:_scene:10008 D/mars::stn: [net_check_logic.cc, UpdateLongLinkInfo, 118]:shortlink:_continueFailCount=1, _isTaskSucc=0, records=0xdb6fef76 I/mars::stn: [net_check_logic.cc, __ShouldNetCheck, 152]:netcheck: shortlink succ_count: 0, is most recent 8 times. valid_record_taskcount: 32. I/mars::stn: [net_check_logic.cc, ShouldNetCheck, 159]:netcheck: shortlink_shouldcheck=false, shortlink succ_count=0, in sub-recent 8 times. D/mars::stn: [net_source.cc, ReportLongIP, 239]:_is_success=false, ip=1.1.1.1, port=1899 V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getCurWifiInfo, 241]:-> getCurWifiInfo [platform_comm.cc, getCurWifiInfo, 241]:<- getCurWifiInfo +0, D/mars::stn: [longlink_task_manager.cc, RunOnStartTask, 280]:retry interval:1000, curtime:22913495, lastbatcherrortime_:22913456, curtime-m_lastbatcherrortime:39 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2, V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 97, after= 500, ret= true V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +5, I/mars::comm: [alarm.cc, Start, 67]:alarm id:532225556472, after:500, seq:97, po.reg.q:531980240112,po.reg.s:0,po.s:882 V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, V/mars::stn: [timing_sync.cc, OnLongLinkStatuChanged, 96]:-> OnLongLinkStatuChanged V/mars::appcomm: [com_tencent_mars_app_AppLogic_C2Java.cc, GetAccountInfo, 70]:-> GetAccountInfo V/mars::appcomm: [com_tencent_mars_app_AppLogic_C2Java.cc, GetAccountInfo, 70]:<- GetAccountInfo +0, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, startAlarm, 49]:-> startAlarm D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 98, after= 240000, ret= true V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +5, I/mars::comm: [alarm.cc, Start, 67]:alarm id:532227366464, after:240000, seq:98, po.reg.q:531980240112,po.reg.s:0,po.s:883 V/mars::stn: [timing_sync.cc, OnLongLinkStatuChanged, 96]:<- OnLongLinkStatuChanged +7, I/mars::comm: [alarm.cc, OnAlarm, 131]:OnAlarm id:532225556472, seq:97, elapsed:501, after:500, miss:1, android alarm:false, runing V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, V/mars::appcomm: [com_tencent_mars_app_AppLogic_C2Java.cc, GetAccountInfo, 70]:-> GetAccountInfo V/mars::appcomm: [com_tencent_mars_app_AppLogic_C2Java.cc, GetAccountInfo, 70]:<- GetAccountInfo +0, I/mars::stn: [longlink_connect_monitor.cc, __AutoIntervalConnect, 187]:start auto connect after:179522 V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 99, after= 179522, ret= true V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +10, I/mars::comm: [alarm.cc, Start, 67]:alarm id:532225556472, after:179522, seq:99, po.reg.q:531980240112,po.reg.s:0,po.s:886 V/mars::comm: [socketbreaker.cc, Clear, 122]:Ret=-1 I/mars::stn: [longlink_connect_monitor.cc, IntervalConnect, 173]:made interval connect interval:20000, posttime:68973, newone:true, connectstatus:0 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, I/mars::stn: [longlink.cc, Run, 374]:-> Run LongLink Rebuild span:0, net:1 V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:-> getCurWifiInfo [platform_comm.cc, getCurWifiInfo, 241]:<- getCurWifiInfo +0, [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x24C6, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +3, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, I/mars::stn: [longlink.cc, ConnectStatus, 345]:connect status from:0 to:1, nettype:1 V/mars::comm: [dns.cc, GetHostByName, 184]:-> GetHostByName V/mars::comm: [platform_comm.cc, stopAlarm, 64]:-> stopAlarm V/mars::comm: [dns.cc, GetIP, 59]:-> GetIP V/mars::comm: [dns.cc, GetIP, 59]:<- GetIP +0, I/mars::comm: [dns.cc, GetHostByName, 265]:dns get ip status:4 host:1.1.1.1, func:true V/mars::comm: [dns.cc, GetHostByName, 184]:<- GetHostByName +2, [dns.cc, GetHostByName, 184]:-> GetHostByName V/mars::comm: [dns.cc, GetIP, 59]:-> GetIP V/mars::comm: [dns.cc, GetIP, 59]:<- GetIP +1, V/mars::comm: [dns.cc, GetHostByName, 184]:<- GetHostByName +3, D/mars::stn: [net_source.cc, MakeIPPorts, 369]:link host:1.1.1.1, new dns ret:false, size:0 dns ret:true, size:1, W/mars::stn: [simple_ipport_sort.cc, FilterbyBanned, 317]:ip:1.1.1.1, port:1899, is ban!! D/mars::stn: [net_source.cc, MakeIPPorts, 398]:link host:1.1.1.1, backup ips size:1 I/mars::stn: [longlink.cc, RunConnect, 437]:task socket dns ip:1.1.1.1:1899:1.1.1.1:BackupIP proxytype:0 useproxy:false I/mars::comm: [complexconnect.cc, ConnectImpatient, 453]:_vecaddr size:1, m_timeout:10000, m_interval:4000, m_error_interval:4000, m_max_connect:3, @0x7BE5DFE4E8 I/mars::comm: [complexconnect.cc, ConnectImpatient, 459]:complex.conn [::ffff:1.1.1.1]:1899 D/mars::comm: [complexconnect.cc, ConnectImpatient, 476]:curtime:22914516, laststart_connecttime:22910516, @0x7BE5DFE4E8 V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, D/mars::comm: [platform_comm.cc, stopAlarm, 72]:id= 99, ret= true V/mars::comm: [platform_comm.cc, stopAlarm, 64]:<- stopAlarm +9, I/mars::comm: [complexconnect.cc, ConnectImpatient, 518]:index:0, @0x7BE5DFE4E8, addr:(1.1.1.1:1899), socket:46, connect I/mars::comm: [alarm.cc, Cancel, 90]:alarm cancel id:532225556472, seq:99, after:179522 V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, V/mars::stn: [timing_sync.cc, OnLongLinkStatuChanged, 96]:-> OnLongLinkStatuChanged V/mars::stn: [timing_sync.cc, OnLongLinkStatuChanged, 96]:<- OnLongLinkStatuChanged +0, I/mars::stn: [net_core.cc, ConnStatusCallBack, 726]:reportNetConnectInfo all_connstatus:3, longlink_connstatus:3 D/: status changed :0 D/mars::comm: [complexconnect.cc, ConnectImpatient, 522]:timeout:4000, @0x7BE5DFE4E8 I/MicroMsg.Alarm: [, , 0]:onReceive id=97, curId=97 I/MicroMsg.Alarm: [, , 0]:onReceive find alarm id:97, pid:11116, delta miss time:523 D/mars::comm: [OnAlarm.inl, Java_com_tencent_mars_comm_Alarm_onAlarm, 13]:BroadcastMessage seq:97 I/mars::comm: [complexconnect.cc, ConnectImpatient, 555]:index:0, @0x7BE5DFE4E8, sock:46, (1.1.1.1:1899), connected Rtt:322, I/mars::comm: [complexconnect.cc, ConnectImpatient, 592]:index:0, sock:46, suc ConnectImpatient:1.1.1.1:1899, RTT:(322, 322), @0x7BE5DFE4E8 [complexconnect.cc, ConnectImpatient, 628]:retsocket:46, connrtt:322, conntotalrtt:322, totalcost:323, @0x7BE5DFE4E8 V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, I/mars::stn: [longlink.cc, RunConnect, 537]:task socket connect suc sock:46, host:1.1.1.1, ip:1.1.1.1, port:1899, local_ip:1.1.1.1, local_port:60084, iptype:BackupIP, costtime:323, rtt:322, totalcost:322, index:0, net:1 V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, I/mars::stn: [longlink.cc, ConnectStatus, 345]:connect status from:1 to:2, nettype:1 D/mars::stn: [smart_heartbeat.cc, OnLongLinkEstablished, 74]:-> OnLongLinkEstablished I/mars::stn: [smart_heartbeat.cc, LoadINI, 284]:-> LoadINI V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getCurWifiInfo, 241]:-> getCurWifiInfo [platform_comm.cc, getCurWifiInfo, 241]:<- getCurWifiInfo +0, I/mars::stn: [smart_heartbeat.cc, LoadINI, 284]:<- LoadINI +0, D/mars::stn: [smart_heartbeat.cc, OnLongLinkEstablished, 74]:<- OnLongLinkEstablished +0, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x24C6, _timeout= 1000 I/mars::stn: [longlink_connect_monitor.cc, OnLongLinkStatuChanged, 221]:cancel auto connect V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +1, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo I/mars::stn: [alarm.h, Alarm, 55]:handler:(531980240112,897) V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, I/mars::stn: [alarm.h, Alarm, 55]:handler:(531980240112,899) I/mars::proto: [stn_callback.cc, Req2Buf, 690]:PROTO -> TASK after package:taskid:45 cmdid:11 channel_id:0 channel_select:2 cgi:/im send_only:0 need_authed:1 limit_flow:0 limit_frequency:0 network_status_sensitive:0 channel_strategy:0 priority:3 retry_count:-1 server_process_cost:-1 total_timetout:-1 type:48topic:MS body size:0 V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm V/mars::stn: [anti_avalanche.cc, Check, 45]:-> Check [frequency_limit.cc, Check, 46]:-> Check [frequency_limit.cc, Check, 46]:<- Check +0, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, V/mars::stn: [anti_avalanche.cc, Check, 45]:<- Check +1, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, I/mars::stn: [longlink_task_manager.cc, RunOnStartTask, 367]:task add into longlink readwrite suc cgi:/im, cmdid:11, taskid:45, size:2, timeout(firstpkg:12, rw:17, task:40), retry:0 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 100, after= 5000, ret= true V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +9, I/mars::comm: [alarm.cc, Start, 67]:alarm id:532137632936, after:5000, seq:100, po.reg.q:531980240112,po.reg.s:0,po.s:901 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x24C6, _timeout= 5000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +3, V/mars::stn: [timing_sync.cc, OnLongLinkStatuChanged, 96]:-> OnLongLinkStatuChanged V/mars::comm: [platform_comm.cc, stopAlarm, 64]:-> stopAlarm V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2, V/mars::stn: [smart_heartbeat.cc, OnHeartbeatStart, 64]:-> OnHeartbeatStart [smart_heartbeat.cc, OnHeartbeatStart, 64]:<- OnHeartbeatStart +0, D/mars::comm: [platform_comm.cc, stopAlarm, 72]:id= 98, ret= true V/mars::comm: [platform_comm.cc, stopAlarm, 64]:<- stopAlarm +8, I/mars::comm: [alarm.cc, Cancel, 90]:alarm cancel id:532227366464, seq:98, after:240000 V/mars::stn: [timing_sync.cc, OnLongLinkStatuChanged, 96]:<- OnLongLinkStatuChanged +9, I/mars::stn: [zombie_task_manager.cc, RedoTasks, 117]:-> RedoTasks V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm I/mars::stn: [zombie_task_manager.cc, RedoTasks, 117]:<- RedoTasks +0, [net_core.cc, ConnStatusCallBack, 726]:reportNetConnectInfo all_connstatus:4, longlink_connstatus:4 D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 101, after= 270000, ret= true V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +6, I/mars::comm: [alarm.cc, Start, 67]:alarm id:532137632776, after:270000, seq:101, po.reg.q:531980240112,po.reg.s:0,po.s:903 I/mars::stn: [longlink.cc, RunReadWrite, 572]:start noop synccheck taskid:4294967294, cmdid:10, last:(0,0), next:270000 V/mars::comm: [platform_comm.cc, stopAlarm, 64]:-> stopAlarm D/mars::comm: [platform_comm.cc, stopAlarm, 72]:id= 101, ret= true V/mars::comm: [platform_comm.cc, stopAlarm, 64]:<- stopAlarm +8, I/mars::comm: [alarm.cc, Cancel, 90]:alarm cancel id:532137632776, seq:101, after:270000 V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 102, after= 270000, ret= true V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +7, I/mars::comm: [alarm.cc, Start, 67]:alarm id:532137632776, after:270000, seq:102, po.reg.q:531980240112,po.reg.s:0,po.s:904 D/mars::proto: [stn_callback.cc, TrafficData, 205]:send:188, recv:0 I/mars::stn: [longlink.cc, RunReadWrite, 650]:task socket send sock:46, all send:188, count:2, sub send taskid:4294967294, cmdid:10, , len(S:116, 116/116), sub send taskid:45, cmdid:11, /im, len(S:72, 72/72), V/mars::stn: [longlink_task_manager.cc, OnSend, 588]:-> OnSend V/mars::stn: [longlink_task_manager.cc, OnSend, 588]:<- OnSend +0, [longlink_task_manager.cc, OnSend, 588]:-> OnSend D/mars::stn: [longlink_task_manager.cc, OnSend, 596]:taskid:45, starttime:22914 V/mars::stn: [longlink_task_manager.cc, OnSend, 588]:<- OnSend +0, V/mars::comm: [socketbreaker.cc, Clear, 122]:Ret=-1 D/mars::proto: [stn_callback.cc, TrafficData, 205]:send:0, recv:74 I/mars::stn: [longlink.cc, RunReadWrite, 740]:task socket recv sock:46, recv len:74, buff len:74 [longlink.cc, RunReadWrite, 759]:task socket recv sock:46, pack recv finish taskid:4294967294, cmdid:10, , packlen:(37/37) I/mars::stn: [longlink.cc, NoopResp, 300]:end noop synccheck [longlink_identify_checker.cc, OnIdentifyResp, 79]:identifycheck(synccheck) resp D/: status changed :2 V/mars::comm: [platform_comm.cc, stopAlarm, 64]:-> stopAlarm I/mars::stn: [net_core.cc, operator, 299]:task start long short taskid:46, cmdid:11, need_authed:true, cgi:, channel_select:2, limit_flow:false, host:, send_only:false, cmdid:11, server_process_cost:-1, retrycount:-1, channel_strategy:0, total_timetout:-1, network_status_sensitive:false, priority:3, report_arg: V/mars::stn: [longlink_task_manager.cc, StartTask, 82]:-> StartTask D/mars::stn: [longlink_task_manager.cc, StartTask, 83]:taskid=46 I/mars::proto: [stn_callback.cc, Req2Buf, 690]:PROTO -> TASK after package:taskid:46 cmdid:11 channel_id:0 channel_select:2 cgi: send_only:0 need_authed:1 limit_flow:0 limit_frequency:0 network_status_sensitive:0 channel_strategy:0 priority:3 retry_count:-1 server_process_cost:-1 total_timetout:-1 type:48topic:MP body size:12 V/mars::stn: [anti_avalanche.cc, Check, 45]:-> Check [frequency_limit.cc, Check, 46]:-> Check V/mars::stn: [frequency_limit.cc, Check, 46]:<- Check +0, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, V/mars::stn: [anti_avalanche.cc, Check, 45]:<- Check +1, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, I/mars::stn: [longlink_task_manager.cc, RunOnStartTask, 367]:task add into longlink readwrite suc cgi:, cmdid:11, taskid:46, size:2, timeout(firstpkg:13, rw:18, task:40), retry:1 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2, V/mars::stn: [longlink_task_manager.cc, StartTask, 82]:<- StartTask +9, D/mars::comm: [platform_comm.cc, stopAlarm, 72]:id= 100, ret= true V/mars::comm: [platform_comm.cc, stopAlarm, 64]:<- stopAlarm +12, I/mars::comm: [alarm.cc, Cancel, 90]:alarm cancel id:532137632936, seq:100, after:5000 D/mars::stn: [net_check_logic.cc, UpdateLongLinkInfo, 118]:shortlink:_continueFailCount=1, _isTaskSucc=1, records=0xb6dfdeed D/mars::stn: [smart_heartbeat.cc, OnHeartResult, 106]:heart result:true, false I/mars::stn: [net_check_logic.cc, ShouldNetCheck, 152]:netcheck: shortlink succ_count: 0, is most recent 8 times. valid_record_taskcount: 32. [net_check_logic.cc, ShouldNetCheck, 159]:netcheck: shortlink_shouldcheck=false, shortlink succ_count=0, in sub-recent 8 times. [zombie_task_manager.cc, RedoTasks, 117]:-> RedoTasks V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout I/mars::stn: [zombie_task_manager.cc, RedoTasks, 117]:<- RedoTasks +0, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x24C6, _timeout= 500 D/mars::stn: [net_source.cc, ReportLongIP, 239]:_is_success=true, ip=1.1.1.1, port=1899 V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, I/mars::stn: [longlink.cc, RunReadWrite, 759]:task socket recv sock:46, pack recv finish taskid:45, cmdid:11, /im, packlen:(37/37) V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:-> getCurWifiInfo V/mars::comm: [platform_comm.cc, getCurWifiInfo, 241]:<- getCurWifiInfo +0, V/mars::comm: [platform_comm.cc, stopAlarm, 64]:-> stopAlarm I/mars::proto: [stn_callback.cc, Buf2Resp, 725]:PROTO -> TASK(taskid:45 cmdid:11 channel_id:0 channel_select:2 cgi:/im send_only:0 need_authed:1 limit_flow:0 limit_frequency:0 network_status_sensitive:0 channel_strategy:0 priority:3 retry_count:-1 server_process_cost:-1 total_timetout:-1 type:48topic:MS body size:0) has response I/mars::proto: [stn_callback.cc, Buf2Resp, 726]:PROTO -> TASK errorcode:0 [stn_callback.cc, Buf2Resp, 734]:PROTO -> TASK business code:0(0success, otherwise failure) D/mars::comm: [platform_comm.cc, stopAlarm, 72]:id= 102, ret= true V/mars::comm: [platform_comm.cc, stopAlarm, 64]:<- stopAlarm +7, I/mars::comm: [alarm.cc, Cancel, 90]:alarm cancel id:532137632776, seq:102, after:270000 V/mars::comm: [platform_comm.cc, startAlarm, 49]:-> startAlarm D/mars::comm: [platform_comm.cc, startAlarm, 58]:id= 103, after= 270000, ret= true V/mars::comm: [platform_comm.cc, startAlarm, 49]:<- startAlarm +4, I/mars::comm: [alarm.cc, Start, 67]:alarm id:532137632776, after:270000, seq:103, po.reg.q:531980240112,po.reg.s:0,po.s:911 D/mars::proto: [stn_callback.cc, TrafficData, 205]:send:40, recv:0 I/mars::stn: [longlink.cc, __RunReadWrite, 650]:task socket send sock:46, all send:40, count:1, sub send taskid:46, cmdid:11, , len(S:40, 40/40), V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, D/mars::stn: [dynamic_timeout.cc, StatusSwitch, 185]:task_status:1, good_count:0, good_time:0, dyntime_status:3, dyntime_failed_normal_count_NORMAL:2, cgi:/im V/mars::stn: [longlink_task_manager.cc, SingleRespHandle, 379]:-> SingleRespHandle I/mars::stn: [longlink_task_manager.cc, SingleRespHandle, 399]:task end callback long cmdid:11, err(0, 0, 0), svr(1.1.1.1:1899, BackupIP, 1.1.1.1), cli(, 1.1.1.1, n:"LT-QA", sig:0), cost(s:2, r:17, c:322, rw:375), all:13890, retry:0, cgi:/im, taskid:45, tid:13665 V/mars::stn: [longlink_task_manager.cc, SingleRespHandle, 379]:<- SingleRespHandle +0, D/mars::stn: [net_check_logic.cc, UpdateLongLinkInfo, 118]:shortlink:_continueFailCount=0, _isTaskSucc=1, records=0x6dbfbddb I/mars::stn: [net_check_logic.cc, ShouldNetCheck, 152]:netcheck: shortlink succ_count: 0, is most recent 8 times. valid_record_taskcount: 32. [net_check_logic.cc, __ShouldNetCheck, 159]:netcheck: shortlink_shouldcheck=false, shortlink succ_count=0, in sub-recent 8 times. [zombie_task_manager.cc, RedoTasks, 117]:-> RedoTasks [zombie_task_manager.cc, RedoTasks, 117]:<- RedoTasks +0, D/mars::stn: [net_source.cc, ReportLongIP, 239]:_is_success=true, ip=1.1.1.1, port=1899 V/mars::comm: [platform_comm.cc, getNetInfo, 131]:-> getNetInfo V/mars::comm: [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getNetInfo, 131]:-> getNetInfo [platform_comm.cc, getNetInfo, 131]:<- getNetInfo +0, [platform_comm.cc, getCurWifiInfo, 241]:-> getCurWifiInfo [platform_comm.cc, getCurWifiInfo, 241]:<- getCurWifiInfo +0, V/mars::stn: [longlink_task_manager.cc, OnSend, 588]:-> OnSend D/mars::stn: [longlink_task_manager.cc, OnSend, 596]:taskid:46, starttime:22915 V/mars::stn: [longlink_task_manager.cc, OnSend, 588]:<- __OnSend +0,

V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +3, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +3, V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:-> wakeupLock_Lock_Timeout [platform_comm.cc, wakeupLock_Lock_Timeout, 479]:_object= 0x2476, _timeout= 30000 V/mars::comm: [platform_comm.cc, wakeupLock_Lock_Timeout, 476]:<- wakeupLock_Lock_Timeout +2,

dulcan1988 commented 4 years ago

-。-

xianfeng-zhu commented 4 years ago

看起来像是请求发送出去了，但是没有收到response数据