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

WO312 · 10-09-2011, 01:43 PM

Quote:

Originally Posted by ravingfans

thanks Trackie, The hyperlink you inserted has a lot of clutter and doesn't load. Could you type the link out?

You have to delete the "(remove this)" from the link and it will load.

Teeps · 10-09-2011, 02:23 PM

Quote:

Originally Posted by WO312

Total BS. I have proven that my system runs great with the tuning adapter disconnected.

Which is the point, as I pointed "them" to this thread as proof.

Since I last did the channel fix and disconnect from the internet. The GC updated after about 3 days. Then hung on that date. I gave it 3 days, disconnected the internet. On the third day GC updated. The last GC update (result from internet disconnect) last Wed appeared stuck, but yesterday evening I noticed that GC HAD updated on its own!!!!

So the GC clock is ticking again 8Oct2011.

lrhorer · 10-09-2011, 07:05 PM

Quote:

Originally Posted by MC Hammer

The only theory I can come up with is that channel map updates from the TA adversely affect the box's ability to run GC. However, not knowing where on the hard drive the map updates are stored, I can't offer any quantifiable information that would support this theory.

They aren't. The Channel Map is stored within the CableCards. The TA overwrites this (I'm not certain if it is overwritten in memory or on the CableCards) dynamically.

lrhorer · 10-09-2011, 07:10 PM

Quote:

Originally Posted by WO312

Total BS. I have proven that my system runs great with the tuning adapter disconnected.

Agreed. TA issues aside, any DB corruption of the schedule data should be over-written with the next phone home. I find it unlikely any persistent issues would be related to a hard drive problem, unless accompanied by very significant other issues. The guide data and the setup data are all kept in the MFS areas.

lrhorer · 10-09-2011, 07:12 PM

Quote:

Originally Posted by WO312

So it appears any tuning adapter is an issue. I still haven't seen anybody from a non-TWC system say they have the problem.

OTOH, I know for a fact most people on TWC systems are not having the issue. Certainly I am not, and I have 3 TWC TAs.

MC Hammer · 10-09-2011, 07:15 PM

Quote:

Originally Posted by lrhorer

They aren't. The Channel Map is stored within the CableCards. The TA overwrites this (I'm not certain if it is overwritten in memory or on the CableCards) dynamically.

Incorrect. The map is provided real-time by the card but a copy of the most recent version is stored on the hard drive.

lrhorer · 10-09-2011, 08:30 PM

Quote:

Originally Posted by MC Hammer

Incorrect. The map is provided real-time by the card but a copy of the most recent version is stored on the hard drive.

I don't see where:

Code:

Directory listing of /
Name					Type	Id	Date	 	Time	Size
AdContent				tyDir	49569	10/03/11	22:17	4
Anchor					tyDir	314739	10/07/11	02:59	2712
AreaCode				tyDir	7499	06/14/07	17:08	16
AuxInfo					tyDir	54110	02/09/07	16:38	68
CaptureRequest				tyDir	9679	10/20/06	08:44	92
Clips					tyDir	7286	06/20/07	05:00	64
Component				tyDir	4912	07/27/06	19:07	40
Config					tyDir	7372	07/27/06	19:42	56
CorrelationIndexPart			tyDir	15994	10/04/07	08:22	20
DataSet					tyDir	4690	10/09/11	08:09	1408
DataSetVersion				tyDir	4691	10/09/11	08:09	1500
Database				tyDir	4683	10/09/11	15:39	4144
DiskUsed				tyDir	15	09/12/11	22:57	44
Drm					tyDir	1833514	12/05/08 	02:21	16
Genre					tyDir	4693	06/20/08 	03:12	2440
GuideIndexV3				tyDir	264421	10/09/11	14:06	376
GuideIndexV3.temp			tyDir	264288	10/09/11 	14:06	4
Headend					tyDir	7882	01/17/09 	06:10	68
HeadendPostalCode			tyDir	7883	01/17/09 	06:10	84
HeadendTms				tyDir	7884	01/17/09 	06:10	68
LeadGeneration				tyDir	16947	10/08/11	15:23	664
LinkTag					tyDir	85112	03/02/07 	04:22	4
Log					tyDir	489050	08/19/07 	20:31	20
LogoGroup				tyDir	7237	06/14/07 	18:30	52
MenuItem				tyDir	16934	03/30/11	18:48	176
MessageItem				tyDir	19554	01/13/07	18:32	48
Package					tyDir	49688	09/06/11 	05:43	4
ParentalControls			tyDir	104866	06/11/07 	17:12	32
Person					tyDir	17006	02/28/07 	02:22	4
PostalCode				tyDir	7891	06/14/07 	17:09	16
Preference				tyDir	16113	10/18/06 	20:29	12
Psip					tyDir	104878	08/19/11 	18:42	1436
RRT					tyDir	9702	06/14/07 	17:19	20
Recording				tyDir	7253	10/03/11 	09:47	316
Resource				tyDir	16	07/27/06 	19:08	68
Rubbish					tyDir	7370	10/09/11 	05:56	1044
Schedule				tyDir	2	09/09/11 	17:55	2872
SeasonPass				tyDir	7368	10/18/06 	20:29	96
Server					tyDir	7	10/09/11 	07:35	3904
Setup					tyDb	253315	10/09/11 	19:01	80284
Showcase				tyDir	16949	10/18/06 	20:32	12
ShowcaseIndex				tyDir	7296	10/09/11 	08:14	376
ShowcaseIndex.temp			tyDir	9705	10/09/11 	08:14	4
SpigotMap				tyDir	4670	07/27/06	19:02	48
State					tyDir	4	08/22/09	22:44	548
StationTms				tyDir	7514	01/22/09 	16:59	52
SwModule				tyDir	8	03/13/11 	19:14	100
SwSystem				tyDir	4685	03/11/11 	11:12	44
Table					tyDir	4687	10/05/11 	19:39	140
Theme					tyDir	340328	09/06/11	07:35	688
Tivo_Retail_HD_ColorCorrected.ts	tyStream7280	07/27/06 	19:26	131072 x 3328(3263)
Trio					tyDir	104849	12/05/08 	02:17	216
TuikRes					tyDir	70	07/27/06 	19:01	44
Welcome_Eiger_1_HD.ts			tyStream7248	09/14/06 	15:21	131072 x 1440(1361)
Welcome_Eiger_2_HD.ts			tyStream7260	09/14/06 	15:21	131072 x 1352(1347)
Welcome_Eiger_3_HD.ts			tyStream7264	09/14/06 	15:21	131072 x 1680(1632)
Welcome_Eiger_4_HD.ts			tyStream7268	09/14/06 	15:21	131072 x 1632(1601)
Welcome_Eiger_5_HD.ts			tyStream7272	09/14/06 	15:21	131072 x 2224(2219)
Welcome_Eiger_6_HD.ts			tyStream7276	09/14/06 	15:21	131072 x 1760(1729)

WO312 · 10-09-2011, 10:18 PM

Quote:

Originally Posted by Teeps

Which is the point, as I pointed "them" to this thread as proof.

Since I last did the channel fix and disconnect from the internet. The GC updated after about 3 days. Then hung on that date. I gave it 3 days, disconnected the internet. On the third day GC updated. The last GC update (result from internet disconnect) last Wed appeared stuck, but yesterday evening I noticed that GC HAD updated on its own!!!!

So the GC clock is ticking again 8Oct2011.

I'm going to experiment with my system. This is what I will do:

1. I just disconnected the internet and I will leave it off until the GC updates by itself.

2. I will reconnect, let the new data download automatically, and wait for the GC to not update for 3 days.

3. Repeat steps 1 and 2 until I can get a natural GC change with the internet connected.

We will see. Teeps, let us know if your system continues to function properly. You should know in the next day or so.

trackie999 · 10-10-2011, 07:10 AM

My dbgc is still running after 101 hours, it is still spitting out rubbish info once in a while...

This test confirms my theory that the daily call is what kills of dbgc after ~24 hours. No wonder dbgc never completed before if it needs 4+ days.

I am going to let it run for a couple more days, chances are it will finish.

Quote:

Originally Posted by WO312

I'm going to experiment with my system. This is what I will do:

1. I just disconnected the internet and I will leave it off until the GC updates by itself.

2. I will reconnect, let the new data download automatically, and wait for the GC to not update for 3 days.

3. Repeat steps 1 and 2 until I can get a natural GC change with the internet connected.

We will see. Teeps, let us know if your system continues to function properly. You should know in the next day or so.

WO312 · 10-10-2011, 09:55 AM

Quote:

Originally Posted by WO312

I'm going to experiment with my system. This is what I will do:

