Sun 6/7 16:48:18.283 [VideoFrame-SAGETV_PROCESS_LOCAL_UI@1b8e5f2] VF processing job VFJob[CloseMF r=0.0 t=0 file=null ifn=null] nPlayin=false
Sun 6/7 16:48:18.283 [AWT-EventQueue-0@1d51302] CloseAndWait is executing the close job synchronously now
Sun 6/7 16:48:18.283 [AWT-EventQueue-0@1d51302] VF processing on UI Thread VFJob[CloseMF r=0.0 t=0 file=null ifn=null]
Sun 6/7 16:48:18.283 [AWT-EventQueue-0@1d51302] Seeker.finishWatch(UIManager:localhost@@SAGETV_PROCESS_LOCAL_UI-12520bb)
Sun 6/7 16:48:18.283 [VideoFrame-SAGETV_PROCESS_LOCAL_UI@1b8e5f2] VF processing job null nPlayin=false
Sun 6/7 16:48:18.283 [AsyncWatch@1293fb7] VideoFrame.watch(A[4842559,4842557,"Star Trek III ch1-2 Main Profile",0@0607.15:32,22,V])
Sun 6/7 16:48:18.283 [VideoFrame-SAGETV_PROCESS_LOCAL_UI@1b8e5f2] VF thread is now waiting for 0:00:00.000
Sun 6/7 16:48:18.284 [AsyncWatch@1293fb7] watchThisFile=MediaFile[id=4842556 A[4842559,4842557,"Star Trek III ch1-2 Main Profile",0@0607.15:32,22,V] mask=V host=htpc encodedBy= format=Quicktime 0:22:35 1499 kbps [#0 Video[H.264 23.98 fps 720x406 360:203 881 kbps progressive]#1 Audio[AAC 48000 Hz 2 channels 160 kbps idx=1 eng]#2 Audio[AC3 48000 Hz 2 channels 447 kbps idx=2 eng]#3 Subpic[TEXT / 0X74786574 eng]] Y:\VIDeos\SageTV\_IMPORT 2\Star Trek III ch1-2 Main Profile.mp4, Seg0[Sun 6/7 15:32:12.552-Sun 6/7 15:54:48.442]]
Sun 6/7 16:48:18.284 [AWT-EventQueue-0@1d51302] processUserEvent-UserEvent[anything] evtTime=Sun 6/7 16:48:18.351
Sun 6/7 16:48:18.288 [AsyncWatch@1293fb7] Watch airing is a file & over, do that instead dvd=false
Sun 6/7 16:48:18.292 [AsyncWatch@1293fb7] Seeker.finishWatch(UIManager:localhost@@SAGETV_PROCESS_LOCAL_UI-12520bb)
Sun 6/7 16:48:18.292 [AsyncWatch@1293fb7] VF.submitJob(VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=4842556 A[4842559,4842557,"Star Trek III ch1-2 Main Profile",0@0607.15:32,22,V] mask=V host=htpc encodedBy= format=Quicktime 0:22:35 1499 kbps [#0 Video[H.264 23.98 fps 720x406 360:203 881 kbps progressive]#1 Audio[AAC 48000 Hz 2 channels 160 kbps idx=1 eng]#2 Audio[AC3 48000 Hz 2 channels 447 kbps idx=2 eng]#3 Subpic[TEXT / 0X74786574 eng]] Y:\VIDeos\SageTV\_IMPORT 2\Star Trek III ch1-2 Main Profile.mp4, Seg0[Sun 6/7 15:32:12.552-Sun 6/7 15:54:48.442]] ifn=null])
Sun 6/7 16:48:18.292 [VideoFrame-SAGETV_PROCESS_LOCAL_UI@1b8e5f2] VF processing job VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=4842556 A[4842559,4842557,"Star Trek III ch1-2 Main Profile",0@0607.15:32,22,V] mask=V host=htpc encodedBy= format=Quicktime 0:22:35 1499 kbps [#0 Video[H.264 23.98 fps 720x406 360:203 881 kbps progressive]#1 Audio[AAC 48000 Hz 2 channels 160 kbps idx=1 eng]#2 Audio[AC3 48000 Hz 2 channels 447 kbps idx=2 eng]#3 Subpic[TEXT / 0X74786574 eng]] Y:\VIDeos\SageTV\_IMPORT 2\Star Trek III ch1-2 Main Profile.mp4, Seg0[Sun 6/7 15:32:12.552-Sun 6/7 15:54:48.442]] ifn=null] nPlayin=false
Sun 6/7 16:48:18.292 [AsyncWatch@1293fb7] setUI(sage.PseudoMenu@1bbb18a[MediaPlayer OSD]) histIdx=2 uiHistory=[sage.PseudoMenu@1ad8e34[Main Menu], sage.PseudoMenu@15b7504[Browser - Videos], sage.PseudoMenu@1bbb18a[MediaPlayer OSD]] redo=false performingActivation=null
Sun 6/7 16:48:18.297 [AWT-EventQueue-0@1d51302] VF processing on UI Thread VFJob[WatchMF r=0.0 t=0 file=MediaFile[id=4842556 A[4842559,4842557,"Star Trek III ch1-2 Main Profile",0@0607.15:32,22,V] mask=V host=htpc encodedBy= format=Quicktime 0:22:35 1499 kbps [#0 Video[H.264 23.98 fps 720x406 360:203 881 kbps progressive]#1 Audio[AAC 48000 Hz 2 channels 160 kbps idx=1 eng]#2 Audio[AC3 48000 Hz 2 channels 447 kbps idx=2 eng]#3 Subpic[TEXT / 0X74786574 eng]] Y:\VIDeos\SageTV\_IMPORT 2\Star Trek III ch1-2 Main Profile.mp4, Seg0[Sun 6/7 15:32:12.552-Sun 6/7 15:54:48.442]] ifn=null]
Sun 6/7 16:48:18.300 [VideoFrame-SAGETV_PROCESS_LOCAL_UI@1b8e5f2] VF processing job VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=4842556 A[4842559,4842557,"Star Trek III ch1-2 Main Profile",0@0607.15:32,22,V] mask=V host=htpc encodedBy= format=Quicktime 0:22:35 1499 kbps [#0 Video[H.264 23.98 fps 720x406 360:203 881 kbps progressive]#1 Audio[AAC 48000 Hz 2 channels 160 kbps idx=1 eng]#2 Audio[AC3 48000 Hz 2 channels 447 kbps idx=2 eng]#3 Subpic[TEXT / 0X74786574 eng]] Y:\VIDeos\SageTV\_IMPORT 2\Star Trek III ch1-2 Main Profile.mp4, Seg0[Sun 6/7 15:32:12.552-Sun 6/7 15:54:48.442]] ifn=null] nPlayin=false
Sun 6/7 16:48:18.316 [ActiveRender-SAGETV_PROCESS_LOCAL_UI@1c933b6] VideoFrame got registration of a subtitle UI component: sage.ZCCLabel[loc=java.awt.Point[x=144,y=521] size=java.awt.Dimension[width=677,height=138]]
Sun 6/7 16:48:18.316 [ActiveRender-SAGETV_PROCESS_LOCAL_UI@1c933b6] VideoFrame got registration of a cc UI component: sage.ZCCLabel[loc=java.awt.Point[x=96,y=68] size=java.awt.Dimension[width=773,height=550]]
Sun 6/7 16:48:18.322 [ReProcessHook@489c0f] Start looking for max channel digits
Sun 6/7 16:48:18.322 [AWT-EventQueue-0@1d51302] VF processing on UI Thread VFJob[LoadMF r=0.0 t=0 file=MediaFile[id=4842556 A[4842559,4842557,"Star Trek III ch1-2 Main Profile",0@0607.15:32,22,V] mask=V host=htpc encodedBy= format=Quicktime 0:22:35 1499 kbps [#0 Video[H.264 23.98 fps 720x406 360:203 881 kbps progressive]#1 Audio[AAC 48000 Hz 2 channels 160 kbps idx=1 eng]#2 Audio[AC3 48000 Hz 2 channels 447 kbps idx=2 eng]#3 Subpic[TEXT / 0X74786574 eng]] Y:\VIDeos\SageTV\_IMPORT 2\Star Trek III ch1-2 Main Profile.mp4, Seg0[Sun 6/7 15:32:12.552-Sun 6/7 15:54:48.442]] ifn=null]
Sun 6/7 16:48:18.322 [ReProcessHook@489c0f] New max channel = 3, from channel 21-1 on lineup 'Local Over the Air Broadcast - 80904 (sdepg)'
Sun 6/7 16:48:18.323 [AWT-EventQueue-0@1d51302] VideoFrame creating new media player for file:MediaFile[id=4842556 A[4842559,4842557,"Star Trek III ch1-2 Main Profile",0@0607.15:32,22,V] mask=V host=htpc encodedBy= format=Quicktime 0:22:35 1499 kbps [#0 Video[H.264 23.98 fps 720x406 360:203 881 kbps progressive]#1 Audio[AAC 48000 Hz 2 channels 160 kbps idx=1 eng]#2 Audio[AC3 48000 Hz 2 channels 447 kbps idx=2 eng]#3 Subpic[TEXT / 0X74786574 eng]] Y:\VIDeos\SageTV\_IMPORT 2\Star Trek III ch1-2 Main Profile.mp4, Seg0[Sun 6/7 15:32:12.552-Sun 6/7 15:54:48.442]]
Sun 6/7 16:48:18.323 [AWT-EventQueue-0@1d51302] VF file=MediaFile[id=4842556 A[4842559,4842557,"Star Trek III ch1-2 Main Profile",0@0607.15:32,22,V] mask=V host=htpc encodedBy= format=Quicktime 0:22:35 1499 kbps [#0 Video[H.264 23.98 fps 720x406 360:203 881 kbps progressive]#1 Audio[AAC 48000 Hz 2 channels 160 kbps idx=1 eng]#2 Audio[AC3 48000 Hz 2 channels 447 kbps idx=2 eng]#3 Subpic[TEXT / 0X74786574 eng]] Y:\VIDeos\SageTV\_IMPORT 2\Star Trek III ch1-2 Main Profile.mp4, Seg0[Sun 6/7 15:32:12.552-Sun 6/7 15:54:48.442]] targetTime = Sun 6/7 15:32:12.552
Sun 6/7 16:48:18.323 [AWT-EventQueue-0@1d51302] VideoFrame.timeSelected(Sun 6/7 15:32:12.552, true) currFile=MediaFile[id=4842556 A[4842559,4842557,"Star Trek III ch1-2 Main Profile",0@0607.15:32,22,V] mask=V host=htpc encodedBy= format=Quicktime 0:22:35 1499 kbps [#0 Video[H.264 23.98 fps 720x406 360:203 881 kbps progressive]#1 Audio[AAC 48000 Hz 2 channels 160 kbps idx=1 eng]#2 Audio[AC3 48000 Hz 2 channels 447 kbps idx=2 eng]#3 Subpic[TEXT / 0X74786574 eng]] Y:\VIDeos\SageTV\_IMPORT 2\Star Trek III ch1-2 Main Profile.mp4, Seg0[Sun 6/7 15:32:12.552-Sun 6/7 15:54:48.442]]
Sun 6/7 16:48:18.323 [AWT-EventQueue-0@1d51302] DShowPlayer createGraph0 called
Sun 6/7 16:48:18.327 [AWT-EventQueue-0@1d51302] Creating GraphPluginHandler...
Sun 6/7 16:48:18.327 [AWT-EventQueue-0@1d51302] Done creating GraphPluginHandler
Sun 6/7 16:48:18.327 [AWT-EventQueue-0@1d51302] Detected default audio stream index to be: 2
Sun 6/7 16:48:18.327 [AWT-EventQueue-0@1d51302] DShowPlayer setVideoRendererFilter0({51B4ABF3-748F-4E3B-A276-C828330E926A}) called
Sun 6/7 16:48:18.330 [ReProcessHook@489c0f] Done looking for max channel digits; elapsed time: 7
Sun 6/7 16:48:18.364 [AWT-EventQueue-0@1d51302] Using VMR9 for video rendering
Sun 6/7 16:48:18.379 [AWT-EventQueue-0@1d51302] Setting up VMR9 deinterlacing
Sun 6/7 16:48:18.379 [AWT-EventQueue-0@1d51302] Curr Mix Prefs=0x1015
Sun 6/7 16:48:18.380 [AWT-EventQueue-0@1d51302] Set to 0x2015, hr=0x0, New Mix Prefs=0x2015
Sun 6/7 16:48:18.380 [AWT-EventQueue-0@1d51302] DShowPlayer setAudioRendererFilter0(SB Live! Wave Device) called
Sun 6/7 16:48:18.408 [AWT-EventQueue-0@1d51302] DShowPlayer setAudioDecoderFilter0(SageTV MPEG Audio Decoder) called
Sun 6/7 16:48:18.423 [AWT-EventQueue-0@1d51302] DShowPlayer setTimeshift=0 buffer=0
Sun 6/7 16:48:18.424 [AWT-EventQueue-0@1d51302] Using the default source filter
Sun 6/7 16:48:18.424 [AWT-EventQueue-0@1d51302] NATIVE Loading the source file
Sun 6/7 16:48:18.428 [AWT-EventQueue-0@1d51302] NATIVE setting up the SageTV Stream Demuxer
Sun 6/7 16:48:18.774 [AWT-EventQueue-0@1d51302] Calling GraphCreated for the PluginHandler
Sun 6/7 16:48:18.774 [AWT-EventQueue-0@1d51302] Done calling GraphCreated for the PluginHandler
Sun 6/7 16:48:18.774 [AWT-EventQueue-0@1d51302] Audio Stream #1 format Other channels 2 freq 48000
Sun 6/7 16:48:18.775 [AWT-EventQueue-0@1d51302] Audio Stream #2 format AC3 channels 2 freq 48000
Sun 6/7 16:48:18.775 [AWT-EventQueue-0@1d51302] NATIVE RenderAudio called stream=2
Sun 6/7 16:48:18.775 [AWT-EventQueue-0@1d51302] NATIVE RenderAudio step 1
Sun 6/7 16:48:18.776 [AWT-EventQueue-0@1d51302] NATIVE RenderAudio step 2
Sun 6/7 16:48:18.776 [AWT-EventQueue-0@1d51302] NATIVE RenderAudio step 3
Sun 6/7 16:48:18.776 [AWT-EventQueue-0@1d51302] NATIVE RenderVideo called
Sun 6/7 16:48:18.776 [AWT-EventQueue-0@1d51302] NATIVE RenderVideo step 1
Sun 6/7 16:48:18.776 [AWT-EventQueue-0@1d51302] NATIVE RenderVideo step 2
Sun 6/7 16:48:18.777 [AWT-EventQueue-0@1d51302] Video source pin information:
Sun 6/7 16:48:18.777 [AWT-EventQueue-0@1d51302] PIN is not connected yet!Sun 6/7 16:48:18.777 [AWT-EventQueue-0@1d51302] M type MEDIATYPE_Video  S type Unknown GUID Name F type FORMAT_MPEG2_VIDEO
Sun 6/7 16:48:18.777 [AWT-EventQueue-0@1d51302] M type MEDIATYPE_Video  S type Unknown GUID Name F type FORMAT_MPEG2_VIDEO
Sun 6/7 16:48:18.777 [AWT-EventQueue-0@1d51302] NATIVE RenderVideo step 3
Sun 6/7 16:48:18.900 [AWT-EventQueue-0@1d51302] VMR9 alloc 738x406 Offscreen DefaultPool  format=NV12 minBuffs=1 arx=20 ary=11 nativeWidth=738 nativeHeight=406 reqNumBuff=1 allocNumBuff=1 hr=0x0
Sun 6/7 16:48:18.900 [AWT-EventQueue-0@1d51302] Setting up VMR9 deinterlacing
Sun 6/7 16:48:18.900 [AWT-EventQueue-0@1d51302] VM9Deinterlacing Tech: PixelAdaptive
Sun 6/7 16:48:18.900 [AWT-EventQueue-0@1d51302] VM9Deinterlacing Tech: BOBVerticalStretch
Sun 6/7 16:48:18.900 [AWT-EventQueue-0@1d51302] Actual deinterlace: hr=0x0 guid={00000000-0000-0000-0000-000000000000}
Sun 6/7 16:48:18.900 [AWT-EventQueue-0@1d51302] deinterlace mode: hr=0x1 guid={00000000-0000-0000-0000-000000000000}
Sun 6/7 16:48:18.900 [AWT-EventQueue-0@1d51302] Setting deinterlace mode to actual mode...
Sun 6/7 16:48:18.901 [AWT-EventQueue-0@1d51302] deinterlace mode: hr=0x1 guid={00000000-0000-0000-0000-000000000000}
Sun 6/7 16:48:18.901 [AWT-EventQueue-0@1d51302] Filter Graph Video Renderer Info:
Sun 6/7 16:48:18.901 [AWT-EventQueue-0@1d51302] Video Renderer:CLSID_VideoMixingRenderer9
Sun 6/7 16:48:18.901 [AWT-EventQueue-0@1d51302] M type MEDIATYPE_Video  S type MEDIASUBTYPE_NV12 F type FORMAT_VideoInfo2
Sun 6/7 16:48:18.901 [AWT-EventQueue-0@1d51302] Progressive video
Sun 6/7 16:48:18.901 [AWT-EventQueue-0@1d51302] DShowGraphFilters=AudRend,VidRend,LAV Video Decoder,Audio Decoder,SageTV Stream Demux,Y:\VIDeos\SageTV\_IMPORT 2\Star Trek III ch1-2 Main Profile.mp4,
Sun 6/7 16:48:18.901 [AWT-EventQueue-0@1d51302] ----------->Get getDurationMillis0=1355917.
Sun 6/7 16:48:18.901 [AWT-EventQueue-0@1d51302] VideoFrame.timeSelected2(Sun 6/7 15:32:12.552, true) currFile=MediaFile[id=4842556 A[4842559,4842557,"Star Trek III ch1-2 Main Profile",0@0607.15:32,22,V] mask=V host=htpc encodedBy= format=Quicktime 0:22:35 1499 kbps [#0 Video[H.264 23.98 fps 720x406 360:203 881 kbps progressive]#1 Audio[AAC 48000 Hz 2 channels 160 kbps idx=1 eng]#2 Audio[AC3 48000 Hz 2 channels 447 kbps idx=2 eng]#3 Subpic[TEXT / 0X74786574 eng]] Y:\VIDeos\SageTV\_IMPORT 2\Star Trek III ch1-2 Main Profile.mp4, Seg0[Sun 6/7 15:32:12.552-Sun 6/7 15:54:48.442]] realDur=1355917
Sun 6/7 16:48:18.902 [AWT-EventQueue-0@1d51302] About to perform media time selection for 0
Sun 6/7 16:48:18.902 [AWT-EventQueue-0@1d51302] Running the playback graph.
Sun 6/7 16:48:18.903 [main@114b553] VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 6/7 16:48:18.920 [AWT-EventQueue-0@1d51302] Channel Change Time=1591570098920 NOTE: Not valid if this was not a direct channel change!
Sun 6/7 16:48:18.961 [AWT-EventQueue-0@1d51302] Default audio language is already selected
Sun 6/7 16:48:18.966 [VideoFrame-SAGETV_PROCESS_LOCAL_UI@1b8e5f2] VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 6/7 16:48:18.999 [Thread-23@1d20e66] VMR9 alloc 738x406 Offscreen DefaultPool  format=NV12 minBuffs=1 arx=320 ary=176 nativeWidth=738 nativeHeight=406 reqNumBuff=1 allocNumBuff=1 hr=0x0
Sun 6/7 16:48:18.999 [AWT-EventQueue-0@1d51302] VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 6/7 16:48:19.000 [AWT-EventQueue-0@1d51302] DShowMediaPlayer is consuming the events...
Sun 6/7 16:48:19.000 [AWT-EventQueue-0@1d51302] Event: 0xd l1=0x0 l2=0x0
Sun 6/7 16:48:19.000 [main@114b553] VF.submitJob(VFJob[DirectControl r=0.0 t=0 file=null ifn=null])
Sun 6/7 16:48:19.000 [VideoFrame-SAGETV_PROCESS_LOCAL_UI@1b8e5f2] VF processing job null nPlayin=true
Sun 6/7 16:48:19.000 [VideoFrame-SAGETV_PROCESS_LOCAL_UI@1b8e5f2] isRec=false rd=1355917 base=0 eos=false
Sun 6/7 16:48:19.001 [VideoFrame-SAGETV_PROCESS_LOCAL_UI@1b8e5f2] VF processing job VFJob[DirectControl r=0.0 t=0 file=null ifn=null] nPlayin=true
Sun 6/7 16:48:19.001 [AWT-EventQueue-0@1d51302] VF processing on UI Thread VFJob[DirectControl r=0.0 t=0 file=null ifn=null]
Sun 6/7 16:48:19.001 [AWT-EventQueue-0@1d51302] DShowMediaPlayer is consuming the events...
Sun 6/7 16:48:19.001 [Fork-OPUS4-111999@96e477] ENDING background video thumb preloader thread, ID = 0.26130020077116123
Sun 6/7 16:48:19.006 [AWT-EventQueue-0@1d51302] Event: 0xa l1=0x19602d0 l2=0x0
Sun 6/7 16:48:19.007 [VideoFrame-SAGETV_PROCESS_LOCAL_UI@1b8e5f2] VF processing job null nPlayin=true
Sun 6/7 16:48:19.007 [VideoFrame-SAGETV_PROCESS_LOCAL_UI@1b8e5f2] isRec=false rd=1355917 base=0 eos=false
Sun 6/7 16:48:19.007 [VideoFrame-SAGETV_PROCESS_LOCAL_UI@1b8e5f2] VF thread is now waiting for 0:22:35.667
Sun 6/7 16:48:22.681 [AWT-EventQueue-0@1d51302] processUserEvent-UserEvent[anything] evtTime=Sun 6/7 16:48:22.757
Sun 6/7 16:48:35.318 [AWT-EventQueue-0@1d51302] processUserEvent-UserEvent[anything] evtTime=Sun 6/7 16:48:35.398
Sun 6/7 16:50:16.590 [Ministry@a37a84] Ministry is waiting for 180 sec