Jun 27 20:30:32 openvpn[300]: MC04-WL/37.29.46.78 peer info: IV_VER=2.4.4 Jun 27 20:30:32 openvpn[300]: MC04-WL/37.29.46.78 peer info: IV_PLAT=linux Jun 27 20:30:32 openvpn[300]: MC04-WL/37.29.46.78 peer info: IV_PROTO=2 Jun 27 20:30:32 openvpn[300]: MC04-WL/37.29.46.78 peer info: IV_LZ4=1 Jun 27 20:30:32 openvpn[300]: MC04-WL/37.29.46.78 peer info: IV_LZ4v2=1 Jun 27 20:30:32 openvpn[300]: MC04-WL/37.29.46.78 peer info: IV_LZO=1 Jun 27 20:30:32 openvpn[300]: MC04-WL/37.29.46.78 peer info: IV_COMP_STUB=1 Jun 27 20:30:32 openvpn[300]: MC04-WL/37.29.46.78 peer info: IV_COMP_STUBv2=1 Jun 27 20:30:32 openvpn[300]: MC04-WL/37.29.46.78 peer info: IV_TCPNL=1 Jun 27 20:30:56 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:32:03 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:33:08 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:34:13 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:35:17 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:35:37 dropbear[9326]: Child connection from 162.243.131.188:33370 Jun 27 20:35:47 dropbear[9326]: Exit before auth: Exited normally Jun 27 20:36:21 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:37:28 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:38:32 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:39:39 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:40:43 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:41:50 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:42:56 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:44:01 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:45:07 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:46:13 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:47:19 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:48:23 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:49:30 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:50:34 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:51:40 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:52:47 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:53:54 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:55:01 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:56:08 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:57:13 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:58:02 3gd[246]: link.cpp:363: connect from ::ffff:185.176.221.168:63377 (19) Jun 27 20:58:02 3gd[246]: link.cpp:192: --> Link::error(): connection closed (19) Jun 27 20:58:02 3gd[246]: sim5320.cpp:676: Sim5320: too short $GPGSV sentence: 2 < 11 Jun 27 20:58:18 ntpd[257]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:59:01 3gd[9429]: 3gd.cpp:61: 3gd-1.0-r160 started Jun 27 20:59:01 3gd[9429]: 3gd.cpp:67: serial number: unknown Jun 27 20:59:01 3gd[9429]: sim5320.cpp:156: powering on SIM5320... Jun 27 20:59:01 3gd[9429]: http_server.cpp:2145: HTTP daemon created Jun 27 20:59:01 3gd[9429]: serial_tty.cpp:65: /dev/ttyS2: port opened with fd 13 Jun 27 20:59:01 3gd[9429]: serial_tty.cpp:65: /dev/ttyS1: port opened with fd 15 Jun 27 20:59:01 openvpn[300]: SIGTERM[hard,] received, process exiting Jun 27 20:59:01 kernel: br0: port 2(tap0) entered disabled state Jun 27 20:59:02 openvpn[9442]: WARNING: file '/usr/share/3gd/key.pem' is group or others accessible Jun 27 20:59:02 openvpn[9442]: OpenVPN 2.4.4 arm-poky-linux-gnueabi [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [MH/PKTINFO] [AEAD] built on Dec 5 2019 Jun 27 20:59:02 openvpn[9442]: library versions: OpenSSL 1.1.1 11 Sep 2018, LZO 2.10 Jun 27 20:59:02 openvpn[9443]: WARNING: --keepalive option is missing from server config Jun 27 20:59:02 kernel: br0: port 2(tap0) entered blocking state Jun 27 20:59:02 kernel: br0: port 2(tap0) entered forwarding state Jun 27 20:59:02 openvpn[9443]: TUN/TAP device tap0 opened Jun 27 20:59:02 openvpn[9443]: setsockopt(IPV6_V6ONLY=0) Jun 27 20:59:02 openvpn[9443]: Listening for incoming TCP connection on [AF_INET6][undef]:1194 Jun 27 20:59:02 openvpn[9443]: TCPv6_SERVER link local (bound): [AF_INET6][undef]:1194 Jun 27 20:59:02 openvpn[9443]: TCPv6_SERVER link remote: [AF_UNSPEC] Jun 27 20:59:02 openvpn[9443]: Initialization Sequence Completed Jun 27 20:59:04 3gd[9429]: link.cpp:363: connect from ::ffff:37.29.46.78:36554 (19) Jun 27 20:59:04 3gd[9429]: tls_tty.cpp:169: TLS connection established: TLSv1.2 GOST2012-GOST8912-GOST8912 Jun 27 20:59:06 openvpn[9443]: TCP connection established with [AF_INET6]::ffff:37.29.46.78:52704 Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 peer info: IV_VER=2.4.4 Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 peer info: IV_PLAT=linux Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 peer info: IV_PROTO=2 Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 peer info: IV_NCP=2 Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 peer info: IV_LZ4=1 Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 peer info: IV_LZ4v2=1 Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 peer info: IV_LZO=1 Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 peer info: IV_COMP_STUB=1 Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 peer info: IV_COMP_STUBv2=1 Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 peer info: IV_TCPNL=1 Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1575', remote='link-mtu 1583' Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 WARNING: 'cipher' is used inconsistently, local='cipher BF-CBC', remote='cipher AES-256-GCM' Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 WARNING: 'auth' is used inconsistently, local='auth SHA1', remote='auth [null-digest]' Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 WARNING: 'keysize' is used inconsistently, local='keysize 128', remote='keysize 256' Jun 27 20:59:08 openvpn[9443]: 37.29.46.78 [MC04-WL] Peer Connection Initiated with [AF_INET6]::ffff:37.29.46.78:52704 Jun 27 20:59:08 openvpn[9443]: MC04-WL/37.29.46.78 MULTI: no dynamic or static remote --ifconfig address is available for MC04-WL/37.29.46.78 Jun 27 20:59:16 3gd[9429]: sim5320.cpp:232: SIM5320 powered on, initializing... Jun 27 20:59:16 3gd[9429]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (1) Jun 27 20:59:16 3gd[9429]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (2) Jun 27 20:59:16 3gd[9429]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (3) Jun 27 20:59:16 3gd[9429]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (4) Jun 27 20:59:16 3gd[9429]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (5) Jun 27 20:59:16 3gd[9429]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (6) Jun 27 20:59:16 pppd[355]: Terminating on signal 15 Jun 27 20:59:16 pppd[355]: Connect time 218387.9 minutes. Jun 27 20:59:16 pppd[355]: Sent 3473099451 bytes, received 4251810040 bytes. Jun 27 20:59:16 3gd[9429]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (7) Jun 27 20:59:16 3gd[9429]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (8) Jun 27 20:59:16 pppd[355]: Script /etc/ppp/ip-down started (pid 9448) Jun 27 20:59:16 pppd[355]: sent [LCP TermReq id=0x2 "User request"] Jun 27 20:59:16 3gd[9429]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (9) Jun 27 20:59:16 3gd[9429]: sim5320.cpp:512: Sim5320: исчерпаны попытки выполнения команды 'AT+CGPS=1,1', рестарт модуля... Jun 27 20:59:16 pppd[355]: rcvd [LCP TermAck id=0x2] Jun 27 20:59:16 pppd[355]: Connection terminated. Jun 27 20:59:16 3gd[9429]: sim5320.cpp:179: powering off SIM5320... Jun 27 20:59:16 pppd[355]: Script /etc/ppp/ip-down finished (pid 9448), status = 0x0 Jun 27 20:59:16 pppd[355]: Exit. Jun 27 20:59:18 ntpd[257]: Deleting interface #9 ppp0, 78.25.91.39#123, interface stats: received=718, sent=698, dropped=0, active_time=124546 secs Jun 27 20:59:18 ntpd[257]: 91.189.89.198 local addr 78.25.91.39 -> Jun 27 20:59:18 ntpd[257]: 91.189.94.4 local addr 78.25.91.39 -> Jun 27 20:59:18 ntpd[257]: 91.189.89.199 local addr 78.25.91.39 -> Jun 27 20:59:18 ntpd[257]: 91.189.91.157 local addr 78.25.91.39 -> Jun 27 20:59:21 pppd[9467]: pppd 2.4.7 started by root, uid 0 Jun 27 20:59:22 chat[9471]: abort on (BUSY) Jun 27 20:59:22 chat[9471]: abort on (VOICE) Jun 27 20:59:22 chat[9471]: abort on (NO CARRIER) Jun 27 20:59:22 chat[9471]: abort on (NO DIALTONE) Jun 27 20:59:22 chat[9471]: abort on (NO DIAL TONE) Jun 27 20:59:22 chat[9471]: send (AT^M) Jun 27 20:59:22 chat[9471]: expect (OK) Jun 27 20:59:22 chat[9471]: ^M Jun 27 20:59:22 chat[9471]: OK Jun 27 20:59:22 chat[9471]: -- got it Jun 27 20:59:22 chat[9471]: send (AT+CGDATA="PPP",1^M) Jun 27 20:59:23 chat[9471]: expect (CONNECT) Jun 27 20:59:23 chat[9471]: ^M Jun 27 20:59:23 chat[9471]: CONNECT Jun 27 20:59:23 chat[9471]: -- got it Jun 27 20:59:23 chat[9471]: send (^M) Jun 27 20:59:23 pppd[9467]: Script /usr/sbin/chat -v -f /etc/chatscripts/pap finished (pid 9470), status = 0x0 Jun 27 20:59:23 pppd[9467]: Serial connection established. Jun 27 20:59:23 pppd[9467]: using channel 2 Jun 27 20:59:23 pppd[9467]: Using interface ppp0 Jun 27 20:59:23 pppd[9467]: Connect: ppp0 <--> /dev/modem Jun 27 20:59:24 pppd[9467]: sent [LCP ConfReq id=0x1 ] Jun 27 20:59:25 ntpd[257]: error resolving pool ntp.ubuntu.com: Temporary failure in name resolution (-3) Jun 27 20:59:27 pppd[9467]: sent [LCP ConfReq id=0x1 ] Jun 27 20:59:30 pppd[9467]: sent [LCP ConfReq id=0x1 ] Jun 27 20:59:30 pppd[9467]: Hangup (SIGHUP) Jun 27 20:59:30 pppd[9467]: Modem hangup Jun 27 20:59:30 pppd[9467]: Connection terminated. Jun 27 20:59:38 kernel: usb 1-2: USB disconnect, device number 2 Jun 27 20:59:38 kernel: option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0 Jun 27 20:59:38 kernel: option 1-2:1.0: device disconnected Jun 27 20:59:38 kernel: option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1 Jun 27 20:59:38 kernel: option 1-2:1.1: device disconnected Jun 27 20:59:38 kernel: option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2 Jun 27 20:59:38 kernel: option 1-2:1.2: device disconnected Jun 27 20:59:38 kernel: option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3 Jun 27 20:59:38 kernel: option 1-2:1.3: device disconnected Jun 27 20:59:38 kernel: option1 ttyUSB4: GSM modem (1-port) converter now disconnected from ttyUSB4 Jun 27 20:59:38 kernel: option 1-2:1.4: device disconnected Jun 27 20:59:49 3gd[9429]: sim5320.cpp:156: powering on SIM5320... Jun 27 20:59:55 kernel: usb 1-2: new high-speed USB device number 3 using atmel-ehci Jun 27 20:59:55 kernel: usb 1-2: New USB device found, idVendor=1e0e, idProduct=9001 Jun 27 20:59:55 kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 27 20:59:55 kernel: usb 1-2: Product: SimTech, Incorporated Jun 27 20:59:55 kernel: usb 1-2: Manufacturer: SimTech, Incorporated Jun 27 20:59:55 kernel: usb 1-2: SerialNumber: 0123456789ABCDEF Jun 27 20:59:55 kernel: option 1-2:1.0: GSM modem (1-port) converter detected Jun 27 20:59:55 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB0 Jun 27 20:59:55 kernel: option 1-2:1.1: GSM modem (1-port) converter detected Jun 27 20:59:55 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB1 Jun 27 20:59:55 kernel: option 1-2:1.2: GSM modem (1-port) converter detected Jun 27 20:59:55 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB2 Jun 27 20:59:55 kernel: option 1-2:1.3: GSM modem (1-port) converter detected Jun 27 20:59:55 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB3 Jun 27 20:59:55 kernel: option 1-2:1.4: GSM modem (1-port) converter detected Jun 27 20:59:55 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB4 Jun 27 21:00:02 chat[9550]: abort on (BUSY) Jun 27 21:00:02 chat[9550]: abort on (VOICE) Jun 27 21:00:02 chat[9550]: abort on (NO CARRIER) Jun 27 21:00:02 chat[9550]: abort on (NO DIALTONE) Jun 27 21:00:02 chat[9550]: abort on (NO DIAL TONE) Jun 27 21:00:02 chat[9550]: send (AT^M) Jun 27 21:00:02 chat[9550]: expect (OK) Jun 27 21:00:02 chat[9550]: ^M Jun 27 21:00:02 chat[9550]: +CPIN: READY^M Jun 27 21:00:02 chat[9550]: AT^M^M Jun 27 21:00:02 chat[9550]: OK Jun 27 21:00:02 chat[9550]: -- got it Jun 27 21:00:02 chat[9550]: send (AT+CGDATA="PPP",1^M) Jun 27 21:00:03 chat[9550]: expect (CONNECT) Jun 27 21:00:03 chat[9550]: ^M Jun 27 21:00:03 chat[9550]: AT+CGDATA^M Jun 27 21:00:03 chat[9550]: SMS DONE^M Jun 27 21:00:03 chat[9550]: ="PPP",1^M^M Jun 27 21:00:03 chat[9550]: CONNECT Jun 27 21:00:03 chat[9550]: -- got it Jun 27 21:00:03 chat[9550]: send (^M) Jun 27 21:00:03 pppd[9467]: Script /usr/sbin/chat -v -f /etc/chatscripts/pap finished (pid 9549), status = 0x0 Jun 27 21:00:03 pppd[9467]: Serial connection established. Jun 27 21:00:03 pppd[9467]: using channel 3 Jun 27 21:00:03 pppd[9467]: Using interface ppp0 Jun 27 21:00:03 pppd[9467]: Connect: ppp0 <--> /dev/modem Jun 27 21:00:04 pppd[9467]: sent [LCP ConfReq id=0x2 ] Jun 27 21:00:04 pppd[9467]: rcvd [LCP ConfReq id=0x0 ] Jun 27 21:00:04 pppd[9467]: No auth is possible Jun 27 21:00:04 pppd[9467]: sent [LCP ConfRej id=0x0 ] Jun 27 21:00:04 pppd[9467]: rcvd [LCP ConfAck id=0x2 ] Jun 27 21:00:04 pppd[9467]: rcvd [LCP ConfReq id=0x1 ] Jun 27 21:00:04 pppd[9467]: sent [LCP ConfAck id=0x1 ] Jun 27 21:00:04 pppd[9467]: sent [LCP EchoReq id=0x0 magic=0x358e8459] Jun 27 21:00:04 pppd[9467]: kernel does not support PPP filtering Jun 27 21:00:04 pppd[9467]: sent [IPCP ConfReq id=0x1 ] Jun 27 21:00:04 pppd[9467]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 21:00:04 pppd[9467]: rcvd [LCP DiscReq id=0x2 magic=0xd882d5c4] Jun 27 21:00:04 pppd[9467]: rcvd [LCP EchoRep id=0x0 magic=0xd882d5c4 35 8e 84 59] Jun 27 21:00:04 pppd[9467]: rcvd [IPCP ConfReq id=0x0] Jun 27 21:00:04 pppd[9467]: sent [IPCP ConfNak id=0x0 ] Jun 27 21:00:04 pppd[9467]: rcvd [IPCP ConfRej id=0x1 ] Jun 27 21:00:04 pppd[9467]: sent [IPCP ConfReq id=0x2 ] Jun 27 21:00:04 pppd[9467]: rcvd [IPCP ConfReq id=0x1] Jun 27 21:00:04 pppd[9467]: sent [IPCP ConfAck id=0x1] Jun 27 21:00:04 pppd[9467]: rcvd [IPCP ConfNak id=0x2 ] Jun 27 21:00:04 pppd[9467]: sent [IPCP ConfReq id=0x3 ] Jun 27 21:00:04 pppd[9467]: rcvd [IPCP ConfAck id=0x3 ] Jun 27 21:00:04 pppd[9467]: Could not determine remote IP address: defaulting to 10.64.64.64 Jun 27 21:00:04 dnsmasq[251]: reading /var/run/ppp/resolv.conf Jun 27 21:00:04 dnsmasq[251]: using nameserver 31.173.96.52#53 Jun 27 21:00:04 dnsmasq[251]: using nameserver 31.173.96.60#53 Jun 27 21:00:04 pppd[9467]: local IP address 78.25.91.39 Jun 27 21:00:04 pppd[9467]: remote IP address 10.64.64.64 Jun 27 21:00:04 pppd[9467]: primary DNS address 31.173.96.52 Jun 27 21:00:04 pppd[9467]: secondary DNS address 31.173.96.60 Jun 27 21:00:04 pppd[9467]: Script /etc/ppp/ip-up started (pid 9555) Jun 27 21:00:04 pppd[9467]: Script /etc/ppp/ip-up finished (pid 9555), status = 0x0 Jun 27 21:00:04 3gd[9429]: link.cpp:363: connect from ::ffff:37.29.46.78:36562 (20) Jun 27 21:00:04 3gd[9429]: tls_tty.cpp:169: TLS connection established: TLSv1.2 GOST2012-GOST8912-GOST8912 Jun 27 21:00:04 3gd[9429]: sim5320.cpp:232: SIM5320 powered on, initializing... Jun 27 21:00:05 pppd[9467]: Terminating on signal 15 Jun 27 21:00:05 pppd[9467]: Connect time 0.1 minutes. Jun 27 21:00:05 pppd[9467]: Sent 3112 bytes, received 3128 bytes. Jun 27 21:00:05 pppd[9467]: Script /etc/ppp/ip-down started (pid 9571) Jun 27 21:00:05 pppd[9467]: sent [LCP TermReq id=0x3 "User request"] Jun 27 21:00:05 pppd[9467]: rcvd [LCP TermAck id=0x3] Jun 27 21:00:05 pppd[9467]: Connection terminated. Jun 27 21:00:05 pppd[9467]: Script /etc/ppp/ip-down finished (pid 9571), status = 0x0 Jun 27 21:00:05 pppd[9467]: Exit. Jun 27 21:00:10 pppd[9589]: pppd 2.4.7 started by root, uid 0 Jun 27 21:00:11 chat[9593]: abort on (BUSY) Jun 27 21:00:11 chat[9593]: abort on (VOICE) Jun 27 21:00:11 chat[9593]: abort on (NO CARRIER) Jun 27 21:00:11 chat[9593]: abort on (NO DIALTONE) Jun 27 21:00:11 chat[9593]: abort on (NO DIAL TONE) Jun 27 21:00:11 chat[9593]: send (AT^M) Jun 27 21:00:11 chat[9593]: expect (OK) Jun 27 21:00:11 chat[9593]: ^M Jun 27 21:00:11 chat[9593]: OK Jun 27 21:00:11 chat[9593]: -- got it Jun 27 21:00:11 chat[9593]: send (AT+CGDATA="PPP",1^M) Jun 27 21:00:11 chat[9593]: expect (CONNECT) Jun 27 21:00:11 chat[9593]: ^M Jun 27 21:00:11 chat[9593]: CONNECT Jun 27 21:00:11 chat[9593]: -- got it Jun 27 21:00:11 chat[9593]: send (^M)