Hi,

I have two systems that are identically configured with R7.2 All the parameters appear to be the same. One automatically deletes recordings when disk capacity is reached and one does not. The "AutoExpireExtraSpace" is set to 10% of the disk (ie, 75GB for one and 50GB) for the other. Can anyone shed light on why this is happening? Why does one system run perfectly fine and the other keeps running out of disk? What can I do to fix it?

Thanks

There are many setting in mythtv, so without looking at the system one can only guess at things to look at. The first place I would look is at the attributes on each recording -- do they clearly show that they are allowed to "auto expire"?

_________________
Marc

The views expressed are my own and do not necessarily reflect the views of my employer.

I'd check

(Setup->TV Settings->General)

and compare the two systems

Then I'd check in Mythweb and see if you are to manually delete a show thru recorded shows

Another approach for looking at differences via mythweb is go to http://<server IP address>>/mythweb/settings/mythtv on both machines. Search for the word "expire" using your browser and see if they have different settings. I would not change anything from this screen, but looking around could give you an idea of what is different.

_________________
Marc

The views expressed are my own and do not necessarily reflect the views of my employer.

Thanks all for the suggestions. Here's what I've found.

marc.aronson: All the settings are identical on both machines when looking at the mythweb settings EXCEPT for something called "GlobalServiceHobbitserver". I don't know what that is and a search yielded nothing. The machine that expires the files correctly has this as a setting to "1". The machine that is not expiring files does not have this as an entry at all. Your other suggestion to see if the attributes are set correctly, I don't know how to do that. Any tips?

RacerX: I've checked all the setting and compared both machine the way you advised. Both are identical.

I'm at a loss and any additional tips would be appreciated.

Thanks!

GlobalServiceHobbitserver is unrelated to this topic, and in fact isn't used anymore by LinHES.
The setting can be removed, but it's harmless to leave it.

jams: Thanks for the response.

What can I provide that might be able to help me resolve this matter?

Thanks

In your frotend, goto recorded programs. Select a program. Hit the menu button. Select "Storage options". If it says "Enable auto expire", the recording has been set to never expire. If it says "disable auto expire", recordings can be cleaned up. If the misbehaved machine says "Enable auto expire", then the issue is the way you set up your recordings, and we have a hint of where to look next...

_________________
Marc

The views expressed are my own and do not necessarily reflect the views of my employer.

marc.aronson:

I checked the attributes on several recordings on the misbehaved machine and the recordings say "Disable Auto Expire". They also say the same thing on the well behaved machine.

For future newbies, here's a more detailed step on how to get to "storage options":

From the Main Menu, select "Media Library" --> "Watch Recordings" --> Then select a specific recording by hitting the "M" button --> select "Storage Options"

Now I'm ready for your next hint....

Thanks!

OK -- so we know that your setting tables are identical and your recording have the "auto expire me" bit set. I can only think of one more place to look -- have you checked the mythbackend logs? My memory is that the logs show something when they auto expire programs. I suggest looking in /var/log/mythtv/mythbackend.log on both machines. See if you can find some message showing that the misbehaved machine is complaining when it trys to autoexpire the programs. I don't know what to look for, but sometimes there are hints in the logs...

_________________
Marc

The views expressed are my own and do not necessarily reflect the views of my employer.

marc.aronson:

Thanks again! I too am not sure what to look for, but perhaps you or others can. I'll include the latest entries from the top of the most recent hour (11:00:00 am EST) (which seem to be duplicated a lot in each recorder) around where 'autoexpire' appears. Let me know if you need more data.

First the well behaved machine:

