Re: Initial integration of the new MM iface in NM



Den 01. nov. 2012 18:35, skrev Marius Kotsbak:
Den 01. nov. 2012 16:48, skrev Aleksander Morgado:
On 01/11/12 09:23, Marius Kotsbak wrote:
ModemManager[27974]: [/dev/cdc-wdm0] Received message...
QMUX:
   length  = 19
   flags   = 0x80
   service = "nas"
   client  = 1
QMI:
   flags       = "response"
   transaction = 12
   tlv_length  = 7
   message     = "Initiate Network Register" (0x0022)
TLV:
   type       = "Result" (0x02)
   length     = 4
   value      = 01:00:25:00
   translated = FAILURE: UimUninitialized
Not sure what we're supposed to do in this case... Does this always
happen when requesting home network registration? Can you try to
reproduce the problem just with mmcli?

$> mmcli -m 0 -e
$> mmcli -m 0 --3gpp-register-home


It is also reproducable in MM, but I don't know if it is a regression in MM or that it's something strange with my local Danish SIM card/subscription.

Well, now I am registered home:

$ mmcli  -m 1

/org/freedesktop/ModemManager1/Modem/1 (device id '4f7a0f572286c2e6c2dc06038f4c5dd394843b63')
  -------------------------
  Hardware |   manufacturer: 'unknown'
           |          model: 'MC7710'
| revision: 'SWI9200X_03.00.11.00AP R4152 CARMD-EN-10527 2011/12/20 14:52:19'
           |   capabilities: 'gsm-umts, lte'
           |        current: 'gsm-umts, lte'
           |   equipment id: '***'
  -------------------------
System | device: '/sys/devices/pci0000:00/0000:00:1d.7/usb1/1-8'
           |        drivers: 'sierra, qmi_wwan'
           |         plugin: 'Sierra'
           |   primary port: 'ttyUSB2'
  -------------------------
  Numbers  |           own : 'unknown'
  -------------------------
  Status   |           lock: 'none'
           | unlock retries: 'unknown'
           |          state: 'registered'
           |    access tech: 'hsdpa'
           | signal quality: '80' (recent)
  -------------------------
  Modes    |      supported: 'cs, 2g, 3g, 4g'
           |        allowed: '2g, 3g, 4g'
           |      preferred: 'none'
  -------------------------
  Bands    |      supported: 'unknown'
           |        current: 'unknown'
  -------------------------
  3GPP     |           imei: '***'
           |  enabled locks: 'none'
           |    operator id: '23820'
           |  operator name: 'TELIA DK'
           |   registration: 'home'
  -------------------------
  SIM      |           path: '/org/freedesktop/ModemManager1/SIM/1'


but still i can't connect:

$ mmcli  -m 1 --simple-connect="apn=www.internet.mtelia.dk"
error: couldn't connect the modem: 'GDBus.Error:org.freedesktop.ModemManager1.Error.MobileEquipment.NoNetwork: No network service'


Debug log when this happens:

ModemManager[9267]: <info> [1351791444.092601] [mm-iface-modem-simple.c:778] connect_auth_ready(): Simple connect started... ModemManager[9267]: <info> [1351791444.092874] [mm-iface-modem-simple.c:551] connection_step(): Simple connect state (4/10): Wait to get fully enabled ModemManager[9267]: <info> [1351791444.093164] [mm-iface-modem-simple.c:563] connection_step(): Simple connect state (5/10): Allowed mode ModemManager[9267]: <info> [1351791444.093308] [mm-iface-modem-simple.c:586] connection_step(): Simple connect state (6/10): Bands ModemManager[9267]: <info> [1351791444.093404] [mm-iface-modem-simple.c:615] connection_step(): Simple connect state (7/10): Register ModemManager[9267]: <debug> [1351791444.093602] [mm-iface-modem-3gpp.c:374] mm_iface_modem_3gpp_register_in_network(): Already registered in network '23820', automatic registration not launched... ModemManager[9267]: <info> [1351791444.093882] [mm-iface-modem-simple.c:638] connection_step(): Simple connect state (8/10): Bearer ModemManager[9267]: <debug> [1351791444.094008] [mm-iface-modem-simple.c:711] connection_step(): Using already existing bearer at '/org/freedesktop/ModemManager1/Bearer/0'... ModemManager[9267]: <info> [1351791444.094110] [mm-iface-modem-simple.c:720] connection_step(): Simple connect state (9/10): Connect ModemManager[9267]: <debug> [1351791444.094246] [mm-bearer.c:517] mm_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0' ModemManager[9267]: <info> [1351791444.094462] [mm-iface-modem.c:1176] mm_iface_modem_update_state(): Modem /org/freedesktop/ModemManager1/Modem/1: state changed (registered -> connecting) ModemManager[9267]: <debug> [1351791444.097686] [mm-broadband-bearer.c:999] connect_3gpp(): Looking for best CID... ModemManager[9267]: <debug> [1351791444.098047] [mm-serial-port.c:929] mm_serial_port_open(): (ttyUSB2) device open count is 2 (open) ModemManager[9267]: <debug> [1351791444.098445] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): --> 'AT+CGDCONT?<CR>' ModemManager[9267]: <debug> [1351791444.118302] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): <-- '<CR><LF>+CGDCONT: 1,"IP","internet.netcom.no","0.0.0.0",0,0<CR><LF>+CGDCONT: 2,"IP","internet","0.0.0.0",0,0<CR><LF>+CGDCONT: 3,"IP","telenor","0.0.0.0",0,0<CR><LF>+CGDCONT: 4,"IP","www.internet.mtelia.dk","0.0.0.0",0,0<CR><LF><CR><LF>OK<CR><LF>' ModemManager[9267]: <debug> [1351791444.118769] [mm-broadband-bearer.c:921] parse_pdp_list(): Found '4' PDP contexts ModemManager[9267]: <debug> [1351791444.118991] [mm-broadband-bearer.c:928] parse_pdp_list(): PDP context [cid=1] [type='ipv4'] [apn='internet.netcom.no'] ModemManager[9267]: <debug> [1351791444.119210] [mm-broadband-bearer.c:928] parse_pdp_list(): PDP context [cid=2] [type='ipv4'] [apn='internet'] ModemManager[9267]: <debug> [1351791444.119358] [mm-broadband-bearer.c:928] parse_pdp_list(): PDP context [cid=3] [type='ipv4'] [apn='telenor'] ModemManager[9267]: <debug> [1351791444.119570] [mm-broadband-bearer.c:928] parse_pdp_list(): PDP context [cid=4] [type='ipv4'] [apn='www.internet.mtelia.dk'] ModemManager[9267]: <debug> [1351791444.119732] [mm-broadband-bearer.c:949] parse_pdp_list(): Found PDP context with CID 4 and PDP type ipv4 for APN 'www.internet.mtelia.dk' ModemManager[9267]: <debug> [1351791444.119939] [mm-serial-port.c:929] mm_serial_port_open(): (ttyUSB2) device open count is 3 (open) ModemManager[9267]: <debug> [1351791444.120230] [mm-serial-port.c:969] mm_serial_port_close(): (ttyUSB2) device open count is 2 (close) ModemManager[9267]: <debug> [1351791444.120449] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): --> 'AT+CGDCONT=4,"IP","www.internet.mtelia.dk"<CR>' ModemManager[9267]: <debug> [1351791444.178367] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>' ModemManager[9267]: <debug> [1351791444.178746] [mm-serial-port.c:929] mm_serial_port_open(): (ttyUSB2) device open count is 3 (open) ModemManager[9267]: <debug> [1351791444.179010] [mm-serial-port.c:969] mm_serial_port_close(): (ttyUSB2) device open count is 2 (close) ModemManager[9267]: <debug> [1351791444.179421] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): --> 'AT+CGATT=1<CR>' ModemManager[9267]: <debug> [1351791444.200891] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>' ModemManager[9267]: <debug> [1351791444.201213] [sierra/mm-broadband-bearer-sierra.c:194] dial_3gpp_context_step(): Not using authentication ModemManager[9267]: <debug> [1351791444.201422] [mm-serial-port.c:929] mm_serial_port_open(): (ttyUSB2) device open count is 3 (open) ModemManager[9267]: <debug> [1351791444.201724] [mm-serial-port.c:969] mm_serial_port_close(): (ttyUSB2) device open count is 2 (close) ModemManager[9267]: <debug> [1351791444.202007] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): --> 'AT$QCPDPP=4,0<CR>' ModemManager[9267]: <debug> [1351791444.225340] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>' ModemManager[9267]: <debug> [1351791444.225680] [mm-serial-port.c:929] mm_serial_port_open(): (ttyUSB2) device open count is 3 (open) ModemManager[9267]: <debug> [1351791444.225943] [mm-serial-port.c:969] mm_serial_port_close(): (ttyUSB2) device open count is 2 (close) ModemManager[9267]: <debug> [1351791444.226280] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): --> 'AT!SCACT=1,4<CR>' ModemManager[9267]: <debug> [1351791444.247609] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): <-- '<CR><LF>+CME ERROR: 30<CR><LF>' ModemManager[9267]: <debug> [1351791444.247926] [mm-serial-parsers.c:316] mm_serial_parser_v1_parse(): Got failure code 30: No network service ModemManager[9267]: <debug> [1351791444.248205] [mm-serial-port.c:969] mm_serial_port_close(): (ttyUSB2) device open count is 1 (close) ModemManager[9267]: <debug> [1351791444.248832] [mm-bearer.c:386] connect_ready(): Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/0': 'No network service' ModemManager[9267]: <info> [1351791444.249111] [mm-iface-modem.c:1176] mm_iface_modem_update_state(): Modem /org/freedesktop/ModemManager1/Modem/1: state changed (connecting -> registered) ModemManager[9267]: <debug> [1351791444.251279] [mm-iface-modem-simple.c:228] connect_bearer_ready(): Couldn't connect bearer: 'No network service' ModemManager[9267]: <debug> [1351791447.812910] [mm-broadband-modem.c:1580] modem_load_signal_quality(): loading signal quality... ModemManager[9267]: <debug> [1351791447.813120] [mm-serial-port.c:929] mm_serial_port_open(): (ttyUSB2) device open count is 2 (open) ModemManager[9267]: <debug> [1351791447.813340] [mm-serial-port.c:929] mm_serial_port_open(): (ttyUSB2) device open count is 3 (open) ModemManager[9267]: <debug> [1351791447.813544] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): --> 'AT+CIND?<CR>' ModemManager[9267]: <debug> [1351791447.828050] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): <-- '<CR><LF>+CIND: 5,4,1,0,0,0,1,0<CR><LF><CR><LF>OK<CR><LF>' ModemManager[9267]: <debug> [1351791447.828554] [mm-serial-port.c:969] mm_serial_port_close(): (ttyUSB2) device open count is 2 (close) ModemManager[9267]: <info> [1351791447.829145] [mm-iface-modem.c:971] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (80) ModemManager[9267]: <debug> [1351791447.829428] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): --> 'AT*CNTI=0<CR>' ModemManager[9267]: <debug> [1351791447.843050] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): <-- '<CR><LF>*CNTI: 0,HSDPA<CR><LF><CR><LF>OK<CR><LF>' ModemManager[9267]: <debug> [1351791447.843598] [mm-serial-port.c:969] mm_serial_port_close(): (ttyUSB2) device open count is 1 (close) ModemManager[9267]: <debug> [1351791477.839087] [mm-broadband-modem.c:1580] modem_load_signal_quality(): loading signal quality... ModemManager[9267]: <debug> [1351791477.839261] [mm-serial-port.c:929] mm_serial_port_open(): (ttyUSB2) device open count is 2 (open) ModemManager[9267]: <debug> [1351791477.839544] [mm-serial-port.c:929] mm_serial_port_open(): (ttyUSB2) device open count is 3 (open) ModemManager[9267]: <debug> [1351791477.839737] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): --> 'AT+CIND?<CR>' ModemManager[9267]: <debug> [1351791477.851378] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): <-- '<CR><LF>+CIND: 5,4,1,0,0,0,1,0<CR><LF><CR><LF>OK<CR><LF>' ModemManager[9267]: <debug> [1351791477.851801] [mm-serial-port.c:969] mm_serial_port_close(): (ttyUSB2) device open count is 2 (close) ModemManager[9267]: <info> [1351791477.852205] [mm-iface-modem.c:971] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (80) ModemManager[9267]: <debug> [1351791477.852432] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): --> 'AT*CNTI=0<CR>' ModemManager[9267]: <debug> [1351791477.873739] [mm-at-serial-port.c:392] debug_log(): (ttyUSB2): <-- '<CR><LF>*CNTI: 0,HSDPA<CR><LF><CR><LF>OK<CR><LF>' ModemManager[9267]: <debug> [1351791477.874181] [mm-serial-port.c:969] mm_serial_port_close(): (ttyUSB2) device open count is 1 (close)

--
Marius



[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]