SageTV Service takes 12+ minutes to load.. and hangs all other services

[Fastrack]

Hi all,

This just starting happening (see below for my debug log). But when I restart my PVR, the Sage Service takes around 12+ minutes to load!! And while it's loading it stops other services like VNC, my ftp server etc.. from loading. Any idea what might be going on??


As you can see between 23:55:51 & 0:06:53 there is zip in the log, can I increase the JVM heap size or such ? My wiz.bin is only 23MB and that seems small from what I've read..

Sun 9/7 23:55:47.659 user.dir2=D:\Program Files\SageTV\SageTV
Sun 9/7 23:55:47.668 classpath="removed as it was huge..."
Sun 9/7 23:55:47.670 JVM version=1.6.0_07
Sun 9/7 23:55:47.670 OS=Windows XP 5.1
Sun 9/7 23:55:47.707 SageTV V6.4.8.184
Sun 9/7 23:55:47.754 hostname=pvr
Sun 9/7 23:55:48.430 Wizard starting to load database info...
Sun 9/7 23:55:48.459 DBFile at version 65 FileSize=23735241
Sun 9/7 23:55:48.459 Wizard allocating table for Year of size 98
Sun 9/7 23:55:48.484 Wizard loading main index for Year
Sun 9/7 23:55:48.486 Wizard allocating table for Network of size 179
Sun 9/7 23:55:48.486 Wizard loading main index for Network
Sun 9/7 23:55:48.490 Wizard allocating table for Title of size 11755
Sun 9/7 23:55:48.491 Wizard loading main index for Title
Sun 9/7 23:55:48.532 Wizard building alt. index 0 for Title
Sun 9/7 23:55:48.545 Wizard allocating table for Channel of size 940
Sun 9/7 23:55:48.546 Wizard loading main index for Channel
Sun 9/7 23:55:48.723 Wizard allocating table for People of size 34056
Sun 9/7 23:55:48.724 Wizard loading main index for People
Sun 9/7 23:55:48.828 Wizard building alt. index 0 for People
Sun 9/7 23:55:48.894 Wizard allocating table for SubCategory of size 169
Sun 9/7 23:55:48.894 Wizard loading main index for SubCategory
Sun 9/7 23:55:48.895 Wizard allocating table for Rated of size 16
Sun 9/7 23:55:48.895 Wizard loading main index for Rated
Sun 9/7 23:55:48.896 Wizard allocating table for ParentalRating of size 0
Sun 9/7 23:55:48.896 Wizard allocating table for ExtendedRating of size 26
Sun 9/7 23:55:48.896 Wizard loading main index for ExtendedRating
Sun 9/7 23:55:48.897 Wizard allocating table for Category of size 176
Sun 9/7 23:55:48.897 Wizard loading main index for Category
Sun 9/7 23:55:48.898 Wizard allocating table for PrimeTitle of size 0
Sun 9/7 23:55:48.898 Wizard allocating table for Bonus of size 462
Sun 9/7 23:55:48.899 Wizard loading main index for Bonus
Sun 9/7 23:55:48.900 Wizard allocating table for Show of size 48270
Sun 9/7 23:55:48.900 Wizard loading main index for Show
Sun 9/7 23:55:50.388 Wizard building alt. index 0 for Show
Sun 9/7 23:55:50.459 Wizard allocating table for Airing of size 99932
Sun 9/7 23:55:50.469 Wizard loading main index for Airing
Sun 9/7 23:55:50.554 Sage waiting for GC to free up some memory usage%=0.9606631
Sun 9/7 23:55:50.729 Sage done waiting for GC to free up some memory usage%=0.5805906
Sun 9/7 23:55:50.921 Wizard building alt. index 0 for Airing
Sun 9/7 23:55:50.978 Wizard building alt. index 1 for Airing
Sun 9/7 23:55:51.051 Wizard allocating table for ManualRecord of size 336
Sun 9/7 23:55:51.052 Wizard loading main index for ManualRecord
Sun 9/7 23:55:51.074 Wizard allocating table for MediaFile of size 23738
Sun 9/7 23:55:51.089 Wizard loading main index for MediaFile
Sun 9/7 23:55:51.567 Sage waiting for GC to free up some memory usage%=0.98138297
Sun 9/7 23:55:51.848 Sage done waiting for GC to free up some memory usage%=0.5748321
Mon 9/8 0:06:53.583 Wizard building alt. index 0 for MediaFile
Mon 9/8 0:06:53.591 Wizard building alt. index 1 for MediaFile
Mon 9/8 0:06:53.679 Wizard allocating table for Watched of size 2616
Mon 9/8 0:06:53.680 Wizard loading main index for Watched
Mon 9/8 0:06:53.693 Wizard building alt. index 0 for Watched
Mon 9/8 0:06:53.695 Wizard allocating table for Agent of size 825
Mon 9/8 0:06:53.696 Wizard loading main index for Agent
Mon 9/8 0:06:53.708 Wizard building alt. index 0 for Agent
Mon 9/8 0:06:53.712 Wizard allocating table for Wasted of size 1454
Mon 9/8 0:06:53.713 Wizard loading main index for Wasted
Mon 9/8 0:06:53.714 Wizard allocating table for Playlist of size 213
Mon 9/8 0:06:53.715 Wizard loading main index for Playlist
Mon 9/8 0:06:53.724 Wizard allocating table for TVEditorial of size 112
Mon 9/8 0:06:53.725 Wizard loading main index for TVEditorial
Mon 9/8 0:06:53.727 Wizard allocating table for SeriesInfo of size 3007
Mon 9/8 0:06:53.728 Wizard loading main index for SeriesInfo
Mon 9/8 0:06:53.859 Wizard processing transactional records...
Mon 9/8 0:06:53.879 Wizard performing validation on database objects...
Mon 9/8 0:06:53.979 Wizard DONE loading database info. loadTime=665.549 sec
Mon 9/8 0:06:54.640 MMC calling detectCaptureDevices on sage.cb@18e3e60

