Bug 30489

Summary: ModemManager подключает мобильный интернет только после передергивания модема
Product: Branch p7 Reporter: Эрик <mister.eric>
Component: ModemManagerAssignee: Mikhail Efremov <sem>
Status: CLOSED WONTFIX QA Contact: qa-p7 <qa-p7>
Severity: normal    
Priority: P3 CC: cas, mike
Version: не указана   
Hardware: all   
OS: Linux   
Attachments:
Description Flags
journalctl -b --full -u ModemManager none

Description Эрик 2014-11-20 03:17:02 MSK
После одного из последних обновлений ModemManager'а, в системе стоит самая новая версия пакета: ModemManager-1.4.0-alt0.M70P.1, модем стал определятся только после передергивания.

Что довольно интересно, но мобильное подключение определяется

ноя 19 18:38:02 notebook.localdomain NetworkManager[1678]: keyfile:     read
connection 'Beeline По умолчанию'

Но попытка подключится обрывается. И только передернув модем, я завожу сеть,
да, и еще если кликнуть правой мышкой по апплету NetworkManager'а, то как я уже
отписывался, нет даже упоминания на мобильное подключение.

Система определяет модем, вывод lsusb:Bus 002 Device 004: ID 12d1:1446 Huawei Technologies Co., Ltd. E1552/E1800/E173
(HSPA modem)
Comment 1 Эрик 2014-11-20 04:38:58 MSK
Статус ModemManager после перезагрузки:

$ systemctl status ModemManager.service
ModemManager.service - Modem Manager
   Loaded: loaded (/lib/systemd/system/ModemManager.service; enabled)
   Active: active (running) since Чт 2014-11-20 07:33:04 KGT; 1min 8s ago
 Main PID: 448 (ModemManager)
   CGroup: name=systemd:/system/ModemManager.service
           └─448 /usr/sbin/ModemManager

ноя 20 07:33:03 notebook.localdomain ModemManager[448]: <info>  ModemManager (version 1.4.0) starting i......
ноя 20 07:33:04 notebook.localdomain systemd[1]: Started Modem Manager.
ноя 20 07:33:06 notebook.localdomain ModemManager[448]: <warn>  Couldn't find support for device at '/s...gin
ноя 20 07:33:06 notebook.localdomain ModemManager[448]: <warn>  Couldn't find support for device at '/s...gin
Comment 2 Andrey Cherepanov 2014-11-20 11:05:13 MSK
На пакет.
Comment 3 Mikhail Efremov 2014-11-20 21:07:51 MSK
Т.е. с MM-1.2.0 все работало? Модем не виден сразу после загрузки или не работает повторное подключение?
Comment 4 Эрик 2014-11-21 02:58:00 MSK
(В ответ на комментарий №3)
> Т.е. с MM-1.2.0 все работало? 

Совершенно верно, работало без нареканий.
Comment 5 Эрик 2014-11-21 03:06:47 MSK
(В ответ на комментарий №3)
> Модем не виден сразу после загрузки или не
> работает повторное подключение?

Если перезагрузиться с модемом, то, хотя система его видит, он не виден ModemManager'у. 


(В ответ на комментарий №1)
> Статус ModemManager после перезагрузки:
> 
> $ systemctl status ModemManager.service
> ModemManager.service - Modem Manager
>    Loaded: loaded (/lib/systemd/system/ModemManager.service; enabled)
>    Active: active (running) since Чт 2014-11-20 07:33:04 KGT; 1min 8s ago
>  Main PID: 448 (ModemManager)
>    CGroup: name=systemd:/system/ModemManager.service
>            └─448 /usr/sbin/ModemManager
> 
> ноя 20 07:33:03 notebook.localdomain ModemManager[448]: <info>  ModemManager
> (version 1.4.0) starting i......
> ноя 20 07:33:04 notebook.localdomain systemd[1]: Started Modem Manager.
> ноя 20 07:33:06 notebook.localdomain ModemManager[448]: <warn>  Couldn't find
> support for device at '/s...gin
> ноя 20 07:33:06 notebook.localdomain ModemManager[448]: <warn>  Couldn't find
> support for device at '/s...gin

