# logcat --------- beginning of /dev/log/main I/DEBUG ( 66): debuggerd: Oct 4 2012 18:09:06 --------- beginning of /dev/log/system I/Vold ( 64): Vold 2.1 (the revenge) firing up D/Vold ( 64): USB mass storage support is not enabled in the kernel D/Vold ( 64): usb_configuration switch is not enabled in the kernel D/Vold ( 64): Volume sdcard state changing -1 (Initializing) -> 0 (No-Media) D/Vold ( 64): Volume sdcard state changing 0 (No-Media) -> 2 (Pending) D/Vold ( 64): Volume sdcard state changing 2 (Pending) -> 1 (Idle-Unmounted) D/AndroidRuntime( 68): D/AndroidRuntime( 68): >>>>>> AndroidRuntime START com.android.internal.os.ZygoteInit <<<<<< D/AndroidRuntime( 68): CheckJNI is ON I/ ( 69): ServiceManager: 0xad50 W/AudioHardwareInterface( 69): Using stubbed audio hardware. No sound will be produced. D/AudioHardwareInterface( 69): setMode(NORMAL) I/CameraService( 69): CameraService started (pid=69) I/AudioFlinger( 69): AudioFlinger's thread 0xc638 ready to run D/dalvikvm( 68): creating instr width table I/Netd ( 65): Netd 1.0 starting I/SamplingProfilerIntegration( 68): Profiler is disabled. I/Zygote ( 68): Preloading classes... D/dalvikvm( 68): GC_EXPLICIT freed 47K, 78% free 232K/1024K, external 0K/0K, paused 2ms D/dalvikvm( 68): GC_EXPLICIT freed 1K, 73% free 282K/1024K, external 0K/0K, paused 2ms D/dalvikvm( 68): GC_EXPLICIT freed 20K, 70% free 315K/1024K, external 0K/0K, paused 2ms D/dalvikvm( 68): GC_EXPLICIT freed 17K, 66% free 353K/1024K, external 0K/0K, paused 2ms D/dalvikvm( 68): GC_EXPLICIT freed 26K, 63% free 381K/1024K, external 0K/0K, paused 3ms D/dalvikvm( 68): GC_EXPLICIT freed 22K, 58% free 440K/1024K, external 0K/0K, paused 3ms W/MediaProfiles( 68): could not find media config xml file D/dalvikvm( 68): GC_EXPLICIT freed 98K, 48% free 542K/1024K, external 0K/0K, paused 4ms D/dalvikvm( 68): GC_EXPLICIT freed 271K, 24% free 881K/1155K, external 0K/0K, paused 8ms D/dalvikvm( 68): GC_EXPLICIT freed 21K, 20% free 929K/1155K, external 0K/0K, paused 7ms D/RenderScript_jni( 68): RenderScript JNI library not found! D/dalvikvm( 68): GC_EXPLICIT freed 24K, 21% free 970K/1219K, external 0K/0K, paused 7ms D/dalvikvm( 68): GC_EXPLICIT freed 24K, 6% free 1277K/1347K, external 0K/0K, paused 10ms D/dalvikvm( 68): GC_EXPLICIT freed 24K, 4% free 1306K/1347K, external 0K/0K, paused 11ms D/dalvikvm( 68): GC_EXPLICIT freed 28K, 6% free 1331K/1411K, external 0K/0K, paused 11ms D/dalvikvm( 68): GC_EXPLICIT freed 14K, 3% free 1372K/1411K, external 0K/0K, paused 11ms W/Zygote ( 68): Class not found for preloading: android.widget.TextView$SelectionModifierCursorController$1 D/dalvikvm( 68): GC_EXPLICIT freed 28K, 6% free 1397K/1475K, external 0K/0K, paused 11ms D/dalvikvm( 68): GC_EXPLICIT freed 31K, 3% free 1436K/1475K, external 0K/0K, paused 12ms D/dalvikvm( 68): GC_EXPLICIT freed 38K, 6% free 1453K/1539K, external 0K/0K, paused 12ms D/dalvikvm( 68): GC_EXPLICIT freed 41K, 5% free 1468K/1539K, external 0K/0K, paused 12ms D/dalvikvm( 68): GC_EXPLICIT freed 55K, 8% free 1481K/1603K, external 0K/0K, paused 13ms D/dalvikvm( 68): GC_FOR_MALLOC freed 2890K, 57% free 2232K/5123K, external 0K/0K, paused 36ms D/dalvikvm( 68): GC_EXPLICIT freed 2439K, 52% free 2485K/5123K, external 0K/0K, paused 33ms D/dalvikvm( 68): GC_EXPLICIT freed 127K, 51% free 2545K/5123K, external 0K/0K, paused 23ms D/dalvikvm( 68): GC_EXPLICIT freed 73K, 50% free 2571K/5123K, external 0K/0K, paused 23ms D/dalvikvm( 68): GC_EXPLICIT freed 36K, 50% free 2588K/5123K, external 0K/0K, paused 23ms D/dalvikvm( 68): GC_EXPLICIT freed 31K, 50% free 2612K/5123K, external 0K/0K, paused 23ms D/dalvikvm( 68): GC_EXPLICIT freed 38K, 49% free 2634K/5123K, external 0K/0K, paused 23ms I/Zygote ( 68): ...preloaded 1829 classes in 3520ms. D/dalvikvm( 68): GC_EXPLICIT freed 18K, 49% free 2632K/5123K, external 0K/0K, paused 24ms I/Zygote ( 68): Preloading resources... D/dalvikvm( 68): GC_EXTERNAL_ALLOC freed <1K, 49% free 2634K/5123K, external 0K/0K, paused 22ms D/dalvikvm( 68): GC_EXPLICIT freed 15K, 48% free 2673K/5123K, external 414K/521K, paused 24ms D/dalvikvm( 68): GC_EXTERNAL_ALLOC freed 7K, 48% free 2683K/5123K, external 516K/521K, paused 24ms I/Zygote ( 68): ...preloaded 51 resources in 364ms. I/Zygote ( 68): ...preloaded 15 resources in 18ms. D/dalvikvm( 68): GC_EXPLICIT freed 14K, 48% free 2707K/5123K, external 716K/1038K, paused 24ms D/dalvikvm( 68): GC_EXPLICIT freed 8K, 48% free 2699K/5123K, external 716K/1038K, paused 24ms D/dalvikvm( 68): GC_EXPLICIT freed 1K, 48% free 2697K/5123K, external 716K/1038K, paused 24ms I/dalvikvm( 68): System server process 88 has been created I/Zygote ( 68): Accepting command socket connections E/BatteryService( 88): acOnlinePath not found E/BatteryService( 88): usbOnlinePath not found E/BatteryService( 88): batteryStatusPath not found E/BatteryService( 88): batteryHealthPath not found E/BatteryService( 88): batteryPresentPath not found E/BatteryService( 88): batteryCapacityPath not found E/BatteryService( 88): batteryVoltagePath not found E/BatteryService( 88): batteryTemperaturePath not found E/BatteryService( 88): batteryTechnologyPath not found I/sysproc ( 88): Entered system_init() I/sysproc ( 88): ServiceManager: 0x7fa90 I/SurfaceFlinger( 88): SurfaceFlinger is starting I/SurfaceFlinger( 88): SurfaceFlinger's main thread ready to run. Initializing graphics H/W... E/SurfaceFlinger( 88): Couldn't open /sys/power/wait_for_fb_sleep or /sys/power/wait_for_fb_wake W/gralloc ( 88): page flipping not supported (yres_virtual=480, requested=960) I/gralloc ( 88): using (fd=25) I/gralloc ( 88): id = omapfb I/gralloc ( 88): xres = 320 px I/gralloc ( 88): yres = 480 px I/gralloc ( 88): xres_virtual = 320 px I/gralloc ( 88): yres_virtual = 480 px I/gralloc ( 88): bpp = 16 I/gralloc ( 88): r = 11:5 I/gralloc ( 88): g = 5:6 I/gralloc ( 88): b = 0:5 I/gralloc ( 88): width = 51 mm (159.372543 dpi) I/gralloc ( 88): height = 76 mm (160.421051 dpi) I/gralloc ( 88): refresh rate = 65.73 Hz D/libEGL ( 88): egl.cfg not found, using default config D/libEGL ( 88): loaded /system/lib/egl/libGLES_android.so W/SurfaceFlinger( 88): ro.sf.lcd_density not defined, using 160 dpi by default. I/SurfaceFlinger( 88): EGL informations: I/SurfaceFlinger( 88): # of configs : 8 I/SurfaceFlinger( 88): vendor : Android I/SurfaceFlinger( 88): version : 1.4 Android META-EGL I/SurfaceFlinger( 88): extensions: EGL_KHR_image EGL_KHR_image_base EGL_KHR_image_pixmap EGL_ANDROID_image_native_buffer EGL_ANDROID_swap_rectangle I/SurfaceFlinger( 88): Client API: OpenGL ES I/SurfaceFlinger( 88): EGLSurface: 5-6-5-0, config=0x0 I/SurfaceFlinger( 88): OpenGL informations: I/SurfaceFlinger( 88): vendor : Android I/SurfaceFlinger( 88): renderer : Android PixelFlinger 1.4 I/SurfaceFlinger( 88): version : OpenGL ES-CM 1.0 I/SurfaceFlinger( 88): 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_OES_compressed_ETC1_RGB8_texture 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 I/SurfaceFlinger( 88): GL_MAX_TEXTURE_SIZE = 4096 I/SurfaceFlinger( 88): GL_MAX_VIEWPORT_DIMS = 4096 I/SurfaceFlinger( 88): flags = 000c0000 D/SensorService( 88): nuSensorService starting... E/SensorService( 88): couldn't load sensors module (No such file or directory) I/sysproc ( 88): System server: starting Android runtime. I/sysproc ( 88): System server: starting Android services. I/sysproc ( 88): System server: entering thread pool. I/SystemServer( 88): Entered the Android system server! I/SystemServer( 88): Entropy Service D/libEGL ( 100): egl.cfg not found, using default config D/libEGL ( 100): loaded /system/lib/egl/libGLES_android.so I/BootAnimation( 100): *********BootAnimation::readyToRun widht=320,height=480 W/zipro ( 100): Unable to open zip '/data/local/bootanimation.zip': No such file or directory W/zipro ( 100): Unable to open zip '/system/media/bootanimation.zip': No such file or directory I/ARMAssembler( 88): generated scanline__00000077:03010104_00000004_00000000 [ 22 ipp] (41 ins) at [0x436f11e8:0x436f128c] in 122071 ns I/BootAnimation( 100): *********BootAnimation::readyToRun xc=32,yc=208 I/ARMAssembler( 100): generated scanline__00000077:03545404_00000A01_00000000 [ 30 ipp] (51 ins) at [0x403241e8:0x403242b4] in 183105 ns I/SystemServer( 88): Power Manager I/SystemServer( 88): Activity Manager I/ActivityManager( 88): Memory class: 16 I/SystemServer( 88): Telephony Registry I/SystemServer( 88): Package Manager I/installd( 71): new connection I/Installer( 88): connecting... D/dalvikvm( 88): GC_CONCURRENT freed 192K, 47% free 3019K/5639K, external 716K/1038K, paused 3ms+3ms D/dalvikvm( 88): GC_CONCURRENT freed 195K, 45% free 3279K/5959K, external 716K/1038K, paused 2ms+4ms W/PackageParser( 88): No actions in intent filter at /system/app/Bluetooth.apk Binary XML file line #124 D/dalvikvm( 88): GC_CONCURRENT freed 230K, 44% free 3494K/6215K, external 716K/1038K, paused 2ms+4ms D/PackageManager( 88): No files in app dir /vendor/app I/PackageManager( 88): Time to scan packages: 3.188 seconds W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.providers.contacts W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.cp in package com.android.providers.contacts W/PackageManager( 88): Unknown permission com.google.android.gm.permission.WRITE_GMAIL in package com.android.settings W/PackageManager( 88): Unknown permission com.google.android.gm.permission.READ_GMAIL in package com.android.settings W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.settings W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.mail in package com.android.contacts W/PackageManager( 88): Unknown permission android.permission.ADD_SYSTEM_SERVICE in package com.android.phone W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.providers.calendar W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.cl in package com.android.providers.calendar W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.ACCESS_GOOGLE_PASSWORD in package com.android.development W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.development W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.ALL_SERVICES in package com.android.development W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.YouTubeUser in package com.android.development W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.mail in package com.android.calendar W/PackageManager( 88): Not granting permission android.permission.SEND_DOWNLOAD_COMPLETED_INTENTS to package com.android.browser (protectionLevel=2 flags=0x9be45) W/PackageManager( 88): Unknown permission android.permission.READ_OWNER_DATA in package com.android.email W/PackageManager( 88): Not granting permission android.permission.DEVICE_POWER to package com.android.deskclock (protectionLevel=2 flags=0x8be45) D/dalvikvm( 88): GC_EXPLICIT freed 296K, 45% free 3505K/6279K, external 716K/1038K, paused 36ms I/SystemServer( 88): Account Manager I/SystemServer( 88): Content Manager I/ActivityThread( 88): Pub settings: com.android.providers.settings.SettingsProvider I/SystemServer( 88): System Content Providers I/SystemServer( 88): Battery Service I/SystemServer( 88): Lights Service I/SystemServer( 88): Vibrator Service D/SettingsProvider( 88): cache for settings table 'secure' rows=26; fullycached=true D/SettingsProvider( 88): cache for settings table 'system' rows=50; fullycached=true I/SystemServer( 88): Alarm Manager I/SystemServer( 88): Init Watchdog I/SystemServer( 88): Window Manager I/InputManager( 88): Initializing input manager I/InputManager( 88): Starting input manager I/EventHub( 88): New device: path=/dev/input/event1 name=TSC2007 Touchscreen id=0x10000 (of 0x1) index=1 fd=81 classes=0x4 E/EventHub( 88): could not get driver version for /dev/input/mouse0, Not a typewriter E/EventHub( 88): could not get driver version for /dev/input/mice, Not a typewriter I/EventHub( 88): New device: path=/dev/input/event2 name=USB Optical Mouse id=0x10001 (of 0x2) index=2 fd=84 classes=0x100 E/EventHub( 88): could not get driver version for /dev/input/mouse1, Not a typewriter I/BluetoothProfileState( 88): Message:Entering Stable State I/BluetoothProfileState( 88): Message:Entering Stable State I/SystemServer( 88): Bluetooth Service I/EventHub( 88): New keyboard: device->id=0x10002 devname='TWL4030 Keypad' propName='hw.keyboards.65538.devname' keylayout='/system/usr/keylayout/qwerty.kl' I/EventHub( 88): New device: path=/dev/input/event0 name=TWL4030 Keypad id=0x10002 (of 0x3) index=3 fd=86 classes=0x1 I/InputReader( 88): Device added: id=0x0, name=TWL4030 Keypad, sources=00000101 I/InputReader( 88): Device added: id=0x10001, name=USB Optical Mouse, sources=00002002 I/InputReader( 88): Device added: id=0x10000, name=TSC2007 Touchscreen, sources=00001002 I/SystemServer( 88): Device Policy I/SystemServer( 88): Status Bar I/SystemServer( 88): Clipboard Service I/SystemServer( 88): Input Method Service I/InputManager-Callbacks( 88): No input device calibration properties found for device TWL4030 Keypad. I/InputManager-Callbacks( 88): No input device calibration properties found for device USB Optical Mouse. I/InputManager-Callbacks( 88): No input device calibration properties found for device TSC2007 Touchscreen. I/InputManagerService( 88): Enabled input methods: com.android.inputmethod.latin/.LatinIME I/SystemServer( 88): NetStat Service I/SystemServer( 88): NetworkManagement Service I/SystemServer( 88): Connectivity Service I/WifiService( 88): WifiService starting up with Wi-Fi disabled D/NetworkManagmentService( 88): Registering observer I/SystemServer( 88): Throttle Service I/SystemServer( 88): Accessibility Manager I/SystemServer( 88): Mount Service I/SystemServer( 88): Notification Manager I/SystemServer( 88): Device Storage Monitor I/SystemServer( 88): Location Manager I/SystemServer( 88): Search Service I/SystemServer( 88): DropBox Service I/SystemServer( 88): Wallpaper Service D/VoldCmdListener( 64): volume list I/SystemServer( 88): Audio Service I/PackageManager( 88): Updating external media status from unmounted to unmounted D/VoldCmdListener( 64): share status ums D/AudioHardwareInterface( 69): setMode(NORMAL) W/AudioPolicyManagerBase( 69): setPhoneState() setting same state 0 I/SystemServer( 88): Headset Observer W/UsbDeviceSettingsManager( 88): settings file not found W/HeadsetObserver( 88): This kernel does not have wired headset support I/SystemServer( 88): HDMI Observer W/HDMIObserver( 88): This kernel does not have hdmi support I/SystemServer( 88): Dock Observer W/DockObserver( 88): This kernel does not have dock station support I/SystemServer( 88): USB Service I/UsbService( 88): This kernel does not have USB configuration switch support I/SystemServer( 88): UI Mode Manager Service D/dalvikvm( 88): GC_CONCURRENT freed 200K, 41% free 3835K/6471K, external 716K/1038K, paused 2ms+4ms I/SystemServer( 88): Backup Service V/BackupManagerService( 88): No ancestral data I/BackupManagerService( 88): Found stale backup journal, scheduling I/BackupManagerService( 88): android I/BackupManagerService( 88): com.android.inputmethod.latin I/BackupManagerService( 88): com.android.browser I/BackupManagerService( 88): com.android.providers.userdictionary I/BackupManagerService( 88): com.android.providers.settings I/BackupManagerService( 88): Backup enabled => false I/SystemServer( 88): AppWidget Service I/WindowManager( 88): SAFE MODE not enabled D/dalvikvm( 88): JIT started for system_server I/SystemServer( 88): Recognition Service I/SystemServer( 88): DiskStats Service D/PowerManagerService( 88): system ready! I/ActivityManager( 88): System now ready I/SystemServer( 88): Making services ready I/StatusBarManagerService( 88): Starting service: ComponentInfo{com.android.systemui/com.android.systemui.statusbar.StatusBarService} I/InputDispatcher( 88): Dropping event because there is no focused window or focused application. I/Zygote ( 88): Process: zygote socket opened I/ActivityManager( 88): Start proc com.android.systemui for service com.android.systemui/.statusbar.StatusBarService: pid=148 uid=1000 gids={3002, 3001, 3003} I/ActivityManager( 88): Config changed: { scale=1.0 imsi=0/0 loc=md_US touch=0 keys=0/0/0 nav=0/0 orien=0 layout=0 uiMode=17 seq=2} W/RecognitionManagerService( 88): no available voice recognition services found I/ActivityManager( 88): Config changed: { scale=1.0 imsi=0/0 loc=md_US touch=3 keys=1/1/2 nav=1/1 orien=1 layout=18 uiMode=17 seq=3} I/ActivityManager( 88): Start proc com.android.inputmethod.latin for service com.android.inputmethod.latin/.LatinIME: pid=157 uid=10007 gids={} W/ActivityManager( 88): Unable to start service Intent { act=@0 }: not found W/ActivityManager( 88): Unable to start service Intent { act=@0 }: not found D/NetworkManagmentService( 88): Registering observer E/ThrottleService( 88): Could not open GPS configuration file /etc/gps.conf I/ActivityManager( 88): Start proc com.android.phone for added application com.android.phone: pid=166 uid=1001 gids={3002, 3001, 3003, 1015} I/ActivityManager( 88): Starting: Intent { act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10000000 cmp=com.android.launcher/com.android.launcher2.Launcher } from pid 0 D/dalvikvm( 88): GREF has increased to 201 I/ActivityManager( 88): Start proc com.android.launcher for activity com.android.launcher/com.android.launcher2.Launcher: pid=168 uid=10016 gids={} I/SystemServer( 88): Enabled StrictMode for system server main thread. D/dalvikvm( 148): GC_CONCURRENT freed 350K, 51% free 2785K/5575K, external 716K/1038K, paused 2ms+3ms D/dalvikvm( 157): GC_CONCURRENT freed 392K, 51% free 2810K/5639K, external 716K/1038K, paused 2ms+2ms D/dalvikvm( 88): GC_EXTERNAL_ALLOC freed 165K, 41% free 3916K/6535K, external 1035K/1038K, paused 59ms I/ActivityManager( 88): Start proc android.process.acore for content provider com.android.providers.userdictionary/.UserDictionaryProvider: pid=188 uid=10015 gids={3003, 1015} D/dalvikvm( 166): GC_CONCURRENT freed 403K, 51% free 2813K/5639K, external 716K/1038K, paused 2ms+20ms D/dalvikvm( 168): GC_CONCURRENT freed 399K, 51% free 2788K/5639K, external 716K/1038K, paused 2ms+26ms I/ActivityThread( 188): Pub com.android.social: com.android.providers.contacts.SocialProvider I/ActivityThread( 166): Pub mms: com.android.providers.telephony.MmsProvider I/ActivityThread( 168): Pub com.android.launcher2.settings: com.android.launcher2.LauncherProvider I/ActivityThread( 166): Pub sms: com.android.providers.telephony.SmsProvider I/ActivityThread( 166): Pub telephony: com.android.providers.telephony.TelephonyProvider I/ActivityThread( 166): Pub icc: com.android.phone.IccProvider I/ActivityThread( 166): Pub mms-sms: com.android.providers.telephony.MmsSmsProvider D/dalvikvm( 157): GC_EXTERNAL_ALLOC freed 68K, 49% free 2891K/5639K, external 1020K/1038K, paused 132ms I//system/bin/tc( 65): Cannot find device "rmnet0" I/logwrapper( 65): /system/bin/tc terminated by exit(1) I//system/bin/tc( 65): Cannot find device "rmnet0" I/logwrapper( 65): /system/bin/tc terminated by exit(1) I//system/bin/tc( 65): Cannot find device "ifb0" I/logwrapper( 65): /system/bin/tc terminated by exit(1) I/ActivityThread( 188): Pub applications: com.android.providers.applications.ApplicationsProvider D/dalvikvm( 188): GC_CONCURRENT freed 397K, 51% free 2778K/5639K, external 716K/1038K, paused 5ms+16ms I/ActivityThread( 188): Pub contacts;com.android.contacts: com.android.providers.contacts.ContactsProvider2 D/CallManager( 166): registerPhone(GSM Handler{40517580}) D/dalvikvm( 168): GC_EXTERNAL_ALLOC freed 48K, 50% free 2844K/5639K, external 1032K/1038K, paused 66ms D/dalvikvm( 88): GC_CONCURRENT freed 344K, 41% free 4010K/6791K, external 1474K/1567K, paused 5ms+30ms I/StatusBarManagerService( 88): registerStatusBar bar=com.android.internal.statusbar.IStatusBar$Stub$Proxy@40615478 W/ActivityManager( 88): Unable to start service Intent { act=com.android.ussd.IExtendedNetworkService }: not found D/VoldCmdListener( 64): share status ums I/TelephonyRegistry( 88): notifyServiceState: 1 home null null null Unknown CSS not supported -1 -1RoamInd: -1DefRoamInd: -1EmergOnly: false I/TelephonyRegistry( 88): notifyDataConnection: state=0 isDataConnectivityPossible=false reason=radioTurnedOff interfaceName=null networkType=0 I/TelephonyRegistry( 88): notifyDataConnection: state=0 isDataConnectivityPossible=false reason=gprsDetached interfaceName=null networkType=0 D/BT HSHFP( 166): Starting BluetoothHeadsetService D/StorageNotification( 148): Startup with UMS connection false (media state unmounted) I/ActivityThread( 188): Pub call_log: com.android.providers.contacts.CallLogProvider I/ActivityThread( 188): Pub user_dictionary: com.android.providers.userdictionary.UserDictionaryProvider I/StorageNotification( 148): UMS connection changed to false (media state unmounted) I/ActivityManager( 88): Displayed com.android.launcher/com.android.launcher2.Launcher: +2s0ms D/dalvikvm( 168): GC_EXTERNAL_ALLOC freed 66K, 49% free 2912K/5639K, external 1561K/1572K, paused 80ms D/dalvikvm( 88): GC_EXTERNAL_ALLOC freed 204K, 40% free 4115K/6791K, external 1563K/1567K, paused 44ms D/PowerManagerService( 88): bootCompleted D/OtaStartupReceiver( 166): Not a CDMA phone, no need to process OTA D/VoldCmdListener( 64): volume mount /mnt/sdcard I/Vold ( 64): /dev/block/vold/179:3 being considered for volume sdcard D/Vold ( 64): Volume sdcard state changing 1 (Idle-Unmounted) -> 3 (Checking) W/logwrapper( 230): Unable to background process (No such file or directory) I/SearchManagerService( 88): Building list of searchable activities I//system/bin/fsck_msdos( 64): ** /dev/block/vold/179:3 I//system/bin/fsck_msdos( 64): ** Phase 1 - Read and Compare FATs I//system/bin/fsck_msdos( 64): Attempting to allocate 885 KB for FAT I/ActivityManager( 88): Start proc android.process.media for broadcast com.android.providers.downloads/.DownloadReceiver: pid=232 uid=10004 gids={1015, 2001, 3003} I/RecoverySystem( 88): No recovery log file I//system/bin/fsck_msdos( 64): Attempting to allocate 885 KB for FAT I/StorageNotification( 148): Media {/mnt/sdcard} state changed from {unmounted} -> {checking} I/ActivityThread( 232): Pub media: com.android.providers.media.MediaProvider I//system/bin/fsck_msdos( 64): ** Phase 2 - Check Cluster Chains I//system/bin/fsck_msdos( 64): ** Phase 3 - Checking Directories D/dalvikvm( 232): GC_CONCURRENT freed 392K, 51% free 2770K/5639K, external 716K/1038K, paused 2ms+3ms D/dalvikvm( 88): GC_EXTERNAL_ALLOC freed 188K, 39% free 4161K/6791K, external 1419K/1928K, paused 147ms I//system/bin/fsck_msdos( 64): ** Phase 4 - Checking for Lost Files I//system/bin/fsck_msdos( 64): 69 files, 855888 free (213972 clusters) I/Vold ( 64): Filesystem check completed OK I/Vold ( 64): Device /dev/block/vold/179:3, target /mnt/sdcard mounted @ /mnt/secure/staging D/Vold ( 64): Volume sdcard state changing 3 (Checking) -> 4 (Mounted) I/PackageManager( 88): Updating external media status from unmounted to mounted V/MediaProvider( 232): Attached volume: internal V/MediaProvider( 232): /mnt/sdcard volume ID: -1642657358 V/MediaProvider( 232): Attached volume: external I/ActivityThread( 232): Pub downloads: com.android.providers.downloads.DownloadProvider D/VoldCmdListener( 64): asec list D/dalvikvm( 168): GC_EXTERNAL_ALLOC freed 127K, 48% free 2984K/5639K, external 2083K/2091K, paused 68ms I/PackageManager( 88): No secure containers on sdcard D/dalvikvm( 168): GC_EXPLICIT freed 42K, 48% free 2954K/5639K, external 2047K/2487K, paused 56ms W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.providers.contacts W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.cp in package com.android.providers.contacts W/PackageManager( 88): Unknown permission com.google.android.gm.permission.WRITE_GMAIL in package com.android.settings W/PackageManager( 88): Unknown permission com.google.android.gm.permission.READ_GMAIL in package com.android.settings W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.settings W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.mail in package com.android.contacts I/SurfaceFlinger( 88): Boot is finished (9121 ms) I/ARMAssembler( 88): generated scanline__00000177:03515104_00000001_00000000 [ 73 ipp] (95 ins) at [0x436f1290:0x436f140c] in 152588 ns W/PackageManager( 88): Unknown permission android.permission.ADD_SYSTEM_SERVICE in package com.android.phone W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.providers.calendar W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.cl in package com.android.providers.calendar W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.ACCESS_GOOGLE_PASSWORD in package com.android.development W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH in package com.android.development W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.ALL_SERVICES in package com.android.development W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.YouTubeUser in package com.android.development W/PackageManager( 88): Unknown permission com.google.android.googleapps.permission.GOOGLE_AUTH.mail in package com.android.calendar W/PackageManager( 88): Not granting permission android.permission.SEND_DOWNLOAD_COMPLETED_INTENTS to package com.android.browser (protectionLevel=2 flags=0x9be45) W/PackageManager( 88): Unknown permission android.permission.READ_OWNER_DATA in package com.android.email W/PackageManager( 88): Not granting permission android.permission.DEVICE_POWER to package com.android.deskclock (protectionLevel=2 flags=0x8be45) I/ActivityThread( 232): Pub drm: com.android.providers.drm.DrmProvider D/dalvikvm( 88): GREF has increased to 301 I/ActivityManager( 88): Start proc com.android.providers.calendar for broadcast com.android.providers.calendar/.CalendarReceiver: pid=244 uid=10011 gids={3003} D/MediaScannerService( 232): start scanning volume internal D/dalvikvm( 244): GC_CONCURRENT freed 384K, 51% free 2764K/5575K, external 716K/1038K, paused 2ms+12ms I/ActivityThread( 244): Pub com.android.calendar: com.android.providers.calendar.CalendarProvider2 I/StorageNotification( 148): Media {/mnt/sdcard} state changed from {checking} -> {mounted} D/MediaScanner( 232): prescan time: 125ms D/MediaScanner( 232): scan time: 51ms D/MediaScanner( 232): postscan time: 1ms D/MediaScanner( 232): total time: 177ms D/MediaScannerService( 232): done scanning volume internal D/Calendar( 244): missed alarms found: 0 I/ActivityManager( 88): Start proc com.android.bluetooth for broadcast com.android.bluetooth/.opp.BluetoothOppReceiver: pid=257 uid=10014 gids={3003, 3002, 3001, 1015} I/ActivityThread( 257): Pub com.android.bluetooth.opp: com.android.bluetooth.opp.BluetoothOppProvider I/ActivityManager( 88): Start proc com.android.deskclock for broadcast com.android.deskclock/.AlarmInitReceiver: pid=265 uid=10020 gids={} D/dalvikvm( 68): GC_EXPLICIT freed 10K, 50% free 2709K/5379K, external 716K/1038K, paused 52ms D/dalvikvm( 257): GC_CONCURRENT freed 386K, 51% free 2793K/5639K, external 716K/1038K, paused 2ms+2ms D/dalvikvm( 68): GC_EXPLICIT freed <1K, 50% free 2709K/5379K, external 716K/1038K, paused 49ms D/dalvikvm( 68): GC_EXPLICIT freed <1K, 50% free 2709K/5379K, external 716K/1038K, paused 47ms I/ActivityThread( 265): Pub com.android.deskclock: com.android.deskclock.AlarmProvider D/dalvikvm( 265): GC_CONCURRENT freed 368K, 51% free 2819K/5639K, external 716K/1038K, paused 3ms+3ms I/ActivityManager( 88): Start proc com.android.mms for broadcast com.android.mms/.transaction.MmsSystemEventReceiver: pid=279 uid=10024 gids={3003, 1015} D/dalvikvm( 88): GC_CONCURRENT freed 444K, 41% free 4211K/7111K, external 1865K/3065K, paused 2ms+4ms I/ActivityThread( 279): Pub com.android.mms.SuggestionsProvider: com.android.mms.SuggestionsProvider D/dalvikvm( 166): GC_CONCURRENT freed 204K, 48% free 3015K/5703K, external 716K/1038K, paused 2ms+13ms D/dalvikvm( 279): GC_CONCURRENT freed 499K, 51% free 2864K/5831K, external 716K/1038K, paused 2ms+6ms D/dalvikvm( 188): GC_CONCURRENT freed 269K, 49% free 2945K/5703K, external 716K/1038K, paused 4ms+25ms I/ActivityManager( 88): Start proc com.android.email for broadcast com.android.email/.service.EmailBroadcastReceiver: pid=297 uid=10027 gids={3003, 1015} D/dalvikvm( 166): GREF has increased to 201 I/ActivityThread( 297): Pub com.android.email.provider: com.android.email.provider.EmailProvider I/ActivityThread( 297): Pub com.android.email.attachmentprovider: com.android.email.provider.AttachmentProvider I/ActivityThread( 297): Pub com.android.exchange.provider: com.android.exchange.provider.ExchangeProvider I/ActivityManager( 88): Start proc com.android.music for broadcast com.android.music/.MediaAppWidgetProvider: pid=309 uid=10001 gids={3003, 1015} D/EAS SyncManager( 297): !!! EAS SyncManager, onCreate D/Eas Debug( 297): Logging: I/ActivityManager( 88): Start proc com.android.quicksearchbox for broadcast com.android.quicksearchbox/.SearchWidgetProvider: pid=322 uid=10010 gids={3003} D/dalvikvm( 297): GC_CONCURRENT freed 541K, 51% free 2866K/5831K, external 716K/1038K, paused 2ms+13ms D/EAS SyncManager( 297): !!! EAS SyncManager, onDestroy D/Email ( 297): BOOT_COMPLETED D/EAS SyncManager( 297): !!! EAS SyncManager, onCreate D/EAS SyncManager( 297): !!! EAS SyncManager, onStartCommand D/EAS SyncManager( 297): !!! EAS SyncManager, stopping self D/EAS SyncManager( 297): !!! EAS SyncManager, onDestroy I/ActivityThread( 322): Pub com.android.quicksearchbox.google: com.android.quicksearchbox.google.GoogleSuggestionProvider I/ActivityThread( 322): Pub com.android.quicksearchbox.shortcuts: com.android.quicksearchbox.ShortcutsProvider I/ActivityManager( 88): Start proc com.android.protips for broadcast com.android.protips/.ProtipWidget: pid=332 uid=10013 gids={} I/ActivityManager( 88): Start proc com.cooliris.media for broadcast com.cooliris.media/com.cooliris.cache.BootReceiver: pid=341 uid=10003 gids={1015, 3003} I/ActivityThread( 341): Pub com.cooliris.picasa.contentprovider: com.cooliris.picasa.PicasaContentProvider D/dalvikvm( 341): GC_CONCURRENT freed 608K, 53% free 2798K/5831K, external 716K/1038K, paused 3ms+2ms I/BootReceiver( 341): Got intent with action android.intent.action.MEDIA_MOUNTED I/BootReceiver( 341): Got intent with action android.intent.action.MEDIA_SCANNER_FINISHED D/MediaScannerService( 232): start scanning volume external D/MediaScanner( 232): prescan time: 25ms D/MediaScanner( 232): scan time: 8ms D/MediaScanner( 232): postscan time: 0ms D/MediaScanner( 232): total time: 33ms I/BootReceiver( 341): Got intent with action android.intent.action.MEDIA_SCANNER_FINISHED D/MediaScannerService( 232): done scanning volume external I/TelephonyRegistry( 88): notifyServiceState: 3 home null null null Unknown CSS not supported -1 -1RoamInd: -1DefRoamInd: -1EmergOnly: false I/TelephonyRegistry( 88): notifyServiceState: 0 home null null null Unknown CSS not supported -1 -1RoamInd: -1DefRoamInd: -1EmergOnly: false D/MccTable( 166): updateMccMncConfiguration: mcc=405, mnc=34 D/MccTable( 166): locale set to null_in I/ActivityManager( 88): Config changed: { scale=1.0 imsi=405/34 loc=md_US touch=3 keys=1/1/2 nav=1/1 orien=1 layout=18 uiMode=17 seq=4} D/dalvikvm( 88): GC_CONCURRENT freed 784K, 44% free 4186K/7431K, external 1865K/3065K, paused 3ms+8ms D/dalvikvm( 166): GC_CONCURRENT freed 409K, 48% free 3148K/6023K, external 716K/1038K, paused 2ms+11ms D/dalvikvm( 168): GC_EXTERNAL_ALLOC freed 221K, 47% free 3090K/5767K, external 2474K/2487K, paused 39ms D/TelephonyProvider( 166): Setting numeric '405034' to be the current operator I/TelephonyRegistry( 88): notifyDataConnection: state=1 isDataConnectivityPossible=true reason=simLoaded interfaceName=null networkType=0 D/dalvikvm( 168): GC_EXTERNAL_ALLOC freed 168K, 46% free 3160K/5767K, external 3078K/3079K, paused 28ms D/dalvikvm( 168): GC_EXPLICIT freed 118K, 48% free 3046K/5767K, external 2025K/2531K, paused 28ms D/dalvikvm( 88): GC_EXPLICIT freed 165K, 44% free 4216K/7431K, external 1865K/3065K, paused 40ms I/pppd ( 365): ######## Starting pppd I/pppd ( 367): ######## pppd additional arguments I/pppd ( 368): Serial connection established. D/pppd ( 368): using channel 1 I/pppd ( 368): Using interface ppp0 I/pppd ( 368): Connect: ppp0 <--> /dev/ttyACM0 D/Tethering( 88): ppp0 is not a tetherable iface, ignoring D/pppd ( 368): rcvd [LCP ConfReq id=0x5 ] D/pppd ( 368): sent [LCP ConfReq id=0x1 ] D/pppd ( 368): sent [LCP ConfAck id=0x5 ] D/pppd ( 368): rcvd [LCP ConfAck id=0x1 ] D/pppd ( 368): sent [LCP EchoReq id=0x0 magic=0xef9c9f0e] D/pppd ( 368): sent [CCP ConfReq id=0x1 ] D/pppd ( 368): sent [IPCP ConfReq id=0x1 ] D/pppd ( 368): rcvd [LCP EchoRep id=0x0 magic=0xb3615054] D/pppd ( 368): rcvd [LCP ProtRej id=0x0 80 fd 01 01 00 0f 1a 04 78 00 18 04 78 00 15 03 2f 32] I/TelephonyRegistry( 88): notifyDataConnection: state=2 isDataConnectivityPossible=true reason=simLoaded interfaceName=ppp0 networkType=0 D/MobileDataStateTracker( 88): CONNECTED event did not supply a default gateway. W/NetworkStateTracker( 88): net.tcp.buffersize.unknown not found in system properties. Using defaults D/Tethering( 88): MasterInitialState.processMessage what=3 D/pppd ( 368): sent [IPCP ConfReq id=0x1 ] D/pppd ( 368): rcvd [IPCP ConfRej id=0x1 ] D/pppd ( 368): sent [IPCP ConfReq id=0x2 ] D/pppd ( 368): rcvd [IPCP ConfReq id=0x1 ] D/pppd ( 368): sent [IPCP ConfAck id=0x1 ] D/pppd ( 368): rcvd [IPCP ConfNak id=0x2 ] D/pppd ( 368): sent [IPCP ConfReq id=0x3 ] D/pppd ( 368): rcvd [IPCP ConfAck id=0x3 ] I/pppd ( 368): local IP address 182.156.97.63 I/pppd ( 368): remote IP address 10.0.0.1 I/pppd ( 368): primary DNS address 121.242.190.210 I/pppd ( 368): secondary DNS address 121.242.190.181 D/pppd ( 368): Script /etc/ppp/ip-up started (pid 372) I/pppd ( 373): ppp/ip-up script started I/pppd ( 382): Using prefix ppp0 I/pppd ( 383): IP-UP TRACE for gprs I/pppd ( 384): DNS1 121.242.190.210 I/pppd ( 385): DNS2 121.242.190.181 I/pppd ( 386): local-ip 182.156.97.63 I/pppd ( 387): remote-ip 10.0.0.1 I/pppd ( 388): gw 10.0.0.1 I/pppd ( 389): IP-UP END TRACE for gprs I/pppd ( 390): ppp/ip-up script ended D/pppd ( 368): Script /etc/ppp/ip-up finished (pid 372), status = 0x0 W/ActivityManager( 88): finishReceiver called but no pending broadcasts I/ActivityManager( 88): Starting: Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=com.android.settings/.Settings } from pid 168 I/ActivityManager( 88): Start proc com.android.settings for activity com.android.settings/.Settings: pid=395 uid=1000 gids={3002, 3001, 3003} D/dalvikvm( 395): GC_CONCURRENT freed 461K, 50% free 2919K/5831K, external 944K/1038K, paused 2ms+3ms I/ActivityManager( 88): Displayed com.android.settings/.Settings: +397ms W/IInputConnectionWrapper( 395): showStatusIcon on inactive InputConnection I/ActivityManager( 88): Starting: Intent { act=android.intent.action.MAIN cmp=com.android.settings/.WirelessSettings } from pid 395 D/dalvikvm( 395): GC_EXTERNAL_ALLOC freed 61K, 51% free 2890K/5831K, external 953K/1038K, paused 25ms V/NFC ( 395): this device does not have NFC support I/ActivityManager( 88): Displayed com.android.settings/.WirelessSettings: +273ms D/dalvikvm( 168): GC_EXPLICIT freed 99K, 48% free 3000K/5767K, external 2521K/2531K, paused 34ms I/ActivityManager( 88): Starting: Intent { act=android.intent.action.MAIN cmp=com.android.phone/.Settings } from pid 395 I/Use2GOnlyCheckBoxPreference( 166): get preferred network type, exception=com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED I/ActivityManager( 88): Displayed com.android.phone/.Settings: +201ms D/GsmUmtsOptions( 166): preferenceTreeClick: return false D/NetworkSettings( 166): onPreferenceTreeClick: preference == mButtonDataEnabled. D/DataConnectionTracker( 166): disableApnType(default) D/DataConnectionTracker( 166): setEnabled(0, false) with old state = true and enabledCount = 1 D/DataConnectionTracker( 166): EVENT_APN_ENABLE_REQUEST 0, 0 D/DataConnectionTracker( 166): dataEnabled = true, enabledCount = 1, isApnTypeActive = true I/pppd ( 368): Terminating on signal 15 I/pppd ( 368): Connect time 2.0 minutes. I/pppd ( 368): Sent 80 bytes, received 104 bytes. E/Tethering( 88): attempting to remove unknown iface (ppp0), ignoring D/DataConnectionTracker( 166): disableApnType(mms) D/DataConnectionTracker( 166): disableApnType(supl) D/DataConnectionTracker( 166): disableApnType(hipri) I/TelephonyRegistry( 88): notifyDataConnection: state=0 isDataConnectivityPossible=true reason=dataDisabled interfaceName=null networkType=0 D/NetUtils( 88): failed to remove default route for ppp0: No such device D/Tethering( 88): MasterInitialState.processMessage what=3 D/dalvikvm( 232): GC_EXPLICIT freed 221K, 50% free 2847K/5639K, external 716K/1038K, paused 25ms ^C # logcat -b radio I/RILC ( 67): ril_event_init ... I/RIL ( 67): Opening tty device /dev/ttyACM0 I/RIL ( 67): Setting speed D/AT ( 67): AT> ATE0Q0V1 D/AT ( 67): AT> ATE0Q0V1 D/AT ( 67): AT> ATE0Q0V1 D/AT ( 67): AT> ATE0Q0V1 D/AT ( 67): AT> ATE0Q0V1 D/AT ( 67): AT> ATE0Q0V1 D/AT ( 67): AT> ATE0Q0V1 D/AT ( 67): AT> ATE0Q0V1 D/AT ( 67): AT> ATE0Q0V1 I/PHONE ( 166): Network Mode set to 0 I/PHONE ( 166): Cdma Subscription set to 1 I/RILJ ( 166): Connected to 'rild' socket I/PHONE ( 166): Creating GSMPhone D/PHONE ( 166): mDoesRilSendMultipleCallRing=true D/PHONE ( 166): mCallRingDelay=3000 W/GSM ( 166): Can't open /system/etc/voicemail-conf.xml W/GSM ( 166): Can't open /system/etc/spn-conf.xml D/GSM ( 166): [DSAC DEB] registerForPsRestrictedEnabled D/GSM ( 166): [DSAC DEB] registerForPsRestrictedDisabled D/GSM ( 166): [GsmDataConnection-1] DataConnection constructor E D/GSM ( 166): [GsmDataConnection-1] clearSettings D/GSM ( 166): [GsmDataConnection-1] DataConnection constructor X D/GSM ( 166): [GsmDataConnection-1] Made GsmDataConnection-1 D/RILJ ( 166): [0000]> RIL_REQUEST_REPORT_STK_SERVICE_IS_RUNNING D/STK ( 166): StkService: StkService: is running D/STK ( 166): StkService: NEW sInstance E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RILJ ( 166): [0001]> GET_CURRENT_CALLS D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but1 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=1 D/RILJ ( 166): 0: [0] RIL_REQUEST_REPORT_STK_SERVICE_IS_RUNNING E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! E/GSM ( 166): Wrong network type: 0 E/GSM ( 166): Wrong network type: 0 D/GSM ( 166): Poll ServiceState done: oldSS=[1 home null null null Unknown CSS not supported 0 0RoamInd: 0DefRoamInd: 0EmergOnly: false] newSS=[1 home null null null Unknown CSS not supported -1 -1RoamInd: -1DefRoamInd: -1EmergOnly: false] oldGprs=1 newGprs=1 oldType=unknown newType=unknown D/GSM ( 166): GSMDataConnTrack handleMessage { what=12 when=-538ms obj=android.os.AsyncResult@4055ff60 } D/GSM ( 166): [GsmDataConnectionTracker] Radio is off and clean up all connection D/GSM ( 166): [GsmDataConnectionTracker] Clean up connection due to radioTurnedOff D/GSM ( 166): [GsmDataConnectionTracker] setState: DISCONNECTING D/GSM ( 166): [GsmDataConnectionTracker] cleanUpConnection: !tearDown, call conn.resetSynchronously D/GSM ( 166): [GsmDataConnection-1] DcInactiveState: msg.what=EVENT_RESET, ignore we're already reset D/GSM ( 166): [GsmDataConnection-1] NotifyDisconnectCompleted D/GSM ( 166): [GsmDataConnection-1] clearSettings D/GSM ( 166): [DataConnection] Stop poll NetStat D/GSM ( 166): [GsmDataConnectionTracker] cleanupConnection: !notificationDeferred D/GSM ( 166): [GsmDataConnectionTracker] gotoIdleAndNotifyDataConnection: reason=radioTurnedOff D/GSM ( 166): [GsmDataConnectionTracker] setState: IDLE D/GSM ( 166): GSMDataConnTrack handleMessage { what=19 when=-547ms obj=android.os.AsyncResult@405602b0 } D/GSM ( 166): [DataConnection] Stop poll NetStat D/GSM ( 166): GSMDataConnTrack handleMessage { what=22 when=-551ms obj=android.os.AsyncResult@40560400 } D/GSM ( 166): [GsmDataConnectionTracker] ***trySetupData due to roamingOff D/GSM ( 166): trySetupData called D/GSM ( 166): [DSAC DEB] trySetupData with mIsPsRestricted=false D/GSM ( 166): [trySetupData] : going into state check D/GSM ( 166): trySetupData Statecheck failed D/GSM ( 166): [GsmDataConnectionTracker] trySetupData: Not ready for data: dataState=IDLE gprsState=1 sim=false UMTS=false phoneState=IDLE isDataAllowed=true dataEnabled=true roaming=false dataOnRoamingEnable=false ps restricted=false desiredPowerState=true MasterDataEnabled=true D/GSM ( 166): [IccCard] Broadcasting intent ACTION_SIM_STATE_CHANGED NOT_READY reason null I/RILJ ( 166): 'rild' socket closed I/RILJ ( 166): java.io.IOException: Connection reset by peer I/RILJ ( 166): at android.net.LocalSocketImpl.readba_native(Native Method) I/RILJ ( 166): at android.net.LocalSocketImpl.access$400(LocalSocketImpl.java:29) I/RILJ ( 166): at android.net.LocalSocketImpl$SocketInputStream.read(LocalSocketImpl.java:92) I/RILJ ( 166): at com.android.internal.telephony.RIL.readRilMessage(RIL.java:461) I/RILJ ( 166): at com.android.internal.telephony.RIL.access$200(RIL.java:206) I/RILJ ( 166): at com.android.internal.telephony.RIL$RILReceiver.run(RIL.java:560) I/RILJ ( 166): at java.lang.Thread.run(Thread.java:1019) I/RILJ ( 166): Disconnected from 'rild' socket D/RILJ ( 166): [0000]< RIL_REQUEST_REPORT_STK_SERVICE_IS_RUNNING error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE D/RILJ ( 166): [0001]< GET_CURRENT_CALLS error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE I/RILC ( 355): ril_event_init ... I/RIL ( 355): Opening tty device /dev/ttyACM0 I/RIL ( 355): Setting speed D/AT ( 355): AT> ATE0Q0V1 D/AT ( 355): AT< ^SYSSTART D/AT ( 355): AT< ATE0Q0V1 D/AT ( 355): AT< OK D/AT ( 355): AT< OK D/AT ( 355): AT> ATE0Q0V1 D/AT ( 355): AT> ATS0=0 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CMEE=2 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CREG=2 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CGREG=1 D/AT ( 355): AT< OK D/AT ( 355): AT> AT^SGAUTH=0 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CCWA=1 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CMUT=1 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CSSN=0,1 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+COLP=0 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CSCS="UCS2" D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CUSD=1 D/AT ( 355): AT< OK D/AT ( 355): AT> At+cgatt=1 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CMGF=0 D/AT ( 355): AT< OK I/RILC ( 355): libril: new connection I/RILC ( 355): RIL Daemon version: android reference-ril 1.0 I/RILJ ( 166): Connected to 'rild' socket D/RILB ( 166): Notifying: radio available D/RILJ ( 166): [0000]> SCREEN_STATE: true E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RILJ ( 166): [UNSL]< UNSOL_RESPONSE_RADIO_STATE_CHANGED RADIO_OFF D/RIL ( 355): onRequest: SCREEN_STATE D/RILJ ( 166): [0000]< SCREEN_STATE error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE D/RILJ ( 166): [0001]> REQUEST_SET_PREFERRED_NETWORK_TYPE : 0 E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RILJ ( 166): [0002]> RIL_REQUEST_CDMA_SET_SUBSCRIPTION : 1 D/RIL ( 355): onRequest: SET_PREFERRED_NETWORK_TYPE D/RILJ ( 166): [0001]< REQUEST_SET_PREFERRED_NETWORK_TYPE error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE D/RILJ ( 166): [0003]> RADIO_POWER E/RILJ ( 166): ERROR: mReqPending is NOT 0 but2 at TIMEOUT, reset! E/GSM ( 166): Wrong network type: 0 E/GSM ( 166): Wrong network type: 0 D/GSM ( 166): Poll ServiceState done: oldSS=[1 home null null null Unknown CSS not supported -1 -1RoamInd: -1DefRoamInd: -1EmergOnly: false] newSS=[3 home null null null Unknown CSS not supported -1 -1RoamInd: -1DefRoamInd: -1EmergOnly: false] oldGprs=1 newGprs=1 oldType=unknown newType=unknown D/RIL ( 355): onRequest: CDMA_SET_SUBSCRIPTION D/RIL ( 355): onRequest: RADIO_POWER D/AT ( 355): AT> AT+CFUN=1 D/RILJ ( 166): [0002]< RIL_REQUEST_CDMA_SET_SUBSCRIPTION error: com.android.internal.telephony.CommandException: RADIO_NOT_AVAILABLE D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CPIN? D/RILB ( 166): Notifying: Radio On D/RILJ ( 166): [UNSL]< UNSOL_RESPONSE_RADIO_STATE_CHANGED SIM_NOT_READY D/GSM ( 166): GSMDataConnTrack handleMessage { what=3 when=-33ms obj=android.os.AsyncResult@405236e8 } D/RILJ ( 166): [0004]> BASEBAND_VERSION D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but1 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=1 D/RILJ ( 166): 0: [3] RADIO_POWER E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RILJ ( 166): [0005]> GET_IMEI D/AT ( 355): AT< +CPIN: READY D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CSMS=1 D/RILB ( 166): Notifying: SIM ready D/RILJ ( 166): [UNSL]< UNSOL_RESPONSE_RADIO_STATE_CHANGED SIM_READY D/RILJ ( 166): [0006]> GET_IMEISV D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but1 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=2 D/RILJ ( 166): 0: [3] RADIO_POWER D/RILJ ( 166): 1: [4] BASEBAND_VERSION E/RILJ ( 166): ERROR: mReqPending is NOT 0 but2 at TIMEOUT, reset! D/RILJ ( 166): [0007]> OPERATOR D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but2 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=4 D/RILJ ( 166): 0: [3] RADIO_POWER D/RILJ ( 166): 1: [4] BASEBAND_VERSION D/RILJ ( 166): 2: [5] GET_IMEI D/RILJ ( 166): 3: [6] GET_IMEISV E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RILJ ( 166): [0008]> GPRS_REGISTRATION_STATE D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but1 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=5 D/RILJ ( 166): 0: [3] RADIO_POWER D/RILJ ( 166): 1: [4] BASEBAND_VERSION D/RILJ ( 166): 2: [5] GET_IMEI D/RILJ ( 166): 3: [6] GET_IMEISV D/RILJ ( 166): 4: [7] OPERATOR E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/AT ( 355): AT< +CSMS: 1,1,1 D/AT ( 355): AT< OK I/RIL ( 355): ######### SIM READY !! D/AT ( 355): AT> AT+CNMI=1,2,2,1,1 D/RILJ ( 166): [0009]> REGISTRATION_STATE D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but1 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=6 D/RILJ ( 166): 0: [3] RADIO_POWER D/RILJ ( 166): 1: [4] BASEBAND_VERSION D/RILJ ( 166): 2: [5] GET_IMEI D/RILJ ( 166): 3: [6] GET_IMEISV D/RILJ ( 166): 4: [7] OPERATOR D/RILJ ( 166): 5: [8] GPRS_REGISTRATION_STATE E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RILJ ( 166): [0010]> QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but1 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=7 D/RILJ ( 166): 0: [3] RADIO_POWER D/RILJ ( 166): 1: [4] BASEBAND_VERSION D/RILJ ( 166): 2: [5] GET_IMEI D/RILJ ( 166): 3: [6] GET_IMEISV D/RILJ ( 166): 4: [7] OPERATOR D/RILJ ( 166): 5: [8] GPRS_REGISTRATION_STATE D/RILJ ( 166): 6: [9] REGISTRATION_STATE E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RILJ ( 166): [0011]> GET_CURRENT_CALLS D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but1 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=8 D/RILJ ( 166): 0: [3] RADIO_POWER D/RILJ ( 166): 1: [4] BASEBAND_VERSION D/RILJ ( 166): 2: [5] GET_IMEI D/RILJ ( 166): 3: [6] GET_IMEISV D/RILJ ( 166): 4: [7] OPERATOR D/RILJ ( 166): 5: [8] GPRS_REGISTRATION_STATE D/RILJ ( 166): 6: [9] REGISTRATION_STATE D/RILJ ( 166): 7: [10] QUERY_NETWORK_SELECTION_MODE E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RILJ ( 166): [0012]> OPERATOR D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but1 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=9 D/RILJ ( 166): 0: [3] RADIO_POWER D/RILJ ( 166): 1: [4] BASEBAND_VERSION D/RILJ ( 166): 2: [5] GET_IMEI D/RILJ ( 166): 3: [6] GET_IMEISV D/RILJ ( 166): 4: [7] OPERATOR D/RILJ ( 166): 5: [8] GPRS_REGISTRATION_STATE D/RILJ ( 166): 6: [9] REGISTRATION_STATE D/RILJ ( 166): 7: [10] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 8: [11] GET_CURRENT_CALLS E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/AT ( 355): AT< OK D/RIL ( 355): onRequest: BASEBAND_VERSION D/RIL ( 355): onRequest: GET_IMEI D/AT ( 355): AT> AT+CGSN D/RILJ ( 166): [0003]< RADIO_POWER D/AT ( 355): AT< 353229024471268 D/RILJ ( 166): [0004]< BASEBAND_VERSION error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED D/RILJ ( 166): [0013]> GPRS_REGISTRATION_STATE D/RILJ ( 166): [0014]> REGISTRATION_STATE D/RILJ ( 166): [0015]> QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but1 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=8 D/RILJ ( 166): 0: [5] GET_IMEI D/RILJ ( 166): 1: [6] GET_IMEISV D/RILJ ( 166): 2: [7] OPERATOR D/RILJ ( 166): 3: [8] GPRS_REGISTRATION_STATE D/RILJ ( 166): 4: [9] REGISTRATION_STATE D/RILJ ( 166): 5: [10] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 6: [11] GET_CURRENT_CALLS D/RILJ ( 166): 7: [12] OPERATOR E/RILJ ( 166): ERROR: mReqPending is NOT 0 but3 at TIMEOUT, reset! D/RILJ ( 166): [0016]> SET_NETWORK_SELECTION_AUTOMATIC D/AT ( 355): AT< OK D/RIL ( 355): onRequest: GET_IMEISV D/RIL ( 355): onRequest: OPERATOR D/AT ( 355): AT> AT+COPS=3,0;+COPS?;+COPS=3,1;+COPS?;+COPS=3,2;+COPS? D/RILJ ( 166): [0005]< GET_IMEI D/RILJ ( 166): [0017]> OPERATOR D/RILJ ( 166): [0018]> GPRS_REGISTRATION_STATE D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but2 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=10 D/RILJ ( 166): 0: [6] GET_IMEISV D/RILJ ( 166): 1: [7] OPERATOR D/RILJ ( 166): 2: [8] GPRS_REGISTRATION_STATE D/RILJ ( 166): 3: [9] REGISTRATION_STATE D/RILJ ( 166): 4: [10] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 5: [11] GET_CURRENT_CALLS D/RILJ ( 166): 6: [12] OPERATOR D/RILJ ( 166): 7: [13] GPRS_REGISTRATION_STATE D/RILJ ( 166): 8: [14] REGISTRATION_STATE D/RILJ ( 166): 9: [15] QUERY_NETWORK_SELECTION_MODE E/RILJ ( 166): ERROR: mReqPending is NOT 0 but2 at TIMEOUT, reset! D/RILJ ( 166): [0006]< GET_IMEISV error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED D/RILJ ( 166): [0019]> REGISTRATION_STATE D/RILJ ( 166): [0020]> QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but1 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=11 D/RILJ ( 166): 0: [7] OPERATOR D/RILJ ( 166): 1: [8] GPRS_REGISTRATION_STATE D/RILJ ( 166): 2: [9] REGISTRATION_STATE D/RILJ ( 166): 3: [10] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 4: [11] GET_CURRENT_CALLS D/RILJ ( 166): 5: [12] OPERATOR D/RILJ ( 166): 6: [13] GPRS_REGISTRATION_STATE D/RILJ ( 166): 7: [14] REGISTRATION_STATE D/RILJ ( 166): 8: [15] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 9: [16] SET_NETWORK_SELECTION_AUTOMATIC D/RILJ ( 166): 10: [17] OPERATOR E/RILJ ( 166): ERROR: mReqPending is NOT 0 but3 at TIMEOUT, reset! D/GSM ( 166): [IccCard] Broadcasting intent ACTION_SIM_STATE_CHANGED READY reason null D/AT ( 355): AT< +COPS: 0,0,"CC 405 NC 034" D/AT ( 355): AT< ERROR E/RIL ( 355): requestOperator must not return error when radio is on D/RIL ( 355): onRequest: GPRS_REGISTRATION_STATE D/AT ( 355): AT> AT+CGREG? D/RILJ ( 166): [0007]< OPERATOR error: com.android.internal.telephony.CommandException: GENERIC_FAILURE V/GSM ( 166): SIMRecords:fetchSimRecords 0 D/RILJ ( 166): [0021]> getIMSI:RIL_REQUEST_GET_IMSI 11 GET_IMSI D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but2 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=13 D/RILJ ( 166): 0: [8] GPRS_REGISTRATION_STATE D/RILJ ( 166): 1: [9] REGISTRATION_STATE D/RILJ ( 166): 2: [10] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 3: [11] GET_CURRENT_CALLS D/RILJ ( 166): 4: [12] OPERATOR D/RILJ ( 166): 5: [13] GPRS_REGISTRATION_STATE D/RILJ ( 166): 6: [14] REGISTRATION_STATE D/RILJ ( 166): 7: [15] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 8: [16] SET_NETWORK_SELECTION_AUTOMATIC D/RILJ ( 166): 9: [17] OPERATOR D/RILJ ( 166): 10: [18] GPRS_REGISTRATION_STATE D/RILJ ( 166): 11: [19] REGISTRATION_STATE D/RILJ ( 166): 12: [20] QUERY_NETWORK_SELECTION_MODE E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RILJ ( 166): [0022]> iccIO: SIM_IO 0xc0 0x2fe2 path: 3F00,0,0,15 D/AT ( 355): AT< +CGREG: 0,1 D/RILJ ( 166): [0023]> iccIO: SIM_IO 0xc0 0x6f40 path: 3F007F10,0,0,15 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: REGISTRATION_STATE D/AT ( 355): AT> AT+CREG? D/RILJ ( 166): [0008]< GPRS_REGISTRATION_STATE {1, ffffffff, ffffffff} E/RILJ ( 166): ERROR: mReqPending is NOT 0 but2 at TIMEOUT, reset! D/RILJ ( 166): [0024]> iccIO: SIM_IO 0xc0 0x6fc9 path: 3F007F20,0,0,15 D/RILJ ( 166): [0025]> iccIO: SIM_IO 0xc0 0x6fad path: 3F007F20,0,0,15 D/AT ( 355): AT< +CREG: 0,1 D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but2 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=15 D/RILJ ( 166): 0: [9] REGISTRATION_STATE D/RILJ ( 166): 1: [10] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 2: [11] GET_CURRENT_CALLS D/RILJ ( 166): 3: [12] OPERATOR D/RILJ ( 166): 4: [13] GPRS_REGISTRATION_STATE D/RILJ ( 166): 5: [14] REGISTRATION_STATE D/RILJ ( 166): 6: [15] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 7: [16] SET_NETWORK_SELECTION_AUTOMATIC D/RILJ ( 166): 8: [17] OPERATOR D/RILJ ( 166): 9: [18] GPRS_REGISTRATION_STATE D/RILJ ( 166): 10: [19] REGISTRATION_STATE D/RILJ ( 166): 11: [20] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 12: [21] GET_IMSI D/RILJ ( 166): 13: [22] SIM_IO D/RILJ ( 166): 14: [23] SIM_IO E/RILJ ( 166): ERROR: mReqPending is NOT 0 but2 at TIMEOUT, reset! D/RILJ ( 166): [0026]> iccIO: SIM_IO 0xc0 0x6fca path: 3F007F20,0,0,15 D/RILJ ( 166): [0027]> iccIO: SIM_IO 0xc0 0x6f11 path: 3F007F20,0,0,15 D/RILJ ( 166): [0028]> iccIO: SIM_IO 0xc0 0x6fcb path: 3F007F20,0,0,15 D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but2 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=17 D/RILJ ( 166): 0: [9] REGISTRATION_STATE D/RILJ ( 166): 1: [10] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 2: [11] GET_CURRENT_CALLS D/RILJ ( 166): 3: [12] OPERATOR D/RILJ ( 166): 4: [13] GPRS_REGISTRATION_STATE D/RILJ ( 166): 5: [14] REGISTRATION_STATE D/RILJ ( 166): 6: [15] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 7: [16] SET_NETWORK_SELECTION_AUTOMATIC D/RILJ ( 166): 8: [17] OPERATOR D/RILJ ( 166): 9: [18] GPRS_REGISTRATION_STATE D/RILJ ( 166): 10: [19] REGISTRATION_STATE D/RILJ ( 166): 11: [20] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 12: [21] GET_IMSI D/RILJ ( 166): 13: [22] SIM_IO D/RILJ ( 166): 14: [23] SIM_IO D/RILJ ( 166): 15: [24] SIM_IO D/RILJ ( 166): 16: [25] SIM_IO E/RILJ ( 166): ERROR: mReqPending is NOT 0 but3 at TIMEOUT, reset! D/AT ( 355): AT< OK D/RIL ( 355): onRequest: QUERY_NETWORK_SELECTION_MODE D/AT ( 355): AT> AT+COPS? D/RILJ ( 166): [0009]< REGISTRATION_STATE {1, ffffffff, ffffffff} D/RILJ ( 166): [0029]> iccIO: SIM_IO 0xc0 0x6f13 path: 3F007F20,0,0,15 D/AT ( 355): AT< +COPS: 0,0,"CC 405 NC 034" D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but3 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=19 D/RILJ ( 166): 0: [10] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 1: [11] GET_CURRENT_CALLS D/RILJ ( 166): 2: [12] OPERATOR D/RILJ ( 166): 3: [13] GPRS_REGISTRATION_STATE D/RILJ ( 166): 4: [14] REGISTRATION_STATE D/RILJ ( 166): 5: [15] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 6: [16] SET_NETWORK_SELECTION_AUTOMATIC D/RILJ ( 166): 7: [17] OPERATOR D/RILJ ( 166): 8: [18] GPRS_REGISTRATION_STATE D/RILJ ( 166): 9: [19] REGISTRATION_STATE D/RILJ ( 166): 10: [20] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 11: [21] GET_IMSI D/RILJ ( 166): 12: [22] SIM_IO D/RILJ ( 166): 13: [23] SIM_IO D/RILJ ( 166): 14: [24] SIM_IO D/RILJ ( 166): 15: [25] SIM_IO D/RILJ ( 166): 16: [26] SIM_IO D/RILJ ( 166): 17: [27] SIM_IO D/RILJ ( 166): 18: [28] SIM_IO E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RILJ ( 166): [0030]> iccIO: SIM_IO 0xc0 0x6f46 path: 3F007F20,0,0,15 D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but1 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=20 D/RILJ ( 166): 0: [10] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 1: [11] GET_CURRENT_CALLS D/RILJ ( 166): 2: [12] OPERATOR D/RILJ ( 166): 3: [13] GPRS_REGISTRATION_STATE D/RILJ ( 166): 4: [14] REGISTRATION_STATE D/RILJ ( 166): 5: [15] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 6: [16] SET_NETWORK_SELECTION_AUTOMATIC D/RILJ ( 166): 7: [17] OPERATOR D/RILJ ( 166): 8: [18] GPRS_REGISTRATION_STATE D/RILJ ( 166): 9: [19] REGISTRATION_STATE D/RILJ ( 166): 10: [20] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 11: [21] GET_IMSI D/RILJ ( 166): 12: [22] SIM_IO D/RILJ ( 166): 13: [23] SIM_IO D/RILJ ( 166): 14: [24] SIM_IO D/RILJ ( 166): 15: [25] SIM_IO D/RILJ ( 166): 16: [26] SIM_IO D/RILJ ( 166): 17: [27] SIM_IO D/RILJ ( 166): 18: [28] SIM_IO D/RILJ ( 166): 19: [29] SIM_IO E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/AT ( 355): AT< OK D/RIL ( 355): onRequest: GET_CURRENT_CALLS D/RIL ( 355): controll came in function preprocess D/AT ( 355): AT> AT+CLCC D/RILJ ( 166): [0010]< QUERY_NETWORK_SELECTION_MODE {0} D/RILJ ( 166): [0031]> iccIO: SIM_IO 0xc0 0x6fcd path: 3F007F20,0,0,15 D/RILJ ( 166): [0032]> iccIO: SIM_IO 0xc0 0x6fc5 path: 3F007F20,0,0,15 D/RILJ ( 166): [0033]> iccIO: SIM_IO 0xc0 0x6f38 path: 3F007F20,0,0,15 D/RILJ ( 166): [0034]> iccIO: SIM_IO 0xc0 0x6f16 path: 3F007F20,0,0,15 D/RILJ ( 166): [0035]> GET_SIM_STATUS D/RILJ ( 166): [0036]> QUERY_FACILITY_LOCK D/RILJ ( 166): [0037]> QUERY_FACILITY_LOCK D/RILJ ( 166): NOTE: mReqWaiting is NOT 0 but1 at TIMEOUT, reset! There still msg waitng for response D/RILJ ( 166): WAKE_LOCK_TIMEOUT mRequestList=20 D/RILJ ( 166): 0: [11] GET_CURRENT_CALLS D/RILJ ( 166): 1: [12] OPERATOR D/RILJ ( 166): 2: [13] GPRS_REGISTRATION_STATE D/RILJ ( 166): 3: [14] REGISTRATION_STATE D/RILJ ( 166): 4: [15] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 5: [16] SET_NETWORK_SELECTION_AUTOMATIC D/RILJ ( 166): 6: [17] OPERATOR D/RILJ ( 166): 7: [18] GPRS_REGISTRATION_STATE D/RILJ ( 166): 8: [19] REGISTRATION_STATE D/RILJ ( 166): 9: [20] QUERY_NETWORK_SELECTION_MODE D/RILJ ( 166): 10: [21] GET_IMSI D/RILJ ( 166): 11: [22] SIM_IO D/RILJ ( 166): 12: [23] SIM_IO D/RILJ ( 166): 13: [24] SIM_IO D/RILJ ( 166): 14: [25] SIM_IO D/RILJ ( 166): 15: [26] SIM_IO D/RILJ ( 166): 16: [27] SIM_IO D/RILJ ( 166): 17: [28] SIM_IO D/RILJ ( 166): 18: [29] SIM_IO D/RILJ ( 166): 19: [30] SIM_IO E/RILJ ( 166): ERROR: mReqPending is NOT 0 but7 at TIMEOUT, reset! D/AT ( 355): AT< OK D/RIL ( 355): controll came in function postprocess D/RIL ( 355): onRequest: OPERATOR D/AT ( 355): AT> AT+COPS=3,0;+COPS?;+COPS=3,1;+COPS?;+COPS=3,2;+COPS? D/RILJ ( 166): [0011]< GET_CURRENT_CALLS D/AT ( 355): AT< +COPS: 0,0,"CC 405 NC 034" D/AT ( 355): AT< ERROR E/RIL ( 355): requestOperator must not return error when radio is on D/RILJ ( 166): [0012]< OPERATOR error: com.android.internal.telephony.CommandException: GENERIC_FAILURE D/RIL ( 355): onRequest: GPRS_REGISTRATION_STATE D/AT ( 355): AT> AT+CGREG? D/AT ( 355): AT< +CGREG: 0,1 D/AT ( 355): AT< OK D/RILJ ( 166): [0013]< GPRS_REGISTRATION_STATE {1, ffffffff, ffffffff} D/RIL ( 355): onRequest: REGISTRATION_STATE D/AT ( 355): AT> AT+CREG? D/AT ( 355): AT< +CREG: 0,1 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: QUERY_NETWORK_SELECTION_MODE D/AT ( 355): AT> AT+COPS? D/RILJ ( 166): [0014]< REGISTRATION_STATE {1, ffffffff, ffffffff} D/AT ( 355): AT< +COPS: 0,0,"CC 405 NC 034" D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SET_NETWORK_SELECTION_AUTOMATIC D/AT ( 355): AT> AT+COPS=0 D/RILJ ( 166): [0015]< QUERY_NETWORK_SELECTION_MODE {0} D/AT ( 355): AT< OK D/RIL ( 355): onRequest: OPERATOR D/AT ( 355): AT> AT+COPS=3,0;+COPS?;+COPS=3,1;+COPS?;+COPS=3,2;+COPS? D/AT ( 355): AT< +COPS: 0,0,"CC 405 NC 034" D/AT ( 355): AT< ERROR E/RIL ( 355): requestOperator must not return error when radio is on D/RILJ ( 166): [0017]< OPERATOR error: com.android.internal.telephony.CommandException: GENERIC_FAILURE D/RIL ( 355): onRequest: GPRS_REGISTRATION_STATE D/AT ( 355): AT> AT+CGREG? E/GSM ( 166): RIL implementation has returned an error where it must succeedcom.android.internal.telephony.CommandException: GENERIC_FAILURE D/AT ( 355): AT< +CGREG: 0,1 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: REGISTRATION_STATE D/AT ( 355): AT> AT+CREG? D/RILJ ( 166): [0018]< GPRS_REGISTRATION_STATE {1, ffffffff, ffffffff} D/AT ( 355): AT< +CREG: 0,1 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: QUERY_NETWORK_SELECTION_MODE D/AT ( 355): AT> AT+COPS? D/RILJ ( 166): [0019]< REGISTRATION_STATE {1, ffffffff, ffffffff} W/GSM ( 166): error parsing RegistrationState: java.lang.NumberFormatException: unable to parse 'ffffffff' as integer D/AT ( 355): AT< +COPS: 0,0,"CC 405 NC 034" D/AT ( 355): AT< OK D/RIL ( 355): onRequest: GET_IMSI D/AT ( 355): AT> AT+CIMI D/RILJ ( 166): [0020]< QUERY_NETWORK_SELECTION_MODE {0} E/GSM ( 166): Wrong network type: 0 E/GSM ( 166): Wrong network type: 0 D/GSM ( 166): Poll ServiceState done: oldSS=[3 home null null null Unknown CSS not supported -1 -1RoamInd: -1DefRoamInd: -1EmergOnly: false] newSS=[0 home null null null Unknown CSS not supported -1 -1RoamInd: -1DefRoamInd: -1EmergOnly: false] oldGprs=1 newGprs=0 oldType=unknown newType=unknown D/AT ( 355): AT< 405034023658143 D/AT ( 355): AT< OK D/RILJ ( 166): [0021]< GET_IMSI D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,12258,0,0,15 D/GSM ( 166): IMSI: 405034xxxxxxx D/GSM ( 166): [IccCard] Broadcasting intent ACTION_SIM_STATE_CHANGED IMSI reason null D/GSM ( 166): GSMDataConnTrack handleMessage { what=26 when=-4ms obj=android.os.AsyncResult@40581f88 } D/GSM ( 166): [GsmDataConnectionTracker] ***trySetupData due to gprsAttached D/GSM ( 166): trySetupData called D/GSM ( 166): [DSAC DEB] trySetupData with mIsPsRestricted=false D/GSM ( 166): [trySetupData] : going into state check D/GSM ( 166): trySetupData Statecheck failed D/GSM ( 166): [GsmDataConnectionTracker] trySetupData: Not ready for data: dataState=IDLE gprsState=0 sim=false UMTS=false phoneState=IDLE isDataAllowed=true dataEnabled=true roaming=false dataOnRoamingEnable=false ps restricted=false desiredPowerState=true MasterDataEnabled=true D/AT ( 355): AT< +CRSM: 144,0,0000000A2FE204000BFFBB01020000 D/AT ( 355): AT< OK D/RILJ ( 166): < iccIO: 0x90 0x0 0000000A2FE204000BFFBB01020000 D/RILJ ( 166): [0022]< SIM_IO IccIoResponse sw1:0x90 sw2:0x0 D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28480,0,0,15 D/RILJ ( 166): [0038]> iccIO: SIM_IO 0xb0 0x2fe2 path: 3F00,0,0,10 E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/AT ( 355): AT< +CRSM: 144,0,0000000E6F40040011FFBB0102010E D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28617,0,0,15 D/RILJ ( 166): < iccIO: 0x90 0x0 0000000E6F40040011FFBB0102010E D/RILJ ( 166): [0023]< SIM_IO IccIoResponse sw1:0x90 sw2:0x0 D/RILJ ( 166): [0039]> iccIO: SIM_IO 0xb2 0x6f40 path: 3F007F10,1,4,14 E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/AT ( 355): AT< +CRSM: 111,0 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28589,0,0,15 D/RILJ ( 166): < iccIO: 0x6f 0x0 null D/RILJ ( 166): [0024]< SIM_IO IccIoResponse sw1:0x6f sw2:0x0 D/RILJ ( 166): [0040]> iccIO: SIM_IO 0xc0 0x6f17 path: 3F007F20,0,0,15 E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/AT ( 355): AT< +CRSM: 144,0,000000036FAD04000BFFBB21020000 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28618,0,0,15 D/RILJ ( 166): < iccIO: 0x90 0x0 000000036FAD04000BFFBB21020000 D/RILJ ( 166): [0025]< SIM_IO IccIoResponse sw1:0x90 sw2:0x0 D/RILJ ( 166): [0041]> iccIO: SIM_IO 0xb0 0x6fad path: 3F007F20,0,0,3 E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/AT ( 355): AT< +CRSM: 111,0 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28433,0,0,15 D/RILJ ( 166): < iccIO: 0x6f 0x0 null D/RILJ ( 166): [0026]< SIM_IO IccIoResponse sw1:0x6f sw2:0x0 D/AT ( 355): AT< +CRSM: 111,0 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28619,0,0,15 D/RILJ ( 166): < iccIO: 0x6f 0x0 null D/RILJ ( 166): [0027]< SIM_IO IccIoResponse sw1:0x6f sw2:0x0 D/AT ( 355): AT< +CRSM: 111,0 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28435,0,0,15 D/RILJ ( 166): < iccIO: 0x6f 0x0 null D/RILJ ( 166): [0028]< SIM_IO IccIoResponse sw1:0x6f sw2:0x0 D/AT ( 355): AT< +CRSM: 111,0 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28486,0,0,15 D/RILJ ( 166): < iccIO: 0x6f 0x0 null D/RILJ ( 166): [0029]< SIM_IO IccIoResponse sw1:0x6f sw2:0x0 D/AT ( 355): AT< +CRSM: 144,0,000000116F4604000BFFBB21020000 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28621,0,0,15 D/RILJ ( 166): < iccIO: 0x90 0x0 000000116F4604000BFFBB21020000 D/RILJ ( 166): [0030]< SIM_IO IccIoResponse sw1:0x90 sw2:0x0 D/RILJ ( 166): [0042]> iccIO: SIM_IO 0xb0 0x6f46 path: 3F007F20,0,0,17 E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/AT ( 355): AT< +CRSM: 111,0 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28613,0,0,15 D/RILJ ( 166): < iccIO: 0x6f 0x0 null D/RILJ ( 166): [0031]< SIM_IO IccIoResponse sw1:0x6f sw2:0x0 D/AT ( 355): AT< +CRSM: 144,0,000000346FC504000BFFBB2102011A D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28472,0,0,15 D/RILJ ( 166): < iccIO: 0x90 0x0 000000346FC504000BFFBB2102011A D/RILJ ( 166): [0032]< SIM_IO IccIoResponse sw1:0x90 sw2:0x0 D/RILJ ( 166): [0043]> iccIO: SIM_IO 0xb2 0x6fc5 path: 3F007F20,1,4,26 E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/AT ( 355): AT< +CRSM: 144,0,0000000F6F3804001BFFBB21020000 D/AT ( 355): AT< OK D/RILJ ( 166): < iccIO: 0x90 0x0 0000000F6F3804001BFFBB21020000 D/RILJ ( 166): [0033]< SIM_IO IccIoResponse sw1:0x90 sw2:0x0 D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28438,0,0,15 D/RILJ ( 166): [0044]> iccIO: SIM_IO 0xb0 0x6f38 path: 3F007F20,0,0,15 E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/AT ( 355): AT< +CRSM: 111,0 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: GET_SIM_STATUS D/AT ( 355): AT> AT+CPIN? D/RILJ ( 166): < iccIO: 0x6f 0x0 null D/RILJ ( 166): [0034]< SIM_IO IccIoResponse sw1:0x6f sw2:0x0 D/AT ( 355): AT< +CPIN: READY D/AT ( 355): AT< OK D/RIL ( 355): onRequest: QUERY_FACILITY_LOCK D/RIL ( 355): onRequest: QUERY_FACILITY_LOCK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=176,12258,0,0,10 D/RILJ ( 166): [0035]< GET_SIM_STATUS com.android.internal.telephony.IccCardStatus@4058e640 D/RILJ ( 166): [0036]< QUERY_FACILITY_LOCK error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED D/RILJ ( 166): [0037]< QUERY_FACILITY_LOCK error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED D/GSM ( 166): [IccCard] Error in querying facility lock:com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED D/GSM ( 166): [IccCard] Error in querying facility lock:com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED D/AT ( 355): AT< +CRSM: 144,0,98193014112063854143 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=178,28480,1,4,14 D/RILJ ( 166): < iccIO: 0x90 0x0 98193014112063854143 D/RILJ ( 166): [0038]< SIM_IO IccIoResponse sw1:0x90 sw2:0x0 D/GSM ( 166): iccid: 89910341110236581434 D/AT ( 355): AT< +CRSM: 144,0,FFFFFFFFFFFFFFFFFFFFFFFFFFFF D/AT ( 355): AT< OK D/RILJ ( 166): < iccIO: 0x90 0x0 FFFFFFFFFFFFFFFFFFFFFFFFFFFF D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=192,28439,0,0,15 D/RILJ ( 166): [0039]< SIM_IO IccIoResponse sw1:0x90 sw2:0x0 D/GSM ( 166): MSISDN: xxxxxxx D/AT ( 355): AT< +CRSM: 111,0 D/AT ( 355): AT< OK D/RILJ ( 166): < iccIO: 0x6f 0x0 null D/RILJ ( 166): [0040]< SIM_IO IccIoResponse sw1:0x6f sw2:0x0 D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=176,28589,0,0,3 D/GSM ( 166): Invalid or missing EF[MAILBOX] D/AT ( 355): AT< +CRSM: 144,0,000000 D/AT ( 355): AT< OK D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=176,28486,0,0,17 D/RILJ ( 166): < iccIO: 0x90 0x0 000000 D/RILJ ( 166): [0041]< SIM_IO IccIoResponse sw1:0x90 sw2:0x0 D/GSM ( 166): EF_AD: 000000 D/GSM ( 166): SIMRecords: MNC length not present in EF_AD D/AT ( 355): AT< +CRSM: 144,0,005441544120444F434F4D4FFFFFFFFFFF D/AT ( 355): AT< OK D/RILJ ( 166): < iccIO: 0x90 0x0 005441544120444F434F4D4FFFFFFFFFFF D/RILJ ( 166): [0042]< SIM_IO IccIoResponse sw1:0x90 sw2:0x0 D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=178,28613,1,4,26 D/GSM ( 166): [SIMRecords] Load EF_SPN: TATA DOCOMO spnDisplayCondition: 0 D/AT ( 355): AT< +CRSM: 144,0,430B83D4203508223E87CFE613450B83D4203508223E87CFE613 D/AT ( 355): AT< OK D/RILJ ( 166): < iccIO: 0x90 0x0 430B83D4203508223E87CFE613450B83D4203508223E87CFE613 D/RILJ ( 166): [0043]< SIM_IO IccIoResponse sw1:0x90 sw2:0x0 D/RIL ( 355): onRequest: SIM_IO D/AT ( 355): AT> AT+CRSM=176,28472,0,0,15 D/AT ( 355): AT< +CRSM: 144,0,FF3FCFFF03003F0F300C0000F00000 D/AT ( 355): AT< OK D/RILJ ( 166): < iccIO: 0x90 0x0 FF3FCFFF03003F0F300C0000F00000 D/RILJ ( 166): [0044]< SIM_IO IccIoResponse sw1:0x90 sw2:0x0 D/GSM ( 166): SIMRecords: record load complete D/GSM ( 166): [IccCard] Broadcasting intent ACTION_SIM_STATE_CHANGED LOADED reason null D/GSM ( 166): GSMDataConnTrack handleMessage { what=4 when=-5ms obj=android.os.AsyncResult@40599a60 } D/GSM ( 166): Get PreferredAPN D/GSM ( 166): GSMDataConnTrack handleMessage { what=5 when=-349ms obj=simLoaded } D/GSM ( 166): [GsmDataConnectionTracker] ***trySetupData due to simLoaded D/GSM ( 166): trySetupData called D/GSM ( 166): [DSAC DEB] trySetupData with mIsPsRestricted=false D/GSM ( 166): [trySetupData] : going into state check D/GSM ( 166): State Check for trySetupData Succeeded D/GSM ( 166): [trySetupData] : checking for IDLE state D/GSM ( 166): [trySetupData] : IDLE state check OK I/GSM ( 166): Preferred APN:405034:405034:[ApnSettingV2] TA TA DoCoMo, 674, 405034, TATA.DOCOMO.INTERNET, , , , , , -1, default, IP, IP I/GSM ( 166): Waiting APN set to preferred APN D/GSM ( 166): APN list Ok D/GSM ( 166): [GsmDataConnectionTracker] Create from allApns : [[ApnSettingV2] TA TA DoCoMo, 674, 405034, TATA.DOCOMO.INTERNET, , , , , , -1, default, IP, IP][[ApnSettingV2] TA TA DoCoMo MMS, 675, 405034, TATA.DOCOMO.MMS, , http://mmsc/, 10.124.26.94, 8799, , -1, mms, IP, IP] D/GSM ( 166): [GsmDataConnectionTracker] Setup waitngApns : [[ApnSettingV2] TA TA DoCoMo, 674, 405034, TATA.DOCOMO.INTERNET, , , , , , -1, default, IP, IP] D/GSM ( 166): Process Success. Calling setupData D/GSM ( 166): SetupData Called D/GSM ( 166): Ready to make rild call D/GSM ( 166): rild reuqest sending D/GSM ( 166): [GsmDataConnection-1] DcInactiveState msg.what=EVENT_CONNECT D/GSM ( 166): [GsmDataConnection-1] Connecting to carrier: 'TA TA DoCoMo' APN: 'TATA.DOCOMO.INTERNET' proxy: '' port: ' D/RILJ ( 166): [0045]> SETUP_DATA_CALL 1 0 TATA.DOCOMO.INTERNET 3 IP D/GSM ( 166): rild reuqest SENT D/GSM ( 166): [GsmDataConnectionTracker] setState: INITING D/GSM ( 166): setupData updated current state E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RIL ( 355): onRequest: SETUP_DATA_CALL D/RIL ( 355): requesting data connection to APN 'TATA.DOCOMO.INTERNET' D/AT ( 355): AT> AT+CGREG=1 D/GSM ( 166): Current status notified D/AT ( 355): AT< OK D/AT ( 355): AT> AT^SGAUTH=0 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CGACT=1 D/AT ( 355): AT< ERROR D/AT ( 355): AT> AT+CGDCONT=1,"IP","TATA.DOCOMO.INTERNET",,0,0 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CGQREQ=1 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CGQMIN=1 D/AT ( 355): AT< OK D/AT ( 355): AT> AT+CGEREP=1,0 D/AT ( 355): AT< ERROR D/AT ( 355): AT> ATD*99***1# D/AT ( 355): AT< CONNECT D/RIL ( 355): Activating pppd_gprs service D/RILJ ( 166): [0045]< SETUP_DATA_CALL {1, ppp0} D/GSM ( 166): [GsmDataConnection-1] DcActivatingState msg.what=EVENT_SETUP_DATA_CONNECTION_DONE D/GSM ( 166): [GsmDataConnection-1] DataConnection setup result='SUCCESS' on cid=1 D/GSM ( 166): [GsmDataConnection-1] DcInactiveState: setEnterNoticationParams cp,cause D/GSM ( 166): [GsmDataConnection-1] DcActiveState: enter notifyConnectCompleted D/GSM ( 166): [GsmDataConnection-1] notifyConnection at 946684853773 cause=No Error D/GSM ( 166): GSMDataConnTrack handleMessage { what=1 when=-1ms arg1=1 obj=android.os.AsyncResult@40518110 } D/GSM ( 166): [GsmDataConnectionTracker] setState: CONNECTED D/GSM ( 166): [DataConnection] Start poll NetStat D/RILJ ( 166): [0046]> SIGNAL_STRENGTH E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RIL ( 355): onRequest: SIGNAL_STRENGTH D/AT ( 355): AT> AT+CSQ E/RIL ( 355): requestSignalStrength must never return an error when radio is on E/RILC ( 355): RIL_onRequestComplete: invalid RIL_Token D/RILJ ( 166): [0046]< SIGNAL_STRENGTH error: com.android.internal.telephony.CommandException: GENERIC_FAILURE D/RILJ ( 166): [0047]> SIGNAL_STRENGTH E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RIL ( 355): onRequest: SIGNAL_STRENGTH D/AT ( 355): AT> AT+CSQ E/RIL ( 355): requestSignalStrength must never return an error when radio is on E/RILC ( 355): RIL_onRequestComplete: invalid RIL_Token D/RILJ ( 166): [0047]< SIGNAL_STRENGTH error: com.android.internal.telephony.CommandException: GENERIC_FAILURE D/RILJ ( 166): [0048]> SIGNAL_STRENGTH E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RIL ( 355): onRequest: SIGNAL_STRENGTH D/AT ( 355): AT> AT+CSQ D/RILJ ( 166): [0048]< SIGNAL_STRENGTH error: com.android.internal.telephony.CommandException: GENERIC_FAILURE E/RIL ( 355): requestSignalStrength must never return an error when radio is on E/RILC ( 355): RIL_onRequestComplete: invalid RIL_Token D/RILJ ( 166): [0049]> SIGNAL_STRENGTH E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RIL ( 355): onRequest: SIGNAL_STRENGTH D/AT ( 355): AT> AT+CSQ E/RIL ( 355): requestSignalStrength must never return an error when radio is on E/RILC ( 355): RIL_onRequestComplete: invalid RIL_Token D/RILJ ( 166): [0049]< SIGNAL_STRENGTH error: com.android.internal.telephony.CommandException: GENERIC_FAILURE D/RILJ ( 166): [0050]> SIGNAL_STRENGTH E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RIL ( 355): onRequest: SIGNAL_STRENGTH D/AT ( 355): AT> AT+CSQ E/RIL ( 355): requestSignalStrength must never return an error when radio is on E/RILC ( 355): RIL_onRequestComplete: invalid RIL_Token D/RILJ ( 166): [0050]< SIGNAL_STRENGTH error: com.android.internal.telephony.CommandException: GENERIC_FAILURE D/RILJ ( 166): [0051]> SIGNAL_STRENGTH E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RIL ( 355): onRequest: SIGNAL_STRENGTH D/AT ( 355): AT> AT+CSQ E/RIL ( 355): requestSignalStrength must never return an error when radio is on E/RILC ( 355): RIL_onRequestComplete: invalid RIL_Token D/RILJ ( 166): [0051]< SIGNAL_STRENGTH error: com.android.internal.telephony.CommandException: GENERIC_FAILURE D/RILJ ( 166): [0052]> REQUEST_GET_PREFERRED_NETWORK_TYPE E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RIL ( 355): onRequest: GET_PREFERRED_NETWORK_TYPE D/RILJ ( 166): [0052]< REQUEST_GET_PREFERRED_NETWORK_TYPE error: com.android.internal.telephony.CommandException: REQUEST_NOT_SUPPORTED D/GSM ( 166): GSMDataConnTrack handleMessage { what=23 when=-1ms } D/GSM ( 166): [GsmDataConnectionTracker] Clean up connection due to dataDisabled D/GSM ( 166): [GsmDataConnectionTracker] setState: DISCONNECTING D/GSM ( 166): [GsmDataConnectionTracker] cleanUpConnection: teardown, call conn.disconnect D/GSM ( 166): [GsmDataConnection-1] DcActiveState msg.what=EVENT_DISCONNECT D/GSM ( 166): [GsmDataConnection-1] tearDownData radio is on, call deactivateDataCall D/RILJ ( 166): [0053]> DEACTIVATE_DATA_CALL 1 D/GSM ( 166): [DataConnection] Stop poll NetStat E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RIL ( 355): onRequest: DEACTIVATE_DATA_CALL D/RIL ( 355): requestDeactivateDataCall() D/RIL ( 355): killConn D/RIL ( 355): killall pppd finished D/RIL ( 355): killConn if already dataconn D/AT ( 355): AT> +++ D/RILJ ( 166): [0053]< DEACTIVATE_DATA_CALL D/GSM ( 166): [GsmDataConnection-1] DcDisconnectingState msg.what=EVENT_DEACTIVATE_DONE D/GSM ( 166): [GsmDataConnection-1] DcInactiveState: setEnterNoticationParams dp D/GSM ( 166): [GsmDataConnection-1] DcInactiveState: enter notifyDisconnectCompleted D/GSM ( 166): [GsmDataConnection-1] NotifyDisconnectCompleted D/GSM ( 166): [GsmDataConnection-1] msg.what=25 msg.obj=dataDisabled D/GSM ( 166): [GsmDataConnection-1] clearSettings D/GSM ( 166): GSMDataConnTrack handleMessage { what=25 when=0 obj=android.os.AsyncResult@40529a18 } D/GSM ( 166): [GsmDataConnectionTracker] EVENT_DISCONNECT_DONE D/GSM ( 166): [GsmDataConnectionTracker] setState: IDLE D/GSM ( 166): [GsmDataConnectionTracker] ***trySetupData due to dataDisabled D/GSM ( 166): trySetupData called D/GSM ( 166): [DSAC DEB] trySetupData with mIsPsRestricted=false D/GSM ( 166): [trySetupData] : going into state check D/GSM ( 166): trySetupData Statecheck failed D/GSM ( 166): [GsmDataConnectionTracker] trySetupData: Not ready for data: dataState=IDLE gprsState=0 sim=true UMTS=false phoneState=IDLE isDataAllowed=false dataEnabled=false roaming=false dataOnRoamingEnable=false ps restricted=false desiredPowerState=true MasterDataEnabled=true D/RILJ ( 166): [0054]> SIGNAL_STRENGTH E/RILJ ( 166): ERROR: mReqPending is NOT 0 but1 at TIMEOUT, reset! D/RIL ( 355): onRequest: SIGNAL_STRENGTH D/AT ( 355): AT> AT+CSQ D/RILJ ( 166): [0054]< SIGNAL_STRENGTH error: com.android.internal.telephony.CommandException: GENERIC_FAILURE E/RIL ( 355): requestSignalStrength must never return an error when radio is on E/RILC ( 355): RIL_onRequestComplete: invalid RIL_Token ^C # ps USER PID PPID VSIZE RSS WCHAN PC NAME root 1 0 268 184 c011afc8 0000875c S /init root 2 0 0 0 c00a5dfc 00000000 S kthreadd root 3 2 0 0 c0091278 00000000 S ksoftirqd/0 root 4 2 0 0 c00c18f8 00000000 S watchdog/0 root 5 2 0 0 c00a23e0 00000000 S events/0 root 6 2 0 0 c00a23e0 00000000 S khelper root 7 2 0 0 c00acf50 00000000 S async/mgr root 8 2 0 0 c00a23e0 00000000 S pm root 9 2 0 0 c00f17c8 00000000 S sync_supers root 10 2 0 0 c00f2224 00000000 S bdi-default root 11 2 0 0 c00a23e0 00000000 S kblockd/0 root 12 2 0 0 c00a23e0 00000000 S omap2_mcspi root 13 2 0 0 c00a23e0 00000000 S ksuspend_usbd root 14 2 0 0 c034b020 00000000 S khubd root 15 2 0 0 c037e79c 00000000 S kseriod root 16 2 0 0 c00a23e0 00000000 S twl4030-irqchip root 17 2 0 0 c02e5f00 00000000 S twl4030-irq root 18 2 0 0 c00a23e0 00000000 S kmmcd root 19 2 0 0 c00a23e0 00000000 S cfg80211 root 20 2 0 0 c00a23e0 00000000 S musb_hdrc root 21 2 0 0 c00a23e0 00000000 S rpciod/0 root 22 2 0 0 c00c1b24 00000000 S khungtaskd root 23 2 0 0 c00e9c30 00000000 S kswapd0 root 24 2 0 0 c00a23e0 00000000 S aio/0 root 25 2 0 0 c00a23e0 00000000 S nfsiod root 26 2 0 0 c00a23e0 00000000 S crypto/0 root 38 2 0 0 c0302f18 00000000 S mtdblockd root 47 2 0 0 c00a23e0 00000000 S kondemand/0 root 48 2 0 0 c00a23e0 00000000 S kconservative/0 root 49 2 0 0 c00a23e0 00000000 S usbhid_resumer root 50 2 0 0 c03e430c 00000000 S binder_deferred root 53 2 0 0 c00a23e0 00000000 S krxrpcd/0 root 54 2 0 0 c03ccb90 00000000 S mmcqd root 55 2 0 0 c012aedc 00000000 S flush-179:0 root 56 2 0 0 c01bac84 00000000 S kjournald root 57 1 248 156 c011afc8 0000875c S /sbin/ueventd root 62 1 692 324 c008e038 afd0c3ac S /system/bin/sh system 63 1 764 248 c03e363c afd0b6fc S /system/bin/servicemanager root 64 1 3820 580 ffffffff afd0bdac S /system/bin/vold root 65 1 3792 552 ffffffff afd0bdac S /system/bin/netd root 66 1 624 252 c04318fc afd0c0cc S /system/bin/debuggerd root 68 1 61376 26600 c011afc8 afd0b844 S zygote media 69 1 25800 4448 ffffffff afd0b6fc S /system/bin/mediaserver bluetooth 70 1 1212 560 c011afc8 afd0c59c S /system/bin/dbus-daemon root 71 1 772 304 c04997f4 afd0b45c S /system/bin/installd keystore 72 1 1700 420 c04318fc afd0c0cc S /system/bin/keystore root 73 1 3352 156 ffffffff 00008294 S /sbin/adbd system 88 68 129680 33220 ffffffff afd0b6fc S system_server system 148 68 74344 20884 ffffffff afd0c51c S com.android.systemui app_7 157 68 78344 19284 ffffffff afd0c51c S com.android.inputmethod.latin radio 166 68 86616 23808 ffffffff afd0c51c S com.android.phone app_16 168 68 76848 22452 ffffffff afd0c51c S com.android.launcher app_15 188 68 75456 19100 ffffffff afd0c51c S android.process.acore app_4 232 68 74404 18092 ffffffff afd0c51c S android.process.media app_11 244 68 72032 17068 ffffffff afd0c51c S com.android.providers.calendar app_14 257 68 71440 16828 ffffffff afd0c51c S com.android.bluetooth app_20 265 68 72164 16672 ffffffff afd0c51c S com.android.deskclock app_24 279 68 85572 17400 ffffffff afd0c51c S com.android.mms app_27 297 68 73860 18088 ffffffff afd0c51c S com.android.email app_1 309 68 71408 15768 ffffffff afd0c51c S com.android.music app_10 322 68 71856 16488 ffffffff afd0c51c S com.android.quicksearchbox app_13 332 68 70824 15516 ffffffff afd0c51c S com.android.protips app_3 341 68 72468 16932 ffffffff afd0c51c S com.cooliris.media root 355 1 5352 684 ffffffff afd0bdac S /system/bin/rild system 395 68 77220 20924 ffffffff afd0c51c S com.android.settings root 411 62 852 304 00000000 afd0b45c R ps #