Долгая активация SIM карт Билайн

Программное обеспечение
1

Добрый день!
Уже давно наблюдаем проблему с долгой активацией SIM карт Билайн.
Проблема в том, что если новую SIM-карту вставить в телефон, она активируется быстро, но если срау вставить в контроллер, процесс может занять вечность.
Прошивка довольно свежая wb-2407, контроллер 7.4.4 (s/n AMRODKWW).

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

3

Добрый день, проанализировал логи ваши и вижу пока два настораживающих момента.

  1. signal quality: 64% (cached) Достаточно низкий уровень сигнала, данный фактор может значительно затруднять подключение к сотовой сети
  2. вижу повторявшиеся ошибки на шине, настоятельно рекомендую устранить данные проблемы.
Oct 14 13:16:48 IQ353006 wb-mqtt-serial[1634]: WARNING: </dev/ttyRS485-2 9600 8 N 2>: closed due to repetitive errors
Oct 14 13:16:51 IQ353006 wb-mqtt-serial[1634]: WARNING: </dev/ttyRS485-1 9600 8 N 2>: closed due to repetitive errors
Oct 14 13:16:53 IQ353006 wb-mqtt-serial[1634]: WARNING: </dev/ttyRS485-2 9600 8 N 2>: closed due to repetitive errors
Oct 14 13:16:57 IQ353006 wb-mqtt-serial[1634]: WARNING: </dev/ttyRS485-1 9600 8 N 2>: closed due to repetitive errors
Oct 14 13:16:58 IQ353006 wb-mqtt-serial[1634]: WARNING: </dev/ttyRS485-2 9600 8 N 2>: closed due to repetitive errors
5

Добрый день! Интересует только момент активации сим карты. Если сим карта ранее где-то использовалась, соединение устанавливается быстро.

7

В логе:

