2020-09-23 10:57:36 syslog info s3auto_cli.py[2228]: [info] next process sleeping. time[179.910799] 2020-09-23 10:57:46 daemon info dhclient[25182]: DHCPREQUEST of 192.168.15.100 on usb1 to 192.168.15.1 port 67 2020-09-23 10:57:46 daemon info dhclient[25182]: DHCPACK of 192.168.15.100 from 192.168.15.1 2020-09-23 10:57:46 user info NetworkManager[4598]: [1600826266.2777] dhcp4 (usb1): address 192.168.15.100 2020-09-23 10:57:46 user info NetworkManager[4598]: [1600826266.2794] dhcp4 (usb1): plen 24 (255.255.255.0) 2020-09-23 10:57:46 user info NetworkManager[4598]: [1600826266.2796] dhcp4 (usb1): gateway 192.168.15.1 2020-09-23 10:57:46 user info NetworkManager[4598]: [1600826266.2797] dhcp4 (usb1): server identifier 192.168.15.1 2020-09-23 10:57:46 user info NetworkManager[4598]: [1600826266.2798] dhcp4 (usb1): lease time 43200 2020-09-23 10:57:46 user info NetworkManager[4598]: [1600826266.2800] dhcp4 (usb1): hostname 'armadillo' 2020-09-23 10:57:46 user info NetworkManager[4598]: [1600826266.2801] dhcp4 (usb1): nameserver '192.168.15.1' 2020-09-23 10:57:46 user info NetworkManager[4598]: [1600826266.2802] dhcp4 (usb1): domain name 'lan' 2020-09-23 10:57:46 daemon notice dbus[572]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' 2020-09-23 10:57:46 user info NetworkManager[4598]: [1600826266.2803] dhcp4 (usb1): state changed bound -> bound 2020-09-23 10:57:46 daemon info systemd[1]: Starting Network Manager Script Dispatcher Service... 2020-09-23 10:57:46 daemon info dhclient[25182]: bound to 192.168.15.100 -- renewal in 19139 seconds. 2020-09-23 10:57:46 daemon notice dbus[572]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' 2020-09-23 10:57:46 daemon info systemd[1]: Started Network Manager Script Dispatcher Service. 2020-09-23 10:57:46 daemon info nm-dispatcher: req:1 'dhcp4-change' [usb1]: new request (4 scripts) 2020-09-23 10:57:46 daemon info nm-dispatcher: req:1 'dhcp4-change' [usb1]: start running ordered scripts... 2020-09-23 10:58:59 user notice connection-recover: ttyACM0: In the route setting, the destination host can not be reached 2020-09-23 11:00:30 user notice fu_recovery_connection_with_server_main.sh: [ERROR] mobile reset because of Ping fail 3 over 3 2020-09-23 11:00:30 daemon info ModemManager[24433]: Caught signal, shutting down... 2020-09-23 11:00:30 daemon info systemd[1]: Stopping Modem Manager... 2020-09-23 11:00:30 daemon info ModemManager[24433]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disabling) 2020-09-23 11:00:31 daemon info ModemManager[24433]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (home -> unknown) 2020-09-23 11:00:31 daemon info ModemManager[24433]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabling -> disabled) 2020-09-23 11:00:31 user info NetworkManager[4598]: [1600826431.3253] ModemManager disappeared from bus 2020-09-23 11:00:31 user info NetworkManager[4598]: [1600826431.3255] device (ttyACM0): state change: activated -> unmanaged (reason 'removed') [100 10 36] 2020-09-23 11:00:31 daemon info ModemManager[24433]: ModemManager is shut down 2020-09-23 11:00:31 daemon info systemd[1]: Stopped Modem Manager. 2020-09-23 11:00:31 user notice /usr/bin/wwan-force-restart: start force restart els31 2020-09-23 11:00:31 user info NetworkManager[4598]: [1600826431.3921] dhcp4 (usb1): canceled DHCP transaction, DHCP client pid 25182 2020-09-23 11:00:31 user info NetworkManager[4598]: [1600826431.3922] dhcp4 (usb1): state changed bound -> done 2020-09-23 11:00:31 daemon notice dbus[572]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' 2020-09-23 11:00:31 daemon info systemd[1]: Starting Network Manager Script Dispatcher Service... 2020-09-23 11:00:31 daemon notice dbus[572]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' 2020-09-23 11:00:31 daemon info systemd[1]: Started Network Manager Script Dispatcher Service. 2020-09-23 11:00:31 daemon info nm-dispatcher: req:1 'down' [ttyACM0]: new request (4 scripts) 2020-09-23 11:00:31 daemon info nm-dispatcher: req:1 'down' [ttyACM0]: start running ordered scripts... 2020-09-23 11:00:31 kern info kernel: usb 2-1: USB disconnect, device number 57 2020-09-23 11:00:31 kern info kernel: cdc_ether 2-1:1.0 usb1: unregister 'cdc_ether' usb-ci_hdrc.1-1, CDC Ethernet Device 2020-09-23 11:00:31 user info NetworkManager[4598]: [1600826431.7923] 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-09-23 11:00:32 user notice fu_resolvconf_modify_check_main.sh: [INFO] /etc/resolv.conf or /etc/hosts changed. 2020-09-23 11:00:45 kern info kernel: usb 2-1: new high-speed USB device number 58 using ci_hdrc 2020-09-23 11:00:45 user info NetworkManager[4598]: [1600826445.8426] manager: (usb1): new Ethernet device (/org/freedesktop/NetworkManager/Devices/38) 2020-09-23 11:00:45 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:38:21:80 2020-09-23 11:00:45 kern info kernel: cdc_acm 2-1:1.2: ttyACM0: USB ACM device 2020-09-23 11:00:45 user info NetworkManager[4598]: [1600826445.9427] 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-09-23 11:00:45 user info NetworkManager[4598]: [1600826445.9429] 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-09-23 11:00:45 user info NetworkManager[4598]: [1600826445.9445] device (usb1): state change: unmanaged -> unavailable (reason 'managed') [10 20 2] 2020-09-23 11:00:45 kern info kernel: IPv6: ADDRCONF(NETDEV_UP): usb1: link is not ready 2020-09-23 11:00:45 kern err kernel: cdc_ether 2-1:1.0 usb1: kevent 12 may have been dropped 2020-09-23 11:00:45 kern err kernel: cdc_ether 2-1:1.0 usb1: kevent 11 may have been dropped 2020-09-23 11:00:45 user info NetworkManager[4598]: [1600826445.9878] keyfile: add connection in-memory (00c3465e-c008-39cb-a77b-ed969ed03966,"Wired connection 1") 2020-09-23 11:00:45 user info NetworkManager[4598]: [1600826445.9939] settings: (usb1): created default wired connection 'Wired connection 1' 2020-09-23 11:00:46 user notice /usr/bin/wwan-force-restart: end force restart els31 2020-09-23 11:00:46 daemon info systemd[1]: Starting Modem Manager... 2020-09-23 11:00:46 daemon info ModemManager[28054]: ModemManager (version 1.6.4) starting in system bus... 2020-09-23 11:00:46 user info NetworkManager[4598]: [1600826446.8186] ModemManager disappeared from bus 2020-09-23 11:00:46 daemon info systemd[1]: Started Modem Manager. 2020-09-23 11:00:46 user info NetworkManager[4598]: [1600826446.9311] ModemManager available in the bus 2020-09-23 11:00:46 user notice fu_recovery_connection_with_server_main.sh: [ERROR] mosquitto hup signal because of the count that bridge connection error is 3 over 3 2020-09-23 11:00:47 daemon warning mosquitto[15504]: Warning: The retry_interval option is no longer available. 2020-09-23 11:00:47 user info NetworkManager[4598]: [1600826447.8195] device (usb1): link connected 2020-09-23 11:00:47 user info NetworkManager[4598]: [1600826447.8213] device (usb1): state change: unavailable -> disconnected (reason 'carrier-changed') [20 30 40] 2020-09-23 11:00:47 user info NetworkManager[4598]: [1600826447.8267] policy: auto-activating connection 'Wired connection 1' 2020-09-23 11:00:47 user info NetworkManager[4598]: [1600826447.8357] device (usb1): Activation: starting connection 'Wired connection 1' (00c3465e-c008-39cb-a77b-ed969ed03966) 2020-09-23 11:00:47 user info NetworkManager[4598]: [1600826447.8372] device (usb1): state change: disconnected -> prepare (reason 'none') [30 40 0] 2020-09-23 11:00:47 user info NetworkManager[4598]: [1600826447.8404] device (usb1): state change: prepare -> config (reason 'none') [40 50 0] 2020-09-23 11:00:47 user info NetworkManager[4598]: [1600826447.8427] device (usb1): state change: config -> ip-config (reason 'none') [50 70 0] 2020-09-23 11:00:47 user info NetworkManager[4598]: [1600826447.8452] dhcp4 (usb1): activation: beginning transaction (timeout in 45 seconds) 2020-09-23 11:00:47 user info NetworkManager[4598]: [1600826447.8547] dhcp4 (usb1): dhclient started with pid 28080 2020-09-23 11:00:47 daemon info dhclient[28080]: DHCPREQUEST of 192.168.15.100 on usb1 to 255.255.255.255 port 67 2020-09-23 11:00:49 daemon info ModemManager[28054]: Creating modem with plugin 'Cinterion ELS' and '2' ports 2020-09-23 11:00:49 daemon info ModemManager[28054]: Modem for device at '/sys/devices/soc0/soc/30800000.aips-bus/30b20000.usb/ci_hdrc.1/usb2/2-1' successfully created 2020-09-23 11:00:50 daemon info dhclient[28080]: DHCPREQUEST of 192.168.15.100 on usb1 to 255.255.255.255 port 67 2020-09-23 11:00:50 daemon info dhclient[28080]: DHCPACK of 192.168.15.100 from 192.168.15.1 2020-09-23 11:00:50 user info NetworkManager[4598]: [1600826450.9763] dhcp4 (usb1): address 192.168.15.100 2020-09-23 11:00:50 user info NetworkManager[4598]: [1600826450.9773] dhcp4 (usb1): plen 24 (255.255.255.0) 2020-09-23 11:00:50 user info NetworkManager[4598]: [1600826450.9774] dhcp4 (usb1): gateway 192.168.15.1 2020-09-23 11:00:50 user info NetworkManager[4598]: [1600826450.9775] dhcp4 (usb1): server identifier 192.168.15.1 2020-09-23 11:00:50 user info NetworkManager[4598]: [1600826450.9776] dhcp4 (usb1): lease time 43200 2020-09-23 11:00:50 user info NetworkManager[4598]: [1600826450.9777] dhcp4 (usb1): hostname 'armadillo' 2020-09-23 11:00:50 user info NetworkManager[4598]: [1600826450.9778] dhcp4 (usb1): nameserver '192.168.15.1' 2020-09-23 11:00:50 user info NetworkManager[4598]: [1600826450.9779] dhcp4 (usb1): domain name 'lan' 2020-09-23 11:00:50 user info NetworkManager[4598]: [1600826450.9781] dhcp4 (usb1): state changed unknown -> bound 2020-09-23 11:00:50 user info NetworkManager[4598]: [1600826450.9928] device (usb1): state change: ip-config -> ip-check (reason 'none') [70 80 0] 2020-09-23 11:00:50 user info NetworkManager[4598]: [1600826450.9990] device (usb1): state change: ip-check -> secondaries (reason 'none') [80 90 0] 2020-09-23 11:00:51 user info NetworkManager[4598]: [1600826451.0032] device (usb1): state change: secondaries -> activated (reason 'none') [90 100 0] 2020-09-23 11:00:51 daemon info dhclient[28080]: bound to 192.168.15.100 -- renewal in 20420 seconds. 2020-09-23 11:00:51 user info NetworkManager[4598]: [1600826451.0124] manager: NetworkManager state is now CONNECTED_LOCAL 2020-09-23 11:00:51 user info NetworkManager[4598]: [1600826451.0139] manager: NetworkManager state is now CONNECTED_GLOBAL 2020-09-23 11:00:51 user info NetworkManager[4598]: [1600826451.0151] policy: set 'Wired connection 1' (usb1) as default for IPv4 routing and DNS 2020-09-23 11:00:51 user info NetworkManager[4598]: [1600826451.0194] device (usb1): Activation: successful, device activated. 2020-09-23 11:00:51 daemon notice dbus[572]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' 2020-09-23 11:00:51 daemon info systemd[1]: Starting Network Manager Script Dispatcher Service... 2020-09-23 11:00:51 daemon notice dbus[572]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' 2020-09-23 11:00:51 daemon info systemd[1]: Started Network Manager Script Dispatcher Service. 2020-09-23 11:00:51 daemon info nm-dispatcher: req:1 'up' [usb1]: new request (4 scripts) 2020-09-23 11:00:51 daemon info nm-dispatcher: req:1 'up' [usb1]: start running ordered scripts... 2020-09-23 11:00:51 daemon info systemd[1]: Reloading OpenBSD Secure Shell server. 2020-09-23 11:00:51 daemon info nm-dispatcher[28092]: SIOCDELRT: No such process 2020-09-23 11:00:51 daemon info systemd[1]: Reloaded OpenBSD Secure Shell server. 2020-09-23 11:00:51 daemon info nm-dispatcher[28092]: SIOCDELRT: No such process 2020-09-23 11:00:51 daemon info nm-dispatcher[28092]: SIOCADDRT: File exists 2020-09-23 11:00:51 daemon info nm-dispatcher[28092]: run-parts: /etc/network/if-up.d/static-routes exited with return code 7 2020-09-23 11:00:51 daemon warning nm-dispatcher: req:1 'up' [usb1], "/etc/NetworkManager/dispatcher.d/01ifupdown": complete: failed with Script '/etc/NetworkManager/dispatcher.d/01ifupdown' exited with error status 1. 2020-09-23 11:00:52 user warning NetworkManager[4598]: [1600826452.0273] dispatcher: (159) 01ifupdown failed (failed): Script '/etc/NetworkManager/dispatcher.d/01ifupdown' exited with error status 1. 2020-09-23 11:00:52 user notice fu_resolvconf_modify_check_main.sh: [INFO] /etc/resolv.conf or /etc/hosts changed. 2020-09-23 11:00:59 daemon err ntpdate[28123]: no server suitable for synchronization found 2020-09-23 11:01:12 daemon info ModemManager[28054]: Modem: state changed (unknown -> disabled) 2020-09-23 11:01:12 user info NetworkManager[4598]: [1600826472.9486] device (usb1): state change: activated -> deactivating (reason 'connection-removed') [100 110 38] 2020-09-23 11:01:12 user info NetworkManager[4598]: [1600826472.9498] manager: NetworkManager state is now CONNECTED_LOCAL 2020-09-23 11:01:13 daemon info ModemManager[28054]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling) 2020-09-23 11:01:13 user info NetworkManager[4598]: [1600826473.0188] manager: (ttyACM0): new Broadband device (/org/freedesktop/NetworkManager/Devices/39) 2020-09-23 11:01:13 user info NetworkManager[4598]: [1600826473.0275] device (ttyACM0): state change: unmanaged -> unavailable (reason 'managed') [10 20 2] 2020-09-23 11:01:13 user info NetworkManager[4598]: [1600826473.0326] device (ttyACM0): modem state 'enabling' 2020-09-23 11:01:13 user info NetworkManager[4598]: [1600826473.0474] device (usb1): state change: deactivating -> disconnected (reason 'connection-removed') [110 30 38] 2020-09-23 11:01:13 kern err kernel: cdc_ether 2-1:1.0 usb1: kevent 12 may have been dropped 2020-09-23 11:01:13 user info NetworkManager[4598]: [1600826473.1321] dhcp4 (usb1): canceled DHCP transaction, DHCP client pid 28080 2020-09-23 11:01:13 user info NetworkManager[4598]: [1600826473.1323] dhcp4 (usb1): state changed bound -> done 2020-09-23 11:01:13 user err NetworkManager[4598]: ((src/nm-dispatcher.c:528)): assertion '' failed 2020-09-23 11:01:13 user warning NetworkManager[4598]: g_object_notify_by_pspec: assertion 'G_IS_OBJECT (object)' failed 2020-09-23 11:01:13 user warning NetworkManager[4598]: g_object_notify_by_pspec: assertion 'G_IS_OBJECT (object)' failed 2020-09-23 11:01:13 user warning NetworkManager[4598]: g_object_notify_by_pspec: assertion 'G_IS_OBJECT (object)' failed 2020-09-23 11:01:13 user warning NetworkManager[4598]: g_object_notify_by_pspec: assertion 'G_IS_OBJECT (object)' failed 2020-09-23 11:01:13 user warning NetworkManager[4598]: g_object_notify_by_pspec: assertion 'G_IS_OBJECT (object)' failed 2020-09-23 11:01:13 daemon warning systemd[1]: NetworkManager.service: Main process exited, code=killed, status=11/SEGV 2020-09-23 11:01:13 daemon notice systemd[1]: NetworkManager.service: Unit entered failed state. 2020-09-23 11:01:13 daemon warning systemd[1]: NetworkManager.service: Failed with result 'signal'. 2020-09-23 11:01:13 daemon info systemd[1]: NetworkManager.service: Service hold-off time over, scheduling restart. 2020-09-23 11:01:13 daemon info systemd[1]: Stopped Network Manager Wait Online. 2020-09-23 11:01:13 daemon info systemd[1]: Stopping Network Manager Wait Online... 2020-09-23 11:01:13 daemon info systemd[1]: Stopped Network Manager. 2020-09-23 11:01:13 daemon info systemd[1]: Starting Network Manager...