Login at Kodi Home

nixterrimus · 2011-02-27, 07:42

Hey there!

I'm not sure where I'm getting a problem, so let me outline where I'm at step by step:

I'm running an IPTV channel in my house. I've set it up using VLC. I setup the channel like this:

Code:
setup channel1 output #rtp{mux=ts,dst=239.255.1.1,port=5004,sdp=sap://,name="Channel 1"}

This works great using VLC. I can fire up a machine anywhere on the network and see that I am receiving an MPEG ts stream that's encoded with the a52 codec for audio (Stream 0) and mp4v codec for video (Stream 1).

I then bring this stream over to tvheadend with the Group of 239.255.1.1, the port 5004, and the interface eth1. When I click the play button through the web interface I get nothin'. But that didn't worry me too much, since it's working in VLC.

However I when I connect via XBMC I get audio but no video!

Here's my log from tv headend:

Code:
Feb 27 00:19:26 htsp: 127.0.0.1: Welcomed client software: XBMC Media Center

Feb 27 00:19:26 htsp: 127.0.0.1 [ XBMC Media Center ]: Identified as user admin

Feb 27 00:19:26 htsp: 127.0.0.1 [ admin | XBMC Media Center ]: Privileges raised

Feb 27 00:19:26 subscription: "127.0.0.1 [ admin | XBMC Media Center ]" subscribing on "Channel 1", weight: 150, adapter: "eth1", network: "", mux: "239.255.1.1", provider: "", service: "", quality: 100

Everything looks good there. However during playback I get no video. Here's a slice from XBMC log where I think there's a problem:

Code:
31:56 T:3054397312 M:223055872   DEBUG: SDLKeyboard: scancode: 36, sym: 13, unicode: 13, modifier: 0

31:56 T:3054397312 M:223055872   DEBUG: OnKey: 61453 pressed, action is Select

31:56 T:3054397312 M:223055872   DEBUG: new file set audiostream:0

31:56 T:3054397312 M:223055872   DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/all/1.pvr)

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/rtmp/mms/udp

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdfile

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv

31:56 T:3054397312 M:223055872   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio

31:56 T:3054397312 M:223055872   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players

31:56 T:3054397312 M:223055872   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)

31:56 T:3054397312 M:223055872   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0

31:56 T:3054397312 M:223055872   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1

31:56 T:3054397312 M:223055872   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)

31:56 T:3054397312 M:223055872   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players

31:56 T:3054397312 M:221913088  NOTICE: DVDPlayer: Opening: pvr://channels/tv/all/1.pvr

31:56 T:3054397312 M:221913088 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED

31:56 T:2813963120 M:221913088   DEBUG: thread start, auto delete: 0

31:56 T:2813963120 M:221913088  NOTICE: Creating InputStream

31:56 T:2813963120 M:221913088   DEBUG: AddOnLog: xbmc.pvrclient/Tvheadend HTSP Client: cHTSPSession::Connect - connecting to '127.0.0.1', port '9982'

31:56 T:2813963120 M:221913088   DEBUG: AddOnLog: xbmc.pvrclient/Tvheadend HTSP Client: cHTSPSession::Connect - connected to server: [HTS Tvheadend], version: [2.12], proto: 5

31:56 T:2813963120 M:221913088   DEBUG: AddOnLog: xbmc.pvrclient/Tvheadend HTSP Client: cHTSPSession::ParseChannelUpdate - id:1, name:'Channel 1', icon:'(null)', event:0

31:56 T:2813963120 M:221872128   DEBUG: AddOnLog: xbmc.pvrclient/Tvheadend HTSP Client: cHTSPDemux::SubscriptionStart - id: 1, type: AC3, language: (null)

31:56 T:2813963120 M:221872128   DEBUG: AddOnLog: xbmc.pvrclient/Tvheadend HTSP Client: cHTSPDemux::SubscriptionStart - subscription started on adapter eth1, mux 239.255.1.1, network , provider , service

31:56 T:2813963120 M:221872128   DEBUG: Open - TV Channel has started on filename pvr://channels/tv/all/1.pvr

31:56 T:2813963120 M:221872128  NOTICE: Creating Demuxer

31:56 T:2813963120 M:221872128   DEBUG: CDVDDemuxPVRClient::RequestStreams(): added stream 0:1 with codec_id 86020

31:56 T:2813963120 M:221872128  NOTICE: Opening audio stream: 0 source: 256

31:56 T:2813963120 M:221872128  NOTICE: Finding audio codec for: 86020

31:56 T:2813963120 M:221872128   DEBUG: FactoryCodec - Audio: FFmpeg - Opening

31:56 T:2813963120 M:221872128   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avutil-50-i486-linux.so)

31:56 T:2813963120 M:221872128   DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avutil-50-i486-linux.so

31:56 T:2813963120 M:221745152   DEBUG: SECTION:LoadDLL(special://xbmcbin/system/players/dvdplayer/avcodec-52-i486-linux.so)

31:56 T:2813963120 M:221745152   DEBUG: Loading: /usr/lib/xbmc/system/players/dvdplayer/avcodec-52-i486-linux.so

31:56 T:2813963120 M:218050560   DEBUG: FactoryCodec - Audio: FFmpeg - Opened

31:56 T:2813963120 M:218050560  NOTICE: Creating audio thread

31:56 T:2825124720 M:218050560   DEBUG: thread start, auto delete: 0

31:56 T:2825124720 M:218050560  NOTICE: running thread: CDVDPlayerAudio::Process()

31:56 T:2813963120 M:218050560   DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/all/1.pvr)

31:56 T:3054397312 M:218050560   DEBUG: ------ Window Init (DialogBusy.xml) ------

31:56 T:3054397312 M:218050560   DEBUG: Alloc resources: 0.01ms (0.01 ms skin load)

31:56 T:2813963120 M:218050560   DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/all/1.pvr)

31:56 T:2813963120 M:218050560   DEBUG: CDVDPlayer::SetCaching - caching state 2

31:56 T:2813963120 M:218050560 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED

31:56 T:2813963120 M:218050560   DEBUG: AddOnLog: xbmc.pvrclient/Tvheadend HTSP Client: cHTSPDemux::Read - Frame type

31:56 T:2825124720 M:218050560   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(24596731766.000000, 1)

31:56 T:2825124720 M:218050560  NOTICE: Creating audio device with codec id: 86020, channels: 6, sample rate: 48000, no pass-through

31:56 T:2825124720 M:218050560    INFO: AudioRendererFactory:  not a explicit device, trying to autodetect.

31:56 T:2825124720 M:218050560    INFO: CPCMRemap: Configured speaker layout: 2.0

31:56 T:2825124720 M:218050560    INFO: CPCMRemap: I channel map: FL,FR,CE,LFE,SL,SR

31:56 T:2825124720 M:218050560   DEBUG: CPCMRemap: Forcing side channel map to back channels

31:56 T:2825124720 M:218050560    INFO: CPCMRemap: O channel map: FL,FR

31:56 T:2825124720 M:218050560   DEBUG: CPCMRemap: Downmix normalization is disabled

31:56 T:2825124720 M:218050560   DEBUG: CPCMRemap: FL = FL(1.000000) CE(0.707107) LFE(0.707107) BL(1.000000)

31:56 T:2825124720 M:218050560   DEBUG: CPCMRemap: FR = FR(1.000000) CE(0.707107) LFE(0.707107) BR(1.000000)

31:56 T:2825124720 M:218050560   DEBUG: CPulseAudioDirectSound::CPulseAudioDirectSound - Requested channels changed from 6 to 2

31:56 T:2825124720 M:218050560   DEBUG: RemoveActiveDevice - Removing device 1

31:56 T:2825124720 M:218050560   DEBUG: CGUIAudioManager::DeInitialize

31:56 T:2813963120 M:218050560   DEBUG: AddOnLog: xbmc.pvrclient/Tvheadend HTSP Client: cHTSPDemux::Read - Frame type

31:56 T:2813963120 M:217849856   DEBUG: Previous line repeats 8 times.

31:56 T:2813963120 M:217849856   DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/all/1.pvr)

31:56 T:2813963120 M:217849856   DEBUG: AddOnLog: xbmc.pvrclient/Tvheadend HTSP Client: cHTSPDemux::Read - Frame type

31:56 T:3012553584 M:217849856   DEBUG: Previous line repeats 1 times.

31:56 T:3012553584 M:217849856   ERROR: PVR: Tvheadend HTSP Client/127.0.0.1:9982 - Client returns bad error (-3) after SignalQuality

The error appears on the last line.

Where is my problem? Have I setup the stream badly with VLC? Is there a problem with TvHeadEnd? Or is there something I've missed in XBMC?

Any help or ideas would be greatly appreciated.

Thanks!
-Nick

opdenkamp · 2011-02-28, 09:59

the "client returns bad error" message can be ignored safely.

I'm not seeing anything strange in your log that could explain your issue.

you could try the latest development version from my sig. it has a newer ffmpeg. could solve that problem.

Pash0k · 2012-11-14, 10:59

Yeah! I have same problem. If i use other port in other chanel - it work. But i thikn 5004 port in use XBMC. I make UDP - it work.
But I want to fix this problem.
My XBMC Eden.
Thanks.

Pash0k · 2012-11-14, 21:14

Port 5004 not work:

Code:
07:19 T:7968  NOTICE: DVDPlayer: Opening: udp://@239.99.4.8:5004

07:19 T:7968  NOTICE: CDVDPlayer::CloseFile()

07:19 T:7968 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED

07:19 T:7968  NOTICE: DVDPlayer: waiting for threads to exit

07:19 T:7968  NOTICE: DVDPlayer: finished waiting

07:19 T:7968 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED

07:19 T:5960  NOTICE: Creating InputStream

07:19 T:5960  NOTICE: Creating Demuxer

07:19 T:5960   ERROR: CDVDPlayer::OpenDemuxStream - Error creating demuxer

07:19 T:5960  NOTICE: CDVDPlayer::OnExit()

07:19 T:5960  NOTICE: CDVDPlayer::OnExit() deleting input stream

07:19 T:7968   ERROR: Playlist Player: skipping unplayable item: 73, path [udp://@239.99.4.8:5004]

Port 1234 work

Code:
07:20 T:7968  NOTICE: DVDPlayer: Opening: udp://@230.33.0.1:1234

07:20 T:7968  NOTICE: CDVDPlayer::CloseFile()

07:20 T:7968 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED

07:20 T:7968  NOTICE: DVDPlayer: waiting for threads to exit

07:20 T:7968  NOTICE: DVDPlayer: finished waiting

07:20 T:7968 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED

07:20 T:8016  NOTICE: Creating InputStream

07:20 T:8016  NOTICE: Creating Demuxer

07:21 T:6596  NOTICE: -->Python Interpreter Initialized<--

07:21 T:6596 WARNING: 'xbmc.output()' is depreciated and will be removed in future releases, please use 'xbmc.log()' instead

07:21 T:6596  NOTICE:  === unhandled exception in adIO ===

07:31 T:8016  NOTICE: Opening video stream: 0 source: 256

07:31 T:8016  NOTICE: Creating video codec with codec id: 2

07:31 T:8016  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video

07:31 T:8016  NOTICE: Creating video thread

07:31 T:8016  NOTICE: Opening audio stream: 1 source: 256

07:31 T:8016  NOTICE: Finding audio codec for: 86016

07:31 T:2784  NOTICE: running thread: video_thread

07:31 T:8016  NOTICE: Creating audio thread

07:31 T:5948  NOTICE: running thread: CDVDPlayerAudio::Process()

07:31 T:5948  NOTICE: Creating audio device with codec id: 86016, channels: 2, sample rate: 48000, no pass-through

07:31 T:2784  NOTICE:  fps: 25.000000, pwidth: 704, pheight: 576, dwidth: 704, dheight: 528

07:31 T:2784  NOTICE: Display resolution DESKTOP : 1920x1080 @ 60.00 - Full Screen (12)

Where is bug?