You are not logged in.

Announcement

[2017.09.08] DeployStudio build v1.7.8 (checksum, release note).
[2016.08.26] DeployStudio build v1.6.19 (release note).
[2013.02.23] DeployStudio last universal build v1.5.17 (release note).

#1 2017-10-26 17:45:55

tyler.schmaltz
Member
Registered: 2017-10-24

DS finalize not running on Late 2015 imac16,2 iMac's

Running DeployStudio server version 1.7.8 on a Macmini6,2 running 10.11.6.

Trying to run some workflows on Late 2015 iMac’s  imac16,2 running 10.12.6.  I am able to netboot the machines juts fine without issues but when I run a postponed package install or AD binding on first reboot. It does not enter or run the DS Finalize stage just goes right to the login screen. Checking /etc/deploystudio i see the packages are there but never get installed. I have tried just a single postponed package install with the same results the package shows up but never gets installed.

In the deploy studio netboot logs I do not see any errors. The workflows run just fine on older macs without issues but for some reason on these 5 2015 iMacs I have i can not get the ds finalize postponed tasks to run at all.


Thanks

Offline

#2 2017-10-26 22:58:12

Meat
Member
From: SF CA US
Registered: 2009-02-04

Re: DS finalize not running on Late 2015 imac16,2 iMac's

On the iMacs in question, is there a ds_finalize.log in /var/log?

Offline

#3 2017-10-27 15:32:59

tyler.schmaltz
Member
Registered: 2017-10-24

Re: DS finalize not running on Late 2015 imac16,2 iMac's

There is no DS_finalize log being written in /var/log.

Here is the runtime log for one of the iMac's.


