Re: [PATCH] Add udev rules for HP (Ericsson) hs2350 Mobile Broadband Module

[David Härdeman <david hardeman nu>]

On Sun, Oct 14, 2012 at 05:00:32PM +0200, Aleksander Morgado wrote:
>On 10/12/2012 09:53 PM, David Härdeman wrote:
>> I should perhaps add that I've tried using the modem on MM 0.5 and 0.6.
>> 
>> Both seem to have the same three problems:
>> 
>> a) Modem doesn't survive a suspend/resume (can't connect after)
>> 
>> b) USSD command don't work
>> 
>> c) Network operator name isn't shown
>> 
>> Haven't testing the bleeding edge of MM, but I need to be able to use
>> the laptop daily so I'm not really in a position to try that (right
>> now).
>
>Can you get ModemManager debug logs to see what is happening?

Attached. Sorry for the delay. The log should be useful for issues b)
and c). It doesn't include a suspend/resume, I'll have to send a
separate log for that later...

There are some annotations I've made myself in the log file, feel free
to ignore them...


-- 
David Härdeman

modem-manager[9576]: <info>  [1348869333.437820] [main.c:167] main(): ModemManager (version 0.5.2.0) starting...
modem-manager[9576]: <info>  [1348869333.442147] [mm-manager.c:120] load_plugin(): Loaded plugin Generic
modem-manager[9576]: <info>  [1348869333.442452] [mm-manager.c:120] load_plugin(): Loaded plugin Novatel
modem-manager[9576]: <info>  [1348869333.442756] [mm-manager.c:120] load_plugin(): Loaded plugin X22X
modem-manager[9576]: <info>  [1348869333.443020] [mm-manager.c:120] load_plugin(): Loaded plugin Ericsson MBM
modem-manager[9576]: <info>  [1348869333.443273] [mm-manager.c:120] load_plugin(): Loaded plugin Nokia
modem-manager[9576]: <info>  [1348869333.443524] [mm-manager.c:120] load_plugin(): Loaded plugin AnyData
modem-manager[9576]: <info>  [1348869333.443784] [mm-manager.c:120] load_plugin(): Loaded plugin Option High-Speed
modem-manager[9576]: <info>  [1348869333.444029] [mm-manager.c:120] load_plugin(): Loaded plugin Longcheer
modem-manager[9576]: <info>  [1348869333.444273] [mm-manager.c:120] load_plugin(): Loaded plugin SimTech
modem-manager[9576]: <info>  [1348869333.444515] [mm-manager.c:120] load_plugin(): Loaded plugin Linktop
modem-manager[9576]: <info>  [1348869333.444763] [mm-manager.c:120] load_plugin(): Loaded plugin ZTE
modem-manager[9576]: <info>  [1348869333.445014] [mm-manager.c:120] load_plugin(): Loaded plugin Huawei
modem-manager[9576]: <info>  [1348869333.445258] [mm-manager.c:120] load_plugin(): Loaded plugin Option
modem-manager[9576]: <info>  [1348869333.445500] [mm-manager.c:120] load_plugin(): Loaded plugin Sierra
modem-manager[9576]: <info>  [1348869333.445731] [mm-manager.c:120] load_plugin(): Loaded plugin Gobi
modem-manager[9576]: <info>  [1348869333.445976] [mm-manager.c:120] load_plugin(): Loaded plugin Wavecom
modem-manager[9576]: <info>  [1348869333.446219] [mm-manager.c:120] load_plugin(): Loaded plugin Samsung
modem-manager[9576]: <info>  [1348869333.446450] [mm-manager.c:120] load_plugin(): Loaded plugin MotoC
modem-manager[9576]: <debug> [1348869333.451384] [mm-manager.c:809] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted
modem-manager[9576]: <debug> [1348869333.451490] [mm-manager.c:809] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
modem-manager[9576]: <debug> [1348869333.451581] [mm-manager.c:809] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
modem-manager[9576]: <debug> [1348869333.451672] [mm-manager.c:793] device_added(): (tty/ttyS0): could not get port's parent device
modem-manager[9576]: <info>  [1348869333.454611] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM0) opening serial port...
modem-manager[9576]: <debug> [1348869333.455296] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
modem-manager[9576]: <debug> [1348869333.455351] [mm-plugin-base.c:859] try_open(): (ttyACM0): probe requested by plugin 'Ericsson MBM'
modem-manager[9576]: <info>  [1348869333.455842] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM1) opening serial port...
modem-manager[9576]: <debug> [1348869333.457650] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM1) device open count is 1 (open)
modem-manager[9576]: <debug> [1348869333.457703] [mm-plugin-base.c:859] try_open(): (ttyACM1): probe requested by plugin 'Ericsson MBM'
modem-manager[9576]: <info>  [1348869333.461362] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM2) opening serial port...
modem-manager[9576]: <debug> [1348869333.465528] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM2) device open count is 1 (open)
modem-manager[9576]: <debug> [1348869333.465582] [mm-plugin-base.c:859] try_open(): (ttyACM2): probe requested by plugin 'Ericsson MBM'
modem-manager[9576]: <debug> [1348869333.469914] [mm-modem-base.c:155] mm_modem_base_add_port(): (usb0) type ignored claimed by /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5
modem-manager[9576]: <info>  [1348869333.469959] [mm-manager.c:564] do_grab_port(): (Ericsson MBM): GSM modem /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5 claimed port usb0
modem-manager[9576]: <debug> [1348869333.469987] [mm-manager.c:317] add_modem(): Added modem /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5
modem-manager[9576]: <debug> [1348869333.470009] [mm-manager.c:243] check_export_modem(): (net/usb0): outstanding support task prevents export of /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5
modem-manager[9576]: <debug> [1348869333.470053] [mm-manager.c:243] check_export_modem(): (tty/ttyACM0): outstanding support task prevents export of /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5
modem-manager[9576]: <debug> [1348869333.557046] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+GCAP<CR>'
modem-manager[9576]: <debug> [1348869333.562044] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'AT+GCAP<CR>'
modem-manager[9576]: <debug> [1348869333.562567] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): --> 'AT+GCAP<CR>'
modem-manager[9576]: <debug> [1348869333.567549] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- 'AT+GCAP<CR>'
modem-manager[9576]: <debug> [1348869333.569143] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+GCAP: +CGSM, +DS<CR><LF>'
modem-manager[9576]: <debug> [1348869333.570150] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+GCAP: +CGSM, +DS<CR><LF>'
modem-manager[9576]: <debug> [1348869333.570649] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): --> 'AT+GCAP<CR>'
modem-manager[9576]: <debug> [1348869333.573782] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.573861] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
modem-manager[9576]: <info>  [1348869333.573877] [mm-serial-port.c:859] mm_serial_port_close(): (ttyACM0) closing serial port...
modem-manager[9576]: <info>  [1348869333.576426] [mm-serial-port.c:880] mm_serial_port_close(): (ttyACM0) serial port closed
modem-manager[9576]: <debug> [1348869333.576870] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyACM0) type primary claimed by /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5
modem-manager[9576]: <info>  [1348869333.577199] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM0) opening serial port...
modem-manager[9576]: <debug> [1348869333.577573] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
modem-manager[9576]: <debug> [1348869333.577641] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
modem-manager[9576]: <debug> [1348869333.577685] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
modem-manager[9576]: <info>  [1348869333.577845] [mm-manager.c:564] do_grab_port(): (Ericsson MBM): GSM modem /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5 claimed port ttyACM0
modem-manager[9576]: <debug> [1348869333.577945] [mm-manager.c:243] check_export_modem(): (tty/ttyACM1): outstanding support task prevents export of /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5
modem-manager[9576]: <debug> [1348869333.578023] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- 'AT+GCAP<CR>'
modem-manager[9576]: <debug> [1348869333.578087] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'ATE0<CR>'
modem-manager[9576]: <debug> [1348869333.580993] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.581061] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM1) device open count is 0 (close)
modem-manager[9576]: <info>  [1348869333.581074] [mm-serial-port.c:859] mm_serial_port_close(): (ttyACM1) closing serial port...
modem-manager[9576]: <info>  [1348869333.583542] [mm-serial-port.c:880] mm_serial_port_close(): (ttyACM1) serial port closed
modem-manager[9576]: <debug> [1348869333.584002] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyACM1) type secondary claimed by /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5
modem-manager[9576]: <info>  [1348869333.584367] [mm-manager.c:564] do_grab_port(): (Ericsson MBM): GSM modem /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5 claimed port ttyACM1
modem-manager[9576]: <debug> [1348869333.584460] [mm-manager.c:243] check_export_modem(): (tty/ttyACM2): outstanding support task prevents export of /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5
modem-manager[9576]: <debug> [1348869333.584698] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'ATE'
modem-manager[9576]: <debug> [1348869333.585105] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '0'
modem-manager[9576]: <debug> [1348869333.585471] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[9576]: <debug> [1348869333.586496] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- '<CR><LF>+GCAP: +CGSM, +DS<CR><LF>'
modem-manager[9576]: <debug> [1348869333.587570] [mm-at-serial-port.c:333] debug_log(): (ttyACM2): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.587658] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM2) device open count is 0 (close)
modem-manager[9576]: <info>  [1348869333.587689] [mm-serial-port.c:859] mm_serial_port_close(): (ttyACM2) closing serial port...
modem-manager[9576]: <info>  [1348869333.590167] [mm-serial-port.c:880] mm_serial_port_close(): (ttyACM2) serial port closed
modem-manager[9576]: <debug> [1348869333.590281] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.590695] [mm-modem-base.c:155] mm_modem_base_add_port(): (ttyACM2) type ignored claimed by /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5
modem-manager[9576]: <info>  [1348869333.591020] [mm-manager.c:564] do_grab_port(): (Ericsson MBM): GSM modem /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5 claimed port ttyACM2
modem-manager[9576]: <debug> [1348869333.591104] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+GMI<CR>'
modem-manager[9576]: <debug> [1348869333.598995] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>HP<CR><LF>'
modem-manager[9576]: <debug> [1348869333.599491] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.599610] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+GMM<CR>'
modem-manager[9576]: <debug> [1348869333.607891] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>HP hs2350 HSPA+ MobileBroadband<CR><LF>'
modem-manager[9576]: <debug> [1348869333.608387] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.608501] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+GMR<CR>'
modem-manager[9576]: <debug> [1348869333.617217] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>R1C08<CR><LF>'
modem-manager[9576]: <debug> [1348869333.617897] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.618008] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGMI<CR>'
modem-manager[9576]: <debug> [1348869333.627241] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>HP<CR><LF>'
modem-manager[9576]: <debug> [1348869333.627763] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.627871] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGMM<CR>'
modem-manager[9576]: <debug> [1348869333.637267] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>HP hs2350 HSPA+ MobileBroadband<CR><LF>'
modem-manager[9576]: <debug> [1348869333.637785] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.637898] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGMR<CR>'
modem-manager[9576]: <debug> [1348869333.647510] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>R1C08<CR><LF>'
modem-manager[9576]: <debug> [1348869333.648028] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.648137] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'ATI<CR>'
modem-manager[9576]: <debug> [1348869333.653142] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>HP hs2350 HSPA+ MobileBroadband<CR><LF>'
modem-manager[9576]: <debug> [1348869333.653639] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.653751] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'ATI1<CR>'
modem-manager[9576]: <debug> [1348869333.657969] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>*EMRDY: 1<CR><LF><CR><LF>*EMRDY: 1<CR><LF><CR><LF>*EMRDY: 1<CR><LF>'
modem-manager[9576]: <debug> [1348869333.660017] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>R1C08<CR><LF>'
modem-manager[9576]: <debug> [1348869333.660520] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.660631] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+GSN<CR>'
modem-manager[9576]: <debug> [1348869333.671768] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>ERROR<CR><LF>'
modem-manager[9576]: <debug> [1348869333.671853] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
modem-manager[9576]: <debug> [1348869333.671911] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGSN<CR>'
modem-manager[9576]: <debug> [1348869333.687652] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>359166040028355<CR><LF>'
modem-manager[9576]: <debug> [1348869333.688387] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.688552] [mm-modem-helpers.c:920] mm_create_device_identifier(): Device ID source '000003f000003d1dHPhs2350HSPA+MobileBroadbandR1C08359166040028355R1C08HPhs2350HSPA+MobileBroadbandHP'
modem-manager[9576]: <debug> [1348869333.688584] [mm-modem-helpers.c:921] mm_create_device_identifier(): Device ID '1d60314fe3b54453a539eddbbd3204742718f63c'
modem-manager[9576]: <debug> [1348869333.688638] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
modem-manager[9576]: <debug> [1348869333.688681] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'ATE0<CR>'
modem-manager[9576]: <debug> [1348869333.695143] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.695303] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
modem-manager[9576]: <debug> [1348869333.695342] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
modem-manager[9576]: <debug> [1348869333.707517] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.707616] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CPIN?<CR>'
modem-manager[9576]: <debug> [1348869333.718908] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CPIN: READY<CR><LF>'
modem-manager[9576]: <debug> [1348869333.719511] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.719621] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
modem-manager[9576]: <debug> [1348869333.719669] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
modem-manager[9576]: <debug> [1348869333.719729] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CRSM=176,12258,0,0,10<CR>'
modem-manager[9576]: <debug> [1348869333.770921] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"98942004006171602275"<CR><LF>'
modem-manager[9576]: <debug> [1348869333.773013] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869333.773114] [mm-serial-port.c:844] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
modem-manager[9576]: <info>  [1348869333.773150] [mm-serial-port.c:859] mm_serial_port_close(): (ttyACM0) closing serial port...
modem-manager[9576]: <info>  [1348869333.775693] [mm-serial-port.c:880] mm_serial_port_close(): (ttyACM0) serial port closed
modem-manager[9576]: <debug> [1348869333.775789] [mm-generic-gsm.c:507] get_iccid_done(): SIM ID source '89490240001617062257'
modem-manager[9576]: <debug> [1348869333.775816] [mm-generic-gsm.c:508] get_iccid_done(): SIM ID '5db4993d92a4b785d4be595f5da17ed8bf13097c'
modem-manager[9576]: <debug> [1348869333.776016] [mm-manager.c:265] check_export_modem(): Exported modem /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5 as /org/freedesktop/ModemManager/Modems/0
modem-manager[9576]: <debug> [1348869333.776106] [mm-manager.c:278] check_export_modem(): (/org/freedesktop/ModemManager/Modems/0): VID 0x03F0 PID 0x3D1D (usb)
modem-manager[9576]: <debug> [1348869333.776144] [mm-manager.c:279] check_export_modem(): (/org/freedesktop/ModemManager/Modems/0): data port is usb0
XXX - NETWORKMANAGER STARTED
modem-manager[9576]: <info>  [1348869431.078468] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM0) opening serial port...
modem-manager[9576]: <debug> [1348869431.079309] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
modem-manager[9576]: <info>  [1348869431.079473] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (disabled -> enabling)
modem-manager[9576]: <debug> [1348869431.079576] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT&F E0 V1 X4 &C1 +CMEE=1<CR>'
modem-manager[9576]: <debug> [1348869431.105256] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'AT&F E0 V1 X4 &C1 +CMEE=1'
modem-manager[9576]: <debug> [1348869431.105746] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR>'
modem-manager[9576]: <debug> [1348869431.111531] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.111616] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT*ENAP=0<CR>'
modem-manager[9576]: <debug> [1348869431.120747] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>*EMRDY: 1<CR><LF>'
modem-manager[9576]: <debug> [1348869431.127447] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 276<CR><LF>'
modem-manager[9576]: Invalid error code: 276
modem-manager[9576]: <debug> [1348869431.127548] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
modem-manager[9576]: <debug> [1348869431.127590] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CFUN=1<CR>'
modem-manager[9576]: <debug> [1348869431.156601] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <info>  [1348869431.156676] [mm-serial-port.c:741] mm_serial_port_open(): (ttyACM1) opening serial port...
modem-manager[9576]: <debug> [1348869431.157268] [mm-serial-port.c:810] mm_serial_port_open(): (ttyACM1) device open count is 1 (open)
modem-manager[9576]: <debug> [1348869431.157322] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT*ERINFO=1<CR>'
modem-manager[9576]: <debug> [1348869431.185014] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>*EMRDY: 1<CR><LF>'
modem-manager[9576]: <debug> [1348869431.186459] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.186594] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+IFC=1,1<CR>'
modem-manager[9576]: <debug> [1348869431.199194] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>ERROR<CR><LF>'
modem-manager[9576]: <debug> [1348869431.199299] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
modem-manager[9576]: <debug> [1348869431.199371] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CPMS="ME","ME","ME"<CR>'
modem-manager[9576]: <debug> [1348869431.229096] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,30,0,30,0,30<CR><LF>'
modem-manager[9576]: <debug> [1348869431.229840] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.229959] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CNMI=2,1,2,1,0<CR>'
modem-manager[9576]: <debug> [1348869431.251772] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.251857] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CMGF=?<CR>'
modem-manager[9576]: <debug> [1348869431.264314] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CMGF: (0)<CR><LF>'
modem-manager[9576]: <debug> [1348869431.264874] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.264953] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CUSD=1<CR>'
modem-manager[9576]: <debug> [1348869431.277729] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.277849] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND=?<CR>'
modem-manager[9576]: <debug> [1348869431.290854] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: ("battchg",(0-5)),("signal",(0-5)),("batterywarning",(0-1)),("chargerconnected",(0-1)),("service",(0-1)),("sounder",(0-1)),("message",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),("callsetup",(0-3)),("callheld",(0-1))<CR><LF>'
modem-manager[9576]: <debug> [1348869431.291626] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.291840] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CFUN?<CR>'
modem-manager[9576]: <debug> [1348869431.305599] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CFUN: 1<CR><LF>'
modem-manager[9576]: <debug> [1348869431.306735] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.306867] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CSCS=?<CR>'
modem-manager[9576]: <debug> [1348869431.318598] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CSCS: ('
modem-manager[9576]: <debug> [1348869431.319861] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '"GSM","IRA","8859-1","UTF-8","UCS2")<CR><LF>'
modem-manager[9576]: <debug> [1348869431.320372] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.320545] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CMGF=0<CR>'
modem-manager[9576]: <debug> [1348869431.333494] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <info>  [1348869431.333572] [mm-generic-gsm.c:1704] sms_set_format_cb(): (ttyACM0): using PDU mode for SMS
modem-manager[9576]: <debug> [1348869431.333635] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CMER=3,0,0,1<CR>'
modem-manager[9576]: <debug> [1348869431.353348] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.353457] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): --> 'AT+CMER=3,0,0,1<CR>'
modem-manager[9576]: <debug> [1348869431.353522] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CSCS="UTF-8"<CR>'
modem-manager[9576]: <debug> [1348869431.368750] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- 'AT+CMER=3,0,0,1'
modem-manager[9576]: <debug> [1348869431.369377] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR>'
modem-manager[9576]: <debug> [1348869431.371971] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.372080] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CSCS?<CR>'
modem-manager[9576]: <debug> [1348869431.372715] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.383317] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CSCS: "'
modem-manager[9576]: <debug> [1348869431.383570] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- 'UTF-8"<CR><LF>'
modem-manager[9576]: <debug> [1348869431.383986] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <info>  [1348869431.384246] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabling -> enabled)
modem-manager[9576]: <debug> [1348869431.384397] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CREG=2<CR>'
modem-manager[9576]: <debug> [1348869431.397554] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.397982] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869431.410761] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869431.411391] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.411641] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
modem-manager[9576]: <debug> [1348869431.423171] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869431.423258] [mm-generic-gsm.c:3007] mm_generic_gsm_set_reg_status(): CS registration state changed: 1
modem-manager[9576]: <info>  [1348869431.423448] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (enabled -> registered)
modem-manager[9576]: <debug> [1348869431.424365] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.424551] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGREG=2<CR>'
modem-manager[9576]: <debug> [1348869431.437785] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.437903] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): --> 'AT+CREG=2<CR>'
modem-manager[9576]: <debug> [1348869431.438009] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
modem-manager[9576]: <debug> [1348869431.438845] [mm-generic-gsm.c:5924] simple_connect(): (usb0): home_only => TRUE
modem-manager[9576]: <debug> [1348869431.438886] [mm-generic-gsm.c:5924] simple_connect(): (usb0): number => "*99#"
modem-manager[9576]: <debug> [1348869431.438910] [mm-generic-gsm.c:5924] simple_connect(): (usb0): allowed_auth => 63
modem-manager[9576]: <debug> [1348869431.438929] [mm-generic-gsm.c:5924] simple_connect(): (usb0): password => "tm"
modem-manager[9576]: <debug> [1348869431.438952] [mm-generic-gsm.c:5924] simple_connect(): (usb0): allowed_mode => 0
modem-manager[9576]: <debug> [1348869431.438990] [mm-generic-gsm.c:5924] simple_connect(): (usb0): username => "t-mobile"
modem-manager[9576]: <debug> [1348869431.439028] [mm-generic-gsm.c:5924] simple_connect(): (usb0): network_mode => 0
modem-manager[9576]: <debug> [1348869431.439063] [mm-generic-gsm.c:5924] simple_connect(): (usb0): apn => "internet.t-mobile"
modem-manager[9576]: <debug> [1348869431.439114] [mm-generic-gsm.c:5813] simple_state_machine(): (usb0): simple connect state 0
modem-manager[9576]: <debug> [1348869431.447395] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- 'AT+CREG=2'
modem-manager[9576]: <debug> [1348869431.447510] [mm-generic-gsm.c:5813] simple_state_machine(): (usb0): simple connect state 2
modem-manager[9576]: <debug> [1348869431.447997] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR>'
modem-manager[9576]: <debug> [1348869431.450490] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.450605] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): --> 'AT+CGREG=2<CR>'
modem-manager[9576]: <debug> [1348869431.460836] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- 'AT+CGREG=2'
modem-manager[9576]: <debug> [1348869431.461873] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR>'
modem-manager[9576]: <debug> [1348869431.462377] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,2,"26201",2 <CR><LF>'
YYY - OPERATOR NUMERIC = 26201
modem-manager[9576]: <debug> [1348869431.463501] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.464125] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.464363] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
modem-manager[9576]: <debug> [1348869431.585755] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,0," ",2 <CR><LF>'
modem-manager[9576]: <debug> [1348869431.586440] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.586633] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869431.599623] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869431.600331] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.600528] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
modem-manager[9576]: <debug> [1348869431.612275] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 2,1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869431.612391] [mm-generic-gsm.c:3007] mm_generic_gsm_set_reg_status(): PS registration state changed: 1
modem-manager[9576]: <debug> [1348869431.612748] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.612857] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
modem-manager[9576]: <debug> [1348869431.636588] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,2,"26201",2 <CR><LF>'
modem-manager[9576]: <debug> [1348869431.637334] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.637485] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
modem-manager[9576]: <debug> [1348869431.760660] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,0," ",2 <CR><LF>'
modem-manager[9576]: <debug> [1348869431.761357] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.761509] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869431.774838] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869431.775455] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.947922] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
modem-manager[9576]: <debug> [1348869431.959715] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869431.960341] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.960448] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
modem-manager[9576]: <debug> [1348869431.972342] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 2,1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869431.972819] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869431.972938] [mm-generic-gsm.c:5813] simple_state_machine(): (usb0): simple connect state 4
modem-manager[9576]: <debug> [1348869431.973047] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CGDCONT?<CR>'
modem-manager[9576]: <debug> [1348869432.002611] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGDCONT: 1,"IP","internet.t-mobile","0.0.0.0",0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869432.003224] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869432.003413] [mm-generic-gsm.c:5813] simple_state_machine(): (usb0): simple connect state 5
modem-manager[9576]: <info>  [1348869432.003612] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (registered -> connecting)
modem-manager[9576]: <debug> [1348869432.003828] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT*EIAAUW=1,1,"t-mobile","tm"<CR>'
modem-manager[9576]: <debug> [1348869432.062327] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869432.062440] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT*E2NAP=1<CR>'
modem-manager[9576]: <debug> [1348869432.077838] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869432.077943] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT*ENAP=1,1<CR>'
modem-manager[9576]: <debug> [1348869432.119360] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869432.180475] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>*E2NAP: 2<CR><LF>'
modem-manager[9576]: <debug> [1348869432.180547] [mm-modem-mbm.c:706] mbm_e2nap_received(): connecting
modem-manager[9576]: <debug> [1348869433.632211] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT*ENAP?<CR>'
modem-manager[9576]: <debug> [1348869433.646196] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>*ENAP:2,""<CR><LF>'
modem-manager[9576]: <debug> [1348869433.647446] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869434.345065] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>*E2NAP: 1<CR><LF>'
modem-manager[9576]: <debug> [1348869434.345128] [mm-modem-mbm.c:703] mbm_e2nap_received(): connected
modem-manager[9576]: <debug> [1348869434.345146] [mm-port.c:181] mm_port_set_connected(): (usb0): port now connected
modem-manager[9576]: <info>  [1348869434.345232] [mm-modem.c:746] mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state changed (connecting -> connected)
modem-manager[9576]: <debug> [1348869434.345288] [mm-generic-gsm.c:5813] simple_state_machine(): (usb0): simple connect state 6
modem-manager[9576]: <debug> [1348869434.347563] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>*ERINFO: 1,0,2<CR><LF>'
modem-manager[9576]: <debug> [1348869461.640016] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869461.653368] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869461.654089] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869474.618875] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 1,"43C8","00353B1B",2<CR><LF>'
modem-manager[9576]: <debug> [1348869474.619982] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 1,"43C8","00353B1B",2<CR><LF>'
modem-manager[9576]: <debug> [1348869474.620138] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CGREG: 1,"43C8","00353B1B",2<CR><LF>'
modem-manager[9576]: <debug> [1348869474.620740] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CREG: 1,"43C8","00353B1B",2<CR><LF>'
modem-manager[9576]: <debug> [1348869491.648002] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869491.661301] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869491.662402] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869497.338731] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869497.341023] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CGREG: 1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869497.341174] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869497.342623] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CREG: 1,"43C8","003541CB",2<CR><LF>'
XXX - CONNECTED
modem-manager[9576]: <debug> [1348869518.197254] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIMI<CR>'
YYY - REQUEST IMSI
modem-manager[9576]: <debug> [1348869518.207739] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>....<CR><LF>'
modem-manager[9576]: <debug> [1348869518.208429] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
YYY - IMSI REPLY
modem-manager[9576]: <debug> [1348869518.209582] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CRSM=176,28486,0,0,17<CR>'
YYY - RESTRICTED SIM ACCESS - READ BINARY, FILEID = 28486, P1,P2,P3
modem-manager[9576]: <debug> [1348869518.283359] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"00636F6E6773746172FFFFFFFFFFFFFFFF"<CR><LF>'
modem-manager[9576]: <debug> [1348869518.284116] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
YYY - RETURN BINARY DATA?
modem-manager[9576]: <debug> [1348869518.285361] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
YYY - GET INDICATORS
modem-manager[9576]: <debug> [1348869518.298455] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869518.299205] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
YYY - BATT=5/5, SIGNAL=5/5, SERVICE=0/1, SOUNDER=0/1, MESSAGE=1/1, 
YYY - +CIND: ("battchg",(0-5)),("signal",(0-5)),("batterywarning",(0-1)),("chargerconnected",(0-1)),("service",(0-1)),("sounder",(0-1)),("message",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),("callsetup",(0-3)),("callheld",(0-1))<CR><LF>'

