Jun 27 20:56:05 dnsmasq[248]: reading /var/run/ppp/resolv.conf Jun 27 20:56:05 dnsmasq[248]: using nameserver 31.173.96.52#53 Jun 27 20:56:05 dnsmasq[248]: using nameserver 31.173.96.60#53 Jun 27 20:56:05 pppd[9537]: Script /etc/ppp/ip-up started (pid 9548) Jun 27 20:56:05 pppd[9537]: Script /etc/ppp/ip-up finished (pid 9548), status = 0x0 Jun 27 20:56:06 3gd[9377]: tls_tty.cpp:234: TLS error: e=5: error:00000000:lib(0):func(0):reason(0) Jun 27 20:56:06 3gd[9377]: link.cpp:192: --> Link::error(): connection closed (17) Jun 27 20:56:07 ntpd[254]: Listen normally on 13 ppp0 46.229.134.176:123 Jun 27 20:56:07 ntpd[254]: new interface(s) found: waking up resolver Jun 27 20:56:08 pppd[9537]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 20:56:11 pppd[9537]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 20:56:14 pppd[9537]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 20:56:17 pppd[9537]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 20:56:20 pppd[9537]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 20:56:21 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 20:56:21 openvpn[9391]: TCP connection established with [AF_INET6]::ffff:178.176.107.254:49528 Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 peer info: IV_VER=2.4.4 Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 peer info: IV_PLAT=linux Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 peer info: IV_PROTO=2 Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 peer info: IV_NCP=2 Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 peer info: IV_LZ4=1 Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 peer info: IV_LZ4v2=1 Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 peer info: IV_LZO=1 Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 peer info: IV_COMP_STUB=1 Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 peer info: IV_COMP_STUBv2=1 Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 peer info: IV_TCPNL=1 Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1575', remote='link-mtu 1583' Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 WARNING: 'cipher' is used inconsistently, local='cipher BF-CBC', remote='cipher AES-256-GCM' Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 WARNING: 'auth' is used inconsistently, local='auth SHA1', remote='auth [null-digest]' Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 WARNING: 'keysize' is used inconsistently, local='keysize 128', remote='keysize 256' Jun 27 20:56:21 openvpn[9391]: 178.176.107.254 [MC04-WL] Peer Connection Initiated with [AF_INET6]::ffff:178.176.107.254:49528 Jun 27 20:56:21 openvpn[9391]: MC04-WL/178.176.107.254 MULTI: no dynamic or static remote --ifconfig address is available for MC04-WL/178.176.107.254 Jun 27 20:56:23 pppd[9537]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 20:56:26 pppd[9537]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 20:56:29 3gd[9377]: link.cpp:363: connect from ::ffff:178.176.107.254:42850 (17) Jun 27 20:56:29 3gd[9377]: tls_tty.cpp:169: TLS connection established: TLSv1.2 GOST2012-GOST8912-GOST8912 Jun 27 20:56:29 pppd[9537]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 20:56:32 pppd[9537]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 20:56:35 pppd[9537]: IPV6CP: timeout sending Config-Requests Jun 27 20:56:42 openvpn[9391]: MC04-WL/178.176.107.254 write TCPv6_SERVER: Connection reset by peer (code=104) Jun 27 20:56:42 openvpn[9391]: MC04-WL/178.176.107.254 write TCPv6_SERVER: Broken pipe (code=32) Jun 27 20:56:42 openvpn[9391]: MC04-WL/178.176.107.254 Connection reset, restarting [0] Jun 27 20:57:28 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:58:34 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 20:59:41 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:00:48 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:01:54 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:03:01 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:04:07 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:05:11 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:06:18 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:07:22 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:08:28 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:09:35 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:10:41 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:11:46 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:12:50 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:13:57 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:15:02 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:16:09 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:16:54 3gd[9377]: link.cpp:363: connect from ::ffff:185.176.221.168:63614 (18) Jun 27 21:16:54 3gd[9377]: link.cpp:192: --> Link::error(): connection closed (18) Jun 27 21:17:14 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:17:56 3gd[9657]: 3gd.cpp:61: 3gd-1.0-r160 started Jun 27 21:17:56 3gd[9657]: 3gd.cpp:67: serial number: unknown Jun 27 21:17:56 3gd[9657]: sim5320.cpp:156: powering on SIM5320... Jun 27 21:17:56 3gd[9657]: http_server.cpp:2145: HTTP daemon created Jun 27 21:17:56 3gd[9657]: serial_tty.cpp:65: /dev/ttyS2: port opened with fd 13 Jun 27 21:17:56 3gd[9657]: serial_tty.cpp:65: /dev/ttyS1: port opened with fd 15 Jun 27 21:17:56 openvpn[9391]: SIGTERM[hard,] received, process exiting Jun 27 21:17:56 kernel: br0: port 2(tap0) entered disabled state Jun 27 21:17:57 openvpn[9670]: WARNING: file '/usr/share/3gd/key.pem' is group or others accessible Jun 27 21:17:57 openvpn[9670]: OpenVPN 2.4.4 arm-poky-linux-gnueabi [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [MH/PKTINFO] [AEAD] built on Dec 5 2019 Jun 27 21:17:57 openvpn[9670]: library versions: OpenSSL 1.1.1 11 Sep 2018, LZO 2.10 Jun 27 21:17:57 openvpn[9671]: WARNING: --keepalive option is missing from server config Jun 27 21:17:57 openvpn[9671]: TUN/TAP device tap0 opened Jun 27 21:17:57 kernel: br0: port 2(tap0) entered blocking state Jun 27 21:17:57 kernel: br0: port 2(tap0) entered forwarding state Jun 27 21:17:57 openvpn[9671]: setsockopt(IPV6_V6ONLY=0) Jun 27 21:17:57 openvpn[9671]: Listening for incoming TCP connection on [AF_INET6][undef]:1194 Jun 27 21:17:57 openvpn[9671]: TCPv6_SERVER link local (bound): [AF_INET6][undef]:1194 Jun 27 21:17:57 openvpn[9671]: TCPv6_SERVER link remote: [AF_UNSPEC] Jun 27 21:17:57 openvpn[9671]: Initialization Sequence Completed Jun 27 21:17:58 3gd[9657]: link.cpp:363: connect from ::ffff:178.176.107.254:42856 (17) Jun 27 21:17:58 3gd[9657]: tls_tty.cpp:169: TLS connection established: TLSv1.2 GOST2012-GOST8912-GOST8912 Jun 27 21:18:01 openvpn[9671]: TCP connection established with [AF_INET6]::ffff:178.176.107.254:49540 Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 peer info: IV_VER=2.4.4 Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 peer info: IV_PLAT=linux Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 peer info: IV_PROTO=2 Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 peer info: IV_NCP=2 Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 peer info: IV_LZ4=1 Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 peer info: IV_LZ4v2=1 Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 peer info: IV_LZO=1 Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 peer info: IV_COMP_STUB=1 Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 peer info: IV_COMP_STUBv2=1 Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 peer info: IV_TCPNL=1 Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1575', remote='link-mtu 1583' Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 WARNING: 'cipher' is used inconsistently, local='cipher BF-CBC', remote='cipher AES-256-GCM' Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 WARNING: 'auth' is used inconsistently, local='auth SHA1', remote='auth [null-digest]' Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 WARNING: 'keysize' is used inconsistently, local='keysize 128', remote='keysize 256' Jun 27 21:18:03 openvpn[9671]: 178.176.107.254 [MC04-WL] Peer Connection Initiated with [AF_INET6]::ffff:178.176.107.254:49540 Jun 27 21:18:03 openvpn[9671]: MC04-WL/178.176.107.254 MULTI: no dynamic or static remote --ifconfig address is available for MC04-WL/178.176.107.254 Jun 27 21:18:11 3gd[9657]: sim5320.cpp:232: SIM5320 powered on, initializing... Jun 27 21:18:11 3gd[9657]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (1) Jun 27 21:18:11 pppd[9537]: Terminating on signal 15 Jun 27 21:18:11 pppd[9537]: Connect time 22.1 minutes. Jun 27 21:18:11 pppd[9537]: Sent 49321313 bytes, received 51986510 bytes. Jun 27 21:18:11 3gd[9657]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (2) Jun 27 21:18:11 3gd[9657]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (3) Jun 27 21:18:11 pppd[9537]: Script /etc/ppp/ip-down started (pid 9676) Jun 27 21:18:11 pppd[9537]: sent [LCP TermReq id=0x2 "User request"] Jun 27 21:18:11 pppd[9537]: rcvd [LCP TermAck id=0x2] Jun 27 21:18:11 pppd[9537]: Connection terminated. Jun 27 21:18:11 3gd[9657]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (4) Jun 27 21:18:11 3gd[9657]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (5) Jun 27 21:18:11 3gd[9657]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (6) Jun 27 21:18:11 3gd[9657]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (7) Jun 27 21:18:12 3gd[9657]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (8) Jun 27 21:18:12 3gd[9657]: sim5320.cpp:517: Sim5320: команда 'AT+CGPS=1,1' завершилась ошибкой, повтор (9) Jun 27 21:18:12 3gd[9657]: sim5320.cpp:512: Sim5320: исчерпаны попытки выполнения команды 'AT+CGPS=1,1', рестарт модуля... Jun 27 21:18:12 3gd[9657]: sim5320.cpp:179: powering off SIM5320... Jun 27 21:18:12 pppd[9537]: Script /etc/ppp/ip-down finished (pid 9676), status = 0x0 Jun 27 21:18:12 pppd[9537]: Exit. Jun 27 21:18:13 ntpd[254]: Deleting interface #13 ppp0, 46.229.134.176#123, interface stats: received=18, sent=18, dropped=0, active_time=1326 secs Jun 27 21:18:13 ntpd[254]: 91.189.94.4 local addr 46.229.134.176 -> Jun 27 21:18:13 ntpd[254]: 91.189.91.157 local addr 46.229.134.176 -> Jun 27 21:18:13 ntpd[254]: 91.189.89.199 local addr 46.229.134.176 -> Jun 27 21:18:13 ntpd[254]: 91.189.89.198 local addr 46.229.134.176 -> Jun 27 21:18:17 pppd[9694]: pppd 2.4.7 started by root, uid 0 Jun 27 21:18:18 chat[9698]: abort on (BUSY) Jun 27 21:18:18 chat[9698]: abort on (VOICE) Jun 27 21:18:18 chat[9698]: abort on (NO CARRIER) Jun 27 21:18:18 chat[9698]: abort on (NO DIALTONE) Jun 27 21:18:18 chat[9698]: abort on (NO DIAL TONE) Jun 27 21:18:18 chat[9698]: send (AT^M) Jun 27 21:18:18 chat[9698]: expect (OK) Jun 27 21:18:18 chat[9698]: ^M Jun 27 21:18:18 chat[9698]: +CPSI: LTE,Online,250-02,0x1712,151125767,128,EUTRAN-BAND20,6350,2,2,-83,-862,-6 Jun 27 21:18:18 chat[9698]: 52,14^M Jun 27 21:18:18 chat[9698]: ^M Jun 27 21:18:18 chat[9698]: OK Jun 27 21:18:18 chat[9698]: -- got it Jun 27 21:18:18 chat[9698]: send (AT+CGDATA="PPP",1^M) Jun 27 21:18:18 chat[9698]: expect (CONNECT) Jun 27 21:18:18 chat[9698]: ^M Jun 27 21:18:18 chat[9698]: +CSQ: 99,99^M Jun 27 21:18:18 chat[9698]: ^M Jun 27 21:18:18 chat[9698]: CONNECT Jun 27 21:18:18 chat[9698]: -- got it Jun 27 21:18:18 chat[9698]: send (^M) Jun 27 21:18:18 pppd[9694]: Script /usr/sbin/chat -v -f /etc/chatscripts/pap finished (pid 9697), status = 0x0 Jun 27 21:18:18 pppd[9694]: Serial connection established. Jun 27 21:18:18 pppd[9694]: using channel 5 Jun 27 21:18:18 pppd[9694]: Using interface ppp0 Jun 27 21:18:18 pppd[9694]: Connect: ppp0 <--> /dev/modem Jun 27 21:18:19 ntpd[254]: error resolving pool ntp.ubuntu.com: Temporary failure in name resolution (-3) Jun 27 21:18:19 pppd[9694]: sent [LCP ConfReq id=0x1 ] Jun 27 21:18:22 pppd[9694]: sent [LCP ConfReq id=0x1 ] Jun 27 21:18:25 pppd[9694]: sent [LCP ConfReq id=0x1 ] Jun 27 21:18:25 pppd[9694]: Hangup (SIGHUP) Jun 27 21:18:25 pppd[9694]: Modem hangup Jun 27 21:18:25 pppd[9694]: Connection terminated. Jun 27 21:18:33 kernel: usb 1-2: USB disconnect, device number 3 Jun 27 21:18:33 kernel: option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0 Jun 27 21:18:33 kernel: option 1-2:1.0: device disconnected Jun 27 21:18:33 kernel: option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1 Jun 27 21:18:33 kernel: option 1-2:1.1: device disconnected Jun 27 21:18:33 kernel: option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2 Jun 27 21:18:33 kernel: option 1-2:1.2: device disconnected Jun 27 21:18:33 kernel: option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3 Jun 27 21:18:33 kernel: option 1-2:1.3: device disconnected Jun 27 21:18:33 kernel: option1 ttyUSB4: GSM modem (1-port) converter now disconnected from ttyUSB4 Jun 27 21:18:33 kernel: option 1-2:1.4: device disconnected Jun 27 21:18:45 3gd[9657]: sim5320.cpp:156: powering on SIM5320... Jun 27 21:18:51 kernel: usb 1-2: new high-speed USB device number 4 using atmel-ehci Jun 27 21:18:51 kernel: usb 1-2: New USB device found, idVendor=1e0e, idProduct=9001 Jun 27 21:18:51 kernel: usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 27 21:18:51 kernel: usb 1-2: Product: SimTech, Incorporated Jun 27 21:18:51 kernel: usb 1-2: Manufacturer: SimTech, Incorporated Jun 27 21:18:51 kernel: usb 1-2: SerialNumber: 0123456789ABCDEF Jun 27 21:18:51 kernel: option 1-2:1.0: GSM modem (1-port) converter detected Jun 27 21:18:51 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB0 Jun 27 21:18:51 kernel: option 1-2:1.1: GSM modem (1-port) converter detected Jun 27 21:18:51 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB1 Jun 27 21:18:51 kernel: option 1-2:1.2: GSM modem (1-port) converter detected Jun 27 21:18:51 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB2 Jun 27 21:18:51 kernel: option 1-2:1.3: GSM modem (1-port) converter detected Jun 27 21:18:51 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB3 Jun 27 21:18:51 kernel: option 1-2:1.4: GSM modem (1-port) converter detected Jun 27 21:18:51 kernel: usb 1-2: GSM modem (1-port) converter now attached to ttyUSB4 Jun 27 21:18:57 chat[9777]: abort on (BUSY) Jun 27 21:18:58 chat[9777]: abort on (VOICE) Jun 27 21:18:58 chat[9777]: abort on (NO CARRIER) Jun 27 21:18:58 chat[9777]: abort on (NO DIALTONE) Jun 27 21:18:58 chat[9777]: abort on (NO DIAL TONE) Jun 27 21:18:58 chat[9777]: send (AT^M) Jun 27 21:18:58 chat[9777]: expect (OK) Jun 27 21:18:58 chat[9777]: ^M Jun 27 21:18:58 chat[9777]: +CPIN: READY^M Jun 27 21:18:58 chat[9777]: AT^M^M Jun 27 21:18:58 chat[9777]: OK Jun 27 21:18:58 chat[9777]: -- got it Jun 27 21:18:58 chat[9777]: send (AT+CGDATA="PPP",1^M) Jun 27 21:18:58 chat[9777]: expect (CONNECT) Jun 27 21:18:58 chat[9777]: ^M Jun 27 21:18:58 chat[9777]: AT+CGDATA^M Jun 27 21:18:58 chat[9777]: SMS DONE^M Jun 27 21:18:58 chat[9777]: ="PPP",1^M^M Jun 27 21:18:58 chat[9777]: CONNECT Jun 27 21:18:58 chat[9777]: -- got it Jun 27 21:18:58 chat[9777]: send (^M) Jun 27 21:18:58 pppd[9694]: Script /usr/sbin/chat -v -f /etc/chatscripts/pap finished (pid 9776), status = 0x0 Jun 27 21:18:58 pppd[9694]: Serial connection established. Jun 27 21:18:58 pppd[9694]: using channel 6 Jun 27 21:18:58 pppd[9694]: Using interface ppp0 Jun 27 21:18:58 pppd[9694]: Connect: ppp0 <--> /dev/modem Jun 27 21:18:59 pppd[9694]: sent [LCP ConfReq id=0x2 ] Jun 27 21:18:59 pppd[9694]: rcvd [LCP ConfReq id=0x0 ] Jun 27 21:18:59 pppd[9694]: No auth is possible Jun 27 21:18:59 pppd[9694]: sent [LCP ConfRej id=0x0 ] Jun 27 21:18:59 pppd[9694]: rcvd [LCP ConfAck id=0x2 ] Jun 27 21:18:59 pppd[9694]: rcvd [LCP ConfReq id=0x1 ] Jun 27 21:18:59 pppd[9694]: sent [LCP ConfAck id=0x1 ] Jun 27 21:18:59 pppd[9694]: sent [LCP EchoReq id=0x0 magic=0x2ada1c8c] Jun 27 21:18:59 pppd[9694]: kernel does not support PPP filtering Jun 27 21:18:59 pppd[9694]: sent [IPCP ConfReq id=0x1 ] Jun 27 21:18:59 pppd[9694]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 21:18:59 pppd[9694]: rcvd [LCP DiscReq id=0x2 magic=0xd88b49b0] Jun 27 21:18:59 pppd[9694]: rcvd [LCP EchoRep id=0x0 magic=0xd88b49b0 2a da 1c 8c] Jun 27 21:18:59 pppd[9694]: rcvd [IPCP ConfReq id=0x0] Jun 27 21:18:59 pppd[9694]: sent [IPCP ConfNak id=0x0 ] Jun 27 21:18:59 pppd[9694]: rcvd [IPCP ConfRej id=0x1 ] Jun 27 21:18:59 pppd[9694]: sent [IPCP ConfReq id=0x2 ] Jun 27 21:18:59 pppd[9694]: rcvd [IPCP ConfReq id=0x1] Jun 27 21:18:59 pppd[9694]: sent [IPCP ConfAck id=0x1] Jun 27 21:18:59 pppd[9694]: rcvd [IPCP ConfNak id=0x2 ] Jun 27 21:18:59 pppd[9694]: sent [IPCP ConfReq id=0x3 ] Jun 27 21:18:59 pppd[9694]: rcvd [IPCP ConfAck id=0x3 ] Jun 27 21:18:59 pppd[9694]: Could not determine remote IP address: defaulting to 10.64.64.64 Jun 27 21:18:59 dnsmasq[248]: reading /var/run/ppp/resolv.conf Jun 27 21:18:59 dnsmasq[248]: using nameserver 31.173.96.52#53 Jun 27 21:18:59 dnsmasq[248]: using nameserver 31.173.96.60#53 Jun 27 21:18:59 pppd[9694]: local IP address 46.229.134.176 Jun 27 21:18:59 pppd[9694]: remote IP address 10.64.64.64 Jun 27 21:18:59 pppd[9694]: primary DNS address 31.173.96.52 Jun 27 21:18:59 pppd[9694]: secondary DNS address 31.173.96.60 Jun 27 21:18:59 pppd[9694]: Script /etc/ppp/ip-up started (pid 9782) Jun 27 21:18:59 pppd[9694]: Script /etc/ppp/ip-up finished (pid 9782), status = 0x0 Jun 27 21:18:59 openvpn[9671]: MC04-WL/178.176.107.254 Connection reset, restarting [-1] Jun 27 21:19:00 3gd[9657]: sim5320.cpp:232: SIM5320 powered on, initializing... Jun 27 21:19:00 pppd[9694]: Terminating on signal 15 Jun 27 21:19:00 pppd[9694]: Connect time 0.1 minutes. Jun 27 21:19:00 pppd[9694]: Sent 14400 bytes, received 400 bytes. Jun 27 21:19:00 pppd[9694]: Script /etc/ppp/ip-down started (pid 9798) Jun 27 21:19:00 pppd[9694]: sent [LCP TermReq id=0x3 "User request"] Jun 27 21:19:00 pppd[9694]: rcvd [LCP TermAck id=0x3] Jun 27 21:19:00 pppd[9694]: Connection terminated. Jun 27 21:19:00 pppd[9694]: Script /etc/ppp/ip-down finished (pid 9798), status = 0x0 Jun 27 21:19:00 pppd[9694]: Exit. Jun 27 21:19:05 pppd[9816]: pppd 2.4.7 started by root, uid 0 Jun 27 21:19:06 chat[9820]: abort on (BUSY) Jun 27 21:19:06 chat[9820]: abort on (VOICE) Jun 27 21:19:06 chat[9820]: abort on (NO CARRIER) Jun 27 21:19:06 chat[9820]: abort on (NO DIALTONE) Jun 27 21:19:06 chat[9820]: abort on (NO DIAL TONE) Jun 27 21:19:06 chat[9820]: send (AT^M) Jun 27 21:19:06 chat[9820]: expect (OK) Jun 27 21:19:06 chat[9820]: ^M Jun 27 21:19:06 chat[9820]: OK Jun 27 21:19:06 chat[9820]: -- got it Jun 27 21:19:06 chat[9820]: send (AT+CGDATA="PPP",1^M) Jun 27 21:19:06 chat[9820]: expect (CONNECT) Jun 27 21:19:06 chat[9820]: ^M Jun 27 21:19:06 chat[9820]: CONNECT Jun 27 21:19:06 chat[9820]: -- got it Jun 27 21:19:06 chat[9820]: send (^M) Jun 27 21:19:06 pppd[9816]: Script /usr/sbin/chat -v -f /etc/chatscripts/pap finished (pid 9819), status = 0x0 Jun 27 21:19:06 pppd[9816]: Serial connection established. Jun 27 21:19:06 pppd[9816]: using channel 7 Jun 27 21:19:06 pppd[9816]: Using interface ppp0 Jun 27 21:19:06 pppd[9816]: Connect: ppp0 <--> /dev/modem Jun 27 21:19:07 pppd[9816]: sent [LCP ConfReq id=0x1 ] Jun 27 21:19:07 pppd[9816]: rcvd [LCP ConfReq id=0x3 ] Jun 27 21:19:07 pppd[9816]: No auth is possible Jun 27 21:19:07 pppd[9816]: sent [LCP ConfRej id=0x3 ] Jun 27 21:19:07 pppd[9816]: rcvd [LCP ConfAck id=0x1 ] Jun 27 21:19:07 pppd[9816]: rcvd [LCP ConfReq id=0x4 ] Jun 27 21:19:07 pppd[9816]: sent [LCP ConfAck id=0x4 ] Jun 27 21:19:07 pppd[9816]: sent [LCP EchoReq id=0x0 magic=0x47465dd3] Jun 27 21:19:07 pppd[9816]: kernel does not support PPP filtering Jun 27 21:19:08 pppd[9816]: sent [IPCP ConfReq id=0x1 ] Jun 27 21:19:08 pppd[9816]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 21:19:08 pppd[9816]: rcvd [LCP DiscReq id=0x5 magic=0xd88b6b01] Jun 27 21:19:08 pppd[9816]: rcvd [LCP EchoRep id=0x0 magic=0xd88b6b01 47 46 5d d3] Jun 27 21:19:08 pppd[9816]: rcvd [IPCP ConfReq id=0x2] Jun 27 21:19:08 pppd[9816]: sent [IPCP ConfNak id=0x2 ] Jun 27 21:19:08 pppd[9816]: rcvd [IPCP ConfRej id=0x1 ] Jun 27 21:19:08 pppd[9816]: sent [IPCP ConfReq id=0x2 ] Jun 27 21:19:08 pppd[9816]: rcvd [IPCP ConfReq id=0x3] Jun 27 21:19:08 pppd[9816]: sent [IPCP ConfAck id=0x3] Jun 27 21:19:08 pppd[9816]: rcvd [IPCP ConfNak id=0x2 ] Jun 27 21:19:08 pppd[9816]: sent [IPCP ConfReq id=0x3 ] Jun 27 21:19:08 pppd[9816]: rcvd [IPCP ConfAck id=0x3 ] Jun 27 21:19:08 pppd[9816]: Could not determine remote IP address: defaulting to 10.64.64.64 Jun 27 21:19:08 pppd[9816]: local IP address 46.229.134.176 Jun 27 21:19:08 pppd[9816]: remote IP address 10.64.64.64 Jun 27 21:19:08 pppd[9816]: primary DNS address 31.173.96.52 Jun 27 21:19:08 pppd[9816]: secondary DNS address 31.173.96.60 Jun 27 21:19:08 dnsmasq[248]: reading /var/run/ppp/resolv.conf Jun 27 21:19:08 dnsmasq[248]: using nameserver 31.173.96.52#53 Jun 27 21:19:08 dnsmasq[248]: using nameserver 31.173.96.60#53 Jun 27 21:19:08 pppd[9816]: Script /etc/ppp/ip-up started (pid 9827) Jun 27 21:19:08 pppd[9816]: Script /etc/ppp/ip-up finished (pid 9827), status = 0x0 Jun 27 21:19:10 ntpd[254]: Listen normally on 14 ppp0 46.229.134.176:123 Jun 27 21:19:10 ntpd[254]: new interface(s) found: waking up resolver Jun 27 21:19:11 pppd[9816]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 21:19:14 pppd[9816]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 21:19:15 3gd[9657]: link.cpp:363: connect from ::ffff:178.176.107.254:42862 (19) Jun 27 21:19:15 3gd[9657]: tls_tty.cpp:169: TLS connection established: TLSv1.2 GOST2012-GOST8912-GOST8912 Jun 27 21:19:17 pppd[9816]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 21:19:20 pppd[9816]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 21:19:23 pppd[9816]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 21:19:23 openvpn[9671]: TCP connection established with [AF_INET6]::ffff:178.176.107.254:49542 Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 peer info: IV_VER=2.4.4 Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 peer info: IV_PLAT=linux Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 peer info: IV_PROTO=2 Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 peer info: IV_NCP=2 Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 peer info: IV_LZ4=1 Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 peer info: IV_LZ4v2=1 Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 peer info: IV_LZO=1 Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 peer info: IV_COMP_STUB=1 Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 peer info: IV_COMP_STUBv2=1 Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 peer info: IV_TCPNL=1 Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1575', remote='link-mtu 1583' Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 WARNING: 'cipher' is used inconsistently, local='cipher BF-CBC', remote='cipher AES-256-GCM' Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 WARNING: 'auth' is used inconsistently, local='auth SHA1', remote='auth [null-digest]' Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 WARNING: 'keysize' is used inconsistently, local='keysize 128', remote='keysize 256' Jun 27 21:19:24 openvpn[9671]: 178.176.107.254 [MC04-WL] Peer Connection Initiated with [AF_INET6]::ffff:178.176.107.254:49542 Jun 27 21:19:24 openvpn[9671]: MC04-WL/178.176.107.254 MULTI: no dynamic or static remote --ifconfig address is available for MC04-WL/178.176.107.254 Jun 27 21:19:25 3gd[9657]: tls_tty.cpp:234: TLS error: e=5: error:00000000:lib(0):func(0):reason(0) Jun 27 21:19:25 3gd[9657]: link.cpp:192: --> Link::error(): connection closed (17) Jun 27 21:19:26 pppd[9816]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 21:19:26 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:19:29 pppd[9816]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 21:19:32 pppd[9816]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 21:19:35 pppd[9816]: sent [IPV6CP ConfReq id=0x1 ] Jun 27 21:19:38 pppd[9816]: IPV6CP: timeout sending Config-Requests Jun 27 21:20:32 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:21:36 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:22:42 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:23:48 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:24:54 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:25:58 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:27:03 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:28:07 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:29:12 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:30:17 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:31:22 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:32:27 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:33:33 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:34:38 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:35:21 dropbear[9906]: Child connection from 95.109.88.253:57406 Jun 27 21:35:21 dropbear[9907]: Child connection from 95.109.88.253:57410 Jun 27 21:35:23 dropbear[9906]: Login attempt for nonexistent user from 95.109.88.253:57406 Jun 27 21:35:23 dropbear[9907]: Login attempt for nonexistent user from 95.109.88.253:57410 Jun 27 21:35:24 dropbear[9906]: Exit before auth: Exited normally Jun 27 21:35:24 dropbear[9907]: Exit before auth: Exited normally Jun 27 21:35:45 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:36:51 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:37:58 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:39:03 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:40:08 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:41:15 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:42:21 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:43:26 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:44:33 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:45:40 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:46:47 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:47:52 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:48:57 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:49:04 3gd[9657]: link.cpp:363: connect from ::ffff:92.63.194.47:55804 (17) Jun 27 21:49:04 3gd[9657]: link.cpp:192: --> Link::error(): connection closed (17) Jun 27 21:50:01 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:50:59 dropbear[9970]: Child connection from 85.209.0.173:16606 Jun 27 21:51:00 dropbear[9970]: Exit before auth: Exited normally Jun 27 21:51:00 dropbear[9975]: Child connection from 85.209.0.173:43378 Jun 27 21:51:00 dropbear[9976]: Child connection from 85.209.0.173:43450 Jun 27 21:51:03 dropbear[9975]: Login attempt for nonexistent user from 85.209.0.173:43378 Jun 27 21:51:04 dropbear[9976]: Bad password attempt for 'root' from 85.209.0.173:43450 Jun 27 21:51:04 dropbear[9975]: Exit before auth: Exited normally Jun 27 21:51:07 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:51:08 dropbear[9976]: Exit before auth (user 'root', 1 fails): Exited normally Jun 27 21:52:14 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:53:19 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:54:26 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:55:31 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:56:37 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:57:42 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 21:58:48 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 21:59:52 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 22:00:59 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 22:02:05 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 22:03:10 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 22:04:14 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 22:05:20 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 22:06:27 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 22:07:34 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 22:08:40 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 22:09:47 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 22:10:51 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 22:11:55 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 22:12:59 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 22:14:05 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 22:15:12 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 22:16:19 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 22:17:23 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c7 Jun 27 22:18:30 ntpd[254]: Soliciting pool server 2001:67c:1560:8003::c8 Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 peer info: IV_VER=2.4.4 Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 peer info: IV_PLAT=linux Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 peer info: IV_PROTO=2 Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 peer info: IV_NCP=2 Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 peer info: IV_LZ4=1 Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 peer info: IV_LZ4v2=1 Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 peer info: IV_LZO=1 Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 peer info: IV_COMP_STUB=1 Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 peer info: IV_COMP_STUBv2=1 Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 peer info: IV_TCPNL=1 Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1575', remote='link-mtu 1583' Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 WARNING: 'cipher' is used inconsistently, local='cipher BF-CBC', remote='cipher AES-256-GCM' Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 WARNING: 'auth' is used inconsistently, local='auth SHA1', remote='auth [null-digest]' Jun 27 22:19:25 openvpn[9671]: MC04-WL/178.176.107.254 WARNING: 'keysize' is used inconsistently, local='keysize 128', remote='keysize 256'