[Galaxy S I9000 Q&A, Help & Troubleshooting] strainhunter:
Hello,

I have a little problem.

My Phone is a Samsung Galaxy S i9000, CM10.1 latest nightlie, semaphore kernel 1.9.8s + bigmem libs.

Since a few days, when i wake my phone from sleep, the lockscreen hangs a few seconds, the i see that the wifi sign i the status bar disappears, then immediately reappears.

then i can unlock the lockscreen without problem. the problem appears after a little time in sleep. i did not encounter it (till now) when there is only a short time between sending to sleep and waking the phone up.

I recorded a log with "catlog" (logcat), there i discovered something i think (i mean i am no total noob, but some part from android make me sweat^^, like terminal stuff and logs^^ (not reading them but understanding them^^). The problem starts at line 3004 in the logcat (at today: "01-31 - 16:21:44.035" where it states "Waking up from sleep...")

i added the logcat txt file, maybe someone wants to take a quick look at it. i would really appreciate it.

i tried a few things. cleared dalvik chache, tried different wifi options but nothing.

a few days ago i installed "Toggle2g" -> http://xdaforums.com/showthread.php?t=739530

it worked fine, without a/the problem. But i got the suspicion that it maybe has something to do with it.

I deinstalled it but the problem stays. if i diable wifi everything is normal, no lag after wake.

Maybe someone can help me?

and sorry for my english, sometimes i dont know the word and the gramatics. I´m from germany.

Greetings,

Strainhunter

i´ll add here a part from the log txt-file from which i think its the critical part (starts wherer my phone wakes and end when wifi is reconnected.

Ill attach the full file too.

01-31 16:21:44.035 I/PowerManagerService( 477): Waking up from sleep...

01-31 16:21:44.128 I/WindowManager( 477): Lock screen displayed!

01-31 16:21:44.253 D/PowerManagerService-JNI( 477): Excessive delay in autosuspend_disable() while turning screen on: 199ms

01-31 16:21:44.367 D/SurfaceFlinger( 198): Screen acquired, type=0 flinger=0x417b8318

01-31 16:21:44.371 D/dalvikvm( 477): GC_FOR_ALLOC freed 425K, 21% free 16661K/20868K, paused 126ms, total 127ms

01-31 16:21:44.375 I/dalvikvm-heap( 477): Grow heap (frag case) to 17.461MB for 1008016-byte allocation

01-31 16:21:44.449 D/dalvikvm( 477): GC_FOR_ALLOC freed 1K, 20% free 17644K/21856K, paused 78ms, total 78ms

01-31 16:21:44.531 D/dalvikvm( 477): GC_FOR_ALLOC freed 10K, 20% free 17634K/21856K, paused 79ms, total 79ms

01-31 16:21:44.535 I/dalvikvm-heap( 477): Grow heap (frag case) to 18.389MB for 984976-byte allocation

01-31 16:21:44.613 D/dalvikvm( 477): GC_FOR_ALLOC freed 0K, 19% free 18596K/22820K, paused 76ms, total 76ms

01-31 16:21:52.039 I/wpa_supplicant( 6016): wlan0: CTRL-EVENT-DISCONNECTED bssid=00:18:e7:df:fc:54 reason=0

01-31 16:21:52.058 D/Tethering( 477): InitialState.processMessage what=4

01-31 16:21:52.058 D/Tethering( 477): sendTetherStateChangedBroadcast 0, 0, 0

01-31 16:21:52.074 E/wpa_supplicant( 6016): wpa_driver_nl80211_driver_cmd: failed to issue private commands

01-31 16:21:52.078 D/CommandListener( 196): Clearing all IP addresses on wlan0

01-31 16:21:52.093 D/UsbTetherTile( 839): ACTION_TETHER_STATE_CHANGED

01-31 16:21:52.097 D/ConnectivityService( 477): ConnectivityChange for WIFI: DISCONNECTED/DISCONNECTED

01-31 16:21:52.109 D/ConnectivityService( 477): Attempting to switch to mobile

01-31 16:21:52.109 D/ConnectivityService( 477): Attempting to switch to BLUETOOTH_TETHER

01-31 16:21:52.132 D/ConnectivityService( 477): resetConnections(wlan0, 3)

01-31 16:21:52.136 I/ActivityManager( 477): Start proc cm.aptoide.pt for broadcast cm.aptoide.pt/.ScheduledDownloadReceiver: pid=9612 uid=10177 gids={50177, 3003, 1015, 1028}

01-31 16:21:52.140 D/NetUtils( 477): android_net_utils_resetConnections in env=0x512b4758 clazz=0x4eb00001 iface=wlan0 mask=0x3

01-31 16:21:52.148 D/WifiStateMachine( 477): Detected an interface down, restart driver

01-31 16:21:52.203 I/dalvikvm( 9612): Turning on JNI app bug workarounds for target SDK version 8...

01-31 16:21:52.292 D/BluetoothAdapter( 839): 1096921456: getState() : mService = null. Returning STATE_OFF

01-31 16:21:52.304 D/BluetoothAdapter( 839): 1096921456: getState() : mService = null. Returning STATE_OFF

01-31 16:21:52.312 D/BluetoothAdapter( 839): 1096921456: getState() : mService = null. Returning STATE_OFF

01-31 16:21:52.320 D/BluetoothAdapter( 839): 1096921456: getState() : mService = null. Returning STATE_OFF

01-31 16:21:52.332 D/BluetoothAdapter( 839): 1096921456: getState() : mService = null. Returning STATE_OFF

01-31 16:21:52.449 E/IMO ( 7236): ConnectionThread:Unexpected I/O error: java.net.UnknownHostException: Host is unresolved: speedy.imo.im

01-31 16:21:52.449 E/IMO ( 7236): AsyncHttpPost:http post problem: javax.net.ssl.SSLPeerUnverifiedException: No peer certificate

01-31 16:21:52.449 I/IMO ( 7236): ConnectionThread:close finished

01-31 16:21:52.457 W/IMO ( 7236): ConnectionThread:Server closed connection unexpectedly.

01-31 16:21:52.511 I/ActivityThread( 9612): Pub extras.contentprovider: cm.aptoide.pt.ExtrasContentProvider

01-31 16:21:52.570 V/Aptoide-ManagerPreferences( 9612): gotSharedPreferences: aptoide_preferences

01-31 16:21:52.617 W/System.err( 7236): javax.net.ssl.SSLPeerUnverifiedException: No peer certificate

01-31 16:21:52.636 W/System.err( 7236):	at org.apache.harmony.xnet.provider.jsse.SSLSessionImpl.getPeerCertificates(SSLSessionImpl.java:137)

01-31 16:21:52.636 W/System.err( 7236):	at org.apache.http.conn.ssl.AbstractVerifier.verify(AbstractVerifier.java:93)

01-31 16:21:52.636 W/System.err( 7236):	at org.apache.http.conn.ssl.SSLSocketFactory.createSocket(SSLSocketFactory.java:381)

01-31 16:21:52.636 W/System.err( 7236):	at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:165)

01-31 16:21:52.636 W/System.err( 7236):	at org.apache.http.impl.conn.AbstractPoolEntry.open(AbstractPoolEntry.java:164)

01-31 16:21:52.636 W/System.err( 7236):	at org.apache.http.impl.conn.AbstractPooledConnAdapter.open(AbstractPooledConnAdapter.java:119)

01-31 16:21:52.636 W/System.err( 7236):	at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:360)

