Discovery Channel Failure: Failed OpenDCT Log: 23:18:01.544 [SageTVRequestHandler-747:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START DCT-HDHomeRun Prime Tuner 1311B773-1 Digital TV Tuner|1983960230|620|3010890963086|/var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts|Great' 23:18:01.545 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHRNativeCaptureDevice - Capture device is now re-locked. 23:18:01.545 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '1983960230' to file name '/var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts'. 23:18:01.545 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:18:01.546 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: '167772271' lockKey: '167772271' sendLength: 44 address: 10.0.0.24 23:18:01.547 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO HDHRNativeCaptureDevice - HDHomeRun is now locked. 23:18:01.547 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG RTPCaptureDeviceServices - Producer was not running. 23:18:01.547 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG BasicCaptureDevice - Consumer is not in progress. 23:18:01.547 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel '620' 23:18:01.549 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO HDHRNativeCaptureDevice - Starting the encoding for the channel '620' from the device 'DCT-HDHomeRun Prime Tuner 1311B773-1' to the file '/var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts' via the upload id '1983960230'... 23:18:01.549 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:18:01.549 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'none' lockKey: '167772271' sendLength: 39 address: 10.0.0.24 23:18:01.550 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:18:01.550 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOHTTPProducerImpl - Connecting to source using the URL 'http://10.0.0.24:5004/tuner1/v620' 23:18:02.011 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG NIOHttpDownloader - HTTP response: 'HTTP/1.1 200 OK', 'Server: HDHomeRun/1.0', 'Connection: close', 'Content-Type: video/mpeg', 'Cache-Control: no-cache', 'Pragma: no-cache', 'transferMode.dlna.org: Streaming', 'Date: Fri, 15 Sep 2017 03:18:01 GMT', 23:18:02.011 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/program' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:18:02.012 [NIOHTTPProducerImpl-755:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOHTTPProducerImpl - Producer thread is running. 23:18:02.012 [NIOHTTPProducerImpl-755:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG NIOHTTPProducerImpl - Thread priority is 9. 23:18:02.012 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO HDHRNativeCaptureDevice - Configuring and starting the new SageTV consumer... 23:18:02.012 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG BasicCaptureDevice - Consumer is not in progress. 23:18:02.012 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/vstatus' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:18:02.012 [MediaServerConsumerImpl-757:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO MediaServerConsumerImpl - MediaServer thread started. 23:18:02.012 [MediaServerConsumerImpl-757:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG MediaServerConsumerImpl - Thread priority is 8. 23:18:02.012 [MediaServerConsumerImpl-757:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO MediaServerConsumerImpl - Opening file via MediaServer... 23:18:02.012 [MediaServerConsumerImpl-757:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOSageTVMediaServer - Connecting to SageTV server on socket /10.0.0.111:7818... 23:18:02.013 [MediaServerConsumerImpl-757:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOSageTVMediaServer - Sending 'WRITEOPEN /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts 1983960230' to SageTV server... 23:18:02.013 [MediaServerConsumerImpl-757:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO MediaServerConsumerImpl - Setting up remuxing on MediaServer... 23:18:02.013 [MediaServerConsumerImpl-757:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOSageTVMediaServer - Sending 'REMUX_SETUP AUTO PS TRUE' to SageTV server... 23:18:02.015 [MediaServerConsumerImpl-757:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO MediaServerConsumerImpl - Media Server consumer is now streaming... 23:18:03.332 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG SageTVRequestHandler - Replied: 'OK' 23:18:03.396 [SageTVTuningMonitor-64] DEBUG HDHomeRunControl - key: '/tuner1/vstatus' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:18:19.433 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG SageTVRequestHandler - SageTV sent: 'STOP DCT-HDHomeRun Prime Tuner 1311B773-1 Digital TV Tuner' 23:18:19.434 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHRNativeCaptureDevice - Stopping encoding... 23:18:19.434 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HTTPCaptureDeviceServices - Stopping producer thread... 23:18:19.434 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HTTPCaptureDeviceServices - Waiting for producer thread to stop... 23:18:19.434 [NIOHTTPProducerImpl-755:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOHTTPProducerImpl - Producer thread has stopped. 23:18:19.434 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG BasicCaptureDevice - Stopping consumer thread... 23:18:19.434 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOSageTVMediaServer - Sending 'CLOSE' to SageTV server... 23:18:19.434 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOSageTVMediaServer - Sending 'QUIT' to SageTV server... 23:18:19.434 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG BasicCaptureDevice - Waiting for consumer thread to stop... 23:18:19.434 [MediaServerConsumerImpl-757:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG MediaServerConsumerImpl - MediaServer consumer was interrupted. 23:18:19.435 [MediaServerConsumerImpl-757:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO MediaServerConsumerImpl - MediaServer thread stopped. 23:18:19.435 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'force' lockKey: '-1' sendLength: 40 address: 10.0.0.24 23:18:19.435 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:18:19.436 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/channel' value: 'none' lockKey: '0' sendLength: 33 address: 10.0.0.24 23:18:19.436 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/target' value: 'none' lockKey: '0' sendLength: 32 address: 10.0.0.24 23:18:19.437 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:18:19.437 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO HDHRNativeCaptureDevice - HDHomeRun is now unlocked. 23:18:19.437 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHRNativeCaptureDevice - Capture device is now re-unlocked. 23:18:19.437 [SageTVRequestHandler-747:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG SageTVRequestHandler - Replied: 'OK' Failed SageTV Log: Thu 9/14 23:17:59.659 [EventRouter-bc5ff4355504@9ae2723] processUserEvent-UserEvent[anything] evtTime=Thu 9/14 23:17:59.659 Thu 9/14 23:17:59.660 [EventRouter-bc5ff4355504@9ae2723] processOptionsMenu optionsMenu=default:BASE-49221|OptionsMenu:Show Options for an Airing, Recording, File, etc. Thu 9/14 23:17:59.662 [EventRouter-bc5ff4355504@9ae2723] BEGIN: Sort video playlists. Thu 9/14 23:17:59.662 [EventRouter-bc5ff4355504@9ae2723] END: Sort video playlists. Sorted 1 playlists. Total Time = '0' Thu 9/14 23:18:00.010 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2011) Thu 9/14 23:18:01.533 [EventRouter-bc5ff4355504@9ae2723] processUserEvent-UserEvent[anything] evtTime=Thu 9/14 23:18:01.533 Thu 9/14 23:18:01.534 [EventRouter-bc5ff4355504@9ae2723] processOptionsMenu optionsMenu=default:BASE-49306|OptionsMenu:Preparing for video playback notice Thu 9/14 23:18:01.536 [ReProcessHook@1afb50a5] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null]) Thu 9/14 23:18:01.536 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false Thu 9/14 23:18:01.537 [VideoFrame-bc5ff4355504@4ed98461] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null] Thu 9/14 23:18:01.537 [VideoFrame-bc5ff4355504@4ed98461] VF processing job null nPlayin=false Thu 9/14 23:18:01.537 [ReProcessHook@1afb50a5] Seeker.finishWatch(UIManager:localhost@@bc5ff4355504-2c264c21) Thu 9/14 23:18:01.537 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.000 Thu 9/14 23:18:01.537 [AsyncWatch@362d44c0] VideoFrame.watch(A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T]) Thu 9/14 23:18:01.537 [AsyncWatch@362d44c0] watchThisFile=null Thu 9/14 23:18:01.538 [AsyncWatch@362d44c0] Called Seeker.requestWatch(A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T]) hostname=UIManager:localhost@@bc5ff4355504-2c264c21 Thu 9/14 23:18:01.538 [AsyncWatch@362d44c0] findBestEncoderForNow(A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] record=false host=UIManager:localhost@@bc5ff4355504-2c264c21) Thu 9/14 23:18:01.538 [AsyncWatch@362d44c0] foundBestEncoder3=sage.Seeker$EncoderState@2697ab0d[DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000] Thu 9/14 23:18:01.538 [AsyncWatch@362d44c0] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 clients=[UIManager:localhost@@bc5ff4355504-2c264c21] ir=false Thu 9/14 23:18:01.538 [AsyncWatch@362d44c0] defaultRecord=A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] Thu 9/14 23:18:01.538 [AsyncWatch@362d44c0] Seeker in AUTOMATIC mode nextRecord=A[44643840,44349013,"Iron Resurrection",31046@0915.18:00,60,T] nextTTA=67318462 Thu 9/14 23:18:01.539 [AsyncWatch@362d44c0] FORCE WATCH executing clients=[UIManager:localhost@@bc5ff4355504-2c264c21] Thu 9/14 23:18:01.539 [AsyncWatch@362d44c0] newRecord=A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] Thu 9/14 23:18:01.539 [AsyncWatch@362d44c0] Change in record to another show. Entering device record mode. - LATER Thu 9/14 23:18:01.539 [AsyncWatch@362d44c0] MARK 1 currRecord=null enc=HDHomeRun 10149692 Tuner 0 clients=[] ir=false Thu 9/14 23:18:01.539 [AsyncWatch@362d44c0] Seeker in AUTOMATIC mode nextRecord=A[44923622,44923615,"The Tonight Show Starring Jimmy Fallon",30305@0914.23:34,63,T] nextTTA=958462 Thu 9/14 23:18:01.539 [AsyncWatch@362d44c0] newRecord=null Thu 9/14 23:18:01.539 [AsyncWatch@362d44c0] NOTHING TO RECORD FOR NOW... Thu 9/14 23:18:01.539 [AsyncWatch@362d44c0] MARK 1 currRecord=null enc=HDHomeRun 10149692 Tuner 1 clients=[] ir=false Thu 9/14 23:18:01.540 [AsyncWatch@362d44c0] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Thu 9/14 23:18:01.540 [AsyncWatch@362d44c0] newRecord=null Thu 9/14 23:18:01.540 [AsyncWatch@362d44c0] NOTHING TO RECORD FOR NOW... Thu 9/14 23:18:01.540 [AsyncWatch@362d44c0] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 1311B773-2 on 10.0.0.111:9000 clients=[] ir=false Thu 9/14 23:18:01.540 [AsyncWatch@362d44c0] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Thu 9/14 23:18:01.540 [AsyncWatch@362d44c0] newRecord=null Thu 9/14 23:18:01.540 [AsyncWatch@362d44c0] NOTHING TO RECORD FOR NOW... Thu 9/14 23:18:01.540 [AsyncWatch@362d44c0] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 1311B773-0 on 10.0.0.111:9000 clients=[] ir=false Thu 9/14 23:18:01.541 [AsyncWatch@362d44c0] Seeker in AUTOMATIC mode nextRecord=A[45003866,44968740,"The Real Housewives of Orange County",58625@0915.02:26,4,T] nextTTA=11278462 Thu 9/14 23:18:01.541 [AsyncWatch@362d44c0] newRecord=null Thu 9/14 23:18:01.541 [AsyncWatch@362d44c0] NOTHING TO RECORD FOR NOW... Thu 9/14 23:18:01.541 [AsyncWatch@362d44c0] Change in record to another show. Entering device record mode. - NOW Thu 9/14 23:18:01.541 [AsyncWatch@362d44c0] Seeker.startRecord(DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T], currTime=Thu 9/14 23:18:01.538) currRecord=null switch=false Thu 9/14 23:18:01.541 [AsyncWatch@362d44c0] Setting up MMC video for recording new show & tuning channel conn=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 Digital TV Tuner Thu 9/14 23:18:01.541 [AsyncWatch@362d44c0] Not using desired quality setting of Great because the capture device doesn't support it Thu 9/14 23:18:01.542 [AsyncWatch@362d44c0] Using quality setting "" for recording Thu 9/14 23:18:01.542 [AsyncWatch@362d44c0] VideoStorage for new file: /var/media/tv - Leave Free 25.0 GB Thu 9/14 23:18:01.542 [AsyncWatch@362d44c0] MediaFile created subfile:/var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@09 14.23:00,60,T] mask=TV host=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps []] Thu 9/14 23:18:01.543 [AsyncWatch@362d44c0] Added:MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV host=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B77 3-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01.539-Wed 12/31 19:00:00.000]] Thu 9/14 23:18:01.543 [AsyncWatch@362d44c0] Seeker channel string=620 Thu 9/14 23:18:02.013 [MediaServerConnection@217aa209] MediaServer is writing in remux mode: AUTO PS TRUE Thu 9/14 23:18:03.332 [AsyncWatch@362d44c0] VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV host=achilles encoded By=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01.539-Wed 12/31 19 :00:00.000]] ifn=null]) Thu 9/14 23:18:03.333 [AsyncWatch@362d44c0] No Start on Watch Thu 9/14 23:18:03.333 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV hos t=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:0 1.539-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:18:03.333 [AsyncWatch@362d44c0] setUI(sage.PseudoMenu@6923fbb4[MediaPlayer OSD]) histIdx=2 uiHistory=[sage.PseudoMenu@5328c18e[Main Menu], sage.PseudoMenu@487af1ab[LiveTVGuide w/ Optional Preview], sage.PseudoMenu@6923fbb4[MediaPlayer OSD]] redo=false performingActivation=false Thu 9/14 23:18:03.333 [VideoFrame-bc5ff4355504@4ed98461] VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] ma sk=TV host=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/1 4 23:18:01.539-Wed 12/31 19:00:00.000]] ifn=null] Thu 9/14 23:18:03.333 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV host =achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01 .539-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:18:03.333 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:18:03.334 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=1705 Thu 9/14 23:18:03.334 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.200 Thu 9/14 23:18:03.382 [ActiveRender-bc5ff4355504@61c1e23c] VideoFrame got registration of a subtitle UI component: sage.ZCCLabel[loc=java.awt.Point[x=51,y=122] size=java.awt.Dimension[width=242,heigh t=32]] Thu 9/14 23:18:03.382 [ActiveRender-bc5ff4355504@61c1e23c] VideoFrame got registration of a cc UI component: sage.ZCCLabel[loc=java.awt.Point[x=34,y=16] size=java.awt.Dimension[width=276,height=128]] Thu 9/14 23:18:03.388 [AsyncWatch@362d44c0] VideoFrame got registration of a subtitle UI component: null Thu 9/14 23:18:03.388 [AsyncWatch@362d44c0] VideoFrame got registration of a cc UI component: null Thu 9/14 23:18:03.426 [ActiveRender-bc5ff4355504@61c1e23c] VideoFrame got registration of a subtitle UI component: sage.ZCCLabel[loc=java.awt.Point[x=186,y=564] size=java.awt.Dimension[width=869,heig ht=148]] Thu 9/14 23:18:03.427 [ActiveRender-bc5ff4355504@61c1e23c] VideoFrame got registration of a cc UI component: sage.ZCCLabel[loc=java.awt.Point[x=124,y=74] size=java.awt.Dimension[width=993,height=594] ] Thu 9/14 23:18:03.429 [ReProcessHook@6e94acfa] Start looking for max channel digits Thu 9/14 23:18:03.430 [ReProcessHook@6e94acfa] New max channel = 2, from channel 6-2 on lineup 'Local Over the Air Broadcast - 23112 (sdepg)' Thu 9/14 23:18:03.430 [ReProcessHook@6e94acfa] New max channel = 3, from channel 23-1 on lineup 'Local Over the Air Broadcast - 23112 (sdepg)' Thu 9/14 23:18:03.431 [ReProcessHook@6e94acfa] New max channel = 4, from channel 1829 on lineup 'Verizon Fios Richmond - Digital - Richmond (sdepg)' Thu 9/14 23:18:03.433 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2015) Thu 9/14 23:18:03.438 [ReProcessHook@6e94acfa] Done looking for max channel digits; elapsed time: 9 Thu 9/14 23:18:03.438 [Fork-JREkiwi-615771@1afb50a5] Starting Comskip Playback v2.1.3 Thread: Thread[Fork-JREkiwi-615771,5,main], for UI context bc5ff4355504 Thu 9/14 23:18:03.533 [Fork-OPUS4A-217436@250c2842] ENDING background channel logo preloader thread, ID = 0.008796444252265223 Thu 9/14 23:18:03.534 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV host =achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01 .539-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:18:03.534 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:18:03.535 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=1504 Thu 9/14 23:18:03.535 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.200 Thu 9/14 23:18:03.735 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV host =achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01 .539-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:18:03.735 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:18:03.736 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=1304 Thu 9/14 23:18:03.736 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.200 Thu 9/14 23:18:03.936 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV host =achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01 .539-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:18:03.936 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:18:03.936 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=1103 Thu 9/14 23:18:03.936 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.200 Thu 9/14 23:18:04.137 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV host =achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01 .539-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:18:04.137 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:18:04.137 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=902 Thu 9/14 23:18:04.137 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.200 Thu 9/14 23:18:04.338 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV host =achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01 .539-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:18:04.338 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:18:04.338 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=701 Thu 9/14 23:18:04.338 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.200 Thu 9/14 23:18:04.538 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV host =achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01 .539-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:18:04.538 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:18:04.539 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=500 Thu 9/14 23:18:04.539 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.200 Thu 9/14 23:18:04.739 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV host =achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01 .539-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:18:04.739 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:18:04.740 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=299 Thu 9/14 23:18:04.740 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.200 Repeated logs of VF thread waiting Thu 9/14 23:18:19.187 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:18:19.187 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=-14148 Thu 9/14 23:18:19.188 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.200 Thu 9/14 23:18:19.388 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV host =achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01 .539-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:18:19.388 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:18:19.388 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=-14349 Thu 9/14 23:18:19.389 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.200 Thu 9/14 23:18:19.428 [EventRouter-bc5ff4355504@9ae2723] processUserEvent-UserEvent[anything] evtTime=Thu 9/14 23:18:19.428 Thu 9/14 23:18:19.429 [EventRouter-bc5ff4355504@9ae2723] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null]) Thu 9/14 23:18:19.429 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] mask=TV host =achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01 .539-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:18:19.429 [VideoFrame-bc5ff4355504@4ed98461] VF clearing out job because of new watch VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042821 A[44640492,44638525,"Naked and Afraid",56905@0914.2 3:00,60,T] mask=TV host=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 DSCHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts, Seg0[Thu 9/14 23:18:01.539-Wed 12/31 19:00:00.000]] ifn=null] Thu 9/14 23:18:19.430 [VideoFrame-bc5ff4355504@4ed98461] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null] Thu 9/14 23:18:19.430 [VideoFrame-bc5ff4355504@4ed98461] VF processing job null nPlayin=false Thu 9/14 23:18:19.430 [EventRouter-bc5ff4355504@9ae2723] Seeker.finishWatch(UIManager:localhost@@bc5ff4355504-2c264c21) Thu 9/14 23:18:19.430 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.000 Thu 9/14 23:18:19.430 [Scheduler@10bdb1af] Scheduler awoken Thu 9/14 23:18:19.431 [Seeker@10d8152c] Seeker awoken Thu 9/14 23:18:19.431 [Seeker@10d8152c] MemStats: Used=1294MB Total=1574MB Max=1610MB Thu 9/14 23:18:19.431 [EventRouter-bc5ff4355504@9ae2723] processUserEvent-UserEvent[back] evtTime=Thu 9/14 23:18:19.430 Thu 9/14 23:18:19.431 [EventRouter-bc5ff4355504@9ae2723] setUI(sage.PseudoMenu@487af1ab[LiveTVGuide w/ Optional Preview]) histIdx=1 uiHistory=[sage.PseudoMenu@5328c18e[Main Menu], sage.PseudoMenu@487 af1ab[LiveTVGuide w/ Optional Preview], sage.PseudoMenu@6923fbb4[MediaPlayer OSD]] redo=true performingActivation=false Thu 9/14 23:18:19.431 [EventRouter-bc5ff4355504@9ae2723] VideoFrame got registration of a subtitle UI component: null Thu 9/14 23:18:19.431 [EventRouter-bc5ff4355504@9ae2723] VideoFrame got registration of a cc UI component: null Thu 9/14 23:18:19.432 [Seeker@10d8152c] MARK 1 currRecord=A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] enc=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 clients=[] ir=false Thu 9/14 23:18:19.432 [Seeker@10d8152c] Seeker in AUTOMATIC mode nextRecord=A[44643840,44349013,"Iron Resurrection",31046@0915.18:00,60,T] nextTTA=67300568 Thu 9/14 23:18:19.432 [Seeker@10d8152c] newRecord=null Thu 9/14 23:18:19.432 [Seeker@10d8152c] Change in record, logging recorded data. Thu 9/14 23:18:19.432 [Seeker@10d8152c] Seeker.endRecord(Thu 9/14 23:18:19.432) currRecord=A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] readySwitch=false Thu 9/14 23:18:19.432 [Seeker@10d8152c] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts]) Thu 9/14 23:18:19.433 [Seeker@10d8152c] VF.submitJob(VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts]) Thu 9/14 23:18:19.433 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts] nPlayin=false Thu 9/14 23:18:19.433 [VideoFrame-bc5ff4355504@4ed98461] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/NakedandAfraid-S07E02-WashedOut-44640492-0.ts] Thu 9/14 23:18:19.433 [VideoFrame-bc5ff4355504@4ed98461] VF processing job null nPlayin=false Thu 9/14 23:18:19.433 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.000[/CODE] History Channel Success: Good OpenDCT Log: 23:27:37.016 [SageTVRequestHandler-769:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START DCT-HDHomeRun Prime Tuner 1311B773-1 Digital TV Tuner|1544699704|628|3010892114028|/var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts|Great' 23:27:37.016 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHRNativeCaptureDevice - Capture device is now re-locked. 23:27:37.016 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '1544699704' to file name '/var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts'. 23:27:37.016 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:27:37.018 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: '167772271' lockKey: '167772271' sendLength: 44 address: 10.0.0.24 23:27:37.018 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO HDHRNativeCaptureDevice - HDHomeRun is now locked. 23:27:37.018 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG RTPCaptureDeviceServices - Producer was not running. 23:27:37.018 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG BasicCaptureDevice - Consumer is not in progress. 23:27:37.019 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel '628' 23:27:37.021 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO HDHRNativeCaptureDevice - Starting the encoding for the channel '628' from the device 'DCT-HDHomeRun Prime Tuner 1311B773-1' to the file '/var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts' via the upload id '1544699704'... 23:27:37.021 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:27:37.022 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'none' lockKey: '167772271' sendLength: 39 address: 10.0.0.24 23:27:37.022 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:27:37.023 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOHTTPProducerImpl - Connecting to source using the URL 'http://10.0.0.24:5004/tuner1/v628' 23:27:37.490 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG NIOHttpDownloader - HTTP response: 'HTTP/1.1 200 OK', 'Server: HDHomeRun/1.0', 'Connection: close', 'Content-Type: video/mpeg', 'Cache-Control: no-cache', 'Pragma: no-cache', 'transferMode.dlna.org: Streaming', 'Date: Fri, 15 Sep 2017 03:27:37 GMT', 23:27:37.490 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/program' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:27:37.490 [NIOHTTPProducerImpl-775:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOHTTPProducerImpl - Producer thread is running. 23:27:37.490 [NIOHTTPProducerImpl-775:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG NIOHTTPProducerImpl - Thread priority is 9. 23:27:37.491 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO HDHRNativeCaptureDevice - Configuring and starting the new SageTV consumer... 23:27:37.491 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG BasicCaptureDevice - Consumer is not in progress. 23:27:37.491 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/vstatus' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:27:37.491 [MediaServerConsumerImpl-777:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO MediaServerConsumerImpl - MediaServer thread started. 23:27:37.491 [MediaServerConsumerImpl-777:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG MediaServerConsumerImpl - Thread priority is 8. 23:27:37.491 [MediaServerConsumerImpl-777:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO MediaServerConsumerImpl - Opening file via MediaServer... 23:27:37.491 [MediaServerConsumerImpl-777:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOSageTVMediaServer - Connecting to SageTV server on socket /10.0.0.111:7818... 23:27:37.492 [MediaServerConsumerImpl-777:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOSageTVMediaServer - Sending 'WRITEOPEN /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts 1544699704' to SageTV server... 23:27:37.492 [MediaServerConsumerImpl-777:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO MediaServerConsumerImpl - Setting up remuxing on MediaServer... 23:27:37.492 [MediaServerConsumerImpl-777:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOSageTVMediaServer - Sending 'REMUX_SETUP AUTO PS TRUE' to SageTV server... 23:27:37.494 [MediaServerConsumerImpl-777:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO MediaServerConsumerImpl - Media Server consumer is now streaming... 23:27:38.731 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG SageTVRequestHandler - Replied: 'OK' 23:27:39.461 [SageTVTuningMonitor-64] DEBUG HDHomeRunControl - key: '/tuner1/vstatus' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:27:51.325 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG SageTVRequestHandler - SageTV sent: 'STOP DCT-HDHomeRun Prime Tuner 1311B773-1 Digital TV Tuner' 23:27:51.325 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHRNativeCaptureDevice - Stopping encoding... 23:27:51.325 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HTTPCaptureDeviceServices - Stopping producer thread... 23:27:51.325 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HTTPCaptureDeviceServices - Waiting for producer thread to stop... 23:27:51.325 [NIOHTTPProducerImpl-775:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG NIOHTTPProducerImpl - The socket has been closed. 23:27:51.325 [NIOHTTPProducerImpl-775:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOHTTPProducerImpl - Producer thread has stopped. 23:27:51.325 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG BasicCaptureDevice - Stopping consumer thread... 23:27:51.325 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOSageTVMediaServer - Sending 'CLOSE' to SageTV server... 23:27:51.326 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO NIOSageTVMediaServer - Sending 'QUIT' to SageTV server... 23:27:51.326 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG BasicCaptureDevice - Waiting for consumer thread to stop... 23:27:51.326 [MediaServerConsumerImpl-777:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG MediaServerConsumerImpl - MediaServer consumer was interrupted. 23:27:51.326 [MediaServerConsumerImpl-777:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO MediaServerConsumerImpl - MediaServer thread stopped. 23:27:51.326 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'force' lockKey: '-1' sendLength: 40 address: 10.0.0.24 23:27:51.327 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:27:51.327 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/channel' value: 'none' lockKey: '0' sendLength: 33 address: 10.0.0.24 23:27:51.328 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/target' value: 'none' lockKey: '0' sendLength: 32 address: 10.0.0.24 23:27:51.328 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 address: 10.0.0.24 23:27:51.328 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] INFO HDHRNativeCaptureDevice - HDHomeRun is now unlocked. 23:27:51.328 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG HDHRNativeCaptureDevice - Capture device is now re-unlocked. 23:27:51.328 [SageTVRequestHandler-769:DCT-HDHomeRun Prime Tuner 1311B773-1] DEBUG SageTVRequestHandler - Replied: 'OK'[/CODE] Good SageTV Log: Thu 9/14 23:27:33.768 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1984) Thu 9/14 23:27:33.771 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1983) Thu 9/14 23:27:33.775 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1976) Thu 9/14 23:27:33.777 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1979) Thu 9/14 23:27:33.778 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1975) Thu 9/14 23:27:33.779 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1981) Thu 9/14 23:27:33.781 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1978) Thu 9/14 23:27:33.781 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1980) Thu 9/14 23:27:33.783 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1977) Thu 9/14 23:27:33.783 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1982) Thu 9/14 23:27:33.786 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1973) Thu 9/14 23:27:33.786 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1974) Thu 9/14 23:27:33.789 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2094) Thu 9/14 23:27:34.823 [EventRouter-bc5ff4355504@9ae2723] processUserEvent-UserEvent[anything] evtTime=Thu 9/14 23:27:34.822 Thu 9/14 23:27:34.823 [EventRouter-bc5ff4355504@9ae2723] processOptionsMenu optionsMenu=default:BASE-49221|OptionsMenu:Show Options for an Airing, Recording, File, etc. Thu 9/14 23:27:34.827 [EventRouter-bc5ff4355504@9ae2723] BEGIN: Sort video playlists. Thu 9/14 23:27:34.827 [EventRouter-bc5ff4355504@9ae2723] END: Sort video playlists. Sorted 1 playlists. Total Time = '0' Thu 9/14 23:27:34.871 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2095) Thu 9/14 23:27:34.883 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2093) Thu 9/14 23:27:37.003 [EventRouter-bc5ff4355504@9ae2723] processUserEvent-UserEvent[anything] evtTime=Thu 9/14 23:27:37.002 Thu 9/14 23:27:37.003 [EventRouter-bc5ff4355504@9ae2723] processOptionsMenu optionsMenu=default:BASE-49306|OptionsMenu:Preparing for video playback notice Thu 9/14 23:27:37.005 [ReProcessHook@520ba98f] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null]) Thu 9/14 23:27:37.006 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false Thu 9/14 23:27:37.006 [VideoFrame-bc5ff4355504@4ed98461] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null] Thu 9/14 23:27:37.006 [VideoFrame-bc5ff4355504@4ed98461] VF processing job null nPlayin=false Thu 9/14 23:27:37.006 [ReProcessHook@520ba98f] Seeker.finishWatch(UIManager:localhost@@bc5ff4355504-2c264c21) Thu 9/14 23:27:37.006 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.000 Thu 9/14 23:27:37.007 [AsyncWatch@6e94acfa] VideoFrame.watch(A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T]) Thu 9/14 23:27:37.007 [AsyncWatch@6e94acfa] watchThisFile=null Thu 9/14 23:27:37.007 [AsyncWatch@6e94acfa] Called Seeker.requestWatch(A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T]) hostname=UIManager:localhost@@bc5ff4355504-2c264c21 Thu 9/14 23:27:37.007 [AsyncWatch@6e94acfa] findBestEncoderForNow(A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] record=false host=UIManager:localhost@@bc5ff4355504-2c264c21) Thu 9/14 23:27:37.007 [AsyncWatch@6e94acfa] foundBestEncoder3=sage.Seeker$EncoderState@2697ab0d[DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000] Thu 9/14 23:27:37.007 [AsyncWatch@6e94acfa] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 clients=[UIManager:localhost@@bc5ff4355504-2c264c21] ir=false Thu 9/14 23:27:37.008 [AsyncWatch@6e94acfa] defaultRecord=A[44640492,44638525,"Naked and Afraid",56905@0914.23:00,60,T] Thu 9/14 23:27:37.008 [AsyncWatch@6e94acfa] Seeker in AUTOMATIC mode nextRecord=A[44643840,44349013,"Iron Resurrection",31046@0915.18:00,60,T] nextTTA=66742993 Thu 9/14 23:27:37.008 [AsyncWatch@6e94acfa] FORCE WATCH executing clients=[UIManager:localhost@@bc5ff4355504-2c264c21] Thu 9/14 23:27:37.008 [AsyncWatch@6e94acfa] newRecord=A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] Thu 9/14 23:27:37.008 [AsyncWatch@6e94acfa] Change in record to another show. Entering device record mode. - LATER Thu 9/14 23:27:37.008 [AsyncWatch@6e94acfa] MARK 1 currRecord=null enc=HDHomeRun 10149692 Tuner 0 clients=[] ir=false Thu 9/14 23:27:37.009 [AsyncWatch@6e94acfa] Seeker in AUTOMATIC mode nextRecord=A[44923622,44923615,"The Tonight Show Starring Jimmy Fallon",30305@0914.23:34,63,T] nextTTA=382993 Thu 9/14 23:27:37.009 [AsyncWatch@6e94acfa] newRecord=null Thu 9/14 23:27:37.009 [AsyncWatch@6e94acfa] NOTHING TO RECORD FOR NOW... Thu 9/14 23:27:37.009 [AsyncWatch@6e94acfa] MARK 1 currRecord=null enc=HDHomeRun 10149692 Tuner 1 clients=[] ir=false Thu 9/14 23:27:37.009 [AsyncWatch@6e94acfa] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Thu 9/14 23:27:37.009 [AsyncWatch@6e94acfa] newRecord=null Thu 9/14 23:27:37.010 [AsyncWatch@6e94acfa] NOTHING TO RECORD FOR NOW... Thu 9/14 23:27:37.010 [AsyncWatch@6e94acfa] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 1311B773-2 on 10.0.0.111:9000 clients=[] ir=false Thu 9/14 23:27:37.010 [AsyncWatch@6e94acfa] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Thu 9/14 23:27:37.010 [AsyncWatch@6e94acfa] newRecord=null Thu 9/14 23:27:37.010 [AsyncWatch@6e94acfa] NOTHING TO RECORD FOR NOW... Thu 9/14 23:27:37.010 [AsyncWatch@6e94acfa] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 1311B773-0 on 10.0.0.111:9000 clients=[] ir=false Thu 9/14 23:27:37.010 [AsyncWatch@6e94acfa] Seeker in AUTOMATIC mode nextRecord=A[45003866,44968740,"The Real Housewives of Orange County",58625@0915.02:26,4,T] nextTTA=10702993 Thu 9/14 23:27:37.011 [AsyncWatch@6e94acfa] newRecord=null Thu 9/14 23:27:37.011 [AsyncWatch@6e94acfa] NOTHING TO RECORD FOR NOW... Thu 9/14 23:27:37.011 [AsyncWatch@6e94acfa] Change in record to another show. Entering device record mode. - NOW Thu 9/14 23:27:37.011 [AsyncWatch@6e94acfa] Seeker.startRecord(DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T], currTime=Thu 9/14 23: 27:37.007) currRecord=null switch=false Thu 9/14 23:27:37.011 [AsyncWatch@6e94acfa] Setting up MMC video for recording new show & tuning channel conn=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 Digital TV Tuner Thu 9/14 23:27:37.011 [AsyncWatch@6e94acfa] Not using desired quality setting of Great because the capture device doesn't support it Thu 9/14 23:27:37.012 [AsyncWatch@6e94acfa] Using quality setting "" for recording Thu 9/14 23:27:37.012 [AsyncWatch@6e94acfa] VideoStorage for new file: /var/media/tv - Leave Free 25.0 GB Thu 9/14 23:27:37.013 [AsyncWatch@6e94acfa] MediaFile created subfile:/var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23: 03,60,T] mask=TV host=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-TS 0:00:00 0 kbps []] Thu 9/14 23:27:37.013 [AsyncWatch@6e94acfa] Added:MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-TS 0:00:00 0 kbps [] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23:27:37.008-Wed 12/31 19:00:00.000]] Thu 9/14 23:27:37.013 [AsyncWatch@6e94acfa] Seeker channel string=628 Thu 9/14 23:27:37.037 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2092) Thu 9/14 23:27:37.051 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2099) Thu 9/14 23:27:37.492 [MediaServerConnection@217aa209] MediaServer is writing in remux mode: AUTO PS TRUE Thu 9/14 23:27:37.972 [MediaServerConnection@217aa209] Detected remuxing format of: AV-INF|f=MPEG2-PS;br=8576000;[bf=vid;f=MPEG2-Video;fps=29.970030;fpsn=30000;fpsd=1001;ar=1.777778;arn=16;ard=9;w=19 20;h=1080;lace=1;cs=yuv420p;main=yes;tag=e0;index=0;];[bf=aud;f=AC3;sr=48000;ch=6;br=384000;main=yes;lang=eng;tag=bd-80010000;index=1;];[bf=aud;f=AC3;sr=48000;ch=2;br=192000;lang=spa;tag=bd-81010000; index=2;]; Thu 9/14 23:27:38.731 [AsyncWatch@6e94acfa] Doing pre-emptive file format detection on recording MediaFile..../var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts Thu 9/14 23:27:38.731 [AsyncWatch@6e94acfa] Using internal format detector first for: /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts Thu 9/14 23:27:38.733 [AsyncWatch@6e94acfa] File Format Parsed-2a /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16: 9 interlaced id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] Thu 9/14 23:27:38.882 [AsyncWatch@6e94acfa] File Format Parsed-2b /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16: 9 interlaced id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] Thu 9/14 23:27:38.883 [AsyncWatch@6e94acfa] VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=achilles encodedBy=D CT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]#1 Audio[AC3 48000 Hz 6 channels 38 4 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23:27:37.008-Wed 12/31 19:00 :00.000]] ifn=null]) Thu 9/14 23:27:38.884 [AsyncWatch@6e94acfa] No Start on Watch Thu 9/14 23:27:38.884 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=ac hilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]#1 Audio[AC3 4800 0 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23:27:37.0 08-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:27:38.884 [AsyncWatch@6e94acfa] setUI(sage.PseudoMenu@6923fbb4[MediaPlayer OSD]) histIdx=2 uiHistory=[sage.PseudoMenu@5328c18e[Main Menu], sage.PseudoMenu@487af1ab[LiveTVGuide w/ Optional Preview], sage.PseudoMenu@6923fbb4[MediaPlayer OSD]] redo=false performingActivation=false Thu 9/14 23:27:38.884 [VideoFrame-bc5ff4355504@4ed98461] VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=T V host=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]#1 Audio [AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 2 3:27:37.008-Wed 12/31 19:00:00.000]] ifn=null] Thu 9/14 23:27:38.885 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=ach illes encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23:27:37.00 8-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:27:38.885 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:27:38.885 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=1623 Thu 9/14 23:27:38.885 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.200 Thu 9/14 23:27:38.928 [ActiveRender-bc5ff4355504@61c1e23c] VideoFrame got registration of a subtitle UI component: sage.ZCCLabel[loc=java.awt.Point[x=42,y=122] size=java.awt.Dimension[width=200,heigh t=32]] Thu 9/14 23:27:38.929 [ActiveRender-bc5ff4355504@61c1e23c] VideoFrame got registration of a cc UI component: sage.ZCCLabel[loc=java.awt.Point[x=28,y=16] size=java.awt.Dimension[width=228,height=128]] Thu 9/14 23:27:38.930 [AsyncWatch@6e94acfa] VideoFrame got registration of a subtitle UI component: null Thu 9/14 23:27:38.930 [AsyncWatch@6e94acfa] VideoFrame got registration of a cc UI component: null Thu 9/14 23:27:38.935 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2100) Thu 9/14 23:27:38.935 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2098) Thu 9/14 23:27:38.937 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2096) Thu 9/14 23:27:38.937 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2097) Thu 9/14 23:27:38.940 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2101) Thu 9/14 23:27:38.942 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2112) Thu 9/14 23:27:38.943 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2113) Thu 9/14 23:27:38.955 [ActiveRender-bc5ff4355504@61c1e23c] VideoFrame got registration of a subtitle UI component: sage.ZCCLabel[loc=java.awt.Point[x=186,y=564] size=java.awt.Dimension[width=869,heig ht=148]] Thu 9/14 23:27:38.956 [ActiveRender-bc5ff4355504@61c1e23c] VideoFrame got registration of a cc UI component: sage.ZCCLabel[loc=java.awt.Point[x=124,y=74] size=java.awt.Dimension[width=993,height=594] ] Thu 9/14 23:27:38.963 [ReProcessHook@362d44c0] Start looking for max channel digits Thu 9/14 23:27:38.963 [ReProcessHook@362d44c0] New max channel = 2, from channel 6-2 on lineup 'Local Over the Air Broadcast - 23112 (sdepg)' Thu 9/14 23:27:38.963 [ReProcessHook@362d44c0] New max channel = 3, from channel 23-1 on lineup 'Local Over the Air Broadcast - 23112 (sdepg)' Thu 9/14 23:27:38.964 [ReProcessHook@362d44c0] New max channel = 4, from channel 1829 on lineup 'Verizon Fios Richmond - Digital - Richmond (sdepg)' Thu 9/14 23:27:38.969 [ReProcessHook@362d44c0] Done looking for max channel digits; elapsed time: 6 Thu 9/14 23:27:38.969 [Fork-JREkiwi-615771@520ba98f] Starting Comskip Playback v2.1.3 Thread: Thread[Fork-JREkiwi-615771,5,main], for UI context bc5ff4355504 Thu 9/14 23:27:38.970 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2106) Thu 9/14 23:27:38.974 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2116) Thu 9/14 23:27:38.975 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2077) Thu 9/14 23:27:38.976 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2104) Thu 9/14 23:27:38.977 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2111) Thu 9/14 23:27:38.978 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2108) Thu 9/14 23:27:38.979 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2103) Thu 9/14 23:27:38.981 [Scheduler@10bdb1af] Scheduler awoken Thu 9/14 23:27:38.982 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2105) Thu 9/14 23:27:38.983 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2076) Thu 9/14 23:27:38.983 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2109) Thu 9/14 23:27:38.986 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2107) Thu 9/14 23:27:38.991 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2110) Thu 9/14 23:27:38.998 [Scheduler@10bdb1af] # Airs=12040 Thu 9/14 23:27:39.003 [Fork-OPUS4A-217436@250c2842] ENDING background channel logo preloader thread, ID = 0.8739267390096069 Thu 9/14 23:27:39.085 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=ach illes encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23:27:37.00 8-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:27:39.086 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:27:39.086 [VideoFrame-bc5ff4355504@4ed98461] Actual record time was:Thu 9/14 23:27:39.086 Thu 9/14 23:27:39.086 [VideoFrame-bc5ff4355504@4ed98461] Set the additional encoding delay to be:2078 Thu 9/14 23:27:39.086 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=1422 Thu 9/14 23:27:39.086 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:01.422 Thu 9/14 23:27:40.252 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=ach illes encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23:27:37.00 8-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:27:40.252 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:27:40.252 [VideoFrame-bc5ff4355504@4ed98461] VF waiting for data to appear in new file...liveWait=2334 Thu 9/14 23:27:40.252 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:02.334 Thu 9/14 23:27:40.255 [Seeker@10d8152c] Diskspace checking is running Thu 9/14 23:27:40.255 [Seeker@10d8152c] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Thu 9/14 23:27:40.280 [Seeker@10d8152c] Enforcing keep at most limits for the files... Thu 9/14 23:27:40.282 [Seeker@10d8152c] DONE enforcing keep at most limits for the files. Thu 9/14 23:27:40.282 [Seeker@10d8152c] Seeker clearing unwanted and partial files... Thu 9/14 23:27:40.284 [Seeker@10d8152c] DONE clearing unwanted and partial files. Thu 9/14 23:27:40.299 [Seeker@10d8152c] needFreeSize = 2.025 GB currFreeSize=7100.607415808 GB Thu 9/14 23:27:40.299 [Seeker@10d8152c] Seeker waiting for 1 mins. Thu 9/14 23:27:40.390 [AsyncPropSaver@250c2842] Done writing out the data to the properties file Thu 9/14 23:27:40.390 [AsyncPropSaver@250c2842] Saving properties file to clients/bc5ff4355504.properties Thu 9/14 23:27:40.431 [AsyncPropSaver@250c2842] Done writing out the data to the properties file Thu 9/14 23:27:42.587 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=ach illes encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23:27:37.00 8-Wed 12/31 19:00:00.000]] ifn=null] nPlayin=false Thu 9/14 23:27:42.587 [VideoFrame-bc5ff4355504@4ed98461] VF network encoder playback detected: true Thu 9/14 23:27:42.587 [VideoFrame-bc5ff4355504@4ed98461] VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]#1 Audio[ AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23 :27:37.008-Wed 12/31 19:00:00.000]] ifn=null] Thu 9/14 23:27:42.587 [VideoFrame-bc5ff4355504@4ed98461] VideoFrame creating new media player for file:MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=ach illes encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23:27:37.00 8-Wed 12/31 19:00:00.000]] Thu 9/14 23:27:42.588 [VideoFrame-bc5ff4355504@4ed98461] VF file=MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=achilles encodedBy=DCT-HDHomeRun Prime Tu ner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id= bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23:27:37.008-Wed 12/31 19:00:00.000]] targetTime = Thu 9/14 23:27:37.008 Thu 9/14 23:27:42.588 [VideoFrame-bc5ff4355504@4ed98461] VideoFrame.timeSelected(Thu 9/14 23:27:37.008, true) currFile=MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23:27:37.008-Wed 12/31 19:00:00.000]] Thu 9/14 23:27:42.588 [VideoFrame-bc5ff4355504@4ed98461] initDriver0() Thu 9/14 23:27:42.588 [VideoFrame-bc5ff4355504@4ed98461] Issuing MCSR client socket to bc5ff4355504 Thu 9/14 23:27:42.588 [VideoFrame-bc5ff4355504@4ed98461] MiniPlayer established for bc5ff4355504 Thu 9/14 23:27:42.589 [VideoFrame-bc5ff4355504@4ed98461] getEstimatedBW=131168000 estimatedBWBytes=16396000 estimatedBWTime=1000 Thu 9/14 23:27:42.589 [VideoFrame-bc5ff4355504@4ed98461] MiniPlayer got an estimate from the UI on bandwidth of 131168Kbps Thu 9/14 23:27:42.589 [VideoFrame-bc5ff4355504@4ed98461] MiniPlayer is using Pull mode playback Thu 9/14 23:27:42.589 [VideoFrame-bc5ff4355504@4ed98461] openURL0(stv://10.0.0.111//var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts) Thu 9/14 23:27:43.106 [VideoFrame-bc5ff4355504@4ed98461] Sigma video dim=java.awt.Dimension[width=1920,height=1080] Thu 9/14 23:27:43.107 [VideoFrame-bc5ff4355504@4ed98461] getDuration : 0 Thu 9/14 23:27:43.107 [VideoFrame-bc5ff4355504@4ed98461] setMute0() Thu 9/14 23:27:43.108 [VideoFrame-bc5ff4355504@4ed98461] VideoFrame.timeSelected2(Thu 9/14 23:27:37.008, true) currFile=MediaFile[id=45042822 A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]# 1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23:27:37.008-Wed 12/31 19:00:00.000]] realDur=0 Thu 9/14 23:27:43.109 [VideoFrame-bc5ff4355504@4ed98461] playPush0() Thu 9/14 23:27:43.109 [VideoFrame-bc5ff4355504@4ed98461] Channel Change Time=1505446063109 NOTE: Not valid if this was not a direct channel change! Thu 9/14 23:27:43.151 [VideoFrame-bc5ff4355504@4ed98461] VF processing job null nPlayin=true Thu 9/14 23:27:43.151 [VideoFrame-bc5ff4355504@4ed98461] isRec=true rd=6143 base=0 eos=false Thu 9/14 23:27:43.151 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:35:22.742 Thu 9/14 23:27:45.958 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(1959) Thu 9/14 23:27:47.962 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2117) Thu 9/14 23:27:47.966 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2079) Thu 9/14 23:27:49.300 [EventRouter-bc5ff4355504@9ae2723] processUserEvent-UserEvent[anything] evtTime=Thu 9/14 23:27:49.300 Thu 9/14 23:27:49.334 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2078) Thu 9/14 23:27:50.342 [FinalRender-bc5ff4355504@4aff0a87] unloadImageMini(2118) Thu 9/14 23:27:51.116 [EventRouter-bc5ff4355504@9ae2723] processUserEvent-UserEvent[anything] evtTime=Thu 9/14 23:27:51.116 Thu 9/14 23:27:51.116 [EventRouter-bc5ff4355504@9ae2723] VF.submitJob(VFJob[CloseMF r=0.0 t=0 file=null ifn=null]) Thu 9/14 23:27:51.117 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=true Thu 9/14 23:27:51.117 [VideoFrame-bc5ff4355504@4ed98461] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null] Thu 9/14 23:27:51.117 [VideoFrame-bc5ff4355504@4ed98461] Added:Watched[id=45042824 Airing=A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T], WatchStart=Thu 9/14 23:27:37.008, WatchEnd=Thu 9/1 4 23:27:44.653, RealStart=Thu 9/14 23:27:42.588, RealEnd=Thu 9/14 23:27:51.117] Thu 9/14 23:27:51.117 [VideoFrame-bc5ff4355504@4ed98461] VF logFileWatch doneAir=A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] theTime=Thu 9/14 23:27:44.653 recTime=Thu 9/14 23:27:37.008 Thu 9/14 23:27:51.118 [VideoFrame-bc5ff4355504@4ed98461] stopPush0() Thu 9/14 23:27:51.118 [VideoFrame-bc5ff4355504@4ed98461] Closing down MiniPlayer Thu 9/14 23:27:51.119 [VideoFrame-bc5ff4355504@4ed98461] closeDriver0() Thu 9/14 23:27:51.270 [MediaServerConnection@1afb50a5] Error in MediaServerConnection of :java.io.IOException: Connection reset by peer Thu 9/14 23:27:51.270 [MediaServerConnection@250c2842] Error in MediaServerConnection of :java.io.IOException: Connection reset by peer Thu 9/14 23:27:51.270 [MediaServerConnection@1afb50a5] java.io.IOException: Connection reset by peer Thu 9/14 23:27:51.270 [MediaServerConnection@1afb50a5] at sun.nio.ch.FileDispatcherImpl.read0(Native Method) Thu 9/14 23:27:51.270 [MediaServerConnection@1afb50a5] at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:39) Thu 9/14 23:27:51.270 [MediaServerConnection@1afb50a5] at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:223) Thu 9/14 23:27:51.271 [MediaServerConnection@1afb50a5] at sun.nio.ch.IOUtil.read(IOUtil.java:197) Thu 9/14 23:27:51.271 [MediaServerConnection@1afb50a5] at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:380) Thu 9/14 23:27:51.271 [MediaServerConnection@1afb50a5] at sage.MediaServer$Connection.readLineBytes(MediaServer.java:241) Thu 9/14 23:27:51.271 [MediaServerConnection@1afb50a5] at sage.MediaServer$Connection.run(MediaServer.java:1410) Thu 9/14 23:27:51.271 [MediaServerConnection@1afb50a5] at sage.Pooler$PooledThread.run(Pooler.java:254) Thu 9/14 23:27:51.271 [MediaServerConnection@250c2842] java.io.IOException: Connection reset by peer Thu 9/14 23:27:51.271 [MediaServerConnection@250c2842] at sun.nio.ch.FileDispatcherImpl.read0(Native Method) Thu 9/14 23:27:51.271 [MediaServerConnection@250c2842] at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:39) Thu 9/14 23:27:51.271 [MediaServerConnection@250c2842] at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:223) Thu 9/14 23:27:51.271 [MediaServerConnection@250c2842] at sun.nio.ch.IOUtil.read(IOUtil.java:197) Thu 9/14 23:27:51.271 [MediaServerConnection@250c2842] at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:380) Thu 9/14 23:27:51.271 [MediaServerConnection@250c2842] at sage.MediaServer$Connection.readLineBytes(MediaServer.java:241) Thu 9/14 23:27:51.271 [MediaServerConnection@250c2842] at sage.MediaServer$Connection.run(MediaServer.java:1410) Thu 9/14 23:27:51.272 [MediaServerConnection@250c2842] at sage.Pooler$PooledThread.run(Pooler.java:254) Thu 9/14 23:27:51.320 [VideoFrame-bc5ff4355504@4ed98461] VF processing job null nPlayin=false Thu 9/14 23:27:51.321 [EventRouter-bc5ff4355504@9ae2723] Seeker.finishWatch(UIManager:localhost@@bc5ff4355504-2c264c21) Thu 9/14 23:27:51.321 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.000 Thu 9/14 23:27:51.321 [Seeker@10d8152c] Seeker awoken Thu 9/14 23:27:51.321 [Scheduler@10bdb1af] Scheduler awoken Thu 9/14 23:27:51.321 [Seeker@10d8152c] MemStats: Used=737MB Total=1574MB Max=1610MB Thu 9/14 23:27:51.322 [EventRouter-bc5ff4355504@9ae2723] processUserEvent-UserEvent[back] evtTime=Thu 9/14 23:27:51.321 Thu 9/14 23:27:51.323 [EventRouter-bc5ff4355504@9ae2723] setUI(sage.PseudoMenu@487af1ab[LiveTVGuide w/ Optional Preview]) histIdx=1 uiHistory=[sage.PseudoMenu@5328c18e[Main Menu], sage.PseudoMenu@487 af1ab[LiveTVGuide w/ Optional Preview], sage.PseudoMenu@6923fbb4[MediaPlayer OSD]] redo=true performingActivation=false Thu 9/14 23:27:51.323 [EventRouter-bc5ff4355504@9ae2723] VideoFrame got registration of a subtitle UI component: null Thu 9/14 23:27:51.323 [EventRouter-bc5ff4355504@9ae2723] VideoFrame got registration of a cc UI component: null Thu 9/14 23:27:51.329 [VideoFrame-bc5ff4355504@4ed98461] VF processing job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts] nPlayin=false Thu 9/14 23:27:51.329 [VideoFrame-bc5ff4355504@4ed98461] VF discarding job VFJob[InactiveFile r=0.0 t=0 file=null ifn=/var/media/tv/MountainMen-S06E14-RacetoRuin-44644203-0.ts] Thu 9/14 23:27:51.329 [Seeker@10d8152c] MARK 1 currRecord=null enc=HDHomeRun 10149692 Tuner 0 clients=[] ir=false Thu 9/14 23:27:51.330 [VideoFrame-bc5ff4355504@4ed98461] VF processing job null nPlayin=false Thu 9/14 23:27:51.330 [Seeker@10d8152c] Seeker in AUTOMATIC mode nextRecord=A[44923622,44923615,"The Tonight Show Starring Jimmy Fallon",30305@0914.23:34,63,T] nextTTA=368677 Thu 9/14 23:27:51.330 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.000 Thu 9/14 23:27:51.330 [Seeker@10d8152c] newRecord=null Thu 9/14 23:27:51.330 [Seeker@10d8152c] NOTHING TO RECORD FOR NOW... Thu 9/14 23:27:51.330 [Seeker@10d8152c] MARK 1 currRecord=null enc=HDHomeRun 10149692 Tuner 1 clients=[] ir=false Thu 9/14 23:27:51.331 [Seeker@10d8152c] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Thu 9/14 23:27:51.331 [Seeker@10d8152c] newRecord=null Thu 9/14 23:27:51.331 [Seeker@10d8152c] NOTHING TO RECORD FOR NOW... Thu 9/14 23:27:51.331 [ThumbnailGen@65056293] MediaFile creating thumbnail to /opt/sagetv/server/GeneratedThumbnails/varmediatvMountainMenS06E14RacetoRuin446442030ts.jpg for MediaFile[id=45042822 A[4 4644203,44639290,"Mountain Men",57708@0914.23:03,60,T] mask=TV host=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 HSTRYHD format=MPEG2-PS 0:00:01 8576 kbps [#0 Video[MPEG 2-Video 29.97003 fps 1920x1080 16:9 interlaced id=e0]#1 Audio[AC3 48000 Hz 6 channels 384 kbps MAIN idx=1 id=bd-80010000]#2 Audio[AC3 48000 Hz 2 channels 192 kbps idx=2 id=bd-81010000]] /var/media/tv /MountainMen-S06E14-RacetoRuin-44644203-0.ts, Seg0[Thu 9/14 23:27:37.008-Thu 9/14 23:27:51.323]] Thu 9/14 23:27:51.331 [Seeker@10d8152c] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 1311B773-2 on 10.0.0.111:9000 clients=[] ir=false Thu 9/14 23:27:51.331 [Seeker@10d8152c] Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807 Thu 9/14 23:27:51.331 [Seeker@10d8152c] newRecord=null Thu 9/14 23:27:51.332 [Seeker@10d8152c] NOTHING TO RECORD FOR NOW... Thu 9/14 23:27:51.332 [Seeker@10d8152c] MARK 1 currRecord=null enc=DCT-HDHomeRun Prime Tuner 1311B773-0 on 10.0.0.111:9000 clients=[] ir=false Thu 9/14 23:27:51.332 [Seeker@10d8152c] Seeker in AUTOMATIC mode nextRecord=A[45003866,44968740,"The Real Housewives of Orange County",58625@0915.02:26,4,T] nextTTA=10688677 Thu 9/14 23:27:51.332 [Seeker@10d8152c] newRecord=null Thu 9/14 23:27:51.332 [Seeker@10d8152c] NOTHING TO RECORD FOR NOW... Thu 9/14 23:27:51.332 [VideoFrame-bc5ff4355504@4ed98461] VF processing job null nPlayin=false Thu 9/14 23:27:51.332 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.000 Thu 9/14 23:27:51.332 [VideoFrame-bc5ff4355504@4ed98461] VF processing job null nPlayin=false Thu 9/14 23:27:51.333 [VideoFrame-bc5ff4355504@4ed98461] VF thread is now waiting for 0:00:00.000 Thu 9/14 23:27:51.333 [AsyncPropSaver@217aa209] Saving properties file to Sage.properties Thu 9/14 23:27:51.333 [Seeker@10d8152c] Checking video directories for new files Thu 9/14 23:27:51.335 [Seeker@10d8152c] Diskspace checking is running Thu 9/14 23:27:51.335 [Seeker@10d8152c] Verifying existence of all TV media files in database fixDurs=false avoidArchive=true Thu 9/14 23:27:51.335 [PluginEventQueue@42c0007e] CD: SystemStatus: Number running = 1 Thu 9/14 23:27:51.336 [PluginEventQueue@42c0007e] CD: SystemStatus: Jobs running = 1 Thu 9/14 23:27:51.336 [PluginEventQueue@42c0007e] CD: SystemStatus: MediaFileID = 45042822 Thu 9/14 23:27:51.336 [PluginEventQueue@42c0007e] CD: SystemStatus: Title : Episode = Mountain Men : Race to Ruin Thu 9/14 23:27:51.336 [PluginEventQueue@42c0007e] CD: SystemStatus: No jobs in queue. Thu 9/14 23:27:51.340 [Scheduler@10bdb1af] # Airs=12040 Thu 9/14 23:27:51.355 [Fork-OPUS4A-217436@250c2842] STARTING background channel logo preloader thread, ID = 0.1435789340445145 Thu 9/14 23:27:51.363 [Seeker@10d8152c] Enforcing keep at most limits for the files... Thu 9/14 23:27:51.365 [Seeker@10d8152c] DONE enforcing keep at most limits for the files. Thu 9/14 23:27:51.365 [Seeker@10d8152c] Seeker clearing unwanted and partial files... Thu 9/14 23:27:51.367 [Seeker@10d8152c] Seeker.destroyFile called for: MediaFile[id=45042792 A[44639678,43253102,"Weather News & Forecasts With Superior Accuracy From Accuweather",97300@0914.22:00,24 0,T] mask=TV host=achilles encodedBy=DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000 AWMAHD format=MPEG2-PS 0:00:01 8192 kbps [#0 Video[MPEG2-Video 29.97003 fps 1920x1080 16:9 progressive id= e0]#1 Audio[AC3 48000 Hz 2 channels 192 kbps MAIN idx=1 id=bd-80010000]] /var/media/tv/WeatherNewsForecastsWithSuperiorAccuracyFromAccuweather-44639678-1.ts, Seg0[Thu 9/14 22:27:03.645-Thu 9/14 22:28 :05.537]] Thu 9/14 23:27:51.367 [Seeker@10d8152c] Added file to delete queue: /var/media/tv/WeatherNewsForecastsWithSuperiorAccuracyFromAccuweather-44639678-1.ts Thu 9/14 23:27:51.367 [Seeker@10d8152c] Deleted media segment file /var/media/tv/WeatherNewsForecastsWithSuperiorAccuracyFromAccuweather-44639678-1.ts dtime=0 len=76949254 Thu 9/14 23:27:51.367 [ProgressiveDeleter@373e6118] Starting async delete for:/var/media/tv/WeatherNewsForecastsWithSuperiorAccuracyFromAccuweather-44639678-1.ts.delete Thu 9/14 23:27:51.367 [Seeker@10d8152c] DONE clearing unwanted and partial files. Thu 9/14 23:27:51.379 [ProgressiveDeleter@373e6118] Leaving early from savePrefs()...another thread is already waiting to do it Thu 9/14 23:27:51.379 [ProgressiveDeleter@373e6118] Completed progressive deletion of: /var/media/tv/WeatherNewsForecastsWithSuperiorAccuracyFromAccuweather-44639678-1.ts.delete Thu 9/14 23:27:51.381 [Seeker@10d8152c] Seeker waiting for 1 mins. Thu 9/14 23:27:51.420 [BGLoader2-bc5ff4355504@231c31b0] Loading RawImage of size 512x288 for /opt/sagetv/server/GeneratedThumbnails/varmediatvMountainMenS06E14RacetoRuin446442030ts.jpg fileSize=23283 Thu 9/14 23:27:51.420 [BGLoader2-bc5ff4355504@231c31b0] Loading 0x0 image from file /opt/sagetv/server/GeneratedThumbnails/varmediatvMountainMenS06E14RacetoRuin446442030ts.jpg Thu 9/14 23:27:51.437 [BGLoader2-bc5ff4355504@231c31b0] Allocated image-5 for /opt/sagetv/server/GeneratedThumbnails/varmediatvMountainMenS06E14RacetoRuin446442030ts.jpg width=512 height=288 ptr=2298 Thu 9/14 23:27:51.459 [AsyncPropSaver@217aa209] Done writing out the data to the properties file Thu 9/14 23:27:51.459 [PooledThread@362d44c0] Saving properties file to Sage.properties Thu 9/14 23:27:51.475 [Fork-JREkiwi-615771@520ba98f] Shutting Down Comskip Playback v2.1.3 Thread: Thread[Fork-JREkiwi-615771,5,main], on UI Context bc5ff4355504 Thu 9/14 23:27:51.509 [PooledThread@362d44c0] Done writing out the data to the properties file Thu 9/14 23:27:51.688 [BGLoader2-bc5ff4355504@231c31b0] ImageUtils loading file /opt/sagetv/server/GeneratedThumbnails/varmediatvMountainMenS06E14RacetoRuin446442030ts.jpg Thu 9/14 23:27:51.691 [BGLoader2-bc5ff4355504@231c31b0] ImageUtils creating BI copy BufferedImage@61879ad3: type = 5 ColorModel: #pixelBits = 24 numComponents = 3 color space = java.awt.color.ICC_Col orSpace@1f11c703 transparency = 1 has alpha = false isAlphaPre = false ByteInterleavedRaster: width = 512 height = 288 #numDataElements 3 dataOff[0] = 2 w=512 h=288 freeMem=811344688 totalMem=1574961 152 Thu 9/14 23:27:52.030 [Scheduler@10bdb1af] MUST SEE FINAL-DCT-HDHomeRun Prime Tuner 1311B773-1 on 10.0.0.111:9000-[A[44643840,44349013,"Iron Resurrection",31046@0915.18:00,60,T], A[44722205,44722050, "DC's Legends of Tomorrow",35569@0919.21:00,60,T], A[44805955,44803134,"Iron Resurrection",31046@0925.18:00,60,T], A[44807907,41929885,"What on Earth?",16616@0925.20:00,60,T], A[44882704,44882669,"DC 's Legends of Tomorrow",35569@0926.21:00,60,T]] Thu 9/14 23:27:52.030 [Scheduler@10bdb1af] Scheduler cleanup - Removing A[44644203,44639290,"Mountain Men",57708@0914.23:03,60,T] from schedule because it has no WP.[/CODE]