utmapp / utmapp/UTM

Virtualized ARM MacOS new VM first run fails "An error occurred during installation. Installation failed."

Open
#7,545 6 comments 0 reactions 0 assignees View on GitHub
apple virtualization
Dominant language
Swift
Stars
35.5k
Forks
1.8k
Avg merge
5d 5h
Merged PRs (30d)
7

Description

**Describe the issue**
Creating a new virtualized MacOS v12+ on ARM works but first run installation process fails.

**Configuration**
* UTM Version: 4.7.4 (115) [from Apple App Store]
* macOS Version: Sequoia 15.6.1
* Mac Chip (Intel, M1, ...): M1 Max

**system.log excerpt**
```
Dec 31 14:03:21 ardrach com.apple.Virtualization.Installation[16798]: Entered:_AMDFUDeviceConnected, libusbrestore-device:0x0x14975b170
Dec 31 14:03:21 ardrach com.apple.Virtualization.Installation[16798]: _findMatchingLocationID: Failure to create 'locationID' property from IORegistryEntry
Dec 31 14:03:21 ardrach CoreDeviceService[911]: Entered:_AMDFUDeviceConnected, libusbrestore-device:0x0x600000190240
Dec 31 14:03:21 ardrach CoreDeviceService[911]: _findMatchingLocationID: Failure to create 'locationID' property from IORegistryEntry
Dec 31 14:03:21 ardrach UARPUpdaterServiceDisplay[389]: (null)
Dec 31 14:03:21 ardrach AMPDeviceDiscoveryAgent[564]: Entered:_AMDFUDeviceConnected, libusbrestore-device:0x0x616021440
Dec 31 14:03:21 ardrach AMPDeviceDiscoveryAgent[564]: _findMatchingLocationID: Failure to create 'locationID' property from IORegistryEntry
Dec 31 14:03:22 ardrach MobileDeviceUpdater[16557]: Entered:_AMDFUDeviceConnected, libusbrestore-device:0x0xbec1d8c00
Dec 31 14:03:22 ardrach UARPUpdaterServiceDisplay[389]: Entered:_AMDFUDeviceConnected, libusbrestore-device:0x0x7002a8000
Dec 31 14:03:22 ardrach MobileDeviceUpdater[16557]: _findMatchingLocationID: Failure to create 'locationID' property from IORegistryEntry
Dec 31 14:03:22 ardrach UARPUpdaterServiceDisplay[389]: _findMatchingLocationID: Failure to create 'locationID' property from IORegistryEntry
Dec 31 14:03:22 ardrach AMPDeviceDiscoveryAgent[564]: failed to get productID from DFU mode device
Dec 31 14:03:22 ardrach com.apple.Virtualization.Installation[16798]: (null)
Dec 31 14:03:22 ardrach com.apple.Virtualization.Installation[16798]: Created state machine for device with ECID=0x40BE2EB03D05BE07
Dec 31 14:03:22 ardrach com.apple.Virtualization.Installation[16798]: State Machine Dump, status:RUNNING - [state:DFU remaining-cycles:2 (current state)] -> [state:Recovery remaining-cycles:2] -> [state:RestoreOS remaining-cycles:1]
Dec 31 14:03:22 ardrach com.apple.Virtualization.Installation[16798]: AMRestorableDeviceCopyPSDProbe: Failure to locate probe for [0x80100000] in IORegistry
Dec 31 14:03:22 --- last message repeated 1 time ---
{0x1103067B:perform_restore_installing;0x110306B4:await_system_image_invert_retry}
restore-step-uptime = 21
restore-step-user-progress = 12
waiting for await_system_image_invert_retry.
[AsyncCP][WaitStart] Start to Wait checkpoint restore_system_image.
[AsyncCP][WaitEnd] Wait checkpoint restore_system_image success.
entering mount_filesystems
LPStaticMedia *copy_media_for_partition_id(partition_id_t): partition id (3) device_node is set to NULL. Returning nil
Skipping mount of update partition
ASR failed and this target does not support retry..bailing
failed to restore/invert APFS image, error 1102
[20:03:53.0639-GMT]{5>7} CHECKPOINT FAILURE:(FAILURE:1102) (null):[0x06B4] await_system_image_invert_retry [0]D(failed to restore APFS image)[1]D(ASR failed(ENOSPC) TotalSpace: 10764 InitialFreeSpace: -1 FreeSpace: 10732 InitialDataVolumeUsage: -1 DataVolumeUsage: 0)
restore-step-results = {0x110706B4:{0:1102}}
restore-step-codes = {0x110706B4:{0:1102}}
restore-step-domains = {0x110706B4:{0:"AMRestoreErrorDomain"}}
restore-step-error = {0x110706B4:"[0]D(failed to restore APFS image)[1]D(ASR failed(ENOSPC) TotalSpace: 10764 InitialFreeSpace: -1 FreeSpace: 10732 InitialDataVolumeUsage: -1 DataVolumeUsage: 0)"}
restore-step-uptime = 21
restore-step-user-progress = 12
[20:03:53.0640-GMT]{5>7} CHECKPOINT NOTICE: (NVRAM set) restore-step-user-progress=12 [sync=true] (first failure)
[20:03:53.0640-GMT]{5>7} CHECKPOINT FAILURE:(FAILURE:1102) RESTORED:[0x067B] perform_restore_installing [0]D(failed to restore APFS image)[1]D(ASR failed(ENOSPC) TotalSpace: 10764 InitialFreeSpace: -1 FreeSpace: 10732 InitialDataVolumeUsage: -1 DataVolumeUsage: 0)
restore-step-results = {0x1107067B:{0:1102};0x110706B4:{0:1102}}
restore-step-codes = {0x1107067B:{0:1102};0x110706B4:{0:1102}}
restore-step-domains = {0x1107067B:{0:"AMRestoreErrorDomain"};0x110706B4:{0:"AMRestoreErrorDomain"}}
restore-step-error = {0x1107067B:"[0]D(failed to restore APFS image)[1]D(ASR failed(ENOSPC) TotalSpace: 10764 InitialFreeSpace: -1 FreeSpace: 10732 InitialDataVolumeUsage: -1 DataVolumeUsage: 0)"}
restore-step-uptime = 21
restore-step-user-progress = 12
[20:03:53.0641-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x067C] cleanup_boot_command
restore-step-ids = {0x1103067B:62;0x110306B4:107;0x1103067C:108}
restore-step-names = {0x1103067B:perform_restore_installing;0x110306B4:await_system_image_invert_retry;0x1103067C:cleanup_boot_command}
restore-step-uptime = 21
restore-step-user-progress = 12
entering reset_boot_command_if_in_values
40A0DDD2-77F8-4392-B4A3XXXXXXXXX6516:recovery-boot-mode =
40A0DDD2-77F8-4392-B4A3XXXXXXXXX6516:iboot-failure-reason =
[20:03:53.0641-GMT]{5>7} CHECKPOINT END: RESTORED:[0x067C] cleanup_boot_command
restore-step-ids = {0x1103067B:62;0x110306B4:107}
restore-step-names = {0x1103067B:perform_restore_installing;0x110306B4:await_system_image_invert_retry}
restore-step-uptime = 21
restore-step-user-progress = 12
[20:03:53.0642-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x1613] cleanup_recovery_os_volume
restore-step-ids = {0x1103067B:62;0x110306B4:107;0x11031613:109}
restore-step-names = {0x1103067B:perform_restore_installing;0x110306B4:await_system_image_invert_retry;0x11031613:cleanup_recovery_os_volume}
restore-step-uptime = 21
restore-step-user-progress = 12
[20:03:53.0642-GMT]{5>7} CHECKPOINT END: RESTORED:[0x1613] cleanup_recovery_os_volume
restore-step-ids = {0x1103067B:62;0x110306B4:107}
restore-step-names = {0x1103067B:perform_restore_installing;0x110306B4:await_system_image_invert_retry}
restore-step-uptime = 21
restore-step-user-progress = 12
[20:03:53.0643-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0647] cleanup_check_result
restore-step-ids = {0x1103067B:62;0x110306B4:107;0x11030647:110}
restore-step-names = {0x1103067B:perform_restore_installing;0x110306B4:await_system_image_invert_retry;0x11030647:cleanup_check_result}
restore-step-uptime = 21
restore-step-user-progress = 12
[20:03:53.0643-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0647] cleanup_check_result
restore-step-ids = {0x1103067B:62;0x110306B4:107}
restore-step-names = {0x1103067B:perform_restore_installing;0x110306B4:await_system_image_invert_retry}
restore-step-uptime = 21
restore-step-user-progress = 12
[20:03:53.0644-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x06C2] cleanup_send_crash_logs
restore-step-ids = {0x1103067B:62;0x110306B4:107;0x110306C2:111}
restore-step-names = {0x1103067B:perform_restore_installing;0x110306B4:await_system_image_invert_retry;0x110306C2:cleanup_send_crash_logs}
restore-step-uptime = 21
restore-step-user-progress = 12
[20:03:53.0645-GMT]{5>7} CHECKPOINT END: RESTORED:[0x06C2] cleanup_send_crash_logs
restore-step-ids = {0x1103067B:62;0x110306B4:107}
restore-step-names = {0x1103067B:perform_restore_installing;0x110306B4:await_system_image_invert_retry}
restore-step-uptime = 21
restore-step-user-progress = 12
[20:03:53.0645-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0648] cleanup_send_final_status
restore-step-ids = {0x1103067B:62;0x110306B4:107;0x11030648:112}
restore-step-names = {0x1103067B:perform_restore_installing;0x110306B4:await_system_image_invert_retry;0x11030648:cleanup_send_final_status}
restore-step-uptime = 21
restore-step-user-progress = 12
d_final_status}
restore-step-uptime = 21
restore-step-user-progress = 12

==== end of device restore output ====
Dec 31 14:03:59 ardrach com.apple.Virtualization.Installation[16798]: : Restore failed (result = 1102)
Dec 31 14:03:59 ardrach com.apple.Virtualization.Installation[16798]: Can't send dump_console command since device is not in recovery mode
Dec 31 14:03:59 ardrach com.apple.Virtualization.Installation[16798]: AMRestorePerformRestoreModeRestoreWithError failed with error: 1102
Dec 31 14:03:59 ardrach com.apple.Virtualization.Installation[16798]: State Machine Dump, status:ERROR - [state:DFU remaining-cycles:1] -> [state:Recovery remaining-cycles:0] -> [state:RestoreOS remaining-cycles:0 (current state)]
Dec 31 14:03:59 ardrach com.apple.Virtualization.Installation[16798]: [PurpleReverseProxy]: Dec 31 14:03:59 com.apple.Virtualization.Installation[16798] : ReverseProxyHost.c:125(ControlMessage): unexpected event 8
Dec 31 14:03:59 ardrach com.apple.Virtualization.Installation[16798]: State is now set to error: AMRestorePerformRestoreModeRestoreWithError failed with error: 1102
...
```