Такая картинка наблюдается при загрузке и с модемом, и без него.

(В ответ на комментарий №3)
>  или не
> работает повторное подключение?

После передергивания модема, до ближайшей перезагрузки все работает, можно хоть бесконечно переподключать интернет-соединение.
Comment 6 Эрик 2014-11-21 05:33:14 MSK
Сегодня словил такую любопытную вещь: если отправить машину в ребут не отключая интернет, то после запуска системы MM видит модем нормально

$ systemctl status ModemManager.service
ModemManager.service - Modem Manager
   Loaded: loaded (/lib/systemd/system/ModemManager.service; enabled)
   Active: active (running) since Пт 2014-11-21 08:22:10 KGT; 59s ago
 Main PID: 450 (ModemManager)
   CGroup: name=systemd:/system/ModemManager.service
           ├─ 450 /usr/sbin/ModemManager
           └─1701 /usr/lib/qmi-proxy

ноя 21 08:22:23 notebook.localdomain ModemManager[450]: [/dev/cdc-wdm0] Allocating new client ID...
ноя 21 08:22:23 notebook.localdomain ModemManager[450]: [/dev/cdc-wdm0] Registered 'nas' (version 1.0) ...'3'
ноя 21 08:22:23 notebook.localdomain ModemManager[450]: <warn>  (ttyUSB2): port attributes not fully set
ноя 21 08:22:24 notebook.localdomain ModemManager[450]: <warn>  couldn't load Supported Bands: 'QMI ope...2''
ноя 21 08:22:24 notebook.localdomain ModemManager[450]: <warn>  couldn't load SIM identifier: 'QMI oper...2''
ноя 21 08:22:24 notebook.localdomain ModemManager[450]: <warn>  couldn't load IMSI: 'QMI operation fail...2''
ноя 21 08:22:24 notebook.localdomain ModemManager[450]: <warn>  couldn't load list of Own Numbers: 'Cou...d''
ноя 21 08:22:24 notebook.localdomain ModemManager[450]: <warn>  couldn't load current allowed/preferred...ce'
ноя 21 08:22:24 notebook.localdomain ModemManager[450]: <warn>  couldn't load current Bands: 'QMI opera...0''
ноя 21 08:22:24 notebook.localdomain ModemManager[450]: <info>  Modem: state changed (unknown -> disabled)


Если же просто выключить машину, то проблема повторяется так, как уже было сказано.
Comment 7 Mikhail Efremov 2015-01-20 19:55:02 MSK
Попробуйте установить пакеты из задания #138910. Я не помню в changelog чего-то обнадеживающего, но все же там некоторое количество багфиксов, вдруг поможет.
Comment 8 Эрик 2015-01-21 01:01:04 MSK
(В ответ на комментарий №7)
> Попробуйте установить пакеты из задания #138910. Я не помню в changelog чего-то
> обнадеживающего, но все же там некоторое количество багфиксов, вдруг поможет.

Сделал
$ rpm -qa ModemManager
ModemManager-1.4.2-alt0.M70P.1

К сожалению, пока с теми же самыми результатами.
Comment 9 Mikhail Efremov 2015-03-23 21:10:17 MSK
Имеет смысл проверить пакеты из задания #142228. На этот раз в changelog есть нечто обнадеживающее. Я не вижу полного лога, но если там было 'failed to connect modem: Cannot recover from a power off', то это точно исправлено.
Comment 10 Эрик 2015-03-24 02:32:04 MSK
(В ответ на комментарий №9)
> Имеет смысл проверить пакеты из задания #142228. На этот раз в changelog есть
> нечто обнадеживающее. Я не вижу полного лога, но если там было 'failed to
> connect modem: Cannot recover from a power off', то это точно исправлено.

Да, обязательно. Сегодня проверю и отпишусь к вечеру.
Comment 11 Эрик 2015-03-28 10:51:57 MSK
К сожалению ничего не изменилось. При холодной загрузке юсб-свисток не стартует.
Comment 12 Mikhail Efremov 2015-04-03 16:53:16 MSK
Покажите все-таки весь лог от MM:
journalctl -b --full -u ModemManager
Comment 13 Эрик 2015-04-27 11:25:31 MSK
Created attachment 6278 [details]
journalctl -b --full -u ModemManager

