Re: NM won't start Qualcomm Gobi

[Marius Kotsbak <marius kotsbak gmail com>]

2012/4/16 Robin Atwood <robin binro org>

On Tuesday 17 Apr 2012, you wrote:

modem-manager[4755]: <info>  [1334607308.752082] [main.c:167] main():
ModemManager (version 0.5.2.0) starting...
modem-manager[4755]: <info>  [1334607308.754001] [mm-manager.c:120]
load_plugin(): Loaded plugin Longcheer
modem-manager[4755]: <info>  [1334607308.754157] [mm-manager.c:120]
load_plugin(): Loaded plugin Novatel
modem-manager[4755]: <info>  [1334607308.754287] [mm-manager.c:120]
load_plugin(): Loaded plugin SimTech
modem-manager[4755]: <info>  [1334607308.754427] [mm-manager.c:120]
load_plugin(): Loaded plugin Nokia
modem-manager[4755]: <info>  [1334607308.754555] [mm-manager.c:120]
load_plugin(): Loaded plugin Gobi
modem-manager[4755]: <info>  [1334607308.754687] [mm-manager.c:120]
load_plugin(): Loaded plugin Option
modem-manager[4755]: <info>  [1334607308.754836] [mm-manager.c:120]
load_plugin(): Loaded plugin Sierra
modem-manager[4755]: <info>  [1334607308.754968] [mm-manager.c:120]
load_plugin(): Loaded plugin AnyData
modem-manager[4755]: <info>  [1334607308.755095] [mm-manager.c:120]
load_plugin(): Loaded plugin Generic
modem-manager[4755]: <info>  [1334607308.755225] [mm-manager.c:120]
load_plugin(): Loaded plugin MotoC
modem-manager[4755]: <info>  [1334607308.755361] [mm-manager.c:120]
load_plugin(): Loaded plugin Option High-Speed
modem-manager[4755]: <info>  [1334607308.755501] [mm-manager.c:120]
load_plugin(): Loaded plugin Ericsson MBM
modem-manager[4755]: <info>  [1334607308.755641] [mm-manager.c:120]
load_plugin(): Loaded plugin Samsung
modem-manager[4755]: <info>  [1334607308.755797] [mm-manager.c:120]
load_plugin(): Loaded plugin Huawei
modem-manager[4755]: <info>  [1334607308.755935] [mm-manager.c:120]
load_plugin(): Loaded plugin ZTE
modem-manager[4755]: <info>  [1334607308.756071] [mm-manager.c:120]
load_plugin(): Loaded plugin X22X
modem-manager[4755]: <info>  [1334607308.756206] [mm-manager.c:120]
load_plugin(): Loaded plugin Linktop
modem-manager[4755]: <info>  [1334607308.756344] [mm-manager.c:120]
load_plugin(): Loaded plugin Wavecom
modem-manager[4755]: <info>  [1334607308.761904] [mm-serial-port.c:741]
mm_serial_port_open(): (ttyUSB0) opening serial port...
modem-manager[4755]: <debug> [1334607308.799808] [mm-serial-port.c:810]
mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
modem-manager[4755]: <debug> [1334607308.799874] [mm-plugin-base.c:859]
try_open(): (ttyUSB0): probe requested by plugin 'Gobi'
modem-manager[4755]: <info>  [1334607308.799911] [mm-serial-port.c:741]
mm_serial_port_open(): (ttyUSB1) opening serial port...
modem-manager[4755]: <debug> [1334607308.799999] [mm-serial-port.c:810]
mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
modem-manager[4755]: <debug> [1334607308.800028] [mm-plugin-base.c:859]
try_open(): (ttyUSB1): probe requested by plugin 'Gobi'
modem-manager[4755]: <info>  [1334607308.800104] [mm-serial-port.c:741]
mm_serial_port_open(): (ttyUSB2) opening serial port...
modem-manager[4755]: <debug> [1334607308.800178] [mm-serial-port.c:810]
mm_serial_port_open(): (ttyUSB2) device open count is 1 (open)
modem-manager[4755]: <debug> [1334607308.800208] [mm-plugin-base.c:859]
try_open(): (ttyUSB2): probe requested by plugin 'Gobi'
modem-manager[4755]: <debug> [1334607308.900603] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB0): --> 'AT+GCAP<CR>'
modem-manager[4755]: <debug> [1334607308.900672] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+GCAP<CR>'

