KPF: found apfs_vfsop_mount KPF: found handle_eval_rootauth KPF: Found AMFI hashtype check kpf_amfi_callback: Found AMFI (Leaf) KPF: Found mac_mount KPF: Found mac_mount kpf_amfi_callback: Found AMFI (Leaf) Qemu FB realize iBoot version: qemu-t8030 Darwin Image4 Validator Version 4.2.0: Thu Apr 21 21:49:47 PDT 2022; root:AppleImage4-158.100.11~2605/AppleImage4/RELEASE_ARM64E AppleImage4: failed to read nvram property: oblit-inprogress: 2 AMFI is running in RESEARCH mode! AMFI: UDID enforcement enabled AUC:[0xffffffe5e5730170]::init(0xffffffe4ff11f958) AUC:[0xffffffe5e5730170]::probe(0xffffffe24c1740a0, 0xffffffed01a9bdec) AppleCredentialManager: init: called, instance = . ACMFirstResponderKernelService: init: called, . ACMRM-S: init: called, starting PersistentStore service. ACMRM-C: init: called, starting AccessoryCache service. ACMKernelService: initValueFromBootArgAliasesUInt32: acc-cache size = 16 (default). ACMKernelService: initValueFromBootArgAliasesUInt32: acc-cache expiration = 2592000 (default). ACMRM: init: called, starting TRM service. ACMRM-A: init: called, starting TRM Analytics service. ACMKernelService: initValueFromBootArgAliasesUInt32: analytics collection period = 86400 (default). ACMKernelService: initValueFromBootArgAliasesUInt32: policy mode timeout = 259200 (default). ACMRM-A: notifyStandardModeTimeoutChanged: called, value = 259200 (modified = YES). ACMKernelService: initValueFromBootArgAliasesUInt32: (bounded) grace period timeout = 3600 (default). ACMRM-A: notifyGracePeriodTimeoutChanged: called, value = 3600 (modified = YES). ACMKernelService: initValueFromBootArgAliasesUInt32: enabled = 1 (default). ACMRM: _disableBy: [TRM ENABLED=YES] (mask=0, DISABLED BY: Def=NO* BtArg=NO LegHW=NO OSEnv=NO | MngCo=NO DwnOS=NO ChkBd=NO coGSw=NO). ACMRM: _disableBy: [TRM ENABLED=YES] (mask=0, DISABLED BY: Def=NO BtArg=NO* LegHW=NO OSEnv=NO | MngCo=NO DwnOS=NO ChkBd=NO coGSw=NO). ACMRM: _loadDisabledByOSEnvironment: disabled by OSEnvironment: NO. ACMRM: _disableBy: [TRM ENABLED=YES] (mask=0, DISABLED BY: Def=NO BtArg=NO LegHW=NO OSEnv=NO* | MngCo=NO DwnOS=NO ChkBd=NO coGSw=NO). ACMRM: _mapAndPublishTRM: set TRM_PolicyTimeout = 259200. ACMRM: _mapAndPublishTRM: sending kIOMessageServicePropertyChange(n=1) while DISABLED, TRM: 259200 -/ff 4294967295 -/ff miss=ff (CUR: 259200 -/ff 4294967295 -/ff). AppleCredentialManager: init: returning, result = true, instance = . AppleARMBootPerf: Error: profile handoff region not found (2) AppleARMBootPerf: Error: failed to publish profile data (2) virtual bool CoreAnalyticsHub::start(IOService *)::114:CoreAnalyticsHub start AppleSSE::start called AppleSSE::start returning, result = 1 AUC:[0xffffffe5e5730170]::start(0xffffffe24c1740a0) Warning: arc4random not implemented Warning: arc4random not implemented Warning: arc4random not implemented AppleSEPKeyStore:319:0: starting (BUILT: Apr 21 2022 22:09:00) AppleSEPKeyStore:524:0: _sep_enabled = 1 AppleCredentialManager: start: called, instance = . ACMRM: _publishIOResource: AppleUSBRestrictedModeTimeout = 259200. AppleCredentialManager: start: initializing power management. AppleCredentialManager: start: will join SEPManager's PM tree in getSEPEndpoint(). AppleCredentialManager: start: registering notifications. AppleCredentialManager: start: started, instance = . AppleCredentialManager: start: returning, result = true, instance = . AppleGPIOICController::start: this: , _gpioicBaseAddress: AppleS5L8960XUSBPhy::start: hsic disabled AppleInterruptController::start: Num Shared Timestamps == 0 AppleS8000AES::start: registers at phys:0x0x235008000/0x0x23d2d0000 virt:0x/0x0x4000 / 0x/0x0x4000 virtual bool AppleARMLightEmUp::start(IOService *): starting... AppleS5L8940XI2CController::start: i2c0 this: _i2cBaseAddress: ApplePMGR: Starting AppleT8030PMGR AppleGen0SPMIController[spmi0]:78: Start AppleS5L8940XI2CController::start: i2c1 this: _i2cBaseAddress: AppleS5L8940XI2CController::start: i2c2 this: _i2cBaseAddress: AppleGPIOICController::start: this: , _gpioicBaseAddress: AppleGen0SPMIController[spmi2]:78: Start 000003.698909 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::prepareDefaults: model iPhone version 12.1 AppleGen0SPMIController[spmi1]:78: Start AppleS5L8940XI2CController::start: smc-i2c1 this: _i2cBaseAddress: AppleGPIOICController::start: this: , _gpioicBaseAddress: AppleS5L8940XI2CController::start: i2c3 this: _i2cBaseAddress: AMFIInitializeLocalSigningPublicKey: disabling local signing since we're in the restore environment AppleOLYHAL::probe: score = 1000 AppleOLYHAL::start: entry with provider 0xffffffd809e20e53 000003.953050 wlan0.A[1] start@1843:Default options property found with value 4 Identified Serial Port uart7 at 0x23521c000() AppleA7IOPNub: withRegistryEntry, 47: allocated nub AppleA7IOPNub: withRegistryEntry, 47: allocated nub Started AppleSerialShim for device: uart 000003.995789 wlan0.A[2] start@2238: Raised adjustBusy(+1), getBusyState() -> 2 Creating an object of AppleOLYHALPlatformFunctionEmbeddedAMFM class 000003.998121 wlan0.A[3] setupNotifier@2303:Setting up notifier for CoreAnalyticsHub RTBuddy(ANS2): start() - (Apr 21 2022@22:08:00) RTBuddy(SMC): start() - (Apr 21 2022@22:08:00) LPM state clear RTBuddy(SMC): Resuming... AppleA7IOPNub: withRegistryEntry, 47: allocated nub RTBuddy(SIO): start() - (Apr 21 2022@22:08:00) LPM: Log data is NOT valid. 0x0 0x0 AppleDialogSPMIPMU::start: Primary PMU detected Starting AppleSMC kext() - (Apr 21 2022@22:07:37) AppleSMCEmbedded::setPowerState(): ENTER powerStateOrdinal=1, _activeKeyCommand=17 newState=1 AppleARMRTC started!##### AppleDialogSPMIPMURTC started!****** Failed to read info-leg_scrpadAppleDialogSPMIPMURTC tick read!&&&&&&& AppleDialogSPMIPMURTC ending!%%% RTBuddy(ANS2): Resuming... 000004.102986 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::gated_handleUSBCableConnect: cable connected, but don't have device configuration yet AppleARMRTC registering service!@@@@@@ AppleARMRTC service registered!$$$$$ AppleARMRTC publishing service!^^^^^^ virtual IOService *AppleANS2NVMeController::probe(IOService *, SInt32 *)::194:Found (ANS2) provider, returning score 100000 void AppleEmbeddedNVMeController::GetRestoreEnvironment()::452:Restore Environment! virtual bool AppleANS2NVMeController::start(IOService *)::394:Found the ANS2Endpoint1 void AppleEmbeddedNVMeController::GetRestoreEnvironment()::452:Restore Environment! bool AppleEmbeddedNVMeController::SetNamespacesStruct()::194:Obtained 7 namespaces from DT virtual bool IONVMeController::start(IOService *)::775:IONVMeController::start virtual IOFilterInterruptEventSource *AppleANS2NVMeController::CreateDeviceInterrupt(IOInterruptEventSource::Action, IOFilterInterruptEventSource::Filter, IOService *)::2778:ANS2 NVMe interrupt index - 0x4 000004.162042 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::gated_handleUSBCableConnect: cable connected, but don't have device configuration yet /Library/Caches/com.apple.xbs/Sources/AppleSMC/AppleSMCEmbeddedCharger/AppleSMCCharger.cpp:408 _setPowerStateGated() ENTER powerStateOrdinal=1, _powerState=1 AppleSMCPMU::start apfs_module_start:2588: load: com.apple.filesystems.apfs, v1934.122.1, apfs-1934.122.1, 2022/04/21 com.apple.AppleFSCompressionTypeZlib kmod start apfs_sysctl_register:1451: done registering sysctls. com.apple.AppleFSCompressionTypeZlib load succeeded L2TP domain init L2TP domain init complete Load request for com.apple.nke.pptp failed: -603946991 BSD root: md0, major 3, minor 0 static AppleNVMeRequestPoolTagReserve *AppleNVMeRequestPoolTagReserve::Create(AppleNVMeWorkLoop *, uint8_t, uint16_t, uint32_t, bool, NVMeBufferAllocator *, lck_mtx_t *, bool, IOMapper *)::138:NVMe Request Pool Init (Tag Reservation Case). First phase, Revirtual IOReturn IONVMeController::CreateSubmissionQueue(uint16_t, uint8_t)::3089:SQ index=0 entrysize=64 virtual IOReturn IONVMeController::CreateSubmissionQueue(uint16_t, uint8_t)::3089:SQ index=1 entrysize=128 virtual void AppleANS2NVMeController::SetModeselRegister(uint32_t)::1186:Setting modesel to 4 virtual IOReturn AppleNVMeRequestPoolTagReserve::Rebuild(uint8_t, uint32_t, bool, bool, IOMapper *)::181:NVMe Request Pool Init (Tag Reservation Case). Second phase, RequestCount = 64 virtual IOReturn AppleANS2NVMeController::EnableCompletionQueue(uint16_t)::1920:nvme: Setting IOCQ base addr 0x81ba30000 hfs: mounted SkyF19F77.arm64eUpdateRamDisk on device b(3, 0) virtual IOReturn AppleANS2NVMeController::EnableSubmissionQueue(uint16_t)::1890:nvme: Setting IOSQ base addr 0x81ba60000 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2008:nvme: Vendor ID : 0x1b36 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2009:nvme: Model Number : QEMU NVMe Ctrl bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2010:nvme: Serial Number : QEMUT8030ANS bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2011:nvme: Firmware Rev : 1.0 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2044:nvme: S3E A0 Invalid 1x slc 1D 0 plane 128GB NAND bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2053:ECCVersion : 0 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2054:FTL Rev : 0.0 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2055:DM_Version : 0 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2056:======================= bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2063:Found 16 namespaces in current NAND bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[1] as nstype[1] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[2] as nstype[2] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[3] as nstype[3] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[4] as nstype[4] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[5] as nstype[5] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[6] as nstype[6] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[7] as nstype[8] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[8] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[9] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[10] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[11] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[12] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[13] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[14] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[15] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[16] as nstype[0] bool AppleEmbeddedNVMeController::SetSwapWriteLimit(uint32_t)::2236: Swap limit set to 2147483648bytes, 2GB virtual bool AppleEmbeddedNVMeController::InitializeController()::507:FW update not complete, create dummy block device uint32_t AppleEmbeddedNVMeNVRAM::GetNVRAMSize()::564:NVRAM size is 8192 bytes virtual bool AppleEmbeddedNVMeController::AllocateNodes(bool)::1622:allocateAll 1 virtual bool AppleEmbeddedNVMeController::StartController()::1591:Setting NAND status to Ready virtual bool IONVMeController::start(IOService *)::828:Successfully initialized NVMe drive com.apple.xpc.launchd|2022-05-29 23:51:19.016106 : hello com.apple.xpc.launchd|2022-05-29 23:51:19.043784 : Darwin Bootstrapper Version 7.0.0: Thu Apr 21 22:07:17 PDT 2022; root:libxpc_executables-2236.120.5~8/launchd/RELEASE_ARM64E com.apple.xpc.launchd|2022-05-29 23:51:19.044974 : boot-args = -restore rd=md0 nand-enable-reformat=1 -progress debug=0x14e kextlog=0xffff serial=3 -v com.apple.xpc.launchd|2022-05-29 23:51:19.045955 : Restore environment starting. com.apple.xpc.launchd|2022-05-29 23:51:19.089936 (system) : entering ondemand mode com.apple.xpc.launchd|2022-05-29 23:51:19.097310 (system) : created com.apple.xpc.launchd|2022-05-29 23:51:19.129122 : Skipping boot-task: fsck com.apple.xpc.launchd|2022-05-29 23:51:19.129797 : Skipping boot-task: mount-phase-1 com.apple.xpc.launchd|2022-05-29 23:51:19.130050 : Skipping boot-task: data-protection com.apple.xpc.launchd|2022-05-29 23:51:19.130457 : Skipping boot-task: finish-obliteration com.apple.xpc.launchd|2022-05-29 23:51:19.130749 : Doing boot task: commit-boot-mode com.apple.xpc.launchd|2022-05-29 23:51:19.131334 : boot-mode committed: (null) com.apple.xpc.launchd|2022-05-29 23:51:19.131822 : Doing boot task: restore-datapartition com.apple.xpc.launchd|2022-05-29 23:51:19.146521 : restore-datapartition: optional boot task not present com.apple.xpc.launchd|2022-05-29 23:51:19.147063 : Skipping boot-task: mount-phase-2 com.apple.xpc.launchd|2022-05-29 23:51:19.147386 : Doing boot task: init-with-data-volume com.apple.xpc.launchd|2022-05-29 23:51:19.151938 : Skipping boot-task: MSUEarlyBootTask com.apple.xpc.launchd|2022-05-29 23:51:19.152389 : Skipping boot-task: fips com.apple.xpc.launchd|2022-05-29 23:51:19.152644 : Skipping boot-task: keybag com.apple.xpc.launchd|2022-05-29 23:51:19.152888 : Skipping boot-task: usermanagerd com.apple.xpc.launchd|2022-05-29 23:51:19.153115 : Skipping boot-task: dirs_cleaner com.apple.xpc.launchd|2022-05-29 23:51:19.154527 : launchd logging initialized. name: com.apple.xpc.launchd pid: 1 com.apple.xpc.launchd|2022-05-29 23:51:19.154779 : Skipping boot-task: xpcroleaccountd com.apple.xpc.launchd|2022-05-29 23:51:19.154802 : Skipping boot-task: init_featureflags com.apple.xpc.launchd|2022-05-29 23:51:19.154850 : Skipping boot-task: auearlyboot com.apple.xpc.launchd|2022-05-29 23:51:19.154915 : Skipping boot-task: tzinit com.apple.xpc.launchd|2022-05-29 23:51:19.154968 : Skipping boot-task: finish-restore com.apple.xpc.launchd|2022-05-29 23:51:19.155195 : Skipping boot-task: finish-demo-restore com.apple.xpc.launchd|2022-05-29 23:51:19.155705 : Skipping boot-task: sysstatuscheck com.apple.xpc.launchd|2022-05-29 23:51:19.155727 : Skipping boot-task: prng_seedctl com.apple.xpc.launchd|2022-05-29 23:51:19.156458 : Unable to open /System/Library/xpc/launchd.plist [2:No such file or directory] com.apple.xpc.launchd|2022-05-29 23:51:19.156721 : Skipping boot-task: launchd_cache_loader com.apple.xpc.launchd|2022-05-29 23:51:19.156832 : No MRM cache found com.apple.xpc.launchd|2022-05-29 23:51:19.186618 : Flushed 33 logs [limit: 20] com.apple.xpc.launchd|2022-05-29 23:51:19.156999 : Unable to load cache com.apple.xpc.launchd|2022-05-29 23:51:19.157464 : launchd UUID: 9654ECF0-8826-38AD-8F66-D914BD4088E3 com.apple.xpc.launchd|2022-05-29 23:51:19.157532 : Early boot complete. Continuing system boot. com.apple.xpc.launchd|2022-05-29 23:51:19.175848 (system) : entering bootstrap mode com.apple.xpc.launchd|2022-05-29 23:51:19.215336 (system/com.apple.PurpleReverseProxy.ramdisk) : (lint): Unable to find persona with type 6: kpersona_find returned -1 com.apple.xpc.launchd|2022-05-29 23:51:19.218538 (system/com.apple.diskimagesiod.ram) : (lint): Unable to find persona with type 6: kpersona_find returned -1 com.apple.xpc.launchd|2022-05-29 23:51:19.221075 (system/com.apple.restored_update) : (lint): Unable to find persona with type 6: kpersona_find returned -1 com.apple.xpc.launchd|2022-05-29 23:51:19.221386 (system) : pending spawn, domain in on-demand-only mode: com.apple.restored_update com.apple.xpc.launchd|2022-05-29 23:51:19.255524 (system) : Failed to bootstrap path: path = /System/Library/NanoLaunchDaemonsAltAccount, error = 2: No such file or directory com.apple.xpc.launchd|2022-05-29 23:51:19.255681 (system) : Failed to bootstrap path: path = /System/Library/NanoLaunchDaemons, error = 2: No such file or directory com.apple.xpc.launchd|2022-05-29 23:51:19.255884 (system) : Failed to bootstrap path: path = /AppleInternal/Library/LaunchDaemons, error = 2: No such file or directory com.apple.xpc.launchd|2022-05-29 23:51:19.256524 (system) : exiting bootstrap mode com.apple.xpc.launchd|2022-05-29 23:51:19.256716 (system) : exiting ondemand mode com.apple.xpc.launchd|2022-05-29 23:51:19.257121 (system/com.apple.restored_update) : internal event: WILL_SPAWN, code = 0 com.apple.xpc.launchd|2022-05-29 23:51:19.257692 (system/com.apple.restored_update) : service state: spawn scheduled com.apple.xpc.launchd|2022-05-29 23:51:19.257770 (system/com.apple.restored_update) : service state: spawning com.apple.xpc.launchd|2022-05-29 23:51:19.263794 (system/com.apple.restored_update) : launching: speculative com.apple.xpc.launchd|2022-05-29 23:51:19.291892 (system/com.apple.restored_update [3]) : xpcproxy spawned with pid 3 com.apple.xpc.launchd|2022-05-29 23:51:19.292394 (system/com.apple.restored_update [3]) : internal event: SPAWNED, code = 0 com.apple.xpc.launchd|2022-05-29 23:51:19.292657 (system/com.apple.restored_update [3]) : service state: xpcproxy com.apple.xpc.launchd|2022-05-29 23:51:19.293722 (system/com.apple.restored_update [3]) : internal event: SOURCE_ATTACH, code = 0 com.apple.xpc.launchd|2022-05-29 23:51:19.811665 (system/com.apple.restored_update [3]) : service state: running com.apple.xpc.launchd|2022-05-29 23:51:19.811878 (system/com.apple.restored_update [3]) : internal event: INIT, code = 0 com.apple.xpc.launchd|2022-05-29 23:51:19.812186 (system/com.apple.restored_update [3]) : Successfully spawned restored_update[3] because speculative com.apple.xpc.launchd|2022-05-29 23:51:24.425442 (system) : failed lookup: name = com.apple.logd, flags = 0x9, requestor = restored_update[3], error = 3: No such process com.apple.xpc.launchd|2022-05-29 23:51:24.438009 (system) : failed lookup: name = com.apple.system.notification_center, flags = 0x9, requestor = restored_update[3], error = 3: No such process com.apple.xpc.launchd|2022-05-29 23:51:24.728422 (system) : failed lookup: name = com.apple.system.logger, flags = 0x9, requestor = restored_update[3], error = 3: No such process [23:51:24.0702-GMT]{1>3} CHECKPOINT NOTICE: Pre-existing NVRAM variable: restore-outcome=initial_engine_failure_no_return [23:51:24.0795-GMT]{1>3} CHECKPOINT ANOMALY: [check_collection]auto-boot(does_not_exist) [23:51:24.0798-GMT]{1>3} CHECKPOINT PROGRESS: START (unknown) -> (initial_monitor_no_return) [23:51:24.0799-GMT]{1>3} CHECKPOINT NOTICE: NVRAM access available on initial check restore-anomalies = {0x00000000:[check_collection]auto-boot(does_not_exist)} restore-outcome = initial_monitor_no_return executing /usr/sbin/nvram -s restore-outcome=initial_monitor_no_return [23:51:25.0701-GMT]{1>3} CHECKPOINT NOTICE: (NVRAM set) restore-outcome=initial_monitor_no_return [sync=true] (initial entry) entering set_boot_stage [23:51:25.0760-GMT]{1>3} CHECKPOINT MONITOR: [0x0204] boot_stage restore-step-monitor = {0x11010204:"boot_stage"} executing /sbin/mount_tmpfs /mnt5 entering show_service_nodes disk0 IOBlockStorageDriver RegistryID : 0x10000022a Busy State : 0x0 Service State : 0x1e NS_01 RegistryID : 0x100000229 Busy State : 0x0 Service State : 0x1e AppleANS2NVMeController RegistryID : 0x100000207 Busy State : 0x0 Service State : 0x1e RTBuddyService RegistryID : 0x1000001ff Busy State : 0x0 Service State : 0x1e RTBuddyV2 RegistryID : 0x1000001f6 Busy State : 0x0 Service State : 0x1e iop-ans-nub RegistryID : 0x100000134 Busy State : 0x0 Service State : 0x1e AppleASCWrapV2 RegistryID : 0x1000001d0 Busy State : 0x0 Service State : 0x0 ans RegistryID : 0x100000133 Busy State : 0x0 Service State : 0x1e AppleT803xIO RegistryID : 0x1000001a7 Busy State : 0x5 Service State : 0x1e arm-io RegistryID : 0x100000118 Busy State : 0x1 Service State : 0x1e AppleARMPE RegistryID : 0x10000018a Busy State : 0x2 Service State : 0x1e N104DEV RegistryID : 0x100000187 Busy State : 0x2 Service State : 0x1e Root RegistryID : 0x100000100 Busy State : 0x0 Service State : 0x0 ---- [23:51:26.0254-GMT]{1>3} CHECKPOINT MONITOR: [0x1180] create_ramdisk restore-step-monitor = {0x11011180:"create_ramdisk"} [23:51:26.0260-GMT]{1>3} CHECKPOINT MONITOR: [0x0206] monitoring_child restore-step-monitor = {0x11010206:"monitoring_child"} com.apple.xpc.launchd|2022-05-29 23:51:28.850392 (system) : failed lookup: name = com.apple.logd, flags = 0x9, requestor = restored_update[6], error = 3: No such process com.apple.xpc.launchd|2022-05-29 23:51:28.867630 (system) : failed lookup: name = com.apple.system.notification_center, flags = 0x9, requestor = restored_update[6], error = 3: No such process libMobileGestalt utility.c:70: Could not open /private/var/containers/Shared/SystemGroup/systemgroup.com.apple.mobilegestaltcache/Library/Caches/com.apple.MobileGestalt.plist: No such file or directory 2022-05-29 23:51:29.016848+0000 restored_update[6:372] Could not open /private/var/containers/Shared/SystemGroup/systemgroup.com.apple.mobilegestaltcache/Library/Caches/com.apple.MobileGestalt.plist: No such file or directory com.apple.xpc.launchd|2022-05-29 23:51:29.049229 (system) : failed lookup: name = com.apple.system.logger, flags = 0x9, requestor = restored_update[6], error = 3: No such process [23:51:29.0041-GMT]{3>6} CHECKPOINT NOTICE: Image4 device: AP nonce clearable entering ramrod_clear_ap_nonce [23:51:29.0090-GMT]{3>6} CHECKPOINT NOTICE: AP nonce consumed [23:51:29.0113-GMT]{3>6} CHECKPOINT NOTICE: Pre-existing NVRAM variable: restore-outcome=initial_monitor_no_return [23:51:29.0181-GMT]{3>6} CHECKPOINT ANOMALY: [check_collection]auto-boot(does_not_exist) [23:51:29.0183-GMT]{3>6} CHECKPOINT PROGRESS: START (unknown) -> (initial_engine_no_return) [23:51:29.0185-GMT]{3>6} CHECKPOINT NOTICE: NVRAM access available on initial check restore-anomalies = {0x00000000:[check_collection]auto-boot(does_not_exist)} restore-outcome = initial_engine_no_return executing /usr/sbin/nvram restore-outcome=initial_engine_no_return [23:51:30.0069-GMT]{3>6} CHECKPOINT BEGIN: MAIN:[0x0400] umask restore-step-ids = {0x11030400:1} restore-step-names = {0x11030400:umask} restore-step-uptime = 13 restore-step-user-progress = -1 [23:51:30.0083-GMT]{3>6} CHECKPOINT END: MAIN:[0x0400] umask restore-step-ids = {} restore-step-names = {} restore-step-uptime = 13 restore-step-user-progress = -1 [23:51:30.0096-GMT]{3>6} CHECKPOINT BEGIN: MAIN:[0x0402] setvbuf restore-step-ids = {0x11030402:2} restore-step-names = {0x11030402:setvbuf} restore-step-uptime = 13 restore-step-user-progress = -1 [23:51:30.0112-GMT]{3>6} CHECKPOINT END: MAIN:[0x0402] setvbuf restore-step-ids = {} restore-step-names = {} restore-step-uptime = 13 restore-step-user-progress = -1 [23:51:30.0126-GMT]{3>6} CHECKPOINT BEGIN: MAIN:[0x0403] kernel_logger_thread restore-step-ids = {0x11030403:3} restore-step-names = {0x11030403:kernel_logger_thread} restore-step-uptime = 13 restore-step-user-progress = -1 [23:51:30.0141-GMT]{3>6} CHECKPOINT END: MAIN:[0x0403] kernel_logger_thread restore-step-ids = {} restore-step-names = {} restore-step-uptime = 13 restore-step-user-progress = -1 [23:51:30.0153-GMT]{3>6} CHECKPOINT BEGIN: MAIN:[0x0406] set_progress_0 restore-step-ids = {0x11030406:4} restore-step-names = {0x11030406:set_progress_0} restore-step-uptime = 13 restore-step-user-progress = -1 2022-05-29 23:51:30.246167+0000 restored_update[6:372] getDisplayListNumber: 0 2022-05-29 23:51:30.249449+0000 restored_update[6:372] IOMobileFramebufferCreateDisplayList: All Display Instances enumerated successfully - FB Count = 0 2022-05-29 23:51:30.358224+0000 restored_update[6:372] IOMobileFramebufferCreateDisplayList: All Display Instances enumerated successfully - FB Count = 0 2022-05-29 23:51:30.567181+0000 restored_update[6:372] IOMobileFramebufferCreateDisplayList: All Display Instances enumerated successfully - FB Count = 0 2022-05-29 23:51:30.876414+0000 restored_update[6:372] IOMobileFramebufferCreateDisplayList: All Display Instances enumerated successfully - FB Count = 0 2022-05-29 23:51:31.285646+0000 restored_update[6:372] IOMobileFramebufferCreateDisplayList: All Display Instances enumerated successfully - FB Count = 0 2022-05-29 23:51:31.794715+0000 restored_update[6:372] IOMobileFramebufferCreateDisplayList: All Display Instances enumerated successfully - FB Count = 0 2022-05-29 23:51:32.402939+0000 restored_update[6:372] IOMobileFramebufferCreateDisplayList: All Display Instances enumerated successfully - FB Count = 0 2022-05-29 23:51:33.111677+0000 restored_update[6:372] IOMobileFramebufferCreateDisplayList: All Display Instances enumerated successfully - FB Count = 0 2022-05-29 23:51:33.920488+0000 restored_update[6:372] IOMobileFramebufferCreateDisplayList: All Display Instances enumerated successfully - FB Count = 0 2022-05-29 23:51:34.830159+0000 restored_update[6:372] IOMobileFramebufferCreateDisplayList: All Display Instances enumerated successfully - FB Count = 0 2022-05-29 23:51:35.839108+0000 restored_update[6:372] IOMobileFramebufferCreateDisplayList: All Display Instances enumerated successfully - FB Count = 0 unable to get display list unable to get framebuffer ramrod_display_set_granular_progress_forced: 0.000000 [23:51:35.0850-GMT]{3>6} CHECKPOINT END: MAIN:[0x0406] set_progress_0 restore-step-ids = {} restore-step-names = {} restore-step-uptime = 18 restore-step-user-progress = 0 [23:51:35.0866-GMT]{3>6} CHECKPOINT BEGIN: MAIN:[0x0407] start_gasgauge_thread restore-step-ids = {0x11030407:5} restore-step-names = {0x11030407:start_gasgauge_thread} restore-step-uptime = 18 restore-step-user-progress = 0 [23:51:35.0894-GMT]{3>6} CHECKPOINT WARNING: MAIN:[0x0407] gasgauge_start_update_thread failed: -1 restored_update: gasgauge_start_update_thread failed: -1 [23:51:35.0900-GMT]{3>6} CHECKPOINT END: MAIN:[0x0407] start_gasgauge_thread restore-step-ids = {} restore-step-names = {} restore-step-warnings = {0x11060407:{0:"gasgauge_start_update_thread failed: -1"}} restore-step-uptime = 18 restore-step-user-progress = 0 [23:51:35.0916-GMT]{3>6} CHECKPOINT BEGIN: MAIN:[0x0408] listen_for_log_client restore-step-ids = {0x11030408:6} restore-step-names = {0x11030408:listen_for_log_client} restore-step-uptime = 18 restore-step-user-progress = 0 [23:51:35.0940-GMT]{3>6} CHECKPOINT END: MAIN:[0x0408] listen_for_log_client restore-step-ids = {} restore-step-names = {} restore-step-uptime = 18 restore-step-user-progress = 0 [23:51:35.0950-GMT]{3>6} CHECKPOINT BEGIN: MAIN:[0x040D] create_listen_socket restore-step-ids = {0x1103040D:7} restore-step-names = {0x1103040D:create_listen_socket} restore-step-uptime = 18 restore-step-user-progress = 0 [23:51:35.0963-GMT]{3>6} CHECKPOINT END: MAIN:[0x040D] create_listen_socket restore-step-ids = {} restore-step-names = {} restore-step-uptime = 18 restore-step-user-progress = 0 [23:51:35.0975-GMT]{3>6} CHECKPOINT BEGIN: MAIN:[0x0404] update_root_mount restore-step-ids = {0x11030404:8} restore-step-names = {0x11030404:update_root_mount} restore-step-uptime = 18 restore-step-user-progress = 0 [23:51:36.0028-GMT]{3>6} CHECKPOINT END: MAIN:[0x0404] update_root_mount restore-step-ids = {} restore-step-names = {} restore-step-uptime = 19 restore-step-user-progress = 0 [23:51:36.0039-GMT]{3>6} CHECKPOINT BEGIN: MAIN:[0x0405] disable_watchdog restore-step-ids = {0x11030405:9} restore-step-names = {0x11030405:disable_watchdog} restore-step-uptime = 19 restore-step-user-progress = 0 [23:51:36.0055-GMT]{3>6} CHECKPOINT END: MAIN:[0x0405] disable_watchdog restore-step-ids = {} restore-step-names = {} restore-step-uptime = 19 restore-step-user-progress = 0 [23:51:36.0067-GMT]{3>6} CHECKPOINT BEGIN: MAIN:[0x0410] usbcretimer_dfucheck restore-step-ids = {0x11030410:10} restore-step-names = {0x11030410:usbcretimer_dfucheck} restore-step-uptime = 19 restore-step-user-progress = 0 entering ramrod_device_has_usbcretimer Module not present on this platform. [23:51:36.0085-GMT]{3>6} CHECKPOINT END: MAIN:[0x0410] usbcretimer_dfucheck restore-step-ids = {} restore-step-names = {} restore-step-uptime = 19 restore-step-user-progress = 0 [23:51:36.0098-GMT]{3>6} CHECKPOINT BEGIN: MAIN:[0x040E] enable_usb restore-step-ids = {0x1103040E:11} restore-step-names = {0x1103040E:enable_usb} restore-step-uptime = 19 restore-step-user-progress = 0 2022-05-29 23:51:36.151792+0000 restored_update[6:372] [fast-path] taking platform fast path for key: re6Zb+zwFKJNlkQTUeT+/w 2022-05-29 23:51:36.154383+0000 restored_update[6:372] [fast-path] taking platform fast path for key: nFRqKto/RuQAV1P+0/qkBA 000023.162393 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::createUSBDevice: configuration: Apple Mobile Device 000023.163770 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::createUSBDevice: interface: AppleUSBMux 000023.165614 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::createUSBDevice: configuration: Reserved 1 + Apple Mobile Device 000023.166668 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::createUSBDevice: interface: Reserved 000023.167717 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::createUSBDevice: interface: AppleUSBMux 000023.168843 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::createUSBDevice: configuration: Reserved 2 + Apple Mobile Device 000023.170070 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::createUSBDevice: interface: Reserved 000023.171037 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::createUSBDevice: interface: AppleUSBMux 000023.172127 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::createUSBDevice: configuration: Reserved 3 + Apple Mobile Device 000023.173185 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::createUSBDevice: interface: Reserved 000023.173961 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::createUSBDevice: interface: AppleUSBMux waiting for matching IOKit service: { IOProviderClass = AppleUSBDeviceMux; } AppleUSBDeviceMux build: Apr 21 2022 22:07:43 000023.191082 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::gated_registerFunction: register function Reserved 000023.194852 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::gated_registerFunction: register function AppleUSBMux 000023.196313 AppleNubSynopsysOTG3Device@: IOUSBDeviceController::startUSBStack: starting usb stack 000023.637952 AppleNubSynopsysOTG3Device@0: IOUSBDeviceController::setupDeviceSetAddress: setting USB device address 23 000023.736878 AppleNubSynopsysOTG3Device@23: IOUSBDeviceController::setupDeviceSetConfiguration: configuration 0 -> 1 AppleUSBDeviceMux::message - kMessageInterfaceWasActivated 000023.763505 AppleNubSynopsysOTG3Device@23: IOUSBDeviceController::setupDeviceSetConfiguration: configuration 1 -> 0 AppleUSBDeviceMux::message - kMessageInterfaceWasDeActivated AppleUSBDeviceMux::reportStats: USB mux statistics: USB mux: 0 reads / 0 errors, 0 writes / 0 errors USB mux: 0 short packets, 0 dups 000024.002146 AppleOLYHAL_log.A[1] AppleOLYHALPortInterfacePCIeAMFM::initWithProvider: amfm not matched 000024.008819 wlan0.A[4] deferredStart@2755: Lowered adjustBusy(-1), getBusyState() -> 4 000024.066684 AppleNubSynopsysOTG3Device@23: IOUSBDeviceController::setupDeviceSetConfiguration: configuration 0 -> 4 AppleUSBDeviceMux::message - kMessageInterfaceWasActivated IOReturn AppleUSBDeviceMux::setPropertiesGated(OSObject *) setting debug level to 7 [23:51:39.0211-GMT]{3>6} CHECKPOINT END: MAIN:[0x040E] enable_usb restore-step-ids = {} restore-step-names = {} restore-step-uptime = 22 restore-step-user-progress = 0 waiting for host to trigger start of restore [timeout of 120 seconds] recv(9, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'GetValue' unrecognized request 'GetValue' unrecognized request 'GetValue' unrecognized request 'GetValue' com.apple.xpc.launchd|2022-05-29 23:52:51.298254 (system/com.apple.PurpleReverseProxy.ramdisk) : internal event: WILL_SPAWN, code = 0 com.apple.xpc.launchd|2022-05-29 23:52:51.302642 (system/com.apple.PurpleReverseProxy.ramdisk) : service state: spawn scheduled com.apple.xpc.launchd|2022-05-29 23:52:51.302803 (system/com.apple.PurpleReverseProxy.ramdisk) : service state: spawning com.apple.xpc.launchd|2022-05-29 23:52:51.306178 (system/com.apple.PurpleReverseProxy.ramdisk) : launching: ipc (socket) com.apple.xpc.launchd|2022-05-29 23:52:51.328085 (system/com.apple.PurpleReverseProxy.ramdisk [8]) : xpcproxy spawned with pid 8 com.apple.xpc.launchd|2022-05-29 23:52:51.332639 (system/com.apple.PurpleReverseProxy.ramdisk [8]) : internal event: SPAWNED, code = 0 com.apple.xpc.launchd|2022-05-29 23:52:51.332741 (system/com.apple.PurpleReverseProxy.ramdisk [8]) : service state: xpcproxy com.apple.xpc.launchd|2022-05-29 23:52:51.348645 (system/com.apple.PurpleReverseProxy.ramdisk [8]) : internal event: SOURCE_ATTACH, code = 0 com.apple.xpc.launchd|2022-05-29 23:52:51.959490 (system/com.apple.PurpleReverseProxy.ramdisk [8]) : service state: running com.apple.xpc.launchd|2022-05-29 23:52:51.959826 (system/com.apple.PurpleReverseProxy.ramdisk [8]) : internal event: INIT, code = 0 com.apple.xpc.launchd|2022-05-29 23:52:51.960238 (system/com.apple.PurpleReverseProxy.ramdisk [8]) : Successfully spawned PurpleReverseProxy[8] because ipc (socket) com.apple.xpc.launchd|2022-05-29 23:52:54.013832 (system) : failed lookup: name = com.apple.logd, flags = 0x9, requestor = PurpleReversePr[8], error = 3: No such process com.apple.xpc.launchd|2022-05-29 23:52:54.029700 (system) : failed lookup: name = com.apple.system.notification_center, flags = 0x9, requestor = PurpleReversePr[8], error = 3: No such process May 29 23:52:54 PurpleReverseProxy[8] : PurpleReverseProxyService.c:390(startProxy_block_invoke_4): got a ctrl connection from a host so we can now accept socks connections [23:52:54.0103-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x0600] client_protocol_version restore-step-ids = {0x11030600:12} restore-step-names = {0x11030600:client_protocol_version} restore-step-uptime = 97 restore-step-user-progress = 0 client protocol version 15 [23:52:54.0124-GMT]{3>6} CHECKPOINT END: RESTORED:[0x0600] client_protocol_version restore-step-ids = {} restore-step-names = {} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0136-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x0601] copy_restore_options restore-step-ids = {0x11030601:13} restore-step-names = {0x11030601:copy_restore_options} restore-step-uptime = 97 restore-step-user-progress = 0 unable to open /usr/local/share/restore//options.n104.plist: No such file or directory 0: NSPOSIXErrorDomain/2: create_dictionary_from_plist: unable to open plist unable to open /usr/local/share/restore//options.plist: No such file or directory 0: NSPOSIXErrorDomain/2: create_dictionary_from_plist: unable to open plist *** UUID 695BE7B6-627D-2CBD-DEB3-45489E137654 *** Restore options: UUID => {contents = "695BE7B6-627D-2CBD-DEB3-45489E137654"} PersonalizedDuringPreflight => {value = true} CreateFilesystemPartitions => {value = false} [23:52:54.0202-GMT]{3>6} CHECKPOINT END: RESTORED:[0x0601] copy_restore_options restore-step-ids = {} restore-step-names = {} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0217-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x06A1] usbcretimer_dfucheck_enforce restore-step-ids = {0x110306A1:14} restore-step-names = {0x110306A1:usbcretimer_dfucheck_enforce} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0232-GMT]{3>6} CHECKPOINT END: RESTORED:[0x06A1] usbcretimer_dfucheck_enforce restore-step-ids = {} restore-step-names = {} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0244-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x0655] is_host_compatible restore-step-ids = {0x11030655:15} restore-step-names = {0x11030655:is_host_compatible} restore-step-uptime = 97 restore-step-user-progress = 0 host/device compatibility check found that a message is missing or is not supported (optional): FirmwareUpdaterPreflight host/device compatibility check found that a message is missing or is not supported (optional): RestoreLocalPolicy host/device compatibility check found that a message is missing or is not supported (optional): AuthInstallCACert Checkpoint engine recorder path set to /mnt5 [23:52:54.0271-GMT]{3>6} CHECKPOINT END: RESTORED:[0x0655] is_host_compatible restore-step-ids = {} restore-step-names = {} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0288-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x0604] set_weight_from_options restore-step-ids = {0x11030604:16} restore-step-names = {0x11030604:set_weight_from_options} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0335-GMT]{3>6} CHECKPOINT END: RESTORED:[0x0604] set_weight_from_options restore-step-ids = {} restore-step-names = {} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0354-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x068F] libauthinstall_callback restore-step-ids = {0x1103068F:17} restore-step-names = {0x1103068F:libauthinstall_callback} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0387-GMT]{3>6} CHECKPOINT END: RESTORED:[0x068F] libauthinstall_callback restore-step-ids = {} restore-step-names = {} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0399-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x0608] device_has_hoover restore-step-ids = {0x11030608:18} restore-step-names = {0x11030608:device_has_hoover} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0420-GMT]{3>6} CHECKPOINT END: RESTORED:[0x0608] device_has_hoover restore-step-ids = {} restore-step-names = {} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0436-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x060B] update_ticket restore-step-ids = {0x1103060B:19} restore-step-names = {0x1103060B:update_ticket} restore-step-uptime = 97 restore-step-user-progress = 0 entering ramrod_ticket_update_verify looking up boot manifest hash device tree ticket_hash: C671DFEDA16BBD3BD127A03A27BF3DE4C0D66ECD8273013AADC04B32CFCF18C03A867BA61442C33223D8098FE644EE64 crypto-hash-method found. Using SHA2-384 computed ticket_hash : C671DFEDA16BBD3BD127A03A27BF3DE4C0D66ECD8273013AADC04B32CFCF18C03A867BA61442C33223D8098FE644EE64 received valid ticket (9067 bytes) [23:52:54.0493-GMT]{3>6} CHECKPOINT END: RESTORED:[0x060B] update_ticket restore-step-ids = {} restore-step-names = {} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0503-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x060D] wait_for_storage restore-step-ids = {0x1103060D:20} restore-step-names = {0x1103060D:wait_for_storage} restore-step-uptime = 97 restore-step-user-progress = 0 entering wait_for_storage_device ramrod_display_set_granular_progress_forced: 0.000000 Searching for NAND service Searching for NAND service Found NAND service: AppleANS2NVMeController NAND initialized. Waiting for devnode. entering wait_for_device: 'EmbeddedDeviceTypeRoot' Using device path /dev/disk0 for EmbeddedDeviceTypeRoot entering ramrod_probe_media_internal entering wait_for_device: 'EmbeddedDeviceTypeRoot' Using device path /dev/disk0 for EmbeddedDeviceTypeRoot [23:52:54.0594-GMT]{3>6} CHECKPOINT END: RESTORED:[0x060D] wait_for_storage restore-step-ids = {} restore-step-names = {} restore-step-uptime = 97 restore-step-user-progress = 0 [23:52:54.0606-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x060E] update_NAND_firmware restore-step-ids = {0x1103060E:21} restore-step-names = {0x1103060E:update_NAND_firmware} restore-step-uptime = 97 restore-step-user-progress = 0 ramrod_display_set_granular_progress_forced: 0.000000 entering update_NAND executing /usr/bin/nvmefwupdater --query virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::433:main dispatcher, selector=0xa IOReturn AppleNVMeUpdateUC::ExecuteRequest(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *)::369:selector=0xa virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::461:main dispatcher, selector=0xa status = 0x00000000 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::433:main dispatcher, selector=0x3 IOReturn AppleNVMeUpdateUC::ExecuteRequest(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *)::369:selector=0x3 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::461:main dispatcher, selector=0x3 status = 0x00000000 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::433:main dispatcher, selector=0x5 IOReturn AppleNVMeUpdateUC::ExecuteRequest(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *)::369:selector=0x5 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::461:main dispatcher, selector=0x5 status = 0x00000000 Invalid-FW-File.pak error NAND firmware file not exist: /usr/standalone/firmware/Invalid-FW-File.pak skipping update_NAND executing /usr/bin/nvmefwupdater --skip virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::433:main dispatcher, selector=0x3 NVMe Updater: Asked to skip the firmware update IOReturn AppleNVMeUpdateUC::ExecuteRequest(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *)::369:selector=0x3 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::461:main dispatcher, selector=0x3 status = 0x00000000 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::433:main dispatcher, selector=0x8 IOReturn AppleNVMeUpdateUC::ExecuteRequest(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *)::369:selector=0x8 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::461:main dispatcher, selector=0x8 status = 0x00000000 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::433:main dispatcher, selector=0x7 IOReturn AppleNVMeUpdateUC::ExecuteRequest(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *)::369:selector=0x7 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::461:main dispatcher, selector=0x7 status = 0x00000000 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::433:main dispatcher, selector=0x7 NVMe Updater: set_pci_port_state: result=0 IOReturn AppleNVMeUpdateUC::ExecuteRequest(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *)::369:selector=0x7 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::461:main dispatcher, selector=0x7 status = 0x00000000 NVMe Updater: set_pci_port_state: result=0 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::433:main dispatcher, selector=0x6 IOReturn AppleNVMeUpdateUC::ExecuteRequest(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *)::369:selector=0x6 virtual void AppleANS2NVMeController::SetModeselRegister(uint32_t)::1186:Setting modesel to 0 virtual IOReturn AppleANS2NVMeController::EnableCompletionQueue(uint16_t)::1920:nvme: Setting IOCQ base addr 0x81ba30000 virtual IOReturn AppleANS2NVMeController::EnableSubmissionQueue(uint16_t)::1890:nvme: Setting IOSQ base addr 0x81ba60000 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2008:nvme: Vendor ID : 0x1b36 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2009:nvme: Model Number : QEMU NVMe Ctrl bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2010:nvme: Serial Number : QEMUT8030ANS bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2011:nvme: Firmware Rev : 1.0 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2044:nvme: S3E A0 Invalid 1x slc 1D 0 plane 128GB NAND bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2053:ECCVersion : 0 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2054:FTL Rev : 0.0 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2055:DM_Version : 0 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2056:======================= bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2063:Found 16 namespaces in current NAND bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[1] as nstype[1] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[2] as nstype[2] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[3] as nstype[3] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[4] as nstype[4] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2460:Set NVRAM NSID to 5 bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[5] as nstype[5] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[6] as nstype[6] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[7] as nstype[8] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[8] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[9] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[10] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[11] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[12] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[13] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[14] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[15] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[16] as nstype[0] bool AppleEmbeddedNVMeController::SetSwapWriteLimit(uint32_t)::2236: Swap limit set to 2147483648bytes, 2GB bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2550:inOptions 0x1 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2617:NSID : 1 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2618:NSType : 1 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2619:NSSize : 0 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2617:NSID : 2 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2618:NSType : 2 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2619:NSSize : 2048 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2617:NSID : 3 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2618:NSType : 3 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2619:NSSize : 32 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2617:NSID : 4 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2618:NSType : 4 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2619:NSSize : 2 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2617:NSID : 5 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2618:NSType : 5 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2619:NSSize : 2 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2617:NSID : 6 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2618:NSType : 6 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2619:NSSize : 1 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2617:NSID : 7 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2618:NSType : 8 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2619:NSSize : 256 bool AppleEmbeddedNVMeController::CreateNamespaces(uint64_t, uint32_t)::2623: Creating 7 namespaces on NAND bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2008:nvme: Vendor ID : 0x1b36 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2009:nvme: Model Number : QEMU NVMe Ctrl bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2010:nvme: Serial Number : QEMUT8030ANS bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2011:nvme: Firmware Rev : 1.0 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2044:nvme: S3E A0 Invalid 1x slc 1D 0 plane 128GB NAND bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2053:ECCVersion : 0 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2054:FTL Rev : 0.0 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2055:DM_Version : 0 bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2056:======================= bool AppleEmbeddedNVMeController::IdentifyNamespaces()::2063:Found 16 namespaces in current NAND bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[1] as nstype[1] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[2] as nstype[2] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[3] as nstype[3] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[4] as nstype[4] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2460:Set NVRAM NSID to 5 bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[5] as nstype[5] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[6] as nstype[6] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[7] as nstype[8] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[8] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[9] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[10] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[11] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[12] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[13] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[14] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[15] as nstype[0] bool AppleEmbeddedNVMeController::DetermineNamespaces()::2475:Identified nsid[16] as nstype[0] bool AppleEmbeddedNVMeController::SetSwapWriteLimit(uint32_t)::2236: Swap limit set to 2147483648bytes, 2GB virtual bool AppleEmbeddedNVMeController::AllocateNodes(bool)::1622:allocateAll 0 virtual bool AppleEmbeddedNVMeController::AllocateNodes(bool)::1649:Creating blockdevice with NSID - 2, NSTYPE - 2 virtual bool AppleEmbeddedNVMeController::AllocateNodes(bool)::1649:Creating blockdevice with NSID - 3, NSTYPE - 3 virtual bool AppleEmbeddedNVMeController::AllocateNodes(bool)::1649:Creating blockdevice with NSID - 6, NSTYPE - 6 [effaceable:ERR ] unable to find content [effaceable:INIT] started virtual bool AppleEmbeddedNVMeController::AllocateNodes(bool)::1649:Creating blockdevice with NSID - 7, NSTYPE - 8 virtual IOReturn AppleNVMeNamespaceDevice::GetDeviceProperties()::272: Logical Blocks Size = 4096 virtual IOReturn AppleNVMeNamespaceDevice::GetDeviceProperties()::275: Block Count = 256 virtual bool AppleNVMeNamespaceDevice::start(IOService *)::111:NVMe Namespace Device registration done for NSID: 7, NSTYPE: 8 virtual IOReturn AppleNVMeUpdateUC::externalMethod(uint32_t, IOExternalMethodArguments *, IOExternalMethodDispatch *, OSObject *, void *)::461:main dispatcher, selector=0x6 status = 0x00000000 NVMe Updater: set_nvme_state: result=0 NVMe Updater: Skipped firmware update successfully [23:52:57.0997-GMT]{3>6} CHECKPOINT END: RESTORED:[0x060E] update_NAND_firmware restore-step-ids = {} restore-step-names = {} restore-step-uptime = 100 restore-step-user-progress = 0 [23:52:58.0012-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x060F] clear_remap_variable restore-step-ids = {0x1103060F:22} restore-step-names = {0x1103060F:clear_remap_variable} restore-step-uptime = 101 restore-step-user-progress = 0 entering clear_remap_variable executing /usr/sbin/nvram -d enable-remap-mode Successfully deleted enable-remap-modeenable-remap-mode [23:52:58.0971-GMT]{3>6} CHECKPOINT END: RESTORED:[0x060F] clear_remap_variable restore-step-ids = {} restore-step-names = {} restore-step-uptime = 101 restore-step-user-progress = 0 [23:52:58.0990-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x065C] print_nvram_variables restore-step-ids = {0x1103065C:23} restore-step-names = {0x1103065C:print_nvram_variables} restore-step-uptime = 101 restore-step-user-progress = 0 ramrod_print_NVRAM_variables executing /usr/sbin/nvram -xp boot-args restore-outcome aW5pdGlhbF9lbmdpbmVfbm9fcmV0dXJu restored-exit-status MHgxMDA= [23:52:59.0910-GMT]{3>6} CHECKPOINT END: RESTORED:[0x065C] print_nvram_variables restore-step-ids = {} restore-step-names = {} restore-step-uptime = 102 restore-step-user-progress = 0 [23:52:59.0927-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x0610] asp_nand_set_writable restore-step-ids = {0x11030610:24} restore-step-names = {0x11030610:asp_nand_set_writable} restore-step-uptime = 102 restore-step-user-progress = 0 ASPStorage instance not found! [23:52:59.0954-GMT]{3>6} CHECKPOINT END: RESTORED:[0x0610] asp_nand_set_writable restore-step-ids = {} restore-step-names = {} restore-step-uptime = 102 restore-step-user-progress = 0 [23:52:59.0974-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x0613] find_filesystem_partitions restore-step-ids = {0x11030613:25} restore-step-names = {0x11030613:find_filesystem_partitions} restore-step-uptime = 102 restore-step-user-progress = 0 entering ramrod_probe_media_internal entering wait_for_device: 'EmbeddedDeviceTypeRoot' Using device path /dev/disk0 for EmbeddedDeviceTypeRoot find_filesystem_partitions: storage=/dev/disk0 container= system= data= baseband data= log= update= xart= hardware= scratch= preboot= find_filesystem_partitions: recovery os container= volume= [23:53:00.0033-GMT]{3>6} CHECKPOINT END: RESTORED:[0x0613] find_filesystem_partitions restore-step-ids = {} restore-step-names = {} restore-step-uptime = 103 restore-step-user-progress = 0 [23:53:00.0051-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x067E] verify_storage_for_update restore-step-ids = {0x1103067E:26} restore-step-names = {0x1103067E:verify_storage_for_update} restore-step-uptime = 103 restore-step-user-progress = 0 [23:53:00.0106-GMT]{3>6} CHECKPOINT FAILURE:(FAILURE:78) RESTORED:[0x067E] verify_storage_for_update [0]D(Storage with invalid GPT header 0000000000000000 0000000000000000) restore-step-results = {0x1107067E:{0:78}} restore-step-codes = {0x1107067E:{0:78}} restore-step-domains = {0x1107067E:{0:"AMRestoreErrorDomain"}} restore-step-error = {0x1107067E:"[0]D(Storage with invalid GPT header 0000000000000000 0000000000000000)"} restore-step-uptime = 103 restore-step-user-progress = 0 [23:53:00.0135-GMT]{3>6} CHECKPOINT NOTICE: (NVRAM set) restore-step-user-progress=0 [sync=true] (first failure) [23:53:00.0141-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x067C] cleanup_boot_command restore-step-ids = {0x1103067E:26;0x1103067C:27} restore-step-names = {0x1103067E:verify_storage_for_update;0x1103067C:cleanup_boot_command} restore-step-uptime = 103 restore-step-user-progress = 0 entering reset_boot_command_if_in_values executing /usr/sbin/nvram -d recovery-boot-mode Successfully deleted recovery-boot-moderecovery-boot-mode executing /usr/sbin/nvram -d iboot-failure-reason Successfully deleted iboot-failure-reasoniboot-failure-reason [23:53:01.0905-GMT]{3>6} CHECKPOINT END: RESTORED:[0x067C] cleanup_boot_command restore-step-ids = {0x1103067E:26} restore-step-names = {0x1103067E:verify_storage_for_update} restore-step-uptime = 104 restore-step-user-progress = 0 [23:53:01.0928-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x1613] cleanup_recovery_os_volume restore-step-ids = {0x1103067E:26;0x11031613:28} restore-step-names = {0x1103067E:verify_storage_for_update;0x11031613:cleanup_recovery_os_volume} restore-step-uptime = 104 restore-step-user-progress = 0 [23:53:01.0955-GMT]{3>6} CHECKPOINT END: RESTORED:[0x1613] cleanup_recovery_os_volume restore-step-ids = {0x1103067E:26} restore-step-names = {0x1103067E:verify_storage_for_update} restore-step-uptime = 104 restore-step-user-progress = 0 [23:53:01.0974-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x0647] cleanup_check_result restore-step-ids = {0x1103067E:26;0x11030647:29} restore-step-names = {0x1103067E:verify_storage_for_update;0x11030647:cleanup_check_result} restore-step-uptime = 104 restore-step-user-progress = 0 [23:53:01.0998-GMT]{3>6} CHECKPOINT END: RESTORED:[0x0647] cleanup_check_result restore-step-ids = {0x1103067E:26} restore-step-names = {0x1103067E:verify_storage_for_update} restore-step-uptime = 104 restore-step-user-progress = 0 [23:53:02.0009-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x0648] cleanup_send_final_status restore-step-ids = {0x1103067E:26;0x11030648:30} restore-step-names = {0x1103067E:verify_storage_for_update;0x11030648:cleanup_send_final_status} restore-step-uptime = 105 restore-step-user-progress = 0 restore failed with CFError: 0: AMRestoreErrorDomain/4e: Storage with invalid GPT header 0000000000000000 0000000000000000 [23:53:02.0072-GMT]{3>6} CHECKPOINT END: RESTORED:[0x0648] cleanup_send_final_status restore-step-ids = {0x1103067E:26} restore-step-names = {0x1103067E:verify_storage_for_update} restore-step-uptime = 105 restore-step-user-progress = 0 [23:53:02.0096-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x0649] cleanup_wait_status_received restore-step-ids = {0x1103067E:26;0x11030649:31} restore-step-names = {0x1103067E:verify_storage_for_update;0x11030649:cleanup_wait_status_received} restore-step-uptime = 105 restore-step-user-progress = 0 waiting for host to acknowledge final status received... recv(10, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'GetValue' final response is missing message type[23:53:14.0433-GMT]{3>6} CHECKPOINT END: RESTORED:[0x0649] cleanup_wait_status_received May 29 23:53:14 PurpleReverseProxy[8] : RPSocket.cpp:218(signal): No client callback, missing event 8 for socket 0x15fd09da0 restore-step-ids = {0x1103067E:26} restore-step-names = {0x1103067E:verify_storage_for_update} May 29 23:53:14 PurpleReverseProxy[8] : RPSocket.cpp:360(FinalizeRPSocket): Closed after reading 103 bytes and writing 109 bytes restore-step-uptime = 117 May 29 23:53:14 PurpleReverseProxy[8] : RPSocket.cpp:363(FinalizeRPSocket): Ending os transaction for restore-step-user-progress = 0 [23:53:14.0461-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x064A] cleanup_write_restore_log restore-step-ids = {0x1103067E:26;0x1103064A:32} restore-step-names = {0x1103067E:verify_storage_for_update;0x1103064A:cleanup_write_restore_log} restore-step-uptime = 117 restore-step-user-progress = 0 send(12, 4) failed: Broken pipe[23:53:14.0479-GMT]{3>6} CHECKPOINT END: RESTORED:[0x064A] cleanup_write_restore_log restore-step-ids = {0x1103067E:26} restore-step-names = {0x1103067E:verify_storage_for_update} restore-step-uptime = 117 restore-step-user-progress = 0 send(12, 4) failed: Broken pipe[23:53:14.0496-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x0651] cleanup_failure_warning restore-step-ids = {0x1103067E:26;0x11030651:33} restore-step-names = {0x1103067E:verify_storage_for_update;0x11030651:cleanup_failure_warning} restore-step-uptime = 117 restore-step-user-progress = 0 send(12, 4) failed: Broken pipe[23:53:14.0517-GMT]{3>6} CHECKPOINT WARNING: RESTORED:[0x0651] not rebooting on failure[78] (CHECKPOINT FINISHED could be lost) restored_update: not rebooting on failure[78] (CHECKPOINT FINISHED could be lost) [23:53:14.0521-GMT]{3>6} CHECKPOINT END: RESTORED:[0x0651] cleanup_failure_warning restore-step-ids = {0x1103067E:26} restore-step-names = {0x1103067E:verify_storage_for_update} restore-step-warnings = {0x11060651:{0:"not rebooting on failure[78] (CHECKPOINT FINISHED could be lost)"}} restore-step-uptime = 117 restore-step-user-progress = 0 send(12, 4) failed: Broken pipe[23:53:14.0548-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x064B] cleanup_post_restore restore-step-ids = {0x1103067E:26;0x1103064B:34} restore-step-names = {0x1103067E:verify_storage_for_update;0x1103064B:cleanup_post_restore} restore-step-uptime = 117 restore-step-user-progress = 0 send(12, 4) failed: Broken pipe000121.559964 AppleNubSynopsysOTG3Device@23: IOUSBDeviceController::gated_setProperties: unknown command StoreDriverDebugLog IOUSBDeviceControllerSendCommand failed: 0xe0000001 waiting for disconnect to continue read error waiting for socket disconnect: Connection reset by peer [23:53:14.0586-GMT]{3>6} CHECKPOINT END: RESTORED:[0x064B] cleanup_post_restore restore-step-ids = {0x1103067E:26} restore-step-names = {0x1103067E:verify_storage_for_update} restore-step-uptime = 117 restore-step-user-progress = 0 send(12, 4) failed: Broken pipe[23:53:14.0615-GMT]{3>6} CHECKPOINT BEGIN: RESTORED:[0x064C] cleanup_release restore-step-ids = {0x1103067E:26;0x1103064C:35} restore-step-names = {0x1103067E:verify_storage_for_update;0x1103064C:cleanup_release} restore-step-uptime = 117 restore-step-user-progress = 0 send(12, 4) failed: Broken pipe[23:53:14.0637-GMT]{3>6} CHECKPOINT END: RESTORED:[0x064C] cleanup_release restore-step-ids = {0x1103067E:26} restore-step-names = {0x1103067E:verify_storage_for_update} restore-step-uptime = 117 restore-step-user-progress = 0 send(12, 4) failed: Broken piperestore completed (-1), requesting reboot [FAILURE] [23:53:14.0662-GMT]{3>6} CHECKPOINT NOTICE: MAIN:[0x0104] handle_new_connections [23:53:14.0666-GMT]{3>6} CHECKPOINT FINISHED-ENGINES:(FAILURE:1) {0x1107067E:{0:78}} [23:53:14.0671-GMT]{3>6} CHECKPOINT PROGRESS: FAILED (initial_engine_no_return) -> (initial_engine_failure_no_return) restore-outcome = initial_engine_failure_no_return executing /usr/sbin/nvram -s restore-outcome=initial_engine_failure_no_return recv(11, 4) failed: connection closed unable to read message size: -1 could not receive message