-- Boot 2866577c47b94e61ba8058684ba854f1 --
Oct 14 12:47:39 IQ353006 systemd[1]: Starting Modem Manager...
Oct 14 12:47:39 IQ353006 ModemManager[463]: <info>  ModemManager (version 1.20.0) starting in system bus...
Oct 14 12:47:40 IQ353006 systemd[1]: Started Modem Manager.
Oct 14 12:47:42 IQ353006 ModemManager[463]: <info>  [base-manager] couldn't check support for device '/sys/devices/platform/soc/1c0b000.ethernet': not supported by any plugin
Oct 14 12:47:42 IQ353006 ModemManager[463]: <info>  [base-manager] couldn't check support for device '/sys/devices/platform/soc/1c19000.usb/usb2/2-1': not supported by any plugin
Oct 14 12:47:42 IQ353006 ModemManager[463]: <info>  [base-manager] couldn't check support for device '/sys/devices/platform/soc/1c50000.ethernet': not supported by any plugin
Oct 14 12:48:01 IQ353006 ModemManager[463]: <info>  [base-manager] couldn't check support for device '/sys/devices/platform/soc/1c13000.usb/musb-hdrc.8.auto': not supported by any plugin
Oct 14 12:48:36 IQ353006 ModemManager[463]: <info>  [device wbc] creating modem with plugin 'simtech' and '5' ports
Oct 14 12:48:36 IQ353006 ModemManager[463]: <info>  [base-manager] modem for device 'wbc' successfully created
Oct 14 12:48:36 IQ353006 ModemManager[463]: <warn>  [modem0] couldn't load supported modes: Couldn't retrieve supported modes
Oct 14 12:48:37 IQ353006 ModemManager[463]: <warn>  [modem0/sim0] couldn't load list of emergency numbers: Failed to parse CRSM query result '+CRSM: 105,129'
Oct 14 12:48:37 IQ353006 ModemManager[463]: <warn>  [modem0/sim0] couldn't load GID1: SIM failure
Oct 14 12:48:37 IQ353006 ModemManager[463]: <warn>  [modem0/sim0] couldn't load GID2: SIM failure
Oct 14 12:48:38 IQ353006 ModemManager[463]: <warn>  [modem0/sim0] couldn't load list of emergency numbers: Failed to parse CRSM query result '+CRSM: 105,129'
Oct 14 12:48:38 IQ353006 ModemManager[463]: <warn>  [modem0/sim0] couldn't load GID1: SIM failure
Oct 14 12:48:38 IQ353006 ModemManager[463]: <warn>  [modem0/sim0] couldn't load GID2: SIM failure
Oct 14 12:48:38 IQ353006 ModemManager[463]: <info>  [modem0] state changed (unknown -> disabled)
Oct 14 12:48:38 IQ353006 ModemManager[463]: <info>  [modem0] state changed (disabled -> enabling)
Oct 14 12:48:38 IQ353006 ModemManager[463]: <info>  [modem0] power state updated: on
Oct 14 12:48:38 IQ353006 ModemManager[463]: <info>  [modem0] 3GPP registration state changed (unknown -> denied)
Oct 14 12:48:41 IQ353006 ModemManager[463]: <info>  [modem0] state changed (enabling -> enabled)
Oct 14 12:48:49 IQ353006 ModemManager[463]: <info>  [modem0] mobile equipment forced PS detach: all contexts have been deactivated
Oct 14 12:48:49 IQ353006 ModemManager[463]: <info>  [modem0] 3GPP registration state changed (denied -> idle)
Oct 14 12:48:54 IQ353006 ModemManager[463]: <info>  [modem0] state changed (enabled -> disabling)
Oct 14 12:48:55 IQ353006 ModemManager[463]: <info>  [modem0] 3GPP registration state changed (idle -> unknown)
Oct 14 12:48:57 IQ353006 ModemManager[463]: <info>  [device wbc] creating modem with plugin 'simtech' and '5' ports
Oct 14 12:48:57 IQ353006 ModemManager[463]: <warn>  [modem1] couldn't load supported modes: Couldn't retrieve supported modes
Oct 14 12:48:57 IQ353006 ModemManager[463]: <info>  [modem1] state changed (unknown -> locked)
Oct 14 12:48:57 IQ353006 ModemManager[463]: <warn>  [modem1] modem couldn't be initialized: Couldn't check unlock status: SIM not inserted
Oct 14 12:48:57 IQ353006 ModemManager[463]: <info>  [modem1] state changed (locked -> failed)
Oct 14 12:48:57 IQ353006 ModemManager[463]: <warn>  [modem1] error initializing: Modem in failed state: sim-missing
Oct 14 12:50:11 IQ353006 ModemManager[463]: <info>  [device wbc] creating modem with plugin 'simtech' and '5' ports
Oct 14 12:50:11 IQ353006 ModemManager[463]: <warn>  [modem2] couldn't load supported modes: Couldn't retrieve supported modes
Oct 14 12:50:12 IQ353006 ModemManager[463]: <warn>  [modem2/sim1] couldn't load list of emergency numbers: Failed to parse CRSM query result '+CRSM: 105,129'
Oct 14 12:50:12 IQ353006 ModemManager[463]: <warn>  [modem2/sim1] couldn't load GID1: SIM failure
Oct 14 12:50:12 IQ353006 ModemManager[463]: <warn>  [modem2/sim1] couldn't load GID2: SIM failure
Oct 14 12:50:12 IQ353006 ModemManager[463]: <warn>  [modem2/sim1] couldn't load list of emergency numbers: Failed to parse CRSM query result '+CRSM: 105,129'
Oct 14 12:50:12 IQ353006 ModemManager[463]: <warn>  [modem2/sim1] couldn't load GID1: SIM failure
Oct 14 12:50:12 IQ353006 ModemManager[463]: <warn>  [modem2/sim1] couldn't load GID2: SIM failure
Oct 14 12:50:12 IQ353006 ModemManager[463]: <warn>  [modem2] couldn't load list of own numbers: Operation not supported
Oct 14 12:50:12 IQ353006 ModemManager[463]: <info>  [modem2] state changed (unknown -> disabled)
Oct 14 12:50:12 IQ353006 ModemManager[463]: <info>  [modem2] state changed (disabled -> enabling)
Oct 14 12:50:12 IQ353006 ModemManager[463]: <info>  [modem2] power state updated: on
Oct 14 12:50:13 IQ353006 ModemManager[463]: <info>  [modem2] 3GPP registration state changed (unknown -> denied)
Oct 14 12:50:15 IQ353006 ModemManager[463]: <info>  [modem2] state changed (enabling -> enabled)
Oct 14 12:50:58 IQ353006 ModemManager[463]: <info>  [modem2] 3GPP registration state changed (denied -> idle)
Oct 14 13:07:59 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:07:59 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:07:59 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:07:59 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:07:59 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:07:59 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:07:59 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:07:59 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:08:08 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:08:08 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:13:00 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:13:00 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:13:00 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:13:00 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:13:00 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:13:00 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:13:00 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:13:00 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:18:01 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:18:01 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:18:01 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:18:01 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:18:01 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:18:01 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:18:01 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:18:01 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:23:02 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:23:02 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:23:02 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:23:02 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:23:02 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:23:02 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register
Oct 14 13:23:02 IQ353006 ModemManager[463]: <info>  [modem2] simple connect started...
Oct 14 13:23:02 IQ353006 ModemManager[463]: <info>  [modem2] simple connect state (6/10): register

Это бага в NM, он зацикливается в некоторых условиях.
После перезапуска NM - он начинает работать сразу?
То, что видел выглядит немного по другому:

Oct 08 14:31:04 NK ModemManager[396]: <info>  [modem2] simple connect started...
Oct 08 14:31:04 NK ModemManager[396]: <info>  [modem2] simple connect state (6/10): register
Oct 08 14:31:04 NK ModemManager[396]: <info>  [modem2] simple connect state (7/10): wait to get packet service state attached
Oct 08 14:31:14 NK ModemManager[396]: <info>  [modem2] simple connect started...
Oct 08 14:31:14 NK ModemManager[396]: <info>  [modem2] simple connect state (6/10): register
Oct 08 14:31:14 NK ModemManager[396]: <info>  [modem2] simple connect state (7/10): wait to get packet service state attached
Oct 08 14:31:24 NK ModemManager[396]: <info>  [modem2] simple connect started...
Oct 08 14:31:24 NK ModemManager[396]: <info>  [modem2] simple connect state (6/10): register
Oct 08 14:31:24 NK ModemManager[396]: <info>  [modem2] simple connect state (7/10): wait to get packet service state attached

Но думаю причина та же.
И да, уже описано и разработчики занимаются.

1 лайк