[Freeswitch-users] Unable to get K3765 dongle signalling using mod_gsmopen

Silviu CHINGARU silviuchingaru at gmail.com
Wed Jun 12 00:06:07 MSD 2013


Hi,

I'm trying to make mod_gsmopen work with my testing freeswitch. I have 
the following setup:
K3765 Huawei dongle
Freeswitch 1.2.9 with mod_gsmopen

I setup a gateway using gsmopen.conf.xml (see attached) and an extension 
in dialplan. I've set consolelog 9 and I'm able to make call via 
mod_gsmopen from any switch phone, but the signaling is not working. So 
when I answer to mobile called by gsmopen module, sip phone is still 
displaying "Conecting...", I can talk to mobile phone via sip phone and 
reverse but when I close the conversation from mobile side is still 
"Conecting...".

In console I don't see any signaling from gsmopen like ringing, answered 
and so on.

I think the serial interface is ok because I can send AT commands to 
init the call but I don't get any signaling. Also the audio interface is 
ok because I have audio both sides (via Early Media because the call is 
never signaled as answered).

I've attached:
- reload.log (output from freeswitch when I type reload mod_gsmopen)
- call.log (output from freeswitch when I init the call via mod_gsmopen)
- gsmopen.conf.xml
(I've changed / masked context, ip and phone number for privacy)


I've used this dongle also with chan_dongle in asterisk and was working 
fine but on freeswitch I'm unable to configure it.

Please help, I don't even know what to read! What could be the problem, 
what is wrong? How can I figure out what is wrong? Thank you very much 
for your time!

Best regards,
Fiftyz

-------------- next part --------------
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (R)
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (G)
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (?)
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:26.983584 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CREG: 1,1| la_counter=2
2013-06-11 22:31:26.983584 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:26.983584 [DEBUG] gsmopen_protocol.cpp:1008 rev |[(nil)|37     ][DEBUG_GSMOPEN  1008 ][gsm01     ][-1, 0, 0] |+CREG: 1,1| +CREG: Display: 1, Registration=1
2013-06-11 22:31:26.983584 [DEBUG] gsmopen_protocol.cpp:1022 rev |[(nil)|37     ][DEBUG_GSMOPEN  1022 ][gsm01     ][-1, 0, 0] |+CREG: 1,1| CELLPHONE is registered to the HOME network
2013-06-11 22:31:26.983584 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:34:11.203579 [NOTICE] switch_channel.c:978 New Channel sofia/sipinterface_4/190 at sip.example.com [e421bdee-d2cd-11e2-8043-9974f952483c]
2013-06-11 22:34:11.203579 [DEBUG] switch_core_session.c:998 Send signal sofia/sipinterface_4/190 at sip.example.com [BREAK]
2013-06-11 22:34:11.203579 [DEBUG] switch_core_session.c:998 Send signal sofia/sipinterface_4/190 at sip.example.com [BREAK]
2013-06-11 22:34:11.203579 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_4/190 at sip.example.com) Running State Change CS_NEW
2013-06-11 22:34:11.203579 [DEBUG] switch_core_state_machine.c:433 (sofia/sipinterface_4/190 at sip.example.com) State NEW
2013-06-11 22:34:11.223577 [DEBUG] switch_core_session.c:998 Send signal sofia/sipinterface_4/190 at sip.example.com [BREAK]
2013-06-11 22:34:11.223577 [DEBUG] sofia.c:1767 detaching session e421bdee-d2cd-11e2-8043-9974f952483c
2013-06-11 22:34:11.483579 [DEBUG] sofia.c:1859 Re-attaching to session e421bdee-d2cd-11e2-8043-9974f952483c
2013-06-11 22:34:11.483579 [DEBUG] switch_core_session.c:998 Send signal sofia/sipinterface_4/190 at sip.example.com [BREAK]
2013-06-11 22:34:11.483579 [DEBUG] switch_core_session.c:998 Send signal sofia/sipinterface_4/190 at sip.example.com [BREAK]
2013-06-11 22:34:11.543576 [DEBUG] sofia.c:5685 Channel sofia/sipinterface_4/190 at sip.example.com entering state [received][100]
2013-06-11 22:34:11.543576 [DEBUG] sofia.c:5696 Remote SDP:
v=0
o=190 96271082 49276412 IN IP4 XXX.XXX.XXX.29
s=SIP CALL
c=IN IP4 XXX.XXX.XXX.29
t=0 0
m=audio 6000 RTP/AVP 8 0 18 3 4 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=rtpmap:3 GSM/8000
a=rtpmap:4 G723/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15