1. I just disconnected the internet and I will leave it off until the GC updates by itself.

2. I will reconnect, let the new data download automatically, and wait for the GC to not update for 3 days.

3. Repeat steps 1 and 2 until I can get a natural GC change with the internet connected.

We will see. Teeps, let us know if your system continues to function properly. You should know in the next day or so.

This morning I checked the Tivo and I had the disconnected tuning adapter message. Seems that while I was fumbling around with the internet disconnect I separated the tuning adapter brick from its power cord. I have very tight quarters back there.

At any rate, there was a failed call but also a GC update. Not sure why the GC updated - was it because the tuning adapter was disconnected or because there was a failed call?

I now have the tuning adapter connected, I forced a call, and I will leave it alone for a few days until the GC doesn't update. Then I will disconnect the internet again.

WO312 · 10-10-2011, 09:59 AM

Quote:

Originally Posted by trackie999

My dbgc is still running after 101 hours, it is still spitting out rubbish info once in a while...

This test confirms my theory that the daily call is what kills of dbgc after ~24 hours. No wonder dbgc never completed before if it needs 4+ days.

I am going to let it run for a couple more days, chances are it will finish.

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.

chrisdfw · 10-10-2011, 11:07 AM

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

Teeps · 10-10-2011, 01:57 PM

Quote:

Originally Posted by chrisdfw

Just to add some data points. This is happening for users with TWC, upgraded drives and tuning adaptors.

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.

trackie999 · 10-10-2011, 03:00 PM

Long rant/answer:

Spoiler:

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.

Quote:

Originally Posted by WO312

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.

trackie999 · 10-10-2011, 03:03 PM

lrhorer: Please tell me you're having the same issue!
Your insights would be greatly appreciated in resolving this matter!

Quote:

Originally Posted by lrhorer

I don't see where:

Code:

Directory listing of /
Name					Type	Id	Date	 	Time	Size
AdContent				tyDir	49569	10/03/11	22:17	4
Anchor					tyDir	314739	10/07/11	02:59	2712
15:21	131072 x 1760(1729)

chrisdfw · 10-10-2011, 03:08 PM

Quote:

Originally Posted by Teeps

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.

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.

WO312 · 10-10-2011, 03:11 PM

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.)

wtherrell · 10-10-2011, 04:40 PM

Quote:

Originally Posted by WO312

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.)

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.

lrhorer · 10-10-2011, 06:13 PM

Quote:

Originally Posted by trackie999

lrhorer: Please tell me you're having the same issue!

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.

lrhorer · 10-10-2011, 06:28 PM

Quote:

Originally Posted by trackie999

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.

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.

lrhorer · 10-10-2011, 06:58 PM

Quote:

Originally Posted by WO312

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??

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.

Quote:

Originally Posted by WO312

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

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:

Quote:

Originally Posted by WO312

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

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

trackie999 · 10-10-2011, 07:36 PM

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.

Quote:

Originally Posted by lrhorer

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.

WO312 · 10-10-2011, 09:22 PM

Quote:

Originally Posted by wtherrell

Sun circles the earth?? From what century have you time-traveled here ??

Hmmm, guess my humor goes right over your head. We MUST be from different centuries.

WO312 · 10-10-2011, 09:26 PM

Quote:

Originally Posted by lrhorer

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

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.

wtherrell · 10-10-2011, 09:44 PM

Quote:

Originally Posted by WO312

Hmmm, guess my humor goes right over your head. We MUST be from different centuries.

No, I got it. Alas, sometimes I just can't control my smart-ass reflex.

pninen · 10-10-2011, 10:52 PM

Quote:

Originally Posted by trackie999

WO312,

DO NOT RUN CHANNEL LINE UP FIX IF YOU RECEIVED S03 ERROR!
YOU WILL GET STUCK IN A NEVER ENDING GUIDED SETUP -> S03 -> REBOOT LOOP!
If you have S03 Error, you can fix it by running "Clear program information & To Do List"

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.

Teeps · 10-11-2011, 08:02 AM

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.

trackie999 · 10-11-2011, 04:10 PM

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.

wtherrell · 10-11-2011, 04:15 PM

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.

Eccles · 10-12-2011, 01:49 AM

Quote:

Originally Posted by pninen

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.

Likewise, I used the Channel Lineup fix the last time my HD hit the S03 problem, and I had no problems at all.