09-19 14:42:07.085: I/InputReader(127): dispatchTouch::touch event's action is 0
09-19 14:42:07.085: I/InputDispatcher(127): Delivering touch to current input target: action: 0, channel '407c5fe8 com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.ui.phone.HomeActivity (server)'
09-19 14:42:07.273: I/InputReader(127): dispatchTouch::touch event's action is 1
09-19 14:42:07.273: I/InputDispatcher(127): Delivering touch to current input target: action: 1, channel '407c5fe8 com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.ui.phone.HomeActivity (server)'
09-19 14:42:08.128: I/InputReader(127): dispatchTouch::touch event's action is 0
09-19 14:42:08.132: I/InputDispatcher(127): Delivering touch to current input target: action: 0, channel '407c5fe8 com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.ui.phone.HomeActivity (server)'
09-19 14:42:08.285: I/InputReader(127): dispatchTouch::touch event's action is 1
09-19 14:42:08.285: I/InputDispatcher(127): Delivering touch to current input target: action: 1, channel '407c5fe8 com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.ui.phone.HomeActivity (server)'
09-19 14:42:08.308: I/ActivityManager(127): Starting: Intent { dat=content://com.ventismedia.android.mediamonkey.provider.MediaMonkeyStoreProvider/audio/media cmp=com.ventismedia.android.mediamonkey/.library.LibraryActivity (has extras) } from pid 15267
09-19 14:42:08.335: W/ActivityManager(127): Trying to launch com.ventismedia.android.mediamonkey/.library.LibraryActivity
09-19 14:42:08.343: E/ResourceType(15267): Style contains key with bad entry: 0x01010336
09-19 14:42:08.343: E/ResourceType(15267): Style contains key with bad entry: 0x01010339
09-19 14:42:08.343: I/SinglePaneActivity(15267): onCreate
09-19 14:42:08.343: W/LOG(15267): setContentView
09-19 14:42:08.355: I/MiniPlayerActivity(15267): onCreate
09-19 14:42:08.363: I/LibraryViewFragment(15267): onActivityCreated
09-19 14:42:08.363: D/VideoFragment(15267): VideoCursorAdapter
09-19 14:42:08.378: W/THREAD CONTROL(15267): Not queried by db thread!!! 25 (-1)
09-19 14:42:08.382: D/DB debug(15267): Query - media
09-19 14:42:08.386: D/QUERY(15267): select _id, title, _data, mime_type  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE type=5  ORDER BY title COLLATE LOCALIZED ASC
09-19 14:42:08.394: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:08.394: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.MediaMonkeyStoreTrack:50
09-19 14:42:08.394: W/TransactionManager(15267): New ThreadSerializer id from -1 to 26
09-19 14:42:08.398: D/DB debug(15267): Query - media - id
09-19 14:42:08.398: D/QUERY(15267): select _id, title, _data, album, album_id, artists, track, year, _ms_id, duration, bookmark, playcount, type, volume_leveling, album_art, rating, lyrics, mime_type  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE _id=? 
09-19 14:42:08.402: D/QUERY(15267): [50]
09-19 14:42:08.406: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:08.406: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.MediaMonkeyStoreTrack:50
09-19 14:42:08.410: D/DB debug(15267): Query - media - id
09-19 14:42:08.410: D/QUERY(15267): select _id, title, _data, album, album_id, artists, track, year, _ms_id, duration, bookmark, playcount, type, volume_leveling, album_art, rating, lyrics, mime_type  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE _id=? 
09-19 14:42:08.410: D/QUERY(15267): [50]
09-19 14:42:08.417: I/AudioService(127):   Remote Control   registerMediaButtonEventReceiver() for ComponentInfo{com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.player.MediaButtonIntentReceiver}
09-19 14:42:08.417: I/ContentService(15267): Service is not running
09-19 14:42:08.425: D/PlaybackService(15267): Intent command received
09-19 14:42:08.425: D/PlaybackService(15267): updateNotification app is : in foreground
09-19 14:42:08.425: I/PlaybackService(15267): hideNotification 
09-19 14:42:08.460: D/VideoFragment(15267): setHolderForBindView
09-19 14:42:08.476: D/VideoFragment(15267): setHolderForBindView
09-19 14:42:08.488: D/VideoFragment(15267): setHolderForBindView
09-19 14:42:08.503: D/VideoFragment(15267): setHolderForBindView
09-19 14:42:08.515: D/VideoFragment(15267): setHolderForBindView
09-19 14:42:08.562: D/VideoFragment(15267): Thumbnail exist
09-19 14:42:08.582: I/ActivityManager(127): Displayed com.ventismedia.android.mediamonkey/.library.LibraryActivity: +249ms
09-19 14:42:08.597: D/VideoFragment(15267): Thumbnail exist
09-19 14:42:08.605: D/VideoFragment(15267): Thumbnail exist
09-19 14:42:08.613: D/VideoFragment(15267): Thumbnail exist
09-19 14:42:08.621: D/VideoFragment(15267): Thumbnail exist
09-19 14:42:08.714: D/dalvikvm(15267): GC_EXTERNAL_ALLOC freed 780K, 48% free 3775K/7239K, external 3763K/3806K, paused 27ms
09-19 14:42:09.625: I/TransactionManager(15267): ThreadSerializer exit unlockMain 26
09-19 14:42:11.289: W/PowerManagerService(127): Timer 0x7->0x3|0x0
09-19 14:42:11.289: I/PowerManagerService(127): Ulight 7->3|0
09-19 14:42:11.289: D/LightsService(127): BUTTON : 0
09-19 14:42:11.789: I/InputReader(127): dispatchTouch::touch event's action is 0
09-19 14:42:11.789: I/InputDispatcher(127): Delivering touch to current input target: action: 0, channel '408c1328 com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.library.LibraryActivity (server)'
09-19 14:42:11.941: I/InputReader(127): dispatchTouch::touch event's action is 1
09-19 14:42:11.945: I/InputDispatcher(127): Delivering touch to current input target: action: 1, channel '408c1328 com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.library.LibraryActivity (server)'
09-19 14:42:12.132: W/TransactionManager(15267): New ThreadSerializer id from -1 to 27
09-19 14:42:12.148: I/ActivityManager(127): Starting: Intent { dat=file:///mnt/sdcard/tmp/video/VID_20120904_102712.m4v typ=video/mp4 cmp=com.ventismedia.android.mediamonkey/.ui.phone.NowVideoPlayingActivity } from pid 15267
09-19 14:42:12.156: W/ActivityManager(127): Trying to launch com.ventismedia.android.mediamonkey/.ui.phone.NowVideoPlayingActivity
09-19 14:42:12.160: I/PlaybackService(15267): onStartCommand - intent:com.ventismedia.android.mediamonkey.player.PlaybackService.PLAY_ACTION
09-19 14:42:12.214: D/PlaybackNotification(15267): ColorText is light!
09-19 14:42:12.214: I/PlaybackService(15267): Parsing intent
09-19 14:42:12.218: D/TrackListManager(15267): Set clear request true
09-19 14:42:12.218: D/TrackListManager(15267): Add addable
09-19 14:42:12.218: D/TrackListManager(15267): Thread starting...
09-19 14:42:12.218: D/TrackListManager(15267): Clear request
09-19 14:42:12.218: D/TrackListManager(15267): Set clear request false
09-19 14:42:12.218: D/TrackListModel(15267): Storing TrackList to preferences
09-19 14:42:12.234: D/TrackList(15267): addMedia(Uri)
09-19 14:42:12.234: D/TrackListManager(15267): Add immidiate addable
09-19 14:42:12.234: D/TrackListManager(15267): Add addable
09-19 14:42:12.234: D/TrackListManager(15267): Standard request
09-19 14:42:12.234: D/DB debug(15267): Query - media - id
09-19 14:42:12.238: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE _id=? 
09-19 14:42:12.238: D/QUERY(15267): [2]
09-19 14:42:12.281: I/TrackList(15267): setCurrentTrack
09-19 14:42:12.281: D/TrackListModel(15267): Storing current track preferences
09-19 14:42:12.281: I/PlaybackService(15267): play
09-19 14:42:12.281: I/AudioService(127):   Remote Control   registerMediaButtonEventReceiver() for ComponentInfo{com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.player.MediaButtonIntentReceiver}
09-19 14:42:12.281: I/AudioService(127):  AudioFocus  requestAudioFocus() from android.media.AudioManager@405c2dd0com.ventismedia.android.mediamonkey.player.PlaybackService@405a78b0
09-19 14:42:12.281: D/PlaybackService(15267): Waiting for play
09-19 14:42:12.281: D/PlaybackService(15267): prepareVideoToPlay
09-19 14:42:12.285: D/PlaybackService(15267): Video playback is not initialized
09-19 14:42:12.285: D/TrackListManager(15267): Add immidiate end
09-19 14:42:12.285: D/TrackListModel(15267): Storing TrackList to preferences
09-19 14:42:12.300: E/ResourceType(15267): Style contains key with bad entry: 0x01010336
09-19 14:42:12.300: E/ResourceType(15267): Style contains key with bad entry: 0x01010339
09-19 14:42:12.300: I/SinglePaneActivity(15267): onCreate
09-19 14:42:12.300: W/LOG(15267): setContentView
09-19 14:42:12.308: D/NowVideoPlayingFragment(15267): onCreate
09-19 14:42:12.308: D/NowVideoPlayingFragment(15267): onCreateView
09-19 14:42:12.390: D/dalvikvm(15267): GC_EXTERNAL_ALLOC freed 668K, 49% free 3741K/7303K, external 3867K/4142K, paused 29ms
09-19 14:42:12.425: D/NowVideoPlayingFragment(15267): onActivityPostCreated
09-19 14:42:12.433: D/NowPlayingSeekHelper(15267): NowPlayingSeekHelper constructor
09-19 14:42:12.433: I/AudioService(127):   Remote Control   registerMediaButtonEventReceiver() for ComponentInfo{com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.player.MediaButtonIntentReceiver}
09-19 14:42:12.437: D/DB debug(15267): Query - media
09-19 14:42:12.437: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE type=5 AND (_data=?)  ORDER BY title COLLATE LOCALIZED ASC
09-19 14:42:12.437: D/QUERY(15267): [/mnt/sdcard/tmp/video/VID_20120904_102712.m4v]
09-19 14:42:12.453: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:12.453: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.NEXT_TRACK_START_ACTION
09-19 14:42:12.453: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:12.453: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.video.VideoTrack:2
09-19 14:42:12.457: D/DB debug(15267): Query - media - id
09-19 14:42:12.457: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE (type=5) AND (_id=?) 
09-19 14:42:12.457: D/QUERY(15267): [2]
09-19 14:42:12.468: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:12.468: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.NEXT_TRACK_START_ACTION
09-19 14:42:12.468: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:12.468: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.video.VideoTrack:2
09-19 14:42:12.472: D/DB debug(15267): Query - media - id
09-19 14:42:12.472: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE (type=5) AND (_id=?) 
09-19 14:42:12.472: D/QUERY(15267): [2]
09-19 14:42:12.484: D/NowVideoPlayingFragment(15267): onServiceConnected
09-19 14:42:12.484: D/NowVideoPlayingFragment(15267): initializePlayerAndPlay
09-19 14:42:12.484: D/NowPlayingServant(15267): MediaPlayer is null
09-19 14:42:12.484: D/PlaybackService(15267): Intent command received
09-19 14:42:12.484: D/PlaybackService(15267): updateNotification app is : in foreground
09-19 14:42:12.484: I/PlaybackService(15267): hideNotification 
09-19 14:42:12.484: I/PlaybackService(15267): onStartCommand - intent:com.ventismedia.android.mediamonkey.player.PlaybackService.PLAY_ACTION
09-19 14:42:12.484: I/PlaybackService(15267): Parsing intent
09-19 14:42:12.484: D/TrackListManager(15267): Set clear request true
09-19 14:42:12.484: D/TrackListManager(15267): Add addable
09-19 14:42:12.484: D/TrackListManager(15267): Clear request
09-19 14:42:12.484: D/TrackListManager(15267): Set clear request false
09-19 14:42:12.484: D/TrackListModel(15267): Storing TrackList to preferences
09-19 14:42:12.492: D/TrackList(15267): addMedia(Uri)
09-19 14:42:12.492: D/TrackListManager(15267): Add immidiate addable
09-19 14:42:12.492: D/TrackListManager(15267): Add addable
09-19 14:42:12.496: D/TrackListManager(15267): Standard request
09-19 14:42:12.496: D/DB debug(15267): Query - media - id
09-19 14:42:12.496: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE _id=? 
09-19 14:42:12.496: D/QUERY(15267): [2]
09-19 14:42:12.507: I/TrackList(15267): setCurrentTrack
09-19 14:42:12.507: D/TrackListModel(15267): Storing current track preferences
09-19 14:42:12.507: I/PlaybackService(15267): play
09-19 14:42:12.507: I/AudioService(127):   Remote Control   registerMediaButtonEventReceiver() for ComponentInfo{com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.player.MediaButtonIntentReceiver}
09-19 14:42:12.507: I/AudioService(127):  AudioFocus  requestAudioFocus() from android.media.AudioManager@405c2dd0com.ventismedia.android.mediamonkey.player.PlaybackService@405a78b0
09-19 14:42:12.511: D/PlaybackService(15267): Waiting for play
09-19 14:42:12.511: D/PlaybackService(15267): prepareVideoToPlay
09-19 14:42:12.511: D/PlaybackService(15267): Video playback is not initialized
09-19 14:42:12.511: D/TrackListManager(15267): Add immidiate end
09-19 14:42:12.511: D/TrackListModel(15267): Storing TrackList to preferences
09-19 14:42:12.652: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:12.656: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.NEXT_TRACK_START_ACTION
09-19 14:42:12.656: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:12.656: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.video.VideoTrack:2
09-19 14:42:12.656: D/DB debug(15267): Query - media - id
09-19 14:42:12.656: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE (type=5) AND (_id=?) 
09-19 14:42:12.656: D/QUERY(15267): [2]
09-19 14:42:12.667: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:12.667: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.NEXT_TRACK_START_ACTION
09-19 14:42:12.667: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:12.667: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.video.VideoTrack:2
09-19 14:42:12.671: D/DB debug(15267): Query - media - id
09-19 14:42:12.671: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE (type=5) AND (_id=?) 
09-19 14:42:12.671: D/QUERY(15267): [2]
09-19 14:42:12.683: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:12.683: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.NEXT_TRACK_START_ACTION
09-19 14:42:12.683: D/NowPlayingServant(15267): RECEIVE - NEXT_TRACK_START_ACTION
09-19 14:42:12.718: V/NowVideoPlayingFragment(15267): surfaceCreated
09-19 14:42:12.718: D/NowVideoPlayingFragment(15267): initializePlayerAndPlay
09-19 14:42:12.718: D/PlaybackService(15267): initVideoPlayback
09-19 14:42:12.718: D/PlaybackService(15267): onInitVideoPlayback
09-19 14:42:12.718: D/PlaybackService(15267): prepareVideoToPlay
09-19 14:42:12.718: V/ANDROID_DRM_TEST(82): Client(884) constructor
09-19 14:42:12.718: V/ANDROID_DRM_TEST(82): Create new client(884) from pid 15267, url=/mnt/sdcard/tmp/video/VID_20120904_102712.m4v, connId=884, audioSessionId=229
09-19 14:42:12.718: V/ANDROID_DRM_TEST(82): setDataSource(/mnt/sdcard/tmp/video/VID_20120904_102712.m4v)
09-19 14:42:12.718: V/ANDROID_DRM_TEST(82): player type = 1
09-19 14:42:12.718: V/ANDROID_DRM_TEST(82):  create PVPlayer
09-19 14:42:12.718: V/PlayerDriver(82): PVSuspensionState struct constructor
09-19 14:42:12.718: V/PVPlayer(82): PVPlayer constructor
09-19 14:42:12.718: V/PVPlayer(82): construct PlayerDriver
09-19 14:42:12.718: V/PlayerDriver(82): constructor
09-19 14:42:12.718: V/PlayerDriver(82): OpenCore hardware module not found
09-19 14:42:12.718: V/PlayerDriver(82): start player thread
09-19 14:42:12.722: V/PlayerDriver(82): startPlayerThread
09-19 14:42:12.722: V/PlayerDriver(82): InitializeForThread
09-19 14:42:12.722: V/PlayerDriver(82): OMX_MasterInit
09-19 14:42:12.722: V/PlayerDriver(82): OsclScheduler::Init
09-19 14:42:12.722: V/PlayerDriver(82): CreatePlayer
09-19 14:42:12.742: V/PlayerDriver(82): AddToScheduler
09-19 14:42:12.742: V/PlayerDriver(82): PendForExec
09-19 14:42:12.742: V/PlayerDriver(82): OsclActiveScheduler::Current
09-19 14:42:12.742: V/PlayerDriver(82): StartScheduler
09-19 14:42:12.746: V/PVPlayer(82): send PLAYER_SETUP
09-19 14:42:12.746: V/PlayerDriver(82): Send player code: 2
09-19 14:42:12.746: V/PlayerDriver(82): CommandCompleted
09-19 14:42:12.746: V/PlayerDriver(82): Completed command PLAYER_SETUP status=1
09-19 14:42:12.746: V/AudioSink(82): AudioOutput(229)
09-19 14:42:12.746: V/ANDROID_DRM_TEST(82):  setDataSource
09-19 14:42:12.746: V/PVPlayer(82): setDataSource(/mnt/sdcard/tmp/video/VID_20120904_102712.m4v)
09-19 14:42:12.746: I/PlayerDriver(82): PlayerDriver::isProtectedFile(/mnt/sdcard/tmp/video/VID_20120904_102712.m4v)
09-19 14:42:12.746: V/PlayerDriver(82): File EXT is : .m4v
09-19 14:42:12.746: E/PlayerDriver(82): PlayerDriver::it is a not Protected file
09-19 14:42:12.746: V/ANDROID_DRM_TEST(82): [884] setVideoSurface(0x63b68)
09-19 14:42:12.746: V/PVPlayer(82): setVideoSurface(0x63b68)
09-19 14:42:12.746: D/PlaybackService(15267): call prepare media player
09-19 14:42:12.746: V/ANDROID_DRM_TEST(82): [884] setVideoSurface(0x63cb0)
09-19 14:42:12.746: V/PVPlayer(82): setVideoSurface(0x63cb0)
09-19 14:42:12.746: V/ANDROID_DRM_TEST(82): [884] setAudioStreamType(3)
09-19 14:42:12.746: V/ANDROID_DRM_TEST(82): [884] prepareAsync
09-19 14:42:12.746: V/PVPlayer(82): prepareAsync
09-19 14:42:12.746: V/PVPlayer(82):   data source = /mnt/sdcard/tmp/video/VID_20120904_102712.m4v
09-19 14:42:12.746: V/PlayerDriver(82): Send player code: 3
09-19 14:42:12.746: V/PlayerDriver(82): handleSetDataSource
09-19 14:42:12.746: V/PlayerDriver(82): handleSetDataSource- scanning for extension
09-19 14:42:12.761: V/PlayerDriver(82): CommandCompleted
09-19 14:42:12.761: V/PlayerDriver(82): Completed command PLAYER_SET_DATA_SOURCE status=1
09-19 14:42:12.761: V/PVPlayer(82): run_init s=0, cancelled=0
09-19 14:42:12.761: V/PlayerDriver(82): Send player code: 6
09-19 14:42:12.761: V/PlayerDriver(82): release string is 2.3.3 len 5
09-19 14:42:12.769: E/(82): ASF_HeaderObjectLoad : ERROR code = 4
09-19 14:42:12.769: E/OsclAsyncFile(82): OsclAsyncFile::ConstructL!! 
09-19 14:42:12.886: V/PlayerDriver(82): HandleInformationalEvent: 41
09-19 14:42:12.890: V/PlayerDriver(82): .... with duration = 10880 ms
09-19 14:42:12.890: V/PlayerDriver(82): CommandCompleted
09-19 14:42:12.890: V/PlayerDriver(82): Completed command PLAYER_INIT status=1
09-19 14:42:12.890: V/PVPlayer(82): run_set_video_surface s=0, cancelled=0
09-19 14:42:12.890: V/PlayerDriver(82): Send player code: 4
09-19 14:42:12.890: W/PlayerDriver(82): Using generic video MIO
09-19 14:42:12.894: V/PlayerDriver(82): CommandCompleted
09-19 14:42:12.894: V/PlayerDriver(82): Completed command PLAYER_SET_VIDEO_SURFACE status=1
09-19 14:42:12.894: V/PVPlayer(82): run_set_audio_output s=0, cancelled=0
09-19 14:42:12.898: V/PlayerDriver(82): Send player code: 5
09-19 14:42:12.898: V/PlayerDriver(82): Create realtime output
09-19 14:42:12.902: V/PlayerDriver(82): CommandCompleted
09-19 14:42:12.902: V/PlayerDriver(82): Completed command PLAYER_SET_AUDIO_SINK status=1
09-19 14:42:12.902: V/PVPlayer(82): run_prepare s=0, cancelled=0
09-19 14:42:12.902: V/PlayerDriver(82): Send player code: 7
09-19 14:42:12.902: V/PlayerDriver(82): disable natpkt - 0
09-19 14:42:12.917: E/OsclAsyncFile(82): OsclAsyncFile::ConstructL!! 
09-19 14:42:13.046: E/OsclAsyncFile(82): OsclAsyncFile::ConstructL!! 
09-19 14:42:13.179: V/AudioSink(82): open(16000, 2, 1, 4, 229)
09-19 14:42:13.179: V/AudioSink(82): setVolume
09-19 14:42:13.191: V/PlayerDriver(82): HandleInformationalEvent: 44
09-19 14:42:13.191: V/PlayerDriver(82): HandleInformationalEvent: type=44 UNHANDLED
09-19 14:42:13.191: V/ANDROID_DRM_TEST(82): [884] notify (0xc6638, 200, 1, 44)
09-19 14:42:13.191: W/MediaPlayer(15267): info/warning (1, 44)
09-19 14:42:13.191: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.191: V/PlayerDriver(82): Completed command PLAYER_PREPARE status=1
09-19 14:42:13.191: V/PlayerDriver(82): PLAYER_PREPARE complete mDownloadContextData=0x0, mDataReadyReceived=0
09-19 14:42:13.191: V/ANDROID_DRM_TEST(82): [884] notify (0xc6638, 1, 0, 0)
09-19 14:42:13.191: V/NowVideoPlayingFragment(15267): surfaceChanged Called width: 480height: 800
09-19 14:42:13.199: V/PVPlayer(82): check_for_live_streaming s=0, cancelled=0
09-19 14:42:13.222: I/ActivityManager(127): Displayed com.ventismedia.android.mediamonkey/.ui.phone.NowVideoPlayingActivity: +1s67ms
09-19 14:42:13.238: I/MediaPlayer(15267): Info (1,44)
09-19 14:42:13.238: V/PlaybackService(15267): Media Info, Media Info Unknown 44
09-19 14:42:13.238: D/PlaybackService(15267): onPrepared
09-19 14:42:13.242: I/VideoUtils(15267): Video size by display change to width: 0 height: 0
09-19 14:42:13.242: I/VideoUtils(15267): Video size changed to width: 0 height: 0
09-19 14:42:13.242: I/PlaybackService(15267): play
09-19 14:42:13.242: I/AudioService(127):   Remote Control   registerMediaButtonEventReceiver() for ComponentInfo{com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.player.MediaButtonIntentReceiver}
09-19 14:42:13.242: I/AudioService(127):  AudioFocus  requestAudioFocus() from android.media.AudioManager@405c2dd0com.ventismedia.android.mediamonkey.player.PlaybackService@405a78b0
09-19 14:42:13.242: D/PlaybackService(15267): Track can be played
09-19 14:42:13.242: V/PlayerDriver(82): Send player code: 16
09-19 14:42:13.242: V/PlayerDriver(82): status=3
09-19 14:42:13.242: V/ANDROID_DRM_TEST(82): [884] isPlaying: 0
09-19 14:42:13.246: V/ANDROID_DRM_TEST(82): [884] reset
09-19 14:42:13.246: E/PVPlayer(82): isDrmfile playing 0
09-19 14:42:13.246: V/PVPlayer(82): reset
09-19 14:42:13.246: V/PlayerDriver(82): Send player code: 18
09-19 14:42:13.246: V/PlayerDriver(82): handleCancelAllCommands
09-19 14:42:13.250: V/AudioSink(82): stop
09-19 14:42:13.250: V/AudioSink(82): stop
09-19 14:42:13.250: V/AudioSink(82): stop
09-19 14:42:13.250: V/AudioSink(82): stop
09-19 14:42:13.511: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.511: V/PlayerDriver(82): Completed command PLAYER_CANCEL_ALL_COMMANDS status=1
09-19 14:42:13.511: V/PlayerDriver(82): Send player code: 11
09-19 14:42:13.511: V/PlayerDriver(82): handleReset
09-19 14:42:13.511: E/PlayerDriver(82): PlayerDriver::handleTvOut state=[4]
09-19 14:42:13.511: E/PlayerDriver(82): PlayerDriver::it is not a DRM file.So don't resume TVOUT
09-19 14:42:13.511: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.511: V/PlayerDriver(82): Completed command PLAYER_RESET status=1
09-19 14:42:13.511: V/PlayerDriver(82): Send player code: 17
09-19 14:42:13.511: V/PlayerDriver(82): handleRemoveDataSource
09-19 14:42:13.511: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.511: V/PlayerDriver(82): Completed command PLAYER_REMOVE_DATA_SOURCE status=1
09-19 14:42:13.511: V/PVPlayer(82): unmap file
09-19 14:42:13.511: V/ANDROID_DRM_TEST(82): Client(885) constructor
09-19 14:42:13.511: V/ANDROID_DRM_TEST(82): Create new client(885) from pid 15267, url=/mnt/sdcard/tmp/video/VID_20120904_102712.m4v, connId=885, audioSessionId=229
09-19 14:42:13.511: V/ANDROID_DRM_TEST(82): setDataSource(/mnt/sdcard/tmp/video/VID_20120904_102712.m4v)
09-19 14:42:13.511: V/ANDROID_DRM_TEST(82): player type = 1
09-19 14:42:13.511: V/ANDROID_DRM_TEST(82):  create PVPlayer
09-19 14:42:13.511: V/PlayerDriver(82): PVSuspensionState struct constructor
09-19 14:42:13.511: V/PVPlayer(82): PVPlayer constructor
09-19 14:42:13.511: V/PVPlayer(82): construct PlayerDriver
09-19 14:42:13.511: V/PlayerDriver(82): constructor
09-19 14:42:13.511: V/PlayerDriver(82): OpenCore hardware module not found
09-19 14:42:13.511: V/PlayerDriver(82): start player thread
09-19 14:42:13.519: V/PlayerDriver(82): startPlayerThread
09-19 14:42:13.519: V/PlayerDriver(82): InitializeForThread
09-19 14:42:13.519: V/PlayerDriver(82): OMX_MasterInit
09-19 14:42:13.519: V/PlayerDriver(82): OsclScheduler::Init
09-19 14:42:13.519: V/PlayerDriver(82): CreatePlayer
09-19 14:42:13.527: V/PlayerDriver(82): AddToScheduler
09-19 14:42:13.527: V/PlayerDriver(82): PendForExec
09-19 14:42:13.527: V/PlayerDriver(82): OsclActiveScheduler::Current
09-19 14:42:13.527: V/PlayerDriver(82): StartScheduler
09-19 14:42:13.527: V/PVPlayer(82): send PLAYER_SETUP
09-19 14:42:13.527: V/PlayerDriver(82): Send player code: 2
09-19 14:42:13.527: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.527: V/PlayerDriver(82): Completed command PLAYER_SETUP status=1
09-19 14:42:13.527: V/AudioSink(82): AudioOutput(229)
09-19 14:42:13.527: V/ANDROID_DRM_TEST(82):  setDataSource
09-19 14:42:13.527: V/PVPlayer(82): setDataSource(/mnt/sdcard/tmp/video/VID_20120904_102712.m4v)
09-19 14:42:13.527: I/PlayerDriver(82): PlayerDriver::isProtectedFile(/mnt/sdcard/tmp/video/VID_20120904_102712.m4v)
09-19 14:42:13.527: V/PlayerDriver(82): File EXT is : .m4v
09-19 14:42:13.527: E/PlayerDriver(82): PlayerDriver::it is a not Protected file
09-19 14:42:13.527: V/ANDROID_DRM_TEST(82): disconnect(884) from pid 15267
09-19 14:42:13.527: V/PVPlayer(82): reset
09-19 14:42:13.527: V/PlayerDriver(82): Send player code: 18
09-19 14:42:13.527: V/PlayerDriver(82): handleCancelAllCommands
09-19 14:42:13.527: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.527: V/PlayerDriver(82): Completed command PLAYER_CANCEL_ALL_COMMANDS status=1
09-19 14:42:13.531: V/PlayerDriver(82): Send player code: 11
09-19 14:42:13.531: V/PlayerDriver(82): handleReset
09-19 14:42:13.531: E/PlayerDriver(82): PlayerDriver::handleTvOut state=[4]
09-19 14:42:13.531: E/PlayerDriver(82): PlayerDriver::it is not a DRM file.So don't resume TVOUT
09-19 14:42:13.531: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.531: V/PlayerDriver(82): Completed command PLAYER_RESET status=1
09-19 14:42:13.531: V/PlayerDriver(82): Send player code: 17
09-19 14:42:13.531: V/PlayerDriver(82): handleRemoveDataSource
09-19 14:42:13.531: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.531: V/PlayerDriver(82): Completed command PLAYER_REMOVE_DATA_SOURCE status=1
09-19 14:42:13.531: V/PVPlayer(82): unmap file
09-19 14:42:13.531: V/PVPlayer(82): PVPlayer destructor
09-19 14:42:13.531: V/PlayerDriver(82): Send player code: 1
09-19 14:42:13.531: V/PlayerDriver(82): DeletePlayer
09-19 14:42:13.531: V/PlayerDriver(82): destructor
09-19 14:42:13.531: V/PlayerDriver(82): OsclScheduler::Cleanup
09-19 14:42:13.531: V/PlayerDriver(82): PVSuspensionState struct destructor
09-19 14:42:13.531: V/ANDROID_DRM_TEST(82): [885] setVideoSurface(0x74480)
09-19 14:42:13.531: V/PVPlayer(82): setVideoSurface(0x74480)
09-19 14:42:13.531: V/ANDROID_DRM_TEST(82): Client(884) destructor pid = 15267
09-19 14:42:13.531: V/AudioSink(82): close
09-19 14:42:13.531: V/AudioPolicyManager(82): releaseOutput() 1
09-19 14:42:13.535: V/ANDROID_DRM_TEST(82): disconnect(884) from pid 15267
09-19 14:42:13.535: V/ANDROID_DRM_TEST(82): [885] setAudioStreamType(3)
09-19 14:42:13.535: V/ANDROID_DRM_TEST(82): [885] prepareAsync
09-19 14:42:13.535: V/PVPlayer(82): prepareAsync
09-19 14:42:13.535: V/PVPlayer(82):   data source = /mnt/sdcard/tmp/video/VID_20120904_102712.m4v
09-19 14:42:13.539: V/PlayerDriver(82): Send player code: 3
09-19 14:42:13.539: V/PlayerDriver(82): handleSetDataSource
09-19 14:42:13.539: V/PlayerDriver(82): handleSetDataSource- scanning for extension
09-19 14:42:13.550: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.550: V/PlayerDriver(82): Completed command PLAYER_SET_DATA_SOURCE status=1
09-19 14:42:13.550: V/PVPlayer(82): run_init s=0, cancelled=0
09-19 14:42:13.550: V/PlayerDriver(82): Send player code: 6
09-19 14:42:13.550: V/PlayerDriver(82): release string is 2.3.3 len 5
09-19 14:42:13.554: E/(82): ASF_HeaderObjectLoad : ERROR code = 4
09-19 14:42:13.554: E/OsclAsyncFile(82): OsclAsyncFile::ConstructL!! 
09-19 14:42:13.671: V/PlayerDriver(82): HandleInformationalEvent: 41
09-19 14:42:13.671: V/PlayerDriver(82): .... with duration = 10880 ms
09-19 14:42:13.671: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.671: V/PlayerDriver(82): Completed command PLAYER_INIT status=1
09-19 14:42:13.671: V/PVPlayer(82): run_set_video_surface s=0, cancelled=0
09-19 14:42:13.675: V/PlayerDriver(82): Send player code: 4
09-19 14:42:13.675: W/PlayerDriver(82): Using generic video MIO
09-19 14:42:13.675: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.675: V/PlayerDriver(82): Completed command PLAYER_SET_VIDEO_SURFACE status=1
09-19 14:42:13.675: V/PVPlayer(82): run_set_audio_output s=0, cancelled=0
09-19 14:42:13.675: V/PlayerDriver(82): Send player code: 5
09-19 14:42:13.675: V/PlayerDriver(82): Create realtime output
09-19 14:42:13.679: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.679: V/PlayerDriver(82): Completed command PLAYER_SET_AUDIO_SINK status=1
09-19 14:42:13.679: V/PVPlayer(82): run_prepare s=0, cancelled=0
09-19 14:42:13.679: V/PlayerDriver(82): Send player code: 7
09-19 14:42:13.679: V/PlayerDriver(82): disable natpkt - 0
09-19 14:42:13.683: E/OsclAsyncFile(82): OsclAsyncFile::ConstructL!! 
09-19 14:42:13.695: I/TransactionManager(15267): ThreadSerializer exit unlockMain 27
09-19 14:42:13.808: E/OsclAsyncFile(82): OsclAsyncFile::ConstructL!! 
09-19 14:42:13.859: D/BatteryService(127): update start
09-19 14:42:13.863: D/BatteryService(127): updateBattery level:75 scale:100 status:2 health:2 present:true voltage: 4010 temperature: 330 technology: Li-ion AC powered:false USB powered:true icon:17302213
09-19 14:42:13.882: I/StatusBarPolicy(212): BAT. S:2 H:2
09-19 14:42:13.941: V/AudioSink(82): open(16000, 2, 1, 4, 229)
09-19 14:42:13.941: V/AudioSink(82): setVolume
09-19 14:42:13.945: V/PlayerDriver(82): HandleInformationalEvent: 44
09-19 14:42:13.945: V/PlayerDriver(82): HandleInformationalEvent: type=44 UNHANDLED
09-19 14:42:13.945: V/ANDROID_DRM_TEST(82): [885] notify (0xf5678, 200, 1, 44)
09-19 14:42:13.945: W/MediaPlayer(15267): info/warning (1, 44)
09-19 14:42:13.945: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.945: V/PlayerDriver(82): Completed command PLAYER_PREPARE status=1
09-19 14:42:13.945: V/PlayerDriver(82): PLAYER_PREPARE complete mDownloadContextData=0x0, mDataReadyReceived=0
09-19 14:42:13.945: V/ANDROID_DRM_TEST(82): [885] notify (0xf5678, 1, 0, 0)
09-19 14:42:13.945: V/ANDROID_DRM_TEST(82): [885] setLooping(0)
09-19 14:42:13.945: V/PVPlayer(82): setLooping(0)
09-19 14:42:13.945: V/PVPlayer(82): check_for_live_streaming s=0, cancelled=0
09-19 14:42:13.949: V/PlayerDriver(82): Send player code: 12
09-19 14:42:13.949: V/ANDROID_DRM_TEST(82): [885] setVolume(1.000000, 1.000000)
09-19 14:42:13.949: V/AudioSink(82): setVolume(1.000000, 1.000000)
09-19 14:42:13.949: V/ANDROID_DRM_TEST(82): [885] setAuxEffectSendLevel(0.000000)
09-19 14:42:13.949: V/AudioSink(82): setAuxEffectSendLevel(0.000000)
09-19 14:42:13.949: V/ANDROID_DRM_TEST(82): [885] start
09-19 14:42:13.949: V/PVPlayer(82): setLooping(0)
09-19 14:42:13.949: V/PlayerDriver(82): Send player code: 12
09-19 14:42:13.949: E/PVPlayer(82): isDrmfile playing 0
09-19 14:42:13.949: E/ANDROID_DRM_TEST(82):   MediaPlayerService::mIsAnyDrmVideoPlaying : 0
09-19 14:42:13.949: V/PVPlayer(82): start
09-19 14:42:13.949: V/PlayerDriver(82): Send player code: 8
09-19 14:42:13.953: E/PlayerDriver(82): PlayerDriver::handleTvOut state=[1]
09-19 14:42:13.953: E/PlayerDriver(82): PlayerDriver::it is not a DRM file.So don't suspend TVOUT
09-19 14:42:13.953: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.953: V/PlayerDriver(82): Completed command PLAYER_START status=1
09-19 14:42:13.953: V/ANDROID_DRM_TEST(82): getDuration
09-19 14:42:13.953: V/PlayerDriver(82): Send player code: 15
09-19 14:42:13.953: V/PlayerDriver(82): CommandCompleted
09-19 14:42:13.953: V/PlayerDriver(82): Completed command PLAYER_GET_DURATION status=1
09-19 14:42:13.953: V/ANDROID_DRM_TEST(82): [885] getDuration = 10880
09-19 14:42:13.953: V/ANDROID_DRM_TEST(82): [885] seekTo(0)
09-19 14:42:13.953: V/PVPlayer(82): seekTo(0)
09-19 14:42:13.953: V/ANDROID_DRM_TEST(82): [885] setVolume(0.900000, 0.900000)
09-19 14:42:13.953: V/AudioSink(82): setVolume(0.900000, 0.900000)
09-19 14:42:13.953: I/VideoTrack(15267): updatePlaycount
09-19 14:42:13.960: V/PlayerDriver(82): Send player code: 13
09-19 14:42:13.960: V/PlayerDriver(82): handleSeek
09-19 14:42:13.988: W/TransactionManager(15267): New ThreadSerializer id from -1 to 29
09-19 14:42:14.093: D/PlaybackService(15267): updateNotification app is : in foreground
09-19 14:42:14.093: I/PlaybackService(15267): hideNotification 
09-19 14:42:14.097: V/PlayerDriver(82): Send player code: 16
09-19 14:42:14.097: V/PlayerDriver(82): status=4
09-19 14:42:14.097: V/ANDROID_DRM_TEST(82): [885] isPlaying: 1
09-19 14:42:14.113: D/PlayerWidgetModel(15267): Loading widget Ids
09-19 14:42:14.242: V/NowVideoPlayingFragment(15267): surfaceChanged Called width: 0height: 0
09-19 14:42:14.246: E/VideoMIO(82): AndroidSurfaceOutput::setParametersSync()  VIDEO RENDERER 1
09-19 14:42:14.250: D/SEC_Overlay(127): overlay_createOverlay:IN w=720 h=480 format=272
09-19 14:42:14.250: D/SEC_Overlay(127): src width, height are changed [w= 720, h= 480]->[w=720, h= 480]
09-19 14:42:14.253: I/SEC_Overlay(127): Opened video1/fd=291/obj=0042f0f8/shm=290/size=4096
09-19 14:42:14.257: I/SEC_Overlay(82): Postponing Stream Enable/0/1
09-19 14:42:14.257: I/VideoMIO(82): initCheck() direct start
09-19 14:42:14.257: V/ANDROID_DRM_TEST(82): [885] notify (0xf5678, 5, 720, 480)
09-19 14:42:14.261: V/PlayerDriver(82): HandleInformationalEvent: 44
09-19 14:42:14.261: V/PlayerDriver(82): HandleInformationalEvent: type=44 UNHANDLED
09-19 14:42:14.261: V/ANDROID_DRM_TEST(82): [885] notify (0xf5678, 200, 1, 44)
09-19 14:42:14.265: V/PlayerDriver(82): CommandCompleted
09-19 14:42:14.265: V/PlayerDriver(82): Completed command PLAYER_SEEK status=1
09-19 14:42:14.265: V/ANDROID_DRM_TEST(82): [885] notify (0xf5678, 4, 0, 0)
09-19 14:42:14.265: W/MediaPlayer(15267): info/warning (1, 44)
09-19 14:42:14.265: D/SEC_Overlay(127): overlay_setPosition(0) 240,400,1,1 => 240,400,8,8
09-19 14:42:14.265: I/SEC_Overlay(127): Rotation/0
09-19 14:42:14.265: W/v4l2_utils(127): v4l2_overlay_set_position:: (0,0,0,0)=>(240,400,8,8) rot=0
09-19 14:42:14.273: V/AudioSink(82): flush
09-19 14:42:14.273: V/AudioSink(82): start
09-19 14:42:14.273: V/AudioPolicyManager(82): startOutput() output 1, stream 3, session 229
09-19 14:42:14.273: V/AudioPolicyManager(82): getDeviceForStrategy() from cache strategy 0, device 2
09-19 14:42:14.273: V/AudioPolicyManager(82): getDeviceForStrategy() from cache strategy 0, device 2
09-19 14:42:14.273: V/AudioPolicyManager(82): getNewDevice() selected device 2
09-19 14:42:14.273: V/AudioPolicyManager(82): setOutputDevice() output 1 device 2 delayMs 0 force 0
09-19 14:42:14.273: V/AudioPolicyManager(82): setOutputDevice() setting same device 2 or null device for output 1
09-19 14:42:14.281: D/AudioHardwareALSA(82): Calling setDevice from write @..1874.
09-19 14:42:14.296: I/MediaPlayer(15267): Info (1,44)
09-19 14:42:14.296: V/PlaybackService(15267): Media Info, Media Info Unknown 44
09-19 14:42:14.296: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:14.296: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.PLAYBACK_STATE_CHANGE_ACTION
09-19 14:42:14.296: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:14.296: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.video.VideoTrack:2
09-19 14:42:14.300: D/DB debug(15267): Query - media - id
09-19 14:42:14.300: W/THREAD CONTROL(15267): Not queried by db thread!!! 30 (29)
09-19 14:42:14.300: D/DB debug(15267): Query - media
09-19 14:42:14.300: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE (type=5) AND (_id=?) 
09-19 14:42:14.300: D/QUERY(15267): select _id, title, _data, mime_type  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE type=5  ORDER BY title COLLATE LOCALIZED ASC
09-19 14:42:14.300: D/QUERY(15267): [2]
09-19 14:42:14.335: I/AudioHardwareALSA(82): Initialized ALSA PLAYBACK device AndroidPlayback_Speaker_normal
09-19 14:42:14.335: V/AudioHardwareALSA(82): Set PLAYBACK PCM format to S16_LE (Signed 16 bit Little Endian)
09-19 14:42:14.335: D/AudioHardwareALSA(82): Using 2 channels for PLAYBACK.
09-19 14:42:14.335: I/AudioHardwareALSA(82): DEFAULT_SAMPLE_RATE is 44100, mDefaults->sampleRate is 44100
09-19 14:42:14.335: D/AudioHardwareALSA(82): Set PLAYBACK sample rate to 44100 HZ
09-19 14:42:14.335: D/AudioHardwareALSA(82): Buffer size: 4096
09-19 14:42:14.335: D/AudioHardwareALSA(82): Latency: 92879
09-19 14:42:14.359: W/AudioFlinger(82): write blocked for 78 msecs, 151 delayed writes, thread 0x2fc40
09-19 14:42:14.386: D/dalvikvm(15267): GC_EXTERNAL_ALLOC freed 392K, 48% free 3858K/7303K, external 3386K/4214K, paused 59ms
09-19 14:42:14.398: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:14.398: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.video.VideoTrack:2
09-19 14:42:14.402: D/DB debug(15267): Query - media - id
09-19 14:42:14.402: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE (type=5) AND (_id=?) 
09-19 14:42:14.406: D/QUERY(15267): [2]
09-19 14:42:14.421: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:14.421: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.PLAYBACK_STATE_CHANGE_ACTION
09-19 14:42:14.425: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:14.425: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.video.VideoTrack:2
09-19 14:42:14.425: D/DB debug(15267): Query - media - id
09-19 14:42:14.425: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE (type=5) AND (_id=?) 
09-19 14:42:14.425: D/QUERY(15267): [2]
09-19 14:42:14.437: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:14.437: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.video.VideoTrack:2
09-19 14:42:14.441: D/DB debug(15267): Query - media - id
09-19 14:42:14.441: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE (type=5) AND (_id=?) 
09-19 14:42:14.441: D/QUERY(15267): [2]
09-19 14:42:14.457: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:14.457: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.PLAYBACK_STATE_CHANGE_ACTION
09-19 14:42:14.457: V/ANDROID_DRM_TEST(82): getCurrentPosition
09-19 14:42:14.457: V/PlayerDriver(82): Send player code: 14
09-19 14:42:14.457: V/PlayerDriver(82): position=92
09-19 14:42:14.457: V/ANDROID_DRM_TEST(82): [885] getCurrentPosition = 92
09-19 14:42:14.457: D/NowPlayingServant(15267): SeekBar on Progess changed
09-19 14:42:14.460: V/ANDROID_DRM_TEST(82): getCurrentPosition
09-19 14:42:14.460: V/PlayerDriver(82): Send player code: 14
09-19 14:42:14.460: V/PlayerDriver(82): position=94
09-19 14:42:14.460: V/ANDROID_DRM_TEST(82): [885] getCurrentPosition = 94
09-19 14:42:14.460: D/NowPlayingServant(15267): SeekBar on Progess changed
09-19 14:42:14.472: D/SEC_Overlay(127): overlay_setPosition(0) 240,400,1,1 => 240,400,8,8
09-19 14:42:14.472: I/SEC_Overlay(127): Nothing to do!
09-19 14:42:14.476: I/MediaPlayer(15267): Info (1,44)
09-19 14:42:14.476: V/PlaybackService(15267): Media Info, Media Info Unknown 44
09-19 14:42:14.492: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:14.492: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:14.546: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:14.546: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:14.597: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:14.597: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:14.640: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:14.640: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:14.695: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:14.695: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:14.742: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:14.742: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:14.796: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:14.796: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:14.847: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:14.847: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:14.937: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:14.937: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:14.949: W/PowerManagerService(127): Timer 0x3->0x3|0x3
09-19 14:42:14.980: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:14.980: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:15.023: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.023: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:15.066: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.066: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:15.109: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.109: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:15.152: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.152: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:15.175: V/PlayerDriver(82): HandleInformationalEvent: 23
09-19 14:42:15.195: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.195: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:15.238: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.238: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:15.281: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.281: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:15.328: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.328: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:15.371: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.371: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:15.414: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.414: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:15.457: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.457: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:15.460: V/ANDROID_DRM_TEST(82): getCurrentPosition
09-19 14:42:15.460: V/PlayerDriver(82): Send player code: 14
09-19 14:42:15.460: V/PlayerDriver(82): position=1096
09-19 14:42:15.460: V/ANDROID_DRM_TEST(82): [885] getCurrentPosition = 1096
09-19 14:42:15.464: D/NowPlayingServant(15267): SeekBar on Progess changed
09-19 14:42:15.468: I/TransactionManager(15267): ThreadSerializer exit unlockMain 29
09-19 14:42:15.503: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.503: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:15.519: D/TrackListManager(15267): Thread ending
09-19 14:42:15.546: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.546: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:15.589: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.589: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:15.632: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.632: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:15.675: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.675: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:15.718: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.718: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:15.765: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.765: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:15.808: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.808: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:15.851: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.851: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:15.898: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.898: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:15.937: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.937: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:15.984: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:15.984: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:16.027: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.027: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:16.070: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.070: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:16.113: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.113: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:16.156: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.156: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:16.175: V/PlayerDriver(82): HandleInformationalEvent: 23
09-19 14:42:16.199: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.199: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:16.242: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.242: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:16.289: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.289: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:16.332: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.332: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:16.375: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.375: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:16.421: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.421: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:16.464: V/ANDROID_DRM_TEST(82): getCurrentPosition
09-19 14:42:16.464: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.464: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:16.464: V/PlayerDriver(82): Send player code: 14
09-19 14:42:16.464: V/PlayerDriver(82): position=2099
09-19 14:42:16.464: V/ANDROID_DRM_TEST(82): [885] getCurrentPosition = 2099
09-19 14:42:16.464: D/NowPlayingServant(15267): SeekBar on Progess changed
09-19 14:42:16.507: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.507: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:16.550: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.550: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:16.593: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.593: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:16.644: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.644: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:16.679: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.679: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:16.726: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.726: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:16.773: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.773: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:16.812: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.812: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:16.859: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.859: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:16.898: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.898: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:16.945: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.945: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:16.988: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:16.988: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:17.035: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.035: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:17.074: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.074: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:17.121: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.121: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:17.160: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.160: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:17.175: V/PlayerDriver(82): HandleInformationalEvent: 23
09-19 14:42:17.203: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.203: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:17.250: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.250: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:17.292: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.292: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:17.335: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.335: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:17.378: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.378: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:17.425: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.425: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:17.468: V/ANDROID_DRM_TEST(82): getCurrentPosition
09-19 14:42:17.468: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.468: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:17.468: V/PlayerDriver(82): Send player code: 14
09-19 14:42:17.468: V/PlayerDriver(82): position=3103
09-19 14:42:17.468: V/ANDROID_DRM_TEST(82): [885] getCurrentPosition = 3103
09-19 14:42:17.468: D/NowPlayingServant(15267): SeekBar on Progess changed
09-19 14:42:17.511: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.511: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:17.554: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.554: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:17.605: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.605: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:17.644: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.644: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:17.687: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.687: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:17.734: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.734: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:17.773: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.773: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:17.816: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.816: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:17.863: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.863: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:17.906: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.906: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:17.949: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.949: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:17.992: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:17.992: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:18.039: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.039: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:18.082: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.082: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:18.125: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.125: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:18.167: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.167: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:18.175: V/PlayerDriver(82): HandleInformationalEvent: 23
09-19 14:42:18.210: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.210: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:18.253: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.253: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:18.296: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.296: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:18.343: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.343: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:18.386: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.386: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:18.429: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.429: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:18.472: V/ANDROID_DRM_TEST(82): getCurrentPosition
09-19 14:42:18.472: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.472: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:18.472: V/PlayerDriver(82): Send player code: 14
09-19 14:42:18.476: V/PlayerDriver(82): position=4111
09-19 14:42:18.476: V/ANDROID_DRM_TEST(82): [885] getCurrentPosition = 4111
09-19 14:42:18.480: D/NowPlayingServant(15267): SeekBar on Progess changed
09-19 14:42:18.515: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.515: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:18.562: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.562: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:18.605: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.605: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:18.648: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.648: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:18.691: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.691: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:18.734: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.734: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:18.777: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.777: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:18.824: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.824: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:18.867: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.867: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:18.910: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.910: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:18.953: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.953: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:18.996: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:18.996: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:19.039: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.039: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:19.085: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.085: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:19.128: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.128: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:19.171: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.171: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:19.175: V/PlayerDriver(82): HandleInformationalEvent: 23
09-19 14:42:19.214: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.214: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:19.257: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.257: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:19.300: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.300: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:19.347: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.347: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:19.394: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.394: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:19.437: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.437: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:19.476: V/ANDROID_DRM_TEST(82): getCurrentPosition
09-19 14:42:19.476: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.476: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:19.480: V/PlayerDriver(82): Send player code: 14
09-19 14:42:19.480: V/PlayerDriver(82): position=5114
09-19 14:42:19.480: V/ANDROID_DRM_TEST(82): [885] getCurrentPosition = 5114
09-19 14:42:19.480: D/NowPlayingServant(15267): SeekBar on Progess changed
09-19 14:42:19.527: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.527: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:19.566: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.566: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:19.609: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.609: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:19.617: I/InputReader(127): dispatchTouch::touch event's action is 0
09-19 14:42:19.617: I/InputDispatcher(127): Delivering touch to current input target: action: 0, channel '40ab4270 com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.ui.phone.NowVideoPlayingActivity (server)'
09-19 14:42:19.644: D/dalvikvm(15159): GC_EXPLICIT freed 98K, 49% free 2836K/5511K, external 2496K/3117K, paused 20ms
09-19 14:42:19.656: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.656: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:19.695: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.695: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=0, fail=-1 
09-19 14:42:19.742: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.742: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=1, fail=-1 
09-19 14:42:19.785: E/v4l2_utils(82): Error = Invalid argument from qbuf
09-19 14:42:19.785: E/VideoMIO(82): writeFrameBuf ZC queueBuffer1 idx=2, fail=-1 
09-19 14:42:19.804: I/InputReader(127): dispatchTouch::touch event's action is 1
09-19 14:42:19.804: I/InputDispatcher(127): Delivering touch to current input target: action: 1, channel '40ab4270 com.ventismedia.android.mediamonkey/com.ventismedia.android.mediamonkey.ui.phone.NowVideoPlayingActivity (server)'
09-19 14:42:19.808: D/NowPlayingServant(15267): onClick
09-19 14:42:19.808: D/AlbumArtOverlayHandler(15267): setCanceled: true
09-19 14:42:19.812: V/PlayerDriver(82): Send player code: 16
09-19 14:42:19.812: V/PlayerDriver(82): status=4
09-19 14:42:19.812: V/ANDROID_DRM_TEST(82): [885] isPlaying: 1
09-19 14:42:19.812: D/PlaybackService(15267): Intent command received
09-19 14:42:19.812: I/PlaybackService(15267): playOrPause
09-19 14:42:19.812: I/PlaybackService(15267): pause
09-19 14:42:19.816: V/ANDROID_DRM_TEST(82): [885] pause
09-19 14:42:19.816: V/PVPlayer(82): pause
09-19 14:42:19.816: V/PlayerDriver(82): Send player code: 10
09-19 14:42:19.816: V/PlayerDriver(82): call pause
09-19 14:42:19.816: V/PlayerDriver(82): HandleInformationalEvent: 23
09-19 14:42:19.820: V/PlayerDriver(82): CommandCompleted
09-19 14:42:19.820: V/PlayerDriver(82): Completed command PLAYER_PAUSE status=1
09-19 14:42:19.820: V/PlayerDriver(82): pause complete
09-19 14:42:19.820: V/ANDROID_DRM_TEST(82): getCurrentPosition
09-19 14:42:19.820: V/PlayerDriver(82): Send player code: 14
09-19 14:42:19.820: V/PlayerDriver(82): position=5453
09-19 14:42:19.820: V/ANDROID_DRM_TEST(82): [885] getCurrentPosition = 5453
09-19 14:42:19.820: I/VideoTrack(15267): updateBookmark
09-19 14:42:19.828: W/TransactionManager(15267): New ThreadSerializer id from -1 to 31
09-19 14:42:19.839: V/AudioSink(82): pause
09-19 14:42:19.839: V/AudioPolicyManager(82): stopOutput() output 1, stream 3, session 229
09-19 14:42:19.839: V/AudioPolicyManager(82): getNewDevice() selected device 0
09-19 14:42:19.839: V/AudioPolicyManager(82): setOutputDevice() output 1 device 0 delayMs 0 force 0
09-19 14:42:19.839: V/AudioPolicyManager(82): setOutputDevice() setting same device 0 or null device for output 1
09-19 14:42:19.871: D/PlaybackService(15267): updateNotification app is : in foreground
09-19 14:42:19.871: I/PlaybackService(15267): hideNotification 
09-19 14:42:19.875: V/PlayerDriver(82): Send player code: 16
09-19 14:42:19.875: V/PlayerDriver(82): status=5
09-19 14:42:19.875: V/ANDROID_DRM_TEST(82): [885] isPlaying: 0
09-19 14:42:19.882: D/PlayerWidgetModel(15267): Loading widget Ids
09-19 14:42:19.886: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:19.886: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.PLAYBACK_STATE_CHANGE_ACTION
09-19 14:42:19.886: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:19.890: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.video.VideoTrack:2
09-19 14:42:19.890: D/DB debug(15267): Query - media - id
09-19 14:42:19.890: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE (type=5) AND (_id=?) 
09-19 14:42:19.890: D/QUERY(15267): [2]
09-19 14:42:19.902: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:19.902: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.video.VideoTrack:2
09-19 14:42:19.906: D/DB debug(15267): Query - media - id
09-19 14:42:19.906: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE (type=5) AND (_id=?) 
09-19 14:42:19.906: D/QUERY(15267): [2]
09-19 14:42:19.917: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:19.917: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.PLAYBACK_STATE_CHANGE_ACTION
09-19 14:42:19.917: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:19.917: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.video.VideoTrack:2
09-19 14:42:19.921: D/DB debug(15267): Query - media - id
09-19 14:42:19.921: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE (type=5) AND (_id=?) 
09-19 14:42:19.921: D/QUERY(15267): [2]
09-19 14:42:19.933: D/TrackListModel(15267): Loading current track preferences
09-19 14:42:19.933: D/TrackListModel(15267): com.ventismedia.android.mediamonkey.player.video.VideoTrack:2
09-19 14:42:19.933: D/DB debug(15267): Query - media - id
09-19 14:42:19.933: D/QUERY(15267): select *  from (SELECT media.*, group_concat(artists.artist, ", ") AS artists FROM (media LEFT JOIN media_artists_map ON media._id=media_artists_map.media_id) LEFT JOIN artists ON artists._id=media_artists_map.artist_id  GROUP BY media._id)WHERE (type=5) AND (_id=?) 
09-19 14:42:19.933: D/QUERY(15267): [2]
09-19 14:42:19.945: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:19.945: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.PLAYBACK_STATE_CHANGE_ACTION
09-19 14:42:19.949: V/ANDROID_DRM_TEST(82): getCurrentPosition
09-19 14:42:19.949: V/PlayerDriver(82): Send player code: 14
09-19 14:42:19.949: V/PlayerDriver(82): position=5453
09-19 14:42:19.949: V/ANDROID_DRM_TEST(82): [885] getCurrentPosition = 5453
09-19 14:42:19.949: D/NowPlayingServant(15267): SeekBar on Progess changed
09-19 14:42:19.949: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:19.949: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.PLAYBACK_STATE_CHANGE_ACTION
09-19 14:42:19.949: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:19.949: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.PLAYBACK_STATE_CHANGE_ACTION
09-19 14:42:19.953: D/NowPlayingBroadcastReceiver(15267): Intent command received
09-19 14:42:19.953: D/NowPlayingBroadcastReceiver(15267): Intent action: com.ventismedia.android.mediamonkey.player.PlaybackService.PLAYBACK_STATE_CHANGE_ACTION
09-19 14:42:20.539: I/WindowOrientationListener(127): orientation 299.30313 gives new rotation = 2
09-19 14:42:20.539: D/SEC_Overlay(127): overlay_setPosition(0) 400,559,1,1 => 400,559,8,8
09-19 14:42:20.539: I/SEC_Overlay(127): Rotation/270
09-19 14:42:20.539: W/v4l2_utils(127): v4l2_overlay_set_position:: (240,400,8,8)=>(233,400,8,8) rot=270
09-19 14:42:20.539: I/WindowManager(127): Setting rotation to 3, animFlags=0
09-19 14:42:20.542: I/ActivityManager(127): Config changed: { scale=1.0 imsi=0/0 loc=ja_JP touch=3 keys=1/1/2 nav=1/1 orien=2 layout=34 uiMode=17 seq=157 FlipFont=0}
09-19 14:42:20.562: D/PhoneApp(241): updateProximitySensorMode: state = IDLE
09-19 14:42:20.570: D/PhoneApp(241): updateProximitySensorMode: lock already released.
09-19 14:42:20.578: D/NowVideoPlayingFragment(15267): onConfigurationChanged
09-19 14:42:20.578: D/NowVideoPlayingFragment(15267): Landscape - onChange
09-19 14:42:20.582: I/VideoUtils(15267): Video size changed to width: 720 height: 480
09-19 14:42:20.582: D/AlbumArtOverlayHandler(15267): isHideDelayed: false
09-19 14:42:20.582: D/NowVideoPlayingActivity(15267): Activity Landscape - onChange
09-19 14:42:20.632: D/SEC_Overlay(127): overlay_setPosition(0) 400,559,1,1 => 400,559,8,8
09-19 14:42:20.632: I/SEC_Overlay(127): Nothing to do!
09-19 14:42:20.667: D/SEC_Overlay(127): overlay_setPosition(0) 400,559,1,1 => 400,559,8,8
09-19 14:42:20.667: I/SEC_Overlay(127): Nothing to do!
09-19 14:42:20.687: D/SEC_Overlay(127): overlay_setPosition(0) 400,559,1,1 => 400,559,8,8
09-19 14:42:20.687: I/SEC_Overlay(127): Nothing to do!
09-19 14:42:20.703: D/SEC_Overlay(127): overlay_setPosition(0) 400,559,1,1 => 400,559,8,8
09-19 14:42:20.703: I/SEC_Overlay(127): Nothing to do!
09-19 14:42:20.718: D/SEC_Overlay(127): overlay_setPosition(-1) 160,320,480,480 => 160,320,480,480
09-19 14:42:20.722: I/SEC_Overlay(127): Nothing to do!
09-19 14:42:20.722: V/NowVideoPlayingFragment(15267): surfaceChanged Called width: 480height: 480
09-19 14:42:20.738: D/SEC_Overlay(127): overlay_setPosition(-1) 160,320,480,480 => 160,320,480,480
09-19 14:42:20.738: I/SEC_Overlay(127): Nothing to do!
09-19 14:42:20.757: D/SEC_Overlay(127): overlay_setPosition(-1) 160,320,480,480 => 160,320,480,480
09-19 14:42:20.757: I/SEC_Overlay(127): Nothing to do!
09-19 14:42:20.804: D/SEC_Overlay(127): overlay_setPosition(0) 0,40,480,720 => 0,40,480,720
09-19 14:42:20.804: I/SEC_Overlay(127): Rotation/270
09-19 14:42:20.804: W/v4l2_utils(127): v4l2_overlay_set_position:: (233,400,8,8)=>(40,0,720,480) rot=270
09-19 14:42:20.804: V/NowVideoPlayingFragment(15267): surfaceChanged Called width: 720height: 480
09-19 14:42:20.816: D/SEC_Overlay(127): overlay_setPosition(0) 0,40,480,720 => 0,40,480,720
09-19 14:42:20.816: I/SEC_Overlay(127): Nothing to do!
09-19 14:42:20.835: D/SEC_Overlay(127): overlay_setPosition(0) 0,40,480,720 => 0,40,480,720
09-19 14:42:20.835: I/SEC_Overlay(127): Nothing to do!
09-19 14:42:20.851: D/SEC_Overlay(127): overlay_setPosition(0) 0,40,480,720 => 0,40,480,720
09-19 14:42:20.851: I/SEC_Overlay(127): Nothing to do!
09-19 14:42:20.957: I/TransactionManager(15267): ThreadSerializer exit unlockMain 31
09-19 14:42:21.421: V/WifiMonitor(127): Event [ SCAN-RESULTS ]
09-19 14:42:21.421: I/wpa_supplicant(10388): CTRL-EVENT-NO-CONNECTION
09-19 14:42:21.421: W/wpa_supplicant(10388): scan interval : value of timeout (128)
09-19 14:42:21.421: V/WifiMonitor(127): Event [CTRL-EVENT-NO-CONNECTION]
09-19 14:42:21.433: I/NetworkStatusReceiver(9158): action: android.net.wifi.SHOW_AP_LIST_DIALOG
09-19 14:42:21.433: E/WifiManager(9158): showApDialog
09-19 14:42:21.433: I/WifiService(127): isShowingAccessPointListDialog():false
09-19 14:42:21.437: I/WifiService(127): setShowAccessPointListDialog():false
09-19 14:42:21.437: I/wpa_supplicant(10388): SCAN_RESULTS : [20] 
09-19 14:42:21.445: I/wpa_supplicant(10388): SCAN_RESULTS : [20]