Kodi: 18.0-ALPHA1 Git:20170128-6d5a87b
TVH: 4.1-2415~ge5f5a4278-dirty (HTSPv26)
Pertinent Kodi Debug Log:
Code:
07:42:46.458 T:139733311875328 DEBUG: DoWork - Marking video item pvr://recordings/tv/active/<tv show 1>, TV%20(<tv channel 1>), 20170130_053000, 1261285051.pvr as watched
07:42:46.727 T:139732657145600 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
07:42:46.727 T:139732657145600 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
07:42:46.729 T:139733311875328 DEBUG: Loading settings for pvr://recordings/tv/active/<tv show 2>, TV%20(<tv channel 2>), 20170130_130000, 2096465009.pvr
07:42:46.730 T:139733311875328 DEBUG: CPlayerCoreFactory::GetPlayers(pvr://recordings/tv/active/<tv show 2>, 20170130_130000, 2096465009.pvr)
07:42:46.730 T:139733311875328 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
07:42:46.730 T:139733311875328 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
07:42:46.730 T:139733311875328 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
07:42:46.730 T:139733311875328 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
07:42:46.730 T:139733311875328 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
07:42:46.730 T:139733311875328 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
07:42:46.730 T:139733311875328 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
07:42:46.730 T:139733311875328 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
07:42:46.730 T:139733311875328 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
07:42:46.730 T:139733311875328 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
07:42:46.730 T:139733311875328 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
07:42:46.730 T:139733311875328 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
07:42:46.730 T:139733311875328 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
07:42:46.730 T:139733311875328 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (VideoPlayer)
07:42:46.730 T:139733311875328 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
07:42:46.730 T:139733311875328 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
07:42:46.730 T:139733311875328 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
07:42:46.730 T:139733311875328 NOTICE: CVideoPlayer::CloseFile()
07:42:46.730 T:139733311875328 NOTICE: VideoPlayer: waiting for threads to exit
07:42:46.738 T:139730184304384 NOTICE: CVideoPlayer::OnExit()
07:42:46.738 T:139730184304384 NOTICE: Closing stream player 1
07:42:46.738 T:139730184304384 NOTICE: Waiting for audio thread to exit
07:42:46.776 T:139730765018880 NOTICE: thread end: CVideoPlayerAudio::OnExit()
07:42:46.776 T:139730184304384 NOTICE: Closing audio device
07:42:46.776 T:139730765018880 DEBUG: Thread VideoPlayerAudio 139730765018880 terminating
07:42:46.776 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Using fltp internally between filters
07:42:46.776 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Matrix coefficients:
07:42:46.776 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FL: FL:1.000000 FR:0.000000 FC:0.707107 LFE:0.000000 SL:0.707107 SR:0.000000
07:42:46.776 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FR: FL:0.000000 FR:1.000000 FC:0.707107 LFE:0.000000 SL:0.000000 SR:0.707107
07:42:46.826 T:139730184304384 DEBUG: CDVDAudio::Flush - flush audio stream
07:42:46.826 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Using fltp internally between filters
07:42:46.827 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Matrix coefficients:
07:42:46.827 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FL: FL:1.000000 FR:0.000000 FC:0.707107 LFE:0.000000 SL:0.707107 SR:0.000000
07:42:46.827 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FR: FL:0.000000 FR:1.000000 FC:0.707107 LFE:0.000000 SL:0.000000 SR:0.707107
07:42:46.827 T:139732640360192 DEBUG: CActiveAE::DiscardStream - audio stream deleted
07:42:46.827 T:139732640360192 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
07:42:46.827 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Using fltp internally between filters
07:42:46.827 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Matrix coefficients:
07:42:46.827 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FL: FL:1.000000 FR:0.000000 FC:0.707107 LFE:0.000000 SL:0.707107 SR:0.000000
07:42:46.827 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FR: FL:0.000000 FR:1.000000 FC:0.707107 LFE:0.000000 SL:0.000000 SR:0.707107
07:42:46.827 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Using fltp internally between filters
07:42:46.827 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Matrix coefficients:
07:42:46.827 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FL: FL:1.000000 FR:0.000000 FC:0.707107 LFE:0.000000 SL:0.707107 SR:0.000000
07:42:46.827 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FR: FL:0.000000 FR:1.000000 FC:0.707107 LFE:0.000000 SL:0.000000 SR:0.707107
07:42:46.827 T:139732640360192 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
07:42:46.827 T:139730184304384 DEBUG: Previous line repeats 1 times.
07:42:46.827 T:139730184304384 NOTICE: Deleting audio codec
07:42:46.827 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Using fltp internally between filters
07:42:46.827 T:139730184304384 NOTICE: Closing stream player 2
07:42:46.827 T:139730184304384 NOTICE: waiting for video thread to exit
07:42:46.828 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Matrix coefficients:
07:42:46.828 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FL: FC:0.707107
07:42:46.828 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FR: FC:0.707107
07:42:46.828 T:139732631967488 INFO: CActiveAESink::OpenSink - initialize sink
07:42:46.857 T:139730477557504 NOTICE: thread end: video_thread
07:42:46.857 T:139730477557504 DEBUG: Thread VideoPlayerVideo 139730477557504 terminating
07:42:46.857 T:139730184304384 NOTICE: deleting video codec
07:42:46.857 T:139730184304384 DEBUG: VAAPI::Release pre-cleanup
07:42:46.857 T:139730184304384 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs close id=3
07:42:46.999 T:139732631967488 DEBUG: CActiveAESink::OpenSink - trying to open device PULSE:Default
07:42:46.999 T:139732631967488 DEBUG: PulseAudio: Context authorizing
07:42:47.000 T:139732631967488 DEBUG: PulseAudio: Context setting name
07:42:47.000 T:139732631967488 DEBUG: PulseAudio: Context ready
07:42:47.000 T:139732631967488 DEBUG: PulseAudio: Stream ready
07:42:47.000 T:139732631967488 NOTICE: PulseAudio: Opened device Default in pcm mode with Buffersize 150 ms
07:42:47.000 T:139732631967488 DEBUG: CActiveAESink::OpenSink - PULSE Initialized:
07:42:47.000 T:139732631967488 DEBUG: Output Device : Default
07:42:47.000 T:139732631967488 DEBUG: Sample Rate : 44100
07:42:47.000 T:139732631967488 DEBUG: Sample Format : AE_FMT_FLOAT
07:42:47.000 T:139732631967488 DEBUG: Channel Count : 2
07:42:47.000 T:139732631967488 DEBUG: Channel Layout: FL,FR
07:42:47.000 T:139732631967488 DEBUG: Frames : 2205
07:42:47.000 T:139732631967488 DEBUG: Frame Size : 8
07:42:47.001 T:139732631967488 DEBUG: ffmpeg[7F1609E45700]: [SWR] Using fltp internally between filters
07:42:47.001 T:139732640360192 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
07:42:47.001 T:139732640360192 DEBUG: Previous line repeats 1 times.
07:42:47.001 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Using fltp internally between filters
07:42:50.010 T:139731767187200 DEBUG: weather.yahoo: no weather data, retry
07:42:51.858 T:139730184304384 DEBUG: Previous line repeats 1 times.
07:42:51.858 T:139730184304384 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command fileClose failed: No response received
07:42:51.858 T:139730184304384 DEBUG: CDVDInputStreamPVRManager::Close - stream closed
07:42:51.858 T:139730184304384 DEBUG: OnPlayBackStopped: play state was 2, starting 1
07:42:51.858 T:139730773411584 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - new connection requested
07:42:51.858 T:139730184304384 DEBUG: Thread VideoPlayer 139730184304384 terminating
07:42:51.858 T:139733311875328 NOTICE: VideoPlayer: finished waiting
07:42:51.858 T:139730773411584 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connecting to <server ip>:<server port>
07:42:51.858 T:139733311875328 DEBUG: DeleteRenderer - deleting renderer
07:42:51.858 T:139733311875328 NOTICE: VAAPI::Close
07:42:51.858 T:139730773411584 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected
07:42:51.858 T:139730184304384 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending hello
07:42:51.859 T:139730467186432 NOTICE: COutput::OnExit: Output Thread terminated
07:42:51.859 T:139730467186432 DEBUG: Thread Vaapi-Output 139730467186432 terminating
07:42:51.859 T:139733311875328 DEBUG: VAAPI::FiniVAAPIOutput destroying 0 video surfaces
07:42:51.859 T:139733311875328 NOTICE: VAAPI::Close - closing decoder context
07:42:51.860 T:139733311875328 DEBUG: LinuxRendererGL: Cleaning up GL resources
07:42:51.860 T:139733311875328 NOTICE: VideoPlayer: Opening: pvr://recordings/tv/active/<tv show 2>, 20170130_130000, 2096465009.pvr
07:42:51.860 T:139733311875328 DEBUG: LinuxRendererGL: Cleaning up GL resources
07:42:51.860 T:139733311875328 DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16
07:42:51.860 T:139730467186432 DEBUG: Thread VideoPlayer start, auto delete: false
07:42:51.860 T:139730467186432 NOTICE: Creating InputStream
07:42:51.860 T:139730467186432 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs open file=dvr/2096465009
07:42:52.002 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Using fltp internally between filters
07:42:52.360 T:139733311875328 DEBUG: ------ Window Init (DialogBusy.xml) ------
07:42:52.362 T:139729896367872 DEBUG: DoWork - Saving file state for video item pvr://recordings/tv/active/<tv show 1>, TV%20(<tv channel 1>), 20170130_053000, 1261285051.pvr
07:42:52.362 T:139733311875328 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
07:42:52.362 T:139733311875328 DEBUG: ------ Window Init (Custom_1109_TopBarOverlay.xml) ------
07:42:52.363 T:139729896367872 DEBUG: DoWork - Marking video item pvr://recordings/tv/active/<tv show 1>, TV%20(<tv channel 1>), 20170130_053000, 1261285051.pvr as watched
07:42:52.611 T:139732657145600 DEBUG: CAnnouncementManager - Announcement: OnUpdate from xbmc
07:42:52.611 T:139732657145600 DEBUG: GOT ANNOUNCEMENT, type: 16, from xbmc, message OnUpdate
07:42:52.850 T:139731767187200 DEBUG: weather.yahoo: no weather data, retry
07:42:53.662 T:139730756626176 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command getDiskSpace failed: No response received
07:42:56.858 T:139730184304384 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - Command hello failed: No response received
07:42:56.859 T:139730184304384 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - failed to send hello
07:42:56.859 T:139730184304384 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connection state change (5 -> 2)
07:42:56.859 T:139730184304384 DEBUG: PVR - PVRConnectionStateChange - state for connection '<server ip>:<server port>' on client 'Tvheadend HTSP Client' changed from '5' to '2'
07:42:57.004 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Using fltp internally between filters
07:42:57.005 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Matrix coefficients:
07:42:57.005 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FL: FC:0.707107
07:42:57.005 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FR: FC:0.707107
07:42:59.106 T:139731767187200 DEBUG: weather.yahoo: no weather data, retry
07:43:01.859 T:139730773411584 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - new connection requested
07:43:01.859 T:139730773411584 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connecting to <server ip>:<server port>
07:43:01.859 T:139730773411584 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected
07:43:01.860 T:139730184304384 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending hello
07:43:01.860 T:139730467186432 ERROR: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs failed to open file
07:43:01.860 T:139730467186432 ERROR: CVideoPlayer::OpenInputStream - error opening [pvr://recordings/tv/active/<tv show 2>, 20170130_130000, 2096465009.pvr]
07:43:01.860 T:139730467186432 NOTICE: CVideoPlayer::OnExit()
07:43:01.860 T:139730467186432 DEBUG: CDVDInputStreamPVRManager::Close - stream closed
07:43:01.860 T:139730467186432 DEBUG: OnPlayBackStopped: play state was 1, starting 1
07:43:01.860 T:139730467186432 DEBUG: Thread VideoPlayer 139730467186432 terminating
07:43:01.862 T:139733311875328 DEBUG: OnPlayBackStopped: play state was 3, starting 0
07:43:01.862 T:139732657145600 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
07:43:01.862 T:139733311875328 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/libsse4-x86_64-linux.so)
07:43:01.862 T:139732657145600 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
07:43:01.863 T:139733311875328 DEBUG: ------ Window Init (DialogNotification.xml) ------
07:43:01.863 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Using fltp internally between filters
07:43:01.864 T:139730184304384 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connected to Tvheadend / 4.1-2415~ge5f5a4278-dirty (HTSPv26)
07:43:01.864 T:139733311875328 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
07:43:01.864 T:139730184304384 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - sending auth
07:43:01.864 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] Matrix coefficients:
07:43:01.864 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FL: FC:0.707107
07:43:01.864 T:139732640360192 DEBUG: ffmpeg[7F160A646700]: [SWR] FR: FC:0.707107
07:43:01.865 T:139733311875328 NOTICE: CVideoPlayer::CloseFile()
07:43:01.865 T:139733311875328 NOTICE: VideoPlayer: waiting for threads to exit
07:43:01.865 T:139733311875328 NOTICE: VideoPlayer: finished waiting
07:43:01.865 T:139733311875328 DEBUG: DeleteRenderer - deleting renderer
07:43:01.865 T:139733311875328 DEBUG: LinuxRendererGL: Cleaning up GL resources
07:43:01.865 T:139733311875328 NOTICE: CVideoPlayer::CloseFile()
07:43:01.866 T:139733311875328 NOTICE: VideoPlayer: waiting for threads to exit
07:43:01.866 T:139733311875328 NOTICE: VideoPlayer: finished waiting
07:43:01.866 T:139730184304384 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - rebuilding state
07:43:01.866 T:139733311875328 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
07:43:01.866 T:139733311875328 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
07:43:01.866 T:139733311875328 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
07:43:01.867 T:139730184304384 INFO: AddOnLog: Tvheadend HTSP Client: pvr.hts - async updates requested
07:43:01.867 T:139730184304384 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - registered
07:43:01.867 T:139730184304384 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - connection state change (2 -> 5)
07:43:01.867 T:139730184304384 DEBUG: PVR - PVRConnectionStateChange - state for connection '<server ip>:<server port>' on client 'Tvheadend HTSP Client' changed from '2' to '5'Pertinent Server Log:
Code:
Jan 30 07:42:12 <server name> tvheadend[8285]: dvr: delete entry bbae2dcb22e208240c85bd7eb93f6d37 "<tv show>" on "<tv channel>" s
Jan 30 07:42:27 <server name> tvheadend[8285]: htsp: ::ffff:<client ip> [ kodi | Kodi Media Center ]: Disconnected
Jan 30 07:42:27 <server name> tvheadend[8285]: htsp: ::ffff:<client ip> [ kodi | Kodi Media Center ]: Write error -- Broken pipe
Jan 30 07:42:27 <server name> tvheadend[8285]: htsp: Got connection from ::ffff:<sanitized>
Jan 30 07:42:27 <server name> tvheadend[8285]: htsp: ::ffff:<client ip>: Welcomed client software: Kodi Media Center (HTSPv25)
Jan 30 07:42:27 <server name> tvheadend[8285]: htsp: ::ffff:<client ip> [ Kodi Media Center ]: Disconnected
Jan 30 07:42:31 <server name> tvheadend[8285]: htsp: Got connection from ::ffff:<sanitized>
Jan 30 07:42:31 <server name> tvheadend[8285]: htsp: ::ffff:<client ip>: Welcomed client software: Kodi Media Center (HTSPv25)
Jan 30 07:42:31 <server name> tvheadend[8285]: htsp: ::ffff:<client ip> [ Kodi Media Center ]: Identified as user '<username>'
Jan 30 07:42:31 <server name> tvheadend[8285]: htsp: ::ffff:<client ip> [ kodi | Kodi Media Center ]: Privileges updated