1. This site uses cookies. By continuing to use this site, you are agreeing to our use of cookies. Learn More.

Automatic soft padding running on the TiVo

Discussion in 'TiVo Series 1 - UK' started by sanderton, Oct 8, 2003.

Thread Status:
Not open for further replies.
  1. May 1, 2004 #781 of 1197
    sanderton

    sanderton TiVoer since 11/2000

    6,341
    0
    Jan 4, 2002
    1 - yes, that's because if you add start padding Tivo's internal clash mechanism will cancel the scheduled recording, not the suggestion. There may be a workaround, but I've not found it.

    2 & 3 - I don't believe these are anything to do with Endpad. If you added end padding which then overlapped with another show, TiVo would cancel the second show, not start it late. Several people have requested that as a function, but I've not added it, honest! Sounds like an IR realted problem to me. If it happens again please post the log just to be sure.
     
  2. May 1, 2004 #782 of 1197
    pmk

    pmk New Member

    388
    0
    Jan 1, 2002
    It is using the internal tuner so its not IR related.

    Never had 2 or 3 problems before installing EndPad but as the numbers being affected change the length of the programme and/or match up directly with start/end padding I thought it was worth reporting.

    It is always 5 mins of "black" bar at the start of a programme this would be a massive coincidence that the TiVo has failed to lock onto the signal for exactly the same time, for the same duration and 5 minutes of End Padding of the previous recording have gone missing matching up to the 5 minutes of the "black" bar.

    If it becomes a huge issue I will get the hard drive out so I can get at the log just thought I would post in case anyone else has similar problems.

    TIA/HTH
     
  3. May 1, 2004 #783 of 1197
    sanderton

    sanderton TiVoer since 11/2000

    6,341
    0
    Jan 4, 2002
    Huge co-incidence I think it is though!

    When the end padding is added Endpad does not touch the start padding of the subsequent recording; that is done several minutes later in a completely separate operation. If the end padding had through some bug been set to 15 mins even though there was a programme after, that programme would be cancelled by TiVo, not started late.

    The first recording is the correct length - it should indeed be 59 mins - so the end padding addition is working fine. The code which adds the start padding is in blissful ignorance of the Endpad end padding setting; it reads the end point of the previous show directly from the TiVo database, and it's coded to do nothing unless the gap is >0.

    Having said that, I doubt there are many folks using 15 mins end padding, so the pool of people to report problems is a bit low.
     
  4. May 2, 2004 #784 of 1197
    pmk

    pmk New Member

    388
    0
    Jan 1, 2002
    Yes it probably is or with my luck my TiVo is in a bad mood but it happened again last night:

    Friday Night With Jonathan Ross 01:55->02:50
    CD:UK 03:00->03:50

    Jonathan Ross was fine (EndPad did its job correctly - nothing could save me from BBC1 running 25mins late however due to a concert or something!)

    However CD:UK had the first 5 minutes of the programme lost with a black area on the time bar. Again exactly 5 minutes, at the start of a programme and the last 5 minutes of the 15 minutes of padding lost in the ether somewhere (that should have been CD:UK anyway).

    Interestingly the scenario is exactly the same as the one reported yesterday except at different hours of the day. I think these programmes are on at the same time next week so I will see if it does it again.

    [Saturday mornings - Dawsons Creek (10:00-10:50 - maybe 10:55 next week so probably not an exact test, Five) and TOTP Saturday (11:00-12:00, BBC1]

    TIA/HTH
     
  5. May 2, 2004 #785 of 1197
    sanderton

    sanderton TiVoer since 11/2000

    6,341
    0
    Jan 4, 2002
    Unless your TiVo is psychic and knew that EndPad was set for 15 mins of padding even though only 10 were added, I don't see how the two things can be connected.
     
  6. May 3, 2004 #786 of 1197
    pmk

    pmk New Member

    388
    0
    Jan 1, 2002
    Thanks clearly it must be something on my set-up that is does not like which is a bit strange or it just does not like s 2 and e 15.

    I had another recording with 5 minutes blank at the beginning others were fine from last night.
     
  7. May 17, 2004 #787 of 1197
    pmk

    pmk New Member

    388
    0
    Jan 1, 2002
    A quick update on my problem I have further information.

    For the past couple of weeks the problem has hardly occurred probably had a couple of recordings where the first 5 minutes have not recorded but I have hardly changed any recordings in my To Do / Conflict Manager lists. Over the past 3/4 days I have made several corrections however.

    Over the past couple of days I have had several recordings that have had the first 5 minutes missed off (i.e. there is space in the time bar but it is black - the recording starts playing at 5 mins).

    On a couple of occassions my TiVo has totally stopped responding to the remote control. After approx 5 minutes any cached IR signals are executed and TiVo comes back to life and if I check the currently recording program it has the first 5 minutes missed.

    The best way I can describe it it its like the TiVo is stuck in a loop using all/remaining CPU time. i.e. if it happens when a recording is playing it still plays but no remote control commands are executed.

    I know I appear to be the only one experiencing these problems but as everything was fine before I installed EndPad and it is always affecting start of programmes which EndPad has control over to me it is pointing to an incompatibility with EndPad on my TiVo? It is not like it is missing 5 minutes in the middle of recordings it is always the first 5 minutes.

    I do have 140 SPs, and I am using 2 mins start padding and 15 mins end padding perhaps its related to when TiVo is doing some indexing or the To Do list changes or something and perhaps EndPad is not getting enough CPU time.

    Interestingly my TiVo has started doing its phone call around the midnight time again over the past few days and problems have started to occur again. When it has been phoning around the 06:00-08:00 time (i.e. last couple of weeks) not noticed many problems as not many programmes are recorded around that time.

    Any ideas would be greatly appreciated.

    [It may also affect the end of recordings when EndPad is adding extra time but it never black lines the time bar - it just does not add the correct amount of extra minutes - I will try to find a pattern, see previous posts]

    TIA
     
  8. May 18, 2004 #788 of 1197
    sanderton

    sanderton TiVoer since 11/2000

    6,341
    0
    Jan 4, 2002
    Are you running one of the red dot removers?

    It all sounds like an IR related problem, and EndPad does nothing with regard to IR or sending channel changes. EndPad does not have 2control" over these programmes al ity does is add start and end padding in exactly the same way as if you did it manually yourself; the triggering of the recording start etc is entirely under TiVo standard software control.

    You seem to be the only person experiencing this, however you do have a very high end padding setting so your usage of EndPad is a little unusual in that respect. Most people will rarely if ever have the situation where Endpad adds a reduced amount of padding - it's typically all or nothing.

    Could you post a section of EndPad log around the time of one of the missing 5 mins recordings, and also the tvlog from the same time period.
     
  9. May 18, 2004 #789 of 1197
    pmk

    pmk New Member

    388
    0
    Jan 1, 2002
    Thanks for replying.

    Its a bog standard TiVo set-up really. The HD was upgraded to 120GB over a year ago and I record off aerial channels 1-5 and an old Philips Freebox box for others. No Red Dot remover etc. I have checked my other remotes and no buttons seem to be depressed. When the problem happens the TV remote works fine.

    I can only get to the logs by getting the case off the TiVo and setting it all up in my PC I think.

    Is it possible to display the EndPad logs via a Backdoor command?
    Are EndPad logs kept for a few days?
    Could you post which directory the logs should be found in?

    EndPad is great and I would not do without it I just wished I could track down this 5 minute problem. I was thinking I could try to change settings to s 2 and e 5 and see if the problem continues but as I record many programmes from early morning BBC/ITV the 15 mins end padding is a necessity.

    TIA
     
  10. May 18, 2004 #790 of 1197
    sanderton

    sanderton TiVoer since 11/2000

    6,341
    0
    Jan 4, 2002
    Suggest you set your TiVo up so that you can connect to it wit a serial cable, saves hoiking disks out.

    Logs are in /var/log and are kept for up to 48 hrs.
     
  11. May 18, 2004 #791 of 1197
    doubledrat

    doubledrat should know better

    208
    0
    Aug 24, 2001
    Attleborough...
    off topic, but any idea why additional logs (eg endpad) don't show up with the backdoor log viewing?
     
  12. May 18, 2004 #792 of 1197
    sanderton

    sanderton TiVoer since 11/2000

    6,341
    0
    Jan 4, 2002
    The log file names must be hardwired in the backdoor code.
     
  13. May 18, 2004 #793 of 1197
    pmk

    pmk New Member

    388
    0
    Jan 1, 2002
    Yes I think I have no option - the benefits far out way the disadvantages.

    Can you recommend a guide for getting the serial cable working and anything else that needs installing to send/retrieve and setup files off the TiVo?

    Cheers
     
  14. May 18, 2004 #794 of 1197
    P63B

    P63B New Member

    32
    0
    Oct 23, 2003
    New HD with EndPad?

    At some point in the future, I'm going to want to replace my 40 + 30 GB drives with a single larger drive (120 GB probably). My Tivo is not networked, and I don't really want to get into this, but having Endpad functionality would certainly be useful.

    Does anyone supply replacement drives with Tivo software and Endpad (with default padding lengths added for particular channels)? Or is this a non-starter because Endpad needs a networked Tivo to be managed/tweaked/to view logs etc?

    Many thanks in advance.
     
  15. May 18, 2004 #795 of 1197
    sanderton

    sanderton TiVoer since 11/2000

    6,341
    0
    Jan 4, 2002
    No-one offers it as a paid service, because that would be against the terms and conditions of use for EndPad.

    If you have the disks "out" to do the upgrade it is relatively straightforward to add EndPad in at that point, assuming you are reasonably Linux literate. You don't need to be networked to install or run it - indeed you don't even need to take the disks out (there are guides further up the thread).
     
  16. May 22, 2004 #796 of 1197
    pmk

    pmk New Member

    388
    0
    Jan 1, 2002
    Okay I took the plunge and bought a TurboNET card so I can easy get access to the log files in the hope it may point to what the problem is. Everything is installed and I have my first report of the black bar problem.

    Dawsons Creek 10:00-11:00 (may have been 10:55 however)
    Details Of Recording Duration 1:05
    Timebar indicates 0:00-0:59
    (Duration 5/6 minutes too long that matches up with 5 minutes of black in next recording)

    Top Of The Pops Saturday 11:00-12:00
    Recording indicates Duration 1:10
    Timebar indicates 00:05-1:15
    (i.e. first 5 minutes of black at beginning)

    My EndPad logs follow I hope that it allows you to help me out with my problem?

    Thanks.

    Saturday 07:27:00 : Woken up
    Saturday 07:27:00 : No recording in progress
    Saturday 07:27:00 : Next recording is {The Saturday Show} on BBC1NW (Suggestion) at 07:30
    Saturday 07:27:00 : No end padding required:
    Saturday 07:27:00 : No recording in progress
    Saturday 07:27:00 : Next end padding setting due 08:55
    Saturday 07:27:00 : Added 120 seconds start padding to {The Saturday Show}
    Saturday 07:27:00 : Next start padding setting due 08:55
    Saturday 07:27:00 : Next wake up will be 07:57
    Sleeping, checking every minute for kill signal...............................
    Saturday 07:57:00 : Woken up
    Saturday 07:57:00 : Recording {The Saturday Show} on BBC1NW (Suggestion) now
    Saturday 07:57:00 : Recording Scheduled End Time: 09:00
    Saturday 07:57:00 : Next recording is {Dawson's Creek} on FIVE at 09:00
    Saturday 07:57:00 : Not time to add end padding yet
    Saturday 07:57:00 : Next end padding setting due 08:55
    Saturday 07:57:00 : Not time to add start padding yet
    Saturday 07:57:00 : Next start padding setting due 08:57
    Saturday 07:57:00 : Next wake up will be 08:27
    Sleeping, checking every minute for kill signal...............................
    Saturday 08:27:00 : Woken up
    Saturday 08:27:00 : Recording {The Saturday Show} on BBC1NW (Suggestion) now
    Saturday 08:27:00 : Recording Scheduled End Time: 09:00
    Saturday 08:27:00 : Next recording is {Dawson's Creek} on FIVE at 09:00
    Saturday 08:27:00 : Not time to add end padding yet
    Saturday 08:27:00 : Next end padding setting due 08:55
    Saturday 08:27:00 : Not time to add start padding yet
    Saturday 08:27:00 : Next start padding setting due 08:57
    Saturday 08:27:00 : Next wake up will be 08:55
    Sleeping, checking every minute for kill signal.............................
    Saturday 08:55:00 : Woken up
    Saturday 08:55:01 : Recording {The Saturday Show} on BBC1NW (Suggestion) now
    Saturday 08:55:01 : Recording Scheduled End Time: 09:00
    Saturday 08:55:01 : Next recording is {Dawson's Creek} on FIVE at 09:00
    Saturday 08:55:01 : Gap to next programme is 0 seconds
    Saturday 08:55:01 : Can't add end padding to {The Saturday Show}
    Saturday 08:55:01 : Next end padding setting due 09:50
    Saturday 08:55:01 : Not time to add start padding yet
    Saturday 08:55:01 : Next start padding setting due 08:57
    Saturday 08:55:01 : Next wake up will be 08:57
    Sleeping, checking every minute for kill signal...
    Saturday 08:57:00 : Woken up
    Saturday 08:57:00 : Recording {The Saturday Show} on BBC1NW (Suggestion) now
    Saturday 08:57:00 : Recording Scheduled End Time: 09:00
    Saturday 08:57:00 : Next recording is {Dawson's Creek} on FIVE at 09:00
    Saturday 08:57:00 : Gap to next programme is 0 seconds
    Saturday 08:57:00 : Can't add end padding to {The Saturday Show}
    Saturday 08:57:00 : Next end padding setting due 09:50
    Saturday 08:57:00 : Gap to previous recording is 0 seconds
    Saturday 08:57:00 : Can't add start padding to {Dawson's Creek}
    Saturday 08:57:00 : Next start padding setting due 09:50
    Saturday 08:57:00 : Next wake up will be 09:27
    Sleeping, checking every minute for kill signal...............................
    Saturday 09:27:00 : Woken up
    Saturday 09:27:01 : Recording {Dawson's Creek} on FIVE now
    Saturday 09:27:01 : Recording Scheduled End Time: 09:55
    Saturday 09:27:01 : Next recording is {Top of the Pops Saturday} on BBC1NW at 10:00
    Saturday 09:27:01 : Not time to add end padding yet
    Saturday 09:27:01 : Next end padding setting due 09:50
    Saturday 09:27:01 : Not time to add start padding yet
    Saturday 09:27:01 : Next start padding setting due 09:57
    Saturday 09:27:01 : Next wake up will be 09:50
    Sleeping, checking every minute for kill signal........................
    Saturday 09:50:00 : Woken up
    Saturday 09:50:00 : Recording {Dawson's Creek} on FIVE now
    Saturday 09:50:00 : Recording Scheduled End Time: 09:55
    Saturday 09:50:00 : Next recording is {Top of the Pops Saturday} on BBC1NW at 10:00
    Saturday 09:50:00 : Gap to next programme is 300 seconds
    Saturday 09:50:00 : Added 298 seconds end padding to {Dawson's Creek}
    Saturday 09:50:00 : Next end padding setting due 10:55
    Saturday 09:50:00 : Not time to add start padding yet
    Saturday 09:50:00 : Next start padding setting due 09:57
    Saturday 09:50:00 : Next wake up will be 09:57
    Sleeping, checking every minute for kill signal........
    Saturday 09:57:00 : Woken up
    Saturday 09:57:00 : Recording {Dawson's Creek} on FIVE now
    Saturday 09:57:00 : Recording Scheduled End Time: 09:59
    Saturday 09:57:00 : Next recording is {Top of the Pops Saturday} on BBC1NW at 10:00
    Saturday 09:57:00 : Gap to next programme is 300 seconds
    Saturday 09:57:01 : Added 298 seconds end padding to {Dawson's Creek}
    Saturday 09:57:01 : Next end padding setting due 10:55
    Saturday 09:57:01 : Gap to previous recording is 2 seconds
    Saturday 09:57:01 : Can't add start padding to {Top of the Pops Saturday}
    Saturday 09:57:01 : Next start padding setting due 10:55
    Saturday 09:57:01 : Next wake up will be 10:27
    Sleeping, checking every minute for kill signal...............................
    Saturday 10:27:01 : Woken up
    Saturday 10:27:01 : Recording {Top of the Pops Saturday} on BBC1NW now
    Saturday 10:27:01 : Recording Scheduled End Time: 11:00
    Saturday 10:27:01 : Next recording is Xcalibur on FIVE (Suggestion) at 11:25
    Saturday 10:27:01 : Not time to add end padding yet
    Saturday 10:27:01 : Next end padding setting due 10:55
    Saturday 10:27:01 : Not time to add start padding yet
    Saturday 10:27:01 : Next start padding setting due 11:22
    Saturday 10:27:01 : Next wake up will be 10:55
    Sleeping, checking every minute for kill signal.............................
    Saturday 10:55:00 : Woken up
    Saturday 10:55:00 : Recording {Top of the Pops Saturday} on BBC1NW now
    Saturday 10:55:00 : Recording Scheduled End Time: 11:00
    Saturday 10:55:00 : Next recording is Xcalibur on FIVE (Suggestion) at 11:25
    Saturday 10:55:00 : Gap to next programme is 1500 seconds
    Saturday 10:55:00 : Added 900 seconds end padding to {Top of the Pops Saturday}
    Saturday 10:55:00 : Next end padding setting due 11:55
    Saturday 10:55:00 : Not time to add start padding yet
    Saturday 10:55:00 : Next start padding setting due 11:22
    Saturday 10:55:00 : Next wake up will be 11:22
    Sleeping, checking every minute for kill signal............................
    Saturday 11:22:00 : Woken up
    Saturday 11:22:00 : No recording in progress
    Saturday 11:22:00 : Next recording is Xcalibur on FIVE (Suggestion) at 11:25
    Saturday 11:22:00 : No end padding required:
    Saturday 11:22:00 : No recording in progress
    Saturday 11:22:00 : Next end padding setting due 11:55
    Saturday 11:22:00 : Added 120 seconds start padding to Xcalibur
    Saturday 11:22:00 : Next start padding setting due 11:55
    Saturday 11:22:00 : Next wake up will be 11:52
    Sleeping, checking every minute for kill signal...............................
    Saturday 11:52:00 : Woken up
    Saturday 11:52:01 : Recording Xcalibur on FIVE (Suggestion) now
    Saturday 11:52:01 : Recording Scheduled End Time: 12:00
    Saturday 11:52:01 : Next recording is {Even Stevens} on CBBC at 14:00
    Saturday 11:52:01 : Not time to add end padding yet
    Saturday 11:52:01 : Next end padding setting due 11:55
    Saturday 11:52:01 : Not time to add start padding yet
    Saturday 11:52:01 : Next start padding setting due 13:57
    Saturday 11:52:01 : Next wake up will be 11:55
    Sleeping, checking every minute for kill signal....
    Saturday 11:55:00 : Woken up
    Saturday 11:55:00 : Recording Xcalibur on FIVE (Suggestion) now
    Saturday 11:55:00 : Recording Scheduled End Time: 12:00
    Saturday 11:55:00 : Next recording is {Even Stevens} on CBBC at 14:00
    Saturday 11:55:00 : Gap to next programme is 7200 seconds
    Saturday 11:55:00 : Added 900 seconds end padding to Xcalibur
    Saturday 11:55:00 : Next end padding setting due 14:45
    Saturday 11:55:00 : Not time to add start padding yet
    Saturday 11:55:00 : Next start padding setting due 13:57
    Saturday 11:55:00 : Next wake up will be 12:25
     
  17. May 22, 2004 #797 of 1197
    shanew

    shanew UK TiVo Tweaker

    336
    0
    Apr 11, 2003
    Baldock, UK
    I installed endpad on my parents TiVo which doesn't have network access.

    With the knowledge that there wouldn't be a new tivo version in the UK (ie the OS partition wouldn't change) but occasionally the /var partition can be rebuilt I wrote a simple bash script which was called at the end of /etc/rc.d/rc.sysinit

    It checked for the existance of the endpad.tcl file and the /var/hack directory. If they didn't exist it would create /var/hack then copy endpad.tcl from /etc/rc.d to /var/hack directory then run it with the default options.

    I checked it worked by getting the script to copy the file on the first boot up.

    Living a long way from my parents I didn't want to go back and reinstall it when the var partition was rebuilt.

    Hope this helps

    Shane.
     
  18. May 23, 2004 #798 of 1197
    sanderton

    sanderton TiVoer since 11/2000

    6,341
    0
    Jan 4, 2002
    The logs show EndPad doing what its supposed to do I'm afraid.

    It doesn't touch the TOTP recording at all, and adds 2 seconds under 5 mins padding to the Dawsons Creek, taking its stop time to 2 seconds short of 11am - ie, no overlap.

    I can't see anything different to how it normally operates.

    Are the two recordings still on your TIVo?

    If they are, can you use TivoWeb to extract the details please; browse to the recordings in Now Playing, then change the URL from "showing" to "object" and post the output here.

    It would also be useful if you could post the section of tvlog from the 15 mins around the time of the oddity (remember the log is GMT). Yesterdays logs are prefaced with an O.
     
  19. May 23, 2004 #799 of 1197
    pmk

    pmk New Member

    388
    0
    Jan 1, 2002
    Thanks for replying Stuart.

    >The logs show EndPad doing what its supposed to do I'm afraid.
    Yes I thought it would. Probably pure coincidence this 5mins black bar problem started to happen after I installed EndPad. I guess I can easily try 5 mins end padding and see if the problem disappears but at a last resort.

    You probably noticed but when looking at the details of the Dawsons Creek recording in the Now Playing it says Duration 1:05 - i.e. this should have been only 1hour the extra 5 minutes on this recording is what has created the 5 mins of black bar on TOTP Sat but this 5 mins does not exist in the Dawson Creek recording either (i.e. only 59 mins in timebar).

    >Are the two recordings still on your TIVo?

    Unfortunately both are not anymore but the next time it happens I will post what you suggest. It usually happens to a programme I have manually forced to record due to a conflict. e.g. TOTP Saturday but not always.

    >tvlog from the 15 mins around the time of the oddity

    See below. The 5 minutes of black in the time line was at the beginning of TOTP Sat that was 11:00 so I am guessing you need the log times around 10:00. The "VERY_LATE" entries look like they may be pointing to something with the milliseconds equating to around 5 mins?

    May 22 09:45:20 (none) Recorder[144]: Adding check schedule task
    May 22 09:45:21 (none) Scheduler[146]: Done ProcessProgramSources
    May 22 09:45:21 (none) Scheduler[146]: Done Checking normal priority Capture Requests
    May 22 09:45:21 (none) Scheduler[146]: FUZZY SOURCE
    May 22 09:45:56 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 09:46:24 (none) Recorder[144]: Adding check schedule task
    May 22 09:46:34 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 09:46:51 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 09:47:08 (none) Scheduler[146]: Done ProcessSpecialProgramSources
    May 22 09:47:08 (none) Scheduler[146]: DataChanged:Completed
    May 22 09:47:36 (none) Scheduler[146]: Mempool highwater 150108
    May 22 09:47:36 (none) Scheduler[146]: DISK SPACE: Total: 103698 Live cache: 1277 Overhead: 396
    May 22 09:47:36 (none) Scheduler[146]: TIVO CLIPS DISK SPACE: Total: 9765 Overhead: 42
    May 22 09:47:38 (none) Scheduler[146]: Done Checking high priority Capture Requests
    May 22 09:49:10 (none) Recorder[144]: Adding check schedule task
    May 22 09:49:19 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 09:50:00 (none) Recorder[144]: Adding check schedule task
    May 22 09:50:05 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 09:50:31 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 09:50:45 (none) Recorder[144]: Adding check schedule task
    May 22 09:50:53 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 09:51:33 (none) Recorder[144]: Adding check schedule task
    May 22 09:51:41 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 09:52:34 (none) Recorder[144]: Adding check schedule task
    May 22 09:52:35 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 09:52:45 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 09:53:03 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 09:54:10 (none) Recorder[144]: Adding check schedule task
    May 22 09:54:18 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1105
    May 22 09:55:00 (none) VideoGuts[148]: InputState processing took 660 msecs
    May 22 09:55:00 (none) VideoGuts[148]: Total Heartbeat processing took 661 msecs
    May 22 09:55:14 (none) Recorder[144]: found showing 426553/78 (program 229943)
    May 22 09:55:15 (none) Recorder[144]: Adding check schedule task
    May 22 09:55:18 (none) Recorder[144]: IncrementalAlloc rec 433209
    May 22 09:55:18 (none) Recorder[144]: Allocating new recording file. Size 61440
    May 22 09:55:18 (none) Recorder[144]: Two small clips in a row!
    May 22 09:55:18 (none) Recorder[144]: Live cache size 1671168
    May 22 09:55:18 (none) Recorder[144]: Recording Id 433209 size 726016
    May 22 09:55:18 (none) Recorder[144]: User recording 104515968 free 22912
    May 22 09:55:18 (none) Recorder[144]: TivoClip total 10000000 free 9712256
    May 22 09:55:18 (none) Recorder[144]: DeleteSomething fUserSaidOk=1
    May 22 09:55:18 (none) Recorder[144]: allocate: 14:00000000:40b1b990 rec 433209
    May 22 09:55:19 (none) Recorder[144]: live cache: 22:00000000:00000000
    May 22 09:55:27 (none) Recorder[144]: candidate: 1:00000000:00000000 430605
    May 22 09:55:27 (none) Recorder[144]: deleting rec 430605
    May 22 09:55:27 (none) Recorder[144]: Abr--Now: rec 430605
    May 22 09:55:28 (none) Recorder[144]: Live cache size 1671168
    May 22 09:55:28 (none) Recorder[144]: Recording Id 433209 size 726016
    May 22 09:55:28 (none) Recorder[144]: User recording 104515968 free 414080
    May 22 09:55:28 (none) Recorder[144]: TivoClip total 10000000 free 9712256
    May 22 09:55:28 (none) Recorder[144]: Allocated stream Id 439523, size 61440
    May 22 09:55:28 (none) mediamgr[144]: AddRecordFile input#0
    May 22 09:55:28 (none) Deadline[144]: Task CheckSchedule started late by 3465ms
    May 22 09:55:28 (none) Deadline[144]: Tasks run 5300
    May 22 09:55:28 (none) Deadline[144]: Existed for 0:63611:194612
    May 22 09:55:28 (none) Deadline[144]: Overhead time 0:1:574264
    May 22 09:55:43 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 09:56:06 (none) Recorder[144]: Adding check schedule task
    May 22 09:56:11 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 09:56:18 (none) TmkTransform::Trace[143]: ClipCache 0x300113cc Secondary Reset
    May 22 09:56:18 (none) ClipCache[139]: 0x300113cc in Entry(), thread 139
    May 22 09:57:01 (none) Recorder[144]: Adding check schedule task
    May 22 09:57:06 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 09:57:23 (none) TmkMediaswitch::Trace[143]: Drift = 4
    May 22 09:57:35 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 09:58:03 (none) Recorder[144]: Adding check schedule task
    May 22 09:58:14 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 09:58:30 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 09:58:51 (none) Recorder[144]: Adding check schedule task
    May 22 09:58:56 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 09:58:58 (none) Recorder[144]: StopPending rec 433209
    May 22 09:58:58 (none) VideoGuts[144]: RecordingStopPending 266
    May 22 09:59:06 (none) Recorder[144]: Adding check schedule task
    May 22 09:59:06 (none) Recorder[144]: CheckSchedule: creating action for: rec 435565
    May 22 09:59:06 (none) Recorder[144]: Creating normal action
    May 22 09:59:06 (none) Recorder[144]: Creating normal action
    May 22 09:59:06 (none) Recorder[144]: CheckSchedule: next creation at 12560 41046: rec 439518
    May 22 09:59:06 (none) Recorder[144]: InitialAction rec 435565
    May 22 09:59:06 (none) Recorder[144]: InitialAction RecId 435565
    May 22 09:59:06 (none) Recorder[144]: Item 435565: estimate kb per second: 219
    May 22 09:59:07 (none) Recorder[144]: RequestTuner rec 435565
    May 22 09:59:07 (none) TvVideoPolicy[144]: RecordingTunerRequest on 267
    May 22 09:59:07 (none) VideoGuts[144]: RecordingTunerResult ALLOWED 267
    May 22 09:59:07 (none) Recorder[144]: Got EVT_VIDEO_SESSION 3
    May 22 09:59:07 (none) Recorder[144]: AquiredTunerLock event received
    May 22 09:59:46 (none) Recorder[144]: Adding check schedule task
    May 22 09:59:48 (none) Recorder[144]: Allocate rec 435565
    May 22 09:59:48 (none) Recorder[144]: Allocating new recording file. Size 524288
    May 22 09:59:48 (none) Recorder[144]: Live cache size 1671168
    May 22 09:59:48 (none) Recorder[144]: Recording Id 433209 size 787456
    May 22 09:59:48 (none) Recorder[144]: Recording Id 435565 size 0
    May 22 09:59:48 (none) Recorder[144]: User recording 104515968 free 352640
    May 22 09:59:48 (none) Recorder[144]: TivoClip total 10000000 free 9712256
    May 22 09:59:48 (none) Recorder[144]: DeleteSomething fUserSaidOk=1
    May 22 09:59:48 (none) Recorder[144]: allocate: 12:00002328:40b1c7a0 rec 435565
    May 22 09:59:48 (none) Recorder[144]: live cache: 22:00000000:00000000
    May 22 09:59:51 (none) mempool[138]: MyWorld block: 1191kB/1650kB chunk: 264kB/733kB unused: 1259kB search: 1 (size=3731424)
    May 22 09:59:51 (none) mempool[138]: Osd block: 96kB/111kB chunk: 947kB/1244kB unused: 1623kB search: 0 (size=3051488)
    May 22 09:59:56 (none) Recorder[144]: candidate: 1:00000000:00000000 430613
    May 22 09:59:56 (none) Recorder[144]: deleting rec 430613
    May 22 09:59:56 (none) Recorder[144]: Abr--Now: rec 430613
    May 22 09:59:57 (none) Recorder[144]: Live cache size 1671168
    May 22 09:59:57 (none) Recorder[144]: Recording Id 433209 size 787456
    May 22 09:59:57 (none) Recorder[144]: Recording Id 435565 size 0
    May 22 09:59:57 (none) Recorder[144]: User recording 104515968 free 962944
    May 22 09:59:57 (none) Recorder[144]: TivoClip total 10000000 free 9712256
    May 22 09:59:57 (none) Recorder[144]: Allocated stream Id 439524, size 524288
    May 22 09:59:57 (none) Recorder[144]: Allocate success! Adding START task
    May 22 09:59:58 (none) Recorder[144]: Stop rec 433209
    May 22 09:59:58 (none) VideoGuts[144]: StopRecording 266
    May 22 09:59:58 (none) InputState[144]: StartCaching 0
    May 22 09:59:58 (none) TmkMediaswitch::Trace[143]: using CBR, bitRate=5960000, maxBitrate=5960000
    May 22 09:59:58 (none) Recorder[144]: Start rec 435565
    May 22 09:59:58 (none) VideoGuts[144]: StartRecording 267
    May 22 09:59:58 (none) InputState[144]: StartRecording 0
    May 22 09:59:58 (none) TmkTransform::Trace[143]: ClipCache 0x30017140 Secondary Reset
    May 22 09:59:58 (none) ClipCache[139]: 0x30017140 in Entry(), thread 139
    May 22 10:04:42 (none) TmkMediaswitch::Trace[143]: using CBR, bitRate=1700000, maxBitrate=1700000
    May 22 10:04:42 (none) VideoGuts[148]: Starved of heartbeats for 289 secs
    May 22 10:04:42 (none) TmkTransform::Trace[143]: ClipCache 0x300176e4 Secondary Reset
    May 22 10:04:42 (none) ClipCache[139]: 0x300176e4 in Entry(), thread 139
    May 22 10:04:42 (none) Deadline[144]: VERY_LATE:Task Start ran late by 283879ms
    May 22 10:04:42 (none) Deadline[144]: VERY_LATE:Task CheckSchedule started late by 292062ms
    May 22 10:04:42 (none) VideoGuts[148]: InputState processing took 499 msecs
    May 22 10:04:42 (none) VideoGuts[148]: Total Heartbeat processing took 499 msecs
    May 22 10:04:43 (none) Deadline[144]: VERY_LATE:Task CheckSchedule ran late by 282340ms
    May 22 10:04:43 (none) Deadline[144]: VERY_LATE:Task LiveCache started late by 290061ms
    May 22 10:04:43 (none) Deadline[144]: VERY_LATE:Task LiveCache ran late by 288062ms
    May 22 10:04:43 (none) Deadline[144]: VERY_LATE:Task IncrementalAlloc started late by 265853ms
    May 22 10:04:43 (none) Deadline[144]: VERY_LATE:Task IncrementalAlloc ran late by 255854ms
    May 22 10:04:43 (none) Recorder[144]: Deadline scheduler took 290469 milliseconds
    May 22 10:04:43 (none) Recorder[144]: Heartbeat took 290 seconds
    May 22 10:04:43 (none) Recorder[144]: SecondaryEventCallback took 290 seconds
    May 22 10:04:43 (none) Recorder[144]: Got EVT_VIDEO_SESSION 9
    May 22 10:04:43 (none) Recorder[144]: Got EVT_VIDEO_SESSION 15
    May 22 10:04:43 (none) Recorder[144]: ScheduleCleanup rec 433209
    May 22 10:04:43 (none) Recorder[144]: AquiredTunerLock event received
    May 22 10:04:43 (none) Recorder[144]: Got EVT_VIDEO_SESSION 9
    May 22 10:04:43 (none) Recorder[144]: Got EVT_VIDEO_SESSION 10
    May 22 10:04:43 (none) Deadline[144]: Task Cleanup started late by 3082ms
    May 22 10:04:43 (none) Recorder[144]: Cleanup rec 433209
    May 22 10:04:43 (none) Recorder[144]: FRecordingEmpty: probing...
    May 22 10:04:43 (none) Deadline[144]: Task Cleanup ran late by 564ms
    May 22 10:04:43 (none) Recorder[144]: Adding check schedule task
    May 22 10:04:53 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:05:10 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:05:10 (none) Recorder[144]: found showing 426567/72 (program 435005)
    May 22 10:05:11 (none) Recorder[144]: Adding check schedule task
    May 22 10:05:21 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:05:30 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 10:05:39 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:06:37 (none) Recorder[144]: Adding check schedule task
    May 22 10:06:47 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:07:03 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:07:31 (none) Recorder[144]: Adding check schedule task
    May 22 10:07:42 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:07:59 (none) Recorder[144]: Adding check schedule task
    May 22 10:08:27 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:08:59 (none) Recorder[144]: Adding check schedule task
    May 22 10:09:09 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:09:23 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:09:57 (none) Recorder[144]: Adding check schedule task
    May 22 10:10:08 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:10:18 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:10:46 (none) Recorder[144]: Adding check schedule task
    May 22 10:10:57 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 10:11:14 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:11:41 (none) Recorder[144]: Adding check schedule task
    May 22 10:11:42 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 10:11:52 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 10:12:10 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:12:41 (none) Recorder[144]: Adding check schedule task
    May 22 10:12:52 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:13:06 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:13:23 (none) Recorder[144]: Adding check schedule task
    May 22 10:13:31 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 10:14:14 (none) Recorder[144]: Adding check schedule task
    May 22 10:14:24 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1018
    May 22 10:14:58 (none) Recorder[144]: Adding check schedule task
    May 22 10:15:05 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 10:15:42 (none) Recorder[144]: Adding check schedule task
    May 22 10:15:50 (none) RecordingList[146]: Err errTmBackgroundHoldoff at line 1026
    May 22 10:16:46 (none) Deadline[144]: Tasks run 5400
     
  20. May 23, 2004 #800 of 1197
    sanderton

    sanderton TiVoer since 11/2000

    6,341
    0
    Jan 4, 2002
    Hmm. The tvlog shows Dawons Creek ending successfully, the Live TV buffer starting up successfully at 2 seconds to 11 and then everything locking up for nearly 5 mins.

    That 2 seconds gap doesn't happen on most setups, as the typical usage of EndPad is 5 mins or less. It's possible I guess that doing three swiches in 2 seconds is locking up myworld somehow.
     
Thread Status:
Not open for further replies.

Share This Page