01-31 16:21:52.636 W/System.err( 7236):	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:555)

01-31 16:21:52.640 W/System.err( 7236):	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:487)

01-31 16:21:52.640 W/System.err( 7236):	at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:465)

01-31 16:21:52.640 W/System.err( 7236):	at com.imo.android.imoim.async.AsyncHttpPost.doInBackground(AsyncHttpPost.java:44)

01-31 16:21:52.640 W/System.err( 7236):	at com.imo.android.imoim.async.AsyncHttpPost.doInBackground(AsyncHttpPost.java:21)

01-31 16:21:52.640 W/System.err( 7236):	at com.imo.android.imoim.async.SingleThreadedAsyncTask$2.call(SingleThreadedAsyncTask.java:205)

01-31 16:21:52.640 W/System.err( 7236):	at java.util.concurrent.FutureTask.run(FutureTask.java:234)

01-31 16:21:52.640 W/System.err( 7236):	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1080)

01-31 16:21:52.640 W/System.err( 7236):	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:573)

01-31 16:21:52.640 W/System.err( 7236):	at java.lang.Thread.run(Thread.java:856)

01-31 16:21:52.640 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.640 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.644 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.644 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.644 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.644 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.644 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.648 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.648 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.648 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.652 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.652 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.652 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.652 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.656 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.656 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.656 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.656 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.656 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.656 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.660 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.660 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.660 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.660 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.660 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.664 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.664 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.664 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.664 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.664 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.667 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.667 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.667 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.671 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.671 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.671 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.671 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.675 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.675 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.675 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.679 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.687 I/wpa_supplicant( 6016): wlan0: CTRL-EVENT-DRIVER-STATE STARTED

01-31 16:21:52.691 D/Tethering( 477): sendTetherStateChangedBroadcast 1, 0, 0

01-31 16:21:52.695 D/UsbTetherTile( 839): ACTION_TETHER_STATE_CHANGED

01-31 16:21:52.730 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.742 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.746 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.746 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.746 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.746 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.746 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.750 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.750 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.750 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.750 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.753 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.753 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.753 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.753 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.753 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.761 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.765 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.765 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.765 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.769 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.769 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.769 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.769 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.769 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.773 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.773 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.773 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.824 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.824 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.871 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:52.941 D/dalvikvm( 477): GC_FOR_ALLOC freed 3054K, 27% free 16704K/22812K, paused 89ms, total 90ms