2013-02-22 11:00:00.451 TVRec(1): Changing from RecordingOnly to None
2013-02-22 11:00:00.455 TVRec(4): Changing from RecordingOnly to None
2013-02-22 11:00:00.470 Updating status for "Sesame Street":"Sesame Street-o-Saurus" on cardid 1 (Recording => Recorded)
2013-02-22 11:00:00.472 Finished recording Gold Rush "Game Changer": channel 1030
2013-02-22 11:00:00.474 Finished recording Sesame Street "Sesame Street-o-Saurus": channel 1009
2013-02-22 11:00:00.486 TVRec(3): Changing from RecordingOnly to None
2013-02-22 11:00:00.514 Finished recording My Wife and Kids "Not So Hostile Takeover": channel 1019
2013-02-22 11:00:00.540 Updating status for "Gold Rush":"Game Changer" on cardid 4 (Recording => Recorded)
2013-02-22 11:00:00.572 Updating status for "My Wife and Kids":"Not So Hostile Takeover" on cardid 3 (Recording => Recorded)
2013-02-22 11:00:00.833 TVRec(2): Changing from RecordingOnly to None
2013-02-22 11:00:00.840 Updating status for "Despierta Amrica!" on cardid 2 (Recording => Recorded)
2013-02-22 11:00:00.841 Finished recording Despierta Amrica!: channel 1011
2013-02-22 11:00:00.978 Finished recording Gold Rush "Game Changer": channel 1030
2013-02-22 11:00:01.038 Finished recording Sesame Street "Sesame Street-o-Saurus": channel 1009
2013-02-22 11:00:01.053 Finished recording My Wife and Kids "Not So Hostile Takeover": channel 1019
2013-02-22 11:00:01.227 Finished recording Despierta Amrica!: channel 1011
2013-02-22 11:00:01.504 MainServer, Error: PREVIEW_SUCCESS but no receivers.
2013-02-22 11:00:03.098 TVRec(1): Changing from None to RecordingOnly
2013-02-22 11:00:03.135 TVRec(1): HW Tuner: 1->1
2013-02-22 11:00:03.278 AutoExpire: CalcParams(): Max required Free Space: 62.0 GB w/freq: 7 min
2013-02-22 11:00:03.298 Started recording: "Daniel Tiger's Neighborhood":"Daniel Plays Ball; O Builds a Tower": channel 1009 on cardid 1, sourceid 1
2013-02-22 11:00:03.405 TVRec(4): Changing from None to RecordingOnly
2013-02-22 11:00:03.452 TVRec(4): HW Tuner: 4->4
2013-02-22 11:00:03.602 AutoExpire: CalcParams(): Max required Free Space: 62.0 GB w/freq: 7 min
2013-02-22 11:00:03.611 Started recording: "Gold Rush":"Road to Gold": channel 1030 on cardid 4, sourceid 1
2013-02-22 11:00:03.644 TVRec(1): rec->GetPathname(): '/myth/tv/1009_20130222110000.mpg'
2013-02-22 11:00:03.647 TVRec(4): rec->GetPathname(): '/myth/tv/1030_20130222110000.mpg'
2013-02-22 11:00:03.741 TVRec(2): Changing from None to RecordingOnly
2013-02-22 11:00:03.806 TVRec(2): HW Tuner: 2->2
2013-02-22 11:00:03.892 AutoExpire: CalcParams(): Max required Free Space: 62.0 GB w/freq: 7 min
2013-02-22 11:00:03.912 Started recording: "La Rosa de Guadalupe":"Un ngel Entre las Sombras": channel 1011 on cardid 2, sourceid 1
2013-02-22 11:00:03.927 MPEGRec(/dev/video3) Warning: Audio sample rate 32000 Hz
is not supported by ivtv driver, using 48000 Hz instead.
2013-02-22 11:00:03.928 MPEGRec(/dev/video0) Warning: Audio sample rate 32000 Hz
is not supported by ivtv driver, using 48000 Hz instead.
*********************** WARNING ***********************
ivtv drivers prior to 0.10.0 can cause lockups when
reading VBI. Drivers between 0.10.5 and 1.0.3+ do not
*********************** WARNING ***********************
ivtv drivers prior to 0.10.0 can cause lockups when
reading VBI. Drivers between 0.10.5 and 1.0.3+ do not
properly capture VBI data on PVR-250 and PVR-350 cards.

properly capture VBI data on PVR-250 and PVR-350 cards.

