Mon 5/29 12:13:59.479 [main@132e575] EMBEDDED=false Mon 5/29 12:13:59.502 [main@132e575] user.dir2=C:\Program Files (x86)\SageTV\SageTV Mon 5/29 12:13:59.502 [main@132e575] classpath=C:\Program Files (x86)\SageTV\SageTV\Sage.jar;C:\Program Files (x86)\SageTV\SageTV\JARs\lucene-core-3.6.0.jar;C:\Program Files (x86)\SageTV\SageTV\xerces.jar;C:\Program Files (x86)\SageTV\SageTV\plugin.jar;C:\Program Files (x86)\SageTV\SageTV\;;JARs\commons-jxpath-1.1.jar;JARs\GoogleWeather.jar;JARs\jcifs-1.1.6.jar;JARs\jdic.jar;JARs\lucene-core-3.6.0.jar;JARs\packager.jar;JARs\sbbi-upnplib-1.0.3.jar;JARs\vecmath.jar Mon 5/29 12:13:59.502 [main@132e575] JVM version=1.8.0_131 Mon 5/29 12:13:59.503 [main@132e575] OS=Windows 10 10.0 Mon 5/29 12:13:59.503 [main@132e575] client=false Mon 5/29 12:13:59.503 [main@132e575] locale=en_US Mon 5/29 12:13:59.510 [ThreadMonitor@e14a7b] Thread CPU monitoring started interval=300000 Mon 5/29 12:13:59.516 [main@132e575] SageTV V9.1.1.651 Mon 5/29 12:13:59.529 [main@132e575] hostname=BULLITT Mon 5/29 12:13:59.530 [main@132e575] Splash: User Interface Manager is initializing... Mon 5/29 12:13:59.723 [main@132e575] Splash: Object Database is initializing... Mon 5/29 12:13:59.805 [main@132e575] dbFile=C:\Program Files (x86)\SageTV\SageTV\Wiz.bin(13163741) dbBackupFile=C:\Program Files (x86)\SageTV\SageTV\Wiz.bak(16974264) Mon 5/29 12:13:59.806 [main@132e575] Wizard starting to load database info... Mon 5/29 12:13:59.806 [main@132e575] Splash: Object Database Source is initializing... Mon 5/29 12:13:59.815 [main@132e575] Reading DB file:C:\Program Files (x86)\SageTV\SageTV\Wiz.bin with version 87 Mon 5/29 12:13:59.816 [main@132e575] DBFile at version 87 FileSize=13163741 Mon 5/29 12:13:59.816 [main@132e575] Wizard allocating table for Year of size 83 Mon 5/29 12:13:59.818 [main@132e575] Splash: Object Database is initializing... 0% done Mon 5/29 12:13:59.818 [main@132e575] Wizard loading main index for Year bytes=1168 Mon 5/29 12:13:59.819 [main@132e575] Load time for Year 1 msec 0.012048192 msec/object Mon 5/29 12:13:59.819 [main@132e575] Index check time for Year 0 msec Mon 5/29 12:13:59.819 [main@132e575] Splash: Object Database is initializing... 0% done Mon 5/29 12:13:59.819 [main@132e575] Wizard allocating table for Network of size 53 Mon 5/29 12:13:59.819 [main@132e575] Splash: Object Database is initializing... 0% done Mon 5/29 12:13:59.819 [main@132e575] Wizard loading main index for Network bytes=793 Mon 5/29 12:13:59.820 [main@132e575] Load time for Network 1 msec 0.018867925 msec/object Mon 5/29 12:13:59.820 [main@132e575] Index check time for Network 0 msec Mon 5/29 12:13:59.820 [main@132e575] Splash: Object Database is initializing... 0% done Mon 5/29 12:13:59.820 [main@132e575] Wizard allocating table for Title of size 4622 Mon 5/29 12:13:59.820 [main@132e575] Splash: Object Database is initializing... 0% done Mon 5/29 12:13:59.821 [main@132e575] Wizard loading main index for Title bytes=134510 Mon 5/29 12:13:59.828 [main@132e575] Load time for Title 7 msec 0.0015144959 msec/object Mon 5/29 12:13:59.829 [main@132e575] Index check time for Title 1 msec Mon 5/29 12:13:59.829 [main@132e575] Splash: Object Database is initializing... 0% done Mon 5/29 12:13:59.829 [main@132e575] Wizard loading alt index 1 for Title bytes=18494 Mon 5/29 12:13:59.830 [main@132e575] Load time for alt index 1 for Title 1 msec Mon 5/29 12:13:59.835 [main@132e575] Index check time for alt index 1 for Title 5 msec Mon 5/29 12:13:59.835 [main@132e575] Splash: Object Database is initializing... 1% done Mon 5/29 12:13:59.836 [main@132e575] Wizard allocating table for Channel of size 151 Mon 5/29 12:13:59.836 [main@132e575] Splash: Object Database is initializing... 1% done Mon 5/29 12:13:59.836 [main@132e575] Wizard loading main index for Channel bytes=8641 Mon 5/29 12:13:59.837 [main@132e575] Load time for Channel 1 msec 0.0066225166 msec/object Mon 5/29 12:13:59.837 [main@132e575] Index check time for Channel 0 msec Mon 5/29 12:13:59.837 [main@132e575] Splash: Object Database is initializing... 1% done Mon 5/29 12:13:59.838 [main@132e575] Wizard allocating table for Bonus of size 3069 Mon 5/29 12:13:59.838 [main@132e575] Splash: Object Database is initializing... 1% done Mon 5/29 12:13:59.838 [main@132e575] Wizard loading main index for Bonus bytes=62417 Mon 5/29 12:13:59.840 [main@132e575] Load time for Bonus 1 msec 3.2583904E-4 msec/object Mon 5/29 12:13:59.840 [main@132e575] Index check time for Bonus 0 msec Mon 5/29 12:13:59.840 [main@132e575] Splash: Object Database is initializing... 1% done Mon 5/29 12:13:59.840 [main@132e575] Wizard allocating table for People of size 37571 Mon 5/29 12:13:59.841 [main@132e575] Splash: Object Database is initializing... 2% done Mon 5/29 12:13:59.841 [main@132e575] Wizard loading main index for People bytes=3013709 Mon 5/29 12:13:59.851 [main@132e575] Lucene index(person): rooted at /rw/\lucene/ running at /rw/\lucene-run/ DiskIndex:false RunningDiskIndex:false Mon 5/29 12:13:59.995 [main@132e575] Lucene index(person) reader opened in 3 Mon 5/29 12:13:59.998 [main@132e575] Lucene index(person) docs: 0 Mon 5/29 12:13:59.998 [main@132e575] Increased Thread pool to size=1 Mon 5/29 12:13:59.999 [main@132e575] Lucene index(person) does not have same item count as wizard (idx:0, wiz:37571) - RESETING Mon 5/29 12:14:00.000 [main@132e575] Index(person) reset Mon 5/29 12:14:00.045 [main@132e575] Load time for People 204 msec 0.0054297196 msec/object Mon 5/29 12:14:00.046 [main@132e575] Index check time for People 1 msec Mon 5/29 12:14:00.047 [main@132e575] Lucene index(person) built from Wizard Mon 5/29 12:14:00.047 [main@132e575] Splash: Object Database is initializing... 2% done Mon 5/29 12:14:00.047 [main@132e575] Wizard loading alt index 2 for People bytes=150290 Mon 5/29 12:14:00.051 [main@132e575] Load time for alt index 2 for People 3 msec Mon 5/29 12:14:00.055 [main@132e575] Index check time for alt index 2 for People 4 msec Mon 5/29 12:14:00.056 [main@132e575] Wizard loading alt index 16 for People bytes=150290 Mon 5/29 12:14:00.059 [main@132e575] Load time for alt index 16 for People 3 msec Mon 5/29 12:14:00.065 [main@132e575] Index check time for alt index 16 for People 6 msec Mon 5/29 12:14:00.065 [main@132e575] Wizard allocating table for SubCategory of size 102 Mon 5/29 12:14:00.065 [main@132e575] Splash: Object Database is initializing... 27% done Mon 5/29 12:14:00.065 [main@132e575] Wizard loading main index for SubCategory bytes=1922 Mon 5/29 12:14:00.065 [main@132e575] Load time for SubCategory 0 msec 0.0 msec/object Mon 5/29 12:14:00.065 [main@132e575] Index check time for SubCategory 0 msec Mon 5/29 12:14:00.066 [main@132e575] Splash: Object Database is initializing... 27% done Mon 5/29 12:14:00.066 [main@132e575] Wizard allocating table for Rated of size 5 Mon 5/29 12:14:00.066 [main@132e575] Splash: Object Database is initializing... 27% done Mon 5/29 12:14:00.066 [main@132e575] Wizard loading main index for Rated bytes=70 Mon 5/29 12:14:00.066 [main@132e575] Load time for Rated 0 msec 0.0 msec/object Mon 5/29 12:14:00.066 [main@132e575] Index check time for Rated 0 msec Mon 5/29 12:14:00.067 [main@132e575] Splash: Object Database is initializing... 27% done Mon 5/29 12:14:00.067 [main@132e575] Wizard allocating table for ParentalRating of size 0 Mon 5/29 12:14:00.067 [main@132e575] Splash: Object Database is initializing... 27% done Mon 5/29 12:14:00.067 [main@132e575] Wizard allocating table for ExtendedRating of size 9 Mon 5/29 12:14:00.067 [main@132e575] Splash: Object Database is initializing... 27% done Mon 5/29 12:14:00.067 [main@132e575] Wizard loading main index for ExtendedRating bytes=206 Mon 5/29 12:14:00.068 [main@132e575] Load time for ExtendedRating 1 msec 0.11111111 msec/object Mon 5/29 12:14:00.068 [main@132e575] Index check time for ExtendedRating 0 msec Mon 5/29 12:14:00.068 [main@132e575] Splash: Object Database is initializing... 27% done Mon 5/29 12:14:00.068 [main@132e575] Wizard allocating table for Category of size 99 Mon 5/29 12:14:00.068 [main@132e575] Splash: Object Database is initializing... 27% done Mon 5/29 12:14:00.068 [main@132e575] Wizard loading main index for Category bytes=1838 Mon 5/29 12:14:00.069 [main@132e575] Load time for Category 1 msec 0.01010101 msec/object Mon 5/29 12:14:00.069 [main@132e575] Index check time for Category 0 msec Mon 5/29 12:14:00.069 [main@132e575] Splash: Object Database is initializing... 27% done Mon 5/29 12:14:00.069 [main@132e575] Wizard allocating table for PrimeTitle of size 0 Mon 5/29 12:14:00.069 [main@132e575] Splash: Object Database is initializing... 27% done Mon 5/29 12:14:00.069 [main@132e575] Wizard allocating table for Show of size 17486 Mon 5/29 12:14:00.070 [main@132e575] Splash: Object Database is initializing... 27% done Mon 5/29 12:14:00.070 [main@132e575] Wizard loading main index for Show bytes=4774884 Mon 5/29 12:14:00.070 [main@132e575] Lucene index(show): rooted at /rw/\lucene/ running at /rw/\lucene-run/ DiskIndex:false RunningDiskIndex:false Mon 5/29 12:14:00.070 [main@132e575] Lucene index(show) reader opened in 0 Mon 5/29 12:14:00.070 [main@132e575] Lucene index(show) docs: 0 Mon 5/29 12:14:00.071 [main@132e575] Lucene index(show) does not have same item count as wizard (idx:0, wiz:17486) - RESETING Mon 5/29 12:14:00.071 [main@132e575] Index(show) reset Mon 5/29 12:14:00.116 [LucenePersonTransactionTask@1d15fc1] 15.74% / 6352 work queue, 1000 inserted @ 0.10 ms/peep avg [tot:103ms] Mon 5/29 12:14:00.135 [main@132e575] Load time for Show 65 msec 0.0037172595 msec/object Mon 5/29 12:14:00.137 [main@132e575] Index check time for Show 1 msec Mon 5/29 12:14:00.137 [main@132e575] Lucene index(show) built from Wizard Mon 5/29 12:14:00.138 [main@132e575] Splash: Object Database is initializing... 27% done Mon 5/29 12:14:00.138 [main@132e575] Wizard loading alt index 3 for Show bytes=69950 Mon 5/29 12:14:00.139 [LucenePersonTransactionTask@1d15fc1] 31.49% / 6352 work queue, 2000 inserted @ 0.06 ms/peep avg [tot:125ms] Mon 5/29 12:14:00.140 [main@132e575] Load time for alt index 3 for Show 2 msec Mon 5/29 12:14:00.145 [main@132e575] Index check time for alt index 3 for Show 5 msec Mon 5/29 12:14:00.145 [main@132e575] Wizard allocating table for Airing of size 88703 Mon 5/29 12:14:00.145 [main@132e575] Splash: Object Database is initializing... 64% done Mon 5/29 12:14:00.145 [main@132e575] Wizard loading main index for Airing bytes=3459423 Mon 5/29 12:14:00.151 [LucenePersonTransactionTask@1d15fc1] 47.23% / 6352 work queue, 3000 inserted @ 0.05 ms/peep avg [tot:135ms] Mon 5/29 12:14:00.163 [LucenePersonTransactionTask@1d15fc1] 62.97% / 6352 work queue, 4000 inserted @ 0.04 ms/peep avg [tot:146ms] Mon 5/29 12:14:00.179 [LucenePersonTransactionTask@1d15fc1] 78.72% / 6352 work queue, 5000 inserted @ 0.03 ms/peep avg [tot:160ms] Mon 5/29 12:14:00.182 [main@132e575] Load time for Airing 37 msec 4.171223E-4 msec/object Mon 5/29 12:14:00.187 [main@132e575] Index check time for Airing 4 msec Mon 5/29 12:14:00.187 [main@132e575] Splash: Object Database is initializing... 64% done Mon 5/29 12:14:00.187 [LucenePersonTransactionTask@1d15fc1] 94.46% / 6352 work queue, 6000 inserted @ 0.03 ms/peep avg [tot:167ms] Mon 5/29 12:14:00.187 [main@132e575] Wizard loading alt index 6 for Airing bytes=354818 Mon 5/29 12:14:00.190 [LucenePersonTransactionTask@1d15fc1] 100% work queue, 6352 insertions @ 0.03 ms/peep [tot:170ms] Mon 5/29 12:14:00.197 [main@132e575] Load time for alt index 6 for Airing 10 msec Mon 5/29 12:14:00.198 [LucenePersonTransactionTask@1d15fc1] 3.20% / 31220 work queue, 7352 inserted @ 0.02 ms/peep avg [tot:176ms] Mon 5/29 12:14:00.198 [LuceneShowTransactionTask@eb6787] 5.92% / 16885 work queue, 1602 inserted @ 0.08 ms/show avg [tot:127ms] Mon 5/29 12:14:00.204 [main@132e575] Index check time for alt index 6 for Airing 7 msec Mon 5/29 12:14:00.204 [main@132e575] Wizard loading alt index 5 for Airing bytes=354818 Mon 5/29 12:14:00.206 [LucenePersonTransactionTask@1d15fc1] 6.41% / 31220 work queue, 8352 inserted @ 0.02 ms/peep avg [tot:184ms] Mon 5/29 12:14:00.211 [main@132e575] Load time for alt index 5 for Airing 7 msec Mon 5/29 12:14:00.215 [LucenePersonTransactionTask@1d15fc1] 9.61% / 31220 work queue, 9352 inserted @ 0.02 ms/peep avg [tot:192ms] Mon 5/29 12:14:00.217 [main@132e575] Index check time for alt index 5 for Airing 6 msec Mon 5/29 12:14:00.217 [main@132e575] Wizard allocating table for ManualRecord of size 0 Mon 5/29 12:14:00.217 [main@132e575] Splash: Object Database is initializing... 95% done Mon 5/29 12:14:00.217 [main@132e575] Wizard allocating table for MediaFile of size 31 Mon 5/29 12:14:00.218 [main@132e575] Splash: Object Database is initializing... 95% done Mon 5/29 12:14:00.218 [main@132e575] Wizard loading main index for MediaFile bytes=11237 Mon 5/29 12:14:00.222 [LucenePersonTransactionTask@1d15fc1] 12.81% / 31220 work queue, 10352 inserted @ 0.02 ms/peep avg [tot:199ms] Mon 5/29 12:14:00.226 [main@132e575] Load time for MediaFile 8 msec 0.2580645 msec/object Mon 5/29 12:14:00.227 [main@132e575] Index check time for MediaFile 0 msec Mon 5/29 12:14:00.227 [main@132e575] Splash: Object Database is initializing... 95% done Mon 5/29 12:14:00.227 [main@132e575] Wizard loading alt index 10 for MediaFile bytes=130 Mon 5/29 12:14:00.228 [main@132e575] Load time for alt index 10 for MediaFile 0 msec Mon 5/29 12:14:00.228 [main@132e575] Index check time for alt index 10 for MediaFile 0 msec Mon 5/29 12:14:00.228 [main@132e575] Wizard loading alt index 12 for MediaFile bytes=130 Mon 5/29 12:14:00.228 [main@132e575] Load time for alt index 12 for MediaFile 0 msec Mon 5/29 12:14:00.228 [main@132e575] Index check time for alt index 12 for MediaFile 0 msec Mon 5/29 12:14:00.229 [main@132e575] Wizard allocating table for Watched of size 4 Mon 5/29 12:14:00.229 [main@132e575] Splash: Object Database is initializing... 95% done Mon 5/29 12:14:00.229 [main@132e575] Wizard loading main index for Watched bytes=246 Mon 5/29 12:14:00.229 [main@132e575] Load time for Watched 0 msec 0.0 msec/object Mon 5/29 12:14:00.230 [main@132e575] Index check time for Watched 0 msec Mon 5/29 12:14:00.230 [main@132e575] Splash: Object Database is initializing... 95% done Mon 5/29 12:14:00.230 [main@132e575] Wizard loading alt index 7 for Watched bytes=22 Mon 5/29 12:14:00.230 [main@132e575] Load time for alt index 7 for Watched 0 msec Mon 5/29 12:14:00.231 [LucenePersonTransactionTask@1d15fc1] 16.02% / 31220 work queue, 11352 inserted @ 0.02 ms/peep avg [tot:205ms] Mon 5/29 12:14:00.231 [main@132e575] Index check time for alt index 7 for Watched 0 msec Mon 5/29 12:14:00.231 [main@132e575] Wizard allocating table for Agent of size 0 Mon 5/29 12:14:00.231 [main@132e575] Splash: Object Database is initializing... 95% done Mon 5/29 12:14:00.231 [main@132e575] Wizard allocating table for Wasted of size 0 Mon 5/29 12:14:00.231 [main@132e575] Splash: Object Database is initializing... 95% done Mon 5/29 12:14:00.232 [main@132e575] Wizard allocating table for Playlist of size 0 Mon 5/29 12:14:00.232 [main@132e575] Splash: Object Database is initializing... 95% done Mon 5/29 12:14:00.232 [main@132e575] Wizard allocating table for TVEditorial of size 0 Mon 5/29 12:14:00.232 [main@132e575] Splash: Object Database is initializing... 95% done Mon 5/29 12:14:00.232 [main@132e575] Wizard allocating table for SeriesInfo of size 1321 Mon 5/29 12:14:00.232 [main@132e575] Splash: Object Database is initializing... 95% done Mon 5/29 12:14:00.233 [main@132e575] Wizard loading main index for SeriesInfo bytes=530077 Mon 5/29 12:14:00.243 [main@132e575] Load time for SeriesInfo 10 msec 0.0075700227 msec/object Mon 5/29 12:14:00.243 [main@132e575] Index check time for SeriesInfo 0 msec Mon 5/29 12:14:00.243 [main@132e575] Splash: Object Database is initializing... 95% done Mon 5/29 12:14:00.244 [main@132e575] Wizard loading alt index 13 for SeriesInfo bytes=5290 Mon 5/29 12:14:00.244 [main@132e575] Load time for alt index 13 for SeriesInfo 0 msec Mon 5/29 12:14:00.244 [main@132e575] Index check time for alt index 13 for SeriesInfo 0 msec Mon 5/29 12:14:00.244 [main@132e575] Wizard loading alt index 15 for SeriesInfo bytes=5290 Mon 5/29 12:14:00.244 [main@132e575] Load time for alt index 15 for SeriesInfo 0 msec Mon 5/29 12:14:00.245 [main@132e575] Index check time for alt index 15 for SeriesInfo 0 msec Mon 5/29 12:14:00.245 [main@132e575] Wizard allocating table for UserRecord of size 0 Mon 5/29 12:14:00.245 [main@132e575] Splash: Object Database is initializing... 100% done Mon 5/29 12:14:00.245 [main@132e575] Wizard processing transactional records... Mon 5/29 12:14:00.246 [LucenePersonTransactionTask@1d15fc1] 19.22% / 31220 work queue, 12352 inserted @ 0.02 ms/peep avg [tot:219ms] Mon 5/29 12:14:00.247 [main@132e575] Splash: Object Database is initializing... 100% done Mon 5/29 12:14:00.248 [main@132e575] Splash: Object Database is initializing... 100% done Mon 5/29 12:14:00.250 [LuceneShowTransactionTask@eb6787] 11.84% / 16885 work queue, 2602 inserted @ 0.07 ms/show avg [tot:179ms] Mon 5/29 12:14:00.251 [main@132e575] Wizard performing validation on database objects... Mon 5/29 12:14:00.258 [LucenePersonTransactionTask@1d15fc1] 22.42% / 31220 work queue, 13352 inserted @ 0.02 ms/peep avg [tot:230ms] Mon 5/29 12:14:00.270 [LucenePersonTransactionTask@1d15fc1] 25.62% / 31220 work queue, 14352 inserted @ 0.02 ms/peep avg [tot:242ms] Mon 5/29 12:14:00.275 [main@132e575] Wizard DONE loading database info. loadTime=0.469 sec Mon 5/29 12:14:00.275 [main@132e575] Splash: Object Database is initializing... 100% done Mon 5/29 12:14:00.277 [main@132e575] Splash: EPG is initializing... Mon 5/29 12:14:00.282 [LucenePersonTransactionTask@1d15fc1] 28.83% / 31220 work queue, 15352 inserted @ 0.02 ms/peep avg [tot:253ms] Mon 5/29 12:14:00.292 [LucenePersonTransactionTask@1d15fc1] 32.03% / 31220 work queue, 16352 inserted @ 0.02 ms/peep avg [tot:263ms] Mon 5/29 12:14:00.300 [main@132e575] Increased hash set pool to size=1 Mon 5/29 12:14:00.311 [LucenePersonTransactionTask@1d15fc1] 35.23% / 31220 work queue, 17352 inserted @ 0.02 ms/peep avg [tot:281ms] Mon 5/29 12:14:00.317 [main@132e575] Splash: Profiler is initializing... Mon 5/29 12:14:00.319 [main@132e575] Loaded 1 messages from system message log file Mon 5/29 12:14:00.373 [main@132e575] Splash: Acquisition System is initializing... Mon 5/29 12:14:00.376 [LucenePersonTransactionTask@1d15fc1] 38.44% / 31220 work queue, 18352 inserted @ 0.02 ms/peep avg [tot:346ms] Mon 5/29 12:14:00.379 [LuceneShowTransactionTask@eb6787] 17.77% / 16885 work queue, 3602 inserted @ 0.09 ms/show avg [tot:308ms] Mon 5/29 12:14:00.380 [main@132e575] MMC calling detectCaptureDevices on sage.NetworkEncoderManager@d25987 Mon 5/29 12:14:00.381 [main@132e575] Doing broadcast discovery of new encoding servers on the network... Mon 5/29 12:14:00.384 [LucenePersonTransactionTask@1d15fc1] 41.64% / 31220 work queue, 19352 inserted @ 0.02 ms/peep avg [tot:354ms] Mon 5/29 12:14:00.391 [LucenePersonTransactionTask@1d15fc1] 44.84% / 31220 work queue, 20352 inserted @ 0.02 ms/peep avg [tot:360ms] Mon 5/29 12:14:00.398 [LucenePersonTransactionTask@1d15fc1] 48.05% / 31220 work queue, 21352 inserted @ 0.02 ms/peep avg [tot:367ms] Mon 5/29 12:14:00.405 [LucenePersonTransactionTask@1d15fc1] 51.25% / 31220 work queue, 22352 inserted @ 0.02 ms/peep avg [tot:373ms] Mon 5/29 12:14:00.412 [LucenePersonTransactionTask@1d15fc1] 54.45% / 31220 work queue, 23352 inserted @ 0.02 ms/peep avg [tot:380ms] Mon 5/29 12:14:00.419 [LucenePersonTransactionTask@1d15fc1] 57.66% / 31220 work queue, 24352 inserted @ 0.02 ms/peep avg [tot:386ms] Mon 5/29 12:14:00.425 [LuceneShowTransactionTask@eb6787] 23.69% / 16885 work queue, 4602 inserted @ 0.08 ms/show avg [tot:352ms] Mon 5/29 12:14:00.428 [LucenePersonTransactionTask@1d15fc1] 60.86% / 31220 work queue, 25352 inserted @ 0.02 ms/peep avg [tot:394ms] Mon 5/29 12:14:00.436 [LucenePersonTransactionTask@1d15fc1] 64.06% / 31220 work queue, 26352 inserted @ 0.02 ms/peep avg [tot:400ms] Mon 5/29 12:14:00.442 [LucenePersonTransactionTask@1d15fc1] 67.26% / 31220 work queue, 27352 inserted @ 0.01 ms/peep avg [tot:406ms] Mon 5/29 12:14:00.448 [LucenePersonTransactionTask@1d15fc1] 70.47% / 31220 work queue, 28352 inserted @ 0.01 ms/peep avg [tot:412ms] Mon 5/29 12:14:00.455 [LucenePersonTransactionTask@1d15fc1] 73.67% / 31220 work queue, 29352 inserted @ 0.01 ms/peep avg [tot:418ms] Mon 5/29 12:14:00.461 [LucenePersonTransactionTask@1d15fc1] 76.87% / 31220 work queue, 30352 inserted @ 0.01 ms/peep avg [tot:424ms] Mon 5/29 12:14:00.468 [LucenePersonTransactionTask@1d15fc1] 80.08% / 31220 work queue, 31352 inserted @ 0.01 ms/peep avg [tot:430ms] Mon 5/29 12:14:00.479 [LuceneShowTransactionTask@eb6787] 29.61% / 16885 work queue, 5602 inserted @ 0.07 ms/show avg [tot:406ms] Mon 5/29 12:14:00.481 [LucenePersonTransactionTask@1d15fc1] 83.28% / 31220 work queue, 32352 inserted @ 0.01 ms/peep avg [tot:442ms] Mon 5/29 12:14:00.492 [LucenePersonTransactionTask@1d15fc1] 86.48% / 31220 work queue, 33352 inserted @ 0.01 ms/peep avg [tot:453ms] Mon 5/29 12:14:00.498 [LucenePersonTransactionTask@1d15fc1] 89.69% / 31220 work queue, 34352 inserted @ 0.01 ms/peep avg [tot:457ms] Mon 5/29 12:14:00.508 [LucenePersonTransactionTask@1d15fc1] 92.89% / 31220 work queue, 35352 inserted @ 0.01 ms/peep avg [tot:465ms] Mon 5/29 12:14:00.527 [LuceneShowTransactionTask@eb6787] 35.53% / 16885 work queue, 6602 inserted @ 0.07 ms/show avg [tot:453ms] Mon 5/29 12:14:00.531 [LucenePersonTransactionTask@1d15fc1] 96.09% / 31220 work queue, 36352 inserted @ 0.01 ms/peep avg [tot:475ms] Mon 5/29 12:14:00.540 [LucenePersonTransactionTask@1d15fc1] 99.30% / 31220 work queue, 37352 inserted @ 0.01 ms/peep avg [tot:482ms] Mon 5/29 12:14:00.545 [LucenePersonTransactionTask@1d15fc1] Snapshot index person snap-count: 0 Mon 5/29 12:14:00.574 [LuceneShowTransactionTask@eb6787] 41.46% / 16885 work queue, 7602 inserted @ 0.07 ms/show avg [tot:495ms] Mon 5/29 12:14:00.634 [LuceneShowTransactionTask@eb6787] 47.38% / 16885 work queue, 8602 inserted @ 0.06 ms/show avg [tot:552ms] Mon 5/29 12:14:00.687 [LuceneShowTransactionTask@eb6787] 53.30% / 16885 work queue, 9602 inserted @ 0.06 ms/show avg [tot:601ms] Mon 5/29 12:14:00.728 [LucenePersonTransactionTask@1d15fc1] 100% work queue, 37571 insertions @ 0.01 ms/peep [tot:484ms] Mon 5/29 12:14:00.740 [LuceneShowTransactionTask@eb6787] 59.22% / 16885 work queue, 10602 inserted @ 0.06 ms/show avg [tot:650ms] Mon 5/29 12:14:00.790 [LuceneShowTransactionTask@eb6787] 65.15% / 16885 work queue, 11602 inserted @ 0.06 ms/show avg [tot:697ms] Mon 5/29 12:14:00.846 [LuceneShowTransactionTask@eb6787] 71.07% / 16885 work queue, 12602 inserted @ 0.06 ms/show avg [tot:750ms] Mon 5/29 12:14:00.909 [LuceneShowTransactionTask@eb6787] 76.99% / 16885 work queue, 13602 inserted @ 0.06 ms/show avg [tot:808ms] Mon 5/29 12:14:00.966 [LuceneShowTransactionTask@eb6787] 82.91% / 16885 work queue, 14602 inserted @ 0.06 ms/show avg [tot:859ms] Mon 5/29 12:14:01.256 [LuceneShowTransactionTask@eb6787] 88.84% / 16885 work queue, 15602 inserted @ 0.07 ms/show avg [tot:1147ms] Mon 5/29 12:14:01.298 [LuceneShowTransactionTask@eb6787] 94.76% / 16885 work queue, 16602 inserted @ 0.07 ms/show avg [tot:1186ms] Mon 5/29 12:14:01.334 [LuceneShowTransactionTask@eb6787] Snapshot index show snap-count: 0 Mon 5/29 12:14:01.391 [LuceneShowTransactionTask@eb6787] 100% work queue, 17486 insertions @ 0.07 ms/show [tot:1219ms] Mon 5/29 12:14:03.396 [main@132e575] Error discovering servers:java.net.SocketTimeoutException: Receive timed out Mon 5/29 12:14:03.400 [main@132e575] devices detected=[] Mon 5/29 12:14:03.404 [main@132e575] EncoderMap={} Mon 5/29 12:14:03.409 [main@132e575] MMC calling detectCaptureDevices on sage.DShowCaptureManager@1275dab Mon 5/29 12:14:03.412 [main@132e575] Splash: DirectShow WDM Capture Manager is initializing... Mon 5/29 12:14:03.419 [main@132e575] Setup globalInvalidParameterHandler Mon 5/29 12:14:03.419 [main@132e575] Device detecting->:{65E8773D-8F56-11D0-A3B9-00A0C9223196} Mon 5/29 12:14:03.425 [main@132e575] Device found--'Ear Force Stealth 450' Mon 5/29 12:14:03.425 [main@132e575] Device detecting->:{FD0A5AF4-B41D-11d2-9C95-00C04F7971E0} Mon 5/29 12:14:03.425 [main@132e575] Device found--'Silicondust HDHomeRun Tuner 1020A3F1-0' Mon 5/29 12:14:03.426 [main@132e575] Device detecting->:{71985F48-1CA1-11d3-9CC8-00C04F7971E0} Mon 5/29 12:14:03.426 [main@132e575] Device found--'Silicondust HDHomeRun Tuner 1020A3F1-0' Mon 5/29 12:14:03.426 [main@132e575] Device found 'Ear Force Stealth 450' Mon 5/29 12:14:03.426 [main@132e575] Device found 'Silicondust HDHomeRun Tuner 1020A3F1-0' Mon 5/29 12:14:03.426 [main@132e575] systemCapDevices=[Ear Force Stealth 450, Silicondust HDHomeRun Tuner 1020A3F1-0] Mon 5/29 12:14:03.432 [main@132e575] Checking encoder key:115125682 Mon 5/29 12:14:03.440 [main@132e575] Created DShowCapDev object for:Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:14:03.444 [main@132e575] EncoderMap1={Silicondust HDHomeRun Tuner 1020A3F1-0=Silicondust HDHomeRun Tuner 1020A3F1-0} Mon 5/29 12:14:03.771 [main@132e575] Capture device Silicondust HDHomeRun Tuner 1020A3F1-0 (0) exists Mon 5/29 12:14:03.772 [main@132e575] @DEBUG@ device found 'Ear Force Stealth 450' (index:0) for 'Silicondust HDHomeRun Tuner 1020A3F1-0'. Mon 5/29 12:14:04.036 [main@132e575] Device desc:''. Mon 5/29 12:14:04.302 [main@132e575] BDA capture is found on location:'Silicondust HDHomeRun Tuner 1020A3F1-0' id:'' for Silicondust HDHomeRun Tuner 1020A3F1-0-0 (@device:sw:{FD0A5AF4-B41D-11D2-9C95-00C04F7971E0}\Silicondust HDHomeRun Tuner 1020A3F1-0) (it's a BDA only). Mon 5/29 12:14:04.302 [main@132e575] BDA CaptureDetail:0x0; hasBDAInput:0x1; BDA type:0x0 Mon 5/29 12:14:04.303 [main@132e575] CaptureFilter in (BDA_RECEIVER_COMPONENT) Receiver component Mon 5/29 12:14:04.303 [main@132e575] fake BDA crossbar cap0 is added for 'Silicondust HDHomeRun Tuner 1020A3F1-0' Mon 5/29 12:14:04.568 [main@132e575] DeviceCap: 0x49000 Mon 5/29 12:14:04.833 [main@132e575] BDA capture is found on location:'Silicondust HDHomeRun Tuner 1020A3F1-0' id:'' for Silicondust HDHomeRun Tuner 1020A3F1-0-0 (@device:sw:{FD0A5AF4-B41D-11D2-9C95-00C04F7971E0}\Silicondust HDHomeRun Tuner 1020A3F1-0) (it's a BDA only). Mon 5/29 12:14:04.833 [main@132e575] BDA FindVideoPin:0x0 hasBDAInput:1 on Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:14:05.333 [main@132e575] Encoder passed the check:Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:14:05.336 [main@132e575] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:14:05.353 [main@132e575] Done writing out the data to the properties file Mon 5/29 12:14:05.355 [main@132e575] Processing new system dev:Ear Force Stealth 450 Mon 5/29 12:14:05.358 [main@132e575] Processing new system dev:Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:14:05.360 [main@132e575] Device already has been processed Mon 5/29 12:14:05.362 [main@132e575] devices detected=[Silicondust HDHomeRun Tuner 1020A3F1-0] Mon 5/29 12:14:05.364 [main@132e575] EncoderMap={Silicondust HDHomeRun Tuner 1020A3F1-0=Silicondust HDHomeRun Tuner 1020A3F1-0} Mon 5/29 12:14:05.366 [main@132e575] Splash: SageTV Core is initializing... Mon 5/29 12:14:05.384 [main@132e575] Checking video directories for new files Mon 5/29 12:14:05.396 [main@132e575] DShowFilters=[AC3 Parser Filter, AC3File, AC3Filter, ACM Wrapper, AVI Decompressor, AVI Draw, AVI Mux, AVI Splitter, AVI/WAV File Source, BDA MPEG2 Transport Information Filter, Closed Captions Analysis Filter, Color Space Converter, DirectVobSub, DirectVobSub (auto-loading version), DV Muxer, DV Splitter, DV Video Decoder, DVD Navigator, Enhanced Video Renderer, File Source (Async.), File Source (URL), File stream renderer, File writer, Haali Video Renderer, Infinite Pin Tee Filter, Internal Script Command Renderer, LAV Audio Decoder, LAV Splitter, LAV Splitter Source, LAV Video Decoder, Line 21 Decoder, Line 21 Decoder 2, Microsoft AC3 Encoder, Microsoft DTV-DVD Audio Decoder, Microsoft DTV-DVD Video Decoder, Microsoft MPEG-2 Audio Encoder, Microsoft MPEG-2 Encoder, Microsoft MPEG-2 Video Encoder, MIDI Parser, MJPEG Decompressor, MP3 Decoder DMO, MPEG Audio Decoder, MPEG Video Decoder, MPEG-2 Demultiplexer, MPEG-2 Sections and Tables, MPEG-2 Splitter, MPEG-2 Video Stream Analyzer, MPEG-I Stream Splitter, MPEG2Dump, Mpeg4 Decoder DMO, Mpeg43 Decoder DMO, Mpeg4s Decoder DMO, Multi-file Parser, Null Renderer, Overlay Mixer, Overlay Mixer2, SageTV FileSource, SageTV MPEG Layer II Audio Encoder, SageTV MpegDeMux, SageTV MpegMux, SageTV Music Visualization, SageTV Stream Demuxer, SageTV TS Splitter 3.0, SAMI (CC) Parser, SampleGrabber, SBE2FileScan, SBE2MediaTypeProfile, Silicondust HDHomeRun WMP Plugin, Smart Tee, StreamBufferSink, StreamBufferSink2, StreamBufferSource, VBI Codec, VBI Surface Allocator, VGA 16 Color Ditherer, Video Mixing Renderer 9, Video Port Manager, Video Renderer, Video Renderer, VPS Decoder, Wave Parser, WM ASF Reader, WM ASF Writer, WMAPro over S/PDIF DMO, WMAudio Decoder DMO, WMSpeech Decoder DMO, WMV Screen decoder DMO, WMVideo Decoder DMO, WST Pager] Mon 5/29 12:14:05.399 [main@132e575] EVR support detected=true Mon 5/29 12:14:05.404 [SageTV@2c63ae] Splash: Profiler is initializing... 0% done Mon 5/29 12:14:05.427 [SageTV@2c63ae] CARNY building airing maps... Mon 5/29 12:14:05.514 [SageTV@2c63ae] CARNY Processing 1000 Agents & 62271 Airs Mon 5/29 12:14:05.518 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=true avoidArchive=false Mon 5/29 12:14:05.520 [SageTV@2c63ae] Splash: Waiting for SageTV to finish initialization... Mon 5/29 12:14:05.523 [Scheduler@d1c7aa] initGraph0 (ver 2.1.0) called capDev='Silicondust HDHomeRun Tuner 1020A3F1-0-0', Country:'United States of America', TuningMode:Air, TunerType: Cap:0x49000 Mon 5/29 12:14:05.523 [Carny@189825b] Carny got a Std job of null Mon 5/29 12:14:05.526 [FSManager@7e2e0f] FSManager is running... Mon 5/29 12:14:05.529 [EPG@1864abb] Increased ArrayList pool to size=1 Mon 5/29 12:14:05.532 [MsgMgrSocket@b38dd2] Spawned thread for MsgManger server socket listening... Mon 5/29 12:14:05.535 [EPG@1864abb] sage.epg.sd.SDRipper@7ca980 needs an update in 12:03:04 Mon 5/29 12:14:05.539 [Carny@189825b] CARNY building airing maps... Mon 5/29 12:14:05.542 [EPG@1864abb] EPG needs an update in 617 minutes Mon 5/29 12:14:05.545 [EPG@1864abb] EPG's works is done. Waiting... Mon 5/29 12:14:05.708 [Carny@189825b] CARNY Processing 1000 Agents & 62271 Airs Mon 5/29 12:14:05.711 [Carny@189825b] Carny waiting for awhile... Mon 5/29 12:14:05.786 [Scheduler@d1c7aa] BDA capture is found on location:'Silicondust HDHomeRun Tuner 1020A3F1-0' id:'' for Silicondust HDHomeRun Tuner 1020A3F1-0-0 (@device:sw:{FD0A5AF4-B41D-11D2-9C95-00C04F7971E0}\Silicondust HDHomeRun Tuner 1020A3F1-0) (it's a BDA only). Mon 5/29 12:14:05.787 [Scheduler@d1c7aa] Add BDA filters (capture:'@device:sw:{FD0A5AF4-B41D-11D2-9C95-00C04F7971E0}\Silicondust HDHomeRun Tuner 1020A3F1-0') for device=Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:14:05.787 [Scheduler@d1c7aa] Not specify TV type ( Network type ), to search matching one. Mon 5/29 12:14:05.815 [Scheduler@d1c7aa] Loaded Sage TV Splitter Filter 0x123e5d4 Mon 5/29 12:14:05.815 [Scheduler@d1c7aa] BDA Filters are loaded Mon 5/29 12:14:05.816 [MainMsg@15eefcd] switchToConnector0 tuningMode:Air. Mon 5/29 12:14:05.819 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:14:05.824 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:14:05.828 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:14:05.831 [LibraryImporter@a2aab6] Checking video directories for new files Mon 5/29 12:14:05.834 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:14:05.837 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:14:05.840 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:14:05.842 [Seeker@8c613a] Seeker waiting for 153722842346158 mins. Mon 5/29 12:14:05.845 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:14:05.847 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:14:05.849 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:14:05.849 [Scheduler@d1c7aa] [] Mon 5/29 12:14:05.852 [Scheduler@d1c7aa] Total Schedule eval time=29 msec Mon 5/29 12:14:05.854 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:14:05.857 [Seeker@8c613a] Seeker awoken Mon 5/29 12:14:05.860 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:14:05.862 [Seeker@8c613a] MemStats: Used=112MB Total=143MB Max=805MB Mon 5/29 12:14:05.865 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:14:05.869 [Seeker@8c613a] Seeker adding new Encoder: Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:14:05.871 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:14:05.874 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:14:05.877 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:14:05.879 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:14:05.882 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:14:05.885 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:14:05.888 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:14:05.888 [Seeker@8c613a] Seeker waiting for 153722842346158 mins. Mon 5/29 12:14:05.890 [Scheduler@d1c7aa] [] Mon 5/29 12:14:05.893 [Scheduler@d1c7aa] Total Schedule eval time=36 msec Mon 5/29 12:14:05.896 [Seeker@8c613a] Seeker awoken Mon 5/29 12:14:05.898 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:14:05.901 [Seeker@8c613a] MemStats: Used=112MB Total=143MB Max=805MB Mon 5/29 12:14:05.904 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:14:05.906 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:14:05.908 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:14:05.910 [Seeker@8c613a] Seeker waiting for 153722842346158 mins. Mon 5/29 12:14:06.333 [LibraryImporter@a2aab6] Seeker is starting the library import scan... Mon 5/29 12:14:06.341 [LibraryImporter@a2aab6] Starting to scan lib import root: C:\Users\Public\Videos Mon 5/29 12:14:06.345 [LibraryImporter@a2aab6] Increased StringBuffer pool to size=1 Mon 5/29 12:14:06.361 [LibraryImporter@a2aab6] Seeker has finished the library import scan. Mon 5/29 12:14:10.505 [Ministry@1948a88] Ministry is starting Mon 5/29 12:14:10.514 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:14:36.001 [SageTVDiscoveryServer@e27e2f] SageTVDiscoveryServer was instantiated. Mon 5/29 12:14:36.004 [SageTV@2c63ae] Trying to find lan network interface Mon 5/29 12:14:36.006 [SageTVServer@199e9d2] SageTVServer was instantiated loadDelay=36.486 sec Mon 5/29 12:14:36.009 [SageTVMiniDiscoveryServer@1bd64c8] SageTVMiniDiscoveryServer was instantiated. Mon 5/29 12:14:36.038 [SageTV@2c63ae] addr: /127.0.0.1 Mon 5/29 12:14:36.041 [SageTV@2c63ae] addr: /0:0:0:0:0:0:0:1 Mon 5/29 12:14:36.043 [SageTV@2c63ae] addr: /192.168.151.236 Mon 5/29 12:14:36.046 [SageTV@2c63ae] Miniserver running on 192.168.151.236 Mon 5/29 12:14:36.048 [SageTV@2c63ae] Parsed 0 clients Mon 5/29 12:14:36.052 [MiniBootp@141385b] 0.0.0.0/0.0.0.0 16867 Mon 5/29 12:14:36.055 [MiniMVP@1ed77d6] 0.0.0.0/0.0.0.0 16881 Mon 5/29 12:14:36.059 [MiniTftp@18df103] 0.0.0.0/0.0.0.0 16869 Mon 5/29 12:14:36.062 [SageTV@2c63ae] Loading locator keys from filesystem since they already exist. Mon 5/29 12:14:36.079 [SageTV@2c63ae] Splash: User Interface Manager is initializing... Mon 5/29 12:14:36.081 [PSNATMGR@f7ebb3] Starting UPnP NAT Manager... Mon 5/29 12:14:36.084 [HotplugStorage@3c96c1] StorageDeviceDetector started... Mon 5/29 12:14:36.086 [SageTV@2c63ae] Loading all core plugins... Mon 5/29 12:14:36.099 [SageTV@2c63ae] CorePluginManager is checking plugin: googleweather type: Library version: 2.0.3 by: Opus4 Mon 5/29 12:14:36.102 [SageTV@2c63ae] LOADED PLUGINS=[SageTVPlugin[googleweather state=Failed name=Google Weather version=2.0.3 type=Library desc=A weather support library that is used by SageTV's updated weather system to retrieve weather information from Weather Underground. author=Opus4 screenshots=null videos=null modified=7/1/2015 installed=5/28/2017 resPath= webpages=null implementation= desktopOnly=false serverOnly=false OS=Win,Linux,Mac dependencies=null packages=null]] Mon 5/29 12:14:36.107 [SageTV@2c63ae] Starting all core plugins... Mon 5/29 12:14:36.109 [SageTV@2c63ae] Done starting core plugins. Mon 5/29 12:14:36.112 [PSNATMGR@1e140e5] Starting UPnP NAT Manager... Mon 5/29 12:14:36.128 [PluginRepoUpdater@190c56e] Checking to see if the plugin repository file SageTVPlugins.xml needs to be updated using MD5 URL: http://download.sagetv.com/SageTVPlugins.md5.txt Mon 5/29 12:14:36.300 [PluginRepoUpdater@190c56e] Local plugin repository file's checksum matches server's, no need to download a new one for SageTVPlugins.xml Mon 5/29 12:14:36.306 [PluginRepoUpdater@190c56e] Checking to see if the plugin repository file SageTVPluginsV9.xml needs to be updated using MD5 URL: https://raw.githubusercontent.com/OpenSageTV/sagetv-plugin-repo/master/SageTVPluginsV9.md5 Mon 5/29 12:14:36.761 [PluginRepoUpdater@190c56e] Local plugin repository file's checksum matches server's, no need to download a new one for SageTVPluginsV9.xml Mon 5/29 12:14:36.764 [PluginRepoUpdater@190c56e] Analyzing plugin repository XML file: SageTVPlugins.xml Mon 5/29 12:14:36.804 [PluginRepoUpdater@190c56e] Done processing plugin repository XML file[SageTVPlugins.xml] repositoryVersion=1.0.1996 Mon 5/29 12:14:36.807 [PluginRepoUpdater@190c56e] Analyzing plugin repository XML file: SageTVPluginsV9.xml Mon 5/29 12:14:36.812 [PluginRepoUpdater@190c56e] Done processing plugin repository XML file[SageTVPluginsV9.xml] repositoryVersion=2017.0520.2318 Mon 5/29 12:14:41.166 [PSNATMGR@f7ebb3] Successfully setup UPnP port mapping! Mon 5/29 12:14:41.178 [PSNATMGR@1e140e5] Successfully setup UPnP port mapping! Mon 5/29 12:17:10.512 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:18:59.517 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 12:18:59.525 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 12:19:05.925 [Seeker@8c613a] Seeker awoken Mon 5/29 12:19:05.931 [Seeker@8c613a] MemStats: Used=75MB Total=143MB Max=805MB Mon 5/29 12:19:05.937 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:19:05.943 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:19:05.949 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:19:05.954 [Seeker@8c613a] Seeker waiting for 153722842346153 mins. Mon 5/29 12:20:10.516 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:23:10.524 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:23:59.519 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 12:23:59.522 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 12:24:05.971 [Seeker@8c613a] Seeker awoken Mon 5/29 12:24:05.978 [Seeker@8c613a] MemStats: Used=76MB Total=143MB Max=805MB Mon 5/29 12:24:05.984 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:24:05.990 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:24:05.996 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:24:06.002 [Seeker@8c613a] Seeker waiting for 153722842346148 mins. Mon 5/29 12:26:10.534 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:28:32.303 [SageTVServer@199e9d2] SageTV received connection from:Socket[addr=/192.168.151.236,port=61600,localport=42024] Mon 5/29 12:28:32.337 [SageTVServer@199e9d2] Suspending DB writes while we send the DB to the client... Mon 5/29 12:28:32.340 [SageTVServer@199e9d2] Sending DB to client of size:13163737 Mon 5/29 12:28:32.795 [SageTVServer@199e9d2] Logging 0 DB xcts to file and sending them to the new client... Mon 5/29 12:28:32.799 [SageTVServer@199e9d2] DONE sending DB to the client and client is now added as a DB sync listener Mon 5/29 12:28:32.906 [ClientListenerParallelizer@47fbd] Listener parallelizer thread has spawned for /192.168.151.236:61600 Mon 5/29 12:28:32.938 [SageTVServer@199e9d2] SageTV received connection from:Socket[addr=/192.168.151.236,port=61601,localport=42024] Mon 5/29 12:28:32.942 [SageTVServer@199e9d2] Increased Thread pool to size=6 Mon 5/29 12:28:32.945 [ConnSendQueue@1958938] MsgSend thread spawned for /192.168.151.236:61600 Mon 5/29 12:28:32.948 [ConnRecvQueue@fe3232] MsgRecv thread has spawned for /192.168.151.236:61600 Mon 5/29 12:28:32.951 [MiniMVP@1ed77d6] Received mvp packet Mon 5/29 12:28:32.976 [MiniMVP@1ed77d6] Sending mvp reply Mon 5/29 12:28:33.189 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:28:33.192 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:28:33.194 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:28:33.197 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:28:33.199 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:28:33.202 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:28:33.204 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:28:33.204 [Scheduler@d1c7aa] [] Mon 5/29 12:28:33.207 [Scheduler@d1c7aa] Total Schedule eval time=15 msec Mon 5/29 12:28:33.209 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:28:33.211 [Seeker@8c613a] Seeker awoken Mon 5/29 12:28:33.214 [Seeker@8c613a] MemStats: Used=76MB Total=143MB Max=805MB Mon 5/29 12:28:33.217 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:28:33.219 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:28:33.222 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:28:33.224 [Seeker@8c613a] Seeker waiting for 153722842346144 mins. Mon 5/29 12:28:33.942 [ConnRecvQueue@fe3232] Recieving 14 capabilties for client /192.168.151.236:61600 Mon 5/29 12:28:33.944 [ConnRecvQueue@fe3232] SystemStatusChanged = true Mon 5/29 12:28:33.947 [ConnRecvQueue@fe3232] RequestToExceedParentalRestrictions = true Mon 5/29 12:28:33.949 [ConnRecvQueue@fe3232] MediaPlayerFileLoadComplete = true Mon 5/29 12:28:33.952 [ConnRecvQueue@fe3232] RecordRequestLiveConflict = true Mon 5/29 12:28:33.954 [ConnRecvQueue@fe3232] MediaFilesImported = true Mon 5/29 12:28:33.957 [ConnRecvQueue@fe3232] ApplicationStarted = true Mon 5/29 12:28:33.960 [ConnRecvQueue@fe3232] MediaPlayerError = true Mon 5/29 12:28:33.962 [ConnRecvQueue@fe3232] DenyChannelChangeToRecord = true Mon 5/29 12:28:33.965 [ConnRecvQueue@fe3232] StorageDeviceAdded = true Mon 5/29 12:28:33.967 [ConnRecvQueue@fe3232] NewUnresolvedSchedulingConflicts = true Mon 5/29 12:28:33.970 [ConnRecvQueue@fe3232] RecordingScheduleChanged = true Mon 5/29 12:28:33.972 [ConnRecvQueue@fe3232] ApplicationExiting = true Mon 5/29 12:28:33.974 [ConnRecvQueue@fe3232] RecordRequestScheduleConflict = true Mon 5/29 12:28:33.977 [ConnRecvQueue@fe3232] WatchRequestConflict = true Mon 5/29 12:28:41.654 [PooledThread@185f86d] Seeker.finishWatch(/192.168.151.236:61600@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:28:41.843 [PooledThread@185f86d] Called Seeker.requestWatch(A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T]) hostname=/192.168.151.236:61600@@SAGETV_PROCESS_LOCAL_UI Mon 5/29 12:28:41.845 [PooledThread@185f86d] findBestEncoderForNow(A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T] record=false host=/192.168.151.236:61600@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:28:41.848 [PooledThread@185f86d] foundBestEncoder3=sage.Seeker$EncoderState@167e0cf[Silicondust HDHomeRun Tuner 1020A3F1-0] Mon 5/29 12:28:41.850 [PooledThread@185f86d] MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1020A3F1-0 clients=[/192.168.151.236:61600@@SAGETV_PROCESS_LOCAL_UI] ir=false Mon 5/29 12:28:41.853 [PooledThread@185f86d] defaultRecord=A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T] Mon 5/29 12:28:41.855 [PooledThread@185f86d] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Mon 5/29 12:28:41.857 [PooledThread@185f86d] FORCE WATCH executing clients=[/192.168.151.236:61600@@SAGETV_PROCESS_LOCAL_UI] Mon 5/29 12:28:41.860 [PooledThread@185f86d] newRecord=A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T] Mon 5/29 12:28:41.862 [PooledThread@185f86d] Change in record to another show. Entering device record mode. - LATER Mon 5/29 12:28:41.865 [PooledThread@185f86d] Change in record to another show. Entering device record mode. - NOW Mon 5/29 12:28:41.867 [PooledThread@185f86d] Seeker.startRecord(Silicondust HDHomeRun Tuner 1020A3F1-0 A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T], currTime=Mon 5/29 12:28:41.850) currRecord=null switch=false Mon 5/29 12:28:41.870 [PooledThread@185f86d] Setting up MMC video for recording new show & tuning channel conn=Silicondust HDHomeRun Tuner 1020A3F1-0 Digital TV Tuner Mon 5/29 12:28:41.872 [PooledThread@185f86d] Using quality setting "Great" for recording Mon 5/29 12:28:41.876 [PooledThread@185f86d] VideoStorage for new file: C:\Users\Public\Recorded TV - Use All Mon 5/29 12:28:41.881 [PooledThread@185f86d] MediaFile created subfile:C:\Users\Public\Recorded TV\KPIX5NewsatNoon-16189-0.mpg MediaFile[id=157581 A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps []] Mon 5/29 12:28:41.885 [PooledThread@185f86d] Added:MediaFile[id=157581 A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\KPIX5NewsatNoon-16189-0.mpg, Seg0[Mon 5/29 12:28:41.851-Wed 12/31 16:00:00.000]] Mon 5/29 12:28:41.889 [PooledThread@185f86d] Seeker channel string=29-5-1 Mon 5/29 12:28:41.891 [MainMsg@15eefcd] autotune0 digital tuner 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' num=29-5-1 (ver 3.1) Mon 5/29 12:28:41.891 [MainMsg@15eefcd] Splitter Filter set output mpeg2 format Mon 5/29 12:28:41.914 [MainMsg@15eefcd] Connecting capture Filters.(@device:sw:{FD0A5AF4-B41D-11D2-9C95-00C04F7971E0}\Silicondust HDHomeRun Tuner 1020A3F1-0), 'Silicondust HDHomeRun Tuner 1020A3F1-0' 0 Mon 5/29 12:28:41.914 [MainMsg@15eefcd] Network not specified, Try Network type ATSC Mon 5/29 12:28:41.914 [MainMsg@15eefcd] Setup ATSC BDATuningSpace (3 0) Mon 5/29 12:28:41.932 [MainMsg@15eefcd] LoadTuneTable ATSC Mon 5/29 12:28:41.932 [MainMsg@15eefcd] Failed loading frq file Silicondust HDHomeRun Tuner 1020A3F1-0-0-ATSC.frq Mon 5/29 12:28:41.932 [MainMsg@15eefcd] Connected Network Provider to Capture Filter (ATSC); directly connect Network to capture bypass Tuner filter. Mon 5/29 12:28:41.937 [MainMsg@15eefcd] Failed to get Pin-1 to map pid hr=0x80004005 Mon 5/29 12:28:41.938 [MainMsg@15eefcd] Capture filters are connected Mon 5/29 12:28:41.938 [MainMsg@15eefcd] Tuner Plugin ctrl created Mon 5/29 12:28:41.938 [MainMsg@15eefcd] Tuner Plugin not setup in registery 'SOFTWARE\Frey Technologies\Common\TunerPlugin' Mon 5/29 12:28:42.453 [MainMsg@15eefcd] DONE: autotune0 hr=0x0 locked:0 Mon 5/29 12:28:42.453 [MainMsg@15eefcd] setEncodingProperties0 979272312 Great Mon 5/29 12:28:42.453 [MainMsg@15eefcd] Set encoding property audiooutputmode to 0 Mon 5/29 12:28:42.454 [MainMsg@15eefcd] Set encoding property audiocrc to 0 Mon 5/29 12:28:42.454 [MainMsg@15eefcd] Set encoding property gopsize to 15 Mon 5/29 12:28:42.454 [MainMsg@15eefcd] Set encoding property videobitrate to 3800000 Mon 5/29 12:28:42.454 [MainMsg@15eefcd] Set encoding property inversetelecine to 0 Mon 5/29 12:28:42.454 [MainMsg@15eefcd] Set encoding property closedgop to 0 Mon 5/29 12:28:42.454 [MainMsg@15eefcd] Set encoding property vbr to 0 Mon 5/29 12:28:42.455 [MainMsg@15eefcd] Set encoding property outputstreamtype to 10 Mon 5/29 12:28:42.455 [MainMsg@15eefcd] Set encoding property width to 720 Mon 5/29 12:28:42.455 [MainMsg@15eefcd] Set encoding property height to 480 Mon 5/29 12:28:42.455 [MainMsg@15eefcd] Set encoding property audiobitrate to 384 Mon 5/29 12:28:42.455 [MainMsg@15eefcd] Set encoding property audiosampling to 48000 Mon 5/29 12:28:42.455 [MainMsg@15eefcd] Set encoding property disablefilter to 1 Mon 5/29 12:28:42.456 [MainMsg@15eefcd] Set encoding property medianfilter to 3 Mon 5/29 12:28:42.456 [MainMsg@15eefcd] Set encoding property fps to 30 Mon 5/29 12:28:42.456 [MainMsg@15eefcd] Set encoding property ipb to 0 Mon 5/29 12:28:42.456 [MainMsg@15eefcd] Set encoding property deinterlace to 0 Mon 5/29 12:28:42.456 [MainMsg@15eefcd] Set encoding property aspectratio to 1 Mon 5/29 12:28:42.456 [MainMsg@15eefcd] setupEncoding0 called for 979272312 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' (ver 1.0) Mon 5/29 12:28:42.457 [MainMsg@15eefcd] QAM:Got capture drv info for QAM mfg:'' Silicondust HDHomeRun Tuner 1020A3F1-0-0 Mon 5/29 12:28:42.457 [MainMsg@15eefcd] Encoding to file C:\Users\Public\Recorded TV\KPIX5NewsatNoon-16189-0.mpg Mon 5/29 12:28:42.457 [MainMsg@15eefcd] setupEncoding0 Trace27 Mon 5/29 12:28:42.457 [MainMsg@15eefcd] setupEncoding0 Trace28 Mon 5/29 12:28:42.458 [MainMsg@15eefcd] startEncoding0 called for 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:28:42.617 [MainMsg@15eefcd] GetBroadcastStandard ATSC for device:'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:28:42.867 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:28:42.870 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:28:42.872 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:28:42.875 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:28:42.878 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:28:42.881 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:28:42.890 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:28:42.890 [Scheduler@d1c7aa] [A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T]] Mon 5/29 12:28:42.893 [Scheduler@d1c7aa] Total Schedule eval time=23 msec Mon 5/29 12:28:42.897 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:28:42.900 [Seeker@8c613a] Seeker awoken Mon 5/29 12:28:42.903 [Seeker@8c613a] MemStats: Used=78MB Total=143MB Max=805MB Mon 5/29 12:28:42.905 [Seeker@8c613a] Seeker.endRecord(Mon 5/29 12:28:42.897) currRecord=A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T] readySwitch=false Mon 5/29 12:28:42.907 [Seeker@8c613a] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Users\Public\Recorded TV\KPIX5NewsatNoon-16189-0.mpg]) Mon 5/29 12:28:42.913 [MainMsg@15eefcd] stopEncoding0 called (config:0x49000) Mon 5/29 12:28:42.913 [MainMsg@15eefcd] stopEncoding0 trace 1 0x123e5d4 Mon 5/29 12:28:42.913 [MainMsg@15eefcd] stopEncoding0 trace 2 Mon 5/29 12:28:42.913 [MainMsg@15eefcd] stopEncoding0 trace 3 0x195e43c Mon 5/29 12:28:42.922 [MainMsg@15eefcd] stopEncoding0 trace 4 Mon 5/29 12:28:42.922 [MainMsg@15eefcd] Stopped graph res=0x0 Mon 5/29 12:28:42.922 [MainMsg@15eefcd] stopEncoding0 trace 5 Mon 5/29 12:28:42.923 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:28:42.927 [PooledThread@fc1c6d] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\KPIX5NewsatNoon-16189-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:28:42.930 [AsyncPropSaver@185f86d] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:28:42.933 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:28:42.936 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:28:42.938 [Seeker@8c613a] Seeker waiting for 153722842346144 mins. Mon 5/29 12:28:42.940 [AsyncPropSaver@185f86d] Done writing out the data to the properties file Mon 5/29 12:28:59.520 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 12:28:59.527 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 12:29:00.770 [PooledThread@185f86d] Seeker.finishWatch(/192.168.151.236:61600@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:29:00.773 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:29:00.776 [Seeker@8c613a] Seeker awoken Mon 5/29 12:29:00.778 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T]] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:29:00.781 [Seeker@8c613a] MemStats: Used=78MB Total=143MB Max=805MB Mon 5/29 12:29:00.786 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:29:00.789 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:29:00.791 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:29:00.794 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:29:00.796 [Scheduler@d1c7aa] Scheduler cleanup - Removing A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T] from schedule because it has no WP. Mon 5/29 12:29:00.799 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:29:00.802 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:29:00.805 [Seeker@8c613a] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\KPIX5NewsatNoon-16189-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:29:00.808 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:29:00.810 [Seeker@8c613a] Removing MediaFile because it failed verification:MediaFile[id=157581 A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\KPIX5NewsatNoon-16189-0.mpg, Seg0[Mon 5/29 12:28:41.851-Mon 5/29 12:28:42.897]] Mon 5/29 12:29:00.812 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:29:00.813 [Seeker@8c613a] Seeker.destroyFile called for: MediaFile[id=157581 A[16189,10990,"KPIX 5 News at Noon",19572@0529.12:00,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\KPIX5NewsatNoon-16189-0.mpg, Seg0[Mon 5/29 12:28:41.851-Mon 5/29 12:28:42.897]] Mon 5/29 12:29:00.815 [Scheduler@d1c7aa] [] Mon 5/29 12:29:00.819 [Seeker@8c613a] Added file to delete queue: C:\Users\Public\Recorded TV\KPIX5NewsatNoon-16189-0.mpg Mon 5/29 12:29:00.822 [PooledThread@fc1c6d] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:29:00.825 [Seeker@8c613a] Deleted media segment file C:\Users\Public\Recorded TV\KPIX5NewsatNoon-16189-0.mpg dtime=8 len=0 Mon 5/29 12:29:00.828 [Scheduler@d1c7aa] Total Schedule eval time=43 msec Mon 5/29 12:29:00.831 [PooledThread@fc1c6d] Done writing out the data to the properties file Mon 5/29 12:29:00.834 [ProgressiveDeleter@b5dc1b] Starting async delete for:C:\Users\Public\Recorded TV\KPIX5NewsatNoon-16189-0.mpg.delete Mon 5/29 12:29:00.839 [ProgressiveDeleter@b5dc1b] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:29:00.851 [Seeker@8c613a] Seeker waiting for 153722842346143 mins. Mon 5/29 12:29:00.860 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:29:00.862 [ProgressiveDeleter@b5dc1b] Done writing out the data to the properties file Mon 5/29 12:29:00.865 [Seeker@8c613a] Seeker awoken Mon 5/29 12:29:00.869 [ProgressiveDeleter@b5dc1b] Completed progressive deletion of: C:\Users\Public\Recorded TV\KPIX5NewsatNoon-16189-0.mpg.delete Mon 5/29 12:29:00.872 [Seeker@8c613a] MemStats: Used=78MB Total=143MB Max=805MB Mon 5/29 12:29:00.875 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:29:00.878 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:29:00.880 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:29:00.883 [Seeker@8c613a] Seeker waiting for 153722842346143 mins. Mon 5/29 12:29:10.539 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:31:17.830 [PooledThread@1491ca3] Seeker.finishWatch(/192.168.151.236:61600@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:32:10.559 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:33:59.533 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 12:33:59.539 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 12:34:00.888 [Seeker@8c613a] Seeker awoken Mon 5/29 12:34:00.892 [Seeker@8c613a] MemStats: Used=76MB Total=143MB Max=805MB Mon 5/29 12:34:00.895 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:34:00.898 [AsyncPropSaver@1491ca3] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:34:00.900 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:34:00.903 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:34:00.906 [Seeker@8c613a] Seeker waiting for 153722842346138 mins. Mon 5/29 12:34:00.909 [AsyncPropSaver@1491ca3] Done writing out the data to the properties file Mon 5/29 12:34:40.021 [PooledThread@1491ca3] Seeker.finishWatch(/192.168.151.236:61600@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:34:40.055 [PooledThread@1491ca3] Seeker.finishWatch(/192.168.151.236:61600@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:34:40.061 [PooledThread@1491ca3] Seeker.finishWatch(/192.168.151.236:61600@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:34:42.372 [ConnRecvQueue@fe3232] Error communicating with server:java.io.EOFException Mon 5/29 12:34:42.379 [ConnRecvQueue@fe3232] Cleaning up c/s connection Mon 5/29 12:34:42.385 [ConnRecvQueue@fe3232] NetworkManager CommunicationFailure : /192.168.151.236:61600 type=1 Mon 5/29 12:34:42.389 [ConnRecvQueue@fe3232] NetworkClient fullCleanup /192.168.151.236:61600 Mon 5/29 12:34:42.392 [ConnRecvQueue@fe3232] Cleaning up c/s connection Mon 5/29 12:34:42.396 [ConnRecvQueue@fe3232] Cleaning up c/s connection Mon 5/29 12:34:42.399 [ConnRecvQueue@fe3232] MsgRecv thread is terminating for /192.168.151.236:61600 Mon 5/29 12:34:42.402 [Seeker@8c613a] Seeker awoken Mon 5/29 12:34:42.406 [Seeker@8c613a] MemStats: Used=77MB Total=143MB Max=805MB Mon 5/29 12:34:42.409 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:34:42.412 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:34:42.415 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:34:42.418 [Seeker@8c613a] Seeker waiting for 153722842346138 mins. Mon 5/29 12:34:43.360 [KeepAlive@a2aab6] Error with c/s comm: keepAlive:java.lang.NullPointerException Mon 5/29 12:34:43.367 [KeepAlive@a2aab6] java.lang.NullPointerException Mon 5/29 12:34:43.367 [KeepAlive@a2aab6] at sage.SageTVConnection.keepAlive(SageTVConnection.java:2791) Mon 5/29 12:34:43.367 [KeepAlive@a2aab6] at sage.SageTVConnection.access$200(SageTVConnection.java:21) Mon 5/29 12:34:43.368 [KeepAlive@a2aab6] at sage.SageTVConnection$5.run(SageTVConnection.java:3287) Mon 5/29 12:34:43.368 [KeepAlive@a2aab6] at sage.Pooler$PooledThread.run(Pooler.java:254) Mon 5/29 12:34:43.368 [KeepAlive@a2aab6] NetworkManager CommunicationFailure : /192.168.151.236:61600 type=2 Mon 5/29 12:34:43.374 [KeepAlive@a2aab6] NetworkManager CommunicationFailure : /192.168.151.236:61600 type=2 Mon 5/29 12:34:47.097 [SageTVDiscoveryServer@e27e2f] Server got broadcast packet: java.net.DatagramPacket@258f43 Mon 5/29 12:34:47.100 [SageTVDiscoveryServer@e27e2f] Server sent back discovery data:java.net.DatagramPacket@258f43 Mon 5/29 12:34:54.684 [SageTVServer@199e9d2] SageTV received connection from:Socket[addr=/192.168.151.236,port=61634,localport=42024] Mon 5/29 12:34:54.721 [SageTVServer@199e9d2] Suspending DB writes while we send the DB to the client... Mon 5/29 12:34:54.724 [SageTVServer@199e9d2] Sending DB to client of size:13164617 Mon 5/29 12:34:55.059 [ConnSendQueue@1958938] MsgSend thread terminating for /192.168.151.236:61600 Mon 5/29 12:34:55.145 [SageTVServer@199e9d2] Logging 0 DB xcts to file and sending them to the new client... Mon 5/29 12:34:55.148 [SageTVServer@199e9d2] DONE sending DB to the client and client is now added as a DB sync listener Mon 5/29 12:34:55.254 [ClientListenerParallelizer@1958938] Listener parallelizer thread has spawned for /192.168.151.236:61634 Mon 5/29 12:34:55.267 [SageTVServer@199e9d2] SageTV received connection from:Socket[addr=/192.168.151.236,port=61635,localport=42024] Mon 5/29 12:34:55.271 [ConnSendQueue@fe3232] MsgSend thread spawned for /192.168.151.236:61634 Mon 5/29 12:34:55.274 [ConnRecvQueue@fc1c6d] MsgRecv thread has spawned for /192.168.151.236:61634 Mon 5/29 12:34:55.282 [MiniMVP@1ed77d6] Received mvp packet Mon 5/29 12:34:55.306 [MiniMVP@1ed77d6] Sending mvp reply Mon 5/29 12:34:55.524 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:34:55.528 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:34:55.533 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:34:55.537 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:34:55.540 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:34:55.543 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:34:55.546 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:34:55.546 [Scheduler@d1c7aa] [] Mon 5/29 12:34:55.549 [Scheduler@d1c7aa] Total Schedule eval time=21 msec Mon 5/29 12:34:55.552 [Seeker@8c613a] Seeker awoken Mon 5/29 12:34:55.555 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:34:55.558 [Seeker@8c613a] MemStats: Used=78MB Total=143MB Max=805MB Mon 5/29 12:34:55.561 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:34:55.565 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:34:55.568 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:34:55.571 [Seeker@8c613a] Seeker waiting for 153722842346138 mins. Mon 5/29 12:34:56.101 [ConnRecvQueue@fc1c6d] Recieving 14 capabilties for client /192.168.151.236:61634 Mon 5/29 12:34:56.104 [ConnRecvQueue@fc1c6d] SystemStatusChanged = true Mon 5/29 12:34:56.107 [ConnRecvQueue@fc1c6d] RequestToExceedParentalRestrictions = true Mon 5/29 12:34:56.110 [ConnRecvQueue@fc1c6d] MediaPlayerFileLoadComplete = true Mon 5/29 12:34:56.112 [ConnRecvQueue@fc1c6d] RecordRequestLiveConflict = true Mon 5/29 12:34:56.115 [ConnRecvQueue@fc1c6d] MediaFilesImported = true Mon 5/29 12:34:56.118 [ConnRecvQueue@fc1c6d] ApplicationStarted = true Mon 5/29 12:34:56.121 [ConnRecvQueue@fc1c6d] MediaPlayerError = true Mon 5/29 12:34:56.123 [ConnRecvQueue@fc1c6d] DenyChannelChangeToRecord = true Mon 5/29 12:34:56.128 [ConnRecvQueue@fc1c6d] StorageDeviceAdded = true Mon 5/29 12:34:56.131 [ConnRecvQueue@fc1c6d] NewUnresolvedSchedulingConflicts = true Mon 5/29 12:34:56.134 [ConnRecvQueue@fc1c6d] RecordingScheduleChanged = true Mon 5/29 12:34:56.137 [ConnRecvQueue@fc1c6d] ApplicationExiting = true Mon 5/29 12:34:56.139 [ConnRecvQueue@fc1c6d] RecordRequestScheduleConflict = true Mon 5/29 12:34:56.142 [ConnRecvQueue@fc1c6d] WatchRequestConflict = true Mon 5/29 12:35:00.890 [ClientListenerParallelizer@47fbd] Listener parallelizer thread is terminating for /192.168.151.236:61600 Mon 5/29 12:35:03.449 [PooledThread@47fbd] Seeker.finishWatch(/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:35:03.603 [PooledThread@47fbd] Called Seeker.requestWatch(A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T]) hostname=/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI Mon 5/29 12:35:03.606 [PooledThread@47fbd] findBestEncoderForNow(A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] record=false host=/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:35:03.608 [PooledThread@47fbd] foundBestEncoder3=sage.Seeker$EncoderState@167e0cf[Silicondust HDHomeRun Tuner 1020A3F1-0] Mon 5/29 12:35:03.611 [PooledThread@47fbd] MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1020A3F1-0 clients=[/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI] ir=false Mon 5/29 12:35:03.613 [PooledThread@47fbd] defaultRecord=A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] Mon 5/29 12:35:03.615 [PooledThread@47fbd] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Mon 5/29 12:35:03.617 [PooledThread@47fbd] FORCE WATCH executing clients=[/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI] Mon 5/29 12:35:03.620 [PooledThread@47fbd] newRecord=A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] Mon 5/29 12:35:03.622 [PooledThread@47fbd] Change in record to another show. Entering device record mode. - LATER Mon 5/29 12:35:03.624 [PooledThread@47fbd] Change in record to another show. Entering device record mode. - NOW Mon 5/29 12:35:03.627 [PooledThread@47fbd] Seeker.startRecord(Silicondust HDHomeRun Tuner 1020A3F1-0 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T], currTime=Mon 5/29 12:35:03.611) currRecord=null switch=false Mon 5/29 12:35:03.629 [PooledThread@47fbd] Setting up MMC video for recording new show & tuning channel conn=Silicondust HDHomeRun Tuner 1020A3F1-0 Digital TV Tuner Mon 5/29 12:35:03.631 [PooledThread@47fbd] Using quality setting "Great" for recording Mon 5/29 12:35:03.634 [PooledThread@47fbd] VideoStorage for new file: C:\Users\Public\Recorded TV - Use All Mon 5/29 12:35:03.636 [PooledThread@47fbd] MediaFile created subfile:C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg MediaFile[id=157582 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps []] Mon 5/29 12:35:03.639 [PooledThread@47fbd] Added:MediaFile[id=157582 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg, Seg0[Mon 5/29 12:35:03.612-Wed 12/31 16:00:00.000]] Mon 5/29 12:35:03.642 [PooledThread@47fbd] Seeker channel string=29-5-1 Mon 5/29 12:35:03.645 [MainMsg@15eefcd] autotune0 digital tuner 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' num=29-5-1 (ver 3.1) Mon 5/29 12:35:03.645 [MainMsg@15eefcd] Splitter Filter set output mpeg2 format Mon 5/29 12:35:04.150 [MainMsg@15eefcd] DONE: autotune0 hr=0x0 locked:0 Mon 5/29 12:35:04.151 [MainMsg@15eefcd] setEncodingProperties0 979272312 Great Mon 5/29 12:35:04.151 [MainMsg@15eefcd] Set encoding property audiooutputmode to 0 Mon 5/29 12:35:04.151 [MainMsg@15eefcd] Set encoding property audiocrc to 0 Mon 5/29 12:35:04.151 [MainMsg@15eefcd] Set encoding property gopsize to 15 Mon 5/29 12:35:04.152 [MainMsg@15eefcd] Set encoding property videobitrate to 3800000 Mon 5/29 12:35:04.152 [MainMsg@15eefcd] Set encoding property inversetelecine to 0 Mon 5/29 12:35:04.152 [MainMsg@15eefcd] Set encoding property closedgop to 0 Mon 5/29 12:35:04.152 [MainMsg@15eefcd] Set encoding property vbr to 0 Mon 5/29 12:35:04.152 [MainMsg@15eefcd] Set encoding property outputstreamtype to 10 Mon 5/29 12:35:04.152 [MainMsg@15eefcd] Set encoding property width to 720 Mon 5/29 12:35:04.152 [MainMsg@15eefcd] Set encoding property height to 480 Mon 5/29 12:35:04.153 [MainMsg@15eefcd] Set encoding property audiobitrate to 384 Mon 5/29 12:35:04.153 [MainMsg@15eefcd] Set encoding property audiosampling to 48000 Mon 5/29 12:35:04.153 [MainMsg@15eefcd] Set encoding property disablefilter to 1 Mon 5/29 12:35:04.153 [MainMsg@15eefcd] Set encoding property medianfilter to 3 Mon 5/29 12:35:04.153 [MainMsg@15eefcd] Set encoding property fps to 30 Mon 5/29 12:35:04.153 [MainMsg@15eefcd] Set encoding property ipb to 0 Mon 5/29 12:35:04.154 [MainMsg@15eefcd] Set encoding property deinterlace to 0 Mon 5/29 12:35:04.154 [MainMsg@15eefcd] Set encoding property aspectratio to 1 Mon 5/29 12:35:04.154 [MainMsg@15eefcd] setupEncoding0 called for 979272312 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' (ver 1.0) Mon 5/29 12:35:04.154 [MainMsg@15eefcd] QAM:Got capture drv info for QAM mfg:'' Silicondust HDHomeRun Tuner 1020A3F1-0-0 Mon 5/29 12:35:04.154 [MainMsg@15eefcd] Encoding to file C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg Mon 5/29 12:35:04.155 [MainMsg@15eefcd] setupEncoding0 Trace27 Mon 5/29 12:35:04.155 [MainMsg@15eefcd] setupEncoding0 Trace28 Mon 5/29 12:35:04.155 [MainMsg@15eefcd] startEncoding0 called for 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:35:04.318 [MainMsg@15eefcd] GetBroadcastStandard ATSC for device:'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:35:04.576 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:35:04.580 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:35:04.583 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:35:04.585 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:35:04.589 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:35:04.592 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:35:04.594 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:35:04.595 [Scheduler@d1c7aa] [A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T]] Mon 5/29 12:35:04.598 [Scheduler@d1c7aa] Total Schedule eval time=18 msec Mon 5/29 12:35:04.601 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:35:04.604 [Seeker@8c613a] Seeker awoken Mon 5/29 12:35:04.607 [Seeker@8c613a] MemStats: Used=79MB Total=143MB Max=805MB Mon 5/29 12:35:04.610 [Seeker@8c613a] Seeker.endRecord(Mon 5/29 12:35:04.601) currRecord=A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] readySwitch=false Mon 5/29 12:35:04.612 [Seeker@8c613a] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg]) Mon 5/29 12:35:04.615 [MainMsg@15eefcd] stopEncoding0 called (config:0x49000) Mon 5/29 12:35:04.615 [MainMsg@15eefcd] stopEncoding0 trace 1 0x123e5d4 Mon 5/29 12:35:04.615 [MainMsg@15eefcd] stopEncoding0 trace 2 Mon 5/29 12:35:04.615 [MainMsg@15eefcd] stopEncoding0 trace 3 0x195e43c Mon 5/29 12:35:04.633 [MainMsg@15eefcd] stopEncoding0 trace 4 Mon 5/29 12:35:04.633 [MainMsg@15eefcd] Stopped graph res=0x0 Mon 5/29 12:35:04.633 [MainMsg@15eefcd] stopEncoding0 trace 5 Mon 5/29 12:35:04.633 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:35:04.636 [AsyncPropSaver@185f86d] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:04.639 [PooledThread@a2aab6] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:35:04.641 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:35:04.644 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:35:04.646 [AsyncPropSaver@185f86d] Done writing out the data to the properties file Mon 5/29 12:35:04.648 [Seeker@8c613a] Seeker waiting for 153722842346137 mins. Mon 5/29 12:35:08.127 [PooledThread@47fbd] Called Seeker.requestWatch(A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T]) hostname=/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI Mon 5/29 12:35:08.129 [PooledThread@47fbd] findBestEncoderForNow(A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] record=false host=/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:35:08.132 [PooledThread@47fbd] foundBestEncoder3=sage.Seeker$EncoderState@167e0cf[Silicondust HDHomeRun Tuner 1020A3F1-0] Mon 5/29 12:35:08.134 [PooledThread@47fbd] MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1020A3F1-0 clients=[/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI] ir=false Mon 5/29 12:35:08.137 [PooledThread@47fbd] defaultRecord=A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] Mon 5/29 12:35:08.139 [PooledThread@47fbd] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Mon 5/29 12:35:08.142 [PooledThread@47fbd] FORCE WATCH executing clients=[/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI] Mon 5/29 12:35:08.144 [PooledThread@47fbd] newRecord=A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] Mon 5/29 12:35:08.147 [PooledThread@47fbd] Change in record to another show. Entering device record mode. - LATER Mon 5/29 12:35:08.149 [PooledThread@47fbd] Change in record to another show. Entering device record mode. - NOW Mon 5/29 12:35:08.152 [PooledThread@47fbd] Seeker.startRecord(Silicondust HDHomeRun Tuner 1020A3F1-0 A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T], currTime=Mon 5/29 12:35:08.134) currRecord=null switch=false Mon 5/29 12:35:08.154 [PooledThread@47fbd] Setting up MMC video for recording new show & tuning channel conn=Silicondust HDHomeRun Tuner 1020A3F1-0 Digital TV Tuner Mon 5/29 12:35:08.156 [PooledThread@47fbd] Using quality setting "Great" for recording Mon 5/29 12:35:08.159 [PooledThread@47fbd] VideoStorage for new file: C:\Users\Public\Recorded TV - Use All Mon 5/29 12:35:08.161 [PooledThread@47fbd] MediaFile created subfile:C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg MediaFile[id=157583 A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT2 format=MPEG2-PS 0:00:00 0 kbps []] Mon 5/29 12:35:08.164 [PooledThread@47fbd] Added:MediaFile[id=157583 A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT2 format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg, Seg0[Mon 5/29 12:35:08.135-Wed 12/31 16:00:00.000]] Mon 5/29 12:35:08.167 [PooledThread@47fbd] Seeker channel string=29-5-2 Mon 5/29 12:35:08.169 [MainMsg@15eefcd] autotune0 digital tuner 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' num=29-5-2 (ver 3.1) Mon 5/29 12:35:08.169 [MainMsg@15eefcd] Splitter Filter set output mpeg2 format Mon 5/29 12:35:08.673 [MainMsg@15eefcd] DONE: autotune0 hr=0x0 locked:0 Mon 5/29 12:35:08.673 [MainMsg@15eefcd] setEncodingProperties0 979272312 Great Mon 5/29 12:35:08.674 [MainMsg@15eefcd] Set encoding property audiooutputmode to 0 Mon 5/29 12:35:08.674 [MainMsg@15eefcd] Set encoding property audiocrc to 0 Mon 5/29 12:35:08.674 [MainMsg@15eefcd] Set encoding property gopsize to 15 Mon 5/29 12:35:08.674 [MainMsg@15eefcd] Set encoding property videobitrate to 3800000 Mon 5/29 12:35:08.674 [MainMsg@15eefcd] Set encoding property inversetelecine to 0 Mon 5/29 12:35:08.674 [MainMsg@15eefcd] Set encoding property closedgop to 0 Mon 5/29 12:35:08.675 [MainMsg@15eefcd] Set encoding property vbr to 0 Mon 5/29 12:35:08.675 [MainMsg@15eefcd] Set encoding property outputstreamtype to 10 Mon 5/29 12:35:08.675 [MainMsg@15eefcd] Set encoding property width to 720 Mon 5/29 12:35:08.675 [MainMsg@15eefcd] Set encoding property height to 480 Mon 5/29 12:35:08.675 [MainMsg@15eefcd] Set encoding property audiobitrate to 384 Mon 5/29 12:35:08.675 [MainMsg@15eefcd] Set encoding property audiosampling to 48000 Mon 5/29 12:35:08.675 [MainMsg@15eefcd] Set encoding property disablefilter to 1 Mon 5/29 12:35:08.676 [MainMsg@15eefcd] Set encoding property medianfilter to 3 Mon 5/29 12:35:08.676 [MainMsg@15eefcd] Set encoding property fps to 30 Mon 5/29 12:35:08.676 [MainMsg@15eefcd] Set encoding property ipb to 0 Mon 5/29 12:35:08.676 [MainMsg@15eefcd] Set encoding property deinterlace to 0 Mon 5/29 12:35:08.676 [MainMsg@15eefcd] Set encoding property aspectratio to 1 Mon 5/29 12:35:08.676 [MainMsg@15eefcd] setupEncoding0 called for 979272312 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' (ver 1.0) Mon 5/29 12:35:08.676 [MainMsg@15eefcd] QAM:Got capture drv info for QAM mfg:'' Silicondust HDHomeRun Tuner 1020A3F1-0-0 Mon 5/29 12:35:08.677 [MainMsg@15eefcd] Encoding to file C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg Mon 5/29 12:35:08.677 [MainMsg@15eefcd] setupEncoding0 Trace27 Mon 5/29 12:35:08.677 [MainMsg@15eefcd] setupEncoding0 Trace28 Mon 5/29 12:35:08.677 [MainMsg@15eefcd] startEncoding0 called for 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:35:08.841 [MainMsg@15eefcd] GetBroadcastStandard ATSC for device:'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:35:09.092 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:35:09.096 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T]] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:35:09.098 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:35:09.100 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:35:09.103 [Scheduler@d1c7aa] Scheduler cleanup - Removing A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] from schedule because it has no WP. Mon 5/29 12:35:09.105 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:35:09.108 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:35:09.110 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:35:09.111 [Scheduler@d1c7aa] [A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T]] Mon 5/29 12:35:09.113 [Scheduler@d1c7aa] Total Schedule eval time=18 msec Mon 5/29 12:35:09.115 [Seeker@8c613a] Seeker awoken Mon 5/29 12:35:09.117 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:35:09.119 [Seeker@8c613a] MemStats: Used=79MB Total=143MB Max=805MB Mon 5/29 12:35:09.122 [Seeker@8c613a] Seeker.endRecord(Mon 5/29 12:35:09.115) currRecord=A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] readySwitch=false Mon 5/29 12:35:09.124 [Seeker@8c613a] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg]) Mon 5/29 12:35:09.127 [MainMsg@15eefcd] stopEncoding0 called (config:0x49000) Mon 5/29 12:35:09.127 [MainMsg@15eefcd] stopEncoding0 trace 1 0x123e5d4 Mon 5/29 12:35:09.127 [MainMsg@15eefcd] stopEncoding0 trace 2 Mon 5/29 12:35:09.127 [MainMsg@15eefcd] stopEncoding0 trace 3 0x195e43c Mon 5/29 12:35:09.144 [MainMsg@15eefcd] stopEncoding0 trace 4 Mon 5/29 12:35:09.144 [MainMsg@15eefcd] Stopped graph res=0x0 Mon 5/29 12:35:09.144 [MainMsg@15eefcd] stopEncoding0 trace 5 Mon 5/29 12:35:09.145 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:35:09.147 [AsyncPropSaver@185f86d] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:09.150 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:35:09.152 [PooledThread@a2aab6] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:35:09.155 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:35:09.157 [AsyncPropSaver@185f86d] Done writing out the data to the properties file Mon 5/29 12:35:09.159 [Seeker@8c613a] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:35:09.162 [Seeker@8c613a] Removing MediaFile because it failed verification:MediaFile[id=157582 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg, Seg0[Mon 5/29 12:35:03.612-Mon 5/29 12:35:04.601]] Mon 5/29 12:35:09.164 [Seeker@8c613a] Seeker.destroyFile called for: MediaFile[id=157582 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg, Seg0[Mon 5/29 12:35:03.612-Mon 5/29 12:35:04.601]] Mon 5/29 12:35:09.166 [PooledThread@185f86d] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:09.169 [Seeker@8c613a] Added file to delete queue: C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg Mon 5/29 12:35:09.172 [Seeker@8c613a] Deleted media segment file C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg dtime=6 len=0 Mon 5/29 12:35:09.174 [ProgressiveDeleter@b5dc1b] Starting async delete for:C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg.delete Mon 5/29 12:35:09.177 [PooledThread@185f86d] Done writing out the data to the properties file Mon 5/29 12:35:09.179 [Seeker@8c613a] Seeker waiting for 153722842346137 mins. Mon 5/29 12:35:09.181 [ProgressiveDeleter@b5dc1b] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:09.190 [ProgressiveDeleter@b5dc1b] Done writing out the data to the properties file Mon 5/29 12:35:09.193 [ProgressiveDeleter@b5dc1b] Completed progressive deletion of: C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg.delete Mon 5/29 12:35:09.195 [PooledThread@47fbd] Called Seeker.requestWatch(A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T]) hostname=/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI Mon 5/29 12:35:09.198 [PooledThread@47fbd] findBestEncoderForNow(A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T] record=false host=/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:35:09.200 [PooledThread@47fbd] foundBestEncoder3=sage.Seeker$EncoderState@167e0cf[Silicondust HDHomeRun Tuner 1020A3F1-0] Mon 5/29 12:35:09.203 [PooledThread@47fbd] MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1020A3F1-0 clients=[/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI] ir=false Mon 5/29 12:35:09.206 [PooledThread@47fbd] defaultRecord=A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] Mon 5/29 12:35:09.210 [PooledThread@47fbd] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Mon 5/29 12:35:09.212 [PooledThread@47fbd] FORCE WATCH executing clients=[/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI] Mon 5/29 12:35:09.214 [PooledThread@47fbd] newRecord=A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T] Mon 5/29 12:35:09.216 [PooledThread@47fbd] Change in record to another show. Entering device record mode. - LATER Mon 5/29 12:35:09.219 [PooledThread@47fbd] Change in record to another show. Entering device record mode. - NOW Mon 5/29 12:35:09.221 [PooledThread@47fbd] Seeker.startRecord(Silicondust HDHomeRun Tuner 1020A3F1-0 A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T], currTime=Mon 5/29 12:35:09.203) currRecord=null switch=false Mon 5/29 12:35:09.223 [PooledThread@47fbd] Setting up MMC video for recording new show & tuning channel conn=Silicondust HDHomeRun Tuner 1020A3F1-0 Digital TV Tuner Mon 5/29 12:35:09.226 [PooledThread@47fbd] Using quality setting "Great" for recording Mon 5/29 12:35:09.228 [PooledThread@47fbd] VideoStorage for new file: C:\Users\Public\Recorded TV - Use All Mon 5/29 12:35:09.231 [PooledThread@47fbd] MediaFile created subfile:C:\Users\Public\Recorded TV\ThomasFriends-FastandReliable-16586-0.mpg MediaFile[id=157584 A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KVIEDT format=MPEG2-PS 0:00:00 0 kbps []] Mon 5/29 12:35:09.233 [PooledThread@47fbd] Added:MediaFile[id=157584 A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KVIEDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\ThomasFriends-FastandReliable-16586-0.mpg, Seg0[Mon 5/29 12:35:09.204-Wed 12/31 16:00:00.000]] Mon 5/29 12:35:09.236 [PooledThread@47fbd] Seeker channel string=9-6-1 Mon 5/29 12:35:09.239 [MainMsg@15eefcd] autotune0 digital tuner 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' num=9-6-1 (ver 3.1) Mon 5/29 12:35:09.239 [MainMsg@15eefcd] Splitter Filter set output mpeg2 format Mon 5/29 12:35:09.748 [MainMsg@15eefcd] DONE: autotune0 hr=0x0 locked:0 Mon 5/29 12:35:09.749 [MainMsg@15eefcd] setEncodingProperties0 979272312 Great Mon 5/29 12:35:09.749 [MainMsg@15eefcd] Set encoding property audiooutputmode to 0 Mon 5/29 12:35:09.749 [MainMsg@15eefcd] Set encoding property audiocrc to 0 Mon 5/29 12:35:09.749 [MainMsg@15eefcd] Set encoding property gopsize to 15 Mon 5/29 12:35:09.749 [MainMsg@15eefcd] Set encoding property videobitrate to 3800000 Mon 5/29 12:35:09.749 [MainMsg@15eefcd] Set encoding property inversetelecine to 0 Mon 5/29 12:35:09.749 [MainMsg@15eefcd] Set encoding property closedgop to 0 Mon 5/29 12:35:09.750 [MainMsg@15eefcd] Set encoding property vbr to 0 Mon 5/29 12:35:09.750 [MainMsg@15eefcd] Set encoding property outputstreamtype to 10 Mon 5/29 12:35:09.750 [MainMsg@15eefcd] Set encoding property width to 720 Mon 5/29 12:35:09.750 [MainMsg@15eefcd] Set encoding property height to 480 Mon 5/29 12:35:09.750 [MainMsg@15eefcd] Set encoding property audiobitrate to 384 Mon 5/29 12:35:09.750 [MainMsg@15eefcd] Set encoding property audiosampling to 48000 Mon 5/29 12:35:09.750 [MainMsg@15eefcd] Set encoding property disablefilter to 1 Mon 5/29 12:35:09.751 [MainMsg@15eefcd] Set encoding property medianfilter to 3 Mon 5/29 12:35:09.751 [MainMsg@15eefcd] Set encoding property fps to 30 Mon 5/29 12:35:09.751 [MainMsg@15eefcd] Set encoding property ipb to 0 Mon 5/29 12:35:09.751 [MainMsg@15eefcd] Set encoding property deinterlace to 0 Mon 5/29 12:35:09.751 [MainMsg@15eefcd] Set encoding property aspectratio to 1 Mon 5/29 12:35:09.751 [MainMsg@15eefcd] setupEncoding0 called for 979272312 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' (ver 1.0) Mon 5/29 12:35:09.752 [MainMsg@15eefcd] QAM:Got capture drv info for QAM mfg:'' Silicondust HDHomeRun Tuner 1020A3F1-0-0 Mon 5/29 12:35:09.752 [MainMsg@15eefcd] Encoding to file C:\Users\Public\Recorded TV\ThomasFriends-FastandReliable-16586-0.mpg Mon 5/29 12:35:09.752 [MainMsg@15eefcd] setupEncoding0 Trace27 Mon 5/29 12:35:09.752 [MainMsg@15eefcd] setupEncoding0 Trace28 Mon 5/29 12:35:09.753 [MainMsg@15eefcd] startEncoding0 called for 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:35:09.903 [MainMsg@15eefcd] GetBroadcastStandard ATSC for device:'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:35:10.160 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:35:10.164 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T]] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:35:10.167 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:35:10.170 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:35:10.173 [Scheduler@d1c7aa] Scheduler cleanup - Removing A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] from schedule because it has no WP. Mon 5/29 12:35:10.175 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:35:10.178 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:35:10.180 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:35:10.180 [Scheduler@d1c7aa] [A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T]] Mon 5/29 12:35:10.182 [Scheduler@d1c7aa] Total Schedule eval time=18 msec Mon 5/29 12:35:10.185 [Seeker@8c613a] Seeker awoken Mon 5/29 12:35:10.187 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:35:10.190 [Seeker@8c613a] MemStats: Used=80MB Total=143MB Max=805MB Mon 5/29 12:35:10.192 [Seeker@8c613a] Seeker.endRecord(Mon 5/29 12:35:10.185) currRecord=A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T] readySwitch=false Mon 5/29 12:35:10.194 [Seeker@8c613a] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Users\Public\Recorded TV\ThomasFriends-FastandReliable-16586-0.mpg]) Mon 5/29 12:35:10.197 [MainMsg@15eefcd] stopEncoding0 called (config:0x49000) Mon 5/29 12:35:10.197 [MainMsg@15eefcd] stopEncoding0 trace 1 0x123e5d4 Mon 5/29 12:35:10.197 [MainMsg@15eefcd] stopEncoding0 trace 2 Mon 5/29 12:35:10.197 [MainMsg@15eefcd] stopEncoding0 trace 3 0x195e43c Mon 5/29 12:35:10.219 [MainMsg@15eefcd] stopEncoding0 trace 4 Mon 5/29 12:35:10.219 [MainMsg@15eefcd] Stopped graph res=0x0 Mon 5/29 12:35:10.219 [MainMsg@15eefcd] stopEncoding0 trace 5 Mon 5/29 12:35:10.219 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:35:10.222 [AsyncPropSaver@a2aab6] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:10.224 [PooledThread@185f86d] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\ThomasFriends-FastandReliable-16586-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:35:10.227 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:35:10.229 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:35:10.232 [AsyncPropSaver@a2aab6] Done writing out the data to the properties file Mon 5/29 12:35:10.234 [Seeker@8c613a] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:35:10.236 [Seeker@8c613a] Removing MediaFile because it failed verification:MediaFile[id=157583 A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT2 format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg, Seg0[Mon 5/29 12:35:08.135-Mon 5/29 12:35:09.115]] Mon 5/29 12:35:10.239 [Seeker@8c613a] Seeker.destroyFile called for: MediaFile[id=157583 A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT2 format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg, Seg0[Mon 5/29 12:35:08.135-Mon 5/29 12:35:09.115]] Mon 5/29 12:35:10.241 [PooledThread@a2aab6] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:10.243 [Seeker@8c613a] Added file to delete queue: C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg Mon 5/29 12:35:10.246 [Seeker@8c613a] Deleted media segment file C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg dtime=5 len=0 Mon 5/29 12:35:10.249 [PooledThread@a2aab6] Done writing out the data to the properties file Mon 5/29 12:35:10.251 [Seeker@8c613a] Seeker waiting for 153722842346137 mins. Mon 5/29 12:35:10.569 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:35:11.315 [PooledThread@47fbd] Called Seeker.requestWatch(A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T]) hostname=/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI Mon 5/29 12:35:11.317 [PooledThread@47fbd] findBestEncoderForNow(A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] record=false host=/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:35:11.320 [PooledThread@47fbd] foundBestEncoder3=sage.Seeker$EncoderState@167e0cf[Silicondust HDHomeRun Tuner 1020A3F1-0] Mon 5/29 12:35:11.322 [PooledThread@47fbd] MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1020A3F1-0 clients=[/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI] ir=false Mon 5/29 12:35:11.325 [PooledThread@47fbd] defaultRecord=A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T] Mon 5/29 12:35:11.327 [PooledThread@47fbd] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Mon 5/29 12:35:11.329 [PooledThread@47fbd] FORCE WATCH executing clients=[/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI] Mon 5/29 12:35:11.332 [PooledThread@47fbd] newRecord=A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] Mon 5/29 12:35:11.334 [PooledThread@47fbd] Change in record to another show. Entering device record mode. - LATER Mon 5/29 12:35:11.337 [PooledThread@47fbd] Change in record to another show. Entering device record mode. - NOW Mon 5/29 12:35:11.339 [PooledThread@47fbd] Seeker.startRecord(Silicondust HDHomeRun Tuner 1020A3F1-0 A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T], currTime=Mon 5/29 12:35:11.322) currRecord=null switch=false Mon 5/29 12:35:11.341 [PooledThread@47fbd] Setting up MMC video for recording new show & tuning channel conn=Silicondust HDHomeRun Tuner 1020A3F1-0 Digital TV Tuner Mon 5/29 12:35:11.343 [PooledThread@47fbd] Using quality setting "Great" for recording Mon 5/29 12:35:11.346 [PooledThread@47fbd] VideoStorage for new file: C:\Users\Public\Recorded TV - Use All Mon 5/29 12:35:11.348 [PooledThread@47fbd] MediaFile created subfile:C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg MediaFile[id=157585 A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT2 format=MPEG2-PS 0:00:00 0 kbps []] Mon 5/29 12:35:11.351 [PooledThread@47fbd] Added:MediaFile[id=157585 A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT2 format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg, Seg0[Mon 5/29 12:35:11.323-Wed 12/31 16:00:00.000]] Mon 5/29 12:35:11.354 [PooledThread@47fbd] Seeker channel string=29-5-2 Mon 5/29 12:35:11.357 [MainMsg@15eefcd] autotune0 digital tuner 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' num=29-5-2 (ver 3.1) Mon 5/29 12:35:11.357 [MainMsg@15eefcd] Splitter Filter set output mpeg2 format Mon 5/29 12:35:11.861 [MainMsg@15eefcd] DONE: autotune0 hr=0x0 locked:0 Mon 5/29 12:35:11.862 [MainMsg@15eefcd] setEncodingProperties0 979272312 Great Mon 5/29 12:35:11.862 [MainMsg@15eefcd] Set encoding property audiooutputmode to 0 Mon 5/29 12:35:11.862 [MainMsg@15eefcd] Set encoding property audiocrc to 0 Mon 5/29 12:35:11.862 [MainMsg@15eefcd] Set encoding property gopsize to 15 Mon 5/29 12:35:11.862 [MainMsg@15eefcd] Set encoding property videobitrate to 3800000 Mon 5/29 12:35:11.863 [MainMsg@15eefcd] Set encoding property inversetelecine to 0 Mon 5/29 12:35:11.863 [MainMsg@15eefcd] Set encoding property closedgop to 0 Mon 5/29 12:35:11.863 [MainMsg@15eefcd] Set encoding property vbr to 0 Mon 5/29 12:35:11.863 [MainMsg@15eefcd] Set encoding property outputstreamtype to 10 Mon 5/29 12:35:11.863 [MainMsg@15eefcd] Set encoding property width to 720 Mon 5/29 12:35:11.863 [MainMsg@15eefcd] Set encoding property height to 480 Mon 5/29 12:35:11.863 [MainMsg@15eefcd] Set encoding property audiobitrate to 384 Mon 5/29 12:35:11.864 [MainMsg@15eefcd] Set encoding property audiosampling to 48000 Mon 5/29 12:35:11.864 [MainMsg@15eefcd] Set encoding property disablefilter to 1 Mon 5/29 12:35:11.864 [MainMsg@15eefcd] Set encoding property medianfilter to 3 Mon 5/29 12:35:11.864 [MainMsg@15eefcd] Set encoding property fps to 30 Mon 5/29 12:35:11.864 [MainMsg@15eefcd] Set encoding property ipb to 0 Mon 5/29 12:35:11.864 [MainMsg@15eefcd] Set encoding property deinterlace to 0 Mon 5/29 12:35:11.864 [MainMsg@15eefcd] Set encoding property aspectratio to 1 Mon 5/29 12:35:11.864 [MainMsg@15eefcd] setupEncoding0 called for 979272312 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' (ver 1.0) Mon 5/29 12:35:11.865 [MainMsg@15eefcd] QAM:Got capture drv info for QAM mfg:'' Silicondust HDHomeRun Tuner 1020A3F1-0-0 Mon 5/29 12:35:11.865 [MainMsg@15eefcd] Encoding to file C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg Mon 5/29 12:35:11.865 [MainMsg@15eefcd] setupEncoding0 Trace27 Mon 5/29 12:35:11.865 [MainMsg@15eefcd] setupEncoding0 Trace28 Mon 5/29 12:35:11.866 [MainMsg@15eefcd] startEncoding0 called for 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:35:12.011 [MainMsg@15eefcd] GetBroadcastStandard ATSC for device:'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:35:12.264 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:35:12.270 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T]] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:35:12.276 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:35:12.282 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:35:12.288 [Scheduler@d1c7aa] Scheduler cleanup - Removing A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T] from schedule because it has no WP. Mon 5/29 12:35:12.291 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:35:12.293 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:35:12.296 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:35:12.296 [Scheduler@d1c7aa] [A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T]] Mon 5/29 12:35:12.298 [Scheduler@d1c7aa] Total Schedule eval time=28 msec Mon 5/29 12:35:12.300 [Seeker@8c613a] Seeker awoken Mon 5/29 12:35:12.303 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:35:12.305 [Seeker@8c613a] MemStats: Used=81MB Total=143MB Max=805MB Mon 5/29 12:35:12.307 [Seeker@8c613a] Seeker.endRecord(Mon 5/29 12:35:12.300) currRecord=A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] readySwitch=false Mon 5/29 12:35:12.309 [Seeker@8c613a] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg]) Mon 5/29 12:35:12.312 [MainMsg@15eefcd] stopEncoding0 called (config:0x49000) Mon 5/29 12:35:12.312 [MainMsg@15eefcd] stopEncoding0 trace 1 0x123e5d4 Mon 5/29 12:35:12.312 [MainMsg@15eefcd] stopEncoding0 trace 2 Mon 5/29 12:35:12.312 [MainMsg@15eefcd] stopEncoding0 trace 3 0x195e43c Mon 5/29 12:35:12.329 [MainMsg@15eefcd] stopEncoding0 trace 4 Mon 5/29 12:35:12.329 [MainMsg@15eefcd] Stopped graph res=0x0 Mon 5/29 12:35:12.329 [MainMsg@15eefcd] stopEncoding0 trace 5 Mon 5/29 12:35:12.330 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:35:12.332 [AsyncPropSaver@a2aab6] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:12.335 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:35:12.337 [PooledThread@185f86d] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:35:12.339 [AsyncPropSaver@a2aab6] Done writing out the data to the properties file Mon 5/29 12:35:12.342 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:35:12.344 [Seeker@8c613a] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\ThomasFriends-FastandReliable-16586-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:35:12.347 [Seeker@8c613a] Removing MediaFile because it failed verification:MediaFile[id=157584 A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KVIEDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\ThomasFriends-FastandReliable-16586-0.mpg, Seg0[Mon 5/29 12:35:09.204-Mon 5/29 12:35:10.185]] Mon 5/29 12:35:12.350 [Seeker@8c613a] Seeker.destroyFile called for: MediaFile[id=157584 A[16586,5654,"Thomas & Friends",34197@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KVIEDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\ThomasFriends-FastandReliable-16586-0.mpg, Seg0[Mon 5/29 12:35:09.204-Mon 5/29 12:35:10.185]] Mon 5/29 12:35:12.353 [PooledThread@a2aab6] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:12.355 [Seeker@8c613a] Added file to delete queue: C:\Users\Public\Recorded TV\ThomasFriends-FastandReliable-16586-0.mpg Mon 5/29 12:35:12.357 [Seeker@8c613a] Deleted media segment file C:\Users\Public\Recorded TV\ThomasFriends-FastandReliable-16586-0.mpg dtime=5 len=0 Mon 5/29 12:35:12.360 [Seeker@8c613a] Seeker waiting for 153722842346137 mins. Mon 5/29 12:35:12.362 [PooledThread@a2aab6] Done writing out the data to the properties file Mon 5/29 12:35:12.586 [PooledThread@a2aab6] Called Seeker.requestWatch(A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T]) hostname=/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI Mon 5/29 12:35:12.589 [PooledThread@a2aab6] findBestEncoderForNow(A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] record=false host=/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:35:12.591 [PooledThread@a2aab6] foundBestEncoder3=sage.Seeker$EncoderState@167e0cf[Silicondust HDHomeRun Tuner 1020A3F1-0] Mon 5/29 12:35:12.594 [PooledThread@a2aab6] MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1020A3F1-0 clients=[/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI] ir=false Mon 5/29 12:35:12.596 [PooledThread@a2aab6] defaultRecord=A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] Mon 5/29 12:35:12.599 [PooledThread@a2aab6] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Mon 5/29 12:35:12.601 [PooledThread@a2aab6] FORCE WATCH executing clients=[/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI] Mon 5/29 12:35:12.603 [PooledThread@a2aab6] newRecord=A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] Mon 5/29 12:35:12.605 [PooledThread@a2aab6] Change in record to another show. Entering device record mode. - LATER Mon 5/29 12:35:12.607 [PooledThread@a2aab6] Change in record to another show. Entering device record mode. - NOW Mon 5/29 12:35:12.610 [PooledThread@a2aab6] Seeker.startRecord(Silicondust HDHomeRun Tuner 1020A3F1-0 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T], currTime=Mon 5/29 12:35:12.594) currRecord=null switch=false Mon 5/29 12:35:12.612 [PooledThread@a2aab6] Setting up MMC video for recording new show & tuning channel conn=Silicondust HDHomeRun Tuner 1020A3F1-0 Digital TV Tuner Mon 5/29 12:35:12.614 [PooledThread@a2aab6] Using quality setting "Great" for recording Mon 5/29 12:35:12.617 [PooledThread@a2aab6] VideoStorage for new file: C:\Users\Public\Recorded TV - Use All Mon 5/29 12:35:12.619 [PooledThread@a2aab6] MediaFile created subfile:C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg MediaFile[id=157586 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps []] Mon 5/29 12:35:12.622 [PooledThread@a2aab6] Added:MediaFile[id=157586 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg, Seg0[Mon 5/29 12:35:12.595-Wed 12/31 16:00:00.000]] Mon 5/29 12:35:12.625 [PooledThread@a2aab6] Seeker channel string=29-5-1 Mon 5/29 12:35:12.627 [MainMsg@15eefcd] autotune0 digital tuner 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' num=29-5-1 (ver 3.1) Mon 5/29 12:35:12.627 [MainMsg@15eefcd] Splitter Filter set output mpeg2 format Mon 5/29 12:35:13.138 [MainMsg@15eefcd] DONE: autotune0 hr=0x0 locked:0 Mon 5/29 12:35:13.138 [MainMsg@15eefcd] setEncodingProperties0 979272312 Great Mon 5/29 12:35:13.139 [MainMsg@15eefcd] Set encoding property audiooutputmode to 0 Mon 5/29 12:35:13.139 [MainMsg@15eefcd] Set encoding property audiocrc to 0 Mon 5/29 12:35:13.139 [MainMsg@15eefcd] Set encoding property gopsize to 15 Mon 5/29 12:35:13.139 [MainMsg@15eefcd] Set encoding property videobitrate to 3800000 Mon 5/29 12:35:13.139 [MainMsg@15eefcd] Set encoding property inversetelecine to 0 Mon 5/29 12:35:13.139 [MainMsg@15eefcd] Set encoding property closedgop to 0 Mon 5/29 12:35:13.139 [MainMsg@15eefcd] Set encoding property vbr to 0 Mon 5/29 12:35:13.140 [MainMsg@15eefcd] Set encoding property outputstreamtype to 10 Mon 5/29 12:35:13.140 [MainMsg@15eefcd] Set encoding property width to 720 Mon 5/29 12:35:13.140 [MainMsg@15eefcd] Set encoding property height to 480 Mon 5/29 12:35:13.140 [MainMsg@15eefcd] Set encoding property audiobitrate to 384 Mon 5/29 12:35:13.140 [MainMsg@15eefcd] Set encoding property audiosampling to 48000 Mon 5/29 12:35:13.140 [MainMsg@15eefcd] Set encoding property disablefilter to 1 Mon 5/29 12:35:13.140 [MainMsg@15eefcd] Set encoding property medianfilter to 3 Mon 5/29 12:35:13.140 [MainMsg@15eefcd] Set encoding property fps to 30 Mon 5/29 12:35:13.141 [MainMsg@15eefcd] Set encoding property ipb to 0 Mon 5/29 12:35:13.141 [MainMsg@15eefcd] Set encoding property deinterlace to 0 Mon 5/29 12:35:13.141 [MainMsg@15eefcd] Set encoding property aspectratio to 1 Mon 5/29 12:35:13.141 [MainMsg@15eefcd] setupEncoding0 called for 979272312 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' (ver 1.0) Mon 5/29 12:35:13.141 [MainMsg@15eefcd] QAM:Got capture drv info for QAM mfg:'' Silicondust HDHomeRun Tuner 1020A3F1-0-0 Mon 5/29 12:35:13.141 [MainMsg@15eefcd] Encoding to file C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg Mon 5/29 12:35:13.142 [MainMsg@15eefcd] setupEncoding0 Trace27 Mon 5/29 12:35:13.142 [MainMsg@15eefcd] setupEncoding0 Trace28 Mon 5/29 12:35:13.142 [MainMsg@15eefcd] startEncoding0 called for 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:35:13.289 [MainMsg@15eefcd] GetBroadcastStandard ATSC for device:'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:35:13.546 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:35:13.552 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T]] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:35:13.558 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:35:13.564 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:35:13.570 [Scheduler@d1c7aa] Scheduler cleanup - Removing A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] from schedule because it has no WP. Mon 5/29 12:35:13.576 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:35:13.578 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:35:13.581 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:35:13.581 [Scheduler@d1c7aa] [A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T]] Mon 5/29 12:35:13.584 [Scheduler@d1c7aa] Total Schedule eval time=32 msec Mon 5/29 12:35:13.586 [Seeker@8c613a] Seeker awoken Mon 5/29 12:35:13.589 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:35:13.592 [Seeker@8c613a] MemStats: Used=81MB Total=143MB Max=805MB Mon 5/29 12:35:13.594 [Seeker@8c613a] Seeker.endRecord(Mon 5/29 12:35:13.586) currRecord=A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] readySwitch=false Mon 5/29 12:35:13.597 [Seeker@8c613a] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg]) Mon 5/29 12:35:13.600 [MainMsg@15eefcd] stopEncoding0 called (config:0x49000) Mon 5/29 12:35:13.600 [MainMsg@15eefcd] stopEncoding0 trace 1 0x123e5d4 Mon 5/29 12:35:13.600 [MainMsg@15eefcd] stopEncoding0 trace 2 Mon 5/29 12:35:13.600 [MainMsg@15eefcd] stopEncoding0 trace 3 0x195e43c Mon 5/29 12:35:13.618 [MainMsg@15eefcd] stopEncoding0 trace 4 Mon 5/29 12:35:13.618 [MainMsg@15eefcd] Stopped graph res=0x0 Mon 5/29 12:35:13.618 [MainMsg@15eefcd] stopEncoding0 trace 5 Mon 5/29 12:35:13.619 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:35:13.621 [AsyncPropSaver@47fbd] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:13.624 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:35:13.626 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:35:13.628 [PooledThread@185f86d] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:35:13.630 [AsyncPropSaver@47fbd] Done writing out the data to the properties file Mon 5/29 12:35:13.633 [Seeker@8c613a] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:35:13.635 [Seeker@8c613a] Removing MediaFile because it failed verification:MediaFile[id=157585 A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT2 format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg, Seg0[Mon 5/29 12:35:11.323-Mon 5/29 12:35:12.300]] Mon 5/29 12:35:13.637 [Seeker@8c613a] Seeker.destroyFile called for: MediaFile[id=157585 A[16656,10195,"Peter Gunn",91800@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT2 format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg, Seg0[Mon 5/29 12:35:11.323-Mon 5/29 12:35:12.300]] Mon 5/29 12:35:13.640 [PooledThread@47fbd] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:13.642 [Seeker@8c613a] Added file to delete queue: C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg Mon 5/29 12:35:13.645 [Seeker@8c613a] Deleted media segment file C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg dtime=5 len=0 Mon 5/29 12:35:13.647 [Seeker@8c613a] Seeker waiting for 153722842346137 mins. Mon 5/29 12:35:13.650 [PooledThread@47fbd] Done writing out the data to the properties file Mon 5/29 12:35:14.209 [ProgressiveDeleter@b5dc1b] Starting async delete for:C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg.delete Mon 5/29 12:35:14.216 [ProgressiveDeleter@b5dc1b] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:14.233 [ProgressiveDeleter@b5dc1b] Done writing out the data to the properties file Mon 5/29 12:35:14.239 [ProgressiveDeleter@b5dc1b] Completed progressive deletion of: C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg.delete Mon 5/29 12:35:19.248 [ProgressiveDeleter@b5dc1b] Starting async delete for:C:\Users\Public\Recorded TV\ThomasFriends-FastandReliable-16586-0.mpg.delete Mon 5/29 12:35:19.256 [ProgressiveDeleter@b5dc1b] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:19.272 [ProgressiveDeleter@b5dc1b] Done writing out the data to the properties file Mon 5/29 12:35:19.277 [ProgressiveDeleter@b5dc1b] Completed progressive deletion of: C:\Users\Public\Recorded TV\ThomasFriends-FastandReliable-16586-0.mpg.delete Mon 5/29 12:35:24.283 [ProgressiveDeleter@b5dc1b] Starting async delete for:C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg.delete.delete Mon 5/29 12:35:24.286 [ProgressiveDeleter@b5dc1b] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:24.294 [ProgressiveDeleter@b5dc1b] Done writing out the data to the properties file Mon 5/29 12:35:24.297 [ProgressiveDeleter@b5dc1b] Completed progressive deletion of: C:\Users\Public\Recorded TV\PeterGunn-TheLongLongRide-16656-0.mpg.delete.delete Mon 5/29 12:35:26.207 [PooledThread@47fbd] Seeker.finishWatch(/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:35:26.210 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:35:26.213 [Seeker@8c613a] Seeker awoken Mon 5/29 12:35:26.215 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T]] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:35:26.218 [Seeker@8c613a] MemStats: Used=82MB Total=143MB Max=805MB Mon 5/29 12:35:26.220 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:35:26.223 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:35:26.225 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:35:26.228 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:35:26.230 [Scheduler@d1c7aa] Scheduler cleanup - Removing A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] from schedule because it has no WP. Mon 5/29 12:35:26.233 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:35:26.236 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:35:26.238 [Seeker@8c613a] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:35:26.241 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:35:26.244 [Seeker@8c613a] Removing MediaFile because it failed verification:MediaFile[id=157586 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg, Seg0[Mon 5/29 12:35:12.595-Mon 5/29 12:35:13.586]] Mon 5/29 12:35:26.246 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:35:26.246 [Seeker@8c613a] Seeker.destroyFile called for: MediaFile[id=157586 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg, Seg0[Mon 5/29 12:35:12.595-Mon 5/29 12:35:13.586]] Mon 5/29 12:35:26.249 [Scheduler@d1c7aa] [] Mon 5/29 12:35:26.253 [Seeker@8c613a] Added file to delete queue: C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg Mon 5/29 12:35:26.255 [PooledThread@47fbd] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:26.258 [Seeker@8c613a] Deleted media segment file C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg dtime=6 len=0 Mon 5/29 12:35:26.260 [Scheduler@d1c7aa] Total Schedule eval time=40 msec Mon 5/29 12:35:26.264 [PooledThread@47fbd] Done writing out the data to the properties file Mon 5/29 12:35:26.267 [Seeker@8c613a] Seeker waiting for 153722842346137 mins. Mon 5/29 12:35:26.270 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:35:26.272 [Seeker@8c613a] Seeker awoken Mon 5/29 12:35:26.275 [Seeker@8c613a] MemStats: Used=82MB Total=143MB Max=805MB Mon 5/29 12:35:26.277 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:35:26.280 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:35:26.282 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:35:26.285 [Seeker@8c613a] Seeker waiting for 153722842346137 mins. Mon 5/29 12:35:29.300 [ProgressiveDeleter@b5dc1b] Starting async delete for:C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg.delete Mon 5/29 12:35:29.304 [ProgressiveDeleter@b5dc1b] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:35:29.313 [ProgressiveDeleter@b5dc1b] Done writing out the data to the properties file Mon 5/29 12:35:29.316 [ProgressiveDeleter@b5dc1b] Completed progressive deletion of: C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg.delete Mon 5/29 12:35:34.034 [PooledThread@a2aab6] Seeker.finishWatch(/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:35:34.068 [PooledThread@a2aab6] Seeker.finishWatch(/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:35:34.074 [PooledThread@a2aab6] Seeker.finishWatch(/192.168.151.236:61634@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:35:35.466 [KeepAlive@1491ca3] Error with c/s comm: keepAlive:java.net.SocketException: Software caused connection abort: recv failed Mon 5/29 12:35:35.472 [KeepAlive@1491ca3] java.net.SocketException: Software caused connection abort: recv failed Mon 5/29 12:35:35.472 [KeepAlive@1491ca3] at java.net.SocketInputStream.socketRead0(Native Method) Mon 5/29 12:35:35.473 [KeepAlive@1491ca3] at java.net.SocketInputStream.socketRead(Unknown Source) Mon 5/29 12:35:35.473 [KeepAlive@1491ca3] at java.net.SocketInputStream.read(Unknown Source) Mon 5/29 12:35:35.473 [KeepAlive@1491ca3] at java.net.SocketInputStream.read(Unknown Source) Mon 5/29 12:35:35.473 [KeepAlive@1491ca3] at java.io.BufferedInputStream.fill(Unknown Source) Mon 5/29 12:35:35.473 [KeepAlive@1491ca3] at java.io.BufferedInputStream.read(Unknown Source) Mon 5/29 12:35:35.473 [KeepAlive@1491ca3] at sage.SageTVConnection$1.readByte(SageTVConnection.java:231) Mon 5/29 12:35:35.473 [KeepAlive@1491ca3] at sage.SageTVConnection.readLineBytes(SageTVConnection.java:2850) Mon 5/29 12:35:35.473 [KeepAlive@1491ca3] at sage.SageTVConnection.keepAlive(SageTVConnection.java:2793) Mon 5/29 12:35:35.473 [KeepAlive@1491ca3] at sage.SageTVConnection.access$200(SageTVConnection.java:21) Mon 5/29 12:35:35.473 [KeepAlive@1491ca3] at sage.SageTVConnection$5.run(SageTVConnection.java:3287) Mon 5/29 12:35:35.474 [KeepAlive@1491ca3] at sage.Pooler$PooledThread.run(Pooler.java:254) Mon 5/29 12:35:35.474 [KeepAlive@1491ca3] NetworkManager CommunicationFailure : /192.168.151.236:61634 type=2 Mon 5/29 12:35:35.479 [KeepAlive@1491ca3] NetworkClient fullCleanup /192.168.151.236:61634 Mon 5/29 12:35:35.485 [KeepAlive@1491ca3] Cleaning up c/s connection Mon 5/29 12:35:35.491 [KeepAlive@1491ca3] Cleaning up c/s connection Mon 5/29 12:35:35.496 [KeepAlive@1491ca3] NetworkManager CommunicationFailure : /192.168.151.236:61634 type=2 Mon 5/29 12:35:35.499 [ConnRecvQueue@fc1c6d] Error communicating with server:java.net.SocketException: Socket Closed Mon 5/29 12:35:35.502 [ConnRecvQueue@fc1c6d] Cleaning up c/s connection Mon 5/29 12:35:35.504 [Seeker@8c613a] Seeker awoken Mon 5/29 12:35:35.506 [ConnRecvQueue@fc1c6d] NetworkManager CommunicationFailure : /192.168.151.236:61634 type=1 Mon 5/29 12:35:35.509 [Seeker@8c613a] MemStats: Used=82MB Total=143MB Max=805MB Mon 5/29 12:35:35.511 [ConnRecvQueue@fc1c6d] MsgRecv thread is terminating for /192.168.151.236:61634 Mon 5/29 12:35:35.513 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:35:35.516 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:35:35.518 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:35:35.520 [Seeker@8c613a] Seeker waiting for 153722842346137 mins. Mon 5/29 12:35:41.575 [SageTVServer@199e9d2] SageTV received connection from:Socket[addr=/127.0.0.1,port=61676,localport=42024] Mon 5/29 12:35:41.612 [SageTVServer@199e9d2] Suspending DB writes while we send the DB to the client... Mon 5/29 12:35:41.615 [SageTVServer@199e9d2] Sending DB to client of size:13169217 Mon 5/29 12:35:41.988 [SageTVServer@199e9d2] Logging 0 DB xcts to file and sending them to the new client... Mon 5/29 12:35:41.991 [SageTVServer@199e9d2] DONE sending DB to the client and client is now added as a DB sync listener Mon 5/29 12:35:42.126 [ClientListenerParallelizer@fc1c6d] Listener parallelizer thread has spawned for /127.0.0.1:61676 Mon 5/29 12:35:42.151 [SageTVServer@199e9d2] SageTV received connection from:Socket[addr=/127.0.0.1,port=61677,localport=42024] Mon 5/29 12:35:42.155 [ConnSendQueue@47fbd] MsgSend thread spawned for /127.0.0.1:61676 Mon 5/29 12:35:42.158 [ConnRecvQueue@185f86d] MsgRecv thread has spawned for /127.0.0.1:61676 Mon 5/29 12:35:42.414 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:35:42.418 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:35:42.421 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:35:42.424 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:35:42.426 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:35:42.429 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:35:42.432 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:35:42.432 [Scheduler@d1c7aa] [] Mon 5/29 12:35:42.434 [Scheduler@d1c7aa] Total Schedule eval time=16 msec Mon 5/29 12:35:42.437 [Seeker@8c613a] Seeker awoken Mon 5/29 12:35:42.440 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:35:42.442 [Seeker@8c613a] MemStats: Used=83MB Total=143MB Max=805MB Mon 5/29 12:35:42.445 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:35:42.448 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:35:42.451 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:35:42.453 [Seeker@8c613a] Seeker waiting for 153722842346137 mins. Mon 5/29 12:35:43.028 [ConnRecvQueue@185f86d] Recieving 14 capabilties for client /127.0.0.1:61676 Mon 5/29 12:35:43.032 [ConnRecvQueue@185f86d] SystemStatusChanged = true Mon 5/29 12:35:43.034 [ConnRecvQueue@185f86d] RequestToExceedParentalRestrictions = true Mon 5/29 12:35:43.037 [ConnRecvQueue@185f86d] MediaPlayerFileLoadComplete = true Mon 5/29 12:35:43.040 [ConnRecvQueue@185f86d] RecordRequestLiveConflict = true Mon 5/29 12:35:43.043 [ConnRecvQueue@185f86d] MediaFilesImported = true Mon 5/29 12:35:43.045 [ConnRecvQueue@185f86d] ApplicationStarted = true Mon 5/29 12:35:43.048 [ConnRecvQueue@185f86d] MediaPlayerError = true Mon 5/29 12:35:43.051 [ConnRecvQueue@185f86d] DenyChannelChangeToRecord = true Mon 5/29 12:35:43.054 [ConnRecvQueue@185f86d] StorageDeviceAdded = true Mon 5/29 12:35:43.056 [ConnRecvQueue@185f86d] NewUnresolvedSchedulingConflicts = true Mon 5/29 12:35:43.059 [ConnRecvQueue@185f86d] RecordingScheduleChanged = true Mon 5/29 12:35:43.062 [ConnRecvQueue@185f86d] ApplicationExiting = true Mon 5/29 12:35:43.064 [ConnRecvQueue@185f86d] RecordRequestScheduleConflict = true Mon 5/29 12:35:43.067 [ConnRecvQueue@185f86d] WatchRequestConflict = true Mon 5/29 12:35:49.075 [ConnSendQueue@fe3232] MsgSend thread terminating for /192.168.151.236:61634 Mon 5/29 12:35:56.268 [ClientListenerParallelizer@1958938] Listener parallelizer thread is terminating for /192.168.151.236:61634 Mon 5/29 12:36:02.165 [PooledThread@1958938] Seeker.finishWatch(/127.0.0.1:61676@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:36:02.328 [PooledThread@1958938] Called Seeker.requestWatch(A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T]) hostname=/127.0.0.1:61676@@SAGETV_PROCESS_LOCAL_UI Mon 5/29 12:36:02.331 [PooledThread@1958938] findBestEncoderForNow(A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] record=false host=/127.0.0.1:61676@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:36:02.333 [PooledThread@1958938] foundBestEncoder3=sage.Seeker$EncoderState@167e0cf[Silicondust HDHomeRun Tuner 1020A3F1-0] Mon 5/29 12:36:02.335 [PooledThread@1958938] MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1020A3F1-0 clients=[/127.0.0.1:61676@@SAGETV_PROCESS_LOCAL_UI] ir=false Mon 5/29 12:36:02.338 [PooledThread@1958938] defaultRecord=A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] Mon 5/29 12:36:02.340 [PooledThread@1958938] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Mon 5/29 12:36:02.343 [PooledThread@1958938] FORCE WATCH executing clients=[/127.0.0.1:61676@@SAGETV_PROCESS_LOCAL_UI] Mon 5/29 12:36:02.345 [PooledThread@1958938] newRecord=A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] Mon 5/29 12:36:02.347 [PooledThread@1958938] Change in record to another show. Entering device record mode. - LATER Mon 5/29 12:36:02.350 [PooledThread@1958938] Change in record to another show. Entering device record mode. - NOW Mon 5/29 12:36:02.352 [PooledThread@1958938] Seeker.startRecord(Silicondust HDHomeRun Tuner 1020A3F1-0 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T], currTime=Mon 5/29 12:36:02.335) currRecord=null switch=false Mon 5/29 12:36:02.354 [PooledThread@1958938] Setting up MMC video for recording new show & tuning channel conn=Silicondust HDHomeRun Tuner 1020A3F1-0 Digital TV Tuner Mon 5/29 12:36:02.357 [PooledThread@1958938] Using quality setting "Great" for recording Mon 5/29 12:36:02.359 [PooledThread@1958938] VideoStorage for new file: C:\Users\Public\Recorded TV - Use All Mon 5/29 12:36:02.362 [PooledThread@1958938] MediaFile created subfile:C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg MediaFile[id=157587 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps []] Mon 5/29 12:36:02.365 [PooledThread@1958938] Added:MediaFile[id=157587 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg, Seg0[Mon 5/29 12:36:02.336-Wed 12/31 16:00:00.000]] Mon 5/29 12:36:02.367 [PooledThread@1958938] Seeker channel string=29-5-1 Mon 5/29 12:36:02.370 [MainMsg@15eefcd] autotune0 digital tuner 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' num=29-5-1 (ver 3.1) Mon 5/29 12:36:02.370 [MainMsg@15eefcd] Splitter Filter set output mpeg2 format Mon 5/29 12:36:02.879 [MainMsg@15eefcd] DONE: autotune0 hr=0x0 locked:0 Mon 5/29 12:36:02.879 [MainMsg@15eefcd] setEncodingProperties0 979272312 Great Mon 5/29 12:36:02.879 [MainMsg@15eefcd] Set encoding property audiooutputmode to 0 Mon 5/29 12:36:02.879 [MainMsg@15eefcd] Set encoding property audiocrc to 0 Mon 5/29 12:36:02.879 [MainMsg@15eefcd] Set encoding property gopsize to 15 Mon 5/29 12:36:02.880 [MainMsg@15eefcd] Set encoding property videobitrate to 3800000 Mon 5/29 12:36:02.880 [MainMsg@15eefcd] Set encoding property inversetelecine to 0 Mon 5/29 12:36:02.880 [MainMsg@15eefcd] Set encoding property closedgop to 0 Mon 5/29 12:36:02.880 [MainMsg@15eefcd] Set encoding property vbr to 0 Mon 5/29 12:36:02.880 [MainMsg@15eefcd] Set encoding property outputstreamtype to 10 Mon 5/29 12:36:02.880 [MainMsg@15eefcd] Set encoding property width to 720 Mon 5/29 12:36:02.880 [MainMsg@15eefcd] Set encoding property height to 480 Mon 5/29 12:36:02.880 [MainMsg@15eefcd] Set encoding property audiobitrate to 384 Mon 5/29 12:36:02.881 [MainMsg@15eefcd] Set encoding property audiosampling to 48000 Mon 5/29 12:36:02.881 [MainMsg@15eefcd] Set encoding property disablefilter to 1 Mon 5/29 12:36:02.881 [MainMsg@15eefcd] Set encoding property medianfilter to 3 Mon 5/29 12:36:02.881 [MainMsg@15eefcd] Set encoding property fps to 30 Mon 5/29 12:36:02.881 [MainMsg@15eefcd] Set encoding property ipb to 0 Mon 5/29 12:36:02.881 [MainMsg@15eefcd] Set encoding property deinterlace to 0 Mon 5/29 12:36:02.881 [MainMsg@15eefcd] Set encoding property aspectratio to 1 Mon 5/29 12:36:02.881 [MainMsg@15eefcd] setupEncoding0 called for 979272312 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' (ver 1.0) Mon 5/29 12:36:02.882 [MainMsg@15eefcd] QAM:Got capture drv info for QAM mfg:'' Silicondust HDHomeRun Tuner 1020A3F1-0-0 Mon 5/29 12:36:02.882 [MainMsg@15eefcd] Encoding to file C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg Mon 5/29 12:36:02.882 [MainMsg@15eefcd] setupEncoding0 Trace27 Mon 5/29 12:36:02.882 [MainMsg@15eefcd] setupEncoding0 Trace28 Mon 5/29 12:36:02.883 [MainMsg@15eefcd] startEncoding0 called for 'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:36:03.045 [MainMsg@15eefcd] GetBroadcastStandard ATSC for device:'Silicondust HDHomeRun Tuner 1020A3F1-0-0' Mon 5/29 12:36:03.303 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:36:03.306 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:36:03.309 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:36:03.312 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:36:03.316 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:36:03.318 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:36:03.321 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:36:03.321 [Scheduler@d1c7aa] [A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T]] Mon 5/29 12:36:03.323 [Scheduler@d1c7aa] Total Schedule eval time=17 msec Mon 5/29 12:36:03.326 [Seeker@8c613a] Seeker awoken Mon 5/29 12:36:03.329 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:36:03.331 [Seeker@8c613a] MemStats: Used=84MB Total=143MB Max=805MB Mon 5/29 12:36:03.333 [Seeker@8c613a] Seeker.endRecord(Mon 5/29 12:36:03.326) currRecord=A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] readySwitch=false Mon 5/29 12:36:03.336 [Seeker@8c613a] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg]) Mon 5/29 12:36:03.338 [MainMsg@15eefcd] stopEncoding0 called (config:0x49000) Mon 5/29 12:36:03.338 [MainMsg@15eefcd] stopEncoding0 trace 1 0x123e5d4 Mon 5/29 12:36:03.338 [MainMsg@15eefcd] stopEncoding0 trace 2 Mon 5/29 12:36:03.338 [MainMsg@15eefcd] stopEncoding0 trace 3 0x195e43c Mon 5/29 12:36:03.355 [MainMsg@15eefcd] stopEncoding0 trace 4 Mon 5/29 12:36:03.356 [MainMsg@15eefcd] Stopped graph res=0x0 Mon 5/29 12:36:03.356 [MainMsg@15eefcd] stopEncoding0 trace 5 Mon 5/29 12:36:03.356 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:36:03.361 [AsyncPropSaver@fe3232] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:36:03.363 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:36:03.366 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:36:03.369 [AsyncPropSaver@fe3232] Done writing out the data to the properties file Mon 5/29 12:36:03.371 [PooledThread@66eafa] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:36:03.373 [Seeker@8c613a] Seeker waiting for 153722842346136 mins. Mon 5/29 12:36:09.740 [PooledThread@1958938] Seeker.finishWatch(/127.0.0.1:61676@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:36:09.743 [Seeker@8c613a] Seeker awoken Mon 5/29 12:36:09.746 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 12:36:09.748 [Seeker@8c613a] MemStats: Used=84MB Total=143MB Max=805MB Mon 5/29 12:36:09.751 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T]] MustSee=[]]} scheduleRandSize=0 Mon 5/29 12:36:09.754 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:36:09.756 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 12:36:09.759 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:36:09.761 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 12:36:09.764 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:36:09.767 [Scheduler@d1c7aa] Scheduler cleanup - Removing A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] from schedule because it has no WP. Mon 5/29 12:36:09.769 [Seeker@8c613a] MediaFile failing verify testFile=C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg isFile=true len=0 isTV=true isRecording=false Mon 5/29 12:36:09.772 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 12:36:09.774 [Seeker@8c613a] Removing MediaFile because it failed verification:MediaFile[id=157587 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg, Seg0[Mon 5/29 12:36:02.336-Mon 5/29 12:36:03.326]] Mon 5/29 12:36:09.778 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 12:36:09.781 [Seeker@8c613a] Seeker.destroyFile called for: MediaFile[id=157587 A[16190,11012,"The Bold and the Beautiful",19572@0529.12:30,30,T] mask=TV host=BULLITT encodedBy=Silicondust HDHomeRun Tuner 1020A3F1-0 KPIXDT format=MPEG2-PS 0:00:00 0 kbps [] C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg, Seg0[Mon 5/29 12:36:02.336-Mon 5/29 12:36:03.326]] Mon 5/29 12:36:09.784 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 12:36:09.784 [Scheduler@d1c7aa] [] Mon 5/29 12:36:09.786 [Seeker@8c613a] Added file to delete queue: C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg Mon 5/29 12:36:09.789 [PooledThread@1958938] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:36:09.791 [Seeker@8c613a] Deleted media segment file C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg dtime=5 len=0 Mon 5/29 12:36:09.794 [Scheduler@d1c7aa] Total Schedule eval time=38 msec Mon 5/29 12:36:09.797 [Seeker@8c613a] Seeker waiting for 153722842346136 mins. Mon 5/29 12:36:09.800 [ProgressiveDeleter@b5dc1b] Starting async delete for:C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg.delete Mon 5/29 12:36:09.802 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 12:36:09.805 [Seeker@8c613a] Seeker awoken Mon 5/29 12:36:09.808 [PooledThread@1958938] Done writing out the data to the properties file Mon 5/29 12:36:09.810 [Seeker@8c613a] MemStats: Used=84MB Total=143MB Max=805MB Mon 5/29 12:36:09.812 [ProgressiveDeleter@b5dc1b] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 12:36:09.815 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:36:09.818 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:36:09.820 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:36:09.824 [ProgressiveDeleter@b5dc1b] Done writing out the data to the properties file Mon 5/29 12:36:09.827 [Seeker@8c613a] Seeker waiting for 153722842346136 mins. Mon 5/29 12:36:09.830 [ProgressiveDeleter@b5dc1b] Completed progressive deletion of: C:\Users\Public\Recorded TV\TheBoldandtheBeautiful-16190-0.mpg.delete Mon 5/29 12:36:24.628 [PooledThread@1958938] Seeker.finishWatch(/127.0.0.1:61676@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:36:24.659 [PooledThread@1958938] Seeker.finishWatch(/127.0.0.1:61676@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:36:24.664 [PooledThread@1958938] Seeker.finishWatch(/127.0.0.1:61676@@SAGETV_PROCESS_LOCAL_UI) Mon 5/29 12:36:26.971 [ConnRecvQueue@185f86d] Error communicating with server:java.io.EOFException Mon 5/29 12:36:26.975 [ConnRecvQueue@185f86d] Cleaning up c/s connection Mon 5/29 12:36:26.978 [ConnRecvQueue@185f86d] NetworkManager CommunicationFailure : /127.0.0.1:61676 type=1 Mon 5/29 12:36:26.980 [ConnRecvQueue@185f86d] NetworkClient fullCleanup /127.0.0.1:61676 Mon 5/29 12:36:26.983 [ConnRecvQueue@185f86d] Cleaning up c/s connection Mon 5/29 12:36:26.985 [ConnRecvQueue@185f86d] Cleaning up c/s connection Mon 5/29 12:36:26.988 [Seeker@8c613a] Seeker awoken Mon 5/29 12:36:26.990 [ConnRecvQueue@185f86d] MsgRecv thread is terminating for /127.0.0.1:61676 Mon 5/29 12:36:26.993 [Seeker@8c613a] MemStats: Used=84MB Total=143MB Max=805MB Mon 5/29 12:36:26.996 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:36:26.998 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:36:27.001 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:36:27.003 [Seeker@8c613a] Seeker waiting for 153722842346136 mins. Mon 5/29 12:36:27.391 [KeepAlive@1491ca3] Error with c/s comm: keepAlive:java.lang.NullPointerException Mon 5/29 12:36:27.398 [KeepAlive@1491ca3] java.lang.NullPointerException Mon 5/29 12:36:27.398 [KeepAlive@1491ca3] at sage.SageTVConnection.keepAlive(SageTVConnection.java:2791) Mon 5/29 12:36:27.398 [KeepAlive@1491ca3] at sage.SageTVConnection.access$200(SageTVConnection.java:21) Mon 5/29 12:36:27.398 [KeepAlive@1491ca3] at sage.SageTVConnection$5.run(SageTVConnection.java:3287) Mon 5/29 12:36:27.398 [KeepAlive@1491ca3] at sage.Pooler$PooledThread.run(Pooler.java:254) Mon 5/29 12:36:27.398 [KeepAlive@1491ca3] NetworkManager CommunicationFailure : /127.0.0.1:61676 type=2 Mon 5/29 12:36:27.404 [KeepAlive@1491ca3] NetworkManager CommunicationFailure : /127.0.0.1:61676 type=2 Mon 5/29 12:36:39.666 [ConnSendQueue@47fbd] MsgSend thread terminating for /127.0.0.1:61676 Mon 5/29 12:36:39.800 [ClientListenerParallelizer@fc1c6d] Listener parallelizer thread is terminating for /127.0.0.1:61676 Mon 5/29 12:38:10.572 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:38:59.534 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 12:38:59.540 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 12:41:10.578 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:41:26.993 [Seeker@8c613a] Seeker awoken Mon 5/29 12:41:26.999 [Seeker@8c613a] MemStats: Used=85MB Total=143MB Max=805MB Mon 5/29 12:41:27.006 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:41:27.010 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:41:27.014 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:41:27.017 [Seeker@8c613a] Seeker waiting for 153722842346131 mins. Mon 5/29 12:43:59.547 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 12:43:59.554 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 12:44:10.586 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:46:27.021 [Seeker@8c613a] Seeker awoken Mon 5/29 12:46:27.027 [Seeker@8c613a] MemStats: Used=86MB Total=143MB Max=805MB Mon 5/29 12:46:27.033 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:46:27.039 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:46:27.045 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:46:27.050 [Seeker@8c613a] Seeker waiting for 153722842346126 mins. Mon 5/29 12:47:10.593 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:48:59.560 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 12:48:59.567 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 12:50:10.597 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:51:27.055 [Seeker@8c613a] Seeker awoken Mon 5/29 12:51:27.064 [Seeker@8c613a] MemStats: Used=86MB Total=143MB Max=805MB Mon 5/29 12:51:27.071 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:51:27.078 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:51:27.084 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:51:27.090 [Seeker@8c613a] Seeker waiting for 153722842346121 mins. Mon 5/29 12:53:10.600 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:53:59.574 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 12:53:59.581 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 12:56:10.603 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 12:56:27.096 [Seeker@8c613a] Seeker awoken Mon 5/29 12:56:27.101 [Seeker@8c613a] MemStats: Used=86MB Total=143MB Max=805MB Mon 5/29 12:56:27.107 [Seeker@8c613a] Checking video directories for new files Mon 5/29 12:56:27.111 [Seeker@8c613a] Diskspace checking is running Mon 5/29 12:56:27.117 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 12:56:27.121 [Seeker@8c613a] Seeker waiting for 153722842346116 mins. Mon 5/29 12:58:59.587 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 12:58:59.590 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 12:59:10.610 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:01:27.126 [Seeker@8c613a] Seeker awoken Mon 5/29 13:01:27.134 [Seeker@8c613a] MemStats: Used=87MB Total=143MB Max=805MB Mon 5/29 13:01:27.140 [Seeker@8c613a] Checking video directories for new files Mon 5/29 13:01:27.146 [Seeker@8c613a] Diskspace checking is running Mon 5/29 13:01:27.150 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 13:01:27.154 [Seeker@8c613a] Seeker waiting for 153722842346111 mins. Mon 5/29 13:02:10.613 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:03:59.600 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 13:03:59.606 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 13:05:10.621 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:06:27.161 [Seeker@8c613a] Seeker awoken Mon 5/29 13:06:27.168 [Seeker@8c613a] MemStats: Used=87MB Total=143MB Max=805MB Mon 5/29 13:06:27.174 [Seeker@8c613a] Checking video directories for new files Mon 5/29 13:06:27.180 [Seeker@8c613a] Diskspace checking is running Mon 5/29 13:06:27.185 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 13:06:27.191 [Seeker@8c613a] Seeker waiting for 153722842346106 mins. Mon 5/29 13:08:10.625 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:08:59.600 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 13:08:59.608 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 13:11:10.627 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:11:27.189 [Seeker@8c613a] Seeker awoken Mon 5/29 13:11:27.196 [Seeker@8c613a] MemStats: Used=87MB Total=143MB Max=805MB Mon 5/29 13:11:27.202 [Seeker@8c613a] Checking video directories for new files Mon 5/29 13:11:27.207 [Seeker@8c613a] Diskspace checking is running Mon 5/29 13:11:27.210 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 13:11:27.214 [Seeker@8c613a] Seeker waiting for 153722842346101 mins. Mon 5/29 13:13:59.619 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 13:13:59.626 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 13:14:05.721 [Carny@189825b] Carny waiting for awhile... Mon 5/29 13:14:10.633 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:16:27.215 [Seeker@8c613a] Seeker awoken Mon 5/29 13:16:27.223 [Seeker@8c613a] MemStats: Used=88MB Total=143MB Max=805MB Mon 5/29 13:16:27.230 [Seeker@8c613a] Checking video directories for new files Mon 5/29 13:16:27.236 [Seeker@8c613a] Diskspace checking is running Mon 5/29 13:16:27.239 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 13:16:27.243 [Seeker@8c613a] Seeker waiting for 153722842346096 mins. Mon 5/29 13:17:10.634 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:18:59.632 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 13:18:59.640 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 13:20:10.642 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:21:27.253 [Seeker@8c613a] Seeker awoken Mon 5/29 13:21:27.261 [Seeker@8c613a] MemStats: Used=89MB Total=143MB Max=805MB Mon 5/29 13:21:27.267 [Seeker@8c613a] Checking video directories for new files Mon 5/29 13:21:27.273 [Seeker@8c613a] Diskspace checking is running Mon 5/29 13:21:27.276 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 13:21:27.279 [Seeker@8c613a] Seeker waiting for 153722842346091 mins. Mon 5/29 13:23:10.645 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:23:59.647 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 13:23:59.654 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 13:26:10.646 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:26:27.274 [Seeker@8c613a] Seeker awoken Mon 5/29 13:26:27.282 [Seeker@8c613a] MemStats: Used=89MB Total=143MB Max=805MB Mon 5/29 13:26:27.288 [Seeker@8c613a] Checking video directories for new files Mon 5/29 13:26:27.294 [Seeker@8c613a] Diskspace checking is running Mon 5/29 13:26:27.298 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 13:26:27.301 [Seeker@8c613a] Seeker waiting for 153722842346086 mins. Mon 5/29 13:28:59.661 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 13:28:59.668 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 13:29:10.646 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:31:27.304 [Seeker@8c613a] Seeker awoken Mon 5/29 13:31:27.311 [Seeker@8c613a] MemStats: Used=89MB Total=143MB Max=805MB Mon 5/29 13:31:27.317 [Seeker@8c613a] Checking video directories for new files Mon 5/29 13:31:27.323 [Seeker@8c613a] Diskspace checking is running Mon 5/29 13:31:27.327 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 13:31:27.330 [Seeker@8c613a] Seeker waiting for 153722842346081 mins. Mon 5/29 13:32:10.653 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:33:59.674 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 13:33:59.682 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 13:35:10.661 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:36:27.338 [Seeker@8c613a] Seeker awoken Mon 5/29 13:36:27.346 [Seeker@8c613a] MemStats: Used=90MB Total=143MB Max=805MB Mon 5/29 13:36:27.352 [Seeker@8c613a] Checking video directories for new files Mon 5/29 13:36:27.357 [Seeker@8c613a] Diskspace checking is running Mon 5/29 13:36:27.361 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 13:36:27.365 [Seeker@8c613a] Seeker waiting for 153722842346076 mins. Mon 5/29 13:38:10.668 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:38:59.682 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 13:38:59.689 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 13:41:10.676 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:41:27.367 [Seeker@8c613a] Seeker awoken Mon 5/29 13:41:27.374 [Seeker@8c613a] MemStats: Used=90MB Total=143MB Max=805MB Mon 5/29 13:41:27.379 [Seeker@8c613a] Checking video directories for new files Mon 5/29 13:41:27.385 [Seeker@8c613a] Diskspace checking is running Mon 5/29 13:41:27.388 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 13:41:27.391 [Seeker@8c613a] Seeker waiting for 153722842346071 mins. Mon 5/29 13:43:59.702 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 13:43:59.709 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 13:44:10.682 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:46:27.395 [Seeker@8c613a] Seeker awoken Mon 5/29 13:46:27.402 [Seeker@8c613a] MemStats: Used=91MB Total=143MB Max=805MB Mon 5/29 13:46:27.407 [Seeker@8c613a] Checking video directories for new files Mon 5/29 13:46:27.413 [Seeker@8c613a] Diskspace checking is running Mon 5/29 13:46:27.419 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 13:46:27.425 [Seeker@8c613a] Seeker waiting for 153722842346066 mins. Mon 5/29 13:47:10.692 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:48:59.723 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 13:48:59.730 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 13:50:10.692 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:51:27.429 [Seeker@8c613a] Seeker awoken Mon 5/29 13:51:27.436 [Seeker@8c613a] MemStats: Used=91MB Total=143MB Max=805MB Mon 5/29 13:51:27.443 [Seeker@8c613a] Checking video directories for new files Mon 5/29 13:51:27.449 [Seeker@8c613a] Diskspace checking is running Mon 5/29 13:51:27.452 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 13:51:27.455 [Seeker@8c613a] Seeker waiting for 153722842346061 mins. Mon 5/29 13:53:10.702 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:53:59.741 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 13:53:59.747 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 13:56:10.702 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 13:56:27.454 [Seeker@8c613a] Seeker awoken Mon 5/29 13:56:27.461 [Seeker@8c613a] MemStats: Used=91MB Total=143MB Max=805MB Mon 5/29 13:56:27.467 [Seeker@8c613a] Checking video directories for new files Mon 5/29 13:56:27.472 [Seeker@8c613a] Diskspace checking is running Mon 5/29 13:56:27.478 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 13:56:27.484 [Seeker@8c613a] Seeker waiting for 153722842346056 mins. Mon 5/29 13:58:59.751 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 13:58:59.757 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 13:59:10.707 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:01:27.490 [Seeker@8c613a] Seeker awoken Mon 5/29 14:01:27.499 [Seeker@8c613a] MemStats: Used=92MB Total=143MB Max=805MB Mon 5/29 14:01:27.505 [Seeker@8c613a] Checking video directories for new files Mon 5/29 14:01:27.511 [Seeker@8c613a] Diskspace checking is running Mon 5/29 14:01:27.514 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 14:01:27.517 [Seeker@8c613a] Seeker waiting for 153722842346051 mins. Mon 5/29 14:02:10.713 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:03:59.763 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 14:03:59.771 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 14:05:10.722 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:06:27.517 [Seeker@8c613a] Seeker awoken Mon 5/29 14:06:27.525 [Seeker@8c613a] MemStats: Used=92MB Total=143MB Max=805MB Mon 5/29 14:06:27.532 [Seeker@8c613a] Checking video directories for new files Mon 5/29 14:06:27.538 [Seeker@8c613a] Diskspace checking is running Mon 5/29 14:06:27.543 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 14:06:27.549 [Seeker@8c613a] Seeker waiting for 153722842346046 mins. Mon 5/29 14:08:10.727 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:08:59.786 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 14:08:59.793 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 14:11:10.741 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:11:27.563 [Seeker@8c613a] Seeker awoken Mon 5/29 14:11:27.570 [Seeker@8c613a] MemStats: Used=93MB Total=143MB Max=805MB Mon 5/29 14:11:27.576 [Seeker@8c613a] Checking video directories for new files Mon 5/29 14:11:27.581 [Seeker@8c613a] Diskspace checking is running Mon 5/29 14:11:27.587 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 14:11:27.593 [Seeker@8c613a] Seeker waiting for 153722842346041 mins. Mon 5/29 14:13:59.799 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 14:13:59.802 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 14:14:05.723 [Carny@189825b] Carny waiting for awhile... Mon 5/29 14:14:10.752 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:16:27.595 [Seeker@8c613a] Seeker awoken Mon 5/29 14:16:27.601 [Seeker@8c613a] MemStats: Used=93MB Total=143MB Max=805MB Mon 5/29 14:16:27.607 [Seeker@8c613a] Checking video directories for new files Mon 5/29 14:16:27.613 [LibraryImporter@47fbd] Checking video directories for new files Mon 5/29 14:16:27.619 [Seeker@8c613a] Diskspace checking is running Mon 5/29 14:16:27.624 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 14:16:27.627 [Seeker@8c613a] Seeker waiting for 153722842346036 mins. Mon 5/29 14:16:28.126 [LibraryImporter@47fbd] Seeker is starting the library import scan... Mon 5/29 14:16:28.136 [LibraryImporter@47fbd] Starting to scan lib import root: C:\Users\Public\Videos Mon 5/29 14:16:28.151 [LibraryImporter@47fbd] Seeker has finished the library import scan. Mon 5/29 14:17:10.758 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:18:59.804 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 14:18:59.812 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 14:20:10.767 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:21:27.629 [Seeker@8c613a] Seeker awoken Mon 5/29 14:21:27.637 [Seeker@8c613a] MemStats: Used=94MB Total=143MB Max=805MB Mon 5/29 14:21:27.644 [Seeker@8c613a] Checking video directories for new files Mon 5/29 14:21:27.650 [Seeker@8c613a] Diskspace checking is running Mon 5/29 14:21:27.655 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 14:21:27.658 [Seeker@8c613a] Seeker waiting for 153722842346031 mins. Mon 5/29 14:23:10.768 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:23:59.823 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 14:23:59.830 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 14:26:10.782 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:26:27.660 [Seeker@8c613a] Seeker awoken Mon 5/29 14:26:27.666 [Seeker@8c613a] MemStats: Used=94MB Total=143MB Max=805MB Mon 5/29 14:26:27.672 [Seeker@8c613a] Checking video directories for new files Mon 5/29 14:26:27.678 [Seeker@8c613a] Diskspace checking is running Mon 5/29 14:26:27.684 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 14:26:27.687 [Seeker@8c613a] Seeker waiting for 153722842346026 mins. Mon 5/29 14:28:59.835 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 14:28:59.843 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 14:29:10.786 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:31:27.692 [Seeker@8c613a] Seeker awoken Mon 5/29 14:31:27.700 [Seeker@8c613a] MemStats: Used=95MB Total=143MB Max=805MB Mon 5/29 14:31:27.707 [Seeker@8c613a] Checking video directories for new files Mon 5/29 14:31:27.713 [Seeker@8c613a] Diskspace checking is running Mon 5/29 14:31:27.719 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 14:31:27.723 [Seeker@8c613a] Seeker waiting for 153722842346021 mins. Mon 5/29 14:32:10.788 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:33:59.852 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 14:33:59.859 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 14:35:10.789 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:36:27.732 [Seeker@8c613a] Seeker awoken Mon 5/29 14:36:27.739 [Seeker@8c613a] MemStats: Used=95MB Total=143MB Max=805MB Mon 5/29 14:36:27.746 [Seeker@8c613a] Checking video directories for new files Mon 5/29 14:36:27.750 [Seeker@8c613a] Diskspace checking is running Mon 5/29 14:36:27.753 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 14:36:27.757 [Seeker@8c613a] Seeker waiting for 153722842346016 mins. Mon 5/29 14:38:10.795 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:38:59.870 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 14:38:59.877 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 14:41:10.808 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:41:27.765 [Seeker@8c613a] Seeker awoken Mon 5/29 14:41:27.773 [Seeker@8c613a] MemStats: Used=95MB Total=143MB Max=805MB Mon 5/29 14:41:27.780 [Seeker@8c613a] Checking video directories for new files Mon 5/29 14:41:27.785 [Seeker@8c613a] Diskspace checking is running Mon 5/29 14:41:27.791 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 14:41:27.797 [Seeker@8c613a] Seeker waiting for 153722842346011 mins. Mon 5/29 14:43:59.888 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 14:43:59.894 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 14:44:10.814 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:46:27.804 [Seeker@8c613a] Seeker awoken Mon 5/29 14:46:27.811 [Seeker@8c613a] MemStats: Used=96MB Total=143MB Max=805MB Mon 5/29 14:46:27.819 [Seeker@8c613a] Checking video directories for new files Mon 5/29 14:46:27.826 [Seeker@8c613a] Diskspace checking is running Mon 5/29 14:46:27.831 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 14:46:27.837 [Seeker@8c613a] Seeker waiting for 153722842346006 mins. Mon 5/29 14:47:10.821 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:48:59.908 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 14:48:59.916 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 14:50:10.828 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:51:27.844 [Seeker@8c613a] Seeker awoken Mon 5/29 14:51:27.852 [Seeker@8c613a] MemStats: Used=96MB Total=143MB Max=805MB Mon 5/29 14:51:27.859 [Seeker@8c613a] Checking video directories for new files Mon 5/29 14:51:27.865 [Seeker@8c613a] Diskspace checking is running Mon 5/29 14:51:27.871 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 14:51:27.877 [Seeker@8c613a] Seeker waiting for 153722842346001 mins. Mon 5/29 14:53:10.838 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:53:59.915 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 14:53:59.922 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 14:56:10.850 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 14:56:27.881 [Seeker@8c613a] Seeker awoken Mon 5/29 14:56:27.888 [Seeker@8c613a] MemStats: Used=97MB Total=143MB Max=805MB Mon 5/29 14:56:27.894 [Seeker@8c613a] Checking video directories for new files Mon 5/29 14:56:27.900 [Seeker@8c613a] Diskspace checking is running Mon 5/29 14:56:27.905 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 14:56:27.908 [Seeker@8c613a] Seeker waiting for 153722842345996 mins. Mon 5/29 14:58:59.920 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 14:58:59.928 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 14:59:10.851 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:01:27.905 [Seeker@8c613a] Seeker awoken Mon 5/29 15:01:27.913 [Seeker@8c613a] MemStats: Used=97MB Total=143MB Max=805MB Mon 5/29 15:01:27.920 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:01:27.926 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:01:27.932 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:01:27.938 [Seeker@8c613a] Seeker waiting for 153722842345991 mins. Mon 5/29 15:02:10.856 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:03:59.933 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 15:03:59.940 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 15:05:10.856 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:06:27.950 [Seeker@8c613a] Seeker awoken Mon 5/29 15:06:27.958 [Seeker@8c613a] MemStats: Used=98MB Total=143MB Max=805MB Mon 5/29 15:06:27.965 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:06:27.971 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:06:27.977 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:06:27.983 [Seeker@8c613a] Seeker waiting for 153722842345986 mins. Mon 5/29 15:08:10.866 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:08:59.945 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 15:08:59.953 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 15:11:10.866 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:11:27.993 [Seeker@8c613a] Seeker awoken Mon 5/29 15:11:28.001 [Seeker@8c613a] MemStats: Used=98MB Total=143MB Max=805MB Mon 5/29 15:11:28.007 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:11:28.013 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:11:28.018 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:11:28.024 [Seeker@8c613a] Seeker waiting for 153722842345981 mins. Mon 5/29 15:13:59.960 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 15:13:59.971 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 15:14:05.733 [Carny@189825b] Carny waiting for awhile... Mon 5/29 15:14:10.875 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:16:28.032 [Seeker@8c613a] Seeker awoken Mon 5/29 15:16:28.040 [Seeker@8c613a] MemStats: Used=99MB Total=143MB Max=805MB Mon 5/29 15:16:28.046 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:16:28.052 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:16:28.058 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:16:28.063 [Seeker@8c613a] Seeker waiting for 153722842345976 mins. Mon 5/29 15:17:10.890 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:18:59.995 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 15:19:00.001 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 15:20:10.910 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:21:28.068 [Seeker@8c613a] Seeker awoken Mon 5/29 15:21:28.074 [Seeker@8c613a] MemStats: Used=99MB Total=143MB Max=805MB Mon 5/29 15:21:28.081 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:21:28.086 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:21:28.090 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:21:28.093 [Seeker@8c613a] Seeker waiting for 153722842345971 mins. Mon 5/29 15:23:10.929 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:24:00.000 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 15:24:00.009 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 15:26:10.941 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:26:28.095 [Seeker@8c613a] Seeker awoken Mon 5/29 15:26:28.102 [Seeker@8c613a] MemStats: Used=99MB Total=143MB Max=805MB Mon 5/29 15:26:28.108 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:26:28.114 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:26:28.120 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:26:28.126 [Seeker@8c613a] Seeker waiting for 153722842345966 mins. Mon 5/29 15:29:00.015 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 15:29:00.019 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 15:29:10.960 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:31:28.132 [Seeker@8c613a] Seeker awoken Mon 5/29 15:31:28.140 [Seeker@8c613a] MemStats: Used=100MB Total=143MB Max=805MB Mon 5/29 15:31:28.148 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:31:28.154 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:31:28.160 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:31:28.166 [Seeker@8c613a] Seeker waiting for 153722842345961 mins. Mon 5/29 15:32:10.961 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:34:00.021 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 15:34:00.028 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 15:35:10.977 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:36:28.170 [Seeker@8c613a] Seeker awoken Mon 5/29 15:36:28.179 [Seeker@8c613a] MemStats: Used=100MB Total=143MB Max=805MB Mon 5/29 15:36:28.186 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:36:28.193 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:36:28.199 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:36:28.203 [Seeker@8c613a] Seeker waiting for 153722842345956 mins. Mon 5/29 15:38:10.981 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:39:00.037 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 15:39:00.045 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 15:41:10.993 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:41:28.205 [Seeker@8c613a] Seeker awoken Mon 5/29 15:41:28.213 [Seeker@8c613a] MemStats: Used=100MB Total=143MB Max=805MB Mon 5/29 15:41:28.220 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:41:28.227 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:41:28.229 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:41:28.232 [Seeker@8c613a] Seeker waiting for 153722842345951 mins. Mon 5/29 15:44:00.054 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 15:44:00.061 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 15:44:11.004 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:46:28.245 [Seeker@8c613a] Seeker awoken Mon 5/29 15:46:28.252 [Seeker@8c613a] MemStats: Used=101MB Total=143MB Max=805MB Mon 5/29 15:46:28.258 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:46:28.264 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:46:28.270 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:46:28.276 [Seeker@8c613a] Seeker waiting for 153722842345946 mins. Mon 5/29 15:47:11.018 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:49:00.074 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 15:49:00.082 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 15:50:11.033 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:51:28.274 [Seeker@8c613a] Seeker awoken Mon 5/29 15:51:28.282 [Seeker@8c613a] MemStats: Used=101MB Total=143MB Max=805MB Mon 5/29 15:51:28.289 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:51:28.295 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:51:28.301 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:51:28.307 [Seeker@8c613a] Seeker waiting for 153722842345941 mins. Mon 5/29 15:53:11.048 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 15:53:24.078 [main@132e575] SageTV.deepSleep(true) Mon 5/29 15:53:24.082 [main@132e575] System power is suspending Mon 5/29 15:53:24.086 [main@132e575] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 15:53:24.097 [main@132e575] Done writing out the data to the properties file Mon 5/29 15:53:24.101 [main@132e575] UIManager.deepSleep(true) Mon 5/29 15:53:24.104 [main@132e575] UIManager System power is suspending Mon 5/29 15:53:24.107 [main@132e575] Waiting for parallel capture threads to cleanup count=1 Mon 5/29 15:53:24.110 [CapDevCleanup@b55116] Freeing capture device before entering standby:Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 15:53:24.113 [CapDevCleanup@b55116] freeDevice called for Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 15:53:24.117 [CapDevCleanup@b55116] teardownGraph0 called for device='Silicondust HDHomeRun Tuner 1020A3F1-0' Mon 5/29 15:53:24.117 [CapDevCleanup@b55116] Tuner Plugin released Mon 5/29 15:53:24.117 [CapDevCleanup@b55116] teardownGraph0 step-1 Mon 5/29 15:53:24.117 [CapDevCleanup@b55116] teardownGraph0 step0 Mon 5/29 15:53:24.117 [CapDevCleanup@b55116] teardownGraph0 step1 Mon 5/29 15:53:24.117 [CapDevCleanup@b55116] teardownGraph0 step2 Mon 5/29 15:53:24.117 [CapDevCleanup@b55116] teardownGraph0 step3 Mon 5/29 15:53:24.117 [CapDevCleanup@b55116] teardownGraph0 step4 Mon 5/29 15:53:24.234 [CapDevCleanup@b55116] teardownGraph0 step4-1 0x123e5d4 Mon 5/29 15:53:24.235 [CapDevCleanup@b55116] teardownGraph0 step5 Mon 5/29 15:53:24.235 [CapDevCleanup@b55116] teardownGraph0 step6. Mon 5/29 15:53:24.235 [CapDevCleanup@b55116] teardownGraph0 pBuilder. Mon 5/29 15:53:24.235 [CapDevCleanup@b55116] teardownGraph0 pGraph. Mon 5/29 15:53:24.235 [CapDevCleanup@b55116] teardownGraph0 step8 Mon 5/29 15:53:24.235 [CapDevCleanup@b55116] teardownGraph0 step9 Mon 5/29 15:53:24.235 [CapDevCleanup@b55116] teardownGraph0 step10 Mon 5/29 15:53:24.235 [main@132e575] Done waiting for parallel capture thread cleanup to complete! Mon 5/29 15:53:24.239 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 15:53:24.241 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={} scheduleRandSize=0 Mon 5/29 15:53:24.244 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 15:53:24.339 [main@132e575] SageTV.deepSleep(true) is done Mon 5/29 15:58:28.343 [main@132e575] SageTV.deepSleep(false) Mon 5/29 15:58:28.358 [main@132e575] SageTV pausing on wakeup to let drivers load properly... Mon 5/29 15:58:43.373 [main@132e575] SageTV done waiting on wakeup to let drivers load properly. Mon 5/29 15:58:43.376 [Seeker@8c613a] Seeker awoken Mon 5/29 15:58:43.380 [main@132e575] UIManager.deepSleep(false) Mon 5/29 15:58:43.383 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 15:58:43.387 [main@132e575] SageTV.deepSleep(false) is done Mon 5/29 15:58:43.391 [Seeker@8c613a] MemStats: Used=102MB Total=143MB Max=805MB Mon 5/29 15:58:43.394 [main@132e575] SageTV.deepSleep(false) Mon 5/29 15:58:43.397 [Scheduler@d1c7aa] initGraph0 (ver 2.1.0) called capDev='Silicondust HDHomeRun Tuner 1020A3F1-0-0', Country:'United States of America', TuningMode:Air, TunerType: Cap:0x49000 Mon 5/29 15:58:43.651 [Scheduler@d1c7aa] BDA capture is found on location:'Silicondust HDHomeRun Tuner 1020A3F1-0' id:'' for Silicondust HDHomeRun Tuner 1020A3F1-0-0 (@device:sw:{FD0A5AF4-B41D-11D2-9C95-00C04F7971E0}\Silicondust HDHomeRun Tuner 1020A3F1-0) (it's a BDA only). Mon 5/29 15:58:43.651 [Scheduler@d1c7aa] Add BDA filters (capture:'@device:sw:{FD0A5AF4-B41D-11D2-9C95-00C04F7971E0}\Silicondust HDHomeRun Tuner 1020A3F1-0') for device=Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 15:58:43.651 [Scheduler@d1c7aa] Not specify TV type ( Network type ), to search matching one. Mon 5/29 15:58:43.656 [Scheduler@d1c7aa] Loaded Sage TV Splitter Filter 0x123e3ac Mon 5/29 15:58:43.656 [Scheduler@d1c7aa] BDA Filters are loaded Mon 5/29 15:58:43.656 [MainMsg@15eefcd] switchToConnector0 tuningMode:Air. Mon 5/29 15:58:43.658 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:58:43.661 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[] MustSee=[]]} scheduleRandSize=0 Mon 5/29 15:58:43.664 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:58:43.666 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 15:58:43.669 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:58:43.672 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 15:58:43.674 [Seeker@8c613a] Seeker waiting for 153722842345934 mins. Mon 5/29 15:58:43.677 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 15:58:43.680 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 15:58:43.682 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 15:58:43.682 [Scheduler@d1c7aa] [] Mon 5/29 15:58:43.685 [Scheduler@d1c7aa] Total Schedule eval time=28 msec Mon 5/29 15:58:43.688 [Seeker@8c613a] Seeker awoken Mon 5/29 15:58:43.691 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 15:58:43.693 [Seeker@8c613a] MemStats: Used=102MB Total=143MB Max=805MB Mon 5/29 15:58:43.696 [Seeker@8c613a] Checking video directories for new files Mon 5/29 15:58:43.699 [Seeker@8c613a] Diskspace checking is running Mon 5/29 15:58:43.702 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 15:58:43.704 [Seeker@8c613a] Seeker waiting for 153722842345934 mins. Mon 5/29 15:59:00.781 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 15:59:00.785 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 16:01:11.739 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 16:03:43.707 [Seeker@8c613a] Seeker awoken Mon 5/29 16:03:43.714 [Seeker@8c613a] MemStats: Used=103MB Total=143MB Max=805MB Mon 5/29 16:03:43.721 [Seeker@8c613a] Checking video directories for new files Mon 5/29 16:03:43.727 [Seeker@8c613a] Diskspace checking is running Mon 5/29 16:03:43.733 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 16:03:43.739 [Seeker@8c613a] Seeker waiting for 153722842345929 mins. Mon 5/29 16:04:00.790 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 16:04:00.794 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 16:04:11.746 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 16:07:11.754 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 16:08:43.742 [Seeker@8c613a] Seeker awoken Mon 5/29 16:08:43.750 [Seeker@8c613a] MemStats: Used=103MB Total=143MB Max=805MB Mon 5/29 16:08:43.756 [Seeker@8c613a] Checking video directories for new files Mon 5/29 16:08:43.759 [Seeker@8c613a] Diskspace checking is running Mon 5/29 16:08:43.763 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 16:08:43.765 [Seeker@8c613a] Seeker waiting for 153722842345924 mins. Mon 5/29 16:09:00.797 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 16:09:00.804 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 16:10:11.759 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 16:13:11.767 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 16:13:43.768 [Seeker@8c613a] Seeker awoken Mon 5/29 16:13:43.772 [Seeker@8c613a] MemStats: Used=104MB Total=143MB Max=805MB Mon 5/29 16:13:43.775 [Seeker@8c613a] Checking video directories for new files Mon 5/29 16:13:43.778 [Seeker@8c613a] Diskspace checking is running Mon 5/29 16:13:43.782 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 16:13:43.785 [Seeker@8c613a] Seeker waiting for 153722842345919 mins. Mon 5/29 16:14:00.809 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 16:14:00.816 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 16:16:11.774 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 16:18:43.787 [Seeker@8c613a] Seeker awoken Mon 5/29 16:18:43.794 [Seeker@8c613a] MemStats: Used=104MB Total=143MB Max=805MB Mon 5/29 16:18:43.800 [Seeker@8c613a] Checking video directories for new files Mon 5/29 16:18:43.806 [LibraryImporter@1491ca3] Checking video directories for new files Mon 5/29 16:18:43.812 [Seeker@8c613a] Diskspace checking is running Mon 5/29 16:18:43.818 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 16:18:43.824 [Seeker@8c613a] Seeker waiting for 153722842345914 mins. Mon 5/29 16:18:44.309 [LibraryImporter@1491ca3] Seeker is starting the library import scan... Mon 5/29 16:18:44.319 [LibraryImporter@1491ca3] Starting to scan lib import root: C:\Users\Public\Videos Mon 5/29 16:18:44.352 [LibraryImporter@1491ca3] Seeker has finished the library import scan. Mon 5/29 16:19:00.822 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 16:19:00.829 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 16:19:06.425 [Carny@189825b] Carny waiting for awhile... Mon 5/29 16:19:11.782 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 16:19:37.493 [PluginRepoUpdater@190c56e] Doing routine update check for a new SageTVPlugins.xml file... Mon 5/29 16:19:37.526 [PluginRepoUpdater@190c56e] Checking to see if the plugin repository file SageTVPlugins.xml needs to be updated using MD5 URL: http://download.sagetv.com/SageTVPlugins.md5.txt Mon 5/29 16:19:37.690 [PluginRepoUpdater@190c56e] Local plugin repository file's checksum matches server's, no need to download a new one for SageTVPlugins.xml Mon 5/29 16:19:37.705 [PluginRepoUpdater@190c56e] Checking to see if the plugin repository file SageTVPluginsV9.xml needs to be updated using MD5 URL: https://raw.githubusercontent.com/OpenSageTV/sagetv-plugin-repo/master/SageTVPluginsV9.md5 Mon 5/29 16:19:37.790 [PluginRepoUpdater@190c56e] Local plugin repository file's checksum matches server's, no need to download a new one for SageTVPluginsV9.xml Mon 5/29 16:19:37.798 [PluginRepoUpdater@190c56e] Analyzing plugin repository XML file: SageTVPlugins.xml Mon 5/29 16:19:37.843 [PluginRepoUpdater@190c56e] Done processing plugin repository XML file[SageTVPlugins.xml] repositoryVersion=1.0.1996 Mon 5/29 16:19:37.846 [PluginRepoUpdater@190c56e] Analyzing plugin repository XML file: SageTVPluginsV9.xml Mon 5/29 16:19:37.853 [PluginRepoUpdater@190c56e] Done processing plugin repository XML file[SageTVPluginsV9.xml] repositoryVersion=2017.0520.2318 Mon 5/29 16:22:11.789 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 16:23:43.829 [Seeker@8c613a] Seeker awoken Mon 5/29 16:23:43.836 [Seeker@8c613a] MemStats: Used=112MB Total=143MB Max=805MB Mon 5/29 16:23:43.842 [Seeker@8c613a] Checking video directories for new files Mon 5/29 16:23:43.848 [Seeker@8c613a] Diskspace checking is running Mon 5/29 16:23:43.854 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 16:23:43.860 [Seeker@8c613a] Seeker waiting for 153722842345909 mins. Mon 5/29 16:24:00.846 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 16:24:00.853 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 16:25:11.793 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 16:27:29.614 [main@132e575] SageTV.deepSleep(true) Mon 5/29 16:27:29.618 [main@132e575] System power is suspending Mon 5/29 16:27:29.621 [main@132e575] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties Mon 5/29 16:27:29.631 [main@132e575] Done writing out the data to the properties file Mon 5/29 16:27:29.634 [main@132e575] UIManager.deepSleep(true) Mon 5/29 16:27:29.637 [main@132e575] UIManager System power is suspending Mon 5/29 16:27:29.640 [main@132e575] Waiting for parallel capture threads to cleanup count=1 Mon 5/29 16:27:29.643 [CapDevCleanup@1213e83] Freeing capture device before entering standby:Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 16:27:29.647 [CapDevCleanup@1213e83] freeDevice called for Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 16:27:29.650 [CapDevCleanup@1213e83] teardownGraph0 called for device='Silicondust HDHomeRun Tuner 1020A3F1-0' Mon 5/29 16:27:29.650 [CapDevCleanup@1213e83] teardownGraph0 step-1 Mon 5/29 16:27:29.650 [CapDevCleanup@1213e83] teardownGraph0 step0 Mon 5/29 16:27:29.650 [CapDevCleanup@1213e83] teardownGraph0 step1 Mon 5/29 16:27:29.650 [CapDevCleanup@1213e83] teardownGraph0 step2 Mon 5/29 16:27:29.651 [CapDevCleanup@1213e83] teardownGraph0 step3 Mon 5/29 16:27:29.651 [CapDevCleanup@1213e83] teardownGraph0 step4 Mon 5/29 16:27:29.662 [CapDevCleanup@1213e83] teardownGraph0 step4-1 0x123e3ac Mon 5/29 16:27:29.662 [CapDevCleanup@1213e83] teardownGraph0 step5 Mon 5/29 16:27:29.662 [CapDevCleanup@1213e83] teardownGraph0 step6. Mon 5/29 16:27:29.662 [CapDevCleanup@1213e83] teardownGraph0 pBuilder. Mon 5/29 16:27:29.662 [CapDevCleanup@1213e83] teardownGraph0 pGraph. Mon 5/29 16:27:29.662 [CapDevCleanup@1213e83] teardownGraph0 step8 Mon 5/29 16:27:29.663 [CapDevCleanup@1213e83] teardownGraph0 step9 Mon 5/29 16:27:29.663 [CapDevCleanup@1213e83] teardownGraph0 step10 Mon 5/29 16:27:29.663 [main@132e575] Done waiting for parallel capture thread cleanup to complete! Mon 5/29 16:27:29.666 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 16:27:29.669 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={} scheduleRandSize=0 Mon 5/29 16:27:29.672 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 16:27:29.771 [main@132e575] SageTV.deepSleep(true) is done Mon 5/29 19:16:55.554 [main@132e575] SageTV.deepSleep(false) Mon 5/29 19:16:55.562 [main@132e575] SageTV pausing on wakeup to let drivers load properly... Mon 5/29 19:17:10.561 [main@132e575] SageTV done waiting on wakeup to let drivers load properly. Mon 5/29 19:17:10.564 [Scheduler@d1c7aa] Scheduler awoken Mon 5/29 19:17:10.567 [main@132e575] UIManager.deepSleep(false) Mon 5/29 19:17:10.569 [Seeker@8c613a] Seeker awoken Mon 5/29 19:17:10.575 [main@132e575] SageTV.deepSleep(false) is done Mon 5/29 19:17:10.578 [Scheduler@d1c7aa] initGraph0 (ver 2.1.0) called capDev='Silicondust HDHomeRun Tuner 1020A3F1-0-0', Country:'United States of America', TuningMode:Air, TunerType: Cap:0x49000 Mon 5/29 19:17:10.578 [main@132e575] SageTV.deepSleep(false) Mon 5/29 19:17:10.580 [Seeker@8c613a] MemStats: Used=113MB Total=143MB Max=805MB Mon 5/29 19:17:10.844 [Scheduler@d1c7aa] BDA capture is found on location:'Silicondust HDHomeRun Tuner 1020A3F1-0' id:'' for Silicondust HDHomeRun Tuner 1020A3F1-0-0 (@device:sw:{FD0A5AF4-B41D-11D2-9C95-00C04F7971E0}\Silicondust HDHomeRun Tuner 1020A3F1-0) (it's a BDA only). Mon 5/29 19:17:10.844 [Scheduler@d1c7aa] Add BDA filters (capture:'@device:sw:{FD0A5AF4-B41D-11D2-9C95-00C04F7971E0}\Silicondust HDHomeRun Tuner 1020A3F1-0') for device=Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 19:17:10.844 [Scheduler@d1c7aa] Not specify TV type ( Network type ), to search matching one. Mon 5/29 19:17:10.848 [Scheduler@d1c7aa] Loaded Sage TV Splitter Filter 0x123e3ac Mon 5/29 19:17:10.849 [Scheduler@d1c7aa] BDA Filters are loaded Mon 5/29 19:17:10.849 [MainMsg@15eefcd] switchToConnector0 tuningMode:Air. Mon 5/29 19:17:10.849 [Scheduler@d1c7aa] Scheduler.updateSchedule() called manual=[] schedules={Silicondust HDHomeRun Tuner 1020A3F1-0=[Sched=[] MustSee=[]]} scheduleRandSize=0 Mon 5/29 19:17:10.852 [LibraryImporter@1491ca3] Checking video directories for new files Mon 5/29 19:17:10.855 [Seeker@8c613a] Checking video directories for new files Mon 5/29 19:17:10.857 [Scheduler@d1c7aa] # Airs=0 Mon 5/29 19:17:10.860 [Seeker@8c613a] Diskspace checking is running Mon 5/29 19:17:10.863 [Scheduler@d1c7aa] MUST SEE FINAL-Silicondust HDHomeRun Tuner 1020A3F1-0-[] Mon 5/29 19:17:10.865 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 19:17:10.868 [Scheduler@d1c7aa] Evaluating Potentials Mon 5/29 19:17:10.870 [Seeker@8c613a] Seeker waiting for 153722842345735 mins. Mon 5/29 19:17:10.873 [Scheduler@d1c7aa] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE Mon 5/29 19:17:10.875 [Scheduler@d1c7aa] Silicondust HDHomeRun Tuner 1020A3F1-0 Mon 5/29 19:17:10.875 [Scheduler@d1c7aa] [] Mon 5/29 19:17:10.878 [Scheduler@d1c7aa] Total Schedule eval time=29 msec Mon 5/29 19:17:10.880 [Seeker@8c613a] Seeker awoken Mon 5/29 19:17:10.883 [Scheduler@d1c7aa] Scheduler starting wait... Mon 5/29 19:17:10.885 [Seeker@8c613a] MemStats: Used=113MB Total=143MB Max=805MB Mon 5/29 19:17:10.887 [Seeker@8c613a] Checking video directories for new files Mon 5/29 19:17:10.891 [Seeker@8c613a] Diskspace checking is running Mon 5/29 19:17:10.893 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 19:17:10.895 [Seeker@8c613a] Seeker waiting for 153722842345735 mins. Mon 5/29 19:17:11.345 [LibraryImporter@1491ca3] Seeker is starting the library import scan... Mon 5/29 19:17:11.352 [LibraryImporter@1491ca3] Starting to scan lib import root: C:\Users\Public\Videos Mon 5/29 19:17:11.360 [LibraryImporter@1491ca3] Seeker has finished the library import scan. Mon 5/29 19:17:25.785 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:18:14.825 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 19:18:14.828 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 19:20:25.786 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:22:10.900 [Seeker@8c613a] Seeker awoken Mon 5/29 19:22:10.910 [Seeker@8c613a] MemStats: Used=113MB Total=143MB Max=805MB Mon 5/29 19:22:10.917 [Seeker@8c613a] Checking video directories for new files Mon 5/29 19:22:10.924 [Seeker@8c613a] Diskspace checking is running Mon 5/29 19:22:10.930 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 19:22:10.936 [Seeker@8c613a] Seeker waiting for 153722842345730 mins. Mon 5/29 19:23:14.831 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 19:23:14.839 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 19:23:25.803 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:26:25.810 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:27:10.943 [Seeker@8c613a] Seeker awoken Mon 5/29 19:27:10.950 [Seeker@8c613a] MemStats: Used=114MB Total=143MB Max=805MB Mon 5/29 19:27:10.957 [Seeker@8c613a] Checking video directories for new files Mon 5/29 19:27:10.963 [Seeker@8c613a] Diskspace checking is running Mon 5/29 19:27:10.969 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 19:27:10.974 [Seeker@8c613a] Seeker waiting for 153722842345725 mins. Mon 5/29 19:28:14.850 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 19:28:14.857 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 19:29:25.823 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:32:10.977 [Seeker@8c613a] Seeker awoken Mon 5/29 19:32:10.984 [Seeker@8c613a] MemStats: Used=114MB Total=143MB Max=805MB Mon 5/29 19:32:10.990 [Seeker@8c613a] Checking video directories for new files Mon 5/29 19:32:10.996 [Seeker@8c613a] Diskspace checking is running Mon 5/29 19:32:11.003 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 19:32:11.009 [Seeker@8c613a] Seeker waiting for 153722842345720 mins. Mon 5/29 19:32:25.828 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:33:14.850 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 19:33:14.854 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 19:35:25.835 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:37:11.015 [Seeker@8c613a] Seeker awoken Mon 5/29 19:37:11.022 [Seeker@8c613a] MemStats: Used=114MB Total=143MB Max=805MB Mon 5/29 19:37:11.029 [Seeker@8c613a] Checking video directories for new files Mon 5/29 19:37:11.036 [Seeker@8c613a] Diskspace checking is running Mon 5/29 19:37:11.042 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 19:37:11.048 [Seeker@8c613a] Seeker waiting for 153722842345715 mins. Mon 5/29 19:38:14.858 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 19:38:14.864 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 19:38:25.858 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:41:25.865 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:42:11.055 [Seeker@8c613a] Seeker awoken Mon 5/29 19:42:11.063 [Seeker@8c613a] MemStats: Used=74MB Total=143MB Max=805MB Mon 5/29 19:42:11.070 [Seeker@8c613a] Checking video directories for new files Mon 5/29 19:42:11.074 [Seeker@8c613a] Diskspace checking is running Mon 5/29 19:42:11.077 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 19:42:11.080 [Seeker@8c613a] Seeker waiting for 153722842345710 mins. Mon 5/29 19:43:14.871 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 19:43:14.879 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 19:44:25.872 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:47:11.071 [Seeker@8c613a] Seeker awoken Mon 5/29 19:47:11.079 [Seeker@8c613a] MemStats: Used=74MB Total=143MB Max=805MB Mon 5/29 19:47:11.085 [Seeker@8c613a] Checking video directories for new files Mon 5/29 19:47:11.091 [Seeker@8c613a] Diskspace checking is running Mon 5/29 19:47:11.096 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 19:47:11.102 [Seeker@8c613a] Seeker waiting for 153722842345705 mins. Mon 5/29 19:47:25.883 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:48:14.896 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 19:48:14.903 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 19:50:25.884 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:52:11.100 [Seeker@8c613a] Seeker awoken Mon 5/29 19:52:11.107 [Seeker@8c613a] MemStats: Used=75MB Total=143MB Max=805MB Mon 5/29 19:52:11.113 [Seeker@8c613a] Checking video directories for new files Mon 5/29 19:52:11.119 [Seeker@8c613a] Diskspace checking is running Mon 5/29 19:52:11.125 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 19:52:11.130 [Seeker@8c613a] Seeker waiting for 153722842345700 mins. Mon 5/29 19:53:14.896 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 19:53:14.903 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 19:53:25.887 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:56:25.895 [Ministry@1948a88] Ministry is waiting for 180 sec Mon 5/29 19:57:11.151 [Seeker@8c613a] Seeker awoken Mon 5/29 19:57:11.159 [Seeker@8c613a] MemStats: Used=75MB Total=143MB Max=805MB Mon 5/29 19:57:11.165 [Seeker@8c613a] Checking video directories for new files Mon 5/29 19:57:11.171 [Seeker@8c613a] Diskspace checking is running Mon 5/29 19:57:11.176 [Seeker@8c613a] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Mon 5/29 19:57:11.179 [Seeker@8c613a] Seeker waiting for 153722842345695 mins. Mon 5/29 19:58:14.924 [ThreadMonitor@e14a7b] Top 10 Cumulative CPU(sec) main:2 LuceneShowTransactionTask:1 Mon 5/29 19:58:14.934 [ThreadMonitor@e14a7b] Top 10 %CPU (last 300s) Mon 5/29 19:59:25.903 [Ministry@1948a88] Ministry is waiting for 180 sec