Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

slow startup with no market and no internet #46

Open
graph opened this issue Oct 3, 2011 · 0 comments
Open

slow startup with no market and no internet #46

graph opened this issue Oct 3, 2011 · 0 comments

Comments

@graph
Copy link

graph commented Oct 3, 2011

I have an activity that extends AbstractBillingActivity. Start up was slow slow. And I couldn't pinpoint why. Even when profiling during the slow startup I couldn't pinpoint what was going on. But it was clear the bottle neck was hapenning after onCreate. I have done a postDelayed(..., 50) just before onCreate finishes to time how long it is and so I can speed things up.

Things speed up after I enabled Wifi And setup android market. I'm not fully sure it's an android billing issue but I guess since I see messages regarding it in the log, and I got a speed up after setting up the android market.

Here are adb log with my stuff removed and hidden away (Except whats important)

10-03 17:15:30.390: DEBUG/NailActivity(2026): time for NailActivity **first init 1.095** this is my code for onCreate() and here I call postDelayed(..., 50)
10-03 17:15:30.390: DEBUG/NailActivity(2026): time for **onResume: 0.0** on Resume happens fast
10-03 17:15:30.430: DEBUG/dalvikvm(2026): GC_CONCURRENT freed 672K, 19% free 8424K/10375K, paused 2ms+3ms
10-03 17:15:30.450: DEBUG/MyView(2026): setUpScale in onSizeChanged
10-03 17:15:35.480: ERROR/vending(329): [8] VendingApplication.getCurrentAccount(): Countdown latch timeout expired when getting current account!
10-03 17:15:37.590: DEBUG/FlurryAgent(2026): generating report
10-03 17:15:37.590: DEBUG/FlurryAgent(2026): Sending report to: http://data.flurry.com/aap.do
10-03 17:15:37.620: DEBUG/FlurryAgent(2026): Sending report exception: Unable to resolve host "data.flurry.com": No address associated with hostname
10-03 17:15:39.290: WARN/ActivityManager(146): Launch timeout has expired, giving up wake lock!
10-03 17:15:39.290: WARN/ActivityManager(146): Activity idle timeout for ActivityRecord{414f54a8 com.mycompany.myapp/.NailActivity}
10-03 17:15:40.470: ERROR/vending(329): [8] InAppBillingState.isEnabled(): IAB latch timeout expired while waiting for metadata response.
10-03 17:15:40.480: WARN/CheckBillingSupported(2026): Error with response code RESULT_BILLING_UNAVAILABLE
10-03 17:15:42.300: INFO/InputDispatcher(146): Application is not responding: Window{40ca40b8 com.mycompany.myapp/com.mycompany.myapp.NailActivity paused=false}.  5005.7ms since event, 5005.4ms since wait started
10-03 17:15:42.300: INFO/WindowManager(146): Input event dispatching timed out sending to com.mycompany.myapp/com.mycompany.myapp.NailActivity
10-03 17:15:42.330: INFO/Process(146): Sending signal. PID: 2026 SIG: 3
10-03 17:15:42.330: INFO/dalvikvm(2026): threadid=4: reacting to signal 3
10-03 17:15:42.340: INFO/dalvikvm(2026): Wrote stack traces to '/data/anr/traces.txt'
**a bunch of stack traces lines like last 3 above repating**
10-03 17:15:42.610: DEBUG/dalvikvm(146): GC_EXPLICIT freed 3064K, 43% free 16217K/28295K, paused 3ms+4ms
10-03 17:15:43.190: ERROR/ActivityManager(146): ANR in com.mycompany.myapp (com.mycompany.myapp/.NailActivity)
10-03 17:15:43.190: ERROR/ActivityManager(146): Reason: keyDispatchingTimedOut
10-03 17:15:43.190: ERROR/ActivityManager(146): Load: 0.25 / 0.1 / 0.06
10-03 17:15:43.190: ERROR/ActivityManager(146): CPU usage from 11490ms to 0ms ago:
10-03 17:15:43.190: ERROR/ActivityManager(146):   2.6% 146/system_server: 1.5% user + 1% kernel / faults: 13 minor
10-03 17:15:43.190: ERROR/ActivityManager(146):   0.5% 2026/com.mycompany.myapp: 0.4% user + 0% kernel / faults: 46 minor
10-03 17:15:43.190: ERROR/ActivityManager(146):   0.3% 85/surfaceflinger: 0.1% user + 0.1% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146):   0% 991/kworker/u:1: 0% user + 0% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146):   0.3% 1333/kworker/u:2: 0% user + 0.3% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146):   0.1% 3/ksoftirqd/0: 0% user + 0.1% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146):   0.1% 230/com.android.launcher: 0.1% user + 0% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146):   0.1% 2053/kworker/0:3: 0.1% user + 0% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146):   0% 83/netd: 0% user + 0% kernel / faults: 63 minor
10-03 17:15:43.190: ERROR/ActivityManager(146):   0% 101/adbd: 0% user + 0% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146):   0% 127/irq/182-3d: 0% user + 0% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146):   0% 329/com.android.vending: 0% user + 0% kernel / faults: 6 minor
10-03 17:15:43.190: ERROR/ActivityManager(146):   0% 835/logcat: 0% user + 0% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146):   0% 1218/kworker/1:0: 0% user + 0% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146): 1.5% TOTAL: 0.8% user + 0.7% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146): CPU usage from 319ms to 845ms later:
10-03 17:15:43.190: ERROR/ActivityManager(146):   7.4% 146/system_server: 0% user + 7.4% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146):     7.4% 171/InputDispatcher: 0% user + 7.4% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146):     1.8% 167/PowerManagerSer: 0% user + 1.8% kernel
10-03 17:15:43.190: ERROR/ActivityManager(146): 2.5% TOTAL: 0% user + 2.5% kernel
10-03 17:15:44.330: DEBUG/dalvikvm(675): GC_EXPLICIT freed 16K, 5% free 6305K/6595K, paused 4ms+2ms
10-03 17:15:45.500: ERROR/vending(329): [41] VendingApplication.getCurrentAccount(): Countdown latch timeout expired when getting current account!
10-03 17:15:49.340: DEBUG/dalvikvm(550): GC_EXPLICIT freed 32K, 7% free 7085K/7559K, paused 6ms+2ms
10-03 17:15:50.490: ERROR/vending(329): [41] **InAppBillingState.isEnabled(): IAB latch timeout expired **while waiting for metadata response. _Now I my code begins to execute and all is normal__
10-03 17:15:51.940: DEBUG/NailActivity(2026): time for NailActivity **after init 1.362** this is how long postDelayed function took (my code)
10-03 17:15:51.940: DEBUG/NailActivity(2026): time for NailActivity **total init 22.645** this is the total init time
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

1 participant