[mythtv-users] mythtv-users Digest, Vol 75, Issue 97

Tue Jun 30 00:41:17 UTC 2009

> ------------------------------
>
> Message: 6
> Date: Mon, 29 Jun 2009 14:35:45 +0100
> From: Nick Morrott <knowledgejunkie at gmail.com>
> Subject: Re: [mythtv-users] 2nd attempt, recorded files are empty
> To: Discussion about mythtv <mythtv-users at mythtv.org>
> Message-ID:
> 	<5387cd30906290635h2b6b491ay74a50f124bdee705 at mail.gmail.com>
> Content-Type: text/plain; charset=UTF-8
>
> On 29/06/2009, Peter Hocking <peter at thehockings.org> wrote:
>   
>> Hi
>>
>>  When I record a TV show using the scheduler, the resulting file is empty.
>> How ever, if I start recording the show while watching it using live TV, it
>> records just fine.
>>
>>  Running Mythbuntu 9.04, mythtv-common:
>>   Installed: 0.21.0+fixes20745-0ubuntu0+mythbuntu3
>>   Candidate: 0.21.0+fixes20745-0ubuntu0+mythbuntu3
>>   Version table:
>>  *** 0.21.0+fixes20745-0ubuntu0+mythbuntu3 0
>>        500 http://weeklybuilds.mythbuntu.org jaunty/main
>> Packages
>>        100 /var/lib/dpkg/status
>>     0.21.0+fixes19961-0ubuntu8 0
>>        500 http://au.archive.ubuntu.com jaunty/multiverse Packages
>>
>>  Running on Athlon 2800 64 bit, (Socket 754) with 2 gi6 of RAM, 500 gig SATA
>> hard drive & SATA DVD- burner with HD Homerun tuner.
>>  LiveTV & recording folder are the same,
>> /var/lib/mythtv/recordings/Test
>>
>>  I've formatted the hard drive 3 times & re-installed,rwice with Ubuntu,
>> then installing MythTV, the last time with Mythbuntu, each time with the
>> same result.
>>
>>  Any suggestions?
>>     
>
> If you have more than one tuner configured, I'd guess the higher
> priority tuner (or the tuner being selected for recordings) is not
> configured correctly, whilst the lower priority tuner (or the one
> being used for LiveTV) *is* configured correctly.
>
> If you have two inputs configured, a way to check for this scenario is
> to delete your configured inputs in mythtv-setup and then create a new
> input connection using the first capture device and the relevant video
> source. Exit mythtv-setup, restart mythbackend and attempt a scheduled
> recording and a LiveTV session. Determine whether either or both
> recordings were successful, and then repeat the process in
> mythtv-setup, creating an input connection on the *second*capture
> device and retrying a scheduled recording and a LiveTV session.
>
> You don't need to touch the capture cards or video sources - just
> delete your configured input connections
>
> The mythbackend logfile will also be outputting useful messages - it
> is likely that it already contains the details of the previous
> scheduled recording failures. If it does, please post a trimmed
> excerpt.
>
> Cheers,
> Nick
>
>   
Thanks for your reply.

I'm using a HD Homerun tuner, which is a dual tuner. Recordings started 
on both tuners when watching live TV record just fine, but scheduled 
recordings on both tuners fail.

Here's a trimmed log from a failed recording;

