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

Defraggler Defraggler Bug Reporting
1

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.

2

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

3

Ok, files attached.

Data.zip

4

In new 2.14.706 this bug exist too.

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

Defraggler.zip

5

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

6

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.

7

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

Thanks

8

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

9

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

10

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!

11

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.