ModemManager[3656]: <info>  [1369765807.695135] [main.c:128] main(): ModemManager (version 0.7.990) starting...
ModemManager[3656]: <debug> [1369765807.706607] [main.c:61] bus_acquired_cb(): Bus acquired, creating manager...
ModemManager[3656]: <debug> [1369765807.708167] [mm-plugin-manager.c:735] load_plugins(): Looking for plugins in '/usr/local/lib/ModemManager'
ModemManager[3656]: <debug> [1369765807.709401] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Generic'
ModemManager[3656]: <debug> [1369765807.709996] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Nokia'
ModemManager[3656]: <debug> [1369765807.710522] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Nokia (Icera)'
ModemManager[3656]: <debug> [1369765807.711041] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Cinterion'
ModemManager[3656]: <debug> [1369765807.711582] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Iridium'
ModemManager[3656]: <debug> [1369765807.712077] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Gobi'
ModemManager[3656]: <debug> [1369765807.712547] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Motorola'
ModemManager[3656]: <debug> [1369765807.713092] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Novatel'
ModemManager[3656]: <debug> [1369765807.714998] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Novatel LTE'
ModemManager[3656]: <debug> [1369765807.715763] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Altair LTE'
ModemManager[3656]: <debug> [1369765807.716479] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Samsung'
ModemManager[3656]: <debug> [1369765807.717172] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Option'
ModemManager[3656]: <debug> [1369765807.717885] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Option High-Speed'
ModemManager[3656]: <debug> [1369765807.718574] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'AnyDATA'
ModemManager[3656]: <debug> [1369765807.719237] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Linktop'
ModemManager[3656]: <debug> [1369765807.719905] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'SimTech'
ModemManager[3656]: <debug> [1369765807.720606] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Wavecom'
ModemManager[3656]: <debug> [1369765807.721338] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Huawei'
ModemManager[3656]: <debug> [1369765807.722043] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Longcheer'
ModemManager[3656]: <debug> [1369765807.722795] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'X22X'
ModemManager[3656]: <debug> [1369765807.723571] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Pantech'
ModemManager[3656]: <debug> [1369765807.724434] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'ZTE'
ModemManager[3656]: <debug> [1369765807.725373] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Sierra'
ModemManager[3656]: <debug> [1369765807.726142] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Ericsson MBM'
ModemManager[3656]: <debug> [1369765807.726849] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Via CBP7'
ModemManager[3656]: <debug> [1369765807.727568] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Telit'
ModemManager[3656]: <debug> [1369765807.728276] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Infineon'
ModemManager[3656]: <debug> [1369765807.728503] [mm-plugin-manager.c:785] load_plugins(): Successfully loaded 27 plugins
ModemManager[3656]: <debug> [1369765807.733239] [main.c:79] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
ModemManager[3656]: <debug> [1369765807.733604] [mm-manager.c:450] mm_manager_start(): Starting automatic device scan...
ModemManager[3656]: <debug> [1369765807.742037] [mm-manager.c:489] mm_manager_start(): Finished device scan...
ModemManager[3656]: <debug> [1369765807.743069] [mm-manager.c:288] device_added(): (tty/ttyGS0): port's parent platform driver is not whitelisted
ModemManager[3656]: <debug> [1369765807.743791] [mm-manager.c:288] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted
ModemManager[3656]: <debug> [1369765807.745950] [mm-manager.c:288] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted
ModemManager[3656]: <debug> [1369765807.746641] [mm-manager.c:288] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
ModemManager[3656]: <debug> [1369765807.747249] [mm-manager.c:288] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
ModemManager[3656]: <debug> [1369765807.748524] [mm-plugin-manager.c:622] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/platform/tegra-ehci.1/usb1/1-1] Checking device support...
ModemManager[3656]: <debug> [1369765807.749080] [mm-plugin.c:322] apply_pre_probing_filters(): (Nokia (Icera)) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.749300] [mm-plugin.c:254] apply_pre_probing_filters(): (Gobi) [ttyACM0] filtered by drivers
ModemManager[3656]: <debug> [1369765807.749493] [mm-plugin.c:322] apply_pre_probing_filters(): (Motorola) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.749683] [mm-plugin.c:322] apply_pre_probing_filters(): (Novatel) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.749871] [mm-plugin.c:322] apply_pre_probing_filters(): (Novatel LTE) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.750060] [mm-plugin.c:322] apply_pre_probing_filters(): (Altair LTE) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.750247] [mm-plugin.c:322] apply_pre_probing_filters(): (Samsung) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.750433] [mm-plugin.c:254] apply_pre_probing_filters(): (Option) [ttyACM0] filtered by drivers
ModemManager[3656]: <debug> [1369765807.750620] [mm-plugin.c:254] apply_pre_probing_filters(): (Option High-Speed) [ttyACM0] filtered by drivers
ModemManager[3656]: <debug> [1369765807.750806] [mm-plugin.c:322] apply_pre_probing_filters(): (AnyDATA) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.750994] [mm-plugin.c:322] apply_pre_probing_filters(): (Linktop) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.751181] [mm-plugin.c:322] apply_pre_probing_filters(): (SimTech) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.751369] [mm-plugin.c:322] apply_pre_probing_filters(): (Wavecom) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.751556] [mm-plugin.c:322] apply_pre_probing_filters(): (Huawei) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.751742] [mm-plugin.c:322] apply_pre_probing_filters(): (Longcheer) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.751929] [mm-plugin.c:322] apply_pre_probing_filters(): (X22X) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.752116] [mm-plugin.c:322] apply_pre_probing_filters(): (Pantech) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.752301] [mm-plugin.c:322] apply_pre_probing_filters(): (ZTE) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.752489] [mm-plugin.c:254] apply_pre_probing_filters(): (Sierra) [ttyACM0] filtered by drivers
ModemManager[3656]: <debug> [1369765807.752709] [mm-plugin.c:376] apply_pre_probing_filters(): (Ericsson MBM) [ttyACM0] filtered by udev tags
ModemManager[3656]: <debug> [1369765807.752902] [mm-plugin.c:322] apply_pre_probing_filters(): (Telit) [ttyACM0] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.753103] [mm-plugin-manager.c:523] build_plugins_list(): (Plugin Manager) [ttyACM0] Found '2' plugins to try...
ModemManager[3656]: <debug> [1369765807.753292] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [ttyACM0]   Will try with plugin 'Infineon'
ModemManager[3656]: <debug> [1369765807.753501] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [ttyACM0]   Will try with plugin 'Generic'
ModemManager[3656]: <debug> [1369765807.753778] [mm-plugin.c:772] mm_plugin_supports_port(): (Infineon) [ttyACM0] probe required: 'at'
ModemManager[3656]: <debug> [1369765807.753992] [mm-port-probe.c:1331] mm_port_probe_run(): (tty/ttyACM0) launching port probing: 'at'
ModemManager[3656]: <debug> [1369765807.755048] [mm-plugin.c:322] apply_pre_probing_filters(): (Nokia (Icera)) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.755267] [mm-plugin.c:254] apply_pre_probing_filters(): (Gobi) [ttyACM1] filtered by drivers
ModemManager[3656]: <debug> [1369765807.755459] [mm-plugin.c:322] apply_pre_probing_filters(): (Motorola) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.755649] [mm-plugin.c:322] apply_pre_probing_filters(): (Novatel) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.755836] [mm-plugin.c:322] apply_pre_probing_filters(): (Novatel LTE) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.756022] [mm-plugin.c:322] apply_pre_probing_filters(): (Altair LTE) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.756209] [mm-plugin.c:322] apply_pre_probing_filters(): (Samsung) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.756396] [mm-plugin.c:254] apply_pre_probing_filters(): (Option) [ttyACM1] filtered by drivers
ModemManager[3656]: <debug> [1369765807.756582] [mm-plugin.c:254] apply_pre_probing_filters(): (Option High-Speed) [ttyACM1] filtered by drivers
ModemManager[3656]: <debug> [1369765807.756767] [mm-plugin.c:322] apply_pre_probing_filters(): (AnyDATA) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.756954] [mm-plugin.c:322] apply_pre_probing_filters(): (Linktop) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.757140] [mm-plugin.c:322] apply_pre_probing_filters(): (SimTech) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.757327] [mm-plugin.c:322] apply_pre_probing_filters(): (Wavecom) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.757518] [mm-plugin.c:322] apply_pre_probing_filters(): (Huawei) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.757705] [mm-plugin.c:322] apply_pre_probing_filters(): (Longcheer) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.757893] [mm-plugin.c:322] apply_pre_probing_filters(): (X22X) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.758079] [mm-plugin.c:322] apply_pre_probing_filters(): (Pantech) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.758291] [mm-plugin.c:322] apply_pre_probing_filters(): (ZTE) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.758478] [mm-plugin.c:254] apply_pre_probing_filters(): (Sierra) [ttyACM1] filtered by drivers
ModemManager[3656]: <debug> [1369765807.758667] [mm-plugin.c:376] apply_pre_probing_filters(): (Ericsson MBM) [ttyACM1] filtered by udev tags
ModemManager[3656]: <debug> [1369765807.758856] [mm-plugin.c:322] apply_pre_probing_filters(): (Telit) [ttyACM1] filtered by vendor/product IDs
ModemManager[3656]: <debug> [1369765807.759048] [mm-plugin-manager.c:523] build_plugins_list(): (Plugin Manager) [ttyACM1] Found '2' plugins to try...
ModemManager[3656]: <debug> [1369765807.759234] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [ttyACM1]   Will try with plugin 'Infineon'
ModemManager[3656]: <debug> [1369765807.759419] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [ttyACM1]   Will try with plugin 'Generic'
ModemManager[3656]: <debug> [1369765807.759616] [mm-plugin.c:772] mm_plugin_supports_port(): (Infineon) [ttyACM1] probe required: 'at'
ModemManager[3656]: <debug> [1369765807.759817] [mm-port-probe.c:1331] mm_port_probe_run(): (tty/ttyACM1) launching port probing: 'at'
ModemManager[3656]: <debug> [1369765807.760509] [mm-manager.c:288] device_added(): (tty/ttyHS1): port's parent platform driver is not whitelisted
ModemManager[3656]: <debug> [1369765807.761182] [mm-manager.c:288] device_added(): (tty/ttyHS2): port's parent platform driver is not whitelisted
ModemManager[3656]: <debug> [1369765807.761837] [mm-manager.c:288] device_added(): (tty/ttyHS3): port's parent platform driver is not whitelisted
ModemManager[3656]: <debug> [1369765807.762489] [mm-manager.c:288] device_added(): (tty/ttyHS4): port's parent platform driver is not whitelisted
ModemManager[3656]: <debug> [1369765807.766584] [mm-manager.c:288] device_added(): (net/usb0): port's parent platform driver is not whitelisted
ModemManager[3656]: <debug> [1369765807.767573] [mm-manager.c:288] device_added(): (net/wlan0): port's parent platform driver is not whitelisted
ModemManager[3656]: <debug> [1369765807.768069] [mm-manager.c:265] device_added(): (net/dummy0): could not get port's parent device
ModemManager[3656]: <debug> [1369765807.768528] [mm-manager.c:265] device_added(): (net/ip6tnl0): could not get port's parent device
ModemManager[3656]: <debug> [1369765807.769201] [mm-manager.c:265] device_added(): (net/sit0): could not get port's parent device
ModemManager[3656]: <debug> [1369765807.770261] [mm-serial-port.c:413] mm_serial_port_open(): (ttyACM0) opening serial port...
ModemManager[3656]: <debug> [1369765807.772904] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
ModemManager[3656]: <debug> [1369765807.773942] [mm-serial-port.c:413] mm_serial_port_open(): (ttyACM1) opening serial port...
ModemManager[3656]: <debug> [1369765807.779741] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM1) device open count is 1 (open)
ModemManager[3656]: <debug> [1369765807.876876] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT<CR>'
ModemManager[3656]: <debug> [1369765807.882038] [mm-at-serial-port.c:411] debug_log(): (ttyACM1): --> 'AT<CR>'
ModemManager[3656]: <debug> [1369765808.082573] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765808.084263] [mm-port-probe.c:151] mm_port_probe_set_result_at(): (tty/ttyACM0) port is AT-capable
ModemManager[3656]: <debug> [1369765808.086081] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
ModemManager[3656]: <debug> [1369765808.086820] [mm-serial-port.c:531] mm_serial_port_close(): (ttyACM0) closing serial port...
ModemManager[3656]: <debug> [1369765808.092274] [mm-serial-port.c:558] mm_serial_port_close(): (ttyACM0) serial port closed
ModemManager[3656]: <debug> [1369765808.093104] [mm-serial-port.c:579] mm_serial_port_close_force(): (ttyACM0) forced to close port
ModemManager[3656]: <debug> [1369765808.094999] [mm-plugin-manager.c:365] plugin_supports_port_ready(): (Plugin Manager) (Infineon) [ttyACM0] found best plugin for port
ModemManager[3656]: <debug> [1369765808.095814] [mm-plugin-manager.c:201] port_probe_context_finished(): (Plugin Manager) (Infineon) [ttyACM0]: found best plugin for device (/sys/devices/platform/tegra-ehci.1/usb1/1-1)
ModemManager[3656]: <debug> [1369765808.096622] [mm-plugin-manager.c:325] suggest_port_probe_result(): (Plugin Manager) (Infineon) [ttyACM1] suggested plugin for port
ModemManager[3656]: <debug> [1369765808.097388] [mm-plugin-manager.c:239] port_probe_context_finished(): (Plugin Manager) 'ttyACM0' port probe finished, still 1 running probes in this device (ttyACM1)
ModemManager[3656]: <debug> [1369765809.826303] [mm-plugin-manager.c:593] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/platform/tegra-ehci.1/usb1/1-1] Minimum probing time consumed
ModemManager[3656]: <debug> [1369765811.826013] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[3656]: <debug> [1369765811.826524] [mm-at-serial-port.c:411] debug_log(): (ttyACM1): --> 'AT<CR>'
ModemManager[3656]: <debug> [1369765814.827305] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[3656]: <debug> [1369765814.828029] [mm-at-serial-port.c:411] debug_log(): (ttyACM1): --> 'AT<CR>'
ModemManager[3656]: <debug> [1369765817.827023] [mm-port-probe-at.c:43] mm_port_probe_response_processor_is_at(): Parsing AT got: 'Serial command timed out'
ModemManager[3656]: <debug> [1369765817.827523] [mm-port-probe.c:161] mm_port_probe_set_result_at(): (tty/ttyACM1) port is not AT-capable
ModemManager[3656]: <debug> [1369765817.827932] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM1) device open count is 0 (close)
ModemManager[3656]: <debug> [1369765817.828156] [mm-serial-port.c:531] mm_serial_port_close(): (ttyACM1) closing serial port...
ModemManager[3656]: <debug> [1369765817.841068] [mm-serial-port.c:558] mm_serial_port_close(): (ttyACM1) serial port closed
ModemManager[3656]: <debug> [1369765817.842557] [mm-serial-port.c:579] mm_serial_port_close_force(): (ttyACM1) forced to close port
ModemManager[3656]: <debug> [1369765817.854446] [mm-plugin-manager.c:365] plugin_supports_port_ready(): (Plugin Manager) (Infineon) [ttyACM1] found best plugin for port
ModemManager[3656]: <debug> [1369765817.854997] [mm-plugin-manager.c:250] port_probe_context_finished(): (Plugin Manager) 'ttyACM1' port probe finished, last one in device
ModemManager[3656]: <debug> [1369765817.855819] [mm-plugin-manager.c:107] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/platform/tegra-ehci.1/usb1/1-1] device support check finished in '10.107014' seconds
ModemManager[3656]: <info>  [1369765817.857282] [mm-device.c:486] mm_device_create_modem(): Creating modem with plugin 'Infineon' and '2' ports
ModemManager[3656]: <warn>  [1369765817.867855] [mm-plugin.c:900] mm_plugin_create_modem(): Could not grab port (tty/ttyACM1): 'Cannot add port 'tty/ttyACM1', unhandled serial type'
ModemManager[3656]: <debug> [1369765817.871365] [mm-base-modem.c:275] mm_base_modem_grab_port(): (ttyACM0) type 'at' claimed by /sys/devices/platform/tegra-ehci.1/usb1/1-1
ModemManager[3656]: <debug> [1369765817.872442] [mm-base-modem.c:1009] log_port(): (/sys/devices/platform/tegra-ehci.1/usb1/1-1) tty/ttyACM0 at (primary)
ModemManager[3656]: <debug> [1369765817.872927] [mm-base-modem.c:1009] log_port(): (/sys/devices/platform/tegra-ehci.1/usb1/1-1) tty/ttyACM0 data (primary)
ModemManager[3656]: <debug> [1369765817.874672] [mm-serial-port.c:413] mm_serial_port_open(): (ttyACM0) opening serial port...
ModemManager[3656]: <debug> [1369765817.877633] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
ModemManager[3656]: <debug> [1369765817.877879] [mm-at-serial-port.c:448] mm_at_serial_port_run_init_sequence(): (ttyACM0): running init sequence...
ModemManager[3656]: <debug> [1369765817.878163] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765817.878429] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <info>  [1369765817.878701] [mm-manager.c:149] find_device_support_ready(): Modem for device at '/sys/devices/platform/tegra-ehci.1/usb1/1-1' successfully created
ModemManager[3656]: <debug> [1369765817.881434] [mm-broadband-modem.c:675] modem_load_current_capabilities(): loading current capabilities...
ModemManager[3656]: <debug> [1369765817.881786] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
ModemManager[3656]: <debug> [1369765817.882126] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATE0<CR>'
ModemManager[3656]: <debug> [1369765817.889640] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765817.889974] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATV1<CR>'
ModemManager[3656]: <debug> [1369765817.897625] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765817.897973] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
ModemManager[3656]: <debug> [1369765817.911375] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765817.911676] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATX4<CR>'
ModemManager[3656]: <debug> [1369765817.921213] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765817.922461] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT&C1<CR>'
ModemManager[3656]: <debug> [1369765817.933444] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765817.934541] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATE0<CR>'
ModemManager[3656]: <debug> [1369765817.943980] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765817.944932] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
ModemManager[3656]: <debug> [1369765817.945777] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
ModemManager[3656]: <debug> [1369765817.962418] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765817.963368] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765817.964287] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+GCAP<CR>'
ModemManager[3656]: <debug> [1369765817.977845] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
ModemManager[3656]: <debug> [1369765817.979491] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
ModemManager[3656]: <debug> [1369765817.980766] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATI<CR>'
ModemManager[3656]: <debug> [1369765817.989585] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
ModemManager[3656]: <debug> [1369765817.990549] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
ModemManager[3656]: <debug> [1369765817.991440] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPIN?<CR>'
ModemManager[3656]: <debug> [1369765818.007087] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.008314] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.009211] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.010046] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+WS46=?<CR>'
ModemManager[3656]: <debug> [1369765818.027089] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
ModemManager[3656]: <debug> [1369765818.028055] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
ModemManager[3656]: <debug> [1369765818.028922] [mm-broadband-modem.c:336] modem_load_current_capabilities_finish(): loaded current capabilities: gsm-umts
ModemManager[3656]: <debug> [1369765818.029906] [mm-iface-modem.c:3885] interface_initialization_step(): Modem allows up to 1 bearers
ModemManager[3656]: <debug> [1369765818.030630] [mm-bearer-list.c:273] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
ModemManager[3656]: <debug> [1369765818.031941] [mm-broadband-modem.c:735] modem_load_manufacturer(): loading manufacturer...
ModemManager[3656]: <debug> [1369765818.032753] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.033709] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.034583] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGMI<CR>'
ModemManager[3656]: <debug> [1369765818.049086] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF> Infineon<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.050217] [mm-broadband-modem.c:719] modem_load_manufacturer_finish(): loaded manufacturer: Infineon
ModemManager[3656]: <debug> [1369765818.051100] [mm-broadband-modem.c:775] modem_load_model(): loading model...
ModemManager[3656]: <debug> [1369765818.051886] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.052733] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.053627] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGMM<CR>'
ModemManager[3656]: <debug> [1369765818.067452] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF> XMM6260<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.068478] [mm-broadband-modem.c:759] modem_load_model_finish(): loaded model: XMM6260
ModemManager[3656]: <debug> [1369765818.069348] [mm-broadband-modem.c:815] modem_load_revision(): loading revision...
ModemManager[3656]: <debug> [1369765818.070137] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.070982] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.071801] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGMR<CR>'
ModemManager[3656]: <debug> [1369765818.085991] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>1231_0.17.0_1205<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.087016] [mm-broadband-modem.c:799] modem_load_revision_finish(): loaded revision: 1231_0.17.0_1205
ModemManager[3656]: <debug> [1369765818.087917] [mm-broadband-modem.c:876] modem_load_equipment_identifier(): loading equipment identifier...
ModemManager[3656]: <debug> [1369765818.088746] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.089607] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.090423] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGSN<CR>'
ModemManager[3656]: <debug> [1369765818.114334] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>354400054096650<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.115448] [mm-broadband-modem.c:858] modem_load_equipment_identifier_finish(): loaded equipment identifier: 354400054096650
ModemManager[3656]: <debug> [1369765818.116328] [mm-broadband-modem.c:964] modem_load_device_identifier(): loading device identifier...
ModemManager[3656]: <debug> [1369765818.117231] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.118075] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.118895] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATI<CR>'
ModemManager[3656]: <debug> [1369765818.127460] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
ModemManager[3656]: <debug> [1369765818.128427] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
ModemManager[3656]: <debug> [1369765818.129563] [mm-modem-helpers.c:148] mm_create_device_identifier(): Device ID source '00001519000000203544000540966501231_0.17.0_1205XMM6260Infineon'
ModemManager[3656]: <debug> [1369765818.130340] [mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID '27d915cfc3ffa8b9d415425dd511570fc40a35f5'
ModemManager[3656]: <debug> [1369765818.131036] [mm-broadband-modem.c:927] modem_load_device_identifier_finish(): loaded device identifier: 27d915cfc3ffa8b9d415425dd511570fc40a35f5
ModemManager[3656]: <debug> [1369765818.131893] [mm-broadband-modem.c:1518] modem_load_supported_modes(): loading initial supported modes...
ModemManager[3656]: <debug> [1369765818.132846] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.133781] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.134647] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+WS46=?<CR>'
ModemManager[3656]: <debug> [1369765818.151707] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
ModemManager[3656]: <debug> [1369765818.152673] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
ModemManager[3656]: <debug> [1369765818.153452] [mm-broadband-modem.c:1455] supported_modes_ws46_test_ready(): Generic query of supported 3GPP networks failed: 'Unknown error'
ModemManager[3656]: <debug> [1369765818.154277] [mm-broadband-modem.c:1462] supported_modes_ws46_test_ready(): Assuming device allows (3GPP) 2G/3G network modes
ModemManager[3656]: <debug> [1369765818.155782] [mm-broadband-modem.c:2952] load_power_state(): loading power state...
ModemManager[3656]: <debug> [1369765818.156621] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.157490] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.158328] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CFUN?<CR>'
ModemManager[3656]: <debug> [1369765818.173782] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CFUN: 1,0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <warn>  [1369765818.175020] [mm-iface-modem.c:3699] load_power_state_ready(): couldn't load Power State: 'Failed to parse +CFUN? response '1,0''
ModemManager[3656]: <debug> [1369765818.175852] [mm-broadband-modem.c:1263] modem_load_unlock_required(): checking if unlock required...
ModemManager[3656]: <debug> [1369765818.176644] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.177603] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.178431] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPIN?<CR>'
ModemManager[3656]: <debug> [1369765818.193664] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.194727] [mm-iface-modem.c:2724] update_lock_info_context_step(): SIM is ready, and no need for the after SIM unlock step...
ModemManager[3656]: <debug> [1369765818.195613] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765818.198515] [mm-sim.c:1077] load_sim_identifier(): loading SIM identifier...
ModemManager[3656]: <debug> [1369765818.199381] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765818.200481] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CRSM=176,12258,0,0,10<CR>'
ModemManager[3656]: <debug> [1369765818.259016] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"*****"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.260475] [mm-sim.c:1066] load_sim_identifier_finish(): loaded SIM identifier: *****
ModemManager[3656]: <debug> [1369765818.261729] [mm-sim.c:1147] load_imsi(): loading IMSI....
ModemManager[3656]: <debug> [1369765818.262680] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.263615] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.264497] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CIMI<CR>'
ModemManager[3656]: <debug> [1369765818.278995] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>*****<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.280162] [mm-sim.c:1136] load_imsi_finish(): loaded IMSI: ****
ModemManager[3656]: <debug> [1369765818.281477] [mm-sim.c:1283] load_operator_identifier(): loading Operator ID...
ModemManager[3656]: <debug> [1369765818.282433] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.283305] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.284218] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CRSM=176,28589,0,0,4<CR>'
ModemManager[3656]: <debug> [1369765818.341591] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"01000102"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.343002] [mm-sim.c:1394] load_operator_name(): loading Operator Name...
ModemManager[3656]: <debug> [1369765818.343905] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.344802] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.345649] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CRSM=176,28486,0,0,17<CR>'
ModemManager[3656]: <debug> [1369765818.405457] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"004E6574436F6DFFFFFFFFFFFFFFFFFFFF"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.406751] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765818.408287] [mm-broadband-modem.c:1141] modem_load_own_numbers(): loading own numbers...
ModemManager[3656]: <debug> [1369765818.409109] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765818.410052] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CNUM<CR>'
ModemManager[3656]: <debug> [1369765818.463726] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CNUM: "","",0<CR><LF><CR><LF>+CNUM: "","",0<CR><LF>'
ModemManager[3656]: <debug> [1369765818.470363] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CNUM: "","",0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.474118] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765818.476332] [mm-broadband-modem.c:3055] modem_3gpp_load_imei(): loading IMEI...
ModemManager[3656]: <debug> [1369765818.476804] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765818.477320] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGSN<CR>'
ModemManager[3656]: <debug> [1369765818.499398] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>354400054096650<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.500027] [mm-broadband-modem.c:3046] modem_3gpp_load_imei_finish(): loaded IMEI: 354400054096650
ModemManager[3656]: <debug> [1369765818.500632] [mm-broadband-modem.c:3214] modem_3gpp_load_enabled_facility_locks(): loading enabled facility locks...
ModemManager[3656]: <debug> [1369765818.501212] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.501683] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.502208] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CLCK=?<CR>'
ModemManager[3656]: <debug> [1369765818.516915] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: ("AO","OI","AI","IR","OX","AB","AG","AC","PS","PN","PU","PP","PC","SC","FD")<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.518379] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.519397] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.520370] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CLCK="SC",2<CR>'
ModemManager[3656]: <debug> [1369765818.543862] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 1<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.545079] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.545942] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.546899] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CLCK="FD",2<CR>'
ModemManager[3656]: <debug> [1369765818.570583] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.571762] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.572621] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.573456] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CLCK="PS",2<CR>'
ModemManager[3656]: <debug> [1369765818.597224] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF><CR><LF>+CLCK: 0<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.598422] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.599276] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.600103] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CLCK="PN",2<CR>'
ModemManager[3656]: <debug> [1369765818.623349] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF><CR><LF>+CLCK: 0<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.624578] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.625446] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.626275] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CLCK="PU",2<CR>'
ModemManager[3656]: <debug> [1369765818.649585] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF><CR><LF>+CLCK: 0<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.650766] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.651613] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.652442] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CLCK="PP",2<CR>'
ModemManager[3656]: <debug> [1369765818.678562] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF><CR><LF>+CLCK: 0<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.684270] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.686742] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.689027] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CLCK="PC",2<CR>'
ModemManager[3656]: <debug> [1369765818.731461] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF><CR><LF>+CLCK: 0<CR><LF><CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.733431] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765818.736620] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765818.737706] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CUSD=?<CR>'
ModemManager[3656]: <debug> [1369765818.754848] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CUSD: (0-2)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.759330] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765818.775064] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765818.776666] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CNMI=?<CR>'
ModemManager[3656]: <debug> [1369765818.794237] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CNMI: (0-2),(0-3),(0-3),(0-2),(0-1)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.795894] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765818.797112] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765818.798274] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPMS=?<CR>'
ModemManager[3656]: <debug> [1369765818.817184] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: ("BM","ME","SM","SR"),("ME","SM"),("BM","ME","SM","SR")<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765818.823009] [mm-iface-modem-messaging.c:1113] load_supported_storages_ready(): Supported storages loaded:
ModemManager[3656]: <debug> [1369765818.825326] [mm-iface-modem-messaging.c:1114] load_supported_storages_ready():   mem1 (list/read/delete) storages: 'bm, me, sm, sr'
ModemManager[3656]: <debug> [1369765818.827257] [mm-iface-modem-messaging.c:1115] load_supported_storages_ready():   mem2 (write/send) storages:       'me, sm'
ModemManager[3656]: <debug> [1369765818.829131] [mm-iface-modem-messaging.c:1116] load_supported_storages_ready():   mem3 (reception) storages:        'bm, me, sm, sr'
ModemManager[3656]: <debug> [1369765818.832858] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765818.836630] [mm-broadband-modem.c:8708] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
ModemManager[3656]: <debug> [1369765818.839104] [mm-broadband-modem.c:8709] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
ModemManager[3656]: <info>  [1369765818.842656] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem: state changed (unknown -> disabled)
ModemManager[3656]: <debug> [1369765818.844944] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
ModemManager[3656]: <debug> [1369765818.846013] [mm-serial-port.c:531] mm_serial_port_close(): (ttyACM0) closing serial port...
ModemManager[3656]: <debug> [1369765818.851461] [mm-serial-port.c:558] mm_serial_port_close(): (ttyACM0) serial port closed
ModemManager[3656]: <debug> [1369765818.852653] [mm-base-modem.c:978] initialize_ready(): modem properly initialized
ModemManager[3656]: <debug> [1369765818.862745] [mm-device.c:416] export_modem(): Exported modem '/sys/devices/platform/tegra-ehci.1/usb1/1-1' at path '/org/freedesktop/ModemManager1/Modem/0'
ModemManager[3656]: <debug> [1369765818.863763] [mm-device.c:424] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Infineon' modem, VID 0x1519 PID 0x0020 (usb)
ModemManager[3656]: <info>  [1369765917.878527] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
ModemManager[3656]: <debug> [1369765917.879445] [mm-serial-port.c:413] mm_serial_port_open(): (ttyACM0) opening serial port...
ModemManager[3656]: (mm-command-serial-port.c:526):channel_enable: runtime check failed: (self->priv->channel == NULL)
ModemManager[3656]: (mm-command-serial-port.c:530):channel_enable: runtime check failed: (self->priv->watch_id == 0)
ModemManager[3656]: <debug> [1369765917.882681] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
ModemManager[3656]: <debug> [1369765917.882953] [mm-broadband-modem.c:7782] enabling_started(): Flashing primary AT port before enabling...
ModemManager[3656]: <debug> [1369765917.987411] [mm-broadband-modem.c:7671] enabling_flash_done(): Running modem initialization sequence...
ModemManager[3656]: <debug> [1369765917.988158] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765917.988957] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATZ<CR>'
ModemManager[3656]: <debug> [1369765918.003956] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.004614] [mm-broadband-modem.c:7653] enabling_modem_init_ready(): Giving some time to settle the modem...
ModemManager[3656]: <debug> [1369765918.004927] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765918.506449] [mm-at-serial-port.c:448] mm_at_serial_port_run_init_sequence(): (ttyACM0): running init sequence...
ModemManager[3656]: <debug> [1369765918.508156] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765918.508740] [mm-iface-modem.c:2835] modem_power_up_ready(): Modem set in full-power mode...
ModemManager[3656]: <debug> [1369765918.514134] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765918.515069] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
ModemManager[3656]: <debug> [1369765918.515719] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATE0<CR>'
ModemManager[3656]: <debug> [1369765918.522529] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- 'ATE0'
ModemManager[3656]: <debug> [1369765918.523397] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR>'
ModemManager[3656]: <debug> [1369765918.525352] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.525768] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATV1<CR>'
ModemManager[3656]: <debug> [1369765918.533722] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.534124] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
ModemManager[3656]: <debug> [1369765918.547825] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.548224] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATX4<CR>'
ModemManager[3656]: <debug> [1369765918.555824] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.556217] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT&C1<CR>'
ModemManager[3656]: <debug> [1369765918.565353] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.566226] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CFUN=1<CR>'
ModemManager[3656]: <debug> [1369765918.582711] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.583351] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
ModemManager[3656]: <debug> [1369765918.584065] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+IFC=1,1<CR>'
ModemManager[3656]: <debug> [1369765918.602222] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
ModemManager[3656]: <debug> [1369765918.602862] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
ModemManager[3656]: <debug> [1369765918.603340] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765918.604087] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSCS=?<CR>'
ModemManager[3656]: <debug> [1369765918.621494] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSCS: ("UCS2", "IRA", "HEX", "GSM")<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.622684] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765918.623285] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765918.623995] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSCS="UCS2"<CR>'
ModemManager[3656]: <debug> [1369765918.646831] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.647799] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765918.648356] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765918.648919] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSCS?<CR>'
ModemManager[3656]: <debug> [1369765918.664120] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.665384] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765918.666124] [mm-broadband-modem.c:8326] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
ModemManager[3656]: <debug> [1369765918.666807] [mm-broadband-modem.c:2414] modem_3gpp_setup_unsolicited_events(): Checking indicator support...
ModemManager[3656]: <debug> [1369765918.667387] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765918.668176] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CIND=?<CR>'
ModemManager[3656]: <debug> [1369765918.684600] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
ModemManager[3656]: <debug> [1369765918.685230] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
ModemManager[3656]: <debug> [1369765918.685702] [mm-broadband-modem.c:2344] cind_format_check_ready(): Marking indications as unsupported: 'Unknown error'
ModemManager[3656]: <debug> [1369765918.686406] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765918.688434] [mm-broadband-modem.c:3378] modem_3gpp_setup_unsolicited_registration_events(): (ttyACM0) setting up 3GPP unsolicited registration messages handlers
ModemManager[3656]: <debug> [1369765918.689264] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765918.689920] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CREG=2<CR>'
ModemManager[3656]: <debug> [1369765918.706084] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.707108] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765918.707811] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765918.708379] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGREG=2<CR>'
ModemManager[3656]: <debug> [1369765918.725458] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.726361] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765918.727161] [mm-iface-modem-3gpp.c:745] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
ModemManager[3656]: <debug> [1369765918.727815] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765918.728474] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
ModemManager[3656]: <debug> [1369765918.742845] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"10D1","012CE305",4<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <info>  [1369765918.743890] [mm-iface-modem-3gpp.c:1090] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
ModemManager[3656]: <debug> [1369765918.744388] [mm-broadband-modem.c:3250] modem_3gpp_load_operator_code(): loading Operator Code...
ModemManager[3656]: <debug> [1369765918.744892] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <info>  [1369765918.745756] [mm-iface-modem.c:726] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> hsdpa)
ModemManager[3656]: <debug> [1369765918.747031] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
ModemManager[3656]: <debug> [1369765918.747703] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
ModemManager[3656]: <debug> [1369765918.752325] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
ModemManager[3656]: <debug> [1369765918.780858] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,2,"24202",2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.781765] [mm-broadband-modem.c:3240] modem_3gpp_load_operator_code_finish(): loaded Operator Code: 24202
ModemManager[3656]: <debug> [1369765918.782533] [mm-broadband-modem.c:3286] modem_3gpp_load_operator_name(): loading Operator Name...
ModemManager[3656]: <debug> [1369765918.783046] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
ModemManager[3656]: <debug> [1369765918.783697] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
ModemManager[3656]: <debug> [1369765918.788698] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
ModemManager[3656]: <debug> [1369765918.804754] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 2,1,"10D1","012CE305",4,"E6"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.806032] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765918.807094] [mm-broadband-modem.c:8339] enabling_step(): Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface...
ModemManager[3656]: <debug> [1369765918.807785] [mm-broadband-modem.c:4692] set_unsolicited_result_code_handlers(): (ttyACM0) Setting unsolicited result code handlers
ModemManager[3656]: <debug> [1369765918.808489] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765918.809118] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
ModemManager[3656]: <debug> [1369765918.837478] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,0,"N NetCom",2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.838875] [mm-broadband-modem.c:3276] modem_3gpp_load_operator_name_finish(): loaded Operator Name: N NetCom
ModemManager[3656]: <debug> [1369765918.839650] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <info>  [1369765918.843838] [mm-iface-modem-3gpp.c:1023] update_registration_reload_current_operator_ready(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
ModemManager[3656]: <debug> [1369765918.844805] [mm-iface-modem.c:1372] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
ModemManager[3656]: <debug> [1369765918.846245] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CUSD=1<CR>'
ModemManager[3656]: <debug> [1369765918.860840] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.862109] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765918.863517] [mm-broadband-modem.c:8367] enabling_step(): Modem has location capabilities, enabling the Location interface...
ModemManager[3656]: <debug> [1369765918.864275] [mm-iface-modem-location.c:739] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
ModemManager[3656]: <debug> [1369765918.865319] [mm-iface-modem-3gpp.c:745] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
ModemManager[3656]: <debug> [1369765918.866247] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765918.866902] [mm-broadband-modem.c:3250] modem_3gpp_load_operator_code(): loading Operator Code...
ModemManager[3656]: <debug> [1369765918.867447] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765918.869148] [mm-broadband-modem.c:8380] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
ModemManager[3656]: <debug> [1369765918.870259] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
ModemManager[3656]: <debug> [1369765918.870979] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
ModemManager[3656]: <debug> [1369765918.884632] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"10D1","012CE305",4<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <info>  [1369765918.885572] [mm-iface-modem-location.c:293] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC: '0', Location area code: '10D1', Cell ID: '12CE305')
ModemManager[3656]: <debug> [1369765918.886405] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 5 (open)
ModemManager[3656]: <debug> [1369765918.886975] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 4 (close)
ModemManager[3656]: <debug> [1369765918.887590] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
ModemManager[3656]: <debug> [1369765918.914861] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,2,"24202",2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.915684] [mm-broadband-modem.c:3240] modem_3gpp_load_operator_code_finish(): loaded Operator Code: 24202
ModemManager[3656]: <info>  [1369765918.916419] [mm-iface-modem-location.c:293] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '242', MNC: '2', Location area code: '10D1', Cell ID: '12CE305')
ModemManager[3656]: <debug> [1369765918.916853] [mm-broadband-modem.c:3286] modem_3gpp_load_operator_name(): loading Operator Name...
ModemManager[3656]: <debug> [1369765918.917361] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 5 (open)
ModemManager[3656]: <debug> [1369765918.917903] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 4 (close)
ModemManager[3656]: <debug> [1369765918.918511] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMGF=?<CR>'
ModemManager[3656]: <debug> [1369765918.933777] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CMGF: (0-1)<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.934742] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 5 (open)
ModemManager[3656]: <debug> [1369765918.935282] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 4 (close)
ModemManager[3656]: <debug> [1369765918.935884] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
ModemManager[3656]: <debug> [1369765918.951477] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 2,1,"10D1","012CE305",4,"E6"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.952527] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
ModemManager[3656]: <debug> [1369765918.953305] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
ModemManager[3656]: <debug> [1369765918.980719] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,0,"N NetCom",2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.981568] [mm-broadband-modem.c:3276] modem_3gpp_load_operator_name_finish(): loaded Operator Name: N NetCom
ModemManager[3656]: <debug> [1369765918.982245] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765918.982946] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMGF=0<CR>'
ModemManager[3656]: <debug> [1369765918.998093] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765918.998825] [mm-broadband-modem.c:5269] cmgf_set_ready(): Successfully set preferred SMS mode: 'PDU'
ModemManager[3656]: <debug> [1369765918.999737] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765919.000320] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765919.001775] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPMS="","ME","ME"<CR>'
ModemManager[3656]: <debug> [1369765919.038817] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,8,0,8,0,8<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765919.039387] [mm-broadband-modem.c:5967] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'bm'
ModemManager[3656]: <debug> [1369765919.039553] [mm-broadband-modem.c:5163] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (BM), mem2 (none)...
ModemManager[3656]: <debug> [1369765919.039730] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765919.039906] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765919.040094] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPMS="BM"<CR>'
ModemManager[3656]: <debug> [1369765919.057375] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,8,0,8,0,8<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765919.057997] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765919.058253] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765919.058469] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
ModemManager[3656]: <debug> [1369765919.071761] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765919.072046] [mm-broadband-modem.c:5967] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'me'
ModemManager[3656]: <debug> [1369765919.072179] [mm-broadband-modem.c:5163] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (ME), mem2 (none)...
ModemManager[3656]: <debug> [1369765919.072340] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765919.072510] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765919.072679] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPMS="ME"<CR>'
ModemManager[3656]: <debug> [1369765919.090005] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,8,0,8,0,8<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765919.090616] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765919.091032] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765919.091513] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
ModemManager[3656]: <debug> [1369765919.105978] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765919.106501] [mm-broadband-modem.c:5967] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sm'
ModemManager[3656]: <debug> [1369765919.106722] [mm-broadband-modem.c:5163] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SM), mem2 (none)...
ModemManager[3656]: <debug> [1369765919.106946] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765919.107212] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765919.107503] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPMS="SM"<CR>'
ModemManager[3656]: <debug> [1369765919.125428] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,20,0,8,0,8<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765919.125973] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765919.126203] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765919.126415] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
ModemManager[3656]: <debug> [1369765919.146553] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765919.146984] [mm-broadband-modem.c:5967] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sr'
ModemManager[3656]: <debug> [1369765919.147139] [mm-broadband-modem.c:5163] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SR), mem2 (none)...
ModemManager[3656]: <debug> [1369765919.147328] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765919.147510] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765919.147688] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPMS="SR"<CR>'
ModemManager[3656]: <debug> [1369765919.164461] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,8,0,8,0,8<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765919.165248] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765919.165861] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765919.166524] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
ModemManager[3656]: <debug> [1369765919.182713] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765919.183854] [mm-broadband-modem.c:5575] set_messaging_unsolicited_events_handlers(): (ttyACM0) Setting messaging unsolicited events handlers
ModemManager[3656]: <debug> [1369765919.184400] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765919.185101] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765919.185789] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CNMI=2,1,2,1,0<CR>'
ModemManager[3656]: <debug> [1369765919.213073] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765919.214299] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369765919.215044] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
ModemManager[3656]: <debug> [1369765919.223307] [mm-iface-modem.c:1127] periodic_signal_quality_check_enable(): Periodic signal quality checks enabled (interval = 3s)
ModemManager[3656]: <debug> [1369765919.224367] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369765919.224967] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765919.225528] [mm-iface-modem.c:844] periodic_access_technologies_check_enable(): Periodic access technology checks enabled
ModemManager[3656]: <debug> [1369765919.234590] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369765919.247865] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 16,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765919.248959] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369765919.249894] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (51)
ModemManager[3656]: <debug> [1369765919.250354] [mm-iface-modem.c:1046] signal_quality_check_ready(): Periodic signal quality checks rescheduled (interval = 30s)
ModemManager[3656]: <debug> [1369765949.843690] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369765949.843948] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765949.844120] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369765949.854143] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 16,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765949.854458] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369765949.854747] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (51)
ModemManager[3656]: <debug> [1369765979.854001] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369765979.854575] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765979.855180] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369765979.928270] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 16,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765979.929856] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369765979.931293] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (51)
ModemManager[3656]: <info>  [1369765980.510399] [mm-iface-modem-simple.c:779] connect_auth_ready(): Simple connect started...
ModemManager[3656]: <debug> [1369765980.510558] [mm-iface-modem-simple.c:793] connect_auth_ready():    PIN: unspecified
ModemManager[3656]: <debug> [1369765980.510620] [mm-iface-modem-simple.c:803] connect_auth_ready():    Allowed mode: unspecified
ModemManager[3656]: <debug> [1369765980.510670] [mm-iface-modem-simple.c:804] connect_auth_ready():    Preferred mode: unspecified
ModemManager[3656]: <debug> [1369765980.510737] [mm-iface-modem-simple.c:809] connect_auth_ready():    Bands: unknown
ModemManager[3656]: <debug> [1369765980.510793] [mm-iface-modem-simple.c:814] connect_auth_ready():    Operator ID: unspecified
ModemManager[3656]: <debug> [1369765980.510852] [mm-iface-modem-simple.c:816] connect_auth_ready():    Allowed roaming: yes
ModemManager[3656]: <debug> [1369765980.510912] [mm-iface-modem-simple.c:818] connect_auth_ready():    APN: internet.netcom.no
ModemManager[3656]: <debug> [1369765980.510970] [mm-iface-modem-simple.c:824] connect_auth_ready():    IP family: unspecified
ModemManager[3656]: <debug> [1369765980.511068] [mm-iface-modem-simple.c:832] connect_auth_ready():    Allowed authentication: unspecified
ModemManager[3656]: <debug> [1369765980.511125] [mm-iface-modem-simple.c:835] connect_auth_ready():    User: unspecified
ModemManager[3656]: <debug> [1369765980.511181] [mm-iface-modem-simple.c:837] connect_auth_ready():    Password: unspecified
ModemManager[3656]: <debug> [1369765980.512110] [mm-iface-modem-simple.c:839] connect_auth_ready():    Number: unspecified
ModemManager[3656]: <info>  [1369765980.512172] [mm-iface-modem-simple.c:552] connection_step(): Simple connect state (4/10): Wait to get fully enabled
ModemManager[3656]: <info>  [1369765980.512292] [mm-iface-modem-simple.c:564] connection_step(): Simple connect state (5/10): Allowed mode
ModemManager[3656]: <info>  [1369765980.512349] [mm-iface-modem-simple.c:587] connection_step(): Simple connect state (6/10): Bands
ModemManager[3656]: <info>  [1369765980.512400] [mm-iface-modem-simple.c:616] connection_step(): Simple connect state (7/10): Register
ModemManager[3656]: <debug> [1369765980.512492] [mm-iface-modem-3gpp.c:384] mm_iface_modem_3gpp_register_in_network(): Already registered in network '24202', automatic registration not launched...
ModemManager[3656]: <info>  [1369765980.512614] [mm-iface-modem-simple.c:639] connection_step(): Simple connect state (8/10): Bearer
ModemManager[3656]: <debug> [1369765980.512682] [mm-iface-modem-simple.c:659] connection_step(): Creating new bearer...
ModemManager[3656]: <debug> [1369765980.512760] [infineon/mm-broadband-modem-infineon.c:85] modem_create_bearer(): Creating Infineon bearer...
ModemManager[3656]: <debug> [1369765980.514606] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765980.514865] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765980.515252] [infineon/mm-broadband-modem-infineon.c:48] modem_create_bearer_finish(): New Infineon bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[3656]: <info>  [1369765980.515525] [mm-iface-modem-simple.c:721] connection_step(): Simple connect state (9/10): Connect
ModemManager[3656]: <debug> [1369765980.515739] [mm-bearer.c:586] mm_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[3656]: <info>  [1369765980.515968] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
ModemManager[3656]: <debug> [1369765980.518640] [mm-broadband-bearer.c:173] detailed_connect_context_new(): No specific IP family requested, defaulting to ipv4
ModemManager[3656]: <debug> [1369765980.518932] [mm-broadband-bearer.c:1015] connect_3gpp(): Looking for best CID...
ModemManager[3656]: <debug> [1369765980.519157] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369765980.519435] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGDCONT?<CR>'
ModemManager[3656]: <debug> [1369765980.535406] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CGDCONT: 1,"IP","internet.netcom.no","46.15.15.235",0,0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369765980.535844] [mm-broadband-bearer.c:939] parse_pdp_list(): Found '1' PDP contexts
ModemManager[3656]: <debug> [1369765980.536057] [mm-broadband-bearer.c:946] parse_pdp_list():   PDP context [cid=1] [type='ipv4'] [apn='internet.netcom.no']
ModemManager[3656]: <debug> [1369765980.536253] [mm-broadband-bearer.c:967] parse_pdp_list(): Found PDP context with CID 1 and PDP type ipv4 for APN 'internet.netcom.no'
ModemManager[3656]: <debug> [1369765980.536493] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369765980.536729] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369765980.536976] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGDCONT=1,"IP","internet.netcom.no"<CR>'
ModemManager[3656]: <debug> [1369765980.583173] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 148<CR><LF>'
ModemManager[3656]: <debug> [1369765980.583610] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 148: Unspecified GPRS error
ModemManager[3656]: <warn>  [1369765980.583940] [mm-broadband-bearer.c:741] initialize_pdp_context_ready(): Couldn't initialize PDP context with our APN: 'Unspecified GPRS error'
ModemManager[3656]: <debug> [1369765980.584241] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369765980.584643] [mm-bearer.c:461] connect_ready(): Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/0': 'Unspecified GPRS error'
ModemManager[3656]: <info>  [1369765980.584987] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> registered)
ModemManager[3656]: <debug> [1369765980.589040] [mm-iface-modem-simple.c:228] connect_bearer_ready(): Couldn't connect bearer: 'Unspecified GPRS error'
ModemManager[3656]: <debug> [1369766009.854002] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766009.854659] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766009.855273] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766009.868397] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 14,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766009.869665] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766009.871059] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (45)
ModemManager[3656]: <debug> [1369766039.853855] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766039.854266] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766039.854687] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766039.921557] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 14,1<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766039.922849] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766039.924282] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (45)
ModemManager[3656]: <debug> [1369766069.854821] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766069.857574] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766069.858502] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766069.873172] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 14,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766069.874059] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766069.874832] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (45)
ModemManager[3656]: <debug> [1369766099.826747] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766099.829309] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766099.829983] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766099.844590] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 16,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766099.845070] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766099.845554] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (51)
ModemManager[3656]: <debug> [1369766129.854225] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766129.854971] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766129.855748] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766129.921413] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 16,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766129.922213] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766129.923111] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (51)
ModemManager[3656]: <info>  [1369766141.614980] [mm-iface-modem-simple.c:779] connect_auth_ready(): Simple connect started...
ModemManager[3656]: <debug> [1369766141.615161] [mm-iface-modem-simple.c:793] connect_auth_ready():    PIN: unspecified
ModemManager[3656]: <debug> [1369766141.615221] [mm-iface-modem-simple.c:803] connect_auth_ready():    Allowed mode: unspecified
ModemManager[3656]: <debug> [1369766141.615270] [mm-iface-modem-simple.c:804] connect_auth_ready():    Preferred mode: unspecified
ModemManager[3656]: <debug> [1369766141.615328] [mm-iface-modem-simple.c:809] connect_auth_ready():    Bands: unknown
ModemManager[3656]: <debug> [1369766141.615378] [mm-iface-modem-simple.c:814] connect_auth_ready():    Operator ID: unspecified
ModemManager[3656]: <debug> [1369766141.615430] [mm-iface-modem-simple.c:816] connect_auth_ready():    Allowed roaming: yes
ModemManager[3656]: <debug> [1369766141.615481] [mm-iface-modem-simple.c:818] connect_auth_ready():    APN: internet.netcom.no
ModemManager[3656]: <debug> [1369766141.615532] [mm-iface-modem-simple.c:824] connect_auth_ready():    IP family: unspecified
ModemManager[3656]: <debug> [1369766141.615582] [mm-iface-modem-simple.c:832] connect_auth_ready():    Allowed authentication: unspecified
ModemManager[3656]: <debug> [1369766141.615634] [mm-iface-modem-simple.c:835] connect_auth_ready():    User: unspecified
ModemManager[3656]: <debug> [1369766141.615686] [mm-iface-modem-simple.c:837] connect_auth_ready():    Password: unspecified
ModemManager[3656]: <debug> [1369766141.616759] [mm-iface-modem-simple.c:839] connect_auth_ready():    Number: unspecified
ModemManager[3656]: <info>  [1369766141.616824] [mm-iface-modem-simple.c:552] connection_step(): Simple connect state (4/10): Wait to get fully enabled
ModemManager[3656]: <info>  [1369766141.616946] [mm-iface-modem-simple.c:564] connection_step(): Simple connect state (5/10): Allowed mode
ModemManager[3656]: <info>  [1369766141.617005] [mm-iface-modem-simple.c:587] connection_step(): Simple connect state (6/10): Bands
ModemManager[3656]: <info>  [1369766141.617058] [mm-iface-modem-simple.c:616] connection_step(): Simple connect state (7/10): Register
ModemManager[3656]: <debug> [1369766141.617151] [mm-iface-modem-3gpp.c:384] mm_iface_modem_3gpp_register_in_network(): Already registered in network '24202', automatic registration not launched...
ModemManager[3656]: <info>  [1369766141.617274] [mm-iface-modem-simple.c:639] connection_step(): Simple connect state (8/10): Bearer
ModemManager[3656]: <debug> [1369766141.617352] [mm-iface-modem-simple.c:712] connection_step(): Using already existing bearer at '/org/freedesktop/ModemManager1/Bearer/0'...
ModemManager[3656]: <info>  [1369766141.617409] [mm-iface-modem-simple.c:721] connection_step(): Simple connect state (9/10): Connect
ModemManager[3656]: <debug> [1369766141.617504] [mm-bearer.c:586] mm_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[3656]: <info>  [1369766141.617606] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
ModemManager[3656]: <debug> [1369766141.618938] [mm-broadband-bearer.c:173] detailed_connect_context_new(): No specific IP family requested, defaulting to ipv4
ModemManager[3656]: <debug> [1369766141.619007] [mm-broadband-bearer.c:1015] connect_3gpp(): Looking for best CID...
ModemManager[3656]: <debug> [1369766141.619086] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766141.619217] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGDCONT?<CR>'
ModemManager[3656]: <debug> [1369766141.637789] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CGDCONT: 1,"IP","internet.netcom.no","46.15.15.235",0,0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766141.638229] [mm-broadband-bearer.c:939] parse_pdp_list(): Found '1' PDP contexts
ModemManager[3656]: <debug> [1369766141.638452] [mm-broadband-bearer.c:946] parse_pdp_list():   PDP context [cid=1] [type='ipv4'] [apn='internet.netcom.no']
ModemManager[3656]: <debug> [1369766141.638659] [mm-broadband-bearer.c:967] parse_pdp_list(): Found PDP context with CID 1 and PDP type ipv4 for APN 'internet.netcom.no'
ModemManager[3656]: <debug> [1369766141.638917] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766141.639176] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766141.639440] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGDCONT=1,"IP","internet.netcom.no"<CR>'
ModemManager[3656]: <debug> [1369766141.687780] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 148<CR><LF>'
ModemManager[3656]: <debug> [1369766141.688322] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 148: Unspecified GPRS error
ModemManager[3656]: <warn>  [1369766141.688616] [mm-broadband-bearer.c:741] initialize_pdp_context_ready(): Couldn't initialize PDP context with our APN: 'Unspecified GPRS error'
ModemManager[3656]: <debug> [1369766141.688867] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369766141.689379] [mm-bearer.c:461] connect_ready(): Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/0': 'Unspecified GPRS error'
ModemManager[3656]: <info>  [1369766141.689760] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> registered)
ModemManager[3656]: <debug> [1369766141.700408] [mm-iface-modem-simple.c:228] connect_bearer_ready(): Couldn't connect bearer: 'Unspecified GPRS error'
ModemManager[3656]: <debug> [1369766159.836106] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766159.837543] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766159.839140] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766159.868012] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 16,3<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766159.878733] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766159.881040] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (51)
ModemManager[3656]: <info>  [1369766162.942813] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> disabling)
ModemManager[3656]: <info>  [1369766162.944506] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (0)
ModemManager[3656]: <debug> [1369766162.944656] [mm-iface-modem.c:1093] periodic_signal_quality_check_disable(): Periodic signal quality checks disabled
ModemManager[3656]: <info>  [1369766162.944832] [mm-iface-modem.c:726] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (hsdpa -> unknown)
ModemManager[3656]: <debug> [1369766162.944931] [mm-iface-modem.c:816] periodic_access_technologies_check_disable(): Periodic access technology checks disabled
ModemManager[3656]: <debug> [1369766162.946111] [mm-broadband-modem.c:8020] disabling_step(): Modem has messaging capabilities, disabling the Messaging interface...
ModemManager[3656]: <debug> [1369766162.946552] [mm-broadband-modem.c:5575] set_messaging_unsolicited_events_handlers(): (ttyACM0) Removing messaging unsolicited events handlers
ModemManager[3656]: <debug> [1369766162.947108] [mm-broadband-modem.c:8032] disabling_step(): Modem has location capabilities, disabling the Location interface...
ModemManager[3656]: <debug> [1369766162.947416] [mm-iface-modem-location.c:745] setup_gathering(): Need to disable the following location sources: '3gpp-lac-ci'
ModemManager[3656]: <debug> [1369766162.947697] [mm-iface-modem-location.c:660] setup_gathering_step(): Disabled location '3gpp-lac-ci' gathering...
ModemManager[3656]: <debug> [1369766162.948206] [mm-broadband-modem.c:8060] disabling_step(): Modem has 3GPP/USSD capabilities, disabling the Modem 3GPP/USSD interface...
ModemManager[3656]: <debug> [1369766162.948491] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766162.948806] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CUSD=0<CR>'
ModemManager[3656]: <debug> [1369766162.962149] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766162.962518] [mm-broadband-modem.c:4692] set_unsolicited_result_code_handlers(): (ttyACM0) Removing unsolicited result code handlers
ModemManager[3656]: <debug> [1369766162.962762] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369766162.963376] [mm-broadband-modem.c:8072] disabling_step(): Modem has 3GPP capabilities, disabling the Modem 3GPP interface...
ModemManager[3656]: <debug> [1369766162.963763] [mm-iface-modem-3gpp.c:1230] periodic_registration_check_disable(): Periodic 3GPP registration checks disabled
ModemManager[3656]: <debug> [1369766162.964059] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766162.964372] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CREG=0<CR>'
ModemManager[3656]: <debug> [1369766162.977385] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766162.977718] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766162.977978] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766162.978234] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGREG=0<CR>'
ModemManager[3656]: <debug> [1369766162.992642] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766162.992976] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369766162.993618] [mm-broadband-modem.c:3432] modem_3gpp_cleanup_unsolicited_registration_events(): (ttyACM0) cleaning up unsolicited registration messages handlers
ModemManager[3656]: <info>  [1369766162.994027] [mm-iface-modem-3gpp.c:1105] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (home -> unknown)
ModemManager[3656]: <debug> [1369766162.994555] [mm-bearer.c:261] modem_3gpp_registration_state_changed(): Bearer not allowed to connect, not registered in 3GPP network
ModemManager[3656]: <debug> [1369766162.995611] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
ModemManager[3656]: <debug> [1369766162.995890] [mm-serial-port.c:531] mm_serial_port_close(): (ttyACM0) closing serial port...
ModemManager[3656]: <debug> [1369766162.998300] [mm-serial-port.c:558] mm_serial_port_close(): (ttyACM0) serial port closed
ModemManager[3656]: <info>  [1369766162.998581] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabling -> disabled)
ModemManager[3656]: <info>  [1369766179.637438] [mm-iface-modem-simple.c:779] connect_auth_ready(): Simple connect started...
ModemManager[3656]: <debug> [1369766179.637750] [mm-iface-modem-simple.c:793] connect_auth_ready():    PIN: unspecified
ModemManager[3656]: <debug> [1369766179.637907] [mm-iface-modem-simple.c:803] connect_auth_ready():    Allowed mode: unspecified
ModemManager[3656]: <debug> [1369766179.638040] [mm-iface-modem-simple.c:804] connect_auth_ready():    Preferred mode: unspecified
ModemManager[3656]: <debug> [1369766179.638181] [mm-iface-modem-simple.c:809] connect_auth_ready():    Bands: unknown
ModemManager[3656]: <debug> [1369766179.638317] [mm-iface-modem-simple.c:814] connect_auth_ready():    Operator ID: unspecified
ModemManager[3656]: <debug> [1369766179.638445] [mm-iface-modem-simple.c:816] connect_auth_ready():    Allowed roaming: yes
ModemManager[3656]: <debug> [1369766179.638573] [mm-iface-modem-simple.c:818] connect_auth_ready():    APN: internet.netcom.no
ModemManager[3656]: <debug> [1369766179.638699] [mm-iface-modem-simple.c:824] connect_auth_ready():    IP family: unspecified
ModemManager[3656]: <debug> [1369766179.638834] [mm-iface-modem-simple.c:832] connect_auth_ready():    Allowed authentication: unspecified
ModemManager[3656]: <debug> [1369766179.638975] [mm-iface-modem-simple.c:835] connect_auth_ready():    User: unspecified
ModemManager[3656]: <debug> [1369766179.639139] [mm-iface-modem-simple.c:837] connect_auth_ready():    Password: unspecified
ModemManager[3656]: <debug> [1369766179.639299] [mm-iface-modem-simple.c:839] connect_auth_ready():    Number: unspecified
ModemManager[3656]: <info>  [1369766179.639432] [mm-iface-modem-simple.c:544] connection_step(): Simple connect state (3/10): Enable
ModemManager[3656]: <info>  [1369766179.639688] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
ModemManager[3656]: <debug> [1369766179.640654] [mm-broadband-modem.c:7763] enabling_started(): Skipping modem initialization: not first enabling
ModemManager[3656]: <debug> [1369766179.641019] [mm-serial-port.c:413] mm_serial_port_open(): (ttyACM0) opening serial port...
ModemManager[3656]: (mm-command-serial-port.c:526):channel_enable: runtime check failed: (self->priv->channel == NULL)
ModemManager[3656]: (mm-command-serial-port.c:530):channel_enable: runtime check failed: (self->priv->watch_id == 0)
ModemManager[3656]: <debug> [1369766179.645192] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
ModemManager[3656]: <debug> [1369766179.645379] [mm-at-serial-port.c:448] mm_at_serial_port_run_init_sequence(): (ttyACM0): running init sequence...
ModemManager[3656]: <debug> [1369766179.645577] [mm-broadband-modem.c:7782] enabling_started(): Flashing primary AT port before enabling...
ModemManager[3656]: <debug> [1369766179.646443] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATE0<CR>'
ModemManager[3656]: <debug> [1369766179.748605] [mm-iface-modem.c:2903] mm_iface_modem_set_power_state(): No need to change power state: already in 'on' power state
ModemManager[3656]: <debug> [1369766179.748863] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766179.749215] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.749491] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766179.749676] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATV1<CR>'
ModemManager[3656]: <debug> [1369766179.756882] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.757112] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
ModemManager[3656]: <debug> [1369766179.770396] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.770718] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'ATX4<CR>'
ModemManager[3656]: <debug> [1369766179.778260] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.778509] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT&C1<CR>'
ModemManager[3656]: <debug> [1369766179.787137] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.787368] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+IFC=1,1<CR>'
ModemManager[3656]: <debug> [1369766179.802137] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
ModemManager[3656]: <debug> [1369766179.802329] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
ModemManager[3656]: <debug> [1369766179.802469] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766179.802797] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766179.802972] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766179.803132] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSCS="UCS2"<CR>'
ModemManager[3656]: <debug> [1369766179.821888] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.822102] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766179.822264] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766179.822433] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSCS?<CR>'
ModemManager[3656]: <debug> [1369766179.834649] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.835046] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369766179.836105] [mm-broadband-modem.c:8326] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
ModemManager[3656]: <debug> [1369766179.836711] [mm-broadband-modem.c:3378] modem_3gpp_setup_unsolicited_registration_events(): (ttyACM0) setting up 3GPP unsolicited registration messages handlers
ModemManager[3656]: <debug> [1369766179.836972] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766179.837174] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CREG=2<CR>'
ModemManager[3656]: <debug> [1369766179.849886] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.860920] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766179.861110] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766179.861307] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGREG=2<CR>'
ModemManager[3656]: <debug> [1369766179.875513] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.875758] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369766179.875952] [mm-iface-modem-3gpp.c:745] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
ModemManager[3656]: <debug> [1369766179.876104] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766179.876294] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
ModemManager[3656]: <debug> [1369766179.888390] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"10D1","012CE305",4<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <info>  [1369766179.888611] [mm-iface-modem-3gpp.c:1090] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering)
ModemManager[3656]: <debug> [1369766179.888747] [mm-broadband-modem.c:3250] modem_3gpp_load_operator_code(): loading Operator Code...
ModemManager[3656]: <debug> [1369766179.888896] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <info>  [1369766179.889144] [mm-iface-modem.c:726] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> hsdpa)
ModemManager[3656]: <debug> [1369766179.889378] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
ModemManager[3656]: <debug> [1369766179.889532] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
ModemManager[3656]: <debug> [1369766179.890571] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
ModemManager[3656]: <debug> [1369766179.914148] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,2,"24202",2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.914420] [mm-broadband-modem.c:3240] modem_3gpp_load_operator_code_finish(): loaded Operator Code: 24202
ModemManager[3656]: <debug> [1369766179.914648] [mm-broadband-modem.c:3286] modem_3gpp_load_operator_name(): loading Operator Name...
ModemManager[3656]: <debug> [1369766179.914794] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
ModemManager[3656]: <debug> [1369766179.914950] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
ModemManager[3656]: <debug> [1369766179.915894] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
ModemManager[3656]: <debug> [1369766179.929143] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 2,1,"10D1","012CE305",4,"E6"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.929487] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766179.929717] [mm-broadband-modem.c:8339] enabling_step(): Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface...
ModemManager[3656]: <debug> [1369766179.929878] [mm-broadband-modem.c:4692] set_unsolicited_result_code_handlers(): (ttyACM0) Setting unsolicited result code handlers
ModemManager[3656]: <debug> [1369766179.930079] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766179.930261] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
ModemManager[3656]: <debug> [1369766179.953893] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,0,"N NetCom",2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.954176] [mm-broadband-modem.c:3276] modem_3gpp_load_operator_name_finish(): loaded Operator Name: N NetCom
ModemManager[3656]: <debug> [1369766179.954389] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <info>  [1369766179.955300] [mm-iface-modem-3gpp.c:1023] update_registration_reload_current_operator_ready(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home)
ModemManager[3656]: <debug> [1369766179.956635] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369766179.957492] [mm-broadband-modem.c:8367] enabling_step(): Modem has location capabilities, enabling the Location interface...
ModemManager[3656]: <debug> [1369766179.957670] [mm-iface-modem-location.c:739] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
ModemManager[3656]: <debug> [1369766179.957895] [mm-iface-modem-3gpp.c:745] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
ModemManager[3656]: <debug> [1369766179.958063] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766179.958233] [mm-broadband-modem.c:3250] modem_3gpp_load_operator_code(): loading Operator Code...
ModemManager[3656]: <debug> [1369766179.958375] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766179.959290] [mm-broadband-modem.c:8380] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
ModemManager[3656]: <debug> [1369766179.959568] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
ModemManager[3656]: <debug> [1369766179.959777] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
ModemManager[3656]: <debug> [1369766179.972146] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"10D1","012CE305",4<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <info>  [1369766179.972405] [mm-iface-modem-location.c:293] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC: '0', Location area code: '10D1', Cell ID: '12CE305')
ModemManager[3656]: <debug> [1369766179.972641] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 5 (open)
ModemManager[3656]: <debug> [1369766179.972798] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 4 (close)
ModemManager[3656]: <debug> [1369766179.972981] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
ModemManager[3656]: <debug> [1369766179.996018] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,2,"24202",2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766179.996257] [mm-broadband-modem.c:3240] modem_3gpp_load_operator_code_finish(): loaded Operator Code: 24202
ModemManager[3656]: <info>  [1369766179.996458] [mm-iface-modem-location.c:293] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '242', MNC: '2', Location area code: '10D1', Cell ID: '12CE305')
ModemManager[3656]: <debug> [1369766179.996582] [mm-broadband-modem.c:3286] modem_3gpp_load_operator_name(): loading Operator Name...
ModemManager[3656]: <debug> [1369766179.996725] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 5 (open)
ModemManager[3656]: <debug> [1369766179.996878] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 4 (close)
ModemManager[3656]: <debug> [1369766179.997084] [mm-broadband-modem.c:5269] cmgf_set_ready(): Successfully set preferred SMS mode: 'PDU'
ModemManager[3656]: <debug> [1369766179.997310] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 5 (open)
ModemManager[3656]: <debug> [1369766179.997466] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 4 (close)
ModemManager[3656]: <debug> [1369766179.997626] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
ModemManager[3656]: <debug> [1369766180.010516] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 2,1,"10D1","012CE305",4,"E6"<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.010861] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
ModemManager[3656]: <debug> [1369766180.011072] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
ModemManager[3656]: <debug> [1369766180.034388] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,0,"N NetCom",2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.034680] [mm-broadband-modem.c:3276] modem_3gpp_load_operator_name_finish(): loaded Operator Name: N NetCom
ModemManager[3656]: <debug> [1369766180.034876] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766180.035086] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPMS="","ME","ME"<CR>'
ModemManager[3656]: <debug> [1369766180.060762] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,8,0,8,0,8<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.060970] [mm-broadband-modem.c:5967] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'bm'
ModemManager[3656]: <debug> [1369766180.061094] [mm-broadband-modem.c:5163] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (BM), mem2 (none)...
ModemManager[3656]: <debug> [1369766180.061258] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766180.061461] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766180.061635] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPMS="BM"<CR>'
ModemManager[3656]: <debug> [1369766180.078264] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,8,0,8,0,8<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.078484] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766180.078648] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766180.078819] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
ModemManager[3656]: <debug> [1369766180.092138] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.092390] [mm-broadband-modem.c:5967] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'me'
ModemManager[3656]: <debug> [1369766180.092515] [mm-broadband-modem.c:5163] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (ME), mem2 (none)...
ModemManager[3656]: <debug> [1369766180.092666] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766180.092832] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766180.093008] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPMS="ME"<CR>'
ModemManager[3656]: <debug> [1369766180.110137] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,8,0,8,0,8<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.110362] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766180.110523] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766180.110696] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
ModemManager[3656]: <debug> [1369766180.124009] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.124242] [mm-broadband-modem.c:5967] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sm'
ModemManager[3656]: <debug> [1369766180.124362] [mm-broadband-modem.c:5163] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SM), mem2 (none)...
ModemManager[3656]: <debug> [1369766180.124542] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766180.124707] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766180.124880] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPMS="SM"<CR>'
ModemManager[3656]: <debug> [1369766180.142393] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,20,0,8,0,8<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.142614] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766180.142777] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766180.142952] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
ModemManager[3656]: <debug> [1369766180.162779] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.163012] [mm-broadband-modem.c:5967] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sr'
ModemManager[3656]: <debug> [1369766180.163133] [mm-broadband-modem.c:5163] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SR), mem2 (none)...
ModemManager[3656]: <debug> [1369766180.163280] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766180.163442] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766180.163646] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CPMS="SR"<CR>'
ModemManager[3656]: <debug> [1369766180.180018] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,8,0,8,0,8<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.180267] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766180.180482] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766180.180692] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
ModemManager[3656]: <debug> [1369766180.193917] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.194267] [mm-broadband-modem.c:5575] set_messaging_unsolicited_events_handlers(): (ttyACM0) Setting messaging unsolicited events handlers
ModemManager[3656]: <debug> [1369766180.194459] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369766180.194718] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766180.194981] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CNMI=2,1,2,1,0<CR>'
ModemManager[3656]: <debug> [1369766180.217400] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.217744] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766180.217989] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
ModemManager[3656]: <debug> [1369766180.219742] [mm-iface-modem.c:1127] periodic_signal_quality_check_enable(): Periodic signal quality checks enabled (interval = 3s)
ModemManager[3656]: <debug> [1369766180.219989] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766180.220211] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766180.220444] [mm-iface-modem.c:844] periodic_access_technologies_check_enable(): Periodic access technology checks enabled
ModemManager[3656]: <info>  [1369766180.220693] [mm-iface-modem-simple.c:552] connection_step(): Simple connect state (4/10): Wait to get fully enabled
ModemManager[3656]: <info>  [1369766180.220914] [mm-iface-modem-simple.c:564] connection_step(): Simple connect state (5/10): Allowed mode
ModemManager[3656]: <info>  [1369766180.221031] [mm-iface-modem-simple.c:587] connection_step(): Simple connect state (6/10): Bands
ModemManager[3656]: <info>  [1369766180.221161] [mm-iface-modem-simple.c:616] connection_step(): Simple connect state (7/10): Register
ModemManager[3656]: <debug> [1369766180.221363] [mm-iface-modem-3gpp.c:384] mm_iface_modem_3gpp_register_in_network(): Already registered in network '24202', automatic registration not launched...
ModemManager[3656]: <info>  [1369766180.221558] [mm-iface-modem-simple.c:639] connection_step(): Simple connect state (8/10): Bearer
ModemManager[3656]: <debug> [1369766180.221682] [mm-iface-modem-simple.c:712] connection_step(): Using already existing bearer at '/org/freedesktop/ModemManager1/Bearer/0'...
ModemManager[3656]: <info>  [1369766180.221796] [mm-iface-modem-simple.c:721] connection_step(): Simple connect state (9/10): Connect
ModemManager[3656]: <debug> [1369766180.221932] [mm-bearer.c:586] mm_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[3656]: <info>  [1369766180.222097] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
ModemManager[3656]: <debug> [1369766180.223746] [mm-broadband-bearer.c:173] detailed_connect_context_new(): No specific IP family requested, defaulting to ipv4
ModemManager[3656]: <debug> [1369766180.223908] [mm-broadband-bearer.c:1015] connect_3gpp(): Looking for best CID...
ModemManager[3656]: <debug> [1369766180.224084] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766180.224294] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766180.234024] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 17,1<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.234283] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <info>  [1369766180.234588] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (54)
ModemManager[3656]: <debug> [1369766180.234724] [mm-iface-modem.c:1046] signal_quality_check_ready(): Periodic signal quality checks rescheduled (interval = 30s)
ModemManager[3656]: <debug> [1369766180.235654] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGDCONT?<CR>'
ModemManager[3656]: <debug> [1369766180.251647] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CGDCONT: 1,"IP","internet.netcom.no","46.15.15.235",0,0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766180.251947] [mm-broadband-bearer.c:939] parse_pdp_list(): Found '1' PDP contexts
ModemManager[3656]: <debug> [1369766180.252068] [mm-broadband-bearer.c:946] parse_pdp_list():   PDP context [cid=1] [type='ipv4'] [apn='internet.netcom.no']
ModemManager[3656]: <debug> [1369766180.252188] [mm-broadband-bearer.c:967] parse_pdp_list(): Found PDP context with CID 1 and PDP type ipv4 for APN 'internet.netcom.no'
ModemManager[3656]: <debug> [1369766180.252361] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766180.252527] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766180.252713] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGDCONT=1,"IP","internet.netcom.no"<CR>'
ModemManager[3656]: <debug> [1369766180.299268] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 148<CR><LF>'
ModemManager[3656]: <debug> [1369766180.299465] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 148: Unspecified GPRS error
ModemManager[3656]: <warn>  [1369766180.299634] [mm-broadband-bearer.c:741] initialize_pdp_context_ready(): Couldn't initialize PDP context with our APN: 'Unspecified GPRS error'
ModemManager[3656]: <debug> [1369766180.299764] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369766180.300062] [mm-bearer.c:461] connect_ready(): Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/0': 'Unspecified GPRS error'
ModemManager[3656]: <info>  [1369766180.300229] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> registered)
ModemManager[3656]: <debug> [1369766180.302141] [mm-iface-modem-simple.c:228] connect_bearer_ready(): Couldn't connect bearer: 'Unspecified GPRS error'
ModemManager[3656]: <info>  [1369766188.752493] [mm-iface-modem-simple.c:779] connect_auth_ready(): Simple connect started...
ModemManager[3656]: <debug> [1369766188.752684] [mm-iface-modem-simple.c:793] connect_auth_ready():    PIN: unspecified
ModemManager[3656]: <debug> [1369766188.752743] [mm-iface-modem-simple.c:803] connect_auth_ready():    Allowed mode: unspecified
ModemManager[3656]: <debug> [1369766188.752793] [mm-iface-modem-simple.c:804] connect_auth_ready():    Preferred mode: unspecified
ModemManager[3656]: <debug> [1369766188.752851] [mm-iface-modem-simple.c:809] connect_auth_ready():    Bands: unknown
ModemManager[3656]: <debug> [1369766188.752903] [mm-iface-modem-simple.c:814] connect_auth_ready():    Operator ID: unspecified
ModemManager[3656]: <debug> [1369766188.752955] [mm-iface-modem-simple.c:816] connect_auth_ready():    Allowed roaming: yes
ModemManager[3656]: <debug> [1369766188.753007] [mm-iface-modem-simple.c:818] connect_auth_ready():    APN: internet.netcom.no
ModemManager[3656]: <debug> [1369766188.753058] [mm-iface-modem-simple.c:824] connect_auth_ready():    IP family: unspecified
ModemManager[3656]: <debug> [1369766188.753109] [mm-iface-modem-simple.c:832] connect_auth_ready():    Allowed authentication: unspecified
ModemManager[3656]: <debug> [1369766188.753159] [mm-iface-modem-simple.c:835] connect_auth_ready():    User: unspecified
ModemManager[3656]: <debug> [1369766188.753209] [mm-iface-modem-simple.c:837] connect_auth_ready():    Password: unspecified
ModemManager[3656]: <debug> [1369766188.754259] [mm-iface-modem-simple.c:839] connect_auth_ready():    Number: unspecified
ModemManager[3656]: <info>  [1369766188.754328] [mm-iface-modem-simple.c:552] connection_step(): Simple connect state (4/10): Wait to get fully enabled
ModemManager[3656]: <info>  [1369766188.754451] [mm-iface-modem-simple.c:564] connection_step(): Simple connect state (5/10): Allowed mode
ModemManager[3656]: <info>  [1369766188.754514] [mm-iface-modem-simple.c:587] connection_step(): Simple connect state (6/10): Bands
ModemManager[3656]: <info>  [1369766188.754566] [mm-iface-modem-simple.c:616] connection_step(): Simple connect state (7/10): Register
ModemManager[3656]: <debug> [1369766188.754657] [mm-iface-modem-3gpp.c:384] mm_iface_modem_3gpp_register_in_network(): Already registered in network '24202', automatic registration not launched...
ModemManager[3656]: <info>  [1369766188.754785] [mm-iface-modem-simple.c:639] connection_step(): Simple connect state (8/10): Bearer
ModemManager[3656]: <debug> [1369766188.754856] [mm-iface-modem-simple.c:712] connection_step(): Using already existing bearer at '/org/freedesktop/ModemManager1/Bearer/0'...
ModemManager[3656]: <info>  [1369766188.754911] [mm-iface-modem-simple.c:721] connection_step(): Simple connect state (9/10): Connect
ModemManager[3656]: <debug> [1369766188.754981] [mm-bearer.c:586] mm_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[3656]: <info>  [1369766188.755081] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
ModemManager[3656]: <debug> [1369766188.756410] [mm-broadband-bearer.c:173] detailed_connect_context_new(): No specific IP family requested, defaulting to ipv4
ModemManager[3656]: <debug> [1369766188.756511] [mm-broadband-bearer.c:1015] connect_3gpp(): Looking for best CID...
ModemManager[3656]: <debug> [1369766188.756589] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766188.756719] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGDCONT?<CR>'
ModemManager[3656]: <debug> [1369766188.775186] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CGDCONT: 1,"IP","internet.netcom.no","46.15.15.235",0,0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766188.775694] [mm-broadband-bearer.c:939] parse_pdp_list(): Found '1' PDP contexts
ModemManager[3656]: <debug> [1369766188.775959] [mm-broadband-bearer.c:946] parse_pdp_list():   PDP context [cid=1] [type='ipv4'] [apn='internet.netcom.no']
ModemManager[3656]: <debug> [1369766188.776200] [mm-broadband-bearer.c:967] parse_pdp_list(): Found PDP context with CID 1 and PDP type ipv4 for APN 'internet.netcom.no'
ModemManager[3656]: <debug> [1369766188.776500] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766188.776801] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766188.777216] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGDCONT=1,"IP","internet.netcom.no"<CR>'
ModemManager[3656]: <debug> [1369766188.829070] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 148<CR><LF>'
ModemManager[3656]: <debug> [1369766188.831058] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 148: Unspecified GPRS error
ModemManager[3656]: <warn>  [1369766188.832174] [mm-broadband-bearer.c:741] initialize_pdp_context_ready(): Couldn't initialize PDP context with our APN: 'Unspecified GPRS error'
ModemManager[3656]: <debug> [1369766188.833220] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369766188.834914] [mm-bearer.c:461] connect_ready(): Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/0': 'Unspecified GPRS error'
ModemManager[3656]: <info>  [1369766188.836183] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> registered)
ModemManager[3656]: <debug> [1369766188.851980] [mm-iface-modem-simple.c:228] connect_bearer_ready(): Couldn't connect bearer: 'Unspecified GPRS error'
ModemManager[3656]: <debug> [1369766210.844555] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766210.846031] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766210.847671] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766210.869696] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 16,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766210.874426] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766210.878568] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (51)
ModemManager[3656]: <debug> [1369766240.826578] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766240.827289] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766240.827908] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766240.841726] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 13,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766240.843159] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766240.844815] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (41)
ModemManager[3656]: <info>  [1369766248.812189] [mm-iface-modem-simple.c:779] connect_auth_ready(): Simple connect started...
ModemManager[3656]: <debug> [1369766248.812351] [mm-iface-modem-simple.c:793] connect_auth_ready():    PIN: unspecified
ModemManager[3656]: <debug> [1369766248.812411] [mm-iface-modem-simple.c:803] connect_auth_ready():    Allowed mode: unspecified
ModemManager[3656]: <debug> [1369766248.812462] [mm-iface-modem-simple.c:804] connect_auth_ready():    Preferred mode: unspecified
ModemManager[3656]: <debug> [1369766248.812522] [mm-iface-modem-simple.c:809] connect_auth_ready():    Bands: unknown
ModemManager[3656]: <debug> [1369766248.812573] [mm-iface-modem-simple.c:814] connect_auth_ready():    Operator ID: unspecified
ModemManager[3656]: <debug> [1369766248.812625] [mm-iface-modem-simple.c:816] connect_auth_ready():    Allowed roaming: yes
ModemManager[3656]: <debug> [1369766248.812675] [mm-iface-modem-simple.c:818] connect_auth_ready():    APN: internet.netcom.no
ModemManager[3656]: <debug> [1369766248.812725] [mm-iface-modem-simple.c:824] connect_auth_ready():    IP family: unspecified
ModemManager[3656]: <debug> [1369766248.812776] [mm-iface-modem-simple.c:832] connect_auth_ready():    Allowed authentication: unspecified
ModemManager[3656]: <debug> [1369766248.812826] [mm-iface-modem-simple.c:835] connect_auth_ready():    User: unspecified
ModemManager[3656]: <debug> [1369766248.812875] [mm-iface-modem-simple.c:837] connect_auth_ready():    Password: unspecified
ModemManager[3656]: <debug> [1369766248.813832] [mm-iface-modem-simple.c:839] connect_auth_ready():    Number: unspecified
ModemManager[3656]: <info>  [1369766248.813902] [mm-iface-modem-simple.c:552] connection_step(): Simple connect state (4/10): Wait to get fully enabled
ModemManager[3656]: <info>  [1369766248.814025] [mm-iface-modem-simple.c:564] connection_step(): Simple connect state (5/10): Allowed mode
ModemManager[3656]: <info>  [1369766248.814092] [mm-iface-modem-simple.c:587] connection_step(): Simple connect state (6/10): Bands
ModemManager[3656]: <info>  [1369766248.814145] [mm-iface-modem-simple.c:616] connection_step(): Simple connect state (7/10): Register
ModemManager[3656]: <debug> [1369766248.814235] [mm-iface-modem-3gpp.c:384] mm_iface_modem_3gpp_register_in_network(): Already registered in network '24202', automatic registration not launched...
ModemManager[3656]: <info>  [1369766248.814362] [mm-iface-modem-simple.c:639] connection_step(): Simple connect state (8/10): Bearer
ModemManager[3656]: <debug> [1369766248.814432] [mm-iface-modem-simple.c:712] connection_step(): Using already existing bearer at '/org/freedesktop/ModemManager1/Bearer/0'...
ModemManager[3656]: <info>  [1369766248.814487] [mm-iface-modem-simple.c:721] connection_step(): Simple connect state (9/10): Connect
ModemManager[3656]: <debug> [1369766248.814556] [mm-bearer.c:586] mm_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
ModemManager[3656]: <info>  [1369766248.814658] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
ModemManager[3656]: <debug> [1369766248.816014] [mm-broadband-bearer.c:173] detailed_connect_context_new(): No specific IP family requested, defaulting to ipv4
ModemManager[3656]: <debug> [1369766248.816083] [mm-broadband-bearer.c:1015] connect_3gpp(): Looking for best CID...
ModemManager[3656]: <debug> [1369766248.816164] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766248.816298] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGDCONT?<CR>'
ModemManager[3656]: <debug> [1369766248.835681] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CGDCONT: 1,"IP","internet.netcom.no","46.15.15.235",0,0<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766248.836260] [mm-broadband-bearer.c:939] parse_pdp_list(): Found '1' PDP contexts
ModemManager[3656]: <debug> [1369766248.836509] [mm-broadband-bearer.c:946] parse_pdp_list():   PDP context [cid=1] [type='ipv4'] [apn='internet.netcom.no']
ModemManager[3656]: <debug> [1369766248.836732] [mm-broadband-bearer.c:967] parse_pdp_list(): Found PDP context with CID 1 and PDP type ipv4 for APN 'internet.netcom.no'
ModemManager[3656]: <debug> [1369766248.837014] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
ModemManager[3656]: <debug> [1369766248.837299] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
ModemManager[3656]: <debug> [1369766248.837599] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CGDCONT=1,"IP","internet.netcom.no"<CR>'
ModemManager[3656]: <debug> [1369766248.887473] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 148<CR><LF>'
ModemManager[3656]: <debug> [1369766248.890200] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 148: Unspecified GPRS error
ModemManager[3656]: <warn>  [1369766248.891343] [mm-broadband-bearer.c:741] initialize_pdp_context_ready(): Couldn't initialize PDP context with our APN: 'Unspecified GPRS error'
ModemManager[3656]: <debug> [1369766248.892400] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <debug> [1369766248.894044] [mm-bearer.c:461] connect_ready(): Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/0': 'Unspecified GPRS error'
ModemManager[3656]: <info>  [1369766248.895346] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> registered)
ModemManager[3656]: <debug> [1369766248.911094] [mm-iface-modem-simple.c:228] connect_bearer_ready(): Couldn't connect bearer: 'Unspecified GPRS error'
ModemManager[3656]: <debug> [1369766270.844006] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766270.844691] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766270.845323] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766270.860667] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 12,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766270.861991] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766270.863430] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (38)
ModemManager[3656]: <debug> [1369766300.854859] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766300.856178] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766300.857781] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766300.920657] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 15,1<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766300.921147] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766300.921710] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (48)
ModemManager[3656]: <debug> [1369766330.855035] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766330.857002] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766330.875981] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766330.892044] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 15,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766330.894171] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766330.895579] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (48)
ModemManager[3656]: <debug> [1369766360.853853] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766360.854289] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766360.854788] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766360.874242] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 13,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766360.877546] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766360.881272] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (41)
ModemManager[3656]: <debug> [1369766389.399491] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 1,"10D1","012CFB86",4<CR><LF><CR><LF>+CGREG: 1,"10D1","012CFB86",4,"E6"<CR><LF>'
ModemManager[3656]: <info>  [1369766389.400517] [mm-iface-modem-location.c:293] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '242', MNC: '2', Location area code: '10D1', Cell ID: '12CFB86')
ModemManager[3656]: <debug> [1369766390.826843] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766390.828156] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766390.829746] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766390.860166] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 14,1<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766390.861143] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766390.862338] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (45)
ModemManager[3656]: <debug> [1369766420.854908] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766420.855684] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766420.856521] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766420.920531] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 13,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766420.921017] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766420.921576] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (41)
ModemManager[3656]: <debug> [1369766450.854546] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766450.874516] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766450.876230] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766450.890048] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 13,3<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766450.891083] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766450.892269] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (41)
ModemManager[3656]: <debug> [1369766475.551548] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 1,"10D1","012CE305",4<CR><LF><CR><LF>+CGREG: 1,"10D1","012CE305",4,"E6"<CR><LF>'
ModemManager[3656]: <info>  [1369766475.553983] [mm-iface-modem-location.c:293] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '242', MNC: '2', Location area code: '10D1', Cell ID: '12CE305')
ModemManager[3656]: <debug> [1369766480.830014] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766480.831268] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766480.832909] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766480.858166] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 15,1<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766480.860010] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766480.862302] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (48)
ModemManager[3656]: <debug> [1369766510.844950] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766510.846345] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766510.855683] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766510.920966] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 15,1<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766510.921531] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766510.922117] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (48)
ModemManager[3656]: <debug> [1369766540.853991] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766540.854658] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766540.855461] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766540.876069] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 12,3<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766540.877146] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766540.878395] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (38)
ModemManager[3656]: <debug> [1369766570.854878] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766570.856214] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766570.857836] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766570.921322] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 14,2<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766570.921878] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766570.922469] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (45)
ModemManager[3656]: <debug> [1369766600.854531] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
ModemManager[3656]: <debug> [1369766600.855992] [mm-serial-port.c:477] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
ModemManager[3656]: <debug> [1369766600.857927] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): --> 'AT+CSQ<CR>'
ModemManager[3656]: <debug> [1369766600.895943] [mm-at-serial-port.c:411] debug_log(): (ttyACM0): <-- '<CR><LF>+CSQ: 13,1<CR><LF><CR><LF>OK<CR><LF>'
ModemManager[3656]: <debug> [1369766600.896996] [mm-serial-port.c:523] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
ModemManager[3656]: <info>  [1369766600.898239] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (41)
