Ticket #38: disconnectUSB.txt

File disconnectUSB.txt, 13.4 KB (added by AlexLir, 4 years ago)
Line 
1usb 1-2: new high-speed USB device number 3 using atmel-ehci
2Jan 31 04:57:55 kernel: usb 1-2: new high-speed USB device number 3 using atmel-ehci
3usb 1-2: New USB device found, idVendor=1e0e, idProduct=9001
4usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
5usb 1-2: Product: SimTech, Incorporated
6usb 1-2: Manufacturer: SimTech, Incorporated
7usb 1-2: SerialNumber: 0123456789ABCDEF
8option 1-2:1.0: GSM modem (1-port) converter detected
9usb 1-2: GSM modem (1-port) converter now attached to ttyUSB0
10option 1-2:1.1: GSM modem (1-port) converter detected
11usb 1-2: GSM modem (1-port) converter now attached to ttyUSB1
12option 1-2:1.2: GSM modem (1-port) converter detected
13usb 1-2: GSM modem (1-port) converter now attached to ttyUSB2
14option 1-2:1.3: GSM modem (1-port) converter detected
15usb 1-2: GSM modem (1-port) converter now attached to ttyUSB3
16option 1-2:1.4: GSM modem (1-port) converter detected
17usb 1-2: GSM modem (1-port) converter now attached to ttyUSB4
18usb 1-2: USB disconnect, device number 2
19option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
20option 1-2:1.0: device disconnected
21option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
22option 1-2:1.1: device disconnected
23option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
24option 1-2:1.2: device disconnected
25option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
26option 1-2:1.3: device disconnected
27option1 ttyUSB4: GSM modem (1-port) converter now disconnected from ttyUSB4
28option 1-2:1.4: device disconnected
29Jan 31 04:56:27 kernel: usb 1-2: USB disconnect, device number 2
30Jan 31 04:56:27 kernel: option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
31Jan 31 04:56:27 kernel: option 1-2:1.0: device disconnected
32Jan 31 04:56:27 kernel: option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
33Jan 31 04:56:27 kernel: option 1-2:1.1: device disconnected
34Jan 31 04:56:27 3gd[243]: sim5320.cpp:169: powering off SIM5320...
35Jan 31 04:56:27 kernel: option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
36Jan 31 04:56:27 kernel: option 1-2:1.2: device disconnected
37Jan 31 04:56:27 pppd[351]: Hangup (SIGHUP)
38Jan 31 04:56:27 pppd[351]: Modem hangup
39Jan 31 04:56:27 pppd[351]: Connect time 4.0 minutes.
40Jan 31 04:56:27 pppd[351]: Sent 172704722 bytes, received 5761449 bytes.
41Jan 31 04:56:27 pppd[351]: Script /etc/ppp/ip-down started (pid 398)
42Jan 31 04:56:27 pppd[351]: Connection terminated.
43Jan 31 04:56:27 kernel: option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
44Jan 31 04:56:27 kernel: option 1-2:1.3: device disconnected
45Jan 31 04:56:27 kernel: option1 ttyUSB4: GSM modem (1-port) converter now disconnected from ttyUSB4
46Jan 31 04:56:27 kernel: option 1-2:1.4: device disconnected
47Jan 31 04:56:27 pppd[351]: Script /etc/ppp/ip-down finished (pid 398), status = 0x0
48Jan 31 04:56:29 ntpd[254]: Deleting interface #10 ppp0, 78.25.91.39#123, interface stats: received=16, sent=16, dropped=1, active_time=240 secs
49Jan 31 04:56:29 ntpd[254]: 91.189.94.4 local addr 78.25.91.39 -> <null>
50Jan 31 04:56:29 ntpd[254]: 91.189.91.157 local addr 78.25.91.39 -> <null>
51Jan 31 04:56:29 ntpd[254]: 91.189.89.199 local addr 78.25.91.39 -> <null>
52Jan 31 04:56:29 ntpd[254]: 91.189.89.198 local addr 78.25.91.39 -> <null>
53Jan 31 04:56:41 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7
54Jan 31 04:56:57 pppd[351]: Failed to open /dev/modem: No such file or directory
55Jan 31 04:57:00 3gd[243]: sim5320.cpp:146: powering on SIM5320...
56Jan 31 04:57:15 3gd[243]: sim5320.cpp:195: cannot open /dev/ttyUSB2: No such file or directory
57Jan 31 04:57:15 3gd[243]: sim5320.cpp:169: powering off SIM5320...
58Jan 31 04:57:27 pppd[351]: Failed to open /dev/modem: No such file or directory
59Jan 31 04:57:47 ntpd[254]: error resolving pool ntp.ubuntu.com: Temporary failure in name resolution (-3)
60Jan 31 04:57:48 3gd[243]: sim5320.cpp:146: powering on SIM5320...
61usb 1-2: new high-speed USB device number 3 using atmel-ehci
62Jan 31 04:57:55 kernel: usb 1-2: new high-speed USB device number 3 using atmel-ehci
63usb 1-2: New USB device found, idVendor=1e0e, idProduct=9001
64usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
65usb 1-2: Product: SimTech, Incorporated
66usb 1-2: Manufacturer: SimTech, Incorporated
67usb 1-2: SerialNumber: 0123456789ABCDEF
68option 1-2:1.0: GSM modem (1-port) converter detected
69usb 1-2: GSM modem (1-port) converter now attached to ttyUSB0
70option 1-2:1.1: GSM modem (1-port) converter detected
71usb 1-2: GSM modem (1-port) converter now attached to ttyUSB1
72option 1-2:1.2: GSM modem (1-port) converter detected
73usb 1-2: GSM modem (1-port) converter now attached to ttyUSB2
74option 1-2:1.3: GSM modem (1-port) converter detected
75usb 1-2: GSM modem (1-port) converter now attached to ttyUSB3
76option 1-2:1.4: GSM modem (1-port) converter detected
77usb 1-2: GSM modem (1-port) converter now attached to ttyUSB4
78Jan 31 04:57:55 kernel: usb 1-2: New USB device found, idVendor=1e0e, idProduct=9001
79Jan 31 04:57:55 kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
80Jan 31 04:57:55 kernel: usb 1-2: Product: SimTech, Incorporated
81Jan 31 04:57:55 kernel: usb 1-2: Manufacturer: SimTech, Incorporated
82Jan 31 04:57:55 kernel: usb 1-2: SerialNumber: 0123456789ABCDEF
83Jan 31 04:57:55 kernel: option 1-2:1.0: GSM modem (1-port) converter detected
84Jan 31 04:57:55 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB0
85Jan 31 04:57:55 kernel: option 1-2:1.1: GSM modem (1-port) converter detected
86Jan 31 04:57:55 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB1
87Jan 31 04:57:55 kernel: option 1-2:1.2: GSM modem (1-port) converter detected
88Jan 31 04:57:55 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB2
89Jan 31 04:57:55 kernel: option 1-2:1.3: GSM modem (1-port) converter detected
90Jan 31 04:57:55 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB3
91Jan 31 04:57:55 kernel: option 1-2:1.4: GSM modem (1-port) converter detected
92Jan 31 04:57:55 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB4
93Jan 31 04:57:58 chat[594]: abort on (BUSY)
94Jan 31 04:57:58 chat[594]: abort on (VOICE)
95Jan 31 04:57:58 chat[594]: abort on (NO CARRIER)
96Jan 31 04:57:58 chat[594]: abort on (NO DIALTONE)
97Jan 31 04:57:58 chat[594]: abort on (NO DIAL TONE)
98Jan 31 04:57:58 chat[594]: send (AT^M)
99Jan 31 04:57:58 chat[594]: expect (OK)
100Jan 31 04:58:01 chat[594]: AT^M^M
101Jan 31 04:58:01 chat[594]: OK
102Jan 31 04:58:01 chat[594]: -- got it
103Jan 31 04:58:01 chat[594]: send (AT+CGDATA="PPP",1^M)
104Jan 31 04:58:01 chat[594]: expect (CONNECT)
105Jan 31 04:58:01 chat[594]: ^M
106Jan 31 04:58:01 chat[594]: AT+CGDATA="PPP",1^M^M
107Jan 31 04:58:01 chat[594]: ERROR^M
108Jan 31 04:58:02 chat[594]: ^M
109Jan 31 04:58:02 chat[594]: +CPIN: READY^M
110Jan 31 04:58:02 chat[594]: ^M
111Jan 31 04:58:02 chat[594]: SMS DONE^M
112Jan 31 04:58:04 chat[594]: ^M
113Jan 31 04:58:04 chat[594]: PB DONE^M
114Jan 31 04:58:04 3gd[243]: sim5320.cpp:222: SIM5320 powered on, initializing...
115Jan 31 04:58:04 chat[594]: $GPGGA,,,,,,0,,,,,,,,*66^M
116Jan 31 04:58:04 chat[594]: $GPVTG,,T,,M,,N,,K,N*2C^M
117Jan 31 04:58:04 chat[594]: $GPRMC,,V,,,,,,,,,,N*53^M
118Jan 31 04:58:04 chat[594]: $GPGSA,A,1,,,,,,,,,,,,,,,*1E^M
119Jan 31 04:58:04 chat[594]: ^M
120Jan 31 04:58:04 chat[594]: +CPSI: LTE,Online,250-02,0x1712,151125782,128,EUTRAN-BAND7,3048,5,5,-109,-1088,-
121Jan 31 04:58:04 chat[594]: 792,11^M
122Jan 31 04:58:04 chat[594]: SIGTERM
123Jan 31 04:58:04 pppd[351]: Child process /usr/sbin/chat -v -f /etc/chatscripts/pap (pid 593) terminated with signal 15
124Jan 31 04:58:04 pppd[351]: Connect script failed
125Jan 31 04:58:05 pppd[351]: Exit.
126Jan 31 04:58:09 pppd[627]: pppd 2.4.7 started by root, uid 0
127Jan 31 04:58:10 chat[631]: abort on (BUSY)
128Jan 31 04:58:10 chat[631]: abort on (VOICE)
129Jan 31 04:58:10 chat[631]: abort on (NO CARRIER)
130Jan 31 04:58:10 chat[631]: abort on (NO DIALTONE)
131Jan 31 04:58:10 chat[631]: abort on (NO DIAL TONE)
132Jan 31 04:58:10 chat[631]: send (AT^M)
133Jan 31 04:58:10 chat[631]: expect (OK)
134Jan 31 04:58:10 chat[631]: ^M
135Jan 31 04:58:10 chat[631]: OK
136Jan 31 04:58:10 chat[631]: -- got it
137Jan 31 04:58:10 chat[631]: send (AT+CGDATA="PPP",1^M)
138Jan 31 04:58:10 chat[631]: expect (CONNECT)
139Jan 31 04:58:10 chat[631]: ^M
140Jan 31 04:58:10 chat[631]: CONNECT
141Jan 31 04:58:10 chat[631]: -- got it
142Jan 31 04:58:10 chat[631]: send (^M)
143Jan 31 04:58:10 pppd[627]: Script /usr/sbin/chat -v -f /etc/chatscripts/pap finished (pid 630), status = 0x0
144Jan 31 04:58:10 pppd[627]: Serial connection established.
145Jan 31 04:58:10 pppd[627]: using channel 2
146Jan 31 04:58:10 pppd[627]: Using interface ppp0
147Jan 31 04:58:10 pppd[627]: Connect: ppp0 <--> /dev/modem
148Jan 31 04:58:11 pppd[627]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x9c54d9cb> <pcomp> <accomp>]
149Jan 31 04:58:11 pppd[627]: rcvd [LCP ConfReq id=0x0 <asyncmap 0x0> <auth chap MD5> <magic 0xd115b27b> <pcomp> <accomp>]
150Jan 31 04:58:11 pppd[627]: No auth is possible
151Jan 31 04:58:11 pppd[627]: sent [LCP ConfRej id=0x0 <auth chap MD5>]
152Jan 31 04:58:11 pppd[627]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x9c54d9cb> <pcomp> <accomp>]
153Jan 31 04:58:11 pppd[627]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xd115b27b> <pcomp> <accomp>]
154Jan 31 04:58:11 pppd[627]: sent [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0xd115b27b> <pcomp> <accomp>]
155Jan 31 04:58:11 pppd[627]: sent [LCP EchoReq id=0x0 magic=0x9c54d9cb]
156Jan 31 04:58:11 pppd[627]: kernel does not support PPP filtering
157Jan 31 04:58:11 pppd[627]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
158Jan 31 04:58:11 pppd[627]: sent [IPV6CP ConfReq id=0x1 <addr fe80::9489:d4e1:361d:a741>]
159Jan 31 04:58:11 pppd[627]: rcvd [LCP DiscReq id=0x2 magic=0xd115b27b]
160Jan 31 04:58:11 pppd[627]: rcvd [LCP EchoRep id=0x0 magic=0xd115b27b 9c 54 d9 cb]
161Jan 31 04:58:11 pppd[627]: rcvd [IPCP ConfReq id=0x0]
162Jan 31 04:58:11 pppd[627]: sent [IPCP ConfNak id=0x0 <addr 0.0.0.0>]
163Jan 31 04:58:11 pppd[627]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
164Jan 31 04:58:11 pppd[627]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
165Jan 31 04:58:11 pppd[627]: rcvd [IPCP ConfReq id=0x1]
166Jan 31 04:58:11 pppd[627]: sent [IPCP ConfAck id=0x1]
167Jan 31 04:58:11 pppd[627]: rcvd [IPCP ConfNak id=0x2 <addr 78.25.91.39> <ms-dns1 31.173.96.52> <ms-dns2 31.173.96.60>]
168Jan 31 04:58:11 pppd[627]: sent [IPCP ConfReq id=0x3 <addr 78.25.91.39> <ms-dns1 31.173.96.52> <ms-dns2 31.173.96.60>]
169Jan 31 04:58:11 pppd[627]: rcvd [IPCP ConfAck id=0x3 <addr 78.25.91.39> <ms-dns1 31.173.96.52> <ms-dns2 31.173.96.60>]
170Jan 31 04:58:11 pppd[627]: Could not determine remote IP address: defaulting to 10.64.64.64
171Jan 31 04:58:12 dnsmasq[248]: reading /var/run/ppp/resolv.conf
172Jan 31 04:58:12 dnsmasq[248]: using nameserver 31.173.96.52#53
173Jan 31 04:58:12 dnsmasq[248]: using nameserver 31.173.96.60#53
174Jan 31 04:58:12 pppd[627]: local IP address 78.25.91.39
175Jan 31 04:58:12 pppd[627]: remote IP address 10.64.64.64
176Jan 31 04:58:12 pppd[627]: primary DNS address 31.173.96.52
177Jan 31 04:58:12 pppd[627]: secondary DNS address 31.173.96.60
178Jan 31 04:58:12 pppd[627]: Script /etc/ppp/ip-up started (pid 638)
179Jan 31 04:58:12 pppd[627]: Script /etc/ppp/ip-up finished (pid 638), status = 0x0
180Jan 31 04:58:13 ntpd[254]: Listen normally on 11 ppp0 78.25.91.39:123
181Jan 31 04:58:13 ntpd[254]: new interface(s) found: waking up resolver
182Jan 31 04:58:14 openvpn[298]: MC04-WL/46.229.134.176 write TCPv6_SERVER: Connection reset by peer (code=104)
183Jan 31 04:58:14 openvpn[298]: MC04-WL/46.229.134.176 write TCPv6_SERVER: Broken pipe (code=32)
184Jan 31 04:58:14 openvpn[298]: MC04-WL/46.229.134.176 Connection reset, restarting [0]
185Jan 31 04:58:14 pppd[627]: sent [IPV6CP ConfReq id=0x1 <addr fe80::9489:d4e1:361d:a741>]
186Jan 31 04:58:15 openvpn[298]: TCP connection established with [AF_INET6]::ffff:46.229.134.176:47584
187Jan 31 04:58:17 openvpn[298]: 46.229.134.176 peer info: IV_VER=2.4.4
188Jan 31 04:58:17 openvpn[298]: 46.229.134.176 peer info: IV_PLAT=linux
189Jan 31 04:58:17 openvpn[298]: 46.229.134.176 peer info: IV_PROTO=2
190Jan 31 04:58:17 openvpn[298]: 46.229.134.176 peer info: IV_NCP=2
191Jan 31 04:58:17 openvpn[298]: 46.229.134.176 peer info: IV_LZ4=1
192Jan 31 04:58:17 openvpn[298]: 46.229.134.176 peer info: IV_LZ4v2=1
193Jan 31 04:58:17 openvpn[298]: 46.229.134.176 peer info: IV_LZO=1
194Jan 31 04:58:17 openvpn[298]: 46.229.134.176 peer info: IV_COMP_STUB=1
195Jan 31 04:58:17 openvpn[298]: 46.229.134.176 peer info: IV_COMP_STUBv2=1
196Jan 31 04:58:17 openvpn[298]: 46.229.134.176 peer info: IV_TCPNL=1
197Jan 31 04:58:17 openvpn[298]: 46.229.134.176 WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1575', remote='link-mtu 1583'
198Jan 31 04:58:17 openvpn[298]: 46.229.134.176 WARNING: 'cipher' is used inconsistently, local='cipher BF-CBC', remote='cipher AES-256-GCM'
199Jan 31 04:58:17 openvpn[298]: 46.229.134.176 WARNING: 'auth' is used inconsistently, local='auth SHA1', remote='auth [null-digest]'
200Jan 31 04:58:17 openvpn[298]: 46.229.134.176 WARNING: 'keysize' is used inconsistently, local='keysize 128', remote='keysize 256'
201Jan 31 04:58:17 openvpn[298]: 46.229.134.176 [MC04-WL] Peer Connection Initiated with [AF_INET6]::ffff:46.229.134.176:47584
202Jan 31 04:58:17 openvpn[298]: MC04-WL/46.229.134.176 MULTI: no dynamic or static remote --ifconfig address is available for MC04-WL/46.229.134.176
203Jan 31 04:58:17 pppd[627]: sent [IPV6CP ConfReq id=0x1 <addr fe80::9489:d4e1:361d:a741>]