Login at Kodi Home

jwbyers · 2015-03-18, 18:26

Hi - I just set up a new PC with Windows 8.1 running Kodi 14.2 RC and the pvr.wmc (0.3.112) addon for PVR support and MB addon (0.9.823) for my mediabrowser collection. I have another PC with Windows 7 and a Ceton Infinitv 4 set up on WMC with ServerWMC 1.0.0.56 build: 1190 running along with a MB server version 3.0.5518.7.

I'm having a problem accessing live tv channels as well as my PVR recordings from the new PC. On the ServerWMC, I have the network path for recorded tv set up properly and have the "Use Credentials on smb:// paths" checked along with the user name "TV" with no password (my Windows 7 user login). When I ran Kodi on the new PC this morning I was able to watch my MB collection with direct play and my Live TV and recorded TV was playing fine (although it was using transcoding for some reason, but it worked fine). Now I exited Kodi and re-entered it and I can't play anything, Live TV, DVR, MB files. I can see everything but when I hit play it just shows the spinner for 30 seconds and stops. I looked in the logs and it looks like it's using "USERNAME" for my smb credentials - here is the log of when I tried to play a recorded tv show:

Code:
29:50 T:3440   DEBUG: CGUIMediaWindow::GetDirectory (pvr://recordings/Sesame Street (10)/)

29:50 T:3440   DEBUG:   ParentPath = [pvr://recordings/]

29:50 T:4112   DEBUG: CPVRDirectory::GetDirectory(pvr://recordings/Sesame Street (10))

29:50 T:2244  NOTICE: Thread BackgroundLoader start, auto delete: false

29:50 T:1264   DEBUG: CThumbExtractor::DoWork - trying to extract filestream details from video file smb://USERNAME@TVPC/Recorded TV/Sesame Street--2014_10_06_06_58_00.wtv

29:50 T:2244   DEBUG: Thread BackgroundLoader 2244 terminating

29:51 T:1264   DEBUG: CDVDDemuxFFmpeg::Open - probing detected format [wtv]

29:52 T:3440   DEBUG: Keyboard: scancode: 0x50, sym: 0x0112, unicode: 0x0000, modifier: 0x0

29:52 T:3440   DEBUG: CApplication::OnKey: down (0xf081) pressed, action is Down

29:52 T:4112    INFO: XCURL::DllLibCurlGlobal::easy_aquire - Created session to http://images.windowsmedia.com

29:52 T:4112   DEBUG: CCurlFile::GetMimeType - http://images.windowsmedia.com/tvcards/org/1481_19.jpg -> image/jpeg

29:52 T:4112   DEBUG: CurlFile::Open(0EB6C720) http://images.windowsmedia.com/tvcards/org/1481_19.jpg

29:52 T:4112   DEBUG: Caching image 'http://images.windowsmedia.com/tvcards/org/1481_19.jpg' to 'd/d29332ef.jpg':

29:52 T:4112   DEBUG: cached image 'special://masterprofile/Thumbnails/d/d29332ef.jpg' size 267x200

29:53 T:1508   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

29:53 T:1508   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetChannelCount"

29:53 T:1508   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

29:53 T:1508   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetTimerCount"

29:53 T:1508   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

29:53 T:1508   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetRecordingsAmount"

29:54 T:3440   DEBUG: Keyboard: scancode: 0x1c, sym: 0x000d, unicode: 0x000d, modifier: 0x0

29:54 T:3440   DEBUG: CApplication::OnKey: return (0xf00d) pressed, action is Select

29:54 T:3440   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

29:54 T:3440   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetResumePosition|10977524286925364"

29:55 T:3440   DEBUG: CGUIMediaWindow::OnPlayMedia smb://USERNAME@TVPC/Recorded TV/Sesame Street-Oscar's Tuxedo-2015_03_18_06_58_00.wtv

29:55 T:3440   DEBUG: Loading settings for smb://TV:@TVPC/Recorded TV/Sesame Street-Oscar's Tuxedo-2015_03_18_06_58_00.wtv

29:55 T:3440   DEBUG: CPlayerCoreFactory::GetPlayers(smb://USERNAME@TVPC/Recorded TV/Sesame Street-Oscar's Tuxedo-2015_03_18_06_58_00.wtv)

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv

29:55 T:3440   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio

29:55 T:3440   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players

29:55 T:3440   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)

29:55 T:3440   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0

29:55 T:3440   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1

29:55 T:3440   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)

29:55 T:3440   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players

29:55 T:3440  NOTICE: DVDPlayer: Opening: smb://USERNAME@TVPC/Recorded TV/Sesame Street-Oscar's Tuxedo-2015_03_18_06_58_00.wtv

29:55 T:3440 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED

29:55 T:3440   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec

29:55 T:3012  NOTICE: Thread DVDPlayer start, auto delete: false

29:55 T:3012  NOTICE: Creating InputStream

29:55 T:3012   DEBUG: CUtil::ScanForExternalSubtitles: Searching for subtitles...

29:55 T:3440   DEBUG: ------ Window Init (DialogBusy.xml) ------

29:59 T:3012   DEBUG: CUtil::ScanForExternalSubtitles: END (total time: 3970 ms)

29:59 T:3012  NOTICE: Creating Demuxer

30:02 T:3012   DEBUG: CDVDDemuxFFmpeg::Open - probing detected format [wtv]

30:03 T:1508   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

30:03 T:1508   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetChannelCount"

30:03 T:1508   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

30:03 T:1508   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetTimerCount"

30:03 T:1508   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

30:03 T:1508   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetRecordingsAmount"

30:05 T:3440    INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http://192.168.10.250 (easy=132D5CE0, multi=0072A0E0)

At the time the ServerWMC log shows:

Code:
Connected client: 'LivingRoomPC', IP: 192.168.10.18, isRemote: True

2015/03/18 11:28:38.031  > addon/plugin Version: 0.3.111, Client OS: windows(6.3)

2015/03/18 11:28:38.031  > path to Rec Folder: 'smb://TV:@TVPC/Recorded TV'

2015/03/18 11:28:38.047 Received client request: LivingRoomPC|192.168.10.18|GetServerVersion|2015-03-18 15:28:38|192.168.10.250

2015/03/18 11:28:38.047 Finished request GetServerVersion in 0.00s

2015/03/18 11:28:39.738 Received client request: LivingRoomPC|192.168.10.18|GetChannels|True

2015/03/18 11:28:39.738 Finished request GetChannels in 0.00s

2015/03/18 11:28:39.744 Received client request: LivingRoomPC|192.168.10.18|GetChannelGroups|True

2015/03/18 11:28:39.755 Finished request GetChannelGroups in 0.01s

2015/03/18 11:28:39.792 Received client request: LivingRoomPC|192.168.10.18|GetChannels|False

2015/03/18 11:28:40.464 Finished request GetChannels in 0.67s

2015/03/18 11:28:40.714 Received client request: LivingRoomPC|192.168.10.18|GetChannelGroups|False

2015/03/18 11:28:40.718 Finished request GetChannelGroups in 0.01s

2015/03/18 11:28:40.729 Received client request: LivingRoomPC|192.168.10.18|GetChannelGroupMembers|False|Most Viewed

2015/03/18 11:28:40.738 Finished request GetChannelGroupMembers in 0.01s

2015/03/18 11:28:40.969 Received client request: LivingRoomPC|192.168.10.18|GetTimers

2015/03/18 11:28:41.802 Finished request GetTimers in 0.83s

2015/03/18 11:28:41.823 Received client request: LivingRoomPC|192.168.10.18|GetRecordings

2015/03/18 11:28:42.364 GetRecordings> Total process time 0.54 sec

2015/03/18 11:28:42.364 GetRecordings> Completed for client LivingRoomPC,  results:

2015/03/18 11:28:42.364 GetRecordings>    Transfered: RecTV folder files: 172,  watched files: 0

2015/03/18 11:28:42.364 GetRecordings>    Ignored:  local files: 0, temp files: 0

2015/03/18 11:28:42.364 GetRecordings>    Weird files:  no program: 0, no channel: 6, orphaned: 0

2015/03/18 11:28:42.364 GetRecordings>    Misc:  drm: 0, icons: 125, instantRec: 0, metaDataReads: 0, urls: 0

2015/03/18 11:28:42.364 GetRecordings>    Invalid StartTimes: 0

2015/03/18 11:28:42.364 Finished request GetRecordings in 0.54s

2015/03/18 11:28:48.692 Received client request: LivingRoomPC|192.168.10.18|GetTimers

2015/03/18 11:28:49.469 Finished request GetTimers in 0.78s

2015/03/18 11:28:54.423 Received client request: TVPC|127.0.0.1|GetTimers

2015/03/18 11:28:55.103 Finished request GetTimers in 0.68s

2015/03/18 11:28:55.109 Received client request: TVPC|127.0.0.1|GetRecordings

2015/03/18 11:28:55.567 Finished request GetRecordings in 0.46s

2015/03/18 11:29:54.261 Received client request: LivingRoomPC|192.168.10.18|GetResumePosition|10977524286925364

2015/03/18 11:29:54.587 Finished request GetResumePosition in 0.33s

I checked the config on both pvr.wmc and MB on the new PC and everything is set up correctly with the right credentials and IPs.

About a half hour later I tried it again and now I'm getting something totally different, when I click into a Recorded TV show, it spins for 30 seconds, then starts playing the file but doesn't show either Direct Play or Transcoding and it plays for <1 second then stops to buffer for 10 seconds then plays <1 second then buffers again, rinse repeat. Here is the log for that one:

Code:
12:28 T:76   DEBUG: CAESinkDirectSound::Deinitialize: Cleaning up

12:28 T:4136   DEBUG: CApplication::ProcessMouse: trying mouse action leftclick

12:28 T:4136   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

12:28 T:4136   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetResumePosition|10977524237851914"

12:28 T:4136   DEBUG: CGUIMediaWindow::OnPlayMedia smb://USERNAME@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv

12:28 T:4136   DEBUG: Loading settings for smb://TV:@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv

12:28 T:4136   DEBUG: CPlayerCoreFactory::GetPlayers(smb://USERNAME@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv)

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv

12:28 T:4136   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio

12:28 T:4136   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players

12:28 T:4136   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)

12:28 T:4136   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0

12:28 T:4136   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1

12:28 T:4136   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)

12:28 T:4136   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players

12:28 T:4136  NOTICE: DVDPlayer: Opening: smb://USERNAME@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv

12:28 T:4136 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED

12:28 T:4136   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec

12:28 T:1748  NOTICE: Thread DVDPlayer start, auto delete: false

12:28 T:1748  NOTICE: Creating InputStream

12:28 T:1748   DEBUG: CUtil::ScanForExternalSubtitles: Searching for subtitles...

12:29 T:4136   DEBUG: ------ Window Init (DialogBusy.xml) ------

12:29 T:4136   DEBUG: ------ Window Init (Pointer.xml) ------

12:30 T:1748   DEBUG: CUtil::ScanForExternalSubtitles: END (total time: 1479 ms)

12:30 T:1748  NOTICE: Creating Demuxer

12:31 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

12:31 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetChannelCount"

12:31 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

12:31 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetTimerCount"

12:31 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

12:31 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetRecordingsAmount"

12:32 T:1748   DEBUG: CDVDDemuxFFmpeg::Open - probing detected format [wtv]

12:33 T:4136   DEBUG: ------ Window Deinit (Pointer.xml) ------

12:36 T:4136    INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http://www.google.com (easy=0480EB98, multi=00000000)

12:41 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

12:41 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetChannelCount"

12:41 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

12:41 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetTimerCount"

12:41 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

12:41 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetRecordingsAmount"

12:41 T:4136   DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib.dll)

12:41 T:4136    INFO: XCURL::DllLibCurlGlobal::CheckIdle - Closing session to http://192.168.10.250 (easy=04750048, multi=009DA058)

12:45 T:2216   DEBUG: Thread JobWorker 2216 terminating (autodelete)

12:45 T:4940   DEBUG: Thread JobWorker 4940 terminating (autodelete)

12:50 T:4136   DEBUG: ------ Window Init (Pointer.xml) ------

12:51 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

12:51 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetChannelCount"

12:51 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

12:51 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetTimerCount"

12:51 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

12:51 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetRecordingsAmount"

12:57 T:4136   DEBUG: ------ Window Deinit (Pointer.xml) ------

13:01 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

13:01 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetChannelCount"

13:01 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

13:01 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetTimerCount"

13:01 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

13:01 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetRecordingsAmount"

13:11 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

13:11 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetChannelCount"

13:11 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

13:11 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetTimerCount"

13:11 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetServiceStatus|0.3.111|windows(6.3)"

13:11 T:4744   DEBUG: AddOnLog: PVR WMC Client: Socket::GetVector> Send request "GetRecordingsAmount"

13:11 T:1748   DEBUG: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting

13:11 T:1748   DEBUG: ffmpeg[6D4]: [wtv] parser not found for codec eia_608, packets or times may be invalid.

13:11 T:1748   DEBUG: ffmpeg[6D4]: [wtv] Could not find codec parameters for stream 3 (Video: mpeg2video, 704x480): unspecified pixel format

13:11 T:1748   DEBUG: ffmpeg[6D4]: [wtv] Consider increasing the value for the 'analyzeduration' and 'probesize' options

13:11 T:1748   DEBUG: ffmpeg[6D4]: [wtv] Could not find codec parameters for stream 5 (Video: mjpeg): unspecified size

13:11 T:1748   DEBUG: ffmpeg[6D4]: [wtv] Consider increasing the value for the 'analyzeduration' and 'probesize' options

13:11 T:1748   DEBUG: CDVDDemuxFFmpeg::Open - av_find_stream_info finished

13:11 T:1748    INFO: ffmpeg[6D4]: Input #0, wtv, from 'smb://TV@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv':

13:11 T:1748    INFO: ffmpeg[6D4]:   Metadata:

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaClassPrimaryID: db9830bd-3ab3-4fab-8a371a995f7ff74

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaClassSecondaryID: ba7f258a-62f7-47a9-b21f4651c42a000

13:11 T:1748    INFO: ffmpeg[6D4]:     Title           : Sesame Street

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/SubTitle     : Ernie's Dance Video

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/SubTitleDescription: Ernie changes his plan about making a dance video.

13:11 T:1748    INFO: ffmpeg[6D4]:     genre           : Kids;General;Educational;Series;Family/Children;Children

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/OriginalReleaseTime: 0

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaCredits : Fran Brill/Leslie Carrara-Rudolph/Ryan Dillon/Eric Jacobson/Joseph Mazzarino/Carmen Osbahr/Martin Robinson/David Rudman/Caroll Spinney/Matt Vogel;;;

13:11 T:1748    INFO: ffmpeg[6D4]:     service_provider: WNETDT

13:11 T:1748    INFO: ffmpeg[6D4]:     service_name    : WNETDT (WNET-DT)

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaNetworkAffiliation: PBS Affiliate

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaOriginalChannel: 513

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaOriginalChannelSubNumber: 0

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaOriginalBroadcastDateTime: 2014-10-10T04:00:00Z

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaOriginalRunTime: 39558969958

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaIsStereo: true

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaIsRepeat: false

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaIsLive  : false

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaIsTape  : false

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaIsDelay : false

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaIsSubtitled: false

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaIsMovie : false

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaIsPremiere: false

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaIsFinale: false

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaIsSAP   : false

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaIsSport : false

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/ParentalRating: TV-Y

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/Provider     : MediaCenterDefault

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/VideoClosedCaptioning: true

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVEncodeTime: 2014-10-13 10:58:04

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVSeriesUID: !MCSeries!48125661

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVServiceID: !MCService!28456564

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVProgramID: !MCProgram!301629641

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVRequestID: 0

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVScheduleItemID: 0

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVQuality  : 0

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVOriginalSoftPrePadding: 420

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVOriginalSoftPostPadding: 240

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVHardPrePadding: 4294966996

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVHardPostPadding: 0

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVATSCContent: true

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVDTVContent: true

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVHDContent: true

13:11 T:1748    INFO: ffmpeg[6D4]:     Duration        : 39573033448

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVEndTime  : 2014-10-13 12:04:01

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVBitrate  : 8.869684

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVKeepUntil: 0

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVActualSoftPrePadding: 415

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVActualSoftPostPadding: 241

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVContentProtected: false

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVContentProtectedPercent: 0

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVExpirationSpan: 9223372036854775807

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVInBandRatingSystem: 255

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVInBandRatingLevel: 255

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVInBandRatingAttributes: 0

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/WMRVWatched  : false

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaThumbWidth: 352

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaThumbHeight: 198

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaThumbStride: 1056

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaThumbRet: 0

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaThumbRatingSystem: 9

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaThumbRatingLevel: 2

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaThumbRatingAttributes: 0

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaThumbAspectRatioX: 16

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaThumbAspectRatioY: 9

13:11 T:1748    INFO: ffmpeg[6D4]:     WM/MediaThumbTimeStamp: 4643610976550397681

13:11 T:1748    INFO: ffmpeg[6D4]:   Duration: N/A, bitrate: N/A

13:11 T:1748    INFO: ffmpeg[6D4]:     Stream #0:0[0x5a](eng): Audio: ac3, 48000 Hz, 2 channels, fltp

13:11 T:1748    INFO: ffmpeg[6D4]:     Stream #0:1[0x5b](spa): Audio: ac3, 48000 Hz, 2 channels, fltp

13:11 T:1748    INFO: ffmpeg[6D4]:     Stream #0:2[0x5c](fre): Audio: ac3, 48000 Hz, 2 channels, fltp

13:11 T:1748    INFO: ffmpeg[6D4]:     Stream #0:3[0x5d]: Video: mpeg2video, 704x480, 10000k tbr, 10000k tbn, 10000k tbc

13:11 T:1748    INFO: ffmpeg[6D4]:     Stream #0:4[0x5e]: Subtitle: eia_608

13:11 T:1748    INFO: ffmpeg[6D4]:     Stream #0:5[0xffffffff]: Video: mjpeg, 90k tbr, 90k tbn, 90k tbc

13:11 T:1748    INFO: ffmpeg[6D4]:     Metadata:

13:11 T:1748    INFO: ffmpeg[6D4]:       title           : TV Thumbnail

13:11 T:1748   DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0

13:11 T:1748   DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1

13:11 T:1748   DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2

13:11 T:1748   DEBUG: CDVDDemuxFFmpeg::AddStream(3, ...) -> 3

13:11 T:1748   DEBUG: CDVDDemuxFFmpeg::AddStream(4, ...) -> 4

13:11 T:1748   DEBUG: CDVDDemuxFFmpeg::AddStream(5, ...) -> 5

13:11 T:1748  NOTICE: Opening stream: 3 source: 256

13:11 T:1748  NOTICE: Creating video codec with codec id: 2

13:11 T:1748   DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no DXVA:yes iMXVPU:no

13:11 T:1748   DEBUG: FactoryCodec - Video:  - Opening

13:11 T:1748  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video

13:11 T:1748   DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened

13:11 T:1748   ERROR: CDVDPlayerVideo::OpenStream - Invalid framerate 10000000, using forced 25fps and just trust timestamps

13:11 T:1748  NOTICE: Creating video thread

13:11 T:1748  NOTICE: Opening stream: 0 source: 256

13:11 T:2568  NOTICE: Thread DVDPlayerVideo start, auto delete: false

13:11 T:1748  NOTICE: Finding audio codec for: 86019

13:11 T:2568  NOTICE: running thread: video_thread

13:11 T:1748   DEBUG: FactoryCodec - Audio: passthrough - Opening

13:11 T:1748   DEBUG: FactoryCodec - Audio: passthrough - Failed

13:11 T:1748   DEBUG: FactoryCodec - Audio: FFmpeg - Opening

13:11 T:1748   DEBUG: FactoryCodec - Audio: FFmpeg - Opened

13:11 T:1748  NOTICE: Creating audio thread

13:11 T:2568   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE

13:11 T:1748  NOTICE: Opening stream: 4 source: 256

13:11 T:2444  NOTICE: Thread DVDPlayerAudio start, auto delete: false

13:11 T:1748   DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening

13:11 T:2444  NOTICE: running thread: CDVDPlayerAudio::Process()

13:11 T:1748   DEBUG: CDVDOverlayCodecFFmpeg::Open - Unable to find codec 1664495672

13:11 T:2444   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE

13:11 T:1748   DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Failed

13:11 T:1748   ERROR: CDVDPlayerSubtitle::OpenStream - Unable to init overlay codec

13:11 T:1748 WARNING: CDVDPlayer::OpenStream - Unsupported stream 4. Stream disabled.

13:11 T:1748   DEBUG: CEdl::ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://TV:@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv

13:11 T:1748   DEBUG: CApplication::OnPlayBackStarted: play state was 1, starting 1

13:11 T:1748   DEBUG: CDVDPlayer::SetCaching - caching state 3

13:11 T:2444   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(3423571.700000, 1)

13:11 T:1748   DEBUG: ffmpeg[6D4]: [wtv] parser not found for codec eia_608, packets or times may be invalid.

13:11 T:2444  NOTICE: Creating audio stream (codec id: 86019, channels: 2, sample rate: 48000, no pass-through)

13:11 T:2568    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback

13:11 T:2444   DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback

13:11 T:76    INFO: CActiveAESink::OpenSink - initialize sink

13:11 T:1748   DEBUG: ffmpeg[6D4]: [wtv] unsupported chunk:405c0600805c0600c05c0600005d0600

13:11 T:2568   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(4338127.200000, 0)

13:11 T:76   DEBUG: CActiveAESink::OpenSink - trying to open device DIRECTSOUND:{E34F83E2-E1E0-4CCB-A97E-AB6B7B8BE61A}

13:12 T:2568   ERROR: ffmpeg[A08]: [mpeg2video] Invalid frame dimensions 0x0.

13:12 T:2568   ERROR: CDVDVideoCodecFFmpeg::Decode - avcodec_decode_video returned failure

13:12 T:4136   DEBUG: CApplication::PlayFile: OpenFile succeed, play state 2

13:12 T:2568   DEBUG: CDVDPlayerVideo - video decoder returned error

13:12 T:4136   DEBUG: CApplication::OnPlayBackStarted: play state was 2, starting 0

13:12 T:76   DEBUG: CAESinkDirectSound::Initialize: Using Window handle: 197692

13:12 T:2568   ERROR: ffmpeg[A08]: [mpeg2video] Invalid frame dimensions 0x0.

13:12 T:2568   ERROR: CDVDVideoCodecFFmpeg::Decode - avcodec_decode_video returned failure

13:12 T:2568   DEBUG: CDVDPlayerVideo - video decoder returned error

13:12 T:2568   ERROR: ffmpeg[A08]: [mpeg2video] Invalid frame dimensions 0x0.

13:12 T:76   DEBUG: CAESinkDirectSound::Initialize: secondary buffer created

13:12 T:2568   ERROR: CDVDVideoCodecFFmpeg::Decode - avcodec_decode_video returned failure

13:12 T:76   DEBUG: CAESinkDirectSound::Initialize: Initializing DirectSound with the following parameters:

13:12 T:76   DEBUG:   Audio Device    : SAMSUNG (2- High Definition Audio Device)

13:12 T:2568   DEBUG: CDVDPlayerVideo - video decoder returned error

13:12 T:76   DEBUG:   Sample Rate     : 48000

13:12 T:2568   ERROR: ffmpeg[A08]: [mpeg2video] Invalid frame dimensions 0x0.

13:12 T:76   DEBUG:   Sample Format   : AE_FMT_FLOAT

13:12 T:2568   ERROR: CDVDVideoCodecFFmpeg::Decode - avcodec_decode_video returned failure

13:12 T:2568   DEBUG: CDVDPlayerVideo - video decoder returned error

13:12 T:76   DEBUG:   Bits Per Sample : 32

13:12 T:2568   ERROR: ffmpeg[A08]: [mpeg2video] Invalid frame dimensions 0x0.

13:12 T:76   DEBUG:   Valid Bits/Samp : 32

13:12 T:76   DEBUG:   Channel Count   : 2

13:12 T:76   DEBUG:   Block Align     : 8

13:12 T:76   DEBUG:   Avg. Bytes Sec  : 384000

13:12 T:76   DEBUG:   Samples/Block   : 32

13:12 T:76   DEBUG:   Format cBSize   : 22

13:12 T:2568   ERROR: CDVDVideoCodecFFmpeg::Decode - avcodec_decode_video returned failure

13:12 T:76   DEBUG:   Channel Layout  : FL,FR

13:12 T:2568   DEBUG: CDVDPlayerVideo - video decoder returned error

13:12 T:76   DEBUG:   Channel Mask    : 3

13:12 T:2568 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available

13:12 T:76   DEBUG:   Frames          : 720

13:12 T:4136   DEBUG: CGUIInfoManager::SetCurrentMovie(smb://USERNAME@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv)

13:12 T:76   DEBUG:   Frame Samples   : 1440

13:12 T:76   DEBUG:   Frame Size      : 8

13:12 T:76   DEBUG: CActiveAESink::OpenSink - DIRECTSOUND Initialized:

13:12 T:76   DEBUG:   Output Device : HDMI - SAMSUNG (2- High Definition Audio Device)

13:12 T:76   DEBUG:   Sample Rate   : 48000

13:12 T:76   DEBUG:   Sample Format : AE_FMT_FLOAT

13:12 T:76   DEBUG:   Channel Count : 2

13:12 T:4136   DEBUG: CVideoDatabase::GetMovieId (smb://USERNAME@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv), query = select idMovie from movie where idFile=28

13:12 T:76   DEBUG:   Channel Layout: FL,FR

13:12 T:4136   DEBUG: CVideoDatabase::GetEpisodeId (smb://USERNAME@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv), query = select idEpisode from episode where idFile=28

13:12 T:76   DEBUG:   Frames        : 720

13:12 T:4136   DEBUG: CVideoDatabase::GetMusicVideoId (smb://USERNAME@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv), query = select idMVideo from musicvideo where idFile=28

13:12 T:76   DEBUG:   Frame Samples : 1440

13:12 T:76   DEBUG:   Frame Size    : 8

13:12 T:3024   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted

13:12 T:4136   DEBUG: Previous line repeats 1 times.

13:12 T:4136   DEBUG: CVideoDatabase::GetMovieId (smb://USERNAME@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv), query = select idMovie from movie where idFile=28

13:12 T:4136   DEBUG: CVideoDatabase::GetEpisodeId (smb://USERNAME@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv), query = select idEpisode from episode where idFile=28

13:12 T:4136   DEBUG: CVideoDatabase::GetMusicVideoId (smb://USERNAME@TVPC/Recorded TV/Sesame Street-Ernie's Dance Video-2014_10_13_06_58_00.wtv), query = select idMVideo from musicvideo where idFile=28

13:12 T:4136   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc

13:12 T:4136   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay

13:12 T:76   DEBUG: CAESinkDirectSound::CheckPlayStatus: Resuming Playback

13:12 T:76 WARNING: CWin32DirectSound::GetSpace - buffer underrun - W:7952, P:272, O:5760.

I'm sorry for the code dumps but I'm really confused as to what is happening here so hopefully the logs help to diagnose the issue. No other changes were made to the PCs other than closing and reopening Kodi. Also tried disabling and re-enabling pvr.wmc and MB add-ons but no change.

I really appreciate the help!!!

krustyreturns · 2015-03-18, 23:58

You don't need credentials when both machines are windows so, for now, just turn that off. Then restart serverwmc and then kodi, and try to play a live stream. If it fails or buffers excessively again, post the whole serverwmc log to pastebin.com and post a link to it here.

As to why its using 'USERNAME' I am not sure, but let's worry about that later since its not necessary right now.

jwbyers · 2015-03-19, 14:10

Thank you for the suggestion krustyreturns! I unchecked the box, cleared the user credentials, saved and restarted servermwc and when I reconnected everything streamed quickly from the new PC. I'm going to try to get another box set up with openELEC and see if I'm able to get them working at the same time. Thank you again!!!

krustyreturns · 2015-03-20, 00:37

Good. You will need to get the credentials turned back on for openelec since it will require them.