modem-manager[4755]: <debug> [1334607308.900690] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB2): --> 'AT+GCAP<CR>'
modem-manager[4755]: <debug> [1334607309.608334] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+GCAP: +CGSM,+DS,
+ES<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.608487] [mm-serial-port.c:844]
mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)

Maybe the plugin fails to parse the response here, with two CR/LF.

modem-manager[4755]: <info>  [1334607309.608506] [mm-serial-port.c:859]
mm_serial_port_close(): (ttyUSB1) closing serial port...
modem-manager[4755]: <info>  [1334607309.609833] [mm-serial-port.c:880]
mm_serial_port_close(): (ttyUSB1) serial port closed
modem-manager[4755]: <debug> [1334607309.616377] [mm-modem-base.c:155]
mm_modem_base_add_port(): (ttyUSB1) type primary claimed by
/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.4
modem-manager[4755]: <info>  [1334607309.616702] [mm-serial-port.c:741]
mm_serial_port_open(): (ttyUSB1) opening serial port...
modem-manager[4755]: <debug> [1334607309.616919] [mm-serial-port.c:810]
mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
modem-manager[4755]: <debug> [1334607309.617057] [mm-serial-port.c:810]
mm_serial_port_open(): (ttyUSB1) device open count is 2 (open)
modem-manager[4755]: <debug> [1334607309.617187] [mm-serial-port.c:810]
mm_serial_port_open(): (ttyUSB1) device open count is 3 (open)
modem-manager[4755]: <info>  [1334607309.617332] [mm-manager.c:564]
do_grab_port(): (Gobi): GSM modem
/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.4 claimed port ttyUSB1
modem-manager[4755]: <debug> [1334607309.617463] [mm-manager.c:317]
add_modem(): Added modem /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.4
modem-manager[4755]: <debug> [1334607309.617590] [mm-manager.c:243]
check_export_modem(): (tty/ttyUSB0): outstanding support task prevents export
of /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.4
modem-manager[4755]: <debug> [1334607309.617754] [mm-manager.c:243]
check_export_modem(): (tty/ttyUSB0): outstanding support task prevents export
of /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.4
modem-manager[4755]: <debug> [1334607309.618015] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'ATE0<CR>'
modem-manager[4755]: <debug> [1334607309.630624] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.630930] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+GMI<CR>'
modem-manager[4755]: <debug> [1334607309.641518] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>Qualcomm
Incorporated<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.641629] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+GMM<CR>'
modem-manager[4755]: <debug> [1334607309.657807] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>Qualcomm Gobi
2000<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.657926] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+GMR<CR>'
modem-manager[4755]: <debug> [1334607309.671659] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>D1025-STUTABGD-3600  1  [Jan 14 2010
14:00:00]<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.671786] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CGMI<CR>'
modem-manager[4755]: <debug> [1334607309.685468] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>Qualcomm
Incorporated<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.685586] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CGMM<CR>'
modem-manager[4755]: <debug> [1334607309.702829] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>Qualcomm Gobi
2000<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.702949] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CGMR<CR>'
modem-manager[4755]: <debug> [1334607309.717415] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>D1025-STUTABGD-3600  1  [Jan 14 2010
14:00:00]<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.717531] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'ATI<CR>'
modem-manager[4755]: <debug> [1334607309.753711] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>Manufacturer: Qualcomm
Incorporated<CR><LF>Model: Qualcomm Gobi 2000<CR><LF>Revision: D1025-
STUTABGD-3600  1  [Jan 14 2010 14:00:00]<CR><LF>SVN: 04<CR><LF>IMEI:
353093038573042<CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.753851] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'ATI1<CR>'
modem-manager[4755]: <debug> [1334607309.787197] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>Manufacturer: Qualcomm
Incorporated<CR><LF>Model: Qualcomm Gobi 2000<CR><LF>Revision: D1025-
STUTABGD-3600  1  [Jan 14 2010 14:00:00]<CR><LF>SVN: 04<CR><LF>IMEI:
353093038573042<CR><LF>+GCAP: +CGSM,+DS,+ES<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.787330] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+GSN<CR>'
modem-manager[4755]: <debug> [1334607309.821056] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <--
'<CR><LF>353093038573042<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.821177] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CGSN<CR>'
modem-manager[4755]: <debug> [1334607309.853934] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <--
'<CR><LF>353093038573042<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.854092] [mm-modem-helpers.c:920]
mm_create_device_identifier(): Device ID source
'000005c600009205Manufacturer:QualcommIncorporatedModel:QualcommGobi2000Revision:D1025-
STUTABGD-36001[Jan14201014:00:00]SVN:04IMEI:353093038573042+GCAP:+CGSM,+DS,
+ES353093038573042D1025-
STUTABGD-36001[Jan14201014:00:00]QualcommGobi2000QualcommIncorporated'
modem-manager[4755]: <debug> [1334607309.854117] [mm-modem-helpers.c:921]
mm_create_device_identifier(): Device ID
'3f32c7fa0ddf37414192397635df72c3a4e18173'
modem-manager[4755]: <debug> [1334607309.854166] [mm-serial-port.c:844]
mm_serial_port_close(): (ttyUSB1) device open count is 2 (close)
modem-manager[4755]: <debug> [1334607309.854189] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'ATE0<CR>'
modem-manager[4755]: <debug> [1334607309.864625] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.864771] [mm-serial-port.c:844]
mm_serial_port_close(): (ttyUSB1) device open count is 1 (close)
modem-manager[4755]: <debug> [1334607309.864797] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CMEE=1<CR>'
modem-manager[4755]: <debug> [1334607309.886837] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.886976] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CPIN?<CR>'
modem-manager[4755]: <debug> [1334607309.900890] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.901042] [mm-serial-port.c:810]
mm_serial_port_open(): (ttyUSB1) device open count is 2 (open)
modem-manager[4755]: <debug> [1334607309.901102] [mm-serial-port.c:844]
mm_serial_port_close(): (ttyUSB1) device open count is 1 (close)
modem-manager[4755]: <debug> [1334607309.901139] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CRSM=176,12258,0,0,10<CR>'
modem-manager[4755]: <debug> [1334607309.989510] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CRSM:
144,0,"985630501150110724F7"<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607309.989648] [mm-serial-port.c:844]
mm_serial_port_close(): (ttyUSB1) device open count is 0 (close)
modem-manager[4755]: <info>  [1334607309.989667] [mm-serial-port.c:859]
mm_serial_port_close(): (ttyUSB1) closing serial port...
modem-manager[4755]: <info>  [1334607309.990249] [mm-serial-port.c:880]
mm_serial_port_close(): (ttyUSB1) serial port closed
modem-manager[4755]: <debug> [1334607309.990331] [mm-generic-gsm.c:507]
get_iccid_done(): SIM ID source '8965030511051170427'
modem-manager[4755]: <debug> [1334607309.990347] [mm-generic-gsm.c:508]
get_iccid_done(): SIM ID '4f70d43adb5e247766d8f60b1c05003634c6c4ee'
modem-manager[4755]: <debug> [1334607309.990417] [mm-manager.c:243]
check_export_modem(): (tty/ttyUSB0): outstanding support task prevents export
of /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.4
modem-manager[4755]: <debug> [1334607313.017870] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB0): --> 'AT+GCAP<CR>'
modem-manager[4755]: <debug> [1334607313.017925] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB2): --> 'AT+GCAP<CR>'
modem-manager[4755]: <debug> [1334607317.018851] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB0): --> 'AT+GCAP<CR>'
modem-manager[4755]: <debug> [1334607317.018911] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB2): --> 'AT+GCAP<CR>'
modem-manager[4755]: <debug> [1334607321.018862] [mm-serial-port.c:844]
mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
modem-manager[4755]: <info>  [1334607321.018922] [mm-serial-port.c:859]
mm_serial_port_close(): (ttyUSB0) closing serial port...
modem-manager[4755]: <info>  [1334607321.019000] [mm-serial-port.c:880]
mm_serial_port_close(): (ttyUSB0) serial port closed
modem-manager[4755]: <info>  [1334607321.019086] [mm-serial-port.c:741]
mm_serial_port_open(): (ttyUSB0) opening serial port...
modem-manager[4755]: <debug> [1334607321.057126] [mm-serial-port.c:810]
mm_serial_port_open(): (ttyUSB0) device open count is 1 (open)
modem-manager[4755]: <debug> [1334607321.057215] [mm-serial-port.c:844]
mm_serial_port_close(): (ttyUSB2) device open count is 0 (close)
modem-manager[4755]: <info>  [1334607321.057238] [mm-serial-port.c:859]
mm_serial_port_close(): (ttyUSB2) closing serial port...
modem-manager[4755]: <info>  [1334607321.057733] [mm-serial-port.c:880]
mm_serial_port_close(): (ttyUSB2) serial port closed
modem-manager[4755]: <info>  [1334607321.057816] [mm-serial-port.c:741]
mm_serial_port_open(): (ttyUSB2) opening serial port...
modem-manager[4755]: <debug> [1334607321.057896] [mm-serial-port.c:810]
mm_serial_port_open(): (ttyUSB2) device open count is 1 (open)
modem-manager[4755]: <debug> [1334607321.057974] [mm-qcdm-serial-port.c:196]
debug_log(): (ttyUSB0): --> 00 78 f0 7e
modem-manager[4755]: <debug> [1334607321.058008] [mm-qcdm-serial-port.c:196]
debug_log(): (ttyUSB2): --> 00 78 f0 7e
modem-manager[4755]: <debug> [1334607324.017845] [mm-qcdm-serial-port.c:196]
debug_log(): (ttyUSB0): --> 00 78 f0 7e
modem-manager[4755]: <debug> [1334607324.017904] [mm-qcdm-serial-port.c:196]
debug_log(): (ttyUSB2): --> 00 78 f0 7e
modem-manager[4755]: <debug> [1334607324.061951] [mm-qcdm-serial-port.c:196]
debug_log(): (ttyUSB0): <-- 00 4a 61 6e 20 31 34 20 32 30 31 30 31 35 3a 30 34
3a 35 33 4a 61 6e 20 31 34 20 32 30 31 30 31 34 3a 30 30 3a 30 30 53 54 55 54
41 42 47 44 00 00 0c 00 00 00 01 b2 7a 00 7e
modem-manager[4755]: <debug> [1334607324.062057] [mm-serial-port.c:844]
mm_serial_port_close(): (ttyUSB0) device open count is 0 (close)
modem-manager[4755]: <info>  [1334607324.062079] [mm-serial-port.c:859]
mm_serial_port_close(): (ttyUSB0) closing serial port...
modem-manager[4755]: <info>  [1334607324.062631] [mm-serial-port.c:880]
mm_serial_port_close(): (ttyUSB0) serial port closed
modem-manager[4755]: <debug> [1334607324.062694] [mm-manager.c:624]
supports_callback(): (tty/ttyUSB0): ignoring port unsupported by physical
modem's plugin
modem-manager[4755]: <debug> [1334607324.062752] [mm-manager.c:243]
check_export_modem(): (tty/ttyUSB2): outstanding support task prevents export
of /sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.4
modem-manager[4755]: <debug> [1334607327.017851] [mm-serial-port.c:844]
mm_serial_port_close(): (ttyUSB2) device open count is 0 (close)
modem-manager[4755]: <info>  [1334607327.017907] [mm-serial-port.c:859]
mm_serial_port_close(): (ttyUSB2) closing serial port...
modem-manager[4755]: <info>  [1334607327.017944] [mm-serial-port.c:880]
mm_serial_port_close(): (ttyUSB2) serial port closed
modem-manager[4755]: <debug> [1334607327.017992] [mm-manager.c:624]
supports_callback(): (tty/ttyUSB2): ignoring port unsupported by physical
modem's plugin
modem-manager[4755]: <debug> [1334607327.018066] [mm-manager.c:265]
check_export_modem(): Exported modem
/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.4 as
/org/freedesktop/ModemManager/Modems/0
modem-manager[4755]: <debug> [1334607327.018126] [mm-manager.c:278]
check_export_modem(): (/org/freedesktop/ModemManager/Modems/0): VID 0x05C6 PID
0x9205 (usb)
modem-manager[4755]: <debug> [1334607327.018137] [mm-manager.c:279]
check_export_modem(): (/org/freedesktop/ModemManager/Modems/0): data port is
ttyUSB1
modem-manager[4755]: <info>  [1334607366.216884] [mm-serial-port.c:741]
mm_serial_port_open(): (ttyUSB1) opening serial port...
modem-manager[4755]: <debug> [1334607366.313174] [mm-serial-port.c:810]
mm_serial_port_open(): (ttyUSB1) device open count is 1 (open)
modem-manager[4755]: <info>  [1334607366.313325] [mm-modem.c:746]
mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state
changed (disabled -> enabling)
modem-manager[4755]: <debug> [1334607366.413765] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'ATZ E0 V1<CR>'
modem-manager[4755]: <debug> [1334607366.444121] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.444226] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'ATZ E0 V1<CR>'
modem-manager[4755]: <debug> [1334607366.471735] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.471861] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'ATE0<CR>'
modem-manager[4755]: <debug> [1334607366.484708] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.484902] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CMEE=1<CR>'
modem-manager[4755]: <debug> [1334607366.502224] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.502326] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'ATX4 &C1<CR>'
modem-manager[4755]: <debug> [1334607366.519627] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.519736] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CFUN=1<CR>'
modem-manager[4755]: <debug> [1334607366.533456] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.533801] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+IFC=1,1<CR>'
modem-manager[4755]: <debug> [1334607366.548740] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.548853] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CPMS="ME","ME","ME"<CR>'
modem-manager[4755]: <debug> [1334607366.590116] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CPMS:
2,23,2,23,2,23<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.590220] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CNMI=2,1,2,1,0<CR>'
modem-manager[4755]: <debug> [1334607366.614820] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CMS ERROR: 303<CR><LF>'
modem-manager[4755]: <debug> [1334607366.614913] [mm-serial-parsers.c:438]
mm_serial_parser_v1_parse(): Got failure code 303: Operation not supported
modem-manager[4755]: <debug> [1334607366.614952] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CMGF=?<CR>'
modem-manager[4755]: <debug> [1334607366.626703] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CMGF: (0-1)<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.626868] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CUSD=1<CR>'
modem-manager[4755]: <debug> [1334607366.639863] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.640037] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CIND=?<CR>'
modem-manager[4755]: <debug> [1334607366.655266] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CIND: ("battchg",(0-5)),("signal",
(0-5)),("service",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),
("GPRS coverage",(0-1)),("callsetup",(0-3))<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.655589] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CSCS=?<CR>'
modem-manager[4755]: <debug> [1334607366.672504] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CSCS:
("IRA","GSM","UCS2")<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.672670] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CMGF=1<CR>'
modem-manager[4755]: <debug> [1334607366.683997] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <info>  [1334607366.684078] [mm-generic-gsm.c:1704]
sms_set_format_cb(): (ttyUSB1): using text mode for SMS
modem-manager[4755]: <debug> [1334607366.684118] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CMER=3,0,0,1<CR>'
modem-manager[4755]: <debug> [1334607366.706567] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.706667] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CSCS="UCS2"<CR>'
modem-manager[4755]: <debug> [1334607366.724563] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.724667] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CSCS?<CR>'
modem-manager[4755]: <debug> [1334607366.739488] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CSCS: "UCS2"<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <info>  [1334607366.739738] [mm-modem.c:746]
mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state
changed (enabling -> enabled)
modem-manager[4755]: <debug> [1334607366.739898] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CREG=2<CR>'
modem-manager[4755]: <debug> [1334607366.756615] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.756926] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CREG?<CR>'
modem-manager[4755]: <debug> [1334607366.774282] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CREG: 2,1, 1A5,
CA92F<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.774371] [mm-generic-gsm.c:3007]
mm_generic_gsm_set_reg_status(): CS registration state changed: 1
modem-manager[4755]: <info>  [1334607366.774525] [mm-modem.c:746]
mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state
changed (enabled -> registered)
modem-manager[4755]: <debug> [1334607366.774596] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CGREG=2<CR>'
modem-manager[4755]: <debug> [1334607366.791852] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.792004] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+COPS=3,2;+COPS?<CR>'
modem-manager[4755]: <debug> [1334607366.792605] [mm-generic-gsm.c:5924]
simple_connect(): (ttyUSB1): number => "*99***1#"
modem-manager[4755]: <debug> [1334607366.792627] [mm-generic-gsm.c:5924]
simple_connect(): (ttyUSB1): network_mode => 0
modem-manager[4755]: <debug> [1334607366.792638] [mm-generic-gsm.c:5924]
simple_connect(): (ttyUSB1): home_only => TRUE
modem-manager[4755]: <debug> [1334607366.792646] [mm-generic-gsm.c:5924]
simple_connect(): (ttyUSB1): apn => "prepaidbb"
modem-manager[4755]: <debug> [1334607366.792656] [mm-generic-gsm.c:5924]
simple_connect(): (ttyUSB1): allowed_mode => 0
modem-manager[4755]: <debug> [1334607366.792670] [mm-generic-gsm.c:5813]
simple_state_machine(): (ttyUSB1): simple connect state 0
modem-manager[4755]: <debug> [1334607366.792699] [mm-generic-gsm.c:5813]
simple_state_machine(): (ttyUSB1): simple connect state 2
modem-manager[4755]: <debug> [1334607366.819222] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+COPS:
0,2,"52503",2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.819448] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+COPS=3,0;+COPS?<CR>'
modem-manager[4755]: <debug> [1334607366.842179] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+COPS:
0,0,"M1-3GSM",2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.842364] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CIND?<CR>'
modem-manager[4755]: <debug> [1334607366.863496] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CIND:
3,3,1,0,0,0,1,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.863642] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT*CNTI=0<CR>'
modem-manager[4755]: <debug> [1334607366.876675] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>*CNTI: 0,UMTS<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.877225] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CGREG?<CR>'
modem-manager[4755]: <debug> [1334607366.891413] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CGREG: 2,1, 1A5,
CA92F<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.891773] [mm-generic-gsm.c:3007]
mm_generic_gsm_set_reg_status(): PS registration state changed: 1
modem-manager[4755]: <debug> [1334607366.891992] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CREG?<CR>'
modem-manager[4755]: <debug> [1334607366.907855] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CREG: 2,1, 1A5,
CA92F<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.907984] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CGREG?<CR>'
modem-manager[4755]: <debug> [1334607366.919348] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CGREG: 2,1, 1A5,
CA92F<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.919487] [mm-generic-gsm.c:5813]
simple_state_machine(): (ttyUSB1): simple connect state 4
modem-manager[4755]: <debug> [1334607366.919527] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+COPS=3,2;+COPS?<CR>'
modem-manager[4755]: <debug> [1334607366.942731] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+COPS:
0,2,"52503",2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.942858] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+COPS=3,0;+COPS?<CR>'
modem-manager[4755]: <debug> [1334607366.971081] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+COPS:
0,0,"M1-3GSM",2<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.971211] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CIND?<CR>'
modem-manager[4755]: <debug> [1334607366.992008] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CIND:
3,3,1,0,0,0,1,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607366.992176] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT*CNTI=0<CR>'
modem-manager[4755]: <debug> [1334607367.010531] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>*CNTI: 0,UMTS<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607367.010658] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CGDCONT?<CR>'
modem-manager[4755]: <debug> [1334607367.030158] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CGDCONT:
1,"IP","prepaidbb","",0,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607367.030304] [mm-generic-gsm.c:5813]
simple_state_machine(): (ttyUSB1): simple connect state 5
modem-manager[4755]: <info>  [1334607367.030409] [mm-modem.c:746]
mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state
changed (registered -> connecting)
modem-manager[4755]: <debug> [1334607367.030454] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'ATD*99***1***1#<CR>'
modem-manager[4755]: <debug> [1334607367.056479] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>ERROR<CR><LF>'

