#################################################################### # TL-MR3420 System Log # Time = 2012-01-01 0:06:50 412s # H-Ver = MR3420 v2 00000000 : S-Ver = 3.13.27 Build 120905 Rel.73281n # L = 192.168.0.1 : M = 255.255.255.0 # 3G/4G : 3G/4G = 0.0.0.0 : M = 0.0.0.0 : G = 0.0.0.0 #################################################################### 1st day 00:00:06 OTHER INFO System started 1st day 00:00:12 DHCP NOTICE DHCP server started 1st day 00:00:12 SECURITY INFO PPTP Passthrough enabled 1st day 00:00:12 SECURITY INFO L2TP Passthrough enabled 1st day 00:00:12 SECURITY INFO IPSEC Passthrough enabled 1st day 00:00:13 SECURITY INFO FTP ALG enabled 1st day 00:00:13 SECURITY INFO TFTP ALG enabled 1st day 00:00:13 SECURITY INFO H323 ALG enabled 1st day 00:00:13 SECURITY INFO RTSP ALG enabled 1st day 00:00:24 DHCP INFO DHCPS:Recv REQUEST from 00:25:64:E6:E0:F5 1st day 00:00:25 DHCP INFO DHCPS:REQUEST ip c0a80419 is not in the address pool 1st day 00:00:25 DHCP INFO DHCPS:Send NAK 1st day 00:00:26 DHCP INFO DHCPS:Recv DISCOVER from 00:25:64:E6:E0:F5 1st day 00:00:27 DHCP INFO DHCPS:Send OFFER with ip 192.168.0.100 1st day 00:00:27 DHCP INFO DHCPS:Recv REQUEST from 00:25:64:E6:E0:F5 1st day 00:00:27 DHCP INFO DHCPS:Send ACK to 192.168.0.100 1st day 00:00:46 3G/4G NOTICE handle_card start 1st day 00:00:46 3G/4G INFO LTE Modem = 0, modemStatus = 1, targetVendorID = 0x0000, targerProductID = 0x0000 1st day 00:00:57 3G/4G INFO Before Mode switch VendorID = 0x12d1, ProductID = 0x1f01 1st day 00:01:09 3G/4G NOTICE echo "/tmp/3G/12d1_1f01_0:55534243000000000000000000000011060000000100000000000000000000" > /tmp/3G/switch_cmd_file 1st day 00:01:09 3G/4G INFO After mode switch vendorID = 0x12d1, productID = 0x1442 1st day 00:01:09 3G/4G NOTICE 803: Before judgeModemTypeByTargetID, index = -1, targetPid = 0x1442 1st day 00:01:12 PPP INFO chat:terminate: status == 6 1st day 00:01:13 PPP INFO chat:terminate: status == 0 1st day 00:01:18 PPP INFO chat:terminate: status == 3 1st day 00:01:23 PPP INFO chat:terminate: status == 3 1st day 00:01:23 3G/4G INFO Main interface_index[0] = 0 1st day 00:01:24 3G/4G NOTICE After mode switch vendorID is 0x12d1 productID is 0x1442 1st day 00:01:26 3G/4G INFO getModemPinStatus 1347 getModemParamByScript return valid type = 1 1st day 00:01:26 3G/4G INFO card_add 962 minfo.modeswitchcmd = /tmp/3G/12d1_1f01_0 minfo.simLock = 16, minfo.modemChipTyp = 1 1st day 00:01:26 3G/4G NOTICE 967: Before judgeModemTypeByTargetID, index = -1, targetPid = 0x1442 1st day 00:01:26 3G/4G NOTICE After judgeModemTypeByTargetID, modem type = 0 1st day 00:01:26 3G/4G INFO LTE Modem = 0, modemStatus = 2, targetVendorID = 0x12d1, targerProductID = 0x1442 1st day 00:01:26 3G/4G INFO USB modem is ready 1st day 00:01:26 3G/4G NOTICE modem index is 4 1st day 00:01:26 3G/4G NOTICE setModemISP 1331 Get ISP by AT command. 1st day 00:01:27 3G/4G NOTICE parseResponseBuf 245 Respone data is: at+cimi^M^M 260171100546402^M ^M OK^M , len = 33 1st day 00:01:27 3G/4G NOTICE modemATCommandGetISP 881 ret = 0, bufISP = at+cimi^M^M 260171100546402^M ^M OK^M 1st day 00:01:27 3G/4G NOTICE modemATCommandGetISP 904 counter = 15 1st day 00:01:27 3G/4G NOTICE buffer is 260171100546402 1st day 00:01:27 3G/4G NOTICE mcc is 260 1st day 00:01:27 3G/4G NOTICE mnc is 171 1st day 00:01:27 3G/4G NOTICE setModemISP 1335 mcc is 260, mnc is 171 1st day 00:01:45 3G/4G NOTICE parseResponseBuf 245 Respone data is: 0000016,00000000,00000000,0000000000000000,0000000000000000,00000000,00000000^M ^M ^DSFLOWRPT:00000018,00000000,00000000,0000000000000000,0000000000000000,00000000,00000000 1st day 00:01:45 3G/4G NOTICE parseResponseBuf 245 Respone data is: 00,00000000,0000000000000000,0000000000000000,00000000,00000000^M ^M ^DSFLOWRPT:0000001C,00000000,00000000,0000000000000000,0000000000000000,00000000,00000000^M ^M ^DSFLOWR 1st day 00:01:45 3G/4G NOTICE parseResponseBuf 245 Respone data is: 00000000000000,0000000000000000,00000000,00000000^M ^M ^DSFLOWRPT:00000020,00000000,00000000,0000000000000000,0000000000000000,00000000,00000000^M ^M ^DSFLOWRPT:00000022,00 1st day 00:01:45 PPP NOTICE Standard authentication. 1st day 00:01:45 3G/4G INFO Run 3G dial cmd: pppd 3g 1 /dev/ttyUSB0 115200 connect "chat -s -v -f /etc/ppp/c 1st day 00:01:47 PPP INFO chat:get_string : OK 1st day 00:01:47 PPP INFO chat:get_string : OK 1st day 00:01:47 PPP INFO chat:get_string : OK 1st day 00:01:47 PPP INFO chat:get_string : CONNECT 1st day 00:01:47 PPP INFO chat:terminate: status == 0 1st day 00:01:48 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0xd0c58bdc] 1st day 00:01:51 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0xd0c58bdc] 1st day 00:01:54 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0xd0c58bdc] 1st day 00:01:57 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0xd0c58bdc] 1st day 00:02:00 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0xd0c58bdc] 1st day 00:02:03 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0xd0c58bdc] 1st day 00:02:06 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0xd0c58bdc] 1st day 00:02:09 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0xd0c58bdc] 1st day 00:02:12 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0xd0c58bdc] 1st day 00:02:15 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0xd0c58bdc] 1st day 00:02:18 PPP INFO chat:terminate: status == 0 1st day 00:02:37 3G/4G NOTICE Now disconnect times is 1 1st day 00:02:37 3G/4G NOTICE 3G Modem connecting error, please check the dial number and APN. 1st day 00:02:37 3G/4G INFO sw3GMobileCmdReq 378 mobileFormatCmd error 1st day 00:02:39 3G/4G NOTICE modem index is 4 1st day 00:02:39 3G/4G NOTICE setModemISP 1331 Get ISP by AT command. 1st day 00:02:46 3G/4G NOTICE modemATCommandGetISP 881 ret = 1, bufISP = 1st day 00:02:46 3G/4G NOTICE modemATCommandGetISP 918 counter = 0 1st day 00:02:46 3G/4G NOTICE setModemISP 1348 Get ISP by AT command failed. 1st day 00:03:08 PPP NOTICE Standard authentication. 1st day 00:03:08 3G/4G INFO Run 3G dial cmd: pppd 3g 1 /dev/ttyUSB0 115200 connect "chat -s -v -f /etc/ppp/c 1st day 00:03:25 PPP INFO chat:terminate: status == 3 1st day 00:03:44 3G/4G NOTICE Now disconnect times is 2 1st day 00:03:44 3G/4G NOTICE 3G Modem can't connect to network, now reset itself to try connecting again. 1st day 00:03:44 3G/4G NOTICE Reset the 3G modem, please wait. 1st day 00:03:44 3G/4G NOTICE handle_card start 1st day 00:03:44 3G/4G INFO LTE Modem = 0, modemStatus = 1, targetVendorID = 0x0000, targerProductID = 0x0000 1st day 00:03:44 3G/4G NOTICE main 82 process is running, return. 1st day 00:03:46 3G/4G INFO sw3GMobileCmdReq 378 mobileFormatCmd error 1st day 00:03:46 3G/4G INFO LTE Modem = 0, modemStatus = 0, targetVendorID = 0x0000, targerProductID = 0x0000 1st day 00:03:49 3G/4G INFO 3G Modem is plugged out. 1st day 00:03:51 3G/4G NOTICE handle_card start 1st day 00:03:51 3G/4G INFO LTE Modem = 0, modemStatus = 1, targetVendorID = 0x0000, targerProductID = 0x0000 1st day 00:04:00 3G/4G INFO Before Mode switch VendorID = 0x12d1, ProductID = 0x1f01 1st day 00:04:13 3G/4G NOTICE echo "/tmp/3G/12d1_1f01_0:55534243000000000000000000000011060000000100000000000000000000" > /tmp/3G/switch_cmd_file 1st day 00:04:13 3G/4G INFO After mode switch vendorID = 0x12d1, productID = 0x1442 1st day 00:04:13 3G/4G NOTICE 803: Before judgeModemTypeByTargetID, index = -1, targetPid = 0x1442 1st day 00:04:16 PPP INFO chat:terminate: status == 6 1st day 00:04:16 PPP INFO chat:terminate: status == 0 1st day 00:04:21 PPP INFO chat:terminate: status == 3 1st day 00:04:27 PPP INFO chat:terminate: status == 3 1st day 00:04:27 3G/4G INFO Main interface_index[0] = 0 1st day 00:04:28 3G/4G NOTICE After mode switch vendorID is 0x12d1 productID is 0x1442 1st day 00:04:29 3G/4G INFO getModemPinStatus 1347 getModemParamByScript return valid type = 1 1st day 00:04:29 3G/4G INFO card_add 962 minfo.modeswitchcmd = /tmp/3G/12d1_1f01_0 minfo.simLock = 16, minfo.modemChipTyp = 1 1st day 00:04:29 3G/4G NOTICE 967: Before judgeModemTypeByTargetID, index = -1, targetPid = 0x1442 1st day 00:04:29 3G/4G NOTICE After judgeModemTypeByTargetID, modem type = 0 1st day 00:04:29 3G/4G INFO LTE Modem = 0, modemStatus = 2, targetVendorID = 0x12d1, targerProductID = 0x1442 1st day 00:04:29 3G/4G INFO USB modem is ready 1st day 00:04:29 3G/4G NOTICE modem index is 4 1st day 00:04:29 3G/4G NOTICE setModemISP 1331 Get ISP by AT command. 1st day 00:04:30 3G/4G NOTICE parseResponseBuf 245 Respone data is: at+cimi^M^M 260171100546402^M ^M OK^M , len = 33 1st day 00:04:30 3G/4G NOTICE modemATCommandGetISP 881 ret = 0, bufISP = at+cimi^M^M 260171100546402^M ^M OK^M 1st day 00:04:30 3G/4G NOTICE modemATCommandGetISP 904 counter = 15 1st day 00:04:30 3G/4G NOTICE buffer is 260171100546402 1st day 00:04:30 3G/4G NOTICE mcc is 260 1st day 00:04:30 3G/4G NOTICE mnc is 171 1st day 00:04:30 3G/4G NOTICE setModemISP 1335 mcc is 260, mnc is 171 1st day 00:04:49 3G/4G NOTICE parseResponseBuf 245 Respone data is: 0000016,00000000,00000000,0000000000000000,0000000000000000,00000000,00000000^M ^M ^DSFLOWRPT:00000018,00000000,00000000,0000000000000000,0000000000000000,00000000,00000000 1st day 00:04:49 3G/4G NOTICE parseResponseBuf 245 Respone data is: 00,00000000,0000000000000000,0000000000000000,00000000,00000000^M ^M ^DSFLOWRPT:0000001C,00000000,00000000,0000000000000000,0000000000000000,00000000,00000000^M ^M ^DSFLOWR 1st day 00:04:49 3G/4G NOTICE parseResponseBuf 245 Respone data is: 00000000000000,0000000000000000,00000000,00000000^M ^M ^DSFLOWRPT:00000020,00000000,00000000,0000000000000000,0000000000000000,00000000,00000000^M ^M ^DSFLOWRPT:00000022,00 1st day 00:04:49 PPP NOTICE Standard authentication. 1st day 00:04:49 3G/4G INFO Run 3G dial cmd: pppd 3g 1 /dev/ttyUSB0 115200 connect "chat -s -v -f /etc/ppp/c 1st day 00:04:50 PPP INFO chat:get_string : OK 1st day 00:04:50 PPP INFO chat:get_string : OK 1st day 00:04:50 PPP INFO chat:get_string : OK 1st day 00:04:50 PPP INFO chat:get_string : CONNECT 1st day 00:04:50 PPP INFO chat:terminate: status == 0 1st day 00:04:51 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0x8dd7b9a6] 1st day 00:04:54 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0x8dd7b9a6] 1st day 00:04:57 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0x8dd7b9a6] 1st day 00:05:00 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0x8dd7b9a6] 1st day 00:05:03 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0x8dd7b9a6] 1st day 00:05:06 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0x8dd7b9a6] 1st day 00:05:09 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0x8dd7b9a6] 1st day 00:05:12 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0x8dd7b9a6] 1st day 00:05:15 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0x8dd7b9a6] 1st day 00:05:18 PPP INFO sent [LCP Req mru=1480 asyncmap=0x0 magic=0x8dd7b9a6] 1st day 00:05:21 PPP INFO chat:terminate: status == 0 1st day 00:05:40 3G/4G NOTICE Now disconnect times is 1 1st day 00:05:40 3G/4G NOTICE 3G Modem connecting error, please check the dial number and APN. 1st day 00:05:40 3G/4G INFO sw3GMobileCmdReq 378 mobileFormatCmd error 1st day 00:05:42 3G/4G NOTICE modem index is 4 1st day 00:05:42 3G/4G NOTICE setModemISP 1331 Get ISP by AT command. 1st day 00:05:49 3G/4G NOTICE modemATCommandGetISP 881 ret = 1, bufISP = 1st day 00:05:49 3G/4G NOTICE modemATCommandGetISP 918 counter = 0 1st day 00:05:49 3G/4G NOTICE setModemISP 1348 Get ISP by AT command failed. 1st day 00:06:11 PPP NOTICE Standard authentication. 1st day 00:06:11 3G/4G INFO Run 3G dial cmd: pppd 3g 1 /dev/ttyUSB0 115200 connect "chat -s -v -f /etc/ppp/c 1st day 00:06:28 PPP INFO chat:terminate: status == 3 1st day 00:06:47 3G/4G NOTICE Now disconnect times is 2 1st day 00:06:47 3G/4G NOTICE 3G Modem can't connect to network, now reset itself to try connecting again. 1st day 00:06:47 3G/4G NOTICE Reset the 3G modem, please wait. 1st day 00:06:49 3G/4G INFO sw3GMobileCmdReq 378 mobileFormatCmd error 1st day 00:06:51 3G/4G NOTICE modem index is 4 1st day 00:06:51 3G/4G NOTICE SIM is locked, can not get ISP by commands, return.