2013-02-22 11:00:03.953 TVRec(2): rec->GetPathname(): '/myth/tv/1011_20130222110000.mpg'
2013-02-22 11:00:03.965 TVRec(3): Changing from None to RecordingOnly
2013-02-22 11:00:04.116 TVRec(3): HW Tuner: 3->3
2013-02-22 11:00:04.173 MPEGRec(/dev/video1) Warning: Audio sample rate 32000 Hz
is not supported by ivtv driver, using 48000 Hz instead.
*********************** WARNING ***********************
ivtv drivers prior to 0.10.0 can cause lockups when
reading VBI. Drivers between 0.10.5 and 1.0.3+ do not
properly capture VBI data on PVR-250 and PVR-350 cards.

2013-02-22 11:00:04.257 AutoExpire: CalcParams(): Max required Free Space: 62.0 GB w/freq: 7 min
2013-02-22 11:00:04.273 Started recording: "35 & Ticking": channel 1019 on cardid 3, sourceid 1
2013-02-22 11:00:04.290 Updating status for "Daniel Tiger's Neighborhood":"Daniel Plays Ball; O Builds a Tower" on cardid 1 (Tuning => Recording)
2013-02-22 11:00:04.294 Updating status for "Gold Rush":"Road to Gold" on cardid 4 (Tuning => Recording)
2013-02-22 11:00:04.315 Updating status for "La Rosa de Guadalupe":"Un ngel Entre las Sombras" on cardid 2 (Tuning => Recording)
2013-02-22 11:00:04.331 Updating status for "35 & Ticking" on cardid 3 (Tuning => Recording)
2013-02-22 11:00:04.341 TVRec(3): rec->GetPathname(): '/myth/tv/1019_20130222110000.mpg'
2013-02-22 11:00:04.472 MPEGRec(/dev/video2) Warning: Audio sample rate 32000 Hz
is not supported by ivtv driver, using 48000 Hz instead.
*********************** WARNING ***********************
ivtv drivers prior to 0.10.0 can cause lockups when
reading VBI. Drivers between 0.10.5 and 1.0.3+ do not
properly capture VBI data on PVR-250 and PVR-350 cards.

2013-02-22 11:00:04.899 MainServer::ANN Monitor
2013-02-22 11:00:04.899 MainServer::ANN Monitor
2013-02-22 11:00:04.899 MainServer::ANN Monitor
2013-02-22 11:00:04.928 adding: rec-nc-64 as a client (events: 0)
2013-02-22 11:00:04.934 adding: rec-nc-64 as a client (events: 0)
2013-02-22 11:00:04.936 adding: rec-nc-64 as a client (events: 0)
2013-02-22 11:00:04.938 MainServer::ANN Monitor
2013-02-22 11:00:04.949 adding: rec-nc-64 as a client (events: 1)
2013-02-22 11:00:04.946 MainServer::ANN Monitor
2013-02-22 11:00:04.942 MainServer::ANN Monitor
2013-02-22 11:00:04.953 adding: rec-nc-64 as a client (events: 1)
2013-02-22 11:00:04.954 adding: rec-nc-64 as a client (events: 1)
2013-02-22 11:00:05.290 Reschedule requested for id 0.
2013-02-22 11:00:05.331 Reschedule requested for id 0.
2013-02-22 11:00:05.463 Reschedule requested for id 0.
2013-02-22 11:00:05.469 Reschedule requested for id 0.
2013-02-22 11:00:19.001 Scheduled 1559 items in 13.7 = 0.18 match + 13.53 place
2013-02-22 11:03:43.411 AutoExpire: CalcParams(): Max required Free Space: 62.0 GB w/freq: 7 min
2013-02-22 11:03:44.682 ProgramInfo(1030_20130207110000.mpg), Error: GetPlaybackURL: '1030_20130207110000.mpg' should be local, but it can not be found.
2013-02-22 11:03:44.706 Expiring 1117 MB for 1009 at 2013-02-16T12:30:00 => "The Victory Garden":New
2013-02-22 11:03:44.755 Reschedule requested for id 0.
2013-02-22 11:03:57.553 Scheduled 1559 items in 12.8 = 0.01 match + 12.79 place
2013-02-22 11:03:58.786 Reschedule requested for id 0.
2013-02-22 11:04:13.635 Scheduled 1559 items in 14.8 = 4.13 match + 10.72 place
2013-02-22 11:04:32.891 UPnpMedia: BuildMediaMap VIDEO scan starting in :/myth/video:
2013-02-22 11:04:33.093 UPnpMedia: BuildMediaMap Done. Found 5 objects
2013-02-22 11:06:17.346 DB Error (Resolution insert):
Query was:
INSERT INTO recordedmarkup (chanid, starttime, mark, type, data) VALUES ( ?, ?, ?, ?, ?);
Bindings were:
:CHANID=1011, ATA=480, :MARK=11161, :STARTTIME=2013-02-22T11:00:00, :TYPE=30
Driver error was [2/1062]:
QMYSQL3: Unable to execute statement
Database error was:
Duplicate entry '1011-2013-02-22 11:00:00-30-11161' for key 'PRIMARY'