Oct 27 09:16:27  DeployStudio Runtime.bin[354] <Error>: CGColorSpaceCreateWithName failed for kCGColorSpaceITUR_709
Oct 27 09:16:27  DeployStudio Runtime.bin[354] <Error>: CGColorSpaceCreateWithName failed for kCGColorSpaceITUR_2020
Oct 27 09:16:27  DeployStudio Runtime.bin[354] <Error>: CGColorSpaceCreateWithName failed for kCGColorSpaceROMMRGB
2017-10-27 09:16:29.900 DeployStudio Runtime.bin[354:13673] ====================================================================================================
2017-10-27 09:16:29.900 DeployStudio Runtime.bin[354:13673] DeployStudio Runtime
2017-10-27 09:16:29.902 DeployStudio Runtime.bin[354:13673] DSCore.framework version 1.7.8 (b170908), Copyright 2017 The DeployStudio Team.
2017-10-27 09:16:29.903 DeployStudio Runtime.bin[354:13673] MAC address:
2017-10-27 09:16:29.917 DeployStudio Runtime.bin[354:13673] Network address:
2017-10-27 09:16:29.983 DeployStudio Runtime.bin[354:13673] Network interface speed: AUTOSELECT (1000BASET <FULL-DUPLEX,FLOW-CONTROL>)
2017-10-27 09:16:29.997 DeployStudio Runtime.bin[354:13673] Operating System: Mac OS X Version 10.12.5 (Build 16F2073)
2017-10-27 09:16:29.997 DeployStudio Runtime.bin[354:13673] Date: 17/10/27 09:16:29
2017-10-27 09:16:29.997 DeployStudio Runtime.bin[354:13673] ====================================================================================================
2017-10-27 09:16:30.691 DeployStudio Runtime.bin[354:13673] 24 plugins were successfully loaded!
2017-10-27 09:16:31.161 DeployStudio Runtime.bin[354:13673] Starting Bonjour lookup...
2017-10-27 09:17:41.847 DeployStudio Runtime.bin[354:15913] The user 'applemdm' was successfully authenticated.
2017-10-27 09:17:41.854 DeployStudio Runtime.bin[354:13673] Connected to server  (1.7.8)
2017-10-27 09:17:41.966 DeployStudio Runtime.bin[354:15913] Checking server reachability (server= port=548) ...
2017-10-27 09:17:41.979 DeployStudio Runtime.bin[354:15913] Mounting repository share point...
2017-10-27 09:17:41.979 DeployStudio Runtime.bin[354:15913] /bin/mkdir /tmp/DSNetworkRepository 2>&1
2017-10-27 09:17:41.989 DeployStudio Runtime.bin[354:15913] /bin/chmod 777 /tmp/DSNetworkRepository 2>&1
2017-10-27 09:17:41.998 DeployStudio Runtime.bin[354:15913] /bin/chmod 777 /tmp/DSNetworkRepository 2>&1
2017-10-27 09:17:42.007 DeployStudio Runtime.bin[354:15913] Mount call...
2017-10-27 09:17:42.851 DeployStudio Runtime.bin[354:15913] Parsing result...
2017-10-27 09:17:42.860 DeployStudio Runtime.bin[354:15913] Checking repository content...
2017-10-27 09:17:43.214 DeployStudio Runtime.bin[354:15913] Checking host firmware version
2017-10-27 09:17:43.299 DeployStudio Runtime.bin[354:15913] Macintosh serial number: D25SY015GG7D
2017-10-27 09:17:43.299 DeployStudio Runtime.bin[354:15913] Macintosh model: iMac16,2
2017-10-27 09:17:43.299 DeployStudio Runtime.bin[354:15913] Boot ROM version: IM162.88Z.0207.B20.1706181928
2017-10-27 09:17:43.299 DeployStudio Runtime.bin[354:15913] Firmware ok!
2017-10-27 09:17:48.771 DeployStudio Runtime.bin[354:16940] Running workflow: 'New Computer setup Staff/Faculty' (C7A21FD4-F9B2-4C13-B2CA-AE974CEE6F71)
2017-10-27 09:17:49.321 DeployStudio Runtime.bin[354:16940] [<NSThread: 0x7fa7e500da30>{number = 4, name = (null)} / <NSThread: 0x7fa7e0c0d050>{number = 1, name = (null)}] -[DSTargetTaskController runtimeView]
2017-10-27 09:17:52.175 DeployStudio Runtime.bin[354:13673] target=[] -[DSTargetTaskController updateRuntimeViewWithConfig:]
2017-10-27 09:17:52.175 DeployStudio Runtime.bin[354:13673] [<NSThread: 0x7fa7e0c0d050>{number = 1, name = main} / <NSThread: 0x7fa7e0c0d050>{number = 1, name = main}] -[DSTargetTaskController runtimeView]
2017-10-27 09:17:58.686 DeployStudio Runtime.bin[354:13750] target=[Macintosh HD] -[DSTargetTaskController proceed]
2017-10-27 09:17:58.688 DeployStudio Runtime.bin[354:13750] -> Target action completed.
2017-10-27 09:17:58.688 DeployStudio Runtime.bin[354:13750] Target selection successful (elapsed time: 0.00 minutes)
2017-10-27 09:18:00.011 DeployStudio Runtime.bin[354:13750] Hostname setup action:
2017-10-27 09:18:00.133 DeployStudio Runtime.bin[354:13750] Task successful (elapsed time: 0.00 minutes)
2017-10-27 09:18:00.133 DeployStudio Runtime.bin[354:13750] -> Hostname setup action completed.
2017-10-27 09:18:00.342 DeployStudio Runtime.bin[354:13750] Configuration action:
2017-10-27 09:18:00.386 DeployStudio Runtime.bin[354:13750] ds_finalize_install.sh - v1.19 (Fri Oct 27 09:18:00 CDT 2017)
2017-10-27 09:18:00.819 DeployStudio Runtime.bin[354:13750] ds_finalize_install.sh - end
2017-10-27 09:18:00.819 DeployStudio Runtime.bin[354:13750] Renaming host to 'library-imac4'/'Library-iMac 4'.
2017-10-27 09:18:00.845 DeployStudio Runtime.bin[354:13750] ds_rename_computer_install.sh - v1.6 (Fri Oct 27 09:18:00 CDT 2017)
2017-10-27 09:18:00.864 DeployStudio Runtime.bin[354:13750] ds_rename_computer_install.sh - end
2017-10-27 09:18:00.866 DeployStudio Runtime.bin[354:13750] /usr/bin/touch "/Volumes/Macintosh HD/var/db/.AppleSetupDone" "/Volumes/Macintosh HD/Library/Receipts/.SetupRegComplete" "/Volumes/Macintosh HD/var/db/.ds.preserve.AppleSetupDone" 2>&1
2017-10-27 09:18:00.890 DeployStudio Runtime.bin[354:13750] Installing ARD agent configuration scripts
2017-10-27 09:18:00.915 DeployStudio Runtime.bin[354:13750] ds_enable_ard_agent_install.sh - v1.6 (Fri Oct 27 09:18:00 CDT 2017)
2017-10-27 09:18:00.930 DeployStudio Runtime.bin[354:13750] ds_enable_ard_agent_install.sh - end
2017-10-27 09:18:00.931 DeployStudio Runtime.bin[354:13750] "/Applications/Utilities/DeployStudio Admin.app/Contents/Plugins/DSConfigureTask.bundle/Contents/Resources/Scripts/ds_enable_firewall.sh" "Macintosh HD" 2>&1
2017-10-27 09:18:00.954 DeployStudio Runtime.bin[354:13750] ds_enable_firewall.sh - v1.0 (Fri Oct 27 09:18:00 CDT 2017)
2017-10-27 09:18:00.997 DeployStudio Runtime.bin[354:13750] ds_enable_firewall.sh - end
2017-10-27 09:18:00.998 DeployStudio Runtime.bin[354:13750] Task successful (elapsed time: 0.01 minutes)
2017-10-27 09:18:00.998 DeployStudio Runtime.bin[354:13750] -> Host configuration action completed.
2017-10-27 09:18:01.203 DeployStudio Runtime.bin[354:13750] ds_finalize_install.sh - v1.19 (Fri Oct 27 09:18:01 CDT 2017)
2017-10-27 09:18:01.558 DeployStudio Runtime.bin[354:13750] ds_finalize_install.sh - end
2017-10-27 09:18:01.559 DeployStudio Runtime.bin[354:13750] /usr/bin/ditto --rsrc "/var/tmp/DSNetworkRepository/Packages/munkitools-3.0.3.3352 (1).pkg" "/Volumes/Macintosh HD/etc/deploystudio/ds_packages/munkitools-3.0.3.3352 (1).pkg" 2>&1
2017-10-27 09:18:01.698 DeployStudio Runtime.bin[354:13750] /bin/chmod -R 755 "/Volumes/Macintosh HD/etc/deploystudio/ds_packages/munkitools-3.0.3.3352 (1).pkg" 2>&1
2017-10-27 09:18:01.708 DeployStudio Runtime.bin[354:13750] /bin/chmod 644 "/Volumes/Macintosh HD/etc/deploystudio/ds_packages/0001.idx" 2>&1
2017-10-27 09:18:01.717 DeployStudio Runtime.bin[354:13750] "/Applications/Utilities/DeployStudio Admin.app/Contents/Plugins/DSInstallTask.bundle/Contents/Resources/Scripts/ds_install_packages_install.sh" "Macintosh HD" 0001.idx 2>&1
2017-10-27 09:18:01.741 DeployStudio Runtime.bin[354:13750] ds_install_packages_install.sh - v1.15 (Fri Oct 27 09:18:01 CDT 2017)
2017-10-27 09:18:01.772 DeployStudio Runtime.bin[354:13750] ds_install_packages_install.sh - end
2017-10-27 09:18:01.773 DeployStudio Runtime.bin[354:13750] -> Install action completed.
2017-10-27 09:18:01.773 DeployStudio Runtime.bin[354:13750] Install successful (elapsed time: 0.01 minutes)
2017-10-27 09:18:01.982 DeployStudio Runtime.bin[354:16988] ds_finalize_install.sh - v1.19 (Fri Oct 27 09:18:01 CDT 2017)
2017-10-27 09:18:02.339 DeployStudio Runtime.bin[354:16988] ds_finalize_install.sh - end
2017-10-27 09:18:02.339 DeployStudio Runtime.bin[354:16988] /usr/bin/ditto --rsrc /var/tmp/DSNetworkRepository/Packages/munki_kickstart.pkg "/Volumes/Macintosh HD/etc/deploystudio/ds_packages/munki_kickstart.pkg" 2>&1
2017-10-27 09:18:02.393 DeployStudio Runtime.bin[354:16988] /bin/chmod -R 755 "/Volumes/Macintosh HD/etc/deploystudio/ds_packages/munki_kickstart.pkg" 2>&1
2017-10-27 09:18:02.403 DeployStudio Runtime.bin[354:16988] /bin/chmod 644 "/Volumes/Macintosh HD/etc/deploystudio/ds_packages/0002.idx" 2>&1
2017-10-27 09:18:02.413 DeployStudio Runtime.bin[354:16988] "/Applications/Utilities/DeployStudio Admin.app/Contents/Plugins/DSInstallTask.bundle/Contents/Resources/Scripts/ds_install_packages_install.sh" "Macintosh HD" 0002.idx 2>&1
2017-10-27 09:18:02.437 DeployStudio Runtime.bin[354:16988] ds_install_packages_install.sh - v1.15 (Fri Oct 27 09:18:02 CDT 2017)
2017-10-27 09:18:02.468 DeployStudio Runtime.bin[354:16988] ds_install_packages_install.sh - end
2017-10-27 09:18:02.468 DeployStudio Runtime.bin[354:16988] -> Install action completed.
2017-10-27 09:18:02.469 DeployStudio Runtime.bin[354:16988] Install successful (elapsed time: 0.01 minutes)
2017-10-27 09:18:15.566 DeployStudio Runtime.bin[354:13673] /usr/sbin/bless --device /dev/disk0 --setBoot --verbose 2>&1
2017-10-27 09:18:15.618 DeployStudio Runtime.bin[354:13673] EFI found at IODeviceTree:/efi
2017-10-27 09:18:15.618 DeployStudio Runtime.bin[354:13673] found ioreg "FirmwareFeaturesMask"; featureMaskValue=0xFF1FFF3F
2017-10-27 09:18:15.618 DeployStudio Runtime.bin[354:13673] found ioreg "FirmwareFeatures"; featureFlagsValue=0xFD07F536
2017-10-27 09:18:15.618 DeployStudio Runtime.bin[354:13673] isPreBootEnvironmentUEFIWindowsBootCapable=1
2017-10-27 09:18:15.618 DeployStudio Runtime.bin[354:13673] given BSD is not a DVD disc medium
2017-10-27 09:18:15.618 DeployStudio Runtime.bin[354:13673] isDVDWithElToritoWithUEFIBootableOS=0
2017-10-27 09:18:15.618 DeployStudio Runtime.bin[354:13673] Checking if disk is complex (if it is associated with booter partitions)
2017-10-27 09:18:15.618 DeployStudio Runtime.bin[354:13673] Preferred system partition found: disk0s1
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673] Preferred system partition found: disk1s1
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673] Returning booter information dictionary:
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673] <CFBasicHash 0x7fe2f7e00320 [0x10b165da0]>{type = mutable dict, count = 3,
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673] entries =>
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673]     0 : <CFString 0x10aafaa60 [0x10b165da0]>{contents = "System Partitions"} = (
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673]     disk0s1,
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673]     disk1s1
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673] )
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673]     1 : <CFString 0x10aafb240 [0x10b165da0]>{contents = "Data Partitions"} = (
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673]     disk0
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673] )
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673]     2 : <CFString 0x10aafb260 [0x10b165da0]>{contents = "Auxiliary Partitions"} = (
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673] )
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673] }
2017-10-27 09:18:15.619 DeployStudio Runtime.bin[354:13673] IOMedia disk0 does not have a partition UUID
2017-10-27 09:18:15.620 DeployStudio Runtime.bin[354:13673] IOMedia disk0 does not have a Volume UUID
2017-10-27 09:18:15.620 DeployStudio Runtime.bin[354:13673] IOMedia disk0 has path IODeviceTree:/PCI0@0/PEG0@1/SSD0@0/AppleS1XController/IONVMeBlockStorageDevice@1/@1:0
2017-10-27 09:18:15.620 DeployStudio Runtime.bin[354:13673] Setting EFI NVRAM:
2017-10-27 09:18:15.620 DeployStudio Runtime.bin[354:13673]     efi-boot-device='<array><dict><key>IOMatch</key><dict><key>IOProviderClass</key><string>IOMedia</string><key>IOPathMatch</key><string>IODeviceTree:/PCI0@0/PEG0@1/SSD0@0/AppleS1XController/IONVMeBlockStorageDevice@1/@1:0</string></dict><key>BLLastBSDName</key><string>disk0</string></dict></array>'
2017-10-27 09:18:15.621 DeployStudio Runtime.bin[354:13673] Setting EFI NVRAM:
2017-10-27 09:18:15.621 DeployStudio Runtime.bin[354:13673]     IONVRAM-DELETE-PROPERTY='efi-boot-file'
2017-10-27 09:18:15.621 DeployStudio Runtime.bin[354:13673] Setting EFI NVRAM:
2017-10-27 09:18:15.621 DeployStudio Runtime.bin[354:13673]     IONVRAM-DELETE-PROPERTY='efi-boot-mkext'
2017-10-27 09:18:15.621 DeployStudio Runtime.bin[354:13673] Setting EFI NVRAM:
2017-10-27 09:18:15.621 DeployStudio Runtime.bin[354:13673]     IONVRAM-DELETE-PROPERTY='efi-boot-kernelcache'
2017-10-27 09:18:15.621 DeployStudio Runtime.bin[354:13673] NVRAM variable "boot-args" not set.
2017-10-27 09:18:15.623 DeployStudio Runtime.bin[354:13673] Umounting repository share point...
2017-10-27 09:18:15.623 DeployStudio Runtime.bin[354:13673] /sbin/umount -f /tmp/DSNetworkRepository 2>&1
2017-10-27 09:18:15.956 DeployStudio Runtime.bin[354:13673] /bin/rmdir /tmp/DSNetworkRepository 2>&1

Offline

#4 2017-10-27 16:43:23

tyler.schmaltz
Member
Registered: 2017-10-24

Re: DS finalize not running on Late 2015 imac16,2 iMac's

poking around the /etc/deloystudio folder i found the DS_finalize.sh and ran that as admin and it installed the packages and rebooted the computer but no AD binding. I rebooted into DS and ran just a AD binding workflow and the computer rebooted into the DS_finalize window and ran the AD binding successfully. So still not sure what caused my problem yet but going to try on the other computers and see what happens.

Offline

#5 2017-10-27 20:25:34

Meat
Member
From: SF CA US
Registered: 2009-02-04

Re: DS finalize not running on Late 2015 imac16,2 iMac's

What disk format is that target partition?

Perhaps it's different because I'm actually imaging in the same workflow, but here is what I see in my similar recent log file (strings obfuscated for no good reason):

2017-10-24 15:37:41.455 DeployStudio Runtime.bin[332:13915] Checking if disk is complex (if it is associated with booter partitions)
2017-10-24 15:37:41.456 DeployStudio Runtime.bin[332:13915] GPT detected
2017-10-24 15:37:41.456 DeployStudio Runtime.bin[332:13915] Booter partition required at index 3
2017-10-24 15:37:41.456 DeployStudio Runtime.bin[332:13915] System partition found
2017-10-24 15:37:41.456 DeployStudio Runtime.bin[332:13915] Booter partition found
2017-10-24 15:37:41.456 DeployStudio Runtime.bin[332:13915] Preferred system partition found: disk0s1
2017-10-24 15:37:41.456 DeployStudio Runtime.bin[332:13915] Returning booter information dictionary:
2017-10-24 15:37:41.456 DeployStudio Runtime.bin[332:13915] <CFBasicHash XXXXXXXXXXXXXX [XXXXXXXXXXX]>{type = mutable dict, count = 3,
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915] entries =>
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915]     0 : <CFString XXXXXXXXXXX [XXXXXXXXXXX]>{contents = "System Partitions"} = (
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915]     disk0s1
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915] )
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915]     1 : <CFString XXXXXXXXXXX [XXXXXXXXXXX]>{contents = "Data Partitions"} = (
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915]     disk0s2
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915] )
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915]     2 : <CFString XXXXXXXXXXX [XXXXXXXXXXX]>{contents = "Auxiliary Partitions"} = (
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915]     disk0s3
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915] )
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915] }
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915] Substituting booter disk0s3
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915] IOMedia disk0s3 has UUID XXXXXXXX-XXXX-XXXX-XXXX-XXXXXXXXXXXX
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915] Setting EFI NVRAM:
2017-10-24 15:37:41.457 DeployStudio Runtime.bin[332:13915]     efi-boot-device='<array><dict><key>IOMatch</key><dict><key>IOProviderClass</key><string>IOMedia</string><key>IOPropertyMatch</key><dict><key>UUID</key><string>XXXXXXXX-XXXX-XXXX-XXXX-XXXXXXXXXXXX</string></dict></dict><key>BLLastBSDName</key><string>disk0s3</string></dict></array>'
2017-10-24 15:37:41.470 DeployStudio Runtime.bin[332:13915] Setting EFI NVRAM:
2017-10-24 15:37:41.470 DeployStudio Runtime.bin[332:13915]     IONVRAM-DELETE-PROPERTY='efi-boot-file'
2017-10-24 15:37:41.470 DeployStudio Runtime.bin[332:13915] Setting EFI NVRAM:
2017-10-24 15:37:41.470 DeployStudio Runtime.bin[332:13915]     IONVRAM-DELETE-PROPERTY='efi-boot-mkext'
2017-10-24 15:37:41.470 DeployStudio Runtime.bin[332:13915] Setting EFI NVRAM:
2017-10-24 15:37:41.470 DeployStudio Runtime.bin[332:13915]     IONVRAM-DELETE-PROPERTY='efi-boot-kernelcache'
2017-10-24 15:37:41.470 DeployStudio Runtime.bin[332:13915] NVRAM variable "boot-args" not set.

I'm also running version 1.7.5 of DeployStudio.

Offline

Board footer

Powered by FluxBB