Tue 11/23 21:11:36.200 [main@12133b1] user.dir2=C:\Program Files (x86)\SageTV\SageTV
Tue 11/23 21:11:36.200 [main@12133b1] 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\CommercialDetector.jar;JARs\commons-io-2.5.jar;JARs\commons-jxpath-1.1.jar;JARs\commons-lang-2.4.jar;JARs\GoogleWeather.jar;JARs\gson-2.7.jar;JARs\hamcrest-core-1.3.jar;JARs\jcifs-1.1.6.jar;JARs\jdic.jar;JARs\lucene-core-3.6.0.jar;JARs\packager.jar;JARs\sagex-api.jar;JARs\sbbi-upnplib-1.0.3.jar;JARs\vecmath.jar
Tue 11/23 21:11:36.201 [main@12133b1] JVM version=1.8.0_191
Tue 11/23 21:11:36.201 [main@12133b1] OS=Windows 10 10.0
Tue 11/23 21:11:36.201 [main@12133b1] client=false
Tue 11/23 21:11:36.201 [main@12133b1] locale=en_US
Tue 11/23 21:11:36.204 [ThreadMonitor@c0b9a0] Thread CPU monitoring started interval=300000
Tue 11/23 21:11:36.206 [main@12133b1] SageTV V9.2.6.976
Tue 11/23 21:11:36.206 [main@12133b1] Data Model=32-bit
Tue 11/23 21:11:36.216 [main@12133b1] hostname=SAGE-SERVER
Tue 11/23 21:11:36.217 [main@12133b1] Splash: User Interface Manager is initializing...
Tue 11/23 21:11:36.300 [main@12133b1] Splash: Object Database is initializing...
Tue 11/23 21:11:36.342 [main@12133b1] dbFile=C:\Program Files (x86)\SageTV\SageTV\Wiz.bin(14370230) dbBackupFile=C:\Program Files (x86)\SageTV\SageTV\Wiz.bak(14503476)
Tue 11/23 21:11:36.342 [main@12133b1] Wizard starting to load database info...
Tue 11/23 21:11:36.342 [main@12133b1] Splash: Object Database Source is initializing...
Tue 11/23 21:11:36.348 [main@12133b1] Reading DB file:C:\Program Files (x86)\SageTV\SageTV\Wiz.bin with version 87
Tue 11/23 21:11:36.348 [main@12133b1] DBFile at version 87 FileSize=14370230
Tue 11/23 21:11:36.348 [main@12133b1] Wizard allocating table for Year of size 95
Tue 11/23 21:11:36.349 [main@12133b1] Splash: Object Database is initializing... 0% done
Tue 11/23 21:11:36.349 [main@12133b1] Wizard loading main index for Year bytes=1336
Tue 11/23 21:11:36.349 [main@12133b1] Load time for Year 0 msec 0.0 msec/object
Tue 11/23 21:11:36.349 [main@12133b1] Index check time for Year 0 msec
Tue 11/23 21:11:36.349 [main@12133b1] Splash: Object Database is initializing... 0% done
Tue 11/23 21:11:36.349 [main@12133b1] Wizard allocating table for Network of size 40
Tue 11/23 21:11:36.350 [main@12133b1] Splash: Object Database is initializing... 0% done
Tue 11/23 21:11:36.350 [main@12133b1] Wizard loading main index for Network bytes=591
Tue 11/23 21:11:36.350 [main@12133b1] Load time for Network 0 msec 0.0 msec/object
Tue 11/23 21:11:36.350 [main@12133b1] Index check time for Network 0 msec
Tue 11/23 21:11:36.350 [main@12133b1] Splash: Object Database is initializing... 0% done
Tue 11/23 21:11:36.350 [main@12133b1] Wizard allocating table for Title of size 4962
Tue 11/23 21:11:36.350 [main@12133b1] Splash: Object Database is initializing... 0% done
Tue 11/23 21:11:36.351 [main@12133b1] Wizard loading main index for Title bytes=152414
Tue 11/23 21:11:36.354 [main@12133b1] Load time for Title 3 msec 6.0459494E-4 msec/object
Tue 11/23 21:11:36.355 [main@12133b1] Index check time for Title 1 msec
Tue 11/23 21:11:36.355 [main@12133b1] Splash: Object Database is initializing... 0% done
Tue 11/23 21:11:36.355 [main@12133b1] Wizard loading alt index 1 for Title bytes=19854
Tue 11/23 21:11:36.356 [main@12133b1] Load time for alt index 1 for Title 1 msec
Tue 11/23 21:11:36.357 [main@12133b1] Index check time for alt index 1 for Title 1 msec
Tue 11/23 21:11:36.358 [main@12133b1] Splash: Object Database is initializing... 1% done
Tue 11/23 21:11:36.358 [main@12133b1] Wizard allocating table for Channel of size 614
Tue 11/23 21:11:36.358 [main@12133b1] Splash: Object Database is initializing... 1% done
Tue 11/23 21:11:36.358 [main@12133b1] Wizard loading main index for Channel bytes=84569
Tue 11/23 21:11:36.359 [main@12133b1] Load time for Channel 1 msec 0.0016286644 msec/object
Tue 11/23 21:11:36.359 [main@12133b1] Index check time for Channel 0 msec
Tue 11/23 21:11:36.359 [main@12133b1] Splash: Object Database is initializing... 1% done
Tue 11/23 21:11:36.359 [main@12133b1] Wizard allocating table for Bonus of size 1429
Tue 11/23 21:11:36.359 [main@12133b1] Splash: Object Database is initializing... 2% done
Tue 11/23 21:11:36.359 [main@12133b1] Wizard loading main index for Bonus bytes=28476
Tue 11/23 21:11:36.360 [main@12133b1] Load time for Bonus 1 msec 6.997901E-4 msec/object
Tue 11/23 21:11:36.360 [main@12133b1] Index check time for Bonus 0 msec
Tue 11/23 21:11:36.360 [main@12133b1] Splash: Object Database is initializing... 2% done
Tue 11/23 21:11:36.360 [main@12133b1] Wizard allocating table for People of size 57773
Tue 11/23 21:11:36.360 [main@12133b1] Splash: Object Database is initializing... 2% done
Tue 11/23 21:11:36.361 [main@12133b1] Wizard loading main index for People bytes=3070738
Tue 11/23 21:11:36.366 [main@12133b1] Lucene index(person): rooted at /rw/\lucene/ running at /rw/\lucene-run/ DiskIndex:false RunningDiskIndex:false
Tue 11/23 21:11:36.443 [main@12133b1] Lucene index(person) reader opened in 1
Tue 11/23 21:11:36.445 [main@12133b1] Lucene index(person) docs: 0
Tue 11/23 21:11:36.445 [main@12133b1] Increased Thread pool to size=1
Tue 11/23 21:11:36.446 [main@12133b1] Lucene index(person) does not have same item count as wizard (idx:0, wiz:57773) - RESETING
Tue 11/23 21:11:36.446 [main@12133b1] Index(person) reset
Tue 11/23 21:11:36.490 [main@12133b1] Load time for People 130 msec 0.0022501862 msec/object
Tue 11/23 21:11:36.491 [main@12133b1] Index check time for People 1 msec
Tue 11/23 21:11:36.491 [main@12133b1] Lucene index(person) built from Wizard
Tue 11/23 21:11:36.491 [main@12133b1] Splash: Object Database is initializing... 2% done
Tue 11/23 21:11:36.492 [main@12133b1] Wizard loading alt index 2 for People bytes=231098
Tue 11/23 21:11:36.494 [main@12133b1] Load time for alt index 2 for People 2 msec
Tue 11/23 21:11:36.499 [main@12133b1] Index check time for alt index 2 for People 5 msec
Tue 11/23 21:11:36.499 [main@12133b1] Wizard loading alt index 16 for People bytes=231098
Tue 11/23 21:11:36.501 [main@12133b1] Load time for alt index 16 for People 2 msec
Tue 11/23 21:11:36.503 [main@12133b1] Index check time for alt index 16 for People 2 msec
Tue 11/23 21:11:36.503 [main@12133b1] Wizard allocating table for SubCategory of size 150
Tue 11/23 21:11:36.504 [main@12133b1] Splash: Object Database is initializing... 27% done
Tue 11/23 21:11:36.504 [main@12133b1] Wizard loading main index for SubCategory bytes=2898
Tue 11/23 21:11:36.504 [main@12133b1] Load time for SubCategory 0 msec 0.0 msec/object
Tue 11/23 21:11:36.504 [main@12133b1] Index check time for SubCategory 0 msec
Tue 11/23 21:11:36.504 [main@12133b1] Splash: Object Database is initializing... 27% done
Tue 11/23 21:11:36.504 [main@12133b1] Wizard allocating table for Rated of size 2
Tue 11/23 21:11:36.505 [main@12133b1] Splash: Object Database is initializing... 27% done
Tue 11/23 21:11:36.505 [main@12133b1] Wizard loading main index for Rated bytes=32
Tue 11/23 21:11:36.505 [main@12133b1] Load time for Rated 0 msec 0.0 msec/object
Tue 11/23 21:11:36.505 [main@12133b1] Index check time for Rated 0 msec
Tue 11/23 21:11:36.505 [main@12133b1] Splash: Object Database is initializing... 27% done
Tue 11/23 21:11:36.505 [main@12133b1] Wizard allocating table for ParentalRating of size 0
Tue 11/23 21:11:36.505 [main@12133b1] Splash: Object Database is initializing... 27% done
Tue 11/23 21:11:36.505 [main@12133b1] Wizard allocating table for ExtendedRating of size 10
Tue 11/23 21:11:36.506 [main@12133b1] Splash: Object Database is initializing... 27% done
Tue 11/23 21:11:36.506 [main@12133b1] Wizard loading main index for ExtendedRating bytes=232
Tue 11/23 21:11:36.506 [main@12133b1] Load time for ExtendedRating 0 msec 0.0 msec/object
Tue 11/23 21:11:36.506 [main@12133b1] Index check time for ExtendedRating 0 msec
Tue 11/23 21:11:36.506 [main@12133b1] Splash: Object Database is initializing... 27% done
Tue 11/23 21:11:36.506 [main@12133b1] Wizard allocating table for Category of size 104
Tue 11/23 21:11:36.506 [main@12133b1] Splash: Object Database is initializing... 27% done
Tue 11/23 21:11:36.507 [main@12133b1] Wizard loading main index for Category bytes=1982
Tue 11/23 21:11:36.507 [main@12133b1] Load time for Category 0 msec 0.0 msec/object
Tue 11/23 21:11:36.507 [main@12133b1] Index check time for Category 0 msec
Tue 11/23 21:11:36.507 [main@12133b1] Splash: Object Database is initializing... 27% done
Tue 11/23 21:11:36.507 [main@12133b1] Wizard allocating table for PrimeTitle of size 0
Tue 11/23 21:11:36.507 [main@12133b1] Splash: Object Database is initializing... 27% done
Tue 11/23 21:11:36.507 [main@12133b1] Wizard allocating table for Show of size 15414
Tue 11/23 21:11:36.507 [main@12133b1] Splash: Object Database is initializing... 27% done
Tue 11/23 21:11:36.508 [main@12133b1] Wizard loading main index for Show bytes=5628430
Tue 11/23 21:11:36.508 [main@12133b1] Lucene index(show): rooted at /rw/\lucene/ running at /rw/\lucene-run/ DiskIndex:false RunningDiskIndex:false
Tue 11/23 21:11:36.508 [main@12133b1] Lucene index(show) reader opened in 0
Tue 11/23 21:11:36.508 [main@12133b1] Lucene index(show) docs: 0
Tue 11/23 21:11:36.508 [main@12133b1] Lucene index(show) does not have same item count as wizard (idx:0, wiz:15414) - RESETING
Tue 11/23 21:11:36.509 [main@12133b1] Index(show) reset
Tue 11/23 21:11:36.525 [LucenePersonTransactionTask@cd2fe0] 16.06% / 6226 work queue, 1000 inserted @ 0.07 ms/peep avg [tot:66ms]
Tue 11/23 21:11:36.532 [LucenePersonTransactionTask@cd2fe0] 32.12% / 6226 work queue, 2000 inserted @ 0.04 ms/peep avg [tot:72ms]
Tue 11/23 21:11:36.559 [LucenePersonTransactionTask@cd2fe0] 48.19% / 6226 work queue, 3000 inserted @ 0.03 ms/peep avg [tot:99ms]
Tue 11/23 21:11:36.563 [LucenePersonTransactionTask@cd2fe0] 64.25% / 6226 work queue, 4000 inserted @ 0.03 ms/peep avg [tot:102ms]
Tue 11/23 21:11:36.567 [main@12133b1] Load time for Show 59 msec 0.0038276892 msec/object
Tue 11/23 21:11:36.567 [LucenePersonTransactionTask@cd2fe0] 80.31% / 6226 work queue, 5000 inserted @ 0.02 ms/peep avg [tot:106ms]
Tue 11/23 21:11:36.568 [main@12133b1] Index check time for Show 1 msec
Tue 11/23 21:11:36.568 [main@12133b1] Lucene index(show) built from Wizard
Tue 11/23 21:11:36.568 [main@12133b1] Splash: Object Database is initializing... 27% done
Tue 11/23 21:11:36.568 [main@12133b1] Wizard loading alt index 3 for Show bytes=61662
Tue 11/23 21:11:36.574 [main@12133b1] Load time for alt index 3 for Show 6 msec
Tue 11/23 21:11:36.575 [main@12133b1] Index check time for alt index 3 for Show 1 msec
Tue 11/23 21:11:36.575 [main@12133b1] Wizard allocating table for Airing of size 26672
Tue 11/23 21:11:36.575 [main@12133b1] Splash: Object Database is initializing... 66% done
Tue 11/23 21:11:36.575 [main@12133b1] Wizard loading main index for Airing bytes=1040214
Tue 11/23 21:11:36.576 [LucenePersonTransactionTask@cd2fe0] 96.37% / 6226 work queue, 6000 inserted @ 0.02 ms/peep avg [tot:114ms]
Tue 11/23 21:11:36.578 [LucenePersonTransactionTask@cd2fe0] 100% work queue, 6226 insertions @ 0.02 ms/peep [tot:115ms]
Tue 11/23 21:11:36.580 [main@12133b1] Load time for Airing 5 msec 1.874625E-4 msec/object
Tue 11/23 21:11:36.581 [main@12133b1] Index check time for Airing 1 msec
Tue 11/23 21:11:36.581 [main@12133b1] Splash: Object Database is initializing... 66% done
Tue 11/23 21:11:36.581 [main@12133b1] Wizard loading alt index 6 for Airing bytes=106694
Tue 11/23 21:11:36.582 [main@12133b1] Load time for alt index 6 for Airing 1 msec
Tue 11/23 21:11:36.582 [LucenePersonTransactionTask@cd2fe0] 1.94% / 51548 work queue, 7226 inserted @ 0.02 ms/peep avg [tot:118ms]
Tue 11/23 21:11:36.583 [main@12133b1] Index check time for alt index 6 for Airing 1 msec
Tue 11/23 21:11:36.583 [main@12133b1] Wizard loading alt index 5 for Airing bytes=106694
Tue 11/23 21:11:36.583 [main@12133b1] Load time for alt index 5 for Airing 0 msec
Tue 11/23 21:11:36.584 [main@12133b1] Index check time for alt index 5 for Airing 1 msec
Tue 11/23 21:11:36.584 [main@12133b1] Wizard allocating table for ManualRecord of size 0
Tue 11/23 21:11:36.584 [main@12133b1] Splash: Object Database is initializing... 75% done
Tue 11/23 21:11:36.584 [main@12133b1] Wizard allocating table for MediaFile of size 520
Tue 11/23 21:11:36.584 [main@12133b1] Splash: Object Database is initializing... 75% done
Tue 11/23 21:11:36.585 [main@12133b1] Wizard loading main index for MediaFile bytes=265824
Tue 11/23 21:11:36.586 [LucenePersonTransactionTask@cd2fe0] 3.88% / 51548 work queue, 8226 inserted @ 0.01 ms/peep avg [tot:122ms]
Tue 11/23 21:11:36.588 [LuceneShowTransactionTask@630834] 34.25% / 2920 work queue, 1117 inserted @ 0.07 ms/show avg [tot:79ms]
Tue 11/23 21:11:36.591 [LucenePersonTransactionTask@cd2fe0] 5.82% / 51548 work queue, 9226 inserted @ 0.01 ms/peep avg [tot:126ms]
Tue 11/23 21:11:36.599 [LucenePersonTransactionTask@cd2fe0] 7.76% / 51548 work queue, 10226 inserted @ 0.01 ms/peep avg [tot:134ms]
Tue 11/23 21:11:36.600 [main@12133b1] Load time for MediaFile 15 msec 0.028846154 msec/object
Tue 11/23 21:11:36.600 [main@12133b1] Index check time for MediaFile 0 msec
Tue 11/23 21:11:36.600 [main@12133b1] Splash: Object Database is initializing... 75% done
Tue 11/23 21:11:36.600 [main@12133b1] Wizard loading alt index 10 for MediaFile bytes=2086
Tue 11/23 21:11:36.600 [main@12133b1] Load time for alt index 10 for MediaFile 0 msec
Tue 11/23 21:11:36.601 [main@12133b1] Index check time for alt index 10 for MediaFile 0 msec
Tue 11/23 21:11:36.601 [main@12133b1] Wizard loading alt index 12 for MediaFile bytes=2086
Tue 11/23 21:11:36.601 [main@12133b1] Load time for alt index 12 for MediaFile 0 msec
Tue 11/23 21:11:36.601 [main@12133b1] Index check time for alt index 12 for MediaFile 0 msec
Tue 11/23 21:11:36.601 [main@12133b1] Wizard allocating table for Watched of size 5
Tue 11/23 21:11:36.601 [main@12133b1] Splash: Object Database is initializing... 77% done
Tue 11/23 21:11:36.601 [main@12133b1] Wizard loading main index for Watched bytes=306
Tue 11/23 21:11:36.602 [main@12133b1] Load time for Watched 1 msec 0.2 msec/object
Tue 11/23 21:11:36.602 [main@12133b1] Index check time for Watched 0 msec
Tue 11/23 21:11:36.602 [main@12133b1] Splash: Object Database is initializing... 77% done
Tue 11/23 21:11:36.602 [main@12133b1] Wizard loading alt index 7 for Watched bytes=26
Tue 11/23 21:11:36.602 [main@12133b1] Load time for alt index 7 for Watched 0 msec
Tue 11/23 21:11:36.602 [main@12133b1] Index check time for alt index 7 for Watched 0 msec
Tue 11/23 21:11:36.602 [main@12133b1] Wizard allocating table for Agent of size 9
Tue 11/23 21:11:36.602 [main@12133b1] Splash: Object Database is initializing... 77% done
Tue 11/23 21:11:36.603 [main@12133b1] Wizard loading main index for Agent bytes=913
Tue 11/23 21:11:36.603 [LucenePersonTransactionTask@cd2fe0] 9.70% / 51548 work queue, 11226 inserted @ 0.01 ms/peep avg [tot:138ms]
Tue 11/23 21:11:36.604 [main@12133b1] Load time for Agent 1 msec 0.11111111 msec/object
Tue 11/23 21:11:36.604 [main@12133b1] Index check time for Agent 0 msec
Tue 11/23 21:11:36.604 [main@12133b1] Splash: Object Database is initializing... 77% done
Tue 11/23 21:11:36.604 [main@12133b1] Wizard loading alt index 11 for Agent bytes=42
Tue 11/23 21:11:36.604 [main@12133b1] Load time for alt index 11 for Agent 0 msec
Tue 11/23 21:11:36.604 [main@12133b1] Index check time for alt index 11 for Agent 0 msec
Tue 11/23 21:11:36.604 [main@12133b1] Wizard allocating table for Wasted of size 2
Tue 11/23 21:11:36.604 [main@12133b1] Splash: Object Database is initializing... 77% done
Tue 11/23 21:11:36.605 [main@12133b1] Wizard loading main index for Wasted bytes=32
Tue 11/23 21:11:36.605 [main@12133b1] Load time for Wasted 0 msec 0.0 msec/object
Tue 11/23 21:11:36.605 [main@12133b1] Index check time for Wasted 0 msec
Tue 11/23 21:11:36.605 [main@12133b1] Splash: Object Database is initializing... 77% done
Tue 11/23 21:11:36.605 [main@12133b1] Wizard allocating table for Playlist of size 0
Tue 11/23 21:11:36.605 [main@12133b1] Splash: Object Database is initializing... 77% done
Tue 11/23 21:11:36.605 [main@12133b1] Wizard allocating table for TVEditorial of size 0
Tue 11/23 21:11:36.605 [main@12133b1] Splash: Object Database is initializing... 77% done
Tue 11/23 21:11:36.606 [main@12133b1] Wizard allocating table for SeriesInfo of size 3402
Tue 11/23 21:11:36.606 [main@12133b1] Splash: Object Database is initializing... 77% done
Tue 11/23 21:11:36.606 [main@12133b1] Wizard loading main index for SeriesInfo bytes=3269150
Tue 11/23 21:11:36.607 [LucenePersonTransactionTask@cd2fe0] 11.64% / 51548 work queue, 12226 inserted @ 0.01 ms/peep avg [tot:142ms]
Tue 11/23 21:11:36.637 [LucenePersonTransactionTask@cd2fe0] 13.58% / 51548 work queue, 13226 inserted @ 0.01 ms/peep avg [tot:172ms]
Tue 11/23 21:11:36.639 [main@12133b1] Load time for SeriesInfo 33 msec 0.009700176 msec/object
Tue 11/23 21:11:36.640 [main@12133b1] Index check time for SeriesInfo 1 msec
Tue 11/23 21:11:36.640 [main@12133b1] Splash: Object Database is initializing... 77% done
Tue 11/23 21:11:36.640 [main@12133b1] Wizard loading alt index 13 for SeriesInfo bytes=13614
Tue 11/23 21:11:36.640 [main@12133b1] Load time for alt index 13 for SeriesInfo 0 msec
Tue 11/23 21:11:36.640 [main@12133b1] Index check time for alt index 13 for SeriesInfo 0 msec
Tue 11/23 21:11:36.641 [main@12133b1] Wizard loading alt index 15 for SeriesInfo bytes=13614
Tue 11/23 21:11:36.641 [main@12133b1] Load time for alt index 15 for SeriesInfo 0 msec
Tue 11/23 21:11:36.641 [main@12133b1] Index check time for alt index 15 for SeriesInfo 0 msec
Tue 11/23 21:11:36.641 [main@12133b1] Wizard allocating table for UserRecord of size 0
Tue 11/23 21:11:36.641 [main@12133b1] Splash: Object Database is initializing... 100% done
Tue 11/23 21:11:36.641 [main@12133b1] Wizard processing transactional records...
Tue 11/23 21:11:36.642 [LucenePersonTransactionTask@cd2fe0] 15.52% / 51548 work queue, 14226 inserted @ 0.01 ms/peep avg [tot:176ms]
Tue 11/23 21:11:36.643 [main@12133b1] Splash: Object Database is initializing... 100% done
Tue 11/23 21:11:36.643 [main@12133b1] Wizard performing validation on database objects...
Tue 11/23 21:11:36.646 [LucenePersonTransactionTask@cd2fe0] 17.46% / 51548 work queue, 15226 inserted @ 0.01 ms/peep avg [tot:180ms]
Tue 11/23 21:11:36.650 [LucenePersonTransactionTask@cd2fe0] 19.40% / 51548 work queue, 16226 inserted @ 0.01 ms/peep avg [tot:184ms]
Tue 11/23 21:11:36.654 [main@12133b1] Wizard DONE loading database info. loadTime=0.312 sec
Tue 11/23 21:11:36.654 [main@12133b1] Splash: Object Database is initializing... 100% done
Tue 11/23 21:11:36.655 [LucenePersonTransactionTask@cd2fe0] 21.34% / 51548 work queue, 17226 inserted @ 0.01 ms/peep avg [tot:189ms]
Tue 11/23 21:11:36.656 [main@12133b1] Splash: EPG is initializing...
Tue 11/23 21:11:36.656 [LuceneShowTransactionTask@630834] 68.49% / 2920 work queue, 2117 inserted @ 0.07 ms/show avg [tot:145ms]
Tue 11/23 21:11:36.660 [LucenePersonTransactionTask@cd2fe0] 23.28% / 51548 work queue, 18226 inserted @ 0.01 ms/peep avg [tot:190ms]
Tue 11/23 21:11:36.664 [LucenePersonTransactionTask@cd2fe0] 25.22% / 51548 work queue, 19226 inserted @ 0.01 ms/peep avg [tot:194ms]
Tue 11/23 21:11:36.669 [LucenePersonTransactionTask@cd2fe0] 27.16% / 51548 work queue, 20226 inserted @ 0.01 ms/peep avg [tot:197ms]
Tue 11/23 21:11:36.669 [main@12133b1] Increased hash set pool to size=1
Tue 11/23 21:11:36.673 [LucenePersonTransactionTask@cd2fe0] 29.10% / 51548 work queue, 21226 inserted @ 0.01 ms/peep avg [tot:201ms]
Tue 11/23 21:11:36.677 [LucenePersonTransactionTask@cd2fe0] 31.04% / 51548 work queue, 22226 inserted @ 0.01 ms/peep avg [tot:205ms]
Tue 11/23 21:11:36.681 [main@12133b1] Splash: Profiler is initializing...
Tue 11/23 21:11:36.683 [main@12133b1] Loaded 2 messages from system message log file
Tue 11/23 21:11:36.713 [main@12133b1] Splash: Acquisition System is initializing...
Tue 11/23 21:11:36.714 [LucenePersonTransactionTask@cd2fe0] 32.98% / 51548 work queue, 23226 inserted @ 0.01 ms/peep avg [tot:241ms]
Tue 11/23 21:11:36.718 [LucenePersonTransactionTask@cd2fe0] 34.92% / 51548 work queue, 24226 inserted @ 0.01 ms/peep avg [tot:245ms]
Tue 11/23 21:11:36.722 [LuceneShowTransactionTask@630834] 100% work queue, 3037 insertions @ 0.07 ms/show [tot:211ms]
Tue 11/23 21:11:36.723 [LucenePersonTransactionTask@cd2fe0] 36.86% / 51548 work queue, 25226 inserted @ 0.01 ms/peep avg [tot:250ms]
Tue 11/23 21:11:36.727 [LucenePersonTransactionTask@cd2fe0] 38.80% / 51548 work queue, 26226 inserted @ 0.01 ms/peep avg [tot:254ms]
Tue 11/23 21:11:36.731 [LucenePersonTransactionTask@cd2fe0] 40.74% / 51548 work queue, 27226 inserted @ 0.01 ms/peep avg [tot:258ms]
Tue 11/23 21:11:36.735 [LucenePersonTransactionTask@cd2fe0] 42.68% / 51548 work queue, 28226 inserted @ 0.01 ms/peep avg [tot:261ms]
Tue 11/23 21:11:36.739 [LucenePersonTransactionTask@cd2fe0] 44.62% / 51548 work queue, 29226 inserted @ 0.01 ms/peep avg [tot:265ms]
Tue 11/23 21:11:36.744 [LucenePersonTransactionTask@cd2fe0] 46.56% / 51548 work queue, 30226 inserted @ 0.01 ms/peep avg [tot:269ms]
Tue 11/23 21:11:36.744 [main@12133b1] MMC calling detectCaptureDevices on sage.NetworkEncoderManager@1fdc050
Tue 11/23 21:11:36.748 [LucenePersonTransactionTask@cd2fe0] 48.50% / 51548 work queue, 31226 inserted @ 0.01 ms/peep avg [tot:273ms]
Tue 11/23 21:11:36.750 [main@12133b1] network encoder version:3.0
Tue 11/23 21:11:36.751 [main@12133b1] Parsed network encoder major version: 3
Tue 11/23 21:11:36.751 [main@12133b1] network encoder version:3.0
Tue 11/23 21:11:36.752 [main@12133b1] Parsed network encoder major version: 3
Tue 11/23 21:11:36.752 [main@12133b1] Doing broadcast discovery of new encoding servers on the network...
Tue 11/23 21:11:36.752 [LucenePersonTransactionTask@cd2fe0] 50.44% / 51548 work queue, 32226 inserted @ 0.01 ms/peep avg [tot:277ms]
Tue 11/23 21:11:36.754 [main@12133b1] Discovery packet received:java.net.DatagramPacket@5cb137
Tue 11/23 21:11:36.755 [main@12133b1] Added server info:0:0:0:0:0:0:0:0,V4.1.0,SAGE-SERVER.sanjuancable.com
Tue 11/23 21:11:36.759 [LucenePersonTransactionTask@cd2fe0] 52.38% / 51548 work queue, 33226 inserted @ 0.01 ms/peep avg [tot:283ms]
Tue 11/23 21:11:36.761 [LuceneShowTransactionTask@630834] 8.08% / 12382 work queue, 4037 inserted @ 0.06 ms/show avg [tot:249ms]
Tue 11/23 21:11:36.763 [LucenePersonTransactionTask@cd2fe0] 54.32% / 51548 work queue, 34226 inserted @ 0.01 ms/peep avg [tot:286ms]
Tue 11/23 21:11:36.767 [LucenePersonTransactionTask@cd2fe0] 56.26% / 51548 work queue, 35226 inserted @ 0.01 ms/peep avg [tot:290ms]
Tue 11/23 21:11:36.771 [LucenePersonTransactionTask@cd2fe0] 58.20% / 51548 work queue, 36226 inserted @ 0.01 ms/peep avg [tot:293ms]
Tue 11/23 21:11:36.775 [LucenePersonTransactionTask@cd2fe0] 60.14% / 51548 work queue, 37226 inserted @ 0.01 ms/peep avg [tot:296ms]
Tue 11/23 21:11:36.783 [LucenePersonTransactionTask@cd2fe0] 62.08% / 51548 work queue, 38226 inserted @ 0.01 ms/peep avg [tot:303ms]
Tue 11/23 21:11:36.787 [LucenePersonTransactionTask@cd2fe0] 64.02% / 51548 work queue, 39226 inserted @ 0.01 ms/peep avg [tot:306ms]
Tue 11/23 21:11:36.791 [LucenePersonTransactionTask@cd2fe0] 65.96% / 51548 work queue, 40226 inserted @ 0.01 ms/peep avg [tot:309ms]
Tue 11/23 21:11:36.795 [LucenePersonTransactionTask@cd2fe0] 67.90% / 51548 work queue, 41226 inserted @ 0.01 ms/peep avg [tot:313ms]
Tue 11/23 21:11:36.798 [LuceneShowTransactionTask@630834] 16.15% / 12382 work queue, 5037 inserted @ 0.06 ms/show avg [tot:286ms]
Tue 11/23 21:11:36.799 [LucenePersonTransactionTask@cd2fe0] 69.84% / 51548 work queue, 42226 inserted @ 0.01 ms/peep avg [tot:317ms]
Tue 11/23 21:11:36.802 [LucenePersonTransactionTask@cd2fe0] 71.78% / 51548 work queue, 43226 inserted @ 0.01 ms/peep avg [tot:320ms]
Tue 11/23 21:11:36.806 [LucenePersonTransactionTask@cd2fe0] 73.72% / 51548 work queue, 44226 inserted @ 0.01 ms/peep avg [tot:323ms]
Tue 11/23 21:11:36.810 [LucenePersonTransactionTask@cd2fe0] 75.66% / 51548 work queue, 45226 inserted @ 0.01 ms/peep avg [tot:327ms]
Tue 11/23 21:11:36.814 [LucenePersonTransactionTask@cd2fe0] 77.60% / 51548 work queue, 46226 inserted @ 0.01 ms/peep avg [tot:331ms]
Tue 11/23 21:11:36.818 [LucenePersonTransactionTask@cd2fe0] 79.54% / 51548 work queue, 47226 inserted @ 0.01 ms/peep avg [tot:335ms]
Tue 11/23 21:11:36.822 [LucenePersonTransactionTask@cd2fe0] 81.48% / 51548 work queue, 48226 inserted @ 0.01 ms/peep avg [tot:339ms]
Tue 11/23 21:11:36.826 [LucenePersonTransactionTask@cd2fe0] 83.42% / 51548 work queue, 49226 inserted @ 0.01 ms/peep avg [tot:343ms]
Tue 11/23 21:11:36.829 [LucenePersonTransactionTask@cd2fe0] 85.36% / 51548 work queue, 50226 inserted @ 0.01 ms/peep avg [tot:346ms]
Tue 11/23 21:11:36.831 [LuceneShowTransactionTask@630834] 24.23% / 12382 work queue, 6037 inserted @ 0.05 ms/show avg [tot:319ms]
Tue 11/23 21:11:36.833 [LucenePersonTransactionTask@cd2fe0] 87.30% / 51548 work queue, 51226 inserted @ 0.01 ms/peep avg [tot:349ms]
Tue 11/23 21:11:36.837 [LucenePersonTransactionTask@cd2fe0] 89.24% / 51548 work queue, 52226 inserted @ 0.01 ms/peep avg [tot:353ms]
Tue 11/23 21:11:36.841 [LucenePersonTransactionTask@cd2fe0] 91.18% / 51548 work queue, 53226 inserted @ 0.01 ms/peep avg [tot:357ms]
Tue 11/23 21:11:36.845 [LucenePersonTransactionTask@cd2fe0] 93.12% / 51548 work queue, 54226 inserted @ 0.01 ms/peep avg [tot:361ms]
Tue 11/23 21:11:36.853 [LucenePersonTransactionTask@cd2fe0] 95.06% / 51548 work queue, 55226 inserted @ 0.01 ms/peep avg [tot:369ms]
Tue 11/23 21:11:36.857 [LucenePersonTransactionTask@cd2fe0] 97.00% / 51548 work queue, 56226 inserted @ 0.01 ms/peep avg [tot:373ms]
Tue 11/23 21:11:36.861 [LucenePersonTransactionTask@cd2fe0] 98.94% / 51548 work queue, 57226 inserted @ 0.01 ms/peep avg [tot:377ms]
Tue 11/23 21:11:36.863 [LucenePersonTransactionTask@cd2fe0] Snapshot index person snap-count: 0
Tue 11/23 21:11:36.871 [LuceneShowTransactionTask@630834] 32.30% / 12382 work queue, 7037 inserted @ 0.05 ms/show avg [tot:359ms]
Tue 11/23 21:11:36.903 [LuceneShowTransactionTask@630834] 40.38% / 12382 work queue, 8037 inserted @ 0.05 ms/show avg [tot:391ms]
Tue 11/23 21:11:36.934 [LuceneShowTransactionTask@630834] 48.46% / 12382 work queue, 9037 inserted @ 0.05 ms/show avg [tot:421ms]
Tue 11/23 21:11:36.971 [LuceneShowTransactionTask@630834] 56.53% / 12382 work queue, 10037 inserted @ 0.05 ms/show avg [tot:457ms]
Tue 11/23 21:11:37.002 [LuceneShowTransactionTask@630834] 64.61% / 12382 work queue, 11037 inserted @ 0.04 ms/show avg [tot:488ms]
Tue 11/23 21:11:37.010 [LucenePersonTransactionTask@cd2fe0] 100% work queue, 57773 insertions @ 0.01 ms/peep [tot:379ms]
Tue 11/23 21:11:37.060 [LuceneShowTransactionTask@630834] 72.69% / 12382 work queue, 12037 inserted @ 0.05 ms/show avg [tot:545ms]
Tue 11/23 21:11:37.250 [LuceneShowTransactionTask@630834] 80.76% / 12382 work queue, 13037 inserted @ 0.06 ms/show avg [tot:735ms]
Tue 11/23 21:11:37.278 [LuceneShowTransactionTask@630834] 88.84% / 12382 work queue, 14037 inserted @ 0.05 ms/show avg [tot:763ms]
Tue 11/23 21:11:37.305 [LuceneShowTransactionTask@630834] 96.91% / 12382 work queue, 15037 inserted @ 0.05 ms/show avg [tot:789ms]
Tue 11/23 21:11:37.322 [LuceneShowTransactionTask@630834] Snapshot index show snap-count: 0
Tue 11/23 21:11:37.376 [LuceneShowTransactionTask@630834] 100% work queue, 15416 insertions @ 0.05 ms/show [tot:806ms]
Tue 11/23 21:11:39.766 [main@12133b1] Error discovering servers:java.net.SocketTimeoutException: Receive timed out
Tue 11/23 21:11:39.767 [main@12133b1] Connected to encoding server at SAGE-SERVER.sanjuancable.com
Tue 11/23 21:11:39.768 [main@12133b1] Ignoring network encoder because it's already in our config: -1778689000
Tue 11/23 21:11:39.768 [main@12133b1] Ignoring network encoder because it's already in our config: -1778689001
Tue 11/23 21:11:39.769 [main@12133b1] devices detected=[Tuner1 on 127.0.0.1:9000, Tuner2 on 127.0.0.1:9000]
Tue 11/23 21:11:39.769 [main@12133b1] EncoderMap={Tuner1 on 127.0.0.1:9000=Tuner1 on 127.0.0.1:9000, Tuner2 on 127.0.0.1:9000=Tuner2 on 127.0.0.1:9000}
Tue 11/23 21:11:39.769 [main@12133b1] MMC calling detectCaptureDevices on sage.DShowCaptureManager@14b1c02
Tue 11/23 21:11:39.769 [main@12133b1] Splash: DirectShow WDM Capture Manager is initializing...
Tue 11/23 21:11:39.770 [main@12133b1] Setup globalInvalidParameterHandler
Tue 11/23 21:11:39.770 [main@12133b1] Device detecting->:{65E8773D-8F56-11D0-A3B9-00A0C9223196}
Tue 11/23 21:11:39.778 [main@12133b1] MergeNameList() Entry: DevName[0] 'À', numDev=0, new DevName1[0] 'À', numDev1=0 
Tue 11/23 21:11:39.778 [main@12133b1] Device detecting->:{FD0A5AF4-B41D-11d2-9C95-00C04F7971E0}
Tue 11/23 21:11:39.778 [main@12133b1] MergeNameList() Entry: DevName[0] 'À', numDev=0, new DevName1[0] 'À', numDev1=0 
Tue 11/23 21:11:39.778 [main@12133b1] Device detecting->:{71985F48-1CA1-11d3-9CC8-00C04F7971E0}
Tue 11/23 21:11:39.778 [main@12133b1] MergeNameList() Entry: DevName[0] 'À', numDev=0, new DevName1[0] 'À', numDev1=0 
Tue 11/23 21:11:39.778 [main@12133b1] systemCapDevices=[]
Tue 11/23 21:11:39.778 [main@12133b1] Checking encoder key:-1802997812
Tue 11/23 21:11:39.779 [main@12133b1] Checking encoder key:1647497613
Tue 11/23 21:11:39.779 [main@12133b1] EncoderMap1={}
Tue 11/23 21:11:39.779 [main@12133b1] devices detected=[]
Tue 11/23 21:11:39.779 [main@12133b1] EncoderMap={Tuner1 on 127.0.0.1:9000=Tuner1 on 127.0.0.1:9000, Tuner2 on 127.0.0.1:9000=Tuner2 on 127.0.0.1:9000}
Tue 11/23 21:11:39.779 [main@12133b1] Splash: SageTV Core is initializing...
Tue 11/23 21:11:39.793 [main@12133b1] Checking video directories for new files
Tue 11/23 21:11:39.807 [main@12133b1] DShowFilters=[AC3 Parser Filter, 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, DV Muxer, DV Splitter, DV Video Decoder, DVD Navigator, Enhanced Video Renderer, File Source (Async.), File Source (URL), File stream renderer, File writer, 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, 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]
Tue 11/23 21:11:39.808 [main@12133b1] EVR support detected=true
Tue 11/23 21:11:39.810 [SageTV@fd4295] Splash: Profiler is initializing... 0% done
Tue 11/23 21:11:39.821 [SageTV@fd4295] CARNY building airing maps...
Tue 11/23 21:11:39.847 [SageTV@fd4295] CARNY Processing 6 Agents & 19098 Airs
Tue 11/23 21:11:39.860 [SageTV@fd4295] Splash: Profiler is initializing... 100% done
Tue 11/23 21:11:39.860 [SageTV@fd4295] CARNY Negative Energy Size: 46
Tue 11/23 21:11:39.860 [SageTV@fd4295] CARNY Traitors:[]
Tue 11/23 21:11:39.860 [SageTV@fd4295] CARNY finished in 50ms
Tue 11/23 21:11:39.861 [EPG@792af8] Increased ArrayList pool to size=1
Tue 11/23 21:11:39.861 [FSManager@19ddb6b] FSManager is running...
Tue 11/23 21:11:39.861 [Carny@574acb] Carny got a Std job of null
Tue 11/23 21:11:39.862 [Seeker@53bf8f] Verifying existence of all TV media files in database fixDurs=true avoidArchive=false
Tue 11/23 21:11:39.862 [EPG@792af8] sage.epg.sd.SDRipper@183d4bc needs an update in 14:33:52
Tue 11/23 21:11:39.862 [MsgMgrSocket@100a98b] Spawned thread for MsgManger server socket listening...
Tue 11/23 21:11:39.862 [EPG@792af8] EPG needs an update in 873 minutes
Tue 11/23 21:11:39.863 [EPG@792af8] EPG's works is done. Waiting...
Tue 11/23 21:11:39.864 [SageTV@fd4295] Splash: Waiting for SageTV to finish initialization...
Tue 11/23 21:11:39.864 [Carny@574acb] CARNY building airing maps...
Tue 11/23 21:11:39.867 [Scheduler@4e3032] Scheduler.updateSchedule() called  manual=[] schedules={Tuner1 on 127.0.0.1:9000=[Sched=[] MustSee=[]], Tuner2 on 127.0.0.1:9000=[Sched=[] MustSee=[]]} scheduleRandSize=0
Tue 11/23 21:11:39.869 [Scheduler@4e3032] # Airs=64
Tue 11/23 21:11:39.874 [Scheduler@4e3032] MUST SEE FINAL-Tuner1 on 127.0.0.1:9000-[A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]]
Tue 11/23 21:11:39.874 [Scheduler@4e3032] MUST SEE FINAL-Tuner2 on 127.0.0.1:9000-[A[259825,244046,"NFL Football",32645@1129.17:00,195,T]]
Tue 11/23 21:11:39.874 [Scheduler@4e3032] Evaluating Potentials
Tue 11/23 21:11:39.874 [Scheduler@4e3032] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE
Tue 11/23 21:11:39.874 [Scheduler@4e3032] Tuner1 on 127.0.0.1:9000
Tue 11/23 21:11:39.874 [Scheduler@4e3032] [A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]]
Tue 11/23 21:11:39.875 [Scheduler@4e3032] Tuner2 on 127.0.0.1:9000
Tue 11/23 21:11:39.875 [Scheduler@4e3032] [A[259825,244046,"NFL Football",32645@1129.17:00,195,T]]
Tue 11/23 21:11:39.875 [Scheduler@4e3032] Total Schedule eval time=9 msec
Tue 11/23 21:11:39.875 [Scheduler@4e3032] Scheduler awoken
Tue 11/23 21:11:39.876 [Scheduler@4e3032] Scheduler.updateSchedule() called  manual=[] schedules={Tuner1 on 127.0.0.1:9000=[Sched=[A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]] MustSee=[A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]]], Tuner2 on 127.0.0.1:9000=[Sched=[A[259825,244046,"NFL Football",32645@1129.17:00,195,T]] MustSee=[A[259825,244046,"NFL Football",32645@1129.17:00,195,T]]]} scheduleRandSize=0
Tue 11/23 21:11:39.877 [Scheduler@4e3032] # Airs=64
Tue 11/23 21:11:39.878 [Scheduler@4e3032] MUST SEE FINAL-Tuner1 on 127.0.0.1:9000-[A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]]
Tue 11/23 21:11:39.879 [Scheduler@4e3032] MUST SEE FINAL-Tuner2 on 127.0.0.1:9000-[A[259825,244046,"NFL Football",32645@1129.17:00,195,T]]
Tue 11/23 21:11:39.879 [Scheduler@4e3032] Evaluating Potentials
Tue 11/23 21:11:39.879 [Scheduler@4e3032] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE
Tue 11/23 21:11:39.879 [Scheduler@4e3032] Tuner1 on 127.0.0.1:9000
Tue 11/23 21:11:39.879 [Scheduler@4e3032] [A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]]
Tue 11/23 21:11:39.879 [Scheduler@4e3032] Tuner2 on 127.0.0.1:9000
Tue 11/23 21:11:39.879 [Scheduler@4e3032] [A[259825,244046,"NFL Football",32645@1129.17:00,195,T]]
Tue 11/23 21:11:39.880 [Scheduler@4e3032] Total Schedule eval time=4 msec
Tue 11/23 21:11:39.880 [Scheduler@4e3032] Scheduler starting wait...
Tue 11/23 21:11:39.889 [Carny@574acb] CARNY Processing 13 Agents & 19098 Airs
Tue 11/23 21:11:39.891 [Carny@574acb] CARNY agent workers processed 13 of 13 active agents.
Tue 11/23 21:11:39.891 [Carny@574acb] CARNY Negative Energy Size: 46
Tue 11/23 21:11:39.891 [Carny@574acb] CARNY Traitors:[]
Tue 11/23 21:11:39.892 [Carny@574acb] CARNY finished in 29ms
Tue 11/23 21:11:39.892 [Carny@574acb] Carny waiting for awhile...
Tue 11/23 21:11:39.892 [Scheduler@4e3032] Scheduler awoken
Tue 11/23 21:11:39.893 [Scheduler@4e3032] Scheduler.updateSchedule() called  manual=[] schedules={Tuner1 on 127.0.0.1:9000=[Sched=[A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]] MustSee=[A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]]], Tuner2 on 127.0.0.1:9000=[Sched=[A[259825,244046,"NFL Football",32645@1129.17:00,195,T]] MustSee=[A[259825,244046,"NFL Football",32645@1129.17:00,195,T]]]} scheduleRandSize=0
Tue 11/23 21:11:39.894 [Scheduler@4e3032] # Airs=81
Tue 11/23 21:11:39.895 [Scheduler@4e3032] MUST SEE FINAL-Tuner1 on 127.0.0.1:9000-[A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]]
Tue 11/23 21:11:39.895 [Scheduler@4e3032] MUST SEE FINAL-Tuner2 on 127.0.0.1:9000-[A[259825,244046,"NFL Football",32645@1129.17:00,195,T]]
Tue 11/23 21:11:39.895 [Scheduler@4e3032] Evaluating Potentials
Tue 11/23 21:11:39.895 [Scheduler@4e3032] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE
Tue 11/23 21:11:39.895 [Scheduler@4e3032] Tuner1 on 127.0.0.1:9000
Tue 11/23 21:11:39.896 [Scheduler@4e3032] [A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]]
Tue 11/23 21:11:39.896 [Scheduler@4e3032] Tuner2 on 127.0.0.1:9000
Tue 11/23 21:11:39.896 [Scheduler@4e3032] [A[259825,244046,"NFL Football",32645@1129.17:00,195,T]]
Tue 11/23 21:11:39.896 [Scheduler@4e3032] Total Schedule eval time=4 msec
Tue 11/23 21:11:39.896 [Scheduler@4e3032] Scheduler starting wait...
Tue 11/23 21:11:40.061 [AsyncPropSaver@1c18353] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties
Tue 11/23 21:11:40.062 [Seeker@53bf8f] Checking video directories for new files
Tue 11/23 21:11:40.062 [Seeker@53bf8f] Diskspace checking is running
Tue 11/23 21:11:40.062 [LibraryImporter@c636ac] Checking video directories for new files
Tue 11/23 21:11:40.062 [Seeker@53bf8f] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true
Tue 11/23 21:11:40.063 [Seeker@53bf8f] Enforcing keep at most limits for the files...
Tue 11/23 21:11:40.063 [Seeker@53bf8f] DONE enforcing keep at most limits for the files.
Tue 11/23 21:11:40.063 [Seeker@53bf8f] Seeker clearing unwanted and partial files...
Tue 11/23 21:11:40.063 [Seeker@53bf8f] DONE clearing unwanted and partial files.
Tue 11/23 21:11:40.066 [Seeker@53bf8f] Seeker waiting for 153722839985401 mins.
Tue 11/23 21:11:40.074 [AsyncPropSaver@1c18353] Done writing out the data to the properties file
Tue 11/23 21:11:40.079 [Seeker@53bf8f] Seeker awoken
Tue 11/23 21:11:40.079 [Seeker@53bf8f] MemStats: Used=84MB Total=152MB Max=778MB
Tue 11/23 21:11:40.079 [Seeker@53bf8f] Seeker adding new Encoder: Tuner1 on 127.0.0.1:9000
Tue 11/23 21:11:40.080 [Seeker@53bf8f] Seeker adding new Encoder: Tuner2 on 127.0.0.1:9000
Tue 11/23 21:11:40.080 [Seeker@53bf8f] MARK 1 currRecord=null enc=Tuner1 on 127.0.0.1:9000 clients=[] ir=false
Tue 11/23 21:11:40.080 [Seeker@53bf8f] Seeker in AUTOMATIC mode nextRecord=A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T] nextTTA=71299920
Tue 11/23 21:11:40.081 [Seeker@53bf8f] newRecord=null
Tue 11/23 21:11:40.081 [Seeker@53bf8f] NOTHING TO RECORD FOR NOW...
Tue 11/23 21:11:40.081 [Seeker@53bf8f] MARK 1 currRecord=null enc=Tuner2 on 127.0.0.1:9000 clients=[] ir=false
Tue 11/23 21:11:40.081 [Seeker@53bf8f] Seeker in AUTOMATIC mode nextRecord=A[259825,244046,"NFL Football",32645@1129.17:00,195,T] nextTTA=503299920
Tue 11/23 21:11:40.081 [Seeker@53bf8f] newRecord=null
Tue 11/23 21:11:40.081 [Seeker@53bf8f] NOTHING TO RECORD FOR NOW...
Tue 11/23 21:11:40.081 [Seeker@53bf8f] Seeker waiting for -27295511 mins.
Tue 11/23 21:11:40.094 [Seeker@53bf8f] Seeker awoken
Tue 11/23 21:11:40.095 [Seeker@53bf8f] MemStats: Used=84MB Total=152MB Max=778MB
Tue 11/23 21:11:40.095 [Seeker@53bf8f] MARK 1 currRecord=null enc=Tuner1 on 127.0.0.1:9000 clients=[] ir=false
Tue 11/23 21:11:40.095 [Seeker@53bf8f] Seeker in AUTOMATIC mode nextRecord=A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T] nextTTA=71299905
Tue 11/23 21:11:40.095 [Seeker@53bf8f] newRecord=null
Tue 11/23 21:11:40.095 [Seeker@53bf8f] NOTHING TO RECORD FOR NOW...
Tue 11/23 21:11:40.095 [Seeker@53bf8f] MARK 1 currRecord=null enc=Tuner2 on 127.0.0.1:9000 clients=[] ir=false
Tue 11/23 21:11:40.096 [Seeker@53bf8f] Seeker in AUTOMATIC mode nextRecord=A[259825,244046,"NFL Football",32645@1129.17:00,195,T] nextTTA=503299905
Tue 11/23 21:11:40.096 [Seeker@53bf8f] newRecord=null
Tue 11/23 21:11:40.096 [Seeker@53bf8f] NOTHING TO RECORD FOR NOW...
Tue 11/23 21:11:40.096 [Seeker@53bf8f] Checking video directories for new files
Tue 11/23 21:11:40.096 [Seeker@53bf8f] Diskspace checking is running
Tue 11/23 21:11:40.096 [Seeker@53bf8f] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true
Tue 11/23 21:11:40.096 [Seeker@53bf8f] Enforcing keep at most limits for the files...
Tue 11/23 21:11:40.097 [Seeker@53bf8f] DONE enforcing keep at most limits for the files.
Tue 11/23 21:11:40.097 [Seeker@53bf8f] Seeker clearing unwanted and partial files...
Tue 11/23 21:11:40.097 [Seeker@53bf8f] DONE clearing unwanted and partial files.
Tue 11/23 21:11:40.097 [Seeker@53bf8f] Seeker waiting for 1183 mins.
Tue 11/23 21:11:40.127 [SageTVServer@151071b] SageTVServer was instantiated loadDelay=3.921 sec
Tue 11/23 21:11:40.128 [SageTVMiniDiscoveryServer@12b5921] SageTVMiniDiscoveryServer was instantiated.
Tue 11/23 21:11:40.130 [SageTVDiscoveryServer@13e7715] SageTVDiscoveryServer was instantiated.
Tue 11/23 21:11:40.131 [SageTV@fd4295] Trying to find lan network interface
Tue 11/23 21:11:40.190 [SageTV@fd4295] addr: /127.0.0.1
Tue 11/23 21:11:40.190 [SageTV@fd4295] addr: /0:0:0:0:0:0:0:1
Tue 11/23 21:11:40.190 [SageTV@fd4295] addr: /192.168.1.143
Tue 11/23 21:11:40.190 [SageTV@fd4295] Miniserver running on 192.168.1.143
Tue 11/23 21:11:40.190 [SageTV@fd4295] Parsed 0 clients
Tue 11/23 21:11:40.191 [MiniBootp@15e9582] 0.0.0.0/0.0.0.0 16867
Tue 11/23 21:11:40.191 [MiniTftp@193fa89] 0.0.0.0/0.0.0.0 16869
Tue 11/23 21:11:40.191 [MiniMVP@16750b0] 0.0.0.0/0.0.0.0 16881
Tue 11/23 21:11:40.194 [SageTV@fd4295] Loading locator keys from filesystem since they already exist.
Tue 11/23 21:11:40.209 [SageTV@fd4295] Splash: User Interface Manager is initializing...
Tue 11/23 21:11:40.209 [PSNATMGR@1dcd440] Starting UPnP NAT Manager...
Tue 11/23 21:11:40.210 [SageTV@fd4295] Loading all core plugins...
Tue 11/23 21:11:40.210 [HotplugStorage@11e7aa7] StorageDeviceDetector started...
Tue 11/23 21:11:40.218 [SageTV@fd4295] CorePluginManager is checking plugin: commercialdetector type: Standard version: 1.16.2 by: tmiranda
Tue 11/23 21:11:40.218 [SageTV@fd4295] Plugin commercialdetector is enabled, try to load it from class: tmiranda.cd.plugin
Tue 11/23 21:11:40.220 [SageTV@fd4295] CorePluginManager is checking plugin: hamcrest-core type: Library version: 1.3 by: stuckless
Tue 11/23 21:11:40.220 [SageTV@fd4295] CorePluginManager is checking plugin: sagetv7 type: STV version: 9.2.6.1 by: sagetv
Tue 11/23 21:11:40.220 [SageTV@fd4295] CorePluginManager is checking plugin: gemstone-icons type: Library version: 1.101.2 by: JOrton, jusjoken
Tue 11/23 21:11:40.220 [SageTV@fd4295] CorePluginManager is checking plugin: sagex-api type: Library version: 9.1.7.0 by: stuckless
Tue 11/23 21:11:40.220 [SageTV@fd4295] CorePluginManager is checking plugin: comskip type: Library version: 80.042.003.2 by: tmiranda
Tue 11/23 21:11:40.221 [SageTV@fd4295] CorePluginManager is checking plugin: gson type: Library version: 2.7 by: SageTV
Tue 11/23 21:11:40.221 [SageTV@fd4295] CorePluginManager is checking plugin: commons-io type: Library version: 2.5 by: stuckless
Tue 11/23 21:11:40.221 [SageTV@fd4295] CorePluginManager is checking plugin: googleweather type: Library version: 3.0.3 by: Opus4, jusjoken
Tue 11/23 21:11:40.221 [SageTV@fd4295] CorePluginManager is checking plugin: commons-lang type: Library version: 2.4 by: stuckless
Tue 11/23 21:11:40.222 [SageTV@fd4295] LOADED PLUGINS=[SageTVPlugin[commercialdetector state=Enabled name=Commercial Detector version=1.16.2 type=Standard desc=A simple way to detect commercials in your recordings.  Creates .edl files that can be read by the Comskip Playback plugin.  The plugin comes complete with comskip and little or no configuration is needed. author=tmiranda screenshots=null videos=null modified=11/7/2021 installed=11/22/2021 resPath= webpages=[http://code.google.com/p/tmiranda/wiki/CommercialDetector, http://forums.sagetv.com/forums/showthread.php?t=49958] implementation=tmiranda.cd.plugin desktopOnly=false serverOnly=false OS=Win,Linux,Mac dependencies=[PluginDependency[sagex-api minVer=7.0.0.0 type=Plugin], PluginDependency[comskip minVer=80.042.003.2 type=Plugin]] packages=null], SageTVPlugin[hamcrest-core state=Failed name=hamcrest-core version=1.3 type=Library desc=SageTV Dependency JAR Plugin for hamcrest-core - no need to download directly author=stuckless screenshots=null videos=null modified=12/12/2015 installed=11/21/2021 resPath= webpages=null implementation= desktopOnly=false serverOnly=false OS=Win,Linux,Mac dependencies=null packages=null], SageTVPlugin[sagetv7 state=Failed name=SageTV 7 UI - for use with V9 (same as UI installed with SageTV) version=9.2.6.1 type=STV desc=This plugin version of the SageTV 7 UI should be current with the latest SageTV release (dependent on developers keeping them current). It is provided for those who want the UI updated without installing the latest version (ie: Java version issues) author=sagetv screenshots=null videos=null modified=8/18/2021 installed=11/23/2021 resPath= webpages=[https://forums.sagetv.com/] implementation= desktopOnly=false serverOnly=false OS=Win,Linux,Mac dependencies=[PluginDependency[googleweather minVer=3.0.1 type=Plugin]] packages=null], SageTVPlugin[gemstone-icons state=Failed name=Weather Icon Files version=1.101.2 type=Library desc=Weather Icon Files - Do not install directly as this is just a dependency!! author=JOrton, jusjoken screenshots=null videos=null modified=8/14/2021 installed=11/21/2021 resPath= webpages=null implementation= desktopOnly=false serverOnly=false OS=Win,Linux,Mac dependencies=null packages=null], SageTVPlugin[sagex-api state=Failed name=sagex-api - SageTV API Extensions version=9.1.7.0 type=Library desc=SageTV API Wrappers with Remote API capabilities author=stuckless screenshots=null videos=null modified=3/4/2018 installed=11/22/2021 resPath=sagex-api webpages=[http://forums.sagetv.com/forums/showthread.php?t=36044] implementation= desktopOnly=false serverOnly=false OS=Win,Linux,Mac dependencies=[PluginDependency[Java minVer=1.7 type=JVM], PluginDependency[SageTV minVer=9.1.7 type=Core]] packages=null], SageTVPlugin[comskip state=Failed name=comskip version=80.042.003.2 type=Library desc=Comskip repackaged as a SageTV7 Plugin.  This is used for CommercialDetector, no need to install directly. author=tmiranda screenshots=null videos=null modified=11/7/2021 installed=11/22/2021 resPath=comskip webpages=[http://www.kaashoek.com/comskip/] implementation= desktopOnly=false serverOnly=false OS=Win,Linux,Mac dependencies=[PluginDependency[SageTV7 minVer=7.0 type=STV]] packages=null], SageTVPlugin[gson state=Failed name=gson Library version=2.7 type=Library desc=gson library - dependency only author=SageTV screenshots=null videos=null modified=1/26/2019 installed=11/21/2021 resPath=gson webpages=[https://repo1.maven.org/maven2/com/google/code/gson/gson/2.7/gson-2.7.jar] implementation= desktopOnly=false serverOnly=false OS=Win,Linux,Mac dependencies=[PluginDependency[SageTV minVer=9.0.7 type=Core]] packages=null], SageTVPlugin[commons-io state=Failed name=commons-io version=2.5 type=Library desc=SageTV Dependency JAR Plugin for commons-io-1.4.jar - no need to download directly author=stuckless screenshots=null videos=null modified=3/20/2014 installed=11/21/2021 resPath= webpages=null implementation= desktopOnly=false serverOnly=false OS=Win,Linux,Mac dependencies=[PluginDependency[Java minVer=1.6 type=JVM]] packages=null], SageTVPlugin[googleweather state=Failed name=Google Weather version=3.0.3 type=Library desc=A weather support library that is used by SageTV's updated weather system to retrieve weather information from OpenWeather. author=Opus4, jusjoken screenshots=null videos=null modified=8/28/2021 installed=11/21/2021 resPath= webpages=null implementation= desktopOnly=false serverOnly=false OS=Win,Linux,Mac dependencies=[PluginDependency[hamcrest-core minVer=1.3 type=Plugin], PluginDependency[gemstone-icons minVer=1.101 type=Plugin], PluginDependency[gson minVer=1.7.1 type=Plugin], PluginDependency[commons-io minVer=2.4 type=Plugin], PluginDependency[commons-lang minVer=2.4 type=Plugin]] packages=null], SageTVPlugin[commons-lang state=Failed name=commons-lang version=2.4 type=Library desc=SageTV Dependency JAR Plugin for commons-lang-2.4.jar - no need to download directly author=stuckless screenshots=null videos=null modified=4/10/2010 installed=11/21/2021 resPath= webpages=null implementation= desktopOnly=false serverOnly=false OS=Win,Linux,Mac dependencies=null packages=null]]
Tue 11/23 21:11:40.229 [PluginRepoUpdater@44cf2f] Checking to see if the plugin repository file SageTVPlugins.xml needs to be updated using MD5 URL: http://download.sagetv.com/SageTVPlugins.md5.txt
Tue 11/23 21:11:40.290 [SageTV@fd4295] Starting all core plugins...
Tue 11/23 21:11:40.291 [SageTV@fd4295] Moving starting of plugin Commercial Detector to after sagex-api - SageTV API Extensions because of its dependency on it.
Tue 11/23 21:11:40.291 [SageTV@fd4295] Moving starting of plugin SageTV 7 UI - for use with V9 (same as UI installed with SageTV) to after Google Weather because of its dependency on it.
Tue 11/23 21:11:40.291 [SageTV@fd4295] Moving starting of plugin Commercial Detector to after comskip because of its dependency on it.
Tue 11/23 21:11:40.291 [SageTV@fd4295] Calling start on plugin: Commercial Detector
Tue 11/23 21:11:40.291 [SageTV@fd4295] CD: PlugIn: Starting. Version = 1.16
Tue 11/23 21:11:40.295 [SageTV@fd4295] Using default logging, since log4j is not in the classpath.
Tue 11/23 21:11:40.303 [SageTV@fd4295] sagex.api:[Tue 11/23 21:11:40.303 [SageTV@fd4295]  infoTue 11/23 21:11:40.303 [SageTV@fd4295] ]:Tue 11/23 21:11:40.303 [SageTV@fd4295] Sage Provider Implementation: sagex.remote.EmbeddedSageAPIProvider; sage://embeddedTue 11/23 21:11:40.303 [SageTV@fd4295] 
Tue 11/23 21:11:40.304 [SageTV@fd4295] CD: PlugIn: LogLevel = Warn.
Tue 11/23 21:11:40.306 [SageTV@fd4295] CD: start: null Name or Number 
Tue 11/23 21:11:40.316 [SageTV@fd4295] CD: SystemStatus: Number running = 0
Tue 11/23 21:11:40.316 [SageTV@fd4295] CD: SystemStatus: No jobs running.
Tue 11/23 21:11:40.317 [SageTV@fd4295] CD: SystemStatus: No jobs in queue.
Tue 11/23 21:11:40.317 [SageTV@fd4295] Moving starting of plugin Google Weather to after commons-lang because of its dependency on it.
Tue 11/23 21:11:40.317 [SageTV@fd4295] Moving starting of plugin SageTV 7 UI - for use with V9 (same as UI installed with SageTV) to after Google Weather because of its dependency on it.
Tue 11/23 21:11:40.317 [SageTV@fd4295] Done starting core plugins.
Tue 11/23 21:11:40.317 [PSNATMGR@1a5c2e2] Starting UPnP NAT Manager...
Tue 11/23 21:11:40.409 [PluginRepoUpdater@44cf2f] Local plugin repository file's checksum matches server's, no need to download a new one for SageTVPlugins.xml
Tue 11/23 21:11:40.411 [PluginRepoUpdater@44cf2f] 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
Tue 11/23 21:11:40.568 [LibraryImporter@c636ac] Seeker is starting the library import scan...
Tue 11/23 21:11:40.571 [LibraryImporter@c636ac] Starting to scan lib import root: C:\Users\Bill\Videos
Tue 11/23 21:11:40.645 [LibraryImporter@c636ac] Skipping video import dir that's also a recording dir:C:\Users\Bill\Videos\SageTV
Tue 11/23 21:11:40.646 [LibraryImporter@c636ac] Starting to scan lib import root: C:\Users\Bill\Pictures
Tue 11/23 21:11:40.738 [LibraryImporter@c636ac] Starting to scan lib import root: C:\Users\Bill\Music
Tue 11/23 21:11:40.769 [LibraryImporter@c636ac] Starting to scan lib import root: C:\Users\Public\Videos
Tue 11/23 21:11:40.800 [LibraryImporter@c636ac] Starting to scan lib import root: C:\Users\Public\Pictures
Tue 11/23 21:11:40.832 [LibraryImporter@c636ac] Starting to scan lib import root: C:\Users\Public\Music
Tue 11/23 21:11:40.885 [LibraryImporter@c636ac] Seeker has finished the library import scan.
Tue 11/23 21:11:41.595 [PluginRepoUpdater@44cf2f] Local plugin repository file's checksum matches server's, no need to download a new one for SageTVPluginsV9.xml
Tue 11/23 21:11:41.596 [PluginRepoUpdater@44cf2f] Analyzing plugin repository XML file: SageTVPlugins.xml
Tue 11/23 21:11:41.691 [PluginRepoUpdater@44cf2f] Done processing plugin repository XML file[SageTVPlugins.xml]  repositoryVersion=1.0.2063
Tue 11/23 21:11:41.691 [PluginRepoUpdater@44cf2f] Analyzing plugin repository XML file: SageTVPluginsV9.xml
Tue 11/23 21:11:41.703 [PluginRepoUpdater@44cf2f] Done processing plugin repository XML file[SageTVPluginsV9.xml]  repositoryVersion=2021.1111.2221
Tue 11/23 21:11:41.964 [SageTVServer@151071b] SageTV received connection from:Socket[addr=/127.0.0.1,port=59920,localport=42024]
Tue 11/23 21:11:41.985 [SageTVServer@151071b] Suspending DB writes while we send the DB to the client...
Tue 11/23 21:11:41.985 [SageTVServer@151071b] Sending DB to client of size:14370226
Tue 11/23 21:11:42.068 [SageTVServer@151071b] Logging 0 DB xcts to file and sending them to the new client...
Tue 11/23 21:11:42.069 [SageTVServer@151071b] DONE sending DB to the client and client is now added as a DB sync listener
Tue 11/23 21:11:42.171 [ClientListenerParallelizer@2db9a7] Listener parallelizer thread has spawned for /127.0.0.1:59920
Tue 11/23 21:11:42.204 [SageTVServer@151071b] SageTV received connection from:Socket[addr=/127.0.0.1,port=59921,localport=42024]
Tue 11/23 21:11:42.207 [SageTVServer@151071b] Increased Thread pool to size=6
Tue 11/23 21:11:42.208 [ConnSendQueue@19caa13] MsgSend thread spawned for /127.0.0.1:59920
Tue 11/23 21:11:42.208 [ConnRecvQueue@ed1cad] MsgRecv thread has spawned for /127.0.0.1:59920
Tue 11/23 21:11:42.451 [Scheduler@4e3032] Scheduler awoken
Tue 11/23 21:11:42.452 [Scheduler@4e3032] Scheduler.updateSchedule() called  manual=[] schedules={Tuner1 on 127.0.0.1:9000=[Sched=[A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]] MustSee=[A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]]], Tuner2 on 127.0.0.1:9000=[Sched=[A[259825,244046,"NFL Football",32645@1129.17:00,195,T]] MustSee=[A[259825,244046,"NFL Football",32645@1129.17:00,195,T]]]} scheduleRandSize=0
Tue 11/23 21:11:42.452 [Scheduler@4e3032] # Airs=81
Tue 11/23 21:11:42.453 [Scheduler@4e3032] MUST SEE FINAL-Tuner1 on 127.0.0.1:9000-[A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]]
Tue 11/23 21:11:42.453 [Scheduler@4e3032] MUST SEE FINAL-Tuner2 on 127.0.0.1:9000-[A[259825,244046,"NFL Football",32645@1129.17:00,195,T]]
Tue 11/23 21:11:42.453 [Scheduler@4e3032] Evaluating Potentials
Tue 11/23 21:11:42.453 [Scheduler@4e3032] COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE
Tue 11/23 21:11:42.454 [Scheduler@4e3032] Tuner1 on 127.0.0.1:9000
Tue 11/23 21:11:42.454 [Scheduler@4e3032] [A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T], A[144290,133258,"The Rachel Maddow Show",64241@1124.18:00,60,T], A[144291,133259,"The Last Word With Lawrence O'Donnell",64241@1124.19:00,60,T], A[190641,180071,"College Football",82547@1126.17:00,210,T], A[256949,246699,"All In With Chris Hayes",64241@1129.17:00,60,T], A[256950,246700,"The Rachel Maddow Show",64241@1129.18:00,60,T], A[256951,246701,"The Last Word With Lawrence O'Donnell",64241@1129.19:00,60,T], A[251297,243031,"The Voice",10518@1129.20:00,121,T], A[277712,267168,"All In With Chris Hayes",64241@1130.17:00,60,T], A[277713,267169,"The Rachel Maddow Show",64241@1130.18:00,60,T], A[277714,267170,"The Last Word With Lawrence O'Donnell",64241@1130.19:00,60,T], A[271985,263479,"The Voice",10518@1130.20:00,60,T], A[298064,287624,"All In With Chris Hayes",64241@1201.17:00,60,T], A[298065,287625,"The Rachel Maddow Show",64241@1201.18:00,60,T], A[298066,287626,"The Last Word With Lawrence O'Donnell",64241@1201.19:00,60,T], A[317954,307646,"All In With Chris Hayes",64241@1202.17:00,60,T], A[317955,307647,"The Rachel Maddow Show",64241@1202.18:00,60,T], A[317956,307648,"The Last Word With Lawrence O'Donnell",64241@1202.19:00,60,T], A[337666,327340,"All In With Chris Hayes",64241@1203.17:00,60,T], A[337667,327341,"The Rachel Maddow Show",64241@1203.18:00,60,T], A[337668,327342,"The Last Word With Lawrence O'Donnell",64241@1203.19:00,60,T], A[369016,361950,"NFL Football",10518@1205.17:20,190,T], A[461260,461218,"All In With Chris Hayes",64241@1206.17:00,60,T], A[461261,461219,"The Rachel Maddow Show",64241@1206.18:00,60,T], A[461262,461220,"The Last Word With Lawrence O'Donnell",64241@1206.19:00,60,T], A[461233,461174,"The Voice",10518@1206.20:00,121,T], A[461358,461325,"All In With Chris Hayes",64241@1207.17:00,60,T], A[461359,461326,"The Rachel Maddow Show",64241@1207.18:00,60,T], A[461360,461327,"The Last Word With Lawrence O'Donnell",64241@1207.19:00,60,T], A[461340,461280,"The Voice",10518@1207.20:00,60,T]]
Tue 11/23 21:11:42.454 [Scheduler@4e3032] Tuner2 on 127.0.0.1:9000
Tue 11/23 21:11:42.454 [Scheduler@4e3032] [A[259825,244046,"NFL Football",32645@1129.17:00,195,T]]
Tue 11/23 21:11:42.454 [Scheduler@4e3032] Total Schedule eval time=2 msec
Tue 11/23 21:11:42.454 [Scheduler@4e3032] Scheduler starting wait...
Tue 11/23 21:11:42.454 [Seeker@53bf8f] Seeker awoken
Tue 11/23 21:11:42.454 [Seeker@53bf8f] MemStats: Used=86MB Total=175MB Max=778MB
Tue 11/23 21:11:42.455 [Seeker@53bf8f] MARK 1 currRecord=null enc=Tuner1 on 127.0.0.1:9000 clients=[] ir=false
Tue 11/23 21:11:42.455 [Seeker@53bf8f] Seeker in AUTOMATIC mode nextRecord=A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T] nextTTA=71297545
Tue 11/23 21:11:42.455 [Seeker@53bf8f] newRecord=null
Tue 11/23 21:11:42.455 [Seeker@53bf8f] NOTHING TO RECORD FOR NOW...
Tue 11/23 21:11:42.455 [Seeker@53bf8f] MARK 1 currRecord=null enc=Tuner2 on 127.0.0.1:9000 clients=[] ir=false
Tue 11/23 21:11:42.455 [Seeker@53bf8f] Seeker in AUTOMATIC mode nextRecord=A[259825,244046,"NFL Football",32645@1129.17:00,195,T] nextTTA=503297545
Tue 11/23 21:11:42.455 [Seeker@53bf8f] newRecord=null
Tue 11/23 21:11:42.455 [Seeker@53bf8f] NOTHING TO RECORD FOR NOW...
Tue 11/23 21:11:42.456 [Seeker@53bf8f] Checking video directories for new files
Tue 11/23 21:11:42.456 [Seeker@53bf8f] Diskspace checking is running
Tue 11/23 21:11:42.456 [Seeker@53bf8f] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true
Tue 11/23 21:11:42.456 [Seeker@53bf8f] Enforcing keep at most limits for the files...
Tue 11/23 21:11:42.456 [Seeker@53bf8f] DONE enforcing keep at most limits for the files.
Tue 11/23 21:11:42.456 [Seeker@53bf8f] Seeker clearing unwanted and partial files...
Tue 11/23 21:11:42.457 [Seeker@53bf8f] DONE clearing unwanted and partial files.
Tue 11/23 21:11:42.457 [Seeker@53bf8f] Seeker waiting for 1183 mins.
Tue 11/23 21:11:42.810 [ConnRecvQueue@ed1cad] Recieving 14 capabilties for client /127.0.0.1:59920
Tue 11/23 21:11:42.811 [ConnRecvQueue@ed1cad]   SystemStatusChanged = true
Tue 11/23 21:11:42.811 [ConnRecvQueue@ed1cad]   RequestToExceedParentalRestrictions = true
Tue 11/23 21:11:42.811 [ConnRecvQueue@ed1cad]   MediaPlayerFileLoadComplete = true
Tue 11/23 21:11:42.811 [ConnRecvQueue@ed1cad]   RecordRequestLiveConflict = true
Tue 11/23 21:11:42.811 [ConnRecvQueue@ed1cad]   MediaFilesImported = true
Tue 11/23 21:11:42.811 [ConnRecvQueue@ed1cad]   ApplicationStarted = true
Tue 11/23 21:11:42.811 [ConnRecvQueue@ed1cad]   MediaPlayerError = true
Tue 11/23 21:11:42.811 [ConnRecvQueue@ed1cad]   DenyChannelChangeToRecord = true
Tue 11/23 21:11:42.811 [ConnRecvQueue@ed1cad]   StorageDeviceAdded = true
Tue 11/23 21:11:42.811 [ConnRecvQueue@ed1cad]   NewUnresolvedSchedulingConflicts = true
Tue 11/23 21:11:42.812 [ConnRecvQueue@ed1cad]   RecordingScheduleChanged = true
Tue 11/23 21:11:42.812 [ConnRecvQueue@ed1cad]   ApplicationExiting = true
Tue 11/23 21:11:42.812 [ConnRecvQueue@ed1cad]   RecordRequestScheduleConflict = true
Tue 11/23 21:11:42.812 [ConnRecvQueue@ed1cad]   WatchRequestConflict = true
Tue 11/23 21:11:44.865 [Ministry@1e46937] Ministry is starting
Tue 11/23 21:11:44.871 [Ministry@1e46937] Doing pre-emptive file format detection on recording MediaFile....C:\Users\Bill\Videos\SageTV\Tuner1on1270019000HDMI-0.mpgbuf
Tue 11/23 21:11:44.877 [Ministry@1e46937] Doing pre-emptive file format detection on recording MediaFile....C:\Users\Bill\Videos\SageTV\Tuner2on1270019000HDMI-0.mpgbuf
Tue 11/23 21:11:44.877 [Ministry@1e46937] Ministry is waiting for 180 sec
Tue 11/23 21:13:39.314 [PooledThread@1c18353] Seeker.finishWatch(/127.0.0.1:59920@@SAGETV_PROCESS_LOCAL_UI)
Tue 11/23 21:13:39.317 [PooledThread@1c18353] Seeker.finishWatch(/127.0.0.1:59920@@SAGETV_PROCESS_LOCAL_UI)
Tue 11/23 21:13:39.318 [PooledThread@1c18353] Seeker.finishWatch(/127.0.0.1:59920@@SAGETV_PROCESS_LOCAL_UI)
Tue 11/23 21:13:41.588 [ConnRecvQueue@ed1cad] Error communicating with server:java.io.EOFException
Tue 11/23 21:13:41.588 [ConnRecvQueue@ed1cad] Cleaning up c/s connection
Tue 11/23 21:13:41.589 [ConnRecvQueue@ed1cad] NetworkManager CommunicationFailure : /127.0.0.1:59920 type=1
Tue 11/23 21:13:41.589 [ConnRecvQueue@ed1cad] NetworkClient fullCleanup /127.0.0.1:59920
Tue 11/23 21:13:41.589 [ConnRecvQueue@ed1cad] Cleaning up c/s connection
Tue 11/23 21:13:41.589 [ConnRecvQueue@ed1cad] Cleaning up c/s connection
Tue 11/23 21:13:41.589 [ConnRecvQueue@ed1cad] MsgRecv thread is terminating for /127.0.0.1:59920
Tue 11/23 21:13:41.589 [Seeker@53bf8f] Seeker awoken
Tue 11/23 21:13:41.589 [Seeker@53bf8f] MemStats: Used=89MB Total=175MB Max=778MB
Tue 11/23 21:13:41.591 [Seeker@53bf8f] MARK 1 currRecord=null enc=Tuner1 on 127.0.0.1:9000 clients=[] ir=false
Tue 11/23 21:13:41.591 [Seeker@53bf8f] Seeker in AUTOMATIC mode nextRecord=A[144289,133257,"All In With Chris Hayes",64241@1124.17:00,60,T] nextTTA=71178409
Tue 11/23 21:13:41.591 [Seeker@53bf8f] newRecord=null
Tue 11/23 21:13:41.591 [Seeker@53bf8f] NOTHING TO RECORD FOR NOW...
Tue 11/23 21:13:41.591 [Seeker@53bf8f] MARK 1 currRecord=null enc=Tuner2 on 127.0.0.1:9000 clients=[] ir=false
Tue 11/23 21:13:41.591 [Seeker@53bf8f] Seeker in AUTOMATIC mode nextRecord=A[259825,244046,"NFL Football",32645@1129.17:00,195,T] nextTTA=503178409
Tue 11/23 21:13:41.591 [Seeker@53bf8f] newRecord=null
Tue 11/23 21:13:41.592 [Seeker@53bf8f] NOTHING TO RECORD FOR NOW...
Tue 11/23 21:13:41.592 [Seeker@53bf8f] Checking video directories for new files
Tue 11/23 21:13:41.592 [Seeker@53bf8f] Diskspace checking is running
Tue 11/23 21:13:41.592 [Seeker@53bf8f] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true
Tue 11/23 21:13:41.592 [Seeker@53bf8f] Enforcing keep at most limits for the files...
Tue 11/23 21:13:41.592 [Seeker@53bf8f] DONE enforcing keep at most limits for the files.
Tue 11/23 21:13:41.592 [Seeker@53bf8f] Seeker clearing unwanted and partial files...
Tue 11/23 21:13:41.593 [Seeker@53bf8f] DONE clearing unwanted and partial files.
Tue 11/23 21:13:41.593 [Seeker@53bf8f] Seeker waiting for 1181 mins.
Tue 11/23 21:13:42.247 [ClientListenerParallelizer@2db9a7] Listener parallelizer  thread is terminating for /127.0.0.1:59920
Tue 11/23 21:13:42.497 [KeepAlive@c636ac] Error with c/s comm: keepAlive:java.lang.NullPointerException
Tue 11/23 21:13:42.499 [KeepAlive@c636ac] java.lang.NullPointerException
Tue 11/23 21:13:42.499 [KeepAlive@c636ac] 	at sage.SageTVConnection.keepAlive(SageTVConnection.java:2525)
Tue 11/23 21:13:42.500 [KeepAlive@c636ac] 	at sage.SageTVConnection.access$100(SageTVConnection.java:21)
Tue 11/23 21:13:42.500 [KeepAlive@c636ac] 	at sage.SageTVConnection$3.run(SageTVConnection.java:3021)
Tue 11/23 21:13:42.500 [KeepAlive@c636ac] 	at sage.Pooler$PooledThread.run(Pooler.java:254)
Tue 11/23 21:13:42.500 [KeepAlive@c636ac] NetworkManager CommunicationFailure : /127.0.0.1:59920 type=2
Tue 11/23 21:13:42.500 [KeepAlive@c636ac] NetworkManager CommunicationFailure : /127.0.0.1:59920 type=2
Tue 11/23 21:13:48.993 [main@12133b1] Sage.exit() called.
Tue 11/23 21:13:48.993 [main@12133b1] Stopping all core plugins
Tue 11/23 21:13:48.994 [main@12133b1] Calling stop on plugin: Commercial Detector
Tue 11/23 21:13:48.994 [main@12133b1] Destroying all core plugins
Tue 11/23 21:13:48.995 [main@12133b1] Calling destroy on plugin: Commercial Detector
Tue 11/23 21:13:48.995 [main@12133b1] Cleaning up servers
Tue 11/23 21:13:48.997 [MiniTftp@193fa89] MiniError-2:java.net.SocketException: socket closed
Tue 11/23 21:13:48.999 [SageTVMiniDiscoveryServer@12b5921] Error w/SageTV client connection:java.net.SocketException: socket closed
Tue 11/23 21:13:48.999 [MiniMVP@16750b0] MiniError-1:java.net.SocketException: socket closed
Tue 11/23 21:13:49.000 [MiniBootp@15e9582] MiniError-3:java.net.SocketException: socket closed
Tue 11/23 21:13:50.334 [main@12133b1] Cleaning up PM
Tue 11/23 21:13:50.335 [main@12133b1] Killed Carny.
Tue 11/23 21:13:50.336 [main@12133b1] Killed Scheduler.
Tue 11/23 21:13:50.337 [main@12133b1] Error calling finishWatch from VF goodbye:java.lang.NullPointerException
Tue 11/23 21:13:50.337 [Ministry@1e46937] Ministry is shutting down....destroying the converts in progress
Tue 11/23 21:13:50.338 [main@12133b1] FSManager goodbye()
Tue 11/23 21:13:50.339 [main@12133b1] Killed Seeker.
Tue 11/23 21:13:50.340 [main@12133b1] Killed EPG.
Tue 11/23 21:13:50.340 [main@12133b1] Killed MMC.
Tue 11/23 21:13:50.340 [main@12133b1] Killing UIMgr UIManager:localhost@@SAGETV_PROCESS_LOCAL_UI-138eb89
Tue 11/23 21:13:50.341 [main@12133b1] Error calling finishWatch from VF goodbye:java.lang.NullPointerException
Tue 11/23 21:13:50.341 [main@12133b1] Killed VideoFrame
Tue 11/23 21:13:50.349 [main@12133b1] Disposed Window
Tue 11/23 21:13:50.349 [main@12133b1] Killed UIManager.
Tue 11/23 21:13:50.350 [main@12133b1] Killed Wizard.
Tue 11/23 21:13:50.350 [main@12133b1] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties
Tue 11/23 21:13:50.353 [main@12133b1] Done writing out the data to the properties file
Tue 11/23 21:13:50.354 [main@12133b1] Bye-bye.
Tue 11/23 21:13:50.354 [SageTV Shutdown@3ab0a2] SageTV SHUTDOWN is activating!