Прошу прощения за поздний ответ, не было возможности.
Comment 14 Mikhail Efremov 2015-04-29 16:14:23 MSK
Хм, вообще лог закнчивается
Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> connected)
Simple connect state (8/8): All done
Т.е. выглядит как будто все хорошо. Ошибки в логе не должны быть фатальными.
Это точно лог случая, когда модем не работает? Если да, то покажите для сранения лог, когда он нормально работает, что ли.
Можно еще попробовать сказать
mmcli -m 0 --set-power-state-on
Comment 15 Эрик 2015-04-29 17:15:56 MSK
(В ответ на комментарий №14)
> Хм, вообще лог закнчивается
> Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting ->
> connected)
> Simple connect state (8/8): All done
> Т.е. выглядит как будто все хорошо. Ошибки в логе не должны быть фатальными.
> Это точно лог случая, когда модем не работает? Если да, то покажите для
> сранения лог, когда он нормально работает, что ли.
> Можно еще попробовать сказать
> mmcli -m 0 --set-power-state-on

Чтобы было понятней, выложу кусочки лога по порядку: 

1) Выключил ноут. Лог:
апр 29 19:48:45 notebook.localdomain systemd[1]: Stopped Modem Manager.
-- Reboot --
апр 29 19:49:20 notebook.localdomain ModemManager[482]: <info>  ModemManager (version 1.4.6) starting in system bus...                                                                
апр 29 19:49:20 notebook.localdomain systemd[1]: Started Modem Manager.                                                                                                               
апр 29 19:49:22 notebook.localdomain ModemManager[482]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.0/0000:01:00.0': not supported by any plugin  
апр 29 19:49:22 notebook.localdomain ModemManager[482]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.2/0000:02:00.0': not supported by any plugin 

Ноут запустился, но как писал в начале, теперь в NM отсутствует созданное мобильное соединение. 
 
$ lsusb
 
Bus 002 Device 004: ID 12d1:1446 Huawei Technologies Co., Ltd. E1552/E1800/E173 (HSPA modem)

Модем определяетcz, а подключение отсутствует.

$ mmcli -m 0 --set-power-state-on
error: couldn't find modem at '/org/freedesktop/ModemManager1/Modem/0'
Comment 16 Эрик 2015-04-29 17:16:40 MSK
(В ответ на комментарий №14)
> Хм, вообще лог закнчивается
> Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting ->
> connected)
> Simple connect state (8/8): All done
> Т.е. выглядит как будто все хорошо. Ошибки в логе не должны быть фатальными.
> Это точно лог случая, когда модем не работает? Если да, то покажите для
> сранения лог, когда он нормально работает, что ли.
> Можно еще попробовать сказать
> mmcli -m 0 --set-power-state-on

Передергиваю модем, подключение появилось.

2) Перезагружаю ноут. Вот кусочек лога после перезагрузки

