Updated from MP1.12 to MP1.16 over the weekend. No major install problems, just usual grumpiness getting new install working. On main tv server MePoTVS, did full uninstall, new install, channel scan setup etc.
Seeing problems with tv recording and episode management. Never had this problem with MP1.12. MP is setup to record the afternoon local news on several channels from 5:00PM to 5:30PM and then the national news from 5:30PM to 6:00PM. Use Episode Management to keep 1 episode so each day MP deletes yesterdays news, recordings have 1 minute pre/post-store.
On 3 or 4 nights tvserver gets hung up trying to delete the “yesterday NEWS.ts” file, tvserver spins forever trying/failing to delete the file and filling log files with the same error messages. When I use setuptv.exe I can do a manual server “stop” then “start”, and things start working again. This happens about 50% of time, some nights everything runs normal/clean, other nights I see the same type of problem, always with the evening news shows that use episode management.
Tvserver system is MP1.16 tvserver/client, 4 core I5 CPU 12 GB ram, Win10 with all updates. The “K-drive” is dedicated 3 GB MP1.16 tvserver recording drive. Windows Defender is set to exclude the recording folder, nothing else on the drive. No other virus scan programs. Two MP1.16 clients have windows remote file access to recording folder to use TV-Series.
When the problem happens I’m using a living room MP1.16 client to watch the recordings while the tvserver is recording the shows. Remote client recording access uses “MP RTSP streaming” does not do direct windows remote file access. TV-Series is setup to access recordings on the “k-drive” using windows file sharing. Had same recordings setup with MP1.12 and never saw this problem.
The main error message is following. Once tvserver gets into error/spinning loop, fills 5MB log files over and over again with the same error messages.
[2017-04-30 18:01:02,951] [Log ] [scheduler thread] [ERROR] - RecordingFileHandler: Error while deleting a recording from disk: The process cannot access the file 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts' because it is being used by another process.
Also see the following, but this message happens without the “cannot access file” problem.
[2017-04-30 18:01:02,938] [Log ] [scheduler thread] [ERROR] - Exception in Program.Persist() with Message The number of returned rows 0 did not match the expected count of 1+.
If concurrency control is enabled this may indicate that the record was updated or deleted by another process
The attached zip file has log files from the hang on Sunday 20170430. The key error log files are “TVService.6.log (6:01PM)”, since it shows the starting error event and TVService-Error.2.log (6:02PM). The other logs are just 5MB repeats of the file delete problem.
Seeing problems with tv recording and episode management. Never had this problem with MP1.12. MP is setup to record the afternoon local news on several channels from 5:00PM to 5:30PM and then the national news from 5:30PM to 6:00PM. Use Episode Management to keep 1 episode so each day MP deletes yesterdays news, recordings have 1 minute pre/post-store.
On 3 or 4 nights tvserver gets hung up trying to delete the “yesterday NEWS.ts” file, tvserver spins forever trying/failing to delete the file and filling log files with the same error messages. When I use setuptv.exe I can do a manual server “stop” then “start”, and things start working again. This happens about 50% of time, some nights everything runs normal/clean, other nights I see the same type of problem, always with the evening news shows that use episode management.
Tvserver system is MP1.16 tvserver/client, 4 core I5 CPU 12 GB ram, Win10 with all updates. The “K-drive” is dedicated 3 GB MP1.16 tvserver recording drive. Windows Defender is set to exclude the recording folder, nothing else on the drive. No other virus scan programs. Two MP1.16 clients have windows remote file access to recording folder to use TV-Series.
When the problem happens I’m using a living room MP1.16 client to watch the recordings while the tvserver is recording the shows. Remote client recording access uses “MP RTSP streaming” does not do direct windows remote file access. TV-Series is setup to access recordings on the “k-drive” using windows file sharing. Had same recordings setup with MP1.12 and never saw this problem.
The main error message is following. Once tvserver gets into error/spinning loop, fills 5MB log files over and over again with the same error messages.
[2017-04-30 18:01:02,951] [Log ] [scheduler thread] [ERROR] - RecordingFileHandler: Error while deleting a recording from disk: The process cannot access the file 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts' because it is being used by another process.
Also see the following, but this message happens without the “cannot access file” problem.
[2017-04-30 18:01:02,938] [Log ] [scheduler thread] [ERROR] - Exception in Program.Persist() with Message The number of returned rows 0 did not match the expected count of 1+.
If concurrency control is enabled this may indicate that the record was updated or deleted by another process
The attached zip file has log files from the hang on Sunday 20170430. The key error log files are “TVService.6.log (6:01PM)”, since it shows the starting error event and TVService-Error.2.log (6:02PM). The other logs are just 5MB repeats of the file delete problem.
4:59 PM, start recording local news
StartRecording K:\MePo1\recordings\KNTV HD\NBC Bay Area News at 5\NBC Bay Area News at 5 - 2017-04-30.ts
StartRecording K:\MePo1\recordings\KGO-HD\ABC7 News 5_00PM\ABC7 News 5_00PM - 2017-04-30.ts
5:29 PM, start recording national news
StartRecording K:\MePo1\recordings\KGO-HD\ABC World News Tonight\ABC World News Tonight - 2017-04-30.ts
StartRecording K:\MePo1\recordings\KPIX-TV\CBS Weekend News\CBS Weekend News - 2017-04-30.ts
StartRecording K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-30.ts
5:31 PM, stop recording local news, delete 2017-04-29 files (ts, xml, comskip, others)
stop record KGO-HD 4/30/2017 4:59:11 PM-4/30/2017 5:30:00 PM ABC7 News 5:00PM
deleting 'K:\MePo1\recordings\KGO-HD\ABC7 News 5_00PM\ABC7 News 5_00PM - 2017-04-29.ts'
stop record KNTV HD 4/30/2017 4:59:09 PM-4/30/2017 5:30:00 PM NBC Bay Area News at 5
deleting 'K:\MePo1\recordings\KNTV HD\NBC Bay Area News at 5\NBC Bay Area News at 5 - 2017-04-28.ts'
To make life interesting, xmltv does epg import, don’t think this triggers problem
17:41:20 plugin:xmltv importing L:\MePo\xmltv\meposd\tvguide.xml
17:48:06 plugin:xmltv import done
6:01 PM, stop recording national news, delete 2017-04-29 files (ts, xml, comskip others)
stop record KPIX-TV 4/30/2017 5:29:08 PM-4/30/2017 6:00:00 PM CBS Weekend News
deleting 'K:\MePo1\recordings\KPIX-TV\CBS Weekend News\CBS Weekend News - 2017-04-29.ts’
then stuff happens when tvserver tries to delete “yesterday” it can’t delete file and spins forever on “NBC Nightly News With Lester Holt - 2017-04-29.ts'”
[2017-04-30 18:01:02,912] [Log ] [scheduler thread] [INFO ] - Scheduler: stop record KNTV HD 4/30/2017 5:29:06 PM-4/30/2017 6:00:00 PM NBC Nightly News With Lester Holt
[2017-04-30 18:01:02,938] [Log ] [scheduler thread] [ERROR] - Exception in Program.Persist() with Message The number of returned rows 0 did not match the expected count of 1+.
If concurrency control is enabled this may indicate that the record was updated or deleted by another process.
[2017-04-30 18:01:02,940] [Log ] [scheduler thread] [INFO ] - diskmanagement: recording NBC Nightly News With Lester Holt ended. type:Once max episodes:1
[2017-04-30 18:01:02,944] [Log ] [scheduler thread] [INFO ] - diskmanagement: Delete episode TvDatabase.Channel NBC Nightly News With Lester Holt Saturday, April 29, 2017 3:59:07 PM
[2017-04-30 18:01:02,945] [Log ] [scheduler thread] [DEBUG] - DeleteRecordingOnDisk: 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts'
[2017-04-30 18:01:02,945] [Log ] [scheduler thread] [DEBUG] - - deleting 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.edl'
[2017-04-30 18:01:02,947] [Log ] [scheduler thread] [DEBUG] - - deleting 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.log'
[2017-04-30 18:01:02,949] [Log ] [scheduler thread] [DEBUG] - - deleting 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.logo.txt'
[2017-04-30 18:01:02,950] [Log ] [scheduler thread] [DEBUG] - - deleting 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts'
[2017-04-30 18:01:02,951] [Log ] [scheduler thread] [ERROR] - RecordingFileHandler: Error while deleting a recording from disk: The process cannot access the file 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts' because it is being used by another process.
[2017-04-30 18:01:02,958] [Log ] [scheduler thread] [ERROR] - RecordingFileHandler: adding filename to list of pending deletions: K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts
[2017-04-30 18:01:02,967] [Log ] [scheduler thread] [INFO ] - diskmanagement: Delete episode TvDatabase.Channel NBC Nightly News With Lester Holt Saturday, April 29, 2017 3:59:07 PM
[2017-04-30 18:01:02,967] [Log ] [scheduler thread] [DEBUG] - DeleteRecordingOnDisk: 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts'
[2017-04-30 18:01:02,968] [Log ] [scheduler thread] [DEBUG] - - deleting 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts'
[2017-04-30 18:01:02,968] [Log ] [scheduler thread] [ERROR] - RecordingFileHandler: Error while deleting a recording from disk: The process cannot access the file 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts' because it is being used by another process.
tvserver spins forever filling log files with the same error messages
StartRecording K:\MePo1\recordings\KNTV HD\NBC Bay Area News at 5\NBC Bay Area News at 5 - 2017-04-30.ts
StartRecording K:\MePo1\recordings\KGO-HD\ABC7 News 5_00PM\ABC7 News 5_00PM - 2017-04-30.ts
5:29 PM, start recording national news
StartRecording K:\MePo1\recordings\KGO-HD\ABC World News Tonight\ABC World News Tonight - 2017-04-30.ts
StartRecording K:\MePo1\recordings\KPIX-TV\CBS Weekend News\CBS Weekend News - 2017-04-30.ts
StartRecording K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-30.ts
5:31 PM, stop recording local news, delete 2017-04-29 files (ts, xml, comskip, others)
stop record KGO-HD 4/30/2017 4:59:11 PM-4/30/2017 5:30:00 PM ABC7 News 5:00PM
deleting 'K:\MePo1\recordings\KGO-HD\ABC7 News 5_00PM\ABC7 News 5_00PM - 2017-04-29.ts'
stop record KNTV HD 4/30/2017 4:59:09 PM-4/30/2017 5:30:00 PM NBC Bay Area News at 5
deleting 'K:\MePo1\recordings\KNTV HD\NBC Bay Area News at 5\NBC Bay Area News at 5 - 2017-04-28.ts'
To make life interesting, xmltv does epg import, don’t think this triggers problem
17:41:20 plugin:xmltv importing L:\MePo\xmltv\meposd\tvguide.xml
17:48:06 plugin:xmltv import done
6:01 PM, stop recording national news, delete 2017-04-29 files (ts, xml, comskip others)
stop record KPIX-TV 4/30/2017 5:29:08 PM-4/30/2017 6:00:00 PM CBS Weekend News
deleting 'K:\MePo1\recordings\KPIX-TV\CBS Weekend News\CBS Weekend News - 2017-04-29.ts’
then stuff happens when tvserver tries to delete “yesterday” it can’t delete file and spins forever on “NBC Nightly News With Lester Holt - 2017-04-29.ts'”
[2017-04-30 18:01:02,912] [Log ] [scheduler thread] [INFO ] - Scheduler: stop record KNTV HD 4/30/2017 5:29:06 PM-4/30/2017 6:00:00 PM NBC Nightly News With Lester Holt
[2017-04-30 18:01:02,938] [Log ] [scheduler thread] [ERROR] - Exception in Program.Persist() with Message The number of returned rows 0 did not match the expected count of 1+.
If concurrency control is enabled this may indicate that the record was updated or deleted by another process.
[2017-04-30 18:01:02,940] [Log ] [scheduler thread] [INFO ] - diskmanagement: recording NBC Nightly News With Lester Holt ended. type:Once max episodes:1
[2017-04-30 18:01:02,944] [Log ] [scheduler thread] [INFO ] - diskmanagement: Delete episode TvDatabase.Channel NBC Nightly News With Lester Holt Saturday, April 29, 2017 3:59:07 PM
[2017-04-30 18:01:02,945] [Log ] [scheduler thread] [DEBUG] - DeleteRecordingOnDisk: 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts'
[2017-04-30 18:01:02,945] [Log ] [scheduler thread] [DEBUG] - - deleting 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.edl'
[2017-04-30 18:01:02,947] [Log ] [scheduler thread] [DEBUG] - - deleting 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.log'
[2017-04-30 18:01:02,949] [Log ] [scheduler thread] [DEBUG] - - deleting 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.logo.txt'
[2017-04-30 18:01:02,950] [Log ] [scheduler thread] [DEBUG] - - deleting 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts'
[2017-04-30 18:01:02,951] [Log ] [scheduler thread] [ERROR] - RecordingFileHandler: Error while deleting a recording from disk: The process cannot access the file 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts' because it is being used by another process.
[2017-04-30 18:01:02,958] [Log ] [scheduler thread] [ERROR] - RecordingFileHandler: adding filename to list of pending deletions: K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts
[2017-04-30 18:01:02,967] [Log ] [scheduler thread] [INFO ] - diskmanagement: Delete episode TvDatabase.Channel NBC Nightly News With Lester Holt Saturday, April 29, 2017 3:59:07 PM
[2017-04-30 18:01:02,967] [Log ] [scheduler thread] [DEBUG] - DeleteRecordingOnDisk: 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts'
[2017-04-30 18:01:02,968] [Log ] [scheduler thread] [DEBUG] - - deleting 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts'
[2017-04-30 18:01:02,968] [Log ] [scheduler thread] [ERROR] - RecordingFileHandler: Error while deleting a recording from disk: The process cannot access the file 'K:\MePo1\recordings\KNTV HD\NBC Nightly News With Lester Holt\NBC Nightly News With Lester Holt - 2017-04-29.ts' because it is being used by another process.
tvserver spins forever filling log files with the same error messages