2013-02-22 11:06:17.362 DB Error (Resolution insert):
Query was:
INSERT INTO recordedmarkup (chanid, starttime, mark, type, data) VALUES ( ?, ?, ?, ?, ?);
Bindings were:
:CHANID=1011, ATA=480, :MARK=11161, :STARTTIME=2013-02-22T11:00:00, :TYPE=31
Driver error was [2/1062]:
QMYSQL3: Unable to execute statement
Database error was:
Duplicate entry '1011-2013-02-22 11:00:00-31-11161' for key 'PRIMARY'


Now the misbehaved machine:

2013-02-22 11:00:00.549 TVRec(1): Changing from RecordingOnly to None
2013-02-22 11:00:00.573 Updating status for "The Doctors":"Lose It, Lance It, Get Rid of It!" on cardid 1 (Recording => Recorded)
2013-02-22 11:00:00.600 Finished recording The Doctors "Lose It, Lance It, Get Rid of It!": channel 1003
2013-02-22 11:00:00.643 TVRec(3): Changing from RecordingOnly to None
2013-02-22 11:00:00.703 TVRec(2): Changing from RecordingOnly to None
2013-02-22 11:00:00.778 Updating status for "The Steve Wilkos Show":"Why Were You Caught With Naked Pictures of My Kids?" on cardid 3 (Recording => Recorded)
2013-02-22 11:00:00.784 Updating status for "What's New Scooby-Doo?":"Reef Grief!" on cardid 2 (Recording => Recorded)
2013-02-22 11:00:00.780 Finished recording The Steve Wilkos Show "Why Were You Caught With Naked Pictures of My Kids?": channel 1010
2013-02-22 11:00:00.779 Finished recording What's New Scooby-Doo? "Reef Grief!": channel 1064
2013-02-22 11:00:00.880 TVRec(4): Changing from RecordingOnly to None
2013-02-22 11:00:00.883 Finished recording Jake and the Never Land Pirates "The Golden Twilight Treasure!; Rock the Croc!": channel 1057
2013-02-22 11:00:00.883 Updating status for "Jake and the Never Land Pirates":"The Golden Twilight Treasure!; Rock the Croc!" on cardid 4 (Recording => Recorded)
2013-02-22 11:00:01.006 Finished recording The Doctors "Lose It, Lance It, Get Rid of It!": channel 1003
2013-02-22 11:00:01.062 Finished recording What's New Scooby-Doo? "Reef Grief!": channel 1064
2013-02-22 11:00:01.203 Finished recording Jake and the Never Land Pirates "The Golden Twilight Treasure!; Rock the Croc!": channel 1057
2013-02-22 11:00:01.209 Finished recording The Steve Wilkos Show "Why Were You Caught With Naked Pictures of My Kids?": channel 1010
2013-02-22 11:00:02.577 MainServer::ANN Monitor
2013-02-22 11:00:02.595 adding: rec-nc-62 as a client (events: 0)
2013-02-22 11:00:02.577 MainServer::ANN Monitor
2013-02-22 11:00:02.604 adding: rec-nc-62 as a client (events: 0)
2013-02-22 11:00:02.596 MainServer::ANN Monitor
2013-02-22 11:00:02.606 adding: rec-nc-62 as a client (events: 1)
2013-02-22 11:00:02.606 MainServer::ANN Monitor
2013-02-22 11:00:02.608 adding: rec-nc-62 as a client (events: 1)
2013-02-22 11:00:02.853 TVRec(2): Changing from None to RecordingOnly
2013-02-22 11:00:02.873 TVRec(2): HW Tuner: 2->2
2013-02-22 11:00:02.923 AutoExpire: CalcParams(): Max required Free Space: 252.0 GB w/freq: 7 min
2013-02-22 11:00:02.938 Started recording: "What's New Scooby-Doo?":"There's No Creature Like Snow Creature": channel 1064 on cardid 2, sourceid 1
2013-02-22 11:00:02.974 TVRec(4): Changing from None to RecordingOnly
2013-02-22 11:00:02.980 TVRec(4): HW Tuner: 4->4
2013-02-22 11:00:03.036 AutoExpire: CalcParams(): Max required Free Space: 252.0 GB w/freq: 7 min
2013-02-22 11:00:03.048 Started recording: "Meet the Small Potatoes": channel 1057 on cardid 4, sourceid 1
2013-02-22 11:00:03.068 TVRec(2): rec->GetPathname(): '/myth/tv/1064_20130222110000.mpg'
2013-02-22 11:00:03.088 MPEGRec(/dev/video1) Warning: Audio sample rate 32000 Hz
is not supported by ivtv driver, using 48000 Hz instead.
*********************** WARNING ***********************
ivtv drivers prior to 0.10.0 can cause lockups when
reading VBI. Drivers between 0.10.5 and 1.0.3+ do not
properly capture VBI data on PVR-250 and PVR-350 cards.

