Jump to content

"Playback failed" | Error creating Demuxer | Wrong Server IP


BlubberGuy

Recommended Posts

BlubberGuy

Hi,

I don´t know if this has to do with the Plugin or with Kodi directly. Just going to ask here first.

Kodi 19.3

Fire TV Stick 4k Max, connected over LAN

Emby in local Network

 

I have the problem that sometimes it just can´t play files. I click on a file and then I get the error Message. This happens then with all Files. I get the same Error when Emby is not running. In this case Emby is definitely running...

When it works again, the same Files that did not work previously can be played normally again.

I am not sure when it happens. I have tried to first start the Server and then Kodi, and I got this and the other way around too.

 

It works again, at least until now, when I restart the FTV. Just exiting Kodi is apparently not enough.

 

Here are parts of the Log:

general>: Version Check: Version available  {'major': '19', 'minor': '4', 'tag': 'stable', 'tagversion': '', 'revision': '20220302-e12e66e019', 'extrainfo': 'final', 'addon_support': 'yes'}
2022-05-02 20:58:50.792 T:12794   DEBUG <general>: Version Check: You are running an older minor version
2022-05-02 20:58:50.796 T:12728   DEBUG <general>: [Warning] CGUITextureManager::GetTexturePath: could not find texture 'dialogs/progress/.png'
2022-05-02 20:58:50.811 T:12774   DEBUG <general>: easy_acquire - Created session to http://127.0.0.1
2022-05-02 20:58:50.813 T:12774   ERROR <general>: CCurlFile::Stat - Failed: Couldn't connect to server(7) for http://127.0.0.1:57342/embyimage-ae743a65719d4773aad4476b02368bb7-283-0-Backdrop-6a9081e558fd10c0770a61eb0576d0cb
2022-05-02 20:58:50.813 T:12774   DEBUG <general>: GetImageHash - unable to stat url http://127.0.0.1:57342/embyimage-ae743a65719d4773aad4476b02368bb7-283-0-Backdrop-6a9081e558fd10c0770a61eb0576d0cb
2022-05-02 20:58:51.154 T:12811   DEBUG <general>: -->Python Interpreter Initialized<--

                                                                                        
                                                                                        EMBY.emby.emby: ---[ INIT EMBYCLIENT: ]---
2022-05-02 20:58:56.767 T:12786    INFO <general>: INFO: EMBY.emby.emby: --[ server/DEFAULT ]
2022-05-02 20:58:56.773 T:12786    INFO <general>: INFO: EMBY.core.connection_manager: Begin connect
2022-05-02 20:58:56.773 T:12786   DEBUG <general>: DEBUG: EMBY.core.connection_manager: Begin connectToServer
2022-05-02 20:58:56.774 T:12786    INFO <general>: INFO: EMBY.core.connection_manager: testing connection mode 0 with server Tower
2022-05-02 20:58:56.774 T:12786    INFO <general>: INFO: EMBY.core.connection_manager: tryConnect url: http://192.168.1.140:8096/emby/system/info/public
2022-05-02 20:58:56.774 T:12786   DEBUG <general>: DEBUG: EMBY.emby.http: --->[ http ] {
                                                       "type": "GET",
                                                       "url": "http://192.168.1.140:8096/emby/system/info/public",
                                                       "headers": {
                                                           "Accept": "application/json",
                                                           "Accept-Charset": "UTF-8,*",
                                                           "Accept-encoding": "gzip",
                                                           "X-Application": "Emby-next-gen/6.2.10",
                                                           "Content-type": "application/x-www-form-urlencoded; charset=UTF-8",
                                                           "Authorization": "Emby Client=Emby-next-gen, Device=Kodi%20%28192.168.1.138%29, DeviceId=b5ca00bf-731c-46fb-9d24-f6904ad5a4a3, Version=6.2.10"
                                                       },
                                                       "timeout": 5
                                                   }


Creating Demuxer
2022-05-02 20:59:15.406 T:12851   DEBUG <general>: ffmpeg[0x7df55420X]: Format matroska,webm detected only with low score of 1, misdetection possible!
2022-05-02 20:59:15.406 T:12851   DEBUG <general>: Open - probing detected format [matroska,webm]
2022-05-02 20:59:15.406 T:12851   ERROR <general>: ffmpeg[0x7df55420X]: [matroska,webm] EBML header parsing failed
2022-05-02 20:59:15.406 T:12851   ERROR <general>: Open - Error, could not open file http://127.0.0.1:57342/movies/f1xxxxxxxxxx
2022-05-02 20:59:15.406 T:12851   ERROR <general>: OpenDemuxStream - Error creating demuxer
2022-05-02 20:59:15.407 T:12851    INFO <general>: CVideoPlayer::OnExit()
2022-05-02 20:59:15.410 T:12854   DEBUG <general>: Thread FileCache 2140223856 terminating
2022-05-02 20:59:15.411 T:12851   DEBUG <general>: Thread VideoPlayer -2133829264 terminating
2022-05-02 20:59:15.417 T:12774    INFO <general>: Deleting settings information for files http://127.0.0.1:57342/movies/f137axxxxxxxxxx
2022-05-02 20:59:15.420 T:12774   DEBUG <general>: OnPlayBackStopped: CApplication::OnPlayBackStopped
2022-05-02 20:59:15.420 T:12733   DEBUG <general>: void CXBMCApp::OnPlayBackStopped()
2022-05-02 20:59:15.426 T:12728   DEBUG <general>: CVideoGUIInfo::InitCurrentItem(http://127.0.0.1:57342/moxxxxxxxxxxxxxxxx
2022-05-02 20:59:15.455 T:12733   DEBUG <general>: void CXBMCApp::OnPlayBackStarted()
2022-05-02 20:59:15.459 T:12786    INFO <general>: INFO: EMBY.hooks.player: [ onPlayBackStarted ]
2022-05-02 20:59:15.460 T:12733   DEBUG <general>: Visible Behind request: false
2022-05-02 20:59:15.520 T:12785   ERROR <general>: EXCEPTION: XBMC is not playing any media file
2022-05-02 20:59:15.555 T:12728   DEBUG <general>: ------ Window Init (DialogBusy.xml) ------
2022-05-02 20:59:15.557 T:12728   DEBUG <general>: ------ Window Init (DialogConfirm.xml) ------
2022-05-02 20:59:15.557 T:12728    INFO <general>: Loading skin file: DialogConfirm.xml, load type: KEEP_IN_MEMORY
2022-05-02 20:59:15.564 T:12728    INFO <general>: CVideoPlayer::CloseFile()
2022-05-02 20:59:15.564 T:12728   DEBUG <general>: DeleteRenderer - deleting renderer
2022-05-02 20:59:15.564 T:12728   DEBUG <general>: LinuxRendererGLES: Cleaning up GLES resources
2022-05-02 20:59:15.565 T:12728   DEBUG <general>: CAndroidUtils: IsHDRDisplay: true
2022-05-02 20:59:15.565 T:12728    INFO <general>: VideoPlayer: waiting for threads to exit
2022-05-02 20:59:15.565 T:12728    INFO <general>: VideoPlayer: finished waiting
2022-05-02 20:59:15.566 T:12728   DEBUG <general>: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
2022-05-02 20:59:15.569 T:12785   ERROR <general>: EXCEPTION: XBMC is not playing any file

 

Interestingly it is trying to access the localhost IP. But the IP of the Server is: 192.168.1.140. ....Although, I already had an Errorlog where the correct IP was used and it still did show the same Error.

 

Here is another File:

 CCurlFile::GetMimeType - http://127.0.0.1:57342/movies/f137a2dd21bbc1b99aa5c0f6bf02a805/embyvideo-ae743a6yyyyyyyyyyyyyy-> 
2022-05-02 20:59:31.039 T:12728   DEBUG <general>: CPlayerCoreFactory::GetPlayers(http://127.0.0.1:57342/movies/f137a2dd21bbc1b99aa5c0f6bf02a805/embyvideo-ae743a65yyyyyyyyyyyyyyyyy)
2022-05-02 20:59:31.039 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2022-05-02 20:59:31.039 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2022-05-02 20:59:31.039 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2022-05-02 20:59:31.039 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: streams
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: matches rule: streams
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: mp2
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: discimage
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: nsv
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerSelectionRule::GetPlayers: considering rule: radio
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
2022-05-02 20:59:31.040 T:12728   DEBUG <general>: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
2022-05-02 20:59:31.041 T:12728   DEBUG <general>: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
2022-05-02 20:59:31.041 T:12728   DEBUG <general>: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
2022-05-02 20:59:31.041 T:12728   DEBUG <general>: CPlayerCoreFactory::GetPlayers: added 1 players
2022-05-02 20:59:31.043 T:12728   DEBUG <general>: Radio UECP (RDS) Processor - new CDVDRadioRDSData
2022-05-02 20:59:31.043 T:12728    INFO <general>: VideoPlayer::OpenFile: http://127.0.0.1:57342/movies/f137a2dd21bbc1b99aa5c0f6bf02yyyyyyyyyyyy
2022-05-02 20:59:31.052 T:12728   DEBUG <general>: CCurlFile::GetMimeType - http://127.0.0.1:57342/movies/f137a2dd21bbc1byyyyyyyyyyyy
2022-05-02 20:59:31.052 T:12898   DEBUG <general>: Thread VideoPlayer start, auto delete: false
2022-05-02 20:59:31.052 T:12728   DEBUG <general>: OnPlayBackStarted: CApplication::OnPlayBackStarted
2022-05-02 20:59:31.054 T:12898    INFO <general>: Creating InputStream
2022-05-02 20:59:31.055 T:12898   DEBUG <general>: CurlFile::Open(0x7b8ff298) http://127.0.0.1:57342/movies/f13yyyyyyyyy
2022-05-02 20:59:31.055 T:12791   DEBUG <general>: [ script.embuary.helper ] Video playlist has been filled. Clear existing music playlist
2022-05-02 20:59:31.061 T:12898   DEBUG <general>: CFileCache::Open - <http://127.0.0.1:57342/movies/f13yyyyyyyyyyy opening
2022-05-02 20:59:31.062 T:12898   DEBUG <general>: CurlFile::Open(0x7c4ecf80) http://127.0.0.1:57342/movies/f137a2dd21bbyyyyyyyyyyyyyyyyyy
2022-05-02 20:59:31.062 T:12774   DEBUG <general>: Loading settings for http://127.0.0.1:57342/movies/f137a2yyyyyyyyyyyyyyyy
2022-05-02 20:59:31.073 T:12898   DEBUG <general>: CFileCache::Open - <http://127.0.0.1:57342/movies/f137yyyyyyyyyyyyyyyyyyyyyyyyyy> source chunk size is 0, setting cache chunk size to 131072
2022-05-02 20:59:31.074 T:12898   DEBUG <general>: CFileCache::Open - <http://127.0.0.1:57342/movies/f137a2dd21bbc1yyyyyyyyyyyyyyyyyyyyyyyyyyyyusing single memory cache sized 20971520 bytes
2022-05-02 20:59:31.074 T:12901   DEBUG <general>: Thread FileCache start, auto delete: false
2022-05-02 20:59:31.074 T:12901   DEBUG <general>: CFileCache::Process - <http://127.0.0.1:57342/movies/f137a2dd21bbc1b99aa5c0f6bf02a805/embyvideo-ae743a6yyyyyyyyyyyyyyyyyyyyyyyyy source read didn't return any data! Hit eof(?)
2022-05-02 20:59:31.074 T:12898    INFO <general>: Creating Demuxer
2022-05-02 20:59:31.075 T:12898   DEBUG <general>: ffmpeg[0x7afd3820X]: Format matroska,webm detected only with low score of 1, misdetection possible!
2022-05-02 20:59:31.075 T:12898   DEBUG <general>: Open - probing detected format [matroska,webm]
2022-05-02 20:59:31.075 T:12898   ERROR <general>: ffmpeg[0x7afd3820X]: [matroska,webm] EBML header parsing failed
2022-05-02 20:59:31.075 T:12898   ERROR <general>: Open - Error, could not open file http://127.0.0.1:57342/movies/f137a2dd2yyyyyyyyyyyyyyyy
2022-05-02 20:59:31.075 T:12898   ERROR <general>: OpenDemuxStream - Error creating demuxer
2022-05-02 20:59:31.075 T:12898    INFO <general>: CVideoPlayer::OnExit()
2022-05-02 20:59:31.077 T:12901   DEBUG <general>: Thread FileCache 2071992688 terminating
2022-05-02 20:59:31.077 T:12898   DEBUG <general>: Thread VideoPlayer 2073033072 terminating
2022-05-02 20:59:31.089 T:12774    INFO <general>: Deleting settings information for files http://127.0.0.1:57342/movies/f137a2dd21bbc1b99aa5c0yyyyyyyyyyyyy
2022-05-02 20:59:31.091 T:12774   DEBUG <general>: OnPlayBackStopped: CApplication::OnPlayBackStopped
2022-05-02 20:59:31.092 T:12733   DEBUG <general>: void CXBMCApp::OnPlayBackStopped()
2022-05-02 20:59:31.094 T:12728   DEBUG <general>: CVideoGUIInfo::InitCurrentItem(http://127.0.0.1:57342/movies/f137a2dd21yyyyyyyyyyyyyy
2022-05-02 20:59:31.126 T:12733   DEBUG <general>: void CXBMCApp::OnPlayBackStarted()
2022-05-02 20:59:31.130 T:12733   DEBUG <general>: Visible Behind request: false
2022-05-02 20:59:31.136 T:12786    INFO <general>: INFO: EMBY.hooks.player: [ onPlayBackStarted ]
2022-05-02 20:59:31.190 T:12785   ERROR <general>: EXCEPTION: XBMC is not playing any media file
2022-05-02 20:59:31.225 T:12728   DEBUG <general>: ------ Window Init (DialogBusy.xml) ------
2022-05-02 20:59:31.227 T:12728   DEBUG <general>: ------ Window Init (DialogConfirm.xml) ------

 

So, it seems that it attempts to connect to localhost instead of the actual Server IP. But at the same time it is apparently checking if the Server is online...

Link to comment
Share on other sites

quickmic

Connecting to localhost is normal in addon mode. The localhost acts a sort of proxy and redirects to the actual Emby server URL.

If you wanna see what actually happens, you need the debug mode enabled (Don't post debug logs in public, it includes your API keys.) You can send me debug logs via PM.

Is there a pattern, when it happens? Could be related to sleep mode or switching from Kodi app back to FireOS menu and vice versa (which also acts as a sort of sleep command).

Currently I think it's a Kodi issue, but not sure.

 

 

 

Link to comment
Share on other sites

BlubberGuy

Hi, thanks.

I have send you a PM.

 

I thought that it depens on the order how Emby and Kodi was started, but I can´t reproduce it properly.

Link to comment
Share on other sites

quickmic
1 hour ago, BlubberGuy said:

Hi, thanks.

I have send you a PM.

 

I thought that it depens on the order how Emby and Kodi was started, but I can´t reproduce it properly.

Please update to plugin.video.emby-next-gen v6.2.15

you are currently running plugin.video.emby-next-gen v6.2.10

Not sure if this fixes all the issue, but I assume some of them.

 

Log:

ERROR: EMBY.emby.http: [ ServerTimeout ]

I seems Emby server was not online when the plugin started. This was fixed in 6.2.15, but maybe there are other issues.

 

Edited by quickmic
Link to comment
Share on other sites

BlubberGuy

Is this the first Message?

Because it´s possible that everything is already loaded and showing, and when I click on something it returns a Playback Error. Only when I get the Welcome Message from emby-next gen it´s possible to play something.

 

The addon doesn´t find any updates.

Link to comment
Share on other sites

quickmic
33 minutes ago, BlubberGuy said:

The addon doesn´t find any updates.

Kodi updates are not always reliable. Then install manually via zip file and select the online emby source.

Is this the first Message? 

Yes (more or less, after the init), and this is a problem.

 

Edited by quickmic
Link to comment
Share on other sites

BlubberGuy

Hi, I tested it some this week and I did not get this problem again.

I guess there was really an problem with my Emby Version.

 

Thanks!

  • Like 1
Link to comment
Share on other sites

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!

Register a new account

Sign in

Already have an account? Sign in here.

Sign In Now
×
×
  • Create New...