Defraggler create big log files on sheduled defragmentation (if settings in ini file)

[St@NNer]

Hello!

2nd build I look this problem: if settings saved in ini-file and a defragmentation is planned, df.exe create txt log-files with names df.exe.[2_14_705][2013-04-19_22-22].txt

If i unmark box "Save settings in ini file", this problem is gone.

Text sample:

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogApplicationInfo#721 c:\Program Files\Defraggler\df.exe

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogApplicationInfo#723 CmdLine: "c:\Program Files\Defraggler\df.exe" "F:" /ts /user "Администратор" /appPath "c:\Program Files\Defraggler" /fs

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogApplicationInfo#727 Version: 2_14_705

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogApplicationInfo#753 FileVersion: 2.14.0.705

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogApplicationInfo#754 ProductVersion: 2.14.0.705

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogApplicationInfo#755 Flags: 0x00000000, OS: 0x00000004, Type: 0x00000002, Subtype: 0x00000000

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogApplicationInfo#756 Flags description: Release

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#774 Computer name: comp

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#781 User name: SYSTEM

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#789 OEM ID: 0

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#790 Number of processors: 4

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#791 Page size: 4096

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#792 Processor type: 586

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#793 Minimum application address: 10000

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#794 Maximum application address: 7ffeffff

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#795 Active processor mask: 15

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#798 Clean boot: 0

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#799 Monitors: 1, Display: 1920x1080, Virtual: 0x0 1920x1080

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#809 Major: 0x00000005, Minor: 0x00000001, Build: 2600, PlatformId: 0x00000002

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#810 SPMajor: 0x0003, SPMinor: 0x0000, CSDVersion: "Service Pack 3"

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#811 SuiteMask: 0x00000100, ProductType: 0x00000001

[2013-04-19] [22:22:00,015] 008a0 0 DfLogger::HostLogger::LogSystemInfo#823 Memory: Load: 24, Physical: 2440945664/3211829248, PageFile: 4557602816/5186383872, Virtual: 2106019840/2147352576, Extended 0

[2013-04-19] [22:22:00,015] 008a0 1 InitPersistentSettings#204 Ini file present. Switching config storage

[2013-04-19] [22:22:00,015] 008a0 1 CProgramInstanceMonitorHelper::GetTaskScheduleMutex#79 Failed to open mutex Global\DefragglerTS (code 0x2)

[2013-04-19] [22:22:00,015] 008a0 1 OpenGlobalEvent#437 Failed to open existing event: G (error: 0x2)

[2013-04-19] [22:22:00,015] 008a0 1 OpenGlobalEvent#437 Failed to open existing event: G (error: 0x2)

[2013-04-19] [22:22:00,015] 008a0 1 CProgramInstanceMonitorHelper::GetTaskScheduleMutex#79 Failed to open mutex Global\DefragglerTS (code 0x2)

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::CVolumeManagerBase#10 Entering CVolumeManagerBase::CVolumeManagerBase

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::CVolumeManagerBase#10 Leaving CVolumeManagerBase::CVolumeManagerBase

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::Initialize#18 Entering CVolumeManagerBase::Initialize

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::Initialize#28 Enabling SE_BACKUP_NAME privilege succeeded.

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::Initialize#34 Enabling SE_RESTORE_NAME privilege succeeded.

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::Initialize#18 Leaving CVolumeManagerBase::Initialize

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::RebuildVolumesMap#159 Entering CVolumeManagerBase::RebuildVolumesMap

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::RebuildVolumesMap#164 Logical drive letters id: 0x000002fc, arrival disks:

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::RebuildVolumesMap#168 Arrival disk: C

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::RebuildVolumesMap#168 Arrival disk: D

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::RebuildVolumesMap#168 Arrival disk: E

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::RebuildVolumesMap#168 Arrival disk: F

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::RebuildVolumesMap#168 Arrival disk: G

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::RebuildVolumesMap#168 Arrival disk: H

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::RebuildVolumesMap#168 Arrival disk: J

[2013-04-19] [22:22:00,015] 008a0 1 CVolumeManagerBase::UpdateVolume#351 Start update for volume C:.

[2013-04-19] [22:22:00,046] 008a0 1 VolumeUtils::CATAIdentifier::GetIdentifyDevice#22 VolumeUtils::CATAIdentifier::GetIdentifyDevice identified device by ATA command

[2013-04-19] [22:22:00,046] 008a0 1 VolumeUtils::IsSsdDrive#348 Rotational speed of drive: C: is 7200

[2013-04-19] [22:22:00,046] 008a0 1 CVolume::CVolume#76 CVolume object created for volume C:.

[2013-04-19] [22:22:00,046] 008a0 1 CVolumeManagerBase::UpdateVolume#370 Volume: C:, size: 17182576640, free: 8074285056, fs: NTFS, name: "System".

[2013-04-19] [22:22:00,046] 008a0 1 CVolumeManagerBase::UpdateVolume#393 Finish update for bvi C:.

[2013-04-19] [22:22:00,046] 008a0 1 CVolumeManagerBase::UpdateVolume#351 Start update for volume D:.

[2013-04-19] [22:22:00,062] 008a0 1 VolumeUtils::CATAIdentifier::GetIdentifyDevice#22 VolumeUtils::CATAIdentifier::GetIdentifyDevice identified device by ATA command

[2013-04-19] [22:22:00,062] 008a0 1 VolumeUtils::IsSsdDrive#348 Rotational speed of drive: D: is 7200

[2013-04-19] [22:22:00,062] 008a0 1 CVolume::CVolume#76 CVolume object created for volume D:.

[2013-04-19] [22:22:00,062] 008a0 1 CVolumeManagerBase::UpdateVolume#370 Volume: D:, size: 482922606592, free: 71286857728, fs: NTFS, name: "Users".

[2013-04-19] [22:22:00,062] 008a0 1 CVolumeManagerBase::UpdateVolume#393 Finish update for bvi D:.

[2013-04-19] [22:22:00,062] 008a0 1 CVolumeManagerBase::UpdateVolume#351 Start update for volume E:.

[2013-04-19] [22:22:00,078] 008a0 1 VolumeUtils::CATAIdentifier::GetIdentifyDevice#22 VolumeUtils::CATAIdentifier::GetIdentifyDevice identified device by ATA command

[2013-04-19] [22:22:00,078] 008a0 1 VolumeUtils::IsSsdDrive#348 Rotational speed of drive: E: is 1

[2013-04-19] [22:22:00,078] 008a0 1 VolumeUtils::IsSsdDrive#367 Drive E: was detected as SSD

[2013-04-19] [22:22:00,078] 008a0 1 CVolume::CVolume#76 CVolume object created for volume E:.

[2013-04-19] [22:22:00,078] 008a0 1 CVolumeManagerBase::UpdateVolume#370 Volume: E:, size: 120031539200, free: 77724024832, fs: NTFS, name: "System8".

[2013-04-19] [22:22:00,078] 008a0 1 CVolumeManagerBase::UpdateVolume#393 Finish update for bvi E:.

[2013-04-19] [22:22:00,078] 008a0 1 CVolumeManagerBase::UpdateVolume#351 Start update for volume F:.

[2013-04-19] [22:22:00,078] 008a0 1 VolumeUtils::CATAIdentifier::GetIdentifyDevice#22 VolumeUtils::CATAIdentifier::GetIdentifyDevice identified device by ATA command

[2013-04-19] [22:22:00,078] 008a0 1 VolumeUtils::IsSsdDrive#348 Rotational speed of drive: F: is 5900

[2013-04-19] [22:22:00,078] 008a0 1 CVolume::CVolume#76 CVolume object created for volume F:.

[2013-04-19] [22:22:00,078] 008a0 1 CVolumeManagerBase::UpdateVolume#370 Volume: F:, size: 1000152887296, free: 75403489280, fs: NTFS, name: "Users8".

[2013-04-19] [22:22:00,078] 008a0 1 CVolumeManagerBase::UpdateVolume#393 Finish update for bvi F:.

[2013-04-19] [22:22:00,078] 008a0 1 CVolumeManagerBase::UpdateVolume#351 Start update for volume G:.

[2013-04-19] [22:22:00,078] 008a0 1 VolumeUtils::IsRaidDrive#461 Volume G: identified as RAID

[2013-04-19] [22:22:00,078] 008a0 1 CVolume::CVolume#76 CVolume object created for volume G:.

[2013-04-19] [22:22:00,078] 008a0 1 CVolumeManagerBase::UpdateVolume#370 Volume: G:, size: 120031477760, free: 4837629952, fs: NTFS, name: "DV".

[2013-04-19] [22:22:00,078] 008a0 1 CVolumeManagerBase::UpdateVolume#393 Finish update for bvi G:.

[2013-04-19] [22:22:00,078] 008a0 1 CVolumeManagerBase::UpdateVolume#351 Start update for volume H:.

[2013-04-19] [22:22:00,078] 008a0 1 CVolumeManagerBase::GetBasicVolumeInfo#257 Unsupported drive type 5 for volume H:

[2013-04-19] [22:22:00,078] 008a0 1 CVolumeManagerBase::UpdateVolume#393 Finish update for bvi H:.

[2013-04-19] [22:22:00,078] 008a0 1 CVolumeManagerBase::UpdateVolume#351 Start update for volume J:.

[2013-04-19] [22:22:00,078] 008a0 1 VolumeUtils::CATAIdentifier::GetIdentifyDeviceAta#107 Error occured while obtaining ATA Identification data for volume: \\.\J:, error code: 50

[2013-04-19] [22:22:00,078] 008a0 1 VolumeUtils::CATAIdentifier::GetIdentifyDeviceAtaPassThrough#153 Error occured while obtaining ATA PASS THROUGH Identification data for volume: \\.\J:, error code: 50

[2013-04-19] [22:22:00,078] 008a0 1 VolumeUtils::CATAIdentifier::GetIdentifyDeviceAtaPassThrough#182 Error occured while obtaining IDE PASS THROUGH Identification data for volume: \\.\J:, error code: 50

[2013-04-19] [22:22:00,078] 008a0 1 VolumeUtils::CATAIdentifier::GetIdentifyDeviceAtaPassThrough#153 Error occured while obtaining ATA PASS THROUGH Identification data for volume: \\.\J:, error code: 50

[2013-04-19] [22:22:00,078] 008a0 1 VolumeUtils::CATAIdentifier::GetIdentifyDeviceAtaPassThrough#182 Error occured while obtaining IDE PASS THROUGH Identification data for volume: \\.\J:, error code: 50

[2013-04-19] [22:22:00,125] 008a0 1 VolumeUtils::CATAIdentifier::GetIdentifyDevice#60 VolumeUtils::CATAIdentifier::GetIdentifyDevice identified device by SAT command

[2013-04-19] [22:22:00,125] 008a0 1 VolumeUtils::IsSsdDrive#348 Rotational speed of drive: J: is 0

[2013-04-19] [22:22:00,125] 008a0 1 CVolume::CVolume#76 CVolume object created for volume J:.

[2013-04-19] [22:22:00,125] 008a0 1 CVolumeManagerBase::UpdateVolume#370 Volume: J:, size: 320070287360, free: 31959236608, fs: NTFS, name: "Stanner-SJ".

[2013-04-19] [22:22:00,125] 008a0 1 CVolumeManagerBase::UpdateVolume#393 Finish update for bvi J:.

[2013-04-19] [22:22:00,125] 008a0 1 CVolumeManagerBase::RebuildVolumesMap#159 Leaving CVolumeManagerBase::RebuildVolumesMap

[2013-04-19] [22:22:00,125] 008a0 1 Wow64FsRedirectorDisabler::Acquire#38 Wow64DisableWow64FsRedirection skipped.

[2013-04-19] [22:22:00,125] 008a0 1 CAnalysis::AnalyzeVolume#23 Entering CAnalysis::AnalyzeVolume

[2013-04-19] [22:22:00,125] 008a0 1 CAnalysis::AnalyzeVolume#31 Analysis of volume F: started

[2013-04-19] [22:22:00,125] 008a0 1 Wow64FsRedirectorDisabler::Acquire#38 Wow64DisableWow64FsRedirection skipped.

[2013-04-19] [22:22:00,125] 008a0 1 CVolume::Initialize#232 Intialization for volume F: started.

[2013-04-19] [22:22:00,125] 008a0 1 CVolume::Initialize#267 GetClustersCount for volume F: returned 244177951 clusters.

[2013-04-19] [22:22:00,578] 008a0 1 CVolume::ReloadMap#486 MftZone: <230981888, 232448192).

[2013-04-19] [22:22:00,578] 008a0 1 CVolume::Initialize#288 Raise VolumeMapChangedSignal for volume F:.

[2013-04-19] [22:22:00,578] 008a0 1 CVolume::Initialize#292 Intialization for volume F: finished.

[2013-04-19] [22:22:00,578] 008a0 1 CAnalysis::AnalyzeVolume#67 Volume F: flushed.

[2013-04-19] [22:22:01,031] 008a0 1 CVolume::ReloadMap#486 MftZone: <230981888, 232448192).

[2013-04-19] [22:22:01,046] 008a0 1 CAnalysis::AnalyzeVolume#83 Initialization finished in 0,917 seconds.

[2013-04-19] [22:22:01,234] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:01,406] 008a0 1 CAnalysis::AnalyzeNtfsVolume#188 ReadFileRecords for 0/16384 took 0,171 seconds.

[2013-04-19] [22:22:01,437] 008a0 1 CAnalysis::AnalyzeNtfsVolume#303 Processing 16384 items took 0,040 seconds.

[2013-04-19] [22:22:01,437] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:01,656] 008a0 1 CAnalysis::AnalyzeNtfsVolume#188 ReadFileRecords for 16385/16384 took 0,208 seconds.

[2013-04-19] [22:22:01,656] 008a0 1 CAnalysis::GetFileFragmentsAndSize#430 ERROR: failed to retrieve file size! (err.code() is 2 for file "?J")

[2013-04-19] [22:22:01,656] 008a0 1 CAnalysis::GetFileFragmentsAndSize#430 ERROR: failed to retrieve file size! (err.code() is 2 for file "?J")

[2013-04-19] [22:22:01,656] 008a0 1 CAnalysis::GetFileFragmentsAndSize#430 ERROR: failed to retrieve file size! (err.code() is 2 for file "?J")

[2013-04-19] [22:22:01,656] 008a0 1 CAnalysis::GetFileFragmentsAndSize#430 ERROR: failed to retrieve file size! (err.code() is 2 for file "?J")

[2013-04-19] [22:22:01,703] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:01,765] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:01,796] 008a0 1 CAnalysis::AnalyzeNtfsVolume#303 Processing 16384 items took 0,139 seconds.

[2013-04-19] [22:22:01,796] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:01,968] 008a0 1 CAnalysis::AnalyzeNtfsVolume#188 ReadFileRecords for 32769/16384 took 0,182 seconds.

[2013-04-19] [22:22:02,046] 008a0 1 CAnalysis::AnalyzeNtfsVolume#303 Processing 16384 items took 0,070 seconds.

[2013-04-19] [22:22:02,046] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:02,187] 008a0 1 CAnalysis::AnalyzeNtfsVolume#188 ReadFileRecords for 49153/16384 took 0,143 seconds.

[2013-04-19] [22:22:02,234] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:02,312] 008a0 1 CAnalysis::AnalyzeNtfsVolume#303 Processing 16384 items took 0,122 seconds.

[2013-04-19] [22:22:02,312] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:02,484] 008a0 1 CAnalysis::AnalyzeNtfsVolume#188 ReadFileRecords for 65537/16384 took 0,179 seconds.

[2013-04-19] [22:22:02,562] 008a0 1 CAnalysis::AnalyzeNtfsVolume#303 Processing 16384 items took 0,074 seconds.

[2013-04-19] [22:22:02,562] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:02,765] 008a0 1 CAnalysis::AnalyzeNtfsVolume#188 ReadFileRecords for 81921/16384 took 0,199 seconds.

[2013-04-19] [22:22:02,843] 008a0 1 CAnalysis::AnalyzeNtfsVolume#303 Processing 16384 items took 0,080 seconds.

[2013-04-19] [22:22:02,843] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:03,000] 008a0 1 CAnalysis::AnalyzeNtfsVolume#188 ReadFileRecords for 98305/16384 took 0,160 seconds.

[2013-04-19] [22:22:03,062] 008a0 1 CAnalysis::AnalyzeNtfsVolume#303 Processing 16384 items took 0,065 seconds.

[2013-04-19] [22:22:03,062] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:03,390] 008a0 1 CAnalysis::AnalyzeNtfsVolume#188 ReadFileRecords for 114689/16384 took 0,322 seconds.

[2013-04-19] [22:22:03,468] 008a0 1 CAnalysis::AnalyzeNtfsVolume#303 Processing 16384 items took 0,087 seconds.

[2013-04-19] [22:22:03,468] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:03,656] 008a0 1 CAnalysis::AnalyzeNtfsVolume#188 ReadFileRecords for 131073/16384 took 0,180 seconds.

[2013-04-19] [22:22:03,734] 008a0 1 CAnalysis::AnalyzeNtfsVolume#303 Processing 16384 items took 0,086 seconds.

[2013-04-19] [22:22:03,734] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:03,937] 008a0 1 CAnalysis::AnalyzeNtfsVolume#188 ReadFileRecords for 147457/16384 took 0,192 seconds.

[2013-04-19] [22:22:04,015] 008a0 1 CAnalysis::AnalyzeNtfsVolume#303 Processing 16384 items took 0,088 seconds.

[2013-04-19] [22:22:04,015] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:04,203] 008a0 1 CAnalysis::AnalyzeNtfsVolume#188 ReadFileRecords for 163841/16384 took 0,179 seconds.

[2013-04-19] [22:22:04,328] 008a0 1 CAnalysis::AnalyzeNtfsVolume#303 Processing 16384 items took 0,124 seconds.

[2013-04-19] [22:22:04,328] 008a0 1 CNtfs::ReadMftVcn#182 Using MFT record data runs for volume F:.

[2013-04-19] [22:22:04,484] 008a0 1 CAnalysis::AnalyzeNtfsVolume#188 ReadFileRecords for 180225/16384 took 0,166 seconds.

 

 

[Nergal]

for some reason your scheduled task is running df.exe /debug

can you schedule it (with ini setting in place)

export the task (for task scheduler)

attach that (as a spoiler, I like that and so do the bugfixers )

optionally do the same thing as well with the fuctioning (non-ini) task

also you can attach txt files (and zips if you'd rather upload the task exports

[St@NNer]

Ok, files attached.

Data.zip

[St@NNer]

In new 2.14.706 this bug exist too.

Attached .job, .ini and .txt files in archive

Defraggler.zip

[piriform4119]

I have experienced this same behavior with versions dating back to version 2.11, i.e. version 2.10 is the last that doesn't do this.

 

I have also double-checked to make sure "Stop VSS when defragmenting NTFS volume" is checked.

 

I am using Windows 7 Ultimate, 64-bit.

 

Thanks

[Nergal]

St@nner, I've not yet been to a computer terminal to see the zips yet, I'm sure the developers are looking into this, and I do plan to once I arrive at a pc.

[piriform4119]

@Nergal, have you been able to check the submitted logs? Can you confirm that the developers are aware of this issue?

 

Thanks

[Winapp2.ini]

The developers read all the threads, and comment if they must. I would imagine they're aware of the issue by now.

[Nergal]

I am just a user like you, I can ban spammers that is another sets me aside from you. As winapp2 said the developers read all threads, they rarely talk unless more data is needed

[piriform4119]

Hi,

 

It's been over two months since the bug was reported, yet there is no fix, suggested workaround, or any kind of acknowledgement from the developers that this is a known problem.

 

At this point, as I don't want to ass-u-me anything, could one of the developers confirm that this is something that is being addressed?

 

Thanks!

[Alan_B]

If you pay for support you may expect the developers to respond directly to you.

 

I am thankful for what they provide free of charge.