01-31 16:21:53.011 W/IMO ( 7236): AsyncHttpPost:no network service, dropping request

01-31 16:21:53.261 I/wpa_supplicant( 6016): wlan0: Trying to associate with 00:18:e7:df:fc:54 (SSID='dlink' freq=2422 MHz)

01-31 16:21:53.421 I/wpa_supplicant( 6016): wlan0: Associated with 00:18:e7:df:fc:54

01-31 16:21:53.437 I/wpa_supplicant( 6016): wlan0: WPA: Key negotiation completed with 00:18:e7:df:fc:54 [PTK=CCMP GTK=TKIP]

01-31 16:21:53.437 I/wpa_supplicant( 6016): wlan0: CTRL-EVENT-CONNECTED - Connection to 00:18:e7:df:fc:54 completed (reauth) [id=0 id_str=]

01-31 16:21:53.625 I/ActivityManager( 477): Process com.wetter.androidclient (pid 9423) has died.

01-31 16:21:53.644 I/IMO ( 7236): AsyncHttpPost:making async http post on thread Thread[main,5,main]

01-31 16:21:53.656 I/IMO ( 7236): AsyncHttpPost:making async http post on thread Thread[main,5,main]

01-31 16:21:53.773 D/PhoneStatusBar( 839): disable: < expand icons alerts ticker system_info BACK HOME recent* CLOCK search >

01-31 16:21:53.812 I/ActivityManager( 477): Start proc com.wetter.androidclient for broadcast com.wetter.androidclient/.widgets.OnScreenUnlockReceiver: pid=9634 uid=10083 gids={50083, 3003, 1028}

01-31 16:21:53.851 D/dalvikvm( 199): GC_EXPLICIT freed 37K, 11% free 2344K/2620K, paused 2ms+13ms, total 50ms

01-31 16:21:53.878 D/PhoneStatusBar( 839): disable: < expand icons alerts ticker system_info back* home* recent clock* search >

01-31 16:21:53.910 D/dalvikvm( 199): GC_EXPLICIT freed <1K, 11% free 2344K/2620K, paused 4ms+8ms, total 58ms

01-31 16:21:53.972 D/dalvikvm( 199): GC_EXPLICIT freed <1K, 11% free 2344K/2620K, paused 5ms+1ms, total 62ms

01-31 16:21:54.058 I/dalvikvm( 9634): Turning on JNI app bug workarounds for target SDK version 10...

01-31 16:21:54.195 W/InputMethodManagerService( 477): Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@41ac5f98 attribute=android.view.inputmethod.EditorInfo@41aa3318, token = android.os.BinderProxy@41ba4680

01-31 16:21:54.218 D/DhcpInfoInternal( 477): makeLinkProperties with empty dns2!

01-31 16:21:54.218 D/DhcpInfoInternal( 477): makeLinkProperties with empty dns2!

01-31 16:21:54.250 D/ConnectivityService( 477): Captive portal check NetworkInfo: type: WIFI[], state: CONNECTING/CAPTIVE_PORTAL_CHECK, reason: (unspecified), extra: "dlink", roaming: false, failover: false, isAvailable: true

01-31 16:21:54.273 D/ConnectivityService( 477): ConnectivityChange for WIFI: CONNECTED/CONNECTED

01-31 16:21:54.429 D/ConnectivityService( 477): ConnectivityChange for mobile: CONNECTED/CONNECTED

01-31 16:21:54.546 D/BluetoothAdapter( 839): 1096921456: getState() : mService = null. Returning STATE_OFF

01-31 16:21:54.570 D/dalvikvm( 9634): GC_CONCURRENT freed 165K, 14% free 2640K/3044K, paused 3ms+14ms, total 92ms

01-31 16:21:54.578 D/ACRA ( 9634): Retrieve application default SharedPreferences.

01-31 16:21:54.609 D/dalvikvm( 943): GC_CONCURRENT freed 1808K, 14% free 16140K/18648K, paused 4ms+93ms, total 487ms

01-31 16:21:54.628 D/ACRA ( 9634): Set OnSharedPreferenceChangeListener.

01-31 16:21:54.640 D/ACRA ( 9634): ACRA is enabled for com.wetter.androidclient, intializing...

01-31 16:21:54.664 D/ACRA ( 9634): Looking for error files in /data/data/com.wetter.androidclient/files

01-31 16:21:54.687 D/Wetter.com( 9634): [16:21:54.690, main]: OnScreenUnlockReceiver received

01-31 16:21:54.691 D/Wetter.com( 9634): [16:21:54.695, main]: OnScreenUnlockReceiver send Broadcast to start widgets

01-31 16:21:54.703 D/Receiver( 9612): Wireless Connected

01-31 16:21:54.718 D/Receiver( 9612): Wireless Connected

01-31 16:21:54.722 D/Receiver( 9612): Wireless Connected