2020-10-14 22:11:05 user notice connection-recover: ttyACM0 reconnect start... 2020-10-14 22:11:10 user info NetworkManager[832]: [1602681070.3647] device (ttyACM0): Activation: starting connection 'gsm-ttyACM0' (8cfeeb99-8ed6-4ded-a489-b2644525a0a1) 2020-10-14 22:11:10 user info NetworkManager[832]: [1602681070.3676] audit: op="connection-activate" uuid="8cfeeb99-8ed6-4ded-a489-b2644525a0a1" name="gsm-ttyACM0" pid=12426 uid=0 result="success" 2020-10-14 22:11:10 user info NetworkManager[832]: [1602681070.3724] device (ttyACM0): state change: disconnected -> prepare (reason 'none') [30 40 0] 2020-10-14 22:11:10 user info NetworkManager[832]: [1602681070.3752] manager: NetworkManager state is now CONNECTING 2020-10-14 22:11:10 daemon notice dbus[740]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' 2020-10-14 22:11:10 user info NetworkManager[832]: [1602681070.3843] device (ttyACM0): state change: prepare -> need-auth (reason 'none') [40 60 0] 2020-10-14 22:11:10 daemon info systemd[1]: Starting Network Manager Script Dispatcher Service... 2020-10-14 22:11:10 user info NetworkManager[832]: [1602681070.4286] device (ttyACM0): state change: need-auth -> prepare (reason 'none') [60 40 0] 2020-10-14 22:11:10 daemon info ModemManager[613]: Simple connect started... 2020-10-14 22:11:10 daemon info ModemManager[613]: Simple connect state (4/8): Wait to get fully enabled 2020-10-14 22:11:10 daemon info ModemManager[613]: Simple connect state (5/8): Register 2020-10-14 22:11:10 daemon notice dbus[740]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' 2020-10-14 22:11:10 daemon info systemd[1]: Started Network Manager Script Dispatcher Service. 2020-10-14 22:11:10 daemon info nm-dispatcher: req:1 'connectivity-change': new request (4 scripts) 2020-10-14 22:11:10 daemon info nm-dispatcher: req:1 'connectivity-change': start running ordered scripts... 2020-10-14 22:11:10 daemon info ModemManager[613]: Simple connect state (6/8): Bearer 2020-10-14 22:11:10 daemon info ModemManager[613]: Simple connect state (7/8): Connect 2020-10-14 22:11:10 daemon info ModemManager[613]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting) 2020-10-14 22:12:10 user notice connection-recover: ttyACM0 reconnect fail 2020-10-14 22:12:10 user notice connection-recover: exec wwan-force-restart 2020-10-14 22:12:10 daemon info systemd[1]: Stopping Modem Manager... 2020-10-14 22:12:10 daemon info ModemManager[613]: Caught signal, shutting down... 2020-10-14 22:12:20 daemon info ModemManager[613]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> unknown) 2020-10-14 22:12:20 user info NetworkManager[832]: [1602681140.9014] ModemManager disappeared from bus 2020-10-14 22:12:20 user info NetworkManager[832]: [1602681140.9017] device (ttyACM0): state change: prepare -> unmanaged (reason 'removed') [40 10 36] 2020-10-14 22:12:20 daemon notice dbus[740]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' 2020-10-14 22:12:20 user info NetworkManager[832]: [1602681140.9032] manager: NetworkManager state is now CONNECTED_LOCAL 2020-10-14 22:12:20 user warning NetworkManager[832]: [1602681140.9199] modem-broadband[ttyACM0]: failed to disconnect modem: GDBus.Error:org.freedesktop.DBus.Error.AccessDenied: Rejected send message, 1 matched rules; type="method_call", sender=":1.3" (uid=0 pid=832 comm="/usr/sbin/NetworkManager --no-daemon ") interface="org.freedesktop.ModemManager1.Modem.Simple" member="Disconnect" error name="(unset)" requested_reply="0" destination=":1.14311" (uid=0 pid=613 comm="/usr/sbin/ModemManager ") 2020-10-14 22:12:20 daemon info ModemManager[613]: ModemManager is shut down 2020-10-14 22:12:20 daemon info systemd[1]: Starting Network Manager Script Dispatcher Service... 2020-10-14 22:12:20 daemon notice dbus[740]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' 2020-10-14 22:12:20 daemon info systemd[1]: Started Network Manager Script Dispatcher Service. 2020-10-14 22:12:20 daemon info nm-dispatcher: req:1 'connectivity-change': new request (4 scripts) 2020-10-14 22:12:20 daemon info nm-dispatcher: req:1 'connectivity-change': start running ordered scripts... 2020-10-14 22:12:20 daemon info systemd[1]: Stopped Modem Manager. 2020-10-14 22:12:21 user notice /usr/bin/wwan-force-restart: start force restart els31 2020-10-14 22:12:21 kern info kernel: usb 2-1: USB disconnect, device number 89 2020-10-14 22:12:21 kern info kernel: cdc_ether 2-1:1.0 usb1: unregister 'cdc_ether' usb-ci_hdrc.1-1, CDC Ethernet Device 2020-10-14 22:12:21 user info NetworkManager[832]: [1602681141.3718] devices removed (path: /sys/devices/soc0/soc/30800000.aips-bus/30b20000.usb/ci_hdrc.1/usb2/2-1/2-1:1.0/net/usb1, iface: usb1) 2020-10-14 22:12:34 kern info kernel: usb 2-1: new high-speed USB device number 90 using ci_hdrc 2020-10-14 22:12:35 user info NetworkManager[832]: [1602681155.1422] manager: (usb1): new Ethernet device (/org/freedesktop/NetworkManager/Devices/188) 2020-10-14 22:12:35 kern info kernel: cdc_ether 2-1:1.0 usb1: register 'cdc_ether' at usb-ci_hdrc.1-1, CDC Ethernet Device, 02:80:70:44:73:80 2020-10-14 22:12:35 kern info kernel: cdc_acm 2-1:1.2: ttyACM0: USB ACM device 2020-10-14 22:12:35 user info NetworkManager[832]: [1602681155.2771] devices added (path: /sys/devices/soc0/soc/30800000.aips-bus/30b20000.usb/ci_hdrc.1/usb2/2-1/2-1:1.0/net/usb1, iface: usb1) 2020-10-14 22:12:35 user info NetworkManager[832]: [1602681155.2773] device added (path: /sys/devices/soc0/soc/30800000.aips-bus/30b20000.usb/ci_hdrc.1/usb2/2-1/2-1:1.0/net/usb1, iface: usb1): no ifupdown configuration found. 2020-10-14 22:12:35 user info NetworkManager[832]: [1602681155.2878] device (usb1): state change: unmanaged -> unavailable (reason 'managed') [10 20 2] 2020-10-14 22:12:35 kern info kernel: IPv6: ADDRCONF(NETDEV_UP): usb1: link is not ready 2020-10-14 22:12:35 user info NetworkManager[832]: [1602681155.3025] device (usb1): link connected 2020-10-14 22:12:35 user info NetworkManager[832]: [1602681155.3283] keyfile: add connection in-memory (f0729543-e9b6-3e84-9a42-86a257d28cd8,"Wired connection 1") 2020-10-14 22:12:35 user info NetworkManager[832]: [1602681155.3360] settings: (usb1): created default wired connection 'Wired connection 1' 2020-10-14 22:12:35 user notice /usr/bin/wwan-force-restart: end force restart els31 2020-10-14 22:12:35 daemon info systemd[1]: Starting Modem Manager... 2020-10-14 22:12:35 daemon info ModemManager[13167]: ModemManager (version 1.6.4) starting in system bus... 2020-10-14 22:12:35 user info NetworkManager[832]: [1602681155.6791] ModemManager disappeared from bus 2020-10-14 22:12:35 daemon info systemd[1]: Started Modem Manager. 2020-10-14 22:12:35 user info NetworkManager[832]: [1602681155.7799] ModemManager available in the bus 2020-10-14 22:12:37 user info NetworkManager[832]: [1602681157.3725] device (usb1): link connected 2020-10-14 22:12:37 user info NetworkManager[832]: [1602681157.3743] device (usb1): state change: unavailable -> disconnected (reason 'carrier-changed') [20 30 40] 2020-10-14 22:12:37 user info NetworkManager[832]: [1602681157.3918] policy: auto-activating connection 'Wired connection 1' 2020-10-14 22:12:37 user info NetworkManager[832]: [1602681157.3981] device (usb1): Activation: starting connection 'Wired connection 1' (f0729543-e9b6-3e84-9a42-86a257d28cd8) 2020-10-14 22:12:37 user info NetworkManager[832]: [1602681157.3992] device (usb1): state change: disconnected -> prepare (reason 'none') [30 40 0] 2020-10-14 22:12:37 user info NetworkManager[832]: [1602681157.4000] manager: NetworkManager state is now CONNECTING 2020-10-14 22:12:37 user info NetworkManager[832]: [1602681157.4045] device (usb1): state change: prepare -> config (reason 'none') [40 50 0] 2020-10-14 22:12:37 user info NetworkManager[832]: [1602681157.4085] device (usb1): state change: config -> ip-config (reason 'none') [50 70 0] 2020-10-14 22:12:37 user info NetworkManager[832]: [1602681157.4109] dhcp4 (usb1): activation: beginning transaction (timeout in 45 seconds) 2020-10-14 22:12:37 user info NetworkManager[832]: [1602681157.4213] dhcp4 (usb1): dhclient started with pid 13175 2020-10-14 22:12:37 daemon notice dbus[740]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' 2020-10-14 22:12:37 kern err kernel: cdc_ether 2-1:1.0 usb1: kevent 12 may have been dropped 2020-10-14 22:12:37 daemon info systemd[1]: Starting Network Manager Script Dispatcher Service... 2020-10-14 22:12:37 daemon notice dbus[740]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' 2020-10-14 22:12:37 daemon info systemd[1]: Started Network Manager Script Dispatcher Service. 2020-10-14 22:12:37 daemon info nm-dispatcher: req:1 'connectivity-change': new request (4 scripts) 2020-10-14 22:12:37 daemon info nm-dispatcher: req:1 'connectivity-change': start running ordered scripts... 2020-10-14 22:12:37 daemon info dhclient[13175]: DHCPREQUEST of 192.168.15.100 on usb1 to 255.255.255.255 port 67 2020-10-14 22:12:38 daemon info dhclient[13175]: DHCPACK of 192.168.15.100 from 192.168.15.1 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2222] dhcp4 (usb1): address 192.168.15.100 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2223] dhcp4 (usb1): plen 24 (255.255.255.0) 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2224] dhcp4 (usb1): gateway 192.168.15.1 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2225] dhcp4 (usb1): server identifier 192.168.15.1 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2226] dhcp4 (usb1): lease time 43200 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2231] dhcp4 (usb1): hostname 'armadillo' 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2232] dhcp4 (usb1): nameserver '192.168.15.1' 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2232] dhcp4 (usb1): domain name 'lan' 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2233] dhcp4 (usb1): state changed unknown -> bound 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2411] device (usb1): state change: ip-config -> ip-check (reason 'none') [70 80 0] 2020-10-14 22:12:38 daemon info dhclient[13175]: bound to 192.168.15.100 -- renewal in 17835 seconds. 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2525] device (usb1): state change: ip-check -> secondaries (reason 'none') [80 90 0] 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2583] device (usb1): state change: secondaries -> activated (reason 'none') [90 100 0] 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2616] manager: NetworkManager state is now CONNECTED_LOCAL 2020-10-14 22:12:38 daemon info nm-dispatcher: req:2 'connectivity-change': new request (4 scripts) 2020-10-14 22:12:38 daemon info nm-dispatcher: req:2 'connectivity-change': start running ordered scripts... 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2833] manager: NetworkManager state is now CONNECTED_GLOBAL 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2880] policy: set 'Wired connection 1' (usb1) as default for IPv4 routing and DNS 2020-10-14 22:12:38 user info NetworkManager[832]: [1602681158.2953] device (usb1): Activation: successful, device activated. 2020-10-14 22:12:38 daemon info nm-dispatcher: req:3 'up' [usb1]: new request (4 scripts) 2020-10-14 22:12:38 daemon info nm-dispatcher: req:3 'up' [usb1]: start running ordered scripts... 2020-10-14 22:12:38 daemon err ntpdate[13228]: the NTP socket is in use, exiting 2020-10-14 22:12:38 daemon info systemd[1]: Reloading OpenBSD Secure Shell server. 2020-10-14 22:12:38 daemon info systemd[1]: Reloaded OpenBSD Secure Shell server. 2020-10-14 22:12:38 daemon err ntpdate[13266]: the NTP socket is in use, exiting 2020-10-14 22:12:38 daemon info ModemManager[13167]: Creating modem with plugin 'Cinterion ELS' and '2' ports 2020-10-14 22:12:38 daemon info ModemManager[13167]: Modem for device at '/sys/devices/soc0/soc/30800000.aips-bus/30b20000.usb/ci_hdrc.1/usb2/2-1' successfully created 2020-10-14 22:12:38 daemon info systemd[1]: Reloading OpenBSD Secure Shell server. 2020-10-14 22:12:39 daemon info systemd[1]: Reloaded OpenBSD Secure Shell server. 2020-10-14 22:12:39 user notice fu_resolvconf_modify_check_main.sh: [INFO] /etc/resolv.conf or /etc/hosts changed. 2020-10-14 22:12:40 daemon info ntpd[31190]: Listen normally on 68 usb1 192.168.15.100:123 2020-10-14 22:12:40 daemon info ntpd[31190]: Listen normally on 69 usb1 [fe80::2071:d0c5:1c69:a4af%100]:123 2020-10-14 22:12:49 syslog info syslog_parse.py[2710]: [info] start 2020-10-14 22:12:49 syslog info syslog_parse.py[2710]: [info] finish sleep[60] 2020-10-14 22:13:01 cron info CRON[13422]: (root) CMD (/var/eiotpkg/com/base/get_mobile_signal_arm.sh) 2020-10-14 22:13:01 cron info CRON[13423]: (root) CMD (/var/eiotpkg/com/base/fu_radiosensitive_main.sh) 2020-10-14 22:13:01 cron info CRON[13427]: (root) CMD (/var/eiotpkg/com/tool/reverse_ssh.sh) 2020-10-14 22:13:01 daemon info ModemManager[13167]: Modem: state changed (unknown -> disabled) 2020-10-14 22:13:01 user info NetworkManager[832]: [1602681181.7135] device (usb1): state change: activated -> deactivating (reason 'connection-removed') [100 110 38] 2020-10-14 22:13:01 user info NetworkManager[832]: [1602681181.7149] manager: NetworkManager state is now CONNECTED_LOCAL 2020-10-14 22:13:01 user info NetworkManager[832]: [1602681181.7635] manager: (ttyACM0): new Broadband device (/org/freedesktop/NetworkManager/Devices/189) 2020-10-14 22:13:01 user info NetworkManager[832]: [1602681181.7651] device (ttyACM0): state change: unmanaged -> unavailable (reason 'managed') [10 20 2] 2020-10-14 22:13:01 user info NetworkManager[832]: [1602681181.7675] device (ttyACM0): modem state 'enabling' 2020-10-14 22:13:01 user info NetworkManager[832]: [1602681181.7739] device (usb1): state change: deactivating -> disconnected (reason 'connection-removed') [110 30 38] 2020-10-14 22:13:01 daemon info ModemManager[13167]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling) 2020-10-14 22:13:01 user info NetworkManager[832]: [1602681181.8511] dhcp4 (usb1): canceled DHCP transaction, DHCP client pid 13175 2020-10-14 22:13:01 user info NetworkManager[832]: [1602681181.8513] dhcp4 (usb1): state changed bound -> done 2020-10-14 22:13:01 user err NetworkManager[832]: ((src/nm-dispatcher.c:528)): assertion '' failed 2020-10-14 22:13:01 user warning NetworkManager[832]: g_object_notify_by_pspec: assertion 'G_IS_OBJECT (object)' failed 2020-10-14 22:13:01 user warning NetworkManager[832]: g_object_notify_by_pspec: assertion 'G_IS_OBJECT (object)' failed 2020-10-14 22:13:01 user warning NetworkManager[832]: g_object_notify_by_pspec: assertion 'G_IS_OBJECT (object)' failed 2020-10-14 22:13:01 user warning NetworkManager[832]: g_object_notify_by_pspec: assertion 'G_IS_OBJECT (object)' failed 2020-10-14 22:13:01 user warning NetworkManager[832]: g_object_notify_by_pspec: assertion 'G_IS_OBJECT (object)' failed 2020-10-14 22:13:01 daemon warning systemd[1]: NetworkManager.service: Main process exited, code=killed, status=11/SEGV 2020-10-14 22:13:01 daemon notice systemd[1]: NetworkManager.service: Unit entered failed state. 2020-10-14 22:13:01 daemon warning systemd[1]: NetworkManager.service: Failed with result 'signal'. 2020-10-14 22:13:02 daemon info systemd[1]: NetworkManager.service: Service hold-off time over, scheduling restart. 2020-10-14 22:13:02 daemon info systemd[1]: Stopped Network Manager. 2020-10-14 22:13:02 daemon info systemd[1]: Starting Network Manager... 2020-10-14 22:13:02 user info NetworkManager[13586]: [1602681182.4016] NetworkManager (version 1.6.2) is starting... 2020-10-14 22:13:02 user info NetworkManager[13586]: [1602681182.4043] Read config: /etc/NetworkManager/NetworkManager.conf 2020-10-14 22:13:02 user info NetworkManager[13586]: [1602681182.4453] manager[0x2372108]: monitoring kernel firmware directory '/lib/firmware'. 2020-10-14 22:13:02 user info NetworkManager[13586]: [1602681182.4484] monitoring ifupdown state file '/run/network/ifstate'. 2020-10-14 22:13:02 user info NetworkManager[13586]: [1602681182.4831] dns-mgr[0x237d828]: init: dns=default, rc-manager=resolvconf 2020-10-14 22:13:02 user info NetworkManager[13586]: [1602681182.5008] rfkill1: found WiFi radio killswitch (at /sys/devices/soc0/soc/30800000.aips-bus/30b50000.usdhc/mmc_host/mmc1/mmc1:0001/mmc1:0001:2/wl18xx.0.auto/ieee80211/phy0/rfkill1) (driver wl18xx_driver) 2020-10-14 22:13:02 user info NetworkManager[13586]: [1602681182.5052] manager[0x2372108]: WiFi hardware radio set enabled 2020-10-14 22:13:02 user info NetworkManager[13586]: [1602681182.5075] manager[0x2372108]: WWAN hardware radio set enabled 2020-10-14 22:13:02 daemon info systemd[1]: Started Network Manager.