Libreelec 11.0.3 on Nuc7i3Bnk - Graphics Problems

Boronsbruder · November 21, 2023 at 6:23 PM

I'm experiencing following problem:
If i try to view UHD-Programs on HD+ with VAAPI enabled there is only sound but no picture.
When VAAPI is disabled i can view stuttering picture with sound because of CPU ist going up to 300% load
The graphics is Intel HD 620 (KBL GT2).

My TV is only FullHD 1920x1080p connected via hdmi so UHD has to scaled down

Here is a short part of the debug log:

Code

2023-11-21 18:45:38.330 T:749     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-21 18:45:38.330 T:749     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 16448000.000000 len 145987
2023-11-21 18:45:38.330 T:749     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-21 18:45:38.330 T:749     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 16428000.000000 len 6049
2023-11-21 18:45:38.330 T:1048    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 16448000.000000 len 145987
2023-11-21 18:45:38.330 T:1048    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 16428000.000000 len 6049
2023-11-21 18:45:38.331 T:1069    error <general>: ffmpeg[0x54916c0]: [hevc] Could not find ref with POC 22802
2023-11-21 18:45:38.332 T:1069    error <general>: ffmpeg[0x54916c0]: [hevc] Failed to end picture decode issue: 18 (invalid parameter).
2023-11-21 18:45:38.332 T:1069    error <general>: ffmpeg[0x54916c0]: [hevc] hardware accelerator failed to decode picture
2023-11-21 18:45:38.332 T:1069    error <general>: ffmpeg[0x54916c0]: [hevc] Could not find ref with POC 22800
2023-11-21 18:45:38.332 T:1069    error <general>: ffmpeg[0x54916c0]: [hevc] Failed to end picture decode issue: 18 (invalid parameter).
2023-11-21 18:45:38.332 T:1069    error <general>: ffmpeg[0x54916c0]: [hevc] hardware accelerator failed to decode picture
2023-11-21 18:45:38.333 T:749     debug <general>: AddOnLog: pvr.hts: receive message [signalStatus]
2023-11-21 18:45:38.333 T:749     debug <general>: AddOnLog: pvr.hts: signalStatus:
2023-11-21 18:45:38.333 T:749     debug <general>: AddOnLog: pvr.hts:   status : GOOD
2023-11-21 18:45:38.333 T:749     debug <general>: AddOnLog: pvr.hts:   ber    : 0
2023-11-21 18:45:38.333 T:749     debug <general>: AddOnLog: pvr.hts:   unc    : 0
2023-11-21 18:45:38.336 T:749     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-21 18:45:38.336 T:749     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 16468000.000000 len 18561
2023-11-21 18:45:38.336 T:1048    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 16468000.000000 len 18561
2023-11-21 18:45:38.337 T:1069    error <general>: ffmpeg[0x54916c0]: [hevc] Failed to end picture decode issue: 18 (invalid parameter).
2023-11-21 18:45:38.337 T:1069    error <general>: ffmpeg[0x54916c0]: [hevc] hardware accelerator failed to decode picture

Display More

Under 10.0.4 There is no problem.
And i tried the legacy-image too

If something more is needed. Please tell me.

castokadru · November 23, 2023 at 10:52 PM

You could try out latest (look at the release date) images from here https://test.libreelec.tv/12.0/Generic/Generic/ and https://test.libreelec.tv/11.0/Generic/Generic/ .

Logfiles from those images probably would be interesting for the developers.

Boronsbruder · November 24, 2023 at 1:43 PM

LibreELEC-Generic.x86_64-11.0-nightly-20231120-4098571.img:

Code

2023-11-24 14:33:11.644 T:729     debug <general>: AddOnLog: pvr.hts: receive message [signalStatus]
2023-11-24 14:33:11.644 T:729     debug <general>: AddOnLog: pvr.hts: signalStatus:
2023-11-24 14:33:11.644 T:729     debug <general>: AddOnLog: pvr.hts:   status : GOOD
2023-11-24 14:33:11.645 T:729     debug <general>: AddOnLog: pvr.hts:   ber    : 0
2023-11-24 14:33:11.645 T:729     debug <general>: AddOnLog: pvr.hts:   unc    : 0
2023-11-24 14:33:11.649 T:696     debug <general>: CDRMAtomicRequest::LogAtomicRequest - DRM Atomic Request:
2023-11-24 14:33:11.649 T:696     debug <general>: 
                                                   Object: plane    ID: 39
                                                     Property: SRC_X    ID: 9    Value: 0
                                                     Property: SRC_Y    ID: 10    Value: 0
                                                     Property: SRC_W    ID: 11    Value: 125829120
                                                     Property: SRC_H    ID: 12    Value: 70778880
                                                     Property: CRTC_X    ID: 13    Value: 0
                                                     Property: CRTC_Y    ID: 14    Value: 0
                                                     Property: CRTC_W    ID: 15    Value: 1920
                                                     Property: CRTC_H    ID: 16    Value: 1080
                                                     Property: FB_ID    ID: 17    Value: 117
                                                     Property: CRTC_ID    ID: 20    Value: 51
2023-11-24 14:33:11.657 T:729     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-24 14:33:11.657 T:729     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 214195388.000000 len 63802
2023-11-24 14:33:11.657 T:913     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 214195388.000000 len 63802
2023-11-24 14:33:11.657 T:729     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-24 14:33:11.657 T:729     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 214175388.000000 len 5510
2023-11-24 14:33:11.657 T:729     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-24 14:33:11.657 T:913     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 214175388.000000 len 5510
2023-11-24 14:33:11.657 T:729     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 214215388.000000 len 799
2023-11-24 14:33:11.657 T:913     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 214215388.000000 len 799
2023-11-24 14:33:11.657 T:935     error <general>: ffmpeg[0x6190090]: [hevc] Could not find ref with POC 35742
2023-11-24 14:33:11.658 T:935     error <general>: ffmpeg[0x6190090]: [hevc] Failed to end picture decode issue: 18 (invalid parameter).
2023-11-24 14:33:11.658 T:935     error <general>: ffmpeg[0x6190090]: [hevc] hardware accelerator failed to decode picture
2023-11-24 14:33:11.658 T:935     error <general>: ffmpeg[0x6190090]: [hevc] Could not find ref with POC 35740
2023-11-24 14:33:11.658 T:935     error <general>: ffmpeg[0x6190090]: [hevc] Failed to end picture decode issue: 18 (invalid parameter).
2023-11-24 14:33:11.658 T:935     error <general>: ffmpeg[0x6190090]: [hevc] hardware accelerator failed to decode picture
2023-11-24 14:33:11.658 T:935     error <general>: ffmpeg[0x6190090]: [hevc] Failed to end picture decode issue: 18 (invalid parameter).
2023-11-24 14:33:11.658 T:935     error <general>: ffmpeg[0x6190090]: [hevc] hardware accelerator failed to decode picture

Display More

Boronsbruder · November 24, 2023 at 1:47 PM

LibreELEC-Generic.x86_64-12.0-nightly-20231124-52b450b.img:

Code

2023-11-24 14:36:43.305 T:839     debug <general>: CDVDDemuxClient::ParsePacket - (1001) profile changed from -99 to 2
2023-11-24 14:36:43.305 T:839     debug <general>: CDVDDemuxClient::ParsePacket - (1001) level changed from -99 to 153
2023-11-24 14:36:43.305 T:839     debug <general>: CDVDDemuxClient::ParsePacket - (1001) aspect changed from 0 to 1.7777777777777777
2023-11-24 14:36:43.305 T:839     debug <general>: CDVDDemuxClient::ParsePacket - (1001) fps changed from 1000000/40000 to 50/1
2023-11-24 14:36:43.305 T:839     debug <general>: CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 173
2023-11-24 14:36:43.305 T:839     debug <general>: CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86019
2023-11-24 14:36:43.305 T:839      info <general>: Opening stream: 1001 source: 256
2023-11-24 14:36:43.305 T:839      info <general>: [WHITELIST] Searching the whitelist for: width: 3840, height: 2160, fps: 50.000, 3D: false
2023-11-24 14:36:43.305 T:839     debug <general>: [WHITELIST] Using the default whitelist because the user whitelist is empty
2023-11-24 14:36:43.305 T:839     debug <general>: [WHITELIST] Searching for an exact resolution with an exact refresh rate
2023-11-24 14:36:43.305 T:839     debug <general>: [WHITELIST] No match for an exact resolution with an exact refresh rate
2023-11-24 14:36:43.305 T:839     debug <general>: [WHITELIST] Searching for an exact resolution with double the refresh rate
2023-11-24 14:36:43.305 T:839     debug <general>: [WHITELIST] No match for an exact resolution with double the refresh rate
2023-11-24 14:36:43.305 T:839     debug <general>: [WHITELIST] Searching for an exact resolution with a 3:2 pulldown refresh rate
2023-11-24 14:36:43.305 T:839     debug <general>: [WHITELIST] No match for a resolution with a 3:2 pulldown refresh rate
2023-11-24 14:36:43.305 T:839     debug <general>: [WHITELIST] Searching for a desktop resolution with an exact refresh rate
2023-11-24 14:36:43.305 T:839     debug <general>: [WHITELIST] Matched a desktop resolution with an exact refresh rate 1920x1080 @ 50.000000 Hz (16)
2023-11-24 14:36:43.305 T:839      info <general>: Display resolution ADJUST : 1920x1080 @ 50.000000 Hz (16) (weight: 0.000)
2023-11-24 14:36:43.307 T:758     debug <general>: CDRMAtomicRequest::LogAtomicRequest - DRM Atomic Request:
2023-11-24 14:36:43.307 T:758     debug <general>: 
                                                   Object: plane    ID: 39
                                                     Property: SRC_X    ID: 9    Value: 0
                                                     Property: SRC_Y    ID: 10    Value: 0
                                                     Property: SRC_W    ID: 11    Value: 125829120
                                                     Property: SRC_H    ID: 12    Value: 70778880
                                                     Property: CRTC_X    ID: 13    Value: 0
                                                     Property: CRTC_Y    ID: 14    Value: 0
                                                     Property: CRTC_W    ID: 15    Value: 1920
                                                     Property: CRTC_H    ID: 16    Value: 1080
                                                     Property: FB_ID    ID: 17    Value: 122
                                                     Property: CRTC_ID    ID: 20    Value: 51