Are you sure this is the right number to dial: *99***1***1#?

I use just "*99#".

modem-manager[4755]: <debug> [1334607367.056575] [mm-serial-parsers.c:438]
mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
modem-manager[4755]: <debug> [1334607367.056623] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CEER<CR>'
modem-manager[4755]: <debug> [1334607367.071046] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CEER: No cause information
available<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <info>  [1334607367.071261] [mm-modem.c:746]
mm_modem_set_state(): Modem /org/freedesktop/ModemManager/Modems/0: state
changed (connecting -> registered)
modem-manager[4755]: <debug> [1334607397.040917] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT+CIND?<CR>'
modem-manager[4755]: <debug> [1334607397.154143] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>+CIND:
3,3,1,0,0,0,1,0<CR><LF><CR><LF>OK<CR><LF>'
modem-manager[4755]: <debug> [1334607397.154310] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): --> 'AT*CNTI=0<CR>'
modem-manager[4755]: <debug> [1334607397.165544] [mm-at-serial-port.c:333]
debug_log(): (ttyUSB1): <-- '<CR><LF>*CNTI: 0,UMTS<CR><LF><CR><LF>OK<CR><LF>'

Thanks for the response, the forum seems a bit broken. Hope this is enough, it
goes on and on.

-Robin
--
----------------------------------------------------------------------
Robin Atwood.

"Ship me somewheres east of Suez, where the best is like the worst,
 Where there ain't no Ten Commandments an' a man can raise a thirst"
        from "Mandalay" by Rudyard Kipling
----------------------------------------------------------------------