Uploaded image for project: 'Jenkins'
  1. Jenkins
  2. JENKINS-7354

Emulator interactions when building with configuration grid

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Closed (View Workflow)
    • Priority: Major
    • Resolution: Fixed
    • Labels:
      None
    • Environment:
      RHEL 5
      Tomcat 6.0.29
      Hudson 1.374
      ADK tools rev. 6
    • Similar Issues:

      Description

      There seem to be interactions between emulators when building on a configuration grid. I have a trivial Android app that I have been using to figure out various build issues, including using Hudson and the Android Plugin.

      I'm sorry that I don't have a completely repeatable sequence that might help in pin-pointing the behavior, but here is the configuration:

      I'm using Hudson's multi-build with two configuration axes: ADK_VER (1.5, 2.1) and SCREEN_RES (HVGA). I have the Android plugin running an emulator with the "Run emulator with properties" option.

      Android OS Version: ${ADK_VER}
      Screen density: 160
      Screen resolution: ${SCREEN_RES}
      Device locale: en_US
      SD card size:

      {blank}

      Reset emulator state at startup:

      {unchecked}
      Show emulator window: {unchecked}

      I'm getting fairly regular failures on one of the two builds that result (and more often the second). However, the failure is seldom exactly the same. One time, the build will be unable to connect to the emulator at the beginning and timeout. Another time, one of the builds will be unable to install the apk because it cannot communicate with the target emulator (toward the end of the build and after it had already established a connection with it for an earlier step).

      The problems don't seem to occur when the "Run each configuration sequentially" setting is checked.

        Attachments

          Activity

          Hide
          orrc Christopher Orr added a comment -

          Yeah.. I have the same apparent problems.

          Problem #1: "the build will be unable to connect to the emulator at the beginning and timeout":

          Is there any helpful info in the Console Output? Perhaps something like "the AVD image hudson_xyz is already in use"?

          There are definitely times where the emulator will not cleanly shut down. I haven't determined why this (inconsistently) happens, as the plugin merely asks Hudson to terminate the emulator process; nothing more complex than that.
          But it ends up having knock-on effects for later builds, as the AVD image remains locked.

          My current "solution" is to have a cron forcibly kill emulators that have been running for over 10 minutes.


          Problem #2: "the builds will be unable to install the apk because it cannot communicate"

          I've also seen this lately, but it's unclear why it happens.
          I did, however, find that always running with "Reset emulator state" checked seemed to cure it. I haven't investigated beyond that to figure out why that should be.


          The "Run each configuration sequentially" point is interesting, though generally I do manage to have at least two parallel builds running on one slave without problems. But thanks for the info.
          Running multiple emulators manually on a desktop is no problem, so really it should work for Hudson too...

          Are you running on a 32 or 64-bit machine, by the way?

          Show
          orrc Christopher Orr added a comment - Yeah.. I have the same apparent problems. Problem #1: "the build will be unable to connect to the emulator at the beginning and timeout": Is there any helpful info in the Console Output? Perhaps something like "the AVD image hudson_xyz is already in use"? There are definitely times where the emulator will not cleanly shut down. I haven't determined why this (inconsistently) happens, as the plugin merely asks Hudson to terminate the emulator process; nothing more complex than that. But it ends up having knock-on effects for later builds, as the AVD image remains locked. My current "solution" is to have a cron forcibly kill emulators that have been running for over 10 minutes. Problem #2: "the builds will be unable to install the apk because it cannot communicate" I've also seen this lately, but it's unclear why it happens. I did, however, find that always running with "Reset emulator state" checked seemed to cure it. I haven't investigated beyond that to figure out why that should be. The "Run each configuration sequentially" point is interesting, though generally I do manage to have at least two parallel builds running on one slave without problems. But thanks for the info. Running multiple emulators manually on a desktop is no problem, so really it should work for Hudson too... Are you running on a 32 or 64-bit machine, by the way?
          Hide
          jtharp jtharp added a comment -

          I'm running on a 64 bit machine (with 64 bit Java).

          Here was one of the errors
          ===================================================
          [echo] Installing /home/hudson/hudson/jobs/AndroidBuildTests/workspace/ADK_VER/2.1/SCREEN_RES/HVGA/test/bin/BuildTest-debug.apk...
          [echo] Executing "install" on the Android Debugging Bridge.
          [echo] Targeting device: localhost:44874...
          [exec] 75 KB/s (7608 bytes in 0.098s)
          [exec] pkg: /data/local/tmp/BuildTest-debug.apk
          [exec] Success
          [exec] - waiting for device -
          [exec] * daemon not running. starting it now *
          [exec] * daemon started successfully *
          [exec] error: unknown host service
          [exec] - waiting for device -
          [exec] error: unknown host service
          [exec] - waiting for device -
          [exec] error: unknown host service
          ... Many more identical lines cut out ...
          [exec] - waiting for device -
          [exec] error: unknown host service
          [exec] - waiting for device -
          $ /usr/android/tools/adb disconnect localhost:44874
          [android] Stopping Android emulator
          [android] Archiving emulator log
          Build was aborted
          Finished: ABORTED

          And the corresponding logcat file.
          ==============================================
          09-01 13:53:13.343 I/vold ( 26): Android Volume Daemon version 2.0
          09-01 13:53:13.373 I/DEBUG ( 27): debuggerd: May 6 2010 09:07:51
          09-01 13:53:13.533 D/qemud ( 34): entering main loop
          09-01 13:53:13.563 E/vold ( 26): Error opening switch name path '/sys/class/switch/test' (No such file or directory)
          09-01 13:53:13.563 E/vold ( 26): Error bootstrapping switch '/sys/class/switch/test' (No such file or directory)
          09-01 13:53:13.563 E/vold ( 26): Error opening switch name path '/sys/class/switch/test2' (No such file or directory)
          09-01 13:53:13.563 E/vold ( 26): Error bootstrapping switch '/sys/class/switch/test2' (No such file or directory)
          09-01 13:53:13.563 D/vold ( 26): Bootstrapping complete
          09-01 13:53:14.213 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10
          09-01 13:53:14.213 D/qemud ( 34): created client 0xe078 listening on fd 8
          09-01 13:53:14.233 D/qemud ( 34): client_fd_receive: attempting registration for service 'boot-properties'
          09-01 13:53:14.233 D/qemud ( 34): client_fd_receive: -> received channel id 1
          09-01 13:53:14.243 D/qemud ( 34): client_registration: registration succeeded for client 1
          09-01 13:53:14.243 I/qemu-props( 45): connected to 'boot-properties' qemud service.
          09-01 13:53:14.254 I/qemu-props( 45): received: qemu.sf.lcd_density=160
          09-01 13:53:14.254 I/qemu-props( 45): received: dalvik.vm.heapsize=16m
          09-01 13:53:14.254 I/qemu-props( 45): received: persist.sys.language=en
          09-01 13:53:14.263 I/qemu-props( 45): received: persist.sys.country=US
          09-01 13:53:14.793 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10
          09-01 13:53:14.793 D/qemud ( 34): created client 0xe078 listening on fd 11
          09-01 13:53:14.793 D/qemud ( 34): fdhandler_event: disconnect on fd 11
          09-01 13:53:14.823 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10
          09-01 13:53:14.823 D/qemud ( 34): created client 0xe078 listening on fd 11
          09-01 13:53:14.823 D/qemud ( 34): client_fd_receive: attempting registration for service 'gsm'
          09-01 13:53:14.823 D/qemud ( 34): client_fd_receive: -> received channel id 2
          09-01 13:53:14.833 D/qemud ( 34): client_registration: registration succeeded for client 2
          09-01 13:53:15.033 D/AndroidRuntime( 29):
          09-01 13:53:15.033 D/AndroidRuntime( 29): >>>>>>>>>>>>>> AndroidRuntime START <<<<<<<<<<<<<<
          09-01 13:53:15.033 D/AndroidRuntime( 29): CheckJNI is ON
          09-01 13:53:15.733 I/ ( 30): ServiceManager: 0xad08
          09-01 13:53:15.733 W/AudioHardwareInterface( 30): Using stubbed audio hardware. No sound will be produced.
          09-01 13:53:15.733 D/AudioHardwareInterface( 30): setMode(NORMAL)
          09-01 13:53:15.773 I/CameraService( 30): CameraService started: pid=30
          09-01 13:53:15.803 I/AudioFlinger( 30): AudioFlinger's thread 0xb370 ready to run
          09-01 13:53:15.953 D/AndroidRuntime( 29): — registering native functions —
          09-01 13:53:16.663 I/SamplingProfilerIntegration( 29): Profiler is disabled.
          09-01 13:53:16.753 I/Zygote ( 29): Preloading classes...
          09-01 13:53:16.773 D/dalvikvm( 29): GC freed 793 objects / 50568 bytes in 18ms
          09-01 13:53:16.933 D/dalvikvm( 29): GC freed 251 objects / 16168 bytes in 8ms
          09-01 13:53:17.093 D/dalvikvm( 29): GC freed 295 objects / 18768 bytes in 9ms
          09-01 13:53:17.184 D/dalvikvm( 29): GC freed 214 objects / 13712 bytes in 11ms
          09-01 13:53:17.433 D/dalvikvm( 29): GC freed 415 objects / 26544 bytes in 36ms
          09-01 13:53:17.683 D/skia ( 29): ------ build_power_table 1.4
          09-01 13:53:17.693 D/skia ( 29): ------ build_power_table 0.714286
          09-01 13:53:17.763 D/dalvikvm( 29): GC freed 418 objects / 28656 bytes in 13ms
          09-01 13:53:17.803 D/dalvikvm( 29): Trying to load lib /system/lib/libmedia_jni.so 0x0
          09-01 13:53:17.983 D/dalvikvm( 29): Added shared lib /system/lib/libmedia_jni.so 0x0
          09-01 13:53:17.993 D/dalvikvm( 29): Trying to load lib /system/lib/libexif.so 0x0
          09-01 13:53:18.003 D/dalvikvm( 29): Added shared lib /system/lib/libexif.so 0x0
          09-01 13:53:18.533 D/dalvikvm( 29): GC freed 2299 objects / 120896 bytes in 17ms
          09-01 13:53:18.533 D/dalvikvm( 29): Trying to load lib /system/lib/libmedia_jni.so 0x0
          09-01 13:53:18.533 D/dalvikvm( 29): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0
          09-01 13:53:18.543 D/dalvikvm( 29): Trying to load lib /system/lib/libmedia_jni.so 0x0
          09-01 13:53:18.543 D/dalvikvm( 29): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0
          09-01 13:53:18.543 D/dalvikvm( 29): Trying to load lib /system/lib/libmedia_jni.so 0x0
          09-01 13:53:18.543 D/dalvikvm( 29): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0
          09-01 13:53:18.543 D/dalvikvm( 29): Trying to load lib /system/lib/libmedia_jni.so 0x0
          09-01 13:53:18.543 D/dalvikvm( 29): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0
          09-01 13:53:19.224 D/dalvikvm( 29): GC freed 3790 objects / 197032 bytes in 31ms
          09-01 13:53:19.343 D/dalvikvm( 29): GC freed 461 objects / 26352 bytes in 26ms
          09-01 13:53:19.453 D/dalvikvm( 29): GC freed 294 objects / 17024 bytes in 29ms
          09-01 13:53:19.543 D/dalvikvm( 29): GC freed 204 objects / 11416 bytes in 31ms
          09-01 13:53:19.613 D/dalvikvm( 29): GC freed 178 objects / 9560 bytes in 32ms
          09-01 13:53:19.643 D/dalvikvm( 29): Trying to load lib /system/lib/libsrec_jni.so 0x0
          09-01 13:53:19.663 D/dalvikvm( 29): Added shared lib /system/lib/libsrec_jni.so 0x0
          09-01 13:53:19.673 D/dalvikvm( 29): Trying to load lib /system/lib/libsrec_jni.so 0x0
          09-01 13:53:19.673 D/dalvikvm( 29): Shared lib '/system/lib/libsrec_jni.so' already loaded in same CL 0x0
          09-01 13:53:20.033 D/dalvikvm( 29): GC freed 296 objects / 34696 bytes in 33ms
          09-01 13:53:20.373 D/dalvikvm( 29): GC freed 787 objects / 47976 bytes in 48ms
          09-01 13:53:20.543 D/dalvikvm( 29): GC freed 337 objects / 38440 bytes in 45ms
          09-01 13:53:20.723 D/dalvikvm( 29): GC freed 418 objects / 25840 bytes in 49ms
          09-01 13:53:20.823 D/dalvikvm( 29): Trying to load lib /system/lib/libwebcore.so 0x0
          09-01 13:53:20.953 D/dalvikvm( 29): Added shared lib /system/lib/libwebcore.so 0x0
          09-01 13:53:21.213 D/dalvikvm( 29): GC freed 432 objects / 25136 bytes in 47ms
          09-01 13:53:21.433 D/dalvikvm( 29): GC freed 489 objects / 30736 bytes in 47ms
          09-01 13:53:21.613 D/dalvikvm( 29): GC freed 349 objects / 28968 bytes in 53ms
          09-01 13:53:21.923 D/dalvikvm( 29): GC freed 309 objects / 21888 bytes in 104ms
          09-01 13:53:24.953 D/dalvikvm( 29): GC freed 915 objects / 80200 bytes in 56ms
          09-01 13:53:25.113 D/dalvikvm( 29): GC freed 664 objects / 35848 bytes in 55ms
          09-01 13:53:25.273 D/dalvikvm( 29): GC freed 709 objects / 41208 bytes in 57ms
          09-01 13:53:25.433 D/dalvikvm( 29): GC freed 735 objects / 40648 bytes in 57ms
          09-01 13:53:27.113 D/dalvikvm( 29): GC freed 14031 objects / 475104 bytes in 77ms
          09-01 13:53:27.613 D/dalvikvm( 29): GC freed 10450 objects / 447872 bytes in 81ms
          09-01 13:53:28.193 D/dalvikvm( 29): GC freed 10899 objects / 472632 bytes in 84ms
          09-01 13:53:28.983 D/dalvikvm( 29): GC freed 8111 objects / 461600 bytes in 83ms
          09-01 13:53:30.003 D/dalvikvm( 29): GC freed 8196 objects / 460616 bytes in 83ms
          09-01 13:53:31.243 D/dalvikvm( 29): GC freed 8498 objects / 516672 bytes in 151ms
          09-01 13:53:32.324 D/dalvikvm( 29): GC freed 6409 objects / 390256 bytes in 114ms
          09-01 13:53:33.544 D/dalvikvm( 29): GC freed 847 objects / 51480 bytes in 93ms
          09-01 13:53:33.713 D/dalvikvm( 29): GC freed 540 objects / 39192 bytes in 89ms
          09-01 13:53:33.893 D/dalvikvm( 29): GC freed 460 objects / 31544 bytes in 81ms
          09-01 13:53:34.084 D/dalvikvm( 29): GC freed 426 objects / 29928 bytes in 106ms
          09-01 13:53:34.124 I/Zygote ( 29): ...preloaded 1942 classes in 17371ms.
          09-01 13:53:34.233 D/dalvikvm( 29): GC freed 117 objects / 14864 bytes in 94ms
          09-01 13:53:34.244 I/Zygote ( 29): Preloading resources...
          09-01 13:53:34.364 D/dalvikvm( 29): GC freed 5 objects / 224 bytes in 92ms
          09-01 13:53:34.393 W/Zygote ( 29): Preloaded drawable resource #0x1080093 (res/drawable-mdpi/sym_def_app_icon.png) that varies with configuration!!
          09-01 13:53:34.404 W/Zygote ( 29): Preloaded drawable resource #0x1080002 (res/drawable-mdpi/arrow_down_float.png) that varies with configuration!!
          09-01 13:53:34.493 W/Zygote ( 29): Preloaded drawable resource #0x10800b4 (res/drawable/btn_check.xml) that varies with configuration!!
          09-01 13:53:34.514 W/Zygote ( 29): Preloaded drawable resource #0x10800b7 (res/drawable-mdpi/btn_check_label_background.9.png) that varies with configuration!!
          09-01 13:53:34.523 W/Zygote ( 29): Preloaded drawable resource #0x10800b8 (res/drawable-mdpi/btn_check_off.png) that varies with configuration!!
          09-01 13:53:34.523 W/Zygote ( 29): Preloaded drawable resource #0x10800bd (res/drawable-mdpi/btn_check_on.png) that varies with configuration!!
          09-01 13:53:34.584 W/Zygote ( 29): Preloaded drawable resource #0x1080004 (res/drawable/btn_default.xml) that varies with configuration!!
          09-01 13:53:34.623 W/Zygote ( 29): Preloaded drawable resource #0x1080005 (res/drawable/btn_default_small.xml) that varies with configuration!!
          09-01 13:53:34.673 W/Zygote ( 29): Preloaded drawable resource #0x1080006 (res/drawable/btn_dropdown.xml) that varies with configuration!!
          09-01 13:53:34.743 W/Zygote ( 29): Preloaded drawable resource #0x1080008 (res/drawable/btn_plus.xml) that varies with configuration!!
          09-01 13:53:34.823 W/Zygote ( 29): Preloaded drawable resource #0x1080007 (res/drawable/btn_minus.xml) that varies with configuration!!
          09-01 13:53:34.897 W/Zygote ( 29): Preloaded drawable resource #0x1080009 (res/drawable/btn_radio.xml) that varies with configuration!!
          09-01 13:53:34.983 W/Zygote ( 29): Preloaded drawable resource #0x108000a (res/drawable/btn_star.xml) that varies with configuration!!
          09-01 13:53:35.073 D/dalvikvm( 29): GC freed 396 objects / 23992 bytes in 85ms
          09-01 13:53:35.103 W/Zygote ( 29): Preloaded drawable resource #0x1080125 (res/drawable/btn_toggle.xml) that varies with configuration!!
          09-01 13:53:35.123 W/Zygote ( 29): Preloaded drawable resource #0x1080187 (res/drawable-mdpi/ic_emergency.png) that varies with configuration!!
          09-01 13:53:35.133 W/Zygote ( 29): Preloaded drawable resource #0x1080012 (res/drawable-mdpi/divider_horizontal_bright.9.png) that varies with configuration!!
          09-01 13:53:35.133 W/Zygote ( 29): Preloaded drawable resource #0x1080014 (res/drawable-mdpi/divider_horizontal_dark.9.png) that varies with configuration!!
          09-01 13:53:35.193 W/Zygote ( 29): Preloaded drawable resource #0x1080016 (res/drawable/edit_text.xml) that varies with configuration!!
          09-01 13:53:35.214 W/Zygote ( 29): Preloaded drawable resource #0x1080161 (res/drawable/expander_group.xml) that varies with configuration!!
          09-01 13:53:35.274 W/Zygote ( 29): Preloaded drawable resource #0x1080062 (res/drawable/list_selector_background.xml) that varies with configuration!!
          09-01 13:53:35.294 W/Zygote ( 29): Preloaded drawable resource #0x1080217 (res/drawable-mdpi/menu_background.9.png) that varies with configuration!!
          09-01 13:53:35.304 W/Zygote ( 29): Preloaded drawable resource #0x1080218 (res/drawable-mdpi/menu_background_fill_parent_width.9.png) that varies with configuration!!
          09-01 13:53:35.354 W/Zygote ( 29): Preloaded drawable resource #0x1080219 (res/drawable/menu_selector.xml) that varies with configuration!!
          09-01 13:53:35.364 W/Zygote ( 29): Preloaded drawable resource #0x1080224 (res/drawable-mdpi/panel_background.9.png) that varies with configuration!!
          09-01 13:53:35.384 W/Zygote ( 29): Preloaded drawable resource #0x108022e (res/drawable-mdpi/popup_bottom_bright.9.png) that varies with configuration!!
          09-01 13:53:35.394 W/Zygote ( 29): Preloaded drawable resource #0x108022f (res/drawable-mdpi/popup_bottom_dark.9.png) that varies with configuration!!
          09-01 13:53:35.414 W/Zygote ( 29): Preloaded drawable resource #0x1080230 (res/drawable-mdpi/popup_bottom_medium.9.png) that varies with configuration!!
          09-01 13:53:35.424 W/Zygote ( 29): Preloaded drawable resource #0x1080231 (res/drawable-mdpi/popup_center_bright.9.png) that varies with configuration!!
          09-01 13:53:35.434 W/Zygote ( 29): Preloaded drawable resource #0x1080232 (res/drawable-mdpi/popup_center_dark.9.png) that varies with configuration!!
          09-01 13:53:35.454 W/Zygote ( 29): Preloaded drawable resource #0x1080235 (res/drawable-mdpi/popup_full_dark.9.png) that varies with configuration!!
          09-01 13:53:35.474 W/Zygote ( 29): Preloaded drawable resource #0x1080238 (res/drawable-mdpi/popup_top_bright.9.png) that varies with configuration!!
          09-01 13:53:35.484 W/Zygote ( 29): Preloaded drawable resource #0x1080239 (res/drawable-mdpi/popup_top_dark.9.png) that varies with configuration!!
          09-01 13:53:35.524 W/Zygote ( 29): Preloaded drawable resource #0x108006d (res/drawable/progress_indeterminate_horizontal.xml) that varies with configuration!!
          09-01 13:53:35.543 W/Zygote ( 29): Preloaded drawable resource #0x108023f (res/drawable/progress_small.xml) that varies with configuration!!
          09-01 13:53:35.553 W/Zygote ( 29): Preloaded drawable resource #0x1080240 (res/drawable/progress_small_titlebar.xml) that varies with configuration!!
          09-01 13:53:35.654 D/dalvikvm( 29): GC freed 452 objects / 31816 bytes in 97ms
          09-01 13:53:35.684 W/Zygote ( 29): Preloaded drawable resource #0x1080262 (res/drawable-mdpi/scrollbar_handle_horizontal.9.png) that varies with configuration!!
          09-01 13:53:35.694 W/Zygote ( 29): Preloaded drawable resource #0x1080263 (res/drawable-mdpi/scrollbar_handle_vertical.9.png) that varies with configuration!!
          09-01 13:53:35.724 W/Zygote ( 29): Preloaded drawable resource #0x1080071 (res/drawable/spinner_dropdown_background.xml) that varies with configuration!!
          09-01 13:53:35.744 W/Zygote ( 29): Preloaded drawable resource #0x1080326 (res/drawable-mdpi/title_bar_shadow.9.png) that varies with configuration!!
          09-01 13:53:35.764 W/Zygote ( 29): Preloaded drawable resource #0x10801c6 (res/drawable-mdpi/indicator_code_lock_drag_direction_green_up.png) that varies with configuration!!
          09-01 13:53:35.774 W/Zygote ( 29): Preloaded drawable resource #0x10801c7 (res/drawable-mdpi/indicator_code_lock_drag_direction_red_up.png) that varies with configuration!!
          09-01 13:53:35.874 D/dalvikvm( 29): GC freed 207 objects / 11648 bytes in 92ms
          09-01 13:53:35.894 W/Zygote ( 29): Preloaded drawable resource #0x10801c8 (res/drawable-mdpi/indicator_code_lock_point_area_default.png) that varies with configuration!!
          09-01 13:53:35.924 W/Zygote ( 29): Preloaded drawable resource #0x10801c9 (res/drawable-mdpi/indicator_code_lock_point_area_green.png) that varies with configuration!!
          09-01 13:53:35.943 W/Zygote ( 29): Preloaded drawable resource #0x10801ca (res/drawable-mdpi/indicator_code_lock_point_area_red.png) that varies with configuration!!
          09-01 13:53:35.954 I/Zygote ( 29): ...preloaded 48 resources in 1705ms.
          09-01 13:53:35.994 I/Zygote ( 29): ...preloaded 15 resources in 43ms.
          09-01 13:53:36.094 D/dalvikvm( 29): GC freed 142 objects / 11904 bytes in 93ms
          09-01 13:53:36.204 D/dalvikvm( 29): GC freed 127 objects / 5104 bytes in 97ms
          09-01 13:53:36.304 D/dalvikvm( 29): GC freed 14 objects / 496 bytes in 91ms
          09-01 13:53:36.324 I/dalvikvm( 29): Splitting out new zygote heap
          09-01 13:53:36.354 I/dalvikvm( 29): System server process 60 has been created
          09-01 13:53:36.364 I/Zygote ( 29): Accepting command socket connections
          09-01 13:53:36.614 D/dalvikvm( 60): Trying to load lib /system/lib/libandroid_servers.so 0x0
          09-01 13:53:36.984 D/dalvikvm( 60): Added shared lib /system/lib/libandroid_servers.so 0x0
          09-01 13:53:37.024 E/BatteryService( 60): usbOnlinePath not found
          09-01 13:53:37.024 E/BatteryService( 60): batteryVoltagePath not found
          09-01 13:53:37.034 E/BatteryService( 60): batteryTemperaturePath not found
          09-01 13:53:37.054 I/sysproc ( 60): Entered system_init()
          09-01 13:53:37.064 I/sysproc ( 60): ServiceManager: 0x11c6b8
          09-01 13:53:37.074 I/SurfaceFlinger( 60): SurfaceFlinger is starting
          09-01 13:53:37.084 I/SurfaceFlinger( 60): SurfaceFlinger's main thread ready to run. Initializing graphics H/W...
          09-01 13:53:37.104 E/SurfaceFlinger( 60): Couldn't open /sys/power/wait_for_fb_sleep or /sys/power/wait_for_fb_wake
          09-01 13:53:37.144 I/gralloc ( 60): using (fd=22)
          09-01 13:53:37.144 I/gralloc ( 60): id =
          09-01 13:53:37.144 I/gralloc ( 60): xres = 320 px
          09-01 13:53:37.144 I/gralloc ( 60): yres = 480 px
          09-01 13:53:37.144 I/gralloc ( 60): xres_virtual = 320 px
          09-01 13:53:37.144 I/gralloc ( 60): yres_virtual = 960 px
          09-01 13:53:37.144 I/gralloc ( 60): bpp = 16
          09-01 13:53:37.144 I/gralloc ( 60): r = 11:5
          09-01 13:53:37.144 I/gralloc ( 60): g = 5:6
          09-01 13:53:37.144 I/gralloc ( 60): b = 0:5
          09-01 13:53:37.154 I/gralloc ( 60): width = 49 mm (165.877548 dpi)
          09-01 13:53:37.154 I/gralloc ( 60): height = 74 mm (164.756760 dpi)
          09-01 13:53:37.154 I/gralloc ( 60): refresh rate = 60.00 Hz
          09-01 13:53:37.174 D/libEGL ( 60): egl.cfg not found, using default config
          09-01 13:53:37.183 D/libEGL ( 60): loaded /system/lib/egl/libGLES_android.so
          09-01 13:53:37.223 I/SurfaceFlinger( 60): EGL informations:
          09-01 13:53:37.223 I/SurfaceFlinger( 60): # of configs : 8
          09-01 13:53:37.223 I/SurfaceFlinger( 60): vendor : Android
          09-01 13:53:37.233 I/SurfaceFlinger( 60): version : 1.4 Android META-EGL
          09-01 13:53:37.233 I/SurfaceFlinger( 60): extensions: EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_ANDROID_image_native_buffer EGL_ANDROID_swap_rectangle EGL_ANDROID_get_render_buffer
          09-01 13:53:37.233 I/SurfaceFlinger( 60): Client API: OpenGL ES
          09-01 13:53:37.243 I/SurfaceFlinger( 60): EGLSurface: 5-6-5-0, config=0x1000000
          09-01 13:53:37.243 I/SurfaceFlinger( 60): flags : 001c0000
          09-01 13:53:37.264 I/SurfaceFlinger( 60): OpenGL informations:
          09-01 13:53:37.264 I/SurfaceFlinger( 60): vendor : Android
          09-01 13:53:37.274 I/SurfaceFlinger( 60): renderer : Android PixelFlinger 1.2
          09-01 13:53:37.274 I/SurfaceFlinger( 60): version : OpenGL ES-CM 1.0
          09-01 13:53:37.274 I/SurfaceFlinger( 60): 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_OES_EGL_image GL_ARB_texture_compression GL_ARB_texture_non_power_of_two GL_ANDROID_user_clip_plane GL_ANDROID_vertex_buffer_object GL_ANDROID_generate_mipmap
          09-01 13:53:37.304 I/sysproc ( 60): System server: starting Android runtime.
          09-01 13:53:37.304 I/sysproc ( 60): System server: starting Android services.
          09-01 13:53:37.304 I/SystemServer( 60): Entered the Android system server!
          09-01 13:53:37.314 I/sysproc ( 60): System server: entering thread pool.
          09-01 13:53:37.434 I/SystemServer( 60): Entropy Service
          09-01 13:53:37.544 I/SystemServer( 60): Power Manager
          09-01 13:53:37.613 I/SystemServer( 60): Activity Manager
          09-01 13:53:37.653 I/ActivityManager( 60): Memory class: 16
          09-01 13:53:37.784 W/UsageStats( 60): Usage stats version changed; dropping
          09-01 13:53:37.974 I/BootAnimation( 70): boot animation disabled
          09-01 13:53:38.054 I/SystemServer( 60): Telephony Registry
          09-01 13:53:38.073 I/SystemServer( 60): Package Manager
          09-01 13:53:38.093 I/Installer( 60): connecting...
          09-01 13:53:38.104 I/installd( 31): new connection
          09-01 13:53:38.294 I/PackageManager( 60): Libs: android.test.runner:/system/framework/android.test.runner.jar javax.obex:/system/framework/javax.obex.jar
          09-01 13:53:38.294 I/PackageManager( 60): Features: android.hardware.camera android.hardware.wifi android.hardware.location.network android.hardware.bluetooth android.hardware.location android.hardware.location.gps android.hardware.camera.autofocus android.hardware.touchscreen android.hardware.sensor.accelerometer android.hardware.sensor.compass
          09-01 13:53:39.023 D/dalvikvm( 60): GC freed 6101 objects / 265520 bytes in 217ms
          09-01 13:53:39.093 W/PackageManager( 60): Running ENG build: no pre-dexopt!
          09-01 13:53:39.243 D/PackageManager( 60): Scanning app dir /system/framework
          09-01 13:53:39.883 D/PackageManager( 60): Scanning app dir /system/app
          09-01 13:53:42.353 D/dalvikvm( 60): GC freed 4441 objects / 282232 bytes in 159ms
          09-01 13:53:43.673 D/PackageManager( 60): Scanning app dir /data/app
          09-01 13:53:43.703 I/PackageParser( 60): com.att.nismob.android.build: compat added android.permission.WRITE_EXTERNAL_STORAGE android.permission.READ_PHONE_STATE
          09-01 13:53:43.723 I/PackageParser( 60): com.att.nismob.android.test: compat added android.permission.WRITE_EXTERNAL_STORAGE android.permission.READ_PHONE_STATE
          09-01 13:53:43.733 D/PackageManager( 60): Scanning app dir /data/app-private
          09-01 13:53:43.743 I/PackageManager( 60): Time to scan packages: 4.651 seconds
          09-01 13:53:43.753 W/PackageManager( 60): Unknown permission com.google.android.providers.gmail.permission.WRITE_GMAIL in package com.android.settings
          09-01 13:53:43.753 W/PackageManager( 60): Unknown permission com.google.android.providers.gmail.permission.READ_GMAIL in package com.android.settings
          09-01 13:53:43.763 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.settings
          09-01 13:53:43.773 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.providers.contacts
          09-01 13:53:43.773 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.cp in package com.android.providers.contacts
          09-01 13:53:43.773 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.mail in package com.android.contacts
          09-01 13:53:43.783 W/PackageManager( 60): Unknown permission android.permission.ADD_SYSTEM_SERVICE in package com.android.phone
          09-01 13:53:43.793 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.development
          09-01 13:53:43.793 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.ALL_SERVICES in package com.android.development
          09-01 13:53:43.793 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.YouTubeUser in package com.android.development
          09-01 13:53:43.803 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.ACCESS_GOOGLE_PASSWORD in package com.android.development
          09-01 13:53:43.803 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.browser
          09-01 13:53:43.963 D/dalvikvm( 60): GC freed 5225 objects / 299976 bytes in 137ms
          09-01 13:53:44.273 D/dalvikvm( 60): GC freed 2676 objects / 179952 bytes in 131ms
          09-01 13:53:44.313 I/SystemServer( 60): Account Manager
          09-01 13:53:44.343 W/ResourceType( 60): Resources don't contain package for resource number 0x7f0700e5
          09-01 13:53:44.353 W/ResourceType( 60): Resources don't contain package for resource number 0x7f020031
          09-01 13:53:44.353 W/ResourceType( 60): Resources don't contain package for resource number 0x7f020030
          09-01 13:53:44.353 W/ResourceType( 60): Resources don't contain package for resource number 0x7f050000
          09-01 13:53:44.383 I/SystemServer( 60): Content Manager
          09-01 13:53:44.403 I/SyncManager( 60): No initial accounts
          09-01 13:53:44.413 I/SyncManager( 60): No initial status
          09-01 13:53:44.413 I/SyncManager( 60): No initial pending operations
          09-01 13:53:44.423 I/SyncManager( 60): No initial statistics
          09-01 13:53:44.643 I/SystemServer( 60): System Content Providers
          09-01 13:53:44.653 I/ActivityThread( 60): Publishing provider settings: com.android.providers.settings.SettingsProvider
          09-01 13:53:44.693 I/SystemServer( 60): Battery Service
          09-01 13:53:44.733 I/SystemServer( 60): Hardware Service
          09-01 13:53:44.743 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10
          09-01 13:53:44.743 D/qemud ( 34): created client 0x11fb8 listening on fd 12
          09-01 13:53:44.743 D/qemud ( 34): client_fd_receive: attempting registration for service 'hw-control'
          09-01 13:53:44.743 D/qemud ( 34): client_fd_receive: -> received channel id 3
          09-01 13:53:44.754 D/qemud ( 34): client_registration: registration succeeded for client 3
          09-01 13:53:44.923 I/SystemServer( 60): Alarm Manager
          09-01 13:53:44.943 I/SystemServer( 60): Init Watchdog
          09-01 13:53:44.953 I/SystemServer( 60): Sensor Service
          09-01 13:53:44.963 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10
          09-01 13:53:44.963 D/qemud ( 34): created client 0xc038 listening on fd 13
          09-01 13:53:44.973 D/qemud ( 34): client_fd_receive: attempting registration for service 'sensors'
          09-01 13:53:44.973 D/qemud ( 34): client_fd_receive: -> received channel id 4
          09-01 13:53:45.075 D/qemud ( 34): client_registration: registration succeeded for client 4
          09-01 13:53:45.197 D/qemud ( 34): fdhandler_event: disconnect on fd 13
          09-01 13:53:45.313 I/SystemServer( 60): Window Manager
          09-01 13:53:45.735 I/EventHub( 60): New keyboard: publicID=65536 device->id=0x10000 devname='qwerty2' propName='hw.keyboards.65536.devname' keylayout='/system/usr/keylayout/qwerty.kl'
          09-01 13:53:45.735 I/EventHub( 60): New device: path=/dev/input/event0 name=qwerty2 id=0x10000 (of 0x1) index=1 fd=45 classes=0x2f
          09-01 13:53:45.778 E/EventHub( 60): could not get driver version for /dev/input/mouse0, Not a typewriter
          09-01 13:53:45.778 E/EventHub( 60): could not get driver version for /dev/input/mice, Not a typewriter
          09-01 13:53:45.778 I/KeyInputQueue( 60): Device added: id=0x0, name=qwerty2, classes=2f
          09-01 13:53:45.778 I/KeyInputQueue( 60): X: unknown values
          09-01 13:53:45.778 I/KeyInputQueue( 60): Y: unknown values
          09-01 13:53:45.778 I/KeyInputQueue( 60): Pressure: unknown values
          09-01 13:53:45.821 I/KeyInputQueue( 60): Size: unknown values
          09-01 13:53:45.821 I/KeyInputQueue( 60): No virtual keys found
          09-01 13:53:46.654 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10
          09-01 13:53:46.654 D/qemud ( 34): created client 0xc038 listening on fd 13
          09-01 13:53:46.699 D/qemud ( 34): client_fd_receive: attempting registration for service 'sensors'
          09-01 13:53:46.699 D/qemud ( 34): client_fd_receive: -> received channel id 5
          09-01 13:53:46.865 D/qemud ( 34): client_registration: registration succeeded for client 5
          09-01 13:53:46.923 D/qemud ( 34): fdhandler_event: disconnect on fd 13
          09-01 13:53:46.923 D/SensorManager( 60): found sensor: Goldfish 3-axis Accelerometer, handle=0
          09-01 13:53:47.127 I/SystemServer( 60): Registering null Bluetooth Service (emulator)
          09-01 13:53:47.127 E/System ( 60): Failure starting core service
          09-01 13:53:47.127 E/System ( 60): java.lang.SecurityException
          09-01 13:53:47.127 E/System ( 60): at android.os.BinderProxy.transact(Native Method)
          09-01 13:53:47.127 E/System ( 60): at android.os.ServiceManagerProxy.addService(ServiceManagerNative.java:146)
          09-01 13:53:47.127 E/System ( 60): at android.os.ServiceManager.addService(ServiceManager.java:72)
          09-01 13:53:47.127 E/System ( 60): at com.android.server.ServerThread.run(SystemServer.java:176)
          09-01 13:53:47.169 E/AndroidRuntime( 60): Crash logging skipped, no checkin service
          09-01 13:53:47.169 I/SystemServer( 60): Status Bar
          09-01 13:53:47.875 I/SystemServer( 60): Clipboard Service
          09-01 13:53:47.875 I/SystemServer( 60): Input Method Service
          09-01 13:53:47.918 W/ResourceType( 60): Resources don't contain package for resource number 0x7f060000
          09-01 13:53:47.961 W/ResourceType( 60): Resources don't contain package for resource number 0x7f060001
          09-01 13:53:47.961 I/InputManagerService( 60): Enabled input methods: com.android.inputmethod.pinyin/.PinyinIME:com.android.inputmethod.latin/.LatinIME:jp.co.omronsoft.openwnn/.OpenWnnJAJP
          09-01 13:53:48.047 I/SystemServer( 60): NetStat Service
          09-01 13:53:48.047 I/SystemServer( 60): Connectivity Service
          09-01 13:53:48.090 V/ConnectivityService( 60): ConnectivityService starting up
          09-01 13:53:48.090 V/ConnectivityService( 60): Starting Wifi Service.
          09-01 13:53:48.219 I/WifiService( 60): WifiService starting up with Wi-Fi disabled
          09-01 13:53:48.261 I/SystemServer( 60): Accessibility Manager
          09-01 13:53:48.305 I/SystemServer( 60): Notification Manager
          09-01 13:53:48.348 I/SystemServer( 60): Mount Service
          09-01 13:53:48.391 I/SystemServer( 60): Device Storage Monitor
          09-01 13:53:48.433 I/SystemServer( 60): Location Manager
          09-01 13:53:48.433 I/SystemServer( 60): Search Service
          09-01 13:53:48.477 I/SystemServer( 60): Checkin Service
          09-01 13:53:48.477 D/libhardware_legacy( 60): using QEMU GPS Hardware emulation
          09-01 13:53:48.477 W/ActivityManager( 60): Unable to start service Intent

          { cmp=com.google.android.server.checkin/.CheckinService }

          : not found
          09-01 13:53:48.477 W/SystemServer( 60): Using fallback Checkin Service.
          09-01 13:53:48.477 I/SystemServer( 60): Wallpaper Service
          09-01 13:53:48.520 I/SystemServer( 60): Audio Service
          09-01 13:53:48.562 W/GpsLocationProvider( 60): Could not open GPS configuration file /etc/gps.conf
          09-01 13:53:48.907 D/GpsLocationProvider( 60): enable
          09-01 13:53:48.995 D/dalvikvm( 60): Trying to load lib /system/lib/libsoundpool.so 0x0
          09-01 13:53:49.038 D/dalvikvm( 60): Added shared lib /system/lib/libsoundpool.so 0x0
          09-01 13:53:49.038 D/SoundPool( 60): error loading /system/media/audio/ui/Effect_Tick.ogg
          09-01 13:53:49.038 W/AudioService( 60): Soundpool could not load file: /system/media/audio/ui/Effect_Tick.ogg
          09-01 13:53:49.038 D/SoundPool( 60): error loading /system/media/audio/ui/KeypressStandard.ogg
          09-01 13:53:49.038 W/AudioService( 60): Soundpool could not load file: /system/media/audio/ui/KeypressStandard.ogg
          09-01 13:53:49.081 D/SoundPool( 60): error loading /system/media/audio/ui/KeypressSpacebar.ogg
          09-01 13:53:49.081 W/AudioService( 60): Soundpool could not load file: /system/media/audio/ui/KeypressSpacebar.ogg
          09-01 13:53:49.081 D/SoundPool( 60): error loading /system/media/audio/ui/KeypressDelete.ogg
          09-01 13:53:49.081 W/AudioService( 60): Soundpool could not load file: /system/media/audio/ui/KeypressDelete.ogg
          09-01 13:53:49.081 D/SoundPool( 60): error loading /system/media/audio/ui/KeypressReturn.ogg
          09-01 13:53:49.081 W/AudioService( 60): Soundpool could not load file: /system/media/audio/ui/KeypressReturn.ogg
          09-01 13:53:49.081 I/SystemServer( 60): Headset Observer
          09-01 13:53:49.123 W/HeadsetObserver( 60): This kernel does not have wired headset support
          09-01 13:53:49.123 I/SystemServer( 60): Dock Observer
          09-01 13:53:49.123 W/DockObserver( 60): This kernel does not have dock station support
          09-01 13:53:49.123 I/SystemServer( 60): Backup Service
          09-01 13:53:49.339 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10
          09-01 13:53:49.339 D/qemud ( 34): created client 0xc038 listening on fd 13
          09-01 13:53:49.382 D/dalvikvm( 60): GC freed 3406 objects / 195768 bytes in 171ms
          09-01 13:53:49.468 D/qemud ( 34): client_fd_receive: attempting registration for service 'gps'
          09-01 13:53:49.468 D/qemud ( 34): client_fd_receive: -> received channel id 6
          09-01 13:53:49.468 W/ActivityManager( 60): Unable to start service Intent

          { cmp=com.google.android.backup/.BackupTransportService }

          : not found
          09-01 13:53:49.468 I/BackupManagerService( 60): Found stale backup journal, scheduling:
          09-01 13:53:49.468 I/BackupManagerService( 60): + android
          09-01 13:53:49.468 I/BackupManagerService( 60): + com.android.inputmethod.latin
          09-01 13:53:49.510 D/qemud ( 34): client_registration: registration succeeded for client 6
          09-01 13:53:49.510 I/BackupManagerService( 60): + com.android.browser
          09-01 13:53:49.510 I/BackupManagerService( 60): + com.android.providers.userdictionary
          09-01 13:53:49.510 I/BackupManagerService( 60): + com.android.providers.settings
          09-01 13:53:49.553 I/BackupManagerService( 60): Backup enabled => false
          09-01 13:53:49.595 I/SystemServer( 60): AppWidget Service
          09-01 13:53:49.729 I/WindowManager( 60): SAFE MODE not enabled
          09-01 13:53:49.773 I/ActivityManager( 60): Config changed:

          { scale=1.0 imsi=0/0 loc=en_US touch=3 keys=2/1/1 nav=3/1 orien=1 layout=18}

          09-01 13:53:49.773 D/PowerManagerService( 60): system ready!
          09-01 13:53:49.815 I/ActivityManager( 60): System now ready
          09-01 13:53:49.900 I/SystemServer( 60): Making services ready
          09-01 13:53:49.986 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030000
          09-01 13:53:50.030 D/GpsLocationProvider( 60): GpsEventThread starting
          09-01 13:53:50.117 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030003
          09-01 13:53:50.117 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030034
          09-01 13:53:50.159 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030012
          09-01 13:53:50.288 I/Zygote ( 60): Process: zygote socket opened
          09-01 13:53:50.331 I/ActivityManager( 60): Start proc com.android.inputmethod.pinyin for service com.android.inputmethod.pinyin/.PinyinIME: pid=100 uid=10007 gids=

          {3003, 1015}
          09-01 13:53:50.547 I/ActivityManager( 60): Start proc com.android.phone for added application com.android.phone: pid=102 uid=1001 gids={3002, 3001, 3003, 1015}
          09-01 13:53:50.547 I/ActivityManager( 60): Starting activity: Intent { act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10000000 cmp=com.android.launcher/.Launcher }
          09-01 13:53:50.632 D/GpsLocationProvider( 60): NetworkThread starting
          09-01 13:53:50.632 D/GpsLocationProvider( 60): NetworkThread wait for network
          09-01 13:53:50.893 I/ActivityManager( 60): Start proc android.process.acore for activity com.android.launcher/.Launcher: pid=106 uid=10007 gids={3003, 1015}

          09-01 13:53:52.066 D/dalvikvm( 60): GC freed 3262 objects / 177816 bytes in 481ms
          09-01 13:53:53.169 W/StatusBar( 60): No icon ID for slot ime
          09-01 13:53:53.555 I/ActivityManager( 60): Start proc com.android.settings for broadcast com.android.settings/.widget.SettingsAppWidgetProvider: pid=120 uid=1000 gids=

          {3002, 3001, 3003}

          09-01 13:53:55.194 D/dalvikvm( 100): Trying to load lib /system/lib/libjni_pinyinime.so 0x43b86298
          09-01 13:53:55.194 D/dalvikvm( 100): Added shared lib /system/lib/libjni_pinyinime.so 0x43b86298
          09-01 13:53:55.280 I/ActivityThread( 106): Publishing provider com.android.globalsearch.stats: com.android.globalsearch.StatsProvider
          09-01 13:53:55.366 I/ARMAssembler( 60): generated scanline__00000177:03515104_00000A01_00000000 [ 55 ipp] (79 ins) at [0x3106c8:0x310804] in 14671000 ns
          09-01 13:53:56.525 I/ActivityThread( 102): Publishing provider icc: com.android.phone.IccProvider
          09-01 13:53:56.831 I/ActivityThread( 106): Publishing provider com.android.launcher.settings: com.android.launcher.LauncherProvider
          09-01 13:53:57.008 I/ActivityThread( 106): Publishing provider com.android.social: com.android.providers.contacts.SocialProvider
          09-01 13:53:57.445 D/dalvikvm( 60): GC freed 2631 objects / 137120 bytes in 381ms
          09-01 13:53:57.617 I/ARMAssembler( 60): generated scanline__00000077:03545404_00000A04_00000000 [ 29 ipp] (51 ins) at [0x3e3740:0x3e380c] in 2196000 ns
          09-01 13:53:57.702 I/ActivityThread( 102): Publishing provider mms-sms: com.android.providers.telephony.MmsSmsProvider
          09-01 13:53:58.266 I/ActivityThread( 102): Publishing provider mms: com.android.providers.telephony.MmsProvider
          09-01 13:53:58.478 I/ActivityThread( 102): Publishing provider sms: com.android.providers.telephony.SmsProvider
          09-01 13:53:58.611 D/dalvikvm( 60): GC freed 657 objects / 52688 bytes in 661ms
          09-01 13:53:58.822 I/ActivityThread( 102): Publishing provider telephony: com.android.providers.telephony.TelephonyProvider
          09-01 13:53:59.940 I/ActivityThread( 106): Publishing provider applications: com.android.providers.applications.ApplicationsProvider
          09-01 13:54:00.027 D/dalvikvm( 60): GC freed 821 objects / 41648 bytes in 409ms
          09-01 13:54:01.315 I/ActivityThread( 106): Publishing provider contacts;com.android.contacts: com.android.providers.contacts.ContactsProvider2
          09-01 13:54:01.573 I/ARMAssembler( 60): generated scanline__00000177:03515104_00001A01_00000000 [ 73 ipp] (98 ins) at [0x43fc48:0x43fdd0] in 22802001 ns
          09-01 13:54:02.045 D/PhoneApp( 102): mProximityWakeLock: null
          09-01 13:54:02.173 W/ActivityManager( 60): Unable to start service Intent

          { act=com.android.ussd.IExtendedNetworkService }

          : not found
          09-01 13:54:02.259 D/PhoneApp( 102): Resetting audio state/mode: IDLE
          09-01 13:54:02.302 D/dalvikvm( 60): GREF has increased to 201
          09-01 13:54:02.558 D/AlarmManagerService( 60): Kernel timezone updated to 420 minutes west of GMT
          09-01 13:54:02.603 D/SystemClock( 102): Setting time of day to sec=1283374442
          09-01 13:54:02.413 I/ActivityManager( 60): Start proc com.android.alarmclock for broadcast com.android.alarmclock/.AlarmInitReceiver: pid=140 uid=10017 gids={}
          09-01 13:54:02.413 W/SystemClock( 102): Unable to set rtc to 1283374442: Invalid argument
          09-01 13:54:02.543 D/MobileDataStateTracker( 60): default Received state= DISCONNECTED, old= DISCONNECTED, reason= radioTurnedOff, apnTypeList= default
          09-01 13:54:02.629 D/MobileDataStateTracker( 60): default Received state= DISCONNECTED, old= DISCONNECTED, reason= gprsDetached, apnTypeList= default
          09-01 13:54:03.236 W/ActivityManager( 60): Activity idle timeout for HistoryRecord

          {43ca69c0 com.android.launcher/.Launcher}

          09-01 13:54:03.546 D/PowerManagerService( 60): bootCompleted
          09-01 13:54:03.546 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10
          09-01 13:54:03.546 D/qemud ( 34): created client 0xc088 listening on fd 14
          09-01 13:54:03.546 D/qemud ( 34): client_fd_receive: attempting registration for service 'sensors'
          09-01 13:54:03.589 D/qemud ( 34): client_fd_receive: -> received channel id 7
          09-01 13:54:03.589 D/qemud ( 34): client_registration: registration succeeded for client 7
          09-01 13:54:03.823 I/ActivityThread( 140): Publishing provider com.android.alarmclock: com.android.alarmclock.AlarmProvider
          09-01 13:54:03.952 D/vold ( 26): Accepted connection from framework
          09-01 13:54:03.995 D/vold ( 26): dispatch_cmd(send_ums_status):
          09-01 13:54:03.995 D/vold ( 26): dispatch_cmd(mount_volume:/sdcard):
          09-01 13:54:03.995 E/vold ( 26): Cannot start volume '/sdcard' (volume is not bound)
          09-01 13:54:03.995 D/MountListener( 60): handleEvent volume_nomedia:/sdcard
          09-01 13:54:04.126 D/MountListener( 60): handleEvent ums_disabled
          09-01 13:54:04.126 D/MediaPlayer( 60): Couldn't open file on client side, trying server side
          09-01 13:54:04.172 D/MountListener( 60): handleEvent ums_disconnected
          09-01 13:54:04.350 E/MediaPlayerService( 30): Couldn't open fd for content://settings/system/notification_sound
          09-01 13:54:04.350 E/MediaPlayer( 60): Unable to to create media player
          09-01 13:54:04.633 W/NotificationService( 60): error loading sound for content://settings/system/notification_sound
          09-01 13:54:04.633 W/NotificationService( 60): java.io.IOException: setDataSource failed.: status=0x80000000
          09-01 13:54:04.633 W/NotificationService( 60): at android.media.MediaPlayer.setDataSource(Native Method)
          09-01 13:54:04.633 W/NotificationService( 60): at android.media.MediaPlayer.setDataSource(MediaPlayer.java:699)
          09-01 13:54:04.633 W/NotificationService( 60): at android.media.AsyncPlayer.startSound(AsyncPlayer.java:62)
          09-01 13:54:04.633 W/NotificationService( 60): at android.media.AsyncPlayer.access$200(AsyncPlayer.java:33)
          09-01 13:54:04.633 W/NotificationService( 60): at android.media.AsyncPlayer$Thread.run(AsyncPlayer.java:99)
          09-01 13:54:05.033 D/dalvikvm( 102): GC freed 3750 objects / 232656 bytes in 586ms
          09-01 13:54:05.161 E/ActivityThread( 102): Failed to find provider info for android.server.checkin
          09-01 13:54:05.161 W/Checkin ( 102): Can't update stat PHONE_GSM_REGISTERED: java.lang.IllegalArgumentException: Unknown URL content://android.server.checkin/stats
          09-01 13:54:05.674 D/MccTable( 102): updateMccMncConfiguration: mcc=310, mnc=260
          09-01 13:54:05.759 D/MccTable( 102): locale set to en_us
          09-01 13:54:05.801 D/MccTable( 102): WIFI_NUM_ALLOWED_CHANNELS set to 11
          09-01 13:54:05.801 I/WifiService( 60): WifiService trying to setNumAllowed to 11 with persist set to true
          09-01 13:54:05.844 W/BackupManagerService( 60): dataChanged but no participant pkg='com.android.providers.settings' uid=1001
          09-01 13:54:05.844 I/ActivityManager( 60): Config changed:

          { scale=1.0 imsi=310/260 loc=en_US touch=3 keys=2/1/1 nav=3/1 orien=1 layout=18}

          09-01 13:54:06.016 I/UsageStats( 60): Unexpected resume of com.android.launcher while already resumed in com.android.launcher
          09-01 13:54:06.509 D/MobileDataStateTracker( 60): default Received state= DISCONNECTED, old= DISCONNECTED, reason= (unspecified), apnTypeList= default
          09-01 13:54:06.509 W/BackupManagerService( 60): dataChanged but no participant pkg='com.android.providers.settings' uid=10017
          09-01 13:54:06.988 D/TelephonyProvider( 102): Setting numeric '310260' to be the current operator
          09-01 13:54:07.031 D/OtaStartupReceiver( 102): Not a CDMA phone, no need to process OTA
          09-01 13:54:07.031 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030012
          09-01 13:54:07.116 E/ActivityThread( 102): Failed to find provider info for android.server.checkin
          09-01 13:54:07.116 W/Checkin ( 102): Can't update stat PHONE_GPRS_ATTEMPTED: java.lang.IllegalArgumentException: Unknown URL content://android.server.checkin/stats
          09-01 13:54:07.159 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030034
          09-01 13:54:07.159 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030003
          09-01 13:54:07.201 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030000
          09-01 13:54:07.330 E/ActivityThread( 102): Failed to find provider info for android.server.checkin
          09-01 13:54:07.330 W/Checkin ( 102): Can't update stat PHONE_GPRS_CONNECTED: java.lang.IllegalArgumentException: Unknown URL content://android.server.checkin/stats
          09-01 13:54:07.672 I/ActivityManager( 60): Start proc android.process.media for broadcast com.android.providers.media/.MediaScannerReceiver: pid=153 uid=10003 gids=

          {1006, 1015, 2001, 3003}

          09-01 13:54:07.672 D/MobileDataStateTracker( 60): default Received state= CONNECTING, old= DISCONNECTED, reason= simLoaded, apnTypeList= *
          09-01 13:54:07.714 D/NetworkStateTracker( 60): setDetailed state, old =IDLE and new state=CONNECTING
          09-01 13:54:07.714 D/ConnectivityService( 60): ConnectivityChange for MOBILE: CONNECTING/CONNECTING
          09-01 13:54:07.886 D/MobileDataStateTracker( 60): default Received state= CONNECTED, old= CONNECTING, reason= simLoaded, apnTypeList= *
          09-01 13:54:07.886 D/MobileDataStateTracker( 60): CONNECTED event did not supply interface name.
          09-01 13:54:07.886 D/NetworkStateTracker( 60): setDetailed state, old =CONNECTING and new state=CONNECTED
          09-01 13:54:07.886 D/ConnectivityService( 60): ConnectivityChange for MOBILE: CONNECTED/CONNECTED
          09-01 13:54:07.928 V/NetworkStateTracker( 60): Setting TCP values: [4094,87380,110208,4096,16384,110208] which comes from [net.tcp.buffersize.umts]
          09-01 13:54:07.970 D/MobileDataStateTracker( 60): replacing old mInterfaceName (null) with null for mms
          09-01 13:54:07.970 D/MobileDataStateTracker( 60): replacing old mInterfaceName (null) with null for supl
          09-01 13:54:07.970 D/MobileDataStateTracker( 60): replacing old mInterfaceName (null) with null for dun
          09-01 13:54:07.970 D/MobileDataStateTracker( 60): replacing old mInterfaceName (null) with null for hipri
          09-01 13:54:08.013 D/ConnectivityService( 60): adding dns 10.0.2.3 for MOBILE
          09-01 13:54:08.013 D/ConnectivityService( 60): adding dns 10.0.2.4 for MOBILE
          09-01 13:54:08.142 D/GpsLocationProvider( 60): updateNetworkState available info: NetworkInfo: type: MOBILE[UMTS], state: CONNECTED/CONNECTED, reason: simLoaded, extra: internet, roaming: false, failover: false, isAvailable: true
          09-01 13:54:08.142 D/GpsLocationProvider( 60): NetworkThread wait for 9223372036854775807ms
          09-01 13:54:08.442 I/ActivityThread( 153): Publishing provider drm: com.android.providers.drm.DrmProvider
          09-01 13:54:08.528 I/ActivityThread( 153): Publishing provider media: com.android.providers.media.MediaProvider
          09-01 13:54:08.728 V/MediaProvider( 153): Attached volume: internal
          09-01 13:54:08.814 I/ActivityThread( 153): Publishing provider downloads: com.android.providers.downloads.DownloadProvider
          09-01 13:54:09.305 W/ContactAggregator( 106): No more aggregation requests
          09-01 13:54:09.738 I/ActivityManager( 60): Start proc com.android.mms for broadcast com.android.mms/.transaction.MmsSystemEventReceiver: pid=161 uid=10014 gids=

          {3003, 1015}
          09-01 13:54:09.823 I/ActivityThread( 106): Publishing provider com.android.globalsearch.SuggestionProvider: com.android.globalsearch.SuggestionProvider
          09-01 13:54:10.326 D/MediaScannerService( 153): start scanning volume internal
          09-01 13:54:11.000 W/WindowManager( 60): App freeze timeout expired.
          09-01 13:54:11.000 W/WindowManager( 60): Force clearing freeze: AppWindowToken{43cb4190 token=HistoryRecord{43ca69c0 com.android.launcher/.Launcher}}
          09-01 13:54:11.639 D/dalvikvm( 106): GC freed 3051 objects / 220360 bytes in 402ms
          09-01 13:54:13.337 D/dalvikvm( 60): GC freed 12147 objects / 654120 bytes in 313ms
          09-01 13:54:13.955 W/BackupManagerService( 60): dataChanged but no participant pkg='com.android.providers.settings' uid=10017
          09-01 13:54:14.083 I/ActivityThread( 106): Publishing provider call_log: com.android.providers.contacts.CallLogProvider
          09-01 13:54:14.083 I/ActivityThread( 106): Publishing provider user_dictionary: com.android.providers.userdictionary.UserDictionaryProvider
          09-01 13:54:14.254 W/BackupManagerService( 60): dataChanged but no participant pkg='com.android.providers.settings' uid=10017
          09-01 13:54:14.446 I/ActivityManager( 60): Start proc com.android.email for broadcast com.android.email/com.android.exchange.BootReceiver: pid=180 uid=10023 gids={3003, 1015}

          09-01 13:54:18.734 I/ActivityThread( 180): Publishing provider com.android.email.provider: com.android.email.provider.EmailProvider
          09-01 13:54:18.776 I/ActivityThread( 180): Publishing provider com.android.email.attachmentprovider: com.android.email.provider.AttachmentProvider
          09-01 13:54:19.289 D/Exchange( 180): BootReceiver onReceive
          09-01 13:54:19.418 D/EAS SyncManager( 180): !!! EAS SyncManager, onCreate
          09-01 13:54:19.933 D/EAS SyncManager( 180): !!! EAS SyncManager, onStartCommand
          09-01 13:54:19.976 D/EAS SyncManager( 180): !!! EAS SyncManager, stopping self
          09-01 13:54:20.062 D/Eas Debug( 180): Logging:
          09-01 13:54:20.105 D/EAS SyncManager( 180): !!! EAS SyncManager, onDestroy
          09-01 13:54:20.877 I/ActivityManager( 60): Displayed activity com.android.launcher/.Launcher: 30389 ms (total 30389 ms)
          09-01 13:54:21.025 I/SurfaceFlinger( 60): Boot is finished (44235 ms)
          09-01 13:54:21.025 I/WindowManager( 60): Setting rotation to 1, animFlags=1
          09-01 13:54:21.068 I/ActivityManager( 60): Config changed:

          { scale=1.0 imsi=310/260 loc=en_US touch=3 keys=2/1/1 nav=3/1 orien=2 layout=18}

          09-01 13:54:21.197 I/UsageStats( 60): Unexpected resume of com.android.launcher while already resumed in com.android.launcher
          09-01 13:54:22.140 D/dalvikvm( 106): GC freed 3356 objects / 214072 bytes in 161ms
          09-01 13:54:22.580 I/ARMAssembler( 60): generated scanline__00000077:03010104_00000004_00000000 [ 22 ipp] (41 ins) at [0x424428:0x4244cc] in 2249000 ns
          09-01 13:54:22.622 I/ARMAssembler( 60): generated scanline__00000177:03515104_00000001_00000000 [ 73 ipp] (95 ins) at [0x46cc50:0x46cdcc] in 1281000 ns
          09-01 13:54:22.808 D/dalvikvm( 60): GREF has increased to 301
          09-01 13:54:22.937 D/HomeLoaders( 106): ----> items cloned, ready to refresh UI
          09-01 13:54:25.771 D/dalvikvm( 60): GC freed 5899 objects / 298864 bytes in 904ms
          09-01 13:54:26.272 D/MediaScanner( 153): opendir /system/media/ failed, errno: 2
          09-01 13:54:26.272 D/MediaScanner( 153): prescan time: 13830ms
          09-01 13:54:26.272 D/MediaScanner( 153): scan time: 56ms
          09-01 13:54:26.272 D/MediaScanner( 153): postscan time: 1ms
          09-01 13:54:26.272 D/MediaScanner( 153): total time: 13887ms
          09-01 13:54:26.315 D/MediaScannerService( 153): done scanning volume internal
          09-01 13:54:44.417 D/AndroidRuntime( 198):
          09-01 13:54:44.417 D/AndroidRuntime( 198): >>>>>>>>>>>>>> AndroidRuntime START <<<<<<<<<<<<<<
          09-01 13:54:44.417 D/AndroidRuntime( 198): CheckJNI is ON
          09-01 13:54:44.813 D/AndroidRuntime( 198): — registering native functions —
          09-01 13:54:46.475 D/PackageParser( 60): Scanning package: /data/app/vmdl39163.tmp
          09-01 13:54:46.518 I/PackageParser( 60): com.att.nismob.android.build: compat added android.permission.WRITE_EXTERNAL_STORAGE android.permission.READ_PHONE_STATE
          09-01 13:54:47.044 I/PackageManager( 60): Removing non-system package:com.att.nismob.android.build
          09-01 13:54:47.044 D/PackageManager( 60): Removing package com.att.nismob.android.build
          09-01 13:54:47.044 D/PackageManager( 60): Activities: com.att.nismob.android.build.Build
          09-01 13:54:47.172 D/PackageManager( 60): Scanning package com.att.nismob.android.build
          09-01 13:54:47.214 I/PackageManager( 60): /data/app/vmdl39163.tmp changed; unpacking
          09-01 13:54:47.274 D/installd( 31): DexInv: — BEGIN '/data/app/vmdl39163.tmp' —
          09-01 13:54:49.111 D/dalvikvm( 204): DexOpt: load 152ms, verify 1178ms, opt 52ms
          09-01 13:54:49.153 D/installd( 31): DexInv: — END '/data/app/vmdl39163.tmp' (success) —
          09-01 13:54:49.153 D/PackageManager( 60): Activities: com.att.nismob.android.build.Build
          09-01 13:54:49.196 D/ActivityManager( 60): Uninstalling process com.att.nismob.android.build
          09-01 13:54:49.282 I/installd( 31): move /data/dalvik-cache/data@app@vmdl39163.tmp@classes.dex -> /data/dalvik-cache/data@app@com.att.nismob.android.build.apk@classes.dex
          09-01 13:54:49.326 D/PackageManager( 60): New package installed in /data/app/com.att.nismob.android.build.apk
          09-01 13:54:49.455 D/AndroidRuntime( 198): Shutting down VM
          09-01 13:54:49.455 D/dalvikvm( 198): DestroyJavaVM waiting for non-daemon threads to exit
          09-01 13:54:49.455 D/dalvikvm( 198): DestroyJavaVM shutting VM down
          09-01 13:54:49.455 D/dalvikvm( 198): HeapWorker thread shutting down
          09-01 13:54:49.541 D/dalvikvm( 198): HeapWorker thread has shut down
          09-01 13:54:49.541 D/jdwp ( 198): JDWP shutting down net...
          09-01 13:54:49.541 I/jdwp ( 198): adbd disconnected
          09-01 13:54:49.541 D/dalvikvm( 198): VM cleaning up
          09-01 13:54:49.541 D/ActivityManager( 60): Uninstalling process com.att.nismob.android.build
          09-01 13:54:49.584 E/AndroidRuntime( 198): ERROR: thread attach failed
          09-01 13:54:49.670 D/dalvikvm( 198): LinearAlloc 0x0 used 621708 of 5242880 (11%)
          09-01 13:54:50.358 D/dalvikvm( 106): GC freed 2613 objects / 132504 bytes in 306ms
          09-01 13:54:51.526 W/ResourceType( 60): Resources don't contain package for resource number 0x7f0700e5
          09-01 13:54:51.611 I/ActivityManager( 60): Start proc com.svox.pico for broadcast com.svox.pico/.VoiceDataInstallerReceiver: pid=208 uid=10012 gids={}
          09-01 13:54:53.694 W/ResourceType( 60): Resources don't contain package for resource number 0x7f020031
          09-01 13:54:53.694 W/ResourceType( 60): Resources don't contain package for resource number 0x7f020030
          09-01 13:54:53.694 W/ResourceType( 60): Resources don't contain package for resource number 0x7f050000
          09-01 13:54:53.694 W/ResourceType( 60): Resources don't contain package for resource number 0x7f060000
          09-01 13:54:53.909 W/ResourceType( 60): Resources don't contain package for resource number 0x7f060001
          09-01 13:54:54.499 D/dalvikvm( 29): GC freed 277 objects / 10592 bytes in 796ms
          09-01 13:54:54.713 W/ResourceType( 60): Resources don't contain package for resource number 0x7f0700e5
          09-01 13:54:54.713 W/ResourceType( 60): Resources don't contain package for resource number 0x7f020031
          09-01 13:54:54.713 W/ResourceType( 60): Resources don't contain package for resource number 0x7f020030
          09-01 13:54:54.713 W/ResourceType( 60): Resources don't contain package for resource number 0x7f050000
          09-01 13:54:54.713 W/ResourceType( 60): Resources don't contain package for resource number 0x7f060000
          09-01 13:54:54.756 W/ResourceType( 60): Resources don't contain package for resource number 0x7f060001
          09-01 13:54:55.444 D/dalvikvm( 29): GC freed 45 objects / 2016 bytes in 780ms
          09-01 13:54:55.530 D/dalvikvm( 29): GC freed 2 objects / 48 bytes in 111ms
          09-01 13:54:55.830 D/dalvikvm( 60): GC freed 6035 objects / 443576 bytes in 221ms
          09-01 13:55:01.729 D/AndroidRuntime( 217):
          09-01 13:55:01.729 D/AndroidRuntime( 217): >>>>>>>>>>>>>> AndroidRuntime START <<<<<<<<<<<<<<
          09-01 13:55:01.771 D/AndroidRuntime( 217): CheckJNI is ON
          09-01 13:55:02.100 D/AndroidRuntime( 217): — registering native functions —
          09-01 13:55:03.515 D/PackageParser( 60): Scanning package: /data/app/vmdl39164.tmp
          09-01 13:55:03.557 I/PackageParser( 60): com.att.nismob.android.test: compat added android.permission.WRITE_EXTERNAL_STORAGE android.permission.READ_PHONE_STATE
          09-01 13:55:03.686 I/PackageManager( 60): Removing non-system package:com.att.nismob.android.test
          09-01 13:55:03.686 D/PackageManager( 60): Removing package com.att.nismob.android.test
          09-01 13:55:03.686 D/PackageManager( 60): Instrumentation: android.test.InstrumentationTestRunner
          09-01 13:55:03.814 D/PackageManager( 60): Scanning package com.att.nismob.android.test
          09-01 13:55:03.856 I/PackageManager( 60): /data/app/vmdl39164.tmp changed; unpacking
          09-01 13:55:03.856 D/installd( 31): DexInv: — BEGIN '/data/app/vmdl39164.tmp' —
          09-01 13:55:04.133 D/dalvikvm( 223): DexOpt: load 19ms, verify 5ms, opt 4ms
          09-01 13:55:04.133 D/installd( 31): DexInv: — END '/data/app/vmdl39164.tmp' (success) —
          09-01 13:55:04.176 D/PackageManager( 60): Instrumentation: android.test.InstrumentationTestRunner
          09-01 13:55:04.219 D/ActivityManager( 60): Uninstalling process com.att.nismob.android.test
          09-01 13:55:04.307 I/installd( 31): move /data/dalvik-cache/data@app@vmdl39164.tmp@classes.dex -> /data/dalvik-cache/data@app@com.att.nismob.android.test.apk@classes.dex
          09-01 13:55:04.350 D/PackageManager( 60): New package installed in /data/app/com.att.nismob.android.test.apk
          09-01 13:55:04.622 D/AndroidRuntime( 217): Shutting down VM
          09-01 13:55:04.622 D/dalvikvm( 217): DestroyJavaVM waiting for non-daemon threads to exit
          09-01 13:55:04.665 D/dalvikvm( 217): DestroyJavaVM shutting VM down
          09-01 13:55:04.665 D/dalvikvm( 217): HeapWorker thread shutting down
          09-01 13:55:04.665 D/dalvikvm( 217): HeapWorker thread has shut down
          09-01 13:55:04.665 D/jdwp ( 217): JDWP shutting down net...
          09-01 13:55:04.665 I/jdwp ( 217): adbd disconnected
          09-01 13:55:04.665 D/dalvikvm( 217): VM cleaning up
          09-01 13:55:04.708 D/ActivityManager( 60): Uninstalling process com.att.nismob.android.test

          Show
          jtharp jtharp added a comment - I'm running on a 64 bit machine (with 64 bit Java). Here was one of the errors =================================================== [echo] Installing /home/hudson/hudson/jobs/AndroidBuildTests/workspace/ADK_VER/2.1/SCREEN_RES/HVGA/test/bin/BuildTest-debug.apk... [echo] Executing "install" on the Android Debugging Bridge. [echo] Targeting device: localhost:44874... [exec] 75 KB/s (7608 bytes in 0.098s) [exec] pkg: /data/local/tmp/BuildTest-debug.apk [exec] Success [exec] - waiting for device - [exec] * daemon not running. starting it now * [exec] * daemon started successfully * [exec] error: unknown host service [exec] - waiting for device - [exec] error: unknown host service [exec] - waiting for device - [exec] error: unknown host service ... Many more identical lines cut out ... [exec] - waiting for device - [exec] error: unknown host service [exec] - waiting for device - $ /usr/android/tools/adb disconnect localhost:44874 [android] Stopping Android emulator [android] Archiving emulator log Build was aborted Finished: ABORTED And the corresponding logcat file. ============================================== 09-01 13:53:13.343 I/vold ( 26): Android Volume Daemon version 2.0 09-01 13:53:13.373 I/DEBUG ( 27): debuggerd: May 6 2010 09:07:51 09-01 13:53:13.533 D/qemud ( 34): entering main loop 09-01 13:53:13.563 E/vold ( 26): Error opening switch name path '/sys/class/switch/test' (No such file or directory) 09-01 13:53:13.563 E/vold ( 26): Error bootstrapping switch '/sys/class/switch/test' (No such file or directory) 09-01 13:53:13.563 E/vold ( 26): Error opening switch name path '/sys/class/switch/test2' (No such file or directory) 09-01 13:53:13.563 E/vold ( 26): Error bootstrapping switch '/sys/class/switch/test2' (No such file or directory) 09-01 13:53:13.563 D/vold ( 26): Bootstrapping complete 09-01 13:53:14.213 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10 09-01 13:53:14.213 D/qemud ( 34): created client 0xe078 listening on fd 8 09-01 13:53:14.233 D/qemud ( 34): client_fd_receive: attempting registration for service 'boot-properties' 09-01 13:53:14.233 D/qemud ( 34): client_fd_receive: -> received channel id 1 09-01 13:53:14.243 D/qemud ( 34): client_registration: registration succeeded for client 1 09-01 13:53:14.243 I/qemu-props( 45): connected to 'boot-properties' qemud service. 09-01 13:53:14.254 I/qemu-props( 45): received: qemu.sf.lcd_density=160 09-01 13:53:14.254 I/qemu-props( 45): received: dalvik.vm.heapsize=16m 09-01 13:53:14.254 I/qemu-props( 45): received: persist.sys.language=en 09-01 13:53:14.263 I/qemu-props( 45): received: persist.sys.country=US 09-01 13:53:14.793 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10 09-01 13:53:14.793 D/qemud ( 34): created client 0xe078 listening on fd 11 09-01 13:53:14.793 D/qemud ( 34): fdhandler_event: disconnect on fd 11 09-01 13:53:14.823 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10 09-01 13:53:14.823 D/qemud ( 34): created client 0xe078 listening on fd 11 09-01 13:53:14.823 D/qemud ( 34): client_fd_receive: attempting registration for service 'gsm' 09-01 13:53:14.823 D/qemud ( 34): client_fd_receive: -> received channel id 2 09-01 13:53:14.833 D/qemud ( 34): client_registration: registration succeeded for client 2 09-01 13:53:15.033 D/AndroidRuntime( 29): 09-01 13:53:15.033 D/AndroidRuntime( 29): >>>>>>>>>>>>>> AndroidRuntime START <<<<<<<<<<<<<< 09-01 13:53:15.033 D/AndroidRuntime( 29): CheckJNI is ON 09-01 13:53:15.733 I/ ( 30): ServiceManager: 0xad08 09-01 13:53:15.733 W/AudioHardwareInterface( 30): Using stubbed audio hardware. No sound will be produced. 09-01 13:53:15.733 D/AudioHardwareInterface( 30): setMode(NORMAL) 09-01 13:53:15.773 I/CameraService( 30): CameraService started: pid=30 09-01 13:53:15.803 I/AudioFlinger( 30): AudioFlinger's thread 0xb370 ready to run 09-01 13:53:15.953 D/AndroidRuntime( 29): — registering native functions — 09-01 13:53:16.663 I/SamplingProfilerIntegration( 29): Profiler is disabled. 09-01 13:53:16.753 I/Zygote ( 29): Preloading classes... 09-01 13:53:16.773 D/dalvikvm( 29): GC freed 793 objects / 50568 bytes in 18ms 09-01 13:53:16.933 D/dalvikvm( 29): GC freed 251 objects / 16168 bytes in 8ms 09-01 13:53:17.093 D/dalvikvm( 29): GC freed 295 objects / 18768 bytes in 9ms 09-01 13:53:17.184 D/dalvikvm( 29): GC freed 214 objects / 13712 bytes in 11ms 09-01 13:53:17.433 D/dalvikvm( 29): GC freed 415 objects / 26544 bytes in 36ms 09-01 13:53:17.683 D/skia ( 29): ------ build_power_table 1.4 09-01 13:53:17.693 D/skia ( 29): ------ build_power_table 0.714286 09-01 13:53:17.763 D/dalvikvm( 29): GC freed 418 objects / 28656 bytes in 13ms 09-01 13:53:17.803 D/dalvikvm( 29): Trying to load lib /system/lib/libmedia_jni.so 0x0 09-01 13:53:17.983 D/dalvikvm( 29): Added shared lib /system/lib/libmedia_jni.so 0x0 09-01 13:53:17.993 D/dalvikvm( 29): Trying to load lib /system/lib/libexif.so 0x0 09-01 13:53:18.003 D/dalvikvm( 29): Added shared lib /system/lib/libexif.so 0x0 09-01 13:53:18.533 D/dalvikvm( 29): GC freed 2299 objects / 120896 bytes in 17ms 09-01 13:53:18.533 D/dalvikvm( 29): Trying to load lib /system/lib/libmedia_jni.so 0x0 09-01 13:53:18.533 D/dalvikvm( 29): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0 09-01 13:53:18.543 D/dalvikvm( 29): Trying to load lib /system/lib/libmedia_jni.so 0x0 09-01 13:53:18.543 D/dalvikvm( 29): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0 09-01 13:53:18.543 D/dalvikvm( 29): Trying to load lib /system/lib/libmedia_jni.so 0x0 09-01 13:53:18.543 D/dalvikvm( 29): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0 09-01 13:53:18.543 D/dalvikvm( 29): Trying to load lib /system/lib/libmedia_jni.so 0x0 09-01 13:53:18.543 D/dalvikvm( 29): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0 09-01 13:53:19.224 D/dalvikvm( 29): GC freed 3790 objects / 197032 bytes in 31ms 09-01 13:53:19.343 D/dalvikvm( 29): GC freed 461 objects / 26352 bytes in 26ms 09-01 13:53:19.453 D/dalvikvm( 29): GC freed 294 objects / 17024 bytes in 29ms 09-01 13:53:19.543 D/dalvikvm( 29): GC freed 204 objects / 11416 bytes in 31ms 09-01 13:53:19.613 D/dalvikvm( 29): GC freed 178 objects / 9560 bytes in 32ms 09-01 13:53:19.643 D/dalvikvm( 29): Trying to load lib /system/lib/libsrec_jni.so 0x0 09-01 13:53:19.663 D/dalvikvm( 29): Added shared lib /system/lib/libsrec_jni.so 0x0 09-01 13:53:19.673 D/dalvikvm( 29): Trying to load lib /system/lib/libsrec_jni.so 0x0 09-01 13:53:19.673 D/dalvikvm( 29): Shared lib '/system/lib/libsrec_jni.so' already loaded in same CL 0x0 09-01 13:53:20.033 D/dalvikvm( 29): GC freed 296 objects / 34696 bytes in 33ms 09-01 13:53:20.373 D/dalvikvm( 29): GC freed 787 objects / 47976 bytes in 48ms 09-01 13:53:20.543 D/dalvikvm( 29): GC freed 337 objects / 38440 bytes in 45ms 09-01 13:53:20.723 D/dalvikvm( 29): GC freed 418 objects / 25840 bytes in 49ms 09-01 13:53:20.823 D/dalvikvm( 29): Trying to load lib /system/lib/libwebcore.so 0x0 09-01 13:53:20.953 D/dalvikvm( 29): Added shared lib /system/lib/libwebcore.so 0x0 09-01 13:53:21.213 D/dalvikvm( 29): GC freed 432 objects / 25136 bytes in 47ms 09-01 13:53:21.433 D/dalvikvm( 29): GC freed 489 objects / 30736 bytes in 47ms 09-01 13:53:21.613 D/dalvikvm( 29): GC freed 349 objects / 28968 bytes in 53ms 09-01 13:53:21.923 D/dalvikvm( 29): GC freed 309 objects / 21888 bytes in 104ms 09-01 13:53:24.953 D/dalvikvm( 29): GC freed 915 objects / 80200 bytes in 56ms 09-01 13:53:25.113 D/dalvikvm( 29): GC freed 664 objects / 35848 bytes in 55ms 09-01 13:53:25.273 D/dalvikvm( 29): GC freed 709 objects / 41208 bytes in 57ms 09-01 13:53:25.433 D/dalvikvm( 29): GC freed 735 objects / 40648 bytes in 57ms 09-01 13:53:27.113 D/dalvikvm( 29): GC freed 14031 objects / 475104 bytes in 77ms 09-01 13:53:27.613 D/dalvikvm( 29): GC freed 10450 objects / 447872 bytes in 81ms 09-01 13:53:28.193 D/dalvikvm( 29): GC freed 10899 objects / 472632 bytes in 84ms 09-01 13:53:28.983 D/dalvikvm( 29): GC freed 8111 objects / 461600 bytes in 83ms 09-01 13:53:30.003 D/dalvikvm( 29): GC freed 8196 objects / 460616 bytes in 83ms 09-01 13:53:31.243 D/dalvikvm( 29): GC freed 8498 objects / 516672 bytes in 151ms 09-01 13:53:32.324 D/dalvikvm( 29): GC freed 6409 objects / 390256 bytes in 114ms 09-01 13:53:33.544 D/dalvikvm( 29): GC freed 847 objects / 51480 bytes in 93ms 09-01 13:53:33.713 D/dalvikvm( 29): GC freed 540 objects / 39192 bytes in 89ms 09-01 13:53:33.893 D/dalvikvm( 29): GC freed 460 objects / 31544 bytes in 81ms 09-01 13:53:34.084 D/dalvikvm( 29): GC freed 426 objects / 29928 bytes in 106ms 09-01 13:53:34.124 I/Zygote ( 29): ...preloaded 1942 classes in 17371ms. 09-01 13:53:34.233 D/dalvikvm( 29): GC freed 117 objects / 14864 bytes in 94ms 09-01 13:53:34.244 I/Zygote ( 29): Preloading resources... 09-01 13:53:34.364 D/dalvikvm( 29): GC freed 5 objects / 224 bytes in 92ms 09-01 13:53:34.393 W/Zygote ( 29): Preloaded drawable resource #0x1080093 (res/drawable-mdpi/sym_def_app_icon.png) that varies with configuration!! 09-01 13:53:34.404 W/Zygote ( 29): Preloaded drawable resource #0x1080002 (res/drawable-mdpi/arrow_down_float.png) that varies with configuration!! 09-01 13:53:34.493 W/Zygote ( 29): Preloaded drawable resource #0x10800b4 (res/drawable/btn_check.xml) that varies with configuration!! 09-01 13:53:34.514 W/Zygote ( 29): Preloaded drawable resource #0x10800b7 (res/drawable-mdpi/btn_check_label_background.9.png) that varies with configuration!! 09-01 13:53:34.523 W/Zygote ( 29): Preloaded drawable resource #0x10800b8 (res/drawable-mdpi/btn_check_off.png) that varies with configuration!! 09-01 13:53:34.523 W/Zygote ( 29): Preloaded drawable resource #0x10800bd (res/drawable-mdpi/btn_check_on.png) that varies with configuration!! 09-01 13:53:34.584 W/Zygote ( 29): Preloaded drawable resource #0x1080004 (res/drawable/btn_default.xml) that varies with configuration!! 09-01 13:53:34.623 W/Zygote ( 29): Preloaded drawable resource #0x1080005 (res/drawable/btn_default_small.xml) that varies with configuration!! 09-01 13:53:34.673 W/Zygote ( 29): Preloaded drawable resource #0x1080006 (res/drawable/btn_dropdown.xml) that varies with configuration!! 09-01 13:53:34.743 W/Zygote ( 29): Preloaded drawable resource #0x1080008 (res/drawable/btn_plus.xml) that varies with configuration!! 09-01 13:53:34.823 W/Zygote ( 29): Preloaded drawable resource #0x1080007 (res/drawable/btn_minus.xml) that varies with configuration!! 09-01 13:53:34.897 W/Zygote ( 29): Preloaded drawable resource #0x1080009 (res/drawable/btn_radio.xml) that varies with configuration!! 09-01 13:53:34.983 W/Zygote ( 29): Preloaded drawable resource #0x108000a (res/drawable/btn_star.xml) that varies with configuration!! 09-01 13:53:35.073 D/dalvikvm( 29): GC freed 396 objects / 23992 bytes in 85ms 09-01 13:53:35.103 W/Zygote ( 29): Preloaded drawable resource #0x1080125 (res/drawable/btn_toggle.xml) that varies with configuration!! 09-01 13:53:35.123 W/Zygote ( 29): Preloaded drawable resource #0x1080187 (res/drawable-mdpi/ic_emergency.png) that varies with configuration!! 09-01 13:53:35.133 W/Zygote ( 29): Preloaded drawable resource #0x1080012 (res/drawable-mdpi/divider_horizontal_bright.9.png) that varies with configuration!! 09-01 13:53:35.133 W/Zygote ( 29): Preloaded drawable resource #0x1080014 (res/drawable-mdpi/divider_horizontal_dark.9.png) that varies with configuration!! 09-01 13:53:35.193 W/Zygote ( 29): Preloaded drawable resource #0x1080016 (res/drawable/edit_text.xml) that varies with configuration!! 09-01 13:53:35.214 W/Zygote ( 29): Preloaded drawable resource #0x1080161 (res/drawable/expander_group.xml) that varies with configuration!! 09-01 13:53:35.274 W/Zygote ( 29): Preloaded drawable resource #0x1080062 (res/drawable/list_selector_background.xml) that varies with configuration!! 09-01 13:53:35.294 W/Zygote ( 29): Preloaded drawable resource #0x1080217 (res/drawable-mdpi/menu_background.9.png) that varies with configuration!! 09-01 13:53:35.304 W/Zygote ( 29): Preloaded drawable resource #0x1080218 (res/drawable-mdpi/menu_background_fill_parent_width.9.png) that varies with configuration!! 09-01 13:53:35.354 W/Zygote ( 29): Preloaded drawable resource #0x1080219 (res/drawable/menu_selector.xml) that varies with configuration!! 09-01 13:53:35.364 W/Zygote ( 29): Preloaded drawable resource #0x1080224 (res/drawable-mdpi/panel_background.9.png) that varies with configuration!! 09-01 13:53:35.384 W/Zygote ( 29): Preloaded drawable resource #0x108022e (res/drawable-mdpi/popup_bottom_bright.9.png) that varies with configuration!! 09-01 13:53:35.394 W/Zygote ( 29): Preloaded drawable resource #0x108022f (res/drawable-mdpi/popup_bottom_dark.9.png) that varies with configuration!! 09-01 13:53:35.414 W/Zygote ( 29): Preloaded drawable resource #0x1080230 (res/drawable-mdpi/popup_bottom_medium.9.png) that varies with configuration!! 09-01 13:53:35.424 W/Zygote ( 29): Preloaded drawable resource #0x1080231 (res/drawable-mdpi/popup_center_bright.9.png) that varies with configuration!! 09-01 13:53:35.434 W/Zygote ( 29): Preloaded drawable resource #0x1080232 (res/drawable-mdpi/popup_center_dark.9.png) that varies with configuration!! 09-01 13:53:35.454 W/Zygote ( 29): Preloaded drawable resource #0x1080235 (res/drawable-mdpi/popup_full_dark.9.png) that varies with configuration!! 09-01 13:53:35.474 W/Zygote ( 29): Preloaded drawable resource #0x1080238 (res/drawable-mdpi/popup_top_bright.9.png) that varies with configuration!! 09-01 13:53:35.484 W/Zygote ( 29): Preloaded drawable resource #0x1080239 (res/drawable-mdpi/popup_top_dark.9.png) that varies with configuration!! 09-01 13:53:35.524 W/Zygote ( 29): Preloaded drawable resource #0x108006d (res/drawable/progress_indeterminate_horizontal.xml) that varies with configuration!! 09-01 13:53:35.543 W/Zygote ( 29): Preloaded drawable resource #0x108023f (res/drawable/progress_small.xml) that varies with configuration!! 09-01 13:53:35.553 W/Zygote ( 29): Preloaded drawable resource #0x1080240 (res/drawable/progress_small_titlebar.xml) that varies with configuration!! 09-01 13:53:35.654 D/dalvikvm( 29): GC freed 452 objects / 31816 bytes in 97ms 09-01 13:53:35.684 W/Zygote ( 29): Preloaded drawable resource #0x1080262 (res/drawable-mdpi/scrollbar_handle_horizontal.9.png) that varies with configuration!! 09-01 13:53:35.694 W/Zygote ( 29): Preloaded drawable resource #0x1080263 (res/drawable-mdpi/scrollbar_handle_vertical.9.png) that varies with configuration!! 09-01 13:53:35.724 W/Zygote ( 29): Preloaded drawable resource #0x1080071 (res/drawable/spinner_dropdown_background.xml) that varies with configuration!! 09-01 13:53:35.744 W/Zygote ( 29): Preloaded drawable resource #0x1080326 (res/drawable-mdpi/title_bar_shadow.9.png) that varies with configuration!! 09-01 13:53:35.764 W/Zygote ( 29): Preloaded drawable resource #0x10801c6 (res/drawable-mdpi/indicator_code_lock_drag_direction_green_up.png) that varies with configuration!! 09-01 13:53:35.774 W/Zygote ( 29): Preloaded drawable resource #0x10801c7 (res/drawable-mdpi/indicator_code_lock_drag_direction_red_up.png) that varies with configuration!! 09-01 13:53:35.874 D/dalvikvm( 29): GC freed 207 objects / 11648 bytes in 92ms 09-01 13:53:35.894 W/Zygote ( 29): Preloaded drawable resource #0x10801c8 (res/drawable-mdpi/indicator_code_lock_point_area_default.png) that varies with configuration!! 09-01 13:53:35.924 W/Zygote ( 29): Preloaded drawable resource #0x10801c9 (res/drawable-mdpi/indicator_code_lock_point_area_green.png) that varies with configuration!! 09-01 13:53:35.943 W/Zygote ( 29): Preloaded drawable resource #0x10801ca (res/drawable-mdpi/indicator_code_lock_point_area_red.png) that varies with configuration!! 09-01 13:53:35.954 I/Zygote ( 29): ...preloaded 48 resources in 1705ms. 09-01 13:53:35.994 I/Zygote ( 29): ...preloaded 15 resources in 43ms. 09-01 13:53:36.094 D/dalvikvm( 29): GC freed 142 objects / 11904 bytes in 93ms 09-01 13:53:36.204 D/dalvikvm( 29): GC freed 127 objects / 5104 bytes in 97ms 09-01 13:53:36.304 D/dalvikvm( 29): GC freed 14 objects / 496 bytes in 91ms 09-01 13:53:36.324 I/dalvikvm( 29): Splitting out new zygote heap 09-01 13:53:36.354 I/dalvikvm( 29): System server process 60 has been created 09-01 13:53:36.364 I/Zygote ( 29): Accepting command socket connections 09-01 13:53:36.614 D/dalvikvm( 60): Trying to load lib /system/lib/libandroid_servers.so 0x0 09-01 13:53:36.984 D/dalvikvm( 60): Added shared lib /system/lib/libandroid_servers.so 0x0 09-01 13:53:37.024 E/BatteryService( 60): usbOnlinePath not found 09-01 13:53:37.024 E/BatteryService( 60): batteryVoltagePath not found 09-01 13:53:37.034 E/BatteryService( 60): batteryTemperaturePath not found 09-01 13:53:37.054 I/sysproc ( 60): Entered system_init() 09-01 13:53:37.064 I/sysproc ( 60): ServiceManager: 0x11c6b8 09-01 13:53:37.074 I/SurfaceFlinger( 60): SurfaceFlinger is starting 09-01 13:53:37.084 I/SurfaceFlinger( 60): SurfaceFlinger's main thread ready to run. Initializing graphics H/W... 09-01 13:53:37.104 E/SurfaceFlinger( 60): Couldn't open /sys/power/wait_for_fb_sleep or /sys/power/wait_for_fb_wake 09-01 13:53:37.144 I/gralloc ( 60): using (fd=22) 09-01 13:53:37.144 I/gralloc ( 60): id = 09-01 13:53:37.144 I/gralloc ( 60): xres = 320 px 09-01 13:53:37.144 I/gralloc ( 60): yres = 480 px 09-01 13:53:37.144 I/gralloc ( 60): xres_virtual = 320 px 09-01 13:53:37.144 I/gralloc ( 60): yres_virtual = 960 px 09-01 13:53:37.144 I/gralloc ( 60): bpp = 16 09-01 13:53:37.144 I/gralloc ( 60): r = 11:5 09-01 13:53:37.144 I/gralloc ( 60): g = 5:6 09-01 13:53:37.144 I/gralloc ( 60): b = 0:5 09-01 13:53:37.154 I/gralloc ( 60): width = 49 mm (165.877548 dpi) 09-01 13:53:37.154 I/gralloc ( 60): height = 74 mm (164.756760 dpi) 09-01 13:53:37.154 I/gralloc ( 60): refresh rate = 60.00 Hz 09-01 13:53:37.174 D/libEGL ( 60): egl.cfg not found, using default config 09-01 13:53:37.183 D/libEGL ( 60): loaded /system/lib/egl/libGLES_android.so 09-01 13:53:37.223 I/SurfaceFlinger( 60): EGL informations: 09-01 13:53:37.223 I/SurfaceFlinger( 60): # of configs : 8 09-01 13:53:37.223 I/SurfaceFlinger( 60): vendor : Android 09-01 13:53:37.233 I/SurfaceFlinger( 60): version : 1.4 Android META-EGL 09-01 13:53:37.233 I/SurfaceFlinger( 60): extensions: EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_ANDROID_image_native_buffer EGL_ANDROID_swap_rectangle EGL_ANDROID_get_render_buffer 09-01 13:53:37.233 I/SurfaceFlinger( 60): Client API: OpenGL ES 09-01 13:53:37.243 I/SurfaceFlinger( 60): EGLSurface: 5-6-5-0, config=0x1000000 09-01 13:53:37.243 I/SurfaceFlinger( 60): flags : 001c0000 09-01 13:53:37.264 I/SurfaceFlinger( 60): OpenGL informations: 09-01 13:53:37.264 I/SurfaceFlinger( 60): vendor : Android 09-01 13:53:37.274 I/SurfaceFlinger( 60): renderer : Android PixelFlinger 1.2 09-01 13:53:37.274 I/SurfaceFlinger( 60): version : OpenGL ES-CM 1.0 09-01 13:53:37.274 I/SurfaceFlinger( 60): 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_OES_EGL_image GL_ARB_texture_compression GL_ARB_texture_non_power_of_two GL_ANDROID_user_clip_plane GL_ANDROID_vertex_buffer_object GL_ANDROID_generate_mipmap 09-01 13:53:37.304 I/sysproc ( 60): System server: starting Android runtime. 09-01 13:53:37.304 I/sysproc ( 60): System server: starting Android services. 09-01 13:53:37.304 I/SystemServer( 60): Entered the Android system server! 09-01 13:53:37.314 I/sysproc ( 60): System server: entering thread pool. 09-01 13:53:37.434 I/SystemServer( 60): Entropy Service 09-01 13:53:37.544 I/SystemServer( 60): Power Manager 09-01 13:53:37.613 I/SystemServer( 60): Activity Manager 09-01 13:53:37.653 I/ActivityManager( 60): Memory class: 16 09-01 13:53:37.784 W/UsageStats( 60): Usage stats version changed; dropping 09-01 13:53:37.974 I/BootAnimation( 70): boot animation disabled 09-01 13:53:38.054 I/SystemServer( 60): Telephony Registry 09-01 13:53:38.073 I/SystemServer( 60): Package Manager 09-01 13:53:38.093 I/Installer( 60): connecting... 09-01 13:53:38.104 I/installd( 31): new connection 09-01 13:53:38.294 I/PackageManager( 60): Libs: android.test.runner:/system/framework/android.test.runner.jar javax.obex:/system/framework/javax.obex.jar 09-01 13:53:38.294 I/PackageManager( 60): Features: android.hardware.camera android.hardware.wifi android.hardware.location.network android.hardware.bluetooth android.hardware.location android.hardware.location.gps android.hardware.camera.autofocus android.hardware.touchscreen android.hardware.sensor.accelerometer android.hardware.sensor.compass 09-01 13:53:39.023 D/dalvikvm( 60): GC freed 6101 objects / 265520 bytes in 217ms 09-01 13:53:39.093 W/PackageManager( 60): Running ENG build: no pre-dexopt! 09-01 13:53:39.243 D/PackageManager( 60): Scanning app dir /system/framework 09-01 13:53:39.883 D/PackageManager( 60): Scanning app dir /system/app 09-01 13:53:42.353 D/dalvikvm( 60): GC freed 4441 objects / 282232 bytes in 159ms 09-01 13:53:43.673 D/PackageManager( 60): Scanning app dir /data/app 09-01 13:53:43.703 I/PackageParser( 60): com.att.nismob.android.build: compat added android.permission.WRITE_EXTERNAL_STORAGE android.permission.READ_PHONE_STATE 09-01 13:53:43.723 I/PackageParser( 60): com.att.nismob.android.test: compat added android.permission.WRITE_EXTERNAL_STORAGE android.permission.READ_PHONE_STATE 09-01 13:53:43.733 D/PackageManager( 60): Scanning app dir /data/app-private 09-01 13:53:43.743 I/PackageManager( 60): Time to scan packages: 4.651 seconds 09-01 13:53:43.753 W/PackageManager( 60): Unknown permission com.google.android.providers.gmail.permission.WRITE_GMAIL in package com.android.settings 09-01 13:53:43.753 W/PackageManager( 60): Unknown permission com.google.android.providers.gmail.permission.READ_GMAIL in package com.android.settings 09-01 13:53:43.763 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.settings 09-01 13:53:43.773 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.providers.contacts 09-01 13:53:43.773 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.cp in package com.android.providers.contacts 09-01 13:53:43.773 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.mail in package com.android.contacts 09-01 13:53:43.783 W/PackageManager( 60): Unknown permission android.permission.ADD_SYSTEM_SERVICE in package com.android.phone 09-01 13:53:43.793 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.development 09-01 13:53:43.793 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.ALL_SERVICES in package com.android.development 09-01 13:53:43.793 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.YouTubeUser in package com.android.development 09-01 13:53:43.803 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.ACCESS_GOOGLE_PASSWORD in package com.android.development 09-01 13:53:43.803 W/PackageManager( 60): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.browser 09-01 13:53:43.963 D/dalvikvm( 60): GC freed 5225 objects / 299976 bytes in 137ms 09-01 13:53:44.273 D/dalvikvm( 60): GC freed 2676 objects / 179952 bytes in 131ms 09-01 13:53:44.313 I/SystemServer( 60): Account Manager 09-01 13:53:44.343 W/ResourceType( 60): Resources don't contain package for resource number 0x7f0700e5 09-01 13:53:44.353 W/ResourceType( 60): Resources don't contain package for resource number 0x7f020031 09-01 13:53:44.353 W/ResourceType( 60): Resources don't contain package for resource number 0x7f020030 09-01 13:53:44.353 W/ResourceType( 60): Resources don't contain package for resource number 0x7f050000 09-01 13:53:44.383 I/SystemServer( 60): Content Manager 09-01 13:53:44.403 I/SyncManager( 60): No initial accounts 09-01 13:53:44.413 I/SyncManager( 60): No initial status 09-01 13:53:44.413 I/SyncManager( 60): No initial pending operations 09-01 13:53:44.423 I/SyncManager( 60): No initial statistics 09-01 13:53:44.643 I/SystemServer( 60): System Content Providers 09-01 13:53:44.653 I/ActivityThread( 60): Publishing provider settings: com.android.providers.settings.SettingsProvider 09-01 13:53:44.693 I/SystemServer( 60): Battery Service 09-01 13:53:44.733 I/SystemServer( 60): Hardware Service 09-01 13:53:44.743 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10 09-01 13:53:44.743 D/qemud ( 34): created client 0x11fb8 listening on fd 12 09-01 13:53:44.743 D/qemud ( 34): client_fd_receive: attempting registration for service 'hw-control' 09-01 13:53:44.743 D/qemud ( 34): client_fd_receive: -> received channel id 3 09-01 13:53:44.754 D/qemud ( 34): client_registration: registration succeeded for client 3 09-01 13:53:44.923 I/SystemServer( 60): Alarm Manager 09-01 13:53:44.943 I/SystemServer( 60): Init Watchdog 09-01 13:53:44.953 I/SystemServer( 60): Sensor Service 09-01 13:53:44.963 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10 09-01 13:53:44.963 D/qemud ( 34): created client 0xc038 listening on fd 13 09-01 13:53:44.973 D/qemud ( 34): client_fd_receive: attempting registration for service 'sensors' 09-01 13:53:44.973 D/qemud ( 34): client_fd_receive: -> received channel id 4 09-01 13:53:45.075 D/qemud ( 34): client_registration: registration succeeded for client 4 09-01 13:53:45.197 D/qemud ( 34): fdhandler_event: disconnect on fd 13 09-01 13:53:45.313 I/SystemServer( 60): Window Manager 09-01 13:53:45.735 I/EventHub( 60): New keyboard: publicID=65536 device->id=0x10000 devname='qwerty2' propName='hw.keyboards.65536.devname' keylayout='/system/usr/keylayout/qwerty.kl' 09-01 13:53:45.735 I/EventHub( 60): New device: path=/dev/input/event0 name=qwerty2 id=0x10000 (of 0x1) index=1 fd=45 classes=0x2f 09-01 13:53:45.778 E/EventHub( 60): could not get driver version for /dev/input/mouse0, Not a typewriter 09-01 13:53:45.778 E/EventHub( 60): could not get driver version for /dev/input/mice, Not a typewriter 09-01 13:53:45.778 I/KeyInputQueue( 60): Device added: id=0x0, name=qwerty2, classes=2f 09-01 13:53:45.778 I/KeyInputQueue( 60): X: unknown values 09-01 13:53:45.778 I/KeyInputQueue( 60): Y: unknown values 09-01 13:53:45.778 I/KeyInputQueue( 60): Pressure: unknown values 09-01 13:53:45.821 I/KeyInputQueue( 60): Size: unknown values 09-01 13:53:45.821 I/KeyInputQueue( 60): No virtual keys found 09-01 13:53:46.654 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10 09-01 13:53:46.654 D/qemud ( 34): created client 0xc038 listening on fd 13 09-01 13:53:46.699 D/qemud ( 34): client_fd_receive: attempting registration for service 'sensors' 09-01 13:53:46.699 D/qemud ( 34): client_fd_receive: -> received channel id 5 09-01 13:53:46.865 D/qemud ( 34): client_registration: registration succeeded for client 5 09-01 13:53:46.923 D/qemud ( 34): fdhandler_event: disconnect on fd 13 09-01 13:53:46.923 D/SensorManager( 60): found sensor: Goldfish 3-axis Accelerometer, handle=0 09-01 13:53:47.127 I/SystemServer( 60): Registering null Bluetooth Service (emulator) 09-01 13:53:47.127 E/System ( 60): Failure starting core service 09-01 13:53:47.127 E/System ( 60): java.lang.SecurityException 09-01 13:53:47.127 E/System ( 60): at android.os.BinderProxy.transact(Native Method) 09-01 13:53:47.127 E/System ( 60): at android.os.ServiceManagerProxy.addService(ServiceManagerNative.java:146) 09-01 13:53:47.127 E/System ( 60): at android.os.ServiceManager.addService(ServiceManager.java:72) 09-01 13:53:47.127 E/System ( 60): at com.android.server.ServerThread.run(SystemServer.java:176) 09-01 13:53:47.169 E/AndroidRuntime( 60): Crash logging skipped, no checkin service 09-01 13:53:47.169 I/SystemServer( 60): Status Bar 09-01 13:53:47.875 I/SystemServer( 60): Clipboard Service 09-01 13:53:47.875 I/SystemServer( 60): Input Method Service 09-01 13:53:47.918 W/ResourceType( 60): Resources don't contain package for resource number 0x7f060000 09-01 13:53:47.961 W/ResourceType( 60): Resources don't contain package for resource number 0x7f060001 09-01 13:53:47.961 I/InputManagerService( 60): Enabled input methods: com.android.inputmethod.pinyin/.PinyinIME:com.android.inputmethod.latin/.LatinIME:jp.co.omronsoft.openwnn/.OpenWnnJAJP 09-01 13:53:48.047 I/SystemServer( 60): NetStat Service 09-01 13:53:48.047 I/SystemServer( 60): Connectivity Service 09-01 13:53:48.090 V/ConnectivityService( 60): ConnectivityService starting up 09-01 13:53:48.090 V/ConnectivityService( 60): Starting Wifi Service. 09-01 13:53:48.219 I/WifiService( 60): WifiService starting up with Wi-Fi disabled 09-01 13:53:48.261 I/SystemServer( 60): Accessibility Manager 09-01 13:53:48.305 I/SystemServer( 60): Notification Manager 09-01 13:53:48.348 I/SystemServer( 60): Mount Service 09-01 13:53:48.391 I/SystemServer( 60): Device Storage Monitor 09-01 13:53:48.433 I/SystemServer( 60): Location Manager 09-01 13:53:48.433 I/SystemServer( 60): Search Service 09-01 13:53:48.477 I/SystemServer( 60): Checkin Service 09-01 13:53:48.477 D/libhardware_legacy( 60): using QEMU GPS Hardware emulation 09-01 13:53:48.477 W/ActivityManager( 60): Unable to start service Intent { cmp=com.google.android.server.checkin/.CheckinService } : not found 09-01 13:53:48.477 W/SystemServer( 60): Using fallback Checkin Service. 09-01 13:53:48.477 I/SystemServer( 60): Wallpaper Service 09-01 13:53:48.520 I/SystemServer( 60): Audio Service 09-01 13:53:48.562 W/GpsLocationProvider( 60): Could not open GPS configuration file /etc/gps.conf 09-01 13:53:48.907 D/GpsLocationProvider( 60): enable 09-01 13:53:48.995 D/dalvikvm( 60): Trying to load lib /system/lib/libsoundpool.so 0x0 09-01 13:53:49.038 D/dalvikvm( 60): Added shared lib /system/lib/libsoundpool.so 0x0 09-01 13:53:49.038 D/SoundPool( 60): error loading /system/media/audio/ui/Effect_Tick.ogg 09-01 13:53:49.038 W/AudioService( 60): Soundpool could not load file: /system/media/audio/ui/Effect_Tick.ogg 09-01 13:53:49.038 D/SoundPool( 60): error loading /system/media/audio/ui/KeypressStandard.ogg 09-01 13:53:49.038 W/AudioService( 60): Soundpool could not load file: /system/media/audio/ui/KeypressStandard.ogg 09-01 13:53:49.081 D/SoundPool( 60): error loading /system/media/audio/ui/KeypressSpacebar.ogg 09-01 13:53:49.081 W/AudioService( 60): Soundpool could not load file: /system/media/audio/ui/KeypressSpacebar.ogg 09-01 13:53:49.081 D/SoundPool( 60): error loading /system/media/audio/ui/KeypressDelete.ogg 09-01 13:53:49.081 W/AudioService( 60): Soundpool could not load file: /system/media/audio/ui/KeypressDelete.ogg 09-01 13:53:49.081 D/SoundPool( 60): error loading /system/media/audio/ui/KeypressReturn.ogg 09-01 13:53:49.081 W/AudioService( 60): Soundpool could not load file: /system/media/audio/ui/KeypressReturn.ogg 09-01 13:53:49.081 I/SystemServer( 60): Headset Observer 09-01 13:53:49.123 W/HeadsetObserver( 60): This kernel does not have wired headset support 09-01 13:53:49.123 I/SystemServer( 60): Dock Observer 09-01 13:53:49.123 W/DockObserver( 60): This kernel does not have dock station support 09-01 13:53:49.123 I/SystemServer( 60): Backup Service 09-01 13:53:49.339 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10 09-01 13:53:49.339 D/qemud ( 34): created client 0xc038 listening on fd 13 09-01 13:53:49.382 D/dalvikvm( 60): GC freed 3406 objects / 195768 bytes in 171ms 09-01 13:53:49.468 D/qemud ( 34): client_fd_receive: attempting registration for service 'gps' 09-01 13:53:49.468 D/qemud ( 34): client_fd_receive: -> received channel id 6 09-01 13:53:49.468 W/ActivityManager( 60): Unable to start service Intent { cmp=com.google.android.backup/.BackupTransportService } : not found 09-01 13:53:49.468 I/BackupManagerService( 60): Found stale backup journal, scheduling: 09-01 13:53:49.468 I/BackupManagerService( 60): + android 09-01 13:53:49.468 I/BackupManagerService( 60): + com.android.inputmethod.latin 09-01 13:53:49.510 D/qemud ( 34): client_registration: registration succeeded for client 6 09-01 13:53:49.510 I/BackupManagerService( 60): + com.android.browser 09-01 13:53:49.510 I/BackupManagerService( 60): + com.android.providers.userdictionary 09-01 13:53:49.510 I/BackupManagerService( 60): + com.android.providers.settings 09-01 13:53:49.553 I/BackupManagerService( 60): Backup enabled => false 09-01 13:53:49.595 I/SystemServer( 60): AppWidget Service 09-01 13:53:49.729 I/WindowManager( 60): SAFE MODE not enabled 09-01 13:53:49.773 I/ActivityManager( 60): Config changed: { scale=1.0 imsi=0/0 loc=en_US touch=3 keys=2/1/1 nav=3/1 orien=1 layout=18} 09-01 13:53:49.773 D/PowerManagerService( 60): system ready! 09-01 13:53:49.815 I/ActivityManager( 60): System now ready 09-01 13:53:49.900 I/SystemServer( 60): Making services ready 09-01 13:53:49.986 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030000 09-01 13:53:50.030 D/GpsLocationProvider( 60): GpsEventThread starting 09-01 13:53:50.117 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030003 09-01 13:53:50.117 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030034 09-01 13:53:50.159 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030012 09-01 13:53:50.288 I/Zygote ( 60): Process: zygote socket opened 09-01 13:53:50.331 I/ActivityManager( 60): Start proc com.android.inputmethod.pinyin for service com.android.inputmethod.pinyin/.PinyinIME: pid=100 uid=10007 gids= {3003, 1015} 09-01 13:53:50.547 I/ActivityManager( 60): Start proc com.android.phone for added application com.android.phone: pid=102 uid=1001 gids={3002, 3001, 3003, 1015} 09-01 13:53:50.547 I/ActivityManager( 60): Starting activity: Intent { act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10000000 cmp=com.android.launcher/.Launcher } 09-01 13:53:50.632 D/GpsLocationProvider( 60): NetworkThread starting 09-01 13:53:50.632 D/GpsLocationProvider( 60): NetworkThread wait for network 09-01 13:53:50.893 I/ActivityManager( 60): Start proc android.process.acore for activity com.android.launcher/.Launcher: pid=106 uid=10007 gids={3003, 1015} 09-01 13:53:52.066 D/dalvikvm( 60): GC freed 3262 objects / 177816 bytes in 481ms 09-01 13:53:53.169 W/StatusBar( 60): No icon ID for slot ime 09-01 13:53:53.555 I/ActivityManager( 60): Start proc com.android.settings for broadcast com.android.settings/.widget.SettingsAppWidgetProvider: pid=120 uid=1000 gids= {3002, 3001, 3003} 09-01 13:53:55.194 D/dalvikvm( 100): Trying to load lib /system/lib/libjni_pinyinime.so 0x43b86298 09-01 13:53:55.194 D/dalvikvm( 100): Added shared lib /system/lib/libjni_pinyinime.so 0x43b86298 09-01 13:53:55.280 I/ActivityThread( 106): Publishing provider com.android.globalsearch.stats: com.android.globalsearch.StatsProvider 09-01 13:53:55.366 I/ARMAssembler( 60): generated scanline__00000177:03515104_00000A01_00000000 [ 55 ipp] (79 ins) at [0x3106c8:0x310804] in 14671000 ns 09-01 13:53:56.525 I/ActivityThread( 102): Publishing provider icc: com.android.phone.IccProvider 09-01 13:53:56.831 I/ActivityThread( 106): Publishing provider com.android.launcher.settings: com.android.launcher.LauncherProvider 09-01 13:53:57.008 I/ActivityThread( 106): Publishing provider com.android.social: com.android.providers.contacts.SocialProvider 09-01 13:53:57.445 D/dalvikvm( 60): GC freed 2631 objects / 137120 bytes in 381ms 09-01 13:53:57.617 I/ARMAssembler( 60): generated scanline__00000077:03545404_00000A04_00000000 [ 29 ipp] (51 ins) at [0x3e3740:0x3e380c] in 2196000 ns 09-01 13:53:57.702 I/ActivityThread( 102): Publishing provider mms-sms: com.android.providers.telephony.MmsSmsProvider 09-01 13:53:58.266 I/ActivityThread( 102): Publishing provider mms: com.android.providers.telephony.MmsProvider 09-01 13:53:58.478 I/ActivityThread( 102): Publishing provider sms: com.android.providers.telephony.SmsProvider 09-01 13:53:58.611 D/dalvikvm( 60): GC freed 657 objects / 52688 bytes in 661ms 09-01 13:53:58.822 I/ActivityThread( 102): Publishing provider telephony: com.android.providers.telephony.TelephonyProvider 09-01 13:53:59.940 I/ActivityThread( 106): Publishing provider applications: com.android.providers.applications.ApplicationsProvider 09-01 13:54:00.027 D/dalvikvm( 60): GC freed 821 objects / 41648 bytes in 409ms 09-01 13:54:01.315 I/ActivityThread( 106): Publishing provider contacts;com.android.contacts: com.android.providers.contacts.ContactsProvider2 09-01 13:54:01.573 I/ARMAssembler( 60): generated scanline__00000177:03515104_00001A01_00000000 [ 73 ipp] (98 ins) at [0x43fc48:0x43fdd0] in 22802001 ns 09-01 13:54:02.045 D/PhoneApp( 102): mProximityWakeLock: null 09-01 13:54:02.173 W/ActivityManager( 60): Unable to start service Intent { act=com.android.ussd.IExtendedNetworkService } : not found 09-01 13:54:02.259 D/PhoneApp( 102): Resetting audio state/mode: IDLE 09-01 13:54:02.302 D/dalvikvm( 60): GREF has increased to 201 09-01 13:54:02.558 D/AlarmManagerService( 60): Kernel timezone updated to 420 minutes west of GMT 09-01 13:54:02.603 D/SystemClock( 102): Setting time of day to sec=1283374442 09-01 13:54:02.413 I/ActivityManager( 60): Start proc com.android.alarmclock for broadcast com.android.alarmclock/.AlarmInitReceiver: pid=140 uid=10017 gids={} 09-01 13:54:02.413 W/SystemClock( 102): Unable to set rtc to 1283374442: Invalid argument 09-01 13:54:02.543 D/MobileDataStateTracker( 60): default Received state= DISCONNECTED, old= DISCONNECTED, reason= radioTurnedOff, apnTypeList= default 09-01 13:54:02.629 D/MobileDataStateTracker( 60): default Received state= DISCONNECTED, old= DISCONNECTED, reason= gprsDetached, apnTypeList= default 09-01 13:54:03.236 W/ActivityManager( 60): Activity idle timeout for HistoryRecord {43ca69c0 com.android.launcher/.Launcher} 09-01 13:54:03.546 D/PowerManagerService( 60): bootCompleted 09-01 13:54:03.546 D/qemud ( 34): fdhandler_accept_event: accepting on fd 10 09-01 13:54:03.546 D/qemud ( 34): created client 0xc088 listening on fd 14 09-01 13:54:03.546 D/qemud ( 34): client_fd_receive: attempting registration for service 'sensors' 09-01 13:54:03.589 D/qemud ( 34): client_fd_receive: -> received channel id 7 09-01 13:54:03.589 D/qemud ( 34): client_registration: registration succeeded for client 7 09-01 13:54:03.823 I/ActivityThread( 140): Publishing provider com.android.alarmclock: com.android.alarmclock.AlarmProvider 09-01 13:54:03.952 D/vold ( 26): Accepted connection from framework 09-01 13:54:03.995 D/vold ( 26): dispatch_cmd(send_ums_status): 09-01 13:54:03.995 D/vold ( 26): dispatch_cmd(mount_volume:/sdcard): 09-01 13:54:03.995 E/vold ( 26): Cannot start volume '/sdcard' (volume is not bound) 09-01 13:54:03.995 D/MountListener( 60): handleEvent volume_nomedia:/sdcard 09-01 13:54:04.126 D/MountListener( 60): handleEvent ums_disabled 09-01 13:54:04.126 D/MediaPlayer( 60): Couldn't open file on client side, trying server side 09-01 13:54:04.172 D/MountListener( 60): handleEvent ums_disconnected 09-01 13:54:04.350 E/MediaPlayerService( 30): Couldn't open fd for content://settings/system/notification_sound 09-01 13:54:04.350 E/MediaPlayer( 60): Unable to to create media player 09-01 13:54:04.633 W/NotificationService( 60): error loading sound for content://settings/system/notification_sound 09-01 13:54:04.633 W/NotificationService( 60): java.io.IOException: setDataSource failed.: status=0x80000000 09-01 13:54:04.633 W/NotificationService( 60): at android.media.MediaPlayer.setDataSource(Native Method) 09-01 13:54:04.633 W/NotificationService( 60): at android.media.MediaPlayer.setDataSource(MediaPlayer.java:699) 09-01 13:54:04.633 W/NotificationService( 60): at android.media.AsyncPlayer.startSound(AsyncPlayer.java:62) 09-01 13:54:04.633 W/NotificationService( 60): at android.media.AsyncPlayer.access$200(AsyncPlayer.java:33) 09-01 13:54:04.633 W/NotificationService( 60): at android.media.AsyncPlayer$Thread.run(AsyncPlayer.java:99) 09-01 13:54:05.033 D/dalvikvm( 102): GC freed 3750 objects / 232656 bytes in 586ms 09-01 13:54:05.161 E/ActivityThread( 102): Failed to find provider info for android.server.checkin 09-01 13:54:05.161 W/Checkin ( 102): Can't update stat PHONE_GSM_REGISTERED: java.lang.IllegalArgumentException: Unknown URL content://android.server.checkin/stats 09-01 13:54:05.674 D/MccTable( 102): updateMccMncConfiguration: mcc=310, mnc=260 09-01 13:54:05.759 D/MccTable( 102): locale set to en_us 09-01 13:54:05.801 D/MccTable( 102): WIFI_NUM_ALLOWED_CHANNELS set to 11 09-01 13:54:05.801 I/WifiService( 60): WifiService trying to setNumAllowed to 11 with persist set to true 09-01 13:54:05.844 W/BackupManagerService( 60): dataChanged but no participant pkg='com.android.providers.settings' uid=1001 09-01 13:54:05.844 I/ActivityManager( 60): Config changed: { scale=1.0 imsi=310/260 loc=en_US touch=3 keys=2/1/1 nav=3/1 orien=1 layout=18} 09-01 13:54:06.016 I/UsageStats( 60): Unexpected resume of com.android.launcher while already resumed in com.android.launcher 09-01 13:54:06.509 D/MobileDataStateTracker( 60): default Received state= DISCONNECTED, old= DISCONNECTED, reason= (unspecified), apnTypeList= default 09-01 13:54:06.509 W/BackupManagerService( 60): dataChanged but no participant pkg='com.android.providers.settings' uid=10017 09-01 13:54:06.988 D/TelephonyProvider( 102): Setting numeric '310260' to be the current operator 09-01 13:54:07.031 D/OtaStartupReceiver( 102): Not a CDMA phone, no need to process OTA 09-01 13:54:07.031 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030012 09-01 13:54:07.116 E/ActivityThread( 102): Failed to find provider info for android.server.checkin 09-01 13:54:07.116 W/Checkin ( 102): Can't update stat PHONE_GPRS_ATTEMPTED: java.lang.IllegalArgumentException: Unknown URL content://android.server.checkin/stats 09-01 13:54:07.159 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030034 09-01 13:54:07.159 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030003 09-01 13:54:07.201 W/ResourceType( 60): Resources don't contain package for resource number 0x7f030000 09-01 13:54:07.330 E/ActivityThread( 102): Failed to find provider info for android.server.checkin 09-01 13:54:07.330 W/Checkin ( 102): Can't update stat PHONE_GPRS_CONNECTED: java.lang.IllegalArgumentException: Unknown URL content://android.server.checkin/stats 09-01 13:54:07.672 I/ActivityManager( 60): Start proc android.process.media for broadcast com.android.providers.media/.MediaScannerReceiver: pid=153 uid=10003 gids= {1006, 1015, 2001, 3003} 09-01 13:54:07.672 D/MobileDataStateTracker( 60): default Received state= CONNECTING, old= DISCONNECTED, reason= simLoaded, apnTypeList= * 09-01 13:54:07.714 D/NetworkStateTracker( 60): setDetailed state, old =IDLE and new state=CONNECTING 09-01 13:54:07.714 D/ConnectivityService( 60): ConnectivityChange for MOBILE: CONNECTING/CONNECTING 09-01 13:54:07.886 D/MobileDataStateTracker( 60): default Received state= CONNECTED, old= CONNECTING, reason= simLoaded, apnTypeList= * 09-01 13:54:07.886 D/MobileDataStateTracker( 60): CONNECTED event did not supply interface name. 09-01 13:54:07.886 D/NetworkStateTracker( 60): setDetailed state, old =CONNECTING and new state=CONNECTED 09-01 13:54:07.886 D/ConnectivityService( 60): ConnectivityChange for MOBILE: CONNECTED/CONNECTED 09-01 13:54:07.928 V/NetworkStateTracker( 60): Setting TCP values: [4094,87380,110208,4096,16384,110208] which comes from [net.tcp.buffersize.umts] 09-01 13:54:07.970 D/MobileDataStateTracker( 60): replacing old mInterfaceName (null) with null for mms 09-01 13:54:07.970 D/MobileDataStateTracker( 60): replacing old mInterfaceName (null) with null for supl 09-01 13:54:07.970 D/MobileDataStateTracker( 60): replacing old mInterfaceName (null) with null for dun 09-01 13:54:07.970 D/MobileDataStateTracker( 60): replacing old mInterfaceName (null) with null for hipri 09-01 13:54:08.013 D/ConnectivityService( 60): adding dns 10.0.2.3 for MOBILE 09-01 13:54:08.013 D/ConnectivityService( 60): adding dns 10.0.2.4 for MOBILE 09-01 13:54:08.142 D/GpsLocationProvider( 60): updateNetworkState available info: NetworkInfo: type: MOBILE [UMTS] , state: CONNECTED/CONNECTED, reason: simLoaded, extra: internet, roaming: false, failover: false, isAvailable: true 09-01 13:54:08.142 D/GpsLocationProvider( 60): NetworkThread wait for 9223372036854775807ms 09-01 13:54:08.442 I/ActivityThread( 153): Publishing provider drm: com.android.providers.drm.DrmProvider 09-01 13:54:08.528 I/ActivityThread( 153): Publishing provider media: com.android.providers.media.MediaProvider 09-01 13:54:08.728 V/MediaProvider( 153): Attached volume: internal 09-01 13:54:08.814 I/ActivityThread( 153): Publishing provider downloads: com.android.providers.downloads.DownloadProvider 09-01 13:54:09.305 W/ContactAggregator( 106): No more aggregation requests 09-01 13:54:09.738 I/ActivityManager( 60): Start proc com.android.mms for broadcast com.android.mms/.transaction.MmsSystemEventReceiver: pid=161 uid=10014 gids= {3003, 1015} 09-01 13:54:09.823 I/ActivityThread( 106): Publishing provider com.android.globalsearch.SuggestionProvider: com.android.globalsearch.SuggestionProvider 09-01 13:54:10.326 D/MediaScannerService( 153): start scanning volume internal 09-01 13:54:11.000 W/WindowManager( 60): App freeze timeout expired. 09-01 13:54:11.000 W/WindowManager( 60): Force clearing freeze: AppWindowToken{43cb4190 token=HistoryRecord{43ca69c0 com.android.launcher/.Launcher}} 09-01 13:54:11.639 D/dalvikvm( 106): GC freed 3051 objects / 220360 bytes in 402ms 09-01 13:54:13.337 D/dalvikvm( 60): GC freed 12147 objects / 654120 bytes in 313ms 09-01 13:54:13.955 W/BackupManagerService( 60): dataChanged but no participant pkg='com.android.providers.settings' uid=10017 09-01 13:54:14.083 I/ActivityThread( 106): Publishing provider call_log: com.android.providers.contacts.CallLogProvider 09-01 13:54:14.083 I/ActivityThread( 106): Publishing provider user_dictionary: com.android.providers.userdictionary.UserDictionaryProvider 09-01 13:54:14.254 W/BackupManagerService( 60): dataChanged but no participant pkg='com.android.providers.settings' uid=10017 09-01 13:54:14.446 I/ActivityManager( 60): Start proc com.android.email for broadcast com.android.email/com.android.exchange.BootReceiver: pid=180 uid=10023 gids={3003, 1015} 09-01 13:54:18.734 I/ActivityThread( 180): Publishing provider com.android.email.provider: com.android.email.provider.EmailProvider 09-01 13:54:18.776 I/ActivityThread( 180): Publishing provider com.android.email.attachmentprovider: com.android.email.provider.AttachmentProvider 09-01 13:54:19.289 D/Exchange( 180): BootReceiver onReceive 09-01 13:54:19.418 D/EAS SyncManager( 180): !!! EAS SyncManager, onCreate 09-01 13:54:19.933 D/EAS SyncManager( 180): !!! EAS SyncManager, onStartCommand 09-01 13:54:19.976 D/EAS SyncManager( 180): !!! EAS SyncManager, stopping self 09-01 13:54:20.062 D/Eas Debug( 180): Logging: 09-01 13:54:20.105 D/EAS SyncManager( 180): !!! EAS SyncManager, onDestroy 09-01 13:54:20.877 I/ActivityManager( 60): Displayed activity com.android.launcher/.Launcher: 30389 ms (total 30389 ms) 09-01 13:54:21.025 I/SurfaceFlinger( 60): Boot is finished (44235 ms) 09-01 13:54:21.025 I/WindowManager( 60): Setting rotation to 1, animFlags=1 09-01 13:54:21.068 I/ActivityManager( 60): Config changed: { scale=1.0 imsi=310/260 loc=en_US touch=3 keys=2/1/1 nav=3/1 orien=2 layout=18} 09-01 13:54:21.197 I/UsageStats( 60): Unexpected resume of com.android.launcher while already resumed in com.android.launcher 09-01 13:54:22.140 D/dalvikvm( 106): GC freed 3356 objects / 214072 bytes in 161ms 09-01 13:54:22.580 I/ARMAssembler( 60): generated scanline__00000077:03010104_00000004_00000000 [ 22 ipp] (41 ins) at [0x424428:0x4244cc] in 2249000 ns 09-01 13:54:22.622 I/ARMAssembler( 60): generated scanline__00000177:03515104_00000001_00000000 [ 73 ipp] (95 ins) at [0x46cc50:0x46cdcc] in 1281000 ns 09-01 13:54:22.808 D/dalvikvm( 60): GREF has increased to 301 09-01 13:54:22.937 D/HomeLoaders( 106): ----> items cloned, ready to refresh UI 09-01 13:54:25.771 D/dalvikvm( 60): GC freed 5899 objects / 298864 bytes in 904ms 09-01 13:54:26.272 D/MediaScanner( 153): opendir /system/media/ failed, errno: 2 09-01 13:54:26.272 D/MediaScanner( 153): prescan time: 13830ms 09-01 13:54:26.272 D/MediaScanner( 153): scan time: 56ms 09-01 13:54:26.272 D/MediaScanner( 153): postscan time: 1ms 09-01 13:54:26.272 D/MediaScanner( 153): total time: 13887ms 09-01 13:54:26.315 D/MediaScannerService( 153): done scanning volume internal 09-01 13:54:44.417 D/AndroidRuntime( 198): 09-01 13:54:44.417 D/AndroidRuntime( 198): >>>>>>>>>>>>>> AndroidRuntime START <<<<<<<<<<<<<< 09-01 13:54:44.417 D/AndroidRuntime( 198): CheckJNI is ON 09-01 13:54:44.813 D/AndroidRuntime( 198): — registering native functions — 09-01 13:54:46.475 D/PackageParser( 60): Scanning package: /data/app/vmdl39163.tmp 09-01 13:54:46.518 I/PackageParser( 60): com.att.nismob.android.build: compat added android.permission.WRITE_EXTERNAL_STORAGE android.permission.READ_PHONE_STATE 09-01 13:54:47.044 I/PackageManager( 60): Removing non-system package:com.att.nismob.android.build 09-01 13:54:47.044 D/PackageManager( 60): Removing package com.att.nismob.android.build 09-01 13:54:47.044 D/PackageManager( 60): Activities: com.att.nismob.android.build.Build 09-01 13:54:47.172 D/PackageManager( 60): Scanning package com.att.nismob.android.build 09-01 13:54:47.214 I/PackageManager( 60): /data/app/vmdl39163.tmp changed; unpacking 09-01 13:54:47.274 D/installd( 31): DexInv: — BEGIN '/data/app/vmdl39163.tmp' — 09-01 13:54:49.111 D/dalvikvm( 204): DexOpt: load 152ms, verify 1178ms, opt 52ms 09-01 13:54:49.153 D/installd( 31): DexInv: — END '/data/app/vmdl39163.tmp' (success) — 09-01 13:54:49.153 D/PackageManager( 60): Activities: com.att.nismob.android.build.Build 09-01 13:54:49.196 D/ActivityManager( 60): Uninstalling process com.att.nismob.android.build 09-01 13:54:49.282 I/installd( 31): move /data/dalvik-cache/data@app@vmdl39163.tmp@classes.dex -> /data/dalvik-cache/data@app@com.att.nismob.android.build.apk@classes.dex 09-01 13:54:49.326 D/PackageManager( 60): New package installed in /data/app/com.att.nismob.android.build.apk 09-01 13:54:49.455 D/AndroidRuntime( 198): Shutting down VM 09-01 13:54:49.455 D/dalvikvm( 198): DestroyJavaVM waiting for non-daemon threads to exit 09-01 13:54:49.455 D/dalvikvm( 198): DestroyJavaVM shutting VM down 09-01 13:54:49.455 D/dalvikvm( 198): HeapWorker thread shutting down 09-01 13:54:49.541 D/dalvikvm( 198): HeapWorker thread has shut down 09-01 13:54:49.541 D/jdwp ( 198): JDWP shutting down net... 09-01 13:54:49.541 I/jdwp ( 198): adbd disconnected 09-01 13:54:49.541 D/dalvikvm( 198): VM cleaning up 09-01 13:54:49.541 D/ActivityManager( 60): Uninstalling process com.att.nismob.android.build 09-01 13:54:49.584 E/AndroidRuntime( 198): ERROR: thread attach failed 09-01 13:54:49.670 D/dalvikvm( 198): LinearAlloc 0x0 used 621708 of 5242880 (11%) 09-01 13:54:50.358 D/dalvikvm( 106): GC freed 2613 objects / 132504 bytes in 306ms 09-01 13:54:51.526 W/ResourceType( 60): Resources don't contain package for resource number 0x7f0700e5 09-01 13:54:51.611 I/ActivityManager( 60): Start proc com.svox.pico for broadcast com.svox.pico/.VoiceDataInstallerReceiver: pid=208 uid=10012 gids={} 09-01 13:54:53.694 W/ResourceType( 60): Resources don't contain package for resource number 0x7f020031 09-01 13:54:53.694 W/ResourceType( 60): Resources don't contain package for resource number 0x7f020030 09-01 13:54:53.694 W/ResourceType( 60): Resources don't contain package for resource number 0x7f050000 09-01 13:54:53.694 W/ResourceType( 60): Resources don't contain package for resource number 0x7f060000 09-01 13:54:53.909 W/ResourceType( 60): Resources don't contain package for resource number 0x7f060001 09-01 13:54:54.499 D/dalvikvm( 29): GC freed 277 objects / 10592 bytes in 796ms 09-01 13:54:54.713 W/ResourceType( 60): Resources don't contain package for resource number 0x7f0700e5 09-01 13:54:54.713 W/ResourceType( 60): Resources don't contain package for resource number 0x7f020031 09-01 13:54:54.713 W/ResourceType( 60): Resources don't contain package for resource number 0x7f020030 09-01 13:54:54.713 W/ResourceType( 60): Resources don't contain package for resource number 0x7f050000 09-01 13:54:54.713 W/ResourceType( 60): Resources don't contain package for resource number 0x7f060000 09-01 13:54:54.756 W/ResourceType( 60): Resources don't contain package for resource number 0x7f060001 09-01 13:54:55.444 D/dalvikvm( 29): GC freed 45 objects / 2016 bytes in 780ms 09-01 13:54:55.530 D/dalvikvm( 29): GC freed 2 objects / 48 bytes in 111ms 09-01 13:54:55.830 D/dalvikvm( 60): GC freed 6035 objects / 443576 bytes in 221ms 09-01 13:55:01.729 D/AndroidRuntime( 217): 09-01 13:55:01.729 D/AndroidRuntime( 217): >>>>>>>>>>>>>> AndroidRuntime START <<<<<<<<<<<<<< 09-01 13:55:01.771 D/AndroidRuntime( 217): CheckJNI is ON 09-01 13:55:02.100 D/AndroidRuntime( 217): — registering native functions — 09-01 13:55:03.515 D/PackageParser( 60): Scanning package: /data/app/vmdl39164.tmp 09-01 13:55:03.557 I/PackageParser( 60): com.att.nismob.android.test: compat added android.permission.WRITE_EXTERNAL_STORAGE android.permission.READ_PHONE_STATE 09-01 13:55:03.686 I/PackageManager( 60): Removing non-system package:com.att.nismob.android.test 09-01 13:55:03.686 D/PackageManager( 60): Removing package com.att.nismob.android.test 09-01 13:55:03.686 D/PackageManager( 60): Instrumentation: android.test.InstrumentationTestRunner 09-01 13:55:03.814 D/PackageManager( 60): Scanning package com.att.nismob.android.test 09-01 13:55:03.856 I/PackageManager( 60): /data/app/vmdl39164.tmp changed; unpacking 09-01 13:55:03.856 D/installd( 31): DexInv: — BEGIN '/data/app/vmdl39164.tmp' — 09-01 13:55:04.133 D/dalvikvm( 223): DexOpt: load 19ms, verify 5ms, opt 4ms 09-01 13:55:04.133 D/installd( 31): DexInv: — END '/data/app/vmdl39164.tmp' (success) — 09-01 13:55:04.176 D/PackageManager( 60): Instrumentation: android.test.InstrumentationTestRunner 09-01 13:55:04.219 D/ActivityManager( 60): Uninstalling process com.att.nismob.android.test 09-01 13:55:04.307 I/installd( 31): move /data/dalvik-cache/data@app@vmdl39164.tmp@classes.dex -> /data/dalvik-cache/data@app@com.att.nismob.android.test.apk@classes.dex 09-01 13:55:04.350 D/PackageManager( 60): New package installed in /data/app/com.att.nismob.android.test.apk 09-01 13:55:04.622 D/AndroidRuntime( 217): Shutting down VM 09-01 13:55:04.622 D/dalvikvm( 217): DestroyJavaVM waiting for non-daemon threads to exit 09-01 13:55:04.665 D/dalvikvm( 217): DestroyJavaVM shutting VM down 09-01 13:55:04.665 D/dalvikvm( 217): HeapWorker thread shutting down 09-01 13:55:04.665 D/dalvikvm( 217): HeapWorker thread has shut down 09-01 13:55:04.665 D/jdwp ( 217): JDWP shutting down net... 09-01 13:55:04.665 I/jdwp ( 217): adbd disconnected 09-01 13:55:04.665 D/dalvikvm( 217): VM cleaning up 09-01 13:55:04.708 D/ActivityManager( 60): Uninstalling process com.att.nismob.android.test
          Hide
          jtharp jtharp added a comment -

          A timeout scenario:
          ===================================
          $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete
          $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete
          $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete
          $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete
          $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete
          $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete
          $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete
          $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete
          $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete
          $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete
          [android] Timed-out after waiting 120 seconds for emulator
          $ /usr/android/tools/adb disconnect localhost:53865
          [android] Stopping Android emulator
          [android] Archiving emulator log

          And its logcat file
          ==================================================
          09-01 14:18:44.422 I/vold ( 534): Android Volume Daemon version 2.0
          09-01 14:18:44.472 I/DEBUG ( 535): debuggerd: Jun 30 2009 17:00:51
          09-01 14:18:44.652 E/vold ( 534): Error opening switch name path '/sys/class/switch/test2' (No such file or directory)
          09-01 14:18:44.652 E/vold ( 534): Error bootstrapping switch '/sys/class/switch/test2' (m)
          09-01 14:18:44.652 E/vold ( 534): Error opening switch name path '/sys/class/switch/test' (No such file or directory)
          09-01 14:18:44.652 E/vold ( 534): Error bootstrapping switch '/sys/class/switch/test' (m)
          09-01 14:18:44.652 D/vold ( 534): Bootstrapping complete
          09-01 14:18:44.732 E/flash_image( 540): can't find recovery partition
          09-01 14:18:44.843 D/qemud ( 542): entering main loop
          09-01 14:18:44.843 D/qemud ( 542): multiplexer_handle_control: unknown control message (18 bytes): 'ko:unknown command'
          09-01 14:18:45.903 D/qemud ( 542): fdhandler_accept_event: accepting on fd 10
          09-01 14:18:45.903 D/qemud ( 542): created client 0xe078 listening on fd 8
          09-01 14:18:45.913 D/qemud ( 542): fdhandler_event: disconnect on fd 8
          09-01 14:18:45.982 D/qemud ( 542): fdhandler_accept_event: accepting on fd 10
          09-01 14:18:45.982 D/qemud ( 542): created client 0xf028 listening on fd 8
          09-01 14:18:45.982 D/qemud ( 542): client_fd_receive: attempting registration for service 'gsm'
          09-01 14:18:45.982 D/qemud ( 542): client_fd_receive: -> received channel id 1
          09-01 14:18:46.443 D/AndroidRuntime( 537):
          09-01 14:18:46.443 D/AndroidRuntime( 537): >>>>>>>>>>>>>> AndroidRuntime START <<<<<<<<<<<<<<
          09-01 14:18:46.443 D/AndroidRuntime( 537): CheckJNI is ON
          09-01 14:18:47.323 I/ ( 538): ServiceManager: 0xac38
          09-01 14:18:47.323 W/AudioHardwareInterface( 538): Using stubbed audio hardware. No sound will be produced.
          09-01 14:18:47.352 I/AudioFlinger( 538): AudioFlinger's thread ready to run for output 0
          09-01 14:18:47.383 I/CameraService( 538): CameraService started: pid=538
          09-01 14:18:47.572 D/AndroidRuntime( 537): — registering native functions —
          09-01 14:18:48.183 I/Zygote ( 537): Preloading classes...
          09-01 14:18:48.203 D/dalvikvm( 537): GC freed 764 objects / 42216 bytes in 19ms
          09-01 14:18:48.573 D/dalvikvm( 537): GC freed 278 objects / 17160 bytes in 8ms
          09-01 14:18:49.003 D/dalvikvm( 537): GC freed 208 objects / 12696 bytes in 11ms
          09-01 14:18:49.133 D/dalvikvm( 537): Trying to load lib /system/lib/libmedia_jni.so 0x0
          09-01 14:18:49.453 D/dalvikvm( 537): Added shared lib /system/lib/libmedia_jni.so 0x0
          09-01 14:18:49.463 D/dalvikvm( 537): Trying to load lib /system/lib/libmedia_jni.so 0x0
          09-01 14:18:49.463 D/dalvikvm( 537): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0
          09-01 14:18:49.463 D/dalvikvm( 537): Trying to load lib /system/lib/libmedia_jni.so 0x0
          09-01 14:18:49.463 D/dalvikvm( 537): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0
          09-01 14:18:49.473 D/dalvikvm( 537): Trying to load lib /system/lib/libmedia_jni.so 0x0
          09-01 14:18:49.473 D/dalvikvm( 537): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0
          09-01 14:18:49.493 D/dalvikvm( 537): GC freed 462 objects / 29144 bytes in 15ms
          09-01 14:18:50.303 D/dalvikvm( 537): GC freed 3584 objects / 171648 bytes in 32ms
          09-01 14:18:52.303 D/dalvikvm( 537): GC freed 11329 objects / 400856 bytes in 52ms
          09-01 14:18:52.873 D/dalvikvm( 537): GC freed 10472 objects / 438272 bytes in 60ms
          09-01 14:18:53.472 D/dalvikvm( 537): GC freed 10975 objects / 459800 bytes in 61ms
          09-01 14:18:55.123 D/dalvikvm( 537): GC freed 14372 objects / 506896 bytes in 72ms
          09-01 14:18:55.923 D/dalvikvm( 537): GC freed 11314 objects / 481360 bytes in 71ms
          09-01 14:18:56.273 D/dalvikvm( 537): GC freed 5928 objects / 248640 bytes in 56ms
          09-01 14:18:56.643 D/dalvikvm( 537): GC freed 349 objects / 37032 bytes in 45ms
          09-01 14:18:56.993 D/dalvikvm( 537): GC freed 778 objects / 48376 bytes in 64ms
          09-01 14:18:57.183 D/dalvikvm( 537): GC freed 321 objects / 37288 bytes in 62ms
          09-01 14:18:57.383 D/dalvikvm( 537): GC freed 477 objects / 29584 bytes in 56ms
          09-01 14:18:57.443 D/dalvikvm( 537): Trying to load lib /system/lib/libwebcore.so 0x0
          09-01 14:18:57.563 D/dalvikvm( 537): Added shared lib /system/lib/libwebcore.so 0x0
          09-01 14:18:57.863 D/dalvikvm( 537): GC freed 441 objects / 26224 bytes in 59ms
          09-01 14:18:58.023 D/dalvikvm( 537): GC freed 506 objects / 41464 bytes in 58ms
          09-01 14:18:58.194 D/dalvikvm( 537): GC freed 537 objects / 38832 bytes in 66ms
          09-01 14:18:58.373 D/dalvikvm( 537): GC freed 342 objects / 22552 bytes in 60ms
          09-01 14:18:58.523 D/dalvikvm( 537): GC freed 338 objects / 18736 bytes in 65ms
          09-01 14:18:58.693 D/dalvikvm( 537): GC freed 629 objects / 32136 bytes in 62ms
          09-01 14:19:00.223 D/dalvikvm( 537): GC freed 14257 objects / 497280 bytes in 102ms
          09-01 14:19:00.813 D/dalvikvm( 537): GC freed 11164 objects / 469576 bytes in 100ms
          09-01 14:19:01.323 D/dalvikvm( 537): GC freed 7134 objects / 311424 bytes in 97ms
          09-01 14:19:01.603 D/dalvikvm( 537): GC freed 752 objects / 43224 bytes in 73ms
          09-01 14:19:01.763 D/dalvikvm( 537): GC freed 598 objects / 31496 bytes in 76ms
          09-01 14:19:01.933 D/dalvikvm( 537): GC freed 413 objects / 26336 bytes in 77ms
          09-01 14:19:01.993 I/Zygote ( 537): ...preloaded 1166 classes in 13812ms.
          09-01 14:19:02.083 D/dalvikvm( 537): GC freed 313 objects / 19952 bytes in 79ms
          09-01 14:19:02.095 I/Zygote ( 537): Preloading resources...
          09-01 14:19:02.183 D/dalvikvm( 537): GC freed 54 objects / 11248 bytes in 76ms
          09-01 14:19:02.973 D/dalvikvm( 537): GC freed 337 objects / 15008 bytes in 160ms
          09-01 14:19:03.423 D/dalvikvm( 537): GC freed 280 objects / 11768 bytes in 79ms
          09-01 14:19:03.473 I/Zygote ( 537): ...preloaded 48 resources in 1375ms.
          09-01 14:19:03.503 I/Zygote ( 537): ...preloaded 15 resources in 27ms.
          09-01 14:19:03.593 D/dalvikvm( 537): GC freed 117 objects / 8448 bytes in 89ms
          09-01 14:19:03.693 D/dalvikvm( 537): GC freed 205 objects / 8104 bytes in 77ms
          09-01 14:19:03.773 D/dalvikvm( 537): GC freed 36 objects / 1400 bytes in 79ms
          09-01 14:19:03.783 I/dalvikvm( 537): Splitting out new zygote heap
          09-01 14:19:03.813 I/dalvikvm( 537): System server process 565 has been created
          09-01 14:19:03.823 I/Zygote ( 537): Accepting command socket connections
          09-01 14:19:04.003 D/dalvikvm( 565): Trying to load lib /system/lib/libandroid_servers.so 0x0
          09-01 14:19:04.323 D/dalvikvm( 565): Added shared lib /system/lib/libandroid_servers.so 0x0
          09-01 14:19:04.353 I/sysproc ( 565): Entered system_init()
          09-01 14:19:04.363 I/sysproc ( 565): ServiceManager: 0x154f00
          09-01 14:19:04.363 I/SurfaceFlinger( 565): SurfaceFlinger is starting
          09-01 14:19:04.372 I/SurfaceFlinger( 565): boot animation disabled
          09-01 14:19:04.372 I/SurfaceFlinger( 565): SurfaceFlinger's main thread ready to run. Initializing graphics H/W...
          09-01 14:19:04.383 E/MemoryHeapBase( 565): error opening /dev/pmem: No such file or directory
          09-01 14:19:04.393 E/SurfaceFlinger( 565): Couldn't open /sys/power/wait_for_fb_sleep or /sys/power/wait_for_fb_wake
          09-01 14:19:04.443 E/GLLogger( 565): couldn't load <libhgl.so> library (Cannot find library)
          09-01 14:19:04.453 I/SurfaceFlinger( 565): EGL informations:
          09-01 14:19:04.463 I/SurfaceFlinger( 565): # of configs : 6
          09-01 14:19:04.463 I/SurfaceFlinger( 565): vendor : Android
          09-01 14:19:04.463 I/SurfaceFlinger( 565): version : 1.31 Android META-EGL
          09-01 14:19:04.463 I/SurfaceFlinger( 565): extensions:
          09-01 14:19:04.463 I/SurfaceFlinger( 565): Client API: OpenGL ES
          09-01 14:19:04.463 I/EGLDisplaySurface( 565): using (fd=21)
          09-01 14:19:04.463 I/EGLDisplaySurface( 565): id =
          09-01 14:19:04.463 I/EGLDisplaySurface( 565): xres = 320 px
          09-01 14:19:04.463 I/EGLDisplaySurface( 565): yres = 480 px
          09-01 14:19:04.463 I/EGLDisplaySurface( 565): xres_virtual = 320 px
          09-01 14:19:04.463 I/EGLDisplaySurface( 565): yres_virtual = 960 px
          09-01 14:19:04.463 I/EGLDisplaySurface( 565): bpp = 16
          09-01 14:19:04.463 I/EGLDisplaySurface( 565): r = 11:5
          09-01 14:19:04.463 I/EGLDisplaySurface( 565): g = 5:6
          09-01 14:19:04.463 I/EGLDisplaySurface( 565): b = 0:5
          09-01 14:19:04.473 I/EGLDisplaySurface( 565): width = 49 mm (165.877548 dpi)
          09-01 14:19:04.473 I/EGLDisplaySurface( 565): height = 74 mm (164.756760 dpi)
          09-01 14:19:04.473 I/EGLDisplaySurface( 565): refresh rate = 60.00 Hz
          09-01 14:19:04.483 W/HAL ( 565): load: module=/system/lib/hw/copybit.goldfish.so error=Cannot find library
          09-01 14:19:04.483 W/HAL ( 565): load: module=/system/lib/hw/copybit.default.so error=Cannot find library
          09-01 14:19:04.483 W/SurfaceFlinger( 565): ro.sf.lcd_density not defined, using 160 dpi by default.
          09-01 14:19:04.503 I/SurfaceFlinger( 565): OpenGL informations:
          09-01 14:19:04.503 I/SurfaceFlinger( 565): vendor : Android
          09-01 14:19:04.503 I/SurfaceFlinger( 565): renderer : Android PixelFlinger 1.0
          09-01 14:19:04.503 I/SurfaceFlinger( 565): version : OpenGL ES-CM 1.0
          09-01 14:19:04.503 I/SurfaceFlinger( 565): 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
          09-01 14:19:04.515 W/HAL ( 565): load: module=/system/lib/hw/copybit.goldfish.so error=Cannot find library
          09-01 14:19:04.515 W/HAL ( 565): load: module=/system/lib/hw/copybit.default.so error=Cannot find library
          09-01 14:19:04.515 W/HAL ( 565): load: module=/system/lib/hw/overlay.goldfish.so error=Cannot find library
          09-01 14:19:04.515 W/HAL ( 565): load: module=/system/lib/hw/overlay.default.so error=Cannot find library
          09-01 14:19:04.543 I/sysproc ( 565): System server: starting Android runtime.
          09-01 14:19:04.543 I/sysproc ( 565): System server: starting Android services.
          09-01 14:19:04.552 I/SystemServer( 565): Entered the Android system server!
          09-01 14:19:04.583 I/sysproc ( 565): System server: entering thread pool.
          09-01 14:19:04.603 I/SystemServer( 565): Starting Power Manager.
          09-01 14:19:04.643 I/SystemServer( 565): Starting Activity Manager.
          09-01 14:19:04.833 I/SystemServer( 565): Starting telephony registry
          09-01 14:19:04.853 I/SystemServer( 565): Starting Package Manager.
          09-01 14:19:04.873 I/Installer( 565): connecting...
          09-01 14:19:04.883 I/installd( 539): new connection
          09-01 14:19:04.983 I/PackageManager( 565): Got library android.awt in /system/framework/android.awt.jar
          09-01 14:19:04.993 I/PackageManager( 565): Got library android.test.runner in /system/framework/android.test.runner.jar
          09-01 14:19:04.993 I/PackageManager( 565): Got library com.android.im.plugin in /system/framework/com.android.im.plugin.jar
          09-01 14:19:05.393 D/PackageManager( 565): Scanning app dir /system/framework
          09-01 14:19:05.613 D/PackageManager( 565): Scanning app dir /system/app
          09-01 14:19:05.843 D/dalvikvm( 565): GC freed 6046 objects / 249440 bytes in 163ms
          09-01 14:19:07.513 D/dalvikvm( 565): GC freed 3541 objects / 220424 bytes in 134ms
          09-01 14:19:07.552 D/PackageManager( 565): Scanning app dir /data/app
          09-01 14:19:07.603 D/PackageManager( 565): Scanning app dir /data/app-private
          09-01 14:19:07.613 I/PackageManager( 565): Time to scan packages: 2.285 seconds
          09-01 14:19:07.623 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.providers.contacts
          09-01 14:19:07.623 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.cp in package com.android.providers.contacts
          09-01 14:19:07.633 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.development
          09-01 14:19:07.633 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.ALL_SERVICES in package com.android.development
          09-01 14:19:07.633 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.YouTubeUser in package com.android.development
          09-01 14:19:07.643 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.ACCESS_GOOGLE_PASSWORD in package com.android.development
          09-01 14:19:07.643 W/PackageManager( 565): Unknown permission com.google.android.providers.gmail.permission.WRITE_GMAIL in package com.android.settings
          09-01 14:19:07.643 W/PackageManager( 565): Unknown permission com.google.android.providers.gmail.permission.READ_GMAIL in package com.android.settings
          09-01 14:19:07.653 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.settings
          09-01 14:19:07.663 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.browser
          09-01 14:19:07.663 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.mail in package com.android.contacts
          09-01 14:19:07.903 D/dalvikvm( 565): GC freed 2899 objects / 164808 bytes in 120ms
          09-01 14:19:07.933 I/SystemServer( 565): Starting Content Manager.
          09-01 14:19:08.153 W/ActivityManager( 565): Unable to start service Intent { action=android.accounts.IAccountsService comp=

          {com.google.android.googleapps/com.google.android.googleapps.GoogleLoginService} }: not found
          09-01 14:19:08.163 W/AccountMonitor( 565): Couldn't connect to Intent { action=android.accounts.IAccountsService comp={com.google.android.googleapps/com.google.android.googleapps.GoogleLoginService}

          } (Missing service?)
          09-01 14:19:08.163 I/SystemServer( 565): Starting System Content Providers.
          09-01 14:19:08.173 I/ActivityThread( 565): Publishing provider settings: com.android.providers.settings.SettingsProvider
          09-01 14:19:08.213 I/ActivityThread( 565): Publishing provider sync: android.content.SyncProvider
          09-01 14:19:08.223 I/SystemServer( 565): Starting Battery Service.
          09-01 14:19:08.233 E/BatteryService( 565): Could not open '/sys/class/power_supply/usb/online'
          09-01 14:19:08.233 E/BatteryService( 565): Could not open '/sys/class/power_supply/battery/batt_vol'
          09-01 14:19:08.243 E/BatteryService( 565): Could not open '/sys/class/power_supply/battery/batt_temp'
          09-01 14:19:08.253 I/SystemServer( 565): Starting Hardware Service.
          09-01 14:19:08.263 D/qemud ( 542): fdhandler_accept_event: accepting on fd 10
          09-01 14:19:08.263 D/qemud ( 542): created client 0x10fd8 listening on fd 11
          09-01 14:19:08.273 D/qemud ( 542): client_fd_receive: attempting registration for service 'hw-control'
          09-01 14:19:08.273 D/qemud ( 542): client_fd_receive: -> received channel id 2

          Show
          jtharp jtharp added a comment - A timeout scenario: =================================== $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete [android] Timed-out after waiting 120 seconds for emulator $ /usr/android/tools/adb disconnect localhost:53865 [android] Stopping Android emulator [android] Archiving emulator log And its logcat file ================================================== 09-01 14:18:44.422 I/vold ( 534): Android Volume Daemon version 2.0 09-01 14:18:44.472 I/DEBUG ( 535): debuggerd: Jun 30 2009 17:00:51 09-01 14:18:44.652 E/vold ( 534): Error opening switch name path '/sys/class/switch/test2' (No such file or directory) 09-01 14:18:44.652 E/vold ( 534): Error bootstrapping switch '/sys/class/switch/test2' (m) 09-01 14:18:44.652 E/vold ( 534): Error opening switch name path '/sys/class/switch/test' (No such file or directory) 09-01 14:18:44.652 E/vold ( 534): Error bootstrapping switch '/sys/class/switch/test' (m) 09-01 14:18:44.652 D/vold ( 534): Bootstrapping complete 09-01 14:18:44.732 E/flash_image( 540): can't find recovery partition 09-01 14:18:44.843 D/qemud ( 542): entering main loop 09-01 14:18:44.843 D/qemud ( 542): multiplexer_handle_control: unknown control message (18 bytes): 'ko:unknown command' 09-01 14:18:45.903 D/qemud ( 542): fdhandler_accept_event: accepting on fd 10 09-01 14:18:45.903 D/qemud ( 542): created client 0xe078 listening on fd 8 09-01 14:18:45.913 D/qemud ( 542): fdhandler_event: disconnect on fd 8 09-01 14:18:45.982 D/qemud ( 542): fdhandler_accept_event: accepting on fd 10 09-01 14:18:45.982 D/qemud ( 542): created client 0xf028 listening on fd 8 09-01 14:18:45.982 D/qemud ( 542): client_fd_receive: attempting registration for service 'gsm' 09-01 14:18:45.982 D/qemud ( 542): client_fd_receive: -> received channel id 1 09-01 14:18:46.443 D/AndroidRuntime( 537): 09-01 14:18:46.443 D/AndroidRuntime( 537): >>>>>>>>>>>>>> AndroidRuntime START <<<<<<<<<<<<<< 09-01 14:18:46.443 D/AndroidRuntime( 537): CheckJNI is ON 09-01 14:18:47.323 I/ ( 538): ServiceManager: 0xac38 09-01 14:18:47.323 W/AudioHardwareInterface( 538): Using stubbed audio hardware. No sound will be produced. 09-01 14:18:47.352 I/AudioFlinger( 538): AudioFlinger's thread ready to run for output 0 09-01 14:18:47.383 I/CameraService( 538): CameraService started: pid=538 09-01 14:18:47.572 D/AndroidRuntime( 537): — registering native functions — 09-01 14:18:48.183 I/Zygote ( 537): Preloading classes... 09-01 14:18:48.203 D/dalvikvm( 537): GC freed 764 objects / 42216 bytes in 19ms 09-01 14:18:48.573 D/dalvikvm( 537): GC freed 278 objects / 17160 bytes in 8ms 09-01 14:18:49.003 D/dalvikvm( 537): GC freed 208 objects / 12696 bytes in 11ms 09-01 14:18:49.133 D/dalvikvm( 537): Trying to load lib /system/lib/libmedia_jni.so 0x0 09-01 14:18:49.453 D/dalvikvm( 537): Added shared lib /system/lib/libmedia_jni.so 0x0 09-01 14:18:49.463 D/dalvikvm( 537): Trying to load lib /system/lib/libmedia_jni.so 0x0 09-01 14:18:49.463 D/dalvikvm( 537): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0 09-01 14:18:49.463 D/dalvikvm( 537): Trying to load lib /system/lib/libmedia_jni.so 0x0 09-01 14:18:49.463 D/dalvikvm( 537): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0 09-01 14:18:49.473 D/dalvikvm( 537): Trying to load lib /system/lib/libmedia_jni.so 0x0 09-01 14:18:49.473 D/dalvikvm( 537): Shared lib '/system/lib/libmedia_jni.so' already loaded in same CL 0x0 09-01 14:18:49.493 D/dalvikvm( 537): GC freed 462 objects / 29144 bytes in 15ms 09-01 14:18:50.303 D/dalvikvm( 537): GC freed 3584 objects / 171648 bytes in 32ms 09-01 14:18:52.303 D/dalvikvm( 537): GC freed 11329 objects / 400856 bytes in 52ms 09-01 14:18:52.873 D/dalvikvm( 537): GC freed 10472 objects / 438272 bytes in 60ms 09-01 14:18:53.472 D/dalvikvm( 537): GC freed 10975 objects / 459800 bytes in 61ms 09-01 14:18:55.123 D/dalvikvm( 537): GC freed 14372 objects / 506896 bytes in 72ms 09-01 14:18:55.923 D/dalvikvm( 537): GC freed 11314 objects / 481360 bytes in 71ms 09-01 14:18:56.273 D/dalvikvm( 537): GC freed 5928 objects / 248640 bytes in 56ms 09-01 14:18:56.643 D/dalvikvm( 537): GC freed 349 objects / 37032 bytes in 45ms 09-01 14:18:56.993 D/dalvikvm( 537): GC freed 778 objects / 48376 bytes in 64ms 09-01 14:18:57.183 D/dalvikvm( 537): GC freed 321 objects / 37288 bytes in 62ms 09-01 14:18:57.383 D/dalvikvm( 537): GC freed 477 objects / 29584 bytes in 56ms 09-01 14:18:57.443 D/dalvikvm( 537): Trying to load lib /system/lib/libwebcore.so 0x0 09-01 14:18:57.563 D/dalvikvm( 537): Added shared lib /system/lib/libwebcore.so 0x0 09-01 14:18:57.863 D/dalvikvm( 537): GC freed 441 objects / 26224 bytes in 59ms 09-01 14:18:58.023 D/dalvikvm( 537): GC freed 506 objects / 41464 bytes in 58ms 09-01 14:18:58.194 D/dalvikvm( 537): GC freed 537 objects / 38832 bytes in 66ms 09-01 14:18:58.373 D/dalvikvm( 537): GC freed 342 objects / 22552 bytes in 60ms 09-01 14:18:58.523 D/dalvikvm( 537): GC freed 338 objects / 18736 bytes in 65ms 09-01 14:18:58.693 D/dalvikvm( 537): GC freed 629 objects / 32136 bytes in 62ms 09-01 14:19:00.223 D/dalvikvm( 537): GC freed 14257 objects / 497280 bytes in 102ms 09-01 14:19:00.813 D/dalvikvm( 537): GC freed 11164 objects / 469576 bytes in 100ms 09-01 14:19:01.323 D/dalvikvm( 537): GC freed 7134 objects / 311424 bytes in 97ms 09-01 14:19:01.603 D/dalvikvm( 537): GC freed 752 objects / 43224 bytes in 73ms 09-01 14:19:01.763 D/dalvikvm( 537): GC freed 598 objects / 31496 bytes in 76ms 09-01 14:19:01.933 D/dalvikvm( 537): GC freed 413 objects / 26336 bytes in 77ms 09-01 14:19:01.993 I/Zygote ( 537): ...preloaded 1166 classes in 13812ms. 09-01 14:19:02.083 D/dalvikvm( 537): GC freed 313 objects / 19952 bytes in 79ms 09-01 14:19:02.095 I/Zygote ( 537): Preloading resources... 09-01 14:19:02.183 D/dalvikvm( 537): GC freed 54 objects / 11248 bytes in 76ms 09-01 14:19:02.973 D/dalvikvm( 537): GC freed 337 objects / 15008 bytes in 160ms 09-01 14:19:03.423 D/dalvikvm( 537): GC freed 280 objects / 11768 bytes in 79ms 09-01 14:19:03.473 I/Zygote ( 537): ...preloaded 48 resources in 1375ms. 09-01 14:19:03.503 I/Zygote ( 537): ...preloaded 15 resources in 27ms. 09-01 14:19:03.593 D/dalvikvm( 537): GC freed 117 objects / 8448 bytes in 89ms 09-01 14:19:03.693 D/dalvikvm( 537): GC freed 205 objects / 8104 bytes in 77ms 09-01 14:19:03.773 D/dalvikvm( 537): GC freed 36 objects / 1400 bytes in 79ms 09-01 14:19:03.783 I/dalvikvm( 537): Splitting out new zygote heap 09-01 14:19:03.813 I/dalvikvm( 537): System server process 565 has been created 09-01 14:19:03.823 I/Zygote ( 537): Accepting command socket connections 09-01 14:19:04.003 D/dalvikvm( 565): Trying to load lib /system/lib/libandroid_servers.so 0x0 09-01 14:19:04.323 D/dalvikvm( 565): Added shared lib /system/lib/libandroid_servers.so 0x0 09-01 14:19:04.353 I/sysproc ( 565): Entered system_init() 09-01 14:19:04.363 I/sysproc ( 565): ServiceManager: 0x154f00 09-01 14:19:04.363 I/SurfaceFlinger( 565): SurfaceFlinger is starting 09-01 14:19:04.372 I/SurfaceFlinger( 565): boot animation disabled 09-01 14:19:04.372 I/SurfaceFlinger( 565): SurfaceFlinger's main thread ready to run. Initializing graphics H/W... 09-01 14:19:04.383 E/MemoryHeapBase( 565): error opening /dev/pmem: No such file or directory 09-01 14:19:04.393 E/SurfaceFlinger( 565): Couldn't open /sys/power/wait_for_fb_sleep or /sys/power/wait_for_fb_wake 09-01 14:19:04.443 E/GLLogger( 565): couldn't load <libhgl.so> library (Cannot find library) 09-01 14:19:04.453 I/SurfaceFlinger( 565): EGL informations: 09-01 14:19:04.463 I/SurfaceFlinger( 565): # of configs : 6 09-01 14:19:04.463 I/SurfaceFlinger( 565): vendor : Android 09-01 14:19:04.463 I/SurfaceFlinger( 565): version : 1.31 Android META-EGL 09-01 14:19:04.463 I/SurfaceFlinger( 565): extensions: 09-01 14:19:04.463 I/SurfaceFlinger( 565): Client API: OpenGL ES 09-01 14:19:04.463 I/EGLDisplaySurface( 565): using (fd=21) 09-01 14:19:04.463 I/EGLDisplaySurface( 565): id = 09-01 14:19:04.463 I/EGLDisplaySurface( 565): xres = 320 px 09-01 14:19:04.463 I/EGLDisplaySurface( 565): yres = 480 px 09-01 14:19:04.463 I/EGLDisplaySurface( 565): xres_virtual = 320 px 09-01 14:19:04.463 I/EGLDisplaySurface( 565): yres_virtual = 960 px 09-01 14:19:04.463 I/EGLDisplaySurface( 565): bpp = 16 09-01 14:19:04.463 I/EGLDisplaySurface( 565): r = 11:5 09-01 14:19:04.463 I/EGLDisplaySurface( 565): g = 5:6 09-01 14:19:04.463 I/EGLDisplaySurface( 565): b = 0:5 09-01 14:19:04.473 I/EGLDisplaySurface( 565): width = 49 mm (165.877548 dpi) 09-01 14:19:04.473 I/EGLDisplaySurface( 565): height = 74 mm (164.756760 dpi) 09-01 14:19:04.473 I/EGLDisplaySurface( 565): refresh rate = 60.00 Hz 09-01 14:19:04.483 W/HAL ( 565): load: module=/system/lib/hw/copybit.goldfish.so error=Cannot find library 09-01 14:19:04.483 W/HAL ( 565): load: module=/system/lib/hw/copybit.default.so error=Cannot find library 09-01 14:19:04.483 W/SurfaceFlinger( 565): ro.sf.lcd_density not defined, using 160 dpi by default. 09-01 14:19:04.503 I/SurfaceFlinger( 565): OpenGL informations: 09-01 14:19:04.503 I/SurfaceFlinger( 565): vendor : Android 09-01 14:19:04.503 I/SurfaceFlinger( 565): renderer : Android PixelFlinger 1.0 09-01 14:19:04.503 I/SurfaceFlinger( 565): version : OpenGL ES-CM 1.0 09-01 14:19:04.503 I/SurfaceFlinger( 565): 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 09-01 14:19:04.515 W/HAL ( 565): load: module=/system/lib/hw/copybit.goldfish.so error=Cannot find library 09-01 14:19:04.515 W/HAL ( 565): load: module=/system/lib/hw/copybit.default.so error=Cannot find library 09-01 14:19:04.515 W/HAL ( 565): load: module=/system/lib/hw/overlay.goldfish.so error=Cannot find library 09-01 14:19:04.515 W/HAL ( 565): load: module=/system/lib/hw/overlay.default.so error=Cannot find library 09-01 14:19:04.543 I/sysproc ( 565): System server: starting Android runtime. 09-01 14:19:04.543 I/sysproc ( 565): System server: starting Android services. 09-01 14:19:04.552 I/SystemServer( 565): Entered the Android system server! 09-01 14:19:04.583 I/sysproc ( 565): System server: entering thread pool. 09-01 14:19:04.603 I/SystemServer( 565): Starting Power Manager. 09-01 14:19:04.643 I/SystemServer( 565): Starting Activity Manager. 09-01 14:19:04.833 I/SystemServer( 565): Starting telephony registry 09-01 14:19:04.853 I/SystemServer( 565): Starting Package Manager. 09-01 14:19:04.873 I/Installer( 565): connecting... 09-01 14:19:04.883 I/installd( 539): new connection 09-01 14:19:04.983 I/PackageManager( 565): Got library android.awt in /system/framework/android.awt.jar 09-01 14:19:04.993 I/PackageManager( 565): Got library android.test.runner in /system/framework/android.test.runner.jar 09-01 14:19:04.993 I/PackageManager( 565): Got library com.android.im.plugin in /system/framework/com.android.im.plugin.jar 09-01 14:19:05.393 D/PackageManager( 565): Scanning app dir /system/framework 09-01 14:19:05.613 D/PackageManager( 565): Scanning app dir /system/app 09-01 14:19:05.843 D/dalvikvm( 565): GC freed 6046 objects / 249440 bytes in 163ms 09-01 14:19:07.513 D/dalvikvm( 565): GC freed 3541 objects / 220424 bytes in 134ms 09-01 14:19:07.552 D/PackageManager( 565): Scanning app dir /data/app 09-01 14:19:07.603 D/PackageManager( 565): Scanning app dir /data/app-private 09-01 14:19:07.613 I/PackageManager( 565): Time to scan packages: 2.285 seconds 09-01 14:19:07.623 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.providers.contacts 09-01 14:19:07.623 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.cp in package com.android.providers.contacts 09-01 14:19:07.633 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.development 09-01 14:19:07.633 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.ALL_SERVICES in package com.android.development 09-01 14:19:07.633 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.YouTubeUser in package com.android.development 09-01 14:19:07.643 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.ACCESS_GOOGLE_PASSWORD in package com.android.development 09-01 14:19:07.643 W/PackageManager( 565): Unknown permission com.google.android.providers.gmail.permission.WRITE_GMAIL in package com.android.settings 09-01 14:19:07.643 W/PackageManager( 565): Unknown permission com.google.android.providers.gmail.permission.READ_GMAIL in package com.android.settings 09-01 14:19:07.653 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.settings 09-01 14:19:07.663 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.browser 09-01 14:19:07.663 W/PackageManager( 565): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.mail in package com.android.contacts 09-01 14:19:07.903 D/dalvikvm( 565): GC freed 2899 objects / 164808 bytes in 120ms 09-01 14:19:07.933 I/SystemServer( 565): Starting Content Manager. 09-01 14:19:08.153 W/ActivityManager( 565): Unable to start service Intent { action=android.accounts.IAccountsService comp= {com.google.android.googleapps/com.google.android.googleapps.GoogleLoginService} }: not found 09-01 14:19:08.163 W/AccountMonitor( 565): Couldn't connect to Intent { action=android.accounts.IAccountsService comp={com.google.android.googleapps/com.google.android.googleapps.GoogleLoginService} } (Missing service?) 09-01 14:19:08.163 I/SystemServer( 565): Starting System Content Providers. 09-01 14:19:08.173 I/ActivityThread( 565): Publishing provider settings: com.android.providers.settings.SettingsProvider 09-01 14:19:08.213 I/ActivityThread( 565): Publishing provider sync: android.content.SyncProvider 09-01 14:19:08.223 I/SystemServer( 565): Starting Battery Service. 09-01 14:19:08.233 E/BatteryService( 565): Could not open '/sys/class/power_supply/usb/online' 09-01 14:19:08.233 E/BatteryService( 565): Could not open '/sys/class/power_supply/battery/batt_vol' 09-01 14:19:08.243 E/BatteryService( 565): Could not open '/sys/class/power_supply/battery/batt_temp' 09-01 14:19:08.253 I/SystemServer( 565): Starting Hardware Service. 09-01 14:19:08.263 D/qemud ( 542): fdhandler_accept_event: accepting on fd 10 09-01 14:19:08.263 D/qemud ( 542): created client 0x10fd8 listening on fd 11 09-01 14:19:08.273 D/qemud ( 542): client_fd_receive: attempting registration for service 'hw-control' 09-01 14:19:08.273 D/qemud ( 542): client_fd_receive: -> received channel id 2
          Hide
          jtharp jtharp added a comment -

          One more can't connect at the beginning:
          =======================================================
          $ /usr/android/tools/adb connect localhost:55388
          ADB server didn't ACK

          • failed to start daemon *
            error: cannot connect to daemon
            [android] Could not connect to running emulator; cannot continue
            [android] Stopping Android emulator
            Finished: NOT_BUILT

          This one had no logcat file.

          Show
          jtharp jtharp added a comment - One more can't connect at the beginning: ======================================================= $ /usr/android/tools/adb connect localhost:55388 ADB server didn't ACK failed to start daemon * error: cannot connect to daemon [android] Could not connect to running emulator; cannot continue [android] Stopping Android emulator Finished: NOT_BUILT This one had no logcat file.
          Hide
          orrc Christopher Orr added a comment -

          Ok, I've been looking at this some more and as far as I can tell, these are all problems with adb.

          - waiting for device -
          error: unknown host service

          This will happen if adb crashes during the build. I've found that adb is prone to crashing at the end of a build, which would then affect other parallel builds.


          $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete
          $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete
          [android] Timed-out after waiting 120 seconds for emulator

          This is also a symptom of adb crashing between the emulator starting and it finishing booting (probably due to a parallel build).


          ADB server didn't ACK

          I still have never seen this one, but searching for that message implies that it's an adb problem.
          If adb can't start – which it does so automatically when anyone tries to access it – then the Hudson plugin can't use it.


          Anyway, as the plugin interacts with adb differently than when manually running emulators on the desktop, I guess it's more likely that the Hudson plugin runs into new, unsolved issues.

          Looking at the source code of adb, I think it may be possible to change how the Hudson plugin uses adb, to be more like the "normal" manual way of working. I'll let you know if there's an update.

          Show
          orrc Christopher Orr added a comment - Ok, I've been looking at this some more and as far as I can tell, these are all problems with adb. - waiting for device - error: unknown host service This will happen if adb crashes during the build. I've found that adb is prone to crashing at the end of a build, which would then affect other parallel builds. $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete $ /usr/android/tools/adb -s localhost:53865 shell getprop dev.bootcomplete [android] Timed-out after waiting 120 seconds for emulator This is also a symptom of adb crashing between the emulator starting and it finishing booting (probably due to a parallel build). ADB server didn't ACK I still have never seen this one, but searching for that message implies that it's an adb problem. If adb can't start – which it does so automatically when anyone tries to access it – then the Hudson plugin can't use it. Anyway, as the plugin interacts with adb differently than when manually running emulators on the desktop, I guess it's more likely that the Hudson plugin runs into new, unsolved issues. Looking at the source code of adb, I think it may be possible to change how the Hudson plugin uses adb, to be more like the "normal" manual way of working. I'll let you know if there's an update.
          Hide
          orrc Christopher Orr added a comment -

          Just a minor update: I tested a newer version of ADB from the Android Open Source Project (from the master branch of about two weeks ago) and it definitely seems more stable.

          I tested some extreme cases, like 12 or 16 parallel emulator builds on one (powerful) machine and the newer version of ADB generally behaved better than the currently-available version.

          Also, some changes I made in version 1.4 of the Android Emulator plugin could maybe also help alleviate these kind of problems.

          Show
          orrc Christopher Orr added a comment - Just a minor update: I tested a newer version of ADB from the Android Open Source Project (from the master branch of about two weeks ago) and it definitely seems more stable. I tested some extreme cases, like 12 or 16 parallel emulator builds on one (powerful) machine and the newer version of ADB generally behaved better than the currently-available version. Also, some changes I made in version 1.4 of the Android Emulator plugin could maybe also help alleviate these kind of problems.
          Hide
          orrc Christopher Orr added a comment -

          I'm going to consider this as fixed.

          Version 1.11 of the plugin will automatically attempt to reconnect with ADB while waiting for the emulator to finish starting up, which allows the build to continue in case ADB crashes.

          Also, with improvements to adb since SDK Tools r7, an update to the way in which the plugin connects to emulators, and snapshot support reducing build time, there should now be less chance of things going wrong!

          Show
          orrc Christopher Orr added a comment - I'm going to consider this as fixed. Version 1.11 of the plugin will automatically attempt to reconnect with ADB while waiting for the emulator to finish starting up, which allows the build to continue in case ADB crashes. Also, with improvements to adb since SDK Tools r7, an update to the way in which the plugin connects to emulators, and snapshot support reducing build time, there should now be less chance of things going wrong!

            People

            • Assignee:
              orrc Christopher Orr
              Reporter:
              jtharp jtharp
            • Votes:
              0 Vote for this issue
              Watchers:
              2 Start watching this issue

              Dates

              • Created:
                Updated:
                Resolved: