MSFN Forum: Trace Windows 7 boot/shutdown/hibernate/standby/resume issues - MSFN Forum

Jump to content


Windows 7 forum rules

If you have questions about customizing Windows 7 that are vLite-specific, please post them in the vLite forum, not here. If you have questions regarding the unattended installation of Windows 7, please post them in the Unattended Windows 7/Server 2008 R2 section.
  • 49 Pages +
  • « First
  • 38
  • 39
  • 40
  • 41
  • 42
  • Last »
  • You cannot start a new topic
  • You cannot reply to this topic

Trace Windows 7 boot/shutdown/hibernate/standby/resume issues

#781 User is offline   doveman 

  • Member
  • PipPip
  • Group: Members
  • Posts: 231
  • Joined: 22-August 05

Posted 16 August 2012 - 08:45 AM

Hi MagicAndre, thanks for this guide.

I'm having a problem where W7 is taking ages to boot to the GUI. I recently installed the ATI SB drivers and then my PC wouldn't boot anymore (BSOD) so I had to boot to Last Known Configuration which worked and has now become the default, with ControlSet001 marked as failed.

I obviously haven't tried installing the ATI SB Drivers again as I don't want to mess it up again, so it's using the MS AHCI SATA driver at the moment. I've set the boot options, NoGUI and OS Boot Information and the last thing I see loading is atipcie.sys and then it doesn't seem to do anything for ages before the Welcome screen appears.

Looking at the trace, PreSMSS takes 5.9s, SMSSInit 11s, WinlogonInit 5.7s, ExplorerInit 8.3s, PostExplorerPeriod 40s, TraceTail 84s. As the delay I'm concerned about is in the pre-GUI stages, I'm guessing the problems in the SMSSInit stage but it sure feels like longer than 11s. I'll go and time it to check.

I also checked the start parameters of the AHCI/ATI drivers in the two ControlSets and this is what I found:

ControlSet001 (Failed/BSOD):
amdsata - 0
amdide - 3
atipcie - 0
msahci - 0

ControlSet002 (Current/Working Set):
amdsata - 3
amdide - 3
atipcie - 0
msahci - 0

Can I upload the trace for you to look at? Will the summary_boot.xml be sufficient or do you need the 377MB .etl?

EDIT: As I suspected, the delay is much longer than the trace suggests. From the boot.ini countdown finishing to the last driver in the list (atipcie.sys) being shown was 5s, then the GUI didn't appear for another 1min22s and it was about another 35s until everything finished loading.

This post has been edited by doveman: 16 August 2012 - 08:53 AM



#782 User is offline   MagicAndre1981 

  • after Windows 7 GA still Vista lover :)
  • Group: Patrons
  • Posts: 4,981
  • Joined: 28-August 05
  • OS:Vista Ultimate x86
  • Country: Country Flag

Posted 16 August 2012 - 11:44 AM

compress the large ETL as 7z, this dramatically reduces the size.

#783 User is offline   doveman 

  • Member
  • PipPip
  • Group: Members
  • Posts: 231
  • Joined: 22-August 05

Posted 16 August 2012 - 01:14 PM

Indeed it does. It's only 38MB now! http://www.mediafire...dor41dw2kan981k

#784 User is offline   MagicAndre1981 

  • after Windows 7 GA still Vista lover :)
  • Group: Patrons
  • Posts: 4,981
  • Joined: 28-August 05
  • OS:Vista Ultimate x86
  • Country: Country Flag

Posted 16 August 2012 - 03:27 PM

the only delay you may see is the run of the program which his added in the key BootExecuteList and the Initialization of the registry:

Attached File  SMSSInit_.png (21.86K)
Number of downloads: 18

There is also a small delay (2.5s) where Windows tries to enum all IDE divices:

<phase name="bootStart" startTime="30" endTime="3249" duration="3218">
  <pnpObject name="PCIIDE\IDEChannel\4+21254128+0+0" type="Device" activity="Enum" startTime="267" endTime="2779" duration="2511" prePendTime="0" description="IDE Channel" friendlyName="ATA Channel 0" /> 



Also starting AntiVir service takes 11s:

Attached File  services_AntiVir.png (48.36K)
Number of downloads: 11

The largest delay is the run of all startup programs which takes 30.5s ( postBootDisturbance="30500")

Uninstall tools like the AMD Control Center and other stuff you don't need.

#785 User is offline   doveman 

  • Member
  • PipPip
  • Group: Members
  • Posts: 231
  • Joined: 22-August 05

Posted 16 August 2012 - 04:44 PM

Thanks for analysing it for me but that doesn't seem to explain the 1min22s delay between atipcie.sys loading and the GUI / Welcome screen appearing, which is what I'm bothered about.

#786 User is offline   MagicAndre1981 

  • after Windows 7 GA still Vista lover :)
  • Group: Patrons
  • Posts: 4,981
  • Joined: 28-August 05
  • OS:Vista Ultimate x86
  • Country: Country Flag

Posted 17 August 2012 - 02:14 PM

I can't see such an delay. Your Windows boots in 31s to the desktop and is fully booted in 61s:

timing bootDoneViaExplorer="31063" bootDoneViaPostBoot="71563"


:blink:

#787 User is offline   doveman 

  • Member
  • PipPip
  • Group: Members
  • Posts: 231
  • Joined: 22-August 05

Posted 17 August 2012 - 03:08 PM

View PostMagicAndre1981, on 17 August 2012 - 02:14 PM, said:

I can't see such an delay. Your Windows boots in 31s to the desktop and is fully booted in 61s:

timing bootDoneViaExplorer="31063" bootDoneViaPostBoot="71563"


:blink:


I wish it did ;) Wierd the delay's not captured in the trace. I'll try and make another one as I didn't actually time it when making that one but rebooted and timed it then. The delay happens every time I boot though.

#788 User is offline   doveman 

  • Member
  • PipPip
  • Group: Members
  • Posts: 231
  • Joined: 22-August 05

Posted 18 August 2012 - 09:16 AM

Well I've double-checked and whether I'm doing the trace or booting normally, there's a 1min26s delay between atipcie.sys loading and the Welcome screen appearing, so the bootDoneViaExplorer="32867" is nonsense :huh:

#789 User is offline   MagicAndre1981 

  • after Windows 7 GA still Vista lover :)
  • Group: Patrons
  • Posts: 4,981
  • Joined: 28-August 05
  • OS:Vista Ultimate x86
  • Country: Country Flag

Posted 18 August 2012 - 12:18 PM

can you make some more traces? Add -numRuns X to the commandline (replace X with a number) to make several traces. Do you see the delay and xbootmgr doesn't capture them?

#790 User is offline   doveman 

  • Member
  • PipPip
  • Group: Members
  • Posts: 231
  • Joined: 22-August 05

Posted 18 August 2012 - 02:38 PM

View PostMagicAndre1981, on 18 August 2012 - 12:18 PM, said:

Do you see the delay and xbootmgr doesn't capture them?


If you're asking whether xbootmgr's trace is showing the 1min26s delay that happens on every boot, no it doesn't.

Quote

can you make some more traces? Add -numRuns X to the commandline (replace X with a number) to make several traces.


I could do but I'm not sure I see any point. The delay happens every boot and neither of the two traces I've done so far reflect it, so for whatever reason xbootmgr doesn't appear to notice it and I don't see why making it repeat x times would change that.

#791 User is offline   MagicAndre1981 

  • after Windows 7 GA still Vista lover :)
  • Group: Patrons
  • Posts: 4,981
  • Joined: 28-August 05
  • OS:Vista Ultimate x86
  • Country: Country Flag

Posted 19 August 2012 - 11:40 AM

I think it doesn't show because the delay only occurs when you activate the boot logging with F8. Now Windows runs in old 640*480 pixel mode which is slow to switch to the full resolution. This was also the cause for slow Vista boot issues. And I think this logging causes the issues.

#792 User is offline   doveman 

  • Member
  • PipPip
  • Group: Members
  • Posts: 231
  • Joined: 22-August 05

Posted 19 August 2012 - 04:12 PM

View PostMagicAndre1981, on 19 August 2012 - 11:40 AM, said:

I think it doesn't show because the delay only occurs when you activate the boot logging with F8. Now Windows runs in old 640*480 pixel mode which is slow to switch to the full resolution. This was also the cause for slow Vista boot issues. And I think this logging causes the issues.


Ah OK, maybe that's it. I can't recall if I had the delay before and that's why I activated the logging (with the bootlog option in msconfig, rather than F8) but I'll try deactivating that and see if it speeds it up.

#793 User is offline   doveman 

  • Member
  • PipPip
  • Group: Members
  • Posts: 231
  • Joined: 22-August 05

Posted 22 August 2012 - 04:45 PM

View PostMagicAndre1981, on 19 August 2012 - 11:40 AM, said:

I think it doesn't show because the delay only occurs when you activate the boot logging with F8. Now Windows runs in old 640*480 pixel mode which is slow to switch to the full resolution. This was also the cause for slow Vista boot issues. And I think this logging causes the issues.


Well I disabled the Noguiboot and Show Boot Info options in msconfig and now it takes just as long but just doesn't show anything for about 1.5mins until the Windows loading animation appears, followed not too long after by the Welcome screen.

#794 User is offline   MagicAndre1981 

  • after Windows 7 GA still Vista lover :)
  • Group: Patrons
  • Posts: 4,981
  • Joined: 28-August 05
  • OS:Vista Ultimate x86
  • Country: Country Flag

Posted 23 August 2012 - 05:07 AM

make a new boot trace

#795 User is offline   robe 

  • Group: Members
  • Posts: 4
  • Joined: 29-August 12
  • OS:Windows 7 x64
  • Country: Country Flag

Posted 29 August 2012 - 11:11 AM

Hi MagicAndre!

Thanks for this tutorial!
I've got an ASUS X53SV that takes about 2 min to boot. I've been checking Event Handler and boot logs but I cant figure it out. Now I have tried to do a boot trace and I cant make out what take so long time booting.
I wonder if you could take a look at it?

The laptop got Intel Core i5 2410M, Geforce GT 540M graphics card, 6 GB RAM.

Best regards

#796 User is offline   MagicAndre1981 

  • after Windows 7 GA still Vista lover :)
  • Group: Patrons
  • Posts: 4,981
  • Joined: 28-August 05
  • OS:Vista Ultimate x86
  • Country: Country Flag

Posted 29 August 2012 - 01:54 PM

Run the xbootmgr command and try to follow the steps.

If this is too complicated, compress the ETl as 7z or RAR and upload it to Dropbox and post the link here.

#797 User is offline   robe 

  • Group: Members
  • Posts: 4
  • Joined: 29-August 12
  • OS:Windows 7 x64
  • Country: Country Flag

Posted 29 August 2012 - 04:02 PM

View PostMagicAndre1981, on 29 August 2012 - 01:54 PM, said:

Run the xbootmgr command and try to follow the steps.

If this is too complicated, compress the ETl as 7z or RAR and upload it to Dropbox and post the link here.


I've sent a PM with a link to you.
I have been checking the etl but I cant figure out why it is taking so long to boot. Also I couldnt put DRIVERS in the command because I got blue screen...

Thanks for looking into it!

This post has been edited by robe: 29 August 2012 - 04:26 PM


#798 User is offline   MagicAndre1981 

  • after Windows 7 GA still Vista lover :)
  • Group: Patrons
  • Posts: 4,981
  • Joined: 28-August 05
  • OS:Vista Ultimate x86
  • Country: Country Flag

Posted 30 August 2012 - 11:36 AM

the trace doesn't include all data. The services.exe causes a high CPU usage and I need the callstack data. Disable Paging Executive first:

REG ADD "HKLM\System\CurrentControlSet\Control\Session Manager\Memory Management" -v DisablePagingExecutive -d 0x1 -t REG_DWORD -f 


reboot and next run this command:

xbootmgr -trace boot -traceFlags BASE+CSWITCH+DRIVERS+POWER -stackwalk profile



and upload the trace.

#799 User is offline   robe 

  • Group: Members
  • Posts: 4
  • Joined: 29-August 12
  • OS:Windows 7 x64
  • Country: Country Flag

Posted 31 August 2012 - 03:39 AM

View PostMagicAndre1981, on 30 August 2012 - 11:36 AM, said:

the trace doesn't include all data. The services.exe causes a high CPU usage and I need the callstack data. Disable Paging Executive first:

REG ADD "HKLM\System\CurrentControlSet\Control\Session Manager\Memory Management" -v DisablePagingExecutive -d 0x1 -t REG_DWORD -f 


reboot and next run this command:

xbootmgr -trace boot -traceFlags BASE+CSWITCH+DRIVERS+POWER -stackwalk profile



and upload the trace.


I tried the commands but I got a blue screen, said something about USB drivers.
After restarting the laptop it booted up with latest working config.

Tried it again without DRIVERS in the command. Sent you a PM with the link to the new boot trace.

This post has been edited by robe: 31 August 2012 - 04:10 AM


#800 User is offline   MagicAndre1981 

  • after Windows 7 GA still Vista lover :)
  • Group: Patrons
  • Posts: 4,981
  • Joined: 28-August 05
  • OS:Vista Ultimate x86
  • Country: Country Flag

Posted 31 August 2012 - 08:57 AM

the new trace doesn't show the high CPU usage of services.exe for such a long time, only some smal spikes :wacko:

The high CPu usage spikes show reguistry issus:

ntkrnlmp.exe!NtInitializeRegistry and later ntkrnlmp.exe!CmpSaveBootControlSet caus the high CPU usage. So Windows hangs while scanning the registry keys.

According to it, it boots in45s to the desktop and is fully booted in 98s.

<timing bootDoneViaExplorer="45570" bootDoneViaPostBoot="108170" osLoaderDuration="3227" postBootRequiredIdleTime="10000" postBootDisturbance="52600"



Here I can see that the Avast, the Atheros and the Windows Image Acquisition service cause delays:

Attached File  service.png (72.49K)
Number of downloads: 11

The Windows Image Acquisition service hang maybe caused by your Logitech USB Camera (HD Webcam C270), so disable the webcam and enable it only if you need it).

The largest delay is the amount of tools you run at startup. Use AutoRuns to disable useless tools.

Share this topic:


  • 49 Pages +
  • « First
  • 38
  • 39
  • 40
  • 41
  • 42
  • Last »
  • You cannot start a new topic
  • You cannot reply to this topic

10 User(s) are reading this topic
0 members, 10 guests, 0 anonymous users



All trademarks mentioned on this page are the property of their respective owners
Copyright © 2001 - 2013 msfn.org
Privacy Policy