2013-02-22 11:00:03.097 TVRec(3): Changing from None to RecordingOnly
2013-02-22 11:00:03.111 TVRec(3): HW Tuner: 3->3
2013-02-22 11:00:03.157 TVRec(4): rec->GetPathname(): '/myth/tv/1057_20130222110000.mpg'
2013-02-22 11:00:03.208 MPEGRec(/dev/video3) Warning: Audio sample rate 32000 Hz
is not supported by ivtv driver, using 48000 Hz instead.
*********************** WARNING ***********************
ivtv drivers prior to 0.10.0 can cause lockups when
reading VBI. Drivers between 0.10.5 and 1.0.3+ do not
properly capture VBI data on PVR-250 and PVR-350 cards.

2013-02-22 11:00:03.354 AutoExpire: CalcParams(): Max required Free Space: 252.0 GB w/freq: 7 min
2013-02-22 11:00:03.355 TVRec(3): rec->GetPathname(): '/myth/tv/1010_20130222110000.mpg'
2013-02-22 11:00:03.370 Started recording: Maury:"Who's My Baby's Father ... My Husband, My Ex or His Nephew?": channel 1010 on cardid 3, sourceid 1
2013-02-22 11:00:03.408 MPEGRec(/dev/video2) Warning: Audio sample rate 32000 Hz
is not supported by ivtv driver, using 48000 Hz instead.
*********************** WARNING ***********************
ivtv drivers prior to 0.10.0 can cause lockups when
reading VBI. Drivers between 0.10.5 and 1.0.3+ do not
properly capture VBI data on PVR-250 and PVR-350 cards.

2013-02-22 11:00:03.576 TVRec(1): Changing from None to RecordingOnly
2013-02-22 11:00:03.583 TVRec(1): HW Tuner: 1->1
2013-02-22 11:00:03.675 AutoExpire: CalcParams(): Max required Free Space: 252.0 GB w/freq: 7 min
2013-02-22 11:00:03.679 Started recording: "The Price Is Right": channel 1003 on cardid 1, sourceid 1
2013-02-22 11:00:03.684 Updating status for "What's New Scooby-Doo?":"There's No Creature Like Snow Creature" on cardid 2 (Tuning => Recording)
2013-02-22 11:00:03.690 Updating status for "Meet the Small Potatoes" on cardid 4 (Tuning => Recording)
2013-02-22 11:00:03.697 Updating status for Maury:"Who's My Baby's Father ... My Husband, My Ex or His Nephew?" on cardid 3 (Tuning => Recording)
2013-02-22 11:00:03.707 Updating status for "The Price Is Right" on cardid 1 (Tuning => Recording)
2013-02-22 11:00:03.777 TVRec(1): rec->GetPathname(): '/myth/tv/1003_20130222110000.mpg'
2013-02-22 11:00:03.807 MPEGRec(/dev/video0) Warning: Audio sample rate 32000 Hz
is not supported by ivtv driver, using 48000 Hz instead.
*********************** WARNING ***********************
ivtv drivers prior to 0.10.0 can cause lockups when
reading VBI. Drivers between 0.10.5 and 1.0.3+ do not
properly capture VBI data on PVR-250 and PVR-350 cards.

