Ticket #322: 2.txt

File 2.txt, 126.9 KB (added by lindi, 3 years ago)

syslog output

Line 
1Jan 26 15:48:19 ginger user.debug 2009.01.26 15:48:19 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 8 bytes: 'AT+CSQ\r\n'
2Jan 26 15:48:19 ginger user.debug 2009.01.26 15:48:19 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 22 bytes from: '\r\n+CSQ: 14,99 \r\n\r\nOK\r\n'
3Jan 26 15:48:19 ginger user.debug 2009.01.26 15:48:19 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSQ' => ['+CSQ: 14,99 ', 'OK']
4Jan 26 15:48:19 ginger user.debug 2009.01.26 15:48:19 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at
5Jan 26 15:48:19 ginger user.debug 2009.01.26 15:48:19 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 44 bytes: 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
6Jan 26 15:48:19 ginger user.debug 2009.01.26 15:48:19 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 14 bytes from: '\r\n+CREG: 0,5\r\n'
7Jan 26 15:48:19 ginger user.debug 2009.01.26 15:48:19 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 55 bytes from: '\r\n+COPS: 0,0,"Saunalahti"\r\n\r\n+COPS: 0,2,"24405"\r\n\r\nOK\r\n'
8Jan 26 15:48:19 ginger user.debug 2009.01.26 15:48:19 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+CREG: 0,5', '+COPS: 0,0,"Saunalahti"', '+COPS: 0,2,"24405"', 'OK']
9Jan 26 15:48:19 ginger user.info 2009.01.26 15:48:19 ogsmd.objects INFO     org.freesmartphone.GSM.Network.Status: {'code': 24405, 'cid': '117A', 'registration': 'roaming', 'lac': '232F', 'strength': 77, 'mode': 'automatic', 'provider': 'Saunalahti'}
10Jan 26 15:48:19 ginger user.debug 2009.01.26 15:48:19 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
11Jan 26 15:48:19 ginger user.debug 2009.01.26 15:48:19 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
12Jan 26 15:48:20 ginger user.debug 2009.01.26 15:48:20 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
13Jan 26 15:48:22 ginger user.debug 2009.01.26 15:48:22 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
14Jan 26 15:48:24 ginger user.debug 2009.01.26 15:48:24 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
15Jan 26 15:48:26 ginger user.debug 2009.01.26 15:48:26 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
16Jan 26 15:48:28 ginger user.debug 2009.01.26 15:48:28 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
17Jan 26 15:48:30 ginger user.debug 2009.01.26 15:48:30 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
18Jan 26 15:48:32 ginger user.debug 2009.01.26 15:48:32 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
19Jan 26 15:48:34 ginger user.debug 2009.01.26 15:48:34 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
20Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes from: '\r\n+CREG: 5,"232F","17E4"\r\n'
21Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CSQ\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x31cf30>>, <bound method NetworkGet
22Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 8 bytes: 'AT+CSQ\r\n'
23Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 16 bytes from: '\r\n+CSQ: 17,99 \r\n'
24Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
25Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSQ' => ['+CSQ: 17,99 ', 'OK']
26Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at
27Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 44 bytes: 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
28Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 14 bytes from: '\r\n+CREG: 0,5\r\n'
29Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 55 bytes from: '\r\n+COPS: 0,0,"Saunalahti"\r\n\r\n+COPS: 0,2,"24405"\r\n\r\nOK\r\n'
30Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+CREG: 0,5', '+COPS: 0,0,"Saunalahti"', '+COPS: 0,2,"24405"', 'OK']
31Jan 26 15:48:35 ginger user.info 2009.01.26 15:48:35 ogsmd.objects INFO     org.freesmartphone.GSM.Network.Status: {'code': 24405, 'cid': '17E4', 'registration': 'roaming', 'lac': '232F', 'strength': 83, 'mode': 'automatic', 'provider': 'Saunalahti'}
32Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
33Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
34Jan 26 15:48:36 ginger user.debug 2009.01.26 15:48:36 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
35Jan 26 15:48:38 ginger user.debug 2009.01.26 15:48:38 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
36Jan 26 15:48:40 ginger user.debug 2009.01.26 15:48:40 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
37Jan 26 15:48:42 ginger user.debug 2009.01.26 15:48:42 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
38Jan 26 15:48:44 ginger user.debug 2009.01.26 15:48:44 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
39Jan 26 15:48:46 ginger user.debug 2009.01.26 15:48:46 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
40Jan 26 15:48:48 ginger user.debug 2009.01.26 15:48:48 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
41Jan 26 15:48:50 ginger user.debug 2009.01.26 15:48:50 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
42Jan 26 15:48:52 ginger user.debug 2009.01.26 15:48:52 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
43Jan 26 15:48:54 ginger user.debug 2009.01.26 15:48:54 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
44Jan 26 15:48:55 ginger user.info kernel: [146964.995000] fbcon_event_notify action=9, data=c190fe08
45Jan 26 15:48:55 ginger user.info kernel: [146964.995000] jbt6k74 spi2.0: **** jbt6k74 vsync suspend
46Jan 26 15:48:56 ginger user.debug 2009.01.26 15:48:56 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
47Jan 26 15:48:56 ginger user.info kernel: [146966.005000] fbcon_event_notify action=12, data=c190fdcc
48Jan 26 15:48:56 ginger user.info kernel: [146966.005000] jbt6k74 spi2.0: **** jbt6k74 unblank
49Jan 26 15:48:56 ginger user.info kernel: [146966.075000] fbcon_event_notify action=9, data=c190fe08
50Jan 26 15:48:56 ginger user.info kernel: [146966.075000] jbt6k74 spi2.0: **** jbt6k74 unblank
51Jan 26 15:48:56 ginger user.info kernel: [146966.315000] pcf50633 0-0073: INT1=0x80 INT2=0x00 INT3=0x00 INT4=0x40 INT5=0x00
52Jan 26 15:48:56 ginger user.info kernel: [146966.335000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
53Jan 26 15:48:56 ginger user.info kernel: [146966.355000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
54Jan 26 15:48:58 ginger user.debug 2009.01.26 15:48:58 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
55Jan 26 15:49:00 ginger user.debug 2009.01.26 15:49:00 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
56Jan 26 15:49:02 ginger user.debug 2009.01.26 15:49:02 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
57Jan 26 15:49:04 ginger user.debug 2009.01.26 15:49:04 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
58Jan 26 15:49:06 ginger user.debug 2009.01.26 15:49:06 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
59Jan 26 15:49:08 ginger user.debug 2009.01.26 15:49:08 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
60Jan 26 15:49:10 ginger user.debug 2009.01.26 15:49:10 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
61Jan 26 15:49:12 ginger user.debug 2009.01.26 15:49:12 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
62Jan 26 15:49:14 ginger user.debug 2009.01.26 15:49:14 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
63Jan 26 15:49:16 ginger user.debug 2009.01.26 15:49:16 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
64Jan 26 15:49:18 ginger user.debug 2009.01.26 15:49:18 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
65Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
66Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes from: '\r\n+CREG: 5,"232F","17D8"\r\n'
67Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CSQ\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x31c170>>, <bound method NetworkGet
68Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 8 bytes: 'AT+CSQ\r\n'
69Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 16 bytes from: '\r\n+CSQ: 24,99 \r\n'
70Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
71Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSQ' => ['+CSQ: 24,99 ', 'OK']
72Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at
73Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 44 bytes: 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
74Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 14 bytes from: '\r\n+CREG: 0,5\r\n'
75Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 55 bytes from: '\r\n+COPS: 0,0,"Saunalahti"\r\n\r\n+COPS: 0,2,"24405"\r\n\r\nOK\r\n'
76Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+CREG: 0,5', '+COPS: 0,0,"Saunalahti"', '+COPS: 0,2,"24405"', 'OK']
77Jan 26 15:49:20 ginger user.info 2009.01.26 15:49:20 ogsmd.objects INFO     org.freesmartphone.GSM.Network.Status: {'code': 24405, 'cid': '17D8', 'registration': 'roaming', 'lac': '232F', 'strength': 93, 'mode': 'automatic', 'provider': 'Saunalahti'}
78Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
79Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
80Jan 26 15:49:22 ginger user.debug 2009.01.26 15:49:22 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
81Jan 26 15:49:24 ginger user.debug 2009.01.26 15:49:24 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
82Jan 26 15:49:26 ginger user.debug 2009.01.26 15:49:26 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
83Jan 26 15:49:28 ginger user.debug 2009.01.26 15:49:28 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
84Jan 26 15:49:30 ginger user.debug 2009.01.26 15:49:30 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
85Jan 26 15:49:32 ginger user.debug 2009.01.26 15:49:32 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
86Jan 26 15:49:34 ginger user.debug 2009.01.26 15:49:34 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
87Jan 26 15:49:36 ginger user.debug 2009.01.26 15:49:36 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
88Jan 26 15:49:38 ginger user.debug 2009.01.26 15:49:38 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
89Jan 26 15:49:40 ginger user.debug 2009.01.26 15:49:40 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
90Jan 26 15:49:42 ginger user.debug 2009.01.26 15:49:42 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
91Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
92Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes from: '\r\n+CREG: 5,"232F","1721"\r\n'
93Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CSQ\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x30ef30>>, <bound method NetworkGet
94Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 8 bytes: 'AT+CSQ\r\n'
95Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 22 bytes from: '\r\n+CSQ: 18,99 \r\n\r\nOK\r\n'
96Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSQ' => ['+CSQ: 18,99 ', 'OK']
97Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at
98Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 44 bytes: 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
99Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 14 bytes from: '\r\n+CREG: 0,5\r\n'
100Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 55 bytes from: '\r\n+COPS: 0,0,"Saunalahti"\r\n\r\n+COPS: 0,2,"24405"\r\n\r\nOK\r\n'
101Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+CREG: 0,5', '+COPS: 0,0,"Saunalahti"', '+COPS: 0,2,"24405"', 'OK']
102Jan 26 15:49:44 ginger user.info 2009.01.26 15:49:44 ogsmd.objects INFO     org.freesmartphone.GSM.Network.Status: {'code': 24405, 'cid': '1721', 'registration': 'roaming', 'lac': '232F', 'strength': 84, 'mode': 'automatic', 'provider': 'Saunalahti'}
103Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
104Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
105Jan 26 15:49:46 ginger user.debug 2009.01.26 15:49:46 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
106Jan 26 15:49:48 ginger user.debug 2009.01.26 15:49:48 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
107Jan 26 15:49:50 ginger user.debug 2009.01.26 15:49:50 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
108Jan 26 15:49:52 ginger user.debug 2009.01.26 15:49:52 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
109Jan 26 15:49:54 ginger user.debug 2009.01.26 15:49:54 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
110Jan 26 15:49:56 ginger user.debug 2009.01.26 15:49:56 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
111Jan 26 15:49:58 ginger user.debug 2009.01.26 15:49:58 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
112Jan 26 15:50:00 ginger user.debug 2009.01.26 15:50:00 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
113Jan 26 15:50:02 ginger user.debug 2009.01.26 15:50:02 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
114Jan 26 15:50:04 ginger user.debug 2009.01.26 15:50:04 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
115Jan 26 15:50:06 ginger user.debug 2009.01.26 15:50:06 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
116Jan 26 15:50:08 ginger user.debug 2009.01.26 15:50:08 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
117Jan 26 15:50:10 ginger user.debug 2009.01.26 15:50:10 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
118Jan 26 15:50:12 ginger user.debug 2009.01.26 15:50:12 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
119Jan 26 15:50:14 ginger user.debug 2009.01.26 15:50:14 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
120Jan 26 15:50:16 ginger user.debug 2009.01.26 15:50:16 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
121Jan 26 15:50:18 ginger user.debug 2009.01.26 15:50:18 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
122Jan 26 15:50:20 ginger user.debug 2009.01.26 15:50:20 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
123Jan 26 15:50:22 ginger user.debug 2009.01.26 15:50:22 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
124Jan 26 15:50:24 ginger user.debug 2009.01.26 15:50:24 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
125Jan 26 15:50:24 ginger user.debug 2009.01.26 15:50:24 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes from: '\r\n+CREG: 5,"232F","1706"\r\n'
126Jan 26 15:50:24 ginger user.debug 2009.01.26 15:50:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CSQ\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x31cb70>>, <bound method NetworkGet
127Jan 26 15:50:24 ginger user.debug 2009.01.26 15:50:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 8 bytes: 'AT+CSQ\r\n'
128Jan 26 15:50:24 ginger user.debug 2009.01.26 15:50:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 22 bytes from: '\r\n+CSQ: 15,99 \r\n\r\nOK\r\n'
129Jan 26 15:50:24 ginger user.debug 2009.01.26 15:50:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSQ' => ['+CSQ: 15,99 ', 'OK']
130Jan 26 15:50:24 ginger user.debug 2009.01.26 15:50:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at
131Jan 26 15:50:24 ginger user.debug 2009.01.26 15:50:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 44 bytes: 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
132Jan 26 15:50:24 ginger user.debug 2009.01.26 15:50:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 14 bytes from: '\r\n+CREG: 0,5\r\n'
133Jan 26 15:50:24 ginger user.debug 2009.01.26 15:50:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 55 bytes from: '\r\n+COPS: 0,0,"Saunalahti"\r\n\r\n+COPS: 0,2,"24405"\r\n\r\nOK\r\n'
134Jan 26 15:50:24 ginger user.debug 2009.01.26 15:50:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+CREG: 0,5', '+COPS: 0,0,"Saunalahti"', '+COPS: 0,2,"24405"', 'OK']
135Jan 26 15:50:24 ginger user.info 2009.01.26 15:50:24 ogsmd.objects INFO     org.freesmartphone.GSM.Network.Status: {'code': 24405, 'cid': '1706', 'registration': 'roaming', 'lac': '232F', 'strength': 79, 'mode': 'automatic', 'provider': 'Saunalahti'}
136Jan 26 15:50:25 ginger user.debug 2009.01.26 15:50:25 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
137Jan 26 15:50:25 ginger user.debug 2009.01.26 15:50:25 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
138Jan 26 15:50:26 ginger user.debug 2009.01.26 15:50:26 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
139Jan 26 15:50:28 ginger user.debug 2009.01.26 15:50:28 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
140Jan 26 15:50:30 ginger user.debug 2009.01.26 15:50:30 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
141Jan 26 15:50:32 ginger user.debug 2009.01.26 15:50:32 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
142Jan 26 15:50:34 ginger user.debug 2009.01.26 15:50:34 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
143Jan 26 15:50:36 ginger user.debug 2009.01.26 15:50:36 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
144Jan 26 15:50:38 ginger user.debug 2009.01.26 15:50:38 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
145Jan 26 15:50:40 ginger user.debug 2009.01.26 15:50:40 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
146Jan 26 15:50:42 ginger user.debug 2009.01.26 15:50:42 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
147Jan 26 15:50:44 ginger user.debug 2009.01.26 15:50:44 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
148Jan 26 15:50:46 ginger user.debug 2009.01.26 15:50:46 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
149Jan 26 15:50:48 ginger user.debug 2009.01.26 15:50:48 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
150Jan 26 15:50:50 ginger user.debug 2009.01.26 15:50:50 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
151Jan 26 15:50:52 ginger user.debug 2009.01.26 15:50:52 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
152Jan 26 15:50:54 ginger user.debug 2009.01.26 15:50:54 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
153Jan 26 15:50:56 ginger user.debug 2009.01.26 15:50:56 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
154Jan 26 15:50:58 ginger user.debug 2009.01.26 15:50:58 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
155Jan 26 15:51:00 ginger user.debug 2009.01.26 15:51:00 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
156Jan 26 15:51:02 ginger user.debug 2009.01.26 15:51:02 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
157Jan 26 15:51:03 ginger user.debug 2009.01.26 15:51:03 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes from: '\r\n+CREG: 5,"232F","16F3"\r\n'
158Jan 26 15:51:03 ginger user.debug 2009.01.26 15:51:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CSQ\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x31c190>>, <bound method NetworkGet
159Jan 26 15:51:03 ginger user.debug 2009.01.26 15:51:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 8 bytes: 'AT+CSQ\r\n'
160Jan 26 15:51:03 ginger user.debug 2009.01.26 15:51:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 21 bytes from: '\r\n+CSQ: 20,0 \r\n\r\nOK\r\n'
161Jan 26 15:51:03 ginger user.debug 2009.01.26 15:51:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSQ' => ['+CSQ: 20,0 ', 'OK']
162Jan 26 15:51:03 ginger user.debug 2009.01.26 15:51:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at
163Jan 26 15:51:03 ginger user.debug 2009.01.26 15:51:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 44 bytes: 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
164Jan 26 15:51:03 ginger user.debug 2009.01.26 15:51:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 14 bytes from: '\r\n+CREG: 0,5\r\n'
165Jan 26 15:51:03 ginger user.debug 2009.01.26 15:51:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 55 bytes from: '\r\n+COPS: 0,0,"Saunalahti"\r\n\r\n+COPS: 0,2,"24405"\r\n\r\nOK\r\n'
166Jan 26 15:51:03 ginger user.debug 2009.01.26 15:51:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+CREG: 0,5', '+COPS: 0,0,"Saunalahti"', '+COPS: 0,2,"24405"', 'OK']
167Jan 26 15:51:03 ginger user.info 2009.01.26 15:51:03 ogsmd.objects INFO     org.freesmartphone.GSM.Network.Status: {'code': 24405, 'cid': '16F3', 'registration': 'roaming', 'lac': '232F', 'strength': 87, 'mode': 'automatic', 'provider': 'Saunalahti'}
168Jan 26 15:51:03 ginger user.debug 2009.01.26 15:51:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
169Jan 26 15:51:04 ginger user.debug 2009.01.26 15:51:04 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
170Jan 26 15:51:04 ginger user.debug 2009.01.26 15:51:04 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
171Jan 26 15:51:06 ginger user.debug 2009.01.26 15:51:06 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
172Jan 26 15:51:08 ginger user.debug 2009.01.26 15:51:08 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
173Jan 26 15:51:10 ginger user.debug 2009.01.26 15:51:10 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
174Jan 26 15:51:12 ginger user.debug 2009.01.26 15:51:12 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
175Jan 26 15:51:14 ginger user.debug 2009.01.26 15:51:14 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
176Jan 26 15:51:16 ginger user.debug 2009.01.26 15:51:16 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
177Jan 26 15:51:18 ginger user.debug 2009.01.26 15:51:18 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
178Jan 26 15:51:20 ginger user.debug 2009.01.26 15:51:20 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
179Jan 26 15:51:22 ginger user.debug 2009.01.26 15:51:22 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
180Jan 26 15:51:24 ginger user.debug 2009.01.26 15:51:24 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
181Jan 26 15:51:26 ginger user.debug 2009.01.26 15:51:26 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
182Jan 26 15:51:28 ginger user.debug 2009.01.26 15:51:28 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
183Jan 26 15:51:30 ginger user.debug 2009.01.26 15:51:30 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
184Jan 26 15:51:32 ginger user.debug 2009.01.26 15:51:32 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
185Jan 26 15:51:34 ginger user.debug 2009.01.26 15:51:34 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
186Jan 26 15:51:36 ginger user.debug 2009.01.26 15:51:36 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
187Jan 26 15:51:38 ginger user.debug 2009.01.26 15:51:38 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
188Jan 26 15:51:40 ginger user.debug 2009.01.26 15:51:40 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
189Jan 26 15:51:42 ginger user.debug 2009.01.26 15:51:42 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
190Jan 26 15:51:44 ginger user.debug 2009.01.26 15:51:44 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
191Jan 26 15:51:46 ginger user.debug 2009.01.26 15:51:46 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
192Jan 26 15:51:48 ginger user.debug 2009.01.26 15:51:48 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
193Jan 26 15:51:50 ginger user.debug 2009.01.26 15:51:50 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
194Jan 26 15:51:52 ginger user.debug 2009.01.26 15:51:52 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
195Jan 26 15:51:54 ginger user.debug 2009.01.26 15:51:54 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
196Jan 26 15:51:56 ginger user.debug 2009.01.26 15:51:56 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
197Jan 26 15:51:58 ginger user.debug 2009.01.26 15:51:58 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
198Jan 26 15:52:00 ginger user.debug 2009.01.26 15:52:00 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
199Jan 26 15:52:02 ginger user.debug 2009.01.26 15:52:02 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
200Jan 26 15:52:04 ginger user.debug 2009.01.26 15:52:04 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
201Jan 26 15:52:06 ginger user.debug 2009.01.26 15:52:06 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
202Jan 26 15:52:08 ginger user.debug 2009.01.26 15:52:08 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
203Jan 26 15:52:10 ginger user.debug 2009.01.26 15:52:10 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
204Jan 26 15:52:12 ginger user.debug 2009.01.26 15:52:12 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
205Jan 26 15:52:14 ginger user.debug 2009.01.26 15:52:14 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
206Jan 26 15:52:16 ginger user.debug 2009.01.26 15:52:16 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
207Jan 26 15:52:18 ginger user.debug 2009.01.26 15:52:18 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
208Jan 26 15:52:20 ginger user.debug 2009.01.26 15:52:20 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
209Jan 26 15:52:22 ginger user.debug 2009.01.26 15:52:22 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
210Jan 26 15:52:24 ginger user.debug 2009.01.26 15:52:24 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
211Jan 26 15:52:26 ginger user.debug 2009.01.26 15:52:26 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
212Jan 26 15:52:28 ginger user.debug 2009.01.26 15:52:28 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
213Jan 26 15:52:30 ginger user.debug 2009.01.26 15:52:30 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
214Jan 26 15:52:32 ginger user.debug 2009.01.26 15:52:32 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
215Jan 26 15:52:34 ginger user.debug 2009.01.26 15:52:34 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
216Jan 26 15:52:36 ginger user.debug 2009.01.26 15:52:36 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
217Jan 26 15:52:38 ginger user.debug 2009.01.26 15:52:38 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
218Jan 26 15:52:40 ginger user.debug 2009.01.26 15:52:40 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
219Jan 26 15:52:42 ginger user.debug 2009.01.26 15:52:42 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
220Jan 26 15:52:44 ginger user.debug 2009.01.26 15:52:44 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
221Jan 26 15:52:46 ginger user.debug 2009.01.26 15:52:46 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
222Jan 26 15:52:48 ginger user.debug 2009.01.26 15:52:48 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
223Jan 26 15:52:50 ginger user.debug 2009.01.26 15:52:50 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
224Jan 26 15:52:52 ginger user.debug 2009.01.26 15:52:52 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
225Jan 26 15:52:54 ginger user.debug 2009.01.26 15:52:54 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
226Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
227Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes from: '\r\n+CREG: 5,"232F","00BD"\r\n'
228Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CSQ\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x30ef30>>, <bound method NetworkGet
229Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 8 bytes: 'AT+CSQ\r\n'
230Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 16 bytes from: '\r\n+CSQ: 12,99 \r\n'
231Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
232Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSQ' => ['+CSQ: 12,99 ', 'OK']
233Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at
234Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 44 bytes: 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
235Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 14 bytes from: '\r\n+CREG: 0,5\r\n'
236Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 27 bytes from: '\r\n+COPS: 0,0,"Saunalahti"\r\n'
237Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 28 bytes from: '\r\n+COPS: 0,2,"24405"\r\n\r\nOK\r\n'
238Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+CREG: 0,5', '+COPS: 0,0,"Saunalahti"', '+COPS: 0,2,"24405"', 'OK']
239Jan 26 15:52:56 ginger user.info 2009.01.26 15:52:56 ogsmd.objects INFO     org.freesmartphone.GSM.Network.Status: {'code': 24405, 'cid': '00BD', 'registration': 'roaming', 'lac': '232F', 'strength': 72, 'mode': 'automatic', 'provider': 'Saunalahti'}
240Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
241Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
242Jan 26 15:52:58 ginger user.debug 2009.01.26 15:52:58 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
243Jan 26 15:53:00 ginger user.debug 2009.01.26 15:53:00 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
244Jan 26 15:53:02 ginger user.debug 2009.01.26 15:53:02 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
245Jan 26 15:53:04 ginger user.debug 2009.01.26 15:53:04 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
246Jan 26 15:53:06 ginger user.debug 2009.01.26 15:53:06 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
247Jan 26 15:53:08 ginger user.debug 2009.01.26 15:53:08 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
248Jan 26 15:53:10 ginger user.debug 2009.01.26 15:53:10 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
249Jan 26 15:53:11 ginger user.debug 2009.01.26 15:53:11 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 17 bytes from: '\r\n%CGREG: 9,,,1\r\n'
250Jan 26 15:53:11 ginger user.info 2009.01.26 15:53:11 ogsmd    INFO     <UnsolicitedResponseChannel via /dev/pts/2>: unhandled unsolicited data incoming: '%CGREG: 9,,,1'
251Jan 26 15:53:12 ginger user.debug 2009.01.26 15:53:12 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
252Jan 26 15:53:14 ginger user.debug 2009.01.26 15:53:14 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
253Jan 26 15:53:16 ginger user.debug 2009.01.26 15:53:16 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
254Jan 26 15:53:17 ginger user.info kernel: [147226.500000] fbcon_event_notify action=9, data=c190fe08
255Jan 26 15:53:17 ginger user.info kernel: [147226.500000] jbt6k74 spi2.0: **** jbt6k74 vsync suspend
256Jan 26 15:53:18 ginger user.debug 2009.01.26 15:53:18 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
257Jan 26 15:53:18 ginger user.info kernel: [147227.540000] fbcon_event_notify action=12, data=c190fdcc
258Jan 26 15:53:18 ginger user.info kernel: [147227.540000] jbt6k74 spi2.0: **** jbt6k74 unblank
259Jan 26 15:53:18 ginger user.info kernel: [147227.610000] fbcon_event_notify action=9, data=c190fe08
260Jan 26 15:53:18 ginger user.info kernel: [147227.610000] jbt6k74 spi2.0: **** jbt6k74 unblank
261Jan 26 15:53:18 ginger user.info kernel: [147227.855000] pcf50633 0-0073: INT1=0x80 INT2=0x00 INT3=0x00 INT4=0x40 INT5=0x00
262Jan 26 15:53:18 ginger user.info kernel: [147227.875000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
263Jan 26 15:53:18 ginger user.info kernel: [147227.895000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
264Jan 26 15:53:20 ginger user.debug 2009.01.26 15:53:20 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
265Jan 26 15:53:22 ginger user.debug 2009.01.26 15:53:22 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
266Jan 26 15:53:24 ginger user.debug 2009.01.26 15:53:24 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
267Jan 26 15:53:26 ginger user.debug 2009.01.26 15:53:26 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
268Jan 26 15:53:27 ginger user.info 2009.01.26 15:53:27 frameworkd.resource INFO     setting resource status for GSM from enabled to suspending
269Jan 26 15:53:27 ginger user.debug 2009.01.26 15:53:27 ogsmd.modem.abstract DEBUG    prepareForSuspend ACK from channel <MiscChannel via /dev/pts/1> received
270Jan 26 15:53:27 ginger user.debug 2009.01.26 15:53:27 ogsmd.modem.abstract DEBUG    prepareForSuspend ACK from channel <CallChannel via /dev/pts/3> received
271Jan 26 15:53:27 ginger user.debug 2009.01.26 15:53:27 ogsmd    DEBUG    (<UnsolicitedResponseChannel via /dev/pts/2>: last communication with modem was 15 seconds ago. Sending EOF to wakeup)
272Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CTZU=0\r\n', None, None, 10), ('AT+CTZR=0\r\n', None, None, 10), ('AT+CREG=0\r\n', None, None, 10), ('AT+CGREG=0\r\n', None, None, 10), ('AT+CGEREP
273Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZU=0\r\n'
274Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
275Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
276Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZU=0' => ['OK']
277Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CTZR=0\r\n', None, None, 10), ('AT+CREG=0\r\n', None, None, 10), ('AT+CGREG=0\r\n', None, None, 10), ('AT+CGEREP=0,0\r\n', None, None, 10), ('AT+CN
278Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZR=0\r\n'
279Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
280Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZR=0' => ['OK']
281Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CREG=0\r\n', None, None, 10), ('AT+CGREG=0\r\n', None, None, 10), ('AT+CGEREP=0,0\r\n', None, None, 10), ('AT+CNMI=2,1,0,0,0\r\n', None, None, 10),
282Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CREG=0\r\n'
283Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
284Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CREG=0' => ['OK']
285Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CGREG=0\r\n', None, None, 10), ('AT+CGEREP=0,0\r\n', None, None, 10), ('AT+CNMI=2,1,0,0,0\r\n', None, None, 10), ('AT%CSQ=0\r\n', None, None, 10),
286Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT+CGREG=0\r\n'
287Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
288Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGREG=0' => ['OK']
289Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CGEREP=0,0\r\n', None, None, 10), ('AT+CNMI=2,1,0,0,0\r\n', None, None, 10), ('AT%CSQ=0\r\n', None, None, 10), ('AT%CGEREP=0\r\n', None, None, 10),
290Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 15 bytes: 'AT+CGEREP=0,0\r\n'
291Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
292Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGEREP=0,0' => ['OK']
293Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CNMI=2,1,0,0,0\r\n', None, None, 10), ('AT%CSQ=0\r\n', None, None, 10), ('AT%CGEREP=0\r\n', None, None, 10), ('AT%CGREG=0\r\n', None, None, 10), ('
294Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 19 bytes: 'AT+CNMI=2,1,0,0,0\r\n'
295Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
296Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CNMI=2,1,0,0,0' => ['OK']
297Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CSQ=0\r\n', None, None, 10), ('AT%CGEREP=0\r\n', None, None, 10), ('AT%CGREG=0\r\n', None, None, 10), ('AT%CBHZ=0\r\n', <function done at 0x393130>
298Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 10 bytes: 'AT%CSQ=0\r\n'
299Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 12 bytes from: '\r\n%CSQ: 0 \r\n'
300Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
301Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CSQ=0' => ['%CSQ: 0 ', 'OK']
302Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CGEREP=0\r\n', None, None, 10), ('AT%CGREG=0\r\n', None, None, 10), ('AT%CBHZ=0\r\n', <function done at 0x393130>, <function done at 0x393130>, 10)
303Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 13 bytes: 'AT%CGEREP=0\r\n'
304Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
305Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CGEREP=0' => ['OK']
306Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CGREG=0\r\n', None, None, 10), ('AT%CBHZ=0\r\n', <function done at 0x393130>, <function done at 0x393130>, 10)])
307Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT%CGREG=0\r\n'
308Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
309Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CGREG=0' => ['OK']
310Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CBHZ=0\r\n', <function done at 0x393130>, <function done at 0x393130>, 10)])
311Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CBHZ=0\r\n'
312Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
313Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CBHZ=0' => ['OK']
314Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd.modem.abstract DEBUG    prepareForSuspend ACK from channel <UnsolicitedResponseChannel via /dev/pts/2> received
315Jan 26 15:53:28 ginger user.info 2009.01.26 15:53:28 frameworkd.resource INFO     setting resource status for GSM from suspending to suspended
316Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([])
317Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: nothing in request queue
318Jan 26 15:53:29 ginger user.notice lindi: suspending
319Jan 26 15:53:30 ginger user.debug 2009.01.26 15:53:30 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
320Jan 26 15:53:32 ginger user.debug 2009.01.26 15:53:32 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
321Jan 26 15:53:33 ginger user.info kernel: [147242.865000] PM: Syncing filesystems ... done.
322Jan 26 15:53:37 ginger user.warn kernel: [147243.060000] Freezing user space processes ...
323Jan 26 15:53:37 ginger user.debug 2009.01.26 15:53:37 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
324Jan 26 15:53:37 ginger user.info kernel: (elapsed 0.04 seconds) done.
325Jan 26 15:53:37 ginger user.warn kernel: [147243.105000] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
326Jan 26 15:53:37 ginger user.warn kernel: [147243.105000] Suspending console(s) (use no_console_suspend to debug)
327Jan 26 15:53:37 ginger user.info kernel: [147243.125000] glamo-mci glamo-mci.0: glamo_mci_set_ios: power down.
328Jan 26 15:53:37 ginger user.info kernel: [147243.185000] jbt6k74 spi2.0: **** jbt6k74 suspend end
329Jan 26 15:53:37 ginger user.info kernel: [147243.185000] fbcon_event_notify action=2, data=c694bdd8
330Jan 26 15:53:37 ginger user.info kernel: [147243.185000] glamo3362 glamo3362.0: ***** glamo_power -> 1
331Jan 26 15:53:37 ginger user.info kernel: [147243.185000] neo1973-pm-bt neo1973-pm-bt.0: __gta02_pm_bt_toggle_radio 0
332Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] gta02_udc_command S3C2410_UDC_P_DISABLE
333Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] suspending dma channel 0
334Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] suspending dma channel 1
335Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] suspending dma channel 2
336Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] suspending dma channel 3
337Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4000008 value 71ff3d8c
338Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f400001c value 0000f9ff
339Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved fb0000c4 value 00000000
340Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved fb0000c8 value 00000000
341Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved fb0000d4 value 00000972
342Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved fb0000d0 value 01551554
343Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved fb0000d8 value 00001f7e
344Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] s3c_pm_enter(3)
345Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] s3c_sleep_save_phys=0x3694be38
346Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOA: save 007e5fff,00010000,00000000,00000000
347Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOB: save 00155555,00000080,000007ff,00000000
348Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOC: save 55555155,0000a000,0000ffdf,00000000
349Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOD: save 55555555,00002019,0000ffff,00000000
350Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOE: save aaaaa6aa,0000ffc4,0000f7ff,00000000
351Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOF: save 0000aaaa,00000021,000000f7,00000000
352Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOG: save 01aa5279,00006f36,0000ffff,00000000
353Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOH: save 001aa9aa,000002ec,000006f6,00000000
354Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved fb000084 value 00000000
355Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4100000 value 00ffffff
356Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f410000c value 00017de0
357Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200000 value 2211d1d0
358Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200004 value 00000700
359Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200008 value 00001bc0
360Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f420000c value 00000700
361Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200010 value 00001f4c
362Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200014 value 00000700
363Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200018 value 00000700
364Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4100014 value 00000005
365Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4100004 value 0002a010
366Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200024 value 009e03e5
367Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4100008 value 00058042
368Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4100010 value 00000004
369Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Disabling IRQ 16 (pin 160)
370Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Leaving IRQ 17 (pin 161) enabled
371Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Disabling IRQ 18 (pin 162)
372Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Disabling IRQ 19 (pin 163)
373Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Leaving IRQ 48 (pin 164) enabled
374Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Leaving IRQ 49 (pin 165) enabled
375Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Disabling IRQ 50 (pin 166)
376Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Disabling IRQ 51 (pin 167)
377Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Leaving IRQ 53 (pin 193) enabled
378Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Disabling IRQ 56 (pin 196)
379Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] sleep: irq wakeup masks: fffffffd,fffffdcf
380Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GSTATUS3 0x304c482c
381Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GSTATUS4 0x00000200
382Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore fb000084 (restore 00000000, was 00000000)
383Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOA: CON 007e5fff => 007e5fff, DAT 00010000 => 00010000
384Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOB: CON 00155555 => 00155555, DAT 00000080 => 00000080
385Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOC: CON 55555155 => 55555155, DAT 0000a000 => 0000a000
386Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOD: CON 55555555 => 55555555, DAT 00002019 => 00002019
387Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOE: CON aaaaa6aa => aaaaa6aa, DAT 0000ffcd => 0000ffc4
388Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOF: CON 00000a08 => 0000aaaa, DAT 00000022 => 00000021
389Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOG: CON 01aa5079 => 01aa5279, DAT 00006e36 => 00006f36
390Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOH: CON 000000aa => 001aa9aa, DAT 000006ee => 000002ec
391Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] post sleep: IRQs 0x0000000f, 0x00010000
392Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] IRQ 17 asserted at resume
393Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] s3c_pm_enter: post sleep, preparing to return
394Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] S3C PM Resume (post-restore)
395Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore fb0000c4 (restore 00000000, was 00000000)
396Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore fb0000c8 (restore 00000000, was 00000000)
397Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore fb0000d4 (restore 00000972, was 00000972)
398Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore fb0000d0 (restore 01551554, was 01551554)
399Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore fb0000d8 (restore 00001f7e, was 00001f7e)
400Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore f4000008 (restore 71ff3d8c, was ffffffff)
401Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore f400001c (restore 0000f9ff, was 0000ffff)
402Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] timer tcon=00000000, tcnt a2c1, tcfg 00000200,00000000, usec 00001eb8
403Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] modem wakeup interrupt
404Jan 26 15:53:37 ginger user.info kernel: [147243.245000] s3c2410-wdt: watchdog enabled
405Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] gta02_udc_command S3C2410_UDC_P_ENABLE
406Jan 26 15:53:37 ginger user.info kernel: [147243.245000] s3c2440-nand s3c2440-nand: Tacls=1, 10ns Twrph0=3 30ns, Twrph1=2 20ns
407Jan 26 15:53:37 ginger user.warn kernel: [147243.245000] not changing prescaler of PWM 3, since it's shared with timer4 (clock tick)
408Jan 26 15:53:37 ginger user.info kernel: [147243.245000] s3c2440-i2c s3c2440-i2c: slave address 0x10
409Jan 26 15:53:37 ginger user.info kernel: [147243.245000] s3c2440-i2c s3c2440-i2c: bus frequency set to 390 KHz
410Jan 26 15:53:37 ginger user.err kernel: [147243.245000] ##### callback_all_resume_dependencies(head=c04dbc9c)
411Jan 26 15:53:37 ginger user.err kernel: [147243.245000] ##### callback_all_resume_dependencies(head=c04dbd78)
412Jan 26 15:53:37 ginger user.err kernel: [147243.245000] ##### callback_all_resume_dependencies(head=c04dbe54)
413Jan 26 15:53:37 ginger user.info kernel: [147243.280000] pcf50633 0-0073: INT1=0x80 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
414Jan 26 15:53:37 ginger user.info kernel: [147243.295000] neo1973-pm-bt neo1973-pm-bt.0: __gta02_pm_bt_toggle_radio 0
415Jan 26 15:53:37 ginger user.info kernel: [147243.305000] neo1973-pm-bt neo1973-pm-bt.0: __gta02_pm_bt_toggle_radio 1
416Jan 26 15:53:37 ginger user.info kernel: [147243.335000] glamo3362 glamo3362.0: ***** glamo_power -> 0
417Jan 26 15:53:37 ginger user.err kernel: [147243.345000] spin_lock_init
418Jan 26 15:53:37 ginger user.info kernel: [147243.350000] fbcon_event_notify action=3, data=c694bdd8
419Jan 26 15:53:37 ginger user.info kernel: [147243.420000] jbt6k74 spi2.0: **** jbt6k74 resume start
420Jan 26 15:53:37 ginger user.info kernel: [147243.715000] jbt6k74 spi2.0: **** jbt6k74 resume end
421Jan 26 15:53:37 ginger user.err kernel: [147243.785000] glamo-mci glamo-mci.0: IGNORING glamo_mci_reset while suspended
422Jan 26 15:53:37 ginger user.info kernel: [147243.815000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 0kHz div=255 (req: 0kHz). Bus width=0
423Jan 26 15:53:37 ginger user.info kernel: [147243.825000] SD power -> 2700mV
424Jan 26 15:53:37 ginger user.info kernel: [147243.825000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
425Jan 26 15:53:37 ginger user.info kernel: [147243.825000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
426Jan 26 15:53:37 ginger user.info kernel: [147243.825000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
427Jan 26 15:53:37 ginger user.info kernel: [147243.845000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
428Jan 26 15:53:37 ginger user.info kernel: [147243.850000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
429Jan 26 15:53:37 ginger user.info kernel: [147243.850000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 16666kHz div=2 (req: 16666kHz). Bus width=0
430Jan 26 15:53:37 ginger user.info kernel: [147243.850000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 16666kHz div=2 (req: 16666kHz). Bus width=2
431Jan 26 15:53:37 ginger user.info kernel: [147243.850000] soc-audio soc-audio: scheduling resume work
432Jan 26 15:53:37 ginger user.warn kernel: [147243.860000] Restarting tasks ... done.
433Jan 26 15:53:37 ginger user.info kernel: [147244.000000] soc-audio soc-audio: starting resume work
434Jan 26 15:53:38 ginger user.info kernel: [147244.635000] soc-audio soc-audio: resume work completed
435Jan 26 15:53:38 ginger user.info kernel: [147244.655000] pcf50633 0-0073: INT1=0x80 INT2=0x00 INT3=0x00 INT4=0x40 INT5=0x00
436Jan 26 15:53:38 ginger user.info kernel: [147244.735000] fbcon_event_notify action=1, data=c190fdf0
437Jan 26 15:53:38 ginger user.info kernel: [147244.795000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
438Jan 26 15:53:38 ginger user.info kernel: [147244.815000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
439Jan 26 15:53:39 ginger authpriv.notice sudo:    lindi : TTY=pts/8 ; PWD=/home/lindi ; USER=root ; COMMAND=/sbin/hwclock --hctosys
440Jan 26 15:53:40 ginger user.debug 2009.01.26 15:53:40 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
441Jan 26 15:53:43 ginger user.notice lindi: resuming (reason * EINT01_GSM)
442Jan 26 15:53:44 ginger user.debug 2009.01.26 15:53:44 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
443Jan 26 15:53:44 ginger user.info 2009.01.26 15:53:44 frameworkd.resource INFO     setting resource status for GSM from suspended to resuming
444Jan 26 15:53:44 ginger user.debug 2009.01.26 15:53:44 ogsmd    DEBUG    TI Calypso specific resume handling... sending reinit in 5 seconds...
445Jan 26 15:53:44 ginger user.debug 2009.01.26 15:53:44 ogsmd.modem.abstract DEBUG    recoverFromSuspend ACK from channel <MiscChannel via /dev/pts/1> received
446Jan 26 15:53:44 ginger user.debug 2009.01.26 15:53:44 ogsmd    DEBUG    TI Calypso specific resume handling... sending reinit in 5 seconds...
447Jan 26 15:53:44 ginger user.debug 2009.01.26 15:53:44 ogsmd.modem.abstract DEBUG    recoverFromSuspend ACK from channel <UnsolicitedResponseChannel via /dev/pts/2> received
448Jan 26 15:53:44 ginger user.debug 2009.01.26 15:53:44 ogsmd    DEBUG    TI Calypso specific resume handling... sending reinit in 5 seconds...
449Jan 26 15:53:44 ginger user.debug 2009.01.26 15:53:44 ogsmd.modem.abstract DEBUG    recoverFromSuspend ACK from channel <CallChannel via /dev/pts/3> received
450Jan 26 15:53:44 ginger user.info 2009.01.26 15:53:44 frameworkd.resource INFO     setting resource status for GSM from resuming to enabled
451Jan 26 15:53:46 ginger user.debug 2009.01.26 15:53:46 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
452Jan 26 15:53:46 ginger authpriv.notice sudo:    lindi : TTY=pts/8 ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/class/i2c-adapter/i2c-0/0-0073/pcf50633-regltr.6/neo1973-pm-bt.0/reset
453Jan 26 15:53:46 ginger authpriv.notice sudo:    lindi : TTY=unknown ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/bus/platform/drivers/s3c2440-sdi/unbind
454Jan 26 15:53:46 ginger authpriv.notice sudo:    lindi : TTY=unknown ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/bus/platform/drivers/gta02-pm-wlan/gta02-pm-wlan.0/power_on
455Jan 26 15:53:48 ginger user.debug 2009.01.26 15:53:48 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
456Jan 26 15:53:50 ginger user.debug 2009.01.26 15:53:50 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
457Jan 26 15:53:52 ginger user.debug 2009.01.26 15:53:52 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
458Jan 26 15:53:53 ginger user.info 2009.01.26 15:53:53 ogsmd.pdp INFO     shutting down pppd, pid 31375.
459Jan 26 15:53:53 ginger local2.info pppd[31375]: Terminating on signal 2
460Jan 26 15:53:53 ginger local2.info pppd[31375]: Connect time 9.5 minutes.
461Jan 26 15:53:53 ginger local2.info pppd[31375]: Sent 70446 bytes, received 127372 bytes.
462Jan 26 15:53:53 ginger user.debug 2009.01.26 15:53:53 ogsmd.pdp DEBUG    got output from ppp: 'Terminating on signal 2\nConnect time 9.5 minutes.\nSent 70446 bytes, received 127372 bytes.\n'
463Jan 26 15:53:53 ginger local2.debug pppd[31375]: Script /etc/ppp/ip-down started (pid 31734)
464Jan 26 15:53:53 ginger user.debug 2009.01.26 15:53:53 ogsmd.pdp DEBUG    got output from ppp: 'Script /etc/ppp/ip-down started (pid 31734)'
465Jan 26 15:53:53 ginger user.debug 2009.01.26 15:53:53 ogsmd.pdp DEBUG    got output from ppp: '\n'
466Jan 26 15:53:53 ginger local2.debug pppd[31375]: sent [LCP TermReq id=0x3 "User request"]
467Jan 26 15:53:53 ginger local2.debug pppd[31375]: rcvd [LCP TermAck id=0x3 "User request"]
468Jan 26 15:53:53 ginger local2.notice pppd[31375]: Connection terminated.
469Jan 26 15:53:53 ginger user.debug 2009.01.26 15:53:53 ogsmd.pdp DEBUG    got output from ppp: 'sent [LCP TermReq id=0x3 "User request"]\nrcvd [LCP TermAck id=0x3 "User request"]\nConnection terminated.\n'
470Jan 26 15:53:54 ginger user.debug 2009.01.26 15:53:54 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=
471Jan 26 15:53:54 ginger user.info 2009.01.26 15:53:54 ogsmd.objects INFO     org.freesmartphone.GSM.PDP.ContextStatus: 1 release {}
472Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (OK)
473Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (BUSY)
474Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (DELAYED)
475Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (NO ANSWER)
476Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (NO CARRIER)
477Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (NO DIALTONE)
478Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (VOICE)
479Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (ERROR)
480Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (RINGING)
481Jan 26 15:53:54 ginger local2.info chat[31740]: timeout set to 60 seconds
482Jan 26 15:53:54 ginger local2.info chat[31740]: send (\k\k\k\d+++ATH^M)
483Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    (<UnsolicitedResponseChannel via /dev/pts/2>: last communication with modem was 26 seconds ago. Sending EOF to wakeup)
484Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT+CGEREP
485Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZU=1\r\n'
486Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
487Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZU=1' => ['OK']
488Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CS
489Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZR=1\r\n'
490Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
491Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZR=1' => ['OK']
492Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CREG=2\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CSMS=1\r\n', None, None, 10), ('AT+CN
493Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CREG=2\r\n'
494Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
495Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CREG=2' => ['OK']
496Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CGREG=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CSMS=1\r\n', None, None, 10), ('AT+CNMI=2,1,2,1,1\r\n', None, None, 10),
497Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT+CGREG=2\r\n'
498Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
499Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGREG=2' => ['OK']
500Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CSMS=1\r\n', None, None, 10), ('AT+CNMI=2,1,2,1,1\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), (
501Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 15 bytes: 'AT+CGEREP=2,1\r\n'
502Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
503Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGEREP=2,1' => ['OK']
504Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CSMS=1\r\n', None, None, 10), ('AT+CNMI=2,1,2,1,1\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%
505Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CSMS=1\r\n'
506Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 22 bytes from: '\r\n+CSMS: 1,1,1\r\n\r\nOK\r\n'
507Jan 26 15:53:55 ginger local2.info chat[31740]: expect (NO CARRIER)
508Jan 26 15:53:55 ginger local2.info chat[31740]: ~!E^@^@d^R{@^@:^F^GzA&*^SUMe^X^@^V-J^UJ)Uw$J^D^@^X^@l}]\^@^@^A^A^H
509Jan 26 15:53:55 ginger local2.info chat[31740]: <R1.^AAE^\i"h,W,1
510Jan 26 15:53:55 ginger local2.info chat[31740]: ^^^HA^^^\\um5^SfNzN}^^OV^V^;l^^UXIN
511Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CSMS=1' => ['+CSMS: 1,1,1', 'OK']
512Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CNMI=2,1,2,1,1\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('A
513Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 19 bytes: 'AT+CNMI=2,1,2,1,1\r\n'
514Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
515Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CNMI=2,1,2,1,1' => ['OK']
516Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CBHZ=
517Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 10 bytes: 'AT%CSQ=1\r\n'
518Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 12 bytes from: '\r\n%CSQ: 1 \r\n'
519Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
520Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CSQ=1' => ['%CSQ: 1 ', 'OK']
521Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CBHZ=0\r\n', <function done at 0x393270
522Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CNIV=1\r\n'
523Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
524Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CNIV=1' => ['OK']
525Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CBHZ=0\r\n', <function done at 0x393270>, <function done at 0x393270>, 10)
526Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 13 bytes: 'AT%CGEREP=1\r\n'
527Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
528Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CGEREP=1' => ['OK']
529Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CGREG=3\r\n', None, None, 10), ('AT%CBHZ=0\r\n', <function done at 0x393270>, <function done at 0x393270>, 10)])
530Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT%CGREG=3\r\n'
531Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
532Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CGREG=3' => ['OK']
533Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CBHZ=0\r\n', <function done at 0x393270>, <function done at 0x393270>, 10)])
534Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CBHZ=0\r\n'
535Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
536Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CBHZ=0' => ['OK']
537Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([])
538Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: nothing in request queue
539Jan 26 15:53:56 ginger user.debug 2009.01.26 15:53:56 ogsmd.pdp DEBUG    route status. old=, last=, current=
540Jan 26 15:53:57 ginger user.debug 2009.01.26 15:53:57 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 44 bytes from: '\r\n+CGEV: ME DEACT "IP","085.077.229.024",1\r\n'
541Jan 26 15:53:57 ginger user.info 2009.01.26 15:53:57 ogsmd    INFO     <UnsolicitedResponseChannel via /dev/pts/2>: unhandled unsolicited data incoming: '+CGEV: ME DEACT "IP","085.077.229.024",1'
542Jan 26 15:53:57 ginger local2.info chat[31740]: ^Lx^W^MBOOg.^M>5c^Ml~^M
543Jan 26 15:53:57 ginger local2.info chat[31740]: NO CARRIER
544Jan 26 15:53:57 ginger local2.info chat[31740]:  -- got it
545Jan 26 15:53:57 ginger local2.info chat[31740]: send (^M)
546Jan 26 15:53:57 ginger local2.info pppd[31375]: Serial link disconnected.
547Jan 26 15:53:57 ginger user.debug 2009.01.26 15:53:57 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 44 bytes from: '\r\n%CGEV: ME DEACT "IP","085.077.229.024",1\r\n'
548Jan 26 15:53:57 ginger user.info 2009.01.26 15:53:57 ogsmd    INFO     <UnsolicitedResponseChannel via /dev/pts/2>: unhandled unsolicited data incoming: '%CGEV: ME DEACT "IP","085.077.229.024",1'
549Jan 26 15:53:57 ginger user.debug 2009.01.26 15:53:57 ogsmd.pdp DEBUG    got output from ppp: 'Serial link disconnected.\n'
550Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    route status. old=, last=, current=
551Jan 26 15:53:58 ginger local2.debug pppd[31375]: Script /etc/ppp/ip-down finished (pid 31734), status = 0x0
552Jan 26 15:53:58 ginger local2.info pppd[31375]: Exit.
553Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    got output from ppp: 'Script /etc/ppp/ip-down finished (pid 31734), status = 0x0\n'
554Jan 26 15:53:58 ginger user.info 2009.01.26 15:53:58 ogsmd.pdp INFO     pppd exited with code 5 and signal 0
555Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    recovering file /etc/ppp/ip-up.d/08setupdns
556Jan 26 15:53:58 ginger local0.info /usr/sbin/gsm0710muxd[6447]: gsm0710muxd.c:612:pseudo_device_read(): Logical channel 4 for (null) closed
557Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    recovering file /etc/ppp/ip-down.d/92removedns
558Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    recovering file /var/tmp/ogsmd/gprs-disconnect-chat
559Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    recovering file /etc/ppp/pap-secrets
560Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    recovering file /etc/ppp/chap-secrets
561Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    recovering file /var/tmp/ogsmd/gprs-connect-chat
562Jan 26 15:54:07 ginger user.info 2009.01.26 15:54:07 frameworkd.resource INFO     setting resource status for GSM from enabled to suspending
563Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd.modem.abstract DEBUG    prepareForSuspend ACK from channel <MiscChannel via /dev/pts/1> received
564Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd.modem.abstract DEBUG    prepareForSuspend ACK from channel <CallChannel via /dev/pts/3> received
565Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    (<UnsolicitedResponseChannel via /dev/pts/2>: last communication with modem was 9 seconds ago. Sending EOF to wakeup)
566Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CTZU=0\r\n', None, None, 10), ('AT+CTZR=0\r\n', None, None, 10), ('AT+CREG=0\r\n', None, None, 10), ('AT+CGREG=0\r\n', None, None, 10), ('AT+CGEREP
567Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZU=0\r\n'
568Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
569Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZU=0' => ['OK']
570Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CTZR=0\r\n', None, None, 10), ('AT+CREG=0\r\n', None, None, 10), ('AT+CGREG=0\r\n', None, None, 10), ('AT+CGEREP=0,0\r\n', None, None, 10), ('AT+CN
571Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZR=0\r\n'
572Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
573Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZR=0' => ['OK']
574Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CREG=0\r\n', None, None, 10), ('AT+CGREG=0\r\n', None, None, 10), ('AT+CGEREP=0,0\r\n', None, None, 10), ('AT+CNMI=2,1,0,0,0\r\n', None, None, 10),
575Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CREG=0\r\n'
576Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
577Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CREG=0' => ['OK']
578Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CGREG=0\r\n', None, None, 10), ('AT+CGEREP=0,0\r\n', None, None, 10), ('AT+CNMI=2,1,0,0,0\r\n', None, None, 10), ('AT%CSQ=0\r\n', None, None, 10),
579Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT+CGREG=0\r\n'
580Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
581Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGREG=0' => ['OK']
582Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CGEREP=0,0\r\n', None, None, 10), ('AT+CNMI=2,1,0,0,0\r\n', None, None, 10), ('AT%CSQ=0\r\n', None, None, 10), ('AT%CGEREP=0\r\n', None, None, 10),
583Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 15 bytes: 'AT+CGEREP=0,0\r\n'
584Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
585Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGEREP=0,0' => ['OK']
586Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CNMI=2,1,0,0,0\r\n', None, None, 10), ('AT%CSQ=0\r\n', None, None, 10), ('AT%CGEREP=0\r\n', None, None, 10), ('AT%CGREG=0\r\n', None, None, 10), ('
587Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 19 bytes: 'AT+CNMI=2,1,0,0,0\r\n'
588Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
589Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CNMI=2,1,0,0,0' => ['OK']
590Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CSQ=0\r\n', None, None, 10), ('AT%CGEREP=0\r\n', None, None, 10), ('AT%CGREG=0\r\n', None, None, 10), ('AT%CBHZ=0\r\n', <function done at 0x379ef0>
591Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 10 bytes: 'AT%CSQ=0\r\n'
592Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 18 bytes from: '\r\n%CSQ: 0 \r\n\r\nOK\r\n'
593Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CSQ=0' => ['%CSQ: 0 ', 'OK']
594Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CGEREP=0\r\n', None, None, 10), ('AT%CGREG=0\r\n', None, None, 10), ('AT%CBHZ=0\r\n', <function done at 0x379ef0>, <function done at 0x379ef0>, 10)
595Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 13 bytes: 'AT%CGEREP=0\r\n'
596Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
597Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CGEREP=0' => ['OK']
598Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CGREG=0\r\n', None, None, 10), ('AT%CBHZ=0\r\n', <function done at 0x379ef0>, <function done at 0x379ef0>, 10)])
599Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT%CGREG=0\r\n'
600Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
601Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CGREG=0' => ['OK']
602Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CBHZ=0\r\n', <function done at 0x379ef0>, <function done at 0x379ef0>, 10)])
603Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CBHZ=0\r\n'
604Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
605Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CBHZ=0' => ['OK']
606Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd.modem.abstract DEBUG    prepareForSuspend ACK from channel <UnsolicitedResponseChannel via /dev/pts/2> received
607Jan 26 15:54:07 ginger user.info 2009.01.26 15:54:07 frameworkd.resource INFO     setting resource status for GSM from suspending to suspended
608Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([])
609Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: nothing in request queue
610Jan 26 15:54:08 ginger user.notice lindi: suspending
611Jan 26 15:54:13 ginger user.info kernel: [147276.895000] PM: Syncing filesystems ... done.
612Jan 26 17:56:58 ginger user.warn kernel: [147277.080000] Freezing user space processes ...
613Jan 26 17:56:58 ginger user.debug 2009.01.26 17:56:58 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 44 bytes from: '\r\n%CPI: 1,0,0,0,1,0,"+35897712644",145,,,0\r\n'
614Jan 26 17:56:59 ginger user.info 2009.01.26 17:56:59 ogsmd.objects INFO     org.freesmartphone.GSM.Call.CallStatus: 1 incoming {'status': 'incoming', 'peer': '+35897712644', 'line': 0, 'direction': 'incoming'}
615Jan 26 17:56:59 ginger user.debug 2009.01.26 17:56:59 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 17 bytes from: '\r\n+CRING: VOICE\r\n'
616Jan 26 17:56:59 ginger user.info 2009.01.26 17:56:59 ogsmd    INFO     <MiscChannel via /dev/pts/1>: unhandled unsolicited data incoming: '+CRING: VOICE'
617Jan 26 17:56:59 ginger user.info kernel: (elapsed 0.03 seconds) done.
618Jan 26 17:56:59 ginger user.warn kernel: [147277.115000] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
619Jan 26 17:56:59 ginger user.warn kernel: [147277.115000] Suspending console(s) (use no_console_suspend to debug)
620Jan 26 17:56:59 ginger user.info kernel: [147277.135000] glamo-mci glamo-mci.0: glamo_mci_set_ios: power down.
621Jan 26 17:56:59 ginger user.info kernel: [147277.195000] jbt6k74 spi2.0: **** jbt6k74 suspend end
622Jan 26 17:56:59 ginger user.info kernel: [147277.195000] fbcon_event_notify action=2, data=c6be5dd8
623Jan 26 17:56:59 ginger user.info kernel: [147277.195000] glamo3362 glamo3362.0: ***** glamo_power -> 1
624Jan 26 17:56:59 ginger user.info kernel: [147277.195000] neo1973-pm-bt neo1973-pm-bt.0: __gta02_pm_bt_toggle_radio 0
625Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] gta02_udc_command S3C2410_UDC_P_DISABLE
626Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] suspending dma channel 0
627Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] suspending dma channel 1
628Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] suspending dma channel 2
629Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] suspending dma channel 3
630Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4000008 value 71ff3d8c
631Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f400001c value 0000f9ff
632Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved fb0000c4 value 00000000
633Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved fb0000c8 value 00000000
634Jan 26 17:56:59 ginger user.debug 2009.01.26 17:56:59 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 44 bytes from: '\r\n%CPI: 1,0,0,1,1,0,"+35897712644",145,,,0\r\n'
635Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved fb0000d4 value 00000972
636Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved fb0000d0 value 01551554
637Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved fb0000d8 value 00001f7e
638Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] s3c_pm_enter(3)
639Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] s3c_sleep_save_phys=0x36be5e38
640Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOA: save 007e5fff,00010000,00000000,00000000
641Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOB: save 00155555,00000080,000007ff,00000000
642Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOC: save 55555155,0000a000,0000ffdf,00000000
643Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOD: save 55555555,00002019,0000ffff,00000000
644Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOE: save aaaaa6aa,0000ffc4,0000f7ff,00000000
645Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOF: save 0000aaaa,00000021,000000f7,00000000
646Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOG: save 01aa5279,00006f36,0000ffff,00000000
647Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOH: save 001aa9aa,000002ec,000006f6,00000000
648Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved fb000084 value 00000000
649Jan 26 17:56:59 ginger user.info 2009.01.26 17:56:59 ogsmd.objects INFO     org.freesmartphone.GSM.Call.CallStatus: 1 incoming {'status': 'incoming', 'direction': 'incoming', 'peer': '+35897712644', 'line': 0}
650Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4100000 value 00ffffff
651Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f410000c value 00017de0
652Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200000 value 2211d1d0
653Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200004 value 00000700
654Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200008 value 00001bc0
655Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f420000c value 00000700
656Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200010 value 00001f4c
657Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200014 value 00000700
658Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200018 value 00000700
659Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4100014 value 00000005
660Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4100004 value 0002a010
661Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200024 value 009e03e5
662Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4100008 value 00058042
663Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4100010 value 00000004
664Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Disabling IRQ 16 (pin 160)
665Jan 26 17:56:59 ginger user.debug 2009.01.26 17:56:59 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: got 17 bytes from: '\r\n+CRING: VOICE\r\n'
666Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Leaving IRQ 17 (pin 161) enabled
667Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Disabling IRQ 18 (pin 162)
668Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Disabling IRQ 19 (pin 163)
669Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Leaving IRQ 48 (pin 164) enabled
670Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Leaving IRQ 49 (pin 165) enabled
671Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Disabling IRQ 50 (pin 166)
672Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Disabling IRQ 51 (pin 167)
673Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Leaving IRQ 53 (pin 193) enabled
674Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Disabling IRQ 56 (pin 196)
675Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] sleep: irq wakeup masks: fffffffd,fffffdcf
676Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GSTATUS3 0x304c482c
677Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GSTATUS4 0x00000002
678Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore fb000084 (restore 00000000, was 00000000)
679Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOA: CON 007e5fff => 007e5fff, DAT 00010000 => 00010000
680Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOB: CON 00155555 => 00155555, DAT 00000080 => 00000080
681Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOC: CON 55555155 => 55555155, DAT 0000a000 => 0000a000
682Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOD: CON 55555555 => 55555555, DAT 00002019 => 00002019
683Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOE: CON aaaaa6aa => aaaaa6aa, DAT 0000ffcd => 0000ffc4
684Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOF: CON 00000a08 => 0000aaaa, DAT 00000022 => 00000021
685Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOG: CON 01aa5079 => 01aa5279, DAT 00006e36 => 00006f36
686Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOH: CON 000000aa => 001aa9aa, DAT 000006ee => 000002ec
687Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] post sleep: IRQs 0x0000000f, 0x00010000
688Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] IRQ 17 asserted at resume
689Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] s3c_pm_enter: post sleep, preparing to return
690Jan 26 17:56:59 ginger user.warn 2009.01.26 17:56:59 ogsmd    WARNING  CALLCHANNEL: UNHANDLED INTERMEDIATE: +CRING: VOICE
691Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] S3C PM Resume (post-restore)
692Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore fb0000c4 (restore 00000000, was 00000000)
693Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore fb0000c8 (restore 00000000, was 00000000)
694Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore fb0000d4 (restore 00000972, was 00000972)
695Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore fb0000d0 (restore 01551554, was 01551554)
696Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore fb0000d8 (restore 00001f7e, was 00001f7e)
697Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore f4000008 (restore 71ff3d8c, was ffffffff)
698Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore f400001c (restore 0000f9ff, was 0000ffff)
699Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] timer tcon=00000000, tcnt a2c1, tcfg 00000200,00000000, usec 00001eb8
700Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] modem wakeup interrupt
701Jan 26 17:56:59 ginger user.info kernel: [147277.255000] s3c2410-wdt: watchdog enabled
702Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] gta02_udc_command S3C2410_UDC_P_ENABLE
703Jan 26 17:56:59 ginger user.info kernel: [147277.255000] s3c2440-nand s3c2440-nand: Tacls=1, 10ns Twrph0=3 30ns, Twrph1=2 20ns
704Jan 26 17:56:59 ginger user.warn kernel: [147277.255000] not changing prescaler of PWM 3, since it's shared with timer4 (clock tick)
705Jan 26 17:56:59 ginger user.info kernel: [147277.255000] s3c2440-i2c s3c2440-i2c: slave address 0x10
706Jan 26 17:56:59 ginger user.info kernel: [147277.255000] s3c2440-i2c s3c2440-i2c: bus frequency set to 390 KHz
707Jan 26 17:56:59 ginger user.err kernel: [147277.255000] ##### callback_all_resume_dependencies(head=c04dbc9c)
708Jan 26 17:56:59 ginger user.err kernel: [147277.255000] ##### callback_all_resume_dependencies(head=c04dbd78)
709Jan 26 17:56:59 ginger user.err kernel: [147277.255000] ##### callback_all_resume_dependencies(head=c04dbe54)
710Jan 26 17:56:59 ginger user.info kernel: [147277.290000] pcf50633 0-0073: INT1=0x80 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
711Jan 26 17:56:59 ginger user.info kernel: [147277.305000] neo1973-pm-bt neo1973-pm-bt.0: __gta02_pm_bt_toggle_radio 0
712Jan 26 17:56:59 ginger user.info kernel: [147277.315000] neo1973-pm-bt neo1973-pm-bt.0: __gta02_pm_bt_toggle_radio 1
713Jan 26 17:56:59 ginger user.info kernel: [147277.345000] glamo3362 glamo3362.0: ***** glamo_power -> 0
714Jan 26 17:56:59 ginger user.err kernel: [147277.355000] spin_lock_init
715Jan 26 17:56:59 ginger user.info kernel: [147277.360000] fbcon_event_notify action=3, data=c6be5dd8
716Jan 26 17:56:59 ginger user.info kernel: [147277.430000] jbt6k74 spi2.0: **** jbt6k74 resume start
717Jan 26 17:56:59 ginger user.info kernel: [147277.725000] jbt6k74 spi2.0: **** jbt6k74 resume end
718Jan 26 17:56:59 ginger user.err kernel: [147277.795000] glamo-mci glamo-mci.0: IGNORING glamo_mci_reset while suspended
719Jan 26 17:56:59 ginger user.info kernel: [147277.825000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 0kHz div=255 (req: 0kHz). Bus width=0
720Jan 26 17:56:59 ginger user.info kernel: [147277.835000] SD power -> 2700mV
721Jan 26 17:56:59 ginger user.info kernel: [147277.835000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
722Jan 26 17:56:59 ginger user.info kernel: [147277.835000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
723Jan 26 17:56:59 ginger user.info kernel: [147277.835000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
724Jan 26 17:56:59 ginger user.info kernel: [147277.855000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
725Jan 26 17:56:59 ginger user.info kernel: [147277.860000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
726Jan 26 17:56:59 ginger user.info kernel: [147277.860000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 16666kHz div=2 (req: 16666kHz). Bus width=0
727Jan 26 17:56:59 ginger user.info kernel: [147277.860000] glamo-mci glamo-mci.0: powered (vdd = 15) clk: 16666kHz div=2 (req: 16666kHz). Bus width=2
728Jan 26 17:56:59 ginger user.info kernel: [147277.860000] soc-audio soc-audio: scheduling resume work
729Jan 26 17:56:59 ginger user.warn kernel: [147277.870000] Restarting tasks ... <6>soc-audio soc-audio: starting resume work
730Jan 26 17:56:59 ginger user.warn kernel: [147278.020000] done.
731Jan 26 17:56:59 ginger authpriv.notice sudo:    lindi : TTY=unknown ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/neo1973:vibrator/trigger
732Jan 26 17:56:59 ginger authpriv.notice sudo:    lindi : TTY=unknown ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/neo1973:vibrator/delay_on
733Jan 26 17:56:59 ginger user.info kernel: [147278.750000] soc-audio soc-audio: resume work completed
734Jan 26 17:56:59 ginger user.info kernel: [147278.775000] pcf50633 0-0073: INT1=0x80 INT2=0x00 INT3=0x00 INT4=0x40 INT5=0x00
735Jan 26 17:56:59 ginger user.info kernel: [147278.905000] fbcon_event_notify action=1, data=c190fdf0
736Jan 26 17:57:00 ginger user.debug 2009.01.26 17:56:59 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 0 bytes from: ''
737Jan 26 17:57:00 ginger user.info 2009.01.26 17:57:00 ogsmd    INFO     <MiscChannel via /dev/pts/1>: HUP on socket, trying to recover
738Jan 26 17:57:00 ginger user.info kernel: [147278.975000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
739Jan 26 17:57:00 ginger user.info kernel: [147279.015000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
740Jan 26 17:57:00 ginger user.debug kernel: [147279.200000] modem wakeup interrupt
741Jan 26 17:57:00 ginger authpriv.notice sudo:    lindi : TTY=unknown ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/neo1973:vibrator/delay_off
742Jan 26 17:57:01 ginger user.warn kernel: [147280.110000] lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
743Jan 26 17:57:01 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 12500000 freq_out 11289600
744Jan 26 17:57:01 ginger user.info kernel: mapped channel 10 to 2
745Jan 26 17:57:01 ginger authpriv.notice sudo:    lindi : TTY=pts/6 ; PWD=/home/lindi ; USER=root ; COMMAND=/sbin/hwclock --hctosys
746Jan 26 17:57:01 ginger user.info gsm0710muxd: gsm0710muxd.c:1935:main(): using '/sys/bus/platform/devices/neo1973-pm-gsm.0' as basedir for pm
747Jan 26 17:57:02 ginger authpriv.notice sudo:    lindi : TTY=unknown ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/neo1973:vibrator/trigger
748Jan 26 17:57:03 ginger user.notice lindi: resuming (reason * EINT01_GSM)
749Jan 26 17:57:03 ginger authpriv.notice sudo:    lindi : TTY=unknown ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/neo1973:vibrator/delay_on
750Jan 26 17:57:03 ginger authpriv.notice sudo:    lindi : TTY=unknown ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/neo1973:vibrator/delay_off
751Jan 26 17:57:07 ginger user.warn kernel: [147285.980000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0
752Jan 26 17:57:07 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
753Jan 26 17:57:07 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
754Jan 26 17:57:07 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
755Jan 26 17:57:07 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 12500000 freq_out 11289600
756Jan 26 17:57:08 ginger user.info kernel: mapped channel 10 to 2
757Jan 26 17:57:09 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1617:open_serial_device(): Opened serial port
758Jan 26 17:57:09 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1641:open_serial_device(): Configured serial device
759Jan 26 17:57:09 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1763:watchdog(): Watchdog started
760Jan 26 17:57:09 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1652:start_muxer(): Configuring modem
761Jan 26 17:57:10 ginger local0.warn /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1656:start_muxer(): Modem does not respond to AT commands, trying close mux mode
762Jan 26 17:57:10 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1696:start_muxer(): Starting mux mode
763Jan 26 17:57:10 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1699:start_muxer(): Waiting for mux-mode
764Jan 26 17:57:11 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1701:start_muxer(): Init control channel
765Jan 26 17:57:11 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:698:c_alloc_channel(): Connecting /dev/pts/1 to virtual channel 1 for ogsmd.misc on /dev/ttySAC0
766Jan 26 17:57:11 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1421:extract_frames(): Logical channel 1 opened
767Jan 26 17:57:11 ginger user.info 2009.01.26 17:57:11 ogsmd    INFO     <MiscChannel via /dev/pts/1>: initializing
768Jan 26 17:57:11 ginger user.debug 2009.01.26 17:57:11 ogsmd    DEBUG    (modem init... try #1)
769Jan 26 17:57:11 ginger user.debug 2009.01.26 17:57:11 ogsmd    DEBUG    read: AT-Command Interpreter ready
770Jan 26 17:57:11 ginger user.info 2009.01.26 17:57:11 ogsmd    INFO     <MiscChannel via /dev/pts/1>: responding OK
771Jan 26 17:57:11 ginger user.debug 2009.01.26 17:57:11 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 0 bytes from: ''
772Jan 26 17:57:11 ginger user.info 2009.01.26 17:57:11 ogsmd    INFO     <UnsolicitedResponseChannel via /dev/pts/2>: HUP on socket, trying to recover
773Jan 26 17:57:13 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:698:c_alloc_channel(): Connecting /dev/pts/2 to virtual channel 2 for ogsmd.unsolicited on /dev/ttySAC0
774Jan 26 17:57:13 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1421:extract_frames(): Logical channel 2 opened
775Jan 26 17:57:13 ginger user.info 2009.01.26 17:57:13 ogsmd    INFO     <UnsolicitedResponseChannel via /dev/pts/2>: initializing
776Jan 26 17:57:13 ginger user.debug 2009.01.26 17:57:13 ogsmd    DEBUG    (modem init... try #1)
777Jan 26 17:57:13 ginger user.debug 2009.01.26 17:57:13 ogsmd    DEBUG    read: AT-Command Interpreter ready
778Jan 26 17:57:13 ginger user.info 2009.01.26 17:57:13 ogsmd    INFO     <UnsolicitedResponseChannel via /dev/pts/2>: responding OK
779Jan 26 17:57:13 ginger user.debug 2009.01.26 17:57:13 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: got 0 bytes from: ''
780Jan 26 17:57:13 ginger user.info 2009.01.26 17:57:13 ogsmd    INFO     <CallChannel via /dev/pts/3>: HUP on socket, trying to recover
781Jan 26 17:57:13 ginger user.warn kernel: [147291.980000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
782Jan 26 17:57:13 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
783Jan 26 17:57:14 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
784Jan 26 17:57:14 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 12500000 freq_out 11289600
785Jan 26 17:57:14 ginger user.info kernel: mapped channel 10 to 2
786Jan 26 17:57:14 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:698:c_alloc_channel(): Connecting /dev/pts/3 to virtual channel 3 for ogsmd.call on /dev/ttySAC0
787Jan 26 17:57:14 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1421:extract_frames(): Logical channel 3 opened
788Jan 26 17:57:14 ginger user.info 2009.01.26 17:57:14 ogsmd    INFO     <CallChannel via /dev/pts/3>: initializing
789Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    (modem init... try #1)
790Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    read: AT-Command Interpreter ready
791Jan 26 17:57:14 ginger user.info 2009.01.26 17:57:14 ogsmd    INFO     <CallChannel via /dev/pts/3>: responding OK
792Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
793Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
794Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([])
795Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: nothing in request queue
796Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    <CallChannel via /dev/pts/3> queue is: deque([])
797Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: nothing in request queue
798Jan 26 17:57:14 ginger user.info 2009.01.26 17:57:14 frameworkd.resource INFO     setting resource status for GSM from suspended to resuming
799Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    TI Calypso specific resume handling... sending reinit in 5 seconds...
800Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd.modem.abstract DEBUG    recoverFromSuspend ACK from channel <MiscChannel via /dev/pts/1> received
801Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    TI Calypso specific resume handling... sending reinit in 5 seconds...
802Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd.modem.abstract DEBUG    recoverFromSuspend ACK from channel <UnsolicitedResponseChannel via /dev/pts/2> received
803Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    TI Calypso specific resume handling... sending reinit in 5 seconds...
804Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd.modem.abstract DEBUG    recoverFromSuspend ACK from channel <CallChannel via /dev/pts/3> received
805Jan 26 17:57:14 ginger user.info 2009.01.26 17:57:14 frameworkd.resource INFO     setting resource status for GSM from resuming to enabled
806Jan 26 17:57:16 ginger user.warn 2009.01.26 17:57:16 ogsmd    WARNING  <MiscChannel via /dev/pts/1>: Got unicode input. Trying to convert to plain string...
807Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT%N0187\r\n', <bound method DebugCommand.responseFromChannel of <ogsmd.modems.abstract.mediator.DebugCommand object at 0x380eb0>>, <bound method DebugCommand.errorF
808Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 10 bytes: 'AT%N0187\r\n'
809Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 10 bytes from: '\r\nEXT: I\r\n'
810Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 47 bytes from: 'Both AEC and Noise reduction are active\r\n\r\nOK\r\n'
811Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT%N0187' => ['EXT: I', 'Both AEC and Noise reduction are active', 'OK']
812Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
813Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
814Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <CallChannel via /dev/pts/3> queue is: deque([('ATA\r\n', None, None, 3600)])
815Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: sending 5 bytes: 'ATA\r\n'
816Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: got 9 bytes from: '\r\nERROR\r\n'
817Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: COMPLETED 'ATA' => ['ERROR']
818Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <CallChannel via /dev/pts/3> queue is: deque([])
819Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: nothing in request queue
820Jan 26 17:57:16 ginger authpriv.notice sudo:    lindi : TTY=pts/6 ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/class/i2c-adapter/i2c-0/0-0073/pcf50633-regltr.6/neo1973-pm-bt.0/reset
821Jan 26 17:57:17 ginger authpriv.notice sudo:    lindi : TTY=unknown ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/bus/platform/drivers/s3c2440-sdi/unbind
822Jan 26 17:57:17 ginger authpriv.notice sudo:    lindi : TTY=unknown ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/bus/platform/drivers/gta02-pm-wlan/gta02-pm-wlan.0/power_on
823Jan 26 17:57:19 ginger user.warn kernel: [147298.215000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
824Jan 26 17:57:19 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
825Jan 26 17:57:20 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
826Jan 26 17:57:20 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 12500000 freq_out 11289600
827Jan 26 17:57:20 ginger user.info kernel: mapped channel 10 to 2
828Jan 26 17:57:24 ginger user.warn 2009.01.26 17:57:24 ogsmd    WARNING  <MiscChannel via /dev/pts/1>: Got unicode input. Trying to convert to plain string...
829Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    (<MiscChannel via /dev/pts/1>: last communication with modem was 8 seconds ago. Sending EOF to wakeup)
830Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT%N0187\r\n', <bound method DebugCommand.responseFromChannel of <ogsmd.modems.abstract.mediator.DebugCommand object at 0x380cb0>>, <bound method DebugCommand.errorF
831Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 10 bytes: 'AT%N0187\r\n'
832Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 10 bytes from: '\r\nEXT: I\r\n'
833Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 47 bytes from: 'Both AEC and Noise reduction are active\r\n\r\nOK\r\n'
834Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT%N0187' => ['EXT: I', 'Both AEC and Noise reduction are active', 'OK']
835Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
836Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
837Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <CallChannel via /dev/pts/3> queue is: deque([('ATA\r\n', None, None, 3600)])
838Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: sending 5 bytes: 'ATA\r\n'
839Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: got 9 bytes from: '\r\nERROR\r\n'
840Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: COMPLETED 'ATA' => ['ERROR']
841Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <CallChannel via /dev/pts/3> queue is: deque([])
842Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: nothing in request queue
843Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT+CGEREP
844Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZU=1\r\n'
845Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
846Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZU=1' => ['OK']
847Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CS
848Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZR=1\r\n'
849Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
850Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZR=1' => ['OK']
851Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CREG=2\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CSMS=1\r\n', None, None, 10), ('AT+CN
852Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CREG=2\r\n'
853Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
854Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CREG=2' => ['OK']
855Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CGREG=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CSMS=1\r\n', None, None, 10), ('AT+CNMI=2,1,2,1,1\r\n', None, None, 10),
856Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT+CGREG=2\r\n'
857Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
858Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGREG=2' => ['OK']
859Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CSMS=1\r\n', None, None, 10), ('AT+CNMI=2,1,2,1,1\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), (
860Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 15 bytes: 'AT+CGEREP=2,1\r\n'
861Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
862Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGEREP=2,1' => ['OK']
863Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CSMS=1\r\n', None, None, 10), ('AT+CNMI=2,1,2,1,1\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%
864Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CSMS=1\r\n'
865Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 9 bytes from: '\r\nERROR\r\n'
866Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CSMS=1' => ['ERROR']
867Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT+CNMI=2,1,2,1,1\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('A
868Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 19 bytes: 'AT+CNMI=2,1,2,1,1\r\n'
869Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 9 bytes from: '\r\nERROR\r\n'
870Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CNMI=2,1,2,1,1' => ['ERROR']
871Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CBHZ=
872Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 10 bytes: 'AT%CSQ=1\r\n'
873Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 18 bytes from: '\r\n%CSQ: 1 \r\n\r\nOK\r\n'
874Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CSQ=1' => ['%CSQ: 1 ', 'OK']
875Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CBHZ=0\r\n', <function done at 0x3932b0
876Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CNIV=1\r\n'
877Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
878Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CNIV=1' => ['OK']
879Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CBHZ=0\r\n', <function done at 0x3932b0>, <function done at 0x3932b0>, 10)
880Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 13 bytes: 'AT%CGEREP=1\r\n'
881Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
882Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CGEREP=1' => ['OK']
883Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CGREG=3\r\n', None, None, 10), ('AT%CBHZ=0\r\n', <function done at 0x3932b0>, <function done at 0x3932b0>, 10)])
884Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT%CGREG=3\r\n'
885Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
886Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CGREG=3' => ['OK']
887Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([('AT%CBHZ=0\r\n', <function done at 0x3932b0>, <function done at 0x3932b0>, 10)])
888Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CBHZ=0\r\n'
889Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 9 bytes from: '\r\nERROR\r\n'
890Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CBHZ=0' => ['ERROR']
891Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([])
892Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: nothing in request queue
893Jan 26 17:57:26 ginger user.warn kernel: [147304.315000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
894Jan 26 17:57:26 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
895Jan 26 17:57:26 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
896Jan 26 17:57:26 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 12500000 freq_out 11289600
897Jan 26 17:57:26 ginger user.info kernel: mapped channel 10 to 2
898Jan 26 17:57:29 ginger user.warn 2009.01.26 17:57:29 ogsmd    WARNING  <MiscChannel via /dev/pts/1>: Got unicode input. Trying to convert to plain string...
899Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([('AT%N0187\r\n', <bound method DebugCommand.responseFromChannel of <ogsmd.modems.abstract.mediator.DebugCommand object at 0x30ee10>>, <bound method DebugCommand.errorF
900Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: sending 10 bytes: 'AT%N0187\r\n'
901Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 10 bytes from: '\r\nEXT: I\r\n'
902Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 47 bytes from: 'Both AEC and Noise reduction are active\r\n\r\nOK\r\n'
903Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: COMPLETED 'AT%N0187' => ['EXT: I', 'Both AEC and Noise reduction are active', 'OK']
904Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
905Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
906Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <CallChannel via /dev/pts/3> queue is: deque([('ATA\r\n', None, None, 3600)])
907Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: sending 5 bytes: 'ATA\r\n'
908Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: got 9 bytes from: '\r\nERROR\r\n'
909Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: COMPLETED 'ATA' => ['ERROR']
910Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <CallChannel via /dev/pts/3> queue is: deque([])
911Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: nothing in request queue
912Jan 26 17:57:32 ginger user.warn kernel: [147310.400000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
913Jan 26 17:57:32 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
914Jan 26 17:57:32 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
915Jan 26 17:57:32 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 12500000 freq_out 11289600
916Jan 26 17:57:32 ginger user.info kernel: mapped channel 10 to 2
917Jan 26 17:57:38 ginger user.warn kernel: [147316.520000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
918Jan 26 17:57:38 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
919Jan 26 17:57:38 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
920Jan 26 17:57:38 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 12500000 freq_out 11289600
921Jan 26 17:57:38 ginger user.info kernel: mapped channel 10 to 2
922Jan 26 17:57:44 ginger user.warn kernel: [147322.610000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
923Jan 26 17:57:44 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
924Jan 26 17:57:44 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
925Jan 26 17:57:44 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 12500000 freq_out 11289600
926Jan 26 17:57:44 ginger user.info kernel: mapped channel 10 to 2
927Jan 26 17:57:50 ginger user.warn kernel: [147328.700000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
928Jan 26 17:57:50 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
929Jan 26 17:57:50 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
930Jan 26 17:57:50 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 12500000 freq_out 11289600
931Jan 26 17:57:50 ginger user.info kernel: mapped channel 10 to 2
932Jan 26 17:57:56 ginger user.warn kernel: [147334.835000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
933Jan 26 17:57:56 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
934Jan 26 17:57:56 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
935Jan 26 17:57:56 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 12500000 freq_out 11289600
936Jan 26 17:57:56 ginger user.info kernel: mapped channel 10 to 2
937Jan 26 17:58:02 ginger user.warn kernel: [147340.920000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
938Jan 26 17:58:02 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
939Jan 26 17:58:03 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
940Jan 26 17:58:03 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 12500000 freq_out 11289600
941Jan 26 17:58:03 ginger user.info kernel: mapped channel 10 to 2
942Jan 26 17:58:04 ginger authpriv.notice sudo:    lindi : TTY=unknown ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/neo1973:vibrator/trigger
943Jan 26 17:58:04 ginger authpriv.notice sudo:    lindi : TTY=unknown ; PWD=/home/lindi ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/neo1973:vibrator/brightness
944Jan 26 17:58:08 ginger user.warn kernel: [147347.155000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
945Jan 26 17:58:08 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
946Jan 26 17:58:09 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
947Jan 26 17:58:09 ginger user.info kernel: lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 12500000 freq_out 11289600
948Jan 26 17:58:09 ginger user.info kernel: mapped channel 10 to 2
949Jan 26 17:58:11 ginger user.warn kernel: [147349.945000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
950Jan 26 17:58:11 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0lindi: wm8753_set_dai_pll codec_dai c0a1c278 pll_id 0 freq_in 0 freq_out 0
951Jan 26 17:58:43 ginger authpriv.notice sudo:    lindi : TTY=pts/9 ; PWD=/home/lindi ; USER=root ; COMMAND=/bin/busybox logread