Fri 12/29 8:21:33.662 Changed default timezone to:Central Standard Time Fri 12/29 8:21:33.738 user.dir2=/opt/sagetv/server Fri 12/29 8:21:33.739 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_getrssnews.jar:JARs/nielm_sageimdb.jar:JARs/nielm_sagemenureader.jar:JARs/nielm_sageutls.jar:JARs/nielm_sagewebserver.jar:JARs/nielm_sagexmlinfo.jar:JARs/sagemc.jar:JARs/sbbi-jmx-1.0.jar:JARs/sbbi-upnplib-1.0.3.jar:JARs/servlet.jar Fri 12/29 8:21:33.739 JVM version=1.5.0_06 Fri 12/29 8:21:33.739 OS=Linux 2.6.17.6 Fri 12/29 8:21:33.752 SageTV V6.0.19.1 Fri 12/29 8:21:33.857 hostname=sagetv Fri 12/29 8:21:35.269 WARNING - MissingResource: "Judge" Fri 12/29 8:21:35.270 WARNING - MissingResource: "Narrator" Fri 12/29 8:21:35.319 Wizard starting to load database info... Fri 12/29 8:21:35.330 DBFile at version 64 FileSize=16304056 Fri 12/29 8:21:35.330 Wizard allocating table for Year of size 92 Fri 12/29 8:21:35.332 Wizard loading main index for Year Fri 12/29 8:21:35.334 Wizard allocating table for Network of size 33 Fri 12/29 8:21:35.334 Wizard loading main index for Network Fri 12/29 8:21:35.336 Wizard allocating table for Title of size 9829 Fri 12/29 8:21:35.341 Wizard loading main index for Title Fri 12/29 8:21:35.512 Wizard building alt. index 0 for Title Fri 12/29 8:21:35.533 Wizard allocating table for Channel of size 66 Fri 12/29 8:21:35.534 Wizard loading main index for Channel Fri 12/29 8:21:35.535 Wizard allocating table for People of size 24095 Fri 12/29 8:21:35.536 Wizard loading main index for People Fri 12/29 8:21:35.603 Sage waiting for GC to free up some memory usage%=0.963534 Fri 12/29 8:21:35.747 Sage done waiting for GC to free up some memory usage%=0.558447 Fri 12/29 8:21:35.751 Wizard building alt. index 0 for People Fri 12/29 8:21:35.804 Wizard allocating table for SubCategory of size 113 Fri 12/29 8:21:35.804 Wizard loading main index for SubCategory Fri 12/29 8:21:35.804 Wizard allocating table for Rated of size 5 Fri 12/29 8:21:35.804 Wizard loading main index for Rated Fri 12/29 8:21:35.805 Wizard allocating table for ParentalRating of size 0 Fri 12/29 8:21:35.805 Wizard allocating table for ExtendedRating of size 10 Fri 12/29 8:21:35.805 Wizard loading main index for ExtendedRating Fri 12/29 8:21:35.805 Wizard allocating table for Category of size 161 Fri 12/29 8:21:35.805 Wizard loading main index for Category Fri 12/29 8:21:35.806 Wizard allocating table for PrimeTitle of size 0 Fri 12/29 8:21:35.806 Wizard allocating table for Bonus of size 227 Fri 12/29 8:21:35.806 Wizard loading main index for Bonus Fri 12/29 8:21:35.806 Wizard allocating table for Show of size 35165 Fri 12/29 8:21:35.812 Wizard loading main index for Show Fri 12/29 8:21:36.896 Wizard building alt. index 0 for Show Fri 12/29 8:21:36.995 Wizard allocating table for Airing of size 58892 Fri 12/29 8:21:36.997 Wizard loading main index for Airing Fri 12/29 8:21:37.148 Wizard building alt. index 0 for Airing Fri 12/29 8:21:37.204 Wizard building alt. index 1 for Airing Fri 12/29 8:21:37.269 Wizard allocating table for ManualRecord of size 4 Fri 12/29 8:21:37.269 Wizard loading main index for ManualRecord Fri 12/29 8:21:37.335 Wizard allocating table for MediaFile of size 13909 Fri 12/29 8:21:37.336 Wizard loading main index for MediaFile Fri 12/29 8:21:39.642 Wizard building alt. index 0 for MediaFile Fri 12/29 8:21:39.652 Wizard building alt. index 1 for MediaFile Fri 12/29 8:21:39.707 Wizard allocating table for Watched of size 845 Fri 12/29 8:21:39.708 Wizard loading main index for Watched Fri 12/29 8:21:39.711 Wizard building alt. index 0 for Watched Fri 12/29 8:21:39.713 Wizard allocating table for Agent of size 4249 Fri 12/29 8:21:39.713 Wizard loading main index for Agent Fri 12/29 8:21:39.759 Wizard building alt. index 0 for Agent Fri 12/29 8:21:39.768 Wizard allocating table for Wasted of size 11321 Fri 12/29 8:21:39.768 Wizard loading main index for Wasted Fri 12/29 8:21:39.776 Wizard allocating table for Playlist of size 284 Fri 12/29 8:21:39.776 Wizard loading main index for Playlist Fri 12/29 8:21:39.854 Wizard allocating table for TVEditorial of size 0 Fri 12/29 8:21:39.855 Wizard allocating table for SeriesInfo of size 0 Fri 12/29 8:21:39.855 Wizard processing transactional records... Fri 12/29 8:21:39.865 Wizard performing validation on database objects... Fri 12/29 8:21:39.970 Wizard DONE loading database info. loadTime=4.651 sec Fri 12/29 8:21:40.709 MMC calling detectCaptureDevices on sage.bz@949f69 Fri 12/29 8:21:40.710 devices detected=[] Fri 12/29 8:21:40.711 EncoderMap={} Fri 12/29 8:21:40.711 MMC calling detectCaptureDevices on sage.v@1b8d6f7 Fri 12/29 8:21:40.713 videoDevices=[video0, video1, video2, video3] Fri 12/29 8:21:40.713 Checking encoder key:-816678122 Fri 12/29 8:21:40.729 Created IVTVCaptureDevice object for:video1 Fri 12/29 8:21:40.729 Checking encoder key:-816678120 Fri 12/29 8:21:40.731 Created IVTVCaptureDevice object for:video3 Fri 12/29 8:21:40.731 Checking encoder key:-816678121 Fri 12/29 8:21:40.732 Created IVTVCaptureDevice object for:video2 Fri 12/29 8:21:40.733 Checking encoder key:-816678123 Fri 12/29 8:21:40.734 Created IVTVCaptureDevice object for:video0 Fri 12/29 8:21:40.734 EncoderMap1={video1=video1, video3=video3, video2=video2, video0=video0} Fri 12/29 8:21:40.735 Saving properties file to Sage.properties Fri 12/29 8:21:40.761 Saving properties file to Sage.properties Fri 12/29 8:21:40.770 Saving properties file to Sage.properties Fri 12/29 8:21:40.792 Saving properties file to Sage.properties Fri 12/29 8:21:40.799 Processing new system dev:video0 Fri 12/29 8:21:40.799 Device already has been processed Fri 12/29 8:21:40.799 Processing new system dev:video1 Fri 12/29 8:21:40.799 Device already has been processed Fri 12/29 8:21:40.799 Processing new system dev:video2 Fri 12/29 8:21:40.799 Device already has been processed Fri 12/29 8:21:40.799 Processing new system dev:video3 Fri 12/29 8:21:40.800 Device already has been processed Fri 12/29 8:21:40.800 devices detected=[video1, video3, video2, video0] Fri 12/29 8:21:40.800 EncoderMap={video2=video2, video0=video0, video1=video1, video3=video3} Fri 12/29 8:21:40.800 MMC calling detectCaptureDevices on sage.a2@82764b Fri 12/29 8:21:40.800 WARNING - MissingResource: "Linux_DVB_Capture_Manager" Fri 12/29 8:21:40.800 videoDevices=[] Fri 12/29 8:21:40.800 detect 3 Fri 12/29 8:21:40.801 Checking encoder key:-816678122 Fri 12/29 8:21:40.801 Device is already accounted for. Fri 12/29 8:21:40.801 Checking encoder key:-816678120 Fri 12/29 8:21:40.801 Device is already accounted for. Fri 12/29 8:21:40.802 Checking encoder key:-816678121 Fri 12/29 8:21:40.802 Device is already accounted for. Fri 12/29 8:21:40.802 Checking encoder key:-816678123 Fri 12/29 8:21:40.802 Device is already accounted for. Fri 12/29 8:21:40.802 EncoderMap1={} Fri 12/29 8:21:40.802 devices detected=[] Fri 12/29 8:21:40.802 EncoderMap={video2=video2, video0=video0, video1=video1, video3=video3} Fri 12/29 8:21:40.846 Checking video directories for new files Fri 12/29 8:21:41.104 CARNY Processing 4253 Agents & 5027 Airs Fri 12/29 8:22:07.829 CARNY Negative Energy Size: 12879 Fri 12/29 8:22:07.840 CARNY Traitors:[] Fri 12/29 8:22:07.843 Verifying existence of all TV media files in database fixDurs=true avoidArchive=false Fri 12/29 8:22:07.872 sage.aq@5821d0 needs an update in 7:30:08 Fri 12/29 8:22:07.873 EPG needs an update in 450 minutes Fri 12/29 8:22:07.873 EPG's works is done. Waiting... Fri 12/29 8:22:07.876 Carny waiting for awhile... Fri 12/29 8:22:07.891 SageTVServer was instantiated. Fri 12/29 8:22:07.898 Trying to find lan network interface Fri 12/29 8:22:07.900 addr: /10.0.0.10 Fri 12/29 8:22:07.900 Miniserver running on 10.0.0.10 Fri 12/29 8:22:07.901 Parsed 0 clients Fri 12/29 8:22:07.903 SageTVDiscoveryServer was instantiated. Fri 12/29 8:22:07.904 SageTVMiniDiscoveryServer was instantiated. Fri 12/29 8:22:07.915 0.0.0.0/0.0.0.0 16867 Fri 12/29 8:22:07.947 0.0.0.0/0.0.0.0 16869 Fri 12/29 8:22:07.950 Loading locator keys from filesystem since they already exist. Fri 12/29 8:22:07.951 0.0.0.0/0.0.0.0 16881 Fri 12/29 8:22:07.971 Starting UPnP NAT Manager... Fri 12/29 8:22:08.009 Loading IVTV capture device Fri 12/29 8:22:08.033 Loaded Fri 12/29 8:22:08.041 IVTV: video2 PVR card type is Hauppauge WinTV PVR-150 Fri 12/29 8:22:08.076 Loading IVTV capture device Fri 12/29 8:22:08.076 IVTV: video3 PVR card type is Hauppauge WinTV PVR-150 Fri 12/29 8:22:08.110 Loading IVTV capture device Fri 12/29 8:22:08.110 IVTV: video1 PVR card type is Hauppauge WinTV PVR-150 Fri 12/29 8:22:08.143 Loading IVTV capture device Fri 12/29 8:22:08.143 IVTV: video0 PVR card type is Hauppauge WinTV PVR-150 Fri 12/29 8:22:08.193 Scheduler.updateSchedule() called time=Fri 12/29 8:22:08.192 manual=[ManualRecord[A[4041419,4037735,"Lost Worlds",14771@0904.20:00,60] time=Mon 9/4 20:00:00.000 dur=1:00:00], ManualRecord[A[4326709,4322733,"South Park",10149@1116.21:00,30] time=Thu 11/16 20:56:00.000 dur=0:38:00], ManualRecord[A[4443302,4443281,"The Simpsons",11658@1217.19:00,30] time=Sun 12/17 19:00:00.000 dur=0:30:00], ManualRecord[A[4443310,4443289,"Family Guy",11658@1217.20:00,30] time=Sun 12/17 20:00:00.000 dur=0:30:00]] schedules={video2=[Sched=[] MustSee=[]], video0=[Sched=[] MustSee=[]], video1=[Sched=[] MustSee=[]], video3=[Sched=[] MustSee=[]]} scheduleRandSize=0 Fri 12/29 8:22:08.690 Server got broadcast packet: java.net.DatagramPacket@1d56e03 Fri 12/29 8:22:08.708 Server sent back mini discovery data:java.net.DatagramPacket@1d56e03to /10.0.0.90 3072 Fri 12/29 8:22:08.708 Server got broadcast packet: java.net.DatagramPacket@129c051 Fri 12/29 8:22:08.717 MiniUI got connection from Socket[addr=/10.0.0.90,port=4347,localport=31099] Fri 12/29 8:22:08.725 MiniUI is adding to its map:000dfe000143 Fri 12/29 8:22:08.725 Creating new UI for client:000dfe000143 Fri 12/29 8:22:08.727 Server sent back mini discovery data:java.net.DatagramPacket@129c051to /10.0.0.91 3072 Fri 12/29 8:22:08.729 Saving properties file to clients/000dfe000143.properties Fri 12/29 8:22:08.734 Creating-2 new UI for client:000dfe000143 UIManager:localhost@@000dfe000143-fb2d90 Fri 12/29 8:22:08.784 MiniUI established for 000dfe000143 Fri 12/29 8:22:09.238 MiniClient GFX_TEXTMODE=null Fri 12/29 8:22:09.239 MiniClient GFX_BLENDMODE=null Fri 12/29 8:22:09.240 MiniClient GFX_DRAWMODE=null Fri 12/29 8:22:09.241 MiniClient GFX_BITMAP_FORMAT=null Fri 12/29 8:22:09.241 MiniClient GFX_SCALING=null Fri 12/29 8:22:09.242 MiniClient GFX_OFFLINE_IMAGE_CACHE=null Fri 12/29 8:22:09.243 MiniClient GFX_SUPPORTED_ASPECTS=null Fri 12/29 8:22:09.244 MiniClient GFX_ASPECT=null Fri 12/29 8:22:09.245 MiniClient GFX_SUPPORTED_RESOLUTIONS=null Fri 12/29 8:22:09.246 MiniClient GFX_RESOLUTION=null Fri 12/29 8:22:09.247 MiniClient GFX_COMPOSITE=null Fri 12/29 8:22:09.248 MiniClient GFX_COLORKEY=null Fri 12/29 8:22:09.248 MiniClient INPUT_DEVICES=null Fri 12/29 8:22:09.249 MiniClient DISPLAY_OVERSCAN=null Fri 12/29 8:22:09.250 MiniClient VIDEO_CODECS=null Fri 12/29 8:22:09.251 MiniClient AUDIO_CODECS=null Fri 12/29 8:22:09.252 MiniClient PULL_AV_CONTAINERS=null Fri 12/29 8:22:09.253 MiniClient PUSH_AV_CONTAINERS=null Fri 12/29 8:22:09.254 MiniClient FIXED_PUSH_MEDIA_FORMAT=null Fri 12/29 8:22:09.255 MiniClient DETAILED_BUFFER_STATS=null Fri 12/29 8:22:09.255 MiniClient PUSH_BUFFER_SEEKING=null Fri 12/29 8:22:09.256 MiniClient MEDIA_PLAYER_BUFFER_DELAY=null Fri 12/29 8:22:09.257 MiniClient CRYPTO_ALGORITHMS=null Fri 12/29 8:22:09.278 ImageUtils loading URL jar:file:/opt/sagetv/server/Sage.jar!/images/tvicon_anim0.gif Fri 12/29 8:22:09.449 ImageUtils creating BI copy BufferedImage@18056df: type = 13 IndexColorModel: #pixelBits = 8 numComponents = 4 color space = java.awt.color.ICC_ColorSpace@c764e3 transparency = 2 transIndex = 25 has alpha = true isAlphaPre = false ByteInterleavedRaster: width = 32 height = 32 #numDataElements 1 dataOff[0] = 0 w=32 h=32 freeMem=45111280 totalMem=113668096 Fri 12/29 8:22:09.577 ImageUtils loading URL jar:file:/opt/sagetv/server/Sage.jar!/images/tvicon_anim1.gif Fri 12/29 8:22:09.583 ImageUtils creating BI copy BufferedImage@1dfb24d: type = 13 IndexColorModel: #pixelBits = 8 numComponents = 4 color space = java.awt.color.ICC_ColorSpace@c764e3 transparency = 2 transIndex = 25 has alpha = true isAlphaPre = false ByteInterleavedRaster: width = 32 height = 32 #numDataElements 1 dataOff[0] = 0 w=32 h=32 freeMem=44232072 totalMem=113668096 Fri 12/29 8:22:09.584 ImageUtils loading URL jar:file:/opt/sagetv/server/Sage.jar!/images/tvicon_anim2.gif Fri 12/29 8:22:09.592 ImageUtils creating BI copy BufferedImage@1d8f985: type = 13 IndexColorModel: #pixelBits = 8 numComponents = 4 color space = java.awt.color.ICC_ColorSpace@c764e3 transparency = 2 transIndex = 25 has alpha = true isAlphaPre = false ByteInterleavedRaster: width = 32 height = 32 #numDataElements 1 dataOff[0] = 0 w=32 h=32 freeMem=44147952 totalMem=113668096 Fri 12/29 8:22:09.593 ImageUtils loading URL jar:file:/opt/sagetv/server/Sage.jar!/images/tvicon_anim3.gif Fri 12/29 8:22:09.595 ImageUtils creating BI copy BufferedImage@9ad97c: type = 13 IndexColorModel: #pixelBits = 8 numComponents = 4 color space = java.awt.color.ICC_ColorSpace@c764e3 transparency = 2 transIndex = 25 has alpha = true isAlphaPre = false ByteInterleavedRaster: width = 32 height = 32 #numDataElements 1 dataOff[0] = 0 w=32 h=32 freeMem=44070832 totalMem=113668096 Fri 12/29 8:22:09.612 Creating-3 new UI for client:000dfe000143 UIManager:localhost@@000dfe000143-fb2d90 Fri 12/29 8:22:09.631 UIMgr loading UI from: /opt/sagetv/server/STVs/SageTV3/SageTV3.xml Fri 12/29 8:22:09.631 VF thread is now running... Fri 12/29 8:22:09.631 VF thread is now waiting for 0:00:00.000 Fri 12/29 8:22:09.948 # Airs=458 Fri 12/29 8:22:10.209 MUST SEE FINAL-video2-[A[4486505,4429885,"The Daily Show With Jon Stewart",10149@1229.09:00,30], A[4486521,4429891,"The Colbert Report",10149@1229.09:30,30], A[4507209,4507106,"SpongeBob SquarePants",11006@1229.17:30,15], A[4507210,4507107,"SpongeBob SquarePants",11006@1229.17:45,15], A[4507211,4507108,"SpongeBob SquarePants",11006@1229.18:00,15], A[4507212,4507109,"SpongeBob SquarePants",11006@1229.18:30,15], A[4507213,4507110,"SpongeBob SquarePants",11006@1229.18:45,15], A[4507214,4507111,"SpongeBob SquarePants",11006@1229.19:30,15], A[4507215,4507112,"SpongeBob SquarePants",11006@1229.19:45,15]] Fri 12/29 8:22:10.210 MUST SEE FINAL-video0-[] Fri 12/29 8:22:10.210 MUST SEE FINAL-video1-[] Fri 12/29 8:22:10.210 MUST SEE FINAL-video3-[] Fri 12/29 8:22:10.222 Evaluating Potentials Fri 12/29 8:22:10.282 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE video2 Fri 12/29 8:22:10.295 [A[4486505,4429885,"The Daily Show With Jon Stewart",10149@1229.09:00,30], A[4486521,4429891,"The Colbert Report",10149@1229.09:30,30], A[4507209,4507106,"SpongeBob SquarePants",11006@1229.17:30,15], A[4507210,4507107,"SpongeBob SquarePants",11006@1229.17:45,15], A[4507211,4507108,"SpongeBob SquarePants",11006@1229.18:00,15], A[4507212,4507109,"SpongeBob SquarePants",11006@1229.18:30,15], A[4507213,4507110,"SpongeBob SquarePants",11006@1229.18:45,15], A[4507214,4507111,"SpongeBob SquarePants",11006@1229.19:30,15], A[4507215,4507112,"SpongeBob SquarePants",11006@1229.19:45,15], A[4493266,4493227,"House",11207@1229.22:00,60], A[4494102,2587785,"Family Guy",11867@1229.23:30,30], A[4494105,4044875,"Futurama",11867@1230.02:30,30], A[4492034,4421901,"The E! True Hollywood Story",10989@1230.06:00,120], A[4492724,4492692,"Clean Sweep",11158@1230.08:00,60], A[4494860,4494835,"Washington's Generals",14771@1230.11:00,60], A[4494862,4494837,"Washington's Generals",14771@1230.13:00,60], A[4494686,4033108,"Bedazzled",14321@1230.15:00,120], A[4494884,4494841,"Blood Diamonds",14771@1230.17:00,120], A[4492708,4492681,"Trading Spaces",11158@1230.21:00,60], A[4493767,4493741,"Saturday Night Live",11459@1230.22:30,91], A[4493766,4493740,"Saturday Night Live",11459@1231.03:01,89], A[4490869,4352272,"Breakfast With the Arts",10035@1231.07:00,120], A[4490918,4278346,"Child Stars III: Teen Rockers",10035@1231.09:00,120], A[4496187,4496131,"Heavyweight Solutions",18327@1231.11:00,60], A[4494117,4494066,"Ace Ventura: When Nature Calls",11867@1231.12:00,120], A[4490921,4429589,"Deceiving Innocence: The Roger Coleman Story",10035@1231.15:00,60], A[4492642,4319440,"Everest: Beyond the Limit",11150@1231.16:00,60], A[4491258,4491208,"Hee Haw",10138@1231.17:00,60], A[4497538,4497508,"Hee Haw",10138@1231.18:00,60], A[4497541,4497511,"Hee Haw",10138@1231.19:00,60], A[4499002,3733120,"Family Guy",11658@1231.20:00,30], A[4497626,3763545,"South Park",10149@1231.21:00,30], A[4499679,4499645,"Divine Design",14902@1231.21:30,30], A[4497628,3336341,"South Park",10149@1231.22:00,30], A[4499195,4499144,"Metalocalypse",12131@1231.22:30,15], A[4497630,3150546,"South Park",10149@1231.23:00,30], A[4497539,4497509,"Hee Haw",10138@0101.00:00,60], A[4499596,4499584,"The History of Sex",14771@0101.01:00,60], A[4499402,4499314,"A Night in Casablanca",12852@0101.03:00,90], A[4499192,4499142,"Metalocalypse",12131@0101.04:30,15], A[4499194,4499143,"Metalocalypse",12131@0101.04:45,15], A[4499489,4499451,"Like Mike",14321@0101.08:00,120]] video0 Fri 12/29 8:22:10.295 [A[4488303,4071223,"That Touch of Mink",12852@1229.17:00,120], A[4494765,4082893,"South Park",14758@1229.22:00,30], A[4494857,4494831,"Washington's Generals",14771@1230.08:00,60], A[4494682,4494647,"The Wrong Guy",14321@1230.11:00,120], A[4495668,4495639,"Super Size Me",16300@1230.20:00,120], A[4495666,4495638,"Crime & Punishment",16300@1230.22:00,60], A[4490795,4490624,"Sunday Morning Shootout",10021@1231.04:00,30], A[4491156,4487638,"Ugly Betty",10093@1231.10:00,60], A[4496545,4428970,"Janice: Christmas With the Dickinsons",21484@1231.11:00,60], A[4491159,4491083,"Ugly Betty",10093@1231.13:00,60], A[4491161,4491088,"Ugly Betty",10093@1231.15:00,60], A[4492643,4437096,"Everest: Beyond the Limit",11150@1231.17:00,60], A[4498384,4498369,"Everest: Beyond the Limit",11150@1231.18:00,60], A[4498451,4498422,"Resolutionaries",11158@1231.19:00,60], A[4499593,4499583,"The History of Sex",14771@1231.20:00,60], A[4499175,4499134,"Metalocalypse",12131@1231.21:00,15], A[4499177,4499135,"Metalocalypse",12131@1231.21:15,15], A[4497364,4497354,"106 & Party Special",10051@1231.22:00,120], A[4499202,4499148,"Metalocalypse",12131@0101.00:45,15], A[4500899,4500894,"The E! True Hollywood Story",18511@0101.01:00,60]] video1 Fri 12/29 8:22:10.305 [A[4491157,4460858,"Ugly Betty",10093@1231.11:00,60], A[4491163,4491092,"Ugly Betty",10093@1231.17:00,60], A[4497362,4490935,"Notarized: Top 100 Video Countdown",10051@1231.18:00,240], A[4499197,4499145,"Metalocalypse",12131@1231.22:00,15], A[4499183,4499138,"Metalocalypse",12131@1231.22:15,15], A[4499187,4499140,"Metalocalypse",12131@1231.23:00,15], A[4499189,4499141,"Metalocalypse",12131@1231.23:15,15], A[4498456,4498425,"Resolutionaries",11158@0101.00:00,60], A[4497540,4497510,"Hee Haw",10138@0101.01:00,60]] video3 Fri 12/29 8:22:10.305 [A[4499697,4499659,"Haulin' House",14902@1231.19:00,30], A[4497537,4497507,"Hee Haw",10138@1231.23:00,60], A[4499204,4499149,"Metalocalypse",12131@0101.01:15,15]] Fri 12/29 8:22:11.029 locale = en Fri 12/29 8:22:11.398 EVPoolSize=1 Fri 12/29 8:22:11.458 Hidden Extras are ENABLED Fri 12/29 8:22:11.506 VF processing job null nPlayin=false Fri 12/29 8:22:11.506 VF thread is now waiting for 0:00:00.000 Fri 12/29 8:22:11.506 Saving properties file to Sage.properties Fri 12/29 8:22:11.521 Saving properties file to clients/000dfe000143.properties Fri 12/29 8:22:11.562 Checking video directories for new files Fri 12/29 8:22:11.596 Diskspace checking is running Fri 12/29 8:22:11.598 Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Fri 12/29 8:22:11.611 Enforcing keep at most limits for the files... Fri 12/29 8:22:11.805 Seeker waiting for 153722847824210 mins. Fri 12/29 8:22:11.814 Seeker awoken Fri 12/29 8:22:11.815 Seeker adding new Encoder: video2 Fri 12/29 8:22:11.816 Seeker adding new Encoder: video0 Fri 12/29 8:22:11.816 Seeker adding new Encoder: video1 Fri 12/29 8:22:11.816 Seeker adding new Encoder: video3 Fri 12/29 8:22:11.818 MARK 1 currRecord=null enc=video2 clients=[] ir=true Fri 12/29 8:22:11.818 defaultRecord=A[4488309,4488278,"Andy Hardy's Private Secretary",12852@1229.07:00,120] Fri 12/29 8:22:11.818 Seeker in AUTOMATIC mode nextRecord=A[4486505,4429885,"The Daily Show With Jon Stewart",10149@1229.09:00,30] nextTTA=2268183 Fri 12/29 8:22:11.818 newRecord=null Fri 12/29 8:22:11.818 NOTHING TO RECORD FOR NOW... Fri 12/29 8:22:11.818 MARK 1 currRecord=null enc=video0 clients=[] ir=true Fri 12/29 8:22:11.819 defaultRecord=A[4486238,4486205,"24",10035@1229.08:00,60] Fri 12/29 8:22:11.819 Seeker in AUTOMATIC mode nextRecord=A[4488303,4071223,"That Touch of Mink",12852@1229.17:00,120] nextTTA=31068183 Fri 12/29 8:22:11.819 newRecord=null Fri 12/29 8:22:11.819 NOTHING TO RECORD FOR NOW... Fri 12/29 8:22:11.819 MARK 1 currRecord=null enc=video1 clients=[] ir=true Fri 12/29 8:22:11.819 defaultRecord=A[4487483,2359993,"Jump Start",11218@1229.05:00,240] Fri 12/29 8:22:11.819 Seeker in AUTOMATIC mode nextRecord=A[4491157,4460858,"Ugly Betty",10093@1231.11:00,60] nextTTA=182268183 Fri 12/29 8:22:11.819 newRecord=null Fri 12/29 8:22:11.819 NOTHING TO RECORD FOR NOW... Fri 12/29 8:22:11.819 MARK 1 currRecord=null enc=video3 clients=[] ir=true Fri 12/29 8:22:11.823 defaultRecord=A[4488369,4484264,"Spin City",14321@1229.08:00,30] Fri 12/29 8:22:11.823 Seeker in AUTOMATIC mode nextRecord=A[4499697,4499659,"Haulin' House",14902@1231.19:00,30] nextTTA=211068183 Fri 12/29 8:22:11.823 newRecord=null Fri 12/29 8:22:11.823 NOTHING TO RECORD FOR NOW... Fri 12/29 8:22:11.828 VF processing job null nPlayin=false Fri 12/29 8:22:11.828 VF thread is now waiting for 0:00:00.000 Fri 12/29 8:22:11.828 Seeker waiting for -19456702 mins. Fri 12/29 8:22:11.834 Seeker awoken Fri 12/29 8:22:11.835 MARK 1 currRecord=null enc=video2 clients=[] ir=true Fri 12/29 8:22:11.835 defaultRecord=A[4488309,4488278,"Andy Hardy's Private Secretary",12852@1229.07:00,120] Fri 12/29 8:22:11.835 Seeker in AUTOMATIC mode nextRecord=A[4486505,4429885,"The Daily Show With Jon Stewart",10149@1229.09:00,30] nextTTA=2268165 Fri 12/29 8:22:11.836 newRecord=null Fri 12/29 8:22:11.836 NOTHING TO RECORD FOR NOW... Fri 12/29 8:22:11.836 MARK 1 currRecord=null enc=video0 clients=[] ir=true Fri 12/29 8:22:11.836 defaultRecord=A[4486238,4486205,"24",10035@1229.08:00,60] Fri 12/29 8:22:11.836 Seeker in AUTOMATIC mode nextRecord=A[4488303,4071223,"That Touch of Mink",12852@1229.17:00,120] nextTTA=31068165 Fri 12/29 8:22:11.836 newRecord=null Fri 12/29 8:22:11.836 NOTHING TO RECORD FOR NOW... Fri 12/29 8:22:11.836 MARK 1 currRecord=null enc=video1 clients=[] ir=true Fri 12/29 8:22:11.836 defaultRecord=A[4487483,2359993,"Jump Start",11218@1229.05:00,240] Fri 12/29 8:22:11.836 Seeker in AUTOMATIC mode nextRecord=A[4491157,4460858,"Ugly Betty",10093@1231.11:00,60] nextTTA=182268165 Fri 12/29 8:22:11.836 newRecord=null Fri 12/29 8:22:11.836 NOTHING TO RECORD FOR NOW... Fri 12/29 8:22:11.836 MARK 1 currRecord=null enc=video3 clients=[] ir=true Fri 12/29 8:22:11.836 defaultRecord=A[4488369,4484264,"Spin City",14321@1229.08:00,30] Fri 12/29 8:22:11.836 Seeker in AUTOMATIC mode nextRecord=A[4499697,4499659,"Haulin' House",14902@1231.19:00,30] nextTTA=211068165 Fri 12/29 8:22:11.837 newRecord=null Fri 12/29 8:22:11.837 NOTHING TO RECORD FOR NOW... Fri 12/29 8:22:11.841 VF processing job null nPlayin=false Fri 12/29 8:22:11.841 VF thread is now waiting for 0:00:00.000 Fri 12/29 8:22:11.841 Checking video directories for new files Fri 12/29 8:22:11.902 Diskspace checking is running Fri 12/29 8:22:11.902 Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Fri 12/29 8:22:11.915 Enforcing keep at most limits for the files... Fri 12/29 8:22:11.923 Loading startup runnable:net.sf.sageplugins.webserver.StartServer Fri 12/29 8:22:11.926 StorageDeviceDetector started... Fri 12/29 8:22:11.928 Loaded startup runnable:net.sf.sageplugins.webserver.StartServer Fri 12/29 8:22:11.932 Starting webserver with args: [-p, 8080, -root, webserver, -t, throttles.properties, -s, servlets.properties, -r, realms.properties, -a, aliases.properties, -nohup] Fri 12/29 8:22:12.123 Seeker is starting the library import scan... Fri 12/29 8:22:12.200 Acme httpd sagetv:8080 listening. Fri 12/29 8:22:12.396 DatePat=EEE, MMM d orgPat=EEEE, MMMM d, yyyy Fri 12/29 8:22:12.441 Seeker waiting for 32 mins. Fri 12/29 8:22:12.461 setUI(sage.bu@6aa01a[Main Menu]) histIdx=0 uiHistory=[sage.bu@6aa01a[Main Menu]] Fri 12/29 8:22:12.482 setUI(sage.bu@3067dc[Configuration Wizard - Home]) histIdx=0 uiHistory=[sage.bu@3067dc[Configuration Wizard - Home]] Fri 12/29 8:22:12.531 setUI(sage.bu@24eaaa[Configuration Wizard - Choose Country]) histIdx=0 uiHistory=[sage.bu@24eaaa[Configuration Wizard - Choose Country]] Fri 12/29 8:22:12.536 Saving properties file to clients/000dfe000143.properties Fri 12/29 8:22:12.538 Saving properties file to Sage.properties Fri 12/29 8:22:12.569 setUI(sage.bu@3923d[Configuration Wizard - Choose Language]) histIdx=0 uiHistory=[sage.bu@3923d[Configuration Wizard - Choose Language]] Fri 12/29 8:22:12.671 Starting Disk Space Bar Updater Thread: Thread[Thread-5,5,main] Fri 12/29 8:22:12.673 Updating Disk Space Bar Fri 12/29 8:22:12.676 EVPoolSize=2 Fri 12/29 8:22:12.684 EVPoolSize=3 Fri 12/29 8:22:12.725 Got used/avail video diskspace Fri 12/29 8:22:12.726 Calculate size of partial recordings Fri 12/29 8:22:12.760 Found 2 partials using 4225730432 bytes Fri 12/29 8:22:12.760 Calculate space required for upcomming manual/favourite airings for next 2 days and display Fri 12/29 8:22:12.855 Creating-4 new UI for client:000dfe000143 UIManager:localhost@@000dfe000143-fb2d90 Fri 12/29 8:22:12.855 MiniUI got connection from Socket[addr=/10.0.0.90,port=4348,localport=31099] Fri 12/29 8:22:12.856 MiniPlayer is adding to its map:000dfe000143 Fri 12/29 8:22:12.856 MiniUI got connection from Socket[addr=/10.0.0.91,port=4885,localport=31099] Fri 12/29 8:22:12.856 MiniUI is adding to its map:000dfe007e9f Fri 12/29 8:22:12.856 Creating new UI for client:000dfe007e9f Fri 12/29 8:22:12.857 Saving properties file to clients/000dfe007e9f.properties Fri 12/29 8:22:12.875 Ministry is starting Fri 12/29 8:22:12.884 Creating-2 new UI for client:000dfe007e9f UIManager:localhost@@000dfe007e9f-12b2a18 Fri 12/29 8:22:12.936 Ministry is waiting for 180 sec Fri 12/29 8:22:12.976 VideoDiskspaceReqProportion: 0.9978180978715686 Fri 12/29 8:22:12.977 VideoDiskspaceFreeProportion: 0.9835131603153373 Fri 12/29 8:22:12.977 Disk Space Bar Update complete for UI context 000dfe000143 Fri 12/29 8:22:13.111 MiniUI established for 000dfe007e9f Fri 12/29 8:22:13.190 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=15] Fri 12/29 8:22:13.373 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=15] numGlyphs=1666 numCachedGlyphs=196 numImages=3 Fri 12/29 8:22:13.386 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=10] Fri 12/29 8:22:13.565 MiniClient GFX_TEXTMODE=null Fri 12/29 8:22:13.565 MiniClient GFX_BLENDMODE=null Fri 12/29 8:22:13.566 MiniClient GFX_DRAWMODE=null Fri 12/29 8:22:13.567 MiniClient GFX_BITMAP_FORMAT=null Fri 12/29 8:22:13.568 MiniClient GFX_SCALING=null Fri 12/29 8:22:13.568 MiniClient GFX_OFFLINE_IMAGE_CACHE=null Fri 12/29 8:22:13.569 MiniClient GFX_SUPPORTED_ASPECTS=null Fri 12/29 8:22:13.579 MiniClient GFX_ASPECT=null Fri 12/29 8:22:13.579 MiniClient GFX_SUPPORTED_RESOLUTIONS=null Fri 12/29 8:22:13.580 MiniClient GFX_RESOLUTION=null Fri 12/29 8:22:13.581 MiniClient GFX_COMPOSITE=null Fri 12/29 8:22:13.582 MiniClient GFX_COLORKEY=null Fri 12/29 8:22:13.582 MiniClient INPUT_DEVICES=null Fri 12/29 8:22:13.583 MiniClient DISPLAY_OVERSCAN=null Fri 12/29 8:22:13.584 MiniClient VIDEO_CODECS=null Fri 12/29 8:22:13.584 MiniClient AUDIO_CODECS=null Fri 12/29 8:22:13.585 MiniClient PULL_AV_CONTAINERS=null Fri 12/29 8:22:13.591 MiniClient PUSH_AV_CONTAINERS=null Fri 12/29 8:22:13.592 MiniClient FIXED_PUSH_MEDIA_FORMAT=null Fri 12/29 8:22:13.592 MiniClient DETAILED_BUFFER_STATS=null Fri 12/29 8:22:13.593 MiniClient PUSH_BUFFER_SEEKING=null Fri 12/29 8:22:13.599 MiniClient MEDIA_PLAYER_BUFFER_DELAY=null Fri 12/29 8:22:13.599 MiniClient CRYPTO_ALGORITHMS=null Fri 12/29 8:22:13.609 Creating-3 new UI for client:000dfe007e9f UIManager:localhost@@000dfe007e9f-12b2a18 Fri 12/29 8:22:13.627 UIMgr loading UI from: /opt/sagetv/server/STVs/SageTV3/SageTV3.xml Fri 12/29 8:22:13.627 VF thread is now running... Fri 12/29 8:22:13.627 VF thread is now waiting for 0:00:00.000 Fri 12/29 8:22:13.630 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=10] numGlyphs=1666 numCachedGlyphs=271 numImages=2 Fri 12/29 8:22:13.631 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=24] Fri 12/29 8:22:14.535 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=24] numGlyphs=1666 numCachedGlyphs=199 numImages=7 Fri 12/29 8:22:14.536 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=18] Fri 12/29 8:22:16.377 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=18] numGlyphs=1666 numCachedGlyphs=229 numImages=5 Fri 12/29 8:22:16.414 Loading 720x480 image from file /opt/sagetv/server/STVs/SageTV3/Background.jpg Fri 12/29 8:22:16.661 Hidden Extras are ENABLED Fri 12/29 8:22:16.701 setUI(sage.bu@c2022e[Main Menu]) histIdx=0 uiHistory=[sage.bu@c2022e[Main Menu]] Fri 12/29 8:22:16.725 setUI(sage.bu@3a57aa[Configuration Wizard - Home]) histIdx=0 uiHistory=[sage.bu@3a57aa[Configuration Wizard - Home]] Fri 12/29 8:22:16.755 setUI(sage.bu@8aedb8[Configuration Wizard - Choose Country]) histIdx=0 uiHistory=[sage.bu@8aedb8[Configuration Wizard - Choose Country]] Fri 12/29 8:22:16.759 Saving properties file to clients/000dfe007e9f.properties Fri 12/29 8:22:16.761 Saving properties file to Sage.properties Fri 12/29 8:22:16.851 setUI(sage.bu@900608[Configuration Wizard - Choose Language]) histIdx=0 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language]] Fri 12/29 8:22:16.891 Starting Disk Space Bar Updater Thread: Thread[Thread-7,5,main] Fri 12/29 8:22:16.892 Updating Disk Space Bar Fri 12/29 8:22:16.917 Got used/avail video diskspace Fri 12/29 8:22:16.917 Calculate size of partial recordings Fri 12/29 8:22:16.929 Found 2 partials using 4225730432 bytes Fri 12/29 8:22:16.929 Calculate space required for upcomming manual/favourite airings for next 2 days and display Fri 12/29 8:22:16.945 Loading 114x30 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:22:16.979 Creating-4 new UI for client:000dfe007e9f UIManager:localhost@@000dfe007e9f-12b2a18 Fri 12/29 8:22:16.979 MiniUI got connection from Socket[addr=/10.0.0.91,port=4886,localport=31099] Fri 12/29 8:22:16.979 MiniPlayer is adding to its map:000dfe007e9f Fri 12/29 8:22:17.038 Loading 720x480 image from file /opt/sagetv/server/STVs/SageTV3/Background.jpg Fri 12/29 8:22:17.230 Loading 24x22 image from file /opt/sagetv/server/STVs/SageTV3/MarkerRadioOff.png Fri 12/29 8:22:17.252 Loading 24x22 image from file /opt/sagetv/server/STVs/SageTV3/MarkerRadioOn.png Fri 12/29 8:22:17.295 VideoDiskspaceReqProportion: 0.9978180978715686 Fri 12/29 8:22:17.295 VideoDiskspaceFreeProportion: 0.9835131603153373 Fri 12/29 8:22:17.296 Disk Space Bar Update complete for UI context 000dfe007e9f Fri 12/29 8:22:17.449 Loading 114x30 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:22:17.704 Loading 24x22 image from file /opt/sagetv/server/STVs/SageTV3/MarkerRadioOff.png Fri 12/29 8:22:17.723 Loading 24x22 image from file /opt/sagetv/server/STVs/SageTV3/MarkerRadioOn.png Dec 29, 2006 8:22:18 AM net.sbbi.upnp.DiscoveryListener run SEVERE: IO Exception during UPNP DiscoveryListener messages listening thread java.net.SocketException: Socket closed at java.net.PlainDatagramSocketImpl.receive0(Native Method) at java.net.PlainDatagramSocketImpl.receive(Unknown Source) at java.net.DatagramSocket.receive(Unknown Source) at net.sbbi.upnp.DiscoveryListener.listenBroadCast(DiscoveryListener.java:213) at net.sbbi.upnp.DiscoveryListener.run(DiscoveryListener.java:192) at java.lang.Thread.run(Unknown Source) Fri 12/29 8:23:02.406 Seeker has finished the library import scan. Fri 12/29 8:23:02.454 setUI(sage.bu@152a09f[Configuration Wizard - Choose Time Zone]) histIdx=1 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@152a09f[Configuration Wizard - Choose Time Zone]] Fri 12/29 8:23:02.476 setUI(sage.bu@14e9726[Configuration Wizard - Ask for Startup Options]) histIdx=1 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@14e9726[Configuration Wizard - Ask for Startup Options]] Fri 12/29 8:23:02.519 setUI(sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1]) histIdx=1 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1]] Fri 12/29 8:23:02.611 Loading 15x16 image from file /opt/sagetv/server/STVs/SageTV3/left.png Fri 12/29 8:23:02.816 Loading 17x16 image from file /opt/sagetv/server/STVs/SageTV3/right.png Fri 12/29 8:23:02.818 Loading 20x16 image from file /opt/sagetv/server/STVs/SageTV3/up.png Fri 12/29 8:23:02.819 Loading 19x16 image from file /opt/sagetv/server/STVs/SageTV3/down.png Fri 12/29 8:23:04.457 EVPoolSize=4 Fri 12/29 8:23:13.507 Updating Disk Space Bar Fri 12/29 8:23:13.528 Got used/avail video diskspace Fri 12/29 8:23:13.528 Calculate size of partial recordings Fri 12/29 8:23:13.536 Found 2 partials using 4225730432 bytes Fri 12/29 8:23:13.536 Calculate space required for upcomming manual/favourite airings for next 2 days and display Fri 12/29 8:23:13.636 VideoDiskspaceReqProportion: 0.9978180978715686 Fri 12/29 8:23:13.636 VideoDiskspaceFreeProportion: 0.9835131603153373 Fri 12/29 8:23:13.636 Disk Space Bar Update complete for UI context 000dfe000143 Fri 12/29 8:23:17.603 Updating Disk Space Bar Fri 12/29 8:23:17.623 Got used/avail video diskspace Fri 12/29 8:23:17.624 Calculate size of partial recordings Fri 12/29 8:23:17.631 Found 2 partials using 4225730432 bytes Fri 12/29 8:23:17.631 Calculate space required for upcomming manual/favourite airings for next 2 days and display Fri 12/29 8:23:17.726 VideoDiskspaceReqProportion: 0.9978180978715686 Fri 12/29 8:23:17.726 VideoDiskspaceFreeProportion: 0.9835131603153373 Fri 12/29 8:23:17.727 Disk Space Bar Update complete for UI context 000dfe007e9f Fri 12/29 8:23:19.952 setUI(sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2]) histIdx=2 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2]] Fri 12/29 8:23:21.343 Loading 113x30 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:22.083 Loading 19x16 image from file /opt/sagetv/server/STVs/SageTV3/up.png Fri 12/29 8:23:22.469 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=16] Fri 12/29 8:23:22.632 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=16] numGlyphs=1666 numCachedGlyphs=247 numImages=4 Fri 12/29 8:23:24.509 Loading 112x30 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:24.856 Loading 16x16 image from file /opt/sagetv/server/STVs/SageTV3/right.png Fri 12/29 8:23:26.603 Loading 18x16 image from file /opt/sagetv/server/STVs/SageTV3/down.png Fri 12/29 8:23:26.968 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=23] Fri 12/29 8:23:27.263 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=23] numGlyphs=1666 numCachedGlyphs=210 numImages=7 Fri 12/29 8:23:27.663 Loading 111x30 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:29.386 Loading 14x16 image from file /opt/sagetv/server/STVs/SageTV3/left.png Fri 12/29 8:23:30.559 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=9] Fri 12/29 8:23:30.781 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=9] numGlyphs=1666 numCachedGlyphs=320 numImages=2 Fri 12/29 8:23:30.783 Loading 110x30 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:31.477 Loading 110x29 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:31.819 Loading 14x15 image from file /opt/sagetv/server/STVs/SageTV3/left.png Fri 12/29 8:23:32.023 Loading 16x15 image from file /opt/sagetv/server/STVs/SageTV3/right.png Fri 12/29 8:23:32.024 Loading 19x15 image from file /opt/sagetv/server/STVs/SageTV3/up.png Fri 12/29 8:23:32.026 Loading 18x15 image from file /opt/sagetv/server/STVs/SageTV3/down.png Fri 12/29 8:23:33.677 Loading 109x29 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:36.474 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=14] Fri 12/29 8:23:36.638 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=14] numGlyphs=1666 numCachedGlyphs=245 numImages=3 Fri 12/29 8:23:37.000 Loading 108x29 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:38.861 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=17] Fri 12/29 8:23:39.004 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=17] numGlyphs=1666 numCachedGlyphs=234 numImages=4 Fri 12/29 8:23:39.855 Loading 18x15 image from file /opt/sagetv/server/STVs/SageTV3/up.png Fri 12/29 8:23:40.300 Loading 107x29 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:41.110 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=13] Fri 12/29 8:23:41.282 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=13] numGlyphs=1666 numCachedGlyphs=260 numImages=3 Fri 12/29 8:23:42.344 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=22] Fri 12/29 8:23:42.641 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=22] numGlyphs=1666 numCachedGlyphs=215 numImages=7 Fri 12/29 8:23:43.026 Loading 107x28 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:43.372 Loading 106x28 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:45.463 Loading 17x15 image from file /opt/sagetv/server/STVs/SageTV3/down.png Fri 12/29 8:23:45.812 Loading 15x15 image from file /opt/sagetv/server/STVs/SageTV3/right.png Fri 12/29 8:23:46.254 Loading 105x28 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:49.409 Loading 104x28 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:49.750 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=8] Fri 12/29 8:23:49.769 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=8] numGlyphs=1666 numCachedGlyphs=196 numImages=1 Fri 12/29 8:23:52.728 Loading 103x28 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:53.183 Loading 13x15 image from file /opt/sagetv/server/STVs/SageTV3/left.png Fri 12/29 8:23:53.830 Loading 13x14 image from file /opt/sagetv/server/STVs/SageTV3/left.png Fri 12/29 8:23:54.025 Loading 15x14 image from file /opt/sagetv/server/STVs/SageTV3/right.png Fri 12/29 8:23:54.027 Loading 18x14 image from file /opt/sagetv/server/STVs/SageTV3/up.png Fri 12/29 8:23:54.029 Loading 17x14 image from file /opt/sagetv/server/STVs/SageTV3/down.png Fri 12/29 8:23:54.596 Loading 103x27 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:23:55.984 Loading 102x27 image from file /opt/sagetv/server/STVs/SageTV3/SageLogo256.png Fri 12/29 8:24:00.571 setUI(sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio]) histIdx=3 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio]] Fri 12/29 8:24:00.587 Saving properties file to clients/000dfe007e9f.properties Fri 12/29 8:24:00.590 Saving properties file to Sage.properties Fri 12/29 8:24:00.629 Loading 275x244 image from file /opt/sagetv/server/STVs/SageTV3/AspectRatioCircle.png Fri 12/29 8:24:00.883 Loading 23x21 image from file /opt/sagetv/server/STVs/SageTV3/MarkerRadioOn.png Fri 12/29 8:24:00.960 Loading 23x21 image from file /opt/sagetv/server/STVs/SageTV3/MarkerRadioOff.png Fri 12/29 8:24:00.968 Loading 22x23 image from file /opt/sagetv/server/STVs/SageTV3/left.png Fri 12/29 8:24:00.985 Loading 24x23 image from file /opt/sagetv/server/STVs/SageTV3/right.png Fri 12/29 8:24:04.524 setUI(sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus]) histIdx=4 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus]] Fri 12/29 8:24:04.540 EVPoolSize=5 Fri 12/29 8:24:08.889 setUI(sage.bu@c145f9[Configuration Wizard - Ask Display Mouse Icons]) histIdx=5 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@c145f9[Configuration Wizard - Ask Display Mouse Icons]] Fri 12/29 8:24:08.904 setUI(sage.bu@ba89c5[Configuration Wizard - Configure Remote]) histIdx=5 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@ba89c5[Configuration Wizard - Configure Remote]] Fri 12/29 8:24:08.920 setUI(sage.bu@17ca589[Configuration Wizard - Ask Enable Intelligent Recording]) histIdx=5 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@17ca589[Configuration Wizard - Ask Enable Intelligent Recording]] Fri 12/29 8:24:08.936 setUI(sage.bu@67d5fe[Configuration Wizard - Ask For Weather Setup]) histIdx=5 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@67d5fe[Configuration Wizard - Ask For Weather Setup]] Fri 12/29 8:24:11.449 setUI(sage.bu@a19277[Configuration Wizard - Ask use Parental Controls]) histIdx=6 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@67d5fe[Configuration Wizard - Ask For Weather Setup], sage.bu@a19277[Configuration Wizard - Ask use Parental Controls]] Fri 12/29 8:24:11.460 Saving properties file to clients/000dfe007e9f.properties Fri 12/29 8:24:11.462 Saving properties file to Sage.properties Fri 12/29 8:24:11.475 setUI(sage.bu@1f25e56[Main Menu]) histIdx=6 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@67d5fe[Configuration Wizard - Ask For Weather Setup], sage.bu@1f25e56[Main Menu]] Fri 12/29 8:24:11.571 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=plain,size=23] Fri 12/29 8:24:11.942 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=plain,size=23] numGlyphs=2929 numCachedGlyphs=244 numImages=8 Fri 12/29 8:24:11.944 Loading 624x38 image from file /opt/sagetv/server/STVs/SageTV3/MenuBarLong.png Fri 12/29 8:24:14.267 Updating Disk Space Bar Fri 12/29 8:24:14.289 Got used/avail video diskspace Fri 12/29 8:24:14.289 Calculate size of partial recordings Fri 12/29 8:24:14.298 Found 2 partials using 4225730432 bytes Fri 12/29 8:24:14.298 Calculate space required for upcomming manual/favourite airings for next 2 days and display Fri 12/29 8:24:14.386 VideoDiskspaceReqProportion: 0.9978180978715686 Fri 12/29 8:24:14.386 VideoDiskspaceFreeProportion: 0.9835131603153373 Fri 12/29 8:24:14.387 Disk Space Bar Update complete for UI context 000dfe000143 Fri 12/29 8:24:15.984 setUI(sage.bu@5d9809[Library]) histIdx=7 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@67d5fe[Configuration Wizard - Ask For Weather Setup], sage.bu@1f25e56[Main Menu], sage.bu@5d9809[Library]] Fri 12/29 8:24:17.743 setUI(sage.bu@14ced4e[Browser - Music]) histIdx=8 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@67d5fe[Configuration Wizard - Ask For Weather Setup], sage.bu@1f25e56[Main Menu], sage.bu@5d9809[Library], sage.bu@14ced4e[Browser - Music]] Fri 12/29 8:24:17.800 setUI(sage.bu@d19957[Browser - Music]) histIdx=8 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@67d5fe[Configuration Wizard - Ask For Weather Setup], sage.bu@1f25e56[Main Menu], sage.bu@5d9809[Library], sage.bu@d19957[Browser - Music]] Fri 12/29 8:24:17.811 BEGIN: Get all music files Fri 12/29 8:24:17.814 ==> Get all music files. GetMediaFiles() Time = '3' Fri 12/29 8:24:17.823 ==> Get all music files. Filter by IsMusicFile Time = '9' Fri 12/29 8:24:17.828 ==> Get all music files. Filter by IsLibraryFile Time = '5' Fri 12/29 8:24:17.829 END: Get all music files. Found 10382 songs. Total Time = '18' Fri 12/29 8:24:17.871 BEGIN: Sort playlists. Fri 12/29 8:24:17.881 END: Sort playlists. Sorted 2 playlists. Total Time = '10' Fri 12/29 8:24:17.883 BEGIN: Group songs by artist. Fri 12/29 8:24:17.992 ==> Group by artist via GetPeopleListInShowInRoles (Artist;Album Artist): Time = '108' Fri 12/29 8:24:18.074 ==> Sort artist group: Time = '82' Fri 12/29 8:24:18.077 END: Group songs by artist. Found 1269 artists. Total Time = '193' Fri 12/29 8:24:18.097 Generating album cache... Fri 12/29 8:24:18.147 Done generating album cache. Fri 12/29 8:24:18.181 ImageUtils loading binary data length=41286 Fri 12/29 8:24:18.207 ImageUtils creating BI copy BufferedImage@15b2028: type = 5 ColorModel: #pixelBits = 24 numComponents = 3 color space = java.awt.color.ICC_ColorSpace@c764e3 transparency = 1 has alpha = false isAlphaPre = false ByteInterleavedRaster: width = 300 height = 300 #numDataElements 3 dataOff[0] = 2 w=300 h=300 freeMem=57131784 totalMem=166526976 Fri 12/29 8:24:18.257 ImageUtils loading binary data length=30518 Fri 12/29 8:24:18.276 ImageUtils creating BI copy BufferedImage@d454f5: type = 5 ColorModel: #pixelBits = 24 numComponents = 3 color space = java.awt.color.ICC_ColorSpace@c764e3 transparency = 1 has alpha = false isAlphaPre = false ByteInterleavedRaster: width = 300 height = 298 #numDataElements 3 dataOff[0] = 2 w=300 h=298 freeMem=65032048 totalMem=166526976 Fri 12/29 8:24:18.291 Updating Disk Space Bar Fri 12/29 8:24:18.312 Got used/avail video diskspace Fri 12/29 8:24:18.312 Calculate size of partial recordings Fri 12/29 8:24:18.320 Found 2 partials using 4225730432 bytes Fri 12/29 8:24:18.320 Calculate space required for upcomming manual/favourite airings for next 2 days and display Fri 12/29 8:24:18.359 ImageUtils loading binary data length=62783 Fri 12/29 8:24:18.441 ImageUtils creating BI copy BufferedImage@186a6d2: type = 5 ColorModel: #pixelBits = 24 numComponents = 3 color space = java.awt.color.ICC_ColorSpace@c764e3 transparency = 1 has alpha = false isAlphaPre = false ByteInterleavedRaster: width = 471 height = 475 #numDataElements 3 dataOff[0] = 2 w=471 h=475 freeMem=61415240 totalMem=166526976 Fri 12/29 8:24:18.450 VideoDiskspaceReqProportion: 0.9978180978715686 Fri 12/29 8:24:18.450 VideoDiskspaceFreeProportion: 0.9835131603153373 Fri 12/29 8:24:18.450 Disk Space Bar Update complete for UI context 000dfe007e9f Fri 12/29 8:24:18.474 ImageUtils loading binary data length=33555 Fri 12/29 8:24:18.493 ImageUtils creating BI copy BufferedImage@1e57a5a: type = 5 ColorModel: #pixelBits = 24 numComponents = 3 color space = java.awt.color.ICC_ColorSpace@c764e3 transparency = 1 has alpha = false isAlphaPre = false ByteInterleavedRaster: width = 300 height = 298 #numDataElements 3 dataOff[0] = 2 w=300 h=298 freeMem=59826800 totalMem=166526976 Fri 12/29 8:24:18.683 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=5] Fri 12/29 8:24:18.721 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=5] numGlyphs=1666 numCachedGlyphs=403 numImages=1 Fri 12/29 8:24:18.722 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=7] Fri 12/29 8:24:18.749 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=7] numGlyphs=1666 numCachedGlyphs=228 numImages=1 Fri 12/29 8:24:18.750 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=12] Fri 12/29 8:24:18.882 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=12] numGlyphs=1666 numCachedGlyphs=209 numImages=2 Fri 12/29 8:24:18.882 Loading new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=20] Fri 12/29 8:24:19.171 Rendered new font to cache font=java.awt.Font[family=Dialog,name=Arial,style=bold,size=20] numGlyphs=1666 numCachedGlyphs=212 numImages=5 Fri 12/29 8:24:19.178 BEGIN: Group songs by genre. Fri 12/29 8:24:19.217 ==> Group by genre via GetShowCategory: Time = '39' Fri 12/29 8:24:19.219 ==> Sort genre group: Time = '2' Fri 12/29 8:24:19.230 END: Group songs by artist. Found 79 genres. Total Time = '52' Fri 12/29 8:24:19.237 BEGIN: Group songs by year. Fri 12/29 8:24:19.249 ==> Group by year via GetShowYear: Time = '11' Fri 12/29 8:24:19.250 ==> Sort year group: Time = '1' Fri 12/29 8:24:19.259 END: Group songs by year. Found 33 years. Total Time = '21' Fri 12/29 8:24:19.260 BEGIN: Get all filenames Fri 12/29 8:24:19.288 ==> Get all filenames. GroupByArrayMethod() Time = '28' Fri 12/29 8:24:19.293 ==> Get all filenames. get keySet. Time = '5' Fri 12/29 8:24:19.380 Loading 31x27 image from file /opt/sagetv/server/STVs/SageTV3/MusicStyleNext.png Fri 12/29 8:24:19.395 Loading 31x27 image from file /opt/sagetv/server/STVs/SageTV3/MusicStylePrev.png Fri 12/29 8:24:19.401 Loading 31x27 image from file /opt/sagetv/server/STVs/SageTV3/MusicOptions.png Fri 12/29 8:24:19.407 Loading 31x27 image from file /opt/sagetv/server/STVs/SageTV3/MusicBack.png Fri 12/29 8:24:19.412 Loading 31x27 image from file /opt/sagetv/server/STVs/SageTV3/MusicRandOffRptOff.png Fri 12/29 8:24:19.419 Loading 63x56 image from file /tmp/stv23522.img Fri 12/29 8:24:19.505 Loading 64x56 image from file /tmp/stv23523.img Fri 12/29 8:24:19.528 Loading 63x56 image from file /tmp/stv23524.img Fri 12/29 8:24:19.571 Loading 64x56 image from file /tmp/stv23525.img Fri 12/29 8:24:19.593 Loading 23x20 image from file /opt/sagetv/server/STVs/SageTV3/down.png Fri 12/29 8:24:20.052 ==> Get all filenames. Convert to Strings. Time = '759' Fri 12/29 8:24:20.060 ==> Get all filenames. Sort Time = '8' Fri 12/29 8:24:20.060 END: Get all filenames. Found 10382 filenames. Total Time = '800' Fri 12/29 8:24:20.485 BEGIN: Sort songs by title. Fri 12/29 8:24:21.105 processOptionsMenu optionsMenu=default:null|OptionsMenu:Show options for song or group of songs Fri 12/29 8:24:21.173 Loading 21x19 image from file /opt/sagetv/server/STVs/SageTV3/MarkerInfo.png Fri 12/29 8:24:21.244 ==> Sort all songs by GetMediaTitle: Time = '756' Fri 12/29 8:24:21.245 END: Sort songs by title. Found 10382 songs. Total Time = '760' Fri 12/29 8:24:21.246 BEGIN: Group songs by album. Fri 12/29 8:24:21.400 END: Group songs by album. Found 743 albums. Total Time = '154' Fri 12/29 8:24:21.461 Loading 23x17 image from file /opt/sagetv/server/STVs/SageTV3/OSDPlay.png Fri 12/29 8:24:23.491 VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null]) Fri 12/29 8:24:23.491 VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false Fri 12/29 8:24:23.491 VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null] Fri 12/29 8:24:23.491 VF processing job null nPlayin=false Fri 12/29 8:24:23.492 VF thread is now waiting for 0:00:00.000 Fri 12/29 8:24:23.492 Seeker.finishWatch(UIManager:localhost@@000dfe007e9f-12b2a18) Fri 12/29 8:24:23.501 VF.startPlaylist Playlist[Now Playing A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4], ] startIdx=0 Fri 12/29 8:24:23.501 VF.submitJob(VFJob[StartPlaylist r=0.0 t=0 file=null ifn=null]) Fri 12/29 8:24:23.507 VF processing job VFJob[StartPlaylist r=0.0 t=0 file=null ifn=null] nPlayin=false Fri 12/29 8:24:23.507 VF processing on UI Thread VFJob[StartPlaylist r=0.0 t=0 file=null ifn=null] Fri 12/29 8:24:23.507 VF.watchNextInPlaylist playlistChain=[Playlist[Now Playing A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4], ]] playindices=[-1] currFile=null Fri 12/29 8:24:23.508 VideoFrame.watch(A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4]) Fri 12/29 8:24:23.508 watchThisFile=MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] Fri 12/29 8:24:23.508 Watch airing is a file & over, do that instead dvd=false Fri 12/29 8:24:23.509 Seeker.finishWatch(UIManager:localhost@@000dfe007e9f-12b2a18) Fri 12/29 8:24:23.509 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] ifn=null]) Fri 12/29 8:24:23.509 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] ifn=null] nPlayin=false Fri 12/29 8:24:23.509 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] ifn=null] Fri 12/29 8:24:23.510 VideoFrame creating new media player for file:MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] Fri 12/29 8:24:23.593 Loading 50x45 image from file /tmp/stv23526.img Fri 12/29 8:24:23.609 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] ifn=null] nPlayin=false Fri 12/29 8:24:23.610 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] ifn=null] Fri 12/29 8:24:23.610 VF file=MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] targetTime = Fri 2/10 14:37:00.822 Fri 12/29 8:24:23.611 VideoFrame.timeSelected(Fri 2/10 14:37:00.822, true) currFile=MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] Fri 12/29 8:24:23.616 initDriver0() Fri 12/29 8:24:23.616 MiniPlayer established for 000dfe007e9f Fri 12/29 8:24:23.624 getDuration : 0 Fri 12/29 8:24:23.632 getDuration : 0 Fri 12/29 8:24:23.886 Loading 23x18 image from file /opt/sagetv/server/STVs/SageTV3/OSDRew.png Fri 12/29 8:24:23.897 Loading 23x18 image from file /opt/sagetv/server/STVs/SageTV3/OSDStop.png Fri 12/29 8:24:23.900 Loading 23x18 image from file /opt/sagetv/server/STVs/SageTV3/OSDPlay.png Fri 12/29 8:24:23.901 Loading 23x18 image from file /opt/sagetv/server/STVs/SageTV3/OSDFwd.png Fri 12/29 8:24:23.903 Loading 18x13 image from file /opt/sagetv/server/STVs/SageTV3/OSDVolOn.png Fri 12/29 8:24:23.906 ImageUtils loading file /opt/sagetv/server/STVs/SageTV3/OSDVolBar.png Fri 12/29 8:24:23.936 MiniPlayer got an estimate from the UI on bandwidth of 50000Kbps Fri 12/29 8:24:23.936 MiniPlayer is using Push mode playback Fri 12/29 8:24:23.936 MiniPlayer is using the MP3 stream wrapper Fri 12/29 8:24:23.938 Creating Mpeg2Transcoder Fri 12/29 8:24:23.939 VIDEO FRAME EXCEPTION THROWN:java.lang.UnsatisfiedLinkError: no Mpeg2Transcoder in java.library.path java.lang.UnsatisfiedLinkError: no Mpeg2Transcoder in java.library.path Fri 12/29 8:24:23.943 at java.lang.ClassLoader.loadLibrary(Unknown Source) Fri 12/29 8:24:23.943 at java.lang.Runtime.loadLibrary0(Unknown Source) Fri 12/29 8:24:23.943 at java.lang.System.loadLibrary(Unknown Source) Fri 12/29 8:24:23.943 at sage.Mpeg2Transcoder.(Unknown Source) Fri 12/29 8:24:23.943 at sage.bt.load(Unknown Source) Fri 12/29 8:24:23.943 at sage.VideoFrame.a(Unknown Source) Fri 12/29 8:24:23.943 at sage.VideoFrame.a(Unknown Source) Fri 12/29 8:24:23.943 at sage.VideoFrame.run(Unknown Source) Fri 12/29 8:24:23.943 at java.lang.Thread.run(Unknown Source) Fri 12/29 8:24:23.943 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] ifn=null] nPlayin=false Fri 12/29 8:24:23.956 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] ifn=null] Fri 12/29 8:24:23.957 VF file=MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] targetTime = Fri 2/10 14:37:00.822 Fri 12/29 8:24:23.957 VideoFrame.timeSelected(Fri 2/10 14:37:00.822, true) currFile=MediaFile[id=4528073 A[4573718,4573717,"Now That's What I Call Christmas! 2 (Disc 1)",0@0210.14:37,4] host=sagetv encodedBy= format=MP3 0:04:07 128 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=Now That's What I Call Christmas! 2 (Disc 1), Duration=243178, ThumbnailOffset=1088, Genre=Holiday, Artist='N Sync, ThumbnailDesc=Cover Image, Title=I Don't Wanna Spend One More Christmas Without You, ThumbnailSize=41286, Track=3}] /var/media/music/Christmas Disc/Various/Now That's What I Call Christmas! 2 (Disc 2)/03 - I Don't Wanna Spend One More Christmas Without You.mp3, Seg0[Fri 2/10 14:37:00.822-Fri 2/10 14:41:04.000]] Fri 12/29 8:24:23.957 initDriver0() Fri 12/29 8:24:23.960 ImageUtils creating BI copy BufferedImage@1d135b6: type = 0 ColorModel: #pixelBits = 32 numComponents = 4 color space = java.awt.color.ICC_ColorSpace@c764e3 transparency = 3 has alpha = true isAlphaPre = false ByteInterleavedRaster: width = 64 height = 4 #numDataElements 4 dataOff[0] = 0 w=64 h=4 freeMem=53245056 totalMem=166526976 Fri 12/29 8:24:23.979 ImageUtils creating scaled masked copy width=59 height=3 mask=null BufferedImage@1813ee4: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 64 height = 4 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0 Fri 12/29 8:24:23.987 Premultiplying alpha for BuffImage... Fri 12/29 8:24:23.988 Done Premultiplying alpha for BuffImage... Fri 12/29 8:24:23.990 ImageUtils loading file /opt/sagetv/server/STVs/SageTV3/OSDVolSlider.png Fri 12/29 8:24:23.991 ImageUtils creating BI copy BufferedImage@1a05ea4: type = 0 ColorModel: #pixelBits = 32 numComponents = 4 color space = java.awt.color.ICC_ColorSpace@c764e3 transparency = 3 has alpha = true isAlphaPre = false ByteInterleavedRaster: width = 9 height = 15 #numDataElements 4 dataOff[0] = 0 w=9 h=15 freeMem=53175104 totalMem=166526976 Fri 12/29 8:24:23.993 ImageUtils creating scaled masked copy width=6 height=9 mask=null BufferedImage@159b14b: type = 2 DirectColorModel: rmask=ff0000 gmask=ff00 bmask=ff amask=ff000000 IntegerInterleavedRaster: width = 9 height = 15 #Bands = 4 xOff = 0 yOff = 0 dataOffset[0] 0 Fri 12/29 8:24:23.994 Premultiplying alpha for BuffImage... Fri 12/29 8:24:23.994 Done Premultiplying alpha for BuffImage... Fri 12/29 8:24:23.996 Loading 31x27 image from file /opt/sagetv/server/STVs/SageTV3/MusicTrackPrev.png Fri 12/29 8:24:24.010 Loading 31x27 image from file /opt/sagetv/server/STVs/SageTV3/MusicTrackNext.png Fri 12/29 8:24:24.015 Loading 31x27 image from file /opt/sagetv/server/STVs/SageTV3/MusicVisuals.png Fri 12/29 8:24:24.578 getDuration : 0 sage.PlaybackException Fri 12/29 8:24:53.983 at sage.bt.load(Unknown Source) Fri 12/29 8:24:53.984 at sage.VideoFrame.a(Unknown Source) Fri 12/29 8:24:53.984 at sage.VideoFrame.a(Unknown Source) Fri 12/29 8:24:53.984 at sage.VideoFrame.run(Unknown Source) Fri 12/29 8:24:53.984 at java.lang.Thread.run(Unknown Source) Fri 12/29 8:24:53.984 VideFrame had an error loading the file. It must abort the file load. Fri 12/29 8:24:53.984 Closing down MiniPlayer Fri 12/29 8:24:53.984 closeDriver0() Fri 12/29 8:24:53.992 processOptionsMenu optionsMenu=default:null|OptionsMenu:ErrorMessage Fri 12/29 8:24:54.003 VF processing job null nPlayin=false Fri 12/29 8:24:54.003 VF thread is now waiting for 0:00:00.000 Fri 12/29 8:24:55.166 MiniUI got connection from Socket[addr=/10.0.0.91,port=4798,localport=31099] Fri 12/29 8:24:55.166 MiniPlayer is adding to its map:000dfe007e9f Fri 12/29 8:25:12.943 Ministry is waiting for 180 sec Fri 12/29 8:25:14.663 Updating Disk Space Bar Fri 12/29 8:25:14.684 Got used/avail video diskspace Fri 12/29 8:25:14.684 Calculate size of partial recordings Fri 12/29 8:25:14.691 Found 2 partials using 4225730432 bytes Fri 12/29 8:25:14.692 Calculate space required for upcomming manual/favourite airings for next 2 days and display Fri 12/29 8:25:14.777 VideoDiskspaceReqProportion: 0.9978180978715686 Fri 12/29 8:25:14.778 VideoDiskspaceFreeProportion: 0.9835131603153373 Fri 12/29 8:25:14.778 Disk Space Bar Update complete for UI context 000dfe000143 Fri 12/29 8:25:17.716 setUI(sage.bu@5d9809[Library]) histIdx=7 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@67d5fe[Configuration Wizard - Ask For Weather Setup], sage.bu@1f25e56[Main Menu], sage.bu@5d9809[Library], sage.bu@d19957[Browser - Music]] Fri 12/29 8:25:18.987 Updating Disk Space Bar Fri 12/29 8:25:19.008 Got used/avail video diskspace Fri 12/29 8:25:19.008 Calculate size of partial recordings Fri 12/29 8:25:19.016 Found 2 partials using 4225730432 bytes Fri 12/29 8:25:19.016 Calculate space required for upcomming manual/favourite airings for next 2 days and display Fri 12/29 8:25:19.101 VideoDiskspaceReqProportion: 0.9978180978715686 Fri 12/29 8:25:19.101 VideoDiskspaceFreeProportion: 0.9835131603153373 Fri 12/29 8:25:19.101 Disk Space Bar Update complete for UI context 000dfe007e9f Fri 12/29 8:25:20.799 processOptionsMenu optionsMenu=default:null|OptionsMenu:File Browser Options Fri 12/29 8:25:23.483 processOptionsMenu optionsMenu=default:null|OptionsMenu:CenteredFileBrower Fri 12/29 8:25:23.531 Loading 174x33 image from file /opt/sagetv/server/STVs/SageTV3/MenuBarShort.png Fri 12/29 8:25:23.775 Loading 35x25 image from file /opt/sagetv/server/STVs/SageTV3/folder.png Fri 12/29 8:25:26.080 processOptionsMenu optionsMenu=default:null|OptionsMenu:CenteredFileBrower Fri 12/29 8:25:27.817 Loading 20x17 image from file /opt/sagetv/server/STVs/SageTV3/down.png Fri 12/29 8:25:47.453 Couldn't find MPEG audio syncword...reading more Fri 12/29 8:25:47.454 Couldn't find MPEG audio syncword...reading more Fri 12/29 8:25:47.455 Couldn't find MPEG audio syncword...reading more Fri 12/29 8:25:47.464 Couldn't find MPEG audio syncword...reading more Fri 12/29 8:25:47.464 Couldn't find MPEG audio syncword...reading more Fri 12/29 8:25:47.465 Couldn't find MPEG audio syncword...reading more Fri 12/29 8:25:47.465 Couldn't find MPEG audio syncword...reading more Fri 12/29 8:25:47.465 Couldn't find MPEG audio syncword...reading more Fri 12/29 8:25:47.465 Mpegaudio frames=7472 xing theDur2=194272 Fri 12/29 8:25:47.466 File Format Parsed /var/media/music.junk/01-Stand.mp3=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] Fri 12/29 8:25:47.495 VideoFrame.watch(A[4581156,4581061,"0304",0@1226.13:29,3]) Fri 12/29 8:25:47.495 watchThisFile=MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] Fri 12/29 8:25:47.495 Watch airing is a file & over, do that instead dvd=false Fri 12/29 8:25:47.496 Seeker.finishWatch(UIManager:localhost@@000dfe007e9f-12b2a18) Fri 12/29 8:25:47.496 VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] ifn=null]) Fri 12/29 8:25:47.497 Seeker awoken Fri 12/29 8:25:47.498 MARK 1 currRecord=null enc=video2 clients=[] ir=true Fri 12/29 8:25:47.498 defaultRecord=A[4488309,4488278,"Andy Hardy's Private Secretary",12852@1229.07:00,120] Fri 12/29 8:25:47.498 Seeker in AUTOMATIC mode nextRecord=A[4486505,4429885,"The Daily Show With Jon Stewart",10149@1229.09:00,30] nextTTA=2052502 Fri 12/29 8:25:47.498 newRecord=null Fri 12/29 8:25:47.498 NOTHING TO RECORD FOR NOW... Fri 12/29 8:25:47.498 MARK 1 currRecord=null enc=video0 clients=[] ir=true Fri 12/29 8:25:47.498 defaultRecord=A[4486238,4486205,"24",10035@1229.08:00,60] Fri 12/29 8:25:47.498 Seeker in AUTOMATIC mode nextRecord=A[4488303,4071223,"That Touch of Mink",12852@1229.17:00,120] nextTTA=30852502 Fri 12/29 8:25:47.499 newRecord=null Fri 12/29 8:25:47.499 NOTHING TO RECORD FOR NOW... Fri 12/29 8:25:47.499 MARK 1 currRecord=null enc=video1 clients=[] ir=true Fri 12/29 8:25:47.499 defaultRecord=A[4487483,2359993,"Jump Start",11218@1229.05:00,240] Fri 12/29 8:25:47.499 Seeker in AUTOMATIC mode nextRecord=A[4491157,4460858,"Ugly Betty",10093@1231.11:00,60] nextTTA=182052502 Fri 12/29 8:25:47.499 newRecord=null Fri 12/29 8:25:47.499 NOTHING TO RECORD FOR NOW... Fri 12/29 8:25:47.499 MARK 1 currRecord=null enc=video3 clients=[] ir=true Fri 12/29 8:25:47.499 defaultRecord=A[4488369,4484264,"Spin City",14321@1229.08:00,30] Fri 12/29 8:25:47.499 Seeker in AUTOMATIC mode nextRecord=A[4499697,4499659,"Haulin' House",14902@1231.19:00,30] nextTTA=210852502 Fri 12/29 8:25:47.499 newRecord=null Fri 12/29 8:25:47.499 NOTHING TO RECORD FOR NOW... Fri 12/29 8:25:47.500 Scheduler awoken Fri 12/29 8:25:47.502 Scheduler.updateSchedule() called time=Fri 12/29 8:25:47.500 manual=[ManualRecord[A[4041419,4037735,"Lost Worlds",14771@0904.20:00,60] time=Mon 9/4 20:00:00.000 dur=1:00:00], ManualRecord[A[4326709,4322733,"South Park",10149@1116.21:00,30] time=Thu 11/16 20:56:00.000 dur=0:38:00], ManualRecord[A[4443302,4443281,"The Simpsons",11658@1217.19:00,30] time=Sun 12/17 19:00:00.000 dur=0:30:00], ManualRecord[A[4443310,4443289,"Family Guy",11658@1217.20:00,30] time=Sun 12/17 20:00:00.000 dur=0:30:00]] schedules={video2=[Sched=[A[4486505,4429885,"The Daily Show With Jon Stewart",10149@1229.09:00,30], A[4486521,4429891,"The Colbert Report",10149@1229.09:30,30], A[4507209,4507106,"SpongeBob SquarePants",11006@1229.17:30,15], A[4507210,4507107,"SpongeBob SquarePants",11006@1229.17:45,15], A[4507211,4507108,"SpongeBob SquarePants",11006@1229.18:00,15], A[4507212,4507109,"SpongeBob SquarePants",11006@1229.18:30,15], A[4507213,4507110,"SpongeBob SquarePants",11006@1229.18:45,15], A[4507214,4507111,"SpongeBob SquarePants",11006@1229.19:30,15], A[4507215,4507112,"SpongeBob SquarePants",11006@1229.19:45,15], A[4493266,4493227,"House",11207@1229.22:00,60], A[4494102,2587785,"Family Guy",11867@1229.23:30,30], A[4494105,4044875,"Futurama",11867@1230.02:30,30], A[4492034,4421901,"The E! True Hollywood Story",10989@1230.06:00,120], A[4492724,4492692,"Clean Sweep",11158@1230.08:00,60], A[4494860,4494835,"Washington's Generals",14771@1230.11:00,60], A[4494862,4494837,"Washington's Generals",14771@1230.13:00,60], A[4494686,4033108,"Bedazzled",14321@1230.15:00,120], A[4494884,4494841,"Blood Diamonds",14771@1230.17:00,120], A[4492708,4492681,"Trading Spaces",11158@1230.21:00,60], A[4493767,4493741,"Saturday Night Live",11459@1230.22:30,91], A[4493766,4493740,"Saturday Night Live",11459@1231.03:01,89], A[4490869,4352272,"Breakfast With the Arts",10035@1231.07:00,120], A[4490918,4278346,"Child Stars III: Teen Rockers",10035@1231.09:00,120], A[4496187,4496131,"Heavyweight Solutions",18327@1231.11:00,60], A[4494117,4494066,"Ace Ventura: When Nature Calls",11867@1231.12:00,120], A[4490921,4429589,"Deceiving Innocence: The Roger Coleman Story",10035@1231.15:00,60], A[4492642,4319440,"Everest: Beyond the Limit",11150@1231.16:00,60], A[4491258,4491208,"Hee Haw",10138@1231.17:00,60], A[4497538,4497508,"Hee Haw",10138@1231.18:00,60], A[4497541,4497511,"Hee Haw",10138@1231.19:00,60], A[4499002,3733120,"Family Guy",11658@1231.20:00,30], A[4497626,3763545,"South Park",10149@1231.21:00,30], A[4499679,4499645,"Divine Design",14902@1231.21:30,30], A[4497628,3336341,"South Park",10149@1231.22:00,30], A[4499195,4499144,"Metalocalypse",12131@1231.22:30,15], A[4497630,3150546,"South Park",10149@1231.23:00,30], A[4497539,4497509,"Hee Haw",10138@0101.00:00,60], A[4499596,4499584,"The History of Sex",14771@0101.01:00,60], A[4499402,4499314,"A Night in Casablanca",12852@0101.03:00,90], A[4499192,4499142,"Metalocalypse",12131@0101.04:30,15], A[4499194,4499143,"Metalocalypse",12131@0101.04:45,15], A[4499489,4499451,"Like Mike",14321@0101.08:00,120]] MustSee=[A[4486505,4429885,"The Daily Show With Jon Stewart",10149@1229.09:00,30], A[4486521,4429891,"The Colbert Report",10149@1229.09:30,30], A[4507209,4507106,"SpongeBob SquarePants",11006@1229.17:30,15], A[4507210,4507107,"SpongeBob SquarePants",11006@1229.17:45,15], A[4507211,4507108,"SpongeBob SquarePants",11006@1229.18:00,15], A[4507212,4507109,"SpongeBob SquarePants",11006@1229.18:30,15], A[4507213,4507110,"SpongeBob SquarePants",11006@1229.18:45,15], A[4507214,4507111,"SpongeBob SquarePants",11006@1229.19:30,15], A[4507215,4507112,"SpongeBob SquarePants",11006@1229.19:45,15]]], video0=[Sched=[A[4488303,4071223,"That Touch of Mink",12852@1229.17:00,120], A[4494765,4082893,"South Park",14758@1229.22:00,30], A[4494857,4494831,"Washington's Generals",14771@1230.08:00,60], A[4494682,4494647,"The Wrong Guy",14321@1230.11:00,120], A[4495668,4495639,"Super Size Me",16300@1230.20:00,120], A[4495666,4495638,"Crime & Punishment",16300@1230.22:00,60], A[4490795,4490624,"Sunday Morning Shootout",10021@1231.04:00,30], A[4491156,4487638,"Ugly Betty",10093@1231.10:00,60], A[4496545,4428970,"Janice: Christmas With the Dickinsons",21484@1231.11:00,60], A[4491159,4491083,"Ugly Betty",10093@1231.13:00,60], A[4491161,4491088,"Ugly Betty",10093@1231.15:00,60], A[4492643,4437096,"Everest: Beyond the Limit",11150@1231.17:00,60], A[4498384,4498369,"Everest: Beyond the Limit",11150@1231.18:00,60], A[4498451,4498422,"Resolutionaries",11158@1231.19:00,60], A[4499593,4499583,"The History of Sex",14771@1231.20:00,60], A[4499175,4499134,"Metalocalypse",12131@1231.21:00,15], A[4499177,4499135,"Metalocalypse",12131@1231.21:15,15], A[4497364,4497354,"106 & Party Special",10051@1231.22:00,120], A[4499202,4499148,"Metalocalypse",12131@0101.00:45,15], A[4500899,4500894,"The E! True Hollywood Story",18511@0101.01:00,60]] MustSee=[]], video1=[Sched=[A[4491157,4460858,"Ugly Betty",10093@1231.11:00,60], A[4491163,4491092,"Ugly Betty",10093@1231.17:00,60], A[4497362,4490935,"Notarized: Top 100 Video Countdown",10051@1231.18:00,240], A[4499197,4499145,"Metalocalypse",12131@1231.22:00,15], A[4499183,4499138,"Metalocalypse",12131@1231.22:15,15], A[4499187,4499140,"Metalocalypse",12131@1231.23:00,15], A[4499189,4499141,"Metalocalypse",12131@1231.23:15,15], A[4498456,4498425,"Resolutionaries",11158@0101.00:00,60], A[4497540,4497510,"Hee Haw",10138@0101.01:00,60]] MustSee=[]], video3=[Sched=[A[4499697,4499659,"Haulin' House",14902@1231.19:00,30], A[4497537,4497507,"Hee Haw",10138@1231.23:00,60], A[4499204,4499149,"Metalocalypse",12131@0101.01:15,15]] MustSee=[]]} scheduleRandSize=66 Fri 12/29 8:25:47.555 VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] ifn=null] nPlayin=false Fri 12/29 8:25:47.556 VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] ifn=null] Fri 12/29 8:25:47.556 VideoFrame creating new media player for file:MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] Fri 12/29 8:25:47.676 # Airs=458 Fri 12/29 8:25:47.689 MUST SEE FINAL-video2-[A[4486505,4429885,"The Daily Show With Jon Stewart",10149@1229.09:00,30], A[4486521,4429891,"The Colbert Report",10149@1229.09:30,30], A[4507209,4507106,"SpongeBob SquarePants",11006@1229.17:30,15], A[4507210,4507107,"SpongeBob SquarePants",11006@1229.17:45,15], A[4507211,4507108,"SpongeBob SquarePants",11006@1229.18:00,15], A[4507212,4507109,"SpongeBob SquarePants",11006@1229.18:30,15], A[4507213,4507110,"SpongeBob SquarePants",11006@1229.18:45,15], A[4507214,4507111,"SpongeBob SquarePants",11006@1229.19:30,15], A[4507215,4507112,"SpongeBob SquarePants",11006@1229.19:45,15]] Fri 12/29 8:25:47.689 MUST SEE FINAL-video0-[] Fri 12/29 8:25:47.689 MUST SEE FINAL-video1-[] Fri 12/29 8:25:47.689 MUST SEE FINAL-video3-[] Fri 12/29 8:25:47.693 Evaluating Potentials Fri 12/29 8:25:47.701 COMPLETE SCHEDULE-----**&^%&*-------COMPLETE SCHEDULE video2 Fri 12/29 8:25:47.703 [A[4486505,4429885,"The Daily Show With Jon Stewart",10149@1229.09:00,30], A[4486521,4429891,"The Colbert Report",10149@1229.09:30,30], A[4489244,4068551,"Other People's Money",18480@1229.15:26,107], A[4507209,4507106,"SpongeBob SquarePants",11006@1229.17:30,15], A[4507210,4507107,"SpongeBob SquarePants",11006@1229.17:45,15], A[4507211,4507108,"SpongeBob SquarePants",11006@1229.18:00,15], A[4507212,4507109,"SpongeBob SquarePants",11006@1229.18:30,15], A[4507213,4507110,"SpongeBob SquarePants",11006@1229.18:45,15], A[4507214,4507111,"SpongeBob SquarePants",11006@1229.19:30,15], A[4507215,4507112,"SpongeBob SquarePants",11006@1229.19:45,15], A[4493266,4493227,"House",11207@1229.22:00,60], A[4494102,2587785,"Family Guy",11867@1229.23:30,30], A[4494105,4044875,"Futurama",11867@1230.02:30,30], A[4490919,4490856,"My Husband's Secret",10035@1230.03:00,60], A[4492034,4421901,"The E! True Hollywood Story",10989@1230.06:00,120], A[4492724,4492692,"Clean Sweep",11158@1230.08:00,60], A[4492723,4492691,"Clean Sweep",11158@1230.09:00,60], A[4494860,4494835,"Washington's Generals",14771@1230.11:00,60], A[4496105,4361174,"Freedom Music",17098@1230.12:00,60], A[4494862,4494837,"Washington's Generals",14771@1230.13:00,60], A[4494686,4033108,"Bedazzled",14321@1230.15:00,120], A[4494884,4494841,"Blood Diamonds",14771@1230.17:00,120], A[4492708,4492681,"Trading Spaces",11158@1230.21:00,60], A[4493767,4493741,"Saturday Night Live",11459@1230.22:30,91], A[4493766,4493740,"Saturday Night Live",11459@1231.03:01,89], A[4490869,4352272,"Breakfast With the Arts",10035@1231.07:00,120], A[4490918,4278346,"Child Stars III: Teen Rockers",10035@1231.09:00,120], A[4496187,4496131,"Heavyweight Solutions",18327@1231.11:00,60], A[4494117,4494066,"Ace Ventura: When Nature Calls",11867@1231.12:00,120], A[4491160,4491085,"Ugly Betty",10093@1231.14:00,60], A[4490921,4429589,"Deceiving Innocence: The Roger Coleman Story",10035@1231.15:00,60], A[4492642,4319440,"Everest: Beyond the Limit",11150@1231.16:00,60], A[4491258,4491208,"Hee Haw",10138@1231.17:00,60], A[4497538,4497508,"Hee Haw",10138@1231.18:00,60], A[4497541,4497511,"Hee Haw",10138@1231.19:00,60], A[4499002,3733120,"Family Guy",11658@1231.20:00,30], A[4497626,3763545,"South Park",10149@1231.21:00,30], A[4499679,4499645,"Divine Design",14902@1231.21:30,30], A[4497628,3336341,"South Park",10149@1231.22:00,30], A[4499195,4499144,"Metalocalypse",12131@1231.22:30,15], A[4497630,3150546,"South Park",10149@1231.23:00,30], A[4497631,2535755,"South Park",10149@1231.23:30,30], A[4497539,4497509,"Hee Haw",10138@0101.00:00,60], A[4499596,4499584,"The History of Sex",14771@0101.01:00,60], A[4499180,4499136,"Metalocalypse",12131@0101.02:30,15], A[4499402,4499314,"A Night in Casablanca",12852@0101.03:00,90], A[4499192,4499142,"Metalocalypse",12131@0101.04:30,15], A[4499194,4499143,"Metalocalypse",12131@0101.04:45,15], A[4499489,4499451,"Like Mike",14321@0101.08:00,120]] video0 Fri 12/29 8:25:47.704 [A[4488303,4071223,"That Touch of Mink",12852@1229.17:00,120], A[4494765,4082893,"South Park",14758@1229.22:00,30], A[4494764,2739780,"South Park",14758@1229.22:30,30], A[4494857,4494831,"Washington's Generals",14771@1230.08:00,60], A[4494858,4494833,"Washington's Generals",14771@1230.09:00,60], A[4494682,4494647,"The Wrong Guy",14321@1230.11:00,120], A[4495668,4495639,"Super Size Me",16300@1230.20:00,120], A[4495666,4495638,"Crime & Punishment",16300@1230.22:00,60], A[4490795,4490624,"Sunday Morning Shootout",10021@1231.04:00,30], A[4492035,4306102,"The E! True Hollywood Story",10989@1231.07:00,60], A[4491154,4138259,"Ugly Betty",10093@1231.08:00,60], A[4491155,4491076,"Ugly Betty",10093@1231.09:00,60], A[4491156,4487638,"Ugly Betty",10093@1231.10:00,60], A[4496545,4428970,"Janice: Christmas With the Dickinsons",21484@1231.11:00,60], A[4491159,4491083,"Ugly Betty",10093@1231.13:00,60], A[4491161,4491088,"Ugly Betty",10093@1231.15:00,60], A[4492643,4437096,"Everest: Beyond the Limit",11150@1231.17:00,60], A[4498384,4498369,"Everest: Beyond the Limit",11150@1231.18:00,60], A[4498451,4498422,"Resolutionaries",11158@1231.19:00,60], A[4499593,4499583,"The History of Sex",14771@1231.20:00,60], A[4499175,4499134,"Metalocalypse",12131@1231.21:00,15], A[4499177,4499135,"Metalocalypse",12131@1231.21:15,15], A[4497627,2467155,"South Park",10149@1231.21:30,30], A[4497364,4497354,"106 & Party Special",10051@1231.22:00,120], A[4499199,4499146,"Metalocalypse",12131@0101.00:00,15], A[4499200,4499147,"Metalocalypse",12131@0101.00:15,15], A[4499201,4324298,"Metalocalypse",12131@0101.00:30,15], A[4499202,4499148,"Metalocalypse",12131@0101.00:45,15], A[4500899,4500894,"The E! True Hollywood Story",18511@0101.01:00,60]] video1 Fri 12/29 8:25:47.704 [A[4494690,4075386,"Kung Pow: Enter the Fist",14321@1230.07:00,120], A[4511348,4362294,"On & Off: Lil Wayne",10051@1230.11:30,30], A[4494861,4494836,"Washington's Generals",14771@1230.12:00,60], A[4492032,4301256,"Saturday Night Live",10989@1230.21:00,60], A[4491157,4460858,"Ugly Betty",10093@1231.11:00,60], A[4491163,4491092,"Ugly Betty",10093@1231.17:00,60], A[4497362,4490935,"Notarized: Top 100 Video Countdown",10051@1231.18:00,240], A[4499197,4499145,"Metalocalypse",12131@1231.22:00,15], A[4499183,4499138,"Metalocalypse",12131@1231.22:15,15], A[4499187,4499140,"Metalocalypse",12131@1231.23:00,15], A[4499189,4499141,"Metalocalypse",12131@1231.23:15,15], A[4498456,4498425,"Resolutionaries",11158@0101.00:00,60], A[4497540,4497510,"Hee Haw",10138@0101.01:00,60]] video3 Fri 12/29 8:25:47.704 [A[4499697,4499659,"Haulin' House",14902@1231.19:00,30], A[4497537,4497507,"Hee Haw",10138@1231.23:00,60], A[4499710,4499668,"Stress Test",14902@0101.00:00,60], A[4499203,4364103,"Metalocalypse",12131@0101.01:00,15], A[4499204,4499149,"Metalocalypse",12131@0101.01:15,15]] Fri 12/29 8:25:47.705 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] ifn=null] nPlayin=false Fri 12/29 8:25:47.705 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] ifn=null] Fri 12/29 8:25:47.705 VF file=MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] targetTime = Tue 12/26 13:29:40.728 Fri 12/29 8:25:47.706 VideoFrame.timeSelected(Tue 12/26 13:29:40.728, true) currFile=MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] Fri 12/29 8:25:47.706 initDriver0() Fri 12/29 8:25:47.706 MiniPlayer established for 000dfe007e9f Fri 12/29 8:25:47.723 Saving properties file to Sage.properties Fri 12/29 8:25:47.730 VF processing job null nPlayin=false Fri 12/29 8:25:47.730 VF thread is now waiting for 0:00:00.000 Fri 12/29 8:25:47.730 Saving properties file to clients/000dfe000143.properties Fri 12/29 8:25:47.731 Saving properties file to clients/000dfe007e9f.properties Fri 12/29 8:25:47.733 EVPoolSize=6 Fri 12/29 8:25:47.742 Checking video directories for new files Fri 12/29 8:25:47.773 Diskspace checking is running Fri 12/29 8:25:47.774 Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Fri 12/29 8:25:47.786 Enforcing keep at most limits for the files... Fri 12/29 8:25:47.927 Seeker waiting for 29 mins. Fri 12/29 8:25:47.935 Seeker awoken Fri 12/29 8:25:47.937 MARK 1 currRecord=null enc=video2 clients=[] ir=true Fri 12/29 8:25:47.938 defaultRecord=A[4488309,4488278,"Andy Hardy's Private Secretary",12852@1229.07:00,120] Fri 12/29 8:25:47.938 Seeker in AUTOMATIC mode nextRecord=A[4486505,4429885,"The Daily Show With Jon Stewart",10149@1229.09:00,30] nextTTA=2052063 Fri 12/29 8:25:47.938 newRecord=null Fri 12/29 8:25:47.938 NOTHING TO RECORD FOR NOW... Fri 12/29 8:25:47.938 MARK 1 currRecord=null enc=video0 clients=[] ir=true Fri 12/29 8:25:47.938 defaultRecord=A[4486238,4486205,"24",10035@1229.08:00,60] Fri 12/29 8:25:47.938 Seeker in AUTOMATIC mode nextRecord=A[4488303,4071223,"That Touch of Mink",12852@1229.17:00,120] nextTTA=30852063 Fri 12/29 8:25:47.938 newRecord=null Fri 12/29 8:25:47.938 NOTHING TO RECORD FOR NOW... Fri 12/29 8:25:47.938 MARK 1 currRecord=null enc=video1 clients=[] ir=true Fri 12/29 8:25:47.938 defaultRecord=A[4487483,2359993,"Jump Start",11218@1229.05:00,240] Fri 12/29 8:25:47.938 Seeker in AUTOMATIC mode nextRecord=A[4494690,4075386,"Kung Pow: Enter the Fist",14321@1230.07:00,120] nextTTA=81252063 Fri 12/29 8:25:47.939 newRecord=null Fri 12/29 8:25:47.939 NOTHING TO RECORD FOR NOW... Fri 12/29 8:25:47.939 MARK 1 currRecord=null enc=video3 clients=[] ir=true Fri 12/29 8:25:47.939 defaultRecord=A[4488369,4484264,"Spin City",14321@1229.08:00,30] Fri 12/29 8:25:47.939 Seeker in AUTOMATIC mode nextRecord=A[4499697,4499659,"Haulin' House",14902@1231.19:00,30] nextTTA=210852063 Fri 12/29 8:25:47.939 newRecord=null Fri 12/29 8:25:47.939 NOTHING TO RECORD FOR NOW... Fri 12/29 8:25:47.955 VF processing job null nPlayin=false Fri 12/29 8:25:47.955 VF thread is now waiting for 0:00:00.000 Fri 12/29 8:25:47.955 Checking video directories for new files Fri 12/29 8:25:47.982 Diskspace checking is running Fri 12/29 8:25:47.982 Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Fri 12/29 8:25:48.005 Enforcing keep at most limits for the files... Fri 12/29 8:25:48.151 Seeker waiting for 29 mins. Fri 12/29 8:25:48.161 MiniPlayer got an estimate from the UI on bandwidth of 50000Kbps Fri 12/29 8:25:48.162 MiniPlayer is using Push mode playback Fri 12/29 8:25:48.162 MiniPlayer is using the MP3 stream wrapper Fri 12/29 8:25:48.162 Creating Mpeg2Transcoder Fri 12/29 8:25:48.162 VIDEO FRAME EXCEPTION THROWN:java.lang.UnsatisfiedLinkError: no Mpeg2Transcoder in java.library.path java.lang.UnsatisfiedLinkError: no Mpeg2Transcoder in java.library.path Fri 12/29 8:25:48.162 at java.lang.ClassLoader.loadLibrary(Unknown Source) Fri 12/29 8:25:48.162 at java.lang.Runtime.loadLibrary0(Unknown Source) Fri 12/29 8:25:48.162 at java.lang.System.loadLibrary(Unknown Source) Fri 12/29 8:25:48.162 at sage.Mpeg2Transcoder.(Unknown Source) Fri 12/29 8:25:48.162 at sage.bt.load(Unknown Source) Fri 12/29 8:25:48.162 at sage.VideoFrame.a(Unknown Source) Fri 12/29 8:25:48.162 at sage.VideoFrame.a(Unknown Source) Fri 12/29 8:25:48.162 at sage.VideoFrame.run(Unknown Source) Fri 12/29 8:25:48.162 at java.lang.Thread.run(Unknown Source) Fri 12/29 8:25:48.163 VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] ifn=null] nPlayin=false Fri 12/29 8:25:48.163 VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] ifn=null] Fri 12/29 8:25:48.163 VF file=MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] targetTime = Tue 12/26 13:29:40.728 Fri 12/29 8:25:48.163 VideoFrame.timeSelected(Tue 12/26 13:29:40.728, true) currFile=MediaFile[id=0 A[4581156,4581061,"0304",0@1226.13:29,3] host=sagetv encodedBy= format=MP3 0:03:17 127 kbps [#0 Audio[MP3 44100 Hz 2 channels 128 kbps idx=0]{Album=0304, Year=2003, Duration=194272, ThumbnailOffset=18040, Genre=Pop, Artist=Jewel, ThumbnailDesc=Cover Image, Title=Stand, ThumbnailSize=16928, Track=1}] /var/media/music.junk/01-Stand.mp3, Seg0[Tue 12/26 13:29:40.728-Tue 12/26 13:32:55.000]] Fri 12/29 8:25:48.164 initDriver0() Fri 12/29 8:25:48.391 Seeker is starting the library import scan... Fri 12/29 8:26:15.063 Updating Disk Space Bar Fri 12/29 8:26:15.084 Got used/avail video diskspace Fri 12/29 8:26:15.084 Calculate size of partial recordings Fri 12/29 8:26:15.092 Found 2 partials using 4225730432 bytes Fri 12/29 8:26:15.092 Calculate space required for upcomming manual/favourite airings for next 2 days and display Fri 12/29 8:26:15.226 VideoDiskspaceReqProportion: 0.9978180978715686 Fri 12/29 8:26:15.226 VideoDiskspaceFreeProportion: 0.9835131603153373 Fri 12/29 8:26:15.227 Disk Space Bar Update complete for UI context 000dfe000143 sage.PlaybackException Fri 12/29 8:26:18.191 at sage.bt.load(Unknown Source) Fri 12/29 8:26:18.191 at sage.VideoFrame.a(Unknown Source) Fri 12/29 8:26:18.191 at sage.VideoFrame.a(Unknown Source) Fri 12/29 8:26:18.191 at sage.VideoFrame.run(Unknown Source) Fri 12/29 8:26:18.191 at java.lang.Thread.run(Unknown Source) Fri 12/29 8:26:18.192 VideFrame had an error loading the file. It must abort the file load. Fri 12/29 8:26:18.192 Closing down MiniPlayer Fri 12/29 8:26:18.192 closeDriver0() Fri 12/29 8:26:18.196 processOptionsMenu optionsMenu=default:null|OptionsMenu:ErrorMessage Fri 12/29 8:26:18.203 VF processing job null nPlayin=false Fri 12/29 8:26:18.203 VF thread is now waiting for 0:00:00.000 Fri 12/29 8:26:19.379 MiniUI got connection from Socket[addr=/10.0.0.91,port=4799,localport=31099] Fri 12/29 8:26:19.379 MiniPlayer is adding to its map:000dfe007e9f Fri 12/29 8:26:19.407 Updating Disk Space Bar Fri 12/29 8:26:19.428 Got used/avail video diskspace Fri 12/29 8:26:19.428 Calculate size of partial recordings Fri 12/29 8:26:19.436 Found 2 partials using 4225730432 bytes Fri 12/29 8:26:19.436 Calculate space required for upcomming manual/favourite airings for next 2 days and display Fri 12/29 8:26:19.522 VideoDiskspaceReqProportion: 0.9978180978715686 Fri 12/29 8:26:19.522 VideoDiskspaceFreeProportion: 0.9835131603153373 Fri 12/29 8:26:19.522 Disk Space Bar Update complete for UI context 000dfe007e9f Fri 12/29 8:27:03.528 setUI(sage.bu@1f25e56[Main Menu]) histIdx=8 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@67d5fe[Configuration Wizard - Ask For Weather Setup], sage.bu@1f25e56[Main Menu], sage.bu@5d9809[Library], sage.bu@1f25e56[Main Menu]] Fri 12/29 8:27:09.255 setUI(sage.bu@5d9809[Library]) histIdx=7 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@67d5fe[Configuration Wizard - Ask For Weather Setup], sage.bu@1f25e56[Main Menu], sage.bu@5d9809[Library], sage.bu@1f25e56[Main Menu]] Fri 12/29 8:27:15.495 Updating Disk Space Bar Fri 12/29 8:27:15.517 Got used/avail video diskspace Fri 12/29 8:27:15.517 Calculate size of partial recordings Fri 12/29 8:27:15.525 Found 2 partials using 4225730432 bytes Fri 12/29 8:27:15.525 Calculate space required for upcomming manual/favourite airings for next 2 days and display Fri 12/29 8:27:15.611 VideoDiskspaceReqProportion: 0.9978180978715686 Fri 12/29 8:27:15.612 VideoDiskspaceFreeProportion: 0.9835131603153373 Fri 12/29 8:27:15.612 Disk Space Bar Update complete for UI context 000dfe000143 Fri 12/29 8:27:19.783 Updating Disk Space Bar Fri 12/29 8:27:19.804 Got used/avail video diskspace Fri 12/29 8:27:19.804 Calculate size of partial recordings Fri 12/29 8:27:19.813 Found 2 partials using 4225730432 bytes Fri 12/29 8:27:19.813 Calculate space required for upcomming manual/favourite airings for next 2 days and display Fri 12/29 8:27:19.900 VideoDiskspaceReqProportion: 0.9978180978715686 Fri 12/29 8:27:19.901 VideoDiskspaceFreeProportion: 0.9835131603153373 Fri 12/29 8:27:19.901 Disk Space Bar Update complete for UI context 000dfe007e9f Fri 12/29 8:27:21.181 setUI(sage.bu@1f25e56[Main Menu]) histIdx=8 uiHistory=[sage.bu@900608[Configuration Wizard - Choose Language], sage.bu@bdadc[Configuration Wizard - Adjust UI Overscan Part 1], sage.bu@1f8aa92[Configuration Wizard - Adjust UI Overscan Part 2], sage.bu@f80604[Configuration Wizard - Adjust Display Aspect Ratio], sage.bu@1cc9008[Configuration Wizard - Ask Display Videos on Menus], sage.bu@67d5fe[Configuration Wizard - Ask For Weather Setup], sage.bu@1f25e56[Main Menu], sage.bu@5d9809[Library], sage.bu@1f25e56[Main Menu]] Fri 12/29 8:27:45.092 SageTV SHUTDOWN is activating! Fri 12/29 8:27:45.096 Sage.exit() called. Fri 12/29 8:27:45.096 Cleaning up servers Fri 12/29 8:27:45.097 Error w/SageTV client connection:java.net.SocketException: Socket closed Fri 12/29 8:27:45.098 MiniError-2:java.net.SocketException: Socket closed Fri 12/29 8:27:45.098 MiniError-3:java.net.SocketException: Socket closed Fri 12/29 8:27:45.099 MiniError-1:java.net.SocketException: Socket closed Fri 12/29 8:27:45.099 MiniUI ServerSocket died from:java.net.SocketException: Socket closed Fri 12/29 8:27:45.099 Cleaning up PM Fri 12/29 8:27:45.100 Killed Carny. Fri 12/29 8:27:45.100 Killed Scheduler. Fri 12/29 8:27:45.100 Error calling finishWatch from VF goodbye:java.lang.NullPointerException Fri 12/29 8:27:45.100 Seeker.finishWatch(UIManager:localhost@@000dfe007e9f-12b2a18) Fri 12/29 8:27:45.100 Seeker awoken Fri 12/29 8:27:45.101 MARK 1 currRecord=null enc=video2 clients=[] ir=true Fri 12/29 8:27:45.101 defaultRecord=A[4488309,4488278,"Andy Hardy's Private Secretary",12852@1229.07:00,120] Fri 12/29 8:27:45.101 Seeker in AUTOMATIC mode nextRecord=A[4486505,4429885,"The Daily Show With Jon Stewart",10149@1229.09:00,30] nextTTA=1934899 Fri 12/29 8:27:45.101 newRecord=null Fri 12/29 8:27:45.101 NOTHING TO RECORD FOR NOW... Fri 12/29 8:27:45.101 MARK 1 currRecord=null enc=video0 clients=[] ir=true Fri 12/29 8:27:45.101 defaultRecord=A[4486238,4486205,"24",10035@1229.08:00,60] Fri 12/29 8:27:45.102 Seeker in AUTOMATIC mode nextRecord=A[4488303,4071223,"That Touch of Mink",12852@1229.17:00,120] nextTTA=30734899 Fri 12/29 8:27:45.102 newRecord=null Fri 12/29 8:27:45.102 NOTHING TO RECORD FOR NOW... Fri 12/29 8:27:45.102 MARK 1 currRecord=null enc=video1 clients=[] ir=true Fri 12/29 8:27:45.102 defaultRecord=A[4487483,2359993,"Jump Start",11218@1229.05:00,240] Fri 12/29 8:27:45.102 Seeker in AUTOMATIC mode nextRecord=A[4494690,4075386,"Kung Pow: Enter the Fist",14321@1230.07:00,120] nextTTA=81134899 Fri 12/29 8:27:45.102 newRecord=null Fri 12/29 8:27:45.102 NOTHING TO RECORD FOR NOW... Fri 12/29 8:27:45.102 MARK 1 currRecord=null enc=video3 clients=[] ir=true Fri 12/29 8:27:45.103 defaultRecord=A[4488369,4484264,"Spin City",14321@1229.08:00,30] Fri 12/29 8:27:45.103 Seeker in AUTOMATIC mode nextRecord=A[4499697,4499659,"Haulin' House",14902@1231.19:00,30] nextTTA=210734899 Fri 12/29 8:27:45.103 newRecord=null Fri 12/29 8:27:45.103 NOTHING TO RECORD FOR NOW... Fri 12/29 8:27:45.118 VF processing job null nPlayin=false Fri 12/29 8:27:45.118 VideoFrame thread is now exiting Fri 12/29 8:27:45.118 VF processing job null nPlayin=false Fri 12/29 8:27:45.118 VF thread is now waiting for 0:00:00.000 Fri 12/29 8:27:45.118 Checking video directories for new files Fri 12/29 8:27:45.154 Diskspace checking is running Fri 12/29 8:27:45.154 Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Fri 12/29 8:27:45.160 Enforcing keep at most limits for the files...