2013-02-22 11:00:04.685 Reschedule requested for id 0.
2013-02-22 11:00:04.688 Reschedule requested for id 0.
2013-02-22 11:00:04.689 Reschedule requested for id 0.
2013-02-22 11:00:04.690 Reschedule requested for id 0.
2013-02-22 11:00:08.885 MainServer::ANN Monitor
2013-02-22 11:00:12.504 adding: rec-nc-62 as a client (events: 0)
2013-02-22 11:00:14.427 MainServer::ANN Monitor
2013-02-22 11:00:17.732 adding: rec-nc-62 as a client (events: 1)
2013-02-22 11:00:30.236 Scheduled 2264 items in 25.5 = 0.01 match + 25.54 place
2013-02-22 11:00:58.245 UPnpMedia: BuildMediaMap VIDEO scan starting in :/myth/video:
2013-02-22 11:00:58.262 UPnpMedia: BuildMediaMap Done. Found 2 objects

How about checking the permissions for

/myth/tv

is there any difference between the two?

Also if your possessed machine ran totally out of space it could have messed things up and Marc has the right idea that it's in the logs

RacerX:

Permissions on both machines in /myth/tv are set to "-rw-r-r--" As for looking in the log files, I'm not sure what I"m looking for. If you can point me in the right direction, I'll dig up the information.

This possessed machine has not run out of space in this iteration. It has run out of space in the past when I've forgotten to delete the files manually. I've installed and re-installed 7.2 on this machine several times thinking that I might have messed up something in the process, but the result is always the same. I'm beginning to wonder if the hardware makes a difference as they are not running on identical hardware.

I've even tried to install 7.4 and see if the same behavior occurs but I couldn't get past the setup screen for schedulesdirect.com. It keeps hanging up on that screen. I haven't tried to push on that issue because I am fairly happy with 7.2 and I'm not sure what 7.4 will add if I cant be sure that it WILL fix this problem.

Thanks for your response.

Behaved machine has this in the log:

Misbehaved machine has this:


62GB vs. 252GB. There's a difference.

Let me ask this: How much storage is on each machine for recordings (show the output of "df -h") and how much is currently in use on each machine?

_________________
Marc

The views expressed are my own and do not necessarily reflect the views of my employer.

Marc.aronson:

Yes, there is a difference that setting, but I did that on purpose to see if it matters. It really doesn't matter what that parameter is: it could be 50G or 200G. The misbehaved recorder will run it down to 0.

Here's the data on the storage for each machine:

Well behaved machine:

[root@rec-nc-64 ~]# df -h
Filesystem Size Used Avail Use% Mounted on
udev 10M 0 10M 0% /dev
run 10M 256K 9.8M 3% /run
/dev/disk/by-uuid/e11ab5c2-3561-4bb3-a579-b1df78b72fe5 4.9G 2.4G 2.3G 51% /
shm 1004M 0 1004M 0% /dev/shm
/dev/sda3 1.4T 1.3T 63G 96% /myth

-------------------

Misbehaved machine:
[root@rec-nc-62 ~]# df -h
Filesystem Size Used Avail Use% Mounted on
udev 10M 0 10M 0% /dev
run 10M 236K 9.8M 3% /run
/dev/disk/by-uuid/1f868c35-c574-4309-b1e1-93ed14a9a0f7 4.9G 2.1G 2.6G 45% /
shm 1004M 0 1004M 0% /dev/shm
/dev/sda3 452G 267G 163G 63% /myth

Thanks