Сообщения в системном журнале

Немного позже один из тестовых контроллеров обновлю до актуального bullseye
И попробую снова добиться коннекта.

Пока же по отключению увидел такие строки в журнале.

|08-04-2024 14:06:59.879| root : TTY=unknown ; PWD=/ ; USER=avahi ; GROUP=avahi ; COMMAND=/usr/lib/avahi/avahi-daemon-check-dns.sh
|08-04-2024 14:06:59.789|Connection terminated.
|08-04-2024 14:06:59.789|Connection terminated.
|08-04-2024 14:06:59.776|Sent 0 bytes, received 646044 bytes.
|08-04-2024 14:06:59.770|Connect time 1439.0 minutes.
|08-04-2024 14:06:59.768|Terminating on signal 15
|07-04-2024 14:08:00.275|remote IP address 10.64.64.64
|07-04-2024 14:08:00.272|local IP address 10.94.16.29
|07-04-2024 14:08:00.267|not replacing default route to eth0 [192.168.11.1]
|07-04-2024 14:08:00.262|Could not determine remote IP address: defaulting to 10.64.64.64
|07-04-2024 14:08:00.253|kernel does not support PPP filtering
|07-04-2024 14:08:00.244|PAP authentication succeeded
|07-04-2024 14:08:00.244|PAP authentication succeeded
|07-04-2024 14:07:59.258|Connect: ppp0 ↔ /dev/ttyGSM
|07-04-2024 14:07:59.255|Using interface ppp0
|07-04-2024 14:07:59.251|Serial connection established.

Ровно через сутки после подключения - отключение.
Это:

Terminating on signal 15
Что означает?
Кто инициатор разрыва?

А вы проверяли выход с СИМ в Интернет?.
У нас то выдаётся IP из закрытой сети.
Хотя по сути со стороны контроллера/модема разница только в APN

Обновил до последней версии
Ни в какие файлы не залазил. Ничего не менял.
Через WEB выставил только APN.
И заработало…
Только зарегистрировалось лишь в 3G почему-то. Не LTE

Судя по времени - провайдер. у большинства соединение рвется раз в сутки.

Да.

У меня обычные карты, публичных тарифов.

Ну, я не могу никак прокомментировать…

Буду тестировать на этой версии, вроде бы пока всё идёт удачно. Сконфигурировал eth0 как нам нужно, мобильное соединение тоже работает нормально и LTE вернулся.

Тему закрываю.
Огромное спасибо за помощь и терпение.

1 лайк

Всё же снова вопрос.
Получаю такой лог от Modem Manager после некоторого времени.

|11-04-2024 15:26:09.901| [modem0] simple connect state (7/10): wait to get packet service state attached|
|11-04-2024 15:26:09.900| [modem0] simple connect state (6/10): register|
|11-04-2024 15:26:09.897| [modem0] simple connect started…|
|11-04-2024 15:25:59.213| [modem0] simple connect state (7/10): wait to get packet service state attached|
|11-04-2024 15:25:59.211| [modem0] simple connect state (6/10): register|
|11-04-2024 15:25:59.208| [modem0] simple connect started…|
|11-04-2024 15:25:48.898| [modem0] simple connect state (7/10): wait to get packet service state attached|
|11-04-2024 15:25:48.897| [modem0] simple connect state (6/10): register|
|11-04-2024 15:25:48.893| [modem0] simple connect started…|
|11-04-2024 15:25:38.212| [modem0] simple connect state (7/10): wait to get packet service state attached|
|11-04-2024 15:25:38.205| [modem0] simple connect state (6/10): register|
|11-04-2024 15:25:38.197| [modem0] simple connect started…|

Если перезагрузить контроллер - снова начинает работать.

Перед тем как отключиться приходят стрпанные сообщения(выделил).
Это что? Входящий вызов?

