Jan 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'
Jan 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'
Jan 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']
Jan 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 
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 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'}
Jan 26 15:48:19 ginger user.debug 2009.01.26 15:48:19 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
Jan 26 15:48:19 ginger user.debug 2009.01.26 15:48:19 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
Jan 26 15:48:20 ginger user.debug 2009.01.26 15:48:20 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:22 ginger user.debug 2009.01.26 15:48:22 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:24 ginger user.debug 2009.01.26 15:48:24 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:26 ginger user.debug 2009.01.26 15:48:26 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:28 ginger user.debug 2009.01.26 15:48:28 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:30 ginger user.debug 2009.01.26 15:48:30 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:32 ginger user.debug 2009.01.26 15:48:32 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:34 ginger user.debug 2009.01.26 15:48:34 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 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'
Jan 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
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 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 
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 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'}
Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
Jan 26 15:48:35 ginger user.debug 2009.01.26 15:48:35 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
Jan 26 15:48:36 ginger user.debug 2009.01.26 15:48:36 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:38 ginger user.debug 2009.01.26 15:48:38 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:40 ginger user.debug 2009.01.26 15:48:40 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:42 ginger user.debug 2009.01.26 15:48:42 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:44 ginger user.debug 2009.01.26 15:48:44 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:46 ginger user.debug 2009.01.26 15:48:46 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:48 ginger user.debug 2009.01.26 15:48:48 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:50 ginger user.debug 2009.01.26 15:48:50 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:52 ginger user.debug 2009.01.26 15:48:52 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:54 ginger user.debug 2009.01.26 15:48:54 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:55 ginger user.info kernel: [146964.995000] fbcon_event_notify action=9, data=c190fe08
Jan 26 15:48:55 ginger user.info kernel: [146964.995000] jbt6k74 spi2.0: **** jbt6k74 vsync suspend
Jan 26 15:48:56 ginger user.debug 2009.01.26 15:48:56 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:48:56 ginger user.info kernel: [146966.005000] fbcon_event_notify action=12, data=c190fdcc
Jan 26 15:48:56 ginger user.info kernel: [146966.005000] jbt6k74 spi2.0: **** jbt6k74 unblank
Jan 26 15:48:56 ginger user.info kernel: [146966.075000] fbcon_event_notify action=9, data=c190fe08
Jan 26 15:48:56 ginger user.info kernel: [146966.075000] jbt6k74 spi2.0: **** jbt6k74 unblank
Jan 26 15:48:56 ginger user.info kernel: [146966.315000] pcf50633 0-0073: INT1=0x80 INT2=0x00 INT3=0x00 INT4=0x40 INT5=0x00
Jan 26 15:48:56 ginger user.info kernel: [146966.335000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
Jan 26 15:48:56 ginger user.info kernel: [146966.355000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
Jan 26 15:48:58 ginger user.debug 2009.01.26 15:48:58 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:00 ginger user.debug 2009.01.26 15:49:00 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:02 ginger user.debug 2009.01.26 15:49:02 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:04 ginger user.debug 2009.01.26 15:49:04 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:06 ginger user.debug 2009.01.26 15:49:06 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:08 ginger user.debug 2009.01.26 15:49:08 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:10 ginger user.debug 2009.01.26 15:49:10 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:12 ginger user.debug 2009.01.26 15:49:12 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:14 ginger user.debug 2009.01.26 15:49:14 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:16 ginger user.debug 2009.01.26 15:49:16 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:18 ginger user.debug 2009.01.26 15:49:18 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 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'
Jan 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
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 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 
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 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'}
Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
Jan 26 15:49:20 ginger user.debug 2009.01.26 15:49:20 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
Jan 26 15:49:22 ginger user.debug 2009.01.26 15:49:22 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:24 ginger user.debug 2009.01.26 15:49:24 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:26 ginger user.debug 2009.01.26 15:49:26 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:28 ginger user.debug 2009.01.26 15:49:28 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:30 ginger user.debug 2009.01.26 15:49:30 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:32 ginger user.debug 2009.01.26 15:49:32 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:34 ginger user.debug 2009.01.26 15:49:34 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:36 ginger user.debug 2009.01.26 15:49:36 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:38 ginger user.debug 2009.01.26 15:49:38 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:40 ginger user.debug 2009.01.26 15:49:40 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:42 ginger user.debug 2009.01.26 15:49:42 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 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'
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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 
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 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'}
Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
Jan 26 15:49:44 ginger user.debug 2009.01.26 15:49:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
Jan 26 15:49:46 ginger user.debug 2009.01.26 15:49:46 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:48 ginger user.debug 2009.01.26 15:49:48 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:50 ginger user.debug 2009.01.26 15:49:50 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:52 ginger user.debug 2009.01.26 15:49:52 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:54 ginger user.debug 2009.01.26 15:49:54 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:56 ginger user.debug 2009.01.26 15:49:56 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:49:58 ginger user.debug 2009.01.26 15:49:58 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:00 ginger user.debug 2009.01.26 15:50:00 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:02 ginger user.debug 2009.01.26 15:50:02 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:04 ginger user.debug 2009.01.26 15:50:04 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:06 ginger user.debug 2009.01.26 15:50:06 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:08 ginger user.debug 2009.01.26 15:50:08 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:10 ginger user.debug 2009.01.26 15:50:10 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:12 ginger user.debug 2009.01.26 15:50:12 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:14 ginger user.debug 2009.01.26 15:50:14 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:16 ginger user.debug 2009.01.26 15:50:16 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:18 ginger user.debug 2009.01.26 15:50:18 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:20 ginger user.debug 2009.01.26 15:50:20 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:22 ginger user.debug 2009.01.26 15:50:22 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:24 ginger user.debug 2009.01.26 15:50:24 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 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'
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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 
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 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'}
Jan 26 15:50:25 ginger user.debug 2009.01.26 15:50:25 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
Jan 26 15:50:25 ginger user.debug 2009.01.26 15:50:25 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
Jan 26 15:50:26 ginger user.debug 2009.01.26 15:50:26 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:28 ginger user.debug 2009.01.26 15:50:28 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:30 ginger user.debug 2009.01.26 15:50:30 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:32 ginger user.debug 2009.01.26 15:50:32 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:34 ginger user.debug 2009.01.26 15:50:34 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:36 ginger user.debug 2009.01.26 15:50:36 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:38 ginger user.debug 2009.01.26 15:50:38 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:40 ginger user.debug 2009.01.26 15:50:40 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:42 ginger user.debug 2009.01.26 15:50:42 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:44 ginger user.debug 2009.01.26 15:50:44 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:46 ginger user.debug 2009.01.26 15:50:46 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:48 ginger user.debug 2009.01.26 15:50:48 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:50 ginger user.debug 2009.01.26 15:50:50 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:52 ginger user.debug 2009.01.26 15:50:52 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:54 ginger user.debug 2009.01.26 15:50:54 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:56 ginger user.debug 2009.01.26 15:50:56 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:50:58 ginger user.debug 2009.01.26 15:50:58 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:00 ginger user.debug 2009.01.26 15:51:00 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:02 ginger user.debug 2009.01.26 15:51:02 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 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'
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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 
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 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'}
Jan 26 15:51:03 ginger user.debug 2009.01.26 15:51:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
Jan 26 15:51:04 ginger user.debug 2009.01.26 15:51:04 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
Jan 26 15:51:04 ginger user.debug 2009.01.26 15:51:04 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:06 ginger user.debug 2009.01.26 15:51:06 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:08 ginger user.debug 2009.01.26 15:51:08 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:10 ginger user.debug 2009.01.26 15:51:10 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:12 ginger user.debug 2009.01.26 15:51:12 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:14 ginger user.debug 2009.01.26 15:51:14 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:16 ginger user.debug 2009.01.26 15:51:16 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:18 ginger user.debug 2009.01.26 15:51:18 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:20 ginger user.debug 2009.01.26 15:51:20 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:22 ginger user.debug 2009.01.26 15:51:22 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:24 ginger user.debug 2009.01.26 15:51:24 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:26 ginger user.debug 2009.01.26 15:51:26 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:28 ginger user.debug 2009.01.26 15:51:28 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:30 ginger user.debug 2009.01.26 15:51:30 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:32 ginger user.debug 2009.01.26 15:51:32 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:34 ginger user.debug 2009.01.26 15:51:34 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:36 ginger user.debug 2009.01.26 15:51:36 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:38 ginger user.debug 2009.01.26 15:51:38 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:40 ginger user.debug 2009.01.26 15:51:40 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:42 ginger user.debug 2009.01.26 15:51:42 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:44 ginger user.debug 2009.01.26 15:51:44 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:46 ginger user.debug 2009.01.26 15:51:46 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:48 ginger user.debug 2009.01.26 15:51:48 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:50 ginger user.debug 2009.01.26 15:51:50 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:52 ginger user.debug 2009.01.26 15:51:52 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:54 ginger user.debug 2009.01.26 15:51:54 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:56 ginger user.debug 2009.01.26 15:51:56 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:51:58 ginger user.debug 2009.01.26 15:51:58 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:00 ginger user.debug 2009.01.26 15:52:00 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:02 ginger user.debug 2009.01.26 15:52:02 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:04 ginger user.debug 2009.01.26 15:52:04 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:06 ginger user.debug 2009.01.26 15:52:06 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:08 ginger user.debug 2009.01.26 15:52:08 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:10 ginger user.debug 2009.01.26 15:52:10 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:12 ginger user.debug 2009.01.26 15:52:12 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:14 ginger user.debug 2009.01.26 15:52:14 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:16 ginger user.debug 2009.01.26 15:52:16 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:18 ginger user.debug 2009.01.26 15:52:18 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:20 ginger user.debug 2009.01.26 15:52:20 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:22 ginger user.debug 2009.01.26 15:52:22 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:24 ginger user.debug 2009.01.26 15:52:24 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:26 ginger user.debug 2009.01.26 15:52:26 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:28 ginger user.debug 2009.01.26 15:52:28 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:30 ginger user.debug 2009.01.26 15:52:30 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:32 ginger user.debug 2009.01.26 15:52:32 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:34 ginger user.debug 2009.01.26 15:52:34 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:36 ginger user.debug 2009.01.26 15:52:36 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:38 ginger user.debug 2009.01.26 15:52:38 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:40 ginger user.debug 2009.01.26 15:52:40 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:42 ginger user.debug 2009.01.26 15:52:42 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:44 ginger user.debug 2009.01.26 15:52:44 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:46 ginger user.debug 2009.01.26 15:52:46 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:48 ginger user.debug 2009.01.26 15:52:48 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:50 ginger user.debug 2009.01.26 15:52:50 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:52 ginger user.debug 2009.01.26 15:52:52 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:54 ginger user.debug 2009.01.26 15:52:54 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 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'
Jan 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
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 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 
Jan 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'
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 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'}
Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
Jan 26 15:52:56 ginger user.debug 2009.01.26 15:52:56 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
Jan 26 15:52:58 ginger user.debug 2009.01.26 15:52:58 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:00 ginger user.debug 2009.01.26 15:53:00 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:02 ginger user.debug 2009.01.26 15:53:02 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:04 ginger user.debug 2009.01.26 15:53:04 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:06 ginger user.debug 2009.01.26 15:53:06 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:08 ginger user.debug 2009.01.26 15:53:08 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:10 ginger user.debug 2009.01.26 15:53:10 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 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'
Jan 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'
Jan 26 15:53:12 ginger user.debug 2009.01.26 15:53:12 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:14 ginger user.debug 2009.01.26 15:53:14 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:16 ginger user.debug 2009.01.26 15:53:16 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:17 ginger user.info kernel: [147226.500000] fbcon_event_notify action=9, data=c190fe08
Jan 26 15:53:17 ginger user.info kernel: [147226.500000] jbt6k74 spi2.0: **** jbt6k74 vsync suspend
Jan 26 15:53:18 ginger user.debug 2009.01.26 15:53:18 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:18 ginger user.info kernel: [147227.540000] fbcon_event_notify action=12, data=c190fdcc
Jan 26 15:53:18 ginger user.info kernel: [147227.540000] jbt6k74 spi2.0: **** jbt6k74 unblank
Jan 26 15:53:18 ginger user.info kernel: [147227.610000] fbcon_event_notify action=9, data=c190fe08
Jan 26 15:53:18 ginger user.info kernel: [147227.610000] jbt6k74 spi2.0: **** jbt6k74 unblank
Jan 26 15:53:18 ginger user.info kernel: [147227.855000] pcf50633 0-0073: INT1=0x80 INT2=0x00 INT3=0x00 INT4=0x40 INT5=0x00
Jan 26 15:53:18 ginger user.info kernel: [147227.875000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
Jan 26 15:53:18 ginger user.info kernel: [147227.895000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
Jan 26 15:53:20 ginger user.debug 2009.01.26 15:53:20 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:22 ginger user.debug 2009.01.26 15:53:22 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:24 ginger user.debug 2009.01.26 15:53:24 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:26 ginger user.debug 2009.01.26 15:53:26 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 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
Jan 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
Jan 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
Jan 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)
Jan 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
Jan 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'
Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 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'
Jan 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']
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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),
Jan 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'
Jan 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'
Jan 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']
Jan 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), 
Jan 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'
Jan 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'
Jan 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']
Jan 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),
Jan 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'
Jan 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'
Jan 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']
Jan 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), ('
Jan 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'
Jan 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'
Jan 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']
Jan 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>
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 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)
Jan 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'
Jan 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'
Jan 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']
Jan 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)])
Jan 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'
Jan 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'
Jan 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']
Jan 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)])
Jan 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'
Jan 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'
Jan 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']
Jan 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
Jan 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
Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([])
Jan 26 15:53:28 ginger user.debug 2009.01.26 15:53:28 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: nothing in request queue
Jan 26 15:53:29 ginger user.notice lindi: suspending
Jan 26 15:53:30 ginger user.debug 2009.01.26 15:53:30 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:32 ginger user.debug 2009.01.26 15:53:32 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:33 ginger user.info kernel: [147242.865000] PM: Syncing filesystems ... done.
Jan 26 15:53:37 ginger user.warn kernel: [147243.060000] Freezing user space processes ... 
Jan 26 15:53:37 ginger user.debug 2009.01.26 15:53:37 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:37 ginger user.info kernel: (elapsed 0.04 seconds) done.
Jan 26 15:53:37 ginger user.warn kernel: [147243.105000] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
Jan 26 15:53:37 ginger user.warn kernel: [147243.105000] Suspending console(s) (use no_console_suspend to debug)
Jan 26 15:53:37 ginger user.info kernel: [147243.125000] glamo-mci glamo-mci.0: glamo_mci_set_ios: power down.
Jan 26 15:53:37 ginger user.info kernel: [147243.185000] jbt6k74 spi2.0: **** jbt6k74 suspend end
Jan 26 15:53:37 ginger user.info kernel: [147243.185000] fbcon_event_notify action=2, data=c694bdd8
Jan 26 15:53:37 ginger user.info kernel: [147243.185000] glamo3362 glamo3362.0: ***** glamo_power -> 1
Jan 26 15:53:37 ginger user.info kernel: [147243.185000] neo1973-pm-bt neo1973-pm-bt.0: __gta02_pm_bt_toggle_radio 0
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] gta02_udc_command S3C2410_UDC_P_DISABLE
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] suspending dma channel 0
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] suspending dma channel 1
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] suspending dma channel 2
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] suspending dma channel 3
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4000008 value 71ff3d8c
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f400001c value 0000f9ff
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved fb0000c4 value 00000000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved fb0000c8 value 00000000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved fb0000d4 value 00000972
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved fb0000d0 value 01551554
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved fb0000d8 value 00001f7e
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] s3c_pm_enter(3)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] s3c_sleep_save_phys=0x3694be38
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOA: save 007e5fff,00010000,00000000,00000000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOB: save 00155555,00000080,000007ff,00000000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOC: save 55555155,0000a000,0000ffdf,00000000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOD: save 55555555,00002019,0000ffff,00000000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOE: save aaaaa6aa,0000ffc4,0000f7ff,00000000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOF: save 0000aaaa,00000021,000000f7,00000000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOG: save 01aa5279,00006f36,0000ffff,00000000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOH: save 001aa9aa,000002ec,000006f6,00000000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved fb000084 value 00000000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4100000 value 00ffffff
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f410000c value 00017de0
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200000 value 2211d1d0
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200004 value 00000700
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200008 value 00001bc0
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f420000c value 00000700
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200010 value 00001f4c
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200014 value 00000700
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200018 value 00000700
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4100014 value 00000005
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4100004 value 0002a010
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4200024 value 009e03e5
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4100008 value 00058042
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] saved f4100010 value 00000004
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Disabling IRQ 16 (pin 160)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Leaving IRQ 17 (pin 161) enabled
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Disabling IRQ 18 (pin 162)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Disabling IRQ 19 (pin 163)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Leaving IRQ 48 (pin 164) enabled
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Leaving IRQ 49 (pin 165) enabled
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Disabling IRQ 50 (pin 166)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Disabling IRQ 51 (pin 167)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Leaving IRQ 53 (pin 193) enabled
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] Disabling IRQ 56 (pin 196)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] sleep: irq wakeup masks: fffffffd,fffffdcf
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GSTATUS3 0x304c482c
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GSTATUS4 0x00000200
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore fb000084 (restore 00000000, was 00000000)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOA: CON 007e5fff => 007e5fff, DAT 00010000 => 00010000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOB: CON 00155555 => 00155555, DAT 00000080 => 00000080
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOC: CON 55555155 => 55555155, DAT 0000a000 => 0000a000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOD: CON 55555555 => 55555555, DAT 00002019 => 00002019
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOE: CON aaaaa6aa => aaaaa6aa, DAT 0000ffcd => 0000ffc4
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOF: CON 00000a08 => 0000aaaa, DAT 00000022 => 00000021
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOG: CON 01aa5079 => 01aa5279, DAT 00006e36 => 00006f36
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] GPIOH: CON 000000aa => 001aa9aa, DAT 000006ee => 000002ec
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] post sleep: IRQs 0x0000000f, 0x00010000
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] IRQ 17 asserted at resume
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] s3c_pm_enter: post sleep, preparing to return
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] S3C PM Resume (post-restore)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore fb0000c4 (restore 00000000, was 00000000)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore fb0000c8 (restore 00000000, was 00000000)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore fb0000d4 (restore 00000972, was 00000972)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore fb0000d0 (restore 01551554, was 01551554)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore fb0000d8 (restore 00001f7e, was 00001f7e)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore f4000008 (restore 71ff3d8c, was ffffffff)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] restore f400001c (restore 0000f9ff, was 0000ffff)
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] timer tcon=00000000, tcnt a2c1, tcfg 00000200,00000000, usec 00001eb8
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] modem wakeup interrupt
Jan 26 15:53:37 ginger user.info kernel: [147243.245000] s3c2410-wdt: watchdog enabled
Jan 26 15:53:37 ginger user.debug kernel: [147243.245000] gta02_udc_command S3C2410_UDC_P_ENABLE
Jan 26 15:53:37 ginger user.info kernel: [147243.245000] s3c2440-nand s3c2440-nand: Tacls=1, 10ns Twrph0=3 30ns, Twrph1=2 20ns
Jan 26 15:53:37 ginger user.warn kernel: [147243.245000] not changing prescaler of PWM 3, since it's shared with timer4 (clock tick)
Jan 26 15:53:37 ginger user.info kernel: [147243.245000] s3c2440-i2c s3c2440-i2c: slave address 0x10
Jan 26 15:53:37 ginger user.info kernel: [147243.245000] s3c2440-i2c s3c2440-i2c: bus frequency set to 390 KHz
Jan 26 15:53:37 ginger user.err kernel: [147243.245000] ##### callback_all_resume_dependencies(head=c04dbc9c)
Jan 26 15:53:37 ginger user.err kernel: [147243.245000] ##### callback_all_resume_dependencies(head=c04dbd78)
Jan 26 15:53:37 ginger user.err kernel: [147243.245000] ##### callback_all_resume_dependencies(head=c04dbe54)
Jan 26 15:53:37 ginger user.info kernel: [147243.280000] pcf50633 0-0073: INT1=0x80 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
Jan 26 15:53:37 ginger user.info kernel: [147243.295000] neo1973-pm-bt neo1973-pm-bt.0: __gta02_pm_bt_toggle_radio 0
Jan 26 15:53:37 ginger user.info kernel: [147243.305000] neo1973-pm-bt neo1973-pm-bt.0: __gta02_pm_bt_toggle_radio 1
Jan 26 15:53:37 ginger user.info kernel: [147243.335000] glamo3362 glamo3362.0: ***** glamo_power -> 0
Jan 26 15:53:37 ginger user.err kernel: [147243.345000] spin_lock_init
Jan 26 15:53:37 ginger user.info kernel: [147243.350000] fbcon_event_notify action=3, data=c694bdd8
Jan 26 15:53:37 ginger user.info kernel: [147243.420000] jbt6k74 spi2.0: **** jbt6k74 resume start
Jan 26 15:53:37 ginger user.info kernel: [147243.715000] jbt6k74 spi2.0: **** jbt6k74 resume end
Jan 26 15:53:37 ginger user.err kernel: [147243.785000] glamo-mci glamo-mci.0: IGNORING glamo_mci_reset while suspended
Jan 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
Jan 26 15:53:37 ginger user.info kernel: [147243.825000] SD power -> 2700mV
Jan 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
Jan 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
Jan 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
Jan 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
Jan 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
Jan 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
Jan 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
Jan 26 15:53:37 ginger user.info kernel: [147243.850000] soc-audio soc-audio: scheduling resume work
Jan 26 15:53:37 ginger user.warn kernel: [147243.860000] Restarting tasks ... done.
Jan 26 15:53:37 ginger user.info kernel: [147244.000000] soc-audio soc-audio: starting resume work
Jan 26 15:53:38 ginger user.info kernel: [147244.635000] soc-audio soc-audio: resume work completed
Jan 26 15:53:38 ginger user.info kernel: [147244.655000] pcf50633 0-0073: INT1=0x80 INT2=0x00 INT3=0x00 INT4=0x40 INT5=0x00
Jan 26 15:53:38 ginger user.info kernel: [147244.735000] fbcon_event_notify action=1, data=c190fdf0
Jan 26 15:53:38 ginger user.info kernel: [147244.795000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
Jan 26 15:53:38 ginger user.info kernel: [147244.815000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
Jan 26 15:53:39 ginger authpriv.notice sudo:    lindi : TTY=pts/8 ; PWD=/home/lindi ; USER=root ; COMMAND=/sbin/hwclock --hctosys
Jan 26 15:53:40 ginger user.debug 2009.01.26 15:53:40 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:43 ginger user.notice lindi: resuming (reason * EINT01_GSM)
Jan 26 15:53:44 ginger user.debug 2009.01.26 15:53:44 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 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
Jan 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...
Jan 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
Jan 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...
Jan 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
Jan 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...
Jan 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
Jan 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
Jan 26 15:53:46 ginger user.debug 2009.01.26 15:53:46 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 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
Jan 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
Jan 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
Jan 26 15:53:48 ginger user.debug 2009.01.26 15:53:48 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:50 ginger user.debug 2009.01.26 15:53:50 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:52 ginger user.debug 2009.01.26 15:53:52 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=ppp0
Jan 26 15:53:53 ginger user.info 2009.01.26 15:53:53 ogsmd.pdp INFO     shutting down pppd, pid 31375.
Jan 26 15:53:53 ginger local2.info pppd[31375]: Terminating on signal 2
Jan 26 15:53:53 ginger local2.info pppd[31375]: Connect time 9.5 minutes.
Jan 26 15:53:53 ginger local2.info pppd[31375]: Sent 70446 bytes, received 127372 bytes.
Jan 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'
Jan 26 15:53:53 ginger local2.debug pppd[31375]: Script /etc/ppp/ip-down started (pid 31734)
Jan 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)'
Jan 26 15:53:53 ginger user.debug 2009.01.26 15:53:53 ogsmd.pdp DEBUG    got output from ppp: '\n'
Jan 26 15:53:53 ginger local2.debug pppd[31375]: sent [LCP TermReq id=0x3 "User request"]
Jan 26 15:53:53 ginger local2.debug pppd[31375]: rcvd [LCP TermAck id=0x3 "User request"]
Jan 26 15:53:53 ginger local2.notice pppd[31375]: Connection terminated.
Jan 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'
Jan 26 15:53:54 ginger user.debug 2009.01.26 15:53:54 ogsmd.pdp DEBUG    route status. old=, last=ppp0, current=
Jan 26 15:53:54 ginger user.info 2009.01.26 15:53:54 ogsmd.objects INFO     org.freesmartphone.GSM.PDP.ContextStatus: 1 release {}
Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (OK)
Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (BUSY)
Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (DELAYED)
Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (NO ANSWER)
Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (NO CARRIER)
Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (NO DIALTONE)
Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (VOICE)
Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (ERROR)
Jan 26 15:53:54 ginger local2.info chat[31740]: abort on (RINGING)
Jan 26 15:53:54 ginger local2.info chat[31740]: timeout set to 60 seconds
Jan 26 15:53:54 ginger local2.info chat[31740]: send (\k\k\k\d+++ATH^M)
Jan 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)
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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),
Jan 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'
Jan 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'
Jan 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']
Jan 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), (
Jan 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'
Jan 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'
Jan 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']
Jan 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%
Jan 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'
Jan 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'
Jan 26 15:53:55 ginger local2.info chat[31740]: expect (NO CARRIER)
Jan 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
Jan 26 15:53:55 ginger local2.info chat[31740]: <R1.^AAE^\i"h,W,1
Jan 26 15:53:55 ginger local2.info chat[31740]: ^^^HA^^^\\um5^SfNzN}^^OV^V^;l^^UXIN
Jan 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']
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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=
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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)
Jan 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'
Jan 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'
Jan 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']
Jan 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)])
Jan 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'
Jan 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'
Jan 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']
Jan 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)])
Jan 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'
Jan 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'
Jan 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']
Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([])
Jan 26 15:53:55 ginger user.debug 2009.01.26 15:53:55 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: nothing in request queue
Jan 26 15:53:56 ginger user.debug 2009.01.26 15:53:56 ogsmd.pdp DEBUG    route status. old=, last=, current=
Jan 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'
Jan 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'
Jan 26 15:53:57 ginger local2.info chat[31740]: ^Lx^W^MBOOg.^M>5c^Ml~^M
Jan 26 15:53:57 ginger local2.info chat[31740]: NO CARRIER
Jan 26 15:53:57 ginger local2.info chat[31740]:  -- got it
Jan 26 15:53:57 ginger local2.info chat[31740]: send (^M)
Jan 26 15:53:57 ginger local2.info pppd[31375]: Serial link disconnected.
Jan 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'
Jan 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'
Jan 26 15:53:57 ginger user.debug 2009.01.26 15:53:57 ogsmd.pdp DEBUG    got output from ppp: 'Serial link disconnected.\n'
Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    route status. old=, last=, current=
Jan 26 15:53:58 ginger local2.debug pppd[31375]: Script /etc/ppp/ip-down finished (pid 31734), status = 0x0
Jan 26 15:53:58 ginger local2.info pppd[31375]: Exit.
Jan 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'
Jan 26 15:53:58 ginger user.info 2009.01.26 15:53:58 ogsmd.pdp INFO     pppd exited with code 5 and signal 0
Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    recovering file /etc/ppp/ip-up.d/08setupdns
Jan 26 15:53:58 ginger local0.info /usr/sbin/gsm0710muxd[6447]: gsm0710muxd.c:612:pseudo_device_read(): Logical channel 4 for (null) closed
Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    recovering file /etc/ppp/ip-down.d/92removedns
Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    recovering file /var/tmp/ogsmd/gprs-disconnect-chat
Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    recovering file /etc/ppp/pap-secrets
Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    recovering file /etc/ppp/chap-secrets
Jan 26 15:53:58 ginger user.debug 2009.01.26 15:53:58 ogsmd.pdp DEBUG    recovering file /var/tmp/ogsmd/gprs-connect-chat
Jan 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
Jan 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
Jan 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
Jan 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)
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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),
Jan 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'
Jan 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'
Jan 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']
Jan 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), 
Jan 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'
Jan 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'
Jan 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']
Jan 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),
Jan 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'
Jan 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'
Jan 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']
Jan 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), ('
Jan 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'
Jan 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'
Jan 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']
Jan 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>
Jan 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'
Jan 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'
Jan 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']
Jan 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)
Jan 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'
Jan 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'
Jan 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']
Jan 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)])
Jan 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'
Jan 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'
Jan 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']
Jan 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)])
Jan 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'
Jan 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'
Jan 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']
Jan 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
Jan 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
Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([])
Jan 26 15:54:07 ginger user.debug 2009.01.26 15:54:07 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: nothing in request queue
Jan 26 15:54:08 ginger user.notice lindi: suspending
Jan 26 15:54:13 ginger user.info kernel: [147276.895000] PM: Syncing filesystems ... done.
Jan 26 17:56:58 ginger user.warn kernel: [147277.080000] Freezing user space processes ... 
Jan 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'
Jan 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'}
Jan 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'
Jan 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'
Jan 26 17:56:59 ginger user.info kernel: (elapsed 0.03 seconds) done.
Jan 26 17:56:59 ginger user.warn kernel: [147277.115000] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
Jan 26 17:56:59 ginger user.warn kernel: [147277.115000] Suspending console(s) (use no_console_suspend to debug)
Jan 26 17:56:59 ginger user.info kernel: [147277.135000] glamo-mci glamo-mci.0: glamo_mci_set_ios: power down.
Jan 26 17:56:59 ginger user.info kernel: [147277.195000] jbt6k74 spi2.0: **** jbt6k74 suspend end
Jan 26 17:56:59 ginger user.info kernel: [147277.195000] fbcon_event_notify action=2, data=c6be5dd8
Jan 26 17:56:59 ginger user.info kernel: [147277.195000] glamo3362 glamo3362.0: ***** glamo_power -> 1
Jan 26 17:56:59 ginger user.info kernel: [147277.195000] neo1973-pm-bt neo1973-pm-bt.0: __gta02_pm_bt_toggle_radio 0
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] gta02_udc_command S3C2410_UDC_P_DISABLE
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] suspending dma channel 0
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] suspending dma channel 1
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] suspending dma channel 2
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] suspending dma channel 3
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4000008 value 71ff3d8c
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f400001c value 0000f9ff
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved fb0000c4 value 00000000
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved fb0000c8 value 00000000
Jan 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'
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved fb0000d4 value 00000972
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved fb0000d0 value 01551554
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved fb0000d8 value 00001f7e
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] s3c_pm_enter(3)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] s3c_sleep_save_phys=0x36be5e38
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOA: save 007e5fff,00010000,00000000,00000000
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOB: save 00155555,00000080,000007ff,00000000
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOC: save 55555155,0000a000,0000ffdf,00000000
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOD: save 55555555,00002019,0000ffff,00000000
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOE: save aaaaa6aa,0000ffc4,0000f7ff,00000000
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOF: save 0000aaaa,00000021,000000f7,00000000
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOG: save 01aa5279,00006f36,0000ffff,00000000
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOH: save 001aa9aa,000002ec,000006f6,00000000
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved fb000084 value 00000000
Jan 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}
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4100000 value 00ffffff
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f410000c value 00017de0
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200000 value 2211d1d0
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200004 value 00000700
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200008 value 00001bc0
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f420000c value 00000700
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200010 value 00001f4c
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200014 value 00000700
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200018 value 00000700
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4100014 value 00000005
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4100004 value 0002a010
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4200024 value 009e03e5
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4100008 value 00058042
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] saved f4100010 value 00000004
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Disabling IRQ 16 (pin 160)
Jan 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'
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Leaving IRQ 17 (pin 161) enabled
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Disabling IRQ 18 (pin 162)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Disabling IRQ 19 (pin 163)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Leaving IRQ 48 (pin 164) enabled
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Leaving IRQ 49 (pin 165) enabled
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Disabling IRQ 50 (pin 166)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Disabling IRQ 51 (pin 167)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Leaving IRQ 53 (pin 193) enabled
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] Disabling IRQ 56 (pin 196)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] sleep: irq wakeup masks: fffffffd,fffffdcf
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GSTATUS3 0x304c482c
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GSTATUS4 0x00000002
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore fb000084 (restore 00000000, was 00000000)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOA: CON 007e5fff => 007e5fff, DAT 00010000 => 00010000
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOB: CON 00155555 => 00155555, DAT 00000080 => 00000080
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOC: CON 55555155 => 55555155, DAT 0000a000 => 0000a000
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOD: CON 55555555 => 55555555, DAT 00002019 => 00002019
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOE: CON aaaaa6aa => aaaaa6aa, DAT 0000ffcd => 0000ffc4
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOF: CON 00000a08 => 0000aaaa, DAT 00000022 => 00000021
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOG: CON 01aa5079 => 01aa5279, DAT 00006e36 => 00006f36
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] GPIOH: CON 000000aa => 001aa9aa, DAT 000006ee => 000002ec
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] post sleep: IRQs 0x0000000f, 0x00010000
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] IRQ 17 asserted at resume
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] s3c_pm_enter: post sleep, preparing to return
Jan 26 17:56:59 ginger user.warn 2009.01.26 17:56:59 ogsmd    WARNING  CALLCHANNEL: UNHANDLED INTERMEDIATE: +CRING: VOICE
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] S3C PM Resume (post-restore)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore fb0000c4 (restore 00000000, was 00000000)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore fb0000c8 (restore 00000000, was 00000000)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore fb0000d4 (restore 00000972, was 00000972)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore fb0000d0 (restore 01551554, was 01551554)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore fb0000d8 (restore 00001f7e, was 00001f7e)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore f4000008 (restore 71ff3d8c, was ffffffff)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] restore f400001c (restore 0000f9ff, was 0000ffff)
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] timer tcon=00000000, tcnt a2c1, tcfg 00000200,00000000, usec 00001eb8
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] modem wakeup interrupt
Jan 26 17:56:59 ginger user.info kernel: [147277.255000] s3c2410-wdt: watchdog enabled
Jan 26 17:56:59 ginger user.debug kernel: [147277.255000] gta02_udc_command S3C2410_UDC_P_ENABLE
Jan 26 17:56:59 ginger user.info kernel: [147277.255000] s3c2440-nand s3c2440-nand: Tacls=1, 10ns Twrph0=3 30ns, Twrph1=2 20ns
Jan 26 17:56:59 ginger user.warn kernel: [147277.255000] not changing prescaler of PWM 3, since it's shared with timer4 (clock tick)
Jan 26 17:56:59 ginger user.info kernel: [147277.255000] s3c2440-i2c s3c2440-i2c: slave address 0x10
Jan 26 17:56:59 ginger user.info kernel: [147277.255000] s3c2440-i2c s3c2440-i2c: bus frequency set to 390 KHz
Jan 26 17:56:59 ginger user.err kernel: [147277.255000] ##### callback_all_resume_dependencies(head=c04dbc9c)
Jan 26 17:56:59 ginger user.err kernel: [147277.255000] ##### callback_all_resume_dependencies(head=c04dbd78)
Jan 26 17:56:59 ginger user.err kernel: [147277.255000] ##### callback_all_resume_dependencies(head=c04dbe54)
Jan 26 17:56:59 ginger user.info kernel: [147277.290000] pcf50633 0-0073: INT1=0x80 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
Jan 26 17:56:59 ginger user.info kernel: [147277.305000] neo1973-pm-bt neo1973-pm-bt.0: __gta02_pm_bt_toggle_radio 0
Jan 26 17:56:59 ginger user.info kernel: [147277.315000] neo1973-pm-bt neo1973-pm-bt.0: __gta02_pm_bt_toggle_radio 1
Jan 26 17:56:59 ginger user.info kernel: [147277.345000] glamo3362 glamo3362.0: ***** glamo_power -> 0
Jan 26 17:56:59 ginger user.err kernel: [147277.355000] spin_lock_init
Jan 26 17:56:59 ginger user.info kernel: [147277.360000] fbcon_event_notify action=3, data=c6be5dd8
Jan 26 17:56:59 ginger user.info kernel: [147277.430000] jbt6k74 spi2.0: **** jbt6k74 resume start
Jan 26 17:56:59 ginger user.info kernel: [147277.725000] jbt6k74 spi2.0: **** jbt6k74 resume end
Jan 26 17:56:59 ginger user.err kernel: [147277.795000] glamo-mci glamo-mci.0: IGNORING glamo_mci_reset while suspended
Jan 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
Jan 26 17:56:59 ginger user.info kernel: [147277.835000] SD power -> 2700mV
Jan 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
Jan 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
Jan 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
Jan 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
Jan 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
Jan 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
Jan 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
Jan 26 17:56:59 ginger user.info kernel: [147277.860000] soc-audio soc-audio: scheduling resume work
Jan 26 17:56:59 ginger user.warn kernel: [147277.870000] Restarting tasks ... <6>soc-audio soc-audio: starting resume work
Jan 26 17:56:59 ginger user.warn kernel: [147278.020000] done.
Jan 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
Jan 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
Jan 26 17:56:59 ginger user.info kernel: [147278.750000] soc-audio soc-audio: resume work completed
Jan 26 17:56:59 ginger user.info kernel: [147278.775000] pcf50633 0-0073: INT1=0x80 INT2=0x00 INT3=0x00 INT4=0x40 INT5=0x00
Jan 26 17:56:59 ginger user.info kernel: [147278.905000] fbcon_event_notify action=1, data=c190fdf0
Jan 26 17:57:00 ginger user.debug 2009.01.26 17:56:59 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: got 0 bytes from: ''
Jan 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
Jan 26 17:57:00 ginger user.info kernel: [147278.975000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
Jan 26 17:57:00 ginger user.info kernel: [147279.015000] pcf50633 0-0073: INT1=0x00 INT2=0x00 INT3=0x00 INT4=0x00 INT5=0x00
Jan 26 17:57:00 ginger user.debug kernel: [147279.200000] modem wakeup interrupt
Jan 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
Jan 26 17:57:01 ginger user.warn kernel: [147280.110000] lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 26 17:57:01 ginger user.info kernel: mapped channel 10 to 2
Jan 26 17:57:01 ginger authpriv.notice sudo:    lindi : TTY=pts/6 ; PWD=/home/lindi ; USER=root ; COMMAND=/sbin/hwclock --hctosys
Jan 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
Jan 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
Jan 26 17:57:03 ginger user.notice lindi: resuming (reason * EINT01_GSM)
Jan 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
Jan 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
Jan 26 17:57:07 ginger user.warn kernel: [147285.980000] lindi: neo1973_gta02_hifi_hw_free codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 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
Jan 26 17:57:07 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 26 17:57:08 ginger user.info kernel: mapped channel 10 to 2
Jan 26 17:57:09 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1617:open_serial_device(): Opened serial port
Jan 26 17:57:09 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1641:open_serial_device(): Configured serial device
Jan 26 17:57:09 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1763:watchdog(): Watchdog started
Jan 26 17:57:09 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1652:start_muxer(): Configuring modem
Jan 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
Jan 26 17:57:10 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1696:start_muxer(): Starting mux mode
Jan 26 17:57:10 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1699:start_muxer(): Waiting for mux-mode
Jan 26 17:57:11 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1701:start_muxer(): Init control channel
Jan 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
Jan 26 17:57:11 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1421:extract_frames(): Logical channel 1 opened
Jan 26 17:57:11 ginger user.info 2009.01.26 17:57:11 ogsmd    INFO     <MiscChannel via /dev/pts/1>: initializing
Jan 26 17:57:11 ginger user.debug 2009.01.26 17:57:11 ogsmd    DEBUG    (modem init... try #1)
Jan 26 17:57:11 ginger user.debug 2009.01.26 17:57:11 ogsmd    DEBUG    read: AT-Command Interpreter ready
Jan 26 17:57:11 ginger user.info 2009.01.26 17:57:11 ogsmd    INFO     <MiscChannel via /dev/pts/1>: responding OK
Jan 26 17:57:11 ginger user.debug 2009.01.26 17:57:11 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: got 0 bytes from: ''
Jan 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
Jan 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
Jan 26 17:57:13 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1421:extract_frames(): Logical channel 2 opened
Jan 26 17:57:13 ginger user.info 2009.01.26 17:57:13 ogsmd    INFO     <UnsolicitedResponseChannel via /dev/pts/2>: initializing
Jan 26 17:57:13 ginger user.debug 2009.01.26 17:57:13 ogsmd    DEBUG    (modem init... try #1)
Jan 26 17:57:13 ginger user.debug 2009.01.26 17:57:13 ogsmd    DEBUG    read: AT-Command Interpreter ready
Jan 26 17:57:13 ginger user.info 2009.01.26 17:57:13 ogsmd    INFO     <UnsolicitedResponseChannel via /dev/pts/2>: responding OK
Jan 26 17:57:13 ginger user.debug 2009.01.26 17:57:13 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: got 0 bytes from: ''
Jan 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
Jan 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
Jan 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
Jan 26 17:57:14 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 26 17:57:14 ginger user.info kernel: mapped channel 10 to 2
Jan 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
Jan 26 17:57:14 ginger local0.info /usr/sbin/gsm0710muxd[31781]: gsm0710muxd.c:1421:extract_frames(): Logical channel 3 opened
Jan 26 17:57:14 ginger user.info 2009.01.26 17:57:14 ogsmd    INFO     <CallChannel via /dev/pts/3>: initializing
Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    (modem init... try #1)
Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    read: AT-Command Interpreter ready
Jan 26 17:57:14 ginger user.info 2009.01.26 17:57:14 ogsmd    INFO     <CallChannel via /dev/pts/3>: responding OK
Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([])
Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: nothing in request queue
Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    <CallChannel via /dev/pts/3> queue is: deque([])
Jan 26 17:57:14 ginger user.debug 2009.01.26 17:57:14 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: nothing in request queue
Jan 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
Jan 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...
Jan 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
Jan 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...
Jan 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
Jan 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...
Jan 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
Jan 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
Jan 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...
Jan 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
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
Jan 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)])
Jan 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'
Jan 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'
Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: COMPLETED 'ATA' => ['ERROR']
Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <CallChannel via /dev/pts/3> queue is: deque([])
Jan 26 17:57:16 ginger user.debug 2009.01.26 17:57:16 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: nothing in request queue
Jan 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
Jan 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
Jan 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
Jan 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
Jan 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
Jan 26 17:57:20 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 26 17:57:20 ginger user.info kernel: mapped channel 10 to 2
Jan 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...
Jan 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)
Jan 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
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
Jan 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)])
Jan 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'
Jan 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'
Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: COMPLETED 'ATA' => ['ERROR']
Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <CallChannel via /dev/pts/3> queue is: deque([])
Jan 26 17:57:24 ginger user.debug 2009.01.26 17:57:24 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: nothing in request queue
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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),
Jan 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'
Jan 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'
Jan 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']
Jan 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), (
Jan 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'
Jan 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'
Jan 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']
Jan 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%
Jan 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'
Jan 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'
Jan 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']
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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=
Jan 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'
Jan 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'
Jan 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']
Jan 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
Jan 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'
Jan 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'
Jan 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']
Jan 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)
Jan 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'
Jan 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'
Jan 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']
Jan 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)])
Jan 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'
Jan 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'
Jan 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']
Jan 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)])
Jan 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'
Jan 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'
Jan 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']
Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2> queue is: deque([])
Jan 26 17:57:25 ginger user.debug 2009.01.26 17:57:25 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/2>: nothing in request queue
Jan 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
Jan 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
Jan 26 17:57:26 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 26 17:57:26 ginger user.info kernel: mapped channel 10 to 2
Jan 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...
Jan 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
Jan 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'
Jan 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'
Jan 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'
Jan 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']
Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <MiscChannel via /dev/pts/1> queue is: deque([])
Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <MiscChannel via /dev/pts/1>: nothing in request queue
Jan 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)])
Jan 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'
Jan 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'
Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: COMPLETED 'ATA' => ['ERROR']
Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <CallChannel via /dev/pts/3> queue is: deque([])
Jan 26 17:57:29 ginger user.debug 2009.01.26 17:57:29 ogsmd    DEBUG    <CallChannel via /dev/pts/3>: nothing in request queue
Jan 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
Jan 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
Jan 26 17:57:32 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 26 17:57:32 ginger user.info kernel: mapped channel 10 to 2
Jan 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
Jan 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
Jan 26 17:57:38 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 26 17:57:38 ginger user.info kernel: mapped channel 10 to 2
Jan 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
Jan 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
Jan 26 17:57:44 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 26 17:57:44 ginger user.info kernel: mapped channel 10 to 2
Jan 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
Jan 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
Jan 26 17:57:50 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 26 17:57:50 ginger user.info kernel: mapped channel 10 to 2
Jan 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
Jan 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
Jan 26 17:57:56 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 26 17:57:56 ginger user.info kernel: mapped channel 10 to 2
Jan 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
Jan 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
Jan 26 17:58:03 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 26 17:58:03 ginger user.info kernel: mapped channel 10 to 2
Jan 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
Jan 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
Jan 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
Jan 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
Jan 26 17:58:09 ginger user.info kernel: lindi: neo1973_gta02_hifi_hw_params codec_dai c0a1c278 set_pll c02e61d0
Jan 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
Jan 26 17:58:09 ginger user.info kernel: mapped channel 10 to 2
Jan 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
Jan 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
Jan 26 17:58:43 ginger authpriv.notice sudo:    lindi : TTY=pts/9 ; PWD=/home/lindi ; USER=root ; COMMAND=/bin/busybox logread