우리 시스템 중 하나에서 최근 4G 네트워크 연결이 끊어진 후 4G 연결이 다시 연결되더라도 모뎀이 연결을 다시 설정할 수 없는 문제가 발생했습니다. 다시 연결하려면 수동으로 시스템을 다시 시작해야 합니다. 우리 기계가 먼 지역에 있기 때문에 이것은 매우 불편합니다. 그래서 저는 무슨 일이 일어났는지, 그리고 앞으로 이런 상황을 피할 수 있는 방법을 알고 싶습니다.
저는 Linux를 처음 접했기 때문에 뻔한 질문을 해서 죄송합니다. 로그 파일에 있는 대부분의 줄을 인터넷에서 검색해 보았지만 별로 도움이 되지 않았습니다.
아래에서 일부 로그를 찾을 수 있습니다. 이는 문제 시작 부분의 로그일 뿐만 아니라 재부팅할 때까지 시스템이 겪은 지속적인 연결 루프를 보여주는 마지막 부분이기도 합니다.
누군가 여기서 무슨 일이 일어나고 있는지, 그리고 이를 방지하는 방법을 이해하도록 도와줄 수 있다면 좋을 것입니다. 당신의 도움을 주셔서 감사합니다!
시스템 메시지:
- 우분투 18.04.3 LTS
- 모뎀: 화웨이 ME909s-120
- 모뎀 관리자 버전: 1.12.2
- 네트워크 관리자 버전: 1.10.6
통나무
Jan 29 23:41:02 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (roaming -> idle)
Jan 29 23:41:02 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> enabled)
Jan 29 23:41:02 NetworkManager[746]: <info> [1580341262.8456] modem["ttyUSB0"]: modem state changed, 'connected' --> 'enabled' (reason: unknown)
Jan 29 23:41:02 NetworkManager[746]: <info> [1580341262.8457] device (ttyUSB0): state change: activated -> failed (reason 'modem-no-carrier', sys-iface-state: 'managed')
Jan 29 23:41:02 NetworkManager[746]: <info> [1580341262.8463] manager: NetworkManager state is now DISCONNECTED
Jan 29 23:41:02 NetworkManager[746]: <warn> [1580341262.8600] device (ttyUSB0): Activation: failed for connection 'lte0'
Jan 29 23:41:02 dbus-daemon[682]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.8' (uid=0 pid=746 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
Jan 29 23:41:02 NetworkManager[746]: <info> [1580341262.8638] device (ttyUSB0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Jan 29 23:41:02 kernel: [3073082.377544] IPv6: ADDRCONF(NETDEV_UP): wwp0s20u3c2: link is not ready
Jan 29 23:41:02 systemd[1]: Starting Network Manager Script Dispatcher Service...
Jan 29 23:41:02 NetworkManager[746]: <info> [1580341262.8696] policy: auto-activating connection 'lte0'
Jan 29 23:41:02 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> disconnecting)
Jan 29 23:41:02 ModemManager[836]: <warn> Couldn't find associated cdc-wdm port for 'net/wwp0s20u3c2'
Jan 29 23:41:02 NetworkManager[746]: <info> [1580341262.8759] modem["ttyUSB0"]: modem state changed, 'enabled' --> 'disconnecting' (reason: user-requested)
Jan 29 23:41:02 NetworkManager[746]: <info> [1580341262.8774] device (ttyUSB0): Activation: starting connection 'lte0' (3a37dbea-8a70-44ed-87ae-3330cadd316c)
Jan 29 23:41:02 NetworkManager[746]: <info> [1580341262.8783] device (ttyUSB0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 29 23:41:02 NetworkManager[746]: <info> [1580341262.8786] manager: NetworkManager state is now CONNECTING
Jan 29 23:41:02 ModemManager[836]: <info> Simple connect started...
Jan 29 23:41:02 ModemManager[836]: <info> Simple connect state (4/8): Wait to get fully enabled
Jan 29 23:41:02 dbus-daemon[682]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jan 29 23:41:02 systemd[1]: Started Network Manager Script Dispatcher Service.
Jan 29 23:41:02 nm-dispatcher: req:1 'down' [wwp0s20u3c2]: new request (1 scripts)
Jan 29 23:41:02 nm-dispatcher: req:1 'down' [wwp0s20u3c2]: start running ordered scripts...
Jan 29 23:41:02 nm-dispatcher: req:2 'connectivity-change': new request (1 scripts)
Jan 29 23:41:02 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> connected)
Jan 29 23:41:02 ModemManager[836]: <info> Simple connect state (5/8): Register
Jan 29 23:41:02 NetworkManager[746]: <info> [1580341262.9043] modem["ttyUSB0"]: modem state changed, 'disconnecting' --> 'connected' (reason: user-requested)
Jan 29 23:41:02 nm-dispatcher: req:2 'connectivity-change': start running ordered scripts...
Jan 29 23:41:02 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (idle -> searching)
Jan 29 23:42:02 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (searching -> idle)
Jan 29 23:42:02 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> enabled)
Jan 29 23:42:02 NetworkManager[746]: <info> [1580341322.9355] modem["ttyUSB0"]: modem state changed, 'connected' --> 'enabled' (reason: unknown)
Jan 29 23:42:02 NetworkManager[746]: <warn> [1580341322.9602] modem-broadband[ttyUSB0]: failed to connect modem: Network timeout
Jan 29 23:42:02 NetworkManager[746]: <info> [1580341322.9603] device (ttyUSB0): state change: prepare -> failed (reason 'gsm-registration-timeout', sys-iface-state: 'managed')
Jan 29 23:42:02 NetworkManager[746]: <info> [1580341322.9609] manager: NetworkManager state is now DISCONNECTED
Jan 29 23:42:02 NetworkManager[746]: <warn> [1580341322.9620] device (ttyUSB0): Activation: failed for connection 'lte0'
Jan 29 23:42:02 NetworkManager[746]: <info> [1580341322.9648] device (ttyUSB0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Jan 29 23:42:02 NetworkManager[746]: <info> [1580341322.9669] policy: auto-activating connection 'lte0'
Jan 29 23:42:02 NetworkManager[746]: <info> [1580341322.9703] device (ttyUSB0): Activation: starting connection 'lte0' (3a37dbea-8a70-44ed-87ae-3330cadd316c)
Jan 29 23:42:02 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> disconnecting)
Jan 29 23:42:02 NetworkManager[746]: <info> [1580341322.9709] device (ttyUSB0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 29 23:42:02 ModemManager[836]: <warn> Couldn't find associated cdc-wdm port for 'net/wwp0s20u3c2'
Jan 29 23:42:02 NetworkManager[746]: <info> [1580341322.9713] manager: NetworkManager state is now CONNECTING
Jan 29 23:42:02 NetworkManager[746]: <info> [1580341322.9748] modem["ttyUSB0"]: modem state changed, 'enabled' --> 'disconnecting' (reason: user-requested)
Jan 29 23:42:02 ModemManager[836]: <info> Simple connect started...
Jan 29 23:42:02 ModemManager[836]: <info> Simple connect state (4/8): Wait to get fully enabled
Jan 29 23:42:03 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> connected)
Jan 29 23:42:03 ModemManager[836]: <info> Simple connect state (5/8): Register
Jan 29 23:42:03 NetworkManager[746]: <info> [1580341323.0029] modem["ttyUSB0"]: modem state changed, 'disconnecting' --> 'connected' (reason: user-requested)
Jan 29 23:42:03 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (idle -> searching)
Jan 29 23:42:26 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (searching -> registering)
Jan 29 23:42:27 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> roaming)
Jan 29 23:42:29 ModemManager[836]: <info> Simple connect state (6/8): Bearer
Jan 29 23:42:29 NetworkManager[746]: <warn> [1580341349.9281] modem-broadband[ttyUSB0]: failed to connect modem: Cannot create new bearer: all existing bearers are connected
Jan 29 23:42:29 NetworkManager[746]: <info> [1580341349.9282] device (ttyUSB0): state change: prepare -> failed (reason 'unknown', sys-iface-state: 'managed')
Jan 29 23:42:29 NetworkManager[746]: <info> [1580341349.9288] manager: NetworkManager state is now DISCONNECTED
Jan 29 23:42:29 NetworkManager[746]: <warn> [1580341349.9299] device (ttyUSB0): Activation: failed for connection 'lte0'
Jan 29 23:42:29 NetworkManager[746]: <info> [1580341349.9318] device (ttyUSB0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Jan 29 23:42:29 NetworkManager[746]: <info> [1580341349.9339] policy: auto-activating connection 'lte0'
Jan 29 23:42:29 NetworkManager[746]: <info> [1580341349.9374] device (ttyUSB0): Activation: starting connection 'lte0' (3a37dbea-8a70-44ed-87ae-3330cadd316c)
Jan 29 23:42:29 NetworkManager[746]: <info> [1580341349.9382] device (ttyUSB0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 29 23:42:29 NetworkManager[746]: <info> [1580341349.9388] manager: NetworkManager state is now CONNECTING
Jan 29 23:42:29 ModemManager[836]: <info> Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connected -> disconnecting)
Jan 29 23:42:29 ModemManager[836]: <warn> Couldn't find associated cdc-wdm port for 'net/wwp0s20u3c2'
Jan 29 23:42:29 NetworkManager[746]: <info> [1580341349.9436] modem["ttyUSB0"]: modem state changed, 'connected' --> 'disconnecting' (reason: user-requested)