[rykr]

We are getting an error tuning some channels with ServerWMC. I've copied the relevants bits from the log below.

2016/02/24 12:18:26.000 OpenLiveStream> -----------------start------------------------
2016/02/24 12:18:26.001 OpenLiveStream> client: Kodi^SERENITY requesting live stream on channel TVLAND/59
2016/02/24 12:18:26.002 LiveRemuxStream> live-tv started
2016/02/24 12:18:26.002 SetChannel> Channel: 59 : TVLAND - 3638828 = { (59 : TVLAND - 2CAEDE4) + [ 59 : TVLAND - 3637670] }
2016/02/24 12:18:26.002 SetChannel> Tuners available for this channel: 7
2016/02/24 12:18:26.002 SetChannel> Tuner DecoyMethod: OnDemand
2016/02/24 12:18:26.002 SetChannel> ---
2016/02/24 12:18:26.005 SetChannel> Attempt: 0, Tuner: HDHomeRun Prime Tuner 13181F93-2 / fa306953-af46-45d5-b79f-f53e2fbbe13e
2016/02/24 12:18:26.005 SetChannel> > Encrypted: False
2016/02/24 12:18:26.007 SetChannel> > RecorderInfo found: True
2016/02/24 12:18:26.008 SetChannel> > Recorder Content Protection: PROT_COPY_FREE
2016/02/24 12:18:26.193 SetChannel> > Status: Available, client:
2016/02/24 12:18:26.193 SetChannel> *** requested number of available tuners found, stopping search ***
2016/02/24 12:18:26.193 SetChannel> ---
2016/02/24 12:18:26.193 SetChannel> available tuners found: 1 [decoys:0], requested: 1, searched: 1
2016/02/24 12:18:26.193 SetChannel> Tuner: HDHomeRun Prime Tuner 13181F93-2 will be used for the live stream
2016/02/24 12:18:26.196 SetChannel> Recorder acquired: True
2016/02/24 12:18:26.198 SetChannel> TuneRequest set
2016/02/24 12:18:26.233 LiveRemuxStream> wtv recording started in 0.23 sec
2016/02/24 12:18:26.233 LiveRemuxStream> stream output file: LiveTV_Kodi^SERENITY_Digital Cable_59_2016_02_24_12_18_26.ts
2016/02/24 12:18:26.234 LiveRemuxStream> started remux thread: 'TVLAND-59:56854568'
2016/02/24 12:18:26.248 Remux::Start> Starting descriptor scan...
2016/02/24 12:18:26.248 Remux::FindDescriptors> Scanning wtv for streams...
2016/02/24 12:18:28.414 Parse> Guid: 0 took 2.17 sec, it was attempted 1082 times
2016/02/24 12:18:28.415 Parse> Next 4 Guids: 0.00 sec, 0.00 sec, 0.00 sec, 0.00 sec,
2016/02/24 12:18:28.416 Parse> Language 'eng' for stream Id 3
2016/02/24 12:18:29.284 WaitUntilTrueOrTimeout> remux data found
2016/02/24 12:18:31.203 MaintenanceTimer> Callback started...
2016/02/24 12:18:31.215 RecordingCache_Refresh> [TimerMaintenance] Cache background refresh started.
2016/02/24 12:18:31.215 TimerCache_Refresh> [TimerMaintenance] Cache background refresh started.
2016/02/24 12:18:31.216 MaintenanceTimer> Callback completed in 0.01 sec.
2016/02/24 12:18:33.068 RecordingCache_WorkerCompleted> [TimerMaintenance] Cache background refresh completed in 1.86 sec, client: Kodi^localhost.
2016/02/24 12:18:33.068 RecordingCache_WorkerCompleted> [TimerMaintenance] Cache background refresh completed in 1.86 sec, client: Kodi^localhost(2).
2016/02/24 12:18:33.068 RecordingCache_WorkerCompleted> [TimerMaintenance] Cache background refresh completed in 1.86 sec, client: Kodi^SERENITY.
2016/02/24 12:18:33.752 TimerCache_WorkerCompleted> [TimerMaintenance] Cache background refresh completed in 2.54 sec, client: Kodi^localhost.
2016/02/24 12:18:33.752 TimerCache_WorkerCompleted> [TimerMaintenance] Cache background refresh completed in 2.54 sec, client: Kodi^localhost(2).
2016/02/24 12:18:33.752 TimerCache_WorkerCompleted> [TimerMaintenance] Cache background refresh completed in 2.54 sec, client: Kodi^SERENITY.
2016/02/24 12:18:39.293 WaitForTsFile> **** error: remux file has no data, second timout reached ****
2016/02/24 12:18:39.293 LiveRemuxStream> process start error: Remux is shutdown calling Close()
2016/02/24 12:18:39.293 Remux::Stop> caller is requesting to stop remux thread before descriptors are found
2016/02/24 12:18:39.293 Remux::Stop> stop remux requested
2016/02/24 12:18:39.303 Parse> Total Descriptor parse time: 13.05 sec
2016/02/24 12:18:39.303 Pass Type: 'Descriptor':
2016/02/24 12:18:39.303 > WtvToPesDemuxer:arse> total guid headers processed: 3,764 (Min:500 - Max:2,000)
2016/02/24 12:18:39.303 > WtvToPesDemuxer:arse> total data packets processed: 795
2016/02/24 12:18:39.304 Remux::FindDescriptors> wtv scanned (13.06 sec), Streams found:
2016/02/24 12:18:39.304 > Audio: ID:3 (eng) ac3 2.0 48000 Hz 192 kb/s
2016/02/24 12:18:39.304 > Video: ID:4 Unknown
2016/02/24 12:18:39.304 Remux::FindDescriptors> Removing 'ID:4 Unknown' for invalid video descriptor
2016/02/24 12:18:39.304 Remux::FindDescriptors> Output streams:
2016/02/24 12:18:39.304 > Audio: ID:3 (eng) ac3 2.0 48000 Hz 192 kb/s
2016/02/24 12:18:39.304 Remux::FindDescriptors> warning: no video streams to remux
2016/02/24 12:18:39.304 Remux::FindDescriptors> ended successfully.
2016/02/24 12:18:39.304 GetLinearStream> Stream used: ID:3 (eng) ac3 2.0 48000 Hz 192 kb/s
2016/02/24 12:18:39.304 Remux::Start> Starting data remux...
2016/02/24 12:18:39.341 WriteChunk> first output file write occurred
2016/02/24 12:18:41.335 CloseRemux> warning: remux job did not end smoothly
2016/02/24 12:18:41.335 LiveRemuxStream::Close> remux stopped successfully
2016/02/24 12:18:41.335 RecordToWTV::Close> isPassive is False => COM recorder will be stopped
2016/02/24 12:18:41.353 Parse> Exception: Thread was being aborted.
2016/02/24 12:18:41.355 Parse> Pass Type: Remux
2016/02/24 12:18:41.367 Parse> Guid count: 4,801 (Min:500 - Max:2,000)
2016/02/24 12:18:41.368 Parse> Header:
2016/02/24 12:18:41.368 Parse> re-throwing exception
2016/02/24 12:18:41.404 LiveRemuxStream::Close> wtv closed successfully
2016/02/24 12:18:41.452 Remux> error: System.Threading.ThreadAbortException: Thread was being aborted.
at Remuxer.demux.microsoft.WtvToPesDemuxer.parse(JobStreamI jobStm, MuxManager muxManager)
at Remuxer.muxer.MuxManager.mux(JobStreamI stream)
at Remuxer.Remux.Start(String clientName, String outFullFileName)
2016/02/24 12:18:41.455 LiveRemuxStream::Close> wtv file size: 6,029,312 (0x5C0000)
2016/02/24 12:18:41.465 Remux> requesting F:\Recorded TV\TempSWMC\LiveTV_Kodi^SERENITY_Digital Cable_59_2016_02_24_12_18_26.wtv be saved from deletion
2016/02/24 12:18:41.466 Remux> ENDED, >>>>>>>>>> Run Time: 0.04 min <<<<<<<<<<
2016/02/24 12:18:41.506 LiveRemuxStream::Close> wtv file deleted: F:\Recorded TV\TempSWMC\LiveTV_Kodi^SERENITY_Digital Cable_59_2016_02_24_12_18_26.wtv
2016/02/24 12:18:41.506 LiveRemuxStream::Close> closed in 2.21 sec
2016/02/24 12:18:41.508 OpenLiveStream> error: Remux is shutdown
2016/02/24 12:18:41.508 Finished request OpenLiveStream in 15.51s

[sbthomas]

My guess is that you have invalid sources for the channel. Go into WMC setup, tv, guide. Find and select the channel (59 - TVLAND) in the list. Click the edit sources option and make sure all the tuners are valid. You can do this by unchecking all boxes except for one, then watching the channel in WMC. Make sure only the valid tuners are selected.

[krustyreturns]

What sbthomas said. In the log snippet you posted it says

2016/02/24 12:18:26.002 SetChannel> Tuners available for this channel: 7

I'm guessing there really aren't seven working tuners for this channel. By default, swmc picks live tv tuners in the opposite order than wmc, so if there are bogus tuners sources swmc shows it where wmc doesn't.