rendered paste body2009.05.13 15:39:54.869 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:39:54.933 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:39:54.963 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:39:55.251 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:39:57.19 odeviced.kernel26 INFO power status now full
2009.05.13 15:39:57.48 oeventsd.fso_triggers INFO Receive PowerStatus, status = full
2009.05.13 15:39:57.61 oeventsd.fso_triggers INFO Receive PowerStatus, status = full
2009.05.13 15:39:57.96 oeventsd.action INFO call dbus method /org/freesmartphone/Usage RequestResource(('CPU',))
2009.05.13 15:39:57.124 oeventsd.fso_triggers INFO Receive PowerStatus, status = full
2009.05.13 15:39:57.146 oeventsd.fso_triggers INFO Receive PowerStatus, status = full
2009.05.13 15:39:57.159 oeventsd INFO turn led gta02_power_blue on
2009.05.13 15:39:57.193 oeventsd.fso_triggers INFO Receive PowerStatus, status = full
2009.05.13 15:39:57.402 frameworkd.resource INFO setting resource status for CPU from disabled to enabling
2009.05.13 15:39:57.411 frameworkd.resource INFO setting resource status for CPU from enabling to enabled
2009.05.13 15:39:57.438 ousaged INFO Enabled CPU in 0.2 seconds
2009.05.13 15:39:57.484 oeventsd.action INFO method RequestResource responded: ()
2009.05.13 15:39:59.683 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
2009.05.13 15:39:59.702 ogsmd DEBUG (<MiscChannel via /dev/pts/1>: last communication with modem was 6 seconds ago. Sending EOF to wakeup)
2009.05.13 15:39:59.918 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: sending 26 bytes: 'AT+CPBS="SM";+CPBR=1,250\r\n'
2009.05.13 15:39:59.952 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 834
2009.05.13 15:39:59.961 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 59 bytes: '\r\n+CPBR:....
2009.05.13 15:40:00.63 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 835
2009.05.13 15:40:00.83 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 212 bytes: '+CPBR:....
2009.05.13 15:40:00.119 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 836
2009.05.13 15:40:00.138 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 320 bytes: '+CPBR:....
2009.05.13 15:40:00.284 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 837
2009.05.13 15:40:00.303 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 1088 bytes: '+CPBR:....
2009.05.13 15:40:00.443 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 838
2009.05.13 15:40:00.458 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 1088 bytes: '+CPBR:....
2009.05.13 15:40:00.577 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 839
2009.05.13 15:40:00.586 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 684 bytes: '+CPBR:....
2009.05.13 15:40:00.648 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CPBS="SM";+CPBR=1,250' => ['+CPBR:....
2009.05.13 15:40:01.28 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
2009.05.13 15:40:03.417 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:40:03.427 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 15, 99, 1\r\n'
2009.05.13 15:40:03.439 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 79
2009.05.13 15:40:05.2 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:40:05.22 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:40:05.38 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:40:05.103 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:40:05.183 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:40:05.281 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:40:05.721 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:40:06.3 odeviced.kernel26 INFO power status now discharging
2009.05.13 15:40:06.53 oeventsd.fso_triggers INFO Receive PowerStatus, status = discharging
2009.05.13 15:40:06.69 oeventsd.fso_triggers INFO Receive PowerStatus, status = discharging
2009.05.13 15:40:06.109 oeventsd.action INFO call dbus method /org/freesmartphone/Usage ReleaseResource(('CPU',))
2009.05.13 15:40:06.140 oeventsd.fso_triggers INFO Receive PowerStatus, status = discharging
2009.05.13 15:40:06.173 oeventsd.fso_triggers INFO Receive PowerStatus, status = discharging
2009.05.13 15:40:06.194 oeventsd INFO turn led gta02_power_blue off
2009.05.13 15:40:06.209 oeventsd.fso_triggers INFO Receive PowerStatus, status = discharging
2009.05.13 15:40:06.776 frameworkd.resource INFO setting resource status for CPU from enabled to disabling
2009.05.13 15:40:06.801 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:40:06.816 frameworkd.resource INFO setting resource status for CPU from disabling to disabled
2009.05.13 15:40:06.833 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:40:06.845 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:40:06.991 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:40:07.13 ousaged INFO Disabled CPU in 0.6 seconds
2009.05.13 15:40:07.46 oeventsd.action INFO method ReleaseResource responded: ()
2009.05.13 15:40:08.354 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:40:08.364 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 18, 99, 2\r\n'
2009.05.13 15:40:08.377 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 84
2009.05.13 15:40:11.525 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
2009.05.13 15:40:11.535 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: sending 11 bytes: 'AT+CMGL=4\r\n'
2009.05.13 15:40:12.178 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 850
2009.05.13 15:40:12.188 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 18 bytes: +CMGL:....
2009.05.13 15:40:12.207 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 851
2009.05.13 15:40:12.227 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 166 bytes: '
2009.05.13 15:40:12.467 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 852
2009.05.13 15:40:12.482 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 81 bytes: +CMGL:....
2009.05.13 15:40:12.507 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 853
2009.05.13 15:40:12.517 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 230 bytes: '
2009.05.13 15:40:12.833 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 144 bytes: '
2009.05.13 15:40:13.47 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 856
2009.05.13 15:40:13.56 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 78 bytes: +CMGL:....
2009.05.13 15:40:13.298 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:40:13.307 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 14, 99, 1\r\n'
2009.05.13 15:40:13.318 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 77
2009.05.13 15:40:13.358 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 857
2009.05.13 15:40:13.374 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 319 bytes: +CMGL:....
2009.05.13 15:40:13.618 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 858
2009.05.13 15:40:13.634 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 186 bytes: +CMGL:....
2009.05.13 15:40:13.658 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 859
2009.05.13 15:40:13.667 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 82 bytes: '
2009.05.13 15:40:13.904 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 860
2009.05.13 15:40:13.927 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 179 bytes: +CMGL:....
2009.05.13 15:40:13.953 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 861
2009.05.13 15:40:13.962 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 150 bytes: '
2009.05.13 15:40:14.60 odeviced.input INFO name POWER pressed 0
2009.05.13 15:40:14.198 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 862
2009.05.13 15:40:14.209 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 50 bytes: +CMGL:....
2009.05.13 15:40:14.246 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 865
2009.05.13 15:40:14.257 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 134 bytes: '
2009.05.13 15:40:14.311 odeviced.input INFO name POWER released 0
2009.05.13 15:40:14.483 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 866
2009.05.13 15:40:14.515 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 260 bytes: +CMGL:....
2009.05.13 15:40:14.567 ousaged INFO suspending all resources...
2009.05.13 15:40:14.814 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 868
2009.05.13 15:40:14.823 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 139 bytes: +CMGL:....
2009.05.13 15:40:14.912 frameworkd.resource INFO setting resource status for GSM from enabled to suspending
2009.05.13 15:40:14.924 ogsmd.modem.abstract DEBUG prepareForSuspend ACK from channel <MiscChannel via /dev/pts/1> received
2009.05.13 15:40:14.932 ogsmd DEBUG TI Calypso specific resume handling... killing reenable-unsolicited-timer.
2009.05.13 15:40:14.962 ogsmd.modem.abstract DEBUG prepareForSuspend ACK from channel <CallChannel via /dev/pts/3> received
2009.05.13 15:40:14.970 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:14.980 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZU=0\r\n'
2009.05.13 15:40:15.3 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 881
2009.05.13 15:40:15.12 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:15.23 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZU=0' => ['OK']
2009.05.13 15:40:15.32 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:15.42 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZR=0\r\n'
2009.05.13 15:40:15.64 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 876
2009.05.13 15:40:15.79 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 244 bytes: +CMGL:....
2009.05.13 15:40:15.112 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 885
2009.05.13 15:40:15.121 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 146 bytes: '
2009.05.13 15:40:15.142 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 884
2009.05.13 15:40:15.151 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:15.163 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZR=0' => ['OK']
2009.05.13 15:40:15.171 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:15.181 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CREG=0\r\n'
2009.05.13 15:40:15.207 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 889
2009.05.13 15:40:15.216 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:15.227 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CREG=0' => ['OK']
2009.05.13 15:40:15.236 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:15.247 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT+CGREG=0\r\n'
2009.05.13 15:40:15.272 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 892
2009.05.13 15:40:15.281 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:15.292 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGREG=0' => ['OK']
2009.05.13 15:40:15.301 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:15.311 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 15 bytes: 'AT+CGEREP=0,0\r\n'
2009.05.13 15:40:15.337 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 895
2009.05.13 15:40:15.346 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:15.367 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGEREP=0,0' => ['OK']
2009.05.13 15:40:15.386 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 886
2009.05.13 15:40:15.401 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 390 bytes: +CMGL:....
2009.05.13 15:40:15.435 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:15.446 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 19 bytes: 'AT+CNMI=2,1,0,0,0\r\n'
2009.05.13 15:40:15.472 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 899
2009.05.13 15:40:15.481 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 19 bytes: '\r\n+CMS ERROR: 500\r\n'
2009.05.13 15:40:15.493 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CNMI=2,1,0,0,0' => ['+CMS ERROR: 500']
2009.05.13 15:40:15.503 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:15.513 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 10 bytes: 'AT%CSQ=0\r\n'
2009.05.13 15:40:15.538 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 902
2009.05.13 15:40:15.547 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 18 bytes: '\r\n%CSQ: 0 \r\n\r\nOK\r\n'
2009.05.13 15:40:15.560 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CSQ=0' => ['%CSQ: 0 ', 'OK']
2009.05.13 15:40:15.569 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:15.579 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CPRI=0\r\n'
2009.05.13 15:40:15.602 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 905
2009.05.13 15:40:15.611 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:15.622 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CPRI=0' => ['OK']
2009.05.13 15:40:15.631 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:15.644 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CBHZ=0\r\n'
2009.05.13 15:40:15.659 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 898
2009.05.13 15:40:15.674 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 252 bytes: +CMGL:....
2009.05.13 15:40:15.703 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 909
2009.05.13 15:40:15.712 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 92 bytes: '
2009.05.13 15:40:15.728 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 908
2009.05.13 15:40:15.737 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:15.748 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CBHZ=0' => ['OK']
2009.05.13 15:40:15.756 ogsmd.modem.abstract DEBUG prepareForSuspend ACK from channel <UnsolicitedResponseChannel via /dev/pts/2> received
2009.05.13 15:40:15.763 ogsmd DEBUG (writing '1' to '/sys/bus/platform/devices/neo1973-pm-gsm.0/flowcontrolled')
2009.05.13 15:40:15.772 frameworkd.resource INFO setting resource status for GSM from suspending to suspended
2009.05.13 15:40:15.786 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:15.918 ousaged INFO ...completed
2009.05.13 15:40:16.61 ousaged INFO triggering kernel suspend
2009.05.13 15:40:17.520 ousaged INFO kernel has resumed - reason = PowerKey
2009.05.13 15:40:17.527 ousaged INFO resuming resources...
2009.05.13 15:40:17.577 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:40:17.622 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:40:17.676 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:40:17.944 frameworkd.resource INFO setting resource status for GSM from suspended to resuming
2009.05.13 15:40:17.952 ogsmd DEBUG (writing '0' to '/sys/bus/platform/devices/neo1973-pm-gsm.0/flowcontrolled')
2009.05.13 15:40:17.966 ogsmd.modem.abstract DEBUG recoverFromSuspend ACK from channel <MiscChannel via /dev/pts/1> received
2009.05.13 15:40:17.974 ogsmd DEBUG TI Calypso specific resume handling... sending reinit in 5 seconds...
2009.05.13 15:40:17.982 ogsmd.modem.abstract DEBUG recoverFromSuspend ACK from channel <UnsolicitedResponseChannel via /dev/pts/2> received
2009.05.13 15:40:17.990 ogsmd.modem.abstract DEBUG recoverFromSuspend ACK from channel <CallChannel via /dev/pts/3> received
2009.05.13 15:40:18.224 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 910
2009.05.13 15:40:18.233 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 48 bytes: +CMGL:....
2009.05.13 15:40:18.732 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 930
2009.05.13 15:40:18.743 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 64 bytes: '
2009.05.13 15:40:18.770 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 931
2009.05.13 15:40:18.784 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 318 bytes: +CMGL:....
2009.05.13 15:40:18.869 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 932
2009.05.13 15:40:18.883 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 704 bytes: +CMGL:....
2009.05.13 15:40:18.971 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 933
2009.05.13 15:40:18.984 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 704 bytes: +CMGL:....
2009.05.13 15:40:19.71 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 934
2009.05.13 15:40:19.89 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 704 bytes: +CMGL:....
2009.05.13 15:40:19.169 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 935
2009.05.13 15:40:19.189 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 711 bytes: +CMGL:....
2009.05.13 15:40:19.256 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 936
2009.05.13 15:40:19.265 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 150 bytes: +CMGL:....
2009.05.13 15:40:19.291 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CMGL=4' => ['+CMGL:....
2009.05.13 15:40:20.184 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
2009.05.13 15:40:20.194 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: sending 8 bytes: 'AT+CSQ\r\n'
2009.05.13 15:40:20.228 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 939
2009.05.13 15:40:20.237 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 16 bytes: '\r\n+CSQ: 12,99 \r\n'
2009.05.13 15:40:20.258 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 940
2009.05.13 15:40:20.267 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:20.279 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CSQ' => ['+CSQ: 12,99 ', 'OK']
2009.05.13 15:40:20.302 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
2009.05.13 15:40:20.322 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: sending 10 bytes: 'AT+CREG?\r\n'
2009.05.13 15:40:20.347 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 943
2009.05.13 15:40:20.357 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 20 bytes: '\r\n+CREG: 0,1\r\n\r\nOK\r\n'
2009.05.13 15:40:20.369 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG?' => ['+CREG: 0,1', 'OK']
2009.05.13 15:40:20.423 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
2009.05.13 15:40:20.452 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: sending 26 bytes: 'AT+CREG=2;+CREG?;+CREG=0\r\n'
2009.05.13 15:40:20.482 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 946
2009.05.13 15:40:20.492 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 28 bytes: '\r\n+CREG: 2,1,"0020","DACC"\r\n'
2009.05.13 15:40:20.514 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 947
2009.05.13 15:40:20.523 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:20.534 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: COMPLETED 'AT+CREG=2;+CREG?;+CREG=0' => ['+CREG: 2,1,"0020","DACC"', 'OK']
2009.05.13 15:40:20.571 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
2009.05.13 15:40:20.581 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: sending 37 bytes: 'AT+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
2009.05.13 15:40:20.632 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 950
2009.05.13 15:40:20.643 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 23 bytes: '\r\n+COPS: 0,0,"E-Plus"\r\n'
2009.05.13 15:40:20.665 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToRead: watch timeout = 951
2009.05.13 15:40:20.674 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: got 28 bytes: '\r\n+COPS: 0,2,"26203"\r\n\r\nOK\r\n'
2009.05.13 15:40:20.697 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: COMPLETED 'AT+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+COPS: 0,0,"E-Plus"', '+COPS: 0,2,"26203"', 'OK']
2009.05.13 15:40:20.727 frameworkd.resource INFO setting resource status for GSM from resuming to enabled
2009.05.13 15:40:20.736 ogsmd.device INFO org.freesmartphone.GSM.Network.Status: {'strength': 72, 'cid': u'DACC', 'registration': 'home', 'lac': u'0020', 'act': 'GSM', 'code': u'26203', 'mode': 'automatic', 'provider': u'E-Plus'}
2009.05.13 15:40:20.788 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
2009.05.13 15:40:21.119 otimed INFO GSM: timezone 'Europe/Berlin'
2009.05.13 15:40:21.209 ousaged INFO ...completed.
2009.05.13 15:40:27.939 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:40:28.224 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:28.283 ogsmd DEBUG (<UnsolicitedResponseChannel via /dev/pts/2>: last communication with modem was 7 seconds ago. Sending EOF to wakeup)
2009.05.13 15:40:28.498 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZU=1\r\n'
2009.05.13 15:40:28.511 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:40:28.538 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:40:29.156 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:40:29.225 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 962
2009.05.13 15:40:29.271 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:29.283 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZU=1' => ['OK']
2009.05.13 15:40:29.344 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:29.437 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZR=1\r\n'
2009.05.13 15:40:29.582 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 965
2009.05.13 15:40:29.594 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:29.606 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZR=1' => ['OK']
2009.05.13 15:40:29.615 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:29.628 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CREG=2\r\n'
2009.05.13 15:40:29.652 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 968
2009.05.13 15:40:29.665 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:29.676 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CREG=2' => ['OK']
2009.05.13 15:40:29.686 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:29.699 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT+CGREG=2\r\n'
2009.05.13 15:40:29.723 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 971
2009.05.13 15:40:29.738 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:29.749 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGREG=2' => ['OK']
2009.05.13 15:40:29.758 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:29.769 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 15 bytes: 'AT+CGEREP=2,1\r\n'
2009.05.13 15:40:29.792 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 974
2009.05.13 15:40:29.802 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:29.813 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGEREP=2,1' => ['OK']
2009.05.13 15:40:29.822 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:29.832 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CSMS=1\r\n'
2009.05.13 15:40:29.857 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 977
2009.05.13 15:40:29.867 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 22 bytes: '\r\n+CSMS: 1,1,1\r\n\r\nOK\r\n'
2009.05.13 15:40:29.879 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CSMS=1' => ['+CSMS: 1,1,1', 'OK']
2009.05.13 15:40:29.889 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:29.899 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 19 bytes: 'AT+CNMI=2,1,2,1,1\r\n'
2009.05.13 15:40:29.922 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 980
2009.05.13 15:40:29.931 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:29.943 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CNMI=2,1,2,1,1' => ['OK']
2009.05.13 15:40:29.952 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:29.962 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 10 bytes: 'AT%CSQ=1\r\n'
2009.05.13 15:40:29.987 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 983
2009.05.13 15:40:29.997 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 18 bytes: '\r\n%CSQ: 1 \r\n\r\nOK\r\n'
2009.05.13 15:40:30.11 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CSQ=1' => ['%CSQ: 1 ', 'OK']
2009.05.13 15:40:30.20 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:30.30 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CPRI=1\r\n'
2009.05.13 15:40:30.52 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 986
2009.05.13 15:40:30.61 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:30.72 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CPRI=1' => ['OK']
2009.05.13 15:40:30.82 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:30.92 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CNIV=1\r\n'
2009.05.13 15:40:30.117 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 989
2009.05.13 15:40:30.126 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:30.138 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CNIV=1' => ['OK']
2009.05.13 15:40:30.146 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:30.157 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CBHZ=0\r\n'
2009.05.13 15:40:30.182 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 992
2009.05.13 15:40:30.191 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:40:30.202 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CBHZ=0' => ['OK']
2009.05.13 15:40:30.212 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:40:38.7 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:40:38.27 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:40:38.39 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:40:39.569 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:40:39.613 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:40:39.663 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:40:39.961 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:40:40.864 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:40:40.875 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 11, 99, 1\r\n'
2009.05.13 15:40:40.889 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 70
2009.05.13 15:40:41.196 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: _readyToSend: watch timeout = None
2009.05.13 15:40:41.206 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: sending 11 bytes: 'AT+CMGL=4\r\n'
2009.05.13 15:40:45.812 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:40:45.824 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 19 bytes: '\r\n%CSQ: 8, 99, 0\r\n'
2009.05.13 15:40:45.835 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 61
2009.05.13 15:40:47.642 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:40:47.651 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes: '\r\n+CREG: 1,"0020","DAB8"\r\n'
2009.05.13 15:40:50.2 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:40:50.23 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:40:50.35 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:40:52.359 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:40:52.368 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes: '\r\n+CREG: 1,"0020","DAC2"\r\n'
2009.05.13 15:40:56.538 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:40:56.611 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:40:56.644 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:40:57.89 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:41:05.543 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:41:05.554 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes: '\r\n+CREG: 1,"0020","00D4"\r\n'
2009.05.13 15:41:07.3 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:41:07.25 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:41:07.213 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:41:07.295 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:41:07.311 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:41:07.320 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 18, 99, 2\r\n'
2009.05.13 15:41:07.332 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 84
2009.05.13 15:41:07.436 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:41:07.502 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:41:07.652 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:41:17.7 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:41:17.28 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:41:17.40 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:41:18.933 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:41:18.955 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:41:19.89 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:41:19.851 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:41:29.2 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:41:29.28 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:41:29.158 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:41:29.199 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:41:29.249 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:41:29.332 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:41:29.534 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:41:39.8 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:41:39.31 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:41:39.119 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:41:39.164 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:41:39.181 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:41:39.193 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:41:39.514 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:41:49.7 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:41:49.28 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:41:49.40 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:41:53.552 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:41:53.563 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes: '\r\n+CREG: 1,"0020","DAC2"\r\n'
2009.05.13 15:41:56.662 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:41:56.672 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 14, 99, 1\r\n'
2009.05.13 15:41:56.684 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 77
2009.05.13 15:42:09.7 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle_dim
2009.05.13 15:42:09.26 oeventsd.fso_triggers INFO Receive IdleState, status = idle_dim
2009.05.13 15:42:09.38 oeventsd.fso_triggers INFO Receive IdleState, status = idle_dim
2009.05.13 15:42:09.56 oeventsd INFO CommandAction xset -display localhost:0 s blank
2009.05.13 15:42:09.295 oeventsd INFO CommandAction xset -display localhost:0 s activate
2009.05.13 15:42:10.66 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:42:10.97 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:42:10.114 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:42:10.335 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:42:20.7 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:42:20.28 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:42:20.40 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:42:20.144 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:42:20.216 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:42:20.239 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:42:20.758 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:42:30.9 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:42:30.31 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:42:30.96 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:42:30.117 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:42:30.255 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:42:30.419 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:42:30.721 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:42:40.7 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:42:40.29 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:42:40.48 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:42:41.408 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:42:41.434 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:42:41.589 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:42:41.881 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:42:51.43 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:42:51.53 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 19 bytes: '\r\n%CSQ: 9, 99, 0\r\n'
2009.05.13 15:42:51.67 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 64
2009.05.13 15:42:52.2 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:42:52.27 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:42:52.114 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:42:52.148 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:42:52.168 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:42:52.182 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:42:52.643 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:42:53.579 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:42:53.590 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes: '\r\n+CREG: 1,"0020","00CA"\r\n'
2009.05.13 15:42:55.982 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:42:55.992 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 18, 99, 2\r\n'
2009.05.13 15:42:56.5 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 84
2009.05.13 15:43:02.7 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:43:02.27 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:43:02.39 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:43:03.188 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:43:03.209 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:43:03.313 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:43:03.697 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:43:10.814 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:43:10.824 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 16, 99, 1\r\n'
2009.05.13 15:43:10.837 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 81
2009.05.13 15:43:13.7 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:43:13.27 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:43:13.39 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:43:17.352 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:43:17.361 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes: '\r\n+CREG: 1,"0020","00D4"\r\n'
2009.05.13 15:43:25.827 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:43:25.836 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes: '\r\n+CREG: 1,"0020","DAC2"\r\n'
2009.05.13 15:43:30.234 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:43:30.289 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:43:30.319 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:43:30.706 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:43:41.3 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:43:41.24 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:43:41.36 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:43:43.768 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:43:43.778 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 19 bytes: '\r\n%CSQ: 7, 99, 0\r\n'
2009.05.13 15:43:43.791 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 57
2009.05.13 15:43:46.319 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:43:46.387 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:43:46.424 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:43:46.686 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:43:50.318 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:43:50.328 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes: '\r\n+CREG: 1,"0020","00CA"\r\n'
2009.05.13 15:43:52.8 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:43:52.17 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 19, 99, 2\r\n'
2009.05.13 15:43:52.29 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 86
2009.05.13 15:43:57.1 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:43:57.22 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:43:57.34 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:44:01.892 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:44:01.902 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 29, 99, 3\r\n'
2009.05.13 15:44:01.913 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 98
2009.05.13 15:44:06.842 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:44:06.851 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 31, 99, 4\r\n'
2009.05.13 15:44:06.863 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 100
2009.05.13 15:44:15.369 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:44:15.432 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:44:15.494 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:44:15.872 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:44:16.722 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:44:16.751 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 28, 99, 3\r\n'
2009.05.13 15:44:16.767 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 97
2009.05.13 15:44:21.672 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:44:21.682 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 23, 99, 2\r\n'
2009.05.13 15:44:21.693 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 91
2009.05.13 15:44:23.277 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:44:23.286 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes: '\r\n+CREG: 1,"0020","DAC2"\r\n'
2009.05.13 15:44:24.962 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:44:24.972 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 31, 99, 3\r\n'
2009.05.13 15:44:24.983 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 100
2009.05.13 15:44:26.2 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:44:26.22 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:44:26.34 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:44:26.364 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 15:44:26.483 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:44:26.581 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 15:44:27.28 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 15:44:37.2 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 15:44:37.23 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:44:37.35 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 15:44:44.738 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:44:44.748 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 24, 99, 2\r\n'
2009.05.13 15:44:44.760 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 93
2009.05.13 15:44:51.513 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:44:51.522 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes: '\r\n+CREG: 1,"0020","DAB8"\r\n'
2009.05.13 15:44:54.622 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:44:54.631 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 26, 99, 3\r\n'
2009.05.13 15:44:54.642 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 95
2009.05.13 15:44:57.15 odeviced.kernel26 INFO capacity now 99
2009.05.13 15:44:57.31 odeviced.kernel26 INFO power status now full
2009.05.13 15:44:57.44 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle_dim
2009.05.13 15:44:57.64 oeventsd.fso_triggers INFO Receive PowerStatus, status = full
2009.05.13 15:44:57.76 oeventsd.fso_triggers INFO Receive PowerStatus, status = full
2009.05.13 15:44:57.91 oeventsd.action INFO call dbus method /org/freesmartphone/Usage RequestResource(('CPU',))
2009.05.13 15:44:57.107 oeventsd.fso_triggers INFO Receive PowerStatus, status = full
2009.05.13 15:44:57.119 oeventsd.fso_triggers INFO Receive PowerStatus, status = full
2009.05.13 15:44:57.131 oeventsd INFO turn led gta02_power_blue on
2009.05.13 15:44:57.146 oeventsd.fso_triggers INFO Receive PowerStatus, status = full
2009.05.13 15:44:57.161 oeventsd.fso_triggers INFO Receive IdleState, status = idle_dim
2009.05.13 15:44:57.173 oeventsd.fso_triggers INFO Receive IdleState, status = idle_dim
2009.05.13 15:44:57.184 oeventsd INFO CommandAction xset -display localhost:0 s blank
2009.05.13 15:44:57.314 oeventsd INFO CommandAction xset -display localhost:0 s activate
2009.05.13 15:44:57.647 frameworkd.resource INFO setting resource status for CPU from disabled to enabling
2009.05.13 15:44:57.657 frameworkd.resource INFO setting resource status for CPU from enabling to enabled
2009.05.13 15:44:57.674 ousaged INFO Enabled CPU in 0.1 seconds
2009.05.13 15:44:57.701 oeventsd.action INFO method RequestResource responded: ()
2009.05.13 15:45:09.7 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle_prelock
2009.05.13 15:45:09.27 oeventsd.fso_triggers INFO Receive IdleState, status = idle_prelock
2009.05.13 15:45:09.40 oeventsd.fso_triggers INFO Receive IdleState, status = idle_prelock
2009.05.13 15:45:11.7 odeviced.idlenotifier INFO odeviced.idlenotifier state change to lock
2009.05.13 15:45:11.27 oeventsd.fso_triggers INFO Receive IdleState, status = lock
2009.05.13 15:45:11.39 oeventsd.fso_triggers INFO Receive IdleState, status = lock
2009.05.13 15:45:14.394 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:45:14.404 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 23, 99, 2\r\n'
2009.05.13 15:45:14.417 ogsmd.device INFO org.freesmartphone.GSM.Network.SignalStrength: 91
2009.05.13 15:45:21.420 odeviced.kernel26 INFO power status now discharging
2009.05.13 15:45:21.438 oeventsd.fso_triggers INFO Receive PowerStatus, status = discharging
2009.05.13 15:45:21.452 oeventsd.fso_triggers INFO Receive PowerStatus, status = discharging
2009.05.13 15:45:21.465 oeventsd.action INFO call dbus method /org/freesmartphone/Usage ReleaseResource(('CPU',))
2009.05.13 15:45:21.481 oeventsd.fso_triggers INFO Receive PowerStatus, status = discharging
2009.05.13 15:45:21.493 oeventsd.fso_triggers INFO Receive PowerStatus, status = discharging
2009.05.13 15:45:21.506 oeventsd INFO turn led gta02_power_blue off
2009.05.13 15:45:21.521 oeventsd.fso_triggers INFO Receive PowerStatus, status = discharging
2009.05.13 15:45:21.637 frameworkd.resource INFO setting resource status for CPU from enabled to disabling
2009.05.13 15:45:21.647 odeviced.idlenotifier INFO odeviced.idlenotifier state change to lock
2009.05.13 15:45:21.662 frameworkd.resource INFO setting resource status for CPU from disabling to disabled
2009.05.13 15:45:21.679 oeventsd.fso_triggers INFO Receive IdleState, status = lock
2009.05.13 15:45:21.691 oeventsd.fso_triggers INFO Receive IdleState, status = lock
2009.05.13 15:45:21.705 ousaged INFO Disabled CPU in 0.1 seconds
2009.05.13 15:45:21.731 oeventsd.action INFO method ReleaseResource responded: ()
2009.05.13 15:45:25.452 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 15:45:25.461 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 26 bytes: '\r\n+CREG: 1,"0020","0098"\r\n'
2009.05.13 15:45:42.5 ogsmd.channel DEBUG <MiscChannel via /dev/pts/1>: TIMEOUT 'AT+CMGL=4' => ???
2009.05.13 15:45:42.19 odeviced.idlenotifier INFO odeviced.idlenotifier state change to suspend
2009.05.13 15:45:42.44 oeventsd.fso_triggers INFO Receive IdleState, status = suspend
2009.05.13 15:45:42.56 oeventsd.fso_triggers INFO Receive IdleState, status = suspend
2009.05.13 15:45:42.102 ousaged INFO suspending all resources...
2009.05.13 15:45:42.398 frameworkd.resource INFO setting resource status for GSM from enabled to suspending
2009.05.13 15:45:42.409 ogsmd.modem.abstract DEBUG prepareForSuspend ACK from channel <MiscChannel via /dev/pts/1> received
2009.05.13 15:45:42.417 ogsmd DEBUG TI Calypso specific resume handling... killing reenable-unsolicited-timer.
2009.05.13 15:45:42.446 ogsmd.modem.abstract DEBUG prepareForSuspend ACK from channel <CallChannel via /dev/pts/3> received
2009.05.13 15:45:42.455 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:45:42.462 ogsmd DEBUG (<UnsolicitedResponseChannel via /dev/pts/2>: last communication with modem was 17 seconds ago. Sending EOF to wakeup)
2009.05.13 15:45:42.678 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZU=0\r\n'
2009.05.13 15:45:42.702 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1053
2009.05.13 15:45:42.711 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:45:42.730 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZU=0' => ['OK']
2009.05.13 15:45:42.743 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:45:42.754 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZR=0\r\n'
2009.05.13 15:45:42.777 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1056
2009.05.13 15:45:42.786 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:45:42.797 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZR=0' => ['OK']
2009.05.13 15:45:42.806 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:45:42.816 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CREG=0\r\n'
2009.05.13 15:45:42.842 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1059
2009.05.13 15:45:42.851 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:45:42.862 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CREG=0' => ['OK']
2009.05.13 15:45:42.871 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:45:42.881 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT+CGREG=0\r\n'
2009.05.13 15:45:42.907 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1062
2009.05.13 15:45:42.916 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:45:42.927 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGREG=0' => ['OK']
2009.05.13 15:45:42.936 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:45:42.946 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 15 bytes: 'AT+CGEREP=0,0\r\n'
2009.05.13 15:45:42.972 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1065
2009.05.13 15:45:42.981 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:45:42.993 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGEREP=0,0' => ['OK']
2009.05.13 15:45:43.1 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:45:43.12 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 19 bytes: 'AT+CNMI=2,1,0,0,0\r\n'
2009.05.13 15:45:43.37 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1068
2009.05.13 15:45:43.46 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:45:43.57 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CNMI=2,1,0,0,0' => ['OK']
2009.05.13 15:45:43.66 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:45:43.76 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 10 bytes: 'AT%CSQ=0\r\n'
2009.05.13 15:45:43.98 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1071
2009.05.13 15:45:43.108 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 18 bytes: '\r\n%CSQ: 0 \r\n\r\nOK\r\n'
2009.05.13 15:45:43.121 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CSQ=0' => ['%CSQ: 0 ', 'OK']
2009.05.13 15:45:43.130 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:45:43.140 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CPRI=0\r\n'
2009.05.13 15:45:43.162 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1074
2009.05.13 15:45:43.171 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:45:43.182 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CPRI=0' => ['OK']
2009.05.13 15:45:43.191 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:45:43.201 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CBHZ=0\r\n'
2009.05.13 15:45:43.227 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1077
2009.05.13 15:45:43.236 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 15:45:43.247 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CBHZ=0' => ['OK']
2009.05.13 15:45:43.255 ogsmd.modem.abstract DEBUG prepareForSuspend ACK from channel <UnsolicitedResponseChannel via /dev/pts/2> received
2009.05.13 15:45:43.263 ogsmd DEBUG (writing '1' to '/sys/bus/platform/devices/neo1973-pm-gsm.0/flowcontrolled')
2009.05.13 15:45:43.272 frameworkd.resource INFO setting resource status for GSM from suspending to suspended
2009.05.13 15:45:43.286 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 15:45:43.417 ousaged INFO ...completed
2009.05.13 15:45:43.441 ousaged INFO triggering kernel suspend
2009.05.13 16:22:29.436 ousaged INFO kernel has resumed - reason = PowerKey
2009.05.13 16:22:29.513 ousaged INFO resuming resources...
2009.05.13 16:22:29.896 odeviced.kernel26 INFO capacity now 98
2009.05.13 16:22:30.724 frameworkd.resource INFO setting resource status for GSM from suspended to resuming
2009.05.13 16:22:30.732 ogsmd DEBUG (writing '0' to '/sys/bus/platform/devices/neo1973-pm-gsm.0/flowcontrolled')
2009.05.13 16:22:30.744 ogsmd.modem.abstract DEBUG recoverFromSuspend ACK from channel <MiscChannel via /dev/pts/1> received
2009.05.13 16:22:30.752 ogsmd DEBUG TI Calypso specific resume handling... sending reinit in 5 seconds...
2009.05.13 16:22:30.759 ogsmd.modem.abstract DEBUG recoverFromSuspend ACK from channel <UnsolicitedResponseChannel via /dev/pts/2> received
2009.05.13 16:22:30.767 ogsmd.modem.abstract DEBUG recoverFromSuspend ACK from channel <CallChannel via /dev/pts/3> received
2009.05.13 16:22:32.24 odeviced.idlenotifier INFO odeviced.idlenotifier state change to busy
2009.05.13 16:22:32.46 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 16:22:32.94 oeventsd INFO CommandAction xset -display localhost:0 s reset
2009.05.13 16:22:32.298 oeventsd.fso_triggers INFO Receive IdleState, status = busy
2009.05.13 16:22:41.31 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 16:22:41.39 ogsmd DEBUG (<UnsolicitedResponseChannel via /dev/pts/2>: last communication with modem was 2217 seconds ago. Sending EOF to wakeup)
2009.05.13 16:22:41.253 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZU=1\r\n'
2009.05.13 16:22:41.277 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1098
2009.05.13 16:22:41.287 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 16:22:41.298 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZU=1' => ['OK']
2009.05.13 16:22:41.308 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 16:22:41.320 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CTZR=1\r\n'
2009.05.13 16:22:41.342 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1101
2009.05.13 16:22:41.353 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 16:22:41.364 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CTZR=1' => ['OK']
2009.05.13 16:22:41.373 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 16:22:41.383 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CREG=2\r\n'
2009.05.13 16:22:41.407 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1104
2009.05.13 16:22:41.416 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 16:22:41.428 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CREG=2' => ['OK']
2009.05.13 16:22:41.437 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 16:22:41.447 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 12 bytes: 'AT+CGREG=2\r\n'
2009.05.13 16:22:41.472 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1107
2009.05.13 16:22:41.481 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 16:22:41.492 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGREG=2' => ['OK']
2009.05.13 16:22:41.501 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 16:22:41.511 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 15 bytes: 'AT+CGEREP=2,1\r\n'
2009.05.13 16:22:41.537 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1110
2009.05.13 16:22:41.546 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 16:22:41.558 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CGEREP=2,1' => ['OK']
2009.05.13 16:22:41.568 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 16:22:41.578 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT+CSMS=1\r\n'
2009.05.13 16:22:41.602 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1113
2009.05.13 16:22:41.612 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 22 bytes: '\r\n+CSMS: 1,1,1\r\n\r\nOK\r\n'
2009.05.13 16:22:41.625 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CSMS=1' => ['+CSMS: 1,1,1', 'OK']
2009.05.13 16:22:41.634 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 16:22:41.644 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 19 bytes: 'AT+CNMI=2,1,2,1,1\r\n'
2009.05.13 16:22:41.667 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1116
2009.05.13 16:22:41.676 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 16:22:41.689 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT+CNMI=2,1,2,1,1' => ['OK']
2009.05.13 16:22:41.698 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 16:22:41.714 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 10 bytes: 'AT%CSQ=1\r\n'
2009.05.13 16:22:41.747 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1119
2009.05.13 16:22:41.766 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 18 bytes: '\r\n%CSQ: 1 \r\n\r\nOK\r\n'
2009.05.13 16:22:41.779 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CSQ=1' => ['%CSQ: 1 ', 'OK']
2009.05.13 16:22:41.789 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 16:22:41.799 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CPRI=1\r\n'
2009.05.13 16:22:41.822 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1122
2009.05.13 16:22:41.831 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 16:22:41.842 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CPRI=1' => ['OK']
2009.05.13 16:22:41.851 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 16:22:41.861 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CNIV=1\r\n'
2009.05.13 16:22:41.887 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1125
2009.05.13 16:22:41.896 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 16:22:41.914 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CNIV=1' => ['OK']
2009.05.13 16:22:41.923 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 16:22:41.933 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: sending 11 bytes: 'AT%CBHZ=0\r\n'
2009.05.13 16:22:41.957 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = 1128
2009.05.13 16:22:41.966 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 6 bytes: '\r\nOK\r\n'
2009.05.13 16:22:41.977 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: COMPLETED 'AT%CBHZ=0' => ['OK']
2009.05.13 16:22:41.986 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToSend: watch timeout = None
2009.05.13 16:22:42.7 odeviced.idlenotifier INFO odeviced.idlenotifier state change to idle
2009.05.13 16:22:42.27 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 16:22:42.39 oeventsd.fso_triggers INFO Receive IdleState, status = idle
2009.05.13 16:22:43.237 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: _readyToRead: watch timeout = None
2009.05.13 16:22:43.246 ogsmd.channel DEBUG <UnsolicitedResponseChannel via /dev/pts/2>: got 20 bytes: '\r\n%CSQ: 24, 99, 2\r\n'