Opened 4 years ago

Last modified 4 years ago

#53 new баг

USB disconnect при работе без антенны.

Reported by: san Owned by: andrei
Priority: major Milestone: 1 очередь
Keywords: Cc: alx

Description

Отключил основную антенну от сима, в условиях нашего офиса, он работает и без антенны, но через некоторое время SIM перезапускается. В логе вижу внезапный USB disconnect (07:47:20).

Есть догадки что это такое? Стоит ли на это обращать внимание?
Воспроизводится стабильно на моём экземпляре.

Jun 30 07:39:43 openvpn[297]: [MC04-WL] Peer Connection Initiated with [AF_INET]46.229.134.176:1194
Jun 30 07:39:44 openvpn[297]: TUN/TAP device tap0 opened
Jun 30 07:39:44 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): tap0: link becomes ready
Jun 30 07:39:44 kernel: br0: port 2(tap0) entered blocking state
Jun 30 07:39:44 kernel: br0: port 2(tap0) entered forwarding state
Jun 30 07:39:44 openvpn[297]: WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this
Jun 30 07:39:44 openvpn[297]: Initialization Sequence Completed
Jun 30 07:39:44 pppd[354]: sent [IPV6CP ConfReq id=0x1 <addr fe80::102d:025c:0221:4786>]
Jun 30 07:39:47 ntpd[255]: Listen normally on 10 tap0 [fe80::8c05:bdff:fef7:9b26%4]:123
Jun 30 07:39:47 ntpd[255]: new interface(s) found: waking up resolver
Jun 30 07:39:47 pppd[354]: sent [IPV6CP ConfReq id=0x1 <addr fe80::102d:025c:0221:4786>]
Jun 30 07:39:50 pppd[354]: sent [IPV6CP ConfReq id=0x1 <addr fe80::102d:025c:0221:4786>]
Jun 30 07:39:53 pppd[354]: sent [IPV6CP ConfReq id=0x1 <addr fe80::102d:025c:0221:4786>]
Jun 30 07:39:56 pppd[354]: sent [IPV6CP ConfReq id=0x1 <addr fe80::102d:025c:0221:4786>]
Jun 30 07:39:59 pppd[354]: sent [IPV6CP ConfReq id=0x1 <addr fe80::102d:025c:0221:4786>]
Jun 30 07:40:02 pppd[354]: IPV6CP: timeout sending Config-Requests
Jun 30 07:40:16 ntpd[255]: Soliciting pool server 2001:67c:1560:8003::c7
Jun 30 07:40:17 ntpd[255]: Soliciting pool server 2001:67c:1560:8003::c8
Jun 30 07:40:18 ntpd[255]: Soliciting pool server 91.189.89.199
Jun 30 07:40:19 ntpd[255]: Soliciting pool server 91.189.94.4
Jun 30 07:41:26 ntpd[255]: Soliciting pool server 91.189.89.198
Jun 30 07:41:27 ntpd[255]: Soliciting pool server 91.189.91.157
Jun 30 07:41:28 ntpd[255]: Soliciting pool server 91.189.94.4
Jun 30 07:44:57 dropbear[397]: Child connection from 46.146.217.16:52280
Jun 30 07:45:37 dropbear[397]: Password auth succeeded for 'root' from 46.146.217.16:52280
Jun 30 07:46:25 3gd[243]: http_server.cpp:700: user admin from [::ffff:46.146.217.16] authenticated
Jun 30 07:47:20 kernel: usb 1-2: USB disconnect, device number 2
Jun 30 07:47:20 kernel: option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
Jun 30 07:47:20 kernel: option 1-2:1.0: device disconnected
Jun 30 07:47:20 kernel: option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
Jun 30 07:47:20 kernel: option 1-2:1.1: device disconnected
Jun 30 07:47:20 3gd[243]: sim5320.cpp:368: epoll(): /dev/ttyUSB2 error (events == 0x0019)
Jun 30 07:47:20 3gd[243]: sim5320.cpp:372: /dev/ttyUSB2: No such file or directory
Jun 30 07:47:20 kernel: option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
Jun 30 07:47:20 kernel: option 1-2:1.2: device disconnected
Jun 30 07:47:20 3gd[243]: sim5320.cpp:391: waiting for 10 sec. to reconnect...
Jun 30 07:47:20 pppd[354]: Hangup (SIGHUP)
Jun 30 07:47:20 pppd[354]: Connect time 7.8 minutes.
Jun 30 07:47:20 pppd[354]: Sent 96781559 bytes, received 7748602 bytes.
Jun 30 07:47:20 kernel: option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
Jun 30 07:47:20 kernel: option 1-2:1.3: device disconnected
Jun 30 07:47:20 pppd[354]: Script /etc/ppp/ip-down started (pid 417)
Jun 30 07:47:20 pppd[354]: sent [LCP TermReq id=0x2 "User request"]
Jun 30 07:47:20 pppd[354]: Modem hangup
Jun 30 07:47:20 pppd[354]: Connection terminated.
Jun 30 07:47:20 kernel: option1 ttyUSB4: GSM modem (1-port) converter now disconnected from ttyUSB4
Jun 30 07:47:20 kernel: option 1-2:1.4: device disconnected
Jun 30 07:47:20 pppd[354]: Script /etc/ppp/ip-down finished (pid 417), status = 0x0
Jun 30 07:47:21 ntpd[255]: Deleting interface #9 ppp0, 178.176.107.254#123, interface stats: received=29, sent=30, dropped=0, active_time=467 secs
Jun 30 07:47:21 ntpd[255]: 91.189.94.4 local addr 178.176.107.254 -> <null>
Jun 30 07:47:21 ntpd[255]: 91.189.91.157 local addr 178.176.107.254 -> <null>
Jun 30 07:47:21 ntpd[255]: 91.189.89.198 local addr 178.176.107.254 -> <null>
Jun 30 07:47:21 ntpd[255]: 91.189.89.199 local addr 178.176.107.254 -> <null>
Jun 30 07:47:26 kernel: usb 1-2: new high-speed USB device number 3 using atmel-ehci
Jun 30 07:47:26 kernel: usb 1-2: New USB device found, idVendor=1e0e, idProduct=9001
Jun 30 07:47:26 kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jun 30 07:47:26 kernel: usb 1-2: Product: SimTech, Incorporated
Jun 30 07:47:26 kernel: usb 1-2: Manufacturer: SimTech, Incorporated
Jun 30 07:47:26 kernel: usb 1-2: SerialNumber: 0123456789ABCDEF
Jun 30 07:47:26 kernel: option 1-2:1.0: GSM modem (1-port) converter detected
Jun 30 07:47:26 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB0
Jun 30 07:47:26 kernel: option 1-2:1.1: GSM modem (1-port) converter detected
Jun 30 07:47:26 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB1
Jun 30 07:47:26 kernel: option 1-2:1.2: GSM modem (1-port) converter detected
Jun 30 07:47:26 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB2
Jun 30 07:47:26 kernel: option 1-2:1.3: GSM modem (1-port) converter detected
Jun 30 07:47:26 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB3
Jun 30 07:47:26 kernel: option 1-2:1.4: GSM modem (1-port) converter detected
Jun 30 07:47:26 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB4
Jun 30 07:47:29 3gd[243]: tcp_tty.cpp:398: TCP inactivity timeout
Jun 30 07:47:29 3gd[243]: link.cpp:192: --> Link::error(): connection closed (19)
Jun 30 07:47:30 3gd[243]: sim5320.cpp:245: trying to reconnect /dev/ttyUSB2...
Jun 30 07:47:30 3gd[243]: sim5320.cpp:275: /dev/ttyUSB2 reconnected.
Jun 30 07:47:32 3gd[243]: sim5320.cpp:517: Sim5320: команда 'AT+CSCS="UCS2"' завершилась ошибкой, повтор (1)
Jun 30 07:47:32 3gd[243]: sim5320.cpp:517: Sim5320: команда 'AT+CSCS="UCS2"' завершилась ошибкой, повтор (2)
Jun 30 07:47:32 3gd[243]: sim5320.cpp:517: Sim5320: команда 'AT+CSCS="UCS2"' завершилась ошибкой, повтор (3)
Jun 30 07:47:32 3gd[243]: sim5320.cpp:517: Sim5320: команда 'AT+CSCS="UCS2"' завершилась ошибкой, повтор (4)
Jun 30 07:47:32 3gd[243]: sim5320.cpp:517: Sim5320: команда 'AT+CSCS="UCS2"' завершилась ошибкой, повтор (5)
Jun 30 07:47:32 3gd[243]: sim5320.cpp:517: Sim5320: команда 'AT+CSCS="UCS2"' завершилась ошибкой, повтор (6)
Jun 30 07:47:32 3gd[243]: sim5320.cpp:517: Sim5320: команда 'AT+CSCS="UCS2"' завершилась ошибкой, повтор (7)
Jun 30 07:47:32 3gd[243]: sim5320.cpp:517: Sim5320: команда 'AT+CSCS="UCS2"' завершилась ошибкой, повтор (8)
Jun 30 07:47:32 3gd[243]: sim5320.cpp:517: Sim5320: команда 'AT+CSCS="UCS2"' завершилась ошибкой, повтор (9)
Jun 30 07:47:32 3gd[243]: sim5320.cpp:512: Sim5320: исчерпаны попытки выполнения команды 'AT+CSCS="UCS2"', рестарт модуля...
Jun 30 07:47:32 3gd[243]: sim5320.cpp:179: powering off SIM5320...

Change History (2)

comment:1 by alx, 4 years ago

Симптомы похожи на случай тикета #38.

comment:2 by san, 4 years ago

Судя по логу SIM отвалился от усб на 6 секунд, а потом снова прицепился.
А после прицепления стал ругаться ошибками, в ответ на попытки 'AT+CSCS="UCS2"' ? так?

команда 'AT+CSCS="UCS2"' завершилась ошибкой, повтор (9)

Note: See TracTickets for help on using tickets.