Recording not stopping (4 Viewers)

[HTPCSourcer]

AW: Re: AW: Re: Recording not stopping

Also, there are errors that comskip is not launching even though I have disabled the plugin.

Might be a silly question, but did you restart the TVServer service after disabling Comskip?

 

[globaldonkey]

good point. maybe not such a silly question I think I did a whole restart of the system, but not 100% sure, so I will do that now and see if this changes anything.

EDIT: Yep looks like I forgot the restart. Comskip now properly disabled and definitely not operating as evidenced by no comskip files being created. So based on that can't rule it out as not part of the problem yet.

 

[jameson_uk]

0003099: Recordings Not Stopping - MediaPortal Bugtracker

 

[globaldonkey]

Just by way of an update (because I saw the mantis bug update). I have had comskip disabled since I last posted here over 4 weeks ago, and I have not had a re-occurrence of a recording not stopping. So I think the comskip launching problem on XP may be the culprit.

 

[RobNorthcott]

FWIW, I've had recordings fail to end a couple of times and I'm not using comskip. I can't be any more help I'm afraid - unless it's still in the logs somewhere...

Might be a different issue, but just thought I'd mention it. If I can find the relevant log (or even remember exactly when the problem recordings were!) I'll post them.

Rob

 

[Jay_UK]

Hi guys,

Not sure if its relevant or related, but I just experienced a scheduled recording not stopping.

I run comskip too

The TV Server log is full of:

2010-11-29 20:10:10.798991 [scheduler thread(21)]: card: StopRecording card=2, user=scheduler22
2010-11-29 20:10:10.801991 [scheduler thread(21)]: card: StopRecording for card:2
2010-11-29 20:10:10.801991 [scheduler thread(21)]: card: StopRecording subchannel null, skipping
2010-11-29 20:10:10.802991 [scheduler thread(21)]: Scheduler: stop record did not succeed (trying again in 1 min.) Watch 29/11/2010 19:43:08-29/11/2010 20:00:00 New Masterchef Australia
2010-11-29 20:10:25.830670 [scheduler thread(21)]: Scheduler: stop record Watch 29/11/2010 19:43:08-29/11/2010 20:00:00 New Masterchef Australia

Thanks,

J.

 

[arneman]

AW: Recording not stopping

Same problem here, runnig MP 1.2.0 Alpha with comskip 1.4.6.0

MediaPortal Version: 1.2.0 ALPHA
MediaPortal Skin: Blue3Wide
Windows Version: XP SP3
CPU Type: AMD X2 5600+
HDD: Samsung HD753LJ
Memory: 2GB
Motherboard: Gigabyte GA-M56S-S3
Video Card: Geforce 8600GT
Video Card Driver: GeForce 185.20
Sound Card: Onboard
Sound Card AC3:
Sound Card Driver:
1. TV Card: PCTV Sat Pro PCI
1. TV Card Type: DVB-S
1. TV Card Driver: 09/12/2006,4.1.1.0
2. TV Card: PCTV Sat Pro PCI
2. TV Card Type: DVB-S
2. TV Card Driver: 09/12/2006,4.1.1.0
3. TV Card: Terratec Cinergy PCI S2 HD
3. TV Card Type: DVB-S2
3. TV Card Driver: 1.1.1.301
4. TV Card:
4. TV Card Type:
4. TV Card Driver:
MPEG2 Video Codec: PowerDVD 10x
MPEG2 Audio Codec: ffdshow
h.264 Video Codec: PowerDVD 10x
Satelite/CableTV Provider: Astra 19.2
HTPC Case:
Cooling:
Power Supply:
Remote: Keysonic Wireless Mini Keyboard, IgorPlug USB (with Eventghost)
TV: Toshiba 42Z3030D
TV - HTPC Connection: DVI to HDMI, Audio: optical SPDIF

ISSUE:

In the last weeks it happens quite often that recordings did not stop. As far as i remember, only recordings on channel "Vox", but different schedules.

These entries repeating all the time, until i manually restart tv-server or system:

2010-12-12 15:08:09.875000 [scheduler thread(16)]: Scheduler: recList: count: 2 add scheduleid: 511 card: Pinnacle Stargate Tuner
2010-12-12 15:30:12.359375 [scheduler thread(16)]: Scheduler: stop record VOX 12.12.2010 12:55:41-12.12.2010 15:15:00 Goodbye Deutschland! Die Auswanderer
2010-12-12 15:30:12.359375 [scheduler thread(16)]: card: StopRecording card=2, user=scheduler526
2010-12-12 15:30:12.359375 [scheduler thread(16)]: card: StopRecording for card:2
2010-12-12 15:30:12.359375 [scheduler thread(16)]: card: StopRecording subchannel null, skipping
2010-12-12 15:30:12.359375 [scheduler thread(16)]: Scheduler: stop record did not succeed (trying again in 1 min.) VOX 12.12.2010 12:55:41-12.12.2010 15:15:00 Goodbye Deutschland! Die Auswanderer
2010-12-12 15:30:27.390625 [scheduler thread(16)]: Scheduler: stop record VOX 12.12.2010 12:55:41-12.12.2010 15:15:00 Goodbye Deutschland! Die Auswanderer
2010-12-12 15:30:27.390625 [scheduler thread(16)]: card: StopRecording card=2, user=scheduler526
2010-12-12 15:30:27.390625 [scheduler thread(16)]: card: StopRecording for card:2
2010-12-12 15:30:27.406250 [scheduler thread(16)]: card: StopRecording subchannel null, skipping
2010-12-12 15:30:27.406250 [scheduler thread(16)]: Scheduler: stop record did not succeed (trying again in 1 min.) VOX 12.12.2010 12:55:41-12.12.2010 15:15:00 Goodbye Deutschland! Die Auswanderer
2010-12-12 15:30:42.421875 [scheduler thread(16)]: Scheduler: stop record VOX 12.12.2010 12:55:41-12.12.2010 15:15:00 Goodbye Deutschland! Die Auswanderer
2010-12-12 15:30:42.437500 [scheduler thread(16)]: card: StopRecording card=2, user=scheduler526
2010-12-12 15:30:42.437500 [scheduler thread(16)]: card: StopRecording for card:2
2010-12-12 15:30:42.437500 [scheduler thread(16)]: card: StopRecording subchannel null, skipping
2010-12-12 15:30:42.437500 [scheduler thread(16)]: Scheduler: stop record did not succeed (trying again in 1 min.) VOX 12.12.2010 12:55:41-12.12.2010 15:15:00 Goodbye Deutschland! Die Auswanderer
2010-12-12 15:30:57.468750 [scheduler thread(16)]: Scheduler: stop record VOX 12.12.2010 12:55:41-12.12.2010 15:15:00 Goodbye Deutschland! Die Auswanderer
2010-12-12 15:30:57.468750 [scheduler thread(16)]: card: StopRecording card=2, user=scheduler526
2010-12-12 15:30:57.468750 [scheduler thread(16)]: card: StopRecording for card:2
2010-12-12 15:30:57.468750 [scheduler thread(16)]: card: StopRecording subchannel null, skipping
2010-12-12 15:30:57.468750 [scheduler thread(16)]: Scheduler: stop record did not succeed (trying again in 1 min.) VOX 12.12.2010 12:55:41-12.12.2010 15:15:00 Goodbye Deutschland! Die Auswanderer

No entries in TSWriter log at this time

No entries in error.log at this time