2013-06-11 22:34:11.543576 [DEBUG] sofia_glue.c:5176 Audio Codec Compare [PCMA:8:8000:20:64000]/[G722:9:8000:20:64000]
2013-06-11 22:34:11.543576 [DEBUG] sofia_glue.c:5176 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMU:0:8000:20:64000]
2013-06-11 22:34:11.543576 [DEBUG] sofia_glue.c:5176 Audio Codec Compare [PCMA:8:8000:20:64000]/[PCMA:8:8000:20:64000]
2013-06-11 22:34:11.543576 [DEBUG] sofia_glue.c:3119 Set Codec sofia/sipinterface_4/190 at sip.example.com PCMA/8000 20 ms 160 samples 64000 bits
2013-06-11 22:34:11.543576 [DEBUG] switch_core_codec.c:111 sofia/sipinterface_4/190 at sip.example.com Original read codec set to PCMA:8
2013-06-11 22:34:11.543576 [DEBUG] sofia_glue.c:5305 Set 2833 dtmf send/recv payload to 101
2013-06-11 22:34:11.543576 [DEBUG] sofia.c:5929 (sofia/sipinterface_4/190 at sip.example.com) State Change CS_NEW -> CS_INIT
2013-06-11 22:34:11.543576 [DEBUG] switch_core_session.c:1333 Send signal sofia/sipinterface_4/190 at sip.example.com [BREAK]
2013-06-11 22:34:11.543576 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_4/190 at sip.example.com) Running State Change CS_INIT
2013-06-11 22:34:11.543576 [DEBUG] switch_core_state_machine.c:454 (sofia/sipinterface_4/190 at sip.example.com) State INIT
2013-06-11 22:34:11.543576 [DEBUG] mod_sofia.c:87 sofia/sipinterface_4/190 at sip.example.com SOFIA INIT
2013-06-11 22:34:11.543576 [DEBUG] mod_sofia.c:127 (sofia/sipinterface_4/190 at sip.example.com) State Change CS_INIT -> CS_ROUTING
2013-06-11 22:34:11.543576 [DEBUG] switch_core_session.c:1333 Send signal sofia/sipinterface_4/190 at sip.example.com [BREAK]
2013-06-11 22:34:11.543576 [DEBUG] switch_core_state_machine.c:454 (sofia/sipinterface_4/190 at sip.example.com) State INIT going to sleep
2013-06-11 22:34:11.543576 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_4/190 at sip.example.com) Running State Change CS_ROUTING
2013-06-11 22:34:11.543576 [DEBUG] switch_channel.c:2034 (sofia/sipinterface_4/190 at sip.example.com) Callstate Change DOWN -> RINGING
2013-06-11 22:34:11.543576 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_4/190 at sip.example.com) State ROUTING
2013-06-11 22:34:11.543576 [DEBUG] mod_sofia.c:150 sofia/sipinterface_4/190 at sip.example.com SOFIA ROUTING
2013-06-11 22:34:11.543576 [DEBUG] switch_core_state_machine.c:117 sofia/sipinterface_4/190 at sip.example.com Standard ROUTING
2013-06-11 22:34:11.543576 [INFO] mod_dialplan_xml.c:557 Processing 190 <190>->XXXXXXXXXX in context sip.example.com
Dialplan: sofia/sipinterface_4/190 at sip.example.com parsing [sip.example.com->extension-intercom] continue=false
Dialplan: sofia/sipinterface_4/190 at sip.example.com Regex (FAIL) [extension-intercom] destination_number(XXXXXXXXXX) =~ /^(1[0-9][0-9])$/ break=on-false
Dialplan: sofia/sipinterface_4/190 at sip.example.com parsing [sip.example.com->gsmopen] continue=false
Dialplan: sofia/sipinterface_4/190 at sip.example.com Regex (PASS) [gsmopen] destination_number(XXXXXXXXXX) =~ /^(.*)$/ break=on-false
Dialplan: sofia/sipinterface_4/190 at sip.example.com Action bridge(gsmopen/gsm01/XXXXXXXXXX) 
2013-06-11 22:34:11.543576 [DEBUG] switch_core_state_machine.c:167 (sofia/sipinterface_4/190 at sip.example.com) State Change CS_ROUTING -> CS_EXECUTE
2013-06-11 22:34:11.543576 [DEBUG] switch_core_session.c:1333 Send signal sofia/sipinterface_4/190 at sip.example.com [BREAK]
2013-06-11 22:34:11.543576 [DEBUG] switch_core_state_machine.c:470 (sofia/sipinterface_4/190 at sip.example.com) State ROUTING going to sleep
2013-06-11 22:34:11.543576 [DEBUG] switch_core_state_machine.c:415 (sofia/sipinterface_4/190 at sip.example.com) Running State Change CS_EXECUTE
2013-06-11 22:34:11.543576 [DEBUG] switch_core_state_machine.c:477 (sofia/sipinterface_4/190 at sip.example.com) State EXECUTE
2013-06-11 22:34:11.543576 [DEBUG] mod_sofia.c:243 sofia/sipinterface_4/190 at sip.example.com SOFIA EXECUTE
2013-06-11 22:34:11.543576 [DEBUG] switch_core_state_machine.c:209 sofia/sipinterface_4/190 at sip.example.com Standard EXECUTE
EXECUTE sofia/sipinterface_4/190 at sip.example.com bridge(gsmopen/gsm01/XXXXXXXXXX)
2013-06-11 22:34:11.543576 [DEBUG] switch_ivr_originate.c:2044 Parsing global variables
2013-06-11 22:34:11.543576 [DEBUG] mod_gsmopen.cpp:1073 rev |[(nil)|37     ][DEBUG_GSMOPEN  1073 ][none      ][-1,-1,-1] 1 SESSION_REQUEST e4566a08-d2cd-11e2-804a-9974f952483c
2013-06-11 22:34:11.543576 [DEBUG] mod_gsmopen.cpp:1115 rev |[(nil)|37     ][DEBUG_GSMOPEN  1115 ][none      ][-1,-1,-1] globals.GSMOPEN_INTERFACES[1].name=|||gsm01|||?
2013-06-11 22:34:11.543576 [DEBUG] gsmopen_protocol.cpp:3160 rev |[(nil)|37     ][DEBUG_GSMOPEN  3160 ][gsm01     ][-1, 0, 0] port /dev/ttyUSB5, SUCCESS open
2013-06-11 22:34:11.543576 [DEBUG] gsmopen_protocol.cpp:3175 rev |[(nil)|37     ][DEBUG_GSMOPEN  3175 ][gsm01     ][-1, 0, 0] serial_audio_init res=0
2013-06-11 22:34:11.543576 [DEBUG] mod_gsmopen.cpp:299 rev |[(nil)|37     ][DEBUG_GSMOPEN  299  ][gsm01     ][-1, 0, 0] gsmopen_codec SUCCESS
2013-06-11 22:34:11.543576 [NOTICE] switch_channel.c:978 New Channel gsmopen/gsm01/XXXXXXXXXX [e4566a08-d2cd-11e2-804a-9974f952483c]
2013-06-11 22:34:11.543576 [DEBUG] mod_gsmopen.cpp:1182 (gsmopen/gsm01/XXXXXXXXXX) State Change CS_NEW -> CS_INIT
2013-06-11 22:34:11.543576 [DEBUG] switch_core_session.c:1333 Send signal gsmopen/gsm01/XXXXXXXXXX [BREAK]
2013-06-11 22:34:11.543576 [DEBUG] mod_gsmopen.cpp:665 rev |[(nil)|37     ][DEBUG_GSMOPEN  665  ][gsm01     ][-1, 0, 0] gsm01 CHANNEL KILL_CHANNEL
2013-06-11 22:34:11.543576 [DEBUG] mod_gsmopen.cpp:676 rev |[(nil)|37     ][DEBUG_GSMOPEN  676  ][gsm01     ][-1, 0, 0] gsmopen/gsm01/XXXXXXXXXX CHANNEL got SWITCH_SIG_BREAK
2013-06-11 22:34:11.543576 [DEBUG] gsmopen_protocol.cpp:2706 rev |[(nil)|37     ][DEBUG_GSMOPEN  2706 ][gsm01     ][-1, 0, 0] Calling GSM, rdest is: XXXXXXXXXX
2013-06-11 22:34:11.543576 [DEBUG] gsmopen_protocol.cpp:2322 rev |[(nil)|37     ][DEBUG_PBX  2322 ][gsm01     ][-1, 0, 0] Dialing XXXXXXXXXX
2013-06-11 22:34:11.543576 [DEBUG] gsmopen_protocol.cpp:2337 rev |[(nil)|37     ][DEBUG_PBX  2337 ][gsm01     ][-1, 3, 3] Dialstring ATDXXXXXXXXXX;
2013-06-11 22:34:11.623579 [DEBUG] gsmopen_protocol.cpp:2165 rev |[(nil)|37     ][DEBUG_GSMOPEN  2165 ][gsm01     ][-1, 3, 3] sending: ATDXXXXXXXXXX;, expecting: OK
2013-06-11 22:34:11.623579 [DEBUG] mod_gsmopen.cpp:2848 rev |[(nil)|37     ][DEBUG_GSMOPEN  2848 ][gsm01     ][-1, 3, 3] WE'RE DIALING, let's take the earlymedia
2013-06-11 22:34:11.623579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (A)
2013-06-11 22:34:11.623579 [NOTICE] mod_gsmopen.cpp:2196 Pre-Answer gsmopen/gsm01/XXXXXXXXXX!
2013-06-11 22:34:11.623579 [DEBUG] switch_channel.c:3265 (gsmopen/gsm01/XXXXXXXXXX) Callstate Change DOWN -> EARLY
2013-06-11 22:34:11.623579 [DEBUG] mod_gsmopen.cpp:2197 rev |[(nil)|37     ][DEBUG_GSMOPEN  2197 ][gsm01     ][-1,24, 3] gsmopen_call: REMOTE PARTY EARLY MEDIA
2013-06-11 22:34:11.623579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (T)
2013-06-11 22:34:11.623579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (D)
2013-06-11 22:34:11.623579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (0)
2013-06-11 22:34:11.623579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (7)
2013-06-11 22:34:11.643576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (3)
2013-06-11 22:34:11.643576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (3)
2013-06-11 22:34:11.643576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (3)
2013-06-11 22:34:11.643576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (5)
2013-06-11 22:34:11.643576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (5)
2013-06-11 22:34:11.643576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (5)
2013-06-11 22:34:11.643576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (7)
2013-06-11 22:34:11.643576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (8)
2013-06-11 22:34:11.643576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (;)
2013-06-11 22:34:11.643576 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1,24, 3] sent (carriage return)
2013-06-11 22:34:11.643576 [DEBUG] gsmopen_protocol.cpp:609 rev |[(nil)|37     ][DEBUG_GSMOPEN  609  ][gsm01     ][-1,24, 3] TIMEOUT=500
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1,24, 3] Read line 0: |OK| la_counter=1
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:1578 rev |[(nil)|37     ][DEBUG_GSMOPEN  1578 ][gsm01     ][-1,24, 3] |OK| got what EXPECTED
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:2165 rev |[(nil)|37     ][DEBUG_GSMOPEN  2165 ][gsm01     ][-1,24, 3] sending: AT^DDSETEX=2, expecting: OK
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (A)
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (T)
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (^)
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (D)
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (D)
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (S)
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (E)
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (T)
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (E)
2013-06-11 22:34:11.663580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (X)
2013-06-11 22:34:11.683576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (=)
2013-06-11 22:34:11.683576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1,24, 3] sent data... (2)
2013-06-11 22:34:11.683576 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1,24, 3] sent (carriage return)
2013-06-11 22:34:11.683576 [DEBUG] gsmopen_protocol.cpp:609 rev |[(nil)|37     ][DEBUG_GSMOPEN  609  ][gsm01     ][-1,24, 3] TIMEOUT=500
2013-06-11 22:34:11.703580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1,24, 3] Read line 0: |OK| la_counter=1
2013-06-11 22:34:11.703580 [DEBUG] gsmopen_protocol.cpp:1578 rev |[(nil)|37     ][DEBUG_GSMOPEN  1578 ][gsm01     ][-1,24, 3] |OK| got what EXPECTED
2013-06-11 22:34:11.703580 [DEBUG] switch_core_state_machine.c:415 (gsmopen/gsm01/XXXXXXXXXX) Running State Change CS_INIT
2013-06-11 22:34:11.703580 [DEBUG] switch_core_state_machine.c:454 (gsmopen/gsm01/XXXXXXXXXX) State INIT
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:525 (gsmopen/gsm01/XXXXXXXXXX) State Change CS_INIT -> CS_ROUTING
2013-06-11 22:34:11.703580 [DEBUG] switch_core_session.c:1333 Send signal gsmopen/gsm01/XXXXXXXXXX [BREAK]
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:665 rev |[(nil)|37     ][DEBUG_GSMOPEN  665  ][gsm01     ][-1,24, 3] gsm01 CHANNEL KILL_CHANNEL
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:676 rev |[(nil)|37     ][DEBUG_GSMOPEN  676  ][gsm01     ][-1,24, 3] gsmopen/gsm01/XXXXXXXXXX CHANNEL got SWITCH_SIG_BREAK
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:530 rev |[(nil)|37     ][DEBUG_GSMOPEN  530  ][gsm01     ][-1,24, 3] gsm01 CHANNEL INIT e4566a08-d2cd-11e2-804a-9974f952483c
2013-06-11 22:34:11.703580 [DEBUG] switch_core_state_machine.c:454 (gsmopen/gsm01/XXXXXXXXXX) State INIT going to sleep
2013-06-11 22:34:11.703580 [DEBUG] switch_core_state_machine.c:415 (gsmopen/gsm01/XXXXXXXXXX) Running State Change CS_ROUTING
2013-06-11 22:34:11.703580 [DEBUG] switch_core_state_machine.c:470 (gsmopen/gsm01/XXXXXXXXXX) State ROUTING
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:632 rev |[(nil)|37     ][DEBUG_GSMOPEN  632  ][gsm01     ][-1,24, 3] gsm01 CHANNEL ROUTING
2013-06-11 22:34:11.703580 [DEBUG] switch_ivr_originate.c:67 (gsmopen/gsm01/XXXXXXXXXX) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2013-06-11 22:34:11.703580 [DEBUG] switch_core_session.c:1333 Send signal gsmopen/gsm01/XXXXXXXXXX [BREAK]
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:665 rev |[(nil)|37     ][DEBUG_GSMOPEN  665  ][gsm01     ][-1,24, 3] gsm01 CHANNEL KILL_CHANNEL
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:676 rev |[(nil)|37     ][DEBUG_GSMOPEN  676  ][gsm01     ][-1,24, 3] gsmopen/gsm01/XXXXXXXXXX CHANNEL got SWITCH_SIG_BREAK
2013-06-11 22:34:11.703580 [DEBUG] switch_core_state_machine.c:470 (gsmopen/gsm01/XXXXXXXXXX) State ROUTING going to sleep
2013-06-11 22:34:11.703580 [DEBUG] switch_core_state_machine.c:415 (gsmopen/gsm01/XXXXXXXXXX) Running State Change CS_CONSUME_MEDIA
2013-06-11 22:34:11.703580 [DEBUG] switch_core_state_machine.c:489 (gsmopen/gsm01/XXXXXXXXXX) State CONSUME_MEDIA
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:695 rev |[(nil)|37     ][DEBUG_GSMOPEN  695  ][gsm01     ][-1,24, 3] gsm01 CHANNEL CONSUME_MEDIA
2013-06-11 22:34:11.703580 [DEBUG] switch_core_state_machine.c:489 (gsmopen/gsm01/XXXXXXXXXX) State CONSUME_MEDIA going to sleep
2013-06-11 22:34:11.703580 [INFO] switch_ivr_originate.c:3427 Sending early media
2013-06-11 22:34:11.703580 [DEBUG] sofia_glue.c:3378 AUDIO RTP [sofia/sipinterface_4/190 at sip.example.com] XXX.XXX.XXX.24 port 24490 -> XXX.XXX.XXX.29 port 6000 codec: 8 ms: 20
2013-06-11 22:34:11.703580 [DEBUG] switch_rtp.c:1985 Starting timer [soft] 160 bytes per 20ms
2013-06-11 22:34:11.703580 [DEBUG] sofia_glue.c:3642 Set 2833 dtmf send payload to 101
2013-06-11 22:34:11.703580 [DEBUG] sofia_glue.c:3648 Set 2833 dtmf receive payload to 101
2013-06-11 22:34:11.703580 [DEBUG] sofia_glue.c:3675 sofia/sipinterface_4/190 at sip.example.com Set rtp dtmf delay to 40
2013-06-11 22:34:11.703580 [DEBUG] mod_sofia.c:2842 Ring SDP:
v=0
o=FreeSWITCH 1370954761 1370954762 IN IP4 XXX.XXX.XXX.24
s=FreeSWITCH
c=IN IP4 XXX.XXX.XXX.24
t=0 0
m=audio 24490 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

