Sun 5/13 19:04:56.465 Changed default timezone to:Eastern Standard Time Sun 5/13 19:04:56.524 user.dir2=/opt/sagetv6.1 Sun 5/13 19:04:56.524 classpath=Sage.jar:.:/:JARs/commons-jxpath-1.1.jar:JARs/commons-logging-api.jar:JARs/commons-logging.jar:JARs/jcifs-1.1.6.jar:JARs/mx4j-impl.jar:JARs/mx4j-jmx.jar:JARs/mx4j-remote.jar:JARs/mx4j-tools.jar:JARs/nielm_sageutls.jar:JARs/nielm_sagewebserver.jar:JARs/nielm_sagexmlinfo.jar:JARs/sbbi-jmx-1.0.jar:JARs/sbbi-upnplib-1.0.3.jar:JARs/servlet.jar Sun 5/13 19:04:56.525 JVM version=1.5.0 Sun 5/13 19:04:56.525 OS=Linux 2.6.18-3-k7 Sun 5/13 19:04:56.533 SageTV V6.1.8.1 Sun 5/13 19:04:56.537 hostname=videodrome.vo3lk3r.com Sun 5/13 19:04:57.391 Wizard starting to load database info... Sun 5/13 19:04:57.394 DBFile at version 65 FileSize=5038480 Sun 5/13 19:04:57.395 Wizard allocating table for Year of size 94 Sun 5/13 19:04:57.396 Wizard loading main index for Year Sun 5/13 19:04:57.398 Wizard allocating table for Network of size 91 Sun 5/13 19:04:57.398 Wizard loading main index for Network Sun 5/13 19:04:57.399 Wizard allocating table for Title of size 5487 Sun 5/13 19:04:57.400 Wizard loading main index for Title Sun 5/13 19:04:57.434 Wizard building alt. index 0 for Title Sun 5/13 19:04:57.446 Wizard allocating table for Channel of size 345 Sun 5/13 19:04:57.446 Wizard loading main index for Channel Sun 5/13 19:04:57.447 Sage waiting for GC to free up some memory usage%=0.960058 Sun 5/13 19:04:57.541 Sage done waiting for GC to free up some memory usage%=0.550094 Sun 5/13 19:04:57.544 Wizard allocating table for People of size 21773 Sun 5/13 19:04:57.545 Wizard loading main index for People Sun 5/13 19:04:57.596 Wizard building alt. index 0 for People Sun 5/13 19:04:57.625 Wizard allocating table for SubCategory of size 101 Sun 5/13 19:04:57.626 Wizard loading main index for SubCategory Sun 5/13 19:04:57.626 Wizard allocating table for Rated of size 7 Sun 5/13 19:04:57.626 Wizard loading main index for Rated Sun 5/13 19:04:57.626 Wizard allocating table for ParentalRating of size 0 Sun 5/13 19:04:57.626 Wizard allocating table for ExtendedRating of size 10 Sun 5/13 19:04:57.627 Wizard loading main index for ExtendedRating Sun 5/13 19:04:57.627 Wizard allocating table for Category of size 83 Sun 5/13 19:04:57.627 Wizard loading main index for Category Sun 5/13 19:04:57.627 Wizard allocating table for PrimeTitle of size 0 Sun 5/13 19:04:57.627 Wizard allocating table for Bonus of size 249 Sun 5/13 19:04:57.627 Wizard loading main index for Bonus Sun 5/13 19:04:57.628 Wizard allocating table for Show of size 11568 Sun 5/13 19:04:57.632 Wizard loading main index for Show Sun 5/13 19:04:58.063 Wizard building alt. index 0 for Show Sun 5/13 19:04:58.083 Wizard allocating table for Airing of size 27613 Sun 5/13 19:04:58.089 Wizard loading main index for Airing Sun 5/13 19:04:58.134 Wizard building alt. index 0 for Airing Sun 5/13 19:04:58.150 Wizard building alt. index 1 for Airing Sun 5/13 19:04:58.170 Wizard allocating table for ManualRecord of size 9 Sun 5/13 19:04:58.170 Wizard loading main index for ManualRecord Sun 5/13 19:04:58.175 Wizard allocating table for MediaFile of size 120 Sun 5/13 19:04:58.175 Wizard loading main index for MediaFile Sun 5/13 19:04:58.230 Wizard building alt. index 0 for MediaFile Sun 5/13 19:04:58.230 Wizard building alt. index 1 for MediaFile Sun 5/13 19:04:58.231 Wizard allocating table for Watched of size 1112 Sun 5/13 19:04:58.231 Wizard loading main index for Watched Sun 5/13 19:04:58.235 Wizard building alt. index 0 for Watched Sun 5/13 19:04:58.237 Wizard allocating table for Agent of size 993 Sun 5/13 19:04:58.237 Wizard loading main index for Agent Sun 5/13 19:04:58.249 Wizard building alt. index 0 for Agent Sun 5/13 19:04:58.253 Wizard allocating table for Wasted of size 1952 Sun 5/13 19:04:58.254 Wizard loading main index for Wasted Sun 5/13 19:04:58.256 Wizard allocating table for Playlist of size 1 Sun 5/13 19:04:58.256 Wizard loading main index for Playlist Sun 5/13 19:04:58.260 Wizard allocating table for TVEditorial of size 65 Sun 5/13 19:04:58.260 Wizard loading main index for TVEditorial Sun 5/13 19:04:58.261 Wizard allocating table for SeriesInfo of size 1746 Sun 5/13 19:04:58.262 Wizard loading main index for SeriesInfo Sun 5/13 19:04:58.336 Wizard processing transactional records... Sun 5/13 19:04:58.337 Wizard performing validation on database objects... Sun 5/13 19:04:58.393 Wizard DONE loading database info. loadTime=1.002 sec Sun 5/13 19:04:58.606 MMC calling detectCaptureDevices on sage.b9@1eed786 Sun 5/13 19:04:58.607 devices detected=[] Sun 5/13 19:04:58.607 EncoderMap={} Sun 5/13 19:04:58.607 MMC calling detectCaptureDevices on sage.ab@187aeca Sun 5/13 19:04:58.609 videoDevices=[video0] Sun 5/13 19:04:58.610 Checking encoder key:-816678123 Sun 5/13 19:04:58.622 Created IVTVCaptureDevice object for:video0 Sun 5/13 19:04:58.622 EncoderMap1={video0=video0} Sun 5/13 19:04:58.623 Saving properties file to Sage.properties Sun 5/13 19:04:58.647 Processing new system dev:video0 Sun 5/13 19:04:58.647 Device already has been processed Sun 5/13 19:04:58.648 devices detected=[video0] Sun 5/13 19:04:58.648 EncoderMap={video0=video0} Sun 5/13 19:04:58.648 MMC calling detectCaptureDevices on sage.a9@1ad086a Sun 5/13 19:04:58.648 WARNING - MissingResource: "Linux_DVB_Capture_Manager" Sun 5/13 19:04:58.648 videoDevices=[] Sun 5/13 19:04:58.648 detect 3 Sun 5/13 19:04:58.649 Checking encoder key:-816678123 Sun 5/13 19:04:58.649 Device is already accounted for. Sun 5/13 19:04:58.650 EncoderMap1={} Sun 5/13 19:04:58.650 devices detected=[] Sun 5/13 19:04:58.650 EncoderMap={video0=video0} Sun 5/13 19:04:58.688 Checking video directories for new files Sun 5/13 19:04:58.690 Removing zero length file from video dir:/var/media/tv/82ndAirborneDivision-2110780-0.mpg Sun 5/13 19:04:58.691 Removing zero length file from video dir:/var/media/tv/Cops-BizarreCallsSpecialEdition-1506163-0.mpg Sun 5/13 19:04:58.753 CARNY Processing 993 Agents & 4893 Airs Sun 5/13 19:05:01.126 CARNY Negative Energy Size: 3188 Sun 5/13 19:05:01.131 CARNY Traitors:[] Sun 5/13 19:05:01.134 Verifying existence of all TV media files in database fixDurs=true avoidArchive=false Sun 5/13 19:05:01.188 sage.ax@1ebf1eb needs an update in 16:05:01 Sun 5/13 19:05:01.189 sage.ax@157816a needs an update in 2562047788015:12:55 Sun 5/13 19:05:01.189 EPG needs an update in 965 minutes Sun 5/13 19:05:01.189 EPG's works is done. Waiting... Sun 5/13 19:05:01.292 Carny waiting for awhile... Sun 5/13 19:05:01.414 SageTVServer was instantiated. Sun 5/13 19:05:01.416 SageTVDiscoveryServer was instantiated. Sun 5/13 19:05:01.419 Trying to find lan network interface Sun 5/13 19:05:01.420 SageTVMiniDiscoveryServer was instantiated. Sun 5/13 19:05:01.517 addr: /192.168.99.1 Sun 5/13 19:05:01.518 addr: /192.168.98.1 Sun 5/13 19:05:01.518 addr: /10.32.32.5 Sun 5/13 19:05:01.518 Found matching server interface of 10.32.32.5 Sun 5/13 19:05:01.518 Miniserver running on 10.32.32.5 Sun 5/13 19:05:01.518 Parsed 0 clients Sun 5/13 19:05:01.538 Checking video directories for new files Sun 5/13 19:05:01.541 Removing zero length file from video dir:/var/media/tv/82ndAirborneDivision-2110780-0.mpg Sun 5/13 19:05:01.541 Removing zero length file from video dir:/var/media/tv/Cops-BizarreCallsSpecialEdition-1506163-0.mpg Sun 5/13 19:05:01.541 Diskspace checking is running Sun 5/13 19:05:01.541 Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Sun 5/13 19:05:01.545 Seeker waiting for 153722847629287 mins. Sun 5/13 19:05:01.547 0.0.0.0/0.0.0.0 16867 Sun 5/13 19:05:01.548 0.0.0.0/0.0.0.0 16869 Sun 5/13 19:05:01.551 Loading locator keys from filesystem since they already exist. Sun 5/13 19:05:01.552 0.0.0.0/0.0.0.0 16881 Sun 5/13 19:05:01.558 Starting UPnP NAT Manager... Sun 5/13 19:05:01.558 Loading IVTV capture device Sun 5/13 19:05:01.558 createEncoder /dev/video0 Sun 5/13 19:05:01.558 verifying interface compatibility Sun 5/13 19:05:01.558 Opened /dev/video0 with interface 1 Sun 5/13 19:05:01.563 Loaded Sun 5/13 19:05:01.568 IVTV: video0 PVR card type is Hauppauge WinTV PVR-150 Sun 5/13 19:05:01.569 setInput0 0 0 1 1 Sun 5/13 19:05:01.618 new setChannel0 frequency 980 (61250). Sun 5/13 19:05:01.794 Channel change succesful. Sun 5/13 19:05:01.794 updateColors0 b=128 c=128 h=128 s=128 Sun 5/13 19:05:01.813 Scheduler.updateSchedule() called time=Sun 5/13 19:05:01.812 manual=[ManualRecord[A[554879,554833,"Modern Marvels",14771@0127.19:00,60] time=Sat 1/27 19:00:00.000 dur=1:00:00], ManualRecord[A[595036,595019,"Modern Marvels",14771@0131.22:00,60] time=Wed 1/31 22:00:00.000 dur=1:00:00], ManualRecord[A[1433166,1433154,"Modern Marvels",14771@0314.02:00,60] time=Wed 3/14 2:00:00.000 dur=1:00:00], ManualRecord[A[1506161,1506109,"Cops",10153@0325.20:00,30] time=Sun 3/25 20:00:00.000 dur=0:30:00], ManualRecord[A[1593760,1593727,"20/20",11829@0330.21:00,60] time=Fri 3/30 21:00:00.000 dur=1:00:00], ManualRecord[A[1714904,1714365,"Frontline/World",11490@0410.22:00,60] time=Tue 4/10 22:00:00.000 dur=1:00:00], ManualRecord[A[1749910,1179013,"UFC Unleashed",11163@0421.19:00,60] time=Sat 4/21 19:00:00.000 dur=1:00:00], ManualRecord[A[1756309,1756284,"Sherman's March",14771@0422.23:00,120] time=Sun 4/22 23:00:00.000 dur=2:00:00], ManualRecord[A[1933595,1407689,"Weaponology",18284@0506.23:00,60] time=Sun 5/6 23:00:00.000 dur=1:00:00]] schedules={video0=[Sched=[] MustSee=[]]} scheduleRandSize=0 Sun 5/13 19:05:01.818 # Airs=758 Sun 5/13 19:05:01.843 MUST SEE FINAL-video0-[A[2026763,2026350,"60 Minutes",11479@0513.19:00,60], A[2029867,2029659,"Frontline",11768@0513.21:00,90], A[2018440,1179144,"World's Wildest Police Videos",11163@0514.11:00,60], A[2018441,1179144,"World's Wildest Police Videos",11163@0514.13:00,60], A[2019680,2018582,"Law & Order",11164@0514.15:00,60], A[2045150,323594,"The O'Reilly Factor",16374@0514.20:00,60], A[2039649,2039395,"Cities of the Underworld",14771@0514.21:00,60], A[2004431,2003890,"The Daily Show With Jon Stewart",10149@0514.23:00,30], A[2019679,2018580,"Law & Order",11164@0515.15:00,60], A[2045153,323594,"The O'Reilly Factor",16374@0515.20:00,60], A[2030697,1968582,"Boston Legal",11829@0515.22:01,59], A[2039487,2039319,"Modern Marvels",14771@0515.23:00,60], A[2004437,2003891,"The Daily Show With Jon Stewart",10149@0516.01:00,30], A[2039483,2039318,"Modern Marvels",14771@0516.02:00,60], A[2018442,1179144,"World's Wildest Police Videos",11163@0516.11:00,60], A[2018443,1179144,"World's Wildest Police Videos",11163@0516.13:00,60], A[2019676,1396064,"Law & Order",11164@0516.15:00,60], A[2039500,1207722,"Modern Marvels",14771@0516.19:00,60], A[2029868,1968394,"Frontline",11768@0520.21:00,60]] Sun 5/13 19:05:01.847 Evaluating Potentials Sun 5/13 19:05:02.009 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE video0 Sun 5/13 19:05:02.010 [A[2026763,2026350,"60 Minutes",11479@0513.19:00,60], A[2019704,1441038,"Law & Order",11164@0513.20:00,60], A[2029867,2029659,"Frontline",11768@0513.21:00,90], A[1999449,1999055,"Gene Simmons Family Jewels",10035@0513.22:30,30], A[2005144,2004887,"Anatomy of Crime",10153@0513.23:00,60], A[2110671,1246436,"Futureweapons",18284@0514.00:00,60], A[2097812,2033088,"Meet the Press",16300@0514.01:00,60], A[2118871,1425336,"Japan's Atomic Bomb",18822@0514.02:00,60], A[2000802,1430771,"The Real Housewives of Orange County",10057@0514.03:00,60], A[2024843,2024352,"Cops",11269@0514.04:30,30], A[2110806,1826063,"Top Ten Fighters",18284@0514.05:00,60], A[2005619,2005548,"Q & A",10161@0514.06:00,50], A[2106577,2106225,"Eleanor Roosevelt",16834@0514.07:00,60], A[1998543,1997719,"Charade",10021@0514.08:30,150], A[2018440,1179144,"World's Wildest Police Videos",11163@0514.11:00,60], A[2039619,2039384,"Man, Moment, Machine",14771@0514.12:00,60], A[2018441,1179144,"World's Wildest Police Videos",11163@0514.13:00,60], A[2004783,1704412,"The Daily Show With Jon Stewart",10149@0514.14:00,30], A[2010745,1916181,"The Real World",10986@0514.14:30,30], A[2019680,2018582,"Law & Order",11164@0514.15:00,60], A[2118545,1434108,"Voyages",18822@0514.16:00,60], A[2097874,1813169,"Hardball With Chris Matthews",16300@0514.17:00,60], A[2019695,2018593,"Law & Order",11164@0514.18:00,60], A[2097875,1813169,"Hardball With Chris Matthews",16300@0514.19:00,60], A[2045150,323594,"The O'Reilly Factor",16374@0514.20:00,60], A[2039649,2039395,"Cities of the Underworld",14771@0514.21:00,60], A[2033415,1968765,"Law & Order: Criminal Intent",11993@0514.22:01,59], A[2004431,2003890,"The Daily Show With Jon Stewart",10149@0514.23:00,30], A[2118887,2118509,"Blood Diamonds",18822@0515.00:00,120], A[2118847,2118483,"Diamond Mines",18822@0515.02:00,60], A[2000679,2000440,"Taxicab Confessions",10057@0515.03:00,60], A[2000677,2000439,"Taxicab Confessions",10057@0515.04:00,60], A[2016262,2016066,"Assignment Discovery",11150@0515.05:01,59], A[2103181,2102822,"Secrets of the Humpback Whale",16616@0515.06:00,60], A[2090901,2090720,"36 Hours to Kill",14988@0515.07:30,90], A[2118778,2118469,"Philosophy",18822@0515.09:00,60], A[2000809,2000519,"Work Out",10057@0515.10:00,60], A[2005477,2005011,"Jami Floyd: Best Defense",10153@0515.11:00,120], A[2000812,1343155,"Work Out",10057@0515.13:00,60], A[2019668,2018570,"Law & Order",11164@0515.14:00,60], A[2019679,2018580,"Law & Order",11164@0515.15:00,60], A[2138813,1915160,"Seconds From Disaster",24959@0515.16:00,60], A[2097877,1813169,"Hardball With Chris Matthews",16300@0515.17:00,60], A[2010879,2010615,"Parental Control",10986@0515.18:30,30], A[2005038,2004823,"Cops",10153@0515.19:00,30], A[2005067,2004842,"Cops",10153@0515.19:30,30], A[2045153,323594,"The O'Reilly Factor",16374@0515.20:00,60], A[2110673,1176096,"Futureweapons",18284@0515.21:00,60], A[2030697,1968582,"Boston Legal",11829@0515.22:01,59], A[2039487,2039319,"Modern Marvels",14771@0515.23:00,60], A[2005034,2004820,"Cops",10153@0516.00:00,30], A[2010739,2010546,"The Real World",10986@0516.00:30,30], A[2004437,2003891,"The Daily Show With Jon Stewart",10149@0516.01:00,30], A[2005027,2004815,"Cops",10153@0516.01:30,30], A[2039483,2039318,"Modern Marvels",14771@0516.02:00,60], A[2097879,1813169,"Hardball With Chris Matthews",16300@0516.03:00,60], A[2045155,323594,"The O'Reilly Factor",16374@0516.04:00,60], A[2016263,2016067,"Assignment Discovery",11150@0516.05:01,59], A[2103069,2102747,"Life of Mammals",16616@0516.06:00,60], A[2038100,2037783,"The Finger Points",12852@0516.07:00,90], A[2000836,2000525,"Work Out",10057@0516.09:00,60], A[2110783,2110422,"Lam Son 719",18284@0516.10:00,60], A[2018442,1179144,"World's Wildest Police Videos",11163@0516.11:00,60], A[2017150,2016800,"What Not to Wear",11158@0516.12:00,60], A[2018443,1179144,"World's Wildest Police Videos",11163@0516.13:00,60], A[2017116,2016766,"A Baby Story",11158@0516.14:00,30], A[2017296,2016931,"Bringing Home Baby",11158@0516.14:30,30], A[2019676,1396064,"Law & Order",11164@0516.15:00,60], A[2000734,2000482,"Project Runway",10057@0516.16:00,60], A[2097880,1813169,"Hardball With Chris Matthews",16300@0516.17:00,60], A[2108462,2108184,"Cops",18179@0516.18:00,30], A[2010848,2010599,"Run's House",10986@0516.18:30,30], A[2039500,1207722,"Modern Marvels",14771@0516.19:00,60], A[2029868,1968394,"Frontline",11768@0520.21:00,60]] Sun 5/13 19:05:02.011 Seeker awoken Sun 5/13 19:05:02.011 Seeker adding new Encoder: video0 Sun 5/13 19:05:02.012 MARK 1 currRecord=null enc=video0 clients=[] ir=true Sun 5/13 19:05:02.013 defaultRecord=A[2026763,2026350,"60 Minutes",11479@0513.19:00,60] Sun 5/13 19:05:02.013 Seeker in AUTOMATIC mode nextRecord=A[2026763,2026350,"60 Minutes",11479@0513.19:00,60] nextTTA=0 Sun 5/13 19:05:02.013 newRecord=A[2026763,2026350,"60 Minutes",11479@0513.19:00,60] Sun 5/13 19:05:02.013 Change in record to another show. Entering device record mode. - LATER Sun 5/13 19:05:02.013 Change in record to another show. Entering device record mode. - NOW Sun 5/13 19:05:02.017 Seeker.startRecord(video0 A[2026763,2026350,"60 Minutes",11479@0513.19:00,60], currTime=Sun 5/13 19:05:02.012) currRecord=null switch=false Sun 5/13 19:05:02.017 Setting up MMC video for recording new show & tuning channel conn=video0 TV Tuner Channel 3 Sun 5/13 19:05:02.017 Using quality setting "MPEG2 Max Quality" for recording Sun 5/13 19:05:02.018 MediaFile startSegment enc=video0 MPEG2 Max Quality MediaFile[id=2180188 A[2026763,2026350,"60 Minutes",11479@0513.19:00,60] host=videodrome.vo3lk3r.com encodedBy=video0 MPEG2 Max Quality format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 12000 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] /var/media/tv/60Minutes-2026763-0.mpg, Seg0[Sun 5/13 19:00:51.131-Sun 5/13 19:01:01.296]] Sun 5/13 19:05:02.042 MediaFile created subfile:/var/media/tv/60Minutes-2026763-1.mpg MediaFile[id=2180188 A[2026763,2026350,"60 Minutes",11479@0513.19:00,60] host=videodrome.vo3lk3r.com encodedBy=video0 MPEG2 Max Quality format=MPEG2-PS 0:00:00 0 kbps [#0 Video[MPEG2-Video 29.97 fps 720x480 4:3 12000 kbps interlaced]#1 Audio[MP2 48000 Hz 2 channels 384 kbps]] /var/media/tv/60Minutes-2026763-0.mpg, Seg0[Sun 5/13 19:00:51.131-Sun 5/13 19:01:01.296]] Sun 5/13 19:05:02.052 Seeker is starting the library import scan... Sun 5/13 19:05:02.092 Seeker channel string=9 Sun 5/13 19:05:02.092 startEncoding for IVTV capture device file=/var/media/tv/60Minutes-2026763-1.mpg chan=9 Sun 5/13 19:05:02.093 IVTV: video0 PVR card type is Hauppauge WinTV PVR-150 Sun 5/13 19:05:02.093 setInput0 0 0 1 1 Sun 5/13 19:05:02.147 Skipping video import dir that's also a recording dir:/var/media/tv Sun 5/13 19:05:02.149 Seeker has finished the library import scan. Sun 5/13 19:05:02.220 new setChannel0 frequency 980 (61250). Sun 5/13 19:05:02.342 Channel change succesful. Sun 5/13 19:05:02.342 updateColors0 b=128 c=128 h=128 s=128 Sun 5/13 19:05:02.348 new setChannel0 frequency 980 (61250). Sun 5/13 19:05:02.402 Server got broadcast packet: java.net.DatagramPacket@5de82a Sun 5/13 19:05:02.410 Server sent back mini discovery data:java.net.DatagramPacket@5de82a to /10.32.32.7 3072 Sun 5/13 19:05:02.424 MiniUI got connection from Socket[addr=/10.32.32.7,port=3995,localport=31099] Sun 5/13 19:05:02.428 MiniUI is adding to its map:000dfe0c616a Sun 5/13 19:05:02.428 Creating new UI for client:000dfe0c616a Sun 5/13 19:05:02.429 Creating-2 new UI for client:000dfe0c616a UIManager:localhost@@000dfe0c616a-13552ed Sun 5/13 19:05:02.460 MiniUI established for 000dfe0c616a Sun 5/13 19:05:02.470 Channel change succesful. Sun 5/13 19:05:02.557 SUCCESSFULLY opened IRTuner on port 3 Sun 5/13 19:05:02.558 setEncodingProperties0 MPEG2 Max Quality Sun 5/13 19:05:02.558 Set encoding property audiooutputmode to 0 Sun 5/13 19:05:02.558 Set encoding property audiocrc to 0 Sun 5/13 19:05:02.558 Set encoding property gopsize to 15 Sun 5/13 19:05:02.558 Set encoding property videobitrate to 12000000 Sun 5/13 19:05:02.558 Set encoding property inversetelecine to 0 Sun 5/13 19:05:02.558 Set encoding property closedgop to 0 Sun 5/13 19:05:02.558 Set encoding property vbr to 0 Sun 5/13 19:05:02.558 Set encoding property outputstreamtype to 0 Sun 5/13 19:05:02.558 Set encoding property width to 720 Sun 5/13 19:05:02.558 Set encoding property height to 480 Sun 5/13 19:05:02.559 Set encoding property audiobitrate to 384 Sun 5/13 19:05:02.559 Set encoding property audiosampling to 48000 Sun 5/13 19:05:02.559 Set encoding property disablefilter to 1 Sun 5/13 19:05:02.559 Set encoding property medianfilter to 3 Sun 5/13 19:05:02.559 Set encoding property fps to 30 Sun 5/13 19:05:02.559 Set encoding property ipb to 0 Sun 5/13 19:05:02.559 Set encoding property deinterlace to 0 Sun 5/13 19:05:02.559 Set encoding property aspectratio to 1 Sun 5/13 19:05:02.559 setEncoding0NewInterface Sun 5/13 19:05:02.690 setup encoding /var/media/tv/60Minutes-2026763-1.mpg Sun 5/13 19:05:02.691 Playing IR tune command of 9 Sun 5/13 19:05:02.730 Starting IVTV capture thread Sun 5/13 19:05:02.730 RootFile=/ fstype=0xef53 fileLength=0 Sun 5/13 19:05:02.731 Seeker waiting for -19651625 mins. Sun 5/13 19:05:02.740 Seeker awoken Sun 5/13 19:05:02.740 MARK 1 currRecord=A[2026763,2026350,"60 Minutes",11479@0513.19:00,60] enc=video0 clients=[] ir=true Sun 5/13 19:05:02.740 Seeker in AUTOMATIC mode nextRecord=A[2019704,1441038,"Law & Order",11164@0513.20:00,60] nextTTA=3297260 Sun 5/13 19:05:02.740 newRecord=A[2026763,2026350,"60 Minutes",11479@0513.19:00,60] Sun 5/13 19:05:02.741 Keeping record just the way it is. Sun 5/13 19:05:02.741 RootFile=/ fstype=0xef53 fileLength=0 Sun 5/13 19:05:02.741 Checking video directories for new files Sun 5/13 19:05:02.781 Removing zero length file from video dir:/var/media/tv/82ndAirborneDivision-2110780-0.mpg Sun 5/13 19:05:02.781 Removing zero length file from video dir:/var/media/tv/Cops-BizarreCallsSpecialEdition-1506163-0.mpg Sun 5/13 19:05:02.782 Diskspace checking is running Sun 5/13 19:05:02.782 Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Sun 5/13 19:05:02.791 Enforcing keep at most limits for the files... Sun 5/13 19:05:02.829 needFreeSize = 1.4625 GB currFreeSize=2.08144384 GB Sun 5/13 19:05:02.829 Seeker waiting for 49 mins. Sun 5/13 19:05:02.862 Loading startup runnable:net.sf.sageplugins.webserver.StartServer Sun 5/13 19:05:02.864 StorageDeviceDetector started... Sun 5/13 19:05:02.867 Setup dir for external device mount: /var/media/external/sdb1 Sun 5/13 19:05:02.867 Executing process: mount /dev/sdb1 /var/media/external/sdb1 Sun 5/13 19:05:02.867 Loaded startup runnable:net.sf.sageplugins.webserver.StartServer Sun 5/13 19:05:02.875 Starting webserver with args: [-p, 8080, -root, webserver, -t, throttles.properties, -s, servlets.properties, -r, realms.properties, -a, aliases.properties, -nohup] Sun 5/13 19:05:02.914 MiniClient GFX_TEXTMODE=null Sun 5/13 19:05:02.915 MiniClient GFX_BLENDMODE=null Sun 5/13 19:05:02.916 MiniClient GFX_DRAWMODE=null Sun 5/13 19:05:02.916 MiniClient GFX_BITMAP_FORMAT=null Sun 5/13 19:05:02.917 MiniClient GFX_SCALING=null Sun 5/13 19:05:02.918 MiniClient GFX_OFFLINE_IMAGE_CACHE=null Sun 5/13 19:05:02.918 MiniClient GFX_SUPPORTED_ASPECTS=null Sun 5/13 19:05:02.919 MiniClient GFX_ASPECT=null Sun 5/13 19:05:02.920 MiniClient GFX_SUPPORTED_RESOLUTIONS=null Sun 5/13 19:05:02.920 MiniClient GFX_RESOLUTION=null Sun 5/13 19:05:02.921 MiniClient GFX_COMPOSITE=null Sun 5/13 19:05:02.921 MiniClient GFX_COLORKEY=null Sun 5/13 19:05:02.922 MiniClient INPUT_DEVICES=null Sun 5/13 19:05:02.923 MiniClient DISPLAY_OVERSCAN=null Sun 5/13 19:05:02.924 MiniClient VIDEO_CODECS=null Sun 5/13 19:05:02.924 MiniClient AUDIO_CODECS=null Sun 5/13 19:05:02.925 MiniClient PULL_AV_CONTAINERS=null Sun 5/13 19:05:02.926 MiniClient PUSH_AV_CONTAINERS=null Sun 5/13 19:05:02.926 MiniClient FIXED_PUSH_MEDIA_FORMAT=null Sun 5/13 19:05:02.927 MiniClient DETAILED_BUFFER_STATS=null Sun 5/13 19:05:02.927 MiniClient PUSH_BUFFER_SEEKING=null Sun 5/13 19:05:02.928 MiniClient MEDIA_PLAYER_BUFFER_DELAY=null Sun 5/13 19:05:02.929 MiniClient IR_PROTOCOL=null Sun 5/13 19:05:02.929 MiniClient CRYPTO_ALGORITHMS=null Sun 5/13 19:05:02.957 Creating-3 new UI for client:000dfe0c616a UIManager:localhost@@000dfe0c616a-13552ed Sun 5/13 19:05:02.962 UIMgr loading UI from: /opt/sagetv6.1/STVs/SageTV3/SageTV3.xml Sun 5/13 19:05:03.024 VF thread is now running... Sun 5/13 19:05:03.024 VF thread is now waiting for 0:00:00.000 Sun 5/13 19:05:03.208 Setup dir for external device mount: /var/media/external/sda1 Sun 5/13 19:05:03.208 Executing process: mount /dev/sda1 /var/media/external/sda1 Sun 5/13 19:05:03.272 Seeker is starting the library import scan... Sun 5/13 19:05:04.100 Skipping video import dir that's also a recording dir:/var/media/tv Sun 5/13 19:05:04.103 Seeker has finished the library import scan. Sun 5/13 19:05:04.201 WARNING - MissingResource: "Graphic_Violence" Sun 5/13 19:05:04.201 WARNING - MissingResource: "Violence" Sun 5/13 19:05:04.201 WARNING - MissingResource: "Mild_Violence" Sun 5/13 19:05:04.201 WARNING - MissingResource: "Graphic_Language" Sun 5/13 19:05:04.201 WARNING - MissingResource: "Language" Sun 5/13 19:05:04.201 WARNING - MissingResource: "Adult_Situations" Sun 5/13 19:05:04.201 WARNING - MissingResource: "Strong_Sexual_Content" Sun 5/13 19:05:04.202 WARNING - MissingResource: "Nudity" Sun 5/13 19:05:04.202 WARNING - MissingResource: "Brief Nudity" Sun 5/13 19:05:04.202 WARNING - MissingResource: "Rape" Sun 5/13 19:05:04.202 WARNING - MissingResource: "NR" Sun 5/13 19:05:04.202 WARNING - MissingResource: "AO" Sun 5/13 19:05:04.202 WARNING - MissingResource: "NC-17" Sun 5/13 19:05:04.202 WARNING - MissingResource: "R" Sun 5/13 19:05:04.202 WARNING - MissingResource: "PG-13" Sun 5/13 19:05:04.203 WARNING - MissingResource: "PG" Sun 5/13 19:05:04.203 WARNING - MissingResource: "G" Sun 5/13 19:05:04.437 locale = en Sun 5/13 19:05:04.618 UIMgr done loading UI from: /opt/sagetv6.1/STVs/SageTV3/SageTV3.xml Sun 5/13 19:05:04.665 EVPoolSize=1 Sun 5/13 19:05:04.698 Hidden Extras are DISABLED Sun 5/13 19:05:04.731 Acme httpd videodrome.vo3lk3r.com:8080 listening. Sun 5/13 19:05:04.859 DatePat=EEE, MMM d orgPat=EEEE, MMMM d, yyyy Sun 5/13 19:05:04.874 setUI(sage.b2@1a8f100[Main Menu]) histIdx=0 uiHistory=[sage.b2@1a8f100[Main Menu]] Sun 5/13 19:05:04.929 Creating-4 new UI for client:000dfe0c616a UIManager:localhost@@000dfe0c616a-13552ed Sun 5/13 19:05:04.929 MiniUI got connection from Socket[addr=/10.32.32.7,port=3996,localport=31099] Sun 5/13 19:05:04.929 MiniPlayer is adding to its map:000dfe0c616a Sun 5/13 19:05:05.039 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=16] Sun 5/13 19:05:05.240 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=16] numGlyphs=1666 numCachedGlyphs=247 numImages=4 Sun 5/13 19:05:05.253 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=23] Sun 5/13 19:05:05.550 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=23] numGlyphs=1666 numCachedGlyphs=210 numImages=7 Sun 5/13 19:05:05.551 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=plain,size=25] Sun 5/13 19:05:05.957 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=plain,size=25] numGlyphs=2929 numCachedGlyphs=256 numImages=9 Sun 5/13 19:05:05.964 Loading 720x480 image from file /opt/sagetv6.1/STVs/SageTV3/Background.jpg Sun 5/13 19:05:06.192 Ministry is starting Sun 5/13 19:05:06.193 Ministry is waiting for 180 sec Sun 5/13 19:05:06.431 Loading 67x21 image from file /opt/sagetv6.1/STVs/SageTV3/SageLogo256small.png Sun 5/13 19:05:06.506 Loading 29x16 image from file /opt/sagetv6.1/STVs/SageTV3/RecordingNow.png Sun 5/13 19:05:06.587 Loading 585x40 image from file /opt/sagetv6.1/STVs/SageTV3/MenuBarLong.png Sun 5/13 19:05:06.609 Found next seq hdr code! Sun 5/13 19:05:09.380 Server got broadcast packet: java.net.DatagramPacket@d39f3c Sun 5/13 19:05:09.388 Server sent back mini discovery data:java.net.DatagramPacket@d39f3c to /10.32.32.52 49496 Sun 5/13 19:05:19.382 Server got broadcast packet: java.net.DatagramPacket@17b51e8 Sun 5/13 19:05:19.391 Server sent back mini discovery data:java.net.DatagramPacket@17b51e8 to /10.32.32.52 49497 Sun 5/13 19:05:29.385 Server got broadcast packet: java.net.DatagramPacket@13e6577 Sun 5/13 19:05:29.392 Server sent back mini discovery data:java.net.DatagramPacket@13e6577 to /10.32.32.52 49498 Sun 5/13 19:05:39.386 Server got broadcast packet: java.net.DatagramPacket@1776d65 Sun 5/13 19:05:39.395 Server sent back mini discovery data:java.net.DatagramPacket@1776d65 to /10.32.32.52 49499 Sun 5/13 19:05:49.387 Server got broadcast packet: java.net.DatagramPacket@1af1934 Sun 5/13 19:05:49.396 Server sent back mini discovery data:java.net.DatagramPacket@1af1934 to /10.32.32.52 49500 Sun 5/13 19:05:58.494 Device cleanup, unmounting drives... Sun 5/13 19:05:58.494 SageTV SHUTDOWN is activating! Sun 5/13 19:05:58.494 Sage.exit() called. Sun 5/13 19:05:58.494 Cleaning up servers Sun 5/13 19:05:58.495 Error w/SageTV client connection:java.net.SocketException: Socket closed Sun 5/13 19:05:58.496 MiniError-2:java.net.SocketException: Socket closed Sun 5/13 19:05:58.496 MiniError-3:java.net.SocketException: Socket closed Sun 5/13 19:05:58.496 MiniError-1:java.net.SocketException: Socket closed Sun 5/13 19:05:58.496 MiniUI ServerSocket died from:java.net.SocketException: Socket closed Sun 5/13 19:05:58.496 Cleaning up PM Sun 5/13 19:05:58.497 Killed Carny. Sun 5/13 19:05:58.497 Killed Scheduler. Sun 5/13 19:05:58.497 Error calling finishWatch from VF goodbye:java.lang.NullPointerException Sun 5/13 19:05:58.497 Ministry is shutting down....destroying the converts in progress Sun 5/13 19:05:58.497 Seeker.endRecord(Sun 5/13 19:05:58.497) currRecord=A[2026763,2026350,"60 Minutes",11479@0513.19:00,60] Sun 5/13 19:05:58.498 VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/60Minutes-2026763-1.mpg]) Sun 5/13 19:05:58.499 stopEncoding for IVTV capture device Sun 5/13 19:05:58.499 Waiting for IVTV capture thread to terminate Sun 5/13 19:05:58.499 VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/60Minutes-2026763-1.mpg] nPlayin=false Sun 5/13 19:05:58.499 VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/60Minutes-2026763-1.mpg] Sun 5/13 19:05:58.499 VF processing job null nPlayin=false Sun 5/13 19:05:58.499 VF thread is now waiting for 0:00:00.000 Sun 5/13 19:05:58.499 Executing process: umount /var/media/external/sda1/ Sun 5/13 19:05:58.511 Executing process: umount /var/media/external/sdb1/ Sun 5/13 19:05:58.514 IVTV capture thread terminating Sun 5/13 19:05:58.529 Killed Seeker. Sun 5/13 19:05:58.529 Saving properties file to Sage.properties Sun 5/13 19:05:58.537 Killed EPG. Sun 5/13 19:05:58.537 Freeing ITVTV capture device Sun 5/13 19:05:58.548 stopEncoding for IVTV capture device Sun 5/13 19:05:58.548 Killed MMC. Sun 5/13 19:05:58.548 Killing UIMgr UIManager:localhost@@000dfe0c616a-13552ed Sun 5/13 19:05:58.548 Killed UI Timers Sun 5/13 19:05:58.548 Seeker.finishWatch(UIManager:localhost@@000dfe0c616a-13552ed) Sun 5/13 19:05:58.548 VF processing job null nPlayin=false Sun 5/13 19:05:58.548 VideoFrame thread is now exiting Sun 5/13 19:05:58.548 Killed VideoFrame Sun 5/13 19:05:58.556 Killed EventRouter Sun 5/13 19:05:58.738 Exception in the MiniUIClientReceiver of: java.net.SocketException: Socket closed java.net.SocketException: Socket closed Sun 5/13 19:05:58.739 at java.net.SocketInputStream.read(Unknown Source) Sun 5/13 19:05:58.739 at java.net.SocketInputStream.read(Unknown Source) Sun 5/13 19:05:58.739 at java.io.FilterInputStream.read(Unknown Source) Sun 5/13 19:05:58.740 at sage.bl$a.run(Unknown Source) Sun 5/13 19:06:00.562 Killed RootPanel Sun 5/13 19:06:00.565 Saving properties file to clients/000dfe0c616a.properties Sun 5/13 19:06:00.594 Disposed Window Sun 5/13 19:06:00.594 Killing UIMgr UIManager:localhost@@SAGETV_PROCESS_LOCAL_UI-e39a3e Sun 5/13 19:06:00.594 Error calling finishWatch from VF goodbye:java.lang.NullPointerException Sun 5/13 19:06:00.594 Killed VideoFrame Sun 5/13 19:06:00.594 Disposed Window Sun 5/13 19:06:00.594 Killed UIManager. Sun 5/13 19:06:00.594 Killed Wizard. Sun 5/13 19:06:00.594 Saving properties file to Sage.properties Sun 5/13 19:06:00.632 Bye-bye.