Ticket #424: unable-to-dial.txt

File unable-to-dial.txt, 60.8 KB (added by johnsu01, 3 years ago)

Log

Line 
1May  1 16:47:45 calvino frameworkd.resource INFO     setting resource status for GSM from disabled to enabling
2May  1 16:47:45 calvino ogsmd.modems DEBUG    requested to build modem 'ti_calypso'
3May  1 16:47:45 calvino ogsmd.channel INFO     <CallChannel via unknown>: Creating channel with timeout = 3600 seconds
4May  1 16:47:45 calvino ogsmd.channel INFO     <UnsolicitedResponseChannel via unknown>: Creating channel with timeout = 300 seconds
5May  1 16:47:45 calvino ogsmd.channel INFO     <MiscChannel via unknown>: Creating channel with timeout = 300 seconds
6May  1 16:47:45 calvino ogsmd.modem.abstract DEBUG    trying to open channel MISC
7May  1 16:47:45 calvino ogsmd.modems.ti_calypso INFO     Requesting new channel from 'gsm0710muxd'
8May  1 16:47:45 calvino gsm0710muxd: gsm0710muxd.c:1988:main(): using '/sys/bus/platform/devices/neo1973-pm-gsm.0' as basedir for pm
9May  1 16:47:52 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:1670:open_serial_device(): Opened serial port
10May  1 16:47:52 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:1694:open_serial_device(): Configured serial device
11May  1 16:47:52 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:1816:watchdog(): Watchdog started
12May  1 16:47:52 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:1705:start_muxer(): Configuring modem
13May  1 16:47:53 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:1709:start_muxer(): Modem does not respond to AT commands, trying close mux mode
14May  1 16:47:53 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:1749:start_muxer(): Starting mux mode
15May  1 16:47:53 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:1752:start_muxer(): Waiting for mux-mode
16May  1 16:47:54 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:1754:start_muxer(): Init control channel
17May  1 16:47:55 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:729:c_alloc_channel(): Connecting /dev/pts/1 to virtual channel 1 for ogsmd.misc on /dev/ttySAC0
18May  1 16:47:55 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:1474:extract_frames(): Logical channel 1 opened
19May  1 16:47:55 calvino ogsmd.channel INFO     <MiscChannel via unknown>: initializing
20May  1 16:47:55 calvino ogsmd    DEBUG    (modem init... try #1)
21May  1 16:47:55 calvino ogsmd    DEBUG    read: AT-Command Interpreter ready
22May  1 16:47:55 calvino ogsmd    INFO     <MiscChannel via /dev/pts/1>: responding OK
23May  1 16:47:55 calvino ogsmd.modem.abstract DEBUG    trying to open channel UNSOL
24May  1 16:47:55 calvino ogsmd.modems.ti_calypso INFO     Requesting new channel from 'gsm0710muxd'
25May  1 16:47:55 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:729:c_alloc_channel(): Connecting /dev/pts/2 to virtual channel 2 for ogsmd.unsolicited on /dev/ttySAC0
26May  1 16:47:55 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:1474:extract_frames(): Logical channel 2 opened
27May  1 16:47:56 calvino ogsmd.channel INFO     <UnsolicitedResponseChannel via unknown>: initializing
28May  1 16:47:56 calvino ogsmd    DEBUG    (modem init... try #1)
29May  1 16:47:56 calvino ogsmd    DEBUG    read: AT-Command Interpreter ready
30May  1 16:47:56 calvino ogsmd    INFO     <UnsolicitedResponseChannel via /dev/pts/2>: responding OK
31May  1 16:47:56 calvino ogsmd.modem.abstract DEBUG    trying to open channel CALL
32May  1 16:47:56 calvino ogsmd.modems.ti_calypso INFO     Requesting new channel from 'gsm0710muxd'
33May  1 16:47:56 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:729:c_alloc_channel(): Connecting /dev/pts/6 to virtual channel 3 for ogsmd.call on /dev/ttySAC0
34May  1 16:47:56 calvino /usr/sbin/gsm0710muxd[19027]: gsm0710muxd.c:1474:extract_frames(): Logical channel 3 opened
35May  1 16:47:57 calvino ogsmd.channel INFO     <CallChannel via unknown>: initializing
36May  1 16:47:57 calvino ogsmd    DEBUG    (modem init... try #1)
37May  1 16:47:57 calvino ogsmd    DEBUG    read: AT-Command Interpreter ready
38May  1 16:47:57 calvino ogsmd    INFO     <CallChannel via /dev/pts/6>: responding OK
39May  1 16:47:57 calvino frameworkd.resource INFO     setting resource status for GSM from enabling to enabled
40May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
41May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 4 bytes: 'AT\r\n'
42May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
43May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 4 bytes: 'AT\r\n'
44May  1 16:47:57 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
45May  1 16:47:57 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 4 bytes: 'AT\r\n'
46May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1528
47May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
48May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT' => ['OK']
49May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1529
50May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
51May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT' => ['OK']
52May  1 16:47:57 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1530
53May  1 16:47:57 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
54May  1 16:47:57 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'AT' => ['OK']
55May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
56May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 5 bytes: 'ATZ\r\n'
57May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
58May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 5 bytes: 'ATZ\r\n'
59May  1 16:47:57 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
60May  1 16:47:57 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 5 bytes: 'ATZ\r\n'
61May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1537
62May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
63May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'ATZ' => ['OK']
64May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1538
65May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
66May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'ATZ' => ['OK']
67May  1 16:47:57 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1539
68May  1 16:47:57 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
69May  1 16:47:57 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'ATZ' => ['OK']
70May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
71May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 8 bytes: 'ATE0V1\r\n'
72May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
73May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 8 bytes: 'ATE0V1\r\n'
74May  1 16:47:57 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
75May  1 16:47:57 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 8 bytes: 'ATE0V1\r\n'
76May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1546
77May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
78May  1 16:47:57 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'ATE0V1' => ['OK']
79May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1547
80May  1 16:47:57 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
81May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'ATE0V1' => ['OK']
82May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1548
83May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
84May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'ATE0V1' => ['OK']
85May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
86May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 11 bytes: 'AT+CMEE=1\r\n'
87May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
88May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CMEE=1\r\n'
89May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
90May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 11 bytes: 'AT+CMEE=1\r\n'
91May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1555
92May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
93May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CMEE=1' => ['OK']
94May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1556
95May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
96May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CMEE=1' => ['OK']
97May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1557
98May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
99May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'AT+CMEE=1' => ['OK']
100May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
101May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 10 bytes: 'AT+CRC=1\r\n'
102May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
103May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 10 bytes: 'AT+CRC=1\r\n'
104May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
105May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 10 bytes: 'AT+CRC=1\r\n'
106May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1564
107May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
108May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CRC=1' => ['OK']
109May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1565
110May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
111May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CRC=1' => ['OK']
112May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1566
113May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
114May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'AT+CRC=1' => ['OK']
115May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
116May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 16 bytes: 'AT+CSCS="UCS2"\r\n'
117May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
118May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 16 bytes: 'AT+CSCS="UCS2"\r\n'
119May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
120May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 16 bytes: 'AT+CSCS="UCS2"\r\n'
121May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1573
122May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
123May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSCS="UCS2"' => ['OK']
124May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1574
125May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
126May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CSCS="UCS2"' => ['OK']
127May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1575
128May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
129May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'AT+CSCS="UCS2"' => ['OK']
130May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
131May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 11 bytes: 'AT+CSDH=1\r\n'
132May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
133May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CSDH=1\r\n'
134May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
135May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 11 bytes: 'AT+CSDH=1\r\n'
136May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1582
137May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
138May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSDH=1' => ['OK']
139May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1583
140May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
141May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CSDH=1' => ['OK']
142May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1584
143May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
144May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'AT+CSDH=1' => ['OK']
145May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
146May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 11 bytes: 'AT+CSNS=0\r\n'
147May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
148May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CSNS=0\r\n'
149May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
150May  1 16:47:58 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 11 bytes: 'AT+CSNS=0\r\n'
151May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1591
152May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
153May  1 16:47:58 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSNS=0' => ['OK']
154May  1 16:47:58 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1592
155May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
156May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CSNS=0' => ['OK']
157May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1593
158May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
159May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'AT+CSNS=0' => ['OK']
160May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
161May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 11 bytes: 'AT+CMGF=0\r\n'
162May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
163May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CMGF=0\r\n'
164May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
165May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 11 bytes: 'AT+CMGF=0\r\n'
166May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1600
167May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
168May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CMGF=0' => ['OK']
169May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1601
170May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
171May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CMGF=0' => ['OK']
172May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1602
173May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
174May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'AT+CMGF=0' => ['OK']
175May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
176May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 11 bytes: 'AT+CLIP=0\r\n'
177May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
178May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CLIP=0\r\n'
179May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
180May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 11 bytes: 'AT+CLIP=0\r\n'
181May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1609
182May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
183May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CLIP=0' => ['OK']
184May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1610
185May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
186May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CLIP=0' => ['OK']
187May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1611
188May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
189May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'AT+CLIP=0' => ['OK']
190May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
191May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 11 bytes: 'AT+COLP=0\r\n'
192May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
193May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+COLP=0\r\n'
194May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
195May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 11 bytes: 'AT+COLP=0\r\n'
196May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1618
197May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
198May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+COLP=0' => ['OK']
199May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1619
200May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
201May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+COLP=0' => ['OK']
202May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1620
203May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
204May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'AT+COLP=0' => ['OK']
205May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
206May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 11 bytes: 'AT+CCWA=0\r\n'
207May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
208May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CCWA=0\r\n'
209May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
210May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 11 bytes: 'AT+CCWA=0\r\n'
211May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1627
212May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
213May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CCWA=0' => ['OK']
214May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1628
215May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
216May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CCWA=0' => ['OK']
217May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1629
218May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
219May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'AT+CCWA=0' => ['OK']
220May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
221May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 11 bytes: 'AT%CUNS=2\r\n'
222May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
223May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CUNS=2\r\n'
224May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
225May  1 16:47:59 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: sending 11 bytes: 'AT%CUNS=2\r\n'
226May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1636
227May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
228May  1 16:47:59 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT%CUNS=2' => ['OK']
229May  1 16:47:59 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1637
230May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
231May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CUNS=2' => ['OK']
232May  1 16:48:00 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToRead: watch timeout = 1638
233May  1 16:48:00 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: got 6 bytes: '\r\nOK\r\n'
234May  1 16:48:00 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: COMPLETED 'AT%CUNS=2' => ['OK']
235May  1 16:48:00 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
236May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
237May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CLIP=1\r\n'
238May  1 16:48:00 calvino ogsmd.channel DEBUG    <CallChannel via /dev/pts/6>: _readyToSend: watch timeout = None
239May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1645
240May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
241May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CLIP=1' => ['OK']
242May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
243May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+COLP=0\r\n'
244May  1 16:48:00 calvino ousaged  INFO     Enabled GSM in 15.3 seconds
245May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1648
246May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
247May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+COLP=0' => ['OK']
248May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
249May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CCWA=1\r\n'
250May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1651
251May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
252May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CCWA=1' => ['OK']
253May  1 16:48:00 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
254May  1 16:48:00 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 10 bytes: 'AT+CPIN?\r\n'
255May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
256May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 13 bytes: 'AT+CSSN=1,1\r\n'
257May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1656
258May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
259May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CSSN=1,1' => ['OK']
260May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
261May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZU=1\r\n'
262May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1659
263May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
264May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZU=1' => ['OK']
265May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
266May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZR=1\r\n'
267May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1662
268May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
269May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZR=1' => ['OK']
270May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
271May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CREG=2\r\n'
272May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1665
273May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
274May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CREG=2' => ['OK']
275May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
276May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CAOC=2\r\n'
277May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1668
278May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
279May  1 16:48:00 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CAOC=2' => ['OK']
280May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
281May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 15 bytes: 'AT+CGEREP=2,1\r\n'
282May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1671
283May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
284May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGEREP=2,1' => ['OK']
285May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
286May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT+CGREG=2\r\n'
287May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1674
288May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
289May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGREG=2' => ['OK']
290May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
291May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 10 bytes: 'AT%CPI=3\r\n'
292May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1677
293May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
294May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CPI=3' => ['OK']
295May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
296May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 17 bytes: 'AT%CSCN=1,2,1,2\r\n'
297May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1680
298May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
299May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CSCN=1,2,1,2' => ['OK']
300May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
301May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 10 bytes: 'AT%CSQ=1\r\n'
302May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1683
303May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 18 bytes: '\r\n%CSQ: 1 \r\n\r\nOK\r\n'
304May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CSQ=1' => ['%CSQ: 1 ', 'OK']
305May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
306May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CPRI=1\r\n'
307May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1686
308May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
309May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CPRI=1' => ['OK']
310May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
311May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CNIV=1\r\n'
312May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1689
313May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
314May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CNIV=1' => ['OK']
315May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
316May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT%CSTAT=1\r\n'
317May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1692
318May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
319May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CSTAT=1' => ['OK']
320May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
321May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 13 bytes: 'AT@ST="-26"\r\n'
322May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1695
323May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
324May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT@ST="-26"' => ['OK']
325May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
326May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT%SLEEP=4\r\n'
327May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1698
328May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 16 bytes: '\r\nEXT: I\r\n\r\nOK\r\n'
329May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%SLEEP=4' => ['OK']
330May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
331May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 10 bytes: 'AT%N0187\r\n'
332May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1701
333May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 57 bytes: '\r\nEXT: I\r\nBoth AEC and Noise reduction are active\r\n\r\nOK\r\n'
334May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%N0187' => ['OK']
335May  1 16:48:01 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
336May  1 16:48:02 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
337May  1 16:48:02 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 16 bytes: '%CSTAT: PHB, 0\r\n'
338May  1 16:48:02 calvino ogsmd    INFO     subsystem PHB readyness now False
339May  1 16:48:02 calvino ogsmd.device INFO     sim ready status False
340May  1 16:48:02 calvino ogsmd    INFO     full readyness now False
341May  1 16:48:05 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
342May  1 16:48:05 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 16 bytes: '%CSTAT: PHB, 0\r\n'
343May  1 16:48:05 calvino ogsmd    INFO     subsystem PHB readyness now False
344May  1 16:48:05 calvino ogsmd    INFO     full readyness now False
345May  1 16:48:05 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
346May  1 16:48:05 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 16 bytes: '%CSTAT: PHB, 0\r\n'
347May  1 16:48:05 calvino ogsmd    INFO     subsystem PHB readyness now False
348May  1 16:48:05 calvino ogsmd    INFO     full readyness now False
349May  1 16:48:06 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1655
350May  1 16:48:06 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 16 bytes: '\r\n+CPIN: READY\r\n'
351May  1 16:48:06 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
352May  1 16:48:06 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 16 bytes: '%CSTAT: PHB, 0\r\n'
353May  1 16:48:06 calvino ogsmd    INFO     subsystem PHB readyness now False
354May  1 16:48:06 calvino ogsmd    INFO     full readyness now False
355May  1 16:48:06 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1704
356May  1 16:48:06 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
357May  1 16:48:06 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
358May  1 16:48:06 calvino ogsmd.device INFO     auth status changed to READY
359May  1 16:48:06 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
360May  1 16:48:06 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 11 bytes: 'AT+CFUN=1\r\n'
361May  1 16:48:10 calvino odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle
362May  1 16:48:10 calvino oeventsd.action INFO     DebugAction : dbus trigger test
363May  1 16:48:10 calvino oeventsd.fso_triggers INFO     Receive IdleState, status = idle
364May  1 16:48:11 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
365May  1 16:48:11 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 32 bytes: '%CSTAT: PHB, 0\r\n%CSTAT: PHB, 0\r\n'
366May  1 16:48:11 calvino ogsmd    INFO     subsystem PHB readyness now False
367May  1 16:48:11 calvino ogsmd    INFO     full readyness now False
368May  1 16:48:11 calvino ogsmd    INFO     subsystem PHB readyness now False
369May  1 16:48:11 calvino ogsmd    INFO     full readyness now False
370May  1 16:48:12 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1710
371May  1 16:48:12 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
372May  1 16:48:12 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CFUN=1' => ['OK']
373May  1 16:48:12 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
374May  1 16:48:12 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 16 bytes: '%CSTAT: PHB, 0\r\n'
375May  1 16:48:12 calvino ogsmd    INFO     subsystem PHB readyness now False
376May  1 16:48:12 calvino ogsmd    INFO     full readyness now False
377May  1 16:48:12 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
378May  1 16:48:12 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 13 bytes: 'AT+CLVL=255\r\n'
379May  1 16:48:12 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
380May  1 16:48:12 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 10 bytes: 'AT+CPIN?\r\n'
381May  1 16:48:12 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1714
382May  1 16:48:12 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
383May  1 16:48:12 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CLVL=255' => ['OK']
384May  1 16:48:12 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1715
385May  1 16:48:12 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 22 bytes: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
386May  1 16:48:12 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
387May  1 16:48:12 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
388May  1 16:48:12 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
389May  1 16:48:12 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
390May  1 16:48:12 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 13 bytes: 'AT+COPS=0,0\r\n'
391May  1 16:48:12 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
392May  1 16:48:12 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 12 bytes: '\r\n+CREG: 2\r\n'
393May  1 16:48:15 calvino kernel: [25863.495000] fbcon_event_notify action=9, data=c667be10
394May  1 16:48:15 calvino kernel: [25863.495000] jbt6k74 spi2.0: **** jbt6k74 vsync suspend
395May  1 16:48:16 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
396May  1 16:48:16 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CSMS=1\r\n'
397May  1 16:48:16 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1723
398May  1 16:48:16 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 19 bytes: '\r\n+CMS ERROR: 314\r\n'
399May  1 16:48:16 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CSMS=1' => ['+CMS ERROR: 314']
400May  1 16:48:16 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
401May  1 16:48:16 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 19 bytes: 'AT+CNMI=2,1,2,1,1\r\n'
402May  1 16:48:16 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1726
403May  1 16:48:16 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 19 bytes: '\r\n+CMS ERROR: 314\r\n'
404May  1 16:48:16 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CNMI=2,1,2,1,1' => ['+CMS ERROR: 314']
405May  1 16:48:16 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
406May  1 16:48:17 calvino kernel: [25865.880000] fbcon_event_notify action=12, data=c667bdc8
407May  1 16:48:17 calvino kernel: [25865.880000] jbt6k74 spi2.0: **** jbt6k74 unblank
408May  1 16:48:17 calvino kernel: [25865.960000] fbcon_event_notify action=9, data=c667be10
409May  1 16:48:17 calvino kernel: [25865.960000] jbt6k74 spi2.0: **** jbt6k74 unblank
410May  1 16:48:17 calvino odeviced.idlenotifier INFO     odeviced.idlenotifier state change to busy
411May  1 16:48:17 calvino oeventsd.action INFO     DebugAction : dbus trigger test
412May  1 16:48:17 calvino oeventsd.fso_triggers INFO     Receive IdleState, status = busy
413May  1 16:48:17 calvino oeventsd.action INFO     DebugAction : Hello This is a test
414May  1 16:48:19 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1721
415May  1 16:48:19 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
416May  1 16:48:19 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+COPS=0,0' => ['OK']
417May  1 16:48:19 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
418May  1 16:48:19 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 46 bytes: '\r\n+CREG: 1,"1449","A50C"\r\n\r\n%CSQ:  22, 99, 2\r\n'
419May  1 16:48:19 calvino ogsmd.device INFO     org.freesmartphone.GSM.Network.SignalStrength: 90
420May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
421May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 8 bytes: 'AT+CSQ\r\n'
422May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1731
423May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 22 bytes: '\r\n+CSQ: 22,99 \r\n\r\nOK\r\n'
424May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSQ' => ['+CSQ: 22,99 ', 'OK']
425May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
426May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 8 bytes: 'AT+CSQ\r\n'
427May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1734
428May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 22 bytes: '\r\n+CSQ: 22,99 \r\n\r\nOK\r\n'
429May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSQ' => ['+CSQ: 22,99 ', 'OK']
430May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
431May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 8 bytes: 'AT+CSQ\r\n'
432May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1737
433May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 22 bytes: '\r\n+CSQ: 22,99 \r\n\r\nOK\r\n'
434May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSQ' => ['+CSQ: 22,99 ', 'OK']
435May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
436May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 10 bytes: 'AT+CREG?\r\n'
437May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1740
438May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 20 bytes: '\r\n+CREG: 0,1\r\n\r\nOK\r\n'
439May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG?' => ['+CREG: 0,1', 'OK']
440May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
441May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 10 bytes: 'AT+CREG?\r\n'
442May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1743
443May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 20 bytes: '\r\n+CREG: 0,1\r\n\r\nOK\r\n'
444May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG?' => ['+CREG: 0,1', 'OK']
445May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
446May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 10 bytes: 'AT+CREG?\r\n'
447May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1746
448May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 20 bytes: '\r\n+CREG: 0,1\r\n\r\nOK\r\n'
449May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG?' => ['+CREG: 0,1', 'OK']
450May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
451May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 26 bytes: 'AT+CREG=2;+CREG?;+CREG=0\r\n'
452May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1749
453May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 34 bytes: '\r\n+CREG: 2,1,"1449","A50C"\r\n\r\nOK\r\n'
454May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG=2;+CREG?;+CREG=0' => ['+CREG: 2,1,"1449","A50C"', 'OK']
455May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
456May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 26 bytes: 'AT+CREG=2;+CREG?;+CREG=0\r\n'
457May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1752
458May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 34 bytes: '\r\n+CREG: 2,1,"1449","A50C"\r\n\r\nOK\r\n'
459May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG=2;+CREG?;+CREG=0' => ['+CREG: 2,1,"1449","A50C"', 'OK']
460May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
461May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 26 bytes: 'AT+CREG=2;+CREG?;+CREG=0\r\n'
462May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1755
463May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 34 bytes: '\r\n+CREG: 2,1,"1449","A50C"\r\n\r\nOK\r\n'
464May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG=2;+CREG?;+CREG=0' => ['+CREG: 2,1,"1449","A50C"', 'OK']
465May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
466May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 37 bytes: 'AT+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
467May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1758
468May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 25 bytes: '\r\n+COPS: 0,0,"T-Mobile"\r\n'
469May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1759
470May  1 16:48:20 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 28 bytes: '\r\n+COPS: 0,2,"31026"\r\n\r\nOK\r\n'
471May  1 16:48:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+COPS: 0,0,"T-Mobile"', '+COPS: 0,2,"31026"', 'OK']
472May  1 16:48:21 calvino ogsmd.device INFO     org.freesmartphone.GSM.Network.Status: {'strength': 90, 'cid': u'A50C', 'registration': 'home', 'lac': u'1449', 'act': 'GSM', 'code': u'31026', 'mode': 'automatic', 'provider': u'T-Mobile'}
473May  1 16:48:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
474May  1 16:48:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 37 bytes: 'AT+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
475May  1 16:48:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1762
476May  1 16:48:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 53 bytes: '\r\n+COPS: 0,0,"T-Mobile"\r\n\r\n+COPS: 0,2,"31026"\r\n\r\nOK\r\n'
477May  1 16:48:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+COPS: 0,0,"T-Mobile"', '+COPS: 0,2,"31026"', 'OK']
478May  1 16:48:21 calvino ogsmd.device INFO     org.freesmartphone.GSM.Network.Status: {'strength': 90, 'cid': u'A50C', 'registration': 'home', 'lac': u'1449', 'act': 'GSM', 'code': u'31026', 'mode': 'automatic', 'provider': u'T-Mobile'}
479May  1 16:48:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
480May  1 16:48:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: sending 37 bytes: 'AT+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
481May  1 16:48:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 1765
482May  1 16:48:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: got 53 bytes: '\r\n+COPS: 0,0,"T-Mobile"\r\n\r\n+COPS: 0,2,"31026"\r\n\r\nOK\r\n'
483May  1 16:48:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+COPS: 0,0,"T-Mobile"', '+COPS: 0,2,"31026"', 'OK']
484May  1 16:48:21 calvino ogsmd.channel DEBUG    <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
485May  1 16:48:21 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
486May  1 16:48:21 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 32 bytes: '%CSTAT: PHB, 0\r\n%CSTAT: SMS, 1\r\n'
487May  1 16:48:21 calvino ogsmd    INFO     subsystem PHB readyness now False
488May  1 16:48:21 calvino ogsmd    INFO     full readyness now False
489May  1 16:48:21 calvino ogsmd    INFO     subsystem SMS readyness now True
490May  1 16:48:22 calvino ogsmd    INFO     full readyness now False
491May  1 16:48:22 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
492May  1 16:48:22 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 14 bytes: '\r\n+CTZV: 105\r\n'
493May  1 16:48:22 calvino ogsmd.device INFO     org.freesmartphone.GSM.Network.TimeZoneReport: %d
494May  1 16:48:22 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
495May  1 16:48:22 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 14 bytes: '\r\n%CPRI: 1,2\r\n'
496May  1 16:48:22 calvino ogsmd.device INFO     org.freesmartphone.GSM.Network.CypherStatus: enabled: unknown
497May  1 16:48:27 calvino odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle
498May  1 16:48:27 calvino oeventsd.action INFO     DebugAction : dbus trigger test
499May  1 16:48:27 calvino oeventsd.fso_triggers INFO     Receive IdleState, status = idle
500May  1 16:48:34 calvino odeviced.idlenotifier INFO     odeviced.idlenotifier state change to busy
501May  1 16:48:34 calvino oeventsd.action INFO     DebugAction : dbus trigger test
502May  1 16:48:34 calvino oeventsd.fso_triggers INFO     Receive IdleState, status = busy
503May  1 16:48:34 calvino oeventsd.action INFO     DebugAction : Hello This is a test
504May  1 16:48:50 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
505May  1 16:48:50 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 17 bytes: '%CSTAT: EONS, 1\r\n'
506May  1 16:48:50 calvino ogsmd    INFO     full readyness now False
507May  1 16:48:51 calvino odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle
508May  1 16:48:51 calvino oeventsd.action INFO     DebugAction : dbus trigger test
509May  1 16:48:51 calvino oeventsd.fso_triggers INFO     Receive IdleState, status = idle
510May  1 16:49:11 calvino kernel: [25920.990000] fbcon_event_notify action=9, data=c667be10
511May  1 16:49:11 calvino kernel: [25920.990000] jbt6k74 spi2.0: **** jbt6k74 vsync suspend
512May  1 16:49:11 calvino odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle_dim
513May  1 16:49:11 calvino oeventsd.action INFO     DebugAction : dbus trigger test
514May  1 16:49:11 calvino oeventsd.fso_triggers INFO     Receive IdleState, status = idle_dim
515May  1 16:49:16 calvino kernel: [25926.150000] fbcon_event_notify action=9, data=c667be10
516May  1 16:49:16 calvino kernel: [25926.150000] jbt6k74 spi2.0: **** jbt6k74 hsync suspend
517May  1 16:49:21 calvino kernel: [25931.310000] fbcon_event_notify action=9, data=c667be10
518May  1 16:49:21 calvino kernel: [25931.310000] jbt6k74 spi2.0: **** jbt6k74 powerdown
519May  1 16:49:22 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
520May  1 16:49:22 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 16 bytes: '%CSTAT: PHB, 1\r\n'
521May  1 16:49:22 calvino ogsmd    INFO     subsystem PHB readyness now True
522May  1 16:49:22 calvino ogsmd.device INFO     sim ready status True
523May  1 16:49:22 calvino ogsmd    INFO     full readyness now True
524May  1 16:49:22 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
525May  1 16:49:22 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 16 bytes: '%CSTAT: RDY, 1\r\n'
526May  1 16:49:22 calvino ogsmd    INFO     full readyness now True
527May  1 16:49:23 calvino odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle_prelock
528May  1 16:49:23 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
529May  1 16:49:23 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CSMS=1\r\n'
530May  1 16:49:23 calvino oeventsd.action INFO     DebugAction : dbus trigger test
531May  1 16:49:23 calvino oeventsd.fso_triggers INFO     Receive IdleState, status = idle_prelock
532May  1 16:49:23 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1772
533May  1 16:49:23 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 22 bytes: '\r\n+CSMS: 1,1,1\r\n\r\nOK\r\n'
534May  1 16:49:23 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CSMS=1' => ['+CSMS: 1,1,1', 'OK']
535May  1 16:49:23 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
536May  1 16:49:23 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 19 bytes: 'AT+CNMI=2,1,2,1,1\r\n'
537May  1 16:49:23 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1775
538May  1 16:49:23 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
539May  1 16:49:23 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CNMI=2,1,2,1,1' => ['OK']
540May  1 16:49:23 calvino ogsmd.channel DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
541May  1 16:49:25 calvino odeviced.idlenotifier INFO     odeviced.idlenotifier state change to lock
542May  1 16:49:25 calvino oeventsd.action INFO     DebugAction : dbus trigger test
543May  1 16:49:25 calvino oeventsd.fso_triggers INFO     Receive IdleState, status = lock