2013-06-11 22:34:11.703580 [NOTICE] mod_sofia.c:2845 Pre-Answer sofia/sipinterface_4/190 at sip.example.com!
2013-06-11 22:34:11.703580 [DEBUG] switch_channel.c:3265 (sofia/sipinterface_4/190 at sip.example.com) Callstate Change RINGING -> EARLY
2013-06-11 22:34:11.703580 [DEBUG] switch_core_session.c:853 Send signal sofia/sipinterface_4/190 at sip.example.com [BREAK]
2013-06-11 22:34:11.703580 [DEBUG] switch_ivr_originate.c:3478 Originate Resulted in Success: [gsmopen/gsm01/XXXXXXXXXX]
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:989 rev |[(nil)|37     ][DEBUG_GSMOPEN  989  ][gsm01     ][-1,24, 3] gsmopen/gsm01/XXXXXXXXXX CHANNEL got SWITCH_MESSAGE_INDICATE_AUDIO_SYNC
2013-06-11 22:34:11.703580 [DEBUG] switch_core_session.c:998 Send signal sofia/sipinterface_4/190 at sip.example.com [BREAK]
2013-06-11 22:34:11.703580 [DEBUG] sofia.c:5685 Channel sofia/sipinterface_4/190 at sip.example.com entering state [early][183]
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:989 rev |[(nil)|37     ][DEBUG_GSMOPEN  989  ][gsm01     ][-1,24, 3] gsmopen/gsm01/XXXXXXXXXX CHANNEL got SWITCH_MESSAGE_INDICATE_AUDIO_SYNC
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:999 rev |[(nil)|37     ][DEBUG_GSMOPEN  999  ][gsm01     ][-1,24, 3] gsmopen/gsm01/XXXXXXXXXX CHANNEL got SWITCH_MESSAGE_INDICATE_BRIDGE
2013-06-11 22:34:11.703580 [DEBUG] switch_core_session.c:853 Send signal gsmopen/gsm01/XXXXXXXXXX [BREAK]
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:665 rev |[(nil)|37     ][DEBUG_GSMOPEN  665  ][gsm01     ][-1,24, 3] gsm01 CHANNEL KILL_CHANNEL
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:676 rev |[(nil)|37     ][DEBUG_GSMOPEN  676  ][gsm01     ][-1,24, 3] gsmopen/gsm01/XXXXXXXXXX CHANNEL got SWITCH_SIG_BREAK
2013-06-11 22:34:11.703580 [DEBUG] switch_core_session.c:853 Send signal sofia/sipinterface_4/190 at sip.example.com [BREAK]
2013-06-11 22:34:11.703580 [DEBUG] switch_ivr_bridge.c:1391 (gsmopen/gsm01/XXXXXXXXXX) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2013-06-11 22:34:11.703580 [DEBUG] switch_core_session.c:1333 Send signal gsmopen/gsm01/XXXXXXXXXX [BREAK]
2013-06-11 22:34:11.703580 [DEBUG] switch_core_state_machine.c:415 (gsmopen/gsm01/XXXXXXXXXX) Running State Change CS_EXCHANGE_MEDIA
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:665 rev |[(nil)|37     ][DEBUG_GSMOPEN  665  ][gsm01     ][-1,24, 3] gsm01 CHANNEL KILL_CHANNEL
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:676 rev |[(nil)|37     ][DEBUG_GSMOPEN  676  ][gsm01     ][-1,24, 3] gsmopen/gsm01/XXXXXXXXXX CHANNEL got SWITCH_SIG_BREAK
2013-06-11 22:34:11.703580 [DEBUG] switch_core_state_machine.c:480 (gsmopen/gsm01/XXXXXXXXXX) State EXCHANGE_MEDIA
2013-06-11 22:34:11.703580 [DEBUG] mod_gsmopen.cpp:703 rev |[(nil)|37     ][DEBUG_GSMOPEN  703  ][gsm01     ][-1,24, 3] gsm01 CHANNEL EXCHANGE_MEDIA
2013-06-11 22:34:11.743580 [DEBUG] mod_gsmopen.cpp:770 rev |[(nil)|37     ][DEBUG_GSMOPEN  770  ][gsm01     ][-1,24, 3] read more than 320, samples=640
2013-06-11 22:34:11.743580 [DEBUG] mod_gsmopen.cpp:837 rev |[(nil)|37     ][DEBUG_GSMOPEN  837  ][gsm01     ][-1,24, 3] BREAK: CHANNEL READ FRAME goto CNG
2013-06-11 22:34:11.803580 [DEBUG] switch_rtp.c:3704 Correct ip/port confirmed.
2013-06-11 22:34:11.863583 [DEBUG] mod_gsmopen.cpp:1002 rev |[(nil)|37     ][DEBUG_GSMOPEN  1002 ][gsm01     ][-1,24, 3] gsmopen/gsm01/XXXXXXXXXX CHANNEL got SWITCH_MESSAGE_INDICATE_TRANSCODING_NECESSARY
-------------- next part --------------
A non-text attachment was scrubbed...
Name: gsmopen.conf.xml
Type: text/xml
Size: 693 bytes
Desc: not available
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20130611/8c4f6abd/attachment-0001.xml 
-------------- next part --------------
?2013-06-11 22:30:24.323580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (R)
2013-06-11 22:30:24.323580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:30:24.343577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (G)
2013-06-11 22:30:24.343577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (?)
2013-06-11 22:30:24.343577 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:30:24.363580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CREG: 1,1| la_counter=2
2013-06-11 22:30:24.363580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:30:24.363580 [DEBUG] gsmopen_protocol.cpp:1008 rev |[(nil)|37     ][DEBUG_GSMOPEN  1008 ][gsm01     ][-1, 0, 0] |+CREG: 1,1| +CREG: Display: 1, Registration=1
2013-06-11 22:30:24.363580 [DEBUG] gsmopen_protocol.cpp:1022 rev |[(nil)|37     ][DEBUG_GSMOPEN  1022 ][gsm01     ][-1, 0, 0] |+CREG: 1,1| CELLPHONE is registered to the HOME network
2013-06-11 22:30:24.363580 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:21.603580 [DEBUG] switch_loadable_module.c:848 Write lock interface 'gsmopen' to wait for existing references.
2013-06-11 22:31:21.603580 [NOTICE] switch_loadable_module.c:856 Deleting Endpoint 'gsmopen'
2013-06-11 22:31:21.603580 [NOTICE] switch_loadable_module.c:991 Deleting API Function 'gsm'
2013-06-11 22:31:21.603580 [DEBUG] switch_loadable_module.c:993 Write lock interface 'gsm' to wait for existing references.
2013-06-11 22:31:21.603580 [NOTICE] switch_loadable_module.c:991 Deleting API Function 'gsmopen'
2013-06-11 22:31:21.603580 [DEBUG] switch_loadable_module.c:993 Write lock interface 'gsmopen' to wait for existing references.
2013-06-11 22:31:21.603580 [NOTICE] switch_loadable_module.c:991 Deleting API Function 'gsmopen_boost_audio'
2013-06-11 22:31:21.603580 [DEBUG] switch_loadable_module.c:993 Write lock interface 'gsmopen_boost_audio' to wait for existing references.
2013-06-11 22:31:21.603580 [INFO] mod_enum.c:872 ENUM Reloaded
2013-06-11 22:31:21.603580 [NOTICE] switch_loadable_module.c:991 Deleting API Function 'gsmopen_dump'
2013-06-11 22:31:21.603580 [DEBUG] switch_loadable_module.c:993 Write lock interface 'gsmopen_dump' to wait for existing references.
2013-06-11 22:31:21.603580 [NOTICE] switch_loadable_module.c:991 Deleting API Function 'gsmopen_sendsms'
2013-06-11 22:31:21.603580 [DEBUG] switch_loadable_module.c:993 Write lock interface 'gsmopen_sendsms' to wait for existing references.
2013-06-11 22:31:21.603580 [DEBUG] switch_loadable_module.c:1129 Write lock interface 'sms' to wait for existing references.
2013-06-11 22:31:21.603580 [NOTICE] switch_loadable_module.c:1138 Deleting Chat interface 'sms'
2013-06-11 22:31:21.603580 [WARNING] mod_gsmopen.cpp:1995 rev |[(nil)|37     ][WARNINGA  1995 ][gsm01     ][-1, 0, 0] SHUTDOWN interface_id=1
2013-06-11 22:31:21.603580 [INFO] switch_time.c:1173 Timezone reloaded 530 definitions
2013-06-11 22:31:21.683579 [DEBUG] mod_gsmopen.cpp:2872 rev |[(nil)|37     ][DEBUG_GSMOPEN  2872 ][gsm01     ][-1, 0, 0] EXIT
2013-06-11 22:31:22.603581 [DEBUG] gsmopen_protocol.cpp:3192 rev |[(nil)|37     ][DEBUG_GSMOPEN  3192 ][gsm01     ][-1, 0, 0] serial_audio_shutdown res=9 (controldev_audio_fd is 0)
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:2033 rev |[(nil)|37     ][DEBUG_GSMOPEN  2033 ][gsm01     ][-1, 0, 0] serial_shutdown res=0 (controldevfd is 0)
2013-06-11 22:31:22.603581 [NOTICE] switch_event.c:403 Subclass reservation deleted for /usr/src/freeswitch-1.2.9/src/mod/endpoints/mod_gsmopen/mod_gsmopen.cpp:gsmopen::incoming_sms
2013-06-11 22:31:22.603581 [NOTICE] mod_gsmopen.cpp:1215 rev |[(nil)|37     ][NOTICA  1215 ][none      ][-1,-1,-1] GSMOPEN Charset Output Test 0 èéòàù
2013-06-11 22:31:22.603581 [NOTICE] mod_gsmopen.cpp:1216 rev |[(nil)|37     ][NOTICA  1216 ][none      ][-1,-1,-1] GSMOPEN Charset Output Test 1 ç°§^£
2013-06-11 22:31:22.603581 [NOTICE] mod_gsmopen.cpp:1217 rev |[(nil)|37     ][NOTICA  1217 ][none      ][-1,-1,-1] GSMOPEN Charset Output Test 2 ???????
2013-06-11 22:31:22.603581 [NOTICE] mod_gsmopen.cpp:1218 rev |[(nil)|37     ][NOTICA  1218 ][none      ][-1,-1,-1] GSMOPEN Charset Output Test 3 ??????
2013-06-11 22:31:22.603581 [NOTICE] mod_gsmopen.cpp:1219 rev |[(nil)|37     ][NOTICA  1219 ][none      ][-1,-1,-1] GSMOPEN Charset Output Test 4 ???
2013-06-11 22:31:22.603581 [NOTICE] mod_gsmopen.cpp:1220 rev |[(nil)|37     ][NOTICA  1220 ][none      ][-1,-1,-1] GSMOPEN Charset Output Test 5 ??
2013-06-11 22:31:22.603581 [NOTICE] mod_gsmopen.cpp:1221 rev |[(nil)|37     ][NOTICA  1221 ][none      ][-1,-1,-1] GSMOPEN Charset Output Test 6 ??
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1234 rev |[(nil)|37     ][DEBUG_GSMOPEN  1234 ][none      ][-1,-1,-1] Default globals.dialplan=XML
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1236 rev |[(nil)|37     ][DEBUG_GSMOPEN  1236 ][none      ][-1,-1,-1] Default globals.destination=5000
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1238 rev |[(nil)|37     ][DEBUG_GSMOPEN  1238 ][none      ][-1,-1,-1] Default globals.context=default
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1246 rev |[(nil)|37     ][DEBUG_GSMOPEN  1246 ][none      ][-1,-1,-1] globals.debug=0
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1248 rev |[(nil)|37     ][DEBUG_GSMOPEN  1248 ][none      ][-1,-1,-1] globals.debug=8
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1254 rev |[(nil)|37     ][DEBUG_GSMOPEN  1254 ][none      ][-1,-1,-1] globals.dialplan=XML
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1260 rev |[(nil)|37     ][DEBUG_GSMOPEN  1260 ][none      ][-1,-1,-1] globals.context=sip.example.com
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1251 rev |[(nil)|37     ][DEBUG_GSMOPEN  1251 ][none      ][-1,-1,-1] globals.hold_music=
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1257 rev |[(nil)|37     ][DEBUG_GSMOPEN  1257 ][none      ][-1,-1,-1] globals.destination=190
2013-06-11 22:31:22.603581 [WARNING] mod_gsmopen.cpp:1673 rev |[(nil)|37     ][WARNINGA  1673 ][gsm01     ][-1, 0, 0] STARTING interface_id=1
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1674 rev |[(nil)|37     ][DEBUG_GSMOPEN  1674 ][gsm01     ][-1, 0, 0] id=1
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1675 rev |[(nil)|37     ][DEBUG_GSMOPEN  1675 ][gsm01     ][-1, 0, 0] name=gsm01
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1676 rev |[(nil)|37     ][DEBUG_GSMOPEN  1676 ][gsm01     ][-1, 0, 0] hold-music=
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1677 rev |[(nil)|37     ][DEBUG_GSMOPEN  1677 ][gsm01     ][-1, 0, 0] context=sip.example.com
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1678 rev |[(nil)|37     ][DEBUG_GSMOPEN  1678 ][gsm01     ][-1, 0, 0] dialplan=XML
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1679 rev |[(nil)|37     ][DEBUG_GSMOPEN  1679 ][gsm01     ][-1, 0, 0] destination=190
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1680 rev |[(nil)|37     ][DEBUG_GSMOPEN  1680 ][gsm01     ][-1, 0, 0] controldevice_name=/dev/ttyUSB3
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1681 rev |[(nil)|37     ][DEBUG_GSMOPEN  1681 ][gsm01     ][-1, 0, 0] controldevice_audio_name=/dev/ttyUSB5
2013-06-11 22:31:22.603581 [DEBUG] mod_gsmopen.cpp:1683 rev |[(nil)|37     ][DEBUG_GSMOPEN  1683 ][gsm01     ][-1, 0, 0] gsmopen_serial_sync_period=300
2013-06-11 22:31:22.603581 [DEBUG] gsmopen_protocol.cpp:120 rev |[(nil)|37     ][DEBUG_GSMOPEN  120  ][gsm01     ][-1, 0, 0] port /dev/ttyUSB3, SUCCESS open
2013-06-11 22:31:22.603581 [DEBUG] gsmopen_protocol.cpp:184 rev |[(nil)|37     ][DEBUG_GSMOPEN  184  ][gsm01     ][-1, 0, 0] sleeping for 500000 usec
2013-06-11 22:31:23.103581 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CFUN=1
2013-06-11 22:31:23.103581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:23.103581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:23.103581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:23.103581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:23.123576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (F)
2013-06-11 22:31:23.123576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (U)
2013-06-11 22:31:23.123576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (N)
2013-06-11 22:31:23.123576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:23.123576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:23.123576 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT^CURC=0
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (^)
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (U)
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (R)
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:23.143583 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:23.163581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:23.163581 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:23.163581 [DEBUG] gsmopen_protocol.cpp:256 rev |[(nil)|37     ][DEBUG_GSMOPEN  256  ][gsm01     ][-1, 0, 0] sleeping for 500000 usec
2013-06-11 22:31:23.663579 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT
2013-06-11 22:31:23.663579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:23.663579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:23.663579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+mode=0
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (m)
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (o)
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (d)
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (e)
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:23.703579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:23.723577 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |COMMAND NOT SUPPORT| la_counter=1
2013-06-11 22:31:23.823577 [DEBUG] gsmopen_protocol.cpp:270 rev |[(nil)|37     ][DEBUG_GSMOPEN  270  ][gsm01     ][-1, 0, 0] AT+mode=0 does not get OK from the phone. If it is NOT Motorola, no problem.
2013-06-11 22:31:23.883579 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: ATZ
2013-06-11 22:31:23.883579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:23.883579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:23.883579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (Z)
2013-06-11 22:31:23.883579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:23.903580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:23.903580 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:23.903580 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: ATE0
2013-06-11 22:31:23.903580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:23.903580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:23.903580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:23.903580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:23.903580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |ATE0| la_counter=2
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CFUN=1
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (F)
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (U)
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (N)
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:23.923580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT^CURC=0
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (^)
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (U)
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (R)
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:23.963582 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:23.983583 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:23.983583 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:23.983583 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CMEE=0
2013-06-11 22:31:23.983583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:23.983583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:23.983583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:23.983583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:23.983583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:23.983583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:23.983583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:23.983583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:23.983583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.003577 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.023580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:24.023580 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.023580 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: ATI0
2013-06-11 22:31:24.023580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.023580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.023580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.023580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.023580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |Manufacturer: huawei| la_counter=6
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |Model: K3765| la_counter=6
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 2: |Revision: 11.126.03.09.00| la_counter=6
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 3: |IMEI: 351617043495734| la_counter=6
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 4: |+GCAP: +CGSM,+FCLASS,+DS| la_counter=6
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 5: |OK| la_counter=6
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: ATI1
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:24.043581 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |Manufacturer: huawei| la_counter=6
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |Model: K3765| la_counter=6
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 2: |Revision: 11.126.03.09.00| la_counter=6
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 3: |IMEI: 351617043495734| la_counter=6
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 4: |+GCAP: +CGSM,+FCLASS,+DS| la_counter=6
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 5: |OK| la_counter=6
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: ATI2
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (2)
2013-06-11 22:31:24.063582 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.083584 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |Manufacturer: huawei| la_counter=6
2013-06-11 22:31:24.083584 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |Model: K3765| la_counter=6
2013-06-11 22:31:24.083584 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 2: |Revision: 11.126.03.09.00| la_counter=6
2013-06-11 22:31:24.083584 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 3: |IMEI: 351617043495734| la_counter=6
2013-06-11 22:31:24.083584 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 4: |+GCAP: +CGSM,+FCLASS,+DS| la_counter=6
2013-06-11 22:31:24.083584 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 5: |OK| la_counter=6
2013-06-11 22:31:24.083584 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.083584 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: ATI3
2013-06-11 22:31:24.083584 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.083584 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.083584 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.103576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (3)
2013-06-11 22:31:24.103576 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |Manufacturer: huawei| la_counter=6
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |Model: K3765| la_counter=6
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 2: |Revision: 11.126.03.09.00| la_counter=6
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 3: |IMEI: 351617043495734| la_counter=6
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 4: |+GCAP: +CGSM,+FCLASS,+DS| la_counter=6
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 5: |OK| la_counter=6
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: ATI4
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (4)
2013-06-11 22:31:24.123580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |Manufacturer: huawei| la_counter=6
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |Model: K3765| la_counter=6
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 2: |Revision: 11.126.03.09.00| la_counter=6
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 3: |IMEI: 351617043495734| la_counter=6
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 4: |+GCAP: +CGSM,+FCLASS,+DS| la_counter=6
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 5: |OK| la_counter=6
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: ATI5
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (5)
2013-06-11 22:31:24.143579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |Manufacturer: huawei| la_counter=6
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |Model: K3765| la_counter=6
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 2: |Revision: 11.126.03.09.00| la_counter=6
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 3: |IMEI: 351617043495734| la_counter=6
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 4: |+GCAP: +CGSM,+FCLASS,+DS| la_counter=6
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 5: |OK| la_counter=6
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: ATI6
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (6)
2013-06-11 22:31:24.163580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.183583 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |Manufacturer: huawei| la_counter=6
2013-06-11 22:31:24.183583 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |Model: K3765| la_counter=6
2013-06-11 22:31:24.183583 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 2: |Revision: 11.126.03.09.00| la_counter=6
2013-06-11 22:31:24.183583 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 3: |IMEI: 351617043495734| la_counter=6
2013-06-11 22:31:24.183583 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 4: |+GCAP: +CGSM,+FCLASS,+DS| la_counter=6
2013-06-11 22:31:24.183583 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 5: |OK| la_counter=6
2013-06-11 22:31:24.183583 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.183583 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: ATI7
2013-06-11 22:31:24.183583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.183583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.203576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.203576 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (7)
2013-06-11 22:31:24.203576 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |Manufacturer: huawei| la_counter=6
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |Model: K3765| la_counter=6
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 2: |Revision: 11.126.03.09.00| la_counter=6
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 3: |IMEI: 351617043495734| la_counter=6
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 4: |+GCAP: +CGSM,+FCLASS,+DS| la_counter=6
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 5: |OK| la_counter=6
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: ATI8
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (8)
2013-06-11 22:31:24.223577 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |Manufacturer: huawei| la_counter=6
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |Model: K3765| la_counter=6
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 2: |Revision: 11.126.03.09.00| la_counter=6
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 3: |IMEI: 351617043495734| la_counter=6
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 4: |+GCAP: +CGSM,+FCLASS,+DS| la_counter=6
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 5: |OK| la_counter=6
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: ATI9
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (9)
2013-06-11 22:31:24.243578 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |Manufacturer: huawei| la_counter=6
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |Model: K3765| la_counter=6
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 2: |Revision: 11.126.03.09.00| la_counter=6
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 3: |IMEI: 351617043495734| la_counter=6
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 4: |+GCAP: +CGSM,+FCLASS,+DS| la_counter=6
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 5: |OK| la_counter=6
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CGMI
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (G)
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.263581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.283579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.303577 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |huawei| la_counter=2
2013-06-11 22:31:24.303577 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:24.303577 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.303577 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CGMM
2013-06-11 22:31:24.303577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.303577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.303577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.303577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.303577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (G)
2013-06-11 22:31:24.303577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.303577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.303577 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |K3765| la_counter=2
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CREG=1
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (R)
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (G)
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:24.323579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.363583 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:24.363583 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.363583 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CREG?
2013-06-11 22:31:24.363583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.363583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.363583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.363583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.363583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (R)
2013-06-11 22:31:24.363583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:24.363583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (G)
2013-06-11 22:31:24.363583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (?)
2013-06-11 22:31:24.363583 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CREG: 1,1| la_counter=2
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:1008 rev |[(nil)|37     ][DEBUG_GSMOPEN  1008 ][gsm01     ][-1, 0, 0] |+CREG: 1,1| +CREG: Display: 1, Registration=1
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:1022 rev |[(nil)|37     ][DEBUG_GSMOPEN  1022 ][gsm01     ][-1, 0, 0] |+CREG: 1,1| CELLPHONE is registered to the HOME network
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CSQ
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (Q)
2013-06-11 22:31:24.383582 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.403579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CSQ: 22,99| la_counter=2
2013-06-11 22:31:24.403579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:24.403579 [DEBUG] gsmopen_protocol.cpp:980 rev |[(nil)|37     ][DEBUG_GSMOPEN  980  ][gsm01     ][-1, 0, 0] |+CSQ: 22,99| +CSQ: Signal Quality: 22, Error Rate=99
2013-06-11 22:31:24.403579 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.403579 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+GSN
2013-06-11 22:31:24.403579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.403579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.403579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.423579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (G)
2013-06-11 22:31:24.423579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:24.423579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (N)
2013-06-11 22:31:24.423579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.443583 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |351617043495734| la_counter=2
2013-06-11 22:31:24.443583 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:24.443583 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.443583 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CIMI
2013-06-11 22:31:24.443583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.443583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.443583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.443583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.443583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.443583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.443583 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.443583 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.463581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |226030390267732| la_counter=2
2013-06-11 22:31:24.463581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:24.463581 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.463581 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CNMI=2,1,0,0,0
2013-06-11 22:31:24.463581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.463581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.463581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.463581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.463581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (N)
2013-06-11 22:31:24.463581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.463581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.463581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:24.483579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (2)
2013-06-11 22:31:24.483579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.483579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:24.483579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.483579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.483579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.483579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.483579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.483579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.483579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.503581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:24.503581 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.503581 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CPMS="ME","ME","ME"
2013-06-11 22:31:24.503581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.503581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.503581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.503581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.503581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (P)
2013-06-11 22:31:24.503581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.503581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:24.503581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:24.503581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.523579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.543581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CPMS: 1,23,1,23,1,23| la_counter=2
2013-06-11 22:31:24.543581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:24.543581 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.543581 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CNMI=2,1,0,0,0
2013-06-11 22:31:24.543581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.543581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.543581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.543581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.543581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (N)
2013-06-11 22:31:24.543581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.563579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.563579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:24.563579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (2)
2013-06-11 22:31:24.563579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.563579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:24.563579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.563579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.563579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.563579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.563579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.563579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.563579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.583580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:24.583580 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.583580 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CPMS="ME","ME","ME"
2013-06-11 22:31:24.583580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.583580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.583580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.583580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.583580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (P)
2013-06-11 22:31:24.583580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.583580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.603579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.623580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CPMS: 1,23,1,23,1,23| la_counter=2
2013-06-11 22:31:24.623580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:24.623580 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.623580 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CNMI=2,1,0,0,0
2013-06-11 22:31:24.623580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.623580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.623580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.623580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (N)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (2)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.643580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.663581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:24.663581 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.663581 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CSCA?
2013-06-11 22:31:24.663581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.663581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.663581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.663581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.663581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:24.683579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.683579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.683579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (?)
2013-06-11 22:31:24.683579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CSCA: "+40766000510",145| la_counter=2
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:1174 rev |[(nil)|37     ][DEBUG_GSMOPEN  1174 ][gsm01     ][-1, 0, 0] |+CSCA: "+40766000510",145| +CSCA: Message Center Address!
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CMGF?
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (G)
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (F)
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (?)
2013-06-11 22:31:24.703578 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.723578 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CMGF: 0| la_counter=2
2013-06-11 22:31:24.723578 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:24.723578 [DEBUG] gsmopen_protocol.cpp:1179 rev |[(nil)|37     ][DEBUG_GSMOPEN  1179 ][gsm01     ][-1, 0, 0] |+CMGF: 0| +CMGF: Message Format!
2013-06-11 22:31:24.723578 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.723578 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CMGF=1
2013-06-11 22:31:24.723578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.723578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.723578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.723578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.723578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.723578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (G)
2013-06-11 22:31:24.743580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (F)
2013-06-11 22:31:24.743580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:24.743580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:24.743580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CSMP=17,167,0,8
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (P)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (7)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (6)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (7)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.763585 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.783579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.783579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (8)
2013-06-11 22:31:24.783579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.843581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:24.843581 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.843581 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CSCS?
2013-06-11 22:31:24.843581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.843581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.843581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.843581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.843581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:24.843581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.843581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:24.843581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (?)
2013-06-11 22:31:24.843581 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.863580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CSCS: "IRA"| la_counter=2
2013-06-11 22:31:24.863580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:24.863580 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.863580 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CSCS="UCS2"
2013-06-11 22:31:24.863580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.863580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.863580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.863580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.863580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:24.863580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.863580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:24.883580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:24.883580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.883580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (U)
2013-06-11 22:31:24.883580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.883580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:24.883580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (2)
2013-06-11 22:31:24.883580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:24.883580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CMGF=0
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (G)
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (F)
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.903579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.923579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:24.923579 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.923579 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CMER=?
2013-06-11 22:31:24.923579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.923579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.943580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.943580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.943580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.943580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:24.943580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (R)
2013-06-11 22:31:24.943580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:24.943580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (?)
2013-06-11 22:31:24.943580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CMER: (0-3),(0),(0),(0-1),(0-1)| la_counter=2
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CMER=3,0,0,1
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (R)
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (3)
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.963582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.983579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.983579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:24.983579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:24.983579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:24.983579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CIND=?
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (N)
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (D)
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (?)
2013-06-11 22:31:25.003582 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:25.023579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+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))| la_counter=2
2013-06-11 22:31:25.023579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:25.023579 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:25.023579 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT*ECAM=?
2013-06-11 22:31:25.023579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:25.023579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:25.023579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (*)
2013-06-11 22:31:25.023579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:25.043579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:25.043579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:25.043579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:25.043579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:25.043579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (?)
2013-06-11 22:31:25.043579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:25.063579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |COMMAND NOT SUPPORT| la_counter=1
2013-06-11 22:31:25.163581 [DEBUG] gsmopen_protocol.cpp:493 rev |[(nil)|37     ][DEBUG_GSMOPEN  493  ][gsm01     ][-1, 0, 0] AT*ECAM=? failed, continue
2013-06-11 22:31:25.163581 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT*ECAM=1
2013-06-11 22:31:25.163581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:25.163581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:25.163581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (*)
2013-06-11 22:31:25.163581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:25.163581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:25.163581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:25.163581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:25.163581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:25.163581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:25.163581 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:25.183584 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |COMMAND NOT SUPPORT| la_counter=1
2013-06-11 22:31:25.283578 [DEBUG] gsmopen_protocol.cpp:498 rev |[(nil)|37     ][DEBUG_GSMOPEN  498  ][gsm01     ][-1, 0, 0] AT*ECAM=1 failed, continue
2013-06-11 22:31:25.283578 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CLCC=0
2013-06-11 22:31:25.283578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:25.283578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:25.283578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:25.283578 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:25.303581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (L)
2013-06-11 22:31:25.303581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:25.303581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:25.303581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:25.303581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:25.303581 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |ERROR| la_counter=1
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:1590 rev |[(nil)|37     ][DEBUG_GSMOPEN  1590 ][gsm01     ][-1, 0, 0] got ERROR
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:507 rev |[(nil)|37     ][DEBUG_GSMOPEN  507  ][gsm01     ][-1, 0, 0] AT+CLCC=0 failed, continue
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CLIP=1
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (L)
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (I)
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (P)
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:25.323580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:25.343581 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:25.343581 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:25.343581 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+MCST=1
2013-06-11 22:31:25.343581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:25.343581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:25.343581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:25.363579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (M)
2013-06-11 22:31:25.363579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:25.363579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:25.363579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:25.363579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:25.363579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:25.363579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:25.383582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |COMMAND NOT SUPPORT| la_counter=1
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:523 rev |[(nil)|37     ][DEBUG_GSMOPEN  523  ][gsm01     ][-1, 0, 0] AT+MCST=1 does not get OK from the phone. If it is NOT Motorola, no problem.
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:2165 rev |[(nil)|37     ][DEBUG_GSMOPEN  2165 ][gsm01     ][-1, 0, 0] sending: at+cmic=0,9, expecting: OK
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (a)
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (t)
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (c)
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (m)
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (i)
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (c)
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (9)
2013-06-11 22:31:25.483582 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:25.503580 [DEBUG] gsmopen_protocol.cpp:609 rev |[(nil)|37     ][DEBUG_GSMOPEN  609  ][gsm01     ][-1, 0, 0] TIMEOUT=500
2013-06-11 22:31:25.523580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |COMMAND NOT SUPPORT| la_counter=1
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:533 rev |[(nil)|37     ][DEBUG_GSMOPEN  533  ][gsm01     ][-1, 0, 0] at+cmic=0,9 does not get OK from the phone. Continuing.
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:2165 rev |[(nil)|37     ][DEBUG_GSMOPEN  2165 ][gsm01     ][-1, 0, 0] sending: AT+CKPD="EEE", expecting: OK
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (K)
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (P)
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (D)
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (")
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:26.023581 [DEBUG] gsmopen_protocol.cpp:609 rev |[(nil)|37     ][DEBUG_GSMOPEN  609  ][gsm01     ][-1, 0, 0] TIMEOUT=500
2013-06-11 22:31:26.043582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |ERROR| la_counter=1
2013-06-11 22:31:26.043582 [DEBUG] gsmopen_protocol.cpp:1590 rev |[(nil)|37     ][DEBUG_GSMOPEN  1590 ][gsm01     ][-1, 0, 0] got ERROR
2013-06-11 22:31:26.043582 [DEBUG] gsmopen_protocol.cpp:542 rev |[(nil)|37     ][DEBUG_GSMOPEN  542  ][gsm01     ][-1, 0, 0] AT+CKPD="EEE" does not get OK from the phone. Continuing.
2013-06-11 22:31:26.043582 [DEBUG] gsmopen_protocol.cpp:2165 rev |[(nil)|37     ][DEBUG_GSMOPEN  2165 ][gsm01     ][-1, 0, 0] sending: AT+CSSN=1,0, expecting: OK
2013-06-11 22:31:26.043582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:26.043582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:26.043582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:26.043582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:26.043582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:26.063580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:26.063580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (N)
2013-06-11 22:31:26.063580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:26.063580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (1)
2013-06-11 22:31:26.063580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (,)
2013-06-11 22:31:26.063580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:26.063580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:26.063580 [DEBUG] gsmopen_protocol.cpp:609 rev |[(nil)|37     ][DEBUG_GSMOPEN  609  ][gsm01     ][-1, 0, 0] TIMEOUT=500
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:1578 rev |[(nil)|37     ][DEBUG_GSMOPEN  1578 ][gsm01     ][-1, 0, 0] |OK| got what EXPECTED
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:2165 rev |[(nil)|37     ][DEBUG_GSMOPEN  2165 ][gsm01     ][-1, 0, 0] sending: at+sidet=0, expecting: OK
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (a)
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (t)
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (s)
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (i)
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (d)
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (e)
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (t)
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (0)
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:26.083582 [DEBUG] gsmopen_protocol.cpp:609 rev |[(nil)|37     ][DEBUG_GSMOPEN  609  ][gsm01     ][-1, 0, 0] TIMEOUT=500
2013-06-11 22:31:26.103580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |COMMAND NOT SUPPORT| la_counter=1
2013-06-11 22:31:26.603581 [DEBUG] gsmopen_protocol.cpp:560 rev |[(nil)|37     ][DEBUG_GSMOPEN  560  ][gsm01     ][-1, 0, 0] at+sidet=0 does not get OK from the phone. Continuing.
2013-06-11 22:31:26.603581 [DEBUG] gsmopen_protocol.cpp:2165 rev |[(nil)|37     ][DEBUG_GSMOPEN  2165 ][gsm01     ][-1, 0, 0] sending: at+clvl=3, expecting: OK
2013-06-11 22:31:26.603581 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (a)
2013-06-11 22:31:26.623580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (t)
2013-06-11 22:31:26.623580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:26.623580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (c)
2013-06-11 22:31:26.623580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (l)
2013-06-11 22:31:26.623580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (v)
2013-06-11 22:31:26.623580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (l)
2013-06-11 22:31:26.623580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (=)
2013-06-11 22:31:26.623580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (3)
2013-06-11 22:31:26.623580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:26.623580 [DEBUG] gsmopen_protocol.cpp:609 rev |[(nil)|37     ][DEBUG_GSMOPEN  609  ][gsm01     ][-1, 0, 0] TIMEOUT=500
2013-06-11 22:31:26.643579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:26.643579 [DEBUG] gsmopen_protocol.cpp:1578 rev |[(nil)|37     ][DEBUG_GSMOPEN  1578 ][gsm01     ][-1, 0, 0] |OK| got what EXPECTED
2013-06-11 22:31:26.643579 [DEBUG] gsmopen_protocol.cpp:3160 rev |[(nil)|37     ][DEBUG_GSMOPEN  3160 ][gsm01     ][-1, 0, 0] port /dev/ttyUSB5, SUCCESS open
2013-06-11 22:31:26.643579 [DEBUG] gsmopen_protocol.cpp:3175 rev |[(nil)|37     ][DEBUG_GSMOPEN  3175 ][gsm01     ][-1, 0, 0] serial_audio_init res=0
2013-06-11 22:31:26.643579 [DEBUG] gsmopen_protocol.cpp:3192 rev |[(nil)|37     ][DEBUG_GSMOPEN  3192 ][gsm01     ][-1, 0, 0] serial_audio_shutdown res=0 (controldev_audio_fd is 0)
2013-06-11 22:31:26.643579 [DEBUG] gsmopen_protocol.cpp:2980 rev |[(nil)|37     ][DEBUG_GSMOPEN  2980 ][none      ][-1,-1,-1] setting boost 0 to 512.000000
2013-06-11 22:31:26.643579 [DEBUG] gsmopen_protocol.cpp:2980 rev |[(nil)|37     ][DEBUG_GSMOPEN  2980 ][none      ][-1,-1,-1] setting boost 0 to 512.000000
2013-06-11 22:31:26.843582 [WARNING] mod_gsmopen.cpp:1768 rev |[(nil)|37     ][WARNINGA  1768 ][gsm01     ][-1, 0, 0] STARTED interface_id=1
2013-06-11 22:31:26.843582 [DEBUG] mod_gsmopen.cpp:1785 rev |[(nil)|37     ][DEBUG_GSMOPEN  1785 ][gsm01     ][-1, 0, 0] id=1
2013-06-11 22:31:26.843582 [DEBUG] mod_gsmopen.cpp:1786 rev |[(nil)|37     ][DEBUG_GSMOPEN  1786 ][gsm01     ][-1, 0, 0] name=gsm01
2013-06-11 22:31:26.843582 [DEBUG] mod_gsmopen.cpp:1787 rev |[(nil)|37     ][DEBUG_GSMOPEN  1787 ][gsm01     ][-1, 0, 0] context=sip.example.com
2013-06-11 22:31:26.843582 [DEBUG] mod_gsmopen.cpp:1788 rev |[(nil)|37     ][DEBUG_GSMOPEN  1788 ][gsm01     ][-1, 0, 0] hold-music=
2013-06-11 22:31:26.843582 [DEBUG] mod_gsmopen.cpp:1789 rev |[(nil)|37     ][DEBUG_GSMOPEN  1789 ][gsm01     ][-1, 0, 0] dialplan=XML
2013-06-11 22:31:26.843582 [DEBUG] mod_gsmopen.cpp:1790 rev |[(nil)|37     ][DEBUG_GSMOPEN  1790 ][gsm01     ][-1, 0, 0] destination=190
2013-06-11 22:31:26.843582 [DEBUG] mod_gsmopen.cpp:1791 rev |[(nil)|37     ][DEBUG_GSMOPEN  1791 ][gsm01     ][-1, 0, 0] controldevice_name=/dev/ttyUSB3
2013-06-11 22:31:26.843582 [DEBUG] mod_gsmopen.cpp:1792 rev |[(nil)|37     ][DEBUG_GSMOPEN  1792 ][gsm01     ][-1, 0, 0] gsmopen_serial_sync_period=300
2013-06-11 22:31:26.843582 [DEBUG] mod_gsmopen.cpp:1793 rev |[(nil)|37     ][DEBUG_GSMOPEN  1793 ][gsm01     ][-1, 0, 0] controldevice_audio_name=/dev/ttyUSB5
2013-06-11 22:31:26.843582 [NOTICE] switch_loadable_module.c:146 Adding Endpoint 'gsmopen'
2013-06-11 22:31:26.843582 [NOTICE] switch_loadable_module.c:298 Adding API Function 'gsm'
2013-06-11 22:31:26.843582 [NOTICE] switch_loadable_module.c:298 Adding API Function 'gsmopen'
2013-06-11 22:31:26.843582 [NOTICE] switch_loadable_module.c:298 Adding API Function 'gsmopen_boost_audio'
2013-06-11 22:31:26.843582 [NOTICE] switch_loadable_module.c:298 Adding API Function 'gsmopen_dump'
2013-06-11 22:31:26.843582 [NOTICE] switch_loadable_module.c:298 Adding API Function 'gsmopen_sendsms'
2013-06-11 22:31:26.843582 [NOTICE] switch_loadable_module.c:405 Adding Chat interface 'sms'
2013-06-11 22:31:26.883579 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT
2013-06-11 22:31:26.883579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:26.883579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:26.883579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:26.903580 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |OK| la_counter=1
2013-06-11 22:31:26.903580 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK
2013-06-11 22:31:26.903580 [DEBUG] gsmopen_protocol.cpp:2165 rev |[(nil)|37     ][DEBUG_GSMOPEN  2165 ][gsm01     ][-1, 0, 0] sending: AT+CBC, expecting: OK
2013-06-11 22:31:26.903580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:26.903580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:26.903580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:26.903580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:26.903580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (B)
2013-06-11 22:31:26.903580 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:26.903580 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:26.903580 [DEBUG] gsmopen_protocol.cpp:609 rev |[(nil)|37     ][DEBUG_GSMOPEN  609  ][gsm01     ][-1, 0, 0] TIMEOUT=500
2013-06-11 22:31:26.923577 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CBC: 2,0| la_counter=2
2013-06-11 22:31:26.923577 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:26.923577 [DEBUG] gsmopen_protocol.cpp:959 rev |[(nil)|37     ][DEBUG_GSMOPEN  959  ][gsm01     ][-1, 0, 0] |+CBC: 2,0| +CBC: Powered by power supply, battery strenght=0
2013-06-11 22:31:26.923577 [DEBUG] gsmopen_protocol.cpp:1578 rev |[(nil)|37     ][DEBUG_GSMOPEN  1578 ][gsm01     ][-1, 0, 0] |OK| got what EXPECTED
2013-06-11 22:31:26.923577 [DEBUG] gsmopen_protocol.cpp:2165 rev |[(nil)|37     ][DEBUG_GSMOPEN  2165 ][gsm01     ][-1, 0, 0] sending: AT+CSQ, expecting: OK
2013-06-11 22:31:26.923577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:26.923577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:26.923577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:26.923577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:26.923577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (S)
2013-06-11 22:31:26.923577 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (Q)
2013-06-11 22:31:26.943576 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:26.943576 [DEBUG] gsmopen_protocol.cpp:609 rev |[(nil)|37     ][DEBUG_GSMOPEN  609  ][gsm01     ][-1, 0, 0] TIMEOUT=500
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CSQ: 22,99| la_counter=2
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:980 rev |[(nil)|37     ][DEBUG_GSMOPEN  980  ][gsm01     ][-1, 0, 0] |+CSQ: 22,99| +CSQ: Signal Quality: 22, Error Rate=99
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1578 rev |[(nil)|37     ][DEBUG_GSMOPEN  1578 ][gsm01     ][-1, 0, 0] |OK| got what EXPECTED
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:2081 rev |[(nil)|37     ][DEBUG_GSMOPEN  2081 ][gsm01     ][-1, 0, 0] sending: AT+CREG?
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (A)
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (T)
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (+)
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (C)
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (R)
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (E)
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (G)
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1918 rev |[(nil)|37     ][DEBUG_GSMOPEN  1918 ][gsm01     ][-1, 0, 0] sent data... (?)
2013-06-11 22:31:26.963579 [DEBUG] gsmopen_protocol.cpp:1966 rev |[(nil)|37     ][DEBUG_GSMOPEN  1966 ][gsm01     ][-1, 0, 0] sent (carriage return)
2013-06-11 22:31:26.983584 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 0: |+CREG: 1,1| la_counter=2
2013-06-11 22:31:26.983584 [DEBUG] gsmopen_protocol.cpp:733 rev |[(nil)|37     ][DEBUG_GSMOPEN  733  ][gsm01     ][-1, 0, 0] Read line 1: |OK| la_counter=2
2013-06-11 22:31:26.983584 [DEBUG] gsmopen_protocol.cpp:1008 rev |[(nil)|37     ][DEBUG_GSMOPEN  1008 ][gsm01     ][-1, 0, 0] |+CREG: 1,1| +CREG: Display: 1, Registration=1
2013-06-11 22:31:26.983584 [DEBUG] gsmopen_protocol.cpp:1022 rev |[(nil)|37     ][DEBUG_GSMOPEN  1022 ][gsm01     ][-1, 0, 0] |+CREG: 1,1| CELLPHONE is registered to the HOME network
2013-06-11 22:31:26.983584 [DEBUG] gsmopen_protocol.cpp:1584 rev |[(nil)|37     ][DEBUG_GSMOPEN  1584 ][gsm01     ][-1, 0, 0] got OK


Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users mailing list