|11-04-2024 12:25:58.653| [modem0] simple connect state (7/10): wait to get packet service state attached|
|11-04-2024 12:25:58.639| [modem0] simple connect state (6/10): register|
|11-04-2024 12:25:58.639| [modem0] simple connect state (6/10): register|
|11-04-2024 12:25:58.636| [modem0] simple connect started…|
|11-04-2024 12:25:56.316| [modem0] simple connect state (7/10): wait to get packet service state attached|
|11-04-2024 12:25:56.307| [modem0] simple connect state (6/10): register|
|11-04-2024 12:25:56.303| [modem0] simple connect started…|
|11-04-2024 12:25:54.930| [modem0/call1] call state changed: ringing-in → terminated (unknown)|
|11-04-2024 12:25:45.896| [modem0] simple connect state (7/10): wait to get packet service state attached|
|11-04-2024 12:25:45.895| [modem0] simple connect state (6/10): register|
|11-04-2024 12:25:45.889| [modem0] simple connect started…|
|11-04-2024 12:25:39.810| [modem0] mobile equipment request to activate context (cid 1)|
|11-04-2024 12:25:39.810| [modem0] mobile equipment request to activate context (cid 1)|
|11-04-2024 12:25:35.810| [modem0] simple connect state (7/10): wait to get packet service state attached|
|11-04-2024 12:25:35.808| [modem0] simple connect state (6/10): register|
|11-04-2024 12:25:35.769| [modem0/bearer0] connection #7 finished: duration 74535s|
|11-04-2024 12:25:35.710| [modem0] simple connect state (5/10): wait after enabled|
|11-04-2024 12:25:35.701| [modem0] state changed (disconnecting → registered)|
|11-04-2024 12:25:35.097| [modem0] simple connect state (4/10): wait to get fully enabled|
|11-04-2024 12:25:35.093| [modem0] simple connect started…|
|11-04-2024 12:25:34.612| [modem0] state changed (connected → disconnecting)|
|11-04-2024 12:25:31.325| [modem0/bearer0] explicitly disconnected|
|11-04-2024 12:25:31.325| [modem0/bearer0] explicitly disconnected|
|11-04-2024 12:25:31.290| [modem0] network request to deactivate context (cid 1)|
|11-04-2024 12:25:24.010| [modem0/call1] call state changed: unknown → ringing-in (incoming-new)|
|11-04-2024 08:51:52.545| [modem0/call0] call state changed: ringing-in → terminated (unknown)|
|11-04-2024 08:51:23.789| [modem0/call0] call state changed: unknown → ringing-in (incoming-new)|
|11-04-2024 08:51:21.938| [modem0] 3GPP registration state changed (registering → home)|
|11-04-2024 08:51:21.849| [modem0] 3GPP registration state changed (home → registering)|
|10-04-2024 15:43:20.589| [modem0] simple connect state (10/10): all done|
|10-04-2024 15:43:20.571| [modem0] state changed (connecting → connected)|
|10-04-2024 15:43:20.441| [modem0] state changed (registered → connecting)|
|10-04-2024 15:43:20.441| [modem0] state changed (registered → connecting)|
|10-04-2024 15:43:20.408| [modem0] simple connect state (9/10): connect|
|10-04-2024 15:43:20.403| [modem0] simple connect state (8/10): bearer|
|10-04-2024 15:43:20.385| [modem0] simple connect state (7/10): wait to get packet service state attached|
|10-04-2024 15:43:20.363| [modem0] simple connect state (6/10): register|
|10-04-2024 15:43:20.309| [modem0/bearer0] connection #6 finished: duration 57s|
|10-04-2024 15:43:20.255| [modem0] simple connect state (5/10): wait after enabled|
|10-04-2024 15:43:20.250| [modem0] state changed (disconnecting → registered)|
|10-04-2024 15:43:19.602| [modem0] simple connect state (4/10): wait to get fully enabled|
|10-04-2024 15:43:19.599| [modem0] simple connect started…|
|10-04-2024 15:43:19.599| [modem0] simple connect started…|
|10-04-2024 15:43:19.169| [modem0] state changed (connected → disconnecting)|
|10-04-2024 15:42:22.317| [modem0] simple connect state (10/10): all done|
|10-04-2024 15:42:22.278| [modem0] state changed (connecting → connected)|
|10-04-2024 15:42:22.155| [modem0] state changed (registered → connecting)|
|10-04-2024 15:42:22.128| [modem0] simple connect state (9/10): connect|
|10-04-2024 15:42:22.123| [modem0] simple connect state (8/10): bearer|
|10-04-2024 15:42:22.122| [modem0] simple connect state (7/10): wait to get packet service state attached|
|10-04-2024 15:42:22.120| [modem0] simple connect state (6/10): register|
|10-04-2024 15:42:22.058| [modem0/bearer0] connection #5 finished: duration 30s|
|10-04-2024 15:42:22.058| [modem0/bearer0] connection #5 finished: duration 30s|
|10-04-2024 15:42:22.017| [modem0] simple connect state (5/10): wait after enabled|
|10-04-2024 15:42:22.014| [modem0] state changed (disconnecting → registered)|
|10-04-2024 15:42:21.351| [modem0] simple connect state (4/10): wait to get fully enabled|
|10-04-2024 15:42:21.349| [modem0] simple connect started…|

В связи с этим вопрос в том как решить таку проблему?
Можно настроить как то соединение?
Или придумывать какой-то триггер который перезапустит контроллер?
Или просто настраивать периодический рестарт контроллера? (что очень не желательно)

