U8K-ULTRA:/ # logcat --------- beginning of main 07-07 17:09:23.086 321 429 I DTVKIT_LOG: DiskMonitorTask:1487 refresh disk start 07-07 17:09:23.087 321 429 I DTVKIT_LOG: DiskMonitorTask:1489 check disk start 07-07 17:09:23.102 355 6094 E SystemControl: [/HDCPTxAuth.cpp, authLoop, 286] hdcp_tx 1.4 authenticate fail, 8s timeout 07-07 17:09:23.102 355 6094 I SystemControl: [/HDCPTxAuth.cpp, authLoop, 294] hdcp_tx authenticate success: 0 07-07 17:09:23.102 355 6094 E SystemControl: [/HDCPTxAuth.cpp, authThread, 183] HDCP authenticate fail --------- beginning of kernel 07-07 17:09:23.104 0 0 I [ 487.780229@4] hdmitx: avmute_store -1 07-07 17:09:23.104 0 0 I [ 487.780238@4] hdmitx: hdmitx21_av_mute_op: AV UNMUTE path=0x80 07-07 17:09:23.425 1428 6106 W adbd : timed out while waiting for FUNCTIONFS_BIND, trying again 07-07 17:09:23.425 1428 6106 I adbd : UsbFfs: connection terminated: monitor thread finished 07-07 17:09:23.426 1428 1428 I adbd : UsbFfs: already offline 07-07 17:09:23.426 1428 1428 I adbd : destroying transport UsbFfs 07-07 17:09:23.426 1428 1428 I adbd : UsbFfsConnection being destroyed 07-07 17:09:23.500 1428 1439 I adbd : opening control endpoint /dev/usb-ffs/adb/ep0 07-07 17:09:23.501 1428 1439 I adbd : UsbFfsConnection constructed 07-07 17:09:23.503 1428 6108 I adbd : UsbFfs-monitor thread spawned 07-07 17:09:23.575 321 422 I DTVKIT_LOG: TunerTask:3532 path:0:tune_idle_timer:8,frontend_usage=0 07-07 17:09:24.125 0 0 D [ 488.800691@2] logd: logdr: UID=0 GID=0 PID=6109 b tail=0 logMask=99 pid=0 start=0ns timeout=0ns 07-07 17:09:24.505 1428 6108 W adbd : timed out while waiting for FUNCTIONFS_BIND, trying again 07-07 17:09:24.505 1428 6108 I adbd : UsbFfs: connection terminated: monitor thread finished 07-07 17:09:24.506 1428 1428 I adbd : UsbFfs: already offline 07-07 17:09:24.507 1428 1428 I adbd : destroying transport UsbFfs 07-07 17:09:24.507 1428 1428 I adbd : UsbFfsConnection being destroyed 07-07 17:09:24.575 321 422 I DTVKIT_LOG: TunerTask:3532 path:0:tune_idle_timer:9,frontend_usage=0 07-07 17:09:24.594 1428 1439 I adbd : opening control endpoint /dev/usb-ffs/adb/ep0 07-07 17:09:24.595 1428 1439 I adbd : UsbFfsConnection constructed 07-07 17:09:24.596 1428 6111 I adbd : UsbFfs-monitor thread spawned 07-07 17:09:25.575 321 422 I DTVKIT_LOG: TunerTask:3532 path:0:tune_idle_timer:10,frontend_usage=0 07-07 17:09:25.597 1428 6111 W adbd : timed out while waiting for FUNCTIONFS_BIND, trying again 07-07 17:09:25.597 1428 6111 I adbd : UsbFfs: connection terminated: monitor thread finished 07-07 17:09:25.598 1428 1428 I adbd : UsbFfs: already offline 07-07 17:09:25.598 1428 1428 I adbd : destroying transport UsbFfs 07-07 17:09:25.598 1428 1428 I adbd : UsbFfsConnection being destroyed 07-07 17:09:25.676 1428 1439 I adbd : opening control endpoint /dev/usb-ffs/adb/ep0 07-07 17:09:25.678 1428 1439 I adbd : UsbFfsConnection constructed 07-07 17:09:25.681 1428 6112 I adbd : UsbFfs-monitor thread spawned 07-07 17:09:26.087 321 429 I DTVKIT_LOG: DiskMonitorTask:1487 refresh disk start 07-07 17:09:26.087 321 429 I DTVKIT_LOG: DiskMonitorTask:1489 check disk start 07-07 17:09:26.575 321 422 I DTVKIT_LOG: TunerTask:3532 path:0:tune_idle_timer:1,frontend_usage=0 07-07 17:09:26.682 1428 6112 W adbd : timed out while waiting for FUNCTIONFS_BIND, trying again 07-07 17:09:26.682 1428 6112 I adbd : UsbFfs: connection terminated: monitor thread finished 07-07 17:09:26.682 1428 1428 I adbd : UsbFfs: already offline 07-07 17:09:26.682 1428 1428 I adbd : destroying transport UsbFfs 07-07 17:09:26.682 1428 1428 I adbd : UsbFfsConnection being destroyed 07-07 17:09:26.742 1428 1439 I adbd : opening control endpoint /dev/usb-ffs/adb/ep0 07-07 17:09:26.743 1428 1439 I adbd : UsbFfsConnection constructed 07-07 17:09:26.744 1428 6114 I adbd : UsbFfs-monitor thread spawned 07-07 17:09:26.909 649 721 D KeyLayoutMap: mapKey: scanCode=28, usageCode=0x00000000 ~ Result keyCode=66, outFlags=0x00000000. 07-07 17:09:26.910 374 374 I android.hardware.power-service.libperfmgr: Power setBoost: INTERACTION duration: 0 07-07 17:09:26.910 374 374 I libperfmgr: Do Powerhint: INTERACTION 07-07 17:09:26.911 374 374 I android.hardware.power-service.libperfmgr: Power setBoost: INTERACTION duration: 0 07-07 17:09:26.911 374 374 I libperfmgr: Do Powerhint: INTERACTION 07-07 17:09:27.112 649 721 D KeyLayoutMap: mapKey: scanCode=28, usageCode=0x00000000 ~ Result keyCode=66, outFlags=0x00000000. 07-07 17:09:27.114 374 374 I android.hardware.power-service.libperfmgr: Power setBoost: INTERACTION duration: 0 07-07 17:09:27.114 374 374 I libperfmgr: Do Powerhint: INTERACTION 07-07 17:09:27.114 374 374 I android.hardware.power-service.libperfmgr: Power setBoost: INTERACTION duration: 0 07-07 17:09:27.114 374 374 I libperfmgr: Do Powerhint: INTERACTION 07-07 17:09:27.130 357 2271 I audio_hw_primary: [audio_stream_out,stream_id:0xf2b00010] time_diff[10026782]us stream_type[pcm] sync_mode[non tunnel] input_size[80257024]bytes, last_position[20054432], last_time[491788182]us, delay[9824], jitter[1]ms 07-07 17:09:27.210 5859 5888 I Kodi : 2025-07-07 17:09:27.210 T:5888 info : VideoPlayer::OpenFile: /storage/62B5-1C14/KODI/2160p.ATVP.WEB-DL.DDP5.1.Atmos.DV.HDR.H.265.mkv 07-07 17:09:27.214 5859 6115 I Kodi : 2025-07-07 17:09:27.214 T:6115 info : Creating InputStream 07-07 17:09:27.224 779 779 V AvrcpMediaPlayerWrapper: trySendMediaUpdate(): Metadata has been updated for org.xbmc.kodi 07-07 17:09:27.224 779 779 D AvrcpMediaPlayerList: sendMediaUpdate 07-07 17:09:27.224 779 779 I AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue 07-07 17:09:27.225 779 779 D AvrcpMediaPlayerList: sendMediaUpdate state=PlaybackState {state=1, position=0, buffered position=0, speed=0.0, updated=479009, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:27.225 779 779 D AvrcpTargetService: onMediaUpdated: track_changed=false state=false queue=false 07-07 17:09:27.225 779 779 D AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=false queue=false 07-07 17:09:27.225 779 779 D AvrcpTargetJni: sendMediaUpdateNative 07-07 17:09:27.225 779 779 I bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=0 : queue=0 07-07 17:09:27.251 5859 6115 I Kodi : 2025-07-07 17:09:27.251 T:6115 info : Creating Demuxer 07-07 17:09:27.253 779 779 V AvrcpMediaPlayerList: onActiveSessionsChanged: number of controllers: 1 07-07 17:09:27.253 779 779 D AvrcpMediaPlayerList: onActiveSessionsChanged: controller: org.xbmc.kodi 07-07 17:09:27.254 779 779 D AvrcpMediaPlayerList: Already have a controller for the player: org.xbmc.kodi, updating instead 07-07 17:09:27.255 779 779 D AvrcpMediaPlayerList: sendMediaUpdate 07-07 17:09:27.255 779 779 I AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue 07-07 17:09:27.255 779 779 D AvrcpMediaPlayerList: sendMediaUpdate state=PlaybackState {state=3, position=0, buffered position=0, speed=1.0, updated=491927, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:27.255 779 779 D AvrcpTargetService: onMediaUpdated: track_changed=false state=true queue=false 07-07 17:09:27.255 779 779 D AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=true queue=false 07-07 17:09:27.255 779 779 D AvrcpTargetJni: sendMediaUpdateNative 07-07 17:09:27.255 779 779 I bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=1 : queue=0 07-07 17:09:27.256 649 4237 I MediaFocusControl: requestAudioFocus() from uid/pid 10105/5859 clientId=android.media.AudioManager@8edfc42org.xbmc.kodi.interfaces.XBMCAudioManagerOnAudioFocusChangeListener@d3cb453 callingPack=org.xbmc.kodi req=1 flags=0x3 sdk=33 07-07 17:09:27.305 5859 6115 I Kodi : 2025-07-07 17:09:27.305 T:6115 info : Opening stream: 0 source: 256 07-07 17:09:27.305 5859 6115 I Kodi : 2025-07-07 17:09:27.305 T:6115 info : Creating video codec with codec id: 173 07-07 17:09:27.307 5859 6115 I Kodi : 2025-07-07 17:09:27.307 T:6115 info : CBitstreamConverter::Open bitstream to annexb init 07-07 17:09:27.307 5859 6115 I Kodi : 2025-07-07 17:09:27.307 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: c2.android.aac.decoder 07-07 17:09:27.307 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.307 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.308 5859 6115 I Kodi : 2025-07-07 17:09:27.308 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: c2.android.amrnb.decoder 07-07 17:09:27.308 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.308 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.308 5859 6115 I Kodi : 2025-07-07 17:09:27.308 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: c2.android.amrwb.decoder 07-07 17:09:27.308 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.308 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.308 5859 6115 I Kodi : 2025-07-07 17:09:27.308 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: c2.android.flac.decoder 07-07 17:09:27.308 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.308 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.308 5859 6115 I Kodi : 2025-07-07 17:09:27.308 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: c2.android.g711.alaw.decoder 07-07 17:09:27.308 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.308 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.308 5859 6115 I Kodi : 2025-07-07 17:09:27.308 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: c2.android.g711.mlaw.decoder 07-07 17:09:27.308 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.308 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.309 5859 6115 I Kodi : 2025-07-07 17:09:27.309 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: c2.android.mp3.decoder 07-07 17:09:27.309 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.309 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.309 5859 6115 I Kodi : 2025-07-07 17:09:27.309 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: c2.android.opus.decoder 07-07 17:09:27.309 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.309 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.309 5859 6115 I Kodi : 2025-07-07 17:09:27.309 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: c2.android.raw.decoder 07-07 17:09:27.309 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.309 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.309 5859 6115 I Kodi : 2025-07-07 17:09:27.309 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: c2.android.vorbis.decoder 07-07 17:09:27.309 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.309 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.309 5859 6115 I Kodi : 2025-07-07 17:09:27.309 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.vp9.decoder.awesome2 07-07 17:09:27.309 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.309 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.310 5859 6115 I Kodi : 2025-07-07 17:09:27.310 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.audio.decoder.ac3 07-07 17:09:27.310 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.310 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.310 5859 6115 I Kodi : 2025-07-07 17:09:27.310 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.audio.decoder.ac4 07-07 17:09:27.310 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.310 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.310 5859 6115 I Kodi : 2025-07-07 17:09:27.310 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.audio.decoder.dts 07-07 17:09:27.310 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.310 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.310 5859 6115 I Kodi : 2025-07-07 17:09:27.310 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.audio.decoder.dtse 07-07 17:09:27.310 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.310 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.310 5859 6115 I Kodi : 2025-07-07 17:09:27.310 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.audio.decoder.dtshd 07-07 17:09:27.310 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.310 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.311 5859 6115 I Kodi : 2025-07-07 17:09:27.310 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.audio.decoder.eac3 07-07 17:09:27.311 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.311 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.311 5859 6115 I Kodi : 2025-07-07 17:09:27.311 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.audio.decoder.ffmpeg 07-07 17:09:27.311 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.311 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.311 5859 6115 I Kodi : 2025-07-07 17:09:27.311 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.audio.decoder.mp2 07-07 17:09:27.311 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.311 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.311 5859 6115 I Kodi : 2025-07-07 17:09:27.311 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.vp6a.decoder.sw 07-07 17:09:27.311 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.311 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.311 5859 6115 I Kodi : 2025-07-07 17:09:27.311 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.vp6f.decoder.sw 07-07 17:09:27.311 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.311 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.311 5859 6115 I Kodi : 2025-07-07 17:09:27.311 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.av1.decoder.awesome2 07-07 17:09:27.312 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.312 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.312 5859 6115 I Kodi : 2025-07-07 17:09:27.312 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.avc.decoder.awesome2 07-07 17:09:27.312 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.312 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.312 5859 6115 I Kodi : 2025-07-07 17:09:27.312 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.avs.decoder.awesome2 07-07 17:09:27.312 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.312 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.312 5859 6115 I Kodi : 2025-07-07 17:09:27.312 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.avs2.decoder.awesome2 07-07 17:09:27.312 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.312 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.312 5859 6115 I Kodi : 2025-07-07 17:09:27.312 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.avs3.decoder.awesome2 07-07 17:09:27.312 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.312 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.313 5859 6115 I Kodi : 2025-07-07 17:09:27.313 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.dolby-vision.dvhe.decoder.awesome2 07-07 17:09:27.313 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.313 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.313 5859 6115 I Kodi : 2025-07-07 17:09:27.313 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.dolby-vision.dvav.decoder.awesome2 07-07 17:09:27.313 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.313 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.313 5859 6115 I Kodi : 2025-07-07 17:09:27.313 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.dolby-vision.dav1.decoder.awesome2 07-07 17:09:27.313 5859 6115 W System.err: java.lang.IllegalArgumentException: codec does not support type 07-07 17:09:27.313 5859 6115 W System.err: at android.media.MediaCodecInfo.getCapabilitiesForType(MediaCodecInfo.java:3898) 07-07 17:09:27.313 5859 6115 I Kodi : 2025-07-07 17:09:27.313 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Testing codec: OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.315 5859 6118 I OMXClient: IOmx service obtained 07-07 17:09:27.316 525 4836 I OMXMaster: makeComponentInstance(OMX.amlogic.hevc.decoder.awesome2) in android.hardwar process 07-07 17:09:27.316 525 4836 D OmxComponentManagerImpl: support frame mode 07-07 17:09:27.316 525 4836 D OmxComponentManagerImpl: use dv frame mode 07-07 17:09:27.316 525 4836 D OmxComponentManagerImpl: support avs frame mode 07-07 17:09:27.316 525 4836 D OmxComponentManagerImpl: format support multi-instance 07-07 17:09:27.316 525 4836 D OmxComponentManagerImpl: getEntryByName_2_num=0, componentName:OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.316 525 4836 D omx_video: processName=media.codec componentname=OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.316 525 4836 V AmlogicVideoDecoderAwesome2: [29]AmlogicVideoDecoder2:115 > 07-07 17:09:27.316 525 4836 V AmlogicVideoDecoderAwesome2: [29]initializeState_l:1796 > 07-07 17:09:27.316 525 4836 D AmlogicVideoDecoderAwesome2: [29]"vendor.media.omx.latency is not set used def=1.000000\n" 07-07 17:09:27.316 525 4836 D AmlogicVideoDecoderAwesome2: [29]"vendor.media.omx.frame_numlatency is not set used def=10\n" 07-07 17:09:27.316 525 4836 D AmlogicVideoDecoderAwesome2: [29]"vendor.omx.frame_numlimmit is not set used def=20\n" 07-07 17:09:27.316 525 4836 D AmlogicVideoDecoderAwesome2: [30]"AmlogicVideoDecoder2, mOmxInstancenum 1, mOmxInstanceID 30" 07-07 17:09:27.316 525 4836 D AmlogicVideoDecoderAwesome2: [30]"vendor.omx2.blkmode is not set used def=0\n" 07-07 17:09:27.316 525 4836 D AmlogicVideoDecoderAwesome2: [30]"vendor.omx2.maxalloc is not set used def=1\n" 07-07 17:09:27.316 525 4836 I AmlogicVideoDecoderAwesome2: [30]"default use NV21" 07-07 17:09:27.316 525 4836 D AmlogicVideoDecoderAwesome2: [30]"vendor.omx2.avc.size_level is not set used def=0\n" 07-07 17:09:27.316 525 4836 D AmlogicVideoDecoderAwesome2: [30]"vendor.omx2.avc.buffer_num is not set used def=12\n" 07-07 17:09:27.316 525 4836 I AmlogicVideoDecoderAwesome2: [30]"avc default size level 0 buffer num 12" 07-07 17:09:27.316 525 4836 I OmxVideoDecoder: [isDisplaySupport8K@109] display_w = 1920, display_h = 1080 07-07 17:09:27.316 525 4836 V AmlogicVideoPassthrough: [0]AmlogicVideoPassthrough:86 > 07-07 17:09:27.316 525 4836 D AmlogicVideoPassthrough: [1]"AmlogicVideoPassthrough, mOmxInstancenum 1, mOmxInstanceID 15" 07-07 17:09:27.316 525 4836 E secmem_tz: [Secure_NegotiateVersion:374] Secure_NegotiateVersion 2 2 2 2 2 2 07-07 17:09:27.316 525 4836 E secmem_tz: [Secure_NegotiateVersion:384] Negotiated secmem version = 2 07-07 17:09:27.317 525 4836 W OmxLogConf: Can not read property media.omx.log_levels, using 0 07-07 17:09:27.317 525 4836 D OmxResManage: ResManWrapper OMX-45 init, fd = 12 07-07 17:09:27.317 525 4836 D AmlogicVideoDecoderAwesome2: [30]"vendor.media.omx2.out_buffer1 is not set used def=8\n" 07-07 17:09:27.317 525 4836 D AmlogicVideoDecoderAwesome2: [30]"out buf:8" 07-07 17:09:27.317 525 4836 D AmlogicVideoDecoderAwesome2: [30]"vendor.media.omx2.dw is not set used def=0\n" 07-07 17:09:27.317 525 4836 D OmxVideoDecoder: setInputCoding OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.317 525 4836 D OmxVideoDecoder: mIsLinux49=0, mIsLinux54=1, major=5, minor=4 07-07 17:09:27.317 525 4836 I OmxVideoDecoder: [isDisplaySupport8K@109] display_w = 1920, display_h = 1080 07-07 17:09:27.317 525 4836 D OmxVideoDecoder: multi dec exist 07-07 17:09:27.317 525 4836 D OmxVideoDecoder: use dv frame mode 07-07 17:09:27.317 525 4836 D OmxVideoDecoder: avs multi dec exist 07-07 17:09:27.317 525 4836 D OmxVideoDecoder: Use frame mode for multidec. 07-07 17:09:27.317 525 4836 D OmxVideoDecoder: Use min out buffer size 07-07 17:09:27.317 525 4836 D OmxVideoDecoder: vendor.media.omx.dec.enable_h264_4k_mmu 1 07-07 17:09:27.317 525 4836 D OmxVideoDecoder: mSupportPiPVideo = 1 07-07 17:09:27.317 525 4836 D OmxVideoDecoder: mMustOsdDisplay 0, mAmVideoUsed=0, mSupportPiPVideo=1, mPipVideoUsed=0, mVideoUsedByOmx=0 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: OMX.amlogic.hevc.decoder.awesome2 : Vendor extensions supported (25) 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.4k-osd{{ enable : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.video-trickmode{{ enable : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.unstable-pts{{ enable : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.player-id{{ value : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.isudp{{ enable : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.realframerate{{ framerate-num : Int32 }, { framerate-den : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.Bitrate{{ value : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.is-mvc{{ enable : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.is-flv{{ nContainIndex : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.dvconfig-box{{ disable : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.profile-level{{ n-profile : Int32 }, { n-level : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.nrdp{{ video-peek-in-tunnel : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.low-latency{{ enable : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.save-mem{{ enable : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.videoLayer{{ type : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.renderByOmx{{ enable : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.tunerhal{{ video-filter-id : Int32 }, { hw-av-sync-id : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.tunerhal.passthrough{{ trick-mode : Int32 }, { trick-speed : Int32 }, { frame-advance : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.tunerhal.passthrough{{ event-mask : Int64 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.VideoQos{{ info : String }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.rotation-angle{{ angle : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.McodecReport{{ info : String }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.START.low-latency{{ enable : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.avc-size-level{{ value : Int32 }, } 07-07 17:09:27.318 525 4836 D OMX-VENDOR-EXT: vendor.avc-4k-mmu{{ enable : Int32 }, } 07-07 17:09:27.318 525 4836 D OmxVideoDecoder: video type 11 support v4l2, use omx2 07-07 17:09:27.318 525 4836 D OmxVideoDecoder: processName=media.codec 07-07 17:09:27.318 525 4836 D OmxComponentManagerImpl: IncreaseEntryNumByName pEntry->mNum=0, pEntry->mMaxNum:9,componentName:OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.319 525 4836 I omx_core: OMX_getHandle_num=1 07-07 17:09:27.319 525 4836 D OmxComponent: mApkName=media.codec 07-07 17:09:27.320 0 0 I [ 491.994776@0] resman: 50 appname:OMX-45, type = 0 prio = 10 07-07 17:09:27.341 5859 5859 D Kodi : XBMCVideoView: Created 07-07 17:09:27.341 5859 5859 D Kodi : XBMCVideoView: Changed, format:-2, width:1920, height:1080 07-07 17:09:27.342 5859 6115 D Kodi : XBMCVideoView.getSurface() = Surface(name=null)/@0x89e99c8 07-07 17:09:27.343 5859 6117 D SurfaceUtils: connecting to surface 0xe4aa5c98, reason connectToSurface 07-07 17:09:27.343 5859 6117 I MediaCodec: [OMX.amlogic.hevc.decoder.awesome2] setting surface generation to 5999618 07-07 17:09:27.343 5859 6117 D SurfaceUtils: disconnecting from surface 0xe4aa5c98, reason connectToSurface(reconnect) 07-07 17:09:27.344 5859 6117 D SurfaceUtils: connecting to surface 0xe4aa5c98, reason connectToSurface(reconnect) 07-07 17:09:27.345 5859 6118 I AmAVUtils: AmAVUtils::getComponentRole isEncoder :0 mime:video/hevc 07-07 17:09:27.345 5859 6118 I MediaVendorExt: AmAVUtils::getComponentRole isEncoder :0 mime:video/hevc 07-07 17:09:27.346 525 4836 D OmxVideoDecoder: setParameter 1822 0x1000017 07-07 17:09:27.346 525 4836 D OmxVideoDecoder: setInputCoding video_decoder.hevc 07-07 17:09:27.347 525 4836 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.enableAndroidNativeBuffers" 07-07 17:09:27.347 525 4836 D OmxVideoDecoder: setParameter 1822 0x7f000000 07-07 17:09:27.347 525 4836 V OmxVideoDecoder: setParameter 2437 EnableNativeBuffers 1 07-07 17:09:27.347 525 4836 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.347 525 4836 V OmxVideoDecoder: nWidth=640,nHeight=480, nMinbufs=0 07-07 17:09:27.347 525 4836 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.allocateNativeHandle" 07-07 17:09:27.347 525 4836 D OmxVideoDecoder: setParameter 1822 0x7f00000a 07-07 17:09:27.347 525 4836 E OMXNodeInstance: setParameter(0xf5dc0cb0:amlogic.hevc.decoder.awesome2, OMX.google.android.index.allocateNativeHandle(0x7f00000a): Output:1 en=0) ERROR: UnsupportedSetting(0x80001019) 07-07 17:09:27.347 525 4836 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.storeANWBufferInMetadata" 07-07 17:09:27.347 525 4836 D OmxVideoDecoder: setParameter 1822 0x7f00000d 07-07 17:09:27.347 525 4836 D OmxVideoDecoder: setParameter 2457 OMX_GoogleAndroidStoreANWBufferInMetadata 07-07 17:09:27.347 525 4836 V OmxVideoDecoder: mUseStoreANWBufferInMetadata=1 07-07 17:09:27.347 525 4836 E OMXNodeInstance: getParameter(0xf5dc0cb0:amlogic.hevc.decoder.awesome2, ??(0x6f600011)) ERROR: UnsupportedIndex(0x8000101a) 07-07 17:09:27.347 525 4836 D OmxVideoDecoder: setParameter 1822 0x6000001 07-07 17:09:27.348 525 4836 D OmxVideoDecoder: setParameter nPortIndex 0 CompFmt 0xb ColorFmt 0 07-07 17:09:27.348 525 5559 D OmxVideoDecoder: setParameter 1822 0x6000001 07-07 17:09:27.348 525 5559 D OmxVideoDecoder: setParameter nPortIndex 1 CompFmt 0 ColorFmt 0x11 07-07 17:09:27.348 525 4836 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 640, height 480 nBufferCountMin:3 07-07 17:09:27.348 525 5559 D OmxVideoDecoder: setParameter 1822 0x2000001 07-07 17:09:27.348 525 5559 D OmxVideoDecoder: setParameter nPortIndex 0 nFrameWidth 3840 nFrameHeight 2160 nSliceHeight 16 07-07 17:09:27.348 525 5559 D OmxVideoDecoder: 4K file codec mm set mVideoClass changed to 2 07-07 17:09:27.348 525 5559 E OmxVideoDecoder: reset input buffer:8294400 07-07 17:09:27.348 525 525 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.348 525 525 V OmxVideoDecoder: nWidth=640,nHeight=480, nMinbufs=0 07-07 17:09:27.348 525 525 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 640, height 480 nBufferCountMin:8 07-07 17:09:27.349 525 525 D OmxVideoDecoder: setParameter 1822 0x2000001 07-07 17:09:27.349 525 525 D OmxVideoDecoder: adaptiveplayback default set w*d: 4096*2304 07-07 17:09:27.349 525 525 D OmxVideoDecoder: media.omx.display_mode=3 07-07 17:09:27.349 525 525 D OmxVideoDecoder: choose mVideoUsedByOmx=0, mVideoUsedByThis=0, mAmVideoUsed=0 07-07 17:09:27.349 525 525 D OmxVideoDecoder: mDisplayMode = DISPLAY_MODE_V4LVIDEO 07-07 17:09:27.349 525 525 D OmxVideoDecoder: choose mDisplayMode=3 07-07 17:09:27.349 525 525 D OmxVideoDecoder: DecoderWidthFromExtractor[3840] * mDecoderHeightFromExtractor[2160] > buffer_maxwidth[1920] * buffer_maxheight[1088] 07-07 17:09:27.349 525 525 D OmxVideoDecoder: setParameter nPortIndex 1 nFrameWidth 3840 nFrameHeight 2160 nSliceHeight 2160 07-07 17:09:27.349 525 525 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.349 525 525 V OmxVideoDecoder: nWidth=3840,nHeight=2160, nMinbufs=0 07-07 17:09:27.349 525 956 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.describeColorAspects" 07-07 17:09:27.349 525 956 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.storeMetaDataInBuffers" 07-07 17:09:27.349 525 956 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.storeANWBufferInMetadata" 07-07 17:09:27.349 525 956 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.prepareForAdaptivePlayback" 07-07 17:09:27.349 525 956 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.configureVideoTunnelMode" 07-07 17:09:27.349 525 956 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.useAndroidNativeBuffer2" 07-07 17:09:27.349 525 956 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.useAndroidNativeBuffer" 07-07 17:09:27.349 525 956 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.enableAndroidNativeBuffers" 07-07 17:09:27.349 525 956 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.allocateNativeHandle" 07-07 17:09:27.349 525 956 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.getAndroidNativeBufferUsage" 07-07 17:09:27.349 525 956 D OmxVideoDecoder: setConfig 3393 0x7f00000c 07-07 17:09:27.349 525 4836 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.describeHDRStaticInfo" 07-07 17:09:27.349 525 956 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.describeHDR10PlusInfo" 07-07 17:09:27.350 525 525 D OmxVideoDecoder: setConfig 3393 0x7f000009 07-07 17:09:27.350 525 525 D OmxVideoDecoder: HDRStaticInfo: R=0 0; G=0 0; B=0 0; W=0 0; MaxDisplayLuminance:0, MinDisplayLuminance=0, mMaxContentLightLevel=0, MaxContentLightLevel=0 07-07 17:09:27.350 525 525 D OmxVideoDecoder: HDRStaticInfo: all parmeter is 0, set mHasHDRStaticInfo to false 07-07 17:09:27.350 5859 6118 I AmAVUtils: AmAVUtils::isAudioExtendFormat input = video/hevc 07-07 17:09:27.350 5859 6118 I AmAVUtils: AmAVUtils::isExtendFormat input = video/hevc 07-07 17:09:27.350 525 5559 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 3840, height 2160 nBufferCountMin:3 07-07 17:09:27.350 525 525 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 3840, height 2160 nBufferCountMin:3 07-07 17:09:27.350 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.350 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=0 (4k-osd) 07-07 17:09:27.350 525 5509 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.350 525 5509 V OmxVideoDecoder: VendorExt: getConfig: index=1 (video-trickmode) 07-07 17:09:27.350 525 5509 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.350 525 5509 V OmxVideoDecoder: VendorExt: getConfig: index=2 (unstable-pts) 07-07 17:09:27.351 525 5509 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.351 525 5509 V OmxVideoDecoder: VendorExt: getConfig: index=3 (player-id) 07-07 17:09:27.351 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.351 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=4 (isudp) 07-07 17:09:27.351 525 5509 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.351 525 5509 V OmxVideoDecoder: VendorExt: getConfig: index=5 (realframerate) 07-07 17:09:27.351 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.351 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=5 (realframerate) 07-07 17:09:27.351 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.351 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=6 (Bitrate) 07-07 17:09:27.351 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.351 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=7 (is-mvc) 07-07 17:09:27.351 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.351 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=8 (is-flv) 07-07 17:09:27.352 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.352 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=9 (dvconfig-box) 07-07 17:09:27.352 525 5509 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.352 525 5509 V OmxVideoDecoder: VendorExt: getConfig: index=10 (profile-level) 07-07 17:09:27.352 525 5509 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.352 525 5509 V OmxVideoDecoder: VendorExt: getConfig: index=10 (profile-level) 07-07 17:09:27.352 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.352 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=11 (nrdp) 07-07 17:09:27.352 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.352 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=12 (low-latency) 07-07 17:09:27.352 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.352 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=13 (save-mem) 07-07 17:09:27.353 525 525 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.353 525 525 V OmxVideoDecoder: VendorExt: getConfig: index=14 (videoLayer) 07-07 17:09:27.353 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.353 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=15 (renderByOmx) 07-07 17:09:27.353 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.353 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=16 (tunerhal) 07-07 17:09:27.353 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.353 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=16 (tunerhal) 07-07 17:09:27.353 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.353 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=17 (tunerhal.passthrough) 07-07 17:09:27.353 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.353 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=17 (tunerhal.passthrough) 07-07 17:09:27.353 525 5509 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.353 525 5509 V OmxVideoDecoder: VendorExt: getConfig: index=18 (tunerhal.passthrough) 07-07 17:09:27.353 525 5509 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.354 525 5509 V OmxVideoDecoder: VendorExt: getConfig: index=19 (VideoQos) 07-07 17:09:27.354 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.354 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=20 (rotation-angle) 07-07 17:09:27.354 525 5509 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.354 525 5509 V OmxVideoDecoder: VendorExt: getConfig: index=21 (McodecReport) 07-07 17:09:27.354 525 5509 D OmxVideoDecoder: mMcodecReport=0 mPeer.get=0xf24c0010 07-07 17:09:27.354 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.354 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=22 (START.low-latency) 07-07 17:09:27.354 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.354 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=23 (avc-size-level) 07-07 17:09:27.354 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.354 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=24 (avc-4k-mmu) 07-07 17:09:27.354 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.354 525 5508 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.354 525 5508 V OmxVideoDecoder: nWidth=3840,nHeight=2160, nMinbufs=0 07-07 17:09:27.354 525 5508 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 3840, height 2160 nBufferCountMin:8 07-07 17:09:27.355 525 5508 D OmxVideoDecoder: getConfig 3251 0x700000f 07-07 17:09:27.355 525 5508 D OmxVideoDecoder: getConfig 3254 nIndex 0x700000f 07-07 17:09:27.355 525 5508 I AmlogicVideoDecoderAwesome2: [30]"getAndroidNativeBufferCrop [3840 x 2160] \n" 07-07 17:09:27.355 525 5508 D OmxVideoDecoder: getConfig OMX_IndexConfigCommonOutputCrop pRect->nWidth=3840, pRect->nHeight=2160 07-07 17:09:27.355 525 5508 D OmxVideoDecoder: setConfig 3393 0x7f00000c 07-07 17:09:27.355 525 5508 D OmxVideoDecoder: getConfig 3251 0x7f00000c 07-07 17:09:27.355 525 5508 D OmxVideoDecoder: getConfig 3251 0x7f00000c 07-07 17:09:27.355 525 5508 E OMXNodeInstance: getConfig(0xf5dc0cb0:amlogic.hevc.decoder.awesome2, ??(0x7f00000c)) ERROR: UnsupportedSetting(0x80001019) 07-07 17:09:27.355 525 5508 D OmxVideoDecoder: getConfig 3251 0x7f000009 07-07 17:09:27.355 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.355 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=0 (4k-osd) 07-07 17:09:27.355 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.355 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=1 (video-trickmode) 07-07 17:09:27.356 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.356 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=2 (unstable-pts) 07-07 17:09:27.356 525 5509 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.356 525 5509 V OmxVideoDecoder: VendorExt: getConfig: index=3 (player-id) 07-07 17:09:27.356 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.356 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=4 (isudp) 07-07 17:09:27.356 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.356 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=5 (realframerate) 07-07 17:09:27.356 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.356 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=5 (realframerate) 07-07 17:09:27.356 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.356 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=6 (Bitrate) 07-07 17:09:27.356 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.356 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=7 (is-mvc) 07-07 17:09:27.357 525 5509 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.357 525 5509 V OmxVideoDecoder: VendorExt: getConfig: index=8 (is-flv) 07-07 17:09:27.357 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.357 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=9 (dvconfig-box) 07-07 17:09:27.357 525 525 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.357 525 525 V OmxVideoDecoder: VendorExt: getConfig: index=10 (profile-level) 07-07 17:09:27.357 525 525 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.357 525 525 V OmxVideoDecoder: VendorExt: getConfig: index=10 (profile-level) 07-07 17:09:27.357 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.357 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=11 (nrdp) 07-07 17:09:27.357 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.357 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=12 (low-latency) 07-07 17:09:27.357 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.357 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=13 (save-mem) 07-07 17:09:27.358 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.358 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=14 (videoLayer) 07-07 17:09:27.358 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.358 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=15 (renderByOmx) 07-07 17:09:27.358 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.358 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=16 (tunerhal) 07-07 17:09:27.358 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.358 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=16 (tunerhal) 07-07 17:09:27.358 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.359 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=17 (tunerhal.passthrough) 07-07 17:09:27.362 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.362 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=17 (tunerhal.passthrough) 07-07 17:09:27.362 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.362 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=18 (tunerhal.passthrough) 07-07 17:09:27.362 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.362 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=19 (VideoQos) 07-07 17:09:27.362 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.362 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=20 (rotation-angle) 07-07 17:09:27.363 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.363 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=21 (McodecReport) 07-07 17:09:27.363 525 4836 D OmxVideoDecoder: mMcodecReport=0 mPeer.get=0xf24c0010 07-07 17:09:27.363 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.363 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=22 (START.low-latency) 07-07 17:09:27.363 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.363 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=23 (avc-size-level) 07-07 17:09:27.363 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.363 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=24 (avc-4k-mmu) 07-07 17:09:27.363 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.364 525 6119 I OmxComponent: OMX_CommandStateSet 854 Cmd 0 nParam1 0x2 07-07 17:09:27.364 525 6119 I OmxComponent: OMX-45 STATE_SET: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.364 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.364 525 5275 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 3840, height 2160 nBufferCountMin:3 07-07 17:09:27.365 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.366 525 6119 I chatty : uid=1046(mediacodec) ecoder.awesome2 identical 1 line 07-07 17:09:27.367 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.367 525 4836 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.367 525 4836 V OmxVideoDecoder: nWidth=3840,nHeight=2160, nMinbufs=0 07-07 17:09:27.367 525 4836 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 3840, height 2160 nBufferCountMin:8 07-07 17:09:27.367 525 4836 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.367 525 4836 V OmxVideoDecoder: nWidth=3840,nHeight=2160, nMinbufs=0 07-07 17:09:27.367 525 4836 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 3840, height 2160 nBufferCountMin:8 07-07 17:09:27.367 525 5275 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.AndroidNativeBufferConsumerUsage" 07-07 17:09:27.367 525 5275 D OmxVideoDecoder: setParameter 1822 0x7f00000b 07-07 17:09:27.367 525 5275 D OmxVideoDecoder: OMX_GoogleAndroidIndexAndroidNativeBufferConsumerUsage 0x900 07-07 17:09:27.367 525 5275 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.getAndroidNativeBufferUsage" 07-07 17:09:27.367 525 5275 D AmlogicVideoDecoderAwesome2: [30]"vendor.media.omx2.dw is not set used def=0\n" 07-07 17:09:27.368 525 5275 D OmxVideoDecoder: Get NativeBufferUsage 0x408033 07-07 17:09:27.368 5859 6118 D SurfaceUtils: set up nativeWindow 0xe4aa5c98 for 3840x2160, color 0x11, rotation 0, usage 0x40a933 07-07 17:09:27.368 525 5275 D OmxVideoDecoder: setParameter 1822 0x2000001 07-07 17:09:27.368 525 5275 D OmxVideoDecoder: adaptiveplayback default set w*d: 4096*2304 07-07 17:09:27.368 525 5275 D OmxVideoDecoder: media.omx.display_mode=3 07-07 17:09:27.368 525 5275 D OmxVideoDecoder: choose mVideoUsedByOmx=0, mVideoUsedByThis=0, mAmVideoUsed=0 07-07 17:09:27.368 525 5275 D OmxVideoDecoder: mDisplayMode = DISPLAY_MODE_V4LVIDEO 07-07 17:09:27.368 525 5275 D OmxVideoDecoder: choose mDisplayMode=3 07-07 17:09:27.368 525 5275 D OmxVideoDecoder: DecoderWidthFromExtractor[3840] * mDecoderHeightFromExtractor[2160] > buffer_maxwidth[1920] * buffer_maxheight[1088] 07-07 17:09:27.368 525 5275 D OmxVideoDecoder: setParameter nPortIndex 1 nFrameWidth 4096 nFrameHeight 2304 nSliceHeight 2304 07-07 17:09:27.368 525 5275 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.368 525 5275 V OmxVideoDecoder: nWidth=4096,nHeight=2304, nMinbufs=0 07-07 17:09:27.368 525 5275 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.369 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.369 525 5275 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.369 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.369 525 5275 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.369 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.369 525 5275 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.369 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.369 525 5275 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.369 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.370 525 5275 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.370 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.370 525 5275 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.370 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.370 525 5275 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.370 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.370 525 4836 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.371 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.371 525 4836 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.371 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.371 525 4836 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.371 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.371 525 4836 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.371 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.371 525 6119 V AmlogicVideoDecoderAwesome2: [30]acquireResources:463 > 07-07 17:09:27.371 525 6119 D AmlogicVideoDecoderAwesome2: [30]"setUp mDurationUs = 0 videoDefine.xFramerate = 0" 07-07 17:09:27.371 525 6119 V debugSetup: media.omx.dumpRecv is disabled 07-07 17:09:27.371 525 6119 V debugSetup: media.omx.dumpCodec is disabled 07-07 17:09:27.371 525 6119 E AmlogicVideoDecoderAwesome2: setUp(): 1310 mOwner->isGameMode()=0 07-07 17:09:27.371 525 6119 D AmlogicVideoDecoderAwesome2: [30]"vendor.media.omx.gamemode is not set used def=0\n" 07-07 17:09:27.371 525 6119 I AmlogicVideoDecoderAwesome2: [30]"setUp width 3840, height 2160\n" 07-07 17:09:27.371 525 6119 D AmlogicVideoDecoderAwesome2: 1080p_buffer enabled for secure. set double write 0x200. 07-07 17:09:27.371 525 6119 D AmlogicVideoDecoderAwesome2: [30]"vendor.media.omx2.dw is not set used def=512\n" 07-07 17:09:27.371 525 6119 D AmlogicVideoDecoderAwesome2: [30]"vendor.media.omx2.margin is not set used def=5\n" 07-07 17:09:27.371 525 6119 I AmlogicVideoDecoderAwesome2: [30]"mIsSurfacTexture 0, mDoubleWriteMode 512, default_margin:5 \n" 07-07 17:09:27.371 525 6119 D AmlogicVideoDecoderAwesome2: [30]"vendor.media.omx2.hithBitrate_mbps is not set used def=0\n" 07-07 17:09:27.372 525 6119 I AmlogicVideoDecoderAwesome2: setUp@1720 , double_write_mode = 512 07-07 17:09:27.372 525 6119 D AmlogicVideoDecoderAwesome2: [30]"codecInit" 07-07 17:09:27.372 525 6119 V AmlogicVideoDecoderAwesome2: [30]"codecInit hevc\n" 07-07 17:09:27.372 525 6119 I resman : acquire fd:12, resid:7, preempt:1, time_out:10000, arg: 07-07 17:09:27.372 525 6119 I resman : acquire fd:12, resid:5, preempt:1, time_out:10000, arg:size:160 07-07 17:09:27.372 525 6119 D VideoDecWraper: VideoDecWraper 07-07 17:09:27.372 525 6119 D AmlogicVideoDecoderAwesome2: [30]"[codecInit:1164] final double_write_mode = 512, File:vendor/amlogic/common/omx/ComponentPeers/libOmxVideo/AmlogicVideoDecoder2.cpp" 07-07 17:09:27.372 525 6119 D VideoDecWraper: initialize:mime:video/hevc 07-07 17:09:27.372 525 6119 I VideoDecWraper: mediahal versionstr:V3.14.77-g8e89b1e 07-07 17:09:27.372 525 6119 I AmVideoDec: AmVideoDec version: V3.14.77-g8e89b1e (drm.autobuild Tue Aug 1 19:53:07 CST 2023), mHalInstanceID 8, mHalInstancenum 1 07-07 17:09:27.372 525 6119 I android.hardware.media.omx@1.0-service: vendor.mediahal.loglevels is not set, used defaultValue = 1 07-07 17:09:27.372 525 6119 I AmVideoDec: [0] "new VideoDec\n" 07-07 17:09:27.372 525 6119 I AmVideoDec: open dec_stat fd -1 07-07 17:09:27.372 525 6119 D VideoDecWraper: getAmVideoDec ok 07-07 17:09:27.372 525 6119 I AmVideoDec: [0] "initialize mime video/hevc secureMode is 0\n" 07-07 17:09:27.372 525 6119 I V4l2Adaptor: V4l2Adaptor::initialize flags is 0x0 07-07 17:09:27.372 525 6121 I V4l2Adaptor: V4l2Adaptor::onInit 07-07 17:09:27.372 525 6121 I android.hardware.media.omx@1.0-service: vendor.mediahal.loglevels is not set, used defaultValue = 1 07-07 17:09:27.372 525 6121 I android.hardware.media.omx@1.0-service: vendor.mediahal.loglevels is not set, used defaultValue = -710633124 07-07 17:09:27.372 525 6121 I VDA : [905887311]"open dec_stat fd -1, gloglevel 1 use_pts_id_ is 0 force dmabuf mode 0\n" 07-07 17:09:27.373 525 6121 I android.hardware.media.omx@1.0-service: vendor.mediahal.loglevels is not set, used defaultValue = 1 07-07 17:09:27.373 0 0 I [ 492.049651@3] resman: 50 acquire [decoder], preempt yes 07-07 17:09:27.373 0 0 I [ 492.049662@3] resman: 50 acquire [decoder] success 07-07 17:09:27.373 525 6121 I AmlManagePts: [0]"AmlManagePts into\n" 07-07 17:09:27.373 525 6121 I VDA : [0]"VDA Initialize flags is 0\n" 07-07 17:09:27.373 497 4231 W ResourceManagerService: Ignoring request to add new resource entry with value <= 0 07-07 17:09:27.373 525 6121 I android.hardware.media.omx@1.0-service: vendor.mediahal.loglevels is not set, used defaultValue = 1 07-07 17:09:27.373 525 6121 I VDA : [0]"Initialize codec2_mode_ is 0 stream_mode_ is 0 use_pts_id_ is 0 force dmabuf mode 0 inputbuffer_cached_ is 1 input_use_bufferpool_ is 0\n" 07-07 17:09:27.374 525 6121 I VDA : [0]"v4l2 private parms, magic:1437256755 len:5, adaptiveback:0 height:3840 width:2160\n" 07-07 17:09:27.374 525 6121 I VDA : [0]"use 8K input size\n" 07-07 17:09:27.374 5859 6115 I Kodi : 2025-07-07 17:09:27.374 T:6115 info : CDVDVideoCodecAndroidMediaCodec::Open Using codec: OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.374 5859 6115 I Kodi : 2025-07-07 17:09:27.374 T:6115 info : Creating video thread 07-07 17:09:27.374 5859 6124 I Kodi : 2025-07-07 17:09:27.374 T:6124 info : running thread: video_thread 07-07 17:09:27.375 5859 6115 I Kodi : 2025-07-07 17:09:27.375 T:6115 info : Opening stream: 1 source: 256 07-07 17:09:27.375 5859 6115 I Kodi : 2025-07-07 17:09:27.375 T:6115 info : Finding audio codec for: 86056 07-07 17:09:27.373 0 0 I [ 492.049700@3] resman: 50 acquire [codec_mm], preempt yes 07-07 17:09:27.377 0 0 I [ 492.049708@3] resman: 50 score 160 secure no 07-07 17:09:27.377 0 0 I [ 492.049712@3] resman: 50 acquire [codec_mm] success 07-07 17:09:27.377 0 0 I [ 492.051202@1] [18]: vcodec_dec decoder ffffff8106ef8000 07-07 17:09:27.377 0 0 I [ 492.051268@1] [18]: release decoder ffffff8106ef8000 07-07 17:09:27.377 0 0 I [ 492.051362@1] [18]: v4ldec has been destroyed. 07-07 17:09:27.377 0 0 I [ 492.051699@2] [19]: vcodec_dec decoder ffffff810186c000 07-07 17:09:27.377 0 0 I : [ 492.051802@2] double write mode 512 margin 5 07-07 17:09:27.377 0 0 D : [ 492.051983@2] vdec_create instance 0000000031be1922, total 1, PM: power-domain 07-07 17:09:27.377 0 0 D : [ 492.052003@2] the vdec clock on, ref cnt: 1 07-07 17:09:27.377 0 0 D : [ 492.052020@2] the clk_hevc_mux clock on, ref cnt: 1 07-07 17:09:27.377 0 0 D : [ 492.052215@2] hevc mux clock is 500000000 Hz 07-07 17:09:27.377 0 0 D : [ 492.052443@2] the pwrc-hevc power on 07-07 17:09:27.377 0 0 D : [ 492.052657@2] the pwrc-hevcb power on 07-07 17:09:27.377 0 0 I : [ 492.053064@2] dos_local_config end, id 3, is_on 1 07-07 17:09:27.377 0 0 I : [ 492.053072@2] vdec_init, dev_name:ammvdec_h265_fb_v4l, vdec_type=VDEC_TYPE_FRAME_BLOCK, format: 11 07-07 17:09:27.377 0 0 D [ 492.053716@2] [0]pdata->config=parm_v4l_codec_enable: 1;parm_v4l_buffer_margin:5;hevc_double_write_mode:512;hevc_buf_width:4096;hevc_buf_height:2304;save_buffer_mode:0;parm_v4l_canvas_mem_mode:0;parm_v4l_canvas_mem_endian:0;parm_v4l_low_latency_mode:0;parm_v4l_metadata_config_flag:49154;parm_v4l_duration:0;dv_profile:0; 07-07 17:09:27.377 0 0 D : [ 492.053764@2] [0]discard dv data 07-07 17:09:27.377 0 0 D [ 492.053772@2] [0]dv_profile: 0 07-07 17:09:27.377 0 0 D : [ 492.054285@2] decoder_bmmu_box_alloc_box, tvp_flags = 0 07-07 17:09:27.375 5180 5252 E demo-jni: next event: add@/devices/platform/vdec/ammvdec_h265_fb_v4l.0 07-07 17:09:27.376 5859 6115 I Kodi : 2025-07-07 17:09:27.376 T:6115 info : CDVDAudioCodecAndroidMediaCodec::Open() Use default handling for non encrypted stream 07-07 17:09:27.376 5859 6115 I Kodi : 2025-07-07 17:09:27.376 T:6115 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder eac3 07-07 17:09:27.377 5859 6115 I Kodi : 2025-07-07 17:09:27.377 T:6115 info : OpenStream: Allowing max Out-Of-Sync Value of 10 ms 07-07 17:09:27.377 5859 6115 I Kodi : 2025-07-07 17:09:27.377 T:6115 info : Creating audio thread 07-07 17:09:27.377 5859 6125 I Kodi : 2025-07-07 17:09:27.377 T:6125 info : running thread: CVideoPlayerAudio::Process() 07-07 17:09:27.380 0 0 D : [ 492.056981@2] [0]dynamic_buf_num_margin=5 07-07 17:09:27.381 0 0 D : [ 492.056992@2] [0]double_write_mode=512 07-07 17:09:27.381 0 0 D : [ 492.056996@2] [0]nal_skip_policy=2 07-07 17:09:27.381 0 0 D [ 492.057005@2] [0]h265: pts_unstable=0 07-07 17:09:27.381 0 0 D [ 492.057010@2] [0]h265: ver (201602101,0) decinfo: 0x0 rate=3600 07-07 17:09:27.381 0 0 D [ 492.057848@2] [0]init_mmu_fb_bufstate: mmu_4k_number = 13824 07-07 17:09:27.377 5859 6115 I Kodi : 2025-07-07 17:09:27.377 T:6115 info : Opening stream: 2 source: 256 07-07 17:09:27.377 5859 6115 I Kodi : 2025-07-07 17:09:27.377 T:6115 info : CDVDSubtitlesLibass: Using libass version 1701000 07-07 17:09:27.377 5859 6115 I Kodi : 2025-07-07 17:09:27.377 T:6115 info : CDVDSubtitlesLibass: Creating ASS library structure 07-07 17:09:27.378 5859 6115 I Kodi : 2025-07-07 17:09:27.378 T:6115 info : CDVDSubtitlesLibass: Initializing ASS Renderer 07-07 17:09:27.378 5859 6115 I Kodi : 2025-07-07 17:09:27.378 T:6115 info : CDVDSubtitlesLibass: Initializing ASS library font settings 07-07 17:09:27.408 0 0 D : [ 492.085744@4] ucode version 4.104, swap enable 1 07-07 17:09:27.408 0 0 I : [ 492.085830@4] [TEE], the fw (hevc_front) will be loaded... 07-07 17:09:27.408 0 0 I : [ 492.085834@4] [TEE], the fw (hevc_back) will be loaded... 07-07 17:09:27.407 5859 5886 D Kodi : Fontconfig error: 07-07 17:09:27.407 5859 5886 D Kodi : Cannot load default config file: No such file: (null) 07-07 17:09:27.407 5859 5886 D Kodi : Fontconfig error: Cannot load default config file: No such file: (null) 07-07 17:09:27.408 5859 6115 I Kodi : 2025-07-07 17:09:27.408 T:6115 info : CDVDSubtitlesLibass: Creating new ASS track 07-07 17:09:27.410 5180 5252 E demo-jni: next event: bind@/devices/platform/vdec/ammvdec_h265_fb_v4l.0 07-07 17:09:27.411 525 6121 I VDA : [0]"setup output format 3132564e\n" 07-07 17:09:27.411 525 6121 I VDA : IsSupportedOutputFormat v4l2_format 3132564e 07-07 17:09:27.411 525 6119 I VideoDecWraper: VideoDecWraper::postAndReplyMsg 07-07 17:09:27.411 525 6119 I VDA : V4l2Adaptor::postAndReplyMsg callerinstanceid : 30 07-07 17:09:27.411 525 6119 D AmlogicVideoDecoderAwesome2: [30]"codecInit done" 07-07 17:09:27.411 525 6126 I VDA : [0]"InitializeTask" 07-07 17:09:27.412 0 0 D : [ 492.087502@4] hevc mux clock is 800000000 Hz 07-07 17:09:27.411 525 6119 D AmlogicVideoDecoderAwesome2: [30]"mOutWidth is 4096 mOutHeight is 2304 mFlvFlag=0 mOutBufferCount is 12" 07-07 17:09:27.413 0 0 D : [ 492.087508@4] hevc video changed to 3840 x 2160 60 fps clk->800MHZ 07-07 17:09:27.413 0 0 D : [ 492.087646@4] vdec_init, vf_provider_name = vdec.h265.00 07-07 17:09:27.411 525 6126 I android.hardware.media.omx@1.0-service: (null) is not set, used defaultValue = 3 07-07 17:09:27.413 0 0 D : [ 492.087649@4] vdec_init, inst_cnt = 21, port type 0x501 07-07 17:09:27.413 0 0 I [ 492.088905@4] [19]: hevc Instance >> ffffff81029e8000 07-07 17:09:27.411 525 6119 D AmlogicVideoDecoderAwesome2: [30]"mOutBufferCount =12 mDecOutWidth 4096 mDecOutHeight 2304\n" 07-07 17:09:27.411 525 6126 I VDA : [0]"CreateInputBuffers count 3 instance_num_: 0\n" 07-07 17:09:27.411 525 6119 D AmlogicVideoDecoderAwesome2: [30]"mIsNativeBuffers =1\n" 07-07 17:09:27.411 525 6119 D AmlogicVideoDecoderAwesome2: [30]"setUp mOutPortChanged=0\n" 07-07 17:09:27.411 525 6119 I OmxComponent: STATE_DONE: OMX_StateLoaded => OMX_StateIdle : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.412 525 6119 I OmxComponent: OMX_CommandStateSet 854 Cmd 0 nParam1 0x3 07-07 17:09:27.412 525 6119 I OmxComponent: OMX-45 STATE_SET: OMX_StateIdle => OMX_StateExecuting : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.412 525 6119 V AmlogicVideoDecoderAwesome2: [30]prepare:357 > 07-07 17:09:27.412 525 6119 V AmlogicVideoDecoderAwesome2: [30]start:371 > 07-07 17:09:27.412 525 6119 D AmlogicVideoDecoderAwesome2: [30]"mVideo start\n" 07-07 17:09:27.412 525 6119 I OmxComponent: OMX-45 STATE_TRANS: OMX_StateIdle => OMX_StateExecuting : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.412 525 6119 I OmxComponent: STATE_DONE: OMX_StateIdle => OMX_StateExecuting : OMX.amlogic.hevc.decoder.awesome2 07-07 17:09:27.412 525 6127 V OMX_WorkerPeer: vendor/amlogic/common/omx/omx_framework/WorkerPeer.cpp:runWorkerStatic:155 -------------------- 07-07 17:09:27.412 525 6127 V OMX_WorkerPeer: vendor/amlogic/common/omx/omx_framework/WorkerPeer.cpp:runWorker:161 -------------------- 07-07 17:09:27.414 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:27.416 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.416 525 4836 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.418 5859 6125 I Kodi : 2025-07-07 17:09:27.418 T:6125 info : Creating audio stream (codec id: 86056, channels: 6, sample rate: 48000, no pass-through) 07-07 17:09:27.419 525 6127 V AmlogicVideoDecoderAwesome2: [30]"doCodecSpec csd_len=105\n" 07-07 17:09:27.419 525 6127 E AmlogicVideoDecoderAwesome: SPS: Main10 profile bitstream 07-07 17:09:27.420 525 6127 E AmlogicVideoDecoderAwesome: SPS: bit_depth=10,10 07-07 17:09:27.420 525 6127 D AmlogicVideoDecoderAwesome2: [30]"hevc csd size = 105, [3840:2160],bitdepth=10" 07-07 17:09:27.420 525 6127 V AmlogicVideoDecoderAwesome2: [30]codecReconfig:1192 > 07-07 17:09:27.420 525 6127 V AmlogicVideoDecoderAwesome2: [30]"codecInit hevc\n" 07-07 17:09:27.420 525 6127 D VideoDecWraper: destroy 07-07 17:09:27.420 525 6121 I VDA : [0]"Destroy:1362" 07-07 17:09:27.428 0 0 I : [ 492.105625@4] [dv_inst_map]map id 0 07-07 17:09:27.428 0 0 I : [ 492.105802@4] Creating DV mp success 07-07 17:09:27.428 0 0 I [ 492.105810@4] [19]: vidioc_decoder_streamon: dv_inst_map ctx 00000000294558dd, dv_id 0 07-07 17:09:27.428 525 6126 I VDA : [0]"----create input VIDIOC_STREAMON----\n" 07-07 17:09:27.429 525 6126 I VDA : [0]"StartDevicePoll\n" 07-07 17:09:27.429 525 6126 I VDA : [0]"DestroyTask INS=0/0, OUTS=0/0"" {IN=0/3, OUT=0/1181586063[kAtAccelerator(1375641427) kAtDevice(917971143) kAtClient(-2098973468)]}" 07-07 17:09:27.429 525 6126 I VDA : [0]"StopDevicePoll\n" 07-07 17:09:27.432 525 6126 I VDA : [0]"StopInputStream input_streamon 1, input_buffer_map_.size 3\n" 07-07 17:09:27.435 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:27.437 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.437 525 6126 I VDA : [0]"----stop input VIDIOC_STREAMOFF----\n" 07-07 17:09:27.437 525 6126 I AmlManagePts: [0]"resetPtsInfo into\n" 07-07 17:09:27.437 525 6126 I AmlManagePts: [0]"resetOutPutInfo into\n" 07-07 17:09:27.437 525 6126 I VDA : [0]"DestroyInputBuffers:4376" 07-07 17:09:27.437 525 4836 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.443 0 0 I [ 492.110020@0] [19]: seek force reset to drop es frames. 07-07 17:09:27.443 0 0 I [ 492.110044@0] [19]: reset mode: 1, es frames buffering: 0 07-07 17:09:27.443 0 0 D : [ 492.110050@0] vdec_v4l2_reset 1 07-07 17:09:27.443 0 0 D [ 492.111343@4] [0]h265: pts_unstable=0 07-07 17:09:27.443 0 0 D [ 492.111351@4] [0]h265: ver (201602101,0) decinfo: 0x0 rate=3600 07-07 17:09:27.443 0 0 D : [ 492.112502@3] [0]reset 07-07 17:09:27.443 525 6126 I VDA : [0]"DestroyOutputBuffers:4419" 07-07 17:09:27.444 0 0 I [ 492.121551@1] [19]: release decoder ffffff810186c000 07-07 17:09:27.444 0 0 I : [ 492.121643@1] vdec_frame_rate_uevent 0 07-07 17:09:27.444 0 0 I [ 492.122038@1] vframe_rate_uevent: sent uevent FRAME_RATE_HINT=0 07-07 17:09:27.443 525 6126 I VDA : [0]"DestroyTask:3550" 07-07 17:09:27.443 525 6121 I AmlManagePts: [0]"~AmlManagePts into\n" 07-07 17:09:27.443 525 6121 I VDA : [0]"~V4L2VideoDecodeAccelerator:591" 07-07 17:09:27.444 5180 5252 E demo-jni: next event: change@/devices/virtual/framerate_adapter/framerate_dev 07-07 17:09:27.444 355 379 I SystemControl: [/UEventObserver.cpp, HDMITxUenventThreadLoop, 447] HDMI TX switch_name:0, switch_state: 07-07 17:09:27.444 355 379 D SystemControl: [/FrameRateAutoAdaption.cpp, isFrameRateOn, 156] --isFrameRateOn 1 07-07 17:09:27.444 355 379 I SystemControl: [/FrameRateAutoAdaption.cpp, isFrameRateOn, 168] hdmi frame rate is on 07-07 17:09:27.446 5180 5252 E demo-jni: next event: unbind@/devices/platform/vdec/ammvdec_h265_fb_v4l.0 07-07 17:09:27.446 5180 5252 E demo-jni: next event: remove@/devices/platform/vdec/ammvdec_h265_fb_v4l.0 07-07 17:09:27.448 525 6127 D VideoDecWraper: initialize:mime:video/hevc 07-07 17:09:27.448 525 6127 I AmVideoDec: [0] "initialize mime video/hevc secureMode is 0\n" 07-07 17:09:27.448 525 6127 I V4l2Adaptor: V4l2Adaptor::initialize flags is 0x0 07-07 17:09:27.448 525 6129 I V4l2Adaptor: V4l2Adaptor::onInit 07-07 17:09:27.448 525 6129 I android.hardware.media.omx@1.0-service: vendor.mediahal.loglevels is not set, used defaultValue = 1 07-07 17:09:27.448 525 6129 I android.hardware.media.omx@1.0-service: vendor.mediahal.loglevels is not set, used defaultValue = -710633124 07-07 17:09:27.448 525 6129 I VDA : [0]"open dec_stat fd -1, gloglevel 1 use_pts_id_ is 0 force dmabuf mode 0\n" 07-07 17:09:27.448 525 6129 I android.hardware.media.omx@1.0-service: vendor.mediahal.loglevels is not set, used defaultValue = 1 07-07 17:09:27.448 525 6129 I AmlManagePts: [0]"AmlManagePts into\n" 07-07 17:09:27.448 525 6129 I VDA : [0]"VDA Initialize flags is 0\n" 07-07 17:09:27.449 0 0 D : [ 492.123341@4] vdec_disable_DMC input->target= 0x1 07-07 17:09:27.448 525 6129 I android.hardware.media.omx@1.0-service: vendor.mediahal.loglevels is not set, used defaultValue = 1 07-07 17:09:27.448 525 6129 I VDA : [0]"Initialize codec2_mode_ is 0 stream_mode_ is 0 use_pts_id_ is 0 force dmabuf mode 0 inputbuffer_cached_ is 1 input_use_bufferpool_ is 0\n" 07-07 17:09:27.449 0 0 D : [ 492.124370@4] vdec_release instance 0000000031be1922, total 1 07-07 17:09:27.449 0 0 I : [ 492.124725@4] dos_local_config end, id 3, is_on 0 07-07 17:09:27.449 0 0 D : [ 492.124739@4] the clk_hevc_mux clock off, ref cnt: 0 07-07 17:09:27.449 0 0 D : [ 492.124756@4] the pwrc-hevc power off 07-07 17:09:27.449 0 0 D : [ 492.124765@4] the pwrc-hevcb power off 07-07 17:09:27.449 0 0 D : [ 492.124771@4] the vdec clock off, ref cnt: 0 07-07 17:09:27.449 0 0 I [ 492.124967@4] [19]: v4ldec has been destroyed. 07-07 17:09:27.449 0 0 I : [ 492.124972@4] dv_inst_unmap 0 07-07 17:09:27.449 0 0 I : [ 492.125014@4] dv_inst_unmap 0 OK 07-07 17:09:27.449 0 0 I [ 492.125017@4] [19]: aml_v4l_ctx_release: dv_inst_unmap ctx 00000000294558dd, dv_id 0 07-07 17:09:27.452 0 0 I [ 492.127781@4] [20]: vcodec_dec decoder ffffff8100e60000 07-07 17:09:27.452 0 0 I [ 492.127856@4] [20]: release decoder ffffff8100e60000 07-07 17:09:27.452 0 0 I [ 492.129986@0] [20]: v4ldec has been destroyed. 07-07 17:09:27.452 525 6129 I VDA : [0]"v4l2 private parms, magic:1437256755 len:5, adaptiveback:0 height:3840 width:2160\n" 07-07 17:09:27.453 525 6129 I VDA : [0]"use 8K input size\n" 07-07 17:09:27.456 0 0 I [ 492.130421@0] [21]: vcodec_dec decoder ffffff8043e7c000 07-07 17:09:27.456 0 0 I : [ 492.130581@0] double write mode 3 margin 5 07-07 17:09:27.456 0 0 D : [ 492.130757@0] vdec_create instance 0000000086a3962d, total 1, PM: power-domain 07-07 17:09:27.456 0 0 D : [ 492.130775@0] the vdec clock on, ref cnt: 1 07-07 17:09:27.456 0 0 D : [ 492.130806@0] the clk_hevc_mux clock on, ref cnt: 1 07-07 17:09:27.456 0 0 D : [ 492.131004@0] hevc mux clock is 500000000 Hz 07-07 17:09:27.456 0 0 D : [ 492.131246@0] the pwrc-hevc power on 07-07 17:09:27.456 0 0 D : [ 492.131460@0] the pwrc-hevcb power on 07-07 17:09:27.456 0 0 I : [ 492.131850@0] dos_local_config end, id 3, is_on 1 07-07 17:09:27.456 0 0 I : [ 492.131859@0] vdec_init, dev_name:ammvdec_h265_fb_v4l, vdec_type=VDEC_TYPE_FRAME_BLOCK, format: 11 07-07 17:09:27.456 0 0 D [ 492.132509@0] [0]pdata->config=parm_v4l_codec_enable: 1;parm_v4l_buffer_margin:5;hevc_double_write_mode:3;hevc_buf_width:4096;hevc_buf_height:2304;save_buffer_mode:0;parm_v4l_canvas_mem_mode:0;parm_v4l_canvas_mem_endian:0;parm_v4l_low_latency_mode:0;parm_v4l_metadata_config_flag:49154;parm_v4l_duration:0;dv_profile:0; 07-07 17:09:27.456 0 0 D : [ 492.132559@0] [0]discard dv data 07-07 17:09:27.456 0 0 D [ 492.132567@0] [0]dv_profile: 0 07-07 17:09:27.456 0 0 D : [ 492.133097@0] decoder_bmmu_box_alloc_box, tvp_flags = 0 07-07 17:09:27.454 5180 5252 E demo-jni: next event: add@/devices/platform/vdec/ammvdec_h265_fb_v4l.0 07-07 17:09:27.460 0 0 D : [ 492.136512@0] [0]dynamic_buf_num_margin=5 07-07 17:09:27.460 0 0 D : [ 492.136538@0] [0]double_write_mode=3 07-07 17:09:27.460 0 0 D : [ 492.136543@0] [0]nal_skip_policy=2 07-07 17:09:27.460 0 0 D [ 492.136552@0] [0]h265: pts_unstable=0 07-07 17:09:27.460 0 0 D [ 492.136557@0] [0]h265: ver (201602101,0) decinfo: 0x0 rate=3600 07-07 17:09:27.464 0 0 D [ 492.139072@0] [0]init_mmu_fb_bufstate: mmu_4k_number = 13824 07-07 17:09:27.472 5859 5909 I Kodi : 2025-07-07 17:09:27.472 T:5909 info : CActiveAESink::OpenSink - initialize sink 07-07 17:09:27.473 5859 5909 D AudioTrack: stop(140): called with 605085 frames delivered 07-07 17:09:27.477 5859 5909 I Kodi : 2025-07-07 17:09:27.477 T:5909 info : Trying to open: samplerate: 48000, channelMask: 12, encoding: 4 07-07 17:09:27.478 5859 5909 I Kodi : 2025-07-07 17:09:27.478 T:5909 info : Minimum size we need for stream: 32800 Bytes 07-07 17:09:27.478 5859 5909 I Kodi : 2025-07-07 17:09:27.478 T:5909 info : Audiotrack buffer params are: period time = 42.708 ms, period size = 16400 bytes, num periods = 4 07-07 17:09:27.478 5859 5909 I Kodi : 2025-07-07 17:09:27.478 T:5909 info : Created Audiotrackbuffer with playing time of 170.833333 ms min buffer size: 65600 bytes 07-07 17:09:27.482 5180 5252 E demo-jni: next event: bind@/devices/platform/vdec/ammvdec_h265_fb_v4l.0 07-07 17:09:27.483 405 4998 W AudioFlinger: createTrack_l(): mismatch between requested flags (00000008) and output flags (00000002) 07-07 17:09:27.489 0 0 D : [ 492.159240@3] ucode version 4.104, swap enable 1 07-07 17:09:27.489 0 0 I : [ 492.159387@3] [TEE], the fw (hevc_front) will be loaded... 07-07 17:09:27.489 0 0 I : [ 492.159398@3] [TEE], the fw (hevc_back) will be loaded... 07-07 17:09:27.489 0 0 D : [ 492.159676@3] hevc mux clock is 800000000 Hz 07-07 17:09:27.489 0 0 D : [ 492.159685@3] hevc video changed to 3840 x 2160 60 fps clk->800MHZ 07-07 17:09:27.489 0 0 D : [ 492.159930@3] vdec_init, vf_provider_name = vdec.h265.00 07-07 17:09:27.489 0 0 D : [ 492.159940@3] vdec_init, inst_cnt = 22, port type 0x501 07-07 17:09:27.489 0 0 I [ 492.164828@1] [21]: hevc Instance >> ffffff8100e3e000 07-07 17:09:27.489 0 0 I : [ 492.165482@0] vdec_frame_rate_uevent 0 07-07 17:09:27.484 357 357 I audio_hw_primary: audiohal_get_latency stream:0xf2b00010 frames:3072 out->config.rate:48000 whole_latency:64, alsa_latency:64, 07-07 17:09:27.484 405 4998 D AudioFlinger: Client defaulted notificationFrames to 4100 for frameCount 8200 07-07 17:09:27.487 525 6129 I VDA : [0]"setup output format 3132564e\n" 07-07 17:09:27.487 525 6129 I VDA : IsSupportedOutputFormat v4l2_format 3132564e 07-07 17:09:27.487 525 6133 I VDA : [0]"InitializeTask" 07-07 17:09:27.487 525 6127 I VideoDecWraper: VideoDecWraper::postAndReplyMsg 07-07 17:09:27.487 525 6133 I android.hardware.media.omx@1.0-service: (null) is not set, used defaultValue = 3 07-07 17:09:27.487 525 6133 I VDA : [0]"CreateInputBuffers count 3 instance_num_: 0\n" 07-07 17:09:27.487 525 6127 I VDA : V4l2Adaptor::postAndReplyMsg callerinstanceid : 30 07-07 17:09:27.487 525 6127 I VDA : [0]"sendMessagetoDecoder set DECODER_INPUTBUFFER_NO" 07-07 17:09:27.488 5180 5252 E demo-jni: next event: change@/devices/virtual/framerate_adapter/framerate_dev 07-07 17:09:27.489 355 379 I SystemControl: [/UEventObserver.cpp, HDMITxUenventThreadLoop, 447] HDMI TX switch_name:0, switch_state: 07-07 17:09:27.489 355 379 D SystemControl: [/FrameRateAutoAdaption.cpp, isFrameRateOn, 156] --isFrameRateOn 1 07-07 17:09:27.489 355 379 I SystemControl: [/FrameRateAutoAdaption.cpp, isFrameRateOn, 168] hdmi frame rate is on 07-07 17:09:27.491 405 4998 D AF::TrackHandle: OpPlayAudio: track:122 usage:1 not muted 07-07 17:09:27.492 525 6127 D AmlogicVideoDecoderAwesome2: [30]"codecInit done" 07-07 17:09:27.492 0 0 I [ 492.169520@0] vframe_rate_uevent: sent uevent FRAME_RATE_HINT=0 07-07 17:09:27.492 0 0 I [ 492.169538@0] [21]: set duration: 0 07-07 17:09:27.492 357 357 I audio_hw_primary: audiohal_get_latency stream:0xf2b00010 frames:3072 out->config.rate:48000 whole_latency:64, alsa_latency:64, 07-07 17:09:27.493 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:27.495 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.496 525 4836 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.497 5859 5909 I Kodi : 2025-07-07 17:09:27.497 T:5909 info : CAESinkAUDIOTRACK::Initializing with: m_sampleRate: 48000 format: AE_FMT_FLOAT (AE) method: PCM stream-type: PCM-STREAM min_buffer_size: 65600 m_frames: 2050 m_frameSize: 8 channels: 2 07-07 17:09:27.502 525 6133 I VDA : [0]"----create input VIDIOC_STREAMON----\n" 07-07 17:09:27.502 525 6133 I VDA : [0]"StartDevicePoll\n" 07-07 17:09:27.504 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:27.506 0 0 I : [ 492.179993@4] [dv_inst_map]map id 0 07-07 17:09:27.506 0 0 I : [ 492.180157@4] Creating DV mp success 07-07 17:09:27.506 0 0 I [ 492.180164@4] [21]: vidioc_decoder_streamon: dv_inst_map ctx 0000000058cca481, dv_id 0 07-07 17:09:27.506 0 0 E : [ 492.180716@4] mmu box NULL !!! 07-07 17:09:27.506 0 0 E : [ 492.180722@4] mmu box NULL !!! 07-07 17:09:27.506 0 0 D : [ 492.180727@4] [0]vh265 cached=0 need_size=16384 speed= 48 ms 07-07 17:09:27.506 0 0 D : [ 492.181063@0] set ucode parse 07-07 17:09:27.506 0 0 W : [ 492.181076@0] hevc get comp info: 64 327680 2160 07-07 17:09:27.506 0 0 D : [ 492.181081@0] margin = 5, sps_max_dec_pic_buffering_minus1_0 = 5 07-07 17:09:27.506 0 0 I [ 492.181095@0] [21]: Parse from ucode, visible(3840 x 2160), coded(3840 x 2176), scan:P 07-07 17:09:27.506 0 0 I [ 492.181413@2] [21]: Picture buffer count: dec:8, vpp:0, ge2d:0, margin:5, total:13 07-07 17:09:27.506 0 0 I [ 492.181432@2] [21]: changes: 1 07-07 17:09:27.506 525 6133 I VDA : [0]"got resolution change event.\n" 07-07 17:09:27.506 525 6133 I VDA : [0]"VIDIOC_G_FMT format.fmt.pix.width 3840\n" 07-07 17:09:27.506 525 6133 I VDA : [0]"[GetVisibleSize] visible width 3840, height 2160\n" 07-07 17:09:27.506 525 6133 I VDA : [0]"[NeedResolutionChange] dpb(0->13),size(0x0->3840x2160)" 07-07 17:09:27.506 525 6133 I VDA : [0]"StopDevicePoll\n" 07-07 17:09:27.508 525 6133 I VDA : [0]"DestroyOutputBuffers:4419" 07-07 17:09:27.508 525 6133 I VDA : [0]"[StartResolutionChange] into resolution" 07-07 17:09:27.508 525 6133 I AmlManagePts: [0]"resetOutPutInfo into\n" 07-07 17:09:27.508 525 6133 I VDA : [0]"VIDIOC_G_FMT format.fmt.pix.width 3840\n" 07-07 17:09:27.509 525 6133 I VDA : [0]"[GetVisibleSize] visible width 3840, height 2160\n" 07-07 17:09:27.509 525 6133 V android.hardware.media.omx@1.0-service: [0707/170927.509051:VERBOSE1:v4l2_video_decode_accelerator.cc(3898)] CheckInterlaced(): 07-07 17:09:27.509 525 6133 I VDA : [0]"CheckInterlaced: format.fmt.pix.field 1\n" 07-07 17:09:27.509 525 6133 V android.hardware.media.omx@1.0-service: [0707/170927.509205:VERBOSE1:v4l2_video_decode_accelerator.cc(3922)] NotifyEvent(): 07-07 17:09:27.509 525 6133 D VideoDecWraper: event 2, param 0x0, paramsize:0 07-07 17:09:27.509 525 6133 D AmlogicVideoDecoderAwesome2: NotifyEvent:event:2 07-07 17:09:27.509 525 6133 D AmlogicVideoDecoderAwesome2: is progressive 07-07 17:09:27.509 525 6133 I AmlManagePts: [0]"setInterlace set interlace[0]\n" 07-07 17:09:27.509 525 6133 I VDA : [0]"output_planes_count_ 1, format.fmt.pix.width 3840\n" 07-07 17:09:27.509 525 6133 I VDA : [0]"output_dpb_size_ 13, output buffer 13, coded_size_ 3840 x 2176\n" 07-07 17:09:27.509 525 6133 I VDA : [0]"CreateOutputBuffers decoder_state_ change to kAwaitingPictureBuffers\n" 07-07 17:09:27.509 525 6133 I VDA : [0]"StartDevicePoll\n" 07-07 17:09:27.510 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.511 525 4836 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.509 525 6129 I V4l2Adaptor: V4l2Adaptor::ProvidePictureBuffers 13 07-07 17:09:27.511 525 6129 D VideoDecWraper: providePictureBuffers:minNumBuffers:13 07-07 17:09:27.511 525 6129 D AmlogicVideoDecoderAwesome2: [30]"ProvidePictureBuffers minNumBuffers:13, size(3840x2160) alignsize(3840x2176)" 07-07 17:09:27.511 525 6129 I AmlogicVideoDecoderAwesome2: [30]"mActualOutWidth 4096, mActualOutHeight 2304, width 3840, height 2160," "portDef.nBufferCountMin 8, minNumBuffersWithoutSurfaceHold 9\n" 07-07 17:09:27.511 525 6129 D AmlogicVideoDecoderAwesome2: [30]"================OMX_EventPortSettingsChanged: 0xf24c0010, dw_realloc 1" 07-07 17:09:27.511 525 6129 D AmlogicVideoDecoderAwesome2: [30]"width 4096:3840, height 2304:2160, buffer count 9:13\n" 07-07 17:09:27.511 525 4836 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.511 525 4836 V OmxVideoDecoder: nWidth=3840,nHeight=2160, nMinbufs=9 07-07 17:09:27.511 525 4836 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 3840, height 2160 nBufferCountMin:9 07-07 17:09:27.511 525 5275 D OmxVideoDecoder: getConfig 3251 0x700000f 07-07 17:09:27.511 525 5275 D OmxVideoDecoder: getConfig 3254 nIndex 0x700000f 07-07 17:09:27.511 525 5275 I AmlogicVideoDecoderAwesome2: [30]"getAndroidNativeBufferCrop [3840 x 2160] \n" 07-07 17:09:27.512 525 5275 D OmxVideoDecoder: getConfig OMX_IndexConfigCommonOutputCrop pRect->nWidth=3840, pRect->nHeight=2160 07-07 17:09:27.512 525 525 D OmxVideoDecoder: setConfig 3393 0x7f00000c 07-07 17:09:27.512 525 525 D OmxVideoDecoder: getConfig 3251 0x7f00000c 07-07 17:09:27.513 525 525 D OmxVideoDecoder: getConfig 3251 0x7f00000c 07-07 17:09:27.513 525 525 E OMXNodeInstance: getConfig(0xf5dc0cb0:amlogic.hevc.decoder.awesome2, ??(0x7f00000c)) ERROR: UnsupportedSetting(0x80001019) 07-07 17:09:27.513 525 525 D OmxVideoDecoder: getConfig 3251 0x7f000009 07-07 17:09:27.513 525 525 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.513 525 525 V OmxVideoDecoder: VendorExt: getConfig: index=0 (4k-osd) 07-07 17:09:27.513 525 525 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.513 525 525 V OmxVideoDecoder: VendorExt: getConfig: index=1 (video-trickmode) 07-07 17:09:27.513 525 6133 D VDA : [0]"DecodeBufferTask DecodeBufferTask early out: state=kAwaitingPictureBuffers\n" 07-07 17:09:27.514 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.514 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=2 (unstable-pts) 07-07 17:09:27.514 525 525 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.514 357 2271 I audio_hw_primary: audiohal_get_latency stream:0xf2b00010 frames:3072 out->config.rate:48000 whole_latency:64, alsa_latency:64, 07-07 17:09:27.515 525 525 V OmxVideoDecoder: VendorExt: getConfig: index=3 (player-id) 07-07 17:09:27.515 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.515 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=4 (isudp) 07-07 17:09:27.515 649 4459 I system_server: oneway function results will be dropped but finished with status OK and parcel size 4 07-07 17:09:27.516 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.516 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=5 (realframerate) 07-07 17:09:27.516 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.516 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=5 (realframerate) 07-07 17:09:27.516 525 525 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.516 525 525 V OmxVideoDecoder: VendorExt: getConfig: index=6 (Bitrate) 07-07 17:09:27.517 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.517 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=7 (is-mvc) 07-07 17:09:27.517 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.517 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=8 (is-flv) 07-07 17:09:27.517 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.517 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=9 (dvconfig-box) 07-07 17:09:27.517 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.517 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=10 (profile-level) 07-07 17:09:27.517 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.517 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=10 (profile-level) 07-07 17:09:27.518 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.518 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=11 (nrdp) 07-07 17:09:27.518 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.518 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=12 (low-latency) 07-07 17:09:27.518 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.518 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=13 (save-mem) 07-07 17:09:27.518 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.518 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=14 (videoLayer) 07-07 17:09:27.518 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.518 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=15 (renderByOmx) 07-07 17:09:27.518 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.518 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=16 (tunerhal) 07-07 17:09:27.518 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.519 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=16 (tunerhal) 07-07 17:09:27.519 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.519 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=17 (tunerhal.passthrough) 07-07 17:09:27.519 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.519 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=17 (tunerhal.passthrough) 07-07 17:09:27.519 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.519 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=18 (tunerhal.passthrough) 07-07 17:09:27.519 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.519 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=19 (VideoQos) 07-07 17:09:27.519 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.519 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=20 (rotation-angle) 07-07 17:09:27.519 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.520 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=21 (McodecReport) 07-07 17:09:27.520 525 5559 D OmxVideoDecoder: mMcodecReport=0 mPeer.get=0xf24c0010 07-07 17:09:27.520 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.520 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=22 (START.low-latency) 07-07 17:09:27.520 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.520 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=23 (avc-size-level) 07-07 17:09:27.520 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.520 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=24 (avc-4k-mmu) 07-07 17:09:27.520 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.520 525 6119 I OmxComponent: OMX_CommandPortDisable 854 Cmd 0x2 nParam1 0x1 07-07 17:09:27.520 525 6119 V AmlogicVideoDecoderAwesome2: [30]disablePort:1271 > 07-07 17:09:27.520 525 6119 D AmlogicVideoDecoderAwesome2: [30]"disablePort output" 07-07 17:09:27.520 525 6119 V AmlogicVideoDecoderAwesome2: [30]flushOutput:1181 > 07-07 17:09:27.520 525 6119 D AmlogicVideoDecoderAwesome2: [30]"__flushOutput__fillBufferDone:0 0xf79c0e50\n" 07-07 17:09:27.520 525 6119 D AmlogicVideoDecoderAwesome2: [30]"__flushOutput__fillBufferDone:1 0xf79c0610\n" 07-07 17:09:27.520 525 6119 D AmlogicVideoDecoderAwesome2: [30]"__flushOutput__fillBufferDone:2 0xf79c27d0\n" 07-07 17:09:27.520 525 6119 D AmlogicVideoDecoderAwesome2: [30]"__flushOutput__fillBufferDone:3 0xf79c2590\n" 07-07 17:09:27.520 525 6119 V AmlogicVideoDecoderAwesome2: [30]flushOutput:1267 > 07-07 17:09:27.523 525 6127 I AmlogicVideoDecoderAwesome2: [30]"check_stream_info mInPutFrameCount 3\n" 07-07 17:09:27.523 525 6127 D AmlogicVideoDecoderAwesome2: [30]"=11, =3840, =2160, =4096, =2304, =0, =83000, =42000, =5602, =438, =434" 07-07 17:09:27.523 525 6133 D VDA : [0]"DecodeBufferTask DecodeBufferTask early out: state=kAwaitingPictureBuffers\n" 07-07 17:09:27.524 779 779 V AvrcpMediaPlayerWrapper: onPlaybackStateChanged(): org.xbmc.kodi : PlaybackState {state=3, position=0, buffered position=0, speed=1.0, updated=492193, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:27.525 525 6119 I OmxComponent: OMX_CommandPortEnable 854 Cmd 0x3 nParam1 0x1 07-07 17:09:27.525 525 6119 V AmlogicVideoDecoderAwesome2: [30]enablePort:1285 > 07-07 17:09:27.525 525 6119 D AmlogicVideoDecoderAwesome2: [30]"enablePort output" 07-07 17:09:27.525 525 6119 D AmlogicVideoDecoderAwesome2: [30]"enablePort: output buffer:mWidth:3840, mHeight:2160,mOutBufferCount=12" 07-07 17:09:27.525 525 6119 D AmlogicVideoDecoderAwesome2: [30]"enablePort: minbufNum:9, actualbufNum=13 nBufferCountMin:9 nBufferCountActual:12" 07-07 17:09:27.525 525 6119 D AmlogicVideoDecoderAwesome2: [30]"enablePort output" 07-07 17:09:27.525 525 4836 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.525 525 4836 V OmxVideoDecoder: nWidth=3840,nHeight=2160, nMinbufs=9 07-07 17:09:27.525 525 4836 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 3840, height 2160 nBufferCountMin:9 07-07 17:09:27.525 525 5275 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.525 525 5275 V OmxVideoDecoder: nWidth=3840,nHeight=2160, nMinbufs=9 07-07 17:09:27.525 525 5275 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 3840, height 2160 nBufferCountMin:9 07-07 17:09:27.526 525 4836 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.AndroidNativeBufferConsumerUsage" 07-07 17:09:27.526 525 4836 D OmxVideoDecoder: setParameter 1822 0x7f00000b 07-07 17:09:27.526 525 4836 D OmxVideoDecoder: OMX_GoogleAndroidIndexAndroidNativeBufferConsumerUsage 0x900 07-07 17:09:27.526 525 5275 D OmxComponent: getExtensionIndex 1245 name="OMX.google.android.index.getAndroidNativeBufferUsage" 07-07 17:09:27.526 525 5275 D AmlogicVideoDecoderAwesome2: [30]"vendor.media.omx2.dw is not set used def=0\n" 07-07 17:09:27.526 525 5275 D OmxVideoDecoder: Get NativeBufferUsage 0x408033 07-07 17:09:27.526 5859 6118 D SurfaceUtils: set up nativeWindow 0xe4aa5c98 for 3840x2160, color 0x11, rotation 0, usage 0x40a933 07-07 17:09:27.526 525 5275 D OmxVideoDecoder: setParameter 1822 0x2000001 07-07 17:09:27.526 525 5275 D OmxVideoDecoder: adaptiveplayback default set w*d: 4096*2304 07-07 17:09:27.526 525 5275 D OmxVideoDecoder: media.omx.display_mode=3 07-07 17:09:27.526 525 5275 D OmxVideoDecoder: choose mVideoUsedByOmx=0, mVideoUsedByThis=0, mAmVideoUsed=0 07-07 17:09:27.526 525 5275 D OmxVideoDecoder: mDisplayMode = DISPLAY_MODE_V4LVIDEO 07-07 17:09:27.526 525 5275 D OmxVideoDecoder: choose mDisplayMode=3 07-07 17:09:27.526 525 5275 D OmxVideoDecoder: DecoderWidthFromExtractor[3840] * mDecoderHeightFromExtractor[2160] > buffer_maxwidth[1920] * buffer_maxheight[1088] 07-07 17:09:27.527 525 5275 D OmxVideoDecoder: setParameter nPortIndex 1 nFrameWidth 4096 nFrameHeight 2304 nSliceHeight 2304 07-07 17:09:27.527 525 5275 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.527 525 5275 V OmxVideoDecoder: nWidth=3840,nHeight=2160, nMinbufs=9 07-07 17:09:27.527 779 779 V AvrcpMediaPlayerWrapper: trySendMediaUpdate(): Metadata has been updated for org.xbmc.kodi 07-07 17:09:27.527 779 779 D AvrcpMediaPlayerList: sendMediaUpdate 07-07 17:09:27.527 779 779 I AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue 07-07 17:09:27.527 779 779 D AvrcpMediaPlayerList: sendMediaUpdate state=PlaybackState {state=3, position=0, buffered position=0, speed=1.0, updated=492198, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:27.527 779 779 D AvrcpTargetService: onMediaUpdated: track_changed=false state=false queue=false 07-07 17:09:27.527 779 779 D AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=false queue=false 07-07 17:09:27.527 779 779 D AvrcpTargetJni: sendMediaUpdateNative 07-07 17:09:27.527 779 779 I bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=0 : queue=0 07-07 17:09:27.528 525 5275 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.528 525 4836 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.529 525 4836 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.529 525 5559 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.529 525 5559 I chatty : uid=1046(mediacodec) HwBinder:525_C identical 1 line 07-07 17:09:27.529 525 5559 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.530 525 4836 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.530 525 4836 I chatty : uid=1046(mediacodec) HwBinder:525_5 identical 4 lines 07-07 17:09:27.530 525 4836 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.531 525 5559 E OMX_AmlogicPeerBufferWrapper: setVideoNativeMetadata 07-07 17:09:27.532 525 4836 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.532 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBufsNum==for port changed=====:13" 07-07 17:09:27.532 525 6119 I AmVideoDec: [0] "setupOutputBufferNum 13\n" 07-07 17:09:27.532 525 6119 I V4l2Adaptor: assignPictureBuffers numOutputBuffers 13 07-07 17:09:27.532 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:0, 0x0, fd:29" 07-07 17:09:27.533 525 6119 I VideoDecWraper: importBufferForPicture:0 07-07 17:09:27.533 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:27.533 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 0, dmabufFd 20, metaFd -1 07-07 17:09:27.533 525 6129 I V4l2Adaptor: onAssignPictureBuffers numOutputBuffers 13 07-07 17:09:27.533 525 6129 I VDA : [0]"AssignPictureBuffers buffer_count=13" 07-07 17:09:27.533 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 0, dmabufFd 20, metaFd -1, plane size 1 07-07 17:09:27.533 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:27.533 525 6133 I VDA : [0]"AssignPictureBuffersTask decoder_state_=kAwaitingPictureBuffers" 07-07 17:09:27.533 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=20, index=0\n"" {IN=1/3, OUT=0/1181586064[kAtAccelerator(1375641428) kAtDevice(917971143) kAtClient(-2098973468)]}" 07-07 17:09:27.533 525 6133 I VDA : [0]"----enqueue output STREAMON----\n" 07-07 17:09:27.536 0 0 I [ 492.212114@2] dim: reg:use[1]ms 07-07 17:09:27.536 0 0 I [ 492.212127@2] dim: create:ch[2],tmode[3] 07-07 17:09:27.536 0 0 I [ 492.212130@2] dim: out:0x80000000 07-07 17:09:27.536 0 0 I [ 492.212309@2] [21]: vpp_wrapper init bsize:16, di(i:16, o:18), wkm:83, bm:0, fmt:80000000, drm:0, prog:1, byp:0, local:1, NR:1 07-07 17:09:27.536 0 0 I [ 492.212314@2] [21]: vpp_wrapper instance count: 1 07-07 17:09:27.536 0 0 D : [ 492.212408@2] decoder_bmmu_box_alloc_box, tvp_flags = 0 07-07 17:09:27.536 0 0 I [ 492.212421@2] [21]: box init, bmmu: ffffff8053e49c00, mmu: ffffff8053e49e00, mmu1: ffffff8053e49400 07-07 17:09:27.536 525 6133 I VDA : [0]"DecodeBufferTask decoder_state_ = kPictureBuffersReady -> kDecoding" 07-07 17:09:27.540 0 0 D : [ 492.215611@0] [0]Bit depth luma = 10 07-07 17:09:27.540 0 0 D : [ 492.215621@0] [0]Bit depth chroma = 10 07-07 17:09:27.540 0 0 E [ 492.215625@0] vh265_isr_thread_fn: 12303, init_decode_head_hw_fb() not implemented yet 07-07 17:09:27.540 0 0 D : [ 492.215629@0] [0]hevc->performance_profile 0 07-07 17:09:27.540 0 0 D : [ 492.215643@0] [0]set pic_list_init_flag 1 07-07 17:09:27.540 0 0 D : [ 492.217778@1] [0]init_buf_spec2 3840 2160 07-07 17:09:27.540 0 0 D : [ 492.217788@1] [0]set pic_list_init_flag to 2 07-07 17:09:27.540 0 0 D : [ 492.217831@0] [0]set pic_list_init_flag to 3 07-07 17:09:27.540 0 0 D : [ 492.217837@0] [vdec_kpi][vh265_isr_thread_fn] First I frame coming. 07-07 17:09:27.540 0 0 D : [ 492.217841@0] [0][0]alloc mv buffer 07-07 17:09:27.541 525 4836 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.542 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:1, 0x0, fd:33" 07-07 17:09:27.542 525 6119 I VideoDecWraper: importBufferForPicture:1 07-07 17:09:27.542 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:27.542 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 1, dmabufFd 24, metaFd -1 07-07 17:09:27.542 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 1, dmabufFd 24, metaFd -1, plane size 1 07-07 17:09:27.542 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:27.542 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=24, index=1\n"" {IN=0/3, OUT=0/1181586065[kAtAccelerator(1375641428) kAtDevice(917971144) kAtClient(-2098973468)]}" 07-07 17:09:27.544 0 0 D : [ 492.219009@2] margin = 5, sps_max_dec_pic_buffering_minus1_0 = 5 07-07 17:09:27.543 525 4836 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.544 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:2, 0x0, fd:37" 07-07 17:09:27.544 525 6119 I VideoDecWraper: importBufferForPicture:2 07-07 17:09:27.544 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:27.544 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 2, dmabufFd 25, metaFd -1 07-07 17:09:27.544 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 2, dmabufFd 25, metaFd -1, plane size 1 07-07 17:09:27.544 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:27.545 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=25, index=2\n"" {IN=1/3, OUT=0/1181586066[kAtAccelerator(1375641428) kAtDevice(917971145) kAtClient(-2098973468)]}" 07-07 17:09:27.552 0 0 D [ 492.228775@0] [0]VH265: output first frame 07-07 17:09:27.552 0 0 I [ 492.228820@0] [21]: H265 set HDR infos 07-07 17:09:27.552 0 0 D : [ 492.228834@0] [vdec_kpi][post_video_frame] First I frame decoded. 07-07 17:09:27.552 0 0 I [ 492.228917@2] [21]: parms status: 7 07-07 17:09:27.551 525 6129 D VideoDecWraper: onUpdateDecInfo info 0xf60809b0, size 200 07-07 17:09:27.552 525 4836 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.552 525 4836 V OmxVideoDecoder: nWidth=3840,nHeight=2160, nMinbufs=0 07-07 17:09:27.552 525 4836 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 3840, height 2160 nBufferCountMin:9 07-07 17:09:27.552 525 5559 D OmxVideoDecoder: getConfig 3251 0x700000f 07-07 17:09:27.552 525 5559 D OmxVideoDecoder: getConfig 3254 nIndex 0x700000f 07-07 17:09:27.552 525 5559 I AmlogicVideoDecoderAwesome2: [30]"getAndroidNativeBufferCrop [4096 x 2304] \n" 07-07 17:09:27.552 525 5559 D OmxVideoDecoder: getConfig OMX_IndexConfigCommonOutputCrop pRect->nWidth=4096, pRect->nHeight=2304 07-07 17:09:27.552 5859 6118 E ACodec : Wrong cropped rect (0, 0, 4096, 2304) vs. frame (3840, 2160) 07-07 17:09:27.556 0 0 I [ 492.231341@1] dim: hf:a:ch[2]:alloc:ok 07-07 17:09:27.552 5859 6118 E ACodec : [OMX.amlogic.hevc.decoder.awesome2] Failed to get port format to send format change 07-07 17:09:27.556 0 0 I [ 492.231357@1] dim: value reg:ch[2]:fix_buf:0;ponly <0,0> 07-07 17:09:27.555 525 6129 I AmlogicVideoDecoderAwesome2: [30]"omx output size changed from 4096*2304 to 3840*2160" 07-07 17:09:27.556 0 0 I [ 492.231369@1] dim: di_cnt_i_buf1:tvp:0 07-07 17:09:27.555 525 6133 V android.hardware.media.omx@1.0-service: [0707/170927.555717:VERBOSE1:v4l2_video_decode_accelerator.cc(3864)] CheckInterlaced(): 07-07 17:09:27.556 0 0 I [ 492.231378@1] dim: di_cnt_pst_afbct:cfg post nub:11 07-07 17:09:27.555 525 6133 I VDA : [0]"VIDIOC_G_FMT format.fmt.pix.width 3840\n" 07-07 17:09:27.556 0 0 I [ 492.231417@1] dim: di_reg_setting:ch[2]:for first ch reg: 07-07 17:09:27.556 0 0 I [ 492.232994@1] dim: ch[2]:reg:mem cfg[0][0][0] 07-07 17:09:27.555 525 6133 I VDA : [0]"[GetVisibleSize] visible width 3840, height 2160\n" 07-07 17:09:27.556 0 0 I [ 492.233000@1] dim: s2_1 07-07 17:09:27.555 525 6133 I VDA : [0]"CheckInterlaced format.fmt.pix.field 1\n" 07-07 17:09:27.556 0 0 I [ 492.233024@1] dim: ch[2]:bypass change:i:0->1:0x4 07-07 17:09:27.555 525 6133 V android.hardware.media.omx@1.0-service: [0707/170927.555898:VERBOSE1:v4l2_video_decode_accelerator.cc(3922)] NotifyEvent(): 07-07 17:09:27.555 525 6133 D VideoDecWraper: event 2, param 0x0, paramsize:0 07-07 17:09:27.555 525 6133 D AmlogicVideoDecoderAwesome2: NotifyEvent:event:2 07-07 17:09:27.555 525 6133 D AmlogicVideoDecoderAwesome2: is progressive 07-07 17:09:27.556 525 6133 I AmlManagePts: [0]"setInterlace set interlace[0]\n" 07-07 17:09:27.556 525 4836 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.556 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:3, 0x0, fd:41" 07-07 17:09:27.556 525 6119 I VideoDecWraper: importBufferForPicture:3 07-07 17:09:27.556 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:27.556 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 3, dmabufFd 21, metaFd -1 07-07 17:09:27.558 357 2271 I audio_hw_primary: audiohal_get_latency stream:0xf2b00010 frames:3072 out->config.rate:48000 whole_latency:64, alsa_latency:64, 07-07 17:09:27.558 525 4836 V OmxVideoDecoder: updatePort mUseNativeBuffers 1 07-07 17:09:27.558 525 4836 V OmxVideoDecoder: nWidth=3840,nHeight=2160, nMinbufs=0 07-07 17:09:27.558 525 4836 I OmxVideoDecoder: getParameter Component Name OMX.amlogic.hevc.decoder.awesome2, width 3840, height 2160 nBufferCountMin:9 07-07 17:09:27.558 525 6129 D AmlogicVideoDecoderAwesome2: Calculate duration from pts is 0 07-07 17:09:27.558 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 3, dmabufFd 21, metaFd -1, plane size 1 07-07 17:09:27.558 525 5559 D OmxVideoDecoder: getConfig 3251 0x700000f 07-07 17:09:27.558 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:27.558 525 5559 D OmxVideoDecoder: getConfig 3254 nIndex 0x700000f 07-07 17:09:27.558 525 5559 I AmlogicVideoDecoderAwesome2: [30]"getAndroidNativeBufferCrop [3840 x 2160] \n" 07-07 17:09:27.558 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=21, index=3\n"" {IN=0/3, OUT=1/1181586067[kAtAccelerator(1375641428) kAtDevice(917971145) kAtClient(-2098973467)]}" 07-07 17:09:27.558 525 5559 D OmxVideoDecoder: getConfig OMX_IndexConfigCommonOutputCrop pRect->nWidth=3840, pRect->nHeight=2160 07-07 17:09:27.558 525 5559 D OmxVideoDecoder: setConfig 3393 0x7f00000c 07-07 17:09:27.558 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000c 07-07 17:09:27.559 525 5559 D OmxVideoDecoder: getConfig 3251 0x7f00000c 07-07 17:09:27.559 525 5559 E OMXNodeInstance: getConfig(0xf5dc0cb0:amlogic.hevc.decoder.awesome2, ??(0x7f00000c)) ERROR: UnsupportedSetting(0x80001019) 07-07 17:09:27.561 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f000009 07-07 17:09:27.562 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.562 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=0 (4k-osd) 07-07 17:09:27.562 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.562 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=1 (video-trickmode) 07-07 17:09:27.562 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.562 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=2 (unstable-pts) 07-07 17:09:27.562 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.562 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=3 (player-id) 07-07 17:09:27.562 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.562 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=4 (isudp) 07-07 17:09:27.562 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.562 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=5 (realframerate) 07-07 17:09:27.563 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.563 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=5 (realframerate) 07-07 17:09:27.563 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.563 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=6 (Bitrate) 07-07 17:09:27.563 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.563 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=7 (is-mvc) 07-07 17:09:27.563 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.563 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=8 (is-flv) 07-07 17:09:27.563 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.563 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=9 (dvconfig-box) 07-07 17:09:27.563 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.563 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=10 (profile-level) 07-07 17:09:27.563 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.563 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=10 (profile-level) 07-07 17:09:27.563 525 5559 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.563 525 5559 V OmxVideoDecoder: VendorExt: getConfig: index=11 (nrdp) 07-07 17:09:27.563 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.563 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=12 (low-latency) 07-07 17:09:27.564 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.564 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=13 (save-mem) 07-07 17:09:27.564 525 5508 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.564 525 5508 V OmxVideoDecoder: VendorExt: getConfig: index=14 (videoLayer) 07-07 17:09:27.564 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.564 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=15 (renderByOmx) 07-07 17:09:27.564 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.564 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=16 (tunerhal) 07-07 17:09:27.564 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.564 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=16 (tunerhal) 07-07 17:09:27.565 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.565 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=17 (tunerhal.passthrough) 07-07 17:09:27.565 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.565 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=17 (tunerhal.passthrough) 07-07 17:09:27.565 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.565 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=18 (tunerhal.passthrough) 07-07 17:09:27.565 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.565 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=19 (VideoQos) 07-07 17:09:27.565 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.565 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=20 (rotation-angle) 07-07 17:09:27.565 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.565 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=21 (McodecReport) 07-07 17:09:27.565 525 4836 D OmxVideoDecoder: mMcodecReport=0 mPeer.get=0xf24c0010 07-07 17:09:27.565 525 5275 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.565 525 5275 V OmxVideoDecoder: VendorExt: getConfig: index=22 (START.low-latency) 07-07 17:09:27.565 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.565 525 4836 V OmxVideoDecoder: VendorExt: getConfig: index=23 (avc-size-level) 07-07 17:09:27.566 525 956 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.566 525 956 V OmxVideoDecoder: VendorExt: getConfig: index=24 (avc-4k-mmu) 07-07 17:09:27.566 525 4836 D OmxVideoDecoder: getConfig 3251 0x6f100004 07-07 17:09:27.566 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:27.567 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:27.568 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.569 525 5275 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.569 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:27.569 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:4, 0x0, fd:46" 07-07 17:09:27.569 525 6119 I VideoDecWraper: importBufferForPicture:4 07-07 17:09:27.569 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:27.569 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 4, dmabufFd 23, metaFd -1 07-07 17:09:27.570 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 4, dmabufFd 23, metaFd -1, plane size 1 07-07 17:09:27.570 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:27.570 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=23, index=4\n"" {IN=0/3, OUT=1/1181586068[kAtAccelerator(1375641428) kAtDevice(917971146) kAtClient(-2098973467)]}" 07-07 17:09:27.572 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.572 525 5275 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.573 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:5, 0x0, fd:50" 07-07 17:09:27.573 525 6119 I VideoDecWraper: importBufferForPicture:5 07-07 17:09:27.573 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:27.573 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 5, dmabufFd 26, metaFd -1 07-07 17:09:27.573 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 5, dmabufFd 26, metaFd -1, plane size 1 07-07 17:09:27.574 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:27.574 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=26, index=5\n"" {IN=0/3, OUT=1/1181586069[kAtAccelerator(1375641428) kAtDevice(917971147) kAtClient(-2098973467)]}" 07-07 17:09:27.576 321 422 I DTVKIT_LOG: TunerTask:3532 path:0:tune_idle_timer:2,frontend_usage=0 07-07 17:09:27.583 5859 5888 I Kodi : 2025-07-07 17:09:27.583 T:5888 info : Instancing CRendererMediaCodecSurface 07-07 17:09:27.583 5859 5888 I Kodi : 2025-07-07 17:09:27.583 T:5888 info : CRendererMediaCodecSurface::Configure 07-07 17:09:27.589 525 6129 I VDA : [0]"sendMessagetoDecoder set DECODER_INPUTBUFFER_NO" 07-07 17:09:27.590 5180 5252 E demo-jni: next event: change@/devices/virtual/framerate_adapter/framerate_dev 07-07 17:09:27.590 525 6129 D AmlogicVideoDecoderAwesome2: PictureReady:setDurationToDecoder mDurationUsFromDecoder: 37500, 07-07 17:09:27.590 525 6129 D AmlogicVideoDecoderAwesome2: Calculate duration from pts is 0 07-07 17:09:27.590 355 379 I SystemControl: [/UEventObserver.cpp, HDMITxUenventThreadLoop, 447] HDMI TX switch_name:3600, switch_state: 07-07 17:09:27.590 355 379 D SystemControl: [/FrameRateAutoAdaption.cpp, isFrameRateOn, 156] --isFrameRateOn 1 07-07 17:09:27.590 355 379 I SystemControl: [/FrameRateAutoAdaption.cpp, isFrameRateOn, 168] hdmi frame rate is on 07-07 17:09:27.591 355 379 D SystemControl: [/FrameRateAutoAdaption.cpp, inputValidateAndParse, 440] in event receive mLastFromVdin:0 lastFrame 3600 videoLayerOn0 and decide policy control or restore 0xee640a60 07-07 17:09:27.591 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:27.594 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:27.596 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.597 525 5275 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.598 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:6, 0x0, fd:54" 07-07 17:09:27.598 525 6119 I VideoDecWraper: importBufferForPicture:6 07-07 17:09:27.598 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:27.598 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 6, dmabufFd 27, metaFd -1 07-07 17:09:27.598 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 6, dmabufFd 27, metaFd -1, plane size 1 07-07 17:09:27.598 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:27.598 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=27, index=6\n"" {IN=0/3, OUT=1/1181586070[kAtAccelerator(1375641428) kAtDevice(917971148) kAtClient(-2098973467)]}" 07-07 17:09:27.598 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:27.603 357 2271 I audio_hw_primary: audiohal_get_latency stream:0xf2b00010 frames:3072 out->config.rate:48000 whole_latency:64, alsa_latency:64, 07-07 17:09:27.603 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.604 525 6129 D AmlogicVideoDecoderAwesome2: Calculate duration from pts is 0 07-07 17:09:27.604 525 956 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.605 0 0 I : [ 492.267466@4] vdec_frame_rate_uevent 3600 07-07 17:09:27.605 0 0 I [ 492.267592@4] vframe_rate_uevent: sent uevent FRAME_RATE_HINT=3600 07-07 17:09:27.605 0 0 I [ 492.267595@4] [21]: set duration: e10 07-07 17:09:27.605 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:7, 0x0, fd:58" 07-07 17:09:27.605 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:27.605 525 6119 I VideoDecWraper: importBufferForPicture:7 07-07 17:09:27.605 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:27.605 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 7, dmabufFd 28, metaFd -1 07-07 17:09:27.607 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 7, dmabufFd 28, metaFd -1, plane size 1 07-07 17:09:27.607 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:27.607 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=28, index=7\n"" {IN=0/3, OUT=2/1181586071[kAtAccelerator(1375641428) kAtDevice(917971148) kAtClient(-2098973466)]}" 07-07 17:09:27.611 525 6129 D AmlogicVideoDecoderAwesome2: Calculate duration from pts is 0 07-07 17:09:27.611 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.611 525 956 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.612 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:8, 0x0, fd:62" 07-07 17:09:27.612 525 6119 I VideoDecWraper: importBufferForPicture:8 07-07 17:09:27.612 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:27.612 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 8, dmabufFd 32, metaFd -1 07-07 17:09:27.612 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 8, dmabufFd 32, metaFd -1, plane size 1 07-07 17:09:27.612 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:27.612 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=32, index=8\n"" {IN=0/3, OUT=3/1181586072[kAtAccelerator(1375641428) kAtDevice(917971148) kAtClient(-2098973465)]}" 07-07 17:09:27.613 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:27.614 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:27.622 525 6129 D AmlogicVideoDecoderAwesome2: Calculate duration from pts is 41875 07-07 17:09:27.623 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:27.642 357 2271 I audio_hw_primary: audiohal_get_latency stream:0xf2b00010 frames:3072 out->config.rate:48000 whole_latency:64, alsa_latency:64, 07-07 17:09:27.685 357 2271 I audio_hw_primary: audiohal_get_latency stream:0xf2b00010 frames:3072 out->config.rate:48000 whole_latency:64, alsa_latency:64, 07-07 17:09:27.720 417 417 I ImageManager: type=1400 audit(0.0:239): avc: denied { getattr } for name="/" dev="dmabuf" ino=1 scontext=u:r:surfaceflinger:s0 tcontext=u:object_r:unlabeled:s0 tclass=filesystem permissive=1 07-07 17:09:27.721 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:27.724 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.724 525 5275 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.725 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:9, 0x0, fd:66" 07-07 17:09:27.725 525 6119 I VideoDecWraper: importBufferForPicture:9 07-07 17:09:27.725 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:27.725 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 9, dmabufFd 36, metaFd -1 07-07 17:09:27.725 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 9, dmabufFd 36, metaFd -1, plane size 1 07-07 17:09:27.725 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:27.726 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=36, index=9\n"" {IN=0/3, OUT=4/1181586073[kAtAccelerator(1375641428) kAtDevice(917971148) kAtClient(-2098973464)]}" 07-07 17:09:27.727 357 2271 I audio_hw_primary: audiohal_get_latency stream:0xf2b00010 frames:3072 out->config.rate:48000 whole_latency:64, alsa_latency:64, 07-07 17:09:27.729 649 4237 I system_server: oneway function results will be dropped but finished with status OK and parcel size 4 07-07 17:09:27.731 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:27.741 366 510 D hwc_nn : NnProcessor 07-07 17:09:27.741 366 510 D hwc_nn : need check I/P source. 07-07 17:09:27.741 366 510 D sr_sdk : enter isInterfaceImplement. 07-07 17:09:27.741 366 510 D sr_sdk : NN interface is implement in libnnsdk.so. 07-07 17:09:27.742 366 510 D hwc_nn : NnProcessor: end 07-07 17:09:27.742 366 510 D hwc_nn : setup 07-07 17:09:27.742 366 510 D hwc_aipq: AipqProcessor 07-07 17:09:27.742 366 510 D hwc_aipq: setup 07-07 17:09:27.742 366 510 D hwc_aipq: threadMain creat 07-07 17:09:27.742 366 510 D MesonHwc: adjustVsyncMode to MixMode 07-07 17:09:27.742 355 475 I SystemControl: [frameRateDisplay] +++ 07-07 17:09:27.742 355 475 I SystemControl: SystemControlHal frameRateDisplay 1,[0 0 1920 1080] 07-07 17:09:27.742 355 475 I SystemControl: [/SysWrite.cpp, getKernelReleaseVersion, 635] getKernelReleaseVersion: 5.4 07-07 17:09:27.742 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:27.742 355 475 D SystemControl: axis is 1920x1080 07-07 17:09:27.742 355 475 E SystemControl: inScreen 1920 1080 [ 0 0 1920 1080] 07-07 17:09:27.742 355 475 D SystemControl: SystemControlHal frect.inscreen() 1 07-07 17:09:27.742 355 475 I SystemControl: [/SysWrite.cpp, getKernelReleaseVersion, 635] getKernelReleaseVersion: 5.4 07-07 17:09:27.742 355 475 D SystemControl: axis is 1920x1080 07-07 17:09:27.742 355 475 E SystemControl: inScreen 1920 1080 [ 0 0 1920 1080] 07-07 17:09:27.742 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, isFrameRateOn, 156] --isFrameRateOn 1 07-07 17:09:27.742 355 475 I SystemControl: [/FrameRateAutoAdaption.cpp, isFrameRateOn, 168] hdmi frame rate is on 07-07 17:09:27.742 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, setVideoLayerOn, 231] last video layer on 0 07-07 17:09:27.745 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, policyControl, 263] mPlayFlag 0 frameRateValue3600 mLastFromVdin 0 07-07 17:09:27.745 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, findNearlyFrame, 385] itr is 12.500000 and videoframe is 2666 07-07 17:09:27.745 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, findNearlyFrame, 385] itr is 23.976024 and videoframe is 2666 07-07 17:09:27.745 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, findNearlyFrame, 385] itr is 23.982013 and videoframe is 2666 07-07 17:09:27.745 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, findNearlyFrame, 385] itr is 24.000000 and videoframe is 2666 07-07 17:09:27.745 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, findNearlyFrame, 385] itr is 25.000000 and videoframe is 2666 07-07 17:09:27.745 366 511 D MesonHwc: VideoComposerDev (10) set (1). 07-07 17:09:27.745 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, findNearlyFrame, 385] itr is 29.971901 and videoframe is 2666 07-07 17:09:27.746 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, findNearlyFrame, 385] itr is 30.000000 and videoframe is 2666 07-07 17:09:27.746 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, findNearlyFrame, 385] itr is 50.000000 and videoframe is 2666 07-07 17:09:27.746 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, findNearlyFrame, 385] itr is 59.925094 and videoframe is 2666 07-07 17:09:27.746 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, findNearlyFrame, 385] itr is 59.962523 and videoframe is 2666 07-07 17:09:27.746 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, findNearlyFrame, 385] itr is 60.000000 and videoframe is 2666 07-07 17:09:27.746 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, findNearlyFrame, 385] itr is 120.000000 and videoframe is 2666 07-07 17:09:27.746 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, policyControl, 294] find new framerate is 3840 07-07 17:09:27.746 1428 6114 W adbd : timed out while waiting for FUNCTIONFS_BIND, trying again 07-07 17:09:27.747 1428 6114 I adbd : UsbFfs: connection terminated: monitor thread finished 07-07 17:09:27.747 366 520 D MesonHwc: [waitMixVsync] waitVBlank to get hw vsync timestamp 07-07 17:09:27.748 0 0 I [ 492.423561@0] vc: [0]vc: set enable index=0, val=1 07-07 17:09:27.749 0 0 I : [ 492.423584@0] dev->index =0, val=2 07-07 17:09:27.749 0 0 I [ 492.423590@0] VID: VD1 off 07-07 17:09:27.749 0 0 I [ 492.423601@0] VID: VD1 set global output as 1 07-07 17:09:27.749 0 0 I [ 492.423660@0] VID: store VD0 path_id changed -1->2 07-07 17:09:27.749 0 0 I [ 492.423680@0] vc: [0]sideband_type =-1 07-07 17:09:27.747 1428 1428 I adbd : UsbFfs: already offline 07-07 17:09:27.747 1428 1428 I adbd : destroying transport UsbFfs 07-07 17:09:27.747 1428 1428 I adbd : UsbFfsConnection being destroyed 07-07 17:09:27.749 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, isCfgModeSupport, 348] resolution is 1080p 07-07 17:09:27.750 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, isCfgModeSupport, 358] hdmi_mode 1080p50hz* 1 10 07-07 17:09:27.750 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, isCfgModeSupport, 366] hdmi_mode 1080p50hz 07-07 17:09:27.750 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, policyControl, 312] frameRateValue 3840 curDisplayMode:1080p50hz,new newDisplayMode mode:1080p50hz 07-07 17:09:27.750 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, frameRateChange, 402] frameRateChange 1080p50hz 1080p50hz 07-07 17:09:27.750 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, outputDispatch, 639] notify displayMode change display: 1080p50hz frameRate 3840 07-07 17:09:27.750 355 475 I SystemControl: [/DisplayMode.cpp, onDispModeSyncEvent, 3815] onDispModeSyncEvent outputmode:1080p50hz state: 3 07-07 17:09:27.750 355 475 I SystemControl: [/DisplayMode.cpp, getDisplayMode, 2678] getDisplayMode mode:1080p50hz 07-07 17:09:27.752 0 0 I : [ 492.423686@4] dev->index =0, val=0 07-07 17:09:27.752 0 0 I [ 492.427822@4] hdmitx: avmute_store 1 07-07 17:09:27.752 0 0 I [ 492.427827@4] hdmitx: hdmitx21_av_mute_op: AVMUTE path=0x80 07-07 17:09:27.760 0 0 E [ 492.437161@0] vpu: vpu: error: vpu_dev_mem_power_on: vpu_dev is null 07-07 17:09:27.760 0 0 E [ 492.437173@0] vpu: vpu: error: vpu_dev_mem_power_on: vpu_dev is null 07-07 17:09:27.760 0 0 E [ 492.437176@0] vpu: vpu: error: vpu_dev_mem_power_on: vpu_dev is null 07-07 17:09:27.760 0 0 E [ 492.437179@0] vpu: vpu: error: vpu_dev_mem_power_on: vpu_dev is null 07-07 17:09:27.760 0 0 I [ 492.438118@0] vd1_port: 0, vd2_port:1, val:0x1, 0x0 07-07 17:09:27.764 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:27.767 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.767 525 5275 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.767 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:10, 0x0, fd:70" 07-07 17:09:27.768 525 6119 I VideoDecWraper: importBufferForPicture:10 07-07 17:09:27.768 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:27.768 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 10, dmabufFd 40, metaFd -1 07-07 17:09:27.768 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 10, dmabufFd 40, metaFd -1, plane size 1 07-07 17:09:27.768 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:27.768 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=40, index=10\n"" {IN=0/3, OUT=5/1181586074[kAtAccelerator(1375641428) kAtDevice(917971148) kAtClient(-2098973463)]}" 07-07 17:09:27.771 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:27.779 5180 5252 E demo-jni: next event: change@/devices/virtual/video/amvideo 07-07 17:09:27.784 0 0 I [ 492.458047@0] video_send_uevent: VIDEO_FORMAT=2, ret=0 07-07 17:09:27.782 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:27.795 1428 1439 I adbd : opening control endpoint /dev/usb-ffs/adb/ep0 07-07 17:09:27.796 1428 1439 I adbd : UsbFfsConnection constructed 07-07 17:09:27.797 1428 6140 I adbd : UsbFfs-monitor thread spawned 07-07 17:09:27.804 0 0 I [ 492.479458@4] hdmitx: phy_store 0 07-07 17:09:27.821 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:27.822 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:27.861 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:27.861 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:27.872 355 475 I SystemControl: [/HDCPTxAuth.cpp, stopVerAll, 316] hdcp_tx 2.2 & 1.4 stop hdcp pwr 07-07 17:09:27.872 355 475 E SystemControl: [/SysWrite.cpp, writeSys, 218] writeSysFs, open /sys/class/amhdmitx/amhdmitx0/hdcp_pwr fail. 07-07 17:09:27.903 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:27.904 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:27.915 355 475 E SystemControl: [/SysWrite.cpp, readSys, 583] readSysFs, open /sys/class/amhdmitx/amhdmitx0/hdcp_pwr fail. Error info [No such file or directory] 07-07 17:09:27.915 355 475 E SystemControl: [/SysWrite.cpp, writeSys, 218] writeSysFs, open /sys/class/amhdmitx/amhdmitx0/hdcp_ctrl fail. 07-07 17:09:27.915 355 475 E SystemControl: [/SysWrite.cpp, writeSys, 218] writeSysFs, open /sys/class/amhdmitx/amhdmitx0/hdcp_ctrl fail. 07-07 17:09:27.918 355 475 I SystemControl: [/HDCPTxAuth.cpp, stop, 121] hdcp_tx pthread exit id = 3986661824, (null) done 07-07 17:09:27.918 366 366 D MesonDisplayServer: getDisplayAttribute "HDMI Color ATTR": "444,8bit" 07-07 17:09:27.920 0 0 I [ 492.597733@4] hdmitx: set frac_rate_policy as 0 07-07 17:09:27.919 355 475 I SystemControl: [/DisplayMode.cpp, isTvSupportDolbyVision, 2751] TV not support DV 07-07 17:09:27.919 355 475 I SystemControl: [/DisplayMode.cpp, updateDeepColor, 2413] cur deepcolor attr value: 444,8bit is support, Do not need change it 07-07 17:09:27.919 355 475 I SystemControl: [/DisplayMode.cpp, updateDeepColor, 2425] cur deepcolor attr value is equals to colorAttribute, Do not need set it 07-07 17:09:27.919 355 475 I SystemControl: [/DisplayMode.cpp, updateDeepColor, 2427] setMboxOutputMode colorAttribute = 444,8bit 07-07 17:09:27.919 355 475 I SystemControl: [/DisplayMode.cpp, getDisplayMode, 2678] getDisplayMode mode:1080p50hz 07-07 17:09:27.919 355 475 I SystemControl: [/DisplayMode.cpp, setSourceOutputMode, 1336] callback with funcytion 07-07 17:09:27.920 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, frameRateTransfer, 627] frameRateTransfer lastIsF = 1 newIsF = 0 07-07 17:09:27.920 355 475 I SystemControl: [/DisplayMode.cpp, setSourceOutputMode, 1340] curMode = 1080p50hz outputmode = 1080p50hz 07-07 17:09:27.920 355 475 I SystemControl: [/DisplayMode.cpp, setDisplayMode, 2688] setDisplayMode mode:1080p50hz 07-07 17:09:27.920 366 511 D MesonDisplayServer: SetDisplay[HDMI] Mode to "1080p50hz" 07-07 17:09:27.920 366 511 D MesonHwc: Set update flag 07-07 17:09:27.924 0 0 I : [ 492.598449@4] [drm] am_hdmitx_connector_atomic_set_property 07-07 17:09:27.924 0 0 I : [ 492.598559@4] [drm] [meson_hdmitx_encoder_atomic_disable] 07-07 17:09:27.924 0 0 I : [ 492.598567@4] [drm] am_meson_crtc_atomic_disable-[0]:in 07-07 17:09:27.924 0 0 I [ 492.598605@4] hdcptx: hdcp_mode_set[128] 0 07-07 17:09:27.934 5180 5252 E demo-jni: next event: change@/devices/platform/vout 07-07 17:09:27.934 1440 4477 D UEventObserver: change@/devices/platform/voutTION=changeVPATH=/devices/platform/voutBSYSTEM=platformut_setmode=1IVER=vout_NAME=vout_FULLNAME=/vout_COMPATIBLE_0=amlogic, vout_COMPATIBLE_N=1DALIAS=of:NvoutT(null)Camlogic,_voutQNUM=380007-07 17:09:27.934 1440 4477 D SetModeUEventObserver: jiangjunlu 07-07 17:09:27.934 1440 4477 I chatty : uid=1000(system) UEventObserver identical 1 line 07-07 17:09:27.934 1440 4477 D SetModeUEventObserver: jiangjunlu 07-07 17:09:27.935 366 519 D MesonHwc: received Uevent: change@/devices/platform/vout 07-07 17:09:27.935 366 519 D MesonHwc: received Uevent: ACTION=change 07-07 17:09:27.935 366 519 D MesonHwc: received Uevent: DEVPATH=/devices/platform/vout 07-07 17:09:27.935 366 519 D MesonHwc: received Uevent: SUBSYSTEM=platform 07-07 17:09:27.935 366 519 D MesonHwc: received Uevent: vout_setmode=1 07-07 17:09:27.935 366 519 D MesonHwc: ModeChange state: [Begin to change] 07-07 17:09:27.935 366 519 D MesonHwc: On mode change state: [Begin to change] 07-07 17:09:27.935 366 519 D MesonHwc: [setConnectorStatus]: power mode 2 07-07 17:09:27.935 366 519 D MesonHwc: displayId:0, blank all display planes 07-07 17:09:27.936 0 0 I [ 492.611516@4] hdmitx: recalc before invalid 0 1, frac 0 07-07 17:09:27.936 0 0 I : [ 492.611528@4] recalc after invalid 0 1, frac 0 07-07 17:09:27.936 0 0 I : [ 492.611549@4] hdmitx_unregister_vrr ret = 0 07-07 17:09:27.936 0 0 I : [ 492.611564@4] [drm] am_meson_crtc_atomic_enable-[0] in: new[1080p50hz], old[1080p50hz], vmode[4-4], uboot[0] 07-07 17:09:27.936 0 0 I [ 492.611579@4] hdmitx: recalc before 1080p50hz 50 1, frac 0 07-07 17:09:27.936 0 0 I : [ 492.611585@4] recalc after 1080p50hz 50 1, frac 0 07-07 17:09:27.936 0 0 I : [ 492.611590@4] [drm] am_meson_crtc_atomic_enable-[0]: enable mode 1080p50hz final vmode 0 07-07 17:09:27.936 0 0 I : [ 492.611607@4] [drm] am_meson_crtc_atomic_enable-[0]: out 07-07 17:09:27.936 0 0 I : [ 492.611612@4] [drm] [meson_hdmitx_encoder_atomic_enable] 07-07 17:09:27.941 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:27.947 0 0 I : [ 492.616776@4] hdmitx_register_vrr ret = 0 07-07 17:09:27.947 0 0 I [ 492.616784@4] hdmitx: hdmitx: get current mode: 1080p50hz 07-07 17:09:27.947 0 0 I [ 492.616788@4] hdmitx: update physical size: 530 300 07-07 17:09:27.947 0 0 I [ 492.616798@4] hdmitx: recalc before 1080p50hz 50 1, frac 0 07-07 17:09:27.947 0 0 I : [ 492.616803@4] recalc after 1080p50hz 50 1, frac 0 07-07 17:09:27.947 0 0 I [ 492.616808@4] hdmitx: setting hdmi mode 1080p50hz 444,8bit 07-07 17:09:27.947 0 0 I [ 492.616811@4] hdmitx: cd/cs/cr: 4/2/1 07-07 17:09:27.947 0 0 I [ 492.616826@4] hdmitx: video: already init VIC = 31 Now VIC = 31 07-07 17:09:27.947 0 0 I [ 492.616833@4] hdmitx: this mode doesn't have frac_rate 07-07 17:09:27.947 0 0 I [ 492.616836@4] hdmitx: frac_rate = 0 07-07 17:09:27.948 0 0 I [ 492.616842@4] hdmitx: set_hdmitx_s5_htx_pll[864] base_pixel_clk 148500 cs 2 cd 4 frac_rate 0 07-07 17:09:27.948 0 0 I [ 492.616848@4] hdmitx: set_hdmitx_s5_htx_pll[890] calculate pixel_clk to 1485000 07-07 17:09:27.948 0 0 I [ 492.616852@4] hdmitx: set21_s5_htxpll_clk_out[217] htxpll vco 5940000 div 8 07-07 17:09:27.948 0 0 I [ 492.617443@4] hdmitx: configure venc 07-07 17:09:27.948 0 0 I [ 492.617451@4] hdmitx: config_tv_enc_calc[65] vic = 31 07-07 17:09:27.948 0 0 I [ 492.617462@4] hdmitx: configure hdmitx video format timing 07-07 17:09:27.948 0 0 I [ 492.617474@4] hdmitx: hdmi_hwp_init374 07-07 17:09:27.948 0 0 I [ 492.617491@4] hdmitx: hdmitx_soft_reset[938] 07-07 17:09:27.948 0 0 I [ 492.617508@4] hdmitx: hw: config hdmitx IP vic = 31 cd:4 cs: 2 07-07 17:09:27.948 0 0 I [ 492.617511@4] hdmitx: configure hdmitx21 07-07 17:09:27.948 0 0 I [ 492.617595@4] hdmitx: warn: SCDC not present, should not send 1:10 07-07 17:09:27.948 0 0 I [ 492.617614@4] hdmitx: hw: set audio 07-07 17:09:27.948 0 0 I [ 492.617619@4] hdmitx: clk_div = 18 07-07 17:09:27.948 0 0 I [ 492.617624@4] hdmitx: audio_param->channel_num = 1 07-07 17:09:27.948 0 0 I [ 492.617630@4] hdmitx: hw: hdmitx tx_aud_src = 0 07-07 17:09:27.948 0 0 I [ 492.617638@4] hdmitx: fs = 3, cd = 4, tmds_clk = 148500 07-07 17:09:27.948 0 0 I [ 492.617643@4] hdmitx: hw: aud_n_para = 6144 07-07 17:09:27.948 0 0 I [ 492.617676@4] hdmitx: audio state AUDIO_UNMUTE 07-07 17:09:27.948 0 0 I [ 492.617689@4] hdmitx: vpp_post_out_color_fmt: 0 07-07 17:09:27.948 0 0 I [ 492.619714@4] hdmitx: hdmitx_set_dispmode[1303] frl_rate 0 htotal 2640 cs 2 cd 4 h_unstable 0 07-07 17:09:27.948 0 0 I [ 492.620728@4] hdmitx: is_deep_phase_unstable[785] gcp_cur_st 0 07-07 17:09:27.962 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:27.963 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.963 525 5275 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:27.964 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:11, 0x0, fd:74" 07-07 17:09:27.964 525 6119 I VideoDecWraper: importBufferForPicture:11 07-07 17:09:27.964 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:27.964 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 11, dmabufFd 53, metaFd -1 07-07 17:09:27.964 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 11, dmabufFd 53, metaFd -1, plane size 1 07-07 17:09:27.964 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:27.964 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=53, index=11\n"" {IN=0/3, OUT=6/1181586075[kAtAccelerator(1375641428) kAtDevice(917971148) kAtClient(-2098973462)]}" 07-07 17:09:27.965 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:27.987 0 0 I [ 492.657964@4] hdmitx: set_phy_by_mode[557] tmds_clk 148500 07-07 17:09:27.988 0 0 I [ 492.657978@4] hdmitx: hdmitx_set_s5_phypara[494] rterm_efuse = 7 07-07 17:09:27.988 0 0 I [ 492.657984@4] hdmitx: hdmitx_dfm_cfg default BW 07-07 17:09:27.988 0 0 I [ 492.659545@4] hdmitx: cts_htx_tmds_clk: 148500000, hdmi_clk_todig: 74248438 07-07 17:09:27.988 0 0 I [ 492.659550@4] hdmitx: ANACTRL_HDMIPHY_CTRL0=17d85062 07-07 17:09:27.988 0 0 I [ 492.659552@4] hdmitx: ANACTRL_HDMIPHY_CTRL1=390000e 07-07 17:09:27.988 0 0 I [ 492.659554@4] hdmitx: ANACTRL_HDMIPHY_CTRL2=f 07-07 17:09:27.988 0 0 I [ 492.659555@4] hdmitx: ANACTRL_HDMIPHY_CTRL3=bd130600 07-07 17:09:27.988 0 0 I [ 492.659557@4] hdmitx: ANACTRL_HDMIPHY_CTRL4=0 07-07 17:09:27.988 0 0 I [ 492.659559@4] hdmitx: ANACTRL_HDMIPHY_CTRL5=ff03 07-07 17:09:27.988 0 0 I [ 492.659561@4] hdmitx: ANACTRL_HDMIPHY_CTRL6=770b 07-07 17:09:27.988 0 0 I [ 492.659562@4] hdmitx: reset 10to20 fifo after enable phy 07-07 17:09:27.988 0 0 I [ 492.659564@4] hdmitx: hdmitx_soft_reset[938] 07-07 17:09:27.988 0 0 I [ 492.659570@4] hdmitx: intr enable delay 2 ms after fifo reset 07-07 17:09:27.995 5180 5252 E demo-jni: next event: change@/devices/platform/vout 07-07 17:09:27.995 1440 4477 D UEventObserver: change@/devices/platform/voutTION=changeVPATH=/devices/platform/voutBSYSTEM=platformut_setmode=0IVER=vout_NAME=vout_FULLNAME=/vout_COMPATIBLE_0=amlogic, vout_COMPATIBLE_N=1DALIAS=of:NvoutT(null)Camlogic,_voutQNUM=380107-07 17:09:27.995 1440 4477 D SetModeUEventObserver: jiangjunlu 07-07 17:09:27.995 1440 4477 D SetModeUEventObserver: jiangjunlu mUeventVoutValue 07-07 17:09:27.995 1440 4477 D SetModeUEventObserver: jiangjunlu 07-07 17:09:27.995 1440 4477 D SetModeUEventObserver: jiangjunlu mUeventVoutValue 07-07 17:09:27.995 1440 4477 D SetModeUEventObserver: jiangjunlu 07-07 17:09:27.996 1440 4477 D SetModeUEventObserver: jiangjunlu mUeventVoutValue 07-07 17:09:27.996 0 0 I [ 492.669988@4] hdmitx: video: Sink is HDMI device 07-07 17:09:27.996 0 0 I [ 492.670009@0] hdmitx: TMDS stable(bit0): 0x7f 07-07 17:09:27.996 0 0 I [ 492.670022@4] hdmitx: hw: set audio 07-07 17:09:27.996 0 0 I [ 492.670026@4] hdmitx: clk_div = 18 07-07 17:09:27.996 0 0 I [ 492.670031@4] hdmitx: audio_param->channel_num = 1 07-07 17:09:27.996 0 0 I [ 492.670036@4] hdmitx: hw: hdmitx tx_aud_src = 0 07-07 17:09:27.996 0 0 I [ 492.670044@4] hdmitx: fs = 3, cd = 4, tmds_clk = 148500 07-07 17:09:27.996 0 0 I [ 492.670048@4] hdmitx: hw: aud_n_para = 6144 07-07 17:09:27.996 0 0 I [ 492.670076@4] hdmitx: audio state AUDIO_UNMUTE 07-07 17:09:27.996 0 0 I [ 492.673108@4] DOLBY: vout_notify_callback: VOUT_EVENT_MODE_CHANGE 07-07 17:09:27.996 0 0 I : [ 492.673114@4] vsync_pts_inc 1800 1/50 07-07 17:09:28.004 0 0 I : [ 492.679925@4] [drm] meson_vout_update_mode_name: hdmitx update vout 1 name 1080p50hz. 07-07 17:09:28.004 0 0 I [ 492.682344@0] hdcptx: mode 0, auth: 0 07-07 17:09:28.003 365 365 W gralloc4: [gralloc]: allocate fixed 1080p buffer for 1/16 usage size:3133440 07-07 17:09:28.005 5859 6118 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:28.005 5180 5252 E demo-jni: next event: change@/devices/virtual/amhdmitx/amhdmitx0 07-07 17:09:28.005 525 5275 W gralloc4: Buffer descriptor with invalid usage bits 0x2000 07-07 17:09:28.005 355 379 I SystemControl: [/UEventObserver.cpp, HDMITxUenventThreadLoop, 447] HDMI TX switch_name:hdmitx_hdcp, switch_state: 0 07-07 17:09:28.006 525 6119 D AmlogicVideoDecoderAwesome2: [30]"======setOutputBuf, line 1483=======:12, 0x0, fd:78" 07-07 17:09:28.006 525 6119 I VideoDecWraper: importBufferForPicture:12 07-07 17:09:28.006 525 6119 D VideoDecWraper: outbuf color format NV21 07-07 17:09:28.006 525 6119 I V4l2Adaptor: importBufferForPicture pictureBufferId 12, dmabufFd 44, metaFd -1 07-07 17:09:28.006 525 6129 I V4l2Adaptor: onImportBufferForPicture pictureBufferId 12, dmabufFd 44, metaFd -1, plane size 1 07-07 17:09:28.006 525 6129 I V4l2Adaptor: onImportBufferForPicture addr 0x0, stride 0, offset 0, size 0 07-07 17:09:28.006 525 6133 I VDA : [0]"ImportBufferForPictureTask fd=44, index=12\n"" {IN=0/3, OUT=7/1181586076[kAtAccelerator(1375641428) kAtDevice(917971148) kAtClient(-2098973461)]}" 07-07 17:09:28.008 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.024 0 0 I [ 492.700387@4] vc: [0]vc: set enable index=0, val=0 07-07 17:09:28.024 0 0 I [ 492.700405@4] VID: store VD0 path_id changed 2->-1 07-07 17:09:28.024 0 0 I : [ 492.700407@4] dev->index =0, val=1 07-07 17:09:28.024 0 0 I [ 492.700409@4] VID: VD1 off 07-07 17:09:28.024 0 0 I [ 492.700413@4] VID: VD1 set global output as 0 07-07 17:09:28.024 0 0 I : [ 492.700423@4] common_vf_unreg_provider video_render.0: vd1 used:true, vd2 used:false, vd3 used:false, f_wait:true (26373 26373), b_out:0, cur_buf:0000000022a2f5fa 07-07 17:09:28.022 366 511 D MesonHwc: setmode:crtc[55], name [1080p50hz] -modeblob[93] 07-07 17:09:28.022 366 519 D MesonHwc: VideoComposerDev (10) set (0). 07-07 17:09:28.023 779 779 V AvrcpMediaPlayerWrapper: onPlaybackStateChanged(): org.xbmc.kodi : PlaybackState {state=3, position=234, buffered position=0, speed=1.0, updated=492693, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:28.024 779 779 W AvrcpMediaPlayerWrapper: onPlaybackStateChanged(): org.xbmc.kodi tried to update with no new data 07-07 17:09:28.031 355 475 I SystemControl: [/DisplayMode.cpp, getDisplayMode, 2678] getDisplayMode mode:1080p50hz 07-07 17:09:28.031 355 475 I SystemControl: [/DisplayMode.cpp, setSourceOutputMode, 1439] setMboxOutputMode cvbsMode = 0 07-07 17:09:28.043 5180 5252 E demo-jni: next event: change@/devices/virtual/video/amvideo 07-07 17:09:28.043 0 0 I [ 492.720528@0] vd1_port: 0, vd2_port:1, val:0x0, 0x0 07-07 17:09:28.043 0 0 I [ 492.721235@0] video_send_uevent: VIDEO_FORMAT=-1, ret=0 07-07 17:09:28.052 0 0 I [ 492.729347@4] hdmitx: hdmitx21_audio_mute_op: AUD_MUTE path=0x4000000 07-07 17:09:28.052 355 6141 I SystemControl: [/HDCPTxAuth.cpp, authThread, 156] hdcp_tx thread loop entry 07-07 17:09:28.052 0 0 I [ 492.729359@4] hdmitx: audio state AUDIO_MUTE 07-07 17:09:28.052 355 475 I SystemControl: [/HDCPTxAuth.cpp, start, 97] hdcp_tx, create hdcp thread id = 3986661824 done 07-07 17:09:28.052 0 0 I [ 492.729377@4] hdmitx: hdmitx21_audio_mute_op: AUD_UNMUTE path=0x4000000 07-07 17:09:28.052 0 0 I [ 492.729381@4] hdmitx: audio state AUDIO_UNMUTE 07-07 17:09:28.052 355 475 I SystemControl: [/SystemControlHal.cpp, onEvent, 71] onEvent event:0, client size:2 07-07 17:09:28.052 0 0 I [ 492.729392@4] hdmitx: avmute_store -1 07-07 17:09:28.052 355 475 I SystemControl: [/DisplayMode.cpp, getBootEnv, 500] key:ubootenv.var.digitaudiooutput value:(null) 07-07 17:09:28.052 0 0 I [ 492.729395@4] hdmitx: hdmitx21_av_mute_op: AV UNMUTE path=0x80 07-07 17:09:28.052 355 475 I SystemControl: [/DisplayMode.cpp, setSourceOutputMode, 1481] set output mode:1080p50hz done 07-07 17:09:28.052 355 475 I SystemControl: [frameRateDisplay] --- 07-07 17:09:28.052 973 1094 I SystemControlEvent: system control callback event: 0 07-07 17:09:28.053 355 6141 I SystemControl: [/HDCPTxAuth.cpp, authInit, 200] hdcp_tx key:22+14 07-07 17:09:28.053 355 475 I SystemControl: [/DisplayMode.cpp, getDisplayMode, 2678] getDisplayMode mode:1080p50hz 07-07 17:09:28.054 973 1094 I SystemControlEvent: Cur output mode=1080p50hz, Prev DDP enable=false, need set DDP enable=false 07-07 17:09:28.056 355 6141 I SystemControl: [/HDCPTxAuth.cpp, authInit, 207] hdcp_tx remote version:14 07-07 17:09:28.056 355 6141 I SystemControl: [/HDCPTxAuth.cpp, stopVerAll, 316] hdcp_tx 2.2 & 1.4 stop hdcp pwr 07-07 17:09:28.056 355 6141 E SystemControl: [/SysWrite.cpp, writeSys, 218] writeSysFs, open /sys/class/amhdmitx/amhdmitx0/hdcp_pwr fail. 07-07 17:09:28.060 0 0 I [ 492.733944@1] hdmitx: hdcp_ver_show: hdev->hdcp_mode: 0, rx_hdcp2_ver = 0 07-07 17:09:28.082 366 519 D MesonHwc: drmplane clear drm bo caches 07-07 17:09:28.082 366 519 D MesonHwc: drmplane clear drm bo caches 07-07 17:09:28.082 366 519 D MesonHwc: cleanupBeforeDestroyLocked displayId:0, clear layers 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerDisplayFrame) met invalid layer id (662) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerSourceCrop) met invalid layer id (662) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerZorder) met invalid layer id (662) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerTransform) met invalid layer id (662) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerBlendMode) met invalid layer id (662) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerPlaneAlpha) met invalid layer id (662) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerBuffer) met invalid layer id (662) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerCompositionType) met invalid layer id (662) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerDisplayFrame) met invalid layer id (405) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerSourceCrop) met invalid layer id (405) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerZorder) met invalid layer id (405) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerTransform) met invalid layer id (405) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerBlendMode) met invalid layer id (405) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerPlaneAlpha) met invalid layer id (405) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerCompositionType) met invalid layer id (405) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerDisplayFrame) met invalid layer id (148) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerSourceCrop) met invalid layer id (148) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerZorder) met invalid layer id (148) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerTransform) met invalid layer id (148) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerBlendMode) met invalid layer id (148) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerPlaneAlpha) met invalid layer id (148) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerBuffer) met invalid layer id (148) in display (0xf1007b80) 07-07 17:09:28.082 366 366 E MesonHwc: (setLayerCompositionType) met invalid layer id (148) in display (0xf1007b80) 07-07 17:09:28.083 366 366 D MesonHwc: adjustVsyncMode to SwMode 07-07 17:09:28.083 355 475 I SystemControl: [frameRateDisplay] +++ 07-07 17:09:28.083 355 475 I SystemControl: SystemControlHal frameRateDisplay 0,[0 0 0 0] 07-07 17:09:28.083 355 475 I SystemControl: [/SysWrite.cpp, getKernelReleaseVersion, 635] getKernelReleaseVersion: 5.4 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4040000 generated error 3 07-07 17:09:28.083 355 475 D SystemControl: axis is 1920x1080 07-07 17:09:28.083 355 475 E SystemControl: inScreen 1920 1080 [ 0 0 0 0] 07-07 17:09:28.083 355 475 D SystemControl: SystemControlHal frect.inscreen() 0 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4070000 generated error 3 07-07 17:09:28.083 355 475 I SystemControl: [/SysWrite.cpp, getKernelReleaseVersion, 635] getKernelReleaseVersion: 5.4 07-07 17:09:28.083 417 417 W HwcComposer: command 0x40a0000 generated error 3 07-07 17:09:28.083 355 475 D SystemControl: axis is 1920x1080 07-07 17:09:28.083 355 475 E SystemControl: inScreen 1920 1080 [ 0 0 0 0] 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4080000 generated error 3 07-07 17:09:28.083 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, isFrameRateOn, 156] --isFrameRateOn 1 07-07 17:09:28.083 355 475 I SystemControl: [/FrameRateAutoAdaption.cpp, isFrameRateOn, 168] hdmi frame rate is on 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4000000 generated error 3 07-07 17:09:28.083 355 475 I SystemControl: [/FrameRateAutoAdaption.cpp, setVideoLayerOn, 217] policycontrol by setVideoLayerOn 1 on 0 lastframe 3600 and cost 341 07-07 17:09:28.083 355 475 I SystemControl: [frameRateDisplay] --- 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4050000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x3010000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4020000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4040000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4070000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x40a0000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4080000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4000000 generated error 3 07-07 17:09:28.084 0 0 I : [ 492.739896@4] [drm] Disabling plane 0, so skip postion calc 07-07 17:09:28.084 0 0 I [ 492.761391@4] hdmitx: drm_hdmitx_get_vrr_cap not support vrr 07-07 17:09:28.084 0 0 E [ 492.761415@4] [drm: meson_hdmitx_get_modes [aml_drm]] *ERROR* Get hdmi para by vic [17] failed. 07-07 17:09:28.085 0 0 E [ 492.761425@4] [drm: meson_hdmitx_get_modes [aml_drm]] *ERROR* Get hdmi para by vic [21] failed. 07-07 17:09:28.085 0 0 E [ 492.761438@4] [drm: meson_hdmitx_get_modes [aml_drm]] *ERROR* Get hdmi para by vic [2] failed. 07-07 17:09:28.085 0 0 E [ 492.761448@4] [drm: meson_hdmitx_get_modes [aml_drm]] *ERROR* Get hdmi para by vic [6] failed. 07-07 17:09:28.085 0 0 I [ 492.761970@1] MUA: buffer->size:3133440 realloc new_size=50135040, pre_size = 3133440 07-07 17:09:28.088 0 0 D : [ 492.765510@4] vf=0000000027298322 vf->vf_ext:00000000039f9e3a vf->flags:1024 07-07 17:09:28.088 0 0 D [ 492.765517@4] v4lvideo_data_copy: vf->type:5279744 vf->compWidth:3840 vf->compHeight:2160 07-07 17:09:28.088 0 0 D [ 492.765520@4] vf->width: 960 vf->height:540 v4l_data->width:3840 v4l_data->height:2160 07-07 17:09:28.088 0 0 D [ 492.765522@4] v4l_data->byte_stride: 3840 v4l_data->size:50135040 07-07 17:09:28.088 0 0 I [ 492.765524@4] width: 960, height: 540, compWidth: 3840, compHeight: 2160. 07-07 17:09:28.083 366 519 D MesonHwc: received Uevent: change@/devices/platform/vout 07-07 17:09:28.083 366 519 D MesonHwc: received Uevent: ACTION=change 07-07 17:09:28.083 366 519 D MesonHwc: received Uevent: DEVPATH=/devices/platform/vout 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4050000 generated error 3 07-07 17:09:28.083 366 519 D MesonHwc: received Uevent: SUBSYSTEM=platform 07-07 17:09:28.083 366 519 D MesonHwc: received Uevent: vout_setmode=0 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4020000 generated error 3 07-07 17:09:28.083 366 519 D MesonHwc: ModeChange state: [Complete] 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4040000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4070000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x40a0000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4080000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4000000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4050000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x3010000 generated error 3 07-07 17:09:28.083 417 417 W HwcComposer: command 0x4020000 generated error 3 07-07 17:09:28.083 366 519 D MesonHwc: DrmProperty: EDID (1), value [92] 07-07 17:09:28.084 366 519 D MesonHwc: DrmProperty: UPDATE (65), value [0] 07-07 17:09:28.084 366 519 D MesonHwc: Connector HDMI-A loadDisplayModes get 12 modes 07-07 17:09:28.084 366 519 D MesonHwc: add fraction display mode (1080p60hz) 07-07 17:09:28.084 366 519 I MesonHwc: add display mode (1080p60hz-1080p60hz-89, 1920x1080, 60.000000) 07-07 17:09:28.084 366 519 D MesonHwc: add fraction display mode (1080i60hz) 07-07 17:09:28.084 366 519 I MesonHwc: add display mode (1080i60hz-1080i60hz-91, 1920x1080, 60.000000) 07-07 17:09:28.084 366 519 I MesonHwc: add display mode (1080p50hz-1080p50hz-94, 1920x1080, 50.000000) 07-07 17:09:28.084 366 519 I MesonHwc: add display mode (1080i50hz-1080i50hz-95, 1920x1080, 50.000000) 07-07 17:09:28.084 366 519 D MesonHwc: add fraction display mode (720p60hz) 07-07 17:09:28.084 366 519 I MesonHwc: add display mode (720p60hz-720p60hz-96, 1280x720, 60.000000) 07-07 17:09:28.084 366 519 I MesonHwc: add display mode (720p50hz-720p50hz-97, 1280x720, 50.000000) 07-07 17:09:28.084 366 519 I MesonHwc: add display mode (576p50hz-576p50hz-98, 720x576, 50.000000) 07-07 17:09:28.084 366 519 I MesonHwc: add display mode (576i50hz-576i50hz-99, 720x576, 50.000000) 07-07 17:09:28.084 366 519 D MesonHwc: add fraction display mode (480p60hz) 07-07 17:09:28.084 366 519 I MesonHwc: add display mode (480p60hz-480p60hz-100, 720x480, 60.000000) 07-07 17:09:28.084 366 519 D MesonHwc: add fraction display mode (480i60hz) 07-07 17:09:28.084 366 519 I MesonHwc: add display mode (480i60hz-480i60hz-101, 720x480, 60.000000) 07-07 17:09:28.084 366 519 I MesonHwc: add display mode (dummy_l-dummy_l-102, 720x480, 50.000000) 07-07 17:09:28.084 366 519 D MesonHwc: add fraction display mode (640x480p60hz) 07-07 17:09:28.084 366 519 I MesonHwc: add display mode (640x480p60hz-640x480p60hz-103, 640x480, 60.000000) 07-07 17:09:28.084 366 519 I MesonHwc: loadDisplayModes (18) end 07-07 17:09:28.085 366 519 I MesonHwc: hdr_preference (dolby_vision) 07-07 17:09:28.092 366 519 D MesonHwc: mask off all hdrCaps, as it's 8bit color depth 07-07 17:09:28.092 366 519 D MesonHwc: dolby version:0, hlg:0, hdr10:0, hdr10+:0 max:0, avg:0, min:0 07-07 17:09:28.093 366 519 D MesonHwc: ERR: PLEASE FIX NON-IMPLEMENT FUN(update). 07-07 17:09:28.093 366 519 D MesonHwc: ModeMgr update to (1080p50hz - 1920x1080) 07-07 17:09:28.093 366 519 D MesonHwc: On mode change state: [Complete] 07-07 17:09:28.093 366 519 D MesonHwc: Hwc2Display::onModeChanged getDisplayMode [1080p50hz] 07-07 17:09:28.093 366 519 D MesonHwc: [setConnectorStatus]: power mode 1 07-07 17:09:28.093 366 519 I MesonHwc: HwcConfig::default frame buffer size (1920 x 1080) 07-07 17:09:28.093 366 519 D MesonHwc: onModeChanged mObserver->onHotplug(true) hdrCapsChanged:0 07-07 17:09:28.093 366 519 D MesonHwc: On hotplug, Fn: 0x1729481, Data: 0xf07412c0, display: 0, connection: 1 07-07 17:09:28.093 366 519 E android.hardware.graphics.composer@2.4-service.droidlogic: halEventcallback onHotplug client2.4 07-07 17:09:28.094 366 519 D MesonHwc: Display (0) ask for refresh. 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: change@/devices/virtual/amhdmitx/amhdmitx0 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: ACTION=change 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: DEVPATH=/devices/virtual/amhdmitx/amhdmitx0 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: SUBSYSTEM=amhdmitx 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: hdmitx_hdcp=0 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: MAJOR=504 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: MINOR=0 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: DEVNAME=amhdmitx0 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: SEQNUM=3802 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: OF_COMPATIBLE_N=1 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: MODALIAS=of:NvoutT(null)Camlogic,_vout 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: SEQNUM=3801 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: 0800 1e1680 180001f f8000000 10004ffc 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: MODALIAS=input:b0006v18D1p0100e0001-e0,1,k71,72,73,74,8B,96,9E,A3,A4,A5,A6,A7,A8,AC,C9,CF,D0,D9,130,131,133,134,161,165,166,16C,16D,16E,172,179,184,188,18E,18F,190,191,192,193,1D2,1D3,1D4,1D5,1D6,1D7,1D8,1D9,1DA,1DB,1DC,1DD,ramlsfw 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: SEQNUM=3563 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: change@/devices/virtual/amhdmitx/amhdmitx0 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: ACTION=change 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: DEVPATH=/devices/virtual/amhdmitx/amhdmitx0 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: SUBSYSTEM=amhdmitx 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: hdmitx_hdcp=0 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: MAJOR=504 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: MINOR=0 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: DEVNAME=amhdmitx0 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: SEQNUM=3802 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: OF_COMPATIBLE_N=1 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: MODALIAS=of:NvoutT(null)Camlogic,_vout 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: SEQNUM=3801 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: 0800 1e1680 180001f f8000000 10004ffc 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: MODALIAS=input:b0006v18D1p0100e0001-e0,1,k71,72,73,74,8B,96,9E,A3,A4,A5,A6,A7,A8,AC,C9,CF,D0,D9,130,131,133,134,161,165,166,16C,16D,16E,172,179,184,188,18E,18F,190,191,192,193,1D2,1D3,1D4,1D5,1D6,1D7,1D8,1D9,1DA,1DB,1DC,1DD,ramlsfw 07-07 17:09:28.094 366 519 D MesonHwc: received Uevent: SEQNUM=3563 07-07 17:09:28.104 0 0 I : [ 492.773774@0] plane 0 size: 16588800, vmalloc addr: 000000005c932d88. 07-07 17:09:28.108 0 0 I : [ 492.784022@4] plane 1 size: 16588800, vmalloc addr: 00000000bc2cbaae. 07-07 17:09:28.116 0 0 I : [ 492.791666@4] plane 2 size: 16588800, vmalloc addr: 00000000787b475a. 07-07 17:09:28.120 0 0 I : [ 492.797569@4] plane 3 size: 16588800, vmalloc addr: 000000009c8597ce. 07-07 17:09:28.120 0 0 W : [ 492.797584@4] header addr: 000000001ef4e8fa 07-07 17:09:28.120 0 0 D : [ 492.797588@4] FBC Decompress time: 0ms 07-07 17:09:28.103 355 6141 E SystemControl: [/SysWrite.cpp, readSys, 583] readSysFs, open /sys/class/amhdmitx/amhdmitx0/hdcp_pwr fail. Error info [No such file or directory] 07-07 17:09:28.103 355 6141 E SystemControl: [/SysWrite.cpp, writeSys, 218] writeSysFs, open /sys/class/amhdmitx/amhdmitx0/hdcp_ctrl fail. 07-07 17:09:28.103 355 6141 E SystemControl: [/SysWrite.cpp, writeSys, 218] writeSysFs, open /sys/class/amhdmitx/amhdmitx0/hdcp_ctrl fail. 07-07 17:09:28.124 355 6141 I SystemControl: [/HDCPTxAuth.cpp, authInit, 233] hdcp_tx 1.4 supported 07-07 17:09:28.124 355 6141 I SystemControl: [/HDCPTxAuth.cpp, authLoop, 256] hdcp_tx begin to authenticate hdcp22:0, hdcp14:1 07-07 17:09:28.188 0 0 D : [ 492.864400@1] bitblk time: 66ms 07-07 17:09:28.196 0 0 I [ 492.872177@1] vf->compwidth: 3840 v4l_data->byte_stride:3840 num_vp9_videos:3 07-07 17:09:28.196 0 0 I [ 492.872188@1] vf->compHeight: 2160 v4l_data->height:2160 num_vp9_videos:3 07-07 17:09:28.199 366 366 W ComposerResources: invalid cache 0 slot 2 07-07 17:09:28.199 417 417 W HwcComposer: command 0x2010000 generated error 4 07-07 17:09:28.202 0 0 I : [ 492.876151@1] mali fe400000.valhall: sg list from dma_buf_map_attachment > dma_buf->size=3133440 07-07 17:09:28.208 417 417 E HWC2 : destroyLayer(0, 662) failed: BadLayer (3) 07-07 17:09:28.208 417 417 E HWC2 : destroyLayer(0, 405) failed: BadLayer (3) 07-07 17:09:28.208 417 417 E HWC2 : destroyLayer(0, 148) failed: BadLayer (3) 07-07 17:09:28.209 417 417 E HWComposer: destroyLayer failed for display 18309309814584587: Invalid display 07-07 17:09:28.209 417 417 I chatty : uid=1000(system) /system/bin/surfaceflinger identical 1 line 07-07 17:09:28.209 417 417 E HWComposer: destroyLayer failed for display 18309309814584587: Invalid display 07-07 17:09:28.212 417 417 I FramebufferSurface: framebuffer size has been limited to [1920x1080] from [1920x1080] 07-07 17:09:28.219 417 576 W SurfaceFlinger: Failed dispatching Hotplug{displayId=18309309814584587, connected} for Connection{0xf2c82830, VSyncRequest::None} 07-07 17:09:28.219 417 417 D SurfaceFlinger: Setting power mode 2 on display 18309309814584587 07-07 17:09:28.219 366 366 D MesonHwc: ERR: PLEASE FIX NON-IMPLEMENT FUN(setPowerMode). 07-07 17:09:28.219 366 366 D MesonHwc: sysfs(/sys/class/amhdmitx/amhdmitx0/allm_cap) read int32_t (0) 07-07 17:09:28.219 366 366 D MesonHwc: sysfs(/sys/class/amhdmitx/amhdmitx0/allm_cap) read int32_t (0) 07-07 17:09:28.219 417 417 D SurfaceFlinger: Finished setting power mode 2 on display 18309309814584587 07-07 17:09:28.220 366 366 E MesonHwc: getPort 11 07-07 17:09:28.220 366 366 E MesonHwc: getPort 11 07-07 17:09:28.221 366 510 D MesonHwc: adjustVsyncMode to MixMode 07-07 17:09:28.221 417 690 W SurfaceFlinger: Can't find offset for 50.00 fps 07-07 17:09:28.221 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.221 355 475 I SystemControl: [frameRateDisplay] +++ 07-07 17:09:28.222 355 475 I SystemControl: SystemControlHal frameRateDisplay 1,[0 0 1920 1080] 07-07 17:09:28.222 355 475 I SystemControl: [/SysWrite.cpp, getKernelReleaseVersion, 635] getKernelReleaseVersion: 5.4 07-07 17:09:28.222 355 475 D SystemControl: axis is 1920x1080 07-07 17:09:28.223 366 510 D MesonHwc: VideoComposerDev (10) set (1). 07-07 17:09:28.223 0 0 I [ 492.900665@4] vc: [0]vc: set enable index=0, val=1 07-07 17:09:28.223 0 0 I : [ 492.900674@4] dev->index =0, val=2 07-07 17:09:28.224 0 0 I [ 492.900676@4] VID: VD1 off 07-07 17:09:28.224 0 0 I [ 492.900681@4] VID: VD1 set global output as 1 07-07 17:09:28.224 0 0 I [ 492.900699@4] VID: store VD0 path_id changed -1->2 07-07 17:09:28.224 0 0 I [ 492.900706@4] vc: [0]sideband_type =-1 07-07 17:09:28.223 355 475 E SystemControl: inScreen 1920 1080 [ 0 0 1920 1080] 07-07 17:09:28.223 355 475 D SystemControl: SystemControlHal frect.inscreen() 1 07-07 17:09:28.223 355 475 I SystemControl: [/SysWrite.cpp, getKernelReleaseVersion, 635] getKernelReleaseVersion: 5.4 07-07 17:09:28.223 355 475 D SystemControl: axis is 1920x1080 07-07 17:09:28.223 355 475 E SystemControl: inScreen 1920 1080 [ 0 0 1920 1080] 07-07 17:09:28.223 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, isFrameRateOn, 156] --isFrameRateOn 1 07-07 17:09:28.223 355 475 I SystemControl: [/FrameRateAutoAdaption.cpp, isFrameRateOn, 168] hdmi frame rate is on 07-07 17:09:28.223 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, setVideoLayerOn, 223] last is also videolayer 1 07-07 17:09:28.223 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, setVideoLayerOn, 231] last video layer on 0 07-07 17:09:28.224 355 475 D SystemControl: [/FrameRateAutoAdaption.cpp, policyControl, 263] mPlayFlag 1 frameRateValue3600 mLastFromVdin 0 07-07 17:09:28.224 355 475 I SystemControl: [frameRateDisplay] --- 07-07 17:09:28.226 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.228 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.233 417 777 E SurfaceFlinger: setDesiredDisplayConfigSpecs: failed to call setDesiredDisplayConfigSpecs: -22 07-07 17:09:28.234 649 669 E SurfaceFlinger: setDesiredDisplayConfigSpecs failed to transact: -22 07-07 17:09:28.237 366 520 D MesonHwc: [waitMixVsync] waitVBlank to get hw vsync timestamp 07-07 17:09:28.244 0 0 I : [ 492.900707@0] dev->index =0, val=0 07-07 17:09:28.241 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.244 0 0 E [ 492.919920@0] vpu: vpu: error: vpu_dev_mem_power_on: vpu_dev is null 07-07 17:09:28.244 0 0 E [ 492.919928@0] vpu: vpu: error: vpu_dev_mem_power_on: vpu_dev is null 07-07 17:09:28.244 0 0 E [ 492.919931@0] vpu: vpu: error: vpu_dev_mem_power_on: vpu_dev is null 07-07 17:09:28.244 0 0 E [ 492.919934@0] vpu: vpu: error: vpu_dev_mem_power_on: vpu_dev is null 07-07 17:09:28.244 0 0 I [ 492.920895@0] vd1_port: 0, vd2_port:1, val:0x1, 0x0 07-07 17:09:28.247 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.247 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.250 0 0 I [ 492.923301@4] hdcptx: hdcp_mode_set[128] 1 07-07 17:09:28.250 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.267 0 0 I [ 492.941230@0] video_send_uevent: VIDEO_FORMAT=2, ret=0 07-07 17:09:28.266 5180 5252 E demo-jni: next event: change@/devices/virtual/video/amvideo 07-07 17:09:28.266 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.286 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.286 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.288 525 956 I chatty : uid=1046(mediacodec) HwBinder:525_3 identical 1 line 07-07 17:09:28.311 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.325 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.329 5180 5252 E demo-jni: next event: change@/devices/virtual/amhdmitx/amhdmitx0 07-07 17:09:28.329 366 519 D MesonHwc: received Uevent: change@/devices/virtual/amhdmitx/amhdmitx0 07-07 17:09:28.329 366 519 D MesonHwc: received Uevent: ACTION=change 07-07 17:09:28.329 366 519 D MesonHwc: received Uevent: DEVPATH=/devices/virtual/amhdmitx/amhdmitx0 07-07 17:09:28.329 366 519 D MesonHwc: received Uevent: SUBSYSTEM=amhdmitx 07-07 17:09:28.329 366 519 D MesonHwc: received Uevent: hdmitx_hdcp=1 07-07 17:09:28.329 366 519 D MesonHwc: received Uevent: MAJOR=504 07-07 17:09:28.329 366 519 D MesonHwc: received Uevent: MINOR=0 07-07 17:09:28.329 366 519 D MesonHwc: received Uevent: DEVNAME=amhdmitx0 07-07 17:09:28.329 366 519 D MesonHwc: received Uevent: SEQNUM=3805 07-07 17:09:28.329 366 519 D MesonHwc: received Uevent: OF_COMPATIBLE_N=1 07-07 17:09:28.330 355 379 I SystemControl: [/UEventObserver.cpp, HDMITxUenventThreadLoop, 447] HDMI TX switch_name:hdmitx_hdcp, switch_state: 1 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: MODALIAS=of:NvoutT(null)Camlogic,_vout 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: SEQNUM=3801 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: 0800 1e1680 180001f f8000000 10004ffc 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: MODALIAS=input:b0006v18D1p0100e0001-e0,1,k71,72,73,74,8B,96,9E,A3,A4,A5,A6,A7,A8,AC,C9,CF,D0,D9,130,131,133,134,161,165,166,16C,16D,16E,172,179,184,188,18E,18F,190,191,192,193,1D2,1D3,1D4,1D5,1D6,1D7,1D8,1D9,1DA,1DB,1DC,1DD,ramlsfw 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: SEQNUM=3563 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: change@/devices/virtual/amhdmitx/amhdmitx0 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: ACTION=change 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: DEVPATH=/devices/virtual/amhdmitx/amhdmitx0 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: SUBSYSTEM=amhdmitx 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: hdmitx_hdcp=1 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: MAJOR=504 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: MINOR=0 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: DEVNAME=amhdmitx0 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: SEQNUM=3805 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: OF_COMPATIBLE_N=1 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: MODALIAS=of:NvoutT(null)Camlogic,_vout 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: SEQNUM=3801 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: 0800 1e1680 180001f f8000000 10004ffc 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: MODALIAS=input:b0006v18D1p0100e0001-e0,1,k71,72,73,74,8B,96,9E,A3,A4,A5,A6,A7,A8,AC,C9,CF,D0,D9,130,131,133,134,161,165,166,16C,16D,16E,172,179,184,188,18E,18F,190,191,192,193,1D2,1D3,1D4,1D5,1D6,1D7,1D8,1D9,1DA,1DB,1DC,1DD,ramlsfw 07-07 17:09:28.330 366 519 D MesonHwc: received Uevent: SEQNUM=3563 07-07 17:09:28.333 0 0 I [ 493.006575@0] hdcptx: mode 1, auth: 1 07-07 17:09:28.347 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.365 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.387 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.406 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.428 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.444 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.467 525 6133 I VDA : [0]"ServiceDeviceTask INs=45/45, OUTs=24/29"" {IN=0/3, OUT=8/1181586076[kAtAccelerator(1375641427) kAtDevice(917971148) kAtClient(-2098973460)]}" 07-07 17:09:28.468 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.473 0 0 I [ 493.149608@0] hdcptx: part 1 done 07-07 17:09:28.473 0 0 I [ 493.149624@0] hdcptx: 1x AuthDone 07-07 17:09:28.473 0 0 I [ 493.149641@0] hdcptx: hdcptx1: the rx all auth is pass 07-07 17:09:28.473 0 0 I [ 493.149663@0] cec: [hdmitx_notify_callback] unsupported notify:4 07-07 17:09:28.485 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.514 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.524 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.526 779 779 V AvrcpMediaPlayerWrapper: onPlaybackStateChanged(): org.xbmc.kodi : PlaybackState {state=3, position=647, buffered position=0, speed=1.0, updated=493195, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:28.527 779 779 E AvrcpMediaPlayerWrapper: The callback playback state doesn't match the current state 07-07 17:09:28.527 779 779 V AvrcpMediaPlayerWrapper: trySendMediaUpdate(): Metadata has been updated for org.xbmc.kodi 07-07 17:09:28.527 779 779 D AvrcpMediaPlayerList: sendMediaUpdate 07-07 17:09:28.527 779 779 I AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue 07-07 17:09:28.527 779 779 D AvrcpMediaPlayerList: sendMediaUpdate state=PlaybackState {state=3, position=0, buffered position=0, speed=1.0, updated=493199, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:28.527 779 779 D AvrcpTargetService: onMediaUpdated: track_changed=false state=false queue=false 07-07 17:09:28.527 779 779 D AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=false queue=false 07-07 17:09:28.527 779 779 D AvrcpTargetJni: sendMediaUpdateNative 07-07 17:09:28.527 779 779 I bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=0 : queue=0 07-07 17:09:28.547 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.565 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.576 321 422 I DTVKIT_LOG: TunerTask:3532 path:0:tune_idle_timer:3,frontend_usage=0 07-07 17:09:28.587 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.604 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.632 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.645 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.686 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.705 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.730 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.745 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.768 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.786 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.799 1428 6140 W adbd : timed out while waiting for FUNCTIONFS_BIND, trying again 07-07 17:09:28.799 1428 6140 I adbd : UsbFfs: connection terminated: monitor thread finished 07-07 17:09:28.799 1428 1428 I adbd : UsbFfs: already offline 07-07 17:09:28.799 1428 1428 I adbd : destroying transport UsbFfs 07-07 17:09:28.799 1428 1428 I adbd : UsbFfsConnection being destroyed 07-07 17:09:28.808 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.825 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.847 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.865 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.868 1428 1439 I adbd : opening control endpoint /dev/usb-ffs/adb/ep0 07-07 17:09:28.869 1428 1439 I adbd : UsbFfsConnection constructed 07-07 17:09:28.869 1428 6142 I adbd : UsbFfs-monitor thread spawned 07-07 17:09:28.887 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.905 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.929 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.945 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:28.969 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:28.985 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.007 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.024 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.024 779 779 V AvrcpMediaPlayerWrapper: onPlaybackStateChanged(): org.xbmc.kodi : PlaybackState {state=3, position=1054, buffered position=0, speed=1.0, updated=493695, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:29.025 779 779 E AvrcpMediaPlayerWrapper: The callback playback state doesn't match the current state 07-07 17:09:29.026 779 779 V AvrcpMediaPlayerWrapper: trySendMediaUpdate(): Metadata has been updated for org.xbmc.kodi 07-07 17:09:29.026 779 779 D AvrcpMediaPlayerList: sendMediaUpdate 07-07 17:09:29.026 779 779 I AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue 07-07 17:09:29.026 779 779 D AvrcpMediaPlayerList: sendMediaUpdate state=PlaybackState {state=3, position=0, buffered position=0, speed=1.0, updated=493697, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:29.026 779 779 D AvrcpTargetService: onMediaUpdated: track_changed=false state=false queue=false 07-07 17:09:29.026 779 779 D AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=false queue=false 07-07 17:09:29.026 779 779 D AvrcpTargetJni: sendMediaUpdateNative 07-07 17:09:29.026 779 779 I bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=0 : queue=0 07-07 17:09:29.047 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.065 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.087 321 429 I DTVKIT_LOG: DiskMonitorTask:1487 refresh disk start 07-07 17:09:29.090 321 429 I DTVKIT_LOG: DiskMonitorTask:1489 check disk start 07-07 17:09:29.090 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.105 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.147 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.165 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.188 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.205 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.228 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.245 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.272 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.285 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.310 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.325 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.347 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.365 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.387 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.405 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.434 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.445 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.467 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.484 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.487 525 6133 I VDA : [0]"ServiceDeviceTask INs=71/71, OUTs=48/53"" {IN=0/3, OUT=8/1181586076[kAtAccelerator(1375641427) kAtDevice(917971148) kAtClient(-2098973460)]}" 07-07 17:09:29.507 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.525 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.526 779 779 V AvrcpMediaPlayerWrapper: onPlaybackStateChanged(): org.xbmc.kodi : PlaybackState {state=3, position=1672, buffered position=0, speed=1.0, updated=494196, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:29.527 779 779 E AvrcpMediaPlayerWrapper: The callback playback state doesn't match the current state 07-07 17:09:29.528 779 779 V AvrcpMediaPlayerWrapper: trySendMediaUpdate(): Metadata has been updated for org.xbmc.kodi 07-07 17:09:29.528 779 779 D AvrcpMediaPlayerList: sendMediaUpdate 07-07 17:09:29.529 779 779 I AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue 07-07 17:09:29.529 779 779 D AvrcpMediaPlayerList: sendMediaUpdate state=PlaybackState {state=3, position=0, buffered position=0, speed=1.0, updated=494199, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:29.529 779 779 D AvrcpTargetService: onMediaUpdated: track_changed=false state=false queue=false 07-07 17:09:29.529 779 779 D AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=false queue=false 07-07 17:09:29.529 779 779 D AvrcpTargetJni: sendMediaUpdateNative 07-07 17:09:29.529 779 779 I bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=0 : queue=0 07-07 17:09:29.547 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.564 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.576 321 422 I DTVKIT_LOG: TunerTask:3532 path:0:tune_idle_timer:4,frontend_usage=0 07-07 17:09:29.587 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.604 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.651 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.665 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.687 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.705 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.730 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.745 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.766 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.785 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.805 357 5594 I audio_hw_primary: alsa format =0x1 delay frames =1536 total frames=6144000 07-07 17:09:29.807 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.807 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.808 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.809 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.810 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:29.825 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.866 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.870 1428 6142 W adbd : timed out while waiting for FUNCTIONFS_BIND, trying again 07-07 17:09:29.871 1428 6142 I adbd : UsbFfs: connection terminated: monitor thread finished 07-07 17:09:29.871 1428 1428 I adbd : UsbFfs: already offline 07-07 17:09:29.871 1428 1428 I adbd : destroying transport UsbFfs 07-07 17:09:29.872 1428 1428 I adbd : UsbFfsConnection being destroyed 07-07 17:09:29.905 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.923 1428 1439 I adbd : opening control endpoint /dev/usb-ffs/adb/ep0 07-07 17:09:29.925 1428 1439 I adbd : UsbFfsConnection constructed 07-07 17:09:29.926 1428 6144 I adbd : UsbFfs-monitor thread spawned 07-07 17:09:29.945 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:29.985 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.007 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.025 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.047 779 779 V AvrcpMediaPlayerWrapper: onPlaybackStateChanged(): org.xbmc.kodi : PlaybackState {state=3, position=2080, buffered position=0, speed=1.0, updated=494715, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:30.047 779 779 E AvrcpMediaPlayerWrapper: The callback playback state doesn't match the current state 07-07 17:09:30.048 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.049 779 779 V AvrcpMediaPlayerWrapper: trySendMediaUpdate(): Metadata has been updated for org.xbmc.kodi 07-07 17:09:30.049 779 779 D AvrcpMediaPlayerList: sendMediaUpdate 07-07 17:09:30.049 779 779 I AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue 07-07 17:09:30.049 779 779 D AvrcpMediaPlayerList: sendMediaUpdate state=PlaybackState {state=3, position=0, buffered position=0, speed=1.0, updated=494720, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:30.049 779 779 D AvrcpTargetService: onMediaUpdated: track_changed=false state=false queue=false 07-07 17:09:30.049 779 779 D AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=false queue=false 07-07 17:09:30.049 779 779 D AvrcpTargetJni: sendMediaUpdateNative 07-07 17:09:30.049 779 779 I bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=0 : queue=0 07-07 17:09:30.065 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.087 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.104 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.147 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.164 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.187 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.205 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.228 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.245 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.267 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.285 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.307 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.325 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.347 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.366 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.390 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.404 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.428 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.445 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.468 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.485 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.506 525 6133 I VDA : [0]"ServiceDeviceTask INs=95/95, OUTs=73/78"" {IN=0/3, OUT=8/1181586076[kAtAccelerator(1375641427) kAtDevice(917971148) kAtClient(-2098973460)]}" 07-07 17:09:30.507 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.525 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.551 779 779 V AvrcpMediaPlayerWrapper: onPlaybackStateChanged(): org.xbmc.kodi : PlaybackState {state=3, position=2700, buffered position=0, speed=1.0, updated=495221, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:30.551 779 779 E AvrcpMediaPlayerWrapper: The callback playback state doesn't match the current state 07-07 17:09:30.552 779 779 V AvrcpMediaPlayerWrapper: trySendMediaUpdate(): Metadata has been updated for org.xbmc.kodi 07-07 17:09:30.552 779 779 D AvrcpMediaPlayerList: sendMediaUpdate 07-07 17:09:30.552 779 779 I AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue 07-07 17:09:30.552 779 779 D AvrcpMediaPlayerList: sendMediaUpdate state=PlaybackState {state=3, position=0, buffered position=0, speed=1.0, updated=495223, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:30.552 779 779 D AvrcpTargetService: onMediaUpdated: track_changed=false state=false queue=false 07-07 17:09:30.552 779 779 D AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=false queue=false 07-07 17:09:30.552 779 779 D AvrcpTargetJni: sendMediaUpdateNative 07-07 17:09:30.552 779 779 I bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=0 : queue=0 07-07 17:09:30.555 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.565 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.577 321 422 I DTVKIT_LOG: TunerTask:3532 path:0:tune_idle_timer:5,frontend_usage=0 07-07 17:09:30.607 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.625 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.650 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.665 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.688 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.705 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.727 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.745 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.767 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.785 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.807 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.825 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.847 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.864 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.887 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.905 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.927 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.927 1428 6144 W adbd : timed out while waiting for FUNCTIONFS_BIND, trying again 07-07 17:09:30.927 1428 6144 I adbd : UsbFfs: connection terminated: monitor thread finished 07-07 17:09:30.929 1428 1428 I adbd : UsbFfs: already offline 07-07 17:09:30.929 1428 1428 I adbd : destroying transport UsbFfs 07-07 17:09:30.929 1428 1428 I adbd : UsbFfsConnection being destroyed 07-07 17:09:30.944 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:30.966 1428 1439 I adbd : opening control endpoint /dev/usb-ffs/adb/ep0 07-07 17:09:30.969 1428 1439 I adbd : UsbFfsConnection constructed 07-07 17:09:30.970 1428 6146 I adbd : UsbFfs-monitor thread spawned 07-07 17:09:30.970 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:30.985 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.007 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.025 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.047 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.065 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.066 779 779 V AvrcpMediaPlayerWrapper: onPlaybackStateChanged(): org.xbmc.kodi : PlaybackState {state=3, position=3116, buffered position=0, speed=1.0, updated=495735, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:31.069 779 779 E AvrcpMediaPlayerWrapper: The callback playback state doesn't match the current state 07-07 17:09:31.072 779 779 V AvrcpMediaPlayerWrapper: trySendMediaUpdate(): Metadata has been updated for org.xbmc.kodi 07-07 17:09:31.072 779 779 D AvrcpMediaPlayerList: sendMediaUpdate 07-07 17:09:31.072 779 779 I AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue 07-07 17:09:31.072 779 779 D AvrcpMediaPlayerList: sendMediaUpdate state=PlaybackState {state=3, position=0, buffered position=0, speed=1.0, updated=495743, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:31.072 779 779 D AvrcpTargetService: onMediaUpdated: track_changed=false state=false queue=false 07-07 17:09:31.072 779 779 D AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=false queue=false 07-07 17:09:31.072 779 779 D AvrcpTargetJni: sendMediaUpdateNative 07-07 17:09:31.072 779 779 I bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=0 : queue=0 07-07 17:09:31.107 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.125 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.147 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.164 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.190 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.205 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.227 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.245 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.267 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.285 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.307 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.324 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.350 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.365 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.387 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.406 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.427 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.445 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.467 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.484 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.507 525 6133 I VDA : [0]"ServiceDeviceTask INs=119/119, OUTs=97/102"" {IN=0/3, OUT=8/1181586076[kAtAccelerator(1375641427) kAtDevice(917971148) kAtClient(-2098973460)]}" 07-07 17:09:31.507 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.525 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.547 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.564 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.577 321 422 I DTVKIT_LOG: TunerTask:3532 path:0:tune_idle_timer:6,frontend_usage=0 07-07 17:09:31.587 779 779 V AvrcpMediaPlayerWrapper: onPlaybackStateChanged(): org.xbmc.kodi : PlaybackState {state=3, position=3727, buffered position=0, speed=1.0, updated=496255, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:31.590 779 779 E AvrcpMediaPlayerWrapper: The callback playback state doesn't match the current state 07-07 17:09:31.591 779 779 V AvrcpMediaPlayerWrapper: trySendMediaUpdate(): Metadata has been updated for org.xbmc.kodi 07-07 17:09:31.591 779 779 D AvrcpMediaPlayerList: sendMediaUpdate 07-07 17:09:31.591 779 779 I AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue 07-07 17:09:31.591 779 779 D AvrcpMediaPlayerList: sendMediaUpdate state=PlaybackState {state=3, position=0, buffered position=0, speed=1.0, updated=496262, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:31.592 779 779 D AvrcpTargetService: onMediaUpdated: track_changed=false state=false queue=false 07-07 17:09:31.592 779 779 D AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=false queue=false 07-07 17:09:31.592 779 779 D AvrcpTargetJni: sendMediaUpdateNative 07-07 17:09:31.592 779 779 I bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=0 : queue=0 07-07 17:09:31.607 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.625 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.647 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.665 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.687 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.706 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.727 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.745 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.767 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.785 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.807 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.825 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.847 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.865 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.887 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.905 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.929 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.945 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:31.967 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:31.971 1428 6146 W adbd : timed out while waiting for FUNCTIONFS_BIND, trying again 07-07 17:09:31.971 1428 6146 I adbd : UsbFfs: connection terminated: monitor thread finished 07-07 17:09:31.971 1428 1428 I adbd : UsbFfs: already offline 07-07 17:09:31.971 1428 1428 I adbd : destroying transport UsbFfs 07-07 17:09:31.971 1428 1428 I adbd : UsbFfsConnection being destroyed 07-07 17:09:31.985 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:32.008 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:32.018 1428 1439 I adbd : opening control endpoint /dev/usb-ffs/adb/ep0 07-07 17:09:32.019 1428 1439 I adbd : UsbFfsConnection constructed 07-07 17:09:32.020 1428 6147 I adbd : UsbFfs-monitor thread spawned 07-07 17:09:32.025 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:32.047 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:32.065 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:32.090 321 429 I DTVKIT_LOG: DiskMonitorTask:1487 refresh disk start 07-07 17:09:32.091 321 429 I DTVKIT_LOG: DiskMonitorTask:1489 check disk start 07-07 17:09:32.106 779 779 V AvrcpMediaPlayerWrapper: onPlaybackStateChanged(): org.xbmc.kodi : PlaybackState {state=3, position=4338, buffered position=0, speed=1.0, updated=496775, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:32.107 779 779 E AvrcpMediaPlayerWrapper: The callback playback state doesn't match the current state 07-07 17:09:32.109 779 779 V AvrcpMediaPlayerWrapper: trySendMediaUpdate(): Metadata has been updated for org.xbmc.kodi 07-07 17:09:32.109 779 779 D AvrcpMediaPlayerList: sendMediaUpdate 07-07 17:09:32.109 779 779 I AvrcpMediaPlayerList: sendMediaUpdate: Creating a one item queue for a player with no queue 07-07 17:09:32.110 779 779 D AvrcpMediaPlayerList: sendMediaUpdate state=PlaybackState {state=3, position=0, buffered position=0, speed=1.0, updated=496780, actions=-1, custom actions=[], active item id=-1, error=null} 07-07 17:09:32.110 779 779 D AvrcpTargetService: onMediaUpdated: track_changed=false state=false queue=false 07-07 17:09:32.110 779 779 D AvrcpNativeInterface: sendMediaUpdate: metadata=false playStatus=false queue=false 07-07 17:09:32.110 779 779 D AvrcpTargetJni: sendMediaUpdateNative 07-07 17:09:32.110 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:32.110 779 779 I bt_stack: [INFO:avrcp_service.cc(346)] virtual void bluetooth::avrcp::AvrcpService::SendMediaUpdate(bool, bool, bool) track_changed=0 : play_state=0 : queue=0 07-07 17:09:32.126 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:32.149 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:32.165 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:32.187 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:32.205 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:32.228 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:32.244 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:32.267 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:32.285 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2) 07-07 17:09:32.310 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:32.310 525 956 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:32.310 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:32.310 525 5275 I chatty : uid=1046(mediacodec) HwBinder:525_8 identical 1 line 07-07 17:09:32.310 525 5275 D OmxVideoDecoder: getConfig 3251 0x7f00000e 07-07 17:09:32.325 417 417 E CompositionEngine: [SurfaceView - org.xbmc.kodi/org.xbmc.kodi.Main#1] Invalid device requested composition type change: CLIENT (1) --> DEVICE (2)