[Nook Color Android Development] sinanju:
Log from WiFi-flavored sorta SOD

Here is the log from a WiFi-flavored SOD. It took a while. It starts with me turning off the screen and it ends with three lines that never appeared previously when I was trying to get it to happen.

Hope this helps. I'll provide logs for the PBD once I get the battery low enough.

I/power ( 1043): *** set_screen_state 0
D/Sensors ( 1043): sensor activation called: handle=0, enabled=0********************************
D/SurfaceFlinger( 1043): About to give-up screen, flinger = 0x947b0
D/WifiService( 1043): ACTION_SCREEN_OFF
D/NetworkCollector( 5429): state now IDLE
D/dalvikvm( 5636): GC_EXPLICIT freed 229K, 46% free 3389K/6215K, external 569K/849K, paused 56ms
D/WifiService( 1043): acquireWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.BinderProxy@40900768}
I/System.out( 1200): [INFO:27996619]: LogSource: Running flush
I/System.out( 1200): [INFO:27996621]: LogSource: Sending payload [bytes=464]
I/System.out( 1200): [INFO:27996696]: LogSource: Response [http=200,length=109]
I/System.out( 1200): [INFO:27996698]: LogSource: Read id 210, status code 200
D/WifiService( 1043): releaseWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.BinderProxy@40900768}
I/wpa_supplicant( 4712): CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
I/wpa_supplicant( 4712): CTRL-EVENT-STATE-CHANGE id=0 state=0 BSSID=00:00:00:00:00:00
V/WifiMonitor( 1043): Event [CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys]

It's worth noting that my WiFi sleep policy was set to Never. Once the device revived, which it did after quite a while (certainly long enough for one to think it was dead and force a reboot), and I was able to reconnect ADB, this was the rest of the event:

I/wpa_supplicant( 4712): CTRL-EVENT-STATE-CHANGE id=0 state=0 BSSID=00:00:00:00:00:00
V/WifiMonitor( 1043): Event [CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys]
V/WifiStateTracker( 1043): New network state is DISCONNECTED
V/WifiMonitor( 1043): Event [CTRL-EVENT-STATE-CHANGE id=0 state=0 BSSID=00:00:00:00:00:00]
V/WifiStateTracker( 1043): Changing supplicant state: COMPLETED ==> DISCONNECTED
I/wpa_supplicant( 4712): CTRL-EVENT-STATE-CHANGE id=0 state=4 BSSID=00:00:00:00:00:00
V/WifiMonitor( 1043): Event [CTRL-EVENT-STATE-CHANGE id=0 state=4 BSSID=00:00:00:00:00:00]
V/WifiStateTracker( 1043): Changing supplicant state: DISCONNECTED ==> ASSOCIATED
I/wpa_supplicant( 4712): Associated with 00:11:50:28:98:9e
I/wpa_supplicant( 4712): CTRL-EVENT-STATE-CHANGE id=0 state=7 BSSID=00:00:00:00:00:00
I/wpa_supplicant( 4712): CTRL-EVENT-CONNECTED - Connection to 00:11:50:28:98:9e completed (reauth) [id=0 id_str=]
V/WifiMonitor( 1043): Event [Associated with 00:11:50:28:98:9e]
V/WifiMonitor( 1043): Event [CTRL-EVENT-STATE-CHANGE id=0 state=7 BSSID=00:00:00:00:00:00]
V/WifiStateTracker( 1043): Changing supplicant state: ASSOCIATED ==> COMPLETED
V/WifiMonitor( 1043): Event [CTRL-EVENT-CONNECTED - Connection to 00:11:50:28:98:9e completed (reauth) [id=0 id_str=]]
V/WifiStateTracker( 1043): New network state is CONNECTED
I/wpa_supplicant( 4712): CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
I/wpa_supplicant( 4712): CTRL-EVENT-STATE-CHANGE id=0 state=0 BSSID=00:00:00:00:00:00
V/WifiMonitor( 1043): Event [CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys]
V/WifiMonitor( 1043): Event [CTRL-EVENT-STATE-CHANGE id=0 state=0 BSSID=00:00:00:00:00:00]
D/WifiStateTracker( 1043): Reset connections and stopping DHCP
D/dalvikvm( 3799): GC_EXPLICIT freed 337K, 45% free 3771K/6791K, external 0K/0K, paused 130ms
D/WifiService( 1043): acquireWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.BinderProxy@40900768}
I/wpa_supplicant( 4712): CTRL-EVENT-STATE-CHANGE id=0 state=2 BSSID=00:00:00:00:00:00
V/WifiMonitor( 1043): Event [CTRL-EVENT-STATE-CHANGE id=0 state=2 BSSID=00:00:00:00:00:00]
W/Smack/Packet( 1200): notify conn break (IOEx), close connection
D/Smack ( 1200): [XMPPConn] close connection, notifyClosed=false
V/WifiStateTracker( 1043): New network state is DISCONNECTED
V/WifiStateTracker( 1043): Changing supplicant state: COMPLETED ==> DISCONNECTED
V/WifiStateTracker( 1043): Changing supplicant state: DISCONNECTED ==> SCANNING
D/WifiStateTracker( 1043): DHCP request started
I/wpa_supplicant( 4712): Trying to associate with 00:11:50:28:98:9e (SSID='KnewCo' freq=2437 MHz)
I/wpa_supplicant( 4712): CTRL-EVENT-STATE-CHANGE id=0 state=3 BSSID=00:11:50:28:98:9e
V/WifiMonitor( 1043): Event [Trying to associate with 00:11:50:28:98:9e (SSID='KnewCo' freq=2437 MHz)]
V/WifiMonitor( 1043): Event [CTRL-EVENT-STATE-CHANGE id=0 state=3 BSSID=00:11:50:28:98:9e]
V/WifiStateTracker( 1043): Changing supplicant state: SCANNING ==> ASSOCIATING
I/System.out( 1200): [INFO:28056731]: LogSource: Running flush
I/System.out( 1200): [INFO:28056733]: LogSource: Sending payload [bytes=464]
I/wpa_supplicant( 4712): CTRL-EVENT-STATE-CHANGE id=0 state=4 BSSID=00:11:50:28:98:9e
V/WifiMonitor( 1043): Event [CTRL-EVENT-STATE-CHANGE id=0 state=4 BSSID=00:11:50:28:98:9e]
V/WifiStateTracker( 1043): Changing supplicant state: ASSOCIATING ==> ASSOCIATED
I/wpa_supplicant( 4712): Associated with 00:11:50:28:98:9e
I/wpa_supplicant( 4712): CTRL-EVENT-STATE-CHANGE id=0 state=7 BSSID=00:00:00:00:00:00
I/wpa_supplicant( 4712): CTRL-EVENT-CONNECTED - Connection to 00:11:50:28:98:9e completed (reauth) [id=0 id_str=]
V/WifiMonitor( 1043): Event [Associated with 00:11:50:28:98:9e]
V/WifiMonitor( 1043): Event [CTRL-EVENT-STATE-CHANGE id=0 state=7 BSSID=00:00:00:00:00:00]
V/WifiMonitor( 1043): Event [CTRL-EVENT-CONNECTED - Connection to 00:11:50:28:98:9e completed (reauth) [id=0 id_str=]]
V/WifiStateTracker( 1043): Changing supplicant state: ASSOCIATED ==> COMPLETED
V/WifiStateTracker( 1043): New network state is CONNECTED
I/System.out( 1200): [SEVERE:28056784]: LogSource: AsyncHttpRequestImpl.run(): exception thrown : Connection to https://www.google.com refused: org.apache.http.conn.HttpHostConnectException
D/WifiStateTracker( 1043): Reset connections and stopping DHCP
I/System.out( 1200): [INFO:28056792]: LogSource: Resending com.google.masf.protocol.PlainRequest@4050ac00
I/System.out( 1200): [INFO:28058793]: LogSource: Running delayed flush
I/System.out( 1200): [INFO:28058804]: LogSource: Running flush
I/System.out( 1200): [INFO:28058824]: LogSource: Sending payload [bytes=464]
I/System.out( 1200): [SEVERE:28058839]: LogSource: AsyncHttpRequestImpl.run(): exception thrown : Connection to https://www.google.com refused: org.apache.http.conn.HttpHostConnectException
I/System.out( 1200): [INFO:28058840]: LogSource: Resending com.google.masf.protocol.PlainRequest@4050ac00
D/dalvikvm( 1200): GC_CONCURRENT freed 421K, 47% free 3585K/6727K, external 0K/512K, paused 4ms+6ms
D/WifiService( 1043): releaseWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.BinderProxy@40900768}
I/System.out( 1200): [INFO:28062841]: LogSource: Running delayed flush
I/System.out( 1200): [INFO:28062852]: LogSource: Running flush
I/System.out( 1200): [INFO:28062855]: LogSource: Sending payload [bytes=464]
I/System.out( 1200): [SEVERE:28062864]: LogSource: AsyncHttpRequestImpl.run(): exception thrown : Connection to https://www.google.com refused: org.apache.http.conn.HttpHostConnectException
I/System.out( 1200): [INFO:28062865]: LogSource: Resending com.google.masf.protocol.PlainRequest@4050ac00
D/dalvikvm( 5429): GC_EXPLICIT freed 15K, 49% free 3496K/6791K, external 0K/0K, paused 104ms
E/WifiStateTracker( 1043): DHCP request failed: Timed out waiting for dhcpcd to start
D/WifiStateTracker( 1043): DHCP request started
D/WifiStateTracker( 1043): DHCP succeeded with lease: 86400
V/WifiStateTracker( 1043): IP configuration: ipaddr 192.168.1.4 gateway 192.168.1.1 netmask 255.255.255.0 dns1 71.243.0.12 dns2 68.237.161.12 DHCP server 192.168.1.1 lease 86400 seconds
D/WifiWatchdogService( 1043): (android.server.ServerThread) KnewCo (00:11:50:28:98:9e) does not require the watchdog
D/Tethering( 1043): MasterInitialState.processMessage what=3
I/MediaUploader( 5838): No need to wake up
D/CMStats ( 5857): CONNECTIVITY_ACTION: noConnectivity = false
D/VpncWidget( 5689): onReceive: action=android.net.conn.CONNECTIVITY_CHANGE
I/System.out( 1200): [INFO:28071366]: LogSource: Running delayed flush
I/System.out( 1200): [INFO:28071377]: LogSource: Running flush
I/System.out( 1200): [INFO:28071379]: LogSource: Sending payload [bytes=464]
I/System.out( 1200): [SEVERE:28072971]: LogSource: AsyncHttpRequestImpl.run(): exception thrown : No route to host: java.net.SocketException
D/ProtoRequestListener( 1200): requestFailed()
W/GTalkService( 1200): [GTalkConnection.27] doConnect: caught XMPPError connecting to mtalk.google.com:5228.: (502)
W/GTalkService( 1200): -- caused by: java.net.SocketException: No route to host
D/dalvikvm( 5746): GC_EXPLICIT freed 363K, 50% free 2929K/5831K, external 0K/0K, paused 54ms
D/dalvikvm( 5786): GC_EXPLICIT freed 10K, 49% free 2912K/5703K, external 0K/0K, paused 73ms
D/dalvikvm( 5838): GC_EXPLICIT freed 2K, 50% free 2705K/5379K, external 0K/0K, paused 43ms
D/dalvikvm( 5848): GC_EXPLICIT freed 217K, 50% free 2904K/5703K, external 0K/0K, paused 59ms
D/dalvikvm( 5857): GC_EXPLICIT freed 3K, 51% free 2681K/5379K, external 0K/0K, paused 43ms
D/dalvikvm( 5746): GC_CONCURRENT freed 445K, 51% free 2919K/5895K, external 0K/0K, paused 4ms+3ms
D/dalvikvm( 5746): GC_CONCURRENT freed 445K, 51% free 2919K/5895K, external 0K/0K, paused 4ms+3ms
D/Email ( 5746): *** synchronizeMailboxGeneric ***
D/dalvikvm( 1200): GC_CONCURRENT freed 389K, 47% free 3580K/6727K, external 0K/512K, paused 5ms+5ms
D/dalvikvm( 1200): GC_CONCURRENT freed 391K, 47% free 3572K/6727K, external 0K/512K, paused 5ms+5ms
D/dalvikvm( 1200): GC_CONCURRENT freed 382K, 47% free 3574K/6727K, external 0K/512K, paused 3ms+3ms
D/GTalkService( 1200): [RosterMgr] roster not modified
D/GTalkService( 1200): [OtrMgr] OTR not modified
D/WifiService( 1043): acquireWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.BinderProxy@40900768}
I/System.out( 1200): [INFO:28116745]: LogSource: Running flush
I/System.out( 1200): [INFO:28116748]: LogSource: Sending payload [bytes=464]
D/dalvikvm( 1200): GC_CONCURRENT freed 453K, 47% free 3632K/6727K, external 0K/512K, paused 5ms+5ms
I/System.out( 1200): [INFO:28117196]: LogSource: Response [http=200,length=109]
I/System.out( 1200): [INFO:28117198]: LogSource: Read id 212, status code 200
D/dalvikvm( 1200): GC_CONCURRENT freed 376K, 46% free 3663K/6727K, external 0K/512K, paused 5ms+5ms
D/WifiService( 1043): releaseWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.BinderProxy@40900768}
D/dalvikvm( 5673): GC_EXPLICIT freed 2K, 51% free 2683K/5379K, external 0K/0K, paused 61ms
D/dalvikvm( 5689): GC_EXPLICIT freed 3K, 51% free 2687K/5379K, external 0K/0K, paused 43ms
D/WifiService( 1043): ACTION_BATTERY_CHANGED pluggedType: 0
D/dalvikvm( 1251): GC_EXPLICIT freed 30K, 51% free 2693K/5447K, external 0K/0K, paused 43ms
D/WifiService( 1043): acquireWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.BinderProxy@40900768}
I/System.out( 1200): [INFO:28176823]: LogSource: Running flush
I/System.out( 1200): [INFO:28176826]: LogSource: Sending payload [bytes=464]
I/System.out( 1200): [INFO:28176899]: LogSource: Response [http=200,length=109]
I/System.out( 1200): [INFO:28176901]: LogSource: Read id 213, status code 200
D/WifiService( 1043): ACTION_BATTERY_CHANGED pluggedType: 0
D/WifiService( 1043): releaseWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.BinderProxy@40900768}
D/dalvikvm( 3799): GC_EXPLICIT freed 233K, 45% free 3766K/6791K, external 0K/0K, paused 60ms
D/WifiService( 1043): ACTION_BATTERY_CHANGED pluggedType: 0
D/WifiService( 1043): acquireWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.BinderProxy@40900768}
D/WifiService( 1043): releaseWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.BinderProxy@40900768}
I/power ( 1043): *** set_screen_state 1
D/Sensors ( 1043): sensor activation called: handle=0, enabled=1********************************
D/WifiService( 1043): ACTION_SCREEN_ON
D/NetworkCollector( 5429): moved
D/dalvikvm( 5429): GC_EXPLICIT freed 12K, 49% free 3496K/6791K, external 0K/0K, paused 51ms
W/KeyCharacterMap( 1043): No keyboard for id 0
W/KeyCharacterMap( 1043): Using default keymap: /system/usr/keychars/qwerty.kcm.bin
D/dalvikvm( 3799): GC_EXPLICIT freed 103K, 45% free 3755K/6791K, external 0K/0K, paused 57ms
D/WifiService( 1043): acquireWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.BinderProxy@40900768}
D/SurfaceFlinger( 1043): Screen about to return, flinger = 0x947b0
I/System.out( 1200): [INFO:28296813]: LogSource: Running flush
I/System.out( 1200): [INFO:28296816]: LogSource: Sending payload [bytes=464]
I/System.out( 1200): [INFO:28296940]: LogSource: Response [http=200,length=109]
I/System.out( 1200): [INFO:28296942]: LogSource: Read id 214, status code 200
D/WifiService( 1043): releaseWifiLockLocked: WifiLock{NetworkLocationProvider type=2 binder=android.os.BinderProxy@40900768}
D/WifiService( 1043): ACTION_BATTERY_CHANGED pluggedType: 0
D/dalvikvm( 1251): GC_EXPLICIT freed 61K, 51% free 2693K/5447K, external 0K/0K, paused 46ms