• Announcements

    • xper

      MSFN Sponsorship and AdBlockers!   07/10/2016

      Dear members, MSFN is made available via subscriptions, donations and advertising revenue. The use of ad-blocking software hurts the site. Please disable ad-blocking software or set an exception for MSFN. Alternatively, become a site sponsor and ads will be disabled automatically and by subscribing you get other sponsor benefits.
Sign in to follow this  
Followers 0
Tripredacus

Installing Devices at first boot takes 3 hours

7 posts in this topic

We're testing out a new bare-bone AIO PC that has an Intel DH61AG motherboard in it. After deploying Windows 7 Professional SP1 x86 on it, and during first boot, it was taking an extremely long time to install devices. The system seems fine otherwise, and the first boot after sysprep is normal. No drivers are missing in Device Manager either.

I have confirmed the slowness in the setupact.log (snippet)

2011-08-24 03:07:35, Info                  SYSPRP Status: Total = 175,	Complete = 102, 	Progress = 58%	(58%)
2011-08-24 03:12:34, Info SYSPRP Status: Active
2011-08-24 03:12:34, Info SYSPRP Status: Total = 175, Complete = 103, Progress = 58% (58%)
2011-08-24 03:17:35, Info SYSPRP Status: Active
2011-08-24 03:17:35, Info SYSPRP Status: Total = 175, Complete = 104, Progress = 59% (59%)
2011-08-24 03:22:36, Info SYSPRP Status: Active
2011-08-24 03:22:36, Info SYSPRP Status: Total = 175, Complete = 105, Progress = 60% (60%)
2011-08-24 03:27:36, Info SYSPRP Status: Active
2011-08-24 03:27:36, Info SYSPRP Status: Total = 175, Complete = 106, Progress = 60% (60%)
2011-08-24 03:32:36, Info SYSPRP Status: Active
2011-08-24 03:32:36, Info SYSPRP Status: Total = 175, Complete = 107, Progress = 61% (61%)
2011-08-24 03:37:40, Info SYSPRP Status: Active
2011-08-24 03:37:40, Info SYSPRP Status: Total = 175, Complete = 108, Progress = 61% (61%)
2011-08-24 03:42:40, Info SYSPRP Status: Active
2011-08-24 03:42:40, Info SYSPRP Status: Total = 175, Complete = 109, Progress = 62% (62%)
2011-08-24 03:47:41, Info SYSPRP Status: Active
2011-08-24 03:47:41, Info SYSPRP Status: Total = 175, Complete = 110, Progress = 62% (62%)
2011-08-24 03:52:41, Info SYSPRP Status: Active
2011-08-24 03:52:41, Info SYSPRP Status: Total = 175, Complete = 111, Progress = 63% (63%)
2011-08-24 03:57:41, Info SYSPRP Status: Active
2011-08-24 03:57:41, Info SYSPRP Status: Total = 175, Complete = 112, Progress = 64% (64%)
2011-08-24 04:02:42, Info SYSPRP Status: Active
2011-08-24 04:02:42, Info SYSPRP Status: Total = 175, Complete = 113, Progress = 64% (64%)
2011-08-24 04:02:42, Info SYSPRP Status: Active
2011-08-24 04:02:42, Info SYSPRP Status: Total = 175, Complete = 115, Progress = 65% (65%)
2011-08-24 04:02:42, Info SYSPRP Status: Active
2011-08-24 04:02:42, Info SYSPRP Status: Total = 175, Complete = 118, Progress = 67% (67%)
2011-08-24 04:07:42, Info SYSPRP Status: Active
2011-08-24 04:07:42, Info SYSPRP Status: Total = 175, Complete = 119, Progress = 68% (68%)
2011-08-24 04:07:42, Info SYSPRP Status: Active
2011-08-24 04:07:42, Info SYSPRP Status: Total = 175, Complete = 124, Progress = 70% (70%)
2011-08-24 04:12:42, Info SYSPRP Status: Active
2011-08-24 04:12:42, Info SYSPRP Status: Total = 175, Complete = 125, Progress = 71% (71%)
2011-08-24 04:17:44, Info SYSPRP Status: Active
2011-08-24 04:17:44, Info SYSPRP Status: Total = 175, Complete = 126, Progress = 72% (72%)
2011-08-24 04:22:45, Info SYSPRP Status: Active
2011-08-24 04:22:45, Info SYSPRP Status: Total = 175, Complete = 127, Progress = 72% (72%)
2011-08-24 04:27:45, Info SYSPRP Status: Active
2011-08-24 04:27:45, Info SYSPRP Status: Total = 175, Complete = 128, Progress = 73% (73%)
2011-08-24 04:32:45, Info SYSPRP Status: Active
2011-08-24 04:32:45, Info SYSPRP Status: Total = 175, Complete = 129, Progress = 73% (73%)
2011-08-24 04:37:46, Info SYSPRP Status: Active
2011-08-24 04:37:46, Info SYSPRP Status: Total = 175, Complete = 130, Progress = 74% (74%)
2011-08-24 04:37:46, Info SYSPRP Status: Active
2011-08-24 04:37:46, Info SYSPRP Status: Total = 175, Complete = 133, Progress = 76% (76%)
2011-08-24 04:42:46, Info SYSPRP Status: Active
2011-08-24 04:42:46, Info SYSPRP Status: Total = 175, Complete = 134, Progress = 76% (76%)
2011-08-24 04:47:46, Info SYSPRP Status: Active
2011-08-24 04:47:46, Info SYSPRP Status: Total = 175, Complete = 135, Progress = 77% (77%)
2011-08-24 04:52:48, Info SYSPRP Status: Active
2011-08-24 04:52:48, Info SYSPRP Status: Total = 175, Complete = 137, Progress = 78% (78%)
2011-08-24 04:52:48, Info SYSPRP Status: Active
2011-08-24 04:52:48, Info SYSPRP Status: Total = 175, Complete = 137, Progress = 78% (78%)
2011-08-24 04:57:48, Info SYSPRP Status: Active
2011-08-24 04:57:48, Info SYSPRP Status: Total = 175, Complete = 138, Progress = 78% (78%)
2011-08-24 05:02:48, Info SYSPRP Status: Active
2011-08-24 05:02:48, Info SYSPRP Status: Total = 175, Complete = 139, Progress = 79% (79%)
2011-08-24 05:07:49, Info SYSPRP Status: Active
2011-08-24 05:07:49, Info SYSPRP Status: Total = 175, Complete = 140, Progress = 80% (80%)
2011-08-24 05:12:49, Info SYSPRP Status: Active
2011-08-24 05:12:49, Info SYSPRP Status: Total = 175, Complete = 141, Progress = 80% (80%)
2011-08-24 05:17:49, Info SYSPRP Status: Active
2011-08-24 05:17:49, Info SYSPRP Status: Total = 175, Complete = 143, Progress = 81% (81%)
2011-08-24 05:17:49, Info SYSPRP Status: Active
2011-08-24 05:17:49, Info SYSPRP Status: Total = 175, Complete = 146, Progress = 83% (83%)
2011-08-24 05:22:49, Info SYSPRP Status: Active
2011-08-24 05:22:49, Info SYSPRP Status: Total = 175, Complete = 149, Progress = 85% (85%)
2011-08-24 05:22:49, Info SYSPRP Status: Active
2011-08-24 05:22:49, Info SYSPRP Status: Total = 175, Complete = 149, Progress = 85% (85%)
2011-08-24 05:27:50, Info SYSPRP Status: Active
2011-08-24 05:27:50, Info SYSPRP Status: Total = 175, Complete = 150, Progress = 85% (85%)
2011-08-24 05:32:50, Info SYSPRP Status: Active
2011-08-24 05:32:50, Info SYSPRP Status: Total = 175, Complete = 153, Progress = 87% (87%)
2011-08-24 05:32:50, Info SYSPRP Status: Active
2011-08-24 05:32:50, Info SYSPRP Status: Total = 175, Complete = 153, Progress = 87% (87%)
2011-08-24 05:37:50, Info SYSPRP Status: Active
2011-08-24 05:37:50, Info SYSPRP Status: Total = 175, Complete = 154, Progress = 88% (88%)
2011-08-24 05:37:50, Info SYSPRP Status: Active
2011-08-24 05:37:50, Info SYSPRP Status: Total = 175, Complete = 155, Progress = 88% (88%)
2011-08-24 05:37:50, Info SYSPRP Status: Active
2011-08-24 05:37:50, Info SYSPRP Status: Total = 175, Complete = 156, Progress = 89% (89%)
2011-08-24 05:42:51, Info SYSPRP Status: Active
2011-08-24 05:42:51, Info SYSPRP Status: Total = 175, Complete = 157, Progress = 89% (89%)
2011-08-24 05:42:51, Info SYSPRP Status: Active
2011-08-24 05:42:51, Info SYSPRP Status: Total = 175, Complete = 158, Progress = 90% (90%)
2011-08-24 05:42:51, Info SYSPRP Status: Active
2011-08-24 05:42:51, Info SYSPRP Status: Total = 175, Complete = 160, Progress = 91% (91%)
2011-08-24 05:47:51, Info SYSPRP Status: Active
2011-08-24 05:47:51, Info SYSPRP Status: Total = 175, Complete = 163, Progress = 93% (93%)
2011-08-24 05:47:51, Info SYSPRP Status: Active
2011-08-24 05:47:51, Info SYSPRP Status: Total = 175, Complete = 163, Progress = 93% (93%)
2011-08-24 05:52:51, Info SYSPRP Status: Active
2011-08-24 05:52:51, Info SYSPRP Status: Total = 175, Complete = 164, Progress = 93% (93%)
2011-08-24 05:52:51, Info SYSPRP Status: Active
2011-08-24 05:52:51, Info SYSPRP Status: Total = 175, Complete = 165, Progress = 94% (94%)
2011-08-24 05:52:51, Info SYSPRP Status: Active
2011-08-24 05:52:51, Info SYSPRP Status: Total = 175, Complete = 166, Progress = 94% (94%)
2011-08-24 05:57:51, Info SYSPRP Status: Active
2011-08-24 05:57:51, Info SYSPRP Status: Total = 175, Complete = 169, Progress = 96% (96%)
2011-08-24 05:57:51, Info SYSPRP Status: Active
2011-08-24 05:57:51, Info SYSPRP Status: Total = 175, Complete = 169, Progress = 96% (96%)
2011-08-24 06:02:52, Info SYSPRP Status: Active
2011-08-24 06:02:52, Info SYSPRP Status: Total = 175, Complete = 170, Progress = 97% (97%)
2011-08-24 06:07:52, Info SYSPRP Status: Active
2011-08-24 06:07:52, Info SYSPRP Status: Total = 175, Complete = 173, Progress = 98% (98%)
2011-08-24 06:07:52, Info SYSPRP Status: Active
2011-08-24 06:07:52, Info SYSPRP Status: Total = 175, Complete = 173, Progress = 98% (98%)
2011-08-24 06:12:52, Info SYSPRP Status: Active
2011-08-24 06:12:52, Info SYSPRP Status: Total = 175, Complete = 174, Progress = 99% (99%)
2011-08-24 06:17:52, Info SYSPRP Status: End

Windeploy log from setupact.log in UnattendGC folder:

2011-08-24 03:06:25, Info                         [windeploy.exe] ------------------------------------------------
2011-08-24 03:06:25, Info [windeploy.exe] WinDeploy.exe launched with command-line []...
2011-08-24 03:06:25, Info [windeploy.exe] Setup has not completed, adding pending reboot.
2011-08-24 03:06:25, Info [windeploy.exe] Found generalization state [0x4], setup.exe completion flag [False] --> launching setup.exe.
2011-08-24 03:06:25, Info [windeploy.exe] Launching [C:\windows\system32\oobe\setup.exe]...
2011-08-24 06:18:03, Info [windeploy.exe] Process exited with exit code [0x0]
2011-08-24 06:18:03, Info [windeploy.exe] Found completion flag [True], reboot requested flag [True] --> rebooting computer before proceeding with deployment...
2011-08-24 06:18:03, Info [windeploy.exe] Making sure that SystemSetupInProgress is cleared.
2011-08-24 06:18:03, Info [windeploy.exe] An immediate reboot or shutdown was requested/required... rebooting / shutting down computer
2011-08-24 06:18:03, Info [windeploy.exe] Flushing registry to disk...
2011-08-24 06:18:04, Info [windeploy.exe] Flush took 718 ms.
2011-08-24 06:18:04, Info [windeploy.exe] WinDeploy.exe exiting with code [0x0]

There are no other logs for the relevant timeframe of 2011-08-24 03:07 to 06:18. Note: Date and time is incorrect due to the BIOS not being updated yet.

And as such, this is a known working image on other hardware.

0

Share this post


Link to post
Share on other sites

what about the 2 log files in \Windows\inf folder

setupapi.app.log

setupapi.dev.log

They may show which driver installation hangs so long.

0

Share this post


Link to post
Share on other sites

I knew I was forgetting something. I'll have to shelve this project for now since they shipped the unit... :realmad:

I'll try to get a recreation done at another location, might take a few days tho.

0

Share this post


Link to post
Share on other sites

USB Root Hub 3:07 - 3:12 (5 minutes)

>>>  [Setup online Device Install (Hardware initiated) - usb\root_hub20\4&133b7487&0]
>>> Section start 2011/08/24 03:07:27.506
dvi: Searching for hardware ID(s):
dvi: usb\root_hub20&vid8086&pid1c2d&rev0005
dvi: usb\root_hub20&vid8086&pid1c2d
dvi: usb\root_hub20
dvi: HardwareID - USB\ROOT_HUB20
dvi: InfName - C:\windows\System32\DriverStore\FileRepository\usbport.inf_x86_neutral_f9abf85fd00186bd\usbport.inf
ump: Server install process exited with code 0x000005b4 03:12:34.920
<<< Section end 2011/08/24 03:12:34.920
<<< [Exit status: FAILURE(0x000005b4)]

Numeric Data Processor 3:07 - ( minutes)

>>>  [Setup online Device Install (Hardware initiated) - acpi\pnp0c04\4&e214901&0]
>>> Section start 2011/08/24 03:07:35.212
dvi: HardwareID - *PNP0C04
ndv: Waiting for previous device install to complete. 03:07:35.586
ndv: Waiting for previous device install to complete. 03:08:35.600
ndv: Waiting for previous device install to complete. 03:09:35.613
ndv: Waiting for previous device install to complete. 03:10:35.626
ndv: Waiting for previous device install to complete. 03:11:35.640
ndv: Waiting for previous device install to complete. 03:12:35.653
ndv: Waiting for previous device install to complete. 03:13:35.666
ndv: Waiting for previous device install to complete. 03:14:35.680
ndv: Waiting for previous device install to complete. 03:15:35.693
ndv: Waiting for previous device install to complete. 03:16:35.706
ndv: Waiting for previous device install to complete. 03:17:35.720
ndv: Waiting for previous device install to complete. 03:18:35.733
ndv: Waiting for previous device install to complete. 03:19:35.746
ndv: Waiting for previous device install to complete. 03:20:35.759
ndv: Waiting for previous device install to complete. 03:21:35.773
ndv: Waiting for previous device install to complete. 03:22:35.786
ndv: Waiting for previous device install to complete. 03:23:35.799
ndv: Waiting for previous device install to complete. 03:24:35.813
ndv: Waiting for previous device install to complete. 03:25:35.826
ndv: Waiting for previous device install to complete. 03:26:35.839
ndv: Waiting for previous device install to complete. 03:27:35.853
ndv: Waiting for previous device install to complete. 03:28:35.866
ndv: Waiting for previous device install to complete. 03:29:35.879
ndv: Waiting for previous device install to complete. 03:30:35.892
ndv: Waiting for previous device install to complete. 03:31:35.906
ndv: Waiting for previous device install to complete. 03:32:35.919
ndv: Waiting for previous device install to complete. 03:33:35.932
ndv: Waiting for previous device install to complete. 03:34:35.946
ndv: Waiting for previous device install to complete. 03:35:35.959
ndv: Waiting for previous device install to complete. 03:36:35.972
ndv: Waiting for previous device install to complete. 03:37:35.986
ump: Server install process exited with code 0x000005b4 03:42:40.763
<<< Section end 2011/08/24 03:42:40.763
<<< [Exit status: FAILURE(0x000005b4)]

There are a bunch of other "waiting for previous device install to complete" messages for other drivers, such as:

ndv: Waiting for previous device install to complete. 03:32:37.151
ndv: Waiting for previous device install to complete. 03:33:37.165
ndv: Waiting for previous device install to complete. 03:34:37.178
ndv: Waiting for previous device install to complete. 03:35:37.191
ndv: Waiting for previous device install to complete. 03:36:37.205
ndv: Waiting for previous device install to complete. 03:37:37.218
ndv: Waiting for previous device install to complete. 03:38:37.231
ndv: Waiting for previous device install to complete. 03:39:37.245
ndv: Waiting for previous device install to complete. 03:40:37.258
ndv: Waiting for previous device install to complete. 03:41:37.271
ndv: Waiting for previous device install to complete. 03:42:37.285
ndv: Waiting for previous device install to complete. 03:43:37.298
ndv: Waiting for previous device install to complete. 03:44:37.311
ndv: Waiting for previous device install to complete. 03:45:37.324
ndv: Waiting for previous device install to complete. 03:46:37.338
ndv: Waiting for previous device install to complete. 03:47:37.351
ndv: Waiting for previous device install to complete. 03:48:37.364
ndv: Waiting for previous device install to complete. 03:49:37.378
ndv: Waiting for previous device install to complete. 03:50:37.391
ndv: Waiting for previous device install to complete. 03:51:37.404
ndv: Waiting for previous device install to complete. 03:52:37.418
ndv: Waiting for previous device install to complete. 03:53:37.431
ndv: Waiting for previous device install to complete. 03:54:37.444
ndv: Waiting for previous device install to complete. 03:55:37.458
ndv: Waiting for previous device install to complete. 03:56:37.471
ndv: Waiting for previous device install to complete. 03:57:37.484
ndv: Waiting for previous device install to complete. 03:58:37.497
ndv: Waiting for previous device install to complete. 03:59:37.511
ndv: Waiting for previous device install to complete. 04:00:37.524
ndv: Waiting for previous device install to complete. 04:01:37.537
ndv: Waiting for previous device install to complete. 04:02:37.551
ndv: Waiting for previous device install to complete. 04:03:37.564
ndv: Waiting for previous device install to complete. 04:04:37.577
ndv: Waiting for previous device install to complete. 04:05:37.591
ndv: Waiting for previous device install to complete. 04:06:37.604
ndv: Waiting for previous device install to complete. 04:07:37.617
ndv: Waiting for previous device install to complete. 04:08:37.630
ndv: Waiting for previous device install to complete. 04:09:37.644
ndv: Waiting for previous device install to complete. 04:10:37.657
ndv: Waiting for previous device install to complete. 04:11:37.670
ndv: Waiting for previous device install to complete. 04:12:37.684
ndv: Waiting for previous device install to complete. 04:13:37.697
ndv: Waiting for previous device install to complete. 04:14:37.710
ndv: Waiting for previous device install to complete. 04:15:37.724
ndv: Waiting for previous device install to complete. 04:16:37.737
ndv: Waiting for previous device install to complete. 04:17:37.750

Quite a few have that failure message as well. Setup.app.log doesn't show anything interesting.

See attachment for full setupapi.dev.log (rar)

setupapi.dev.rar

0

Share this post


Link to post
Share on other sites

Does a stock Win7 install do this on the same hardware as well?

0

Share this post


Link to post
Share on other sites

Does a stock Win7 install do this on the same hardware as well?

Not so far that we can tell, however the site that had done the (Ultimate) DVD install is notorious for not relaying problems back to us...

I know they did not use the WDS to image it because I haven't put the NIC drivers in the boot.wim yet. :sneaky:

We have another one coming in I can test again. I'll at least try deploying another (different) image to it.

0

Share this post


Link to post
Share on other sites

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!


Register a new account

Sign in

Already have an account? Sign in here.


Sign In Now
Sign in to follow this  
Followers 0

  • Recently Browsing   0 members

    No registered users viewing this page.