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

when will tivo fix "program info will runout on 7-18-2011 connect to tivo" problem

Discussion in 'TiVo Series3 HDTV DVRs' started by tootal2, Jul 13, 2011.

  1. WO312

    WO312 Active Member

    1,373
    0
    Jan 24, 2003
    Finger...
    You are well beyond my technical capability. If your theory proves out, can we use this to do something to help our situation?? Or is this something Tivo could use to fix the situation. Not that they would. All new efforts seem to be focused on Premier only.
     
  2. chrisdfw

    chrisdfw New Member

    15
    0
    Dec 4, 2008
    Just to add some data points. This is happening for users with TWC, upgraded drives and tuning adaptors. I was able to get GC to update with only allowing the TiVo to talk on Wednesdays. I have now rebuilt the TiVo with a 2GB swap file to test. I am Dallas with TWC, with a moto TA and a TiVo HD with a 2TB drives
     
  3. Teeps

    Teeps Active Member

    1,665
    4
    Aug 16, 2001
    Torrance,Cal...
    Not entirely true, as my S3 had the problem with standard HDD, before installing a DVR_Dude 2tb drive.

    My S3 developed this problem, with standard HDD, about 1 month after the Cisco Tuning Adapter (Time Warner) was put online here in Torrance,CA.
    First sign of problem was the "running out of guide data" message.
    Then after several forced connections S03 error occurs.
     
  4. trackie999

    trackie999 New Member

    89
    0
    Sep 14, 2009
    Long rant/answer:
    I am getting tired of baby sitting my Tivos just so they can do their most basic function: Record a TV show.

    How would you feel if you got a news paper subscription and the paperboy wouldn't drop your paper off unless you're waiting at the end of your driveway at 5 o'clock in the morning?

    I don't know how many times I pulled up a show on Now Playing List and it was another show. Tivo thought it was the right show because it never loaded updated guide data.

    How do you feel when Tivo refuses to record anything because it has run out of guide data, yet when I pull up the guide I have days of guide data.

    I am just dumbstruck when I go into the To Do List and it shows that it is currently recording TV Show A. When I go to Live TV the Guide Info is for TV Show B. My Tivo either has a split personality or faulty software!

    This is a serious flaw that is not being acknowledged by Tivo. I have very little faith that they will attempt to resolve as all support is funneled into fixing their half baked premiere products.

    So, my options are:
    1) Get my cable company's DVR
    2) Build a MythTV Box
    3) Class action suit anyone?
    4) Figure it out myself

    TWC's DVRs came a long way. They are on par with Tivos on speed, but the UI is just puke.
    MythTV is looking better every year, it's now a viable solution with cable card & TA support. It's a lot of money to replicate what my Tivo should be doing.
    We could go to court, but even if we win (in 2-5 years) Tivo would probably just go bankrupt. (And my Tivo would still not work)

    I am going with Option #4.
    My primary goal is to come up with a workaround that is fully automated.
    We may or may not be able to identify the root cause of the issue, but we will be able to eliminate a bunch of potential causes and this can help Tivo with providing a permanent fix.

     
  5. trackie999

    trackie999 New Member

    89
    0
    Sep 14, 2009
    lrhorer: Please tell me you're having the same issue!
    Your insights would be greatly appreciated in resolving this matter!

     
  6. chrisdfw

    chrisdfw New Member

    15
    0
    Dec 4, 2008
    If its happening on a stock drive,Please keep after tivo support. Those of us with upgraded drives have no hope of getting them to fix it.
     
  7. WO312

    WO312 Active Member

    1,373
    0
    Jan 24, 2003
    Finger...
    If it is true that it only affects TWC, how could that be? What could be different in the TWC implementation of SDV compared to everybody else??

    And I'll never understand why this only affects what must be a relatively small number of people, or there would be an uproar.

    And why my 1 TB upgrade worked great, and the problem only started with the 2 TB upgrade.

    And why the sun rises in the east. (O.K. , I know that one. That's the way the sun circles the earth.)
     
  8. wtherrell

    wtherrell Member

    467
    1
    Dec 23, 2004
    Huntersville...
    Sun circles the earth?? From what century have you time-traveled here ??

    It is now October 10. My system shows last GC was August 10. 2 months-wow. I unplugged internet connection on Oct. 8 but no GC update yet. :(
     
  9. lrhorer

    lrhorer Active Member

    6,924
    0
    Aug 31, 2003
    San...
    I'm afraid not. I'm on TWC San Antonio with 3 Cisco TAs. Two S3 TiVos and one THD. All three have upgraded drives. (All three are also hacked.)

    Is there anyone out there with 2 or more TAs that is either suffering the problem on both / all or on only one?

    Edit: Ah, I see Trackie999 has the issue on three different TiVos. That would seem to rule out a hardware failure.
     
  10. lrhorer

    lrhorer Active Member

    6,924
    0
    Aug 31, 2003
    San...
    Well, as I understand it, the issue can be temporarily abated by taking down the internet connection to the Tivo for 3 or 4 days, right? One possibility would be to set up a proxy server for the TiVo's internet connections. The proxy can be set to automatically disable itself for 3 or 4 days at a time and then come back online for 5 or 6 days.
     
  11. lrhorer

    lrhorer Active Member

    6,924
    0
    Aug 31, 2003
    San...
    Oh, I can think of a number of different possibilities. One that jumps to mind is the number of SDV channels may be huge, especially when one considers duplicates. Perhaps some buffer somewhere is overflowing when the number of channels reported by the TA exceeds some magic number (2047, maybe?). This buffer overflow could then be corrupting the GC process in some very specific way.

    I'm not saying this is the case, it's just one possible scenario.

    Again, there are lots of possibilities. The specific lineup may have something to do with it, or a firmware rev. With that in mind, are any of you running the same version I am:

    [​IMG]

    I would tend to suspect coincidence on that venue. Any chance you still have your 1T laying around for testing purposes?
     
  12. trackie999

    trackie999 New Member

    89
    0
    Sep 14, 2009
    I am trying the firewalled Tivo approach. My first attempt failed when my router allowed Tivo to connect after 77 hours. (I was out of town and assumed it would be done by then)

    My current attempt has been going for almost 5 days now...

    Take a look at the info from my current run:
    Code:
    Oct  6 05:06:48 (none) DbGc[12083]: fEnableIndexingM=1 fEnableGcM=1
    Oct  6 05:10:19 (none) dbcache[12083]: Creating dbcache in program dbgc-mcp, size = 10
    Oct  6 05:10:20 (none) DbGc[12083]: SetState(): eVirgin --> eBefored
    Oct  6 05:10:20 (none) DbGc[12083]: SetState(): eBefored --> eMarkingActive
    Oct  6 07:30:31 (none) DbGc[12083]: SetState(): eMarkingActive --> eMarkingReactive
    Oct  6 07:30:40 (none) DbGc[12083]: SetState(): eMarkingReactive --> eTryingToInstallGcMask
    Oct  6 07:30:40 (none) DbGc[12083]: SetState(): eTryingToInstallGcMask --> eInstallingGcMask
    Oct  6 07:30:43 (none) DbGc[12083]: SetState(): eInstallingGcMask --> eSweeping
    Oct  6 12:48:51 (none) DbGc::LogMemoryUsage[12083]: LogMemoryUsage( After DbShowingIndexer::VisitAllStationDays )
    Oct  6 12:48:51 (none) TmkMempool[12083]: STAT dbgc-mcp: blocks=3648 bytes=1069912 bytesMax=1563840 unusedBytes=3093328 chunks=31 chunkBytes=974336 freeChunks=2 freeChunkBytes=1536 maxFreeChunk=2955848
    Oct  6 12:49:00 (none) DbGc::LogMemoryUsage[12083]: LogMemoryUsage( pShowingWriterM )
    Oct  6 12:49:00 (none) TmkMempool[12083]: STAT dbgc-mcp: blocks=3648 bytes=1069912 bytesMax=1563840 unusedBytes=3093328 chunks=31 chunkBytes=974336 freeChunks=2 freeChunkBytes=1536 maxFreeChunk=2955848
    Oct  6 12:49:18 (none) DbGc::LogMemoryUsage[12083]: LogMemoryUsage( pProgramWriterM )
    Oct  6 12:49:18 (none) TmkMempool[12083]: STAT dbgc-mcp: blocks=3648 bytes=1069912 bytesMax=1563840 unusedBytes=3093328 chunks=31 chunkBytes=974336 freeChunks=2 freeChunkBytes=1536 maxFreeChunk=2955848
    Oct  6 12:49:18 (none) DbGc::LogMemoryUsage[12083]: LogMemoryUsage( pGenreWriterM )
    Oct  6 12:49:18 (none) TmkMempool[12083]: STAT dbgc-mcp: blocks=3648 bytes=1069912 bytesMax=1563840 unusedBytes=3093328 chunks=31 chunkBytes=974336 freeChunks=2 freeChunkBytes=1536 maxFreeChunk=2955848
    Oct  6 12:49:25 (none) DbGc::LogMemoryUsage[12083]: LogMemoryUsage( pSeriesIndexerM )
    Oct  6 12:49:25 (none) TmkMempool[12083]: STAT dbgc-mcp: blocks=3648 bytes=1069912 bytesMax=1563840 unusedBytes=3093328 chunks=31 chunkBytes=974336 freeChunks=2 freeChunkBytes=1536 maxFreeChunk=2955848
    Oct  6 12:49:25 (none) DbGc::LogMemoryUsage[12083]: LogMemoryUsage( After running pShowingIndexer )
    Oct  6 12:49:25 (none) TmkMempool[12083]: STAT dbgc-mcp: blocks=3648 bytes=1069912 bytesMax=1563840 unusedBytes=3093328 chunks=31 chunkBytes=974336 freeChunks=2 freeChunkBytes=1536 maxFreeChunk=2955848
    Oct  6 12:49:30 (none) DbGc[12083]: DbGc::AfterIndexersFSuccessful = 1
    Oct  6 12:49:30 (none) DbGc[12083]: DbGc::StoreCompletionInDb: 15253/46170 (0x0)
    Oct  6 12:49:30 (none) DbGc[12083]: DbGc::Mark succeeded, sweep soon
    Oct  6 12:49:30 (none) TmkActivityStats[12083]: DbGcBackgroundAction-->(Pri:0 Time Sample: 7 h 42 min)
    Oct  6 12:49:30 (none) TmkActivityStats[12113]:   DbGcClientActivity-->(Pri:0 Time Sample: 9 h 12 min)
    Oct  6 18:49:58 (none) TmkActivityStats[14904]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  7 00:50:27 (none) TmkActivityStats[16192]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  7 06:50:55 (none) TmkActivityStats[17658]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  7 12:51:24 (none) TmkActivityStats[19019]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  8 00:52:20 (none) TmkActivityStats[21601]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  8 02:44:28 (none) DbGc[22006]: TrioRubbished id:1609861 s:2
    Oct  8 02:44:29 (none) DbGc[22006]: TrioRubbished id:1609862 s:2
    Oct  8 02:44:30 (none) DbGc[21969]: TrioRubbished id:1609863 s:2
    Oct  8 02:44:31 (none) DbGc[21969]: TrioRubbished id:1609864 s:2
    Oct  8 02:44:37 (none) DbGc[22006]: TrioRubbished id:1632297 s:2
    Oct  8 02:44:55 (none) DbGc[22006]: TrioRubbished id:1838060 s:2
    Oct  8 02:45:31 (none) DbGc[22030]: TrioRubbished id:1920572 s:2
    Oct  8 02:46:07 (none) DbGc[22006]: TrioRubbished id:2055187 s:2
    Oct  8 02:47:16 (none) DbGc[22037]: TrioRubbished id:2063708 s:2
    Oct  8 02:47:17 (none) DbGc[22037]: TrioRubbished id:2068875 s:2
    Oct  8 02:47:17 (none) DbGc[22037]: TrioRubbished id:2073805 s:2
    Oct  8 02:47:18 (none) DbGc[22037]: TrioRubbished id:2077229 s:2
    Oct  8 02:47:22 (none) DbGc[22032]: TrioRubbished id:2083744 s:2
    Oct  8 02:47:23 (none) DbGc[22032]: TrioRubbished id:2083745 s:2
    Oct  8 02:47:23 (none) DbGc[22032]: TrioRubbished id:2083746 s:2
    Oct  8 02:47:24 (none) DbGc[22032]: TrioRubbished id:2083747 s:2
    Oct  8 02:47:24 (none) DbGc[22032]: TrioRubbished id:2083748 s:2
    Oct  8 02:47:25 (none) DbGc[22032]: TrioRubbished id:2083749 s:2
    Oct  8 02:47:25 (none) DbGc[22032]: TrioRubbished id:2083750 s:2
    Oct  8 02:47:26 (none) DbGc[22032]: TrioRubbished id:2083751 s:2
    Oct  8 02:47:29 (none) DbGc[22032]: TrioRubbished id:2083752 s:2
    Oct  8 02:47:29 (none) DbGc[22032]: TrioRubbished id:2083753 s:2
    Oct  8 02:47:29 (none) DbGc[22032]: TrioRubbished id:2083754 s:2
    Oct  8 02:47:30 (none) DbGc[22032]: TrioRubbished id:2083758 s:2
    Oct  8 02:47:30 (none) DbGc[22032]: TrioRubbished id:2083759 s:2
    Oct  8 02:47:30 (none) DbGc[22032]: TrioRubbished id:2083760 s:2
    Oct  8 02:47:31 (none) DbGc[22032]: TrioRubbished id:2083761 s:2
    Oct  8 02:47:31 (none) DbGc[22032]: TrioRubbished id:2083762 s:2
    Oct  8 02:47:32 (none) DbGc[22032]: TrioRubbished id:2083763 s:2
    Oct  8 02:47:32 (none) DbGc[22032]: TrioRubbished id:2083764 s:2
    Oct  8 02:47:32 (none) DbGc[22032]: TrioRubbished id:2083779 s:2
    Oct  8 06:52:49 (none) TmkActivityStats[22912]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  8 12:53:17 (none) TmkActivityStats[24248]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  8 18:32:47 (none) DbGc[25516]: TrioRubbished id:2162512 s:2
    Oct  8 18:32:48 (none) DbGc[25516]: TrioRubbished id:2162513 s:2
    Oct  8 18:32:50 (none) DbGc[25502]: TrioRubbished id:2162514 s:2
    Oct  8 18:53:46 (none) TmkActivityStats[25596]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  9 00:54:14 (none) TmkActivityStats[26857]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  9 06:54:43 (none) TmkActivityStats[28108]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  9 12:55:11 (none) TmkActivityStats[29424]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  9 13:44:43 (none) DbGc[29612]: TrioRubbished id:2234524 s:2
    Oct  9 13:55:33 (none) DbGc[29642]: TrioRubbished id:2266835 s:2
    Oct  9 13:55:37 (none) DbGc[29643]: TrioRubbished id:2266887 s:2
    Oct  9 13:55:46 (none) DbGc[29642]: TrioRubbished id:2267045 s:2
    Oct  9 15:56:07 (none) DbGc[30115]: TrioRubbished id:2267171 s:2
    Oct  9 18:55:40 (none) TmkActivityStats[30675]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 10 00:56:08 (none) TmkActivityStats[31948]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 10 02:09:55 (none) DbGc[32208]: TrioRubbished id:2267172 s:2
    Oct 10 02:09:59 (none) DbGc[32208]: TrioRubbished id:2267285 s:2
    Oct 10 06:56:36 (none) TmkActivityStats[884]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 10 12:57:05 (none) TmkActivityStats[2226]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 10 18:57:33 (none) TmkActivityStats[3386]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 11 00:58:02 (none) TmkActivityStats[4720]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 11 06:58:30 (none) TmkActivityStats[6067]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    
    
    You can see Oct 6 12:49:30 (none) DbGc[12083]: DbGc::StoreCompletionInDb: 15253/46170 (0x0)
    This date matches System Information -> Indexing date (-4 hr offset from UTC)

    Should it take 10 hours and 13 minutes to go from 2267171 to 2267172? (mfs_dumpobj info attached)
    Oct 9 15:56:07 (none) DbGc[30115]: TrioRubbished id:2267171 s:2
    Oct 10 02:09:55 (none) DbGc[32208]: TrioRubbished id:2267172 s:2

    It's been sweeping ever since, you can see that it maybe picked up ~60 objects over a 4 day period.

    I tried to increase dbgc's (and children's) priorities thinking that it was cpu starved but it made no difference.

    My guess is maybe guide records in mfs apps are locked and dbgc is idling away waiting for a record to be freed. (I have no idea if there are record locks in mfs) I also observed very little cpu activity and strace was also pretty boring to watch...

    Do you think routerplus would provide any insight?

    I attached my TA's sw info below.

    ... Also, I have 3 TivoHDs and all of them are having this issue. I purchased them years apart, so we can rule out bad hardware.


     

    Attached Files:

  13. WO312

    WO312 Active Member

    1,373
    0
    Jan 24, 2003
    Finger...
    Hmmm, guess my humor goes right over your head. We MUST be from different centuries.
     
  14. WO312

    WO312 Active Member

    1,373
    0
    Jan 24, 2003
    Finger...
    I DO still have my 1 TB . Not a bad idea. Maybe I will do the experiment, but it's a real pain to open my Tivo as I have it sandwiched pretty well. I think I will continue with my current experiment first.
     
  15. wtherrell

    wtherrell Member

    467
    1
    Dec 23, 2004
    Huntersville...
    No, I got it. Alas, sometimes I just can't control my smart-ass reflex.;)
     
  16. pninen

    pninen New Member

    103
    0
    Jun 25, 2003
    Well I recently had the S03 error, and I fixed it by doing the channel line up fix, and it worked for me. It rebooted twice and then it was all good.

    PS: People have asked if this only happens on drives 1TB and above. The answer is no. It happened on my 750GB drive. TiVo told me the drive was bad so I bought a brand new 2TB drive from weaknees, and had the problem again in just a few weeks. Now I keep it under control by unplugging the internet connection for >48 hours every now and then.

    Big pain in the ass.
     
  17. Teeps

    Teeps Active Member

    1,665
    4
    Aug 16, 2001
    Torrance,Cal...
    GC on my S3 (2tb), as of this morning, is still showing Saturday 8OCT2011.

    GC had self updated on 8OCT, from a previous GC date that was "forced" by an internet disconnection (only) of about 3 days.
     
  18. trackie999

    trackie999 New Member

    89
    0
    Sep 14, 2009
    Good news everyone!

    After 5 days 16 hours and 40.5 minutes my dbgc completed :)

    System Information Screen shows:
    Program Information To: Wednesday, October 19, 2011 (Oct 6 + 13)
    GC: Tuesday, October 11 12:11PM

    Code:
    Oct  6 05:06:48 (none) DbGc[12083]: fEnableIndexingM=1 fEnableGcM=1
    Oct  6 05:10:19 (none) dbcache[12083]: Creating dbcache in program dbgc-mcp, size = 10
    Oct  6 05:10:20 (none) DbGc[12083]: SetState(): eVirgin --> eBefored
    Oct  6 05:10:20 (none) DbGc[12083]: SetState(): eBefored --> eMarkingActive
    Oct  6 07:30:31 (none) DbGc[12083]: SetState(): eMarkingActive --> eMarkingReactive
    Oct  6 07:30:40 (none) DbGc[12083]: SetState(): eMarkingReactive --> eTryingToInstallGcMask
    Oct  6 07:30:40 (none) DbGc[12083]: SetState(): eTryingToInstallGcMask --> eInstallingGcMask
    Oct  6 07:30:43 (none) DbGc[12083]: SetState(): eInstallingGcMask --> eSweeping
    Oct  6 12:48:51 (none) DbGc::LogMemoryUsage[12083]: LogMemoryUsage( After DbShowingIndexer::VisitAllStationDays )
    Oct  6 12:48:51 (none) TmkMempool[12083]: STAT dbgc-mcp: blocks=3648 bytes=1069912 bytesMax=1563840 unusedBytes=3093328 chunks=31 chunkBytes=974336 freeChunks=2 freeChunkBytes=1536 maxFreeChunk=2955848
    Oct  6 12:49:00 (none) DbGc::LogMemoryUsage[12083]: LogMemoryUsage( pShowingWriterM )
    Oct  6 12:49:00 (none) TmkMempool[12083]: STAT dbgc-mcp: blocks=3648 bytes=1069912 bytesMax=1563840 unusedBytes=3093328 chunks=31 chunkBytes=974336 freeChunks=2 freeChunkBytes=1536 maxFreeChunk=2955848
    Oct  6 12:49:18 (none) DbGc::LogMemoryUsage[12083]: LogMemoryUsage( pProgramWriterM )
    Oct  6 12:49:18 (none) TmkMempool[12083]: STAT dbgc-mcp: blocks=3648 bytes=1069912 bytesMax=1563840 unusedBytes=3093328 chunks=31 chunkBytes=974336 freeChunks=2 freeChunkBytes=1536 maxFreeChunk=2955848
    Oct  6 12:49:18 (none) DbGc::LogMemoryUsage[12083]: LogMemoryUsage( pGenreWriterM )
    Oct  6 12:49:18 (none) TmkMempool[12083]: STAT dbgc-mcp: blocks=3648 bytes=1069912 bytesMax=1563840 unusedBytes=3093328 chunks=31 chunkBytes=974336 freeChunks=2 freeChunkBytes=1536 maxFreeChunk=2955848
    Oct  6 12:49:25 (none) DbGc::LogMemoryUsage[12083]: LogMemoryUsage( pSeriesIndexerM )
    Oct  6 12:49:25 (none) TmkMempool[12083]: STAT dbgc-mcp: blocks=3648 bytes=1069912 bytesMax=1563840 unusedBytes=3093328 chunks=31 chunkBytes=974336 freeChunks=2 freeChunkBytes=1536 maxFreeChunk=2955848
    Oct  6 12:49:25 (none) DbGc::LogMemoryUsage[12083]: LogMemoryUsage( After running pShowingIndexer )
    Oct  6 12:49:25 (none) TmkMempool[12083]: STAT dbgc-mcp: blocks=3648 bytes=1069912 bytesMax=1563840 unusedBytes=3093328 chunks=31 chunkBytes=974336 freeChunks=2 freeChunkBytes=1536 maxFreeChunk=2955848
    Oct  6 12:49:30 (none) DbGc[12083]: DbGc::AfterIndexersFSuccessful = 1
    Oct  6 12:49:30 (none) DbGc[12083]: DbGc::StoreCompletionInDb: 15253/46170 (0x0)
    Oct  6 12:49:30 (none) DbGc[12083]: DbGc::Mark succeeded, sweep soon
    Oct  6 12:49:30 (none) TmkActivityStats[12083]: DbGcBackgroundAction-->(Pri:0 Time Sample: 7 h 42 min)
    Oct  6 12:49:30 (none) TmkActivityStats[12113]:   DbGcClientActivity-->(Pri:0 Time Sample: 9 h 12 min)
    Oct  6 18:49:58 (none) TmkActivityStats[14904]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  7 00:50:27 (none) TmkActivityStats[16192]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  7 06:50:55 (none) TmkActivityStats[17658]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  7 12:51:24 (none) TmkActivityStats[19019]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  8 00:52:20 (none) TmkActivityStats[21601]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  8 02:44:28 (none) DbGc[22006]: TrioRubbished id:1609861 s:2
    Oct  8 02:44:29 (none) DbGc[22006]: TrioRubbished id:1609862 s:2
    Oct  8 02:44:30 (none) DbGc[21969]: TrioRubbished id:1609863 s:2
    Oct  8 02:44:31 (none) DbGc[21969]: TrioRubbished id:1609864 s:2
    Oct  8 02:44:37 (none) DbGc[22006]: TrioRubbished id:1632297 s:2
    Oct  8 02:44:55 (none) DbGc[22006]: TrioRubbished id:1838060 s:2
    Oct  8 02:45:31 (none) DbGc[22030]: TrioRubbished id:1920572 s:2
    Oct  8 02:46:07 (none) DbGc[22006]: TrioRubbished id:2055187 s:2
    Oct  8 02:47:16 (none) DbGc[22037]: TrioRubbished id:2063708 s:2
    Oct  8 02:47:17 (none) DbGc[22037]: TrioRubbished id:2068875 s:2
    Oct  8 02:47:17 (none) DbGc[22037]: TrioRubbished id:2073805 s:2
    Oct  8 02:47:18 (none) DbGc[22037]: TrioRubbished id:2077229 s:2
    Oct  8 02:47:22 (none) DbGc[22032]: TrioRubbished id:2083744 s:2
    Oct  8 02:47:23 (none) DbGc[22032]: TrioRubbished id:2083745 s:2
    Oct  8 02:47:23 (none) DbGc[22032]: TrioRubbished id:2083746 s:2
    Oct  8 02:47:24 (none) DbGc[22032]: TrioRubbished id:2083747 s:2
    Oct  8 02:47:24 (none) DbGc[22032]: TrioRubbished id:2083748 s:2
    Oct  8 02:47:25 (none) DbGc[22032]: TrioRubbished id:2083749 s:2
    Oct  8 02:47:25 (none) DbGc[22032]: TrioRubbished id:2083750 s:2
    Oct  8 02:47:26 (none) DbGc[22032]: TrioRubbished id:2083751 s:2
    Oct  8 02:47:29 (none) DbGc[22032]: TrioRubbished id:2083752 s:2
    Oct  8 02:47:29 (none) DbGc[22032]: TrioRubbished id:2083753 s:2
    Oct  8 02:47:29 (none) DbGc[22032]: TrioRubbished id:2083754 s:2
    Oct  8 02:47:30 (none) DbGc[22032]: TrioRubbished id:2083758 s:2
    Oct  8 02:47:30 (none) DbGc[22032]: TrioRubbished id:2083759 s:2
    Oct  8 02:47:30 (none) DbGc[22032]: TrioRubbished id:2083760 s:2
    Oct  8 02:47:31 (none) DbGc[22032]: TrioRubbished id:2083761 s:2
    Oct  8 02:47:31 (none) DbGc[22032]: TrioRubbished id:2083762 s:2
    Oct  8 02:47:32 (none) DbGc[22032]: TrioRubbished id:2083763 s:2
    Oct  8 02:47:32 (none) DbGc[22032]: TrioRubbished id:2083764 s:2
    Oct  8 02:47:32 (none) DbGc[22032]: TrioRubbished id:2083779 s:2
    Oct  8 06:52:49 (none) TmkActivityStats[22912]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  8 12:53:17 (none) TmkActivityStats[24248]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  8 18:32:47 (none) DbGc[25516]: TrioRubbished id:2162512 s:2
    Oct  8 18:32:48 (none) DbGc[25516]: TrioRubbished id:2162513 s:2
    Oct  8 18:32:50 (none) DbGc[25502]: TrioRubbished id:2162514 s:2
    Oct  8 18:53:46 (none) TmkActivityStats[25596]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  9 00:54:14 (none) TmkActivityStats[26857]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  9 06:54:43 (none) TmkActivityStats[28108]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  9 12:55:11 (none) TmkActivityStats[29424]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct  9 13:44:43 (none) DbGc[29612]: TrioRubbished id:2234524 s:2
    Oct  9 13:55:33 (none) DbGc[29642]: TrioRubbished id:2266835 s:2
    Oct  9 13:55:37 (none) DbGc[29643]: TrioRubbished id:2266887 s:2
    Oct  9 13:55:46 (none) DbGc[29642]: TrioRubbished id:2267045 s:2
    Oct  9 15:56:07 (none) DbGc[30115]: TrioRubbished id:2267171 s:2
    Oct  9 18:55:40 (none) TmkActivityStats[30675]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 10 00:56:08 (none) TmkActivityStats[31948]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 10 02:09:55 (none) DbGc[32208]: TrioRubbished id:2267172 s:2
    Oct 10 02:09:59 (none) DbGc[32208]: TrioRubbished id:2267285 s:2
    Oct 10 06:56:36 (none) TmkActivityStats[884]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 10 12:57:05 (none) TmkActivityStats[2226]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 10 18:57:33 (none) TmkActivityStats[3386]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 11 00:58:02 (none) TmkActivityStats[4720]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 11 06:58:30 (none) TmkActivityStats[6067]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 11 12:58:59 (none) TmkActivityStats[7338]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    Oct 11 16:11:34 (none) DbGc[8008]: Sweep done (eSucceeded) -- rescheduling marking in 61200 sec
    Oct 11 16:11:34 (none) DbGc[8008]: SetState(): eSweeping --> eFinished
    Oct 11 16:11:34 (none) DbGc[8008]: shouldTrioRubbishM 6696 56 65
    Oct 11 16:11:34 (none) DbGcStats[8008]: ---------------
    Oct 11 16:11:34 (none) DbGcStats[8008]: DbGC statistics
    Oct 11 16:11:34 (none) DbGcStats[8008]: {
    Oct 11 16:11:34 (none) DbGcStats[8008]:           Type       #InMemory   #Queued     #Collected
    Oct 11 16:11:34 (none) DbGcStats[8008]:           ~~~~       ~~~~~~~~~   ~~~~~~~~~~  ~~~~~~~~~~
    Oct 11 16:11:34 (none) DbGcStats[8008]:             Program     36972      17152       2198
    Oct 11 16:11:34 (none) DbGcStats[8008]:              Series     16823          0        327
    Oct 11 16:11:34 (none) DbGcStats[8008]:             Station      1123          0          8
    Oct 11 16:11:34 (none) DbGcStats[8008]:          StationDay       225      10373          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:             LoopSet        10          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:            SwSystem         0          1          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:            SwModule         6          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:           Recording         0       4552        400
    Oct 11 16:11:34 (none) DbGcStats[8008]:            Showcase         0         10          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:               Image       514          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:             Headend         0         14          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:       ResourceGroup         0          1          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:               Setup         0          4          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:       ComponentCode       753          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:           Component       381          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:          SeasonPass         7         83          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:          PostalCode         2          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:        PrefsElement       146          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:               Genre       204          2          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:            IrFormat        84          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:         IrBlastData       192          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:           VideoClip        20          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:        IrTivoFormat         1        164          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:         MessageItem         4          2          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:             DataSet       136          0         27
    Oct 11 16:11:34 (none) DbGcStats[8008]:            AreaCode         0          1          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:       DatabaseState         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:        MyWorldState         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:           LogoGroup         0          4          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:               Table         0          7          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:      CaptureRequest        10          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:             AuxInfo        13          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:          MediaState         0          1          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:      AvalancheState         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:            MenuItem        11        103          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:      LeadGeneration         0          8          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:        TuikResource      1721          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:    TuikResourceHolder         0       1803          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:    TuikResourceGroup        28         15          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:            AvConfig         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:       GeneralConfig         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:         PhoneConfig         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:      LocationConfig         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:           ArmConfig         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:    CorrelationPartHolder         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:       ServiceConfig         0          1          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:     LiveCacheConfig         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:    LocksLimitsState         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:       EncryptionKey        15         13          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:           NetConfig         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:            UserInfo         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:           DvdConfig         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:        StaticConfig         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:           SpigotMap         0          4          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:    ModelInformation         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:    ClosedCaptioningState         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:      ProviderConfig         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:        AdContentSet         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:            UnionTag        13          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:    NetworkRecordRequest         2          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:      RecorderConfig         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:    FrontPanelConfig         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:      MessageManager         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:           SecureLog         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:       SecureLogPart         1          0          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:    PodChannelHolder         0          9          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:    RegionRatingTable         0          1          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:           Cablecard         0          1          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:           SyncState         0          2          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:        ServerBackup         0         10          0
    Oct 11 16:11:34 (none) DbGcStats[8008]:           ~~~~~~~~~   ~~~~~~~~   ~~~~~~~~~~  ~~~~~~~~~~
    Oct 11 16:11:34 (none) DbGcStats[8008]:               total     59440      34341       2960
    Oct 11 16:11:34 (none) DbGcStats[8008]:  
    Oct 11 16:11:34 (none) DbGcStats[8008]:   nRubbish=854  nTrioRubbish( recent=1 stale=0 made=41 ) 
    Oct 11 16:11:34 (none) DbGcStats[8008]:   indexed=1 GCed=1
    Oct 11 16:11:34 (none) DbGcStats[8008]:   fFoundDanglingRef=0
    Oct 11 16:11:34 (none) DbGcStats[8008]:   State:   eFinished
    Oct 11 16:11:34 (none) DbGcStats[8008]:   Outcome: eSucceeded
    Oct 11 16:11:34 (none) DbGcStats[8008]:   102402/262144 inodes used
    Oct 11 16:11:34 (none) DbGcStats[8008]:  
    Oct 11 16:11:34 (none) DbGcStats[8008]:   Attempts To Install GC Mask: 1
    Oct 11 16:11:34 (none) DbGcStats[8008]:   ActiveLockConflicts during MarkActive: 0
    Oct 11 16:11:34 (none) DbGcStats[8008]:   ActiveLockConflicts during MarkActive (BurpQueue): 0
    Oct 11 16:11:34 (none) DbGcStats[8008]:   ActiveLockConflicts during MarkReactive (BurpQueue): 0
    Oct 11 16:11:34 (none) DbGcStats[8008]:   ActiveLockConflicts while sweeping: 0
    Oct 11 16:11:34 (none) DbGcStats[8008]:   nNonMaskableFsIdsM while installing GC Mask: 12791
    Oct 11 16:11:34 (none) DbGcStats[8008]:  
    Oct 11 16:11:34 (none) DbGcStats[8008]:   msec MarkActive:      8400504 (140.0 mins)
    Oct 11 16:11:34 (none) DbGcStats[8008]:   msec MarkReactive:       8652 (0.1 mins)
    Oct 11 16:11:34 (none) DbGcStats[8008]:   msec Indexing:       19408346 (323.5 mins)
    Oct 11 16:11:34 (none) DbGcStats[8008]:   msec InstallGcMask:      2829 (0.0 mins)
    Oct 11 16:11:34 (none) DbGcStats[8008]:   msec SweepOne:         962887 (16.0 mins)
    Oct 11 16:11:34 (none) DbGcStats[8008]:   sec  Sweep State:      463251 (7,720.9 mins)
    Oct 11 16:11:34 (none) DbGcStats[8008]:  
    Oct 11 16:11:34 (none) DbGcStats[8008]:          before App Blocks 34.1769% free (223536/654056)
    Oct 11 16:11:34 (none) DbGcStats[8008]:          before Media Blocks 2.39975% free (58961920/2457006080)
    Oct 11 16:11:34 (none) DbGcStats[8008]:             now App Blocks 34.6784% free (226816/654056)
    Oct 11 16:11:34 (none) DbGcStats[8008]:             now Media Blocks 0.865209% free (21258240/2457006080)
    Oct 11 16:11:34 (none) DbGcStats[8008]:  
    Oct 11 16:11:34 (none) DbGcStats[8008]: }
    Oct 11 16:11:34 (none) DbGcStats[8008]: ---------------
    Oct 11 16:11:35 (none) DbGc[8008]: DbGc::StoreCompletionInDb: 15258/58294 (0x0)
    Oct 11 18:59:27 (none) TmkActivityStats[8523]:   DbGcClientActivity-->(Pri:0 Time Sample:  6 h 0 min)
    As you can see vast majority of the time was spent in sweep state.


    Also tverr is full of these messages:
    Oct 11 05:59:54 (none) MakeDeltas[5861]: line 565 s = 0x30001
    Oct 11 06:00:05 (none) last message repeated 40 times
    Oct 11 06:00:10 (none) AckSyncId[5861]: line 784 s = 0x30001
    Oct 11 06:00:21 (none) last message repeated 40 times

    Process ID changes every time so these processes must be dying for some reason. Any idea what s=0x30001 represents?
    Edit: I found ackSyncId string in tivoapp @ 01EA9E20 and MakeDeltas @ 01EA9D90
    lrhorer: would you attempt to ID purpose?

    Router will allow this Tivo to connect at midnight tonight, will see how long it will take to complete next dbgc run.
     
  19. wtherrell

    wtherrell Member

    467
    1
    Dec 23, 2004
    Huntersville...
    Well, unplugging the internet connection did not seem to be doing any good. My last GC was right after I had to clear and delete last August to correct SO3 error. Two months ago.Today GC was still on August 10. So today I did the Channel fix and the GC finally updated to today, Oct. 11. Maybe two months of garbage was too much for the Tivo HD to handle in 3 days or so of being disconnected from the internet. I am going to watch the updates and when it gets 4 days behind the program guide connection, I will try the internet disconnect again for a couple of days.
     
  20. Eccles

    Eccles Mostly harmless

    350
    0
    Dec 27, 2001
    Austin, TX
    Likewise, I used the Channel Lineup fix the last time my HD hit the S03 problem, and I had no problems at all.
     

Share This Page