**config.plist**
```

Backend
Apple
ConfigurationVersion
4
Display


DynamicResolution

HeightPixels
1200
PixelsPerInch
80
WidthPixels
1920


Drive


Identifier
890B4ABC-20C9-449E-85EE-D02980AC336A
ImageName
890B4ABC-20C9-449E-85EE-D02980AC336A.img
Nvme

ReadOnly



Information

Icon
mac
IconCustom

Name
macOS
UUID
77780DCE-0A66-482B-9612-816D7F3DFE59

Network


MacAddress
de:b8:f2:4d:8e:34
Mode
Shared


Serial

System

Architecture
aarch64
Boot

OperatingSystem
macOS
UEFIBoot


CPUCount
2
MacPlatform

AuxiliaryStoragePath
AuxiliaryStorage
HardwareModel

YnBsaXN0MDDTAQIDBAQFXxAZRGF0YVJlcHJlc2VudGF0aW9uVmVy
c2lvbl8QD1BsYXRmb3JtVmVyc2lvbl8QEk1pbmltdW1TdXBwb3J0
ZWRPUxACowYHBxAPEAAIDys9UlRYWgAAAAAAAAEBAAAAAAAAAAgA
AAAAAAAAAAAAAAAAAABc

MachineIdentifier

YnBsaXN0MDDRAQJURUNJRBNAvi6wPQW+BwgLEAAAAAAAAAEBAAAA
AAAAAAMAAAAAAAAAAAAAAAAAAAAZ


MemorySize
4096

Virtualization

Audio

Balloon

ClipboardSharing

Entropy

Keyboard
Mac
Pointer
Trackpad

```

Contributor guide

Open the contributing guide

Assessment

This issue has not been assessed yet.

Get new issues in your inbox

A short digest of beginner-friendly GitHub issues.