Closed Neil-0609 closed 2 years ago
ASR STATUS: setup ASR: Validating target... ASR: done ASR STATUS: metadata ASR: Validating source... ASR: done ASR: Retrieving scan information... ASR: done ASR: Validating sizes... ASR: done ASR STATUS: restore ASR RESTORE PROGRESS: 2% ramrod_display_set_granular_progress_forced: 4.000000 ASR RESTORE PROGRESS: 4% ramrod_display_set_granular_progress_forced: 5.000000 ASR RESTORE PROGRESS: 6% ramrod_display_set_granular_progress_forced: 6.000000 ASR RESTORE PROGRESS: 8% ramrod_display_set_granular_progress_forced: 6.000000 ASR RESTORE PROGRESS: 10% ramrod_display_set_granular_progress_forced: 7.000000 ASR RESTORE PROGRESS: 12% ramrod_display_set_granular_progress_forced: 7.000000 ASR RESTORE PROGRESS: 14% ramrod_display_set_granular_progress_forced: 8.000000 ASR RESTORE PROGRESS: 16% ramrod_display_set_granular_progress_forced: 8.000000 ASR RESTORE PROGRESS: 18% ramrod_display_set_granular_progress_forced: 9.000000 ASR RESTORE PROGRESS: 20% ramrod_display_set_granular_progress_forced: 9.000000 ASR RESTORE PROGRESS: 22% ramrod_display_set_granular_progress_forced: 10.000000 ASR RESTORE PROGRESS: 24% ramrod_display_set_granular_progress_forced: 10.000000 ASR RESTORE PROGRESS: 26% ramrod_display_set_granular_progress_forced: 11.000000 recv(9, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'GetValue' ASR RESTORE PROGRESS: 28% ramrod_display_set_granular_progress_forced: 11.000000 ASR RESTORE PROGRESS: 30% ramrod_display_set_granular_progress_forced: 12.000000 ASR RESTORE PROGRESS: 32% ramrod_display_set_granular_progress_forced: 12.000000 ASR RESTORE PROGRESS: 34% ramrod_display_set_granular_progress_forced: 13.000000 ASR RESTORE PROGRESS: 36% ramrod_display_set_granular_progress_forced: 13.000000 ASR RESTORE PROGRESS: 38% ramrod_display_set_granular_progress_forced: 14.000000 ASR RESTORE PROGRESS: 40% ramrod_display_set_granular_progress_forced: 15.000000 ASR RESTORE PROGRESS: 42% ramrod_display_set_granular_progress_forced: 15.000000 ASR RESTORE PROGRESS: 44% ramrod_display_set_granular_progress_forced: 16.000000 ASR RESTORE PROGRESS: 46% ramrod_display_set_granular_progress_forced: 16.000000 ASR RESTORE PROGRESS: 48% ramrod_display_set_granular_progress_forced: 17.000000 ASR RESTORE PROGRESS: 50% ramrod_display_set_granular_progress_forced: 17.000000 ASR RESTORE PROGRESS: 52% ramrod_display_set_granular_progress_forced: 18.000000 ASR RESTORE PROGRESS: 54% ramrod_display_set_granular_progress_forced: 18.000000 ASR RESTORE PROGRESS: 56% ramrod_display_set_granular_progress_forced: 19.000000 ASR RESTORE PROGRESS: 58% ramrod_display_set_granular_progress_forced: 19.000000 recv(9, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'GetValue' ASR RESTORE PROGRESS: 60% ramrod_display_set_granular_progress_forced: 20.000000 ASR RESTORE PROGRESS: 62% ramrod_display_set_granular_progress_forced: 20.000000 ASR RESTORE PROGRESS: 64% ramrod_display_set_granular_progress_forced: 21.000000 ASR RESTORE PROGRESS: 66% ramrod_display_set_granular_progress_forced: 21.000000 ASR RESTORE PROGRESS: 68% ramrod_display_set_granular_progress_forced: 22.000000 ASR RESTORE PROGRESS: 70% ramrod_display_set_granular_progress_forced: 22.000000 ASR RESTORE PROGRESS: 72% ramrod_display_set_granular_progress_forced: 23.000000 ASR RESTORE PROGRESS: 74% ramrod_display_set_granular_progress_forced: 24.000000 ASR RESTORE PROGRESS: 76% ramrod_display_set_granular_progress_forced: 24.000000 ASR RESTORE PROGRESS: 78% ramrod_display_set_granular_progress_forced: 25.000000 ASR RESTORE PROGRESS: 80% ramrod_display_set_granular_progress_forced: 25.000000 ASR RESTORE PROGRESS: 82% ramrod_display_set_granular_progress_forced: 26.000000 ASR RESTORE PROGRESS: 84% ramrod_display_set_granular_progress_forced: 26.000000 ASR RESTORE PROGRESS: 86% ramrod_display_set_granular_progress_forced: 27.000000 ASR RESTORE PROGRESS: 88% ramrod_display_set_granular_progress_forced: 27.000000 recv(9, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'GetValue' ASR RESTORE PROGRESS: 90% ramrod_display_set_granular_progress_forced: 28.000000 ASR RESTORE PROGRESS: 92% ramrod_display_set_granular_progress_forced: 28.000000 ASR RESTORE PROGRESS: 94% ramrod_display_set_granular_progress_forced: 29.000000 ASR RESTORE PROGRESS: 96% ramrod_display_set_granular_progress_forced: 29.000000 ASR RESTORE PROGRESS: 98% ramrod_display_set_granular_progress_forced: 30.000000 ASR RESTORE PROGRESS: 100% ramrod_display_set_granular_progress_forced: 30.000000 ASR: Copied 7480168448 bytes in 189.47 seconds, 38555.15 KiB/s ASR STATUS: verify ASR VERIFY PROGRESS: 2% ramrod_display_set_granular_progress_forced: 31.000000 ASR VERIFY PROGRESS: 4% ramrod_display_set_granular_progress_forced: 31.000000 ASR VERIFY PROGRESS: 6% ramrod_display_set_granular_progress_forced: 32.000000 ASR VERIFY PROGRESS: 8% ramrod_display_set_granular_progress_forced: 32.000000 ASR VERIFY PROGRESS: 10% ramrod_display_set_granular_progress_forced: 32.000000 ASR VERIFY PROGRESS: 12% ramrod_display_set_granular_progress_forced: 33.000000 ASR VERIFY PROGRESS: 14% ramrod_display_set_granular_progress_forced: 33.000000 ASR VERIFY PROGRESS: 16% ramrod_display_set_granular_progress_forced: 33.000000 ASR VERIFY PROGRESS: 18% ramrod_display_set_granular_progress_forced: 34.000000 ASR VERIFY PROGRESS: 20% ramrod_display_set_granular_progress_forced: 34.000000 ASR VERIFY PROGRESS: 22% ramrod_display_set_granular_progress_forced: 35.000000 ASR VERIFY PROGRESS: 24% ramrod_display_set_granular_progress_forced: 35.000000 ASR VERIFY PROGRESS: 26% ramrod_display_set_granular_progress_forced: 35.000000 ASR VERIFY PROGRESS: 28% ramrod_display_set_granular_progress_forced: 36.000000 ASR VERIFY PROGRESS: 30% ramrod_display_set_granular_progress_forced: 36.000000 ASR VERIFY PROGRESS: 32% ramrod_display_set_granular_progress_forced: 37.000000 ASR VERIFY PROGRESS: 34% ramrod_display_set_granular_progress_forced: 37.000000 ASR VERIFY PROGRESS: 36% ramrod_display_set_granular_progress_forced: 37.000000 ASR VERIFY PROGRESS: 38% ramrod_display_set_granular_progress_forced: 38.000000 ASR VERIFY PROGRESS: 40% ramrod_display_set_granular_progress_forced: 38.000000 ASR VERIFY PROGRESS: 42% ramrod_display_set_granular_progress_forced: 38.000000 ASR VERIFY PROGRESS: 44% ramrod_display_set_granular_progress_forced: 39.000000 ASR VERIFY PROGRESS: 46% ramrod_display_set_granular_progress_forced: 39.000000 ASR VERIFY PROGRESS: 48% ramrod_display_set_granular_progress_forced: 40.000000 ASR VERIFY PROGRESS: 50% ramrod_display_set_granular_progress_forced: 40.000000 ASR VERIFY PROGRESS: 52% ramrod_display_set_granular_progress_forced: 40.000000 ASR VERIFY PROGRESS: 54% ramrod_display_set_granular_progress_forced: 41.000000 ASR VERIFY PROGRESS: 56% ramrod_display_set_granular_progress_forced: 41.000000 ASR VERIFY PROGRESS: 58% ramrod_display_set_granular_progress_forced: 41.000000 ASR VERIFY PROGRESS: 60% ramrod_display_set_granular_progress_forced: 42.000000 ASR VERIFY PROGRESS: 62% ramrod_display_set_granular_progress_forced: 42.000000 ASR VERIFY PROGRESS: 64% ramrod_display_set_granular_progress_forced: 43.000000 ASR VERIFY PROGRESS: 66% ramrod_display_set_granular_progress_forced: 43.000000 ASR VERIFY PROGRESS: 68% ramrod_display_set_granular_progress_forced: 43.000000 ASR VERIFY PROGRESS: 70% ramrod_display_set_granular_progress_forced: 44.000000 ASR VERIFY PROGRESS: 72% ramrod_display_set_granular_progress_forced: 44.000000 ASR VERIFY PROGRESS: 74% ramrod_display_set_granular_progress_forced: 45.000000 ASR VERIFY PROGRESS: 76% ramrod_display_set_granular_progress_forced: 45.000000 ASR VERIFY PROGRESS: 78% ramrod_display_set_granular_progress_forced: 45.000000 ASR VERIFY PROGRESS: 80% ramrod_display_set_granular_progress_forced: 46.000000 ASR VERIFY PROGRESS: 82% ramrod_display_set_granular_progress_forced: 46.000000 ASR VERIFY PROGRESS: 84% ramrod_display_set_granular_progress_forced: 46.000000 ASR VERIFY PROGRESS: 86% ramrod_display_set_granular_progress_forced: 47.000000 ASR VERIFY PROGRESS: 88% ramrod_display_set_granular_progress_forced: 47.000000 ASR VERIFY PROGRESS: 90% ramrod_display_set_granular_progress_forced: 48.000000 ASR VERIFY PROGRESS: 92% ramrod_display_set_granular_progress_forced: 48.000000 ASR VERIFY PROGRESS: 94% ramrod_display_set_granular_progress_forced: 48.000000 ASR VERIFY PROGRESS: 96% ramrod_display_set_granular_progress_forced: 49.000000 ASR VERIFY PROGRESS: 98% ramrod_display_set_granular_progress_forced: 49.000000 ASR VERIFY PROGRESS: 100% ramrod_display_set_granular_progress_forced: 50.000000 ASR: Calculated SHA-1 checksum 7480168448 bytes in 12.11 seconds, 603092.17 KiB/s ASR: Restored target device is /mnt1/apfs_invert_asr_img. ASR: Restore completed successfully. ASR STATUS: finish entering force_unmount_partition System unmounted restore_apfs_image : finished restoring an APFS image invert_apfs_image : inverting : /System/Library/Filesystems/apfs.fs/apfs_invert /dev/disk0s1 0 apfs_invert_asr_img executing /System/Library/Filesystems/apfs.fs/apfs_invert -d /dev/disk0s1 -s 1 -n apfs_invert_asr_img -f ASR: Mounting outer volume (/dev/disk0s1 s1)... ASR: nx_mount:1184: disk0s1 initializing cache w/hash_size 32768 and cache size 65536 ASR: nx_mount:1295: disk0s1 container cleanly-unmounted flag set. ASR: nx_mount:1460: disk0s1 checkpoint search: largest xid 18, best xid 18 @ 35 ASR: spaceman_metazone_init:191: disk0s1 metazone for device 0 of size 244140 blocks (encrypted: 7568349-7690419 unencrypted: 7690419-7812489) ASR: spaceman_datazone_init:625: disk0s1 allocation zone on dev 0 for allocations of 1 blocks starting at paddr 4096000 ASR: spaceman_datazone_init:625: disk0s1 allocation zone on dev 0 for allocations of 2 blocks starting at paddr 32768 ASR: spaceman_datazone_init:625: disk0s1 allocation zone on dev 0 for allocations of 3 blocks starting at paddr 65536 ASR: spaceman_datazone_init:625: disk0s1 allocation zone on dev 0 for allocations of 4 blocks starting at paddr 98304 ASR: dev_dump:256: Aggregate constructed: dev=0x13df04130 di=0 dv_num_slice=27 dv_num_slice_blk=294912 dv_num_lslice_blk=144777 ASR: spaceman_scan_free_blocks:3172: disk0s1 scan took 0.005226 s (no trims) ASR: Getting image dstream info... ASR: apfs_invert_asr_img: dstream_id=16, size=7738490880 ASR: Mounting inner volume (apfs_invert_asr_img)... ASR: nx_mount:1184: initializing cache w/hash_size 32768 and cache size 65536 ASR: nx_mount:1460: checkpoint search: largest xid 307, best xid 307 @ 3 ASR: Copying inner volume extentref tree into outer volume... ASR: Copied 76454 original extents and created 0 new extents ASR: Copying inner volume fsroot tree into outer volume... ASR: Copied 1216402 fs_root records plus 0 new file extents ASR: Cleaning up unused blocks... ASR: Freed 84093 data blocks and kept 1742123 data blocks ASR: Updating the superblock... ASR: Deleting old fs_root... ASR: Deleting old extentref tree... ASR: tx_flush:1043: disk0s1 tx xid 19 took 6752694 us to flush ASR: Finishing transaction... ASR: Unmounting... ASR: nx_volume_group_update:7762: disk0s1s1 Volume System is not in a volume group ASR: sanity_check_alloced_blocks:805: disk0s1s1 fs_alloc_count mismatch: fs root nodes 1 extent 1 omap 449 snap_meta 1 doc_id 0 prev_doc_id 0 fext: 0 pfkur: 0 er: 0 udata: 1742123 fs_alloc_count 1792938 != count 1742576 ASR: dev_dump:256: Aggregate destructed: dev=0x13df04130 di=0 dv_num_slice=27 dv_num_slice_blk=294912 dv_num_lslice_blk=144777 ASR: Success! invert_apfs_image : succeeded inverting : /System/Library/Filesystems/apfs.fs/apfs_invert /dev/disk0s1 0 apfs_invert_asr_img ASR succeed on initial attempt ASR and invert Succeeded [08:36:44.0698-GMT]{3>5} CHECKPOINT END: (null):[0x065B] asr_and_invert_image restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 241 restore-step-user-progress = 50 [08:36:44.0699-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x0680] read_new_os_build_version restore-step-ids = {0x11030677:49;0x11030680:58} restore-step-names = {0x11030677:perform_main_os_prepare;0x11030680:read_new_os_build_version} restore-step-uptime = 241 restore-step-user-progress = 50 entering mount_partition executing /sbin/mount_apfs -R /dev/disk0s1s1 /mnt1 /dev/disk0s1s1 mounted on /mnt1 System mounted read-write ramrod_read_new_os_build_version: new OS version: 19F77 (user) [08:36:44.0742-GMT]{3>5} CHECKPOINT END: (null):[0x0680] read_new_os_build_version restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 241 restore-step-user-progress = 50 [08:36:44.0742-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x0675] init_gigalocker restore-step-ids = {0x11030677:49;0x11030675:59} restore-step-names = {0x11030677:perform_main_os_prepare;0x11030675:init_gigalocker} restore-step-uptime = 241 restore-step-user-progress = 50 We should have an xART partition. entering mount_partition executing /sbin/mount_apfs -R /dev/disk0s1s3 /mnt7 /dev/disk0s1s3 mounted on /mnt7 xART mounted read-write entering ramrod_init_gigalocker We should have an xART partition. executing /usr/libexec/seputil --gigalocker-init seputil: Gigalocker file (/mnt7/44A42284-993A-5358-AC6A-A8713640A897.gl) doesn't exist: No such file or directory seputil: Gigalocker initialization completed gigalocker: ONLINE [08:36:45.0168-GMT]{3>5} CHECKPOINT END: (null):[0x0675] init_gigalocker restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 242 restore-step-user-progress = 50 [08:36:45.0168-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x068C] init_permanent_update_volume restore-step-ids = {0x11030677:49;0x1103068C:60} restore-step-names = {0x11030677:perform_main_os_prepare;0x1103068C:init_permanent_update_volume} restore-step-uptime = 242 restore-step-user-progress = 50 libpartition: entering _partition_wait_for_device: 'EmbeddedDeviceTypeRoot'
libpartition: Using device path /dev/disk0 for EmbeddedDeviceTypeRoot
libpartition: entering partition_probe_media. libpartition: APFS Container 'Container' /dev/disk0s1
libpartition: unexpected partition 'Hardware' - skipping libpartition: not deleting existing update filesystem executing /sbin/mount -t apfs -o nobrowse /dev/disk0s1s7 /mnt4 libpartition: create_update_partition_folder_hierarchy: Creating folder hierarchy for update partition libpartition: Creating hardware folder hierarchy
libpartition: Creating root folder libpartition: Successfully created root Hardware folder. Proceeding to create the rest of the folder hierarchy libpartition: Successfully created Battery under /mnt4/Hardware with permissions 0700 for user root libpartition: create_update_partition_folder_hierarchy: Done creating folder hierarchy for update partition Update volume was successfully unmounted. libpartition: Deleting contents of /mnt4... libpartition: Could not reset metadata on /mnt4: Read-only file system libpartition: Deleting contents of /mnt4 failed (result: 30). entering ramrod_probe_media_internal entering wait_for_device: 'EmbeddedDeviceTypeRoot' Using device path /dev/disk0 for EmbeddedDeviceTypeRoot device partitioning scheme is GPT APFS Container 'Container' /dev/disk0s1 device is APFS formatted Captured preboot partition on main OS container 2 [08:36:45.0259-GMT]{3>5} CHECKPOINT END: (null):[0x068C] init_permanent_update_volume restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 242 restore-step-user-progress = 50 [08:36:45.0261-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x0626] mount_filesystem restore-step-ids = {0x11030677:49;0x11030626:61} restore-step-names = {0x11030677:perform_main_os_prepare;0x11030626:mount_filesystem} restore-step-uptime = 242 restore-step-user-progress = 50 restored_mount_filesystem: OK to fsck since this is a erase install entering unmount_filesystems ramrod_display_set_granular_progress_forced: 50.000000 entering force_unmount_partition Preboot not mounted (unmount ignored) Skipping unmount of the update partition ramrod_display_set_granular_progress_forced: 50.000000 entering force_unmount_partition Hardware not mounted (unmount ignored) ramrod_display_set_granular_progress_forced: 50.000000 entering force_unmount_partition Baseband Data not mounted (unmount ignored) ramrod_display_set_granular_progress_forced: 50.000000 entering force_unmount_partition Data not mounted (unmount ignored) ramrod_display_set_granular_progress_forced: 50.000000 entering force_unmount_partition entering ramrod_shutdown_gigalocker We should have an xART partition. executing /usr/libexec/seputil --gigalocker-shutdown xART unmounted ramrod_display_set_granular_progress_forced: 50.000000 entering force_unmount_partition System unmounted entering fsck_filesystems ramrod_display_set_granular_progress_forced: 50.000000 Starting an xpc transaction. executing /sbin/fsck_apfs -dyo /dev/disk0s1s1 Requested cache size: 250MB (7k blocks 32kB) Checking the container superblock. Checking the checkpoint with transaction ID 23. Checking the space manager. Checking the space manager free queue trees. Checking the object map. Checking volume /dev/rdisk0s1s1. Checking the APFS volume superblock. The volume System was formatted by newfs_apfs (1677.41.3.100.4) and last modified by apfs_kext (1934.122.1). Checking the object map. Checking the snapshot metadata tree. Checking the snapshot metadata. Checking the fsroot tree. recv(9, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'GetValue' Checking the extent ref tree. Verifying volume object map space. Verifying allocated space. The volume /dev/disk0s1s1 appears to be OK. fsck succeeded on /dev/disk0s1s1 ramrod_display_set_granular_progress_forced: 50.000000 Starting an xpc transaction. executing /sbin/fsck_apfs -dyo /dev/disk0s1s3 Requested cache size: 250MB (7k blocks 32kB) Checking the container superblock. Checking the checkpoint with transaction ID 23. Checking the space manager. Checking the space manager free queue trees. Checking the object map. Checking volume /dev/rdisk0s1s3. Checking the APFS volume superblock. The volume xART was formatted by newfs_apfs (1934.122.1) and last modified by apfs_kext (1934.122.1). Checking the object map. Checking the snapshot metadata tree. Checking the snapshot metadata. Checking the fsroot tree. Checking the extent ref tree. Verifying volume object map space. Verifying allocated space. The volume /dev/disk0s1s3 appears to be OK. fsck succeeded on /dev/disk0s1s3 ramrod_display_set_granular_progress_forced: 50.000000 fsck_filesystems: No dev node for 'Data' partition leaving fsck_filesystems, returning 26 Ignoring fsck failure for this restore. We should have an xART partition. entering mount_partition executing /sbin/mount_apfs -R /dev/disk0s1s3 /mnt7 /dev/disk0s1s3 mounted on /mnt7 xART mounted read-write entering ramrod_init_gigalocker We should have an xART partition. executing /usr/libexec/seputil --gigalocker-init seputil: Gigalocker file (/mnt7/44A42284-993A-5358-AC6A-A8713640A897.gl) exists seputil: Gigalocker initialization completed gigalocker: ONLINE entering mount_filesystems ramrod_display_set_granular_progress_forced: 51.000000 entering mount_partition executing /sbin/mount_apfs -R /dev/disk0s1s1 /mnt1 /dev/disk0s1s1 mounted on /mnt1 System mounted read-write ramrod_display_set_granular_progress_forced: 51.000000 entering mount_partition executing /sbin/mount_apfs -R /dev/disk0s1s4 /mnt3 /dev/disk0s1s4 mounted on /mnt3 Baseband Data mounted read-write ramrod_display_set_granular_progress_forced: 51.000000 entering mount_partition executing /sbin/mount_apfs -R /dev/disk0s1s5 /mnt6 /dev/disk0s1s5 mounted on /mnt6 Hardware mounted read-write Skipping mount of update partition ramrod_display_set_granular_progress_forced: 51.000000 entering mount_partition executing /sbin/mount_apfs -R /dev/disk0s1s6 /mnt9 /dev/disk0s1s6 mounted on /mnt9 Preboot mounted read-write Tried to unmount a volume at '/mnt4' that wasn't mounted. Ignoring the error. Successfully called unmount on the update partition mount point(/mnt4). Attempting to mount the update partition at /mnt4 libpartition: entering mount_update_partition_if_exists
libpartition: entering _partition_wait_for_device: 'EmbeddedDeviceTypeRoot'
libpartition: Using device path /dev/disk0 for EmbeddedDeviceTypeRoot
libpartition: entering partition_probe_media. libpartition: APFS Container 'Container' /dev/disk0s1
libpartition: unexpected partition 'Hardware' - skipping executing /sbin/mount -t apfs -o nobrowse /dev/disk0s1s7 /mnt4 libpartition: Successfully mounted update partition at /mnt4 Successfully mounted update volume entering set_system_vol_name successfully renamed System partition after inverting it [08:36:57.0690-GMT]{3>5} CHECKPOINT END: (null):[0x0626] mount_filesystem restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 254 restore-step-user-progress = 51 [08:36:57.0691-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x0662] write_persistent_files restore-step-ids = {0x11030677:49;0x11030662:62} restore-step-names = {0x11030677:perform_main_os_prepare;0x11030662:write_persistent_files} restore-step-uptime = 254 restore-step-user-progress = 51 _hardware_folder_data_write_file: Attempting to recreate directory /mnt4/Hardware/Battery _hardware_folder_data_write_file: Successfully created directory /mnt4/Hardware/Battery Successfully persisted hardware folder contents [08:36:57.0692-GMT]{3>5} CHECKPOINT END: (null):[0x0662] write_persistent_files restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 254 restore-step-user-progress = 51 [08:36:57.0692-GMT]{3>5} CHECKPOINT END: RESTORED:[0x0677] perform_main_os_prepare restore-step-ids = {} restore-step-names = {} restore-step-uptime = 254 restore-step-user-progress = 51 [08:36:57.0693-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x067A] perform_short_prepare restore-step-ids = {0x1103067A:63} restore-step-names = {0x1103067A:perform_short_prepare} restore-step-uptime = 254 restore-step-user-progress = 51 [08:36:57.0694-GMT]{3>5} CHECKPOINT END: RESTORED:[0x067A] perform_short_prepare restore-step-ids = {} restore-step-names = {} restore-step-uptime = 254 restore-step-user-progress = 51 [08:36:57.0694-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x067B] perform_restore_installing restore-step-ids = {0x1103067B:64} restore-step-names = {0x1103067B:perform_restore_installing} restore-step-uptime = 254 restore-step-user-progress = 51 [08:36:57.0695-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x0627] fdr_restore_saved_data restore-step-ids = {0x1103067B:64;0x11030627:65} restore-step-names = {0x1103067B:perform_restore_installing;0x11030627:fdr_restore_saved_data} restore-step-uptime = 254 restore-step-user-progress = 51 entering mount_partition System already mounted read-write (mount ignored) entering mount_partition Hardware already mounted read-write (mount ignored) creating directory (owner=0 group=0 mode=755, class=-1) /mnt6/FactoryData creating directory (owner=0 group=0 mode=755, class=-1) /mnt6/FactoryData/System creating directory (owner=0 group=0 mode=755, class=-1) /mnt6/FactoryData/System/Library creating directory (owner=0 group=0 mode=755, class=-1) /mnt6/FactoryData/System/Library/Caches creating directory (owner=0 group=0 mode=755, class=-1) /mnt6/FactoryData/System/Library/Caches/com.apple.factorydata fdrArchive is not NULL..proceeding with fdr_restore_saved_data entering mount_partition Hardware already mounted read-write (mount ignored) [ramrod_unarchive_dir] extracting archive (70 bytes) to dir '/mnt6/FactoryData/System/Library/Caches/com.apple.factorydata' [_read_data] [num bytes to read = 12] [byte offset: 0] [length: 70] [_read_data] [num bytes to read = 1048564] [byte offset: 12] [length: 70] [_read_data] [num bytes to read = 1048576] [byte offset: 70] [length: 70] [ramrod_unarchive_dir] result = 0 [08:36:57.0697-GMT]{3>5} CHECKPOINT END: (null):[0x0627] fdr_restore_saved_data restore-step-ids = {0x1103067B:64} restore-step-names = {0x1103067B:perform_restore_installing} restore-step-uptime = 254 restore-step-user-progress = 51 [08:36:57.0697-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x069A] update_device_firmware_pre_fdr restore-step-ids = {0x1103067B:64;0x1103069A:66} restore-step-names = {0x1103067B:perform_restore_installing;0x1103069A:update_device_firmware_pre_fdr} restore-step-uptime = 254 restore-step-user-progress = 51 entering update_device_firmware_pre_fdr entering mount_partition Preboot already mounted read-write (mount ignored) entering ramrod_ticket_copy_hash_data crypto-hash-method found. Using SHA2-384 creating directory (owner=0 group=0 mode=755, class=-1) /mnt9/A248AA5FB704CE1E22B7AFA7905A8A8B1F73972C3828B42DBC3FA818E131505EE7435F941D138DBB4D58E83A76339D37 creating directory (owner=0 group=0 mode=755, class=-1) /mnt9/A248AA5FB704CE1E22B7AFA7905A8A8B1F73972C3828B42DBC3FA818E131505EE7435F941D138DBB4D58E83A76339D37/usr creating directory (owner=0 group=0 mode=755, class=-1) /mnt9/A248AA5FB704CE1E22B7AFA7905A8A8B1F73972C3828B42DBC3FA818E131505EE7435F941D138DBB4D58E83A76339D37/usr/standalone creating directory (owner=0 group=0 mode=755, class=-1) /mnt9/A248AA5FB704CE1E22B7AFA7905A8A8B1F73972C3828B42DBC3FA818E131505EE7435F941D138DBB4D58E83A76339D37/usr/standalone/firmware creating directory (owner=0 group=0 mode=755, class=-1) /mnt9/A248AA5FB704CE1E22B7AFA7905A8A8B1F73972C3828B42DBC3FA818E131505EE7435F941D138DBB4D58E83A76339D37/System creating directory (owner=0 group=0 mode=755, class=-1) /mnt9/A248AA5FB704CE1E22B7AFA7905A8A8B1F73972C3828B42DBC3FA818E131505EE7435F941D138DBB4D58E83A76339D37/System/Library creating directory (owner=0 group=0 mode=755, class=-1) /mnt9/A248AA5FB704CE1E22B7AFA7905A8A8B1F73972C3828B42DBC3FA818E131505EE7435F941D138DBB4D58E83A76339D37/System/Library/Caches creating directory (owner=0 group=0 mode=755, class=-1) /mnt9/A248AA5FB704CE1E22B7AFA7905A8A8B1F73972C3828B42DBC3FA818E131505EE7435F941D138DBB4D58E83A76339D37/System/Library/Caches/com.apple.kernelcaches entering ramrod_update_firmware_pre_fdr No steps to process for Checkpoint Engine 28. [08:36:57.0699-GMT]{3>5} CHECKPOINT END: (null):[0x069A] update_device_firmware_pre_fdr restore-step-ids = {0x1103067B:64} restore-step-names = {0x1103067B:perform_restore_installing} restore-step-uptime = 254 restore-step-user-progress = 51 [08:36:57.0699-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x069B] fdr_prepare restore-step-ids = {0x1103067B:64;0x1103069B:67} restore-step-names = {0x1103067B:perform_restore_installing;0x1103069B:fdr_prepare} restore-step-uptime = 254 restore-step-user-progress = 51 _AMFDRAPTicketHasBooleanEntitlement: no ticket in options AMFDRSealingMapCreateAndPopulateSealedData: populate sealedData with sealing manifest, sealingManifestOverride : (null), forceSealing : (null), allowForbidden : (null), allowUnSeal : 0 AMSupportPlatformCreateBufferFromNativeFilePath: open failed: No such file or directory AMSupportPlatformCreateBufferFromNativeFilePath: /mnt6/FactoryData//System/Library/Caches/com.apple.factorydata/seal-00008010-000449E610326526 AMFDRCreateError: AMFDRDataLocalCopy: Failed to read data: code=4 AMFDRCreateError: AMFDRDataCopy: seal:00008010-000449E610326526 get data failed - foundData is NULL: code=10 AMFDRCreateError: AMFDRSealingMapPopulateSealingManifest: sealingManifest is NULL: code=10 AMFDRCreateError: AMFDRSealingMapCreateAndPopulateSealedData: AMFDRSealingMapPopulateSealingManifest failed on amfdr: code=10 AMFDRSealedDataRestoreOptions: sealedData is NULL failed to save FDR persistent state RestoredFDRSaveData success RestoredFDRRestoreSavedData: cleaned up on-disk FDR data RestoredFDRRestoreSavedData: No FDR state to restore RestoredFDRRestoreSavedData success [08:36:57.0701-GMT]{3>5} CHECKPOINT END: (null):[0x069B] fdr_prepare restore-step-ids = {0x1103067B:64} restore-step-names = {0x1103067B:perform_restore_installing} restore-step-uptime = 254 restore-step-user-progress = 51 [08:36:57.0702-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x0652] clear_fips_data_file restore-step-ids = {0x1103067B:64;0x11030652:68} restore-step-names = {0x1103067B:perform_restore_installing;0x11030652:clear_fips_data_file} restore-step-uptime = 254 restore-step-user-progress = 51 [08:36:57.0703-GMT]{3>5} CHECKPOINT END: (null):[0x0652] clear_fips_data_file restore-step-ids = {0x1103067B:64} restore-step-names = {0x1103067B:perform_restore_installing} restore-step-uptime = 254 restore-step-user-progress = 51 [08:36:57.0703-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x0634] fdr_recover restore-step-ids = {0x1103067B:64;0x11030634:69} restore-step-names = {0x1103067B:perform_restore_installing;0x11030634:fdr_recover} restore-step-uptime = 254 restore-step-user-progress = 51 entering RestoredFDRRecover AMFDRDataApTicketIsTrusted: AMFDRDataApTicketIsTrusted returning true AMFDRSealingMapRecoverCurrentDevice: populate sealedData with sealing manifest, sealingManifestOverride : (null), forceSealing : (null), allowForbidden : (null), allowUnSeal : 0, updateDataClasses : (null), updateProperties : (null) AMSupportPlatformCreateBufferFromNativeFilePath: open failed: No such file or directory AMSupportPlatformCreateBufferFromNativeFilePath: /mnt6/FactoryData//System/Library/Caches/com.apple.factorydata/seal-00008010-000449E610326526 AMFDRCreateError: AMFDRDataLocalCopy: Failed to read data: code=4 AMFDRCreateError: AMFDRDataCopy: seal:00008010-000449E610326526 get data failed - foundData is NULL: code=10 AMFDRCreateError: AMFDRSealingMapPopulateSealingManifest: sealingManifest is NULL: code=10 AMFDRSealingMapRecoverCurrentDevice: could not populate the local sealing manifest, skipping AMFDRCryptoCreateFDRCsrContext: adding apManifestTruncated to CSR _AMFDRHttpRequestSendSyncNoRetry: No cookie found _AMFDRHttpCopyPurpleReverseProxyInformation: Failed to get proxy info for URL 'https://gg.apple.com/ca/authorize' AMFDRCreateError: _AMFDRHttpMessageSendSync: Failed to copy proxy information and proxy is enabled.: code=10 AMFDRCreateError: _AMFDRHttpRequestSendSyncNoRetry: _AMFDRHttpMessageSendSync failed: code=8 AMFDRCreateError: _AMFDRPermissionsRequestWithString: AMFDRPermissionsRequest failed: code=8 AMFDRCreateError: AMFDRPermissionsRequest: _AMFDRSavePersistentData failed: code=8 AMFDRCreateError: AMFDRDataHTTPLoadPersistent: Permissions request failed.: code=12 AMFDRCreateError: AMFDRDataHTTPCopy: AMFDRDataHTTPLoadPersistent failed: code=8 AMFDRCreateError: AMFDRDataCopy: seal:00008010-000449E610326526 get data failed - foundData is NULL: code=10 AMFDRCreateError: AMFDRSealingMapPopulateSealingManifest: sealingManifest is NULL: code=10 AMFDRCreateError: AMFDRSealingMapRecoverCurrentDevice: AMFDRSealingMapPopulateSealingManifest failed on amfdrRemote: code=10 AMFDRDataRecoverCurrentDevice(sealData=false) failed, errCode=10 0: AMFDRError/a: AMFDRSealingMapPopulateSealingManifest failed on amfdrRemote 1: AMFDRError/a: sealingManifest is NULL 2: AMFDRError/a: seal:00008010-000449E610326526 get data failed - foundData is NULL 3: AMFDRError/8: AMFDRDataHTTPLoadPersistent failed 4: AMFDRError/c: Permissions request failed. 5: AMFDRError/8: _AMFDRSavePersistentData failed 6: AMFDRError/8: AMFDRPermissionsRequest failed 7: AMFDRError/8: _AMFDRHttpMessageSendSync failed 8: AMFDRError/a: Failed to copy proxy information and proxy is enabled. [08:37:02.0760-GMT]{3>5} CHECKPOINT FAILURE:(FAILURE:53) (null):[0x0634] fdr_recover D(AMFDRDataHTTPLoadPersistent failed)[6]D(Permissions request failed.)[7]D(_AMFDRSavePersistentData failed)[8]D(AMFDRPermissionsRequest failed)[9]D(_AMFDRHttpMessageSendSync failed)[10]D(Failed to copy proxy information and proxy is enabled.) restore-step-results = {0x11070634:{0:53}} restore-step-codes = {0x11070634:{0:53}} restore-step-domains = {0x11070634:{0:"AMRestoreErrorDomain"}} restore-step-error = {0x11070634:"D(AMFDRDataHTTPLoadPersistent failed)[6]D(Permissions request failed.)[7]D(_AMFDRSavePersistentData failed)[8]D(AMFDRPermissionsRequest failed)[9]D(_AMFDRHttpMessageSendSync failed)[10]D(Failed to copy proxy information and proxy is enabled.)"} restore-step-uptime = 259 restore-step-user-progress = 51 [08:37:02.0764-GMT]{3>5} CHECKPOINT NOTICE: (NVRAM set) restore-step-user-progress=51 [sync=true] (first failure) [08:37:02.0765-GMT]{3>5} CHECKPOINT FAILURE:(FAILURE:53) RESTORED:[0x067B] perform_restore_installing D(AMFDRDataHTTPLoadPersistent failed)[6]D(Permissions request failed.)[7]D(_AMFDRSavePersistentData failed)[8]D(AMFDRPermissionsRequest failed)[9]D(_AMFDRHttpMessageSendSync failed)[10]D(Failed to copy proxy information and proxy is enabled.) restore-step-results = {0x1107067B:{0:53};0x11070634:{0:53}} restore-step-codes = {0x1107067B:{0:53};0x11070634:{0:53}} restore-step-domains = {0x1107067B:{0:"AMRestoreErrorDomain"};0x11070634:{0:"AMRestoreErrorDomain"}} restore-step-error = {0x1107067B:"D(AMFDRDataHTTPLoadPersistent failed)[6]D(Permissions request failed.)[7]D(_AMFDRSavePersistentData failed)[8]D(AMFDRPermissionsRequest failed)[9]D(_AMFDRHttpMessageSendSync failed)[10]D(Failed to copy proxy information and proxy is enabled.)"} restore-step-uptime = 259 restore-step-user-progress = 51 [08:37:02.0769-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x067C] cleanup_boot_command restore-step-ids = {0x1103067B:64;0x11030634:69;0x1103067C:70} restore-step-names = {0x1103067B:perform_restore_installing;0x11030634:fdr_recover;0x1103067C:cleanup_boot_command} restore-step-uptime = 259 restore-step-user-progress = 51 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 [08:37:02.0860-GMT]{3>5} CHECKPOINT END: RESTORED:[0x067C] cleanup_boot_command restore-step-ids = {0x1103067B:64;0x11030634:69} restore-step-names = {0x1103067B:perform_restore_installing;0x11030634:fdr_recover} restore-step-uptime = 259 restore-step-user-progress = 51 [08:37:02.0861-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x1613] cleanup_recovery_os_volume restore-step-ids = {0x1103067B:64;0x11030634:69;0x11031613:71} restore-step-names = {0x1103067B:perform_restore_installing;0x11030634:fdr_recover;0x11031613:cleanup_recovery_os_volume} restore-step-uptime = 259 restore-step-user-progress = 51 [08:37:02.0862-GMT]{3>5} CHECKPOINT END: RESTORED:[0x1613] cleanup_recovery_os_volume restore-step-ids = {0x1103067B:64;0x11030634:69} restore-step-names = {0x1103067B:perform_restore_installing;0x11030634:fdr_recover} restore-step-uptime = 259 restore-step-user-progress = 51 [08:37:02.0863-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x0647] cleanup_check_result restore-step-ids = {0x1103067B:64;0x11030634:69;0x11030647:72} restore-step-names = {0x1103067B:perform_restore_installing;0x11030634:fdr_recover;0x11030647:cleanup_check_result} restore-step-uptime = 259 restore-step-user-progress = 51 [08:37:02.0864-GMT]{3>5} CHECKPOINT END: RESTORED:[0x0647] cleanup_check_result restore-step-ids = {0x1103067B:64;0x11030634:69} restore-step-names = {0x1103067B:perform_restore_installing;0x11030634:fdr_recover} restore-step-uptime = 259 restore-step-user-progress = 51 [08:37:02.0865-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x0648] cleanup_send_final_status restore-step-ids = {0x1103067B:64;0x11030634:69;0x11030648:73} restore-step-names = {0x1103067B:perform_restore_installing;0x11030634:fdr_recover;0x11030648:cleanup_send_final_status} restore-step-uptime = 259 restore-step-user-progress = 51 restore failed with CFError: 0: AMRestoreErrorDomain/35: failed to recover FDR data 1: AMRestoreErrorDomain/35: Failed to recover FDR data 2: AMFDRError/a: AMFDRSealingMapPopulateSealingManifest failed on amfdrRemote 3: AMFDRError/a: sealingManifest is NULL 4: AMFDRError/a: seal:00008010-000449E610326526 get data failed - foundData is NULL 5: AMFDRError/8: AMFDRDataHTTPLoadPersistent failed 6: AMFDRError/c: Permissions request failed. 7: AMFDRError/8: _AMFDRSavePersistentData failed 8: AMFDRError/8: AMFDRPermissionsRequest failed 9: AMFDRError/8: _AMFDRHttpMessageSendSync failed 10: AMFDRError/a: Failed to copy proxy information and proxy is enabled. [08:37:02.0866-GMT]{3>5} CHECKPOINT END: RESTORED:[0x0648] cleanup_send_final_status restore-step-ids = {0x1103067B:64;0x11030634:69} restore-step-names = {0x1103067B:perform_restore_installing;0x11030634:fdr_recover} restore-step-uptime = 259 restore-step-user-progress = 51 [08:37:02.0867-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x0649] cleanup_wait_status_received restore-step-ids = {0x1103067B:64;0x11030634:69;0x11030649:74} restore-step-names = {0x1103067B:perform_restore_installing;0x11030634:fdr_recover;0x11030649:cleanup_wait_status_received} restore-step-uptime = 259 restore-step-user-progress = 51 waiting for host to acknowledge final status received... final response is missing message type[08:37:02.0912-GMT]{3>5} CHECKPOINT END: RESTORED:[0x0649] cleanup_wait_status_received restore-step-ids = {0x1103067B:64;0x11030634:69} restore-step-names = {0x1103067B:perform_restore_installing;0x11030634:fdr_recover} restore-step-uptime = 259 restore-step-user-progress = 51 [08:37:02.0913-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x064A] cleanup_write_restore_log restore-step-ids = {0x1103067B:64;0x11030634:69;0x1103064A:75} restore-step-names = {0x1103067B:perform_restore_installing;0x11030634:fdr_recover;0x1103064A:cleanup_write_restore_log} restore-step-uptime = 259 restore-step-user-progress = 51 attempting to dump restore log writing log file: /mnt1/restore.log
Checkpoint 4294968857 complete with code 0 Checkpoint 1562 complete with code 0 Checkpoint 1563 complete with code 0 Checkpoint 4294968929 complete with code 0 Unmounting filesystems (29) Unmounting filesystems (29) Unmounting filesystems (29) Unmounting filesystems (29) Unmounting filesystems (29) Unmounting filesystems (29) Checkpoint 1565 complete with code 0 Checkpoint 4294968910 complete with code 0 Checkpoint 1567 complete with code 0 Checkpoint 4294968970 complete with code 0 Creating partition map (11) Checkpoint 4294968865 complete with code 0 Checkpoint 1632 complete with code 0 Checkpoint 1570 complete with code 0 Checkpoint 1629 complete with code 0 Checkpoint 5645 complete with code 0 Creating filesystem (12) Checkpoint 1624 complete with code 0 Checkpoint 1625 complete with code 0 Checkpoint 1626 complete with code 0 About to send filesystem... D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 272 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
Connected to ASR Validating the filesystem D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 336 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 325 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 335 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 335 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 335 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 325 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 338 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 336 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 325 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 335 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 335 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 335 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 325 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 338 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 325 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 328 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 327 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 327 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 330 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 327 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 328 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 325 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 331 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 333 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 334 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 335 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 332 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 336 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 325 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 335 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 335 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 335 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 325 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 338 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 338 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 234 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
Filesystem validated Sending filesystem now... ERROR:check fa fail,error code :-138 Done sending filesystem Verifying restore (14) Checkpoint 1627 complete with code 0 Checkpoint 1664 complete with code 0 Checkpoint 1653 complete with code 0 Checkpoint 1676 complete with code 0 Unmounting filesystems (29) Unmounting filesystems (29) Unmounting filesystems (29) Unmounting filesystems (29) Unmounting filesystems (29) Unmounting filesystems (29) Checking filesystems (15) Checking filesystems (15) Checking filesystems (15) Mounting filesystems (16) Mounting filesystems (16) Mounting filesystems (16) Mounting filesystems (16) Checkpoint 1574 complete with code 0 Checkpoint 1634 complete with code 0 Checkpoint 4294968951 complete with code 0 Checkpoint 4294968954 complete with code 0 Checkpoint 4294968871 complete with code 0 Checkpoint 4294968986 complete with code 0 Checkpoint 1691 complete with code 0 Checkpoint 18446744069414585938 complete with code 0 Checkpoint 4294968884 complete with code 53 Checkpoint 1659 complete with code 53 Checkpoint 1660 complete with code 0 Checkpoint 5651 complete with code 0 Checkpoint 1607 complete with code 0 Got status message Status: Failed to recover FDR data.
Log is available: [08:43:23.0852-GMT]{3>5} CHECKPOINT NOTICE: Image4 device: AP nonce clearable entering ramrod_clear_ap_nonce [08:43:23.0853-GMT]{3>5} CHECKPOINT WARNING:(FAILURE:-1) Failed to consume AP nonce [0]D(ramrod_clear_ap_nonce: IOServiceGetMatchingService for class AppleMobileApNonce failed) waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:23.0853-GMT]{3>5} CHECKPOINT NOTICE: NVRAM access is not currently available [08:43:23.0853-GMT]{3>5} CHECKPOINT PROGRESS: START (unknown) -> (presumed_initial_engine_no_return) [08:43:23.0853-GMT]{3>5} CHECKPOINT BEGIN: MAIN:[0x0400] umask [08:43:23.0853-GMT]{3>5} CHECKPOINT END: MAIN:[0x0400] umask waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:23.0853-GMT]{3>5} CHECKPOINT BEGIN: MAIN:[0x0402] setvbuf [08:43:23.0853-GMT]{3>5} CHECKPOINT END: MAIN:[0x0402] setvbuf waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:23.0853-GMT]{3>5} CHECKPOINT BEGIN: MAIN:[0x0403] kernel_logger_thread [08:43:23.0853-GMT]{3>5} CHECKPOINT END: MAIN:[0x0403] kernel_logger_thread waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:23.0853-GMT]{3>5} CHECKPOINT BEGIN: MAIN:[0x0406] set_progress_0 inverting UI colorfound display: primary Will use display primary Display state is 1 display-boot-rotation = 0 display-scale = 2 display-rotation = 0 Using product_suffix of iphone found applelogo at /usr/share/progressui/applelogo@2x.tga display: 750 x 1334 Progress Bar Y offset at 112, display class 8 skip_display_poweron_for_device: HW.target returned: D10AP skip_display_poweron_for_device: DeviceName is: iPhone powering on display ramrod_display_set_granular_progress_forced: 0.000000 [08:43:23.0870-GMT]{3>5} CHECKPOINT END: MAIN:[0x0406] set_progress_0 waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:23.0870-GMT]{3>5} CHECKPOINT BEGIN: MAIN:[0x0407] start_gasgauge_thread [08:43:23.0871-GMT]{3>5} CHECKPOINT END: MAIN:[0x0407] start_gasgauge_thread waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:23.0873-GMT]{3>5} CHECKPOINT BEGIN: MAIN:[0x0408] listen_for_log_client [08:43:23.0873-GMT]{3>5} CHECKPOINT END: MAIN:[0x0408] listen_for_log_client waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:23.0873-GMT]{3>5} CHECKPOINT BEGIN: MAIN:[0x040D] create_listen_socket [08:43:23.0873-GMT]{3>5} CHECKPOINT END: MAIN:[0x040D] create_listen_socket waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:23.0874-GMT]{3>5} CHECKPOINT BEGIN: MAIN:[0x0404] update_root_mount [08:43:23.0876-GMT]{3>5} CHECKPOINT END: MAIN:[0x0404] update_root_mount waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:23.0876-GMT]{3>5} CHECKPOINT BEGIN: MAIN:[0x0405] disable_watchdog [08:43:23.0876-GMT]{3>5} CHECKPOINT END: MAIN:[0x0405] disable_watchdog waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:23.0876-GMT]{3>5} CHECKPOINT BEGIN: MAIN:[0x0410] usbcretimer_dfucheck entering ramrod_device_has_usbcretimer Module not present on this platform. [08:43:23.0877-GMT]{3>5} CHECKPOINT END: MAIN:[0x0410] usbcretimer_dfucheck waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:23.0877-GMT]{3>5} CHECKPOINT BEGIN: MAIN:[0x040E] enable_usb waiting for matching IOKit service: { IOProviderClass = AppleUSBDeviceMux; } [08:43:31.0897-GMT]{3>5} CHECKPOINT END: MAIN:[0x040E] enable_usb waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } 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' recv(10, 4) failed: connection closed unable to read message size: -1 could not receive message waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:32.0364-GMT]{3>5} CHECKPOINT NOTICE: NVRAM access is not currently available [08:43:32.0364-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x0600] client_protocol_version client protocol version 15 [08:43:32.0364-GMT]{3>5} CHECKPOINT END: RESTORED:[0x0600] client_protocol_version waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:32.0364-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x0601] copy_restore_options unable to open /usr/local/share/restore//options.d10.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 8EC23366-B038-8CA2-2CF7-94313DED78C5 Restore options: UUID => <CFString 0x108f14190 [0x106e25a60]>{contents = "8EC23366-B038-8CA2-2CF7-94313DED78C5"} PersonalizedDuringPreflight => <CFBoolean 0x10704fe78 [0x106e25a60]>{value = true} CreateFilesystemPartitions => <CFBoolean 0x10704fe78 [0x106e25a60]>{value = true} [08:43:32.0366-GMT]{3>5} CHECKPOINT END: RESTORED:[0x0601] copy_restore_options waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:32.0366-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x06A1] usbcretimer_dfucheck_enforce [08:43:32.0366-GMT]{3>5} CHECKPOINT END: RESTORED:[0x06A1] usbcretimer_dfucheck_enforce waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:32.0367-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x0655] is_host_compatible 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 [08:43:32.0367-GMT]{3>5} CHECKPOINT END: RESTORED:[0x0655] is_host_compatible waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:32.0367-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x0604] set_weight_from_options [08:43:32.0368-GMT]{3>5} CHECKPOINT END: RESTORED:[0x0604] set_weight_from_options waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:32.0368-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x068F] libauthinstall_callback [08:43:32.0370-GMT]{3>5} CHECKPOINT END: RESTORED:[0x068F] libauthinstall_callback waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:32.0371-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x0608] device_has_hoover [08:43:32.0372-GMT]{3>5} CHECKPOINT END: RESTORED:[0x0608] device_has_hoover waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:32.0372-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x060B] update_ticket entering ramrod_ticket_update_verify looking up boot manifest hash device tree ticket_hash: 5CBF036699AA8CD34163DA65D17F48AD92A0CE6D241A45500E52E0B03C61BDEF2140F625C88404BAC2DF597DEC5F6BE0 crypto-hash-method found. Using SHA2-384 computed ticket_hash : 5CBF036699AA8CD34163DA65D17F48AD92A0CE6D241A45500E52E0B03C61BDEF2140F625C88404BAC2DF597DEC5F6BE0 received valid ticket (7003 bytes) [08:43:32.0419-GMT]{3>5} CHECKPOINT END: RESTORED:[0x060B] update_ticket waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:32.0420-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x060D] wait_for_storage entering wait_for_storage_device ramrod_display_set_granular_progress_forced: 0.000000 Searching for NAND service Searching for NAND service Found NAND service: AppleEmbeddedNVMeController 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 [08:43:32.0436-GMT]{3>5} CHECKPOINT END: RESTORED:[0x060D] wait_for_storage waiting for matching IOKit service: { IOProviderClass = IOResources; IOResourceMatch = IONVRAM; } [08:43:32.0436-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x060E] update_NAND_firmware ramrod_display_set_granular_progress_forced: 0.000000 entering update_NAND executing /usr/bin/nvmefwupdater --query S3E/0000001002015095.pak NAND update file: "/usr/standalone/firmware/S3E/0000001002015095.pak" executing /usr/bin/nvmefwupdater --validate /usr/standalone/firmware/S3E/0000001002015095.pak NVMe Updater: Performing BFH OFW stage... NVMe Updater: BFH params: phy tx eq=0x0 NVMe Updater: BFH params: phy rx eq=0x0 NVMe Updater: BFH params: host soc platform=0x4 NVMe Updater: BFH params: host soc revision=0x5 NVMe Updater: BFH params: indirection-size=0x8 NVMe Updater: BFH params: icc-20us-ma=0x2e4 NVMe Updater: BFH params: vcc-mv=0xb6d NVMe Updater: BFH params: imp-mohm=0xad NVMe Updater: BFH params: write-perf-mlc-mbps=0x0 NVMe Updater: BFH params: write-perf-tlc-mbps=0x0 NVMe Updater: BFH params: icc-1us-ma=0x44c NVMe Updater: BFH params: icc-5us-ma=0x384 NVMe Updater: BFH params: icc-duration-idx=0x1 NVMe Updater: BFH params: disable-pcie-phy-override=0x0 NVMe Updater: set_pci_port_state: result=0 NVMe Updater: set_pci_port_state: result=0 NVMe Updater: set_nvme_state: result=0 NVMe Updater: FW Revision from update file : 57.20.01 NVMe Updater: Validating FW... NVMe Updater: Passthrough command done fNVMeStatus=0x0 fReturn=0x0 NVMe Updater: Current config: S3E, Rev B0 NVMe Updater: NANDDeviceID 0x5095 NVMe Updater: ECCVersionNANDRevision 0x0001 NVMe Updater: FTL Versions: CLog 23.1, DM 100 NVMe Updater: FTL Util FMT 8 NVMe Updater: Num configurations in update file: 1 NVMe Updater: Update config 0: S3E, Rev B0 NVMe Updater: NANDDeviceID 0x5095NVMe Updater: ECCVersionNANDRevision 0x0001NVMe Updater: FTL Versions: CLog 23.1, DM Min 100 NVMe Updater: FTL Util FMT Min 0 NVMe Updater: DriveConfig version : drive 2, update 2 NVMe Updater: Update found - regular update NVMe Updater: returning Regular Update Required executing /usr/bin/nvmefwupdater --update /usr/standalone/firmware/S3E/0000001002015095.pak NVMe Updater: Downloading FW... NVMe Updater: Passthrough command done fNVMeStatus=0x0 fReturn=0x0 NVMe Updater: Activating FW... NVMe Updater: Passthrough command done fNVMeStatus=0x10b fReturn=0x0 NVMe Updater: FW Revision from update file : 57.20.01 NVMe Updater: Successfully updated FW... executing /usr/bin/nvmefwupdater --sendtunabletables NVMe Updater: Device tree entry not found. (IODeviceTree:/arm-io/ans) NVMe Updater: Checking for standard tunable bundle id property: tunable-table-bundle NVMe Updater: Device tree entry not found. (IODeviceTree:/arm-io/ans) NVMe Updater: Did not find tunable table bundle file property nvmefwupdater was unable to find the tunable table bundle name in the device tree [08:43:38.0648-GMT]{3>5} CHECKPOINT END: RESTORED:[0x060E] update_NAND_firmware [08:43:38.0649-GMT]{3>5} CHECKPOINT NOTICE: Pre-existing NVRAM variable: auto-boot=false [08:43:38.0649-GMT]{3>5} CHECKPOINT NOTICE: Pre-existing NVRAM variable: restore-outcome=recovery_mode [08:43:38.0650-GMT]{3>5} CHECKPOINT NOTICE: Pre-existing NVRAM variable: restore-child-failures={initial:EXIT(1)[REBOOT]} executing /usr/sbin/nvram -d restore-child-failures Successfully deleted restore-child-failuresrestore-child-failures [08:43:38.0694-GMT]{3>5} CHECKPOINT NOTICE: Old restore failure indication(s) [08:43:38.0694-GMT]{3>5} CHECKPOINT PROGRESS: NVRAM_NO_RETURN (presumed_initial_engine_no_return) -> (initial_engine_no_return) restore-step-ids = {} restore-step-names = {} restore-step-warnings = {0x11060108:{0:"Failed to consume AP nonce"}} restore-step-uptime = 15 restore-step-user-progress = 0 restore-outcome = initial_engine_no_return executing /usr/sbin/nvram restore-outcome=initial_engine_no_return [08:43:38.0724-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x060F] clear_remap_variable restore-step-ids = {0x1103060F:22} restore-step-names = {0x1103060F:clear_remap_variable} restore-step-uptime = 15 restore-step-user-progress = 0 entering clear_remap_variable executing /usr/sbin/nvram -d enable-remap-mode Successfully deleted enable-remap-modeenable-remap-mode [08:43:38.0743-GMT]{3>5} CHECKPOINT END: RESTORED:[0x060F] clear_remap_variable restore-step-ids = {} restore-step-names = {} restore-step-uptime = 15 restore-step-user-progress = 0 [08:43:38.0744-GMT]{3>5} CHECKPOINT BEGIN: RESTORED:[0x065C] print_nvram_variables restore-step-ids = {0x1103065C:23} restore-step-names = {0x1103065C:print_nvram_variables} restore-step-uptime = 15 restore-step-user-progress = 0 ramrod_print_NVRAM_variables executing /usr/sbin/nvram -xp <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
10 MiB total IORegistry requires a 10 MiB Hardware Partition block size for /dev/disk0s1: 4096 /System/Library/Filesystems/apfs.fs/newfs_apfs -s 10485760 -A -D -o role=h -v Hardware /dev/disk0s1 executing /System/Library/Filesystems/apfs.fs/newfs_apfs -s 10485760 -A -D -o role=h -v Hardware /dev/disk0s1 Waiting to decide if scratch is needed. Update partition is not created as part of create_apfs_filesystems..skipping block size for /dev/disk0s1: 4096 /System/Library/Filesystems/apfs.fs/newfs_apfs -A -D -o role=b -v Preboot /dev/disk0s1 executing /System/Library/Filesystems/apfs.fs/newfs_apfs -A -D -o role=b -v Preboot /dev/disk0s1 [08:43:43.0736-GMT]{3>5} CHECKPOINT END: (null):[0x0658] format_media restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 20 restore-step-user-progress = 0 [08:43:43.0737-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x0659] find_volumes_after_format restore-step-ids = {0x11030677:49;0x11030659:55} restore-step-names = {0x11030677:perform_main_os_prepare;0x11030659:find_volumes_after_format} restore-step-uptime = 20 restore-step-user-progress = 0 entering ramrod_probe_media_internal entering wait_for_device: 'EmbeddedDeviceTypeRoot' Using device path /dev/disk0 for EmbeddedDeviceTypeRoot device partitioning scheme is GPT APFS Container 'Container' /dev/disk0s1 device is APFS formatted Captured preboot partition on main OS container 2 find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data= baseband data=/dev/disk0s1s4 log= update= xart=/dev/disk0s1s3 hardware=/dev/disk0s1s5 scratch= preboot=/dev/disk0s1s6 find_filesystem_partitions: recovery os container= volume= [08:43:43.0741-GMT]{3>5} CHECKPOINT END: (null):[0x0659] find_volumes_after_format restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 20 restore-step-user-progress = 0 [08:43:43.0741-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x065A] baseband_migration_data restore-step-ids = {0x11030677:49;0x1103065A:56} restore-step-names = {0x11030677:perform_main_os_prepare;0x1103065A:baseband_migration_data} restore-step-uptime = 20 restore-step-user-progress = 0 [08:43:43.0742-GMT]{3>5} CHECKPOINT END: (null):[0x065A] baseband_migration_data restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 20 restore-step-user-progress = 0 [08:43:43.0742-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x065B] asr_and_invert_image restore-step-ids = {0x11030677:49;0x1103065B:57} restore-step-names = {0x11030677:perform_main_os_prepare;0x1103065B:asr_and_invert_image} restore-step-uptime = 20 restore-step-user-progress = 0 libpartition: entering _partition_wait_for_device: 'EmbeddedDeviceTypeRoot'
libpartition: Using device path /dev/disk0 for EmbeddedDeviceTypeRoot
libpartition: entering partition_probe_media. libpartition: APFS Container 'Container' /dev/disk0s1
libpartition: unexpected partition 'Hardware' - skipping libpartition: /sbin/newfs_apfs libpartition: -o libpartition: role=p libpartition: -A libpartition: -v libpartition: Update libpartition: /dev/disk0s1 libpartition:
executing /sbin/newfs_apfs -o role=p -A -v Update /dev/disk0s1 libpartition: entering _partition_wait_for_device: 'EmbeddedDeviceTypeRoot'
libpartition: Using device path /dev/disk0 for EmbeddedDeviceTypeRoot
libpartition: entering partition_probe_media. libpartition: APFS Container 'Container' /dev/disk0s1
libpartition: unexpected partition 'Hardware' - skipping executing /sbin/mount -t apfs -o nobrowse /dev/disk0s1s7 /mnt4 libpartition: create_update_partition_folder_hierarchy: Creating folder hierarchy for update partition libpartition: Creating hardware folder hierarchy
libpartition: Creating root folder libpartition: Successfully created root Hardware folder. Proceeding to create the rest of the folder hierarchy libpartition: Successfully created Battery under /mnt4/Hardware with permissions 0700 for user root libpartition: create_update_partition_folder_hierarchy: Done creating folder hierarchy for update partition Attempting to delete stale low space cleanup cookie file(if any) Successfully deleted low space cleanup cookie file libpartition: entering get_main_container_space_info
libpartition: No data device found, can't retrieve space info Initial space usage: InitialDataVolumeSpaceUsed: 0 MB InitialContainerFreeSpace: 30445 MB Current system volume stats: 108 MiB blocks, 28 MiB available, 29 MiB free restore_apfs_image : started restoring an APFS image entering mount_partition executing /sbin/mount_apfs -R /dev/disk0s1s1 /mnt1 /dev/disk0s1s1 mounted on /mnt1 System mounted read-write Successfully marked device node /dev/rdisk0s1s1 as static data OS digest = 978bf18cf801f93ee5aeebd82f6ce44184a71dedc576f9de91ecb2b352ee80b61c10b2905d0182e09aceedeabcc5cfd0 Restoring to APFS Inverter Image = /mnt1/apfs_invert_asr_img executing /usr/sbin/asr -source asr://localhost:12345 -target /mnt1/apfs_invert_asr_img --embed -erase -noprompt --chunkchecksum --puppetstrings --expectedhash 978bf18cf801f93ee5aeebd82f6ce44184a71dedc576f9de91ecb2b352ee80b61c10b2905d0182e09aceedeabcc5cfd0 ASR: 2022-05-31 08:43:43.956055+0000 asr[33:906] [connection] [0x123f0c1e0] activating connection: mach=true listener=false peer=false name=com.apple.cfprefsd.daemon ASR: 2022-05-31 08:43:43.956248+0000 asr[33:906] [connection] [0x123f0c1e0] failed to do a bootstrap look-up: xpc_error=[3: No such process] ASR: 2022-05-31 08:43:43.956309+0000 asr[33:906] [connection] [0x123f0c1e0] invalidated after a failed init ASR: 2022-05-31 08:43:43.956546+0000 asr[33:906] [User Defaults] Could not connect to cfprefsd. No CFPreferences/NSUserDefaults changes will be saved ASR STATUS: start 567.120.2 multicast-client ASR: 2022-05-31 08:43:43.959300+0000 asr[33:906] [si_destination_compare] send failed: Invalid argument ASR: 2022-05-31 08:43:43.959346+0000 asr[33:906] [si_destination_compare] send failed: Undefined error: 0 ASR: 2022-05-31 08:43:43.959382+0000 asr[33:906] [si_destination_compare] send failed: Invalid argument ASR: Waiting for connection attempt from server ASR STATUS: setup ASR: Validating target... ASR: done ASR STATUS: metadata ASR: Validating source... ASR: done ASR: Retrieving scan information... ASR: done ASR: Validating sizes... ASR: done ASR STATUS: restore ASR RESTORE PROGRESS: 2% ramrod_display_set_granular_progress_forced: 4.000000 ASR RESTORE PROGRESS: 4% ramrod_display_set_granular_progress_forced: 5.000000 ASR RESTORE PROGRESS: 6% ramrod_display_set_granular_progress_forced: 6.000000 ASR RESTORE PROGRESS: 8% ramrod_display_set_granular_progress_forced: 6.000000 ASR RESTORE PROGRESS: 10% ramrod_display_set_granular_progress_forced: 7.000000 ASR RESTORE PROGRESS: 12% ramrod_display_set_granular_progress_forced: 7.000000 ASR RESTORE PROGRESS: 14% ramrod_display_set_granular_progress_forced: 8.000000 ASR RESTORE PROGRESS: 16% ramrod_display_set_granular_progress_forced: 8.000000 ASR RESTORE PROGRESS: 18% ramrod_display_set_granular_progress_forced: 9.000000 ASR RESTORE PROGRESS: 20% ramrod_display_set_granular_progress_forced: 9.000000 ASR RESTORE PROGRESS: 22% ramrod_display_set_granular_progress_forced: 10.000000 ASR RESTORE PROGRESS: 24% ramrod_display_set_granular_progress_forced: 10.000000 ASR RESTORE PROGRESS: 26% ramrod_display_set_granular_progress_forced: 11.000000 recv(9, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'GetValue' ASR RESTORE PROGRESS: 28% ramrod_display_set_granular_progress_forced: 11.000000 ASR RESTORE PROGRESS: 30% ramrod_display_set_granular_progress_forced: 12.000000 ASR RESTORE PROGRESS: 32% ramrod_display_set_granular_progress_forced: 12.000000 ASR RESTORE PROGRESS: 34% ramrod_display_set_granular_progress_forced: 13.000000 ASR RESTORE PROGRESS: 36% ramrod_display_set_granular_progress_forced: 13.000000 ASR RESTORE PROGRESS: 38% ramrod_display_set_granular_progress_forced: 14.000000 ASR RESTORE PROGRESS: 40% ramrod_display_set_granular_progress_forced: 15.000000 ASR RESTORE PROGRESS: 42% ramrod_display_set_granular_progress_forced: 15.000000 ASR RESTORE PROGRESS: 44% ramrod_display_set_granular_progress_forced: 16.000000 ASR RESTORE PROGRESS: 46% ramrod_display_set_granular_progress_forced: 16.000000 ASR RESTORE PROGRESS: 48% ramrod_display_set_granular_progress_forced: 17.000000 ASR RESTORE PROGRESS: 50% ramrod_display_set_granular_progress_forced: 17.000000 ASR RESTORE PROGRESS: 52% ramrod_display_set_granular_progress_forced: 18.000000 ASR RESTORE PROGRESS: 54% ramrod_display_set_granular_progress_forced: 18.000000 ASR RESTORE PROGRESS: 56% ramrod_display_set_granular_progress_forced: 19.000000 ASR RESTORE PROGRESS: 58% ramrod_display_set_granular_progress_forced: 19.000000 ASR RESTORE PROGRESS: 60% ramrod_display_set_granular_progress_forced: 20.000000 recv(9, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'GetValue' ASR RESTORE PROGRESS: 62% ramrod_display_set_granular_progress_forced: 20.000000 ASR RESTORE PROGRESS: 64% ramrod_display_set_granular_progress_forced: 21.000000 ASR RESTORE PROGRESS: 66% ramrod_display_set_granular_progress_forced: 21.000000 ASR RESTORE PROGRESS: 68% ramrod_display_set_granular_progress_forced: 22.000000 ASR RESTORE PROGRESS: 70% ramrod_display_set_granular_progress_forced: 22.000000 ASR RESTORE PROGRESS: 72% ramrod_display_set_granular_progress_forced: 23.000000 ASR RESTORE PROGRESS: 74% ramrod_display_set_granular_progress_forced: 24.000000 ASR RESTORE PROGRESS: 76% ramrod_display_set_granular_progress_forced: 24.000000 ASR RESTORE PROGRESS: 78% ramrod_display_set_granular_progress_forced: 25.000000 ASR RESTORE PROGRESS: 80% ramrod_display_set_granular_progress_forced: 25.000000 ASR RESTORE PROGRESS: 82% ramrod_display_set_granular_progress_forced: 26.000000 ASR RESTORE PROGRESS: 84% ramrod_display_set_granular_progress_forced: 26.000000 ASR RESTORE PROGRESS: 86% ramrod_display_set_granular_progress_forced: 27.000000 ASR RESTORE PROGRESS: 88% ramrod_display_set_granular_progress_forced: 27.000000 recv(9, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'GetValue' ASR RESTORE PROGRESS: 90% ramrod_display_set_granular_progress_forced: 28.000000 ASR RESTORE PROGRESS: 92% ramrod_display_set_granular_progress_forced: 28.000000 ASR RESTORE PROGRESS: 94% ramrod_display_set_granular_progress_forced: 29.000000 ASR RESTORE PROGRESS: 96% ramrod_display_set_granular_progress_forced: 29.000000 ASR RESTORE PROGRESS: 98% ramrod_display_set_granular_progress_forced: 30.000000 ASR RESTORE PROGRESS: 100% ramrod_display_set_granular_progress_forced: 30.000000 ASR: Copied 7480168448 bytes in 188.14 seconds, 38826.00 KiB/s ASR STATUS: verify ASR VERIFY PROGRESS: 2% ramrod_display_set_granular_progress_forced: 31.000000 ASR VERIFY PROGRESS: 4% ramrod_display_set_granular_progress_forced: 31.000000 ASR VERIFY PROGRESS: 6% ramrod_display_set_granular_progress_forced: 32.000000 ASR VERIFY PROGRESS: 8% ramrod_display_set_granular_progress_forced: 32.000000 ASR VERIFY PROGRESS: 10% ramrod_display_set_granular_progress_forced: 32.000000 ASR VERIFY PROGRESS: 12% ramrod_display_set_granular_progress_forced: 33.000000 ASR VERIFY PROGRESS: 14% ramrod_display_set_granular_progress_forced: 33.000000 ASR VERIFY PROGRESS: 16% ramrod_display_set_granular_progress_forced: 33.000000 ASR VERIFY PROGRESS: 18% ramrod_display_set_granular_progress_forced: 34.000000 ASR VERIFY PROGRESS: 20% ramrod_display_set_granular_progress_forced: 34.000000 ASR VERIFY PROGRESS: 22% ramrod_display_set_granular_progress_forced: 35.000000 ASR VERIFY PROGRESS: 24% ramrod_display_set_granular_progress_forced: 35.000000 ASR VERIFY PROGRESS: 26% ramrod_display_set_granular_progress_forced: 35.000000 ASR VERIFY PROGRESS: 28% ramrod_display_set_granular_progress_forced: 36.000000 ASR VERIFY PROGRESS: 30% ramrod_display_set_granular_progress_forced: 36.000000 ASR VERIFY PROGRESS: 32% ramrod_display_set_granular_progress_forced: 37.000000 ASR VERIFY PROGRESS: 34% ramrod_display_set_granular_progress_forced: 37.000000 ASR VERIFY PROGRESS: 36% ramrod_display_set_granular_progress_forced: 37.000000 ASR VERIFY PROGRESS: 38% ramrod_display_set_granular_progress_forced: 38.000000 ASR VERIFY PROGRESS: 40% ramrod_display_set_granular_progress_forced: 38.000000 ASR VERIFY PROGRESS: 42% ramrod_display_set_granular_progress_forced: 38.000000 ASR VERIFY PROGRESS: 44% ramrod_display_set_granular_progress_forced: 39.000000 ASR VERIFY PROGRESS: 46% ramrod_display_set_granular_progress_forced: 39.000000 ASR VERIFY PROGRESS: 48% ramrod_display_set_granular_progress_forced: 40.000000 ASR VERIFY PROGRESS: 50% ramrod_display_set_granular_progress_forced: 40.000000 ASR VERIFY PROGRESS: 52% ramrod_display_set_granular_progress_forced: 40.000000 ASR VERIFY PROGRESS: 54% ramrod_display_set_granular_progress_forced: 41.000000 ASR VERIFY PROGRESS: 56% ramrod_display_set_granular_progress_forced: 41.000000 ASR VERIFY PROGRESS: 58% ramrod_display_set_granular_progress_forced: 41.000000 ASR VERIFY PROGRESS: 60% ramrod_display_set_granular_progress_forced: 42.000000 ASR VERIFY PROGRESS: 62% ramrod_display_set_granular_progress_forced: 42.000000 ASR VERIFY PROGRESS: 64% ramrod_display_set_granular_progress_forced: 43.000000 ASR VERIFY PROGRESS: 66% ramrod_display_set_granular_progress_forced: 43.000000 ASR VERIFY PROGRESS: 68% ramrod_display_set_granular_progress_forced: 43.000000 ASR VERIFY PROGRESS: 70% ramrod_display_set_granular_progress_forced: 44.000000 ASR VERIFY PROGRESS: 72% ramrod_display_set_granular_progress_forced: 44.000000 ASR VERIFY PROGRESS: 74% ramrod_display_set_granular_progress_forced: 45.000000 ASR VERIFY PROGRESS: 76% ramrod_display_set_granular_progress_forced: 45.000000 ASR VERIFY PROGRESS: 78% ramrod_display_set_granular_progress_forced: 45.000000 ASR VERIFY PROGRESS: 80% ramrod_display_set_granular_progress_forced: 46.000000 ASR VERIFY PROGRESS: 82% ramrod_display_set_granular_progress_forced: 46.000000 ASR VERIFY PROGRESS: 84% ramrod_display_set_granular_progress_forced: 46.000000 ASR VERIFY PROGRESS: 86% ramrod_display_set_granular_progress_forced: 47.000000 ASR VERIFY PROGRESS: 88% ramrod_display_set_granular_progress_forced: 47.000000 ASR VERIFY PROGRESS: 90% ramrod_display_set_granular_progress_forced: 48.000000 ASR VERIFY PROGRESS: 92% ramrod_display_set_granular_progress_forced: 48.000000 ASR VERIFY PROGRESS: 94% ramrod_display_set_granular_progress_forced: 48.000000 ASR VERIFY PROGRESS: 96% ramrod_display_set_granular_progress_forced: 49.000000 ASR VERIFY PROGRESS: 98% ramrod_display_set_granular_progress_forced: 49.000000 ASR VERIFY PROGRESS: 100% ramrod_display_set_granular_progress_forced: 50.000000 ASR: Calculated SHA-1 checksum 7480168448 bytes in 12.08 seconds, 604645.28 KiB/s ASR: Restored target device is /mnt1/apfs_invert_asr_img. ASR: Restore completed successfully. ASR STATUS: finish entering force_unmount_partition System unmounted restore_apfs_image : finished restoring an APFS image invert_apfs_image : inverting : /System/Library/Filesystems/apfs.fs/apfs_invert /dev/disk0s1 0 apfs_invert_asr_img executing /System/Library/Filesystems/apfs.fs/apfs_invert -d /dev/disk0s1 -s 1 -n apfs_invert_asr_img -f ASR: Mounting outer volume (/dev/disk0s1 s1)... ASR: nx_mount:1184: disk0s1 initializing cache w/hash_size 32768 and cache size 65536 ASR: nx_mount:1295: disk0s1 container cleanly-unmounted flag set. ASR: nx_mount:1460: disk0s1 checkpoint search: largest xid 18, best xid 18 @ 35 ASR: spaceman_metazone_init:191: disk0s1 metazone for device 0 of size 244140 blocks (encrypted: 7568349-7690419 unencrypted: 7690419-7812489) ASR: spaceman_datazone_init:625: disk0s1 allocation zone on dev 0 for allocations of 1 blocks starting at paddr 4096000 ASR: spaceman_datazone_init:625: disk0s1 allocation zone on dev 0 for allocations of 2 blocks starting at paddr 32768 ASR: spaceman_datazone_init:625: disk0s1 allocation zone on dev 0 for allocations of 3 blocks starting at paddr 65536 ASR: spaceman_datazone_init:625: disk0s1 allocation zone on dev 0 for allocations of 4 blocks starting at paddr 98304 ASR: dev_dump:256: Aggregate constructed: dev=0x105c07110 di=0 dv_num_slice=27 dv_num_slice_blk=294912 dv_num_lslice_blk=144777 ASR: spaceman_scan_free_blocks:3172: disk0s1 scan took 0.004864 s (no trims) ASR: Getting image dstream info... ASR: apfs_invert_asr_img: dstream_id=16, size=7738490880 ASR: Mounting inner volume (apfs_invert_asr_img)... ASR: nx_mount:1184: initializing cache w/hash_size 32768 and cache size 65536 ASR: nx_mount:1460: checkpoint search: largest xid 307, best xid 307 @ 3 ASR: Copying inner volume extentref tree into outer volume... ASR: Copied 76454 original extents and created 0 new extents ASR: Copying inner volume fsroot tree into outer volume... ASR: Copied 1216402 fs_root records plus 0 new file extents ASR: Cleaning up unused blocks... ASR: Freed 84093 data blocks and kept 1742123 data blocks ASR: Updating the superblock... ASR: Deleting old fs_root... ASR: Deleting old extentref tree... ASR: tx_flush:1043: disk0s1 tx xid 19 took 6972555 us to flush ASR: Finishing transaction... ASR: Unmounting... ASR: nx_volume_group_update:7762: disk0s1s1 Volume System is not in a volume group ASR: sanity_check_alloced_blocks:805: disk0s1s1 fs_alloc_count mismatch: fs root nodes 1 extent 1 omap 449 snap_meta 1 doc_id 0 prev_doc_id 0 fext: 0 pfkur: 0 er: 0 udata: 1742123 fs_alloc_count 1792938 != count 1742576 ASR: dev_dump:256: Aggregate destructed: dev=0x105c07110 di=0 dv_num_slice=27 dv_num_slice_blk=294912 dv_num_lslice_blk=144777 ASR: Success! invert_apfs_image : succeeded inverting : /System/Library/Filesystems/apfs.fs/apfs_invert /dev/disk0s1 0 apfs_invert_asr_img ASR succeed on initial attempt ASR and invert Succeeded [08:47:22.0993-GMT]{3>5} CHECKPOINT END: (null):[0x065B] asr_and_invert_image restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 239 restore-step-user-progress = 50 [08:47:22.0994-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x0680] read_new_os_build_version restore-step-ids = {0x11030677:49;0x11030680:58} restore-step-names = {0x11030677:perform_main_os_prepare;0x11030680:read_new_os_build_version} restore-step-uptime = 239 restore-step-user-progress = 50 entering mount_partition executing /sbin/mount_apfs -R /dev/disk0s1s1 /mnt1 /dev/disk0s1s1 mounted on /mnt1 System mounted read-write ramrod_read_new_os_build_version: new OS version: 19F77 (user) [08:47:23.0036-GMT]{3>5} CHECKPOINT END: (null):[0x0680] read_new_os_build_version restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 240 restore-step-user-progress = 50 [08:47:23.0036-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x0675] init_gigalocker restore-step-ids = {0x11030677:49;0x11030675:59} restore-step-names = {0x11030677:perform_main_os_prepare;0x11030675:init_gigalocker} restore-step-uptime = 240 restore-step-user-progress = 50 We should have an xART partition. entering mount_partition executing /sbin/mount_apfs -R /dev/disk0s1s3 /mnt7 /dev/disk0s1s3 mounted on /mnt7 xART mounted read-write entering ramrod_init_gigalocker We should have an xART partition. executing /usr/libexec/seputil --gigalocker-init seputil: Gigalocker file (/mnt7/44A42284-993A-5358-AC6A-A8713640A897.gl) doesn't exist: No such file or directory seputil: Gigalocker initialization completed gigalocker: ONLINE [08:47:23.0459-GMT]{3>5} CHECKPOINT END: (null):[0x0675] init_gigalocker restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 240 restore-step-user-progress = 50 [08:47:23.0460-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x068C] init_permanent_update_volume restore-step-ids = {0x11030677:49;0x1103068C:60} restore-step-names = {0x11030677:perform_main_os_prepare;0x1103068C:init_permanent_update_volume} restore-step-uptime = 240 restore-step-user-progress = 50 libpartition: entering _partition_wait_for_device: 'EmbeddedDeviceTypeRoot'
libpartition: Using device path /dev/disk0 for EmbeddedDeviceTypeRoot
libpartition: entering partition_probe_media. libpartition: APFS Container 'Container' /dev/disk0s1
libpartition: unexpected partition 'Hardware' - skipping libpartition: not deleting existing update filesystem executing /sbin/mount -t apfs -o nobrowse /dev/disk0s1s7 /mnt4 libpartition: create_update_partition_folder_hierarchy: Creating folder hierarchy for update partition libpartition: Creating hardware folder hierarchy
libpartition: Creating root folder libpartition: Successfully created root Hardware folder. Proceeding to create the rest of the folder hierarchy libpartition: Successfully created Battery under /mnt4/Hardware with permissions 0700 for user root libpartition: create_update_partition_folder_hierarchy: Done creating folder hierarchy for update partition Update volume was successfully unmounted. libpartition: Deleting contents of /mnt4... libpartition: Could not reset metadata on /mnt4: Read-only file system libpartition: Deleting contents of /mnt4 failed (result: 30). entering ramrod_probe_media_internal entering wait_for_device: 'EmbeddedDeviceTypeRoot' Using device path /dev/disk0 for EmbeddedDeviceTypeRoot device partitioning scheme is GPT APFS Container 'Container' /dev/disk0s1 device is APFS formatted Captured preboot partition on main OS container 2 [08:47:23.0542-GMT]{3>5} CHECKPOINT END: (null):[0x068C] init_permanent_update_volume restore-step-ids = {0x11030677:49} restore-step-names = {0x11030677:perform_main_os_prepare} restore-step-uptime = 240 restore-step-user-progress = 50 [08:47:23.0543-GMT]{3>5} CHECKPOINT BEGIN: (null):[0x0626] mount_filesystem restore-step-ids = {0x11030677:49;0x11030626:61} restore-step-names = {0x11030677:perform_main_os_prepare;0x11030626:mount_filesystem} restore-step-uptime = 240 restore-step-user-progress = 50 restored_mount_filesystem: OK to fsck since this is a erase install entering unmount_filesystems ramrod_display_set_granular_progress_forced: 50.000000 entering force_unmount_partition Preboot not mounted (unmount ignored) Skipping unmount of the update partition ramrod_display_set_granular_progress_forced: 50.000000 entering force_unmount_partition Hardware not mounted (unmount ignored) ramrod_display_set_granular_progress_forced: 50.000000 entering force_unmount_partition Baseband Data not mounted (unmount ignored) ramrod_display_set_granular_progress_forced: 50.000000 entering force_unmount_partition Data not mounted (unmount ignored) ramrod_display_set_granular_progress_forced: 50.000000 entering force_unmount_partition entering ramrod_shutdown_gigalocker We should have an xART partition. executing /usr/libexec/seputil --gigalocker-shutdown xART unmounted ramrod_display_set_granular_progress_forced: 50.000000 entering force_unmount_partition System unmounted entering fsck_filesystems ramrod_display_set_granular_progress_forced: 50.000000 Starting an xpc transaction. executing /sbin/fsck_apfs -dyo /dev/disk0s1s1 Requested cache size: 250MB (7k blocks * 32kB) Checking the container superblock. Checking the checkpoint with transaction ID 24. Checking the space manager. Checking the space manager free queue trees. Checking the object map. Checking volume /dev/rdisk0s1s1. Checking the APFS volume superblock. The volume System was formatted by newfs_apfs (1677.41.3.100.4) and last modified by apfs_kext (1934.122.1). Checking the object map. Checking the snapshot metadata tree. Checking the snapshot metadata. Checking the fsroot tree. recv(9, 4) failed: connection closed unable to read message size: -1
Found device in Recovery mode Identified device as d10ap, iPhone9,1 Extracting BuildManifest from IPSW Product Version: 15.5 Product Build: 19F77 Major: 19 INFO: device serial number is C76SW0X0HG70 Device supports Image4: true Variant: Customer Erase Install (IPSW) This restore will erase your device data. Checking IPSW for required components... All required components found in IPSW Found ECID 1207152369886502 Getting ApNonce in recovery mode... ff 10 72 ea f0 ec fa 0c 00 a3 85 c5 ca 58 e6 f1 ac 98 e9 d1 08 aa e6 3c 81 d2 2f 55 96 b6 e8 27 Trying to fetch new SHSH blob Getting SepNonce in recovery mode... 95 86 fa ea 02 68 0e ed 6e 76 86 db dc ec ba 6a 34 b8 08 f3 D:\libimobiledevice\libimobiledevice_vs_master\idevicerestore\src\common.c:printing 8672 bytes plist: <?xml version="1.0" encoding="UTF-8"?> <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">