Error on mediaplayback #3
Description
User reported the following issue with playback on their phone. They are
running MIUI.
HTC EVO 4g (Sprint) Latest MIUI.
Log Collector version: 1.1.0
Device model: PC36100
Firmware version: 2.3.7
Kernel version: 2.6.37.6-cyanogenmod-01493-g70877de
shade@toxygene )
#1 PREEMPT Sat Apr 30 04:36:42 EDT 2011
Build number: 1.12.9.0
--------- beginning of /dev/log/main
12-25 23:55:39.685 I/ActivityManager( 136): Starting: Intent {
act=android.intent.action.MAIN flg=0x10100000
cmp=net.mandaria.radioredditpro/net.mandaria.radioreddit.activities.RadioReddit
} from pid 14484
12-25 23:55:39.725 W/ActivityManager( 136): Duplicate finish request for
HistoryRecord{40a184f8 com.android.sidekick/.SideKickActivity}
12-25 23:55:39.846 I/ActivityManager( 136): Start proc
net.mandaria.radioredditpro for activity
net.mandaria.radioredditpro/net.mandaria.radioreddit.activities.RadioReddit:
pid=14507 uid=10103 gids={3003}
12-25 23:55:40.026 I/dalvikvm(14507): Could not find method
net.mandaria.radioreddit.activities.RadioReddit.getActionBar, referenced from
method net.mandaria.radioreddit.activities.RadioReddit.onPrepareOptionsMenu
12-25 23:55:40.026 W/dalvikvm(14507): VFY: unable to resolve virtual method
946: Lnet/mandaria/radioreddit/activities/RadioReddit;.getActionBar
()Landroid/app/ActionBar;
12-25 23:55:40.026 D/dalvikvm(14507): VFY: replacing opcode 0x6e at 0x0036
12-25 23:55:40.026 D/dalvikvm(14507): VFY: dead code 0x0039-0062 in
Lnet/mandaria/radioreddit/activities/RadioReddit;.onPrepareOptionsMenu
(Landroid/view/Menu;)Z
12-25 23:55:40.026 I/dalvikvm(14507): Could not find method
net.mandaria.radioreddit.activities.RadioReddit.invalidateOptionsMenu,
referenced from method net.mandaria.radioreddit.activities.RadioReddit$1.run
12-25 23:55:40.026 W/dalvikvm(14507): VFY: unable to resolve virtual method
955: Lnet/mandaria/radioreddit/activities/RadioReddit;.invalidateOptionsMenu ()V
12-25 23:55:40.026 D/dalvikvm(14507): VFY: replacing opcode 0x6e at 0x00c3
12-25 23:55:40.076 D/dalvikvm(14507): GC_EXTERNAL_ALLOC freed 64K, 50% free
2719K/5379K, external 0K/0K, paused 40ms
12-25 23:55:40.146 D/dalvikvm(14507): GC_EXTERNAL_ALLOC freed 8K, 50% free
2728K/5379K, external 1K/513K, paused 42ms
12-25 23:55:40.266 D/dalvikvm(14507): GC_EXTERNAL_ALLOC freed 2K, 50% free
2733K/5379K, external 1506K/2018K, paused 40ms
12-25 23:55:40.346 D/dalvikvm(14507): GC_EXTERNAL_ALLOC freed <1K, 50% free
2735K/5379K, external 2208K/3199K, paused 29ms
12-25 23:55:40.396 D/FlurryAgent(14507): startSession called
12-25 23:55:40.396 D/FlurryAgent(14507): Initializing Flurry session
12-25 23:55:40.396 D/FlurryAgent(14507): Starting new session
12-25 23:55:40.396 W/Settings(14507): Setting android_id has moved from
android.provider.Settings.System to android.provider.Settings.Secure, returning
read-only value.
12-25 23:55:40.416 D/FlurryAgent(14507): Sending report to:
http://data.flurry.com/aap.do
12-25 23:55:40.436 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Playback service created
12-25 23:55:40.446 D/FlurryAgent(14507): startSession called
12-25 23:55:40.456 D/net.mandaria.radioreddit.media.PlaybackService(14507):
Bound PlaybackService, count 1
12-25 23:55:40.516 W/IInputConnectionWrapper(14484): showStatusIcon on inactive
InputConnection
12-25 23:55:40.516 W/InputManagerService( 136): Starting input on non-focused
client com.android.internal.view.IInputMethodClient$Stub$Proxy@40ac64c0
(uid=1000 pid=14484)
12-25 23:55:40.566 I/ActivityManager( 136): Displayed
net.mandaria.radioredditpro/net.mandaria.radioreddit.activities.RadioReddit:
+741ms
12-25 23:55:41.567 D/dalvikvm(14492): GC_CONCURRENT freed 150K, 47% free
3030K/5639K, external 826K/1061K, paused 11ms+3ms
12-25 23:55:41.617 D/FlurryAgent(14507): Report successful
12-25 23:55:41.617 D/FlurryAgent(14507): Processing report response
12-25 23:55:41.617 D/FlurryAgent(14507): Done sending initial agent report
12-25 23:55:41.787 D/dalvikvm(14507): GC_CONCURRENT freed 108K, 47% free
3037K/5639K, external 3086K/3562K, paused 7ms+3ms
12-25 23:55:41.848 D/MeTask (14492): Content length: 200
12-25 23:55:41.908 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Playback service - listen() start
12-25 23:55:41.908 D/net.mandaria.radioreddit.media.PlaybackService(14507):
listening to http://texas.radioreddit.com:8000 stream=true
12-25 23:55:41.908 D/net.mandaria.radioreddit.media.PlaybackService(14507): SDK
Version 10
12-25 23:55:41.908 D/net.mandaria.radioreddit.media.PlaybackService(14507):
reset: http://texas.radioreddit.com:8000
12-25 23:55:41.908 I/StagefrightPlayer( 101):
setDataSource('http://texas.radioreddit.com:8000')
12-25 23:55:41.908 D/net.mandaria.radioreddit.media.PlaybackService(14507):
Preparing: http://texas.radioreddit.com:8000
12-25 23:55:41.908 D/net.mandaria.radioreddit.media.PlaybackService(14507):
Waiting for prepare
12-25 23:55:41.908 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Playback service - listen() stop
12-25 23:55:41.918 I/NuHTTPDataSource( 101): connect to
texas.radioreddit.com:8000/ @0
12-25 23:55:41.978 W/NuHTTPDataSource( 101): Server did not give us the
content length!
12-25 23:55:42.008 E/HTTPStream( 101): recv failed, server is gone, total
received: 4746 bytes
12-25 23:55:42.008 E/HTTPStream( 101): recv failed, errno = -1 (Operation not
permitted)
12-25 23:55:42.008 E/NuCachedSource2( 101): source returned error -1004
12-25 23:55:42.178 E/MediaPlayer(14507): error (1, -2147483648)
12-25 23:55:42.178 E/MediaPlayer(14507): Error (1,-2147483648)
12-25 23:55:42.178 W/net.mandaria.radioreddit.media.PlaybackService(14507):
onError(1, -2147483648)
12-25 23:55:42.178 W/net.mandaria.radioreddit.media.PlaybackService(14507):
MediaPlayer refused to play current item. Bailing on prepare.
12-25 23:55:42.198 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Playback service - onCompletion() start
12-25 23:55:42.198 W/net.mandaria.radioreddit.media.PlaybackService(14507):
MediaPlayer refused to play current item. Bailing on prepare.
12-25 23:55:42.198 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Playback service - cleanup() start
12-25 23:55:42.198 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Playback service - cleanup() stop
12-25 23:55:42.198 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Playback service - onCompletion() stop
12-25 23:55:42.318 D/DownloadThreadsTask(14492): Content length [sent]: 6992
12-25 23:55:42.388 D/dalvikvm(14492): GC_CONCURRENT freed 372K, 48% free
3100K/5959K, external 659K/1061K, paused 23ms+5ms
12-25 23:55:42.748 D/dalvikvm(14492): GC_CONCURRENT freed 387K, 48% free
3134K/6023K, external 659K/1061K, paused 3ms+4ms
12-25 23:55:42.838 D/dalvikvm(14507): GC_CONCURRENT freed 1158K, 56% free
2950K/6599K, external 3237K/3562K, paused 2ms+3ms
12-25 23:55:43.039 D/dalvikvm(14492): GC_CONCURRENT freed 440K, 49% free
3163K/6087K, external 659K/1061K, paused 2ms+7ms
12-25 23:55:43.219 D/dalvikvm(14492): GC_CONCURRENT freed 471K, 49% free
3125K/6087K, external 659K/1061K, paused 3ms+3ms
12-25 23:55:43.299 W/KeyCharacterMap(14507): No keyboard for id 65537
12-25 23:55:43.299 W/KeyCharacterMap(14507): Using default keymap:
/system/usr/keychars/qwerty.kcm.bin
12-25 23:55:43.399 W/HapticFeedbackUtil( 136): vibrate: null or empty pattern
12-25 23:55:43.519 W/IInputConnectionWrapper( 3667): showStatusIcon on inactive
InputConnection
12-25 23:55:43.559 D/dalvikvm(14492): GC_CONCURRENT freed 379K, 49% free
3149K/6087K, external 659K/1061K, paused 2ms+3ms
12-25 23:55:43.649 D/dalvikvm(14507): GC_CONCURRENT freed 1023K, 54% free
3043K/6599K, external 3185K/3562K, paused 2ms+2ms
12-25 23:55:43.859 D/net.mandaria.radioreddit.media.PlaybackService(14507):
Unbinding PlaybackService, count 0
12-25 23:55:43.859 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Will stop self
12-25 23:55:43.859 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Playback service - onDestroy() start
12-25 23:55:43.859 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Service exiting
12-25 23:55:43.859 I/net.mandaria.radioreddit.media.PlaybackService(14507):
Begin stopUpdateTimer()
12-25 23:55:43.859 I/net.mandaria.radioreddit.media.PlaybackService(14507):
Stop stopUpdateTimer()
12-25 23:55:43.859 D/net.mandaria.radioreddit.media.PlaybackService(14507): stop
12-25 23:55:43.859 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Playback service - cleanup() start
12-25 23:55:43.859 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Playback service - cleanup() stop
12-25 23:55:43.869 W/net.mandaria.radioreddit.media.PlaybackService(14507):
Playback service - onDestroy() stop
12-25 23:55:44.150 D/dalvikvm(14492): GC_CONCURRENT freed 377K, 48% free
3206K/6087K, external 659K/1061K, paused 27ms+5ms
12-25 23:55:44.820 I/ActivityManager( 136): Starting: Intent { flg=0x34200000
cmp=com.android.sidekick/.SideKickActivity } from pid 136
12-25 23:55:44.991 D/szipinf (14484): Initializing inflate state
12-25 23:55:45.031 D/dalvikvm(14484): GC_EXTERNAL_ALLOC freed 317K, 49% free
2979K/5767K, external 4733K/4735K, paused 31ms
12-25 23:55:45.101 D/dalvikvm(14484): GC_EXTERNAL_ALLOC freed 128K, 48% free
3002K/5767K, external 2949K/2980K, paused 31ms
12-25 23:55:45.121 D/szipinf (14484): Initializing inflate state
12-25 23:55:45.261 D/dalvikvm(14484): GC_EXTERNAL_ALLOC freed 108K, 49% free
2963K/5767K, external 2881K/3070K, paused 31ms
12-25 23:55:45.361 D/dalvikvm(14492): GC_CONCURRENT freed 408K, 48% free
3259K/6215K, external 692K/1061K, paused 2ms+3ms
12-25 23:55:45.721 W/HapticFeedbackUtil( 136): vibrate: null or empty pattern
12-25 23:55:45.731 I/ActivityManager( 136): Displayed
com.android.sidekick/.SideKickActivity: +906ms
12-25 23:55:46.963 I/ActivityManager( 136): Starting: Intent {
act=android.intent.action.MAIN flg=0x10100000
cmp=com.xtralogic.android.logcollector/.SendLogActivity } from pid 14484
12-25 23:55:46.973 W/ActivityManager( 136): Duplicate finish request for
HistoryRecord{40be6710 com.android.sidekick/.SideKickActivity}
12-25 23:55:47.043 I/ActivityManager( 136): Start proc
com.xtralogic.android.logcollector for activity
com.xtralogic.android.logcollector/.SendLogActivity: pid=14525 uid=10105
gids={1007, 1015}
12-25 23:55:47.233 D/dalvikvm(14525): GC_EXTERNAL_ALLOC freed 64K, 50% free
2713K/5379K, external 0K/0K, paused 44ms
12-25 23:55:47.803 I/ActivityManager( 136): Displayed
com.xtralogic.android.logcollector/.SendLogActivity: +758ms
12-25 23:55:47.813 I/ActivityManager( 136): No longer want
com.akproduction.notepad (pid 14249): hidden #16
12-25 23:55:48.954 D/dalvikvm(14525): GC_EXTERNAL_ALLOC freed 23K, 49% free
2757K/5379K, external 511K/521K, paused 45ms
12-25 23:55:49.325 D/dalvikvm(14525): GC_CONCURRENT freed 190K, 48% free
2954K/5639K, external 556K/1029K, paused 3ms+8ms
Original issue reported on code.google.com by mand...@gmail.com on 26 Dec 2011 at 6:49