Troubleshooting Help me analysis dumeMessages of the battery drain

[ekis]

Hi there, are there anyone here familiar with the dumpMessages of the kindle3. My kindle3(3g) now can only standby for one day after a full charge. I've noticed the battery drain in one day with the def:battinfo:cap.
Is the battery demaged? Or something else wrong? I've try to reset the kindle many times, the problem still exist. Hope someone can help me. Thanks!

Here is one piece of the dumpMessages(note from def:battinfo:cap=100% to def:battinfo:cap=03% quickly):


110903:172823 powerd[3883]: I def:battinfo:cap=100%, mAh=1903mAh, volt=4198mV, current=27mA, temp=84F, bp=4198mV, lmd=1903mAh, cycl=0, cyct=53:
110903:173001 crond[3127]: USER root pid 14969 cmd /usr/sbin/checkpmond
110903:173001 crond[3127]: USER root pid 14972 cmd /usr/sbin/tinyrot
110903:173001 crond[3127]: USER root pid 14973 cmd /usr/sbin/loginfo powerdcheck
110903:173002 system: I tinyrot:def::Rotating file /var/log/messages with max size 256
110903:173002 syslog-ng[949]: SIGHUP received, restarting syslog-ng
110903:173003 syslog-ng[949]: new configuration initialized
110903:173025 powerd[3883]: I def:chrgrevt::Received charger event
110903:173025 powerd[3883]: W def:chrgrnobatt::Charger event without battery information
110903:173025 cvm[3978]: I LipcService:EventArrived:source=com.lab126.hal,nam e=usbPlugOut,arg0=<None>,arg1=<None>:
110903:173025 cvm[3978]: E SystemServiceImpl:UnhandledEvent:event=usbPlugOut: HAL Event
110903:173025 powerd[3883]: I lipc:evts:name=notCharging, origin=com.lab126.powerd:Event sent
110903:173025 cvm[3978]: I LipcService:EventArrived:source=com.lab126.powerd, name=notCharging,arg0=<None>,arg1=<None>:
110903:173025 lipc-send-event[15131]: I lipc:evts:name=usbPlugOut, origin=com.lab126.hal:Event sent
110903:173025 CHGFAULT occurred: 0x385600
110903:173026 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=signalStrength,arg0=4/5,arg1=<None>:
110903:173026 wifid[2543]: I lipc:evts:name=signalStrength, origin=com.lab126.wifid, fparam=4/5:Event sent
110903:173026 wifid[2543]: I wmgr:rssi update:rssi=4/5:
110903:173026 Charger: Turning on CPUFreq
110903:173125 cvm[3978]: I LipcService:EventArrived:source=com.lab126.powerd, name=readyToSuspend,arg0=10,arg1=<None>:
110903:173125 cvm[3978]: I PowerImpl:Information::handlePowerStateChangeEvent 4 10
110903:173125 powerd[3883]: I def:statech:prev=SCREEN SAVER, next=READY TO SUSPEND:State change: SCREEN SAVER -> READY TO SUSPEND
110903:173125 audioServer[2924]: I lipc:event cb::audio stopped
110903:173125 wifid[2543]: I ipchandler:suspend:level=10, action=disregard:
110903:173125 powerd[3883]: I lipc:evts:name=readyToSuspend, origin=com.lab126.powerd, fparam=10:Event sent
110903:173130 powerd[3883]: I lipc:evts:name=readyToSuspend, origin=com.lab126.powerd, fparam=7:Event sent
110903:173130 cvm[3978]: I LipcService:EventArrived:source=com.lab126.powerd, name=readyToSuspend,arg0=7,arg1=<None>:
110903:173130 audioServer[2924]: I lipc:event cb::audio stopped
110903:173130 wifid[2543]: I ipchandler:suspend:level=7, action=disregard:
110903:173130 cmd[2556]: I def:checkready::ReadyToSuspend, Checking if all interfaces are disconnected
110903:173130 cmd[2556]: I lipc:sip:prop=deferSuspend, source=com.lab126.powerd, value=20:Set int property
110903:173130 powerd[3883]: I def:defer:delay=20:Defer suspend request
110903:173130 cmd[2556]: I lipc:ssp:prop=cmDisconnect, source=com.lab126.wifid:Set string property
110903:173130 wifid[2543]: I ipchandler:set_property:prop=cmDisconnect:
110903:173130 wifid[2543]: I sysev:dispatch:code=DiscRequest:
110903:173130 cmd[2556]: I lipc:ssp:prop=cmDisconnect, source=com.lab126.wan:Set string property
110903:173130 cmd[2556]: I lipc:evts:name=interfaceChange, origin=com.lab126.cmd, fparam= :Event sent
110903:173130 cmd[2556]: I def:disconnect::ReadyToSuspend, sent Disconnect to all interfaces
110903:173130 cvm[3978]: I ConnectionService:LipcEventArrived:source=com.lab1 26.cmd,name=interfaceChange,arg0=<None>,arg1=none:
110903:173130 cvm[3978]: I HTTPUtil:clearDNSCache:cache_size=1:clearing DNS cacheMap
110903:173130 cvm[3978]: I WirelessImpl:SettingProxyInfo:interface=none:Setti ng up proxies
110903:173130 cvm[3978]: I URLUtil:UpdateGlobalsTrace::before updating the map
110903:173130 cvm[3978]: I URLUtil:UpdateGlobalsTrace::after updating the map
110903:173130 cvm[3978]: I URLUtil:UpdateGlobalsTrace::before updating the map
110903:173130 cvm[3978]: I URLUtil:UpdateGlobalsTrace::after updating the map
110903:173130 cvm[3978]: I URLUtil:UpdateGlobalsTrace::before updating the map
110903:173130 cvm[3978]: I URLUtil:UpdateGlobalsTrace::after updating the map
110903:173130 cvm[3978]: I SettingsPage:Information::The current view is not the Settings so skip refresh the WiFi widget
110903:173130 wifid[2543]: I wmgr:handleCtrlEvent:ev=Disconnect, state=Connected:
110903:173130 wifid[2543]: I wmgr:state:hook=leave, state=Connected:
110903:173130 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=signalStrength,arg0=0/5,arg1=<None>:
110903:173130 wifid[2543]: I lipc:evts:name=signalStrength, origin=com.lab126.wifid, fparam=0/5:Event sent
110903:173130 wifid[2543]: I wmgr:state:from=Connected, to=Idle:Connected -> Idle
110903:173130 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=cmStateChange,arg0=READY,arg1=<None>:
110903:173130 wifid[2543]: I sysev:dispatch:code=Connection state changed:
110903:173130 wifid[2543]: I lipc:evts:name=cmStateChange, origin=com.lab126.wifid, fparam=READY:Event sent
110903:173130 cmd[2556]: I def:statechange::New Status for wifi is READY
110903:173130 wifid[2543]: I wmgr:event:handleWpasupNotify(<2>CTRL-EVENT-DISCONNECTED), state=Idle:
110903:173131 wifid[2543]: I wmgr:handleCtrlEvent:ev=ConfirmDisconnect, state=Idle:
110903:173131 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=cmDisconnected,arg0=<None>,arg1=<None>:
110903:173131 wifid[2543]: I sysev:dispatch:code=Disconnected:
110903:173131 wifid[2543]: I lipc:evts:name=cmDisconnected, origin=com.lab126.wifid:Event sent
110903:173131 wifid[2543]: I netm:event:handleCtrlEvent (Reset) connectionNumber=7:
110903:173131 udhcpc[24622]: Received SIGTERM
110903:173131 udhcpc[24622]: Unicasting a release of xxx.xxx.1.205 to xxx.xxx.1.254
110903:173131 udhcpc[24622]: Sending release...
110903:173131 udhcpc[24622]: Entering released state
110903:173148 phd[2674]: I udp_tph:restart_udp_tph_rcvr:ret = 12:
110903:173148 phd[2674]: I udp_tph:restart_udp_tph_rcvr:ret = 17:
110903:173150 cvm[3978]: I LipcService:EventArrived:source=com.lab126.powerd, name=readyToSuspend,arg0=7,arg1=<None>:
110903:173150 powerd[3883]: I lipc:evts:name=readyToSuspend, origin=com.lab126.powerd, fparam=7:Event sent
110903:173150 audioServer[2924]: I lipc:event cb::audio stopped
110903:173150 wifid[2543]: I ipchandler:suspend:level=7, action=disregard:
110903:173150 cmd[2556]: I def:checkready::ReadyToSuspend, Checking if all interfaces are disconnected
110903:173150 cmd[2556]: I def:disconnected::ReadyToSuspend, All interfaces disconnected
110903:173150 cmd[2556]: I def:nointf::No active Interface available
110903:173150 cmd[2556]: I def:curintf:currentintf:No network interface available
110903:173150 cmd[2556]: I def:tcpreset_proc_entry:tcpreset= /proc/tcpreset:Resetting TCP sockets
110903:173150 cmd[2556]: I def:tcpreset_details:resetparam=43,skiplist=22,590 0,8000:TCP Reset details
110903:173155 cvm[3978]: I LipcService:EventArrived:source=com.lab126.powerd, name=readyToSuspend,arg0=6,arg1=<None>:
110903:173155 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=cmDisconnected,arg0=<None>,arg1=<None>:
110903:173155 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=cmStateChange,arg0=NA,arg1=<None>:
110903:173155 powerd[3883]: I lipc:evts:name=readyToSuspend, origin=com.lab126.powerd, fparam=6:Event sent
110903:173155 audioServer[2924]: I lipc:event cb::audio stopped
110903:173155 wifid[2543]: I ipchandler:suspend:level=6, action=process:
110903:173155 wifid[2543]: I sysev:dispatch:code=Suspend:
110903:173155 wifid[2543]: I lipc:evts:name=cmDisconnected, origin=com.lab126.wifid:Event sent
110903:173155 wifid[2543]: I lipc:evts:name=cmStateChange, origin=com.lab126.wifid, fparam=NA:Event sent
110903:173155 cmd[2556]: I def:statechange::New Status for wifi is NA
110903:173155 wifid[2543]: I netm:event:handleCtrlEvent (Reset) connectionNumber=8:
110903:173156 wifid[2543]: I sysev:dispatch:code=SuspendPhase2:
110903:173156 wifid[2543]: I wmgr:handleCtrlEvent:ev=Suspend, state=Idle:
110903:173156 system: W wpa_supplicant:def:wpa supplicant stopped
110903:173156 iwioctl: cmd=0x8be5 not allowed in this mode
110903:173156 system: I wifi:def:WiFi Device mac = 28:EF:01:10:D0:CB
110903:173156 ar6000_cleanup_module
110903:173156 +ar6000_destroy: 0
110903:173156 debug_hdr_ptr: 0x5018f0
110903:173156 ar6000_destroy(): WMI stopped
110903:173156 Shuting down HTC ....
110903:173156 sdio_claim_host
110903:173156 SDIO: Disabling IRQ for mmc1:0001:1...
110903:173156 IRQ thread exiting
110903:173156 mmc1: IRQ thread exiting with code 1
110903:173156 SDIO: sdio_card_irq_put
110903:173156 SDIO: mmc_io_rw_direct
110903:173156 SDIO: mmc_io_rw_direct 1
110903:173156 sdio_release_irq
110903:173156 sdio_release_host
110903:173156 DevMaskInterrupts
110903:173156 HTCFlushSendPkts
110903:173156 HTCFlushRecvBuffers
110903:173156 After HTCStop
110903:173156 HTCDestroy
110903:173156 ar6000_reset_device
110903:173156 HIFShutDownDevice
110903:173157 -ar6000_destroy
110903:173157 ar6000_destroy
110903:173157 HIFShutDownDevice
110903:173157 system: I wifi:def:loadAR6000 finished, args=(unloadall)
110903:173157 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=cmIntfNotAvailable,arg0=<None>,arg1=<None>:
110903:173157 wifid[2543]: I wmgr:state:from=Idle, to=Powered down:Idle -> Powered down
110903:173157 wifid[2543]: I sysev:dispatch:code=IF not available:
110903:173157 wifid[2543]: I lipc:evts:name=cmIntfNotAvailable, origin=com.lab126.wifid:Event sent
110903:173157 wifid[2543]: I sysev:dispatch:code=Connection state changed:
110903:173201 system: I tinyrot:def::Rotating file /var/log/messages with max size 256
110903:173201 syslog-ng[949]: SIGHUP received, restarting syslog-ng
110903:173202 syslog-ng[949]: new configuration initialized
110903:173204 powerd[3883]: I def:statech:prev=READY TO SUSPEND, next=SUSPENDED:State change: READY TO SUSPEND -> SUSPENDED
110903:173204 powerd[3883]: I def:r2stime:secs=39, next=SUSPENDED:Time spent in ready to suspend
110903:173204 cvm[3978]: I LipcService:EventArrived:source=com.lab126.powerd, name=suspending,arg0=<None>,arg1=<None>:
110903:173204 cvm[3978]: I PowerImpl:Information::handlePowerStateChangeEvent 6 0
110903:173204 cvm[3978]: I ScreenSaverBooklet:BAD_SWITCH_REASON:reason=null,B ookletName=null:Already in SS booklet, ignoring.
110903:173204 powerd[3883]: I lipc:evts:name=suspending, origin=com.lab126.powerd:Event sent
110903:173205 powerd[3883]: I def:suspend::System suspending
110905:054145 PM: Syncing filesystems ... done.
110905:054145 Freezing user space processes ... (elapsed 0.00 seconds) done.
110905:054145 Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
110905:054145 Suspending console(s)
110905:054145 battery: I def:avg:Average_mAH_usage=2mAH, time=94seconds:
110905:054145 Suspending VFP ...
110905:054145 kernel: I pmic:wakeup:source=Lobath:
110905:054145 Charger: Atlas lobathi interrupt - 4185
110905:054145 mcsd[2750]: W lipc:sel:ret=4:Select failed
110905:054145 cvm[3978]: W lipc:sel:ret=4:Select failed
110905:054145 cvm[3978]: W lipc:sel:ret=4:Select failed
110905:054145 powerd[3883]: W lipc:sel:ret=4:Select failed
110905:054145 audioServer[2924]: W lipc:sel:ret=4:Select failed
110905:054145 ttsd[2965]: E file_reader:wake_pipe_closed:rc=-1, errno=11 (EAGAIN):read(wake_file) failed
110905:054145 phd[2674]: I udp_tph:restart_udp_tph_rcvr:ret = 17:
110905:054145 wifid[2543]: W lipc:sel:ret=4:Select failed
110905:054145 syslog-ng[949]: STATS: dropped 0
110905:054145 Restarting tasks ... done.
110905:054145 powerd[3883]: I def:resume::System resumed
110905:054145 cvm[3978]: I LipcService:EventArrived:source=com.lab126.powerd, name=resuming,arg0=<None>,arg1=<None>:
110905:054145 cvm[3978]: I PowerImpl:Information::handlePowerStateChangeEvent 7 0
110905:054145 cvm[3978]: I PrefixFreeVolume:ForceRescan:path=/mnt/us/documents:
110905:054145 cvm[3978]: I PrefixFreeVolume:ForceRescan:path=/mnt/us/audible:
110905:054145 cvm[3978]: I PrefixFreeVolume:ForceRescan:path=/mnt/us/pictures:
110905:054145 cvm[3978]: I PrefixFreeVolume:ForceRescan:path=/opt/amazon/dict:
110905:054145 cvm[3978]: I lipc:gsp:prop=activeInterface, source=com.lab126.cmd:Get string property
110905:054145 wand[2583]: I lipc:evts:name=cmStateChange, origin=com.lab126.wan, fparam=PENDING:Event sent
110905:054145 wand[2583]: I lipc:evts:name=powerStateChanged, origin=com.lab126.wan, fparam=6:Event sent
110905:054145 wand[2583]: I def:wpstate:state=powerStateWakingUp:wan power state changed
110905:054145 wifid[2543]: I ipchandler:wakeup:ev=resuming:
110905:054145 wifid[2543]: I sysev:dispatch:code=Wakeup:
110905:054145 wifid[2543]: I sysev:power:pwr=4, persist=0:processing Wakeup
110905:054145 cmd[2556]: I def:sysactive::System Resuming
110905:054145 cmd[2556]: I lipc:gsp:prop=state, source=com.lab126.powerd:Get string property
110905:054145 wifid[2543]: I wmgr:handleCtrlEvent:ev=Wakeup, state=Powered down:
110905:054145 powerd[3883]: I lipc:evts:name=resuming, origin=com.lab126.powerd:Event sent
110905:054145 powerd[3883]: I def:statech:prev=SUSPENDED, next=SCREEN SAVER:State change: SUSPENDED -> SCREEN SAVER
110905:054145 cmd[2556]: I lipc:gsp:prop=cmState, source=com.lab126.wifid:Get string property
110905:054145 cvm[3978]: I HTTPUtil:clearDNSCache:cache_size=0:clearing DNS cacheMap
110905:054145 cvm[3978]: I WirelessImpl:SettingProxyInfo:interface=none:Setti ng up proxies
110905:054145 cvm[3978]: I URLUtil:UpdateGlobalsTrace::before updating the map
110905:054145 cvm[3978]: I URLUtil:UpdateGlobalsTrace::after updating the map
110905:054145 cvm[3978]: I URLUtil:UpdateGlobalsTrace::before updating the map
110905:054145 cvm[3978]: I URLUtil:UpdateGlobalsTrace::after updating the map
110905:054145 cvm[3978]: I URLUtil:UpdateGlobalsTrace::before updating the map
110905:054145 cvm[3978]: I URLUtil:UpdateGlobalsTrace::after updating the map
110905:054145 cvm[3978]: I SettingsPage:Information::The current view is not the Settings so skip refresh the WiFi widget
110905:054145 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wan,nam e=cmStateChange,arg0=PENDING,arg1=<None>:
110905:054145 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wan,nam e=powerStateChanged,arg0=6,arg1=<None>:
110905:054145 wifid[2543]: I ipchandler:get-prop:cmState=NA:
110905:054145 cmd[2556]: I lipc:gsp:prop=cmState, source=com.lab126.wan:Get string property
110905:054145 cmd[2556]: I def:connectreq:intf_name=wan:Sending a connection request
110905:054145 cmd[2556]: I lipc:ssp:prop=cmConnMode, source=com.lab126.wan:Set string property
110905:054145 cmd[2556]: I lipc:ssp:prop=cmConnect, source=com.lab126.wan:Set string property
110905:054145 cmd[2556]: I def:intfna::No connected network interface
110905:054145 cmd[2556]: I def:statechange::New Status for wan is PENDING
110905:054145 cmd[2556]: I def:tcpreset_proc_entry:tcpreset= /proc/tcpreset:Resetting TCP sockets
110905:054145 cmd[2556]: I def:tcpreset_details:resetparam=43,skiplist=22,590 0,8000:TCP Reset details
110905:054145 system: I wancontrol:pc:processing "devicewake"
110905:054145 usb 1-1: new full speed USB device using fsl-ehci and address 3
110905:054146 system: I wifi:def:WiFi Device mac = 28:EF:01:10:D0:CB
110905:054146 usb 1-1: configuration #1 chosen from 1 choice
110905:054146 option 1-1:1.0: GSM modem (1-port) converter detected
110905:054146 usb 1-1: GSM modem (1-port) converter now attached to ttyUSB0
110905:054146 option 1-1:1.1: GSM modem (1-port) converter detected
110905:054146 usb 1-1: GSM modem (1-port) converter now attached to ttyUSB1
110905:054146 ar6000: Shasta Atheros Ar6000 WiFi Driver
110905:054146 ar6000_probe: initializing!
110905:054146 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wan,nam e=powerStateChanged,arg0=1,arg1=<None>:
110905:054146 wand[2583]: I lipc:evts:name=powerStateChanged, origin=com.lab126.wan, fparam=1:Event sent
110905:054146 wand[2583]: I def:wpstate:state=powerStateUp:wan power state changed
110905:054146 mmc1: card 0001 removed
110905:054146 mmc1: queuing CIS tuple 0x01 length 3
110905:054146 mmc1: queuing CIS tuple 0x1a length 5
110905:054146 mmc1: queuing CIS tuple 0x1b length 8
110905:054146 mmc1: queuing CIS tuple 0x14 length 0
110905:054146 mmc1: queuing CIS tuple 0x80 length 1
110905:054146 mmc1: queuing CIS tuple 0x81 length 1
110905:054146 mmc1: queuing CIS tuple 0x82 length 1
110905:054146 mmc1: new SDIO card at address 0001
110905:054146 kernel: I perf:wifi:wifi_driver_loaded=60816.67 seconds:
110905:054147 wand[2583]: E dtp:simstat:status=7:bad sim status
110905:054147 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wan,nam e=nonfatalFailure,arg0=-13,arg1=<None>:
110905:054147 wand[2583]: E def:modopen:status=2:can't open the module library
110905:054147 wand[2583]: E def:sigthrf:errno=11:can't start signal thread
110905:054147 wand[2583]: I def:restrtwan::restarting wan
110905:054147 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wan,nam e=powerStateChanged,arg0=4,arg1=<None>:
110905:054147 wand[2583]: I lipc:evts:name=nonfatalFailure, origin=com.lab126.wan, fparam=-13:Event sent
110905:054147 wand[2583]: I lipc:evts:name=powerStateChanged, origin=com.lab126.wan, fparam=4:Event sent
110905:054147 wand[2583]: I def:wpstate:state=powerStateStopping:wan power state changed
110905:054147 system: I wancontrol:pc:processing "wanoffkill"
110905:054147 usbcore: deregistering interface driver option
110905:054147 option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
110905:054147 option 1-1:1.1: device disconnected
110905:054147 option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
110905:054147 option 1-1:1.0: device disconnected
110905:054147 usbserial: USB Serial deregistering driver GSM modem (1-port)
110905:054147 usbcore: deregistering interface driver usbserial
110905:054148 AR6000 Reg Code = 0x40000060
110905:054148 system: I wifi:def:loadAR6000 finished, args=()
110905:054148 system: I wpa_supplicant:def:wpa supplicant started
110905:054148 wifid[2543]: I wmgr:progress:connecting to /var/run/wpa_supplicant/wlan0:
110905:054148 wifid[2543]: W wmgr:progress:count=0:wpa connect failed
110905:054149 wifid[2543]: I wmgr:state:from=Powered down, to=Idle:Powered down -> Idle
110905:054149 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=cmStateChange,arg0=READY,arg1=<None>:
110905:054149 wifid[2543]: I sysev:dispatch:code=Connection state changed:
110905:054149 wifid[2543]: I ipchandler:set_property:prop=cmConnMode:
110905:054149 wifid[2543]: I ipchandler:set_property:prop=cmConnect, param=<0>:
110905:054149 wifid[2543]: I lipc:evts:name=cmStateChange, origin=com.lab126.wifid, fparam=READY:Event sent
110905:054149 wifid[2543]: I sysev:dispatch:code=ConnRequest:
110905:054149 cmd[2556]: I def:statechange::New Status for wifi is READY
110905:054149 cmd[2556]: I def:intf_ready:intf=wifi status=:Interface changed from NA to a different state
110905:054149 cmd[2556]: I lipc:ssp:prop=cmConnMode, source=com.lab126.wifid:Set string property
110905:054149 cmd[2556]: I lipc:ssp:prop=cmConnect, source=com.lab126.wifid:Set string property
110905:054149 cmd[2556]: I def:tcpreset_proc_entry:tcpreset= /proc/tcpreset:Resetting TCP sockets
110905:054149 cmd[2556]: I def:tcpreset_details:resetparam=43,skiplist=22,590 0,8000:TCP Reset details
110905:054149 wifid[2543]: I wmgr:wpasup:wiped 0 networks:
110905:054149 fsl-ehci fsl-ehci.0: remove, state 1
110905:054149 usb usb1: USB disconnect, address 1
110905:054149 usb 1-1: USB disconnect, address 3
110905:054149 fsl-ehci fsl-ehci.0: USB bus 1 deregistered
110905:054149 usbcore: deregistering device driver usb
110905:054149 usbcore: deregistering interface driver usbfs
110905:054149 usbcore: deregistering interface driver hub
110905:054149 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wan,nam e=powerStateChanged,arg0=0,arg1=<None>:
110905:054149 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wan,nam e=powerStateChanged,arg0=3,arg1=<None>:
110905:054149 wand[2583]: I lipc:evts:name=powerStateChanged, origin=com.lab126.wan, fparam=0:Event sent
110905:054149 wand[2583]: I def:wpstate:state=powerStateDown:wan power state changed
110905:054149 wand[2583]: I lipc:evts:name=powerStateChanged, origin=com.lab126.wan, fparam=3:Event sent
110905:054149 wand[2583]: I def:wpstate:state=powerStateStarting:wan power state changed
110905:054149 wifid[2543]: I wmgr:syncNetworkList:configured-networks=1, hidden-networks=0:
110905:054149 wifid[2543]: I wmgr:state:from=Idle, to=Searching:Idle -> Searching
110905:054149 wifid[2543]: I wmgr:handleCtrlEvent:ev=Connect, state=Searching:
110905:054149 wifid[2543]: I spectator:conn-start:t=60820.130404, essid=any:
110905:054149 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=cmStateChange,arg0=PENDING,arg1=<None>:
110905:054149 wifid[2543]: I sysev:dispatch:code=Connection state changed:
110905:054149 wifid[2543]: I lipc:evts:name=cmStateChange, origin=com.lab126.wifid, fparam=PENDING:Event sent
110905:054149 cmd[2556]: I def:statechange::New Status for wifi is PENDING
110905:054149 cmd[2556]: I def:tcpreset_proc_entry:tcpreset= /proc/tcpreset:Resetting TCP sockets
110905:054149 cmd[2556]: I def:tcpreset_details:resetparam=43,skiplist=22,590 0,8000:TCP Reset details
110905:054149 wifid[2543]: I wmgr:handleCtrlEvent:ev=Scan, state=Searching:
110905:054149 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=scanning,arg0=<None>,arg1=<None>:
110905:054149 wifid[2543]: I sysev:dispatch:code=Scanning:
110905:054149 wifid[2543]: I spectator:scan-start:t=60820.140861:
110905:054149 wifid[2543]: I lipc:evts:name=scanning, origin=com.lab126.wifid:Event sent
110905:054149 system: I wancontrol:pc:processing "wanon"
110905:054149 mwan: I set_wan_on_off:wpd:wait=3:modem power on delay
110905:054150 wifid[2543]: I wmgr:event:handleWpasupNotify(<2>CTRL-EVENT-SCAN-RESULTS), state=Searching:
110905:054150 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=scanComplete,arg0=<None>,arg1=<None>:
110905:054150 wifid[2543]: I sysev:dispatch:code=Scan complete:
110905:054150 wifid[2543]: I spectator:scan-complete:t=60820.448931:
110905:054150 wifid[2543]: I lipc:evts:name=scanComplete, origin=com.lab126.wifid:Event sent
110905:054151 wifid[2543]: I wmgr:event:handleWpasupNotify(<2>CTRL-EVENT-DISCONNECTED), state=Searching:
110905:054151 wifid[2543]: I spectator:conn-assoc-fail:t=60821.855675, bssid=00:00:00:00:00:00:
110905:054151 wifid[2543]: I sysev:dispatch:code=Conn failed:
110905:054151 wifid[2543]: I lipc:evts:name=cmConnectionFailed, origin=com.lab126.wifid, fparam=Failed to connect to WiFi netwo:Event sent
110905:054151 wifid[2543]: I sysev:connf:reas=3, detail=Network not found, disp=Failed to connect to WiFi network:conn request failed
110905:054151 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=cmConnectionFailed,arg0=Failed to connect to WiFi network,arg1=<None>:
110905:054152 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wifid,n ame=cmDisconnected,arg0=<None>,arg1=<None>:
110905:054152 wifid[2543]: I wmgr:handleCtrlEvent:ev=ConfirmDisconnect, state=Searching:
110905:054152 wifid[2543]: I sysev:dispatch:code=Disconnected:
110905:054152 cmd[2556]: I def:tcpreset_proc_entry:tcpreset= /proc/tcpreset:Resetting TCP sockets
110905:054152 cmd[2556]: I def:tcpreset_details:resetparam=43,skiplist=22,590 0,8000:TCP Reset details
110905:054152 wifid[2543]: I lipc:evts:name=cmDisconnected, origin=com.lab126.wifid:Event sent
110905:054152 wifid[2543]: I netm:event:handleCtrlEvent (Reset) connectionNumber=9:
110905:054200 usbcore: registered new interface driver usbfs
110905:054200 usbcore: registered new interface driver hub
110905:054200 usbcore: registered new device driver usb
110905:054200 fsl-ehci fsl-ehci.0: Freescale On-Chip EHCI Host Controller
110905:054200 fsl-ehci fsl-ehci.0: new USB bus registered, assigned bus number 1
110905:054200 fsl-ehci fsl-ehci.0: irq 35, io mem 0x53ff4400
110905:054200 fsl-ehci fsl-ehci.0: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004
110905:054200 usb usb1: configuration #1 chosen from 1 choice
110905:054200 hub 1-0:1.0: USB hub found
110905:054200 hub 1-0:1.0: 1 port detected
110905:054200 usb 1-1: new full speed USB device using fsl-ehci and address 2
110905:054200 cvm[3978]: I Search:INDEXING_THREAD_START::
110905:054200 cvm[3978]: I lipc:gip:prop=userstoreFreeSpace, source=com.lab126.volumd:Get int property
110905:054200 cvm[3978]: I Search:CHECKING_FREE_SPACE:path=/mnt/us/documents,KB=3198400:
110905:054200 usb 1-1: configuration #1 chosen from 1 choice
110905:054200 usbcore: registered new interface driver usbserial
110905:054200 usbserial: USB Serial Driver core
110905:054200 usbserial: USB Serial support registered for GSM modem (1-port)
110905:054200 option 1-1:1.0: GSM modem (1-port) converter detected
110905:054200 usb 1-1: GSM modem (1-port) converter now attached to ttyUSB0
110905:054200 option 1-1:1.1: GSM modem (1-port) converter detected
110905:054200 usb 1-1: GSM modem (1-port) converter now attached to ttyUSB1
110905:054200 usbcore: registered new interface driver option
110905:054200 option: USB Driver for GSM modems: v0.7.2
110905:054200 cvm[3978]: I ContentCatalogImpl:ItemIterator:index=1,items=7,vo lumes=6,time=44:Catalog Iteration done
110905:054201 wand[2583]: I lipc:evts:name=powerStateChanged, origin=com.lab126.wan, fparam=1:Event sent
110905:054201 wand[2583]: I def:wpstate:state=powerStateUp:wan power state changed
110905:054201 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wan,nam e=powerStateChanged,arg0=1,arg1=<None>:
110905:054201 crond[3127]: time disparity of 2170 minutes detected
110905:054201 cvm[3978]: I lipc:gip:prop=userstoreFreeSpace, source=com.lab126.volumd:Get int property
110905:054201 cvm[3978]: I Search:CHECKING_FREE_SPACE:path=/mnt/us/documents,KB=3198400:
110905:054201 cvm[3978]: I lipc:gip:prop=userstoreFreeSpace, source=com.lab126.volumd:Get int property
110905:054201 cvm[3978]: I Search:CHECKING_FREE_SPACE:path=/mnt/us/documents,KB=3198400:
110905:054201 cvm[3978]: I lipc:gip:prop=userstoreFreeSpace, source=com.lab126.volumd:Get int property
110905:054201 cvm[3978]: I Search:CHECKING_FREE_SPACE:path=/mnt/us/documents,KB=3198400:
110905:054201 cvm[3978]: I Search:INDEXING_THREAD_FINISH:milliseconds=1237:
110905:054204 Charger: low battery event sent to userspace
110905:054204 Charger: gasguage reported voltage: 3396mV, Capacity: 3
110905:054204 powerd[3883]: I def:chrgrevt::Received charger event
110905:054204 powerd[3883]: I def:chrgrlow::Charger event - Low battery
110905:054204 powerd[3883]: I def:critbatt:v=3396:Critical battery reached
110905:054204 arcotg_udc: Putting Phy in low power mode
110905:054204 eink_fb: I bs_cmd_ld_img_upd_data_which:def:temp=29C:from pmic
110905:054205 wand[2583]: I def:firmware:version=P60WAT13:radio firmware version
110905:054205 cvm[3978]: I LipcService:EventArrived:source=com.lab126.wan,nam e=serviceStateChanged,arg0=2048,arg1=<None>:
110905:054205 cvm[3978]: I WanControllerImpl:WANServiceInfo:signal=0,service= 0,roaming=8:
110905:054205 wand[2583]: I def:signal:level=-1,good=N,roaming=8,mode=None:wan signal level
110905:054205 wand[2583]: I lipc:evts:name=serviceStateChanged, origin=com.lab126.wan, fparam=2048:Event sent
110905:054205 eink_fb: I bs_cmd_ld_img_upd_data_which:def:temp=29C:from pmic
110905:054206 cvm[3978]: I LipcService:EventArrived:source=com.lab126.powerd, name=battLevelCrit,arg0=3396,arg1=<None>:
110905:054206 ttsd[2965]: I pcmplay:adjust_prio:prio=0, pid=3194:Adjusting PCM feeder thread's priority
110905:054206 ttsd[2965]: I pcmplay:adjust_prio:prio=0, pid=3195:Adjusting PCM feeder thread's priority
110905:054206 cvm[3978]: I Reader:Information::Reader::goHome() - Error handling for when Reader is not in foreground... will go home on the next call to Reader::drawPage()
110905:054206 powerd[3883]: I lipc:evts:name=battLevelCrit, origin=com.lab126.powerd, fparam=3396:Event sent
110905:054207 powerd[3883]: I def:shtdwn::Shutting down the system
110905:054207 shutdown[15849]: shutting down for system halt
110905:054208 init: Switching to runlevel: 0
110905:054210 system: I K04framework:def:stopping booklet framework
110905:054210 lipc-get-prop[15904]: I lipc:gip:prop=state, source=com.lab126.powerd:Get int property
110905:054210 powerd[3883]: W lipc:pt:prop=getstateInt:Property found but with a differnt type
110905:054210 lipc-get-prop[15904]: I lipc:gsp:prop=state, source=com.lab126.powerd:Get string property
110905:054210 Accessory port enable
110905:054210 mxc_keyb: I def:unlocked2:status=unlocked:
110905:054210 powerd[3883]: I lipc:evts:name=outOfScreenSaver, origin=com.lab126.powerd, fparam=1:Event sent
110905:054210 powerd[3883]: I def:statech:prev=SCREEN SAVER, next=ACTIVE:State change: SCREEN SAVER -> ACTIVE
110905:054210 powerd[3883]: I def:acc::Turn acc port on
110905:054210 fiveway: I def:unlock2:status=unlocked:
110905:054210 cvm[3978]: I LipcService:EventArrived:source=com.lab126.powerd, name=outOfScreenSaver,arg0=1,arg1=<None>:
110905:054210 cvm[3978]: I PowerImpl:Information::handlePowerStateChangeEvent 3 1
110905:054210 cvm[3978]: I ScreenSaverBooklet:EnterPassword::Requesting user password
110905:054210 cvm[3978]: I ScreenSaverBooklet:SSB_TRACE_USB_EXPORT:delayUSBEx port=false:Password disabled
110905:054210 cvm[3978]: I ScreenSaverBooklet:IncrementSSImage::Incrementing the screen saver image index
110905:054210 cvm[3978]: I ScreenSaverBooklet:SwitchingToLastBooklet:lastBook let=Home:Switching to last booklet
110905:054210 cvm[3978]: I BookletManager:SwitchingBooklets:from=ScreenSaverB ooklet,to=Home:
110905:054210 cvm[3978]: I lipc:gsp:prop=orientation, source=com.lab126.system:Get string property
110905:054210 cmd[2556]: I lipc:ssp:prop=cmConnMode, source=com.lab126.wifid:Set string property
110905:054210 wifid[2543]: I ipchandler:set_property:prop=cmConnMode:
110905:054210 cmd[2556]: I lipc:ssp:prop=cmConnect, source=com.lab126.wifid:Set string property
110905:054210 wifid[2543]: I ipchandler:set_property:prop=cmConnect, param=<0>:
110905:054210 wifid[2543]: I sysev:dispatch:code=ConnRequest:
110905:054210 cmd[2556]: I def:reconnect:intf=com.lab126.wifid:Issued reconnect
110905:054210 cmd[2556]: I lipc:ssp:prop=cmConnMode, source=com.lab126.wan:Set string property
110905:054210 cmd[2556]: I lipc:ssp:prop=cmConnect, source=com.lab126.wan:Set string property
110905:054210 cmd[2556]: I def:reconnect:intf=com.lab126.wan:Issued reconnect
110905:054210 cvm[3978]: I lipc:sip:prop=Volume, source=com.lab126.audio, value=14:Set int property
110905:054210 audioServer[2924]: I volume:set:vol = 14:Setting the volume
110905:054210 cvm[3978]: I ContentCatalogImpl:ItemIterator:index=1,items=7,vo lumes=6,time=2:Catalog Iteration done
110905:054210 cvm[3978]: I RepositoryController:FETCHED CONTENT FROM CATALOG:numPeriodicals=0,numBooks=7,numArchivedIte ms=0:
110905:054210 cvm[3978]: I RegistrationInfoImpl:ProcessingRegistration:status =start:
110905:054210 cvm[3978]: I RegistrationInfoImpl:RegInfoDownload::Reg info Download in Process
110905:054210 cvm[3978]: I RegistrationInfoImpl:RegInfoDownload::Reg info Download in Process
110905:054210 cvm[3978]: I RegistrationInfoImpl:RegInfoDownload::Reg info Download in Process
110905:054210 cvm[3978]: W SystemBarImpl:SPIN:spinCount=0:count <=0 when stopSpinner() called
110905:054210 wifid[2543]: I wmgr:handleCtrlEvent:ev=Connect, state=Searching:
110905:054210 wifid[2543]: I spectator:conn-start:t=60841.182657, essid=any:
110905:054210 cvm[3978]: I ConnectionService:EnsureConnectionCall:interface=:
110905:054210 cvm[3978]: I lipc:ssp:prop=ensureConnection, source=com.lab126.cmd:Set string property
110905:054210 cmd[2556]: I def:req::Got a connection request
110905:054210 cmd[2556]: I lipc:gsp:prop=cmState, source=com.lab126.wifid:Get string property
110905:054210 wifid[2543]: I ipchandler:get-prop:cmState=PENDING:
110905:054210 cmd[2556]: I def:connectreq:intf_name=wifi:Sending a connection request
110905:054210 cmd[2556]: I lipc:ssp:prop=cmConnMode, source=com.lab126.wifid:Set string property
110905:054210 wifid[2543]: I ipchandler:set_property:prop=cmConnMode:
110905:054210 cmd[2556]: I lipc:ssp:prop=cmConnect, source=com.lab126.wifid:Set string property
110905:054210 wifid[2543]: I ipchandler:set_property:prop=cmConnect, param=<0>:
110905:054210 wifid[2543]: I sysev:dispatch:code=ConnRequest:
110905:054210 cmd[2556]: I lipc:gsp:prop=cmState, source=com.lab126.wan:Get string property
110905:054210 cmd[2556]: I def:connectreq:intf_name=wan:Sending a connection request
110905:054210 cmd[2556]: I lipc:ssp:prop=cmConnMode, source=com.lab126.wan:Set string property
110905:054210 cmd[2556]: I lipc:ssp:prop=cmConnect, source=com.lab126.wan:Set string property
110905:054210 cmd[2556]: I def:intfna::No connected network interface
110905:054210 cmd[2556]: I def:wait::Waiting for response from network interfaces
110905:054210 lipc-send-event[15909]: I lipc:evts:name=dbg_power_button_pressed, origin=com.lab126.powerd.debug:Event sent
110905:054210 wifid[2543]: I wmgr:handleCtrlEvent:ev=Connect, state=Searching:
110905:054210 wifid[2543]: I spectator:conn-start:t=60841.284123, essid=any:
110905:054211 cvm[3978]: I RegistrationInfoImpl:RegInfoDownload::Reg info Download in Process
110905:054211 cvm[3978]: I RegistrationInfoImpl:RegInfoDownload::Reg info Download in Process
110905:054211 cvm[3978]: I RegistrationInfoImpl:RegInfoDownload::Reg info Download in Process
110905:054211 cvm[3978]: I MainView:Information::The device has WAN module so skip popping up the WiFi dialog.
110905:054211 No Accessory Found
110905:054212 root: Terminated
110905:054212 lipc-set-prop[15926]: I lipc:sip:prop=Control, source=com.lab126.audio, value=3:Set int property
110905:054212 system: I start.sh:stop:signal=15,exitval=143:CVM stopped using SIGTERM
110905:054213 wifid[2543]: I wmgr:event:handleWpasupNotify(<2>CTRL-EVENT-DISCONNECTED), state=Searching:
110905:054213 wifid[2543]: I spectator:conn-assoc-fail:t=60844.192675, bssid=00:00:00:00:00:00:
110905:054213 wifid[2543]: I sysev:dispatch:code=Conn failed:
110905:054213 wifid[2543]: I lipc:evts:name=cmConnectionFailed, origin=com.lab126.wifid, fparam=Failed to connect to WiFi netwo:Event sent
110905:054213 wifid[2543]: I sysev:connf:reas=3, detail=Network not found, disp=Failed to connect to WiFi network:conn request failed
110905:054214 system: I K06browserd:def:Stopping Browser Daemon
110905:054214 lipc-set-prop[15966]: I lipc:ssp:prop=stop, source=com.lab126.pmond:Set string property
110905:054214 pmond[2342]: I def:stop::Stopping process browserd
110905:054214 system: I K07linkfonts:def:couldn't stop linkfonts
110905:054214 wifid[2543]: I wmgr:handleCtrlEvent:ev=ConfirmDisconnect, state=Searching:
110905:054214 wifid[2543]: I sysev:dispatch:code=Disconnected:
110905:054214 cmd[2556]: I def:tcpreset_proc_entry:tcpreset= /proc/tcpreset:Resetting TCP sockets
110905:054214 cmd[2556]: I def:tcpreset_details:resetparam=43,skiplist=22,590 0,8000:TCP Reset details
110905:054214 wifid[2543]: I lipc:evts:name=cmDisconnected, origin=com.lab126.wifid:Event sent
110905:054214 wifid[2543]: I netm:event:handleCtrlEvent (Reset) connectionNumber=10:
110905:054214 system: I K10cron:def:stopping crond
110905:054214 lipc-daemon[3154]: I def:term::lipc-daemon received sigterm
110905:054214 system: I K10tmd:def:Stopping Transfer Manager Daemon
110905:054214 lipc-set-prop[16071]: I lipc:ssp:prop=stop, source=com.lab126.pmond:Set string property
110905:054214 tmd[3153]: I sigterm:bye::
110905:054214 tmd[3153]: I tq:dump_queues:max_id_so_far = 0, current_network = none, next_retry = {never} for recompute_retry_time() init, any_transport = @031407.21,intvl=0m, wifi_transport = @031407.21,intvl=0m:
110905:054214 tmd[3153]: I tq:queue_dump_header:name = inbox_queue, size = 0:
110905:054214 tmd[3153]: I tq:queue_dump_header:name = active_queue, size = 0:
110905:054214 tmd[3153]: I tq:queue_dump_header:name = dormant_queue, size = 0:
110905:054214 tmd[3153]: I tq:cehs_pool_header:cehs_size = 0:
110905:054214 pmond[2342]: I def:stop::Stopping process tmd
110905:054214 pmond[2342]: I def:exit:proc=browserd, exitcode=9, signal=9:browserd (3882) caught signal 9
110905:054215 system: I K15audio:def:stopping audio server
110905:054215 lipc-set-prop[16098]: I lipc:ssp:prop=stop, source=com.lab126.pmond:Set string property
110905:054215 pmond[2342]: I def:stop::Stopping process audioserver
110905:054215 pmond[2342]: I def:exit:proc=audioserver, exitcode=15, signal=15:audioserver (2924) caught signal 15
110905:054215 pmond[2342]: I def:exit:proc=tmd, exitcode=7:tmd (3153) exited
110905:054215 powerd[3883]: I def:suspenddelta:sec=130210, cap=-97, mAh=-1853, volt=-789, bp=-789:Suspend delta
110905:054215 system: I K17ttsd:def:stopping TTS daemon
110905:054215 lipc-set-prop[16124]: I lipc:ssp:prop=stop, source=com.lab126.pmond:Set string property
110905:054215 pmond[2342]: I def:stop::Stopping process ttsd
110905:054215 pmond[2342]: I def:exit:proc=ttsd, exitcode=15, signal=15:ttsd (2965) caught signal 15
110905:054215 powerd[3883]: I def:battinfo:cap=03%, mAh=48mAh, volt=3396mV, current=-145mA, temp=80F, bp=3396mV, lmd=1903mAh, cycl=0, cyct=53:
110905:054215 powerd[3883]: I lipc:evts:name=battLevelChanged, origin=com.lab126.powerd, fparam=3:Event sent
110905:054215 powerd[3883]: E def:fo:fname=/sys/devices/platform/charger/battery_full:Failed to open file
110905:054215 powerd[3883]: W def:sled::Failed to set LED
110905:054215 powerd[3883]: W def:batchk:type=unknown:Battery check failed
110905:054215 lipc-send-event[16170]: I lipc:evts:name=usbUnconfigured, origin=com.lab126.hal:Event sent
110905:054215 lipc-get-prop[16173]: I lipc:gip:prop=driveModeState, source=com.lab126.volumd:Get int property
110905:054215 system: I K19userstore:def:Found BOOTUP flag file