Re: [MM 0.7] Bearer properties simplification

[Tore Anderson <tore fud no>]

* Aleksander Morgado

> I tried to simplify a bit the handling of the Bearer properties in 
> MM;
> the rework is available in the 'bearer-properties' branch in the
> following git repo: git://gitorious.org/aleksander/modemmanager.git

Hi Aleksander,

I just tried the branch in order to hopefully be able to add support 
for IPV6 and IPV4V6 PDP contexts. However, MM crashes immediately after 
I run "mmcli -m 0 --simple-connect=apn=v6". Last message before it dies 
is:

(ModemManager:19652): GLib-GObject-WARNING **: invalid cast from 
`MMBroadbandModemNokia' to `MMBearer'

A full debug log is attached.

Best regards,
Tore Anderson

<info>  [1334255830.310041] [main.c:127] main(): ModemManager (version 0.7.0) starting...
<debug> [1334255830.326134] [main.c:75] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
<debug> [1334255830.334295] [mm-plugin-manager.c:627] load_plugins(): Looking for plugins in '/opt/mm/lib/ModemManager'
<info>  [1334255830.342019] [mm-plugin-manager.c:604] found_plugin(): Loaded plugin 'AnyDATA'
<info>  [1334255830.345213] [mm-plugin-manager.c:604] found_plugin(): Loaded plugin 'Motorola'
<info>  [1334255830.348365] [mm-plugin-manager.c:604] found_plugin(): Loaded plugin 'Nokia'
<info>  [1334255830.351407] [mm-plugin-manager.c:604] found_plugin(): Loaded plugin 'Linktop'
<info>  [1334255830.358711] [mm-plugin-manager.c:604] found_plugin(): Loaded plugin 'Option'
<info>  [1334255830.368133] [mm-plugin-manager.c:604] found_plugin(): Loaded plugin 'Gobi'
<info>  [1334255830.376212] [mm-plugin-manager.c:604] found_plugin(): Loaded plugin 'Option High-Speed'
<info>  [1334255830.383750] [mm-plugin-manager.c:604] found_plugin(): Loaded plugin 'Novatel'
<info>  [1334255830.388539] [mm-plugin-manager.c:604] found_plugin(): Loaded plugin 'Iridium'
<info>  [1334255830.391922] [mm-plugin-manager.c:604] found_plugin(): Loaded plugin 'Cinterion'
<info>  [1334255830.394928] [mm-plugin-manager.c:604] found_plugin(): Loaded plugin 'Generic'
<info>  [1334255830.397910] [mm-plugin-manager.c:683] load_plugins(): Successfully loaded 11 plugins
<debug> [1334255830.405162] [mm-manager.c:655] mm_manager_start(): Starting device scan...
<debug> [1334255830.420926] [mm-manager.c:525] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted
<debug> [1334255830.429058] [mm-manager.c:525] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted
<debug> [1334255830.436480] [mm-manager.c:525] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
<debug> [1334255830.441347] [mm-manager.c:525] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
<debug> [1334255830.448654] [mm-manager.c:671] mm_manager_start(): Finished device scan...
<debug> [1334255830.456447] [mm-plugin-base.c:649] supports_port(): (Nokia) checking port support (tty,ttyACM0)
<debug> [1334255830.459549] [mm-plugin-base.c:719] supports_port(): (Nokia)   launching probe for (tty,ttyACM0)
<info>  [1334255830.466556] [mm-port-probe.c:866] mm_port_probe_run(): (ttyACM0) launching port probing: 'at'
<debug> [1334255830.476102] [mm-plugin-base.c:649] supports_port(): (Nokia) checking port support (tty,ttyACM1)
<debug> [1334255830.484158] [mm-plugin-base.c:719] supports_port(): (Nokia)   launching probe for (tty,ttyACM1)
<info>  [1334255830.491709] [mm-port-probe.c:866] mm_port_probe_run(): (ttyACM1) launching port probing: 'at'
<debug> [1334255830.497052] [mm-plugin-base.c:649] supports_port(): (Nokia) checking port support (tty,ttyACM2)
<debug> [1334255830.500477] [mm-plugin-base.c:719] supports_port(): (Nokia)   launching probe for (tty,ttyACM2)
<info>  [1334255830.503572] [mm-port-probe.c:866] mm_port_probe_run(): (ttyACM2) launching port probing: 'at'
<info>  [1334255830.507531] [mm-serial-port.c:860] mm_serial_port_open(): (ttyACM0) opening serial port...
<debug> [1334255830.515473] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
<info>  [1334255830.525085] [mm-serial-port.c:860] mm_serial_port_open(): (ttyACM1) opening serial port...
<debug> [1334255830.533588] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM1) device open count is 1 (open)
<info>  [1334255830.541446] [mm-serial-port.c:860] mm_serial_port_open(): (ttyACM2) opening serial port...
<debug> [1334255830.546584] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM2) device open count is 1 (open)
<debug> [1334255830.555103] [mm-manager.c:327] find_port_support_ready_cb(): (net/eth0): not supported by any plugin
<debug> [1334255830.558242] [mm-manager.c:327] find_port_support_ready_cb(): (net/usb0): not supported by any plugin
<debug> [1334255830.561319] [mm-manager.c:327] find_port_support_ready_cb(): (net/wlan0): not supported by any plugin
<debug> [1334255830.625762] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'ATE1 E0<CR>'
<debug> [1334255830.642571] [mm-at-serial-port.c:359] debug_log(): (ttyACM1): --> 'ATE1 E0<CR>'
<debug> [1334255831.336840] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1334255831.344325] [mm-port-probe.c:100] mm_port_probe_set_result_at(): (ttyACM0) port is AT-capable
<debug> [1334255831.347855] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
<info>  [1334255831.351300] [mm-serial-port.c:984] mm_serial_port_close(): (ttyACM0) closing serial port...
<info>  [1334255831.356697] [mm-serial-port.c:1005] mm_serial_port_close(): (ttyACM0) serial port closed
<info>  [1334255831.359716] [mm-serial-port.c:1074] mm_serial_port_close_force(): (ttyACM0) forced to close port
<debug> [1334255831.369248] [mm-port-probe.c:767] mm_port_probe_run_cancel_at_probing(): (ttyACM1) requested to cancel all AT probing
<debug> [1334255831.378824] [mm-port-probe.c:767] mm_port_probe_run_cancel_at_probing(): (ttyACM2) requested to cancel all AT probing
<debug> [1334255831.386598] [mm-port-probe.c:446] serial_probe_at_parse_response(): (ttyACM1) no need to keep on probing the port for AT support
<debug> [1334255831.394319] [mm-plugin-manager.c:275] supports_port_ready_cb(): (Nokia): (ttyACM0) found best plugin for port
<debug> [1334255831.398010] [mm-at-serial-port.c:359] debug_log(): (ttyACM1): <-- 'ATE1 E0<CR><CR><LF>OK<CR><LF>'
<debug> [1334255831.401634] [mm-port-probe.c:512] serial_probe_at(): (ttyACM2) no need to launch probing for AT support
<debug> [1334255831.404611] [mm-port-probe.c:512] serial_probe_at(): (ttyACM1) no need to launch probing for AT support
<debug> [1334255831.407514] [mm-port-probe.c:106] mm_port_probe_set_result_at(): (ttyACM1) port is not AT-capable
<debug> [1334255831.416959] [mm-plugin-manager.c:178] suggest_supports_info_result(): (Nokia): (ttyACM1) suggested plugin for port
<debug> [1334255831.426271] [mm-plugin-manager.c:178] suggest_supports_info_result(): (Nokia): (ttyACM2) suggested plugin for port
<debug> [1334255831.433783] [mm-manager.c:342] find_port_support_ready_cb(): (tty/ttyACM0): found plugin 'Nokia' giving best support
<debug> [1334255831.444523] [mm-base-modem.c:238] mm_base_modem_grab_port(): (ttyACM0) type 'at' claimed by /sys/devices/pci0000:00/0000:00:1a.7/usb1/1-3
<info>  [1334255831.448263] [mm-manager.c:389] grab_port(): (Nokia): modem /sys/devices/pci0000:00/0000:00:1a.7/usb1/1-3 claimed port ttyACM0
<debug> [1334255831.451820] [mm-manager.c:256] add_modem(): Added modem /sys/devices/pci0000:00/0000:00:1a.7/usb1/1-3
<debug> [1334255831.454750] [mm-manager.c:187] check_export_modem(): (tty/ttyACM1): outstanding support task prevents export of '/sys/devices/pci0000:00/0000:00:1a.7/usb1/1-3'
<debug> [1334255831.457893] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM1) device open count is 0 (close)
<info>  [1334255831.467302] [mm-serial-port.c:984] mm_serial_port_close(): (ttyACM1) closing serial port...
<info>  [1334255831.479067] [mm-serial-port.c:1005] mm_serial_port_close(): (ttyACM1) serial port closed
<info>  [1334255831.486809] [mm-serial-port.c:1074] mm_serial_port_close_force(): (ttyACM1) forced to close port
<debug> [1334255831.494511] [mm-plugin-manager.c:275] supports_port_ready_cb(): (Nokia): (ttyACM1) found best plugin for port
<debug> [1334255831.498149] [mm-port-probe.c:512] serial_probe_at(): (ttyACM2) no need to launch probing for AT support
<debug> [1334255831.501215] [mm-port-probe.c:106] mm_port_probe_set_result_at(): (ttyACM2) port is not AT-capable
<debug> [1334255831.504136] [mm-manager.c:342] find_port_support_ready_cb(): (tty/ttyACM1): found plugin 'Nokia' giving best support
<warn>  [1334255831.508676] [mm-manager.c:378] grab_port(): plugin 'Nokia' claimed to support tty/ttyACM1 but couldn't: (3) Ignoring non-AT port
<debug> [1334255831.518052] [mm-manager.c:187] check_export_modem(): (tty/ttyACM2): outstanding support task prevents export of '/sys/devices/pci0000:00/0000:00:1a.7/usb1/1-3'
<debug> [1334255831.527093] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM2) device open count is 0 (close)
<info>  [1334255831.534486] [mm-serial-port.c:984] mm_serial_port_close(): (ttyACM2) closing serial port...
<info>  [1334255831.542801] [mm-serial-port.c:1005] mm_serial_port_close(): (ttyACM2) serial port closed
<info>  [1334255831.545986] [mm-serial-port.c:1074] mm_serial_port_close_force(): (ttyACM2) forced to close port
<debug> [1334255831.548986] [mm-plugin-manager.c:275] supports_port_ready_cb(): (Nokia): (ttyACM2) found best plugin for port
<debug> [1334255831.551745] [mm-manager.c:342] find_port_support_ready_cb(): (tty/ttyACM2): found plugin 'Nokia' giving best support
<warn>  [1334255831.556774] [mm-manager.c:378] grab_port(): plugin 'Nokia' claimed to support tty/ttyACM2 but couldn't: (3) Ignoring non-AT port
<debug> [1334255831.566075] [mm-base-modem.c:598] log_port(): (/sys/devices/pci0000:00/0000:00:1a.7/usb1/1-3) tty/ttyACM0 primary
<debug> [1334255831.574628] [mm-base-modem.c:598] log_port(): (/sys/devices/pci0000:00/0000:00:1a.7/usb1/1-3) tty/ttyACM0 data
<info>  [1334255831.582072] [mm-iface-modem.c:979] mm_iface_modem_update_state(): Modem: state changed (unknown -> initializing)
<info>  [1334255831.588259] [mm-serial-port.c:860] mm_serial_port_open(): (ttyACM0) opening serial port...
<debug> [1334255831.591727] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
<debug> [1334255831.594787] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1334255831.597730] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255831.603721] [mm-broadband-modem.c:477] modem_load_current_capabilities(): loading current capabilities...
<debug> [1334255831.612835] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
<debug> [1334255831.621310] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'ATE0<CR>'
<debug> [1334255831.634084] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1334255831.640071] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
<debug> [1334255831.643330] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
<debug> [1334255831.657322] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1334255831.660118] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255831.664974] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+GCAP<CR>'
<debug> [1334255831.683097] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>+GCAP: +CGSM,+FCLASS,+DS<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255831.691987] [mm-broadband-modem.c:459] modem_load_current_capabilities_finish(): loaded current capabilities: gsm-umts
<debug> [1334255831.699819] [mm-bearer-list.c:273] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
<debug> [1334255831.708001] [mm-broadband-modem.c:520] modem_load_manufacturer(): loading manufacturer...
<debug> [1334255831.717436] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255831.726222] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255831.733951] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CGMI<CR>'
<debug> [1334255831.748978] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>Nokia Corporation<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255831.752933] [mm-broadband-modem.c:505] modem_load_manufacturer_finish(): loaded manufacturer: Nokia Corporation
<debug> [1334255831.756516] [mm-broadband-modem.c:561] modem_load_model(): loading model...
<debug> [1334255831.759684] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255831.762966] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255831.767055] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CGMM<CR>'
<debug> [1334255831.778976] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>Nokia USB Modem 21M-02<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255831.782104] [mm-broadband-modem.c:546] modem_load_model_finish(): loaded model: Nokia USB Modem 21M-02
<debug> [1334255831.790582] [mm-broadband-modem.c:602] modem_load_revision(): loading revision...
<debug> [1334255831.800138] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255831.808186] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255831.815991] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CGMR<CR>'
<debug> [1334255831.829119] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>Modem mode<CR><LF>21M-02 R2.0.0<CR><LF>P4 rev: CL399514 with 0 local change(s).<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255831.832592] [mm-broadband-modem.c:587] modem_load_revision_finish(): loaded revision: Modem mode
21M-02 R2.0.0
P4 rev: CL399514 with 0 local change(s).
<debug> [1334255831.835790] [mm-broadband-modem.c:645] modem_load_equipment_identifier(): loading equipment identifier...
<debug> [1334255831.838936] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255831.847635] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255831.857308] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CGSN<CR>'
<debug> [1334255831.890992] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>357878040019869<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255831.898805] [mm-broadband-modem.c:628] modem_load_equipment_identifier_finish(): loaded equipment identifier: 357878040019869
<debug> [1334255831.903011] [mm-broadband-modem.c:743] modem_load_device_identifier(): loading device identifier...
<debug> [1334255831.906460] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255831.909687] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255831.912786] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'ATI<CR>'
<debug> [1334255831.925738] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>0634978<CR><LF>21M-02 R2.0.0<CR><LF>20110712<CR><LF>(c) Nokia<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255831.935407] [mm-modem-helpers.c:128] mm_create_device_identifier(): Device ID source '0000042100000638063497821M-02R2.0.020110712(c)Nokia357878040019869Modemmode21M-02R2.0.0P4rev:CL399514with0localchange(s).NokiaUSBModem21M-02NokiaCorporation'
<debug> [1334255831.943343] [mm-modem-helpers.c:129] mm_create_device_identifier(): Device ID '3940a1ccc3abc6347b5b9b80d89602aba41001a1'
<debug> [1334255831.951092] [mm-broadband-modem.c:706] modem_load_device_identifier_finish(): loaded device identifier: 3940a1ccc3abc6347b5b9b80d89602aba41001a1
<debug> [1334255831.955190] [mm-broadband-modem.c:775] modem_load_own_numbers(): loading own numbers...
<debug> [1334255831.958841] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255831.962060] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255831.965223] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CNUM<CR>'
<debug> [1334255831.982353] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1334255831.991849] [mm-broadband-modem.c:897] modem_load_unlock_required(): checking if unlock required...
<debug> [1334255831.999825] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255832.007503] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255832.011701] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CPIN?<CR>'
<debug> [1334255832.025485] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255832.029178] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1334255832.032774] [mm-sim.c:1086] load_imsi(): loading IMSI...
<debug> [1334255832.042406] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1334255832.052028] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CIMI<CR>'
<debug> [1334255832.068483] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>242050700418333<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255832.076272] [mm-sim.c:1075] load_imsi_finish(): loaded IMSI: 242050700418333
<debug> [1334255832.079792] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1334255832.083458] [mm-broadband-modem.c:930] modem_load_supported_modes(): loading initial supported modes...
<debug> [1334255832.087605] [mm-broadband-modem.c:2005] modem_3gpp_load_imei(): loading IMEI...
<debug> [1334255832.090640] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1334255832.100263] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CGSN<CR>'
<debug> [1334255832.135379] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>357878040019869<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255832.143144] [mm-broadband-modem.c:1996] modem_3gpp_load_imei_finish(): loaded IMEI: 357878040019869
<debug> [1334255832.150816] [mm-broadband-modem.c:2153] modem_3gpp_load_enabled_facility_locks(): loading enabled facility locks...
<debug> [1334255832.154283] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255832.157768] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255832.160935] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CLCK=?<CR>'
<debug> [1334255832.175627] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: ("SC","AO","OI","OX","AI","IR","AB","AG","AC","FD","BN","PS","PN","PU","PP","PC")<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255832.185273] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255832.194855] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255832.202536] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CLCK="SC",2<CR>'
<debug> [1334255832.227237] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 1<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255832.230756] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255832.234262] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255832.237389] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CLCK="FD",2<CR>'
<debug> [1334255832.257360] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255832.266951] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255832.276386] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255832.284092] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CLCK="PS",2<CR>'
<debug> [1334255832.308985] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255832.312441] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255832.315994] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255832.319136] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CLCK="PN",2<CR>'
<debug> [1334255832.339494] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255832.348819] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255832.357900] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255832.365591] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CLCK="PU",2<CR>'
<debug> [1334255832.388622] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255832.392123] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255832.395357] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255832.398463] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CLCK="PP",2<CR>'
<debug> [1334255832.422117] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255832.431789] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255832.440405] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255832.447889] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CLCK="PC",2<CR>'
<debug> [1334255832.470342] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255832.473761] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1334255832.477620] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1334255832.480816] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CUSD=?<CR>'
<debug> [1334255832.498373] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>+CUSD: (0,1,2)<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255832.507976] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1334255832.517132] [mm-broadband-modem.c:6575] iface_modem_messaging_initialize_ready(): Couldn't initialize interface: 'Messaging not supported'
<debug> [1334255832.525084] [mm-broadband-modem.c:6576] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
<info>  [1334255832.530941] [mm-iface-modem.c:979] mm_iface_modem_update_state(): Modem: state changed (initializing -> disabled)
<debug> [1334255832.534516] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
<info>  [1334255832.537752] [mm-serial-port.c:984] mm_serial_port_close(): (ttyACM0) closing serial port...
<info>  [1334255832.543214] [mm-serial-port.c:1005] mm_serial_port_close(): (ttyACM0) serial port closed
<debug> [1334255832.549234] [mm-base-modem.c:582] initialize_ready(): modem properly initialized
<debug> [1334255832.560822] [mm-manager.c:225] check_export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:1a.7/usb1/1-3' at path '/org/freedesktop/ModemManager1/Modem/0'
<debug> [1334255832.569549] [mm-manager.c:147] debug_modem_info(): (/org/freedesktop/ModemManager1/Modem/0): 'Nokia' modem, VID 0x0421 PID 0x0638 (usb)
<info>  [1334255833.362601] [mm-iface-modem-simple.c:512] connection_step(): Simple connect state (3/9): Enable
<info>  [1334255833.368441] [mm-iface-modem.c:979] mm_iface_modem_update_state(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
<info>  [1334255833.372350] [mm-serial-port.c:860] mm_serial_port_open(): (ttyACM0) opening serial port...
<debug> [1334255833.376352] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
<debug> [1334255833.480522] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1334255833.486795] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'ATZ E1 E0 V1<CR>'
<debug> [1334255833.511287] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1334255833.519960] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'ATZ E1 E0 V1<CR>'
<debug> [1334255833.539746] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'ATZ E1 E0 V1'
<debug> [1334255833.545037] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [1334255833.548534] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
<debug> [1334255833.560614] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CMEE=1'
<debug> [1334255833.563722] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [1334255833.573436] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'ATX4 &C1<CR>'
<debug> [1334255833.590835] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'ATX4 &C1'
<debug> [1334255833.598501] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [1334255833.605973] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255833.609790] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255833.613078] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255833.615902] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+IFC=1,1<CR>'
<debug> [1334255833.628943] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+IFC=1,1'
<debug> [1334255833.638211] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>ERROR<CR><LF>'
<debug> [1334255833.647363] [mm-serial-parsers.c:306] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
<debug> [1334255833.654853] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255833.662250] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CSCS=?<CR>'
<debug> [1334255833.675117] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CSCS=?'
<debug> [1334255833.678387] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+CSCS: ("GSM","HEX","IRA","PCCP","PCDN","UCS2","8859-1")<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255833.681310] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255833.684097] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255833.693118] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CSCS="UCS2"<CR>'
<debug> [1334255833.716666] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CSCS="UCS2"'
<debug> [1334255833.724224] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [1334255833.731746] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255833.735684] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255833.738896] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CSCS?<CR>'
<debug> [1334255833.749738] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CSCS?'
<debug> [1334255833.752680] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
<info>  [1334255833.761937] [mm-iface-modem.c:979] mm_iface_modem_update_state(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled)
<debug> [1334255833.771368] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1334255833.779170] [mm-broadband-modem.c:6243] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
<debug> [1334255833.786661] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1334255833.790444] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CIND=?<CR>'
<debug> [1334255833.802831] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CIND=?'
<debug> [1334255833.805678] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+CIND: ("service",(0-1)),("call",(0-1)),("callsetup",(0-3))<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255833.808645] [mm-broadband-modem.c:1337] cind_format_check_ready(): Modem supports service indications via CIND at index '1'
<debug> [1334255833.817813] [mm-broadband-modem.c:1446] set_unsolicited_events_handlers(): (ttyACM0) Setting 3GPP unsolicited events handlers
<debug> [1334255833.827468] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1334255833.835188] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1334255833.842126] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CMER=3,0,0,1<CR>'
<debug> [1334255833.860631] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CMER=3,0,0,1'
<debug> [1334255833.863845] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [1334255833.866803] [mm-broadband-modem.c:2310] modem_3gpp_setup_unsolicited_registration(): (ttyACM0) setting up 3GPP unsolicited registration messages handlers
<debug> [1334255833.870598] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1334255833.880008] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1334255833.889104] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CREG=2<CR>'
<debug> [1334255833.906075] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CREG=2'
<debug> [1334255833.912781] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [1334255833.916015] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255833.919191] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255833.922279] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CGREG=2<CR>'
<debug> [1334255833.936535] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CGREG=2'
<debug> [1334255833.945976] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [1334255833.955238] [mm-iface-modem-3gpp.c:531] mm_iface_modem_3gpp_run_all_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes')
<debug> [1334255833.962850] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255833.969842] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255833.973367] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
<debug> [1334255833.985001] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CREG?'
<debug> [1334255833.988255] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+CREG: 2,1,"0FAA","0001534D",6<CR><LF><CR><LF>OK<CR><LF>'
<info>  [1334255833.992396] [mm-iface-modem-3gpp.c:701] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> home)
<debug> [1334255834.001790] [mm-broadband-modem.c:2189] modem_3gpp_load_operator_code(): loading Operator Code...
<debug> [1334255834.010924] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255834.018517] [mm-broadband-modem.c:2225] modem_3gpp_load_operator_name(): loading Operator Name...
<debug> [1334255834.025404] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
<debug> [1334255834.028806] [mm-iface-modem.c:1118] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
<info>  [1334255834.032242] [mm-iface-modem.c:979] mm_iface_modem_update_state(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabled -> registered)
<debug> [1334255834.035767] [mm-iface-modem.c:915] periodic_signal_quality_check_enable(): Periodic signal quality checks enabled
<debug> [1334255834.039841] [mm-broadband-modem.c:1242] modem_load_signal_quality(): loading signal quality...
<debug> [1334255834.049331] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 5 (open)
<info>  [1334255834.058406] [mm-iface-modem.c:551] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> hspa)
<debug> [1334255834.066161] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 6 (open)
<debug> [1334255834.070746] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 5 (close)
<debug> [1334255834.075301] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
<debug> [1334255834.096852] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+COPS=3,2;+COPS?'
<debug> [1334255834.099632] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+COPS: 0,2,"24205",6<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255834.106717] [mm-broadband-modem.c:2179] modem_3gpp_load_operator_code_finish(): loaded Operator Code: 24205
<debug> [1334255834.115987] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 4 (close)
<debug> [1334255834.124191] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
<debug> [1334255834.150051] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+COPS=3,0;+COPS?'
<debug> [1334255834.154567] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+COPS: 0,0,"Mobile Norway",6<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255834.157846] [mm-broadband-modem.c:2215] modem_3gpp_load_operator_name_finish(): loaded Operator Name: Mobile Norway
<debug> [1334255834.160757] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
<debug> [1334255834.164136] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
<debug> [1334255834.179409] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CIND?'
<debug> [1334255834.188776] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+CIND: 1,0,0<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255834.197071] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1334255834.204757] [mm-iface-modem.c:841] signal_quality_check_ready(): Couldn't refresh signal quality: 'Could not parse CIND signal quality results signal index (255) outside received range (0-3)'
<debug> [1334255834.209609] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
<debug> [1334255834.222172] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CGREG?'
<debug> [1334255834.225132] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+CGREG: 2,1,"0FAA","0001534D",6,"32"<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255834.228017] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1334255834.235034] [mm-broadband-modem.c:6256] enabling_step(): Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface...
<debug> [1334255834.244091] [mm-broadband-modem.c:3510] set_unsolicited_result_code_handlers(): (ttyACM0) Setting unsolicited result code handlers
<debug> [1334255834.252037] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1334255834.259401] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CUSD=1<CR>'
<debug> [1334255834.273049] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CUSD=1'
<debug> [1334255834.276254] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [1334255834.279223] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1334255834.281953] [mm-broadband-modem.c:6288] enabling_step(): Modem has location capabilities, enabling the Location interface...
<debug> [1334255834.289051] [mm-iface-modem-location.c:630] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
<debug> [1334255834.298183] [mm-iface-modem-3gpp.c:531] mm_iface_modem_3gpp_run_all_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes')
<debug> [1334255834.306089] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1334255834.313362] [mm-broadband-modem.c:2189] modem_3gpp_load_operator_code(): loading Operator Code...
<debug> [1334255834.317908] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1334255834.321287] [mm-broadband-modem.c:2225] modem_3gpp_load_operator_name(): loading Operator Name...
<debug> [1334255834.324107] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
<info>  [1334255834.327269] [mm-iface-modem-simple.c:523] connection_step(): Simple connect state (4/9): Allowed mode
<info>  [1334255834.336589] [mm-iface-modem-simple.c:543] connection_step(): Simple connect state (5/9): Bands
<info>  [1334255834.346254] [mm-iface-modem-simple.c:563] connection_step(): Simple connect state (6/9): Register
<debug> [1334255834.353968] [mm-iface-modem-3gpp.c:120] mm_iface_modem_3gpp_register_in_network(): Already registered in network '24205'...
<info>  [1334255834.361696] [mm-iface-modem-simple.c:586] connection_step(): Simple connect state (7/9): Bearer
<debug> [1334255834.365458] [mm-iface-modem-simple.c:597] connection_step(): Creating new bearer...
<debug> [1334255834.369018] [mm-broadband-modem.c:253] modem_create_bearer(): Creating Broadband bearer in broadband modem
<debug> [1334255834.372992] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 5 (open)
<debug> [1334255834.376092] [mm-broadband-bearer.c:1834] modem_3gpp_registration_state_changed(): Bearer allowed to connect, registered in home network
<debug> [1334255834.385755] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 4 (close)
<debug> [1334255834.395378] [mm-broadband-modem.c:214] modem_create_bearer_finish(): New bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
<info>  [1334255834.403068] [mm-iface-modem-simple.c:624] connection_step(): Simple connect state (8/9): Connect
<debug> [1334255834.410655] [mm-bearer.c:250] mm_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
<info>  [1334255834.414514] [mm-iface-modem.c:979] mm_iface_modem_update_state(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
<debug> [1334255834.418353] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 5 (open)
<debug> [1334255834.421465] [mm-broadband-bearer.c:972] connect_3gpp(): Looking for best CID...
<debug> [1334255834.424538] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 6 (open)
<debug> [1334255834.434424] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
<debug> [1334255834.452149] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CREG?'
<debug> [1334255834.459478] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+CREG: 2,1,"0FAA","0001534D",6<CR><LF><CR><LF>OK<CR><LF>'
<info>  [1334255834.467075] [mm-iface-modem-location.c:265] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC: '0', Location area code: 'FAA', Cell ID: '1534D')
<debug> [1334255834.470688] [mm-serial-port.c:929] mm_serial_port_open(): (ttyACM0) device open count is 7 (open)
<debug> [1334255834.474036] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 6 (close)
<debug> [1334255834.476777] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
<debug> [1334255834.498047] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+COPS=3,2;+COPS?'
<debug> [1334255834.506970] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+COPS: 0,2,"24205",6<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255834.516096] [mm-broadband-modem.c:2179] modem_3gpp_load_operator_code_finish(): loaded Operator Code: 24205
<info>  [1334255834.523517] [mm-iface-modem-location.c:265] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '242', MNC: '5', Location area code: 'FAA', Cell ID: '1534D')
<debug> [1334255834.530930] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 5 (close)
<debug> [1334255834.534442] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
<debug> [1334255834.556025] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+COPS=3,0;+COPS?'
<debug> [1334255834.558899] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+COPS: 0,0,"Mobile Norway",6<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255834.563948] [mm-broadband-modem.c:2215] modem_3gpp_load_operator_name_finish(): loaded Operator Name: Mobile Norway
<debug> [1334255834.573316] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 4 (close)
<debug> [1334255834.582057] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CGDCONT?<CR>'
<debug> [1334255834.600682] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CGDCONT?'
<debug> [1334255834.606725] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255834.609846] [mm-broadband-bearer.c:895] parse_pdp_list(): No PDP contexts found
<debug> [1334255834.612901] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
<debug> [1334255834.624789] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CGREG?'
<debug> [1334255834.629830] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+CGREG: 2,1,"0FAA","0001534D",6,"32"<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255834.639291] [mm-serial-port.c:969] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
<debug> [1334255834.648176] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): --> 'AT+CGDCONT=?<CR>'
<debug> [1334255834.668020] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- 'AT+CGDCONT=?'
<debug> [1334255834.673996] [mm-at-serial-port.c:359] debug_log(): (ttyACM0): <-- '<CR><CR><LF>+CGDCONT: (1-10),"IP",,,(0,1),(0,1)<CR><LF>+CGDCONT: (1-10),"IPV6",,,(0,1),(0,1)<CR><LF>+CGDCONT: (1-10),"IPV4V6",,,(0,1),(0,1)<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1334255834.677194] [mm-broadband-bearer.c:854] parse_cid_range(): Using CID 1

(ModemManager:19652): GLib-GObject-WARNING **: invalid cast from `MMBroadbandModemNokia' to `MMBearer'