2009-06-26 14:26:23.847 MainServer::HandleAnnounce Monitor
2009-06-26 14:26:23.852 adding: tv-server as a client (events: 0)
2009-06-26 14:26:23.853 MainServer::HandleAnnounce Monitor
2009-06-26 14:26:23.854 adding: tv-server as a client (events: 1)
2009-06-26 14:26:23.856 Reschedule requested for id -1.
2009-06-26 14:26:23.900 Scheduled 14 items in 0.0 = 0.01 match + 0.03
place
2009-06-26 14:28:27.916 Reschedule requested for id 0.
2009-06-26 14:28:27.963 Scheduled 14 items in 0.0 = 0.00 match + 0.04
place
2009-06-26 14:28:57.548 TVRec(1): ASK_RECORDING 1 29 0 0
2009-06-26 14:29:28.150 TVRec(1): Changing from None to RecordingOnly
2009-06-26 14:29:28.152 TVRec(1): HW Tuner: 1->1
2009-06-26 14:29:28.178 HDHRChan(12108420/0): device found at address
192.168.11.78
2009-06-26 14:29:28.309 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 15 min
2009-06-26 14:29:28.312 Started recording: In Search of Bony: channel
1030 on cardid 1, sourceid 1
2009-06-26 14:31:35.710 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 15 min
2009-06-26 14:43:23.170 UPnpMedia: BuildMediaMap VIDEO scan starting
in :/var/lib/mythtv/videos:
2009-06-26 14:43:23.175 UPnpMedia: BuildMediaMap Done. Found 0 objects
2009-06-26 14:46:35.764 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 15 min
2009-06-26 15:01:35.810 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 15 min
2009-06-26 15:13:26.177 UPnpMedia: BuildMediaMap VIDEO scan starting
in :/var/lib/mythtv/videos:
2009-06-26 15:13:26.179 UPnpMedia: BuildMediaMap Done. Found 0 objects
2009-06-26 15:16:35.856 AutoExpire: CalcParams(): Max required Free
Space: 3.0 GB w/freq: 15 min
2009-06-26 15:27:09.530 MainServer::HandleAnnounce Monitor
2009-06-26 15:27:09.536 adding: tv-server as a client (events: 0)
2009-06-26 15:27:09.537 MainServer::HandleAnnounce Monitor
2009-06-26 15:27:09.538 adding: tv-server as a client (events: 1)
2009-06-26 15:27:09.540 Reschedule requested for id -1.
2009-06-26 15:27:09.585 Scheduled 14 items in 0.0 = 0.01 match + 0.03
place
2009-06-26 15:30:30.363 TVRec(1): Changing from RecordingOnly to None
2009-06-26 15:30:30.372 Finished recording In Search of Bony: channel
1030
2009-06-26 15:30:30.373 Reschedule requested for id 0.
2009-06-26 15:30:30.422 Scheduled 13 items in 0.0 = 0.00 match + 0.04
place
2009-06-26 15:30:31.416 Finished recording In Search of Bony: channel
1030
2009-06-26 15:30:31.674 Using runtime prefix = /usr
2009-06-26 15:30:31.685 Empty LocalHostName.
2009-06-26 15:30:31.686 Using localhost value of tv-server
2009-06-26 15:30:31.711 New DB connection, total: 1
2009-06-26 15:30:31.719 Connected to database 'mythconverg' at host:
localhost
2009-06-26 15:30:31.721 Closing DB connection named 'DBManager0'
2009-06-26 15:30:31.723 Connected to database 'mythconverg' at host:
localhost
2009-06-26 15:30:31.749 New DB connection, total: 2
2009-06-26 15:30:31.751 Connected to database 'mythconverg' at host:
localhost
2009-06-26 15:30:31.757 Current Schema Version: 1214
2009-06-26 15:30:31.775 Preview Error: Previewer file
'/var/lib/mythtv/recordings/Test/1030_20090626142900.mpg' is not valid.
2009-06-26 15:30:31.777 Preview Error: Run() file not local:
'/var/lib/mythtv/recordings/Test/1030_20090626142900.mpg'
2009-06-26 15:30:31.792 Preview Error: Preview process not ok.
fileinfo(/var/lib/mythtv/recordings/Test/1030_20090626142900.mpg.png)
exists: 0 readable: 0 size: 0
2009-06-26 15:31:23.103 JobQueue: Commercial Flagging Starting for In
Search of Bony recorded from channel 1030 at Fri Jun 26 14:29:00 2009
2009-06-26 15:31:23.273 Using runtime prefix = /usr
2009-06-26 15:31:23.277 Empty LocalHostName.
2009-06-26 15:31:23.278 Using localhost value of tv-server
2009-06-26 15:31:23.291 New DB connection, total: 1
2009-06-26 15:31:23.298 Connected to database 'mythconverg' at host:
localhost
2009-06-26 15:31:23.301 Closing DB connection named 'DBManager0'
2009-06-26 15:31:23.307 Connected to database 'mythconverg' at host:
localhost
2009-06-26 15:31:23.319 New DB connection, total: 2
2009-06-26 15:31:23.323 Connected to database 'mythconverg' at host:
localhost
2009-06-26 15:31:29.832
RingBuf(/var/lib/mythtv/recordings/Test/1030_20090626142900.mpg):
Invalid file (fd -1) when opening
'/var/lib/mythtv/recordings/Test/1030_20090626142900.mpg'.
2009-06-26 15:31:29.914 Connecting to backend server:
192.168.11.111:6543 (try 1 of 5)
2009-06-26 15:31:29.917 Using protocol version 40
2009-06-26 15:31:29.918 MainServer::HandleAnnounce Monitor
2009-06-26 15:31:29.926 adding: tv-server as a client (events: 0)
2009-06-26 15:31:29.935 MainServer::HandleAnnounce Monitor
2009-06-26 15:31:29.942 adding: tv-server as a client (events: 1)
2009-06-26 15:31:29.980 NVP::OpenFile(): Error, file not
found: /var/lib/mythtv/recordings/Test/1030_20090626142900.mpg
2009-06-26 15:31:30.156 Using runtime prefix = /usr
2009-06-26 15:31:30.161 Empty LocalHostName.
2009-06-26 15:31:30.162 Using localhost value of tv-server
2009-06-26 15:31:30.182 New DB connection, total: 1
2009-06-26 15:31:30.189 Connected to database 'mythconverg' at host:
localhost
2009-06-26 15:31:30.191 Closing DB connection named 'DBManager0'
2009-06-26 15:31:30.199 Connected to database 'mythconverg' at host:
localhost
2009-06-26 15:31:30.208 New DB connection, total: 2
2009-06-26 15:31:30.215 Connected to database 'mythconverg' at host:
localhost
2009-06-26 15:31:30.225 Current Schema Version: 1214
2009-06-26 15:31:30.239 Preview Error: Previewer file
'/var/lib/mythtv/recordings/Test/1030_20090626142900.mpg' is not valid.
2009-06-26 15:31:30.241 Preview Error: Run() file not local:
'/var/lib/mythtv/recordings/Test/1030_20090626142900.mpg'
2009-06-26 15:31:30.256 Preview Error: Preview process not ok.
fileinfo(/var/lib/mythtv/recordings/Test/1030_20090626142900.mpg.png)
exists: 0 readable: 0 size: 0
2009-06-26 15:31:35.902 AutoExpire: CalcParams(): Max required Free
Space: 1.0 GB w/freq: 15 min
2009-06-26 15:31:35.910 Expiring 956 MBytes for 1006 @ Thu Jun 25
15:00:00 2009 => New Idea TV
2009-06-26 15:31:35.976 ERROR when trying to delete
file: 
/var/lib/mythtv/recordings/1006_20090625151239.mpg/1006_20090625151239.mpg. 
File doesn't exist. Database metadata will not be removed.
2009-06-26 15:43:30.182 UPnpMedia: BuildMediaMap VIDEO scan starting
in :/var/lib/mythtv/videos:
2009-06-26 15:43:30.187 UPnpMedia: BuildMediaMap Done. Found 0 objects
2009-06-26 15:46:35.959 AutoExpire: CalcParams(): Max required Free
Space: 1.0 GB w/freq: 15 min
2009-06-26 15:46:35.966 Expiring 956 MBytes for 1006 @ Thu Jun 25
15:00:00 2009 => New Idea TV
2009-06-26 15:46:35.967 Expiring 564 MBytes for 1006 @ Thu Jun 25
15:30:00 2009 => All for Kids
2009-06-26 15:46:35.970 ERROR when trying to delete
file: 
/var/lib/mythtv/recordings/1006_20090625151239.mpg/1006_20090625151239.mpg. 
File doesn't exist. Database metadata will not be removed.
2009-06-26 15:46:35.996 ERROR when trying to delete

Thanks

Peter