[2014-10-15 08:58:05,579] [Log ] [scheduler thread] [INFO ] - Scheduler: Time to record WCBS-HD 8:58 AM-10:00 AM The Queen Latifah Show [2014-10-15 08:58:05,579] [Log ] [scheduler thread] [INFO ] - Controller: find free card for channel WCBS-HD [2014-10-15 08:58:05,579] [Log ] [scheduler thread] [INFO ] - Controller: find card for channel WCBS-HD [2014-10-15 08:58:05,579] [Log ] [scheduler thread] [INFO ] - Controller: got 1 tuning details for WCBS-HD [2014-10-15 08:58:05,595] [Log ] [scheduler thread] [INFO ] - Controller: channel #1 ATSC:tv:Cable WCBS-HD Freq:505250 ONID:1 TSID:8707 SID:1025 PMT:0x1D60 FTA:True LCN:71 phys:71 maj:2 min:1 mod:Mod256Qam [2014-10-15 08:58:05,595] [Log ] [scheduler thread] [INFO ] - Controller: card:1 type:RadioWebStream is disabled [2014-10-15 08:58:05,595] [Log ] [scheduler thread] [INFO ] - Controller: card:2 type:DvbIP is disabled [2014-10-15 08:58:05,595] [Log ] [scheduler thread] [INFO ] - Controller: card:3 type:Atsc can tune to channel [2014-10-15 08:58:05,595] [Log ] [scheduler thread] [INFO ] - Controller: card:4 type:Atsc can tune to channel [2014-10-15 08:58:05,610] [Log ] [scheduler thread] [INFO ] - Controller: found 2 card(s) for channel [2014-10-15 08:58:05,610] [Log ] [scheduler thread] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 32 msec [2014-10-15 08:58:05,610] [Log ] [scheduler thread] [INFO ] - Controller: found 2 free card(s) [2014-10-15 08:58:05,642] [Log ] [scheduler thread] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 63 msec [2014-10-15 08:58:05,673] [Log ] [scheduler thread] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=1854, tuningdetails=ATSC:tv:Cable WCBS-HD Freq:505250 ONID:1 TSID:8707 SID:1025 PMT:0x1D60 FTA:True LCN:71 phys:71 maj:2 min:1 mod:Mod256Qam [2014-10-15 08:58:05,720] [Log ] [scheduler thread] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=1855, tuningdetails=ATSC:tv:Cable WCBS-HD Freq:505250 ONID:1 TSID:8707 SID:1025 PMT:0x1D60 FTA:True LCN:71 phys:71 maj:2 min:1 mod:Mod256Qam [2014-10-15 08:58:05,767] [Log ] [scheduler thread] [INFO ] - Controller: card:3 type:Atsc users: 0 [2014-10-15 08:58:05,782] [Log ] [scheduler thread] [INFO ] - Controller: card:3 type:Atsc is available [2014-10-15 08:58:05,813] [Log ] [scheduler thread] [INFO ] - Controller: card:4 type:Atsc users: 0 [2014-10-15 08:58:05,813] [Log ] [scheduler thread] [INFO ] - Controller: card:4 type:Atsc is available [2014-10-15 08:58:05,813] [Log ] [scheduler thread] [INFO ] - scheduler: try max 2 of 2 free cards for recording [2014-10-15 08:58:05,829] [Log ] [scheduler thread] [INFO ] - Scheduler : record, first tune to channel [2014-10-15 08:58:05,829] [Log ] [scheduler thread] [INFO ] - card: Tune on card 3 to subchannel WCBS-HD [2014-10-15 08:58:05,829] [Log ] [scheduler thread] [DEBUG] - card: user: scheduler132:3:-1 tune ATSC:tv:Cable WCBS-HD Freq:505250 ONID:1 TSID:8707 SID:1025 PMT:0x1D60 FTA:True LCN:71 phys:71 maj:2 min:1 mod:Mod256Qam [2014-10-15 08:58:05,845] [Log ] [scheduler thread] [DEBUG] - TimeShifter.OnBeforeTune: resetting audio/video events [2014-10-15 08:58:05,845] [Log ] [scheduler thread] [INFO ] - atsc:Tune:ATSC:tv:Cable WCBS-HD Freq:505250 ONID:1 TSID:8707 SID:1025 PMT:0x1D60 FTA:True LCN:71 phys:71 maj:2 min:1 mod:Mod256Qam [2014-10-15 08:58:05,845] [Log ] [scheduler thread] [INFO ] - atsc:using new channel tuning settings [2014-10-15 08:58:05,845] [Log ] [scheduler thread] [INFO ] - dvb:Submiting tunerequest Channel:WCBS-HD subChannel:-1 [2014-10-15 08:58:05,860] [Log ] [scheduler thread] [INFO ] - dvb:Getting new subchannel [2014-10-15 08:58:05,860] [Log ] [scheduler thread] [INFO ] - dvb:GetNewSubChannel:0 #0 [2014-10-15 08:58:05,860] [Log ] [scheduler thread] [INFO ] - TvDvbChannel ctor new subchIndex:0 [2014-10-15 08:58:05,876] [Log ] [scheduler thread] [INFO ] - card: AddTuneEvent card: 3 / subch: 0 [2014-10-15 08:58:05,876] [Log ] [scheduler thread] [INFO ] - dvb:Submit tunerequest size:1 new:0 [2014-10-15 08:58:05,876] [Log ] [scheduler thread] [INFO ] - subch:0 OnBeforeTune [2014-10-15 08:58:05,876] [Log ] [scheduler thread] [INFO ] - dvb:Submit tunerequest calling put_TuneRequest [2014-10-15 08:58:05,907] [Log ] [scheduler thread] [INFO ] - dvb:Submit tunerequest done calling put_TuneRequest [2014-10-15 08:58:05,923] [Log ] [scheduler thread] [INFO ] - subch:0 OnAfterTune [2014-10-15 08:58:05,970] [Log ] [scheduler thread] [INFO ] - RunGraph [2014-10-15 08:58:06,001] [Log ] [scheduler thread] [INFO ] - subch:0 OnGraphStart [2014-10-15 08:58:06,017] [Log ] [scheduler thread] [INFO ] - dvb: RunGraph [2014-10-15 08:58:07,675] [Log ] [scheduler thread] [INFO ] - dvb: LockInOnSignal ok [2014-10-15 08:58:07,675] [Log ] [scheduler thread] [INFO ] - subch:0 OnGraphStarted [2014-10-15 08:58:07,691] [Log ] [scheduler thread] [INFO ] - subch:0 SetupPmtGrabber:pid 1D60 sid:401 [2014-10-15 08:58:07,691] [Log ] [scheduler thread] [INFO ] - subch:0 set pmt grabber pmt:1D60 sid:401 [2014-10-15 08:58:07,691] [Log ] [scheduler thread] [DEBUG] - TimeShifter.OnAfterTune: resetting audio/video time [2014-10-15 08:58:07,691] [Log ] [scheduler thread] [DEBUG] - WaitForPMT: Waiting for PMT 1D60 [2014-10-15 08:58:07,800] [Log ] [23 ] [INFO ] - subch:0 OnPMTReceived() pmt:1D60 ran:True dynamic:False [2014-10-15 08:58:07,816] [Log ] [scheduler thread] [DEBUG] - WaitForPMT: Found PMT after 0.1250005 seconds. [2014-10-15 08:58:07,847] [Log ] [scheduler thread] [INFO ] - subch:0 SendPmt:401 401 FFFFFFFF 4 [2014-10-15 08:58:07,863] [Log ] [scheduler thread] [INFO ] - Decode pmt [2014-10-15 08:58:07,878] [Log ] [scheduler thread] [INFO ] - Set descriptor data with length 6 [2014-10-15 08:58:07,909] [Log ] [scheduler thread] [INFO ] - Set descriptor data with length 6 [2014-10-15 08:58:07,909] [Log ] [scheduler thread] [INFO ] - subch:0 SendPMT: Channel is FTA, nothing to do. [2014-10-15 08:58:07,941] [Log ] [scheduler thread] [INFO ] - subch:0 SetMpegPidMapping [2014-10-15 08:58:07,956] [Log ] [scheduler thread] [INFO ] - subch:0 pid:1D61 pcr [2014-10-15 08:58:07,972] [Log ] [scheduler thread] [INFO ] - subch:0 pid:1D60 pmt [2014-10-15 08:58:07,972] [Log ] [scheduler thread] [INFO ] - subch:0 pid:1D61 video type:MPEG-2 [2014-10-15 08:58:07,972] [Log ] [scheduler thread] [INFO ] - subch:0 map pid:1D61 video type:MPEG-2 [2014-10-15 08:58:07,972] [Log ] [scheduler thread] [INFO ] - subch:0 pid:1D62 audio lang:eng type:AC3 [2014-10-15 08:58:07,988] [Log ] [scheduler thread] [INFO ] - subch:0 map pid:1D62 audio lang:eng type:AC3 [2014-10-15 08:58:08,019] [Log ] [scheduler thread] [INFO ] - subch:0 pid:1D63 audio lang:spa type:AC3 [2014-10-15 08:58:08,034] [Log ] [scheduler thread] [INFO ] - subch:0 map pid:1D63 audio lang:spa type:AC3 [2014-10-15 08:58:08,097] [Log ] [scheduler thread] [DEBUG] - OnPMTReceived: MDAPI disabled. Possible reasons are _mdplugs=null or provider not listed [2014-10-15 08:58:08,113] [Log ] [scheduler thread] [INFO ] - subch:0 stop tif [2014-10-15 08:58:08,128] [Log ] [scheduler thread] [DEBUG] - WaitForPmt: PMT handling took 0.2812507 seconds. [2014-10-15 08:58:08,144] [Log ] [scheduler thread] [DEBUG] - card: Tuner locked: True [2014-10-15 08:58:08,175] [Log ] [scheduler thread] [INFO ] - ************************************************** [2014-10-15 08:58:08,175] [Log ] [scheduler thread] [INFO ] - ***** SIGNAL LEVEL: 92, SIGNAL QUALITY: 92 ***** [2014-10-15 08:58:08,175] [Log ] [scheduler thread] [INFO ] - ************************************************** [2014-10-15 08:58:08,191] [Log ] [scheduler thread] [DEBUG] - card: tuned user: scheduler132 subchannel: 0 [2014-10-15 08:58:08,191] [Log ] [scheduler thread] [INFO ] - user:scheduler132 add [2014-10-15 08:58:08,206] [Log ] [scheduler thread] [DEBUG] - Scheduler: MakeFileName() using "moviesformat" (_isSerie=False) [2014-10-15 08:58:08,206] [Log ] [scheduler thread] [INFO ] - Scheduler : record to E:\Recordings\The Queen Latifah Show\The Queen Latifah Show - WCBS-HD - 2014-10-15 - 9_00 AM.ts [2014-10-15 08:58:08,206] [Log ] [scheduler thread] [INFO ] - Controller: epg stop [2014-10-15 08:58:08,206] [Log ] [scheduler thread] [DEBUG] - Recorder.start add audioVideoEventHandler [2014-10-15 08:58:08,222] [Log ] [scheduler thread] [INFO ] - card: StartRecording 3 E:\Recordings\The Queen Latifah Show\The Queen Latifah Show - WCBS-HD - 2014-10-15 - 9_00 AM.ts [2014-10-15 08:58:08,222] [Log ] [scheduler thread] [INFO ] - StartRecording to E:\Recordings\The Queen Latifah Show\The Queen Latifah Show - WCBS-HD - 2014-10-15 - 9_00 AM.ts [2014-10-15 08:58:08,222] [Log ] [scheduler thread] [INFO ] - subch:0 StartRecord(E:\Recordings\The Queen Latifah Show\The Queen Latifah Show - WCBS-HD - 2014-10-15 - 9_00 AM.ts) [2014-10-15 08:58:08,222] [Log ] [scheduler thread] [INFO ] - subch:0-0 tswriter StartRecording... [2014-10-15 08:58:08,222] [Log ] [scheduler thread] [INFO ] - SetRecorderPids [2014-10-15 08:58:08,238] [Log ] [scheduler thread] [INFO ] - Set video / audio observer [2014-10-15 08:58:08,238] [Log ] [scheduler thread] [INFO ] - DVB subch:0 Started recording [2014-10-15 08:58:08,238] [Log ] [scheduler thread] [INFO ] - card: WaitForFile - waiting _eventAudio & _eventVideo [2014-10-15 08:58:08,253] [Log ] [23 ] [INFO ] - PID seen - type = Video [2014-10-15 08:58:08,253] [Log ] [23 ] [DEBUG] - Recorder audioVideoEventHandler Video [2014-10-15 08:58:08,300] [Log ] [23 ] [INFO ] - PID seen - type = Audio [2014-10-15 08:58:08,316] [Log ] [23 ] [DEBUG] - Recorder audioVideoEventHandler Audio [2014-10-15 08:58:08,316] [Log ] [scheduler thread] [INFO ] - card: WaitForFile - video and audio are seen after 0.0781251 seconds [2014-10-15 08:58:08,331] [Log ] [scheduler thread] [DEBUG] - CardReservation.RemoveTuneTicket: removed reservation with id=1854, tuningdetails=ATSC:tv:Cable WCBS-HD Freq:505250 ONID:1 TSID:8707 SID:1025 PMT:0x1D60 FTA:True LCN:71 phys:71 maj:2 min:1 mod:Mod256Qam [2014-10-15 08:58:08,331] [Log ] [scheduler thread] [INFO ] - card: SignalTuneEvent card: 3 / subch: 0 [2014-10-15 08:58:08,355] [Log ] [scheduler thread] [DEBUG] - Scheduler: adding new row in db for title="The Queen Latifah Show" of type="0" [2014-10-15 08:58:08,406] [Log ] [23 ] [INFO ] - PID seen - type = Audio [2014-10-15 08:58:08,422] [Log ] [23 ] [DEBUG] - Recorder audioVideoEventHandler Audio [2014-10-15 08:58:08,453] [Log ] [scheduler thread] [INFO ] - Scheduler: recList: count: 1 add scheduleid: 132 card: Silicondust HDHomeRun Tuner 1032C851-0 [2014-10-15 08:58:08,453] [Log ] [scheduler thread] [DEBUG] - CardReservation.CancelCardReservation id=1855 [2014-10-15 09:05:19,236] [Log ] [90 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 09:05:19,251] [Log ] [90 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 09:05:19,251] [Log ] [90 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 09:20:19,240] [Log ] [44 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 09:20:19,240] [Log ] [44 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 09:20:19,240] [Log ] [44 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 09:35:19,246] [Log ] [63 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 09:35:19,246] [Log ] [63 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 09:35:19,246] [Log ] [63 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 09:50:19,259] [Log ] [13 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 09:50:19,259] [Log ] [13 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 09:50:19,259] [Log ] [13 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 10:02:01,367] [Log ] [scheduler thread] [INFO ] - Scheduler: stop record WCBS-HD 10/15/2014 8:58:08 AM-10/15/2014 10:00:00 AM The Queen Latifah Show [2014-10-15 10:02:01,367] [Log ] [scheduler thread] [INFO ] - card: StopRecording card=3, user=scheduler132 [2014-10-15 10:02:01,367] [Log ] [scheduler thread] [INFO ] - basesubchannel.StopRecording 0 [2014-10-15 10:02:01,367] [Log ] [scheduler thread] [INFO ] - tvdvbchannel.OnStopRecording subch=0, subch index=0 [2014-10-15 10:02:01,367] [Log ] [scheduler thread] [INFO ] - tvdvbchannel.OnStopRecording subch:0-0 tswriter StopRecording... [2014-10-15 10:02:01,382] [Log ] [scheduler thread] [INFO ] - tvcard:FreeSubChannel: subchannels count 1 subch#0 [2014-10-15 10:02:01,398] [Log ] [scheduler thread] [INFO ] - DVB subch:0 Decompose() [2014-10-15 10:02:01,398] [Log ] [scheduler thread] [INFO ] - FreeSubChannel CA: freeing sub channel : 0 [2014-10-15 10:02:01,398] [Log ] [scheduler thread] [INFO ] - tvcard:FreeSubChannel : no subchannels present, pausing graph [2014-10-15 10:02:01,398] [Log ] [scheduler thread] [INFO ] - dvb:StopGraph called [2014-10-15 10:02:01,398] [Log ] [scheduler thread] [INFO ] - tvcard:FreeAllSubChannels [2014-10-15 10:02:01,398] [Log ] [scheduler thread] [INFO ] - dvb:StopGraph [2014-10-15 10:02:01,429] [Log ] [scheduler thread] [INFO ] - debug: IMediaControl stopped! hr = 0x0 :) [2014-10-15 10:02:01,429] [Log ] [scheduler thread] [DEBUG] - usermanagement.RemoveUser: scheduler132, subch: 0 of 0, card: 3 [2014-10-15 10:02:01,429] [Log ] [scheduler thread] [INFO ] - user:scheduler132 remove [2014-10-15 10:02:01,429] [Log ] [scheduler thread] [INFO ] - dvb:StopGraph called [2014-10-15 10:02:01,429] [Log ] [scheduler thread] [INFO ] - tvcard:FreeAllSubChannels [2014-10-15 10:02:01,429] [Log ] [scheduler thread] [INFO ] - dvb:StopGraph filterstate already stopped, returning. [2014-10-15 10:02:01,445] [Log ] [scheduler thread] [INFO ] - Controller: epg start [2014-10-15 10:02:01,445] [EPG ] [scheduler thread] [INFO ] - EPG: grabber disabled [2014-10-15 10:02:01,492] [Log ] [scheduler thread] [DEBUG] - ThumbProcessor.OnTvServerEvent: Enqueue recording E:\Recordings\The Queen Latifah Show\The Queen Latifah Show - WCBS-HD - 2014-10-15 - 9_00 AM.ts [2014-10-15 10:02:01,492] [Log ] [20 ] [INFO ] - ThumbProcessor.DoWork: Creating thumb for E:\Recordings\The Queen Latifah Show\The Queen Latifah Show - WCBS-HD - 2014-10-15 - 9_00 AM.ts [2014-10-15 10:02:01,507] [Log ] [20 ] [DEBUG] - TvThumbnails.VideoThumbCreator: preGapSec: 60 [2014-10-15 10:02:01,929] [Log ] [20 ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: Inspecting media : E:\Recordings\The Queen Latifah Show\The Queen Latifah Show - WCBS-HD - 2014-10-15 - 9_00 AM.ts [2014-10-15 10:02:01,929] [Log ] [20 ] [DEBUG] - MediaInfoWrapper.MediaInfoWrapper: VideoDuration : 3832729 [2014-10-15 10:02:01,929] [Log ] [20 ] [DEBUG] - E:\Recordings\The Queen Latifah Show\The Queen Latifah Show - WCBS-HD - 2014-10-15 - 9_00 AM.ts duration is 3832, TimeIntBwThumbs is 1886 [2014-10-15 10:02:02,960] [Log ] [20 ] [DEBUG] - TvThumbnails.VideoThumbCreator: thumb creation -loglevel quiet -ss 60 -i "E:\Recordings\The Queen Latifah Show\The Queen Latifah Show - WCBS-HD - 2014-10-15 - 9_00 AM.ts" -y -ss 3 -vf yadif=0:-1:0,scale=600:337,setsar=1:1,tile=1x1 -vframes 1 -vsync 0 -an "C:\WINDOWS\TEMP\The Queen Latifah Show - WCBS-HD - 2014-10-15 - 9_00 AM_0.jpg" [2014-10-15 10:02:02,960] [Log ] [20 ] [DEBUG] - CreateTileThumb: before Saving thumb! tmpFile: C:\WINDOWS\TEMP\tmpCF8E.tmp [2014-10-15 10:02:02,976] [Log ] [20 ] [DEBUG] - CreateTileThumb: Saving thumb! [2014-10-15 10:02:02,992] [Log ] [20 ] [DEBUG] - CreateTileThumb: after Saving thumb! [2014-10-15 10:02:03,101] [Log ] [20 ] [DEBUG] - TvThumbnails.VideoThumbCreator: thumb creation success C:\WINDOWS\TEMP\The Queen Latifah Show - WCBS-HD - 2014-10-15 - 9_00 AM.jpg [2014-10-15 10:02:03,664] [Log ] [20 ] [INFO ] - ThumbProcessor.DoWork: Thumbnail successfully created for - E:\Recordings\The Queen Latifah Show\The Queen Latifah Show - WCBS-HD - 2014-10-15 - 9_00 AM.ts [2014-10-15 10:05:19,262] [Log ] [58 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 10:05:19,262] [Log ] [58 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 10:05:19,262] [Log ] [58 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 10:20:19,274] [Log ] [30 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 10:20:19,274] [Log ] [30 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 10:20:19,274] [Log ] [30 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 10:35:19,279] [Log ] [13 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 10:35:19,294] [Log ] [13 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 10:35:19,310] [Log ] [13 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 10:50:19,288] [Log ] [55 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 10:50:19,288] [Log ] [55 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 10:50:19,288] [Log ] [55 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 11:05:19,296] [Log ] [92 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 11:05:19,296] [Log ] [92 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 11:05:19,296] [Log ] [92 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 11:20:19,309] [Log ] [81 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 11:20:19,309] [Log ] [81 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 11:20:19,309] [Log ] [81 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 11:35:19,323] [Log ] [49 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 11:35:19,323] [Log ] [49 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 11:35:19,323] [Log ] [49 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 11:50:19,336] [Log ] [38 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 11:50:19,336] [Log ] [38 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 11:50:19,336] [Log ] [38 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 12:05:19,350] [Log ] [49 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 12:05:19,350] [Log ] [49 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 12:05:19,350] [Log ] [49 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 12:20:19,360] [Log ] [4 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 12:20:19,360] [Log ] [4 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 12:20:19,360] [Log ] [4 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 12:35:19,373] [Log ] [69 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 12:35:19,373] [Log ] [69 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 12:35:19,373] [Log ] [69 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 12:50:19,388] [Log ] [3 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 12:50:19,388] [Log ] [3 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 12:50:19,388] [Log ] [3 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 13:05:19,402] [Log ] [59 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 13:05:19,402] [Log ] [59 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 13:05:19,402] [Log ] [59 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 13:20:19,413] [Log ] [59 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 13:20:19,413] [Log ] [59 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 13:20:19,413] [Log ] [59 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 13:35:19,426] [Log ] [84 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 13:35:19,426] [Log ] [84 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 13:35:19,426] [Log ] [84 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 13:50:19,438] [Log ] [63 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 13:50:19,438] [Log ] [63 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 13:50:19,438] [Log ] [63 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 14:05:19,446] [Log ] [3 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 14:05:19,446] [Log ] [3 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 14:05:19,446] [Log ] [3 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 14:20:19,448] [Log ] [62 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 14:20:19,448] [Log ] [62 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 14:20:19,448] [Log ] [62 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 14:35:19,461] [Log ] [81 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 14:35:19,461] [Log ] [81 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 14:35:19,461] [Log ] [81 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 14:50:19,474] [Log ] [62 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 14:50:19,474] [Log ] [62 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 14:50:19,474] [Log ] [62 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 15:05:19,486] [Log ] [92 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 15:05:19,486] [Log ] [92 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 15:05:19,486] [Log ] [92 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 15:20:19,496] [Log ] [59 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 15:20:19,496] [Log ] [59 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 15:20:19,496] [Log ] [59 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 15:35:19,512] [Log ] [30 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 15:35:19,512] [Log ] [30 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 15:35:19,512] [Log ] [30 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 15:50:19,524] [Log ] [82 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 15:50:19,524] [Log ] [82 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 15:50:19,524] [Log ] [82 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 16:05:19,537] [Log ] [82 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 16:05:19,537] [Log ] [82 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 16:05:19,537] [Log ] [82 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 16:20:19,547] [Log ] [69 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 16:20:19,547] [Log ] [69 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 16:20:19,547] [Log ] [69 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 16:35:19,559] [Log ] [25 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 16:35:19,559] [Log ] [25 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 16:35:19,559] [Log ] [25 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 16:50:19,574] [Log ] [55 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 16:50:19,574] [Log ] [55 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 16:50:19,574] [Log ] [55 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 17:05:19,588] [Log ] [71 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 17:05:19,588] [Log ] [71 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 17:05:19,588] [Log ] [71 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 17:20:19,600] [Log ] [30 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 17:20:19,600] [Log ] [30 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 17:20:19,600] [Log ] [30 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 17:35:19,614] [Log ] [30 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 17:35:19,614] [Log ] [30 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 17:35:19,614] [Log ] [30 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 17:39:37,460] [Log ] [46 ] [DEBUG] - Detecting BDA/WDM devices... [2014-10-15 17:39:37,460] [Log ] [46 ] [DEBUG] - Detect legacy AM filter devices [2014-10-15 17:39:37,476] [Log ] [46 ] [DEBUG] - Detect AM KS crossbar devices [2014-10-15 17:39:37,476] [Log ] [46 ] [DEBUG] - Detect AM KS TV tuner devices [2014-10-15 17:39:37,476] [Log ] [46 ] [DEBUG] - Detect BDA source devices [2014-10-15 17:40:05,090] [Log ] [46 ] [DEBUG] - Detecting BDA/WDM devices... [2014-10-15 17:40:05,090] [Log ] [46 ] [DEBUG] - Detect legacy AM filter devices [2014-10-15 17:40:05,106] [Log ] [46 ] [DEBUG] - Detect AM KS crossbar devices [2014-10-15 17:40:05,106] [Log ] [46 ] [DEBUG] - Detect AM KS TV tuner devices [2014-10-15 17:40:05,106] [Log ] [46 ] [DEBUG] - Detect BDA source devices [2014-10-15 17:50:19,624] [Log ] [46 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 17:50:19,624] [Log ] [46 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 17:50:19,624] [Log ] [46 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 18:05:19,639] [Log ] [35 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 18:05:19,639] [Log ] [35 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 18:05:19,639] [Log ] [35 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 18:18:07,384] [Log ] [49 ] [DEBUG] - Detecting BDA/WDM devices... [2014-10-15 18:18:07,384] [Log ] [49 ] [DEBUG] - Detect legacy AM filter devices [2014-10-15 18:18:07,384] [Log ] [49 ] [DEBUG] - Detect AM KS crossbar devices [2014-10-15 18:18:07,384] [Log ] [49 ] [DEBUG] - Detect AM KS TV tuner devices [2014-10-15 18:18:07,400] [Log ] [49 ] [DEBUG] - Detect BDA source devices [2014-10-15 18:18:18,179] [Log ] [81 ] [DEBUG] - Detecting BDA/WDM devices... [2014-10-15 18:18:18,179] [Log ] [81 ] [DEBUG] - Detect legacy AM filter devices [2014-10-15 18:18:18,195] [Log ] [81 ] [DEBUG] - Detect AM KS crossbar devices [2014-10-15 18:18:18,195] [Log ] [81 ] [DEBUG] - Detect AM KS TV tuner devices [2014-10-15 18:18:18,195] [Log ] [81 ] [DEBUG] - Detect BDA source devices [2014-10-15 18:18:28,619] [Log ] [81 ] [DEBUG] - Detecting BDA/WDM devices... [2014-10-15 18:18:28,619] [Log ] [81 ] [DEBUG] - Detect legacy AM filter devices [2014-10-15 18:18:28,635] [Log ] [81 ] [DEBUG] - Detect AM KS crossbar devices [2014-10-15 18:18:28,635] [Log ] [81 ] [DEBUG] - Detect AM KS TV tuner devices [2014-10-15 18:18:28,635] [Log ] [81 ] [DEBUG] - Detect BDA source devices [2014-10-15 18:20:19,649] [Log ] [69 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 18:20:19,649] [Log ] [69 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 18:20:19,649] [Log ] [69 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 18:30:40,641] [Log ] [38 ] [DEBUG] - Detecting BDA/WDM devices... [2014-10-15 18:30:40,644] [Log ] [38 ] [DEBUG] - Detect legacy AM filter devices [2014-10-15 18:30:40,658] [Log ] [38 ] [DEBUG] - Detect AM KS crossbar devices [2014-10-15 18:30:40,661] [Log ] [38 ] [DEBUG] - Detect AM KS TV tuner devices [2014-10-15 18:30:40,663] [Log ] [38 ] [DEBUG] - Detect BDA source devices [2014-10-15 18:35:19,658] [Log ] [41 ] [INFO ] - DiskManagement: checking free disk space [2014-10-15 18:35:19,658] [Log ] [41 ] [DEBUG] - Quota GetDisks: E: [2014-10-15 18:35:19,658] [Log ] [41 ] [DEBUG] - DiskManagement: minimiumFreeDiskSpace for E: is 512000 [2014-10-15 18:38:03,754] [Log ] [90 ] [DEBUG] - Detecting BDA/WDM devices... [2014-10-15 18:38:03,769] [Log ] [90 ] [DEBUG] - Detect legacy AM filter devices [2014-10-15 18:38:03,769] [Log ] [90 ] [DEBUG] - Detect AM KS crossbar devices [2014-10-15 18:38:03,785] [Log ] [90 ] [DEBUG] - Detect AM KS TV tuner devices [2014-10-15 18:38:03,785] [Log ] [90 ] [DEBUG] - Detect BDA source devices [2014-10-15 18:38:17,115] [Log ] [35 ] [DEBUG] - Detecting BDA/WDM devices... [2014-10-15 18:38:17,115] [Log ] [35 ] [DEBUG] - Detect legacy AM filter devices [2014-10-15 18:38:17,115] [Log ] [35 ] [DEBUG] - Detect AM KS crossbar devices [2014-10-15 18:38:17,130] [Log ] [35 ] [DEBUG] - Detect AM KS TV tuner devices [2014-10-15 18:38:17,130] [Log ] [35 ] [DEBUG] - Detect BDA source devices