<html>
<head>
<style><!--
.hmmessage P
{
margin:0px;
padding:0px
}
body.hmmessage
{
font-size: 10pt;
font-family:Tahoma
}
--></style>
</head>
<body class='hmmessage'>
<br><br>&gt; Date: Mon, 13 Dec 2010 10:12:37 +0000<br>&gt; From: knowledgejunkie@gmail.com<br>&gt; To: mythtv-users@mythtv.org<br>&gt; Subject: Re: [mythtv-users] Recording unavailable ,        zero length at incorrect time<br>&gt; <br>&gt; On 13 December 2010 09:48, Sean Salisbury &lt;sean_salisbury@hotmail.com&gt; wrote:<br>&gt; &gt; I recently updated my myth backend and frontends to 0.24 via mythbuntu repos<br>&gt; &gt; and have started experiencing unavailable recordings. Having looked through<br>&gt; &gt; the mailing lists and tried various suggested methods (use quick tuning,<br>&gt; &gt; delete cards in setup and start again) no solution has been found.<br>&gt; &gt;<br>&gt; &gt; To try and track down any errors I enabled backend logging to try and find<br>&gt; &gt; an obvious problem however I am now confused.<br>&gt; &gt;<br>&gt; &gt; Last night I had "Mad Max" scheduled to record at 22:00 and the backend logs<br>&gt; &gt; shows this nicely<br>&gt; &gt;<br>&gt; &gt;<br>&gt; &gt; 2010-12-12 21:57:30.342 Scheduler: Update next_record for 115<br>&gt; &gt; --- print list start ---<br>&gt; &gt; Title - Subtitle&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; Ch Station Day Start&nbsp; End&nbsp;&nbsp; S C I&nbsp; T N<br>&gt; &gt; Pri<br>&gt; &gt; Mad Max&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; 24 ITV4&nbsp;&nbsp;&nbsp; 12 22:00-23:55&nbsp; 1 3 1&nbsp; S 3 1<br>&gt; &gt; From Hell&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; 31 FIVE US 13 22:00-00:35&nbsp; 1 3 1&nbsp; S 3 1<br>&gt; &gt; Severance&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; 1 BBC ONE 17 23:30-01:00&nbsp; 1 3 1&nbsp; S 3 1<br>&gt; &gt; ---&nbsp; print list end&nbsp; ---<br>&gt; &gt; 2010-12-12 21:57:30.345 Scheduled 3 items in 0.1 = 0.00 match + 0.06 place<br>&gt; &gt;<br>&gt; &gt;<br>&gt; &gt; However the next mention of recording of Mad Max comes nearly 6 hours late<br>&gt; &gt; with the following from the backend log<br>&gt; &gt;<br>&gt; &gt;<br>&gt; &gt; 2010-12-13 03:47:08.356 TVRec(4): ASK_RECORDING 4 29 0 0<br>&gt; &gt; 2010-12-13 03:47:08.357 MythEvent: ASK_RECORDING 4 29 0 0<br>&gt; &gt; 2010-12-13 03:47:08.358 MythSocket(88e4240:41): write -&gt; 41 589<br>&gt; &gt; BACKEND_MESSAGE[]:[]ASK_RECORDING 4 29 0 0[]:[]Mad Max[]:[][]:[]N...<br>&gt; &gt; 2010-12-13 03:47:08.485 MythEvent: SYSTEM_EVENT REC_PENDING SECS -20829<br>&gt; &gt; CARDID 3 CHANID 1024 STARTTIME 2010-12-12T22:00:00 SENDER elliot<br>&gt; <br>&gt; For some reason, your Mad Max rule was set to start early by -20829<br>&gt; seconds (note the negative sign) - this has the effect of starting the<br>&gt; recording a little over 5:45 hours _late_ due to the negative timing,<br>&gt; which agrees with the actual recording.<br>&gt; <br>&gt; Make sure you have not got a ridiculous system-wide global pre-roll<br>&gt; setting or per-rule start early setting in effect. If these are both<br>&gt; set to zero, I'd run the optimize_db.pl script to check the integrity<br>&gt; of the MythTV database.<br>&gt; <br>&gt; Cheers,<br>&gt; Nick<br>&gt; <br>&gt; -- <br>&gt; Nick Morrott<br>&gt; <br>&gt; MythTV Official wiki: http://mythtv.org/wiki/<br>&gt; MythTV users list archive: http://www.gossamer-threads.com/lists/mythtv/users<br>&gt; <br>&gt; "An investment in knowledge always pays the best interest." - Benjamin Franklin<br>&gt; _______________________________________________<br>&gt; mythtv-users mailing list<br>&gt; mythtv-users@mythtv.org<br>&gt; http://mythtv.org/cgi-bin/mailman/listinfo/mythtv-users<br><br><br>After checking settings, running optimize script more failures occured last night, one recording starting 90 mins late and one not recording at all (or maybe not yet).<br><br>Is there an example of sql commands I could use to query the offset for all upcoming recordings to see if its affecting all recordings.<br><br>Also noticed the following mysql error before recording at the wrong time last night <br><br>2010-12-13 23:31:15.693 Error preparing query: SELECT value&nbsp; FROM codecparams WHERE profile = :WHEREPROFILE AND name = :WHERENAME<br>2010-12-13 23:31:15.693 Driver error was [2/2013]:<br>QMYSQL3: Unable to prepare statement<br>Database error was:<br>Lost connection to MySQL server during query<br><br>2010-12-13 23:31:15.695 MySQL reconnected successfully<br>2010-12-13 23:31:15.696 DB Error (SimpleDBStorage::Load()):<br>Query was:<br>SELECT value&nbsp; FROM codecparams WHERE profile = :WHEREPROFILE AND name = :WHERENAME<br>Bindings were:<br>:WHERENAME=recordingtype, :WHEREPROFILE=18<br>Driver error was [2/1064]:<br>QMYSQL: Unable to execute query<br>Database error was:<br>You have an error in your SQL syntax; check the manual that corresponds to your MySQL server version for the right syntax to use near ':WHEREPROFILE AND name = :WHERENAME' at line 1<br><br>QMYSQLResult::cleanup: unable to free statement handle<br>QMYSQLResult::cleanup: unable to free statement handle<br><br><br>So it looks like I've got a database/query/version problem somewhere, any suggestions as I will soon be in trouble for no recordings on the run up to christmas.<br><br>Many thanks<br><br>Sean<br><br><br>                                               </body>
</html>