[Opus4]

If you want to try increasing the max heap size, do a tag search on the forum for "heap". If that doesn't do anything, then I would contact support for their suggestions.

- Andy

[Fastrack]

Originally Posted by Opus4

If you want to try increasing the max heap size, do a tag search on the forum for "heap". If that doesn't do anything, then I would contact support for their suggestions.

- Andy

I tried changing the value to 512mb for max heap, no dice. Same issue. I'm in the process of submitting a Bug Report.

[MattHelm]

Is your HDHomerun working? When my dad's died (power supply) Sage sent the CPU to 100%, and would not show (or record) upcoming shows. As soon as the HDHomerun was back online, Sage was fine.

[mickp]

It might be unrelated but... for a long time I've experienced problems with starting Sage as a service upon startup. I'm not sure of the exact cause but the solution/workaround has been to delay the start of the service. See this thread for details.

Mick.

[Fastrack]

Yes the HDHR is working perfectly thank god And yeah I do not have the defective power supply already checked that.

I can tell you that if I stop / start the service once windows is loaded that it works fine, so I'll check out your thread.

Ben

[wbarber69]

I've experienced this same problem for years now I always just chalked it up to old oversized wiz.bin

[MattHelm]

Just a suggestion, not sure if will help or not. It souunds like you have somekind of race condition. Are you running anything that might interfere with Sage?

Try this. Set the server from starting at boot. Reset machine, and wait, just for giggles, 5 minutes. Then, using the server control, start Sagesever. Does it still hang for 12 minutes? If not, there must be something in your startup. If it does, it 100% Sage. At least then you know where the problem is.

[Fastrack]

Originally Posted by MattHelm

Just a suggestion, not sure if will help or not. It souunds like you have somekind of race condition. Are you running anything that might interfere with Sage?

Try this. Set the server from starting at boot. Reset machine, and wait, just for giggles, 5 minutes. Then, using the server control, start Sagesever. Does it still hang for 12 minutes? If not, there must be something in your startup. If it does, it 100% Sage. At least then you know where the problem is.

Yes I've tried this and confirmed it doesn't have the issue, however trying to determine what SageTV is waiting for is next to impossible considering nothing has changed on this box for a LONG time. I'm trying out the batch file mentioned on the other thread we'll see how it goes

[Fastrack]

I set the service to manual and I'm now using this batch file. Everything is working now. I dono there must be a service tying up Sage when the PC boots. But NO other service is slow when Sage is set to manual.

@echo off
echo Starting SageTV service Please Wait....
echo This will also launch the SageTV interface
echo Waiting 30 seconds...
ping -n 30 127.0.0.1 > nul
@echo on
sc start sagetv
@echo off
start "d:\Program Files\SageTV\SageTV\SageTV.exe"