Да, это входящий звонок.

Вот с этим - надо в поддержку оператора написать. Я подобного не видел еще.
Выложите диагностический архив, позову более опытных коллег.

Сегодня утром ситуация, увы, повторилась.

|12-04-2024 08:56:54.913| [modem0] simple connect state (7/10): wait to get packet service state attached|
|12-04-2024 08:56:54.903| [modem0] simple connect state (6/10): register|
|12-04-2024 08:56:54.899| [modem0] simple connect started…|
|12-04-2024 08:56:44.251| [modem0] simple connect state (7/10): wait to get packet service state attached|
|12-04-2024 08:56:44.248| [modem0] simple connect state (6/10): register|
|12-04-2024 08:56:44.245| [modem0] simple connect started…|
|12-04-2024 08:56:43.093| [modem0/call1] call state changed: ringing-in → terminated (unknown)|
|12-04-2024 08:56:33.915| [modem0] simple connect state (7/10): wait to get packet service state attached|
|12-04-2024 08:56:33.914| [modem0] simple connect state (6/10): register|
|12-04-2024 08:56:33.909| [modem0] simple connect started…|
|12-04-2024 08:56:33.909| [modem0] simple connect started…|
|12-04-2024 08:56:27.492| [modem0] mobile equipment request to activate context (cid 1)|
|12-04-2024 08:56:23.530| [modem0] simple connect state (7/10): wait to get packet service state attached|
|12-04-2024 08:56:23.529| [modem0] simple connect state (6/10): register|
|12-04-2024 08:56:23.475| [modem0/bearer0] connection #1 finished: duration 61769s|
|12-04-2024 08:56:23.431| [modem0] simple connect state (5/10): wait after enabled|
|12-04-2024 08:56:23.428| [modem0] state changed (disconnecting → registered)|
|12-04-2024 08:56:22.904| [modem0] simple connect state (4/10): wait to get fully enabled|
|12-04-2024 08:56:22.903| [modem0] simple connect started…|
|12-04-2024 08:56:22.357| [modem0] state changed (connected → disconnecting)|
|12-04-2024 08:56:22.357| [modem0] state changed (connected → disconnecting)|
|12-04-2024 08:56:19.062| [modem0/bearer0] explicitly disconnected|
|12-04-2024 08:56:19.055| [modem0] network request to deactivate context (cid 1)|
|12-04-2024 08:56:12.373| [modem0/call1] call state changed: unknown → ringing-in (incoming-new)|
|11-04-2024 16:05:57.204| [modem0/call0] call state changed: ringing-in → terminated (unknown)|
|11-04-2024 16:05:27.485| [modem0/call0] call state changed: unknown → ringing-in (incoming-new)|
|11-04-2024 16:05:25.676| [modem0] 3GPP registration state changed (registering → home)|
|11-04-2024 16:05:25.585| [modem0] 3GPP registration state changed (home → registering)|
|11-04-2024 15:46:55.755| [modem0] simple connect state (10/10): all done|
|11-04-2024 15:46:55.714| [modem0] state changed (connecting → connected)|
|11-04-2024 15:46:55.714| [modem0] state changed (connecting → connected)|
|11-04-2024 15:46:55.579| [modem0] state changed (registered → connecting)|
|11-04-2024 15:46:55.575| [modem0] simple connect state (9/10): connect|
|11-04-2024 15:46:55.561| [modem0] simple connect state (8/10): bearer|
|11-04-2024 15:46:55.558| [modem0] simple connect state (7/10): wait to get packet service state attached|
|11-04-2024 15:46:55.244| [modem0] state changed (enabled → registered)|
|11-04-2024 15:46:55.240| [modem0] 3GPP registration state changed (registering → home)|
|11-04-2024 15:46:55.135| [modem0] 3GPP registration state changed (idle → registering)|
|11-04-2024 15:46:45.791| [modem0] simple connect state (6/10): register|
|11-04-2024 15:46:45.690| [modem0] simple connect state (5/10): wait after enabled|
|11-04-2024 15:46:45.690| [modem0] simple connect state (5/10): wait after enabled|
|11-04-2024 15:46:45.684| [modem0] state changed (enabling → enabled)|
|11-04-2024 15:46:43.163| [modem0] 3GPP registration state changed (unknown → idle)|
|11-04-2024 15:46:42.269| [modem0] power state updated: on|

Прикладываю архив

приложен диагностический архив, доступен только сотрудникам поддержки
(159,3 КБ)

Отлично. Позвал разработчиков.
А что за входящие звонки - получилось узнать у оператора?