modem-manager[9576]: <debug> [1348869518.301569] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
YYY - READ ALL SMS MESSAGES
modem-manager[9576]: <debug> [1348869518.315490] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
YYY - DONE

modem-manager[9576]: <debug> [1348869518.318685] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CUSD=2<CR>'
modem-manager[9576]: <debug> [1348869518.331095] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'

YYY - CANCEL ONGOING USSD


modem-manager[9576]: <debug> [1348869518.331197] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
YYY- FAIL

modem-manager[9576]: <debug> [1348869518.337624] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869518.350479] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869518.351102] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869518.353370] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
modem-manager[9576]: <debug> [1348869518.366984] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
YYY - REPEAT CRAP


modem-manager[9576]: <debug> [1348869525.603726] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CUSD=1,"2A31303023",15<CR>'
modem-manager[9576]: <debug> [1348869527.985858] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 30<CR><LF>'
modem-manager[9576]: <debug> [1348869527.985982] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 30: No network service

YYY - ENABLE USSD, SEND "2A31303023", string coding see 3GPP TS 23.038

modem-manager[9576]: <debug> [1348869531.766219] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CUSD=2<CR>'
modem-manager[9576]: <debug> [1348869531.779396] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 100<CR><LF>'
modem-manager[9576]: <debug> [1348869531.779496] [mm-serial-parsers.c:438] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
XXX - ATTEMPTED AND FAILED
modem-manager[9576]: <debug> [1348869551.650637] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869551.664003] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869551.664734] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869581.661006] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869581.674409] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869581.675153] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869589.255951] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 1,"43C8","00353B1B",2<CR><LF>'
modem-manager[9576]: <debug> [1348869589.257232] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CGREG: 1,"43C8","00353B1B",2<CR><LF>'
modem-manager[9576]: <debug> [1348869589.257423] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 1,"43C8","00353B1B",2<CR><LF>'
modem-manager[9576]: <debug> [1348869589.258224] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CREG: 1,"43C8","00353B1B",2<CR><LF>'
modem-manager[9576]: <debug> [1348869611.653855] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869611.667200] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869611.667941] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869641.661199] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869641.674626] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869641.675363] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869671.660605] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869671.674308] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869671.675149] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869701.661377] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869701.674643] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869701.675394] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869724.611722] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869724.612301] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CGREG: 1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869724.612930] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869724.614557] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CREG: 1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869731.634621] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869731.648733] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869731.649465] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869761.660738] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869761.673900] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869761.674642] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869791.660894] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869791.674384] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869791.675127] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869801.491108] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 1,"43C8","00353B1B",2<CR><LF>'
modem-manager[9576]: <debug> [1348869801.492613] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CGREG: 1,"43C8","00353B1B",2<CR><LF>'
modem-manager[9576]: <debug> [1348869801.492746] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 1,"43C8","00353B1B",2<CR><LF>'
modem-manager[9576]: <debug> [1348869801.493605] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CREG: 1,"43C8","00353B1B",2<CR><LF>'
modem-manager[9576]: <debug> [1348869809.157599] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
modem-manager[9576]: <debug> [1348869809.158047] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
modem-manager[9576]: <debug> [1348869810.176811] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIEV: 2,5<CR><LF>'
modem-manager[9576]: <debug> [1348869810.177567] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CIEV: 2,5<CR><LF>'
modem-manager[9576]: <debug> [1348869811.196826] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
modem-manager[9576]: <debug> [1348869811.197108] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
modem-manager[9576]: <debug> [1348869813.800150] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIEV: 2,5<CR><LF>'
modem-manager[9576]: <debug> [1348869813.800899] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CIEV: 2,5<CR><LF>'
modem-manager[9576]: <debug> [1348869816.357364] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
modem-manager[9576]: <debug> [1348869816.357601] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
modem-manager[9576]: <debug> [1348869827.902128] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIEV: 2,5<CR><LF>'
modem-manager[9576]: <debug> [1348869827.902365] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CIEV: 2,5<CR><LF>'
modem-manager[9576]: <debug> [1348869834.274833] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
modem-manager[9576]: <debug> [1348869834.275628] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
modem-manager[9576]: <debug> [1348869839.705140] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIEV: 2,5<CR><LF>'
modem-manager[9576]: <debug> [1348869839.705395] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CIEV: 2,5<CR><LF>'
modem-manager[9576]: <debug> [1348869849.925048] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
modem-manager[9576]: <debug> [1348869849.925338] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CIEV: 2,4<CR><LF>'
modem-manager[9576]: <debug> [1348869866.585608] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 1,"43C8","00353B14",2<CR><LF>'
modem-manager[9576]: <debug> [1348869866.586124] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CGREG: 1,"43C8","00353B14",2<CR><LF>'
modem-manager[9576]: <debug> [1348869866.586862] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 1,"43C8","00353B14",2<CR><LF>'
modem-manager[9576]: <debug> [1348869866.587879] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CREG: 1,"43C8","00353B14",2<CR><LF>'
modem-manager[9576]: <debug> [1348869875.436264] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIEV: 2,5<CR><LF>'
modem-manager[9576]: <debug> [1348869875.437088] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CIEV: 2,5<CR><LF>'
modem-manager[9576]: <debug> [1348869892.928080] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869892.928533] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CGREG: 1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869892.929145] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869892.929893] [mm-at-serial-port.c:333] debug_log(): (ttyACM1): <-- '<CR><LF>+CREG: 1,"43C8","003541CB",2<CR><LF>'
modem-manager[9576]: <debug> [1348869911.649789] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869911.663096] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869911.663709] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869941.661010] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869941.674527] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869941.675248] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348869971.661519] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348869971.674796] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348869971.675534] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
modem-manager[9576]: <debug> [1348870001.639199] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
modem-manager[9576]: <debug> [1348870001.652581] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
modem-manager[9576]: <debug> [1348870001.653353] [mm-at-serial-port.c:333] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'