Tue 9/28 19:04:18.539 [EventRouter-001d6a5ca9e8@1c1de42] ParentY=62, TopMenuWidgetHeight=461, SubMenuVerticalAlignment=0.13449023861171366
Tue 9/28 19:04:18.541 [EventRouter-001d6a5ca9e8@1c1de42] Opening sub menu via select/right: xSubmenuTV
Tue 9/28 19:04:21.696 [EventRouter-001d6a5ca9e8@1c1de42] setUI(sage.b6@1384878[Sage Partial Recordings SHORTCUTS]) histIdx=8 uiHistory=[sage.b6@15a005b[Main Menu], sage.b6@1430010[Xtra: SageTV Recordings by malore], sage.b6@bd27a0[MediaPlayer OSD], sage.b6@1e5722[Main Menu], sage.b6@1430010[Xtra: SageTV Recordings by malore], sage.b6@1e5722[Main Menu], sage.b6@10aec67[Xtra: SageTV Recordings by malore], sage.b6@1e5722[Main Menu], sage.b6@1384878[Sage Partial Recordings SHORTCUTS]]
Tue 9/28 19:04:21.734 [EventRouter-001d6a5ca9e8@1c1de42] setUI(sage.b6@1df43f[SageRecordings STYLE LINKS]) histIdx=8 uiHistory=[sage.b6@15a005b[Main Menu], sage.b6@1430010[Xtra: SageTV Recordings by malore], sage.b6@bd27a0[MediaPlayer OSD], sage.b6@1e5722[Main Menu], sage.b6@1430010[Xtra: SageTV Recordings by malore], sage.b6@1e5722[Main Menu], sage.b6@10aec67[Xtra: SageTV Recordings by malore], sage.b6@1e5722[Main Menu], sage.b6@1df43f[SageRecordings STYLE LINKS]]
Tue 9/28 19:04:21.737 [EventRouter-001d6a5ca9e8@1c1de42] setUI(sage.b6@10aec67[Xtra: SageTV Recordings by malore]) histIdx=8 uiHistory=[sage.b6@15a005b[Main Menu], sage.b6@1430010[Xtra: SageTV Recordings by malore], sage.b6@bd27a0[MediaPlayer OSD], sage.b6@1e5722[Main Menu], sage.b6@1430010[Xtra: SageTV Recordings by malore], sage.b6@1e5722[Main Menu], sage.b6@10aec67[Xtra: SageTV Recordings by malore], sage.b6@1e5722[Main Menu], sage.b6@10aec67[Xtra: SageTV Recordings by malore]]
Tue 9/28 19:04:21.774 [ReProcessHook@1f29d4d] ===== List Creation Elapsed Time = [1]
Tue 9/28 19:04:21.776 [ReProcessHook@1f29d4d] TotalCategoryFilterList=[]
Tue 9/28 19:04:21.779 [ReProcessHook@1f29d4d] Category Filter Elapsed Time = [3]
Tue 9/28 19:04:21.781 [ReProcessHook@1f29d4d] Other Filter Elapsed Time = [0]
Tue 9/28 19:04:21.783 [ReProcessHook@1f29d4d] FilterChannelsIn=true, FilterChannels=[]
Tue 9/28 19:04:21.786 [ReProcessHook@1f29d4d] Channel Filter Elapsed Time = [3]
Tue 9/28 19:04:21.788 [ReProcessHook@1f29d4d] No duplicates Elapsed Time = [0]
Tue 9/28 19:04:21.791 [ReProcessHook@1f29d4d] Sorting Elapsed Time = [0]
Tue 9/28 19:04:21.794 [ReProcessHook@1f29d4d] Grouping Elapsed Time = [1]
Tue 9/28 19:04:21.796 [ReProcessHook@1f29d4d] ===== Overall Group Creation Elapsed Time = [23]
Tue 9/28 19:04:22.351 [Fork-OPUS4-108092@4c49ea] ENDING Forked weather thread.
Tue 9/28 19:04:24.006 [EventRouter-001d6a5ca9e8@1c1de42] processOptionsMenu optionsMenu=default:BASE-49221|OptionsMenu:Show Options for an Airing, Recording, File, etc.
Tue 9/28 19:04:24.040 [EventRouter-001d6a5ca9e8@1c1de42] BEGIN: Sort video playlists.
Tue 9/28 19:04:24.042 [EventRouter-001d6a5ca9e8@1c1de42] END: Sort video playlists. Sorted 1 playlists. Total Time = '1'
Tue 9/28 19:04:29.014 [Fork-BASE-55376@dd695c] Updating Disk Space Bar
Tue 9/28 19:04:29.024 [Fork-BASE-55376@dd695c] Got used/avail video diskspace
Tue 9/28 19:04:29.027 [Fork-BASE-55376@dd695c] Calculate size of partial recordings
Tue 9/28 19:04:29.030 [Fork-BASE-55376@dd695c] Found 1 partials using 2037612544 bytes
Tue 9/28 19:04:29.045 [Fork-BASE-55376@dd695c] Calculate space required for upcoming manual/favourite airings for next X hours and display
Tue 9/28 19:04:29.087 [Fork-BASE-55376@dd695c] VideoDiskspaceReqProportion: 0.9046441582933888
Tue 9/28 19:04:29.089 [Fork-BASE-55376@dd695c] VideoDiskspaceFreeProportion: 0.1511893422829251
Tue 9/28 19:04:29.091 [Fork-BASE-55376@dd695c] Disk Space Bar Update complete for UI context 001d6a5ca9e8
Tue 9/28 19:04:37.593 [EventRouter-001d6a5ca9e8@1c1de42] processOptionsMenu optionsMenu=default:BASE-60249|OptionsMenu:delete confirm
Tue 9/28 19:04:41.143 [EventRouter-001d6a5ca9e8@1c1de42] Failed deleting file; put it into the async delete queue and try to do it later: C:\Videos\SageTV\NewsHour-1469452-0.ts
Tue 9/28 19:04:41.146 [EventRouter-001d6a5ca9e8@1c1de42] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties
Tue 9/28 19:04:41.304 [EventRouter-001d6a5ca9e8@1c1de42] Failed renaming file to move into delete queue: C:\Videos\SageTV\NewsHour-1469452-0.ts target=C:\Videos\SageTV\NewsHour-1469452-0.ts.delete leave it with its current filename and putting it into the queue.
Tue 9/28 19:04:41.306 [EventRouter-001d6a5ca9e8@1c1de42] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties
Tue 9/28 19:04:41.467 [EventRouter-001d6a5ca9e8@1c1de42] Deleted media segment file C:\Videos\SageTV\NewsHour-1469452-0.ts dtime=326 len=2037612544
Tue 9/28 19:04:41.469 [ProgressiveDeleter@129d183] Starting progressive delete for:C:\Videos\SageTV\NewsHour-1469452-0.ts
Tue 9/28 19:04:41.472 [PluginEventQueue@7a11e7] CD: sageEvent: event received = MediaFileRemoved
Tue 9/28 19:04:41.474 [PluginEventQueue@7a11e7] CD: sageEvent: MediaFile is still being processed.
Tue 9/28 19:04:41.476 [EventRouter-001d6a5ca9e8@1c1de42] Updated:Watched[id=1552802 Airing=A[1469452,40105,"News Hour",10050@0928.17:59,61], WatchStart=Tue 9/28 17:59:00.000, WatchEnd=Tue 9/28 19:00:00.000, RealStart=Tue 9/28 18:22:29.536, RealEnd=Tue 9/28 18:55:22.186]
Tue 9/28 19:04:41.479 [PluginEventQueue@7a11e7] CD: Stopping ComskipJob.
Tue 9/28 19:04:41.481 [Carny@164e48d] Carny got a WatchMark job of A[1469452,40105,"News Hour",10050@0928.17:59,61]
Tue 9/28 19:04:41.484 [PluginEventQueue@7a11e7] CD: ComskipManager: Looking for video file for C:\Videos\SageTV\NewsHour-1469452-0.edl
Tue 9/28 19:04:41.516 [EventRouter-001d6a5ca9e8@1c1de42] ===== List Creation Elapsed Time = [1]
Tue 9/28 19:04:41.518 [EventRouter-001d6a5ca9e8@1c1de42] TotalCategoryFilterList=[]
Tue 9/28 19:04:41.520 [EventRouter-001d6a5ca9e8@1c1de42] Category Filter Elapsed Time = [2]
Tue 9/28 19:04:41.525 [EventRouter-001d6a5ca9e8@1c1de42] Other Filter Elapsed Time = [1]
Tue 9/28 19:04:41.527 [EventRouter-001d6a5ca9e8@1c1de42] FilterChannelsIn=true, FilterChannels=[]
Tue 9/28 19:04:41.529 [EventRouter-001d6a5ca9e8@1c1de42] Channel Filter Elapsed Time = [2]
Tue 9/28 19:04:41.532 [Thread-48@1a90919] CD: run: comskip return code = 1
Tue 9/28 19:04:41.534 [Thread-48@1a90919] CD: ComskipManager: job has completed with status true
Tue 9/28 19:04:41.537 [Thread-48@1a90919] CD: ComskipManager: No more jobs to process.
Tue 9/28 19:04:41.540 [Thread-48@1a90919] CD: CSC removeStatus: item after remove 1552800
Tue 9/28 19:04:41.543 [PluginEventQueue@7a11e7] CD: ComskipManager: MediaFile still has corresponding video file.
Tue 9/28 19:04:41.545 [EventRouter-001d6a5ca9e8@1c1de42] No duplicates Elapsed Time = [0]
Tue 9/28 19:04:41.548 [PluginEventQueue@7a11e7] CD: sageEvent: MediaFile cleanup successful News Hour
Tue 9/28 19:04:41.550 [EventRouter-001d6a5ca9e8@1c1de42] Sorting Elapsed Time = [0]
Tue 9/28 19:04:41.553 [EventRouter-001d6a5ca9e8@1c1de42] Grouping Elapsed Time = [1]
Tue 9/28 19:04:41.556 [EventRouter-001d6a5ca9e8@1c1de42] ===== Overall Group Creation Elapsed Time = [41]
Tue 9/28 19:04:41.648 [Fork-BASE-49749@4c49ea] ===== List Creation Elapsed Time = [1]
Tue 9/28 19:04:41.651 [Fork-BASE-49749@4c49ea] TotalCategoryFilterList=[]
Tue 9/28 19:04:41.654 [Fork-BASE-49749@4c49ea] Category Filter Elapsed Time = [3]
Tue 9/28 19:04:41.657 [Fork-BASE-49749@4c49ea] Other Filter Elapsed Time = [1]
Tue 9/28 19:04:41.660 [Fork-BASE-49749@4c49ea] FilterChannelsIn=true, FilterChannels=[]
Tue 9/28 19:04:41.663 [Fork-BASE-49749@4c49ea] Channel Filter Elapsed Time = [3]
Tue 9/28 19:04:41.666 [Fork-BASE-49749@4c49ea] No duplicates Elapsed Time = [1]
Tue 9/28 19:04:41.669 [Fork-BASE-49749@4c49ea] Sorting Elapsed Time = [0]
Tue 9/28 19:04:41.672 [Fork-BASE-49749@4c49ea] Grouping Elapsed Time = [0]
Tue 9/28 19:04:41.675 [Fork-BASE-49749@4c49ea] ===== Overall Group Creation Elapsed Time = [28]
Tue 9/28 19:04:41.732 [Carny@164e48d] CARNY Processing 3577 Agents & 49150 Airs
Tue 9/28 19:04:53.887 [ProgressiveDeleter@129d183] Saving properties file to C:\Program Files (x86)\SageTV\SageTV\Sage.properties
Tue 9/28 19:04:53.971 [ProgressiveDeleter@129d183] Completed progressive deletion of: sage.u$a@1fc0804
Tue 9/28 19:05:08.302 [Seeker@1ca18e9] Seeker awoken