Нет. Трудность в том что мы - подрядчики. СИМки - заказчика.
Информацию передал. Попросил узнать реально ли со стороны провайдера вообще запретить голосовые вызовы.

Но всё же хотелось бы чтобы по окончании звонка соединение восстанавливалось без необходимости перезагружать контроллер.

Вот воспроизведу реакцию модема на входящий звонок, но скорее в понедельник - и да, думаю устраним.

У меня есть подозрения, что это не входящий звонок возможно.
Потому что это происходит через день или каждый день.
Странно.
Не может какая-то другая причина интерпретироваться как входящий звонок?

Думаю что нет. Еще не воспроизводил, попробую сегодня.

вот в логах еще что увидел

|17-04-2024 15:39:04.030| [modem0] port ttyUSB1 timed out 10 consecutive times, marking modem as invalid|
|17-04-2024 15:38:59.697| [modem0] port ttyUSB1 timed out 9 consecutive times|
|17-04-2024 15:38:24.944| [modem0] port ttyUSB1 timed out 8 consecutive times|
|17-04-2024 15:38:20.711| [modem0] port ttyUSB1 timed out 7 consecutive times|
|17-04-2024 15:37:45.814| [modem0] port ttyUSB1 timed out 6 consecutive times|
|17-04-2024 15:37:41.480| [modem0] port ttyUSB1 timed out 5 consecutive times|
|17-04-2024 15:37:22.483| [modem0] simple connect state (7/10): wait to get packet service state attached|
|17-04-2024 15:37:22.482| [modem0] simple connect state (6/10): register|
|17-04-2024 15:37:22.478| [modem0] simple connect started…|
|17-04-2024 15:37:11.750| [modem0] simple connect state (7/10): wait to get packet service state attached|
|17-04-2024 15:37:11.750| [modem0] simple connect state (7/10): wait to get packet service state attached|
|17-04-2024 15:37:11.749| [modem0] simple connect state (6/10): register|
|17-04-2024 15:37:11.744| [modem0] simple connect started…|
|17-04-2024 15:37:06.133| [modem0] port ttyUSB1 timed out 4 consecutive times|
|17-04-2024 15:37:01.850| [modem0] port ttyUSB1 timed out 3 consecutive times|
|17-04-2024 15:37:01.470| [modem0] simple connect state (7/10): wait to get packet service state attached|
|17-04-2024 15:37:01.469| [modem0] simple connect state (6/10): register|
|17-04-2024 15:37:01.465| [modem0] simple connect started…|
|17-04-2024 15:36:50.836| [modem0] simple connect state (7/10): wait to get packet service state attached|
|17-04-2024 15:36:50.828| [modem0] simple connect state (6/10): register|
|17-04-2024 15:36:50.828| [modem0] simple connect state (6/10): register|
|17-04-2024 15:36:50.825| [modem0] simple connect started…|
|17-04-2024 15:36:40.467| [modem0] simple connect state (7/10): wait to get packet service state attached|
|17-04-2024 15:36:40.466| [modem0] simple connect state (6/10): register|
|17-04-2024 15:36:40.461| [modem0] simple connect started…|
|17-04-2024 15:36:29.777| [modem0] simple connect state (7/10): wait to get packet service state attached|
|17-04-2024 15:36:29.770| [modem0] simple connect state (6/10): register|
|17-04-2024 15:36:29.767| [modem0] simple connect started…|
|17-04-2024 15:36:27.060| [modem0] port ttyUSB1 timed out 2 consecutive times|
|17-04-2024 15:36:19.464| [modem0] simple connect state (7/10): wait to get packet service state attached|
|17-04-2024 15:36:19.464| [modem0] simple connect state (7/10): wait to get packet service state attached|
|17-04-2024 15:36:19.463| [modem0] simple connect state (6/10): register|
|17-04-2024 15:36:19.458| [modem0] simple connect started…|
|17-04-2024 15:36:09.542| [modem0] simple connect state (7/10): wait to get packet service state attached|
|17-04-2024 15:36:09.536| [modem0] simple connect state (6/10): register|
|17-04-2024 15:36:09.533| [modem0] simple connect started…|
|17-04-2024 15:36:01.605| [modem0/call3] call state changed: ringing-in → terminated (unknown)|
> |17-04-2024 15:35:33.687| [modem0/call3] call state changed: unknown → ringing-in (incoming-new)|

Интересны нижние две строки, согласно которых вроде как опять был входящий.
И верхняя строка, где модем признаётся неисправным ((

Да, это довольно неожиданное поведение. Скажите - какой у вас модем, его серийиный номер и версию? попробую проверить на таком же.

v1.6G/1
WBC-4G
v1.6

Серийник возможно с другой стороны.