2023-11-24 14:36:43.308 T:803     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-24 14:36:43.308 T:803     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1190511.000000 len 81552
2023-11-24 14:36:43.308 T:803     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-24 14:36:43.308 T:803     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1150511.000000 len 6485
2023-11-24 14:36:43.308 T:803     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-24 14:36:43.308 T:803     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1130511.000000 len 715
2023-11-24 14:36:43.308 T:803     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-24 14:36:43.308 T:803     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1170511.000000 len 250
2023-11-24 14:36:43.312 T:813     debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-11-24 14:36:43.318 T:813     debug <general>: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetEpisodes", "params": {"properties": ["title", "playcount", "season", "episode", "showtitle", "plot", "file", "rating", "resume", "tvshowid", "art", "streamdetails", "firstaired", "runtime", "director", "writer", "cast", "dateadded", "lastplayed"], "limits": {"end": 1}, "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "lessthan", "value": "1"}, "tvshowid": 31}}
2023-11-24 14:36:43.318 T:813     debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-11-24 14:36:43.324 T:839      info <general>: Creating video codec with codec id: 173
2023-11-24 14:36:43.324 T:839      info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: HEVC (High Efficiency Video Coding)
2023-11-24 14:36:43.325 T:839     debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-hevc
2023-11-24 14:36:43.326 T:839     debug <general>: CVideoPlayerVideo::OpenStream - open stream with codec id: 173
2023-11-24 14:36:43.327 T:839      info <general>: Creating video thread
2023-11-24 14:36:43.327 T:842     debug <general>: Thread VideoPlayerVideo start, auto delete: false
2023-11-24 14:36:43.327 T:842      info <general>: running thread: video_thread
2023-11-24 14:36:43.327 T:839      info <general>: Opening stream: 1002 source: 256
2023-11-24 14:36:43.327 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 870511.000000 len 89246
2023-11-24 14:36:43.327 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 830511.000000 len 35953
2023-11-24 14:36:43.327 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 810511.000000 len 665
2023-11-24 14:36:43.327 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 850511.000000 len 1572
2023-11-24 14:36:43.327 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 82511.000000 len 960
2023-11-24 14:36:43.327 T:839     debug <general>: CDVDDemuxClient::ParsePacket - (1002) samplerate changed from 3 to 48000
2023-11-24 14:36:43.327 T:839      info <general>: Opening stream: 1001 source: 256
2023-11-24 14:36:43.327 T:839      info <general>: Opening stream: 1002 source: 256
2023-11-24 14:36:43.327 T:839      info <general>: Finding audio codec for: 86019
2023-11-24 14:36:43.328 T:839      info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3
2023-11-24 14:36:43.328 T:758     debug <general>: CDRMAtomicRequest::LogAtomicRequest - DRM Atomic Request:
2023-11-24 14:36:43.328 T:758     debug <general>: 
                                                   Object: plane    ID: 39
                                                     Property: SRC_X    ID: 9    Value: 0
                                                     Property: SRC_Y    ID: 10    Value: 0
                                                     Property: SRC_W    ID: 11    Value: 125829120
                                                     Property: SRC_H    ID: 12    Value: 70778880
                                                     Property: CRTC_X    ID: 13    Value: 0
                                                     Property: CRTC_Y    ID: 14    Value: 0
                                                     Property: CRTC_W    ID: 15    Value: 1920
                                                     Property: CRTC_H    ID: 16    Value: 1080
                                                     Property: FB_ID    ID: 17    Value: 123
                                                     Property: CRTC_ID    ID: 20    Value: 51
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 114511.000000 len 960
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 146511.000000 len 960
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 178511.000000 len 960
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 210511.000000 len 960
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 242511.000000 len 960
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 910511.000000 len 58751
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 890511.000000 len 345
2023-11-24 14:36:43.328 T:841     debug <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false
2023-11-24 14:36:43.328 T:842     debug <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false
2023-11-24 14:36:43.328 T:839     debug <general>: CVideoPlayer::HandleMessages - player 1 reported state: 0
2023-11-24 14:36:43.328 T:841     debug <general>: CDVDAudio::Pause - pausing audio stream
2023-11-24 14:36:43.328 T:839     debug <general>: CVideoPlayer::HandleMessages - player 2 reported state: 0
2023-11-24 14:36:43.328 T:842     debug <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2023-11-24 14:36:43.328 T:839     debug <general>: CVideoPlayer::HandleMessages - player 2 reported state: 0
2023-11-24 14:36:43.328 T:839     debug <general>: CVideoPlayer::HandleMessages - player 1 reported state: 0
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 930511.000000 len 489
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1110511.000000 len 210965
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1030511.000000 len 95654
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 990511.000000 len 66817
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 970511.000000 len 192
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1010511.000000 len 359
2023-11-24 14:36:43.328 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1070511.000000 len 68169
2023-11-24 14:36:43.329 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1050511.000000 len 682
2023-11-24 14:36:43.329 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1090511.000000 len 372
2023-11-24 14:36:43.329 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 274511.000000 len 960
2023-11-24 14:36:43.329 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 306511.000000 len 960
2023-11-24 14:36:43.329 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 338511.000000 len 960
2023-11-24 14:36:43.329 T:841      info <general>: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, no pass-through)
2023-11-24 14:36:43.329 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 370511.000000 len 960
2023-11-24 14:36:43.329 T:841     debug <general>: CVideoPlayerAudio:: synctype set to 1: resample
2023-11-24 14:36:43.329 T:779     debug <general>: OnAVChange: CApplication::OnAVChange
2023-11-24 14:36:43.330 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 402511.000000 len 960
2023-11-24 14:36:43.330 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 434511.000000 len 960
2023-11-24 14:36:43.330 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1270511.000000 len 201399
2023-11-24 14:36:43.330 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1190511.000000 len 81552
2023-11-24 14:36:43.330 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1150511.000000 len 6485
2023-11-24 14:36:43.330 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1130511.000000 len 715
2023-11-24 14:36:43.330 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1170511.000000 len 250
2023-11-24 14:36:43.331 T:771      info <general>: CActiveAESink::OpenSink - initialize sink
2023-11-24 14:36:43.331 T:771     debug <general>: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0
2023-11-24 14:36:43.331 T:771      info <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"
2023-11-24 14:36:43.333 T:842     debug <general>: libva info: VA-API version 1.20.0
2023-11-24 14:36:43.333 T:842     debug <general>: libva info: Trying to open /usr/lib/dri/iHD_drv_video.so
2023-11-24 14:36:43.333 T:842     debug <general>: libva info: Found init function __vaDriverInit_1_20
2023-11-24 14:36:43.335 T:842     debug <general>: libva info: va_openDriver() returns 0
2023-11-24 14:36:43.336 T:843     debug <general>: Thread Vaapi-Output start, auto delete: false
2023-11-24 14:36:43.336 T:843      info <general>: COutput::OnStartup: Output Thread created
2023-11-24 14:36:43.337 T:803     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-24 14:36:43.337 T:803     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1230511.000000 len 63991
2023-11-24 14:36:43.337 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1230511.000000 len 63991
2023-11-24 14:36:43.337 T:803     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-24 14:36:43.337 T:803     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1210511.000000 len 567
2023-11-24 14:36:43.337 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1210511.000000 len 567
2023-11-24 14:36:43.337 T:803     debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-11-24 14:36:43.337 T:803     debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1250511.000000 len 242
2023-11-24 14:36:43.337 T:839     debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1250511.000000 len 242
2023-11-24 14:36:43.342 T:771      info <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
2023-11-24 14:36:43.342 T:771      info <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2023-11-24 14:36:43.342 T:771      info <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
2023-11-24 14:36:43.342 T:771     debug <general>: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600
2023-11-24 14:36:43.350 T:813     debug <general>: RunQuery took 29 ms for 815 items query: select * from episode_view  WHERE (episode_view.idShow = 31) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
2023-11-24 14:36:43.351 T:813     debug <general>: DatabaseUtils::GetSortFieldList: unknown field 36
2023-11-24 14:36:43.357 T:771     debug <general>: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600
2023-11-24 14:36:43.357 T:771     debug <general>: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
2023-11-24 14:36:43.360 T:842     debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-hevc-vaapi
2023-11-24 14:36:43.360 T:758     debug <general>: FreeVisualisation() started
2023-11-24 14:36:43.360 T:758     debug <general>: SECTION:UnloadDll(/storage/.kodi/addons/visualization.matrix/visualization.matrix.so.20.2.0)
2023-11-24 14:36:43.360 T:758      info <general>: ADDON: Dll Destroyed - Matrix
2023-11-24 14:36:43.360 T:758     debug <general>: FreeVisualisation() done
2023-11-24 14:36:43.361 T:771     debug <general>: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
2023-11-24 14:36:43.361 T:771     debug <general>: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
2023-11-24 14:36:43.361 T:771     debug <general>: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR)
2023-11-24 14:36:43.363 T:758     debug <general>: CDRMAtomicRequest::LogAtomicRequest - DRM Atomic Request:
2023-11-24 14:36:43.363 T:758     debug <general>: 
                                                   Object: plane    ID: 39
                                                     Property: SRC_X    ID: 9    Value: 0
                                                     Property: SRC_Y    ID: 10    Value: 0
                                                     Property: SRC_W    ID: 11    Value: 125829120
                                                     Property: SRC_H    ID: 12    Value: 70778880
                                                     Property: CRTC_X    ID: 13    Value: 0
                                                     Property: CRTC_Y    ID: 14    Value: 0
                                                     Property: CRTC_W    ID: 15    Value: 1920
                                                     Property: CRTC_H    ID: 16    Value: 1080
                                                     Property: FB_ID    ID: 17    Value: 117
                                                     Property: CRTC_ID    ID: 20    Value: 51
2023-11-24 14:36:43.364 T:771     debug <general>: CActiveAESink::OpenSink - ALSA Initialized:
2023-11-24 14:36:43.364 T:771     debug <general>:   Output Device : HDA Intel PCH
2023-11-24 14:36:43.364 T:771     debug <general>:   Sample Rate   : 48000
2023-11-24 14:36:43.364 T:771     debug <general>:   Sample Format : AE_FMT_S32NE
2023-11-24 14:36:43.364 T:771     debug <general>:   Channel Count : 2
2023-11-24 14:36:43.364 T:771     debug <general>:   Channel Layout: FL, FR
2023-11-24 14:36:43.364 T:771     debug <general>:   Frames        : 2400
2023-11-24 14:36:43.364 T:771     debug <general>:   Frame Size    : 8
2023-11-24 14:36:43.366 T:770     debug <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2023-11-24 14:36:43.373 T:813      info <general>: Skipped 1 duplicate messages..
2023-11-24 14:36:43.373 T:813     debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-11-24 14:36:43.379 T:813     debug <general>: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetEpisodes", "params": {"properties": ["title", "playcount", "season", "episode", "showtitle", "plot", "file", "rating", "resume", "tvshowid", "art", "streamdetails", "firstaired", "runtime", "director", "writer", "cast", "dateadded", "lastplayed"], "limits": {"end": 1}, "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "lessthan", "value": "1"}, "tvshowid": 23}}
2023-11-24 14:36:43.380 T:813     debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-11-24 14:36:43.387 T:758     debug <general>: CDRMAtomicRequest::LogAtomicRequest - DRM Atomic Request:
2023-11-24 14:36:43.387 T:758     debug <general>: 
                                                   Object: plane    ID: 39
                                                     Property: SRC_X    ID: 9    Value: 0
                                                     Property: SRC_Y    ID: 10    Value: 0
                                                     Property: SRC_W    ID: 11    Value: 125829120
                                                     Property: SRC_H    ID: 12    Value: 70778880
                                                     Property: CRTC_X    ID: 13    Value: 0
                                                     Property: CRTC_Y    ID: 14    Value: 0
                                                     Property: CRTC_W    ID: 15    Value: 1920
                                                     Property: CRTC_H    ID: 16    Value: 1080
                                                     Property: FB_ID    ID: 17    Value: 120
                                                     Property: CRTC_ID    ID: 20    Value: 51
2023-11-24 14:36:43.388 T:813     debug <general>: RunQuery took 4 ms for 75 items query: select * from episode_view  WHERE (episode_view.idShow = 23) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
2023-11-24 14:36:43.388 T:813     debug <general>: DatabaseUtils::GetSortFieldList: unknown field 36
2023-11-24 14:36:43.390 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Failed to end picture decode issue: 18 (invalid parameter).
2023-11-24 14:36:43.390 T:842     error <general>: ffmpeg[0x602f200]: [hevc] hardware accelerator failed to decode picture
2023-11-24 14:36:43.391 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Could not find ref with POC -19227
2023-11-24 14:36:43.392 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Failed to end picture decode issue: 18 (invalid parameter).
2023-11-24 14:36:43.392 T:842     error <general>: ffmpeg[0x602f200]: [hevc] hardware accelerator failed to decode picture
2023-11-24 14:36:43.394 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Could not find ref with POC -19227
2023-11-24 14:36:43.394 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Could not find ref with POC -19219
2023-11-24 14:36:43.394 T:813     debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-11-24 14:36:43.400 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Failed to end picture decode issue: 18 (invalid parameter).
2023-11-24 14:36:43.400 T:842     error <general>: ffmpeg[0x602f200]: [hevc] hardware accelerator failed to decode picture
2023-11-24 14:36:43.400 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Could not find ref with POC -19227
2023-11-24 14:36:43.400 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Could not find ref with POC -19223
2023-11-24 14:36:43.400 T:813     debug <general>: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetEpisodes", "params": {"properties": ["title", "playcount", "season", "episode", "showtitle", "plot", "file", "rating", "resume", "tvshowid", "art", "streamdetails", "firstaired", "runtime", "director", "writer", "cast", "dateadded", "lastplayed"], "limits": {"end": 1}, "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "lessthan", "value": "1"}, "tvshowid": 58}}
2023-11-24 14:36:43.400 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Could not find ref with POC -19219
2023-11-24 14:36:43.400 T:813     debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-11-24 14:36:43.406 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Failed to end picture decode issue: 18 (invalid parameter).
2023-11-24 14:36:43.406 T:842     error <general>: ffmpeg[0x602f200]: [hevc] hardware accelerator failed to decode picture
2023-11-24 14:36:43.406 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Could not find ref with POC -19227
2023-11-24 14:36:43.406 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Could not find ref with POC -19225
2023-11-24 14:36:43.406 T:842     error <general>: ffmpeg[0x602f200]: [hevc] Could not find ref with POC -19223

Display More

**chewitt** · November 25, 2023 at 7:25 AM

Full debug logs please, not snippets.

Boronsbruder · November 28, 2023 at 12:16 PM

kodi.log

Shared with Dropbox

www.dropbox.com

Here a Dropbox Link....
Because the log file is roundabout 22 MB large from boot until sound and no picture....

Boronsbruder · December 5, 2023 at 11:57 AM

Today i had some time for testing
The problem is only when viewing UHD live-TV via Tvheadend HTSP.
mkv H265 files downloaded from Jellyfish run fine

Code

2023-12-05 12:46:43.829 T:2015    debug <general>: CVideoPlayer::HandleMessages - player started 2
2023-12-05 12:46:43.829 T:2015    debug <general>: VideoPlayer::Sync - Video - pts: 0.000000, cache: 50000.000000, totalcache: 100000.000000
2023-12-05 12:46:43.829 T:2015  warning <general>: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
2023-12-05 12:46:43.829 T:2015    debug <general>: CVideoPlayer::SetCaching - caching state 0
2023-12-05 12:46:43.829 T:2015    debug <general>: CDVDClock::SetSpeedAdjust - adjusted:0.000000
2023-12-05 12:46:43.830 T:1970    debug <general>: OnAVStarted: CApplication::OnAVStarted
2023-12-05 12:46:43.830 T:984     debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-05 12:46:43.839 T:983     debug <general>: Activating window ID: 12005
2023-12-05 12:46:43.839 T:983     debug <general>: ------ Window Deinit (MyVideoNav.xml) ------
2023-12-05 12:46:43.839 T:983     debug <general>: FreeVisualisation() done
2023-12-05 12:46:43.839 T:983     debug <general>: ------ Window Init (VideoFullScreen.xml) ------
2023-12-05 12:46:43.839 T:983      info <general>: [WHITELIST] Searching the whitelist for: width: 3840, height: 2160, fps: 29.970, 3D: false
2023-12-05 12:46:43.839 T:983     debug <general>: [WHITELIST] Using the default whitelist because the user whitelist is empty
2023-12-05 12:46:43.839 T:983     debug <general>: [WHITELIST] Searching for an exact resolution with an exact refresh rate
2023-12-05 12:46:43.840 T:983     debug <general>: [WHITELIST] No match for an exact resolution with an exact refresh rate
2023-12-05 12:46:43.840 T:983     debug <general>: [WHITELIST] Searching for an exact resolution with double the refresh rate
2023-12-05 12:46:43.840 T:983     debug <general>: [WHITELIST] No match for an exact resolution with double the refresh rate
2023-12-05 12:46:43.840 T:983     debug <general>: [WHITELIST] Searching for an exact resolution with a 3:2 pulldown refresh rate
2023-12-05 12:46:43.840 T:983     debug <general>: [WHITELIST] No match for a resolution with a 3:2 pulldown refresh rate
2023-12-05 12:46:43.840 T:983     debug <general>: [WHITELIST] Searching for a desktop resolution with an exact refresh rate
2023-12-05 12:46:43.840 T:983     debug <general>: [WHITELIST] No match for a desktop resolution with an exact refresh rate
2023-12-05 12:46:43.840 T:983     debug <general>: [WHITELIST] Searching for a desktop resolution with double the refresh rate
2023-12-05 12:46:43.840 T:983     debug <general>: [WHITELIST] Matched a desktop resolution with double the refresh rate 1920x1080 @ 59.940063 Hz (19)
2023-12-05 12:46:43.840 T:983      info <general>: Display resolution ADJUST : 1920x1080 @ 59.940063 Hz (19) (weight: 0.000)
2023-12-05 12:46:43.840 T:984     debug <general>: GetMovieId (smb://ZEROSERVER/Video/movie/testfiles/jellyfish-400-mbps-4k-uhd-hevc-10bit.mkv), query = select idMovie from movie where idFile=10778
2023-12-05 12:46:43.841 T:983     debug <general>: CDRMAtomicRequest::LogAtomicRequest - DRM Atomic Request:
2023-12-05 12:46:43.841 T:983     debug <general>: 
                                                   Object: plane	ID: 39
                                                     Property: SRC_X	ID: 9	Value: 0
                                                     Property: SRC_Y	ID: 10	Value: 0
                                                     Property: SRC_W	ID: 11	Value: 125829120
                                                     Property: SRC_H	ID: 12	Value: 70778880
                                                     Property: CRTC_X	ID: 13	Value: 0
                                                     Property: CRTC_Y	ID: 14	Value: 0
                                                     Property: CRTC_W	ID: 15	Value: 1920
                                                     Property: CRTC_H	ID: 16	Value: 1080
                                                     Property: FB_ID	ID: 17	Value: 117
                                                     Property: CRTC_ID	ID: 20	Value: 51
2023-12-05 12:46:43.843 T:1032    debug <general>: JSONRPC: Incoming request: {"id":10298,"jsonrpc":"2.0","method":"Playlist.GetItems","params":{"playlistid":1,"properties":["title","thumbnail","fanart","rating","genre","artist","track","season","episode","year","duration","albumid","album","showtitle","playcount","file"]}}
2023-12-05 12:46:43.844 T:984     debug <general>: GetEpisodeId (smb://ZEROSERVER/Video/movie/testfiles/jellyfish-400-mbps-4k-uhd-hevc-10bit.mkv), query = select idEpisode from episode where idFile=10778
2023-12-05 12:46:43.846 T:984     debug <general>: GetMusicVideoId (smb://ZEROSERVER/Video/movie/testfiles/jellyfish-400-mbps-4k-uhd-hevc-10bit.mkv), query = select idMVideo from musicvideo where idFile=10778
2023-12-05 12:46:43.854 T:983     debug <general>: ------ Window Deinit (DialogBusy.xml) ------
2023-12-05 12:46:43.854 T:1032    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-05 12:46:43.856 T:983     debug <general>: CDRMAtomicRequest::LogAtomicRequest - DRM Atomic Request:
2023-12-05 12:46:43.856 T:983     debug <general>: 
                                                   Object: plane	ID: 39
                                                     Property: SRC_X	ID: 9	Value: 0
                                                     Property: SRC_Y	ID: 10	Value: 0
                                                     Property: SRC_W	ID: 11	Value: 125829120
                                                     Property: SRC_H	ID: 12	Value: 70778880
                                                     Property: CRTC_X	ID: 13	Value: 0
                                                     Property: CRTC_Y	ID: 14	Value: 0
                                                     Property: CRTC_W	ID: 15	Value: 1920
                                                     Property: CRTC_H	ID: 16	Value: 1080
                                                     Property: FB_ID	ID: 17	Value: 120
                                                     Property: CRTC_ID	ID: 20	Value: 51

Display More

Boronsbruder · December 6, 2023 at 11:22 AM

Next tries:
- Record UHD via thvheadend pass profile:

play it in kodi -> works fine

play it with vlc om my pc -> short video then video stops and audio continues

play it with windows 11 mediaplayer -> bugged video

play it with legacy mediaplayer -> running fine

- stream UHD via tvheadend "pass"-profile to vlc on my pc -> short video then video stops and audio continues (same as when play recorded file)

- stream UHD via tvheadend "matroska"-profile:

is working fine on vlc and on kodi with "use http for streams" enabled

So Libreelec seems not to be the problem but what can it be?
The Tvheadend-HTSP addon?
Tvheadend itself (i don't think so, because with pass the transport stream should be untouched)
And why is the passed file running only some mediaplayers?

Here more infos about my system
Tvheadend 4.3 on Debian Bullseye Server (Intel(R) Xeon(R) CPU E5-1650 v3 @ 3.50GHz / 64GB RAM) with TBS 6909x
Libreelec 12 nightly on Intel Nuc7i3BNK

Can any admin please move it from "Bug reports" to where it should be posted....

**Da Flex** · December 6, 2023 at 3:05 PM

Quote from Boronsbruder

Can any admin please move it from "Bug reports" to where it should be posted....

We keep it here till better knowledge.

Boronsbruder · December 22, 2023 at 6:43 AM

I recorded a new log with LibreELEC-Generic.x86_64-12.0-nightly-20231221-581a2fe because there is still no UHD

Code

2023-12-22 07:15:08.547 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [subscriptionStart]
2023-12-22 07:15:08.548 T:1115    debug <general>: AddOnLog: pvr.hts: demux subscription start
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   id: 1001, type HEVC, codec: 173
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   id: 1002, type AC3, codec: 86019
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts: demux stream change
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts: demux sourceInfo:
2023-12-22 07:15:08.550 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :-11 pts 18442240474082181120.000000 len 0
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   satpos : 19.2E
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   mux     : 10993.75H
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   adapter : TurboSight TBS 6909x (Octa DVB-S/S2/S2X) #7 : DVB-S #0
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   network : DVB ASTRA
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   provider : SES ASTRA
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   service : UHD1 by ASTRA / HD+
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [queueStatus]
2023-12-22 07:15:08.550 T:1155    debug <general>: CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 173
2023-12-22 07:15:08.550 T:1155    debug <general>: CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86019
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts: stream stats:
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   idx:1001 num:0
2023-12-22 07:15:08.550 T:1155     info <general>: Opening stream: 1001 source: 256
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   idx:1002 num:0
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts: queue stats:
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   pkts  1
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   bytes 960
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   delay 0
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   Idrop 0
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   Pdrop 0
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts:   Bdrop 0
2023-12-22 07:15:08.550 T:1155     info <general>: [WHITELIST] Searching the whitelist for: width: 3840, height: 2160, fps: 25.000, 3D: false
2023-12-22 07:15:08.550 T:1155    debug <general>: [WHITELIST] Using the default whitelist because the user whitelist is empty
2023-12-22 07:15:08.550 T:1155    debug <general>: [WHITELIST] Searching for an exact resolution with an exact refresh rate
2023-12-22 07:15:08.550 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.550 T:1155    debug <general>: [WHITELIST] No match for an exact resolution with an exact refresh rate
2023-12-22 07:15:08.550 T:1155    debug <general>: [WHITELIST] Searching for an exact resolution with double the refresh rate
2023-12-22 07:15:08.550 T:1155    debug <general>: [WHITELIST] No match for an exact resolution with double the refresh rate
2023-12-22 07:15:08.550 T:1155    debug <general>: [WHITELIST] Searching for an exact resolution with a 3:2 pulldown refresh rate
2023-12-22 07:15:08.550 T:1155    debug <general>: [WHITELIST] No match for a resolution with a 3:2 pulldown refresh rate
2023-12-22 07:15:08.551 T:1155    debug <general>: [WHITELIST] Searching for a desktop resolution with an exact refresh rate
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 192000.000000 len 960
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1155    debug <general>: [WHITELIST] No match for a desktop resolution with an exact refresh rate
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 224000.000000 len 960
2023-12-22 07:15:08.551 T:1155    debug <general>: [WHITELIST] Searching for a desktop resolution with double the refresh rate
2023-12-22 07:15:08.551 T:1155    debug <general>: [WHITELIST] Matched a desktop resolution with double the refresh rate 1920x1080 @ 50.000000 Hz (16)
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1155     info <general>: Display resolution ADJUST : 1920x1080 @ 50.000000 Hz (16) (weight: 0.000)
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 256000.000000 len 960
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 288000.000000 len 960
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 320000.000000 len 960
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 352000.000000 len 960
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 384000.000000 len 960
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 416000.000000 len 960
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 448000.000000 len 960
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 480000.000000 len 960
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 512000.000000 len 960
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 544000.000000 len 960
2023-12-22 07:15:08.551 T:1124    debug <general>: RunQuery took 1 ms for 32 items query: select * from musicvideo_view  WHERE ((musicvideo_view.playCount IS NULL OR musicvideo_view.playCount < 1))
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 576000.000000 len 960
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 608000.000000 len 960
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.551 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 640000.000000 len 960
2023-12-22 07:15:08.552 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.552 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 672000.000000 len 960
2023-12-22 07:15:08.552 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.552 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 704000.000000 len 960
2023-12-22 07:15:08.552 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.552 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 736000.000000 len 960
2023-12-22 07:15:08.553 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.554 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type I pts 1692000.000000 len 192745
2023-12-22 07:15:08.554 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.554 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1612000.000000 len 96164
2023-12-22 07:15:08.556 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [timeshiftStatus]
2023-12-22 07:15:08.556 T:1115    debug <general>: AddOnLog: pvr.hts: timeshiftStatus:
2023-12-22 07:15:08.556 T:1115    debug <general>: AddOnLog: pvr.hts:   full  : 0
2023-12-22 07:15:08.556 T:1115    debug <general>: AddOnLog: pvr.hts:   shift : 0
2023-12-22 07:15:08.556 T:1115    debug <general>: AddOnLog: pvr.hts:   start : 1692000
2023-12-22 07:15:08.556 T:1115    debug <general>: AddOnLog: pvr.hts:   end   : 1692000
2023-12-22 07:15:08.559 T:1155    error <general>: OpenStream: Codec id 173 require extradata.
2023-12-22 07:15:08.559 T:1155  warning <general>: OpenStream - Unsupported stream 1001. Stream disabled.
2023-12-22 07:15:08.559 T:1155     info <general>: Opening stream: 1002 source: 256
2023-12-22 07:15:08.559 T:1155     info <general>: Finding audio codec for: 86019
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]: For transform of length 128, inverse, mdct_float, flags: [aligned, out_of_place], found 3 matches:
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 544
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     2: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     3: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]: For transform of length 64, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 3 matches:
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     1: fft_sr_asm_float_avx2 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 480
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     2: fft_sr_asm_float_fma3 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 448
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     3: fft_sr_asm_float_avx - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 416
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]: Transform tree:
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     mdct_inv_float_avx2 - type: mdct_float, len: 128, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only]
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:         fft_sr_asm_float_avx2 - type: fft_float, len: 64, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call]
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]: For transform of length 256, inverse, mdct_float, flags: [aligned, out_of_place], found 3 matches:
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 544
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     2: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     3: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]: For transform of length 128, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 3 matches:
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     1: fft_sr_asm_float_avx2 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 480
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     2: fft_sr_asm_float_fma3 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 448
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     3: fft_sr_asm_float_avx - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 416
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]: Transform tree:
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:     mdct_inv_float_avx2 - type: mdct_float, len: 256, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only]
2023-12-22 07:15:08.560 T:1155    debug <general>: ffmpeg[0x553fac0]:         fft_sr_asm_float_avx2 - type: fft_float, len: 128, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call]
2023-12-22 07:15:08.560 T:1155     info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3
2023-12-22 07:15:08.560 T:1155     info <general>: Creating audio thread
2023-12-22 07:15:08.560 T:1157    debug <general>: Thread VideoPlayerAudio start, auto delete: false
2023-12-22 07:15:08.560 T:1099    debug <general>: OnAVChange: CApplication::OnAVChange
2023-12-22 07:15:08.560 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 192000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: CDVDDemuxClient::ParsePacket - (1002) samplerate changed from 3 to 48000
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 224000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 256000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 288000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 320000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 352000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 384000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 416000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 448000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 480000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 512000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 544000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 576000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 608000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 640000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 672000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 704000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 736000.000000 len 960
2023-12-22 07:15:08.561 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1692000.000000 len 192745
2023-12-22 07:15:08.562 T:1155    debug <general>: ffmpeg[0x553fac0]: [extract_extradata] nal_unit_type: 32(VPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.562 T:1155    debug <general>: ffmpeg[0x553fac0]: [extract_extradata] nal_unit_type: 33(SPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.562 T:1155    debug <general>: ffmpeg[0x553fac0]: [extract_extradata] nal_unit_type: 34(PPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.562 T:1155     info <general>: Skipped 2 duplicate messages..
2023-12-22 07:15:08.562 T:1155    debug <general>: ffmpeg[0x553fac0]: [extract_extradata] nal_unit_type: 35(AUD), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.562 T:1155    debug <general>: ffmpeg[0x553fac0]: [extract_extradata] nal_unit_type: 39(SEI_PREFIX), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.563 T:1155     info <general>: Skipped 4 duplicate messages..
2023-12-22 07:15:08.563 T:1155    debug <general>: ffmpeg[0x553fac0]: [extract_extradata] nal_unit_type: 21(CRA_NUT), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.563 T:1155    debug <general>: GetPacketExtradata: fetching extradata, extradata_size(261)
2023-12-22 07:15:08.563 T:1155    debug <general>: CDVDDemuxClient::ParsePacket - split extradata
2023-12-22 07:15:08.563 T:1157     info <general>: running thread: CVideoPlayerAudio::Process()
2023-12-22 07:15:08.563 T:1157    debug <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false
2023-12-22 07:15:08.563 T:1157    debug <general>: CDVDAudio::Pause - pausing audio stream
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] nal_unit_type: 32(VPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] nal_unit_type: 33(SPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] nal_unit_type: 34(PPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.572 T:1155     info <general>: Skipped 2 duplicate messages..
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] nal_unit_type: 35(AUD), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] nal_unit_type: 39(SEI_PREFIX), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.572 T:1155     info <general>: Skipped 4 duplicate messages..
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] nal_unit_type: 21(CRA_NUT), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Decoding VPS
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Main 10 profile bitstream
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Decoding SPS
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Main 10 profile bitstream
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Decoding VUI
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Decoding PPS
2023-12-22 07:15:08.572 T:1155     info <general>: Skipped 2 duplicate messages..
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Decoding SEI
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Skipped PREFIX SEI 6
2023-12-22 07:15:08.572 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Decoding SEI
2023-12-22 07:15:08.574 T:1155     info <general>: Skipped 3 duplicate messages..
2023-12-22 07:15:08.574 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Format yuv420p10le chosen by get_format().
2023-12-22 07:15:08.586 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.586 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1572000.000000 len 88120
2023-12-22 07:15:08.586 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.586 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1552000.000000 len 1443
2023-12-22 07:15:08.586 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.586 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1592000.000000 len 1460
2023-12-22 07:15:08.592 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.592 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 768000.000000 len 960
2023-12-22 07:15:08.592 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.592 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 800000.000000 len 960
2023-12-22 07:15:08.592 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.592 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 832000.000000 len 960
2023-12-22 07:15:08.592 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.592 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 864000.000000 len 960
2023-12-22 07:15:08.592 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.592 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 896000.000000 len 960
2023-12-22 07:15:08.592 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.592 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 928000.000000 len 960
2023-12-22 07:15:08.611 T:1124    debug <general>: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetMovies", "params": {"properties": ["title", "originaltitle", "votes", "playcount", "year", "genre", "studio", "country", "tagline", "plot", "runtime", "file", "plotoutline", "lastplayed", "trailer", "rating", "resume", "art", "streamdetails", "mpaa", "director", "writer", "cast", "dateadded", "imdbnumber"], "limits": {"end": 20}, "sort": {"method": "random"}, "filter": {"field": "inprogress", "operator": "true", "value": ""}}}
2023-12-22 07:15:08.611 T:1124    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.615 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.615 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1652000.000000 len 86981
2023-12-22 07:15:08.615 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.615 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1632000.000000 len 1525
2023-12-22 07:15:08.615 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.615 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1672000.000000 len 1169
2023-12-22 07:15:08.619 T:1124    debug <general>: RunQuery took 3 ms for 3 items query: select * from movie_view  WHERE (movie_view.idFile  IN (SELECT DISTINCT idFile FROM bookmark WHERE type = 1))
2023-12-22 07:15:08.627 T:1124    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.638 T:1124    debug <general>: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetTVShows", "params": {"properties": ["title", "studio", "mpaa", "file", "art"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "lastplayed"}, "filter": {"and": [{"field": "playcount", "operator": "lessthan", "value": "1"}, {"field": "inprogress", "operator": "true", "value": ""}]}}}
2023-12-22 07:15:08.638 T:1124    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.644 T:1121    debug <general>: RunQuery took 55 ms for 46 items query: SELECT * FROM tvshow_view 
2023-12-22 07:15:08.648 T:1121    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.672 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.672 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type P pts 1852000.000000 len 140426
2023-12-22 07:15:08.683 T:1121    debug <general>: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "AudioLibrary.Getartists", "params": {"properties": ["art"], "limits": {"start": 0, "end": 40}, "sort": {"method": "random"}}}
2023-12-22 07:15:08.683 T:1121    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.693 T:1124    debug <general>: RunQuery took 49 ms for 3 items query: SELECT * FROM tvshow_view  WHERE ((CASE WHEN COALESCE(tvshow_view.totalCount - tvshow_view.watchedcount, 0) > 0 THEN 0 ELSE 1 END  < 1)) AND ( ((tvshow_view.watchedcount > 0 AND tvshow_view.watchedcount < tvshow_view.totalCount) OR (tvshow_view.watchedcount = 0 AND EXISTS (SELECT 1 FROM episode_view WHERE episode_view.idShow = tvshow_view.idShow AND episode_view.resumeTimeInSeconds > 0))))
2023-12-22 07:15:08.693 T:1124    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.699 T:1124    debug <general>: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetEpisodes", "params": {"properties": ["title", "playcount", "season", "episode", "showtitle", "plot", "file", "rating", "resume", "tvshowid", "art", "streamdetails", "firstaired", "runtime", "director", "writer", "cast", "dateadded", "lastplayed"], "limits": {"end": 1}, "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "lessthan", "value": "1"}, "tvshowid": 14}}
2023-12-22 07:15:08.700 T:1124    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.702 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.702 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1772000.000000 len 92089
2023-12-22 07:15:08.709 T:1124    debug <general>: RunQuery took 4 ms for 100 items query: select * from episode_view  WHERE (episode_view.idShow = 14) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
2023-12-22 07:15:08.709 T:1124    debug <general>: DatabaseUtils::GetSortFieldList: unknown field 36
2023-12-22 07:15:08.713 T:1124    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.719 T:1124    debug <general>: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.GetEpisodes", "params": {"properties": ["title", "playcount", "season", "episode", "showtitle", "plot", "file", "rating", "resume", "tvshowid", "art", "streamdetails", "firstaired", "runtime", "director", "writer", "cast", "dateadded", "lastplayed"], "limits": {"end": 1}, "sort": {"method": "episode"}, "filter": {"field": "playcount", "operator": "lessthan", "value": "1"}, "tvshowid": 40}}
2023-12-22 07:15:08.719 T:1124    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.736 T:1124    debug <general>: RunQuery took 11 ms for 333 items query: select * from episode_view  WHERE (episode_view.idShow = 40) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
2023-12-22 07:15:08.736 T:1124    debug <general>: DatabaseUtils::GetSortFieldList: unknown field 36
2023-12-22 07:15:08.736 T:1121    debug <general>: GetArtistsByWhereJSON query: SELECT a1.*, art.art_id AS idArt, art.type AS artType, art.url AS artURL FROM (SELECT artist.idArtist, strArtist FROM artist  WHERE (EXISTS (SELECT 1 FROM song_artist WHERE song_artist.idArtist = artist.idArtist AND song_artist.idRole = 1) OR EXISTS (SELECT 1 FROM album_artist WHERE album_artist.idArtist = artist.idArtist)) AND (artist.strArtist != '') ORDER BY RAND() LIMIT 40) AS a1 LEFT JOIN art ON art.media_id = a1.idArtist AND art.media_type = 'artist' GROUP BY a1.idArtist, art.art_id ORDER BY a1.idArtist, arttype
2023-12-22 07:15:08.738 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.738 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1732000.000000 len 88311
2023-12-22 07:15:08.739 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.739 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1712000.000000 len 1161
2023-12-22 07:15:08.739 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.739 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1752000.000000 len 1401
2023-12-22 07:15:08.745 T:1124    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250

Display More

Boronsbruder · December 22, 2023 at 6:44 AM

Code

2023-12-22 07:15:08.767 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.767 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1812000.000000 len 87322
2023-12-22 07:15:08.773 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.773 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1792000.000000 len 1334
2023-12-22 07:15:08.773 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.773 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1832000.000000 len 1611
2023-12-22 07:15:08.781 T:1121    debug <general>: GetArtistsByWhereJSON - query took 44 ms
2023-12-22 07:15:08.782 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.782 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 960000.000000 len 960
2023-12-22 07:15:08.782 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.783 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 992000.000000 len 960
2023-12-22 07:15:08.783 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.783 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 1024000.000000 len 960
2023-12-22 07:15:08.783 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.783 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 1056000.000000 len 960
2023-12-22 07:15:08.783 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.783 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 1088000.000000 len 960
2023-12-22 07:15:08.783 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.783 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1002:1002 type _ pts 1120000.000000 len 960
2023-12-22 07:15:08.783 T:1121    debug <general>: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "VideoLibrary.Getmusicvideos", "params": {"properties": ["art"], "limits": {"start": 0, "end": 40}, "sort": {"method": "random"}}}
2023-12-22 07:15:08.784 T:1121    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.790 T:1121    debug <general>: RunQuery took 1 ms for 32 items query: select * from musicvideo_view 
2023-12-22 07:15:08.791 T:1121    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.797 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Decoded frame with POC 19835.
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] nal_unit_type: 32(VPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] nal_unit_type: 33(SPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] nal_unit_type: 34(PPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.804 T:1155     info <general>: Skipped 2 duplicate messages..
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] nal_unit_type: 35(AUD), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] nal_unit_type: 39(SEI_PREFIX), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.804 T:1155     info <general>: Skipped 4 duplicate messages..
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] nal_unit_type: 21(CRA_NUT), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] Decoding VPS
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] Main 10 profile bitstream
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] Decoding SPS
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] Main 10 profile bitstream
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] Decoding VUI
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] Decoding PPS
2023-12-22 07:15:08.804 T:1155     info <general>: Skipped 2 duplicate messages..
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] Decoding SEI
2023-12-22 07:15:08.804 T:1124    debug <general>: RunQuery took 48 ms for 1073 items query: select * from episode_view  WHERE (episode_view.idShow = 8) AND (((episode_view.playCount IS NULL OR episode_view.playCount < 1)))
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] Skipped PREFIX SEI 6
2023-12-22 07:15:08.804 T:1155    debug <general>: ffmpeg[0x553fac0]: [NULL] Decoding SEI
2023-12-22 07:15:08.804 T:1155     info <general>: Skipped 3 duplicate messages..
2023-12-22 07:15:08.804 T:1155    debug <general>: CDVDDemuxClient::ParsePacket - (1001) profile changed from -99 to 2
2023-12-22 07:15:08.804 T:1124    debug <general>: DatabaseUtils::GetSortFieldList: unknown field 36
2023-12-22 07:15:08.804 T:1155    debug <general>: CDVDDemuxClient::ParsePacket - (1001) level changed from -99 to 153
2023-12-22 07:15:08.805 T:1155    debug <general>: CDVDDemuxClient::ParsePacket - (1001) aspect changed from 0 to 1.7777777777777777
2023-12-22 07:15:08.805 T:1155    debug <general>: CDVDDemuxClient::ParsePacket - (1001) fps changed from 1000000/40000 to 50/1
2023-12-22 07:15:08.805 T:1155    debug <general>: CDVDDemuxClient::RequestStream(): added/updated stream 1001 with codec_id 173
2023-12-22 07:15:08.805 T:1155    debug <general>: CDVDDemuxClient::RequestStream(): added/updated stream 1002 with codec_id 86019
2023-12-22 07:15:08.805 T:1155     info <general>: Opening stream: 1001 source: 256
2023-12-22 07:15:08.805 T:1155     info <general>: [WHITELIST] Searching the whitelist for: width: 3840, height: 2160, fps: 50.000, 3D: false
2023-12-22 07:15:08.805 T:1155    debug <general>: [WHITELIST] Using the default whitelist because the user whitelist is empty
2023-12-22 07:15:08.805 T:1155    debug <general>: [WHITELIST] Searching for an exact resolution with an exact refresh rate
2023-12-22 07:15:08.805 T:1155    debug <general>: [WHITELIST] No match for an exact resolution with an exact refresh rate
2023-12-22 07:15:08.805 T:1155    debug <general>: [WHITELIST] Searching for an exact resolution with double the refresh rate
2023-12-22 07:15:08.805 T:1155    debug <general>: [WHITELIST] No match for an exact resolution with double the refresh rate
2023-12-22 07:15:08.805 T:1155    debug <general>: [WHITELIST] Searching for an exact resolution with a 3:2 pulldown refresh rate
2023-12-22 07:15:08.805 T:1155    debug <general>: [WHITELIST] No match for a resolution with a 3:2 pulldown refresh rate
2023-12-22 07:15:08.805 T:1155    debug <general>: [WHITELIST] Searching for a desktop resolution with an exact refresh rate
2023-12-22 07:15:08.805 T:1155    debug <general>: [WHITELIST] Matched a desktop resolution with an exact refresh rate 1920x1080 @ 50.000000 Hz (16)
2023-12-22 07:15:08.805 T:1155     info <general>: Display resolution ADJUST : 1920x1080 @ 50.000000 Hz (16) (weight: 0.000)
2023-12-22 07:15:08.820 T:1155     info <general>: Creating video codec with codec id: 173
2023-12-22 07:15:08.820 T:1155     info <general>: CDVDVideoCodecFFmpeg::Open() Using codec: HEVC (High Efficiency Video Coding)
2023-12-22 07:15:08.820 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] nal_unit_type: 32(VPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.820 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] nal_unit_type: 33(SPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.820 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] nal_unit_type: 34(PPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.820 T:1155     info <general>: Skipped 2 duplicate messages..
2023-12-22 07:15:08.820 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Decoding VPS
2023-12-22 07:15:08.820 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Main 10 profile bitstream
2023-12-22 07:15:08.820 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Decoding SPS
2023-12-22 07:15:08.820 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Main 10 profile bitstream
2023-12-22 07:15:08.820 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Decoding VUI
2023-12-22 07:15:08.820 T:1155    debug <general>: ffmpeg[0x553fac0]: [hevc] Decoding PPS
2023-12-22 07:15:08.820 T:1155     info <general>: Skipped 2 duplicate messages..
2023-12-22 07:15:08.820 T:1155    debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-hevc
2023-12-22 07:15:08.820 T:1155    debug <general>: CVideoPlayerVideo::OpenStream - open stream with codec id: 173
2023-12-22 07:15:08.821 T:1155     info <general>: Creating video thread
2023-12-22 07:15:08.821 T:1158    debug <general>: Thread VideoPlayerVideo start, auto delete: false
2023-12-22 07:15:08.821 T:1158     info <general>: running thread: video_thread
2023-12-22 07:15:08.821 T:1158    debug <general>: CVideoPlayerVideo - CDVDMsg::GENERAL_PAUSE: false
2023-12-22 07:15:08.821 T:1155     info <general>: Opening stream: 1002 source: 256
2023-12-22 07:15:08.821 T:1155     info <general>: Finding audio codec for: 86019
2023-12-22 07:15:08.821 T:1155    debug <general>: ffmpeg[0x553fac0]: For transform of length 128, inverse, mdct_float, flags: [aligned, out_of_place], found 3 matches:
2023-12-22 07:15:08.821 T:1155    debug <general>: ffmpeg[0x553fac0]:     1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 544
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     2: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     3: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]: For transform of length 64, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 3 matches:
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     1: fft_sr_asm_float_avx2 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 480
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     2: fft_sr_asm_float_fma3 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 448
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     3: fft_sr_asm_float_avx - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 416
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]: Transform tree:
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     mdct_inv_float_avx2 - type: mdct_float, len: 128, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only]
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:         fft_sr_asm_float_avx2 - type: fft_float, len: 64, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call]
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]: For transform of length 256, inverse, mdct_float, flags: [aligned, out_of_place], found 3 matches:
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     1: mdct_inv_float_avx2 - type: mdct_float, len: [16, ∞], factors[2]: [2, any], flags: [aligned, out_of_place, inv_only], prio: 544
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     2: mdct_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: 96
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     3: mdct_naive_inv_float_c - type: mdct_float, len: [2, ∞], factors[2]: [2, any], flags: [unaligned, out_of_place, inv_only], prio: -130976
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]: For transform of length 128, inverse, fft_float, flags: [aligned, inplace, preshuf, asm_call], found 3 matches:
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     1: fft_sr_asm_float_avx2 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 480
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     2: fft_sr_asm_float_fma3 - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 448
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     3: fft_sr_asm_float_avx - type: fft_float, len: [64, 131072], factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call], prio: 416
2023-12-22 07:15:08.822 T:1124    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]: Transform tree:
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:     mdct_inv_float_avx2 - type: mdct_float, len: 256, factors[2]: [2, any], flags: [aligned, out_of_place, inv_only]
2023-12-22 07:15:08.822 T:1155    debug <general>: ffmpeg[0x553fac0]:         fft_sr_asm_float_avx2 - type: fft_float, len: 128, factor: 2, flags: [aligned, inplace, out_of_place, preshuf, asm_call]
2023-12-22 07:15:08.822 T:1155     info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder ac3
2023-12-22 07:15:08.822 T:1155    debug <general>: CVideoPlayer::HandleMessages - player 1 reported state: 0
2023-12-22 07:15:08.822 T:1155    debug <general>: CVideoPlayer::HandleMessages - player 2 reported state: 0
2023-12-22 07:15:08.822 T:1158    debug <general>: CVideoPlayerVideo - Stillframe left, switching to normal playback
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1612000.000000 len 96164
2023-12-22 07:15:08.822 T:1158    debug <general>: CVideoPlayerVideo::CalcDropRequirement - hurry: 0
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1572000.000000 len 88120
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1552000.000000 len 1443
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1592000.000000 len 1460
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 768000.000000 len 960
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 800000.000000 len 960
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 832000.000000 len 960
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 864000.000000 len 960
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 896000.000000 len 960
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 928000.000000 len 960
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1652000.000000 len 86981
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1632000.000000 len 1525
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1672000.000000 len 1169
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1852000.000000 len 140426
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1772000.000000 len 92089
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1732000.000000 len 88311
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1712000.000000 len 1161
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1752000.000000 len 1401
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1812000.000000 len 87322
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1792000.000000 len 1334
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1832000.000000 len 1611
2023-12-22 07:15:08.822 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] nal_unit_type: 32(VPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 960000.000000 len 960
2023-12-22 07:15:08.822 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] nal_unit_type: 33(SPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 992000.000000 len 960
2023-12-22 07:15:08.822 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] nal_unit_type: 34(PPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.822 T:1155     info <general>: Skipped 1 duplicate messages..
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 1024000.000000 len 960
2023-12-22 07:15:08.822 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] nal_unit_type: 34(PPS), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.822 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] nal_unit_type: 35(AUD), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.822 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] nal_unit_type: 39(SEI_PREFIX), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.823 T:1158     info <general>: Skipped 4 duplicate messages..
2023-12-22 07:15:08.823 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] nal_unit_type: 21(CRA_NUT), nuh_layer_id: 0, temporal_id: 0
2023-12-22 07:15:08.823 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Decoding VPS
2023-12-22 07:15:08.823 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Main 10 profile bitstream
2023-12-22 07:15:08.823 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Decoding SPS
2023-12-22 07:15:08.823 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Main 10 profile bitstream
2023-12-22 07:15:08.823 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Decoding VUI
2023-12-22 07:15:08.823 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Decoding PPS
2023-12-22 07:15:08.823 T:1158     info <general>: Skipped 2 duplicate messages..
2023-12-22 07:15:08.823 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Decoding SEI
2023-12-22 07:15:08.823 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Skipped PREFIX SEI 6
2023-12-22 07:15:08.823 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Decoding SEI
2023-12-22 07:15:08.823 T:1155     info <general>: Skipped 3 duplicate messages..
2023-12-22 07:15:08.822 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 1056000.000000 len 960
2023-12-22 07:15:08.823 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 1088000.000000 len 960
2023-12-22 07:15:08.823 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1002 pts 1120000.000000 len 960
2023-12-22 07:15:08.825 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.825 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type P pts 2012000.000000 len 129354
2023-12-22 07:15:08.825 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 2012000.000000 len 129354
2023-12-22 07:15:08.825 T:1158    debug <general>: VAAPI - open decoder
2023-12-22 07:15:08.825 T:1157    debug <general>: CVideoPlayerAudio - CDVDMsg::GENERAL_PAUSE: false
2023-12-22 07:15:08.825 T:1157    debug <general>: CDVDAudio::Pause - pausing audio stream
2023-12-22 07:15:08.825 T:1158    debug <general>: libva info: VA-API version 1.20.0
2023-12-22 07:15:08.826 T:1158    debug <general>: libva info: Trying to open /usr/lib/dri/iHD_drv_video.so
2023-12-22 07:15:08.826 T:1158    debug <general>: libva info: Found init function __vaDriverInit_1_20
2023-12-22 07:15:08.826 T:1157     info <general>: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, no pass-through)
2023-12-22 07:15:08.826 T:1157    debug <general>: CVideoPlayerAudio:: synctype set to 1: resample
2023-12-22 07:15:08.826 T:1095    debug <general>: ffmpeg[0x354e220]: [SWR] Using fltp internally between filters
2023-12-22 07:15:08.826 T:1096     info <general>: CActiveAESink::OpenSink - initialize sink
2023-12-22 07:15:08.826 T:1096    debug <general>: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=PCH,DEV=0
2023-12-22 07:15:08.826 T:1096     info <general>: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=PCH,DEV=0"
2023-12-22 07:15:08.829 T:1158    debug <general>: libva info: va_openDriver() returns 0
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - initialize version 1.20
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - driver in use: Intel iHD driver for Intel(R) Gen Graphics - 23.4.3 (581a2fe)
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileNone
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileMPEG2Simple
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileMPEG2Main
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileH264Main
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileH264High
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileVC1Simple
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileVC1Main
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileVC1Advanced
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileJPEGBaseline
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileH264ConstrainedBaseline
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileVP8Version0_3
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileHEVCMain
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileHEVCMain10
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileVP9Profile0
2023-12-22 07:15:08.829 T:1158    debug <general>: VAAPI - profile VAProfileVP9Profile2
2023-12-22 07:15:08.830 T:1159    debug <general>: Thread Vaapi-Output start, auto delete: false
2023-12-22 07:15:08.830 T:1159     info <general>: COutput::OnStartup: Output Thread created
2023-12-22 07:15:08.841 T:1096     info <general>: CAESinkALSA::Initialize - Opened device "hdmi:CARD=PCH,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02"
2023-12-22 07:15:08.841 T:1096    debug <general>: CAESinkALSA::SelectALSAChannelMap - Selected ALSA map "FL FR"
2023-12-22 07:15:08.841 T:1096     info <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2023-12-22 07:15:08.841 T:1096     info <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE
2023-12-22 07:15:08.841 T:1096    debug <general>: CAESinkALSA::InitializeHW - Request: periodSize 2400, bufferSize 9600
2023-12-22 07:15:08.856 T:1158    debug <general>: CDVDVideoCodecFFmpeg - Updated codec: ff-hevc-vaapi
2023-12-22 07:15:08.856 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Format vaapi chosen by get_format().
2023-12-22 07:15:08.856 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Format vaapi requires hwaccel initialisation.
2023-12-22 07:15:08.857 T:1085    debug <general>: FreeVisualisation() started
2023-12-22 07:15:08.857 T:1096    debug <general>: CAESinkALSA::InitializeHW - Got: periodSize 2400, bufferSize 9600
2023-12-22 07:15:08.857 T:1085    debug <general>: SECTION:UnloadDll(/storage/.kodi/addons/visualization.matrix/visualization.matrix.so.20.2.0)
2023-12-22 07:15:08.857 T:1096    debug <general>: CAESinkALSA::InitializeHW - Setting timeout to 200 ms
2023-12-22 07:15:08.857 T:1085     info <general>: ADDON: Dll Destroyed - Matrix
2023-12-22 07:15:08.857 T:1085    debug <general>: FreeVisualisation() done
2023-12-22 07:15:08.859 T:1096    debug <general>: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2
2023-12-22 07:15:08.859 T:1096    debug <general>: CAESinkALSA::GetChannelLayout - Requested Layout: FL, FR
2023-12-22 07:15:08.859 T:1096    debug <general>: CAESinkALSA::GetChannelLayout - Got Layout: FL, FR (ALSA: FL FR)
2023-12-22 07:15:08.859 T:1096    debug <general>: CActiveAESink::OpenSink - ALSA Initialized:
2023-12-22 07:15:08.859 T:1096    debug <general>:   Output Device : HDA Intel PCH
2023-12-22 07:15:08.859 T:1096    debug <general>:   Sample Rate   : 48000
2023-12-22 07:15:08.859 T:1096    debug <general>:   Sample Format : AE_FMT_S32NE
2023-12-22 07:15:08.859 T:1096    debug <general>:   Channel Count : 2
2023-12-22 07:15:08.859 T:1096    debug <general>:   Channel Layout: FL, FR
2023-12-22 07:15:08.859 T:1096    debug <general>:   Frames        : 2400
2023-12-22 07:15:08.859 T:1096    debug <general>:   Frame Size    : 8
2023-12-22 07:15:08.860 T:1096    debug <general>: ffmpeg[0x354e8a8]: [SWR] Using fltp internally between filters
2023-12-22 07:15:08.860 T:1095    debug <general>: ffmpeg[0x354e220]: [SWR] Using fltp internally between filters
2023-12-22 07:15:08.860 T:1095     info <general>: Skipped 1 duplicate messages..
2023-12-22 07:15:08.860 T:1095    debug <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2023-12-22 07:15:08.860 T:1115    debug <general>: AddOnLog: pvr.hts: receive message [muxpkt]
2023-12-22 07:15:08.860 T:1095    debug <general>: ffmpeg[0x354e220]: [SWR] Using fltp internally between filters
2023-12-22 07:15:08.861 T:1115    debug <general>: AddOnLog: pvr.hts: demux pkt idx 1001:1001 type B pts 1932000.000000 len 100191
2023-12-22 07:15:08.861 T:1095    debug <general>: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2023-12-22 07:15:08.861 T:1095    debug <general>: ffmpeg[0x354e220]: [SWR] Using fltp internally between filters
2023-12-22 07:15:08.861 T:1155    debug <general>: AddOnLog: pvr.hts: demux read idx :1001 pts 1932000.000000 len 100191
2023-12-22 07:15:08.861 T:1155    debug <general>: CVideoPlayer::HandleMessages - player 1 reported state: 0
2023-12-22 07:15:08.861 T:1113    debug <general>: OnAVChange: CApplication::OnAVChange
2023-12-22 07:15:08.861 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Decode context initialised: 0x16/0x10000000.
2023-12-22 07:15:08.861 T:1124    debug <general>: JSONRPC: Incoming request: {"jsonrpc": "2.0", "id": 1, "method": "AudioLibrary.GetAlbums", "params": {"properties": ["title", "description", "albumlabel", "theme", "mood", "style", "type", "artist", "genre", "year", "thumbnail", "fanart", "rating", "playcount"], "limits": {"end": 20}, "sort": {"order": "descending", "method": "playcount"}}}
2023-12-22 07:15:08.862 T:1124    debug <general>: connect replacing configured host 192.168.116.250 with resolved host 192.168.116.250
2023-12-22 07:15:08.881 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Param buffer (type 0, 604 bytes) is 0.
2023-12-22 07:15:08.881 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Param buffer (type 1, 1016 bytes) is 0x1.
2023-12-22 07:15:08.881 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Slice 0 param buffer (264 bytes) is 0x2.
2023-12-22 07:15:08.882 T:1124    debug <general>: GetAlbumsByWhereJSON query: SELECT a1.*, album_artist.idArtist AS idArtist, artist.strArtist AS strArtist FROM (SELECT albumview.idAlbum, strAlbum, strReview, strGenres, strThemes, strMoods, strStyles, strType, strLabel, fRating, iTimesPlayed, CAST(strReleaseDate AS SIGNED INTEGER) AS iYear, art.url AS thumbnail FROM albumview LEFT JOIN art ON art.media_id = idAlbum AND art.media_type = 'album' AND art.type = 'thumb' WHERE albumview.strReleaseType = 'album' ORDER BY albumview.iTimesPlayed DESC, udfNaturalSortFormat(albumview.strAlbum, 8, '.') DESC, albumview.idAlbum DESC LIMIT 20) AS a1 JOIN album_artist ON album_artist.idAlbum = a1.idAlbum JOIN artist ON artist.idArtist = album_artist.idArtist GROUP BY a1.idAlbum, album_artist.idArtist ORDER BY a1.iTimesPlayed DESC, udfNaturalSortFormat(a1.strAlbum, 8, '.') DESC, a1.idAlbum DESC, album_artist.iOrder
2023-12-22 07:15:08.882 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Slice 0 data buffer (192416 bytes) is 0x3.
2023-12-22 07:15:08.882 T:1158    debug <general>: ffmpeg[0x554bf90]: [hevc] Decode to surface 0.
2023-12-22 07:15:08.882 T:1158    error <general>: ffmpeg[0x554bf90]: [hevc] Failed to end picture decode issue: 18 (invalid parameter).
2023-12-22 07:15:08.882 T:1158    error <general>: ffmpeg[0x554bf90]: [hevc] hardware accelerator failed to decode picture

Display More

Libreelec 11.0.3 on Nuc7i3Bnk - Graphics Problems

Similar Threads

No HDMI sound on LibreELEC 11.0.3 in Phillips TV

Libreelec 11 on Raspberry 4 problems: video calibration and HDD wakeup

LE 11.0.3 restart by itself