апр 29 19:54:56 notebook.localdomain systemd[1]: Stopped Modem Manager.
-- Reboot --
апр 29 19:55:18 notebook.localdomain ModemManager[477]: <info>  ModemManager (version 1.4.6) starting in system bus...
апр 29 19:55:19 notebook.localdomain systemd[1]: Started Modem Manager.
апр 29 19:55:19 notebook.localdomain ModemManager[477]: <warn>  (ttyUSB0): port attributes not fully set
апр 29 19:55:19 notebook.localdomain ModemManager[477]: <warn>  (ttyUSB1): port attributes not fully set
апр 29 19:55:19 notebook.localdomain ModemManager[477]: <warn>  (ttyUSB2): port attributes not fully set
апр 29 19:55:19 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
апр 29 19:55:19 notebook.localdomain ModemManager[477]: cannot connect to proxy: Could not connect: Connection refused
апр 29 19:55:19 notebook.localdomain ModemManager[477]: spawning new qmi-proxy (try 1)...
апр 29 19:55:19 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] Checking version info (10 retries)...
апр 29 19:55:19 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] QMI Device supports 5 services:
апр 29 19:55:19 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    ctl (1.2)
апр 29 19:55:19 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    wds (1.3)
апр 29 19:55:19 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    dms (1.2)
апр 29 19:55:19 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    nas (1.0)
апр 29 19:55:19 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    cat (0.0)
апр 29 19:55:21 notebook.localdomain ModemManager[477]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.0/0000:01:00.0': not supported by any plugin
апр 29 19:55:21 notebook.localdomain ModemManager[477]: <warn>  Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.2/0000:02:00.0': not supported by any plugin
апр 29 19:55:22 notebook.localdomain ModemManager[477]: <warn>  (ttyUSB1): port attributes not fully set
апр 29 19:55:22 notebook.localdomain ModemManager[477]: <warn>  (ttyUSB2): port attributes not fully set
апр 29 19:55:32 notebook.localdomain ModemManager[477]: <info>  Creating modem with plugin 'Huawei' and '5' ports
апр 29 19:55:32 notebook.localdomain ModemManager[477]: <info>  Modem for device at '/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.3' successfully created
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] Opening device with flags 'version-info, net-802-3, net-no-qos-header, proxy'...
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] Checking version info (10 retries)...
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] QMI Device supports 5 services:
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    ctl (1.2)
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    wds (1.3)
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    dms (1.2)
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    nas (1.0)
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    cat (0.0)
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] Setting network port data format...
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] Checking version info (10 retries)...
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] QMI Device supports 5 services:
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    ctl (1.2)
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    wds (1.3)
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    dms (1.2)
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    nas (1.0)
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0]    cat (0.0)
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] Allocating new client ID...
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] Registered 'dms' (version 1.2) client with ID '2'
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] Allocating new client ID...
апр 29 19:55:32 notebook.localdomain ModemManager[477]: [/dev/cdc-wdm0] Registered 'nas' (version 1.0) client with ID '2'
апр 29 19:55:32 notebook.localdomain ModemManager[477]: <warn>  (ttyUSB2): port attributes not fully set
апр 29 19:55:33 notebook.localdomain ModemManager[477]: <warn>  couldn't load Supported Bands: 'QMI operation failed: Cannot send message: QMI service 'dms' version '1.3' required, g
апр 29 19:55:33 notebook.localdomain ModemManager[477]: <warn>  couldn't load Power State: 'Unhandled power state: 'unknown' (255)'
апр 29 19:55:33 notebook.localdomain ModemManager[477]: <warn>  couldn't load SIM identifier: 'Couldn't get UIM ICCID: QMI protocol error (71): 'InvalidQmiCommand''
апр 29 19:55:33 notebook.localdomain ModemManager[477]: <warn>  couldn't load IMSI: 'Couldn't get UIM IMSI: QMI protocol error (71): 'InvalidQmiCommand''
апр 29 19:55:33 notebook.localdomain ModemManager[477]: <warn>  couldn't load list of Own Numbers: 'Couldn't get MSISDN: QMI protocol error (16): 'NotProvisioned''
апр 29 19:55:33 notebook.localdomain ModemManager[477]: <warn>  couldn't load current allowed/preferred modes: 'Loading current modes is not supported by this device'
апр 29 19:55:33 notebook.localdomain ModemManager[477]: <warn>  couldn't load current Bands: 'QMI operation failed: Cannot send message: QMI service 'nas' version '1.1' required, got
апр 29 19:55:33 notebook.localdomain ModemManager[477]: <info>  Modem: state changed (unknown -> disabled)

Подключение есть. 

$ mmcli -m 0 --set-power-state-on
error: couldn't set new power state in the modem: 'GDBus.Error:org.freedesktop.DBus.Error.AccessDenied: Rejected send message, 2 matched rules; type="method_call", sender=":1.40" (uid=500 pid=2298 comm="mmcli -m 0 --set-power-state-on ") interface="org.freedesktop.ModemManager1.Modem" member="SetPowerState" error name="(unset)" requested_reply="0" destination=":1.3" (uid=0 pid=472 comm="/usr/sbin/ModemManager ")'
Comment 17 Mikhail Efremov 2016-03-24 21:26:51 MSK
Опять имеет смысл проверить пакеты из задания #161980
Comment 18 Mikhail Efremov 2023-10-25 22:34:42 MSK
P7 уже не поддерживается.