Huawei Е398 проблема dhclient: DHCPDISCOVER

[Ant-Bob]

Доброй ночи)
Дано: Debian Jessie, Gnome 3.14, модем мегафоновский м150-2 Huawei Е398.
Модем опробован на многих дистрах (решил слезть с Минта вот и тестирую) и везде нормально заводился(бывало что подшаманить нужно, но немного), а с Дебианом затык. Второй вечер сижу и не могу понять что не так.
lsusb

Открыть содержимое (спойлер)

Код Выделить Развернуть

Bus 002 Device 004: ID 062a:4102 Creative Labs
Bus 002 Device 003: ID 8086:0189 Intel Corp.
Bus 002 Device 008: ID 12d1:1506 Huawei Technologies Co., Ltd. E398 LTE/UMTS/GSM Modem/Networkcard
Bus 002 Device 002: ID 8087:0024 Intel Corp. Integrated Rate Matching Hub
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 001 Device 003: ID 2232:1020 
Bus 001 Device 002: ID 8087:0024 Intel Corp. Integrated Rate Matching Hub
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub


[свернуть]

Log

Открыть содержимое (спойлер)

Код Выделить Развернуть

Mar 19 00:38:07 Bobunov-pc udisksd[1219]: Mounted /dev/sr1 at /media/ant-bob/MegaFon on behalf of uid 1000
Mar 19 00:38:09 Bobunov-pc ModemManager[540]: <warn>  (ttyUSB2): port attributes not fully set
Mar 19 00:38:09 Bobunov-pc ModemManager[540]: <warn>  (Plugin Manager) (cdc-wdm1): plugin mismatch error (expected: 'Huawei', got: 'Generic')
Mar 19 00:38:10 Bobunov-pc ModemManager[540]: <info>  Creating modem with plugin 'Huawei' and '4' ports
Mar 19 00:38:10 Bobunov-pc ModemManager[540]: <info>  Modem for device at '/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2' successfully created
Mar 19 00:38:13 Bobunov-pc ModemManager[540]: <warn>  couldn't load current allowed/preferred modes: 'No SYSCFGEX combination found matching the current one (0201)'
Mar 19 00:38:14 Bobunov-pc ModemManager[540]: Invalid mobile equipment error code: 50
Mar 19 00:38:14 Bobunov-pc ModemManager[540]: <info>  Modem: state changed (unknown -> disabled)
Mar 19 00:38:14 Bobunov-pc NetworkManager[537]: <warn> (cdc-wdm1): failed to look up interface index
Mar 19 00:38:14 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): modem state changed, 'disabled' --> 'enabling' (reason: user preference)
Mar 19 00:38:14 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): new Broadband device (driver: 'huawei_cdc_ncm, option1' ifindex: 0)
Mar 19 00:38:14 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): exported as /org/freedesktop/NetworkManager/Devices/3
Mar 19 00:38:14 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 19 00:38:14 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): preparing device
Mar 19 00:38:14 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): modem state 'enabling'
Mar 19 00:38:14 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Mar 19 00:38:14 Bobunov-pc ModemManager[540]: <info>  Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disabled -> enabling)
Mar 19 00:38:14 Bobunov-pc ModemManager[540]: <warn>  (ttyUSB1): port attributes not fully set
Mar 19 00:38:14 Bobunov-pc ModemManager[540]: Invalid mobile equipment error code: 50
Mar 19 00:38:14 Bobunov-pc gnome-session[1115]: ** (gnome-shell:1252): CRITICAL **: nma_mobile_providers_database_lookup_cdma_sid: assertion 'sid > 0' failed
Mar 19 00:38:14 Bobunov-pc ModemManager[540]: <info>  Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (unknown -> registering)
Mar 19 00:38:14 Bobunov-pc ModemManager[540]: <info>  Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (registering -> home)
Mar 19 00:38:14 Bobunov-pc ModemManager[540]: <info>  Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP location updated (MCC: '0', MNC: '0', Location area code: '1952', Cell ID: '1D0CC0C')
Mar 19 00:38:14 Bobunov-pc ModemManager[540]: <info>  Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP location updated (MCC: '250', MNC: '2', Location area code: '1952', Cell ID: '1D0CC0C')
Mar 19 00:38:14 Bobunov-pc ModemManager[540]: <info>  Modem /org/freedesktop/ModemManager1/Modem/1: state changed (enabling -> registered)
Mar 19 00:38:14 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): modem state changed, 'enabling' --> 'registered' (reason: user-requested)
Mar 19 00:44:15 Bobunov-pc NetworkManager[537]: <info> (eth0): device state change: activated -> deactivating (reason 'user-requested') [100 110 39]
Mar 19 00:44:15 Bobunov-pc NetworkManager[537]: <info> NetworkManager state is now DISCONNECTING
Mar 19 00:44:15 Bobunov-pc NetworkManager[537]: <info> (eth0): device state change: deactivating -> disconnected (reason 'user-requested') [110 30 39]
Mar 19 00:44:15 Bobunov-pc NetworkManager[537]: <info> (eth0): deactivating device (reason 'user-requested') [39]
Mar 19 00:44:16 Bobunov-pc NetworkManager[537]: <info> (eth0): canceled DHCP transaction, DHCP client pid 2277
Mar 19 00:44:16 Bobunov-pc avahi-daemon[547]: Withdrawing address record for fe80::ea11:32ff:fec6:888f on eth0.
Mar 19 00:44:16 Bobunov-pc avahi-daemon[547]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::ea11:32ff:fec6:888f.
Mar 19 00:44:16 Bobunov-pc avahi-daemon[547]: Interface eth0.IPv6 no longer relevant for mDNS.
Mar 19 00:44:16 Bobunov-pc avahi-daemon[547]: Withdrawing address record for 192.168.1.5 on eth0.
Mar 19 00:44:16 Bobunov-pc avahi-daemon[547]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.5.
Mar 19 00:44:16 Bobunov-pc avahi-daemon[547]: Interface eth0.IPv4 no longer relevant for mDNS.
Mar 19 00:44:16 Bobunov-pc NetworkManager[537]: <info> Removing DNS information from /sbin/resolvconf
Mar 19 00:44:16 Bobunov-pc NetworkManager[537]: <info> NetworkManager state is now DISCONNECTED
Mar 19 00:44:16 Bobunov-pc dbus[549]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Mar 19 00:44:16 Bobunov-pc dbus[549]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 19 00:44:16 Bobunov-pc nm-dispatcher: Dispatching action 'down' for eth0
Mar 19 00:44:20 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) starting connection 'Megafon'
Mar 19 00:44:20 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 1 of 5 (Device Prepare) scheduled...
Mar 19 00:44:20 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 1 of 5 (Device Prepare) started...
Mar 19 00:44:20 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 19 00:44:20 Bobunov-pc NetworkManager[537]: <info> NetworkManager state is now CONNECTING
Mar 19 00:44:20 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 1 of 5 (Device Prepare) complete.
Mar 19 00:44:20 Bobunov-pc ModemManager[540]: <info>  Simple connect started...
Mar 19 00:44:20 Bobunov-pc ModemManager[540]: <info>  Simple connect state (4/8): Wait to get fully enabled
Mar 19 00:44:20 Bobunov-pc ModemManager[540]: <info>  Simple connect state (5/8): Register
Mar 19 00:44:20 Bobunov-pc ModemManager[540]: <info>  Simple connect state (6/8): Bearer
Mar 19 00:44:20 Bobunov-pc ModemManager[540]: <info>  Simple connect state (7/8): Connect
Mar 19 00:44:20 Bobunov-pc ModemManager[540]: <info>  Modem /org/freedesktop/ModemManager1/Modem/1: state changed (registered -> connecting)
Mar 19 00:44:20 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Mar 19 00:44:22 Bobunov-pc ModemManager[540]: <info>  Modem /org/freedesktop/ModemManager1/Modem/1: state changed (connecting -> connected)
Mar 19 00:44:22 Bobunov-pc ModemManager[540]: <info>  Simple connect state (8/8): All done
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): modem state changed, 'connecting' --> 'connected' (reason: user-requested)
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 2 of 5 (Device Configure) scheduled...
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 2 of 5 (Device Configure) starting...
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): device state change: prepare -> config (reason 'none') [40 50 0]
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 2 of 5 (Device Configure) successful.
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 3 of 5 (IP Configure Start) scheduled.
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 2 of 5 (Device Configure) complete.
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 3 of 5 (IP Configure Start) started...
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> (cdc-wdm1): device state change: config -> ip-config (reason 'none') [50 70 0]
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> Activation (wwan0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> dhclient started with pid 2666
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 3 of 5 (IP Configure Start) complete.
Mar 19 00:44:22 Bobunov-pc rsyslogd-2007: action 'action 17' suspended, next retry is Thu Mar 19 00:44:52 2015 [try http://www.rsyslog.com/e/2007 ]
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 4 of 5 (IPv6 Configure Timeout) started...
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> Activation (cdc-wdm1) Stage 4 of 5 (IPv6 Configure Timeout) complete.
Mar 19 00:44:22 Bobunov-pc NetworkManager[537]: <info> (wwan0): DHCPv4 state changed nbi -> preinit
Mar 19 00:44:22 Bobunov-pc dhclient: DHCPDISCOVER on wwan0 to 255.255.255.255 port 67 interval 5
Mar 19 00:44:24 Bobunov-pc avahi-daemon[547]: Joining mDNS multicast group on interface wwan0.IPv6 with address fe80::21e:10ff:fe1f:0.
Mar 19 00:44:24 Bobunov-pc avahi-daemon[547]: New relevant interface wwan0.IPv6 for mDNS.
Mar 19 00:44:24 Bobunov-pc avahi-daemon[547]: Registering new address record for fe80::21e:10ff:fe1f:0 on wwan0.*.
Mar 19 00:44:27 Bobunov-pc dhclient: DHCPDISCOVER on wwan0 to 255.255.255.255 port 67 interval 13
Mar 19 00:44:40 Bobunov-pc dhclient: DHCPDISCOVER on wwan0 to 255.255.255.255 port 67 interval 17
Mar 19 00:44:57 Bobunov-pc dhclient: DHCPDISCOVER on wwan0 to 255.255.255.255 port 67 interval 15

[свернуть]

Network Manager подхватывает нормально, и подключение создано, а вот коннекта нет.
Resolv установлен, usb-modeswitch тоже все установлены. Пробывал в resolv.conf прописать nameserver, но он перезаписывается при следующем коннекте.
Проводной инет подхватывает нормально (с него пишу), а вот мегафоновский отказывается.

Буду рад если кто поделиться идей куда копать.
Нужны будут какие дополнительные логи - отправлю. Название темы может быть не совсем точным, т.к. сам проблему пока не локализовал.

Заранее благодарен за ваше потраченное время.

[Leo]

Модем ваш должен нормально работать. Product ID есть среди поддерживаемых usb-modeswitch'ем.
Если используете NM - не нужно трогать Resolv и прочие сетевые конфиги руками.
Вот эту информацию читали?
Я думаю дело в неправильных настройках NM. На худой конец можете wvdial попробовать, рабочий конфиг можете нагуглить или спросить у ребят, кто использует. Вот тут, например, человек опытом делится.

[Ant-Bob]

На чистом Jessie коннекта не было. С вики статью видел не прокатило. Сейчас копаю логи и пытаюсь понять что они мне говорят.
Заметил вот такую тенденцию
dmesg | grep tty

Открыть содержимое (спойлер)

Код Выделить Развернуть

[16519.024620] option1 ttyUSB8: GSM modem (1-port) converter now disconnected from ttyUSB8
[16519.026125] option1 ttyUSB9: GSM modem (1-port) converter now disconnected from ttyUSB9
[16528.075787] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB9
[16528.076295] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB10
[21312.204072] option1 ttyUSB9: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[21312.204081] option1 ttyUSB9: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[21312.204087] option1 ttyUSB9: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[21312.204093] option1 ttyUSB9: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[21312.204327] option1 ttyUSB9: GSM modem (1-port) converter now disconnected from ttyUSB9
[21312.204634] option1 ttyUSB10: GSM modem (1-port) converter now disconnected from ttyUSB10
[24098.099484] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB10
[24098.099851] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB11
[24558.538912] option1 ttyUSB10: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[24558.538920] option1 ttyUSB10: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[24558.538926] option1 ttyUSB10: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[24558.538932] option1 ttyUSB10: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[24558.539150] option1 ttyUSB10: GSM modem (1-port) converter now disconnected from ttyUSB10
[24558.539421] option1 ttyUSB11: GSM modem (1-port) converter now disconnected from ttyUSB11
[24644.237678] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB11
[24644.238119] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB12
[25251.905078] option1 ttyUSB11: GSM modem (1-port) converter now disconnected from ttyUSB11
[25251.905325] option1 ttyUSB12: GSM modem (1-port) converter now disconnected from ttyUSB12
[25261.149047] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB12
[25261.151174] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB13
[26240.014092] option1 ttyUSB12: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[26240.014099] option1 ttyUSB12: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[26240.014105] option1 ttyUSB12: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[26240.014111] option1 ttyUSB12: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[26240.014353] option1 ttyUSB12: GSM modem (1-port) converter now disconnected from ttyUSB12
[26240.014600] option1 ttyUSB13: GSM modem (1-port) converter now disconnected from ttyUSB13
[26253.184633] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB13
[26253.184819] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB14
[26646.964595] option1 ttyUSB13: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[26646.964602] option1 ttyUSB13: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[26646.964607] option1 ttyUSB13: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[26646.964611] option1 ttyUSB13: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[26646.964860] option1 ttyUSB13: GSM modem (1-port) converter now disconnected from ttyUSB13
[26646.965136] option1 ttyUSB14: GSM modem (1-port) converter now disconnected from ttyUSB14
[26745.984691] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB14
[26745.986665] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB15
[27013.425061] option1 ttyUSB14: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[27013.425069] option1 ttyUSB14: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[27013.425074] option1 ttyUSB14: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[27013.425080] option1 ttyUSB14: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[27013.425296] option1 ttyUSB14: GSM modem (1-port) converter now disconnected from ttyUSB14
[27013.425567] option1 ttyUSB15: GSM modem (1-port) converter now disconnected from ttyUSB15
[27027.366209] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB15
[27027.366591] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB16
[27322.225666] option1 ttyUSB15: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[27322.225675] option1 ttyUSB15: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[27322.225681] option1 ttyUSB15: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[27322.225687] option1 ttyUSB15: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[27322.225930] option1 ttyUSB15: GSM modem (1-port) converter now disconnected from ttyUSB15
[27322.226215] option1 ttyUSB16: GSM modem (1-port) converter now disconnected from ttyUSB16
[27821.820733] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB16
[27821.821242] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB17
[28391.880199] option1 ttyUSB16: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[28391.880207] option1 ttyUSB16: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[28391.880212] option1 ttyUSB16: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[28391.880216] option1 ttyUSB16: usb_wwan_indat_callback: resubmit read urb failed. (-19)
[28391.880466] option1 ttyUSB16: GSM modem (1-port) converter now disconnected from ttyUSB16
[28391.880872] option1 ttyUSB17: GSM modem (1-port) converter now disconnected from ttyUSB17
[28403.480341] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB17
[28403.480692] usb 2-1.2: GSM modem (1-port) converter now attached to ttyUSB18


[свернуть]

[Leo]

Вот баг-репорт по вашей проблеме.
Написано что NM версии 0.9.10.0-1 не работает с E398, а wvdial - работает при правильном указании tty. Его чел выковыривает командой

Код Выделить Развернуть

ls -al /dev/ | grep ttyUSB

[ihammers]

У меня на Jessie все нормально работает.

Код Выделить Развернуть

~$ lsusb
Bus 001 Device 013: ID 12d1:1506 Huawei Technologies Co., Ltd. E398 LTE/UMTS/GSM Modem/Networkcard

Код Выделить Развернуть

~$ nmcli con
ИМЯ              UUID                                  ТИП              УСТРОЙСТВО
Megafon          e12761de-1ff1-4b07-b9b9-6e2ad277e150  gsm              ttyUSB2


Как происходила настройка?
Я делал так:

• ~$ sudo apt-get install usb-modeswitch usb-modeswitch-data
• В редакторе соединения (Network-Manager) добавить новое "Мобильное..."
• Выбрать любое GSM-устройство, либо huawei E352b
• Страна: Россия
• Выбрать оператора: Мегафон
• Выбор тарифного плана: По умолчанию
• Нажать готово
• Параметры соединения больше не менять, хотя изменить название соединения можно
• В аплете NW выбрать подключение Мегафон