android Emulator always stop at "waiting for Home..."
- by wuwupp
hi,there, I freshed install Eclipse, jdk, android sdk 1.5 in winxp. but when I run the "hello world" app, the emulator always stop at "andorid" loading message. In eclipse console, it shows "waiting for HOME..." and in DDMS LogCat, it shows following msg:
there are some error and warning. So, what's wrong with my case? I have googled lots of results, but no one can help me.
Please help me. Many thx
06-13 00:07:54.323: INFO/DEBUG(551): debuggerd: Jun 30 2009 17:00:51
06-13 00:07:54.383: INFO/vold(550): Android Volume Daemon version 2.0
06-13 00:07:54.724: ERROR/flash_image(556): can't find recovery partition
06-13 00:07:55.223: DEBUG/qemud(558): entering main loop
06-13 00:07:55.323: DEBUG/qemud(558): multiplexer_handle_control: unknown control message (18 bytes): 'ko:unknown command'
06-13 00:07:55.493: INFO/vold(550): New MMC card 'SU02G' (serial 1012966) added @ /devices/platform/goldfish_mmc.0/mmc_host/mmc0/mmc0:e118
06-13 00:07:55.773: INFO/vold(550): Disk (blkdev 179:0), 262144 secs (128 MB) 0 partitions
06-13 00:07:55.773: INFO/vold(550): New blkdev 179.0 on media SU02G, media path /devices/platform/goldfish_mmc.0/mmc_host/mmc0/mmc0:e118, Dpp 0
06-13 00:07:55.814: INFO/vold(550): Evaluating dev '/devices/platform/goldfish_mmc.0/mmc_host/mmc0/mmc0:e118/block/mmcblk0' for mountable filesystems for '/sdcard'
06-13 00:07:56.014: ERROR/vold(550): Error opening switch name path '/sys/class/switch/test2' (No such file or directory)
06-13 00:07:56.014: ERROR/vold(550): Error bootstrapping switch '/sys/class/switch/test2' (m)
06-13 00:07:56.073: ERROR/vold(550): Error opening switch name path '/sys/class/switch/test' (No such file or directory)
06-13 00:07:56.073: ERROR/vold(550): Error bootstrapping switch '/sys/class/switch/test' (m)
06-13 00:07:56.073: DEBUG/vold(550): Bootstrapping complete
06-13 00:07:56.743: INFO//system/bin/dosfsck(550): dosfsck 3.0.1 (23 Nov 2008)
06-13 00:07:56.753: INFO//system/bin/dosfsck(550): dosfsck 3.0.1, 23 Nov 2008, FAT32, LFN
06-13 00:07:56.783: INFO//system/bin/dosfsck(550): Checking we can access the last sector of the filesystem
06-13 00:07:56.893: INFO//system/bin/dosfsck(550): Boot sector contents:
06-13 00:07:56.924: INFO//system/bin/dosfsck(550): System ID "MSWIN4.1"
06-13 00:07:56.934: INFO//system/bin/dosfsck(550): Media byte 0xf8 (hard disk)
06-13 00:07:56.953: INFO//system/bin/dosfsck(550): 512 bytes per logical sector
06-13 00:07:56.974: INFO//system/bin/dosfsck(550): 512 bytes per cluster
06-13 00:07:57.005: INFO//system/bin/dosfsck(550): 32 reserved sectors
06-13 00:07:57.013: INFO//system/bin/dosfsck(550): First FAT starts at byte 16384 (sector 32)
06-13 00:07:57.013: INFO//system/bin/dosfsck(550): 2 FATs, 32 bit entries
06-13 00:07:57.023: INFO//system/bin/dosfsck(550): 1040384 bytes per FAT (= 2032 sectors)
06-13 00:07:57.043: INFO//system/bin/dosfsck(550): Root directory start at cluster 2 (arbitrary size)
06-13 00:07:57.043: INFO//system/bin/dosfsck(550): Data area starts at byte 2097152 (sector 4096)
06-13 00:07:57.043: INFO//system/bin/dosfsck(550): 258048 data clusters (132120576 bytes)
06-13 00:07:57.103: INFO//system/bin/dosfsck(550): 9 sectors/track, 2 heads
06-13 00:07:57.103: INFO//system/bin/dosfsck(550): 0 hidden sectors
06-13 00:07:57.123: INFO//system/bin/dosfsck(550): 262144 sectors total
06-13 00:07:57.313: DEBUG/qemud(558): fdhandler_accept_event: accepting on fd 10
06-13 00:07:57.313: DEBUG/qemud(558): created client 0xe078 listening on fd 8
06-13 00:07:57.313: DEBUG/qemud(558): fdhandler_event: disconnect on fd 8
06-13 00:07:57.623: DEBUG/qemud(558): fdhandler_accept_event: accepting on fd 10
06-13 00:07:57.623: DEBUG/qemud(558): created client 0xf028 listening on fd 8
06-13 00:07:57.643: DEBUG/qemud(558): client_fd_receive: attempting registration for service 'gsm'
06-13 00:07:57.763: DEBUG/qemud(558): client_fd_receive: - received channel id 1
06-13 00:08:12.553: INFO//system/bin/dosfsck(550): Checking for unused clusters.
06-13 00:08:13.483: INFO//system/bin/dosfsck(550): Checking free cluster summary.
06-13 00:08:13.643: DEBUG/AndroidRuntime(553): AndroidRuntime START <<<<<<<<<<<<<<
06-13 00:08:13.705: DEBUG/AndroidRuntime(553): CheckJNI is ON
06-13 00:08:13.793: INFO//system/bin/dosfsck(550): /dev/block//vold/179:0: 0 files, 1/258048 clusters
06-13 00:08:14.063: INFO/logwrapper(550): /system/bin/dosfsck terminated by exit(0)
06-13 00:08:14.143: DEBUG/vold(550): Filesystem check completed OK
06-13 00:08:14.683: INFO/vold(550): Sucessfully mounted vfat filesystem 179:0 on /sdcard (safe-mode on)
06-13 00:08:17.023: INFO/(554): ServiceManager: 0xac38
06-13 00:08:17.883: INFO/AudioFlinger(554): AudioFlinger's thread ready to run for output 0
06-13 00:08:18.163: INFO/CameraService(554): CameraService started: pid=554
06-13 00:08:21.824: DEBUG/AndroidRuntime(553): --- registering native functions ---
06-13 00:08:27.813: INFO/Zygote(553): Preloading classes...
06-13 00:08:27.994: DEBUG/dalvikvm(553): GC freed 764 objects / 42216 bytes in 88ms
06-13 00:08:30.234: DEBUG/dalvikvm(553): GC freed 278 objects / 17160 bytes in 48ms
06-13 00:08:33.094: DEBUG/dalvikvm(553): GC freed 208 objects / 12696 bytes in 44ms
06-13 00:08:34.343: DEBUG/dalvikvm(553): Trying to load lib /system/lib/libmedia_jni.so 0x0
06-13 00:08:35.803: DEBUG/dalvikvm(553): Added shared lib /system/lib/libmedia_jni.so 0x0
06-13 00:08:35.903: DEBUG/dalvikvm(553): Trying to load lib /system/lib/libmedia_jni.so 0x0
06-13 00:08:35.903: DEBUG/dalvikvm(553): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0
06-13 00:08:36.003: DEBUG/dalvikvm(553): Trying to load lib /system/lib/libmedia_jni.so 0x0
06-13 00:08:36.003: DEBUG/dalvikvm(553): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0
06-13 00:08:36.215: DEBUG/dalvikvm(553): Trying to load lib /system/lib/libmedia_jni.so 0x0
06-13 00:08:36.244: DEBUG/dalvikvm(553): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0
06-13 00:08:36.455: DEBUG/dalvikvm(553): GC freed 462 objects / 29144 bytes in 70ms
06-13 00:08:44.123: DEBUG/dalvikvm(553): GC freed 3584 objects / 171648 bytes in 125ms
06-13 00:09:10.473: DEBUG/dalvikvm(553): GC freed 11329 objects / 400856 bytes in 196ms
06-13 00:09:17.373: DEBUG/dalvikvm(553): GC freed 10472 objects / 438272 bytes in 199ms
06-13 00:09:24.563: DEBUG/dalvikvm(553): GC freed 10975 objects / 459800 bytes in 202ms
06-13 00:09:46.403: DEBUG/dalvikvm(553): GC freed 14372 objects / 506896 bytes in 252ms
06-13 00:09:53.793: DEBUG/dalvikvm(553): GC freed 11314 objects / 481360 bytes in 215ms
06-13 00:09:57.743: DEBUG/dalvikvm(553): GC freed 5928 objects / 248640 bytes in 195ms
06-13 00:10:01.324: DEBUG/dalvikvm(553): GC freed 349 objects / 37032 bytes in 190ms
06-13 00:10:05.253: DEBUG/dalvikvm(553): GC freed 778 objects / 48376 bytes in 217ms
06-13 00:10:06.564: DEBUG/dalvikvm(553): GC freed 321 objects / 37288 bytes in 219ms
06-13 00:10:08.194: DEBUG/dalvikvm(553): GC freed 477 objects / 29584 bytes in 212ms
06-13 00:10:08.663: DEBUG/dalvikvm(553): Trying to load lib /system/lib/libwebcore.so 0x0
06-13 00:10:09.743: DEBUG/dalvikvm(553): Added shared lib /system/lib/libwebcore.so 0x0
06-13 00:10:11.634: DEBUG/dalvikvm(553): GC freed 441 objects / 26224 bytes in 236ms
06-13 00:10:12.893: DEBUG/dalvikvm(553): GC freed 506 objects / 41464 bytes in 235ms
06-13 00:10:14.153: DEBUG/dalvikvm(553): GC freed 537 objects / 38832 bytes in 239ms
06-13 00:10:15.883: DEBUG/dalvikvm(553): GC freed 342 objects / 22552 bytes in 248ms
06-13 00:10:17.124: DEBUG/dalvikvm(553): GC freed 338 objects / 18736 bytes in 264ms
06-13 00:10:18.523: DEBUG/dalvikvm(553): GC freed 629 objects / 32136 bytes in 260ms
06-13 00:10:38.933: DEBUG/dalvikvm(553): GC freed 14257 objects / 497280 bytes in 368ms
06-13 00:10:46.453: DEBUG/dalvikvm(553): GC freed 11164 objects / 469576 bytes in 360ms
06-13 00:10:52.973: DEBUG/dalvikvm(553): GC freed 7134 objects / 311432 bytes in 339ms
06-13 00:10:55.595: DEBUG/dalvikvm(553): GC freed 752 objects / 43224 bytes in 520ms
06-13 00:10:56.863: DEBUG/dalvikvm(553): GC freed 598 objects / 31496 bytes in 307ms
06-13 00:10:58.543: DEBUG/dalvikvm(553): GC freed 413 objects / 26336 bytes in 355ms
06-13 00:10:59.263: INFO/Zygote(553): ...preloaded 1166 classes in 151403ms.
06-13 00:10:59.683: DEBUG/dalvikvm(553): GC freed 313 objects / 19952 bytes in 343ms
06-13 00:10:59.793: INFO/Zygote(553): Preloading resources...
06-13 00:11:00.683: DEBUG/dalvikvm(553): GC freed 54 objects / 11248 bytes in 340ms
06-13 00:11:05.723: DEBUG/dalvikvm(553): GC freed 337 objects / 15008 bytes in 317ms
06-13 00:11:08.703: DEBUG/dalvikvm(553): GC freed 280 objects / 11768 bytes in 312ms
06-13 00:11:09.303: INFO/Zygote(553): ...preloaded 48 resources in 9513ms.
06-13 00:11:09.795: INFO/Zygote(553): ...preloaded 15 resources in 454ms.
06-13 00:11:10.303: DEBUG/dalvikvm(553): GC freed 118 objects / 8616 bytes in 420ms
06-13 00:11:10.913: DEBUG/dalvikvm(553): GC freed 205 objects / 8104 bytes in 308ms
06-13 00:11:11.344: DEBUG/dalvikvm(553): GC freed 36 objects / 1400 bytes in 320ms
06-13 00:11:11.543: INFO/dalvikvm(553): Splitting out new zygote heap
06-13 00:11:12.973: INFO/dalvikvm(553): System server process 585 has been created
06-13 00:11:13.336: INFO/Zygote(553): Accepting command socket connections
06-13 00:11:14.963: INFO/jdwp(585): received file descriptor 10 from ADB
06-13 00:11:16.843: WARN/System.err(585): Can't dispatch DDM chunk 46454154: no handler defined
06-13 00:11:16.953: WARN/System.err(585): Can't dispatch DDM chunk 4d505251: no handler defined
06-13 00:11:17.763: DEBUG/dalvikvm(585): Trying to load lib /system/lib/libandroid_servers.so 0x0
06-13 00:11:19.714: DEBUG/dalvikvm(585): Added shared lib /system/lib/libandroid_servers.so 0x0
06-13 00:11:20.123: INFO/sysproc(585): Entered system_init()
06-13 00:11:20.223: INFO/sysproc(585): ServiceManager: 0x1017b8
06-13 00:11:20.359: INFO/SurfaceFlinger(585): SurfaceFlinger is starting
06-13 00:11:20.493: INFO/SurfaceFlinger(585): SurfaceFlinger's main thread ready to run. Initializing graphics H/W...
06-13 00:11:20.634: ERROR/MemoryHeapBase(585): error opening /dev/pmem: No such file or directory
06-13 00:11:20.704: ERROR/SurfaceFlinger(585): Couldn't open /sys/power/wait_for_fb_sleep or /sys/power/wait_for_fb_wake
06-13 00:11:22.013: ERROR/GLLogger(585): couldn't load library (Cannot find library)
06-13 00:11:22.103: INFO/SurfaceFlinger(585): EGL informations:
06-13 00:11:22.113: INFO/SurfaceFlinger(585): # of configs : 6
06-13 00:11:22.123: INFO/SurfaceFlinger(585): vendor : Android
06-13 00:11:22.123: INFO/SurfaceFlinger(585): version : 1.31 Android META-EGL
06-13 00:11:22.134: INFO/SurfaceFlinger(585): extensions:
06-13 00:11:22.134: INFO/SurfaceFlinger(585): Client API: OpenGL ES
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): using (fd=22)
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): id =
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): xres = 320 px
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): yres = 480 px
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): xres_virtual = 320 px
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): yres_virtual = 960 px
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): bpp = 16
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): r = 11:5
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): g = 5:6
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): b = 0:5
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): width = 49 mm (165.877548 dpi)
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): height = 74 mm (164.756760 dpi)
06-13 00:11:22.193: INFO/EGLDisplaySurface(585): refresh rate = 60.00 Hz
06-13 00:11:22.533: WARN/HAL(585): load: module=/system/lib/hw/copybit.goldfish.so error=Cannot find library
06-13 00:11:22.543: WARN/HAL(585): load: module=/system/lib/hw/copybit.default.so error=Cannot find library
06-13 00:11:22.553: WARN/SurfaceFlinger(585): ro.sf.lcd_density not defined, using 160 dpi by default.
06-13 00:11:22.644: INFO/SurfaceFlinger(585): OpenGL informations:
06-13 00:11:22.654: INFO/SurfaceFlinger(585): vendor : Android
06-13 00:11:22.654: INFO/SurfaceFlinger(585): renderer : Android PixelFlinger 1.0
06-13 00:11:22.654: INFO/SurfaceFlinger(585): version : OpenGL ES-CM 1.0
06-13 00:11:22.654: INFO/SurfaceFlinger(585): extensions: GL_OES_byte_coordinates GL_OES_fixed_point GL_OES_single_precision GL_OES_read_format GL_OES_compressed_paletted_texture GL_OES_draw_texture GL_OES_matrix_get GL_OES_query_matrix GL_ARB_texture_compression GL_ARB_texture_non_power_of_two GL_ANDROID_direct_texture GL_ANDROID_user_clip_plane GL_ANDROID_vertex_buffer_object GL_ANDROID_generate_mipmap
06-13 00:11:22.673: WARN/HAL(585): load: module=/system/lib/hw/copybit.goldfish.so error=Cannot find library
06-13 00:11:22.683: WARN/HAL(585): load: module=/system/lib/hw/copybit.default.so error=Cannot find library
06-13 00:11:22.703: WARN/HAL(585): load: module=/system/lib/hw/overlay.goldfish.so error=Cannot find library
06-13 00:11:22.713: WARN/HAL(585): load: module=/system/lib/hw/overlay.default.so error=Cannot find library
06-13 00:11:23.663: INFO/sysproc(585): System server: starting Android runtime.
06-13 00:11:23.733: INFO/sysproc(585): System server: starting Android services.
06-13 00:11:23.953: INFO/SystemServer(585): Entered the Android system server!
06-13 00:11:24.303: INFO/sysproc(585): System server: entering thread pool.
06-13 00:11:24.763: ERROR/GLLogger(585): couldn't load library (Cannot find library)
06-13 00:11:25.893: INFO/ARMAssembler(585): generated scanline__00000077:03545404_00000A01_00000000 [ 30 ipp] (51 ins) at [0x18f708:0x18f7d4] in 72796961 ns
06-13 00:11:26.193: INFO/SystemServer(585): Starting Power Manager.
06-13 00:11:26.953: INFO/SystemServer(585): Starting Activity Manager.
06-13 00:11:31.733: INFO/SystemServer(585): Starting telephony registry
06-13 00:11:32.054: INFO/SystemServer(585): Starting Package Manager.
06-13 00:11:32.553: INFO/Installer(585): connecting...
06-13 00:11:32.914: INFO/installd(555): new connection
06-13 00:11:35.193: INFO/PackageManager(585): Got library android.awt in /system/framework/android.awt.jar
06-13 00:11:35.313: INFO/PackageManager(585): Got library android.test.runner in /system/framework/android.test.runner.jar
06-13 00:11:35.324: INFO/PackageManager(585): Got library com.android.im.plugin in /system/framework/com.android.im.plugin.jar
06-13 00:11:44.643: DEBUG/PackageManager(585): Scanning app dir /system/framework
06-13 00:11:49.513: DEBUG/PackageManager(585): Scanning app dir /system/app
06-13 00:11:51.493: DEBUG/dalvikvm(585): GC freed 6088 objects / 251280 bytes in 1237ms
06-13 00:12:27.497: DEBUG/dalvikvm(585): GC freed 3435 objects / 216088 bytes in 792ms
06-13 00:12:29.213: DEBUG/PackageManager(585): Scanning app dir /data/app
06-13 00:12:30.223: DEBUG/PackageManager(585): Scanning app dir /data/app-private
06-13 00:12:30.425: INFO/PackageManager(585): Time to scan packages: 47.319 seconds
06-13 00:12:30.703: WARN/PackageManager(585): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.providers.contacts
06-13 00:12:30.803: WARN/PackageManager(585): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.cp in package com.android.providers.contacts
06-13 00:12:30.853: WARN/PackageManager(585): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.development
06-13 00:12:30.913: WARN/PackageManager(585): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.ALL_SERVICES in package com.android.development
06-13 00:12:31.133: WARN/PackageManager(585): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.YouTubeUser in package com.android.development
06-13 00:12:31.143: WARN/PackageManager(585): Unknown permission com.google.android.googleapps.permission.ACCESS_GOOGLE_PASSWORD in package com.android.development
06-13 00:12:31.234: WARN/PackageManager(585): Unknown permission com.google.android.providers.gmail.permission.WRITE_GMAIL in package com.android.settings
06-13 00:12:31.254: WARN/PackageManager(585): Unknown permission com.google.android.providers.gmail.permission.READ_GMAIL in package com.android.settings
06-13 00:12:31.303: WARN/PackageManager(585): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.settings
06-13 00:12:31.683: WARN/PackageManager(585): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.browser
06-13 00:12:31.803: WARN/PackageManager(585): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.mail in package com.android.contacts
06-13 00:12:34.603: DEBUG/dalvikvm(585): GC freed 2851 objects / 161304 bytes in 845ms
06-13 00:12:35.403: INFO/SystemServer(585): Starting Content Manager.
06-13 00:12:39.954: WARN/ActivityManager(585): Unable to start service Intent { action=android.accounts.IAccountsService comp={com.google.android.googleapps/com.google.android.googleapps.GoogleLoginService} }: not found
06-13 00:12:40.063: WARN/AccountMonitor(585): Couldn't connect to Intent { action=android.accounts.IAccountsService comp={com.google.android.googleapps/com.google.android.googleapps.GoogleLoginService} } (Missing service?)
06-13 00:12:40.253: INFO/SystemServer(585): Starting System Content Providers.
06-13 00:12:40.553: INFO/ActivityThread(585): Publishing provider settings: com.android.providers.settings.SettingsProvider
06-13 00:12:41.433: INFO/ActivityThread(585): Publishing provider sync: android.content.SyncProvider
06-13 00:12:41.683: INFO/SystemServer(585): Starting Battery Service.
06-13 00:12:42.293: ERROR/BatteryService(585): Could not open '/sys/class/power_supply/usb/online'
06-13 00:12:42.433: ERROR/BatteryService(585): Could not open '/sys/class/power_supply/battery/batt_vol'
06-13 00:12:42.543: ERROR/BatteryService(585): Could not open '/sys/class/power_supply/battery/batt_temp'
06-13 00:12:42.933: INFO/SystemServer(585): Starting Hardware Service.
06-13 00:12:43.398: DEBUG/qemud(558): fdhandler_accept_event: accepting on fd 10
06-13 00:12:43.623: DEBUG/qemud(558): created client 0x10fd8 listening on fd 11
06-13 00:12:43.743: DEBUG/qemud(558): client_fd_receive: attempting registration for service 'hw-control'
06-13 00:12:43.873: DEBUG/qemud(558): client_fd_receive: - received channel id 2
06-13 00:15:20.695: WARN/SurfaceFlinger(585): executeScheduledBroadcasts() skipped, contention on the client. We'll try again later...