search

Sandmann79 / xbmc

Prime Video Addon for Kodi Media Center
https://forum.kodi.tv/showthread.php?tid=349255
GNU General Public License v3.0
760 stars 191 forks source link

Videoplayback starts, but the video has no picture #687

Closed ChrizH84 closed 6 months ago

ChrizH84 commented 1 year ago

Addon used

Account type

System Setup (please provide the following information):

Upload Logs

When I try to start watching a movie, it starts to play (it plays the sound). But the video itself doesn't plays. Instead, I see the movielist where I've started to play and the progress on the bottom right corner flickers. Other Streamingservices seems to work, also live-tv. I'm using the current LibreElec version with mainly standard settings (especially for technical things). I could imagine that the problem is related to the AMD system, but I'm not sure if it works on Intel.

Any ideas?

ChrizH84 commented 1 year ago

Sry, the logs we're not attached: info : VideoPlayer::OpenFile: plugin://plugin.video.amazon-test/?asin=B07PF4Z9XB&mode=PlayVideo&name=Zwei+vom+Affen+gebissen&adult=0&trailer=0&selbitrate=0 2023-05-01 08:20:47.981 T:1094 info : Creating InputStream 2023-05-01 08:20:47.989 T:1094 warning : AddOnLog: inputstream.adaptive: Set headers to the manifest by using "inputstream.adaptive.stream_headers" property is a deprecated behaviour that will be removed in future. To set headers to the manifest, use "inputstream.adaptive.manifest_headers" property. 2023-05-01 08:20:47.990 T:1094 info : AddOnLog: inputstream.adaptive: [Repr. chooser] Resolution set: 3840x2160, max allowed: 3840x2160, Adjust refresh rate: 1 2023-05-01 08:20:47.990 T:1094 error : GetDirectory - Error getting /usr/lib/kodi/addons/inputstream.adaptive/ 2023-05-01 08:20:48.954 T:1094 info : AddOnLog: inputstream.adaptive: Successfully parsed manifest file (Periods: 1, Streams in first period: 5, Type: VOD) 2023-05-01 08:20:49.832 T:1094 info : Creating Demuxer 2023-05-01 08:20:49.832 T:1094 info : Opening stream: 1001 source: 256 2023-05-01 08:20:49.980 T:1094 info : [WHITELIST] Searching the whitelist for: width: 960, height: 540, fps: 23.976, 3D: false 2023-05-01 08:20:49.981 T:1094 info : Display resolution ADJUST : 3840x2160 @ 23.976025 Hz (32) (weight: 0.000) 2023-05-01 08:20:49.995 T:961 info : VideoPlayer: OnLostDisplay received 2023-05-01 08:20:49.995 T:961 warning : CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED 2023-05-01 08:20:49.995 T:961 warning : CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED 2023-05-01 08:20:49.995 T:961 info : GLES: Maximum texture width: 16384 2023-05-01 08:20:49.996 T:1094 info : Creating video codec with codec id: 27 2023-05-01 08:20:49.996 T:1094 info : AddOnLog: inputstream.adaptive: VideoCodec::Open 2023-05-01 08:20:49.996 T:1094 warning : AddOnLog: inputstream.adaptive: ToCdmVideoCodecProfile: Unknown codec profile 0 2023-05-01 08:20:49.996 T:1094 info : Creating video thread 2023-05-01 08:20:49.997 T:1103 info : running thread: video_thread 2023-05-01 08:20:49.997 T:1094 info : Opening stream: 1002 source: 256 2023-05-01 08:20:50.055 T:1094 info : Finding audio codec for: 86018 2023-05-01 08:20:50.056 T:1094 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac 2023-05-01 08:20:50.056 T:1094 info : OpenStream: Allowing max Out-Of-Sync Value of 10 ms 2023-05-01 08:20:50.056 T:1094 info : Creating audio thread 2023-05-01 08:20:50.056 T:1106 info : running thread: CVideoPlayerAudio::Process() 2023-05-01 08:20:50.154 T:961 info : VideoPlayer: OnResetDisplay received 2023-05-01 08:20:50.163 T:1067 info : [Amazon VOD] Starting Playback: 0.0/5275.0 2023-05-01 08:20:50.305 T:1106 info : CVideoPlayerAudio: display reset occurred, checking for passthrough 2023-05-01 08:20:50.306 T:1106 info : CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac 2023-05-01 08:20:50.306 T:1106 info : Creating audio stream (codec id: 86018, channels: 2, sample rate: 48000, no pass-through) 2023-05-01 08:20:50.319 T:986 info : CAESinkALSA - Unable to open device "default" for playback 2023-05-01 08:20:50.322 T:961 error : CEGLImage::CreateImage - failed to import buffer into EGL image: 0x3003 2023-05-01 08:20:50.410 T:986 info : Skipped 2 duplicate messages.. 2023-05-01 08:20:50.410 T:986 warning : Pulseaudio module module-allow-passthrough not loaded - opening PT devices might fail 2023-05-01 08:20:50.410 T:986 info : Found 2 Lists of Devices 2023-05-01 08:20:50.410 T:986 info : Enumerated ALSA devices: <............ AUDIO ...............> 2023-05-01 08:20:50.410 T:987 info : CActiveAESink::OpenSink - initialize sink 2023-05-01 08:20:50.410 T:987 info : CAESinkALSA::Initialize - Attempting to open device "iec958:CARD=Generic_1,DEV=0" 2023-05-01 08:20:50.411 T:987 info : CAESinkALSA::Initialize - Opened device "iec958:CARD=Generic_1,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 2023-05-01 08:20:50.411 T:987 info : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2023-05-01 08:20:50.411 T:987 info : CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 2023-05-01 08:20:50.421 T:987 info : CActiveAESink::OpenSink - initialize sink 2023-05-01 08:20:50.422 T:987 info : CAESinkALSA::Initialize - Attempting to open device "iec958:CARD=Generic_1,DEV=0" 2023-05-01 08:20:50.422 T:987 info : CAESinkALSA::Initialize - Opened device "iec958:CARD=Generic_1,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x02" 2023-05-01 08:20:50.422 T:987 info : CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 2023-05-01 08:20:50.422 T:987 info : CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 2023-05-01 08:20:50.447 T:961 info : Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY 2023-05-01 08:20:50.450 T:961 info : [WHITELIST] Searching the whitelist for: width: 960, height: 540, fps: 23.976, 3D: false 2023-05-01 08:20:50.451 T:961 info : Display resolution ADJUST : 3840x2160 @ 23.976025 Hz (32) (weight: 5171885965829836422250496.000) 2023-05-01 08:20:50.451 T:961 error : CEGLImage::CreateImage - failed to import buffer into EGL image: 0x3003 2023-05-01 08:20:56.369 T:961 info : Skipped 141 duplicate messages.. 2023-05-01 08:20:56.369 T:961 info : CVideoPlayer::CloseFile() 2023-05-01 08:20:56.369 T:961 info : VideoPlayer: waiting for threads to exit 2023-05-01 08:20:56.371 T:1094 info : CVideoPlayer::OnExit() 2023-05-01 08:20:56.371 T:1094 info : Closing stream player 1 2023-05-01 08:20:56.371 T:1094 info : Waiting for audio thread to exit 2023-05-01 08:20:56.381 T:1106 info : thread end: CVideoPlayerAudio::OnExit() 2023-05-01 08:20:56.382 T:1094 info : Closing audio device 2023-05-01 08:20:56.432 T:1094 info : Deleting audio codec 2023-05-01 08:20:56.432 T:987 info : CActiveAESink::OpenSink - initialize sink 2023-05-01 08:20:56.432 T:1094 info : Closing stream player 2 2023-05-01 08:20:56.432 T:1094 info : waiting for video thread to exit 2023-05-01 08:20:56.479 T:1103 warning : OutputPicture - timeout waiting for buffer 2023-05-01 08:20:56.479 T:1103 info : thread end: video_thread 2023-05-01 08:20:56.480 T:1094 info : deleting video codec

Gemini88 commented 1 year ago

@ChrizH84 yh same here and its only happening with drm protected/subscription required addons i.e. Amazon, Netflix etc, and to another note its just 4K playback thats effected from my testing) 1080p there's no problems with playback. Im guessing a widevine error which points me to "inputstream adaptive" as the culprit but time and testing will tell

BBJake commented 1 year ago

I have the same problem. It only shows "Prime video" on the background and plays the audio. But no video. In the logs i find the following;:

2023-07-24 21:55:34.096 T:584      info <general>: [Amazon VOD] Service: Proxy bound to 127.0.0.1:58123
2023-07-24 21:55:34.099 T:584      info <general>: [Amazon VOD] Service: Proxy server started
2023-07-24 21:55:34.118 T:584      info <general>: [Amazon VOD] Service started
2023-07-24 21:55:35.800 T:452      info <general>: Loading skin file: MyVideoNav.xml, load type: KEEP_IN_MEMORY
2023-07-24 21:55:39.175 T:590      info <general>: JSONRPC Server: New connection added
2023-07-24 21:55:42.182 T:452   warning <general>: CGUIMediaWindow::OnMessage - updating in progress
2023-07-24 21:55:42.811 T:668      info <general>: Skipped 4 duplicate messages..
2023-07-24 21:55:42.811 T:668      info <general>: [Amazon VOD] Version: 0.9.8+matrix.1
2023-07-24 21:55:42.821 T:668      info <general>: [Amazon VOD] Unicode filename support: False
2023-07-24 21:55:42.823 T:668      info <general>: [Amazon VOD] Locale: en-us / Language: de
2023-07-24 21:55:42.913 T:668   warning <general>: Setting most video properties through ListItem.setInfo() is deprecated and might be removed in future Kodi versions. Please use the respective setter in InfoTagVideo.
2023-07-24 21:55:45.798 T:452   warning <general>: Skipped 6 duplicate messages..
2023-07-24 21:55:45.798 T:452   warning <general>: CGUIMediaWindow::OnMessage - updating in progress
2023-07-24 21:55:46.349 T:683      info <general>: Skipped 4 duplicate messages..
2023-07-24 21:55:46.349 T:683      info <general>: [Amazon VOD] Version: 0.9.8+matrix.1
2023-07-24 21:55:46.372 T:683      info <general>: [Amazon VOD] Unicode filename support: False
2023-07-24 21:55:46.376 T:683      info <general>: [Amazon VOD] Locale: en-us / Language: de
2023-07-24 21:55:46.480 T:683   warning <general>: Setting most video properties through ListItem.setInfo() is deprecated and might be removed in future Kodi versions. Please use the respective setter in InfoTagVideo.
2023-07-24 21:55:49.363 T:452   warning <general>: Skipped 6 duplicate messages..
2023-07-24 21:55:49.363 T:452   warning <general>: CGUIMediaWindow::OnMessage - updating in progress
2023-07-24 21:55:49.522 T:700      info <general>: Skipped 5 duplicate messages..
2023-07-24 21:55:49.522 T:700      info <general>: [Amazon VOD] Version: 0.9.8+matrix.1
2023-07-24 21:55:49.522 T:700      info <general>: [Amazon VOD] Unicode filename support: False
2023-07-24 21:55:49.524 T:700      info <general>: [Amazon VOD] Locale: en-us / Language: de
2023-07-24 21:55:49.530 T:700   warning <general>: Setting most video properties through ListItem.setInfo() is deprecated and might be removed in future Kodi versions. Please use the respective setter in InfoTagVideo.
2023-07-24 21:55:54.596 T:452   warning <general>: Skipped 6 duplicate messages..
2023-07-24 21:55:54.596 T:452   warning <general>: CGUIMediaWindow::OnMessage - updating in progress
2023-07-24 21:55:54.713 T:700   warning <general>: Skipped 5 duplicate messages..
2023-07-24 21:55:54.713 T:700   warning <general>: Setting most video properties through ListItem.setInfo() is deprecated and might be removed in future Kodi versions. Please use the respective setter in InfoTagVideo.
2023-07-24 21:56:06.064 T:700   warning <general>: Skipped 20 duplicate messages..
2023-07-24 21:56:06.064 T:700   warning <general>: [script.module.inputstreamhelper] Widevine update check was made on 2023-07-24 21:35
2023-07-24 21:56:06.081 T:700      info <general>: [Amazon VOD] postURL: http://127.0.0.1:58123/gpr/https%3A%2F%2Fatv-ps-eu.amazon.de%2Fcdp%2Fcatalog%2FGetPlaybackResources%3Fasin%3DB0B76P3HRY%26deviceTypeID%3DAOAGZA014O5RE%26firmware%3D1%26deviceID%3Da4941eb2b9c14534be9ac51dff1047b4%26marketplaceID%3DA1PA6795UKMFR9%26format%3Djson%26version%3D2%26gascEnabled%3Dfalse%26subtitleFormat%3DTTMLv2%26resourceUsage%3DImmediateConsumption%26consumptionType%3DStreaming%26deviceDrmOverride%3DCENC%26deviceStreamingTechnologyOverride%3DDASH%26deviceProtocolOverride%3DHttps%26deviceBitrateAdaptationsOverride%3DCVBR%252CCBR%26audioTrackId%3Dall%26languageFeature%3DMLFv2%26videoMaterialType%3DFeature%26desiredResources%3DPlaybackUrls%2CSubtitleUrls%2CForcedNarratives%2CTransitionTimecodes%26supportedDRMKeyScheme%3DDUAL_KEY
2023-07-24 21:56:08.372 T:700      info <general>: [Amazon VOD] Using Host: Cloudfront
2023-07-24 21:56:08.373 T:700      info <general>: [Amazon VOD] checkURL: https://pop-dub-ww.cf.dash.row.aiv-cdn.net/dm/2$V1FSL0aMex9a95kMPEQ9xtqpf94~/5e61/3091/fedc/4eeb-bc9c-62d67c17ed58/2e21a97d-46e1-4889-8ff4-145fda353818_corrected.mpd
2023-07-24 21:56:08.497 T:700      info <general>: [Amazon VOD] Using inputstream.adaptive Version: 20.3.9
2023-07-24 21:56:08.624 T:452      info <general>: VideoPlayer::OpenFile: plugin://plugin.video.amazon-test/?asin=B0B76P3HRY&mode=PlayVideo&name=Das+Auge&adult=0&trailer=0&selbitrate=0
2023-07-24 21:56:08.632 T:742      info <general>: Creating InputStream
2023-07-24 21:56:08.726 T:742   warning <general>: AddOnLog: inputstream.adaptive: Set headers to the manifest by using "inputstream.adaptive.stream_headers" property is a deprecated behaviour that will be removed in future.
                                                   To set headers to the manifest, use "inputstream.adaptive.manifest_headers" property.
2023-07-24 21:56:08.729 T:742      info <general>: AddOnLog: inputstream.adaptive: [Repr. chooser] Resolution set: 1920x1080, max allowed: 1920x1080, Adjust refresh rate: 1
2023-07-24 21:56:54.807 T:742      info <general>: AddOnLog: inputstream.adaptive: Successfully parsed manifest file (Periods: 1, Streams in first period: 257, Type: VOD)
2023-07-24 21:56:59.839 T:742      info <general>: Creating Demuxer
2023-07-24 21:56:59.859 T:742      info <general>: Opening stream: 1002 source: 256
2023-07-24 21:56:59.927 T:742      info <general>: Finding audio codec for: 86056
2023-07-24 21:56:59.928 T:742      info <general>: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder eac3
2023-07-24 21:56:59.928 T:742      info <general>: OpenStream: Allowing max Out-Of-Sync Value of 10 ms
2023-07-24 21:56:59.928 T:742      info <general>: Creating audio thread
2023-07-24 21:56:59.929 T:776      info <general>: running thread: CVideoPlayerAudio::Process()
2023-07-24 21:56:59.930 T:742      info <general>: Opening stream: 0 source: 1025
2023-07-24 21:57:00.056 T:742      info <general>: CDVDSubtitlesLibass: Using libass version 1500000
2023-07-24 21:57:00.056 T:742      info <general>: CDVDSubtitlesLibass: Creating ASS library structure
2023-07-24 21:57:00.056 T:742      info <general>: CDVDSubtitlesLibass: Initializing ASS Renderer
2023-07-24 21:57:00.058 T:742      info <general>: CDVDSubtitlesLibass: Initializing ASS library font settings
2023-07-24 21:57:00.183 T:742      info <general>: CDVDSubtitlesLibass: Creating new ASS track
2023-07-24 21:57:00.271 T:776      info <general>: Creating audio stream (codec id: 86056, channels: 6, sample rate: 48000, no pass-through)
2023-07-24 21:57:00.290 T:567      info <general>: CActiveAESink::OpenSink - initialize sink
2023-07-24 21:57:00.354 T:700      info <general>: [Amazon VOD] Starting Playback: 0.0/4333.0
2023-07-24 21:57:00.490 T:567      info <general>: CAESinkALSA::Initialize - Attempting to open device "default"
2023-07-24 21:57:00.501 T:567      info <general>: CAESinkALSA::Initialize - Opened device "default"
2023-07-24 21:57:00.502 T:567      info <general>: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats
2023-07-24 21:57:00.502 T:567      info <general>: CAESinkALSA::InitializeHW - Using data format AE_FMT_S24NE3
2023-07-24 21:57:00.516 T:742   warning <general>: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
2023-07-24 21:57:00.840 T:452      info <general>: Loading skin file: MusicVisualisation.xml, load type: KEEP_IN_MEMORY
2023-07-24 21:57:01.803 T:738     error <general>: ----------------------------------------
2023-07-24 21:57:01.803 T:738     error <general>: 

2023-07-24 21:57:01.803 T:738     error <general>: Exception occurred during processing of request from
2023-07-24 21:57:01.803 T:738     error <general>:  
2023-07-24 21:57:01.803 T:738     error <general>: ('127.0.0.1', 51594)
2023-07-24 21:57:01.803 T:738     error <general>: 

2023-07-24 21:57:01.806 T:738     error <general>: Traceback (most recent call last):

2023-07-24 21:57:01.806 T:738     error <general>: 
2023-07-24 21:57:01.806 T:738     error <general>:   File "/usr/lib/python3.9/socketserver.py", line 650, in process_request_thread
                                                       self.finish_request(request, client_address)

2023-07-24 21:57:01.806 T:738     error <general>: 
2023-07-24 21:57:01.806 T:738     error <general>:   File "/usr/lib/python3.9/socketserver.py", line 360, in finish_request
                                                       self.RequestHandlerClass(request, client_address, self)

2023-07-24 21:57:01.806 T:738     error <general>: 
2023-07-24 21:57:01.806 T:738     error <general>:   File "/usr/lib/python3.9/socketserver.py", line 720, in __init__
                                                       self.handle()

2023-07-24 21:57:01.806 T:738     error <general>: 
2023-07-24 21:57:01.806 T:738     error <general>:   File "/usr/lib/python3.9/http/server.py", line 429, in handle
                                                       self.handle_one_request()

2023-07-24 21:57:01.806 T:738     error <general>: 
2023-07-24 21:57:01.806 T:738     error <general>:   File "/usr/lib/python3.9/http/server.py", line 395, in handle_one_request
                                                       self.raw_requestline = self.rfile.readline(65537)

2023-07-24 21:57:01.806 T:738     error <general>: 
2023-07-24 21:57:01.806 T:738     error <general>:   File "/usr/lib/python3.9/socket.py", line 704, in readinto
                                                       return self._sock.recv_into(b)

2023-07-24 21:57:01.806 T:738     error <general>: 
2023-07-24 21:57:01.806 T:738     error <general>: socket.timeout: timed out

2023-07-24 21:57:01.806 T:738     error <general>: 
2023-07-24 21:57:01.807 T:738     error <general>: ----------------------------------------
2023-07-24 21:57:01.807 T:738     error <general>:
BBJake commented 1 year ago

For me this works again with 0.9.9 Beta 4

Sandmann79 commented 10 months ago

Has this issue been fixed for all?

Sandmann79 commented 6 months ago

No recent activity here, closing this issue