5 Replies Latest reply on Jun 19, 2012 6:22 AM by Iain Taylor

    Windows PXE Provisioning hanging

    Iain Taylor

      Hi I am trying to provision w2k8 servers using the pxe process, I am getting to stage 14/16 and then it seems to hang

      I am using version 8.1.04 installed on RHEL.

      The server OS seems to be getting installed correctly and the agent is then getting installed. Could it be that the BMI callback port has not been opened to the apps server from the Provisioning VLAN or could there be something else.

       

      Please see below for the latest Provision history and the runonce log.

       

       

      Provision history log

       

      Info        Jun 15, 2012 12:09:21 PM              "c:\BLProv>net use k: \\10.66.214.45\datastore  ***********  /user:10.66.214.45\pxeuser "

      The command completed successfully.

       

       

      X:\Windows\system32>imagex /apply "k:\WIM_Images\W2008x64R2S1.wim" 1 C:

       

      ImageX Tool for Windows

      Copyright (C) Microsoft Corp. All rights reserved.

      Version: 6.1.7600.16385

       

      [   0% ] Applying progress

      [   0% ] Applying progress

      [   1% ] Applying progress

      [   2% ] Applying progress

      [   3% ] Applying progress

      [   4% ] Applying progress: 4:17 mins remaining

      [   5% ] Applying progress: 4:36 mins remaining

      [   6% ] Applying progress: 4:44 mins remaining

      [   7% ] Applying progress: 4:38 mins remaining

      [   8% ] Applying progress: 4:28 mins remaining

      [   9% ] Applying progress: 4:23 mins remaining

      [  10% ] Applying progress: 4:25 mins remaining

      [  11% ] Applying progress: 4:29 mins remaining

      [  12% ] Applying progress: 4:34 mins remaining

      [  13% ] Applying progress: 4:38 mins remaining

      [  14% ] Applying progress: 4:40 mins remaining

      [  15% ] Applying progress: 4:44 mins remaining

      [  16% ] Applying progress: 4:45 mins remaining

      [  17% ] Applying progress: 4:43 mins remaining

      [  18% ] Applying progress: 4:39 mins remaining

      [  19% ] Applying progress: 4:34 mins remaining

      [  20% ] Applying progress: 4:29 mins remaining

      [  21% ] Applying progress: 4:24 mins remaining

      [  22% ] Applying progress: 4:21 mins remaining

      [  23% ] Applying progress: 4:18 mins remaining

      [  24% ] Applying progress: 4:14 mins remaining

      [  25% ] Applying progress: 4:10 mins remaining

      [  26% ] Applying progress: 4:07 mins remaining

      [  27% ] Applying progress: 4:02 mins remaining

      [  28% ] Applying progress: 3:57 mins remaining

      [  29% ] Applying progress: 3:56 mins remaining

      [  30% ] Applying progress: 3:56 mins remaining

      [  31% ] Applying progress: 3:58 mins remaining

      [  32% ] Applying progress: 3:59 mins remaining

      [  33% ] Applying progress: 3:55 mins remaining

      [  34% ] Applying progress: 3:52 mins remaining

      [  35% ] Applying progress: 3:47 mins remaining

      [  36% ] Applying progress: 3:42 mins remaining

      [  37% ] Applying progress: 3:39 mins remaining

      [  38% ] Applying progress: 3:35 mins remaining

      [  39% ] Applying progress: 3:31 mins remaining

      [  40% ] Applying progress: 3:27 mins remaining

      [  41% ] Applying progress: 3:22 mins remaining

      [  42% ] Applying progress: 3:17 mins remaining

      [  43% ] Applying progress: 3:12 mins remaining

      [  44% ] Applying progress: 3:07 mins remaining

      [  45% ] Applying progress: 3:03 mins remaining

      [  46% ] Applying progress: 2:58 mins remaining

      [  47% ] Applying progress: 2:54 mins remaining

      [  48% ] Applying progress: 2:50 mins remaining

      [  49% ] Applying progress: 2:46 mins remaining

      [  50% ] Applying progress: 2:43 mins remaining

      [  51% ] Applying progress: 2:40 mins remaining

      [  52% ] Applying progress: 2:37 mins remaining

      [  53% ] Applying progress: 2:33 mins remaining

      [  54% ] Applying progress: 2:31 mins remaining

      [  55% ] Applying progress: 2:27 mins remaining

      [  56% ] Applying progress: 2:23 mins remaining

      [  57% ] Applying progress: 2:19 mins remaining

      [  58% ] Applying progress: 2:15 mins remaining

      [  59% ] Applying progress: 2:12 mins remaining

      [  60% ] Applying progress: 2:08 mins remaining

      [  61% ] Applying progress: 2:05 mins remaining

      [  62% ] Applying progress: 2:02 mins remaining

      [  63% ] Applying progress: 1:59 mins remaining

      [  64% ] Applying progress: 1:57 mins remaining

      [  65% ] Applying progress: 1:53 mins remaining

      [  66% ] Applying progress: 1:52 mins remaining

      [  67% ] Applying progress: 1:49 mins remaining

      [  68% ] Applying progress: 1:47 mins remaining

      [  69% ] Applying progress: 1:44 mins remaining

      [  70% ] Applying progress: 1:40 mins remaining

      [  71% ] Applying progress: 1:37 mins remaining

      [  72% ] Applying progress: 1:33 mins remaining

      [  73% ] Applying progress: 1:30 mins remaining

      [  74% ] Applying progress: 1:27 mins remaining

      [  75% ] Applying progress: 1:23 mins remaining

      [  76% ] Applying progress: 1:20 mins remaining

      [  77% ] Applying progress: 1:16 mins remaining

      [  78% ] Applying progress: 1:12 mins remaining

      [  79% ] Applying progress: 1:08 mins remaining

      [  80% ] Applying progress: 1:04 mins remaining

      [  81% ] Applying progress: 60 secs remaining

      [  82% ] Applying progress: 58 secs remaining

      [  83% ] Applying progress: 54 secs remaining

      [  84% ] Applying progress: 51 secs remaining

      [  85% ] Applying progress: 48 secs remaining

      [  86% ] Applying progress: 44 secs remaining

      [  87% ] Applying progress: 41 secs remaining

      [  88% ] Applying progress: 38 secs remaining

      [  89% ] Applying progress: 35 secs remaining

      [  90% ] Applying progress: 32 secs remaining

      [  91% ] Applying progress: 29 secs remaining

      [  92% ] Applying progress: 26 secs remaining

      [  93% ] Applying progress: 23 secs remaining

      [  94% ] Applying progress: 16 secs remaining

      [  95% ] Applying progress: 4 secs remaining

      [  96% ] Applying progress: 5 secs remaining

      [  97% ] Applying progress: 6 secs remaining

      [  98% ] Applying progress: 4 secs remaining

      [  99% ] Applying progress: 1 secs remaining

      [ 100% ] Applying progress

      [ 100% ] Applying progress

       

       

      Successfully applied image.

      Total elapsed time: 6 min 39 sec

       

       

       

      X:\Windows\system32>mkdir C:\Windows\Panther\Unattend

       

      X:\Windows\system32>copy c:\BLProv\unattend.txt C:\Windows\Panther\Unattend\Unattend.xml 

              1 file(s) copied.

       

      Info        Jun 15, 2012 12:09:18 PM              Completed Make Run Once

      Info        Jun 15, 2012 12:02:32 PM             

      X:\Windows\system32>echo 'Make Run Once Completed'

      'Make Run Once Completed'

       

      Info        Jun 15, 2012 12:02:30 PM              Starting Make Run Once

      Info        Jun 15, 2012 12:02:30 PM              Completed Format

      Info        Jun 15, 2012 12:02:23 PM             

      X:\Windows\system32>set postprt=1

       

      X:\Windows\system32>set MACADDR=00-50-56-8C-45-D7

       

      Info        Jun 15, 2012 12:02:22 PM              Starting Format

      Info        Jun 15, 2012 12:02:22 PM              Completed Post Disk Partition

      Info        Jun 15, 2012 12:02:15 PM             

      X:\Windows\system32>call X:\Windows\System32\BladeLogic\bin\dskprt-pre.bat

       

      X:\Windows\system32>set dskprt=1

       

      X:\Windows\system32>set MACADDR=00-50-56-8C-45-D7

       

      X:\Windows\system32>call X:\Windows\System32\BladeLogic\bin\dskprt-core.bat

       

      X:\Windows\system32>DiskPart /s X:\Windows\System32\BladeLogic\bin\part.txt

       

      Microsoft DiskPart version 6.1.7600

      Copyright (C) 1999-2008 Microsoft Corporation.

      On computer: MININT-7DADJ52

       

      Disk 0 is now the selected disk.

       

      DiskPart succeeded in cleaning the disk.

       

      DiskPart succeeded in creating the specified partition.

       

      Partition 1 is now the selected partition.

       

      DiskPart successfully assigned the drive letter or mount point.

       

      DiskPart marked the current partition as active.

       


          0 percent completed

          0 percent completed

          0 percent completed

          0 percent completed

          0 percent completed

          0 percent completed

          0 percent completed

          0 percent completed

          0 percent completed

          0 percent completed

          0 percent completed

        100 percent completed

       

      DiskPart successfully formatted the volume.

       

      Disk 0 is now the selected disk.

       

      The volume you selected is not valid or does not exist.

       

      There is no volume selected.

       

      X:\Windows\system32>call X:\Windows\System32\BladeLogic\bin\dskprt-post.bat

       

      X:\Windows\system32>X:\Windows\System32\BladeLogic\bootsect.exe /nt60 c:

      Target volumes will be updated with BOOTMGR compatible bootcode.

       

      C: (\\?\Volume{0392b253-b714-11e1-b7d1-0050568c45d7})

       

          Successfully updated NTFS filesystem bootcode.

       

      Bootcode was successfully updated on all targeted volumes.

       

      X:\Windows\system32>md c:\BLProv

       

      Info        Jun 15, 2012 12:02:13 PM              Starting Disk Partition

      Info        Jun 15, 2012 12:02:13 PM              Completed Pre Disk Partition

      Info        Jun 15, 2012 12:01:52 PM             

      X:\Windows\system32>echo 'Pre Install Done'

      'Pre Install Done'

       

      Info        Jun 15, 2012 12:01:50 PM              Starting Pre Disk Partition

      Info        Jun 15, 2012 12:01:50 PM              Completed Disk Array Config

      Info        Jun 15, 2012 12:01:43 PM             

      X:\Windows\system32>if exist X:\windows\system32\Bladelogic\BLWIN64 goto donotreboot

       

      X:\Windows\system32>echo 'Switch Boot Image Done'

      'Switch Boot Image Done'

       

      Info        Jun 15, 2012 12:01:42 PM              Starting Switch Boot Image

      Info        Jun 15, 2012 11:56:18 AM              Executing work item Provision Job:PollForProvisionedWorkItem;  on application server: lt3bsa01

      Info        Jun 15, 2012 11:56:15 AM              pxe image file: AXA_WinPE_Boot_Image

      Info        Jun 15, 2012 11:56:14 AM              Running provisioning job with data store: DEV_Windows_Datastore

      Info        Jun 15, 2012 11:56:05 AM              Started running the job 'wt3bmc99' with priority 'NORMAL' on application server 'lt3bsa02'(2,000,200)

       

       

       

      BLProv\runonce log

       

       

      C:\Windows\system32>set log=runonce.log

       

      C:\Windows\system32>set counter=0

       

      C:\Windows\system32>if 0 == 5 goto failnet

       

      C:\Windows\system32>set /a counter=0+1

       

      C:\Windows\system32>ping 10.66.214.45   | find "TTL="  1>NUL

       

      C:\Windows\system32>if 0 == 1 goto errnet

       

      C:\Windows\system32>if 0 == 0 goto done

      "c:\BLProv>net use R: \\10.66.214.45\datastore  ***********  /user:10.66.214.45\pxeuser "

      The command completed successfully.

       

       

      C:\Windows\system32>copy R:\AgentInstalls\x86_64\rscd.exe c:\BLProv

              1 file(s) copied.

       

      C:\Windows\system32>copy R:\AgentInstalls\x86_64\rscd.iss c:\BLProv

              1 file(s) copied.

       

      C:\Windows\system32>c:\BLProv\rscd.exe /a /s /f1c:\BLProv\rscd.iss

       

      C:\Windows\system32>echo BLAdmins:BLAdmin rw,map=Administrator 1>C:\Windows\rsc\users

      "c:\BLProv>net use S: \\10.66.214.45\datastore  ***********  /user:10.66.214.45\pxeuser "

      The command completed successfully.

       

       

      C:\Windows\system32>S:\bmiwin.exe 10.66.214.45 9831 00-50-56-8C-45-D7

       

      C:\Windows\system32>net use R: /delete

      R: was deleted successfully.

       

       

      C:\Windows\system32>net use S: /delete

      S: was deleted successfully.

       

       

      C:\Windows\system32>del c:\BLProv\unattend.txt

       

      many thanks in advance anyone

       

      Iain

        • 1. Windows PXE Provisioning hanging

          Hi Iain,

           

          Ive seen many examples of 14/16 step failures being the bmi callback.  You should be able to log in to the machine that has stalled during provision.  Manually execute the commands found in runonce (start with the BMI callback) and you will see where it is erroring and most likely why.

          1 of 1 people found this helpful
          • 2. Windows PXE Provisioning hanging
            Iain Taylor

              Do you know how or where the runonce.bat is created/stored? Is it a file that is editable? As we want to also copy a pre-made exports and users.local after the rscd agent is installed as the user mappings we would like are not able to be created via the silent installer.

             

            we discovered that the bmiwin.exe was missing from the datastore root location, which Network share. if we manage to copy the bimwin.exe to the local machine and then run the provisioning job completes successfully.

            • 3. Re: Windows PXE Provisioning hanging
              Bill Robinson

              It’s created on the fly.  It’s created based on what is in the system package, I believe if you add to the ‘post-install’ command area that will go in the runonce.

               

              If you put bmiwin on the share in the correct location, does the job finish?

              1 of 1 people found this helpful
              • 4. Windows PXE Provisioning hanging
                Iain Taylor

                Hi Bill, Thank you for responing, If we put it in the share in the correct location currently it is coming up with a permissions error that is getting investigated as we speak, its on a samba share, but the funny thing is the rscd agent and .iss file is getting copied down ok.

                • 5. Re: Windows PXE Provisioning hanging
                  Iain Taylor

                  Hi Bill, we managed to get to the bottom of this, even though the file was copied across, it wasn't given execute rights, once we chmod'd the file we were able to provision the server successfully.