Same day, same channel, same tv-card (2 tuners, PCTV Sat Pro PCI, card #2 and #3), another schedule is starting and stopping correctly, while the tv server is still trying to stop the other recording from above:

2010-12-12 18:29:56.359375 [scheduler thread(16)]: Scheduler: stop record VOX 12.12.2010 12:55:41-12.12.2010 15:15:00 Goodbye Deutschland! Die Auswanderer
2010-12-12 18:29:56.359375 [scheduler thread(16)]: card: StopRecording card=2, user=scheduler526
2010-12-12 18:29:56.375000 [scheduler thread(16)]: card: StopRecording for card:2
2010-12-12 18:29:56.375000 [scheduler thread(16)]: card: StopRecording subchannel null, skipping
2010-12-12 18:29:56.375000 [scheduler thread(16)]: Scheduler: stop record did not succeed (trying again in 1 min.) VOX 12.12.2010 12:55:41-12.12.2010 15:15:00 Goodbye Deutschland! Die Auswanderer
2010-12-12 18:30:11.406250 [scheduler thread(16)]: Scheduler: stop record VOX 12.12.2010 16:50:11-12.12.2010 18:15:00 auto mobil
2010-12-12 18:30:11.406250 [scheduler thread(16)]: card: StopRecording card=3, user=scheduler355
2010-12-12 18:30:11.406250 [scheduler thread(16)]: card: StopRecording for card:3
2010-12-12 18:30:11.406250 [scheduler thread(16)]: tvdvbchannel.OnStopRecording subch=3, subch index=3
2010-12-12 18:30:11.406250 [scheduler thread(16)]: tvdvbchannel.OnStopRecording subch:3-3 tswriter StopRecording...
2010-12-12 18:30:11.437500 [scheduler thread(16)]: tvcard:FreeSubChannel: subchannels count 3 subch#3 keep graph=False
2010-12-12 18:30:11.437500 [scheduler thread(16)]: DVB subch:3 Decompose()
2010-12-12 18:30:11.437500 [scheduler thread(16)]: FreeSubChannel CA: freeing sub channel : 3
2010-12-12 18:30:11.437500 [scheduler thread(16)]: user:scheduler355 remove
2010-12-12 18:30:11.437500 [scheduler thread(16)]: card: StopRecording setting new context owner on user 'scheduler526'
2010-12-12 18:30:11.437500 [Channel state thread(18)]: Controller: DoSetChannelStates for 398 channels
2010-12-12 18:30:11.437500 [Channel state thread(18)]: ChannelStates.DoSetChannelStates took 1 msec
2010-12-12 18:30:11.500000 [scheduler thread(16)]: Scheduler: endtime=12.12.2010 18:15:00, Program.EndTime=12.12.2010 18:15:00, postRecTime=15
2010-12-12 18:30:11.500000 [scheduler thread(16)]: diskmanagement: recording auto mobil ended. type:Weekly max episodes:2147483647
2010-12-12 18:30:11.500000 [scheduler thread(16)]: Scheduler: stop record VOX 12.12.2010 12:55:41-12.12.2010 15:15:00 Goodbye Deutschland! Die Auswanderer
2010-12-12 18:30:11.500000 [scheduler thread(16)]: card: StopRecording card=2, user=scheduler526
2010-12-12 18:30:11.500000 [scheduler thread(16)]: card: StopRecording for card:2
2010-12-12 18:30:11.500000 [scheduler thread(16)]: card: StopRecording subchannel null, skipping
2010-12-12 18:30:11.500000 [scheduler thread(16)]: Scheduler: stop record did not succeed (trying again in 1 min.) VOX 12.12.2010 12:55:41-12.12.2010 15:15:00 Goodbye Deutschland! Die Auswanderer

Can't explain... Any ideas?

Regards

Arne

EDIT: Comskip log (22MB for the record, which won't stop) says nearly every third frame: "Audio PTS jumped 1 frames at frame x, repairing timeline" The other recording on this channel, which stopped correctly, had this message only about 10 times in the whole log, not every third frame. I wonder if it is a comskip issue or if the stream from tv-server is corrupt when record won't stop, that comskip only log these issues.

 

[jameson_uk]

Just to note I do not believe this has anything to do with comskip at all, there is a chance it is but please do not get hung up with this being a comskip related issue

 

[arneman]

AW: Recording not stopping

Just to note I do not believe this has anything to do with comskip at all, there is a chance it is but please do not get hung up with this being a comskip related issue

You're right, but maybe the results from comskip logs are a clue to the proper issue

 

[gibman]

This problem typically boils down to a subchannel leak.

meaning the tvservice loses track of subchannels.
eg. real sbchannels count is 0, but it "thinks" its still at 1.

those leaks can happen in numerous ways.

currently tvservice is not threadsafe when it comes to concurrent start of timeshifting/recording.
Ive seen this cause the same problems.

Also, current svn, has a few fixes for better subchannels handling.
there was a fix for this for the "skystar2 wdm driver". look in mantis.

I dont think its comskip either.

/gibman