Closed JoseCarlosGarcia95 closed 7 years ago
Any output from idevicerestore would be helpful.
Sure, here you've got!
About to send NORData... Found firmware path Firmware/all_flash Getting firmware manifest Firmware/all_flash/manifest ERROR: zip_name_locate: Firmware/all_flash/manifest ERROR: Unable to extract firmware manifest from ipsw ERROR: Unable to send NOR data ERROR: Unable to successfully restore device ERROR: Unable to restore device
In the other hand, when I try to restore my device (without entering in dfu mode, and my device is in iOS 10.3) syslog show the following error:
[19:14:23.0814-GMT]{4>8} CHECKPOINT NOTICE: Image4 device: AP nonce clearable entering ramrod_clear_ap_nonce [19:14:23.0827-GMT]{4>8} CHECKPOINT NOTICE: AP nonce consumed No IOFlashController instance found [19:14:23.0829-GMT]{4>8} CHECKPOINT NOTICE: Pre-existing NVRAM variable: auto-boot=false [19:14:23.0829-GMT]{4>8} CHECKPOINT NOTICE: Pre-existing NVRAM variable: restore-outcome=initial_monitor_no_return [19:14:23.0830-GMT]{4>8} CHECKPOINT ANOMALY: [check_collection]restore-step-monitor(does_not_exist) [19:14:23.0831-GMT]{4>8} CHECKPOINT PROGRESS: START (unknown) -> (initial_engine_no_return) [19:14:23.0831-GMT]{4>8} CHECKPOINT NOTICE: NVRAM access available on initial check restore-anomalies = {0x00000000:[check_collection]restore-step-monitor(does_not_exist)} restore-outcome = initial_engine_no_return executing /usr/sbin/nvram restore-outcome=initial_engine_no_return [19:14:23.0847-GMT]{4>8} CHECKPOINT BEGIN: MAIN:[0x0400] umask restore-step-ids = {0x11030400:1} restore-step-names = {0x11030400:umask} restore-step-uptime = 0 restore-step-user-progress = -1 [19:14:23.0849-GMT]{4>8} CHECKPOINT END: MAIN:[0x0400] umask restore-step-ids = {} restore-step-names = {} restore-step-uptime = 0 restore-step-user-progress = -1 [19:14:23.0850-GMT]{4>8} CHECKPOINT BEGIN: MAIN:[0x0402] setvbuf restore-step-ids = {0x11030402:2} restore-step-names = {0x11030402:setvbuf} restore-step-uptime = 0 restore-step-user-progress = -1 [19:14:23.0852-GMT]{4>8} CHECKPOINT END: MAIN:[0x0402] setvbuf restore-step-ids = {} restore-step-names = {} restore-step-uptime = 0 restore-step-user-progress = -1 [19:14:23.0854-GMT]{4>8} CHECKPOINT BEGIN: MAIN:[0x0403] kernel_logger_thread restore-step-ids = {0x11030403:3} restore-step-names = {0x11030403:kernel_logger_thread} restore-step-uptime = 0 restore-step-user-progress = -1 [19:14:23.0855-GMT]{4>8} CHECKPOINT END: MAIN:[0x0403] kernel_logger_thread unable to open /dev/klog: No such file or directory restore-step-ids = {} restore-step-names = {} restore-step-uptime = 0 restore-step-user-progress = -1 [19:14:23.0857-GMT]{4>8} CHECKPOINT BEGIN: MAIN:[0x0406] set_progress_0 restore-step-ids = {0x11030406:4} restore-step-names = {0x11030406:set_progress_0} restore-step-uptime = 0 restore-step-user-progress = -1 display-scale = 3 display-rotation = 0 found applelogo at /usr/share/progressui/applelogo@3x.tga found display: primary display: 1080 x 1920 powering on display ramrod_display_set_granular_progress: 0.000000 [19:14:23.0908-GMT]{4>8} CHECKPOINT END: MAIN:[0x0406] set_progress_0 restore-step-ids = {} restore-step-names = {} restore-step-uptime = 0 restore-step-user-progress = 0 [19:14:23.0909-GMT]{4>8} CHECKPOINT BEGIN: MAIN:[0x0407] start_gasgauge_thread restore-step-ids = {0x11030407:5} restore-step-names = {0x11030407:start_gasgauge_thread} restore-step-uptime = 0 restore-step-user-progress = 0 [19:14:23.0912-GMT]{4>8} CHECKPOINT END: MAIN:[0x0407] start_gasgauge_thread restore-step-ids = {} restore-step-names = {} restore-step-uptime = 0 restore-step-user-progress = 0 [19:14:23.0914-GMT]{4>8} CHECKPOINT BEGIN: MAIN:[0x0408] listen_for_log_client restore-step-ids = {0x11030408:6} restore-step-names = {0x11030408:listen_for_log_client} restore-step-uptime = 0 restore-step-user-progress = 0 [19:14:23.0916-GMT]{4>8} CHECKPOINT END: MAIN:[0x0408] listen_for_log_client restore-step-ids = {} restore-step-names = {} restore-step-uptime = 0 restore-step-user-progress = 0 [19:14:23.0919-GMT]{4>8} CHECKPOINT BEGIN: MAIN:[0x040D] create_listen_socket restore-step-ids = {0x1103040D:7} restore-step-names = {0x1103040D:create_listen_socket} restore-step-uptime = 0 restore-step-user-progress = 0 [19:14:23.0921-GMT]{4>8} CHECKPOINT END: MAIN:[0x040D] create_listen_socket restore-step-ids = {} restore-step-names = {} restore-step-uptime = 0 restore-step-user-progress = 0 [19:14:23.0922-GMT]{4>8} CHECKPOINT BEGIN: MAIN:[0x0404] update_root_mount restore-step-ids = {0x11030404:8} restore-step-names = {0x11030404:update_root_mount} restore-step-uptime = 0 restore-step-user-progress = 0 [19:14:23.0926-GMT]{4>8} CHECKPOINT END: MAIN:[0x0404] update_root_mount restore-step-ids = {} restore-step-names = {} restore-step-uptime = 0 restore-step-user-progress = 0 [19:14:23.0928-GMT]{4>8} CHECKPOINT BEGIN: MAIN:[0x0405] disable_watchdog restore-step-ids = {0x11030405:9} restore-step-names = {0x11030405:disable_watchdog} restore-step-uptime = 0 restore-step-user-progress = 0 [19:14:23.0931-GMT]{4>8} CHECKPOINT END: MAIN:[0x0405] disable_watchdog restore-step-ids = {} restore-step-names = {} restore-step-uptime = 0 restore-step-user-progress = 0 [19:14:23.0933-GMT]{4>8} CHECKPOINT BEGIN: MAIN:[0x040E] enable_usb restore-step-ids = {0x1103040E:10} restore-step-names = {0x1103040E:enable_usb} restore-step-uptime = 0 restore-step-user-progress = 0 waiting for matching IOKit service: <CFBasicHash 0x15bd08c00 [0x100ecacd8]>{type = mutable dict, count = 1, entries => 0 : <CFString 0x1010f3640 [0x100ecacd8]>{contents = "IOProviderClass"} = <CFString 0x15bd08c40 [0x100ecacd8]>{contents = "AppleUSBDeviceMux"} }
[19:14:26.0948-GMT]{4>8} CHECKPOINT END: MAIN:[0x040E] enable_usb restore-step-ids = {} restore-step-names = {} restore-step-uptime = 3 restore-step-user-progress = 0 waiting for host to trigger start of restore [timeout of 120 seconds] recv(8, 4) failed: connection closed unrecognized request 'GetValue' unable to read message size: -1 could not receive message unrecognized request 'ValidatePair' [19:14:27.0526-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0600] client_protocol_version restore-step-ids = {0x11030600:11} restore-step-names = {0x11030600:client_protocol_version} restore-step-uptime = 4 restore-step-user-progress = 0 client protocol version 14 [19:14:27.0535-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0600] client_protocol_version restore-step-ids = {} restore-step-names = {} restore-step-uptime = 4 restore-step-user-progress = 0 [19:14:27.0542-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0601] copy_restore_options restore-step-ids = {0x11030601:12} restore-step-names = {0x11030601:copy_restore_options} restore-step-uptime = 4 restore-step-user-progress = 0 UUID FE53251F-83E6-AD7C-31EF-F9E0373A2967 Restore options: CreateFilesystemPartitions => <CFBoolean 0x100ecb240 [0x100ecacd8]>{value = false} MinimumSystemPartition => <CFNumber 0xb00000000000dda3 [0x100ecacd8]>{value = +3546, type = kCFNumberSInt64Type} UUID => <CFString 0x15bd09b50 [0x100ecacd8]>{contents = "FE53251F-83E6-AD7C-31EF-F9E0373A2967"} SystemPartitionSize => <CFNumber 0xb00000000000dda3 [0x100ecacd8]>{value = +3546, type = kCFNumberSInt64Type} SystemPartitionPadding => <CFBasicHash 0x15be0bf70 [0x100ecacd8]>{type = mutable dict, count = 9, entries => 0 : 512 = <CFNumber 0xb000000000005003 [0x100ecacd8]>{value = +1280, type = kCFNumberSInt64Type} 2 : 128 = <CFNumber 0xb000000000005003 [0x100ecacd8]>{value = +1280, type = kCFNumberSInt64Type} 3 : 16 = <CFNumber 0xb000000000000a03 [0x100ecacd8]>{value = +160, type = kCFNumberSInt64Type} 4 : 1024 = <CFNumber 0xb000000000005003 [0x100ecacd8]>{value = +1280, type = kCFNumberSInt64Type} 5 : 32 = <CFNumber 0xb000000000001403 [0x100ecacd8]>{value = +320, type = kCFNumberSInt64Type} 6 : 768 = <CFNumber 0xb000000000005003 [0x100ecacd8]>{value = +1280, type = kCFNumberSInt64Type} 7 : 8 = <CFNumber 0xb000000000000503 [0x100ecacd8]>{value = +80, type = kCFNumberSInt64Type} 8 : 64 = <CFNumber 0xb000000000002803 [0x100ecacd8]>{value = +640, type = kCFNumberSInt64Type} 10 : 256 = <CFNumber 0xb000000000005003 [0x100ecacd8]>{value = +1280, type = kCFNumberSInt64Type} }
PersonalizedDuringPreflight => <CFBoolean 0x100ecb230 [0x100ecacd8]>{value = true}
[19:14:27.0558-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0601] copy_restore_options
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 4
restore-step-user-progress = 0
[19:14:27.0563-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0655] is_host_compatible
restore-step-ids = {0x11030655:13}
restore-step-names = {0x11030655:is_host_compatible}
restore-step-uptime = 4
restore-step-user-progress = 0
[19:14:27.0568-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0655] is_host_compatible
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 4
restore-step-user-progress = 0
[19:14:27.0572-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0604] set_weight_from_options
restore-step-ids = {0x11030604:14}
restore-step-names = {0x11030604:set_weight_from_options}
restore-step-uptime = 4
restore-step-user-progress = 0
[19:14:27.0578-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0604] set_weight_from_options
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 4
restore-step-user-progress = 0
[19:14:27.0583-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0608] device_has_hoover
restore-step-ids = {0x11030608:15}
restore-step-names = {0x11030608:device_has_hoover}
restore-step-uptime = 4
restore-step-user-progress = 0
[19:14:27.0589-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0608] device_has_hoover
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 4
restore-step-user-progress = 0
[19:14:27.0594-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0609] load_sep_os
restore-step-ids = {0x11030609:16}
restore-step-names = {0x11030609:load_sep_os}
restore-step-uptime = 4
restore-step-user-progress = 0
entering load_sep_os
device has sep - getting firmware
entering copy_restore_sep
got sep firmware - making call to load it
entering ramrod_load_sep_os
entering ramrod_execute_command_with_input_data: /usr/libexec/seputil (0x1063c0000 - 4986470)
executing /usr/libexec/seputil --restore+art -
waiting for child to exit
child exited
exit status: 0
[19:14:30.0340-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0609] load_sep_os
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 7
restore-step-user-progress = 0
[19:14:30.0341-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x060B] update_ticket
restore-step-ids = {0x1103060B:17}
restore-step-names = {0x1103060B:update_ticket}
restore-step-uptime = 7
restore-step-user-progress = 0
entering ramrod_ticket_update
looking up boot manifest hash
crypto-hash-method found. Using SHA1
device tree ticket_hash: 86A523BF800B9B25390E4E0B734338407ABFD5FE
computed ticket_hash : 86A523BF800B9B25390E4E0B734338407ABFD5FE
received valid ticket (5576 bytes)
[19:14:30.0346-GMT]{4>8} CHECKPOINT END: RESTORED:[0x060B] update_ticket
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 7
restore-step-user-progress = 0
[19:14:30.0347-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x060C] partition_nand
restore-step-ids = {0x1103060C:18}
restore-step-names = {0x1103060C:partition_nand}
restore-step-uptime = 7
restore-step-user-progress = 0
entering partition_nand_device
[19:14:30.0349-GMT]{4>8} CHECKPOINT END: RESTORED:[0x060C] partition_nand
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 7
restore-step-user-progress = 0
[19:14:30.0351-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x060D] wait_for_storage
restore-step-ids = {0x1103060D:19}
restore-step-names = {0x1103060D:wait_for_storage}
restore-step-uptime = 7
restore-step-user-progress = 0
entering wait_for_storage_device
ramrod_display_set_granular_progress: 0.000000
Searching for NAND service
Found NAND service: ASPStorage
NAND initialized. Waiting for devnode.
[19:14:30.0355-GMT]{4>8} CHECKPOINT END: RESTORED:[0x060D] wait_for_storage
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 7
restore-step-user-progress = 0
[19:14:30.0356-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x060E] update_s3e_firmware
restore-step-ids = {0x1103060E:20}
restore-step-names = {0x1103060E:update_s3e_firmware}
restore-step-uptime = 7
restore-step-user-progress = 0
[19:14:30.0358-GMT]{4>8} CHECKPOINT END: RESTORED:[0x060E] update_s3e_firmware
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 7
restore-step-user-progress = 0
[19:14:30.0359-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x060F] clear_remap_variable
restore-step-ids = {0x1103060F:21}
restore-step-names = {0x1103060F:clear_remap_variable}
restore-step-uptime = 7
restore-step-user-progress = 0
entering clear_remap_variable
executing /usr/sbin/nvram -d enable-remap-mode
enable-remap-mode
[19:14:30.0376-GMT]{4>8} CHECKPOINT END: RESTORED:[0x060F] clear_remap_variable
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 7
restore-step-user-progress = 0
[19:14:30.0378-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0610] asp_nand_set_writable
restore-step-ids = {0x11030610:22}
restore-step-names = {0x11030610:asp_nand_set_writable}
restore-step-uptime = 7
restore-step-user-progress = 0
Service name : ASPStorage
Set ASP writable successfully
[19:14:30.0383-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0610] asp_nand_set_writable
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 7
restore-step-user-progress = 0
[19:14:30.0385-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0611] reprobe_storage
restore-step-ids = {0x11030611:23}
restore-step-names = {0x11030611:reprobe_storage}
restore-step-uptime = 7
restore-step-user-progress = 0
entering ramrod_reprobe_device_path
entering ramrod_probe_media
device partitioning scheme is GPT
APFS Container /dev/disk0s1
device is APFS formatted
find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log=
[19:14:30.0401-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0611] reprobe_storage
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 7
restore-step-user-progress = 0
[19:14:30.0403-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0612] reprobe_firmware
restore-step-ids = {0x11030612:24}
restore-step-names = {0x11030612:reprobe_firmware}
restore-step-uptime = 7
restore-step-user-progress = 0
entering ramrod_reprobe_device_path
entering ramrod_probe_media
device partitioning scheme is GPT
APFS Container /dev/disk0s1
device is APFS formatted
find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log=
[19:14:30.0410-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0612] reprobe_firmware
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 7
restore-step-user-progress = 0
[19:14:30.0411-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0613] find_filesystem_partitions
restore-step-ids = {0x11030613:25}
restore-step-names = {0x11030613:find_filesystem_partitions}
restore-step-uptime = 7
restore-step-user-progress = 0
entering ramrod_probe_media
device partitioning scheme is GPT
APFS Container /dev/disk0s1
device is APFS formatted
find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log=
[19:14:30.0415-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0613] find_filesystem_partitions
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 7
restore-step-user-progress = 0
[19:14:30.0417-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0614] mount_system_partition
restore-step-ids = {0x11030614:26}
restore-step-names = {0x11030614:mount_system_partition}
restore-step-uptime = 7
restore-step-user-progress = 0
ramrod_display_set_granular_progress: 0.000000
entering mount_partition
executing /System/Library/Filesystems/apfs.fs/fsck_apfs -fqy /dev/disk0s1s1
nx_mount:777: Container cleanly-unmounted flag set.
nx_mount:895: checkpoint search: largest xid 3382, best xid 3382 @ 43
recv(8, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
nx_check:538: skipping check of fs[1] oid 1030 fs root tree because it's encrypted
error: option -f (unimplemented)
Checking /dev/rdisk0s1
Checking with nx_check.
executing /System/Library/Filesystems/apfs.fs/mount_apfs -o rdonly /dev/disk0s1s1 /mnt1
/dev/disk0s1s1 mounted on /mnt1
System mounted read-only
[19:14:39.0709-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0614] mount_system_partition
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 16
restore-step-user-progress = 0
[19:14:39.0714-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0654] read_previous_os_build_version
restore-step-ids = {0x11030654:27}
restore-step-names = {0x11030654:read_previous_os_build_version}
restore-step-uptime = 16
restore-step-user-progress = 0
ramrod_read_previous_os_build_version: previous OS version: (null)
[19:14:39.0727-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0654] read_previous_os_build_version
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 16
restore-step-user-progress = 0
[19:14:39.0732-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0615] copy_hardware_info
restore-step-ids = {0x11030615:28}
restore-step-names = {0x11030615:copy_hardware_info}
restore-step-uptime = 16
restore-step-user-progress = 0
[19:14:39.0738-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0615] copy_hardware_info
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 16
restore-step-user-progress = 0
[19:14:39.0744-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0616] fdr_create
restore-step-ids = {0x11030616:29}
restore-step-names = {0x11030616:fdr_create}
restore-step-uptime = 16
restore-step-user-progress = 0
restored_fdr_initialize: FDR is supported
CryptoAcceleratorEncrypt: perform aes => 0 (kIOReturnSuccess)
pseudo_ccrng_allocate: ccdrbg_init() -> 0
CryptoGenerateRSAKeys: ccrsa_generate_key() -> 0
CryptoGenerateRSAKeys: [RSA Public Key SHA1: 20 bytes]
: 3e 5f f8 80 94 64 52 01 dc fb 2b 92 43 97 40 36
: c2 f1 b5 a2
: -----------------------------------------------
CryptoGenerateRSAKeys: SecKeyCreateRSAPrivateKey -> 0x100085e60
CryptoGenerateRSAKeys: SecKeyCreateRSAPublicKey -> 0x100085e58
created HTTP FDR client 0x15be23450
created local FDR client 0x15be1fa40
Received response without expected RESTORED_FDR_TRUST_DATA
Found local trust object, computing digest..
Local trust object digest DOES match AP ticket.
Not enabling SSO for FDR
RestoredFDRCreate() returned 0
FDR is supported on this device
[19:14:42.0065-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0616] fdr_create
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 19
restore-step-user-progress = 0
[19:14:42.0070-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0617] fdr_save_data
restore-step-ids = {0x11030617:30}
restore-step-names = {0x11030617:fdr_save_data}
restore-step-uptime = 19
restore-step-user-progress = 0
_AMFDRSealingMapCopyDataClassesAndInstances: amfdr data version changed. Old version: (null) new version 1
AMFDRSealedDataPopulate: IM4M found, storing in options.
AMSupportPlatformCreateBufferFromNativeFilePath: open failed: No such file or directory
AMSupportPlatformCreateBufferFromNativeFilePath: open failed: No such file or directory
AMFDRCreateError: AMFDRDataLocalCopy: Failed to read data: code=4
AMFDRCreateError: AMFDRDataCopy: bbpc:0412ACC9 get data failed - foundData is NULL: code=10
AMFDRSealedDataPopulate: Failed to fetch data for bbpc:0412ACC9, but it is not required for sealing
AMFDRSetOptions: amfdr is NULL
saved FDR persistent state
RestoredFDRSaveData success
[19:14:42.0120-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0617] fdr_save_data
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 19
restore-step-user-progress = 0
[19:14:42.0127-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0618] store_baseband_migration
restore-step-ids = {0x11030618:31}
restore-step-names = {0x11030618:store_baseband_migration}
restore-step-uptime = 19
restore-step-user-progress = 0
[19:14:42.0134-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0618] store_baseband_migration
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 19
restore-step-user-progress = 0
[19:14:42.0142-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0619] check_for_restore_log
restore-step-ids = {0x11030619:32}
restore-step-names = {0x11030619:check_for_restore_log}
restore-step-uptime = 19
restore-step-user-progress = 0
entering check_for_restore_log
restore log does not exist
[19:14:42.0152-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0619] check_for_restore_log
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 19
restore-step-user-progress = 0
[19:14:42.0159-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x061A] check_for_ota_logs
restore-step-ids = {0x1103061A:33}
restore-step-names = {0x1103061A:check_for_ota_logs}
restore-step-uptime = 19
restore-step-user-progress = 0
entering mount_partition
executing /System/Library/Filesystems/apfs.fs/fsck_apfs -fqy /dev/disk0s1s2
nx_mount:777: Container cleanly-unmounted flag set.
nx_mount:895: checkpoint search: largest xid 3382, best xid 3382 @ 43
recv(12, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
nx_check:538: skipping check of fs[1] oid 1030 fs root tree because it's encrypted
error: option -f (unimplemented)
Checking /dev/rdisk0s1
Checking with nx_check.
executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s2 /mnt2
/dev/disk0s1s2 mounted on /mnt2
Data mounted read-write
entering dump_panic_logs
executing /System/Library/CoreServices/DumpPanic -r/mnt2/mobile/Library/Logs/CrashReporter
entering check_for_ota_logs_with_mount_point
Diagnostic log submission enabled
Searching /mnt2/mobile/Library/Logs/CrashReporter/
check_for_ota_logs_with_mount_point complete
Successfully checked for OTA logs
[19:14:51.0133-GMT]{4>8} CHECKPOINT END: RESTORED:[0x061A] check_for_ota_logs
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 28
restore-step-user-progress = 0
[19:14:51.0135-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x061B] submit_update_stats
restore-step-ids = {0x1103061B:34}
restore-step-names = {0x1103061B:submit_update_stats}
restore-step-uptime = 28
restore-step-user-progress = 0
entering mount_partition
executing /sbin/mount -u -o -w /dev/disk0s1s1 /mnt1
mount_apfs: unknown option '-w'
mount_apfs: [-o options] [-u UID] [-g GID] <-C device | -s snapshot volume | device>
calling UMEventShimSubmitEvents : start
: filesystem copy of nvram will be used. ([1485785954][620] vs. [0][0])
: Diagnostic log submission enabled
: posting to https://xp.apple.com/report/2/psr_ota length = 1177
: data = {"clientId":"F22E1503-8B70-4125-BBA7-ADD6B9137C14","events":[{"installTonight":false,"otaOutcome":"success","deviceModel":"N56AP","otaInitialStepIDs":"{}","batteryIsCharging":true,"batteryLevel":100,"convPanic":"14E5230e","otaInitialUserProgress":"100","eventTime":"1485803700001","reportVersion":1,"otaInitialStepNames":"{}","originalOSVersion":"14D27","postConv":"postV=apfs-249.50.174.1.1 r=0 d=10 d100=10 p=100 m=142 u=-22","currentOSVersion":"14C92","otaLogsFound":0,"uptime":37,"type":"ota","breadcrumbs":"2:BihACDFGIKNgPQRacdeSTUWXZ","systemFsCapacity":61025,"otaLogsSubmitted":0,"storageCapacity":122050,"preConv":"preV=apfs-249.50.174.1.1 sz=61035 sf=48306 ca=58 aa=93 ea=11 ba=2 ja=17 ta=181 cu=32 au=16 eu=0 tu=67 n_d=16900 n_f=54300 n_s=0 n_h=2500 n_lf=29300 n_le=1600 n_ea=102400 ss=2900 es=5","systemFsFree":57400,"result":"success","targetOSVersion":"14E5230e","otaInitialStepMonitor":"{0x11010207:\"wifexited\"}","brainVersion":"14A601","event":"updateFinished","otaAnomalies":"{0x00000000:[check_collection]ota-step-monitor(does_not_exist)}","otaInitialUptime":"620","currentOSType":"Restore","dataFsFree":50363,"deviceClass":"iPhone","dataFsCapacity":61025}]}
: session (null) didReceiveChallenge
-[AMSupportURLSession URLSession:didReceiveChallenge:completionHandler:]: Authentication challenge received. Method: NSURLAuthenticationMethodServerTrust -[AMSupportURLSession URLSession:didReceiveChallenge:completionHandler:]: Attempting trust evaluate AMSupportX509ChainEvaluateTrust: Number of trusted roots: 3 AMSupportX509ChainEvaluateTrust: Number of server certificates: 1 AMSupportX509ChainEvaluateTrust: First server cert length 1880 AMSupportX509ChainEvaluateTrust: Trusted root #0 has length 1570 AMSupportX509DecodeVerifyCertIssuer: PKI: cert was not issued by issuer (subject != issuer) AMSupportX509ChainEvaluateTrust: Trusted root #1 has length 1239 AMSupportX509DecodeVerifyCertIssuer: PKI: cert was not issued by issuer (subject != issuer) AMSupportX509ChainEvaluateTrust: Trusted root #2 has length 1327 AMSupportX509DecodeVerifyCertIssuer: PKI: verify cert was issued and signed by issuer (success) AMSupportX509ChainEvaluateTrust: PKI: verify cert was issued by trusted root 2 (success) AMSupportX509ChainEvaluateTrust: PKI: Chain validation complete. (success) -[AMSupportURLSession URLSession:didReceiveChallenge:completionHandler:]: Trust evaluation succeeded, proceeding.. : 1 events successfully submitted
UMEventShimSubmitEvents done [19:15:02.0072-GMT]{4>8} CHECKPOINT END: RESTORED:[0x061B] submit_update_stats restore-step-ids = {} restore-step-names = {} restore-step-uptime = 39 restore-step-user-progress = 0 [19:15:02.0077-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x061D] unmount_unconditionally restore-step-ids = {0x1103061D:35} restore-step-names = {0x1103061D:unmount_unconditionally} restore-step-uptime = 39 restore-step-user-progress = 0 entering unmount_filesystems ramrod_display_set_granular_progress: 1.000000 entering unmount_partition System unmounted ramrod_display_set_granular_progress: 1.000000 entering unmount_partition Data unmounted create_baseband_update_options: Got imeisv: 18 baseband updater options = <CFBasicHash 0x15be205a0 [0x100ecacd8]>{type = mutable dict, count = 4, entries => 0 : <CFString 0x10027fb80 [0x100ecacd8]>{contents = "IMEISwVersion"} = <CFNumber 0xb000000000000122 [0x100ecacd8]>{value = +18, type = kCFNumberSInt32Type} 1 : <CFString 0x10027fb40 [0x100ecacd8]>{contents = "restoredInternal"} = <CFBoolean 0x100ecb240 [0x100ecacd8]>{value = false} 2 : <CFString 0x10027fca0 [0x100ecacd8]>{contents = "autoSetupEnv"} = <CFBoolean 0x100ecb240 [0x100ecacd8]>{value = false} 6 : <CFString 0x10027fcc0 [0x100ecacd8]>{contents = "disablePing"} = <CFBoolean 0x100ecb230 [0x100ecacd8]>{value = true} }
_baseband_update_requires_filesystems_once: querying baseband to determine if it requires system filesystem bbupdater: BEGIN: Command: requirePartitionMount bbupdater: cmdCopyRequirePartitionMount: supports flashless 1 bbupdater: cmdCopyRequirePartitionMount: supports REFS 1 bbupdater: END: Command: requirePartitionMount _baseband_update_requires_filesystems_once: query returned <CFBasicHash 0x15be2d1b0 [0x100ecacd8]>{type = mutable dict, count = 3, entries => 0 : <CFString 0x10027f520 [0x100ecacd8]>{contents = "requireUserPartitionMount"} = <CFBoolean 0x100ecb230 [0x100ecacd8]>{value = true} 1 : <CFString 0x10027f540 [0x100ecacd8]>{contents = "requestedBasebandFSPartitionMountSize"} = <CFNumber 0xb0000000000000a3 [0x100ecacd8]>{value = +10, type = kCFNumberSInt64Type} 2 : <CFString 0x10027f4e0 [0x100ecacd8]>{contents = "requireSystemPartitionMount"} = <CFBoolean 0x100ecb230 [0x100ecacd8]>{value = true} }
ramrod_display_set_granular_progress: 1.000000
entering unmount_partition
Baseband Data not mounted (unmount ignored)
[19:15:02.0131-GMT]{4>8} CHECKPOINT END: RESTORED:[0x061D] unmount_unconditionally
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0136-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x064E] clean_nand
restore-step-ids = {0x1103064E:36}
restore-step-names = {0x1103064E:clean_nand}
restore-step-uptime = 39
restore-step-user-progress = 1
entering clean_NAND
[19:15:02.0142-GMT]{4>8} CHECKPOINT END: RESTORED:[0x064E] clean_nand
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0147-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0653] create_readwrite_ramdisk
restore-step-ids = {0x11030653:37}
restore-step-names = {0x11030653:create_readwrite_ramdisk}
restore-step-uptime = 39
restore-step-user-progress = 1
executing /usr/sbin/hdik -nomount ram://5120
/dev/disk4
executing /sbin/newfs_hfs -J -v readwrite_ramdisk /dev/disk4
Initialized /dev/rdisk4 as a 3 MB case-insensitive HFS Plus volume with a 512k journal
executing /sbin/fsck_hfs -fdy /dev/disk4
journal_replay(/dev/disk4) returned 0
/dev/rdisk4
Using cacheBlockSize=32K cacheTotalBlock=3908 cacheSize=125056K.
Executing fsck_hfs (version hfs-366.30.3).
Checking Journaled HFS Plus volume.
The volume name is readwrite_ramdisk
Checking extents overflow file.
Checking catalog file.
Checking multi-linked files.
Checking catalog hierarchy.
Checking extended attributes file.
Checking volume bitmap.
Checking volume information.
The volume readwrite_ramdisk appears to be OK.
CheckHFS returned 0, fsmodified = 0
executing /sbin/mount_hfs /dev/disk4 /mnt5
mount_hfs: Could not create property for re-key environment check: No such file or directory
[19:15:02.0387-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0653] create_readwrite_ramdisk
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0390-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x061F] format_effaceable_storage
restore-step-ids = {0x1103061F:38}
restore-step-names = {0x1103061F:format_effaceable_storage}
restore-step-uptime = 39
restore-step-user-progress = 1
entering format_effaceable_storage
effaceable storage is formatted, nothing to do
[19:15:02.0394-GMT]{4>8} CHECKPOINT END: RESTORED:[0x061F] format_effaceable_storage
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0396-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0643] get_apfs_options
restore-step-ids = {0x11030643:39}
restore-step-names = {0x11030643:get_apfs_options}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0399-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0643] get_apfs_options
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0402-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0620] format_storage_for_lwvm
restore-step-ids = {0x11030620:40}
restore-step-names = {0x11030620:format_storage_for_lwvm}
restore-step-uptime = 39
restore-step-user-progress = 1
entering format_storage_for_LwVM
[19:15:02.0405-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0620] format_storage_for_lwvm
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0408-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0622] reload_filesystem_partitions
restore-step-ids = {0x11030622:41}
restore-step-names = {0x11030622:reload_filesystem_partitions}
restore-step-uptime = 39
restore-step-user-progress = 1
entering ramrod_probe_media
device partitioning scheme is GPT
APFS Container /dev/disk0s1
device is APFS formatted
find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log=
[19:15:02.0416-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0622] reload_filesystem_partitions
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0419-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0645] check_not_apfs_mount
restore-step-ids = {0x11030645:42}
restore-step-names = {0x11030645:check_not_apfs_mount}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0422-GMT]{4>8} CHECKPOINT BEGIN: NOT_APFS:[0x0900] baseband_migration_data
restore-step-ids = {0x11030645:42;0x11030900:43}
restore-step-names = {0x11030645:check_not_apfs_mount;0x11030900:baseband_migration_data}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0424-GMT]{4>8} CHECKPOINT END: NOT_APFS:[0x0900] baseband_migration_data
restore-step-ids = {0x11030645:42}
restore-step-names = {0x11030645:check_not_apfs_mount}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0426-GMT]{4>8} CHECKPOINT BEGIN: NOT_APFS:[0x0901] check_update
restore-step-ids = {0x11030645:42;0x11030901:44}
restore-step-names = {0x11030645:check_not_apfs_mount;0x11030901:check_update}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0428-GMT]{4>8} CHECKPOINT BEGIN: NOT_APFS_UPDATE:[0x0A00] roll_media_keys
restore-step-ids = {0x11030645:42;0x11030901:44;0x11030A00:45}
restore-step-names = {0x11030645:check_not_apfs_mount;0x11030901:check_update;0x11030A00:roll_media_keys}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0430-GMT]{4>8} CHECKPOINT END: NOT_APFS_UPDATE:[0x0A00] roll_media_keys
restore-step-ids = {0x11030645:42;0x11030901:44}
restore-step-names = {0x11030645:check_not_apfs_mount;0x11030901:check_update}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0432-GMT]{4>8} CHECKPOINT BEGIN: NOT_APFS_UPDATE:[0x0A02] options_system_image
restore-step-ids = {0x11030645:42;0x11030901:44;0x11030A02:46}
restore-step-names = {0x11030645:check_not_apfs_mount;0x11030901:check_update;0x11030A02:options_system_image}
restore-step-uptime = 39
restore-step-user-progress = 1
[19:15:02.0434-GMT]{4>8} CHECKPOINT BEGIN: NOT_APFS_OPTIONS:[0x0B00] mount_filesystem
restore-step-ids = {0x11030645:42;0x11030901:44;0x11030A02:46;0x11030B00:47}
restore-step-names = {0x11030645:check_not_apfs_mount;0x11030901:check_update;0x11030A02:options_system_image;0x11030B00:mount_filesystem}
restore-step-uptime = 39
restore-step-user-progress = 1
entering mount_filesystems
ramrod_display_set_granular_progress: 1.000000
entering mount_partition
executing /System/Library/Filesystems/apfs.fs/fsck_apfs -fqy /dev/disk0s1s1
nx_mount:777: Container cleanly-unmounted flag set.
nx_mount:895: checkpoint search: largest xid 3383, best xid 3383 @ 45
recv(14, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
nx_check:538: skipping check of fs[1] oid 1030 fs root tree because it's encrypted
error: option -f (unimplemented)
Checking /dev/rdisk0s1
Checking with nx_check.
executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s1 /mnt1
/dev/disk0s1s1 mounted on /mnt1
System mounted read-write
ramrod_display_set_granular_progress: 1.000000
entering mount_partition
executing /System/Library/Filesystems/apfs.fs/fsck_apfs -fqy /dev/disk0s1s2
nx_mount:777: Container cleanly-unmounted flag set.
nx_mount:895: checkpoint search: largest xid 3383, best xid 3383 @ 45
recv(18, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
nx_check:538: skipping check of fs[1] oid 1030 fs root tree because it's encrypted
error: option -f (unimplemented)
Checking /dev/rdisk0s1
Checking with nx_check.
executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s2 /mnt2
/dev/disk0s1s2 mounted on /mnt2
Data mounted read-write
ramrod_display_set_granular_progress: 1.000000
entering mount_partition
executing /System/Library/Filesystems/apfs.fs/fsck_apfs -fqy /dev/disk0s1s3
nx_mount:777: Container cleanly-unmounted flag set.
nx_mount:895: checkpoint search: largest xid 3383, best xid 3383 @ 45
recv(17, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
nx_check:538: skipping check of fs[1] oid 1030 fs root tree because it's encrypted
error: option -f (unimplemented)
Checking /dev/rdisk0s1
Checking with nx_check.
executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s3 /mnt3
/dev/disk0s1s3 mounted on /mnt3
Baseband Data mounted read-write
[19:15:28.0926-GMT]{4>8} CHECKPOINT END: NOT_APFS_OPTIONS:[0x0B00] mount_filesystem
restore-step-ids = {0x11030645:42;0x11030901:44;0x11030A02:46}
restore-step-names = {0x11030645:check_not_apfs_mount;0x11030901:check_update;0x11030A02:options_system_image}
restore-step-uptime = 65
restore-step-user-progress = 1
[19:15:28.0929-GMT]{4>8} CHECKPOINT BEGIN: NOT_APFS_OPTIONS:[0x0B01] resize_system_partition
restore-step-ids = {0x11030645:42;0x11030901:44;0x11030A02:46;0x11030B01:48}
restore-step-names = {0x11030645:check_not_apfs_mount;0x11030901:check_update;0x11030A02:options_system_image;0x11030B01:resize_system_partition}
restore-step-uptime = 65
restore-step-user-progress = 1
entering resize_system_partition_to_options
Can't resize system partition since this is not an LwVM managed disk. Skipping resize operation.
entering ramrod_probe_media
device partitioning scheme is GPT
APFS Container /dev/disk0s1
device is APFS formatted
find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log=
[19:15:28.0937-GMT]{4>8} CHECKPOINT END: NOT_APFS_OPTIONS:[0x0B01] resize_system_partition
restore-step-ids = {0x11030645:42;0x11030901:44;0x11030A02:46}
restore-step-names = {0x11030645:check_not_apfs_mount;0x11030901:check_update;0x11030A02:options_system_image}
restore-step-uptime = 65
restore-step-user-progress = 1
[19:15:28.0939-GMT]{4>8} CHECKPOINT BEGIN: NOT_APFS_OPTIONS:[0x0B02] unmount_filesystem
restore-step-ids = {0x11030645:42;0x11030901:44;0x11030A02:46;0x11030B02:49}
restore-step-names = {0x11030645:check_not_apfs_mount;0x11030901:check_update;0x11030A02:options_system_image;0x11030B02:unmount_filesystem}
restore-step-uptime = 65
restore-step-user-progress = 1
entering unmount_filesystems
ramrod_display_set_granular_progress: 1.000000
entering unmount_partition
System unmounted
ramrod_display_set_granular_progress: 1.000000
entering unmount_partition
Data unmounted
ramrod_display_set_granular_progress: 1.000000
entering unmount_partition
Baseband Data unmounted
[19:15:28.0954-GMT]{4>8} CHECKPOINT END: NOT_APFS_OPTIONS:[0x0B02] unmount_filesystem
restore-step-ids = {0x11030645:42;0x11030901:44;0x11030A02:46}
restore-step-names = {0x11030645:check_not_apfs_mount;0x11030901:check_update;0x11030A02:options_system_image}
restore-step-uptime = 65
restore-step-user-progress = 1
[19:15:28.0957-GMT]{4>8} CHECKPOINT END: NOT_APFS_UPDATE:[0x0A02] options_system_image
restore-step-ids = {0x11030645:42;0x11030901:44}
restore-step-names = {0x11030645:check_not_apfs_mount;0x11030901:check_update}
restore-step-uptime = 65
restore-step-user-progress = 1
[19:15:28.0960-GMT]{4>8} CHECKPOINT END: NOT_APFS:[0x0901] check_update
restore-step-ids = {0x11030645:42}
restore-step-names = {0x11030645:check_not_apfs_mount}
restore-step-uptime = 65
restore-step-user-progress = 1
[19:15:28.0963-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0645] check_not_apfs_mount
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 65
restore-step-user-progress = 1
[19:15:28.0966-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0624] restore_images
restore-step-ids = {0x11030624:50}
restore-step-names = {0x11030624:restore_images}
restore-step-uptime = 65
restore-step-user-progress = 1
entering restore_images
OS digest = d0d7ebf2d6c4c27ae034c9f5185f42391a8582d8
Successfully marked device node /dev/rdisk0s1s1 as static data
executing /usr/sbin/asr -source asr://localhost:12345 -target /dev/disk0s1s1 -erase -noprompt --chunkchecksum --puppetstrings --expectedhash d0d7ebf2d6c4c27ae034c9f5185f42391a8582d8
ASR STATUS: start 391 multicast-client
ASR: Waiting for connection attempt from server
ASR STATUS: setup
ASR: Validating target...done
ASR STATUS: metadata
ASR: Validating source...
ASR: done
ASR: Repartitioning target device...
ASR: Could not repartition target device
ASR STATUS: fail
[19:15:31.0238-GMT]{4>8} CHECKPOINT FAILURE:(FAILURE:14) RESTORED:[0x0624] restore_images [0]D(failed to restore image)
restore-step-results = {0x11070624:{0:14}}
restore-step-codes = {0x11070624:{0:14}}
restore-step-domains = {0x11070624:{0:"AMRestoreErrorDomain"}}
restore-step-error = {0x11070624:"[0]D(failed to restore image)"}
restore-step-uptime = 68
restore-step-user-progress = 1
[19:15:31.0241-GMT]{4>8} CHECKPOINT NOTICE: (NVRAM set) restore-step-user-progress=1 [sync=true] (first failure)
[19:15:31.0241-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0647] cleanup_check_result
restore-step-ids = {0x11030624:50;0x11030647:51}
restore-step-names = {0x11030624:restore_images;0x11030647:cleanup_check_result}
restore-step-uptime = 68
restore-step-user-progress = 1
[19:15:31.0243-GMT]{4>8} CHECKPOINT END: RESTORED:[0x0647] cleanup_check_result
restore-step-ids = {0x11030624:50}
restore-step-names = {0x11030624:restore_images}
restore-step-uptime = 68
restore-step-user-progress = 1
[19:15:31.0244-GMT]{4>8} CHECKPOINT BEGIN: RESTORED:[0x0648] cleanup_send_final_status
restore-step-ids = {0x11030624:50;0x11030648:52}
restore-step-names = {0x11030624:restore_images;0x11030648:cleanup_send_final_status}
restore-step-uptime = 68
restore-step-user-progress = 1
Maybe the ipsw structure changed?
ERROR: zip_name_locate: Firmware/all_flash/manifest
Yes, apple has removed manifest. I think that manifest could be generated using some device variables, for example, iOS 10.2.1 manifest:
LLB.n66.RELEASE.im4p
iBoot.n66.RELEASE.im4p
DeviceTree.n66ap.im4p
applelogo@3x~iphone.s8000.im4p
recoverymode@1920~iphone-lightning.s8000.im4p
batterylow0@3x~iphone.s8000.im4p
batterylow1@3x~iphone.s8000.im4p
batterycharging0@3x~iphone.s8000.im4p
batterycharging1@3x~iphone.s8000.im4p
glyphplugin@1920~iphone-lightning.s8000.im4p
batteryfull@3x~iphone.s8000.im4p
sep-firmware.n66.RELEASE.im4p
Some variables means: s8000: is the processor class https://www.theiphonewiki.com/wiki/S8000 n66: iPhone 6s class
I have created a zip with the new structure (with *.dmg deleted): https://mega.nz/#!28RTUBZS!BH_Kw1pxjbL0uJ2MK4zLVbj_Trw0YpRokLLrho8EYzc
PD: If I've got time this week I will push an update with the fix.
Can you try with this patch? https://gist.github.com/nikias/8a9f4d7937d71bd2d375944d096cd781
Also, if you can, try to restore with a pre-10.3 iOS version. Basically the manifest was removed because all the info has been in the BuildManifest.plist for a long time now.
Sure, I'll try this weekend
Personalizing IMG4 component RestoreKernelCache... Sending RestoreKernelCache (12543000 bytes)... About to restore device... Waiting for device...
And working for iOS 10.2.1
I have tested a dirty trick and now is working:
PD: I'm getting this error: ERROR: FDR 0x7f4d44002dd0 Unable to receive proxy payload (-2) but it's look like that my iPhone has been restored successfully
Can you test my patch and try without a modified ipsw? idevicerestore is supposed to work without any manual dirty tricks ;)
I have tried your patch and I'm getting stuck at "Waiting for device...." (Without editing the ipsw first ofc)
The changes of my patch are in a part that is way after that part, in fact after uploading the root filesystem. This must be something else.
I have found the bug, the sep firmware has never been sent because they're using 'IsSecondaryFirmwarePayload' and no 'IsFirmwarePayload' In the other hand, Apple has deleted glyphplugin from manifest?
Confirmed that your patch is working, my wire was broken.
Patch works fine for me as well. Thanks @nikias!!!!!
See commit aaec66651aba5f617c5c2c22b019d115e2db8e8f.
@nikias @JoseCarlosGarcia95 I have iphone 6 on 10.3 beta 1 how can I do it? plz help to restore to 10.2
is there any tutorial if there gave me the link
@OothecaPickleGNUrmsTUXFSF How you did it Can you help me to restore to 10.2 I'm now on 10.3 beta 1
Hey! Please don't spam this! When I tried to restore to iOS 10.2.1 from iOS 10.3 I had some troubles due to apfs. How I solved this? I put my device in DFU mode and then I could restore without problems.
PD: I don't think that this is a bug.
@JoseCarlosGarcia95 There is tutorial for that plz any thing
Take a look here: https://www.theiphonewiki.com/wiki/DFU_Mode
@JoseCarlosGarcia95 Thank you Bro But I want tutorial for use libimobiledevice I'm sorry for noise you
The problem is you're causing noise for EVERYONE on this ticket (and the maintainers in any case) whenever you comment on something. This is a bug tracker and not a support forum. Please only report bugs here. Thanks. And please don't reply to this message.
@JoseCarlosGarcia95 @OothecaPickleGNUrmsTUXFSF have you by any chance tried this on a newer 10.3 or 10.3.1 restore?
@nikias what do you mean?
@OothecaPickleGNUrmsTUXFSF have you tried to restore a device with the recent iOS 10.3 or 10.3.1 release? Because in #155 people report devices going to DFU mode after restore...
I'll do it right now and get back to you.
@nikias It seems that @ankitrwt attempted to restore a 64-bit device. Would you like me to try restoring my iPhone 5S?
@OothecaPickleGNUrmsTUXFSF oh good observation. Maybe it's about the FDR part of the restore... Only restore if you can sacrifice it, I don't want you to lose a specific firmware version just because of my curiosity :)
@nikias No, it's okay; I actually have two jailbroken 5S's, so I can afford to lose one. :) Do you want me to try restoring?
@OothecaPickleGNUrmsTUXFSF you don't have to.
hello @nikias @OothecaPickleGNUrmsTUXFSF i tried again but stil the results are for iPhone 6
root:Firmware_CacheDir# idevicerestore -e -l -C .
NOTE: using cached version data
Found device in Normal mode
Identified device as n53ap, iPhone6,2
Latest firmware is iPhone_4.0_64bit_10.3_14E277_Restore.ipsw
Verifying './iPhone_4.0_64bit_10.3_14E277_Restore.ipsw'...
Checksum matches.
Extracting BuildManifest from IPSW
Product Version: 10.3
Product Build: 14E277 Major: 14
Device supports Image4: true
Variant: Customer Erase Install (IPSW)
This restore will erase your device data.
Found ECID 2706612266844
Getting ApNonce in normal mode... 42 15 d8 b0 f3 18 90 fd f1 2b 42 c3 0f 12 f8 35 50 c3 d3 60
Trying to fetch new SHSH blob
Getting SepNonce in normal mode... 2e 59 0d 19 a1 a8 54 52 b5 20 74 f5 aa 5f 03 f1 7c e3 c5 dc
WARNING: Unable to find BbSkeyId node
Request URL set to https://gs.apple.com/TSS/controller?action=2
Sending TSS request attempt 1... response successfully received
Received SHSH blobs
Using cached filesystem from './iPhone_4.0_64bit_10.3_14E277_Restore/058-67581-067.dmg'
Entering recovery mode...
INFO: device serial number is DX4RTH7JFRC5
Extracting iBEC.iphone6.RELEASE.im4p...
Personalizing IMG4 component iBEC...
Sending iBEC (648345 bytes)...
Recovery Mode Environment:
iBoot build-version=iBoot-3406.50.244
iBoot build-style=RELEASE
Sending AppleLogo...
Extracting applelogo@2x~iphone.s5l8960x.im4p...
Personalizing IMG4 component AppleLogo...
Sending AppleLogo (11640 bytes)...
ramdisk-size=0x10000000
Extracting 058-55512-165.dmg...
Personalizing IMG4 component RestoreRamDisk...
Sending RestoreRamDisk (41575446 bytes)...
Extracting DeviceTree.n53ap.im4p...
Personalizing IMG4 component RestoreDeviceTree...
Sending RestoreDeviceTree (107921 bytes)...
Extracting kernelcache.release.iphone6...
Personalizing IMG4 component RestoreKernelCache...
Sending RestoreKernelCache (12168008 bytes)...
About to restore device...
Waiting for device...
Device b43439f38a77718f40dc28bea621309b9cca1353 is now connected in restore mode...
Connecting now...
Connected to com.apple.mobile.restored, version 14
Device b43439f38a77718f40dc28bea621309b9cca1353 has successfully entered restore mode
Hardware Information:
BoardID: 2
ChipID: 35168
UniqueChipID: 2706612266844
ProductionMode: true
Starting FDR listener thread
About to send NORData...
Found firmware path Firmware/all_flash
ERROR: zip_name_locate: Firmware/all_flash/manifest
Getting firmware manifest from build identity
Extracting LLB.iphone6.RELEASE.im4p...
Personalizing IMG4 component LLB...
Extracting applelogo@2x~iphone.s5l8960x.im4p...
Personalizing IMG4 component AppleLogo...
Extracting batterycharging0@2x~iphone.s5l8960x.im4p...
Personalizing IMG4 component BatteryCharging0...
Extracting batterycharging1@2x~iphone.s5l8960x.im4p...
Personalizing IMG4 component BatteryCharging1...
Extracting batteryfull@2x~iphone.s5l8960x.im4p...
Personalizing IMG4 component BatteryFull...
Extracting batterylow0@2x~iphone.s5l8960x.im4p...
Personalizing IMG4 component BatteryLow0...
Extracting batterylow1@2x~iphone.s5l8960x.im4p...
Personalizing IMG4 component BatteryLow1...
Extracting glyphplugin@1136~iphone-lightning.s5l8960x.im4p...
Personalizing IMG4 component BatteryPlugin...
Extracting DeviceTree.n53ap.im4p...
Personalizing IMG4 component DeviceTree...
Extracting recoverymode@1136~iphone-lightning.s5l8960x.im4p...
Personalizing IMG4 component RecoveryMode...
Extracting iBoot.iphone6.RELEASE.im4p...
Personalizing IMG4 component iBoot...
Extracting sep-firmware.n53.RELEASE.im4p...
Personalizing IMG4 component RestoreSEP...
Extracting sep-firmware.n53.RELEASE.im4p...
Personalizing IMG4 component SEP...
Sending NORData now...
Done sending NORData
About to send RootTicket...
Sending RootTicket now...
Done sending RootTicket
Waiting for NAND (28)
Checking filesystems (15)
Checking filesystems (15)
Checking filesystems (15)
Unmounting filesystems (29)
Unmounting filesystems (29)
Creating partition map (11)
Creating filesystem (12)
About to send filesystem...
Connected to ASR
Validating the filesystem
Filesystem validated
Sending filesystem now...
[==================================================] 100.0%
Done sending filesystem
Verifying restore (14)
[==================================================] 100.0%
Checking filesystems (15)
Checking filesystems (15)
Mounting filesystems (16)
Mounting filesystems (16)
About to send KernelCache...
Extracting kernelcache.release.iphone6...
Personalizing IMG4 component KernelCache...
Sending KernelCache now...
Done sending KernelCache
Installing kernelcache (27)
Flashing firmware (18)
[==================================================] 100.0%
Updating gas gauge software (47)
Updating gas gauge software (47)
Updating Stockholm (55)
About to send FUD data...
Sending FUD data now...
Done sending FUD data
About to send FUD data...
Sending FUD data now...
Done sending FUD data
Updating baseband (19)
About to send BasebandData...
Sending BasebandData now...
Done sending BasebandData
Updating baseband (19)
Updating baseband (19)
Updating baseband (19)
Updating baseband (19)
Updating Baseband in progress...
About to send BasebandData...
Sending BasebandData now...
Done sending BasebandData
Updating Baseband in progress...
About to send BasebandData...
Sending BasebandData now...
Done sending BasebandData
Updating baseband (19)
Updating baseband (19)
Updating baseband (19)
Updating Baseband in progress...
About to send BasebandData...
Sending BasebandData now...
Done sending BasebandData
Updating baseband (19)
Updating baseband (19)
Updating baseband (19)
Updating baseband (19)
Updating baseband (19)
Updating Baseband completed.
Fixing up /var (17)
Creating system key bag (50)
Modifying persistent boot-args (25)
Unmounting filesystems (29)
Unmounting filesystems (29)
Got status message
Status: Restore Finished
Cleaning up...
DONE
root:Firmware_CacheDir# idevicerestore -e -l -C .
NOTE: using cached version data
Found device in DFU mode
Identified device as n61ap, iPhone7,2
Latest firmware is iPhone_4.7_10.3_14E277_Restore.ipsw
Verifying './iPhone_4.7_10.3_14E277_Restore.ipsw'...
Checksum matches.
Extracting BuildManifest from IPSW
Product Version: 10.3
Product Build: 14E277 Major: 14
Device supports Image4: true
Variant: Customer Erase Install (IPSW)
This restore will erase your device data.
Found ECID 1804333217319974
Getting ApNonce in dfu mode... 9d 39 27 21 bc 7c 53 04 ef be 7f 8f 7a 59 f8 73 2e 1d bf 4a
Trying to fetch new SHSH blob
Getting SepNonce in dfu mode... d5 c5 81 ef 1b 3f f4 89 05 3c aa fa ac 04 98 6e 79 aa 82 30
WARNING: Unable to find BbSkeyId node
Request URL set to https://gs.apple.com/TSS/controller?action=2
Sending TSS request attempt 1... response successfully received
Received SHSH blobs
Extracting filesystem from IPSW
[==================================================] 100.0%
Extracting iBSS.n61.RELEASE.im4p...
Personalizing IMG4 component iBSS...
Sending iBSS (177782 bytes)...
[==================================================] 100.0%
Nonce: 9d 39 27 21 bc 7c 53 04 ef be 7f 8f 7a 59 f8 73 2e 1d bf 4a
Extracting iBEC.n61.RELEASE.im4p...
Personalizing IMG4 component iBEC...
Sending iBEC (657014 bytes)...
[==================================================] 100.0%
INFO: device serial number is FFMQW43JG5MV
Recovery Mode Environment:
iBoot build-version=iBoot-3406.50.244
iBoot build-style=RELEASE
Sending AppleLogo...
Extracting applelogo@2x~iphone.t7000.im4p...
Personalizing IMG4 component AppleLogo...
Sending AppleLogo (12117 bytes)...
ramdisk-size=0x10000000
Extracting 058-55512-165.dmg...
Personalizing IMG4 component RestoreRamDisk...
Sending RestoreRamDisk (41571827 bytes)...
Extracting DeviceTree.n61ap.im4p...
Personalizing IMG4 component RestoreDeviceTree...
Sending RestoreDeviceTree (123986 bytes)...
Extracting kernelcache.release.iphone7...
Personalizing IMG4 component RestoreKernelCache...
Sending RestoreKernelCache (12591071 bytes)...
About to restore device...
Waiting for device...
Device 80bf669ed94eab01711cde24101d07f748991871 is now connected in restore mode...
Connecting now...
Connected to com.apple.mobile.restored, version 14
Device 80bf669ed94eab01711cde24101d07f748991871 has successfully entered restore mode
Hardware Information:
BoardID: 6
ChipID: 28672
UniqueChipID: 1804333217319974
ProductionMode: true
Previous restore exit status: 0x100
Starting FDR listener thread
About to send NORData...
Found firmware path Firmware/all_flash
ERROR: zip_name_locate: Firmware/all_flash/manifest
Getting firmware manifest from build identity
Extracting LLB.n61.RELEASE.im4p...
Personalizing IMG4 component LLB...
Extracting applelogo@2x~iphone.t7000.im4p...
Personalizing IMG4 component AppleLogo...
Extracting batterycharging0@2x~iphone.t7000.im4p...
Personalizing IMG4 component BatteryCharging0...
Extracting batterycharging1@2x~iphone.t7000.im4p...
Personalizing IMG4 component BatteryCharging1...
Extracting batteryfull@2x~iphone.t7000.im4p...
Personalizing IMG4 component BatteryFull...
Extracting batterylow0@2x~iphone.t7000.im4p...
Personalizing IMG4 component BatteryLow0...
Extracting batterylow1@2x~iphone.t7000.im4p...
Personalizing IMG4 component BatteryLow1...
Extracting glyphplugin@1334~iphone-lightning.t7000.im4p...
Personalizing IMG4 component BatteryPlugin...
Extracting DeviceTree.n61ap.im4p...
Personalizing IMG4 component DeviceTree...
Extracting recoverymode@1334~iphone-lightning.t7000.im4p...
Personalizing IMG4 component RecoveryMode...
Extracting iBoot.n61.RELEASE.im4p...
Personalizing IMG4 component iBoot...
Extracting sep-firmware.n61.RELEASE.im4p...
Personalizing IMG4 component RestoreSEP...
Extracting sep-firmware.n61.RELEASE.im4p...
Personalizing IMG4 component SEP...
Sending NORData now...
Done sending NORData
About to send RootTicket...
Sending RootTicket now...
Done sending RootTicket
Waiting for NAND (28)
Checking filesystems (15)
Checking filesystems (15)
Checking filesystems (15)
Checking filesystems (15)
About to send FDR Trust data...
Sending FDR Trust data now...
Done sending FDR Trust Data
Previous Restore Log Received:
SYSLOG: Jan 1 00:52:52 localhost bootlog[0]
[00:52:56.0423-GMT]{5>7} CHECKPOINT END: MAIN:[0x040E] enable_usb restore-step-ids = {} restore-step-names = {} restore-step-uptime = 4 restore-step-user-progress = 0 waiting for host to trigger start of restore [timeout of 120 seconds] recv(8, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'ValidatePair' unrecognized request 'ValidatePair' [00:52:56.0994-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0600] client_protocol_version restore-step-ids = {0x11030600:11} restore-step-names = {0x11030600:client_protocol_version} restore-step-uptime = 4 restore-step-user-progress = 0 client protocol version 14 [00:52:57.0003-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0600] client_protocol_version restore-step-ids = {} restore-step-names = {} restore-step-uptime = 5 restore-step-user-progress = 0 [00:52:57.0010-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0601] copy_restore_options restore-step-ids = {0x11030601:12} restore-step-names = {0x11030601:copy_restore_options} restore-step-uptime = 5 restore-step-user-progress = 0 UUID 900A5E5F-5C4E-4AD8-1F4C-DED63E6F4423 Restore options: CreateFilesystemPartitions => <CFBoolean 0x100ed80e0 [0x100ed7b88]>{value = true} MinimumSystemPartition => <CFNumber 0xb00000000000e853 [0x100ed7b88]>{value = +3717, type = kCFNumberSInt64Type} UUID => <CFString 0x1037046d0 [0x100ed7b88]>{contents = "900A5E5F-5C4E-4AD8-1F4C-DED63E6F4423"} SystemPartitionSize => <CFNumber 0xb00000000000e853 [0x100ed7b88]>{value = +3717, type = kCFNumberSInt64Type} FormatForAPFS => <CFBoolean 0x100ed80e0 [0x100ed7b88]>{value = true} FormatForLwVM => <CFBoolean 0x100ed80f0 [0x100ed7b88]>{value = false} SystemPartitionPadding => <CFBasicHash 0x103704980 [0x100ed7b88]>{type = mutable dict, count = 9, entries => 0 : 512 = <CFNumber 0xb000000000005003 [0x100ed7b88]>{value = +1280, type = kCFNumberSInt64Type} 2 : 128 = <CFNumber 0xb000000000005003 [0x100ed7b88]>{value = +1280, type = kCFNumberSInt64Type} 3 : 16 = <CFNumber 0xb000000000000a03 [0x100ed7b88]>{value = +160, type = kCFNumberSInt64Type} 4 : 1024 = <CFNumber 0xb000000000005003 [0x100ed7b88]>{value = +1280, type = kCFNumberSInt64Type} 5 : 32 = <CFNumber 0xb000000000001403 [0x100ed7b88]>{value = +320, type = kCFNumberSInt64Type} 6 : 768 = <CFNumber 0xb000000000005003 [0x100ed7b88]>{value = +1280, type = kCFNumberSInt64Type} 7 : 8 = <CFNumber 0xb000000000000503 [0x100ed7b88]>{value = +80, type = kCFNumberSInt64Type} 8 : 64 = <CFNumber 0xb000000000002803 [0x100ed7b88]>{value = +640, type = kCFNumberSInt64Type} 10 : 256 = <CFNumber 0xb000000000005003 [0x100ed7b88]>{value = +1280, type = kCFNumberSInt64Type} }
PersonalizedDuringPreflight => <CFBoolean 0x100ed80e0 [0x100ed7b88]>{value = true}
[00:52:57.0027-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0601] copy_restore_options restore-step-ids = {} restore-step-names = {} restore-step-uptime = 5 restore-step-user-progress = 0 [00:52:57.0032-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0655] is_host_compatible restore-step-ids = {0x11030655:13} restore-step-names = {0x11030655:is_host_compatible} restore-step-uptime = 5 restore-step-user-progress = 0 [00:52:57.0038-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0655] is_host_compatible restore-step-ids = {} restore-step-names = {} restore-step-uptime = 5 restore-step-user-progress = 0 [00:52:57.0043-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0604] set_weight_from_options restore-step-ids = {0x11030604:14} restore-step-names = {0x11030604:set_weight_from_options} restore-step-uptime = 5 restore-step-user-progress = 0 [00:52:57.0048-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0604] set_weight_from_options restore-step-ids = {} restore-step-names = {} restore-step-uptime = 5 restore-step-user-progress = 0 [00:52:57.0053-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0608] device_has_hoover restore-step-ids = {0x11030608:15} restore-step-names = {0x11030608:device_has_hoover} restore-step-uptime = 5 restore-step-user-progress = 0 [00:52:57.0060-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0608] device_has_hoover restore-step-ids = {} restore-step-names = {} restore-step-uptime = 5 restore-step-user-progress = 0 [00:52:57.0065-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0609] load_sep_os restore-step-ids = {0x11030609:16} restore-step-names = {0x11030609:load_sep_os} restore-step-uptime = 5 restore-step-user-progress = 0 entering load_sep_os device has sep - getting firmware entering copy_restore_sep got sep firmware - making call to load it entering ramrod_load_sep_os entering ramrod_execute_command_with_input_data: /usr/libexec/seputil (0x106840000 - 5011046) executing /usr/libexec/seputil --restore+art - waiting for child to exit child exited exit status: 0 [00:53:00.0080-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0609] load_sep_os restore-step-ids = {} restore-step-names = {} restore-step-uptime = 8 restore-step-user-progress = 0 [00:53:00.0082-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x060B] update_ticket restore-step-ids = {0x1103060B:17} restore-step-names = {0x1103060B:update_ticket} restore-step-uptime = 8 restore-step-user-progress = 0 entering ramrod_ticket_update looking up boot manifest hash crypto-hash-method found. Using SHA1 device tree ticket_hash: FA96124302EEE469CD686AB90AA3236F03ECC9B1 computed ticket_hash : FA96124302EEE469CD686AB90AA3236F03ECC9B1 received valid ticket (5576 bytes) [00:53:00.0087-GMT]{5>7} CHECKPOINT END: RESTORED:[0x060B] update_ticket restore-step-ids = {} restore-step-names = {} restore-step-uptime = 8 restore-step-user-progress = 0 [00:53:00.0089-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x060C] partition_nand restore-step-ids = {0x1103060C:18} restore-step-names = {0x1103060C:partition_nand} restore-step-uptime = 8 restore-step-user-progress = 0 entering partition_nand_device [00:53:00.0090-GMT]{5>7} CHECKPOINT END: RESTORED:[0x060C] partition_nand restore-step-ids = {} restore-step-names = {} restore-step-uptime = 8 restore-step-user-progress = 0 [00:53:00.0092-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x060D] wait_for_storage restore-step-ids = {0x1103060D:19} restore-step-names = {0x1103060D:wait_for_storage} restore-step-uptime = 8 restore-step-user-progress = 0 entering wait_for_storage_device ramrod_display_set_granular_progress_forced: 0.000000 Searching for NAND service Found NAND service: ASPStorage NAND initialized. Waiting for devnode. [00:53:00.0096-GMT]{5>7} CHECKPOINT END: RESTORED:[0x060D] wait_for_storage restore-step-ids = {} restore-step-names = {} restore-step-uptime = 8 restore-step-user-progress = 0 [00:53:00.0098-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x060E] update_s3e_firmware restore-step-ids = {0x1103060E:20} restore-step-names = {0x1103060E:update_s3e_firmware} restore-step-uptime = 8 restore-step-user-progress = 0 [00:53:00.0100-GMT]{5>7} CHECKPOINT END: RESTORED:[0x060E] update_s3e_firmware restore-step-ids = {} restore-step-names = {} restore-step-uptime = 8 restore-step-user-progress = 0 [00:53:00.0102-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x060F] clear_remap_variable restore-step-ids = {0x1103060F:21} restore-step-names = {0x1103060F:clear_remap_variable} restore-step-uptime = 8 restore-step-user-progress = 0 entering clear_remap_variable executing /usr/sbin/nvram -d enable-remap-mode enable-remap-mode [00:53:00.0119-GMT]{5>7} CHECKPOINT END: RESTORED:[0x060F] clear_remap_variable restore-step-ids = {} restore-step-names = {} restore-step-uptime = 8 restore-step-user-progress = 0 [00:53:00.0121-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x065C] print_nvram_variables restore-step-ids = {0x1103065C:22} restore-step-names = {0x1103065C:print_nvram_variables} restore-step-uptime = 8 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">
[00:53:00.0138-GMT]{5>7} CHECKPOINT END: RESTORED:[0x065C] print_nvram_variables restore-step-ids = {} restore-step-names = {} restore-step-uptime = 8 restore-step-user-progress = 0 [00:53:00.0139-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0610] asp_nand_set_writable restore-step-ids = {0x11030610:23} restore-step-names = {0x11030610:asp_nand_set_writable} restore-step-uptime = 8 restore-step-user-progress = 0 Service name : ASPStorage Set ASP writable successfully [00:53:00.0145-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0610] asp_nand_set_writable restore-step-ids = {} restore-step-names = {} restore-step-uptime = 8 restore-step-user-progress = 0 [00:53:00.0147-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0611] reprobe_storage restore-step-ids = {0x11030611:24} restore-step-names = {0x11030611:reprobe_storage} restore-step-uptime = 8 restore-step-user-progress = 0 entering ramrod_reprobe_device_path entering ramrod_probe_media device partitioning scheme is GPT APFS Container /dev/disk0s1 device is APFS formatted find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log= update= [00:53:00.0164-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0611] reprobe_storage restore-step-ids = {} restore-step-names = {} restore-step-uptime = 8 restore-step-user-progress = 0 [00:53:00.0165-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0612] reprobe_firmware restore-step-ids = {0x11030612:25} restore-step-names = {0x11030612:reprobe_firmware} restore-step-uptime = 8 restore-step-user-progress = 0 entering ramrod_reprobe_device_path entering ramrod_probe_media device partitioning scheme is GPT APFS Container /dev/disk0s1 device is APFS formatted find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log= update= [00:53:00.0173-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0612] reprobe_firmware restore-step-ids = {} restore-step-names = {} restore-step-uptime = 8 restore-step-user-progress = 0 [00:53:00.0175-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0613] find_filesystem_partitions restore-step-ids = {0x11030613:26} restore-step-names = {0x11030613:find_filesystem_partitions} restore-step-uptime = 8 restore-step-user-progress = 0 entering ramrod_probe_media device partitioning scheme is GPT APFS Container /dev/disk0s1 device is APFS formatted find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log= update= entering fsck_filesystems ramrod_display_set_granular_progress_forced: 0.000000 executing /System/Library/Filesystems/apfs.fs/fsck_apfs -dy /dev/disk0s1s1 Checking volume. Checking the container superblock. Checking the space manager. Checking the object map. Checking the APFS volume superblock. warning: apfs_sb at apfs_fs_index (0): apfs_formatted_by.timestamp is invalid (1489569127000000000), is greater than current time (3180209314000) Checking the object map. Checking the fsroot tree. recv(10, 4) failed: connection closed unable to read message size: -1 could not receive message recv(8, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'ValidatePair' recv(10, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'ValidatePair' recv(8, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'ValidatePair' Checking the snapshot metadata tree. Checking the extent ref tree. Checking the snapshots. The volume /dev/disk0s1s1 appears to be OK. fsck succeeded on /dev/disk0s1s1 ramrod_display_set_granular_progress_forced: 0.000000 executing /System/Library/Filesystems/apfs.fs/fsck_apfs -dy /dev/disk0s1s2 Checking volume. Checking the container superblock. Checking the space manager. Checking the object map. Checking the APFS volume superblock. Checking the object map. dev_init:1470: Using /private/var/apfs and /private/var/nx for device-io. Checking the fsroot tree. Checking the snapshot metadata tree. Checking the extent ref tree. Checking the snapshots. The volume /dev/disk0s1s2 appears to be OK. fsck succeeded on /dev/disk0s1s2 create_baseband_update_options: Got imeisv: 19 baseband updater options = <CFBasicHash 0x10360f550 [0x100ed7b88]>{type = mutable dict, count = 4, entries => 0 : <CFString 0x100363b80 [0x100ed7b88]>{contents = "IMEISwVersion"} = <CFNumber 0xb000000000000132 [0x100ed7b88]>{value = +19, type = kCFNumberSInt32Type} 1 : <CFString 0x100363b40 [0x100ed7b88]>{contents = "restoredInternal"} = <CFBoolean 0x100ed80f0 [0x100ed7b88]>{value = false} 2 : <CFString 0x100363ca0 [0x100ed7b88]>{contents = "autoSetupEnv"} = <CFBoolean 0x100ed80f0 [0x100ed7b88]>{value = false} 6 : <CFString 0x100363cc0 [0x100ed7b88]>{contents = "disablePing"} = <CFBoolean 0x100ed80e0 [0x100ed7b88]>{value = true} }
_baseband_update_requires_filesystems_once: querying baseband to determine if it requires system filesystem bbupdater: BEGIN: Command: requirePartitionMount bbupdater: cmdCopyRequirePartitionMount: supports flashless 1 bbupdater: cmdCopyRequirePartitionMount: supports REFS 1 bbupdater: END: Command: requirePartitionMount _baseband_update_requires_filesystems_once: query returned <CFBasicHash 0x10360f9a0 [0x100ed7b88]>{type = mutable dict, count = 3, entries => 0 : <CFString 0x100363520 [0x100ed7b88]>{contents = "requireUserPartitionMount"} = <CFBoolean 0x100ed80e0 [0x100ed7b88]>{value = true} 1 : <CFString 0x100363540 [0x100ed7b88]>{contents = "requestedBasebandFSPartitionMountSize"} = <CFNumber 0xb0000000000000a3 [0x100ed7b88]>{value = +10, type = kCFNumberSInt64Type} 2 : <CFString 0x1003634e0 [0x100ed7b88]>{contents = "requireSystemPartitionMount"} = <CFBoolean 0x100ed80e0 [0x100ed7b88]>{value = true} }
ramrod_display_set_granular_progress_forced: 0.000000 executing /System/Library/Filesystems/apfs.fs/fsck_apfs -dy /dev/disk0s1s3 Checking volume. Checking the container superblock. Checking the space manager. Checking the object map. Checking the APFS volume superblock. Checking the object map. Checking the fsroot tree. Checking the snapshot metadata tree. Checking the extent ref tree. Checking the snapshots. ** The volume /dev/disk0s1s3 appears to be OK. fsck succeeded on /dev/disk0s1s3 leaving fsck_filesystems, returning 0 [00:53:36.0289-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0613] find_filesystem_partitions restore-step-ids = {} restore-step-names = {} restore-step-uptime = 44 restore-step-user-progress = 0 [00:53:36.0295-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0614] mount_system_partition restore-step-ids = {0x11030614:27} restore-step-names = {0x11030614:mount_system_partition} restore-step-uptime = 44 restore-step-user-progress = 0 ramrod_display_set_granular_progress_forced: 0.000000 entering mount_partition executing /System/Library/Filesystems/apfs.fs/mount_apfs -o rdonly /dev/disk0s1s1 /mnt1 /dev/disk0s1s1 mounted on /mnt1 System mounted read-only [00:53:36.0350-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0614] mount_system_partition restore-step-ids = {} restore-step-names = {} restore-step-uptime = 44 restore-step-user-progress = 0 [00:53:36.0356-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0654] read_previous_os_build_version restore-step-ids = {0x11030654:28} restore-step-names = {0x11030654:read_previous_os_build_version} restore-step-uptime = 44 restore-step-user-progress = 0 ramrod_read_previous_os_build_version: previous OS version: (null) [00:53:36.0367-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0654] read_previous_os_build_version restore-step-ids = {} restore-step-names = {} restore-step-uptime = 44 restore-step-user-progress = 0 [00:53:36.0372-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0615] copy_hardware_info restore-step-ids = {0x11030615:29} restore-step-names = {0x11030615:copy_hardware_info} restore-step-uptime = 44 restore-step-user-progress = 0 [00:53:36.0377-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0615] copy_hardware_info restore-step-ids = {} restore-step-names = {} restore-step-uptime = 44 restore-step-user-progress = 0 [00:53:36.0382-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0616] fdr_create restore-step-ids = {0x11030616:30} restore-step-names = {0x11030616:fdr_create} restore-step-uptime = 44 restore-step-user-progress = 0 restored_fdr_initialize: FDR is supported CryptoAcceleratorEncrypt: perform aes => 0 (kIOReturnSuccess) pseudo_ccrng_allocate: ccdrbg_init() -> 0 recv(10, 4) failed: connection closed unable to read message size: -1 could not receive message unrecognized request 'ValidatePair' CryptoGenerateRSAKeys: ccrsa_generate_key() -> 0 CryptoGenerateRSAKeys: [RSA Public Key SHA1: 20 bytes] : 4b 02 e6 6e 84 14 c1 19 2b 41 85 9f 57 3b f9 e4 : 7f 84 a5 2f : ----------------------------------------------- CryptoGenerateRSAKeys: SecKeyCreateRSAPrivateKey -> 0x1000ff5c8 CryptoGenerateRSAKeys: SecKeyCreateRSAPublicKey -> 0x1000ff5c0 bbupdater: 3218.773[0.0]BBUpdaterController::: Entering cmdCopyIdentifier created HTTP FDR client 0x1036149d0 created local FDR client 0x103616680 Received response without expected RESTORED_FDR_TRUST_DATA Found local trust object, computing digest.. Local trust object digest DOES match AP ticket. Not enabling SSO for FDR RestoredFDRCreate() returned 0 FDR is supported on this device [00:53:38.0821-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0616] fdr_create restore-step-ids = {} restore-step-names = {} restore-step-uptime = 46 restore-step-user-progress = 0 [00:53:38.0826-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0617] fdr_save_data restore-step-ids = {0x11030617:31} restore-step-names = {0x11030617:fdr_save_data} restore-step-uptime = 46 restore-step-user-progress = 0 _AMFDRSealingMapCopyDataClassesAndInstances: amfdr data version changed. Old version: (null) new version 1 AMFDRSealedDataPopulate: IM4M found, storing in options. AMSupportPlatformCreateBufferFromNativeFilePath: open failed: No such file or directory AMSupportPlatformCreateBufferFromNativeFilePath: /mnt1/System/Library/Caches/com.apple.factorydata/bbpc-0B02866F AMFDRCreateError: AMFDRDataLocalCopy: Failed to read data: code=4 AMFDRCreateError: AMFDRDataCopy: bbpc:0B02866F get data failed - foundData is NULL: code=10 AMFDRSealedDataPopulate: Failed to fetch data for bbpc:0B02866F, but it is not required for sealing AMFDRSetOptions: amfdr is NULL saved FDR persistent state RestoredFDRSaveData success [00:53:38.0879-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0617] fdr_save_data restore-step-ids = {} restore-step-names = {} restore-step-uptime = 46 restore-step-user-progress = 0 [00:53:38.0887-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0618] store_baseband_migration restore-step-ids = {0x11030618:32} restore-step-names = {0x11030618:store_baseband_migration} restore-step-uptime = 46 restore-step-user-progress = 0 entering ramrod_probe_media device partitioning scheme is GPT APFS Container /dev/disk0s1 device is APFS formatted find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log= update= could not locate filesystems: 0, ignoring error for an erase install [00:53:38.0906-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0618] store_baseband_migration restore-step-ids = {} restore-step-names = {} restore-step-uptime = 46 restore-step-user-progress = 0 [00:53:38.0914-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0619] check_for_restore_log restore-step-ids = {0x11030619:33} restore-step-names = {0x11030619:check_for_restore_log} restore-step-uptime = 46 restore-step-user-progress = 0 entering check_for_restore_log restore log does not exist [00:53:38.0924-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0619] check_for_restore_log restore-step-ids = {} restore-step-names = {} restore-step-uptime = 46 restore-step-user-progress = 0 [00:53:38.0932-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x061A] check_for_ota_logs restore-step-ids = {0x1103061A:34} restore-step-names = {0x1103061A:check_for_ota_logs} restore-step-uptime = 46 restore-step-user-progress = 0 entering mount_partition executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s2 /mnt2 /dev/disk0s1s2 mounted on /mnt2 Data mounted read-write entering dump_panic_logs executing /System/Library/CoreServices/DumpPanic -r/mnt2/mobile/Library/Logs/CrashReporter entering check_for_ota_logs_with_mount_point OTA logs will be preserved through the erase install restore log does not exist failed to read user settings from: /mnt2/mobile/Library/UserConfigurationProfiles/EffectiveUserSettings.plist. trying legacy path restore log does not exist skipping log submission, failed to read user settings from: /mnt2/mobile/Library/ConfigurationProfiles/EffectiveUserSettings.plist Diagnostic log submission disabled Searching /mnt2/mobile/Library/Logs/CrashReporter/ check_for_ota_logs_with_mount_point complete Successfully checked for OTA logs [00:53:39.0312-GMT]{5>7} CHECKPOINT END: RESTORED:[0x061A] check_for_ota_logs restore-step-ids = {} restore-step-names = {} restore-step-uptime = 47 restore-step-user-progress = 0 [00:53:39.0318-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x061B] submit_update_stats restore-step-ids = {0x1103061B:35} restore-step-names = {0x1103061B:submit_update_stats} restore-step-uptime = 47 restore-step-user-progress = 0 entering mount_partition executing /sbin/mount -u -w /dev/disk0s1s1 /mnt1 Changed permissions on /dev/disk0s1s1 mounted at /mnt1 to readwrite entering mount_partition Data already mounted read-write (mount ignored) AMSupportHttpCopyProxySettings_block_invoke: Proxy available AMSupportHttpCopyProxySettings_block_invoke: AMSupportHttpCopyProxySettings: RPNotificationProxyAvailable AMSupportHttpCopyProxySettings_block_invoke: AMSupportHttpCopyProxySettings: RPNotificationAborted AMSupportHttpCopyProxySettings: proxyInfo = <CFBasicHash 0x103718060 [0x100ed7b88]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x100eb09f0 [0x100ed7b88]>{contents = "SOCKSProxy"} = <CFString 0x10014cc50 [0x100ed7b88]>{contents = "127.0.0.1"} 1 : <CFString 0x100eb0a10 [0x100ed7b88]>{contents = "SOCKSPort"} = <CFNumber 0xb000000000004392 [0x100ed7b88]>{value = +1081, type = kCFNumberSInt32Type} }
calling UMEventShimSubmitEvents : start
: ignoring call to recordPostUpdateEvent when no install is in progress
UMEventShimSubmitEvents done
[00:53:39.0449-GMT]{5>7} CHECKPOINT END: RESTORED:[0x061B] submit_update_stats
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 47
restore-step-user-progress = 0
[00:53:39.0451-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x061D] unmount_unconditionally
restore-step-ids = {0x1103061D:36}
restore-step-names = {0x1103061D:unmount_unconditionally}
restore-step-uptime = 47
restore-step-user-progress = 0
entering unmount_filesystems
ramrod_display_set_granular_progress_forced: 1.000000
entering unmount_partition
System unmounted
ramrod_display_set_granular_progress_forced: 1.000000
entering unmount_partition
Data unmounted
ramrod_display_set_granular_progress_forced: 1.000000
entering unmount_partition
Baseband Data not mounted (unmount ignored)
[00:53:39.0492-GMT]{5>7} CHECKPOINT END: RESTORED:[0x061D] unmount_unconditionally
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 47
restore-step-user-progress = 1
[00:53:39.0495-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x064E] clean_nand
restore-step-ids = {0x1103064E:37}
restore-step-names = {0x1103064E:clean_nand}
restore-step-uptime = 47
restore-step-user-progress = 1
entering clean_NAND
NAND format complete
entering ramrod_probe_media
find_filesystem_partitions: storage=/dev/disk0 container= system= data= baseband data= log= update=
[00:53:40.0129-GMT]{5>7} CHECKPOINT END: RESTORED:[0x064E] clean_nand
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0135-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0653] create_readwrite_ramdisk
restore-step-ids = {0x11030653:38}
restore-step-names = {0x11030653:create_readwrite_ramdisk}
restore-step-uptime = 48
restore-step-user-progress = 1
executing /usr/sbin/hdik -nomount ram://5120
/dev/disk4
executing /sbin/newfs_hfs -J -v readwrite_ramdisk /dev/disk4
Initialized /dev/rdisk4 as a 3 MB case-insensitive HFS Plus volume with a 512k journal
executing /sbin/fsck_hfs -fdy /dev/disk4
journal_replay(/dev/disk4) returned 0
/dev/rdisk4
Using cacheBlockSize=32K cacheTotalBlock=3956 cacheSize=126592K.
Executing fsck_hfs (version hfs-366.50.19).
Checking Journaled HFS Plus volume.
The volume name is readwrite_ramdisk
Checking extents overflow file.
Checking catalog file.
Checking multi-linked files.
Checking catalog hierarchy.
Checking extended attributes file.
Checking volume bitmap.
Checking volume information.
The volume readwrite_ramdisk appears to be OK.
CheckHFS returned 0, fsmodified = 0
executing /sbin/mount_hfs /dev/disk4 /mnt5
mount_hfs: Could not create property for re-key environment check: No such file or directory
[00:53:40.0385-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0653] create_readwrite_ramdisk
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0389-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x061F] format_effaceable_storage
restore-step-ids = {0x1103061F:39}
restore-step-names = {0x1103061F:format_effaceable_storage}
restore-step-uptime = 48
restore-step-user-progress = 1
entering format_effaceable_storage
effaceable storage is formatted, clearing it
effaceable storaged cleared
[00:53:40.0415-GMT]{5>7} CHECKPOINT END: RESTORED:[0x061F] format_effaceable_storage
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0419-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0643] get_apfs_options
restore-step-ids = {0x11030643:40}
restore-step-names = {0x11030643:get_apfs_options}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0421-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0643] get_apfs_options
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0424-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0620] format_storage_for_lwvm
restore-step-ids = {0x11030620:41}
restore-step-names = {0x11030620:format_storage_for_lwvm}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0427-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0620] format_storage_for_lwvm
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0430-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0621] create_filesystem_partitions
restore-step-ids = {0x11030621:42}
restore-step-names = {0x11030621:create_filesystem_partitions}
restore-step-uptime = 48
restore-step-user-progress = 1
APFS Erase Install
entering create_partition_for_apfs
ramrod_display_set_granular_progress_forced: 1.000000
[00:53:40.0490-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0621] create_filesystem_partitions
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0495-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x065E] update_partitions_for_apfs
restore-step-ids = {0x1103065E:43}
restore-step-names = {0x1103065E:update_partitions_for_apfs}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0500-GMT]{5>7} CHECKPOINT END: RESTORED:[0x065E] update_partitions_for_apfs
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0505-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0622] reload_filesystem_partitions
restore-step-ids = {0x11030622:44}
restore-step-names = {0x11030622:reload_filesystem_partitions}
restore-step-uptime = 48
restore-step-user-progress = 1
entering ramrod_probe_media
device partitioning scheme is GPT
APFS Container /dev/disk0s1
find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system= data= baseband data= log= update=
[00:53:40.0514-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0622] reload_filesystem_partitions
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0516-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x065D] delete_mobilebackups
restore-step-ids = {0x1103065D:45}
restore-step-names = {0x1103065D:delete_mobilebackups}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0519-GMT]{5>7} CHECKPOINT END: RESTORED:[0x065D] delete_mobilebackups
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0522-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0645] check_not_apfs_mount
restore-step-ids = {0x11030645:46}
restore-step-names = {0x11030645:check_not_apfs_mount}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0525-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0645] check_not_apfs_mount
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0527-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0624] restore_images
restore-step-ids = {0x11030624:47}
restore-step-names = {0x11030624:restore_images}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0530-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0624] restore_images
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0533-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0625] find_partitions_after_images
restore-step-ids = {0x11030625:48}
restore-step-names = {0x11030625:find_partitions_after_images}
restore-step-uptime = 48
restore-step-user-progress = 1
entering ramrod_probe_media
device partitioning scheme is GPT
APFS Container /dev/disk0s1
find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system= data= baseband data= log= update=
[00:53:40.0538-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0625] find_partitions_after_images
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0541-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x064F] check_apfs_format
restore-step-ids = {0x1103064F:49}
restore-step-names = {0x1103064F:check_apfs_format}
restore-step-uptime = 48
restore-step-user-progress = 1
[00:53:40.0544-GMT]{5>7} CHECKPOINT BEGIN: APFS_RELOAD:[0x0C00] format_media
restore-step-ids = {0x1103064F:49;0x11030C00:50}
restore-step-names = {0x1103064F:check_apfs_format;0x11030C00:format_media}
restore-step-uptime = 48
restore-step-user-progress = 1
entering create_apfs_filesystems
entering ramrod_probe_media
device partitioning scheme is GPT
APFS Container /dev/disk0s1
find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system= data= baseband data= log= update=
ramrod_display_set_granular_progress_forced: 1.000000
/System/Library/Filesystems/apfs.fs/newfs_apfs -C /dev/disk0s1
executing /System/Library/Filesystems/apfs.fs/newfs_apfs -C /dev/disk0s1
spaceman_metazone_init:326: spaceman metazone of size 524288 blocks (encrypted: 15100701-15362845 unencrypted: 15362845-15624989)
block size for /dev/disk0s1: 4096
/System/Library/Filesystems/apfs.fs/newfs_apfs -A -o fsindex=0 -v System /dev/disk0s1
executing /System/Library/Filesystems/apfs.fs/newfs_apfs -A -o fsindex=0 -v System /dev/disk0s1
creating encrypted data partition
block size for /dev/disk0s1: 4096
/System/Library/Filesystems/apfs.fs/newfs_apfs -A -o fsindex=1 -v Data -P /dev/disk0s1
executing /System/Library/Filesystems/apfs.fs/newfs_apfs -A -o fsindex=1 -v Data -P /dev/disk0s1
block size for /dev/disk0s1: 4096
/System/Library/Filesystems/apfs.fs/newfs_apfs -s 10485760 -A -o fsindex=2 -v Baseband Data /dev/disk0s1
executing /System/Library/Filesystems/apfs.fs/newfs_apfs -s 10485760 -A -o fsindex=2 -v Baseband Data /dev/disk0s1
[00:53:42.0402-GMT]{5>7} CHECKPOINT END: APFS_RELOAD:[0x0C00] format_media
restore-step-ids = {0x1103064F:49}
restore-step-names = {0x1103064F:check_apfs_format}
restore-step-uptime = 50
restore-step-user-progress = 1
[00:53:42.0404-GMT]{5>7} CHECKPOINT BEGIN: APFS_RELOAD:[0x0C01] find_partitions
restore-step-ids = {0x1103064F:49;0x11030C01:51}
restore-step-names = {0x1103064F:check_apfs_format;0x11030C01:find_partitions}
restore-step-uptime = 50
restore-step-user-progress = 1
entering ramrod_probe_media
device partitioning scheme is GPT
APFS Container /dev/disk0s1
device is APFS formatted
find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log= update=
[00:53:42.0408-GMT]{5>7} CHECKPOINT END: APFS_RELOAD:[0x0C01] find_partitions
restore-step-ids = {0x1103064F:49}
restore-step-names = {0x1103064F:check_apfs_format}
restore-step-uptime = 50
restore-step-user-progress = 1
[00:53:42.0410-GMT]{5>7} CHECKPOINT BEGIN: APFS_RELOAD:[0x0C06] reserve_overprov_space
restore-step-ids = {0x1103064F:49;0x11030C06:52}
restore-step-names = {0x1103064F:check_apfs_format;0x11030C06:reserve_overprov_space}
restore-step-uptime = 50
restore-step-user-progress = 1
executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s2 /mnt2
/dev/disk0s1s2 mounted on /mnt2
device_size = 64000000000 (59 GB)
file_size = 640000000 (0 GB)
recv(8, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
[00:53:47.0555-GMT]{5>7} CHECKPOINT END: APFS_RELOAD:[0x0C06] reserve_overprov_space
restore-step-ids = {0x1103064F:49}
restore-step-names = {0x1103064F:check_apfs_format}
restore-step-uptime = 55
restore-step-user-progress = 1
[00:53:47.0558-GMT]{5>7} CHECKPOINT BEGIN: APFS_RELOAD:[0x0C03] baseband_migration_data
restore-step-ids = {0x1103064F:49;0x11030C03:53}
restore-step-names = {0x1103064F:check_apfs_format;0x11030C03:baseband_migration_data}
restore-step-uptime = 55
restore-step-user-progress = 1
[00:53:47.0559-GMT]{5>7} CHECKPOINT END: APFS_RELOAD:[0x0C03] baseband_migration_data
restore-step-ids = {0x1103064F:49}
restore-step-names = {0x1103064F:check_apfs_format}
restore-step-uptime = 55
restore-step-user-progress = 1
[00:53:47.0561-GMT]{5>7} CHECKPOINT BEGIN: APFS_RELOAD:[0x0C05] restore_invert
restore-step-ids = {0x1103064F:49;0x11030C05:54}
restore-step-names = {0x1103064F:check_apfs_format;0x11030C05:restore_invert}
restore-step-uptime = 55
restore-step-user-progress = 1
restore_apfs_image : started restoring an APFS image
entering mount_partition
executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s1 /mnt1
/dev/disk0s1s1 mounted on /mnt1
System mounted read-write
Successfully marked device node /dev/rdisk0s1s1 as static data
OS digest = 76d8a82f8412375d56cf730e1ad512a978d35bc2
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 76d8a82f8412375d56cf730e1ad512a978d35bc2
ASR STATUS: start 404 multicast-client
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: 6.000000
recv(15, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 4%
ramrod_display_set_granular_progress_forced: 6.000000
ASR RESTORE PROGRESS: 6%
ramrod_display_set_granular_progress_forced: 7.000000
recv(13, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 8%
ramrod_display_set_granular_progress_forced: 8.000000
ASR RESTORE PROGRESS: 10%
ramrod_display_set_granular_progress_forced: 8.000000
ASR RESTORE PROGRESS: 12%
ramrod_display_set_granular_progress_forced: 9.000000
recv(14, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 14%
ramrod_display_set_granular_progress_forced: 9.000000
ASR RESTORE PROGRESS: 16%
ramrod_display_set_granular_progress_forced: 10.000000
recv(13, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 18%
ramrod_display_set_granular_progress_forced: 10.000000
ASR RESTORE PROGRESS: 20%
ramrod_display_set_granular_progress_forced: 11.000000
recv(14, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 22%
ramrod_display_set_granular_progress_forced: 11.000000
ASR RESTORE PROGRESS: 24%
ramrod_display_set_granular_progress_forced: 12.000000
recv(13, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 26%
ramrod_display_set_granular_progress_forced: 12.000000
ASR RESTORE PROGRESS: 28%
ramrod_display_set_granular_progress_forced: 13.000000
recv(14, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 30%
ramrod_display_set_granular_progress_forced: 13.000000
ASR RESTORE PROGRESS: 32%
ramrod_display_set_granular_progress_forced: 14.000000
recv(13, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 34%
ramrod_display_set_granular_progress_forced: 14.000000
ASR RESTORE PROGRESS: 36%
ramrod_display_set_granular_progress_forced: 15.000000
ASR RESTORE PROGRESS: 38%
ramrod_display_set_granular_progress_forced: 15.000000
recv(14, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 40%
ramrod_display_set_granular_progress_forced: 16.000000
ASR RESTORE PROGRESS: 42%
ramrod_display_set_granular_progress_forced: 17.000000
recv(13, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 44%
ramrod_display_set_granular_progress_forced: 17.000000
ASR RESTORE PROGRESS: 46%
ramrod_display_set_granular_progress_forced: 18.000000
recv(14, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 48%
ramrod_display_set_granular_progress_forced: 18.000000
ASR RESTORE PROGRESS: 50%
ramrod_display_set_granular_progress_forced: 19.000000
recv(13, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 52%
ramrod_display_set_granular_progress_forced: 19.000000
ASR RESTORE PROGRESS: 54%
ramrod_display_set_granular_progress_forced: 20.000000
ASR RESTORE PROGRESS: 56%
ramrod_display_set_granular_progress_forced: 20.000000
ASR RESTORE PROGRESS: 58%
ramrod_display_set_granular_progress_forced: 21.000000
recv(14, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 60%
ramrod_display_set_granular_progress_forced: 21.000000
ASR RESTORE PROGRESS: 62%
ramrod_display_set_granular_progress_forced: 22.000000
ASR RESTORE PROGRESS: 64%
ramrod_display_set_granular_progress_forced: 22.000000
ASR RESTORE PROGRESS: 66%
ramrod_display_set_granular_progress_forced: 23.000000
ASR RESTORE PROGRESS: 68%
ramrod_display_set_granular_progress_forced: 23.000000
ASR RESTORE PROGRESS: 70%
ramrod_display_set_granular_progress_forced: 24.000000
recv(13, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 72%
ramrod_display_set_granular_progress_forced: 24.000000
ASR RESTORE PROGRESS: 74%
ramrod_display_set_granular_progress_forced: 25.000000
ASR RESTORE PROGRESS: 76%
ramrod_display_set_granular_progress_forced: 26.000000
recv(14, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 78%
ramrod_display_set_granular_progress_forced: 26.000000
ASR RESTORE PROGRESS: 80%
ramrod_display_set_granular_progress_forced: 27.000000
recv(13, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 82%
ramrod_display_set_granular_progress_forced: 27.000000
ASR RESTORE PROGRESS: 84%
ramrod_display_set_granular_progress_forced: 28.000000
recv(14, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 86%
ramrod_display_set_granular_progress_forced: 28.000000
ASR RESTORE PROGRESS: 88%
ramrod_display_set_granular_progress_forced: 29.000000
ASR RESTORE PROGRESS: 90%
ramrod_display_set_granular_progress_forced: 29.000000
ASR RESTORE PROGRESS: 92%
ramrod_display_set_granular_progress_forced: 30.000000
ASR RESTORE PROGRESS: 94%
ramrod_display_set_granular_progress_forced: 30.000000
ASR RESTORE PROGRESS: 96%
ramrod_display_set_granular_progress_forced: 31.000000
recv(13, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR RESTORE PROGRESS: 98%
ramrod_display_set_granular_progress_forced: 31.000000
ASR RESTORE PROGRESS: 100%
ramrod_display_set_granular_progress_forced: 32.000000
ASR: Copied 3898081280 bytes in 177.67 seconds, 21425.29 KiB/s
ASR STATUS: verify
ASR VERIFY PROGRESS: 2%
ramrod_display_set_granular_progress_forced: 32.000000
ASR VERIFY PROGRESS: 4%
ramrod_display_set_granular_progress_forced: 33.000000
ASR VERIFY PROGRESS: 6%
ramrod_display_set_granular_progress_forced: 33.000000
ASR VERIFY PROGRESS: 8%
ramrod_display_set_granular_progress_forced: 33.000000
ASR VERIFY PROGRESS: 10%
ramrod_display_set_granular_progress_forced: 34.000000
ASR VERIFY PROGRESS: 12%
ramrod_display_set_granular_progress_forced: 34.000000
ASR VERIFY PROGRESS: 14%
ramrod_display_set_granular_progress_forced: 35.000000
ASR VERIFY PROGRESS: 16%
ramrod_display_set_granular_progress_forced: 35.000000
ASR VERIFY PROGRESS: 18%
ramrod_display_set_granular_progress_forced: 35.000000
ASR VERIFY PROGRESS: 20%
ramrod_display_set_granular_progress_forced: 36.000000
ASR VERIFY PROGRESS: 22%
ramrod_display_set_granular_progress_forced: 36.000000
ASR VERIFY PROGRESS: 24%
ramrod_display_set_granular_progress_forced: 36.000000
ASR VERIFY PROGRESS: 26%
ramrod_display_set_granular_progress_forced: 37.000000
ASR VERIFY PROGRESS: 28%
ramrod_display_set_granular_progress_forced: 37.000000
ASR VERIFY PROGRESS: 30%
ramrod_display_set_granular_progress_forced: 38.000000
ASR VERIFY PROGRESS: 32%
ramrod_display_set_granular_progress_forced: 38.000000
ASR VERIFY PROGRESS: 34%
ramrod_display_set_granular_progress_forced: 38.000000
ASR VERIFY PROGRESS: 36%
ramrod_display_set_granular_progress_forced: 39.000000
ASR VERIFY PROGRESS: 38%
ramrod_display_set_granular_progress_forced: 39.000000
ASR VERIFY PROGRESS: 40%
ramrod_display_set_granular_progress_forced: 40.000000
ASR VERIFY PROGRESS: 42%
ramrod_display_set_granular_progress_forced: 40.000000
ASR VERIFY PROGRESS: 44%
ramrod_display_set_granular_progress_forced: 40.000000
ASR VERIFY PROGRESS: 46%
ramrod_display_set_granular_progress_forced: 41.000000
ASR VERIFY PROGRESS: 48%
ramrod_display_set_granular_progress_forced: 41.000000
ASR VERIFY PROGRESS: 50%
ramrod_display_set_granular_progress_forced: 41.000000
ASR VERIFY PROGRESS: 52%
ramrod_display_set_granular_progress_forced: 42.000000
ASR VERIFY PROGRESS: 54%
ramrod_display_set_granular_progress_forced: 42.000000
ASR VERIFY PROGRESS: 56%
ramrod_display_set_granular_progress_forced: 43.000000
ASR VERIFY PROGRESS: 58%
ramrod_display_set_granular_progress_forced: 43.000000
ASR VERIFY PROGRESS: 60%
ramrod_display_set_granular_progress_forced: 43.000000
ASR VERIFY PROGRESS: 62%
ramrod_display_set_granular_progress_forced: 44.000000
ASR VERIFY PROGRESS: 64%
ramrod_display_set_granular_progress_forced: 44.000000
ASR VERIFY PROGRESS: 66%
ramrod_display_set_granular_progress_forced: 44.000000
ASR VERIFY PROGRESS: 68%
ramrod_display_set_granular_progress_forced: 45.000000
ASR VERIFY PROGRESS: 70%
ramrod_display_set_granular_progress_forced: 45.000000
ASR VERIFY PROGRESS: 72%
ramrod_display_set_granular_progress_forced: 46.000000
ASR VERIFY PROGRESS: 74%
ramrod_display_set_granular_progress_forced: 46.000000
ASR VERIFY PROGRESS: 76%
ramrod_display_set_granular_progress_forced: 46.000000
ASR VERIFY PROGRESS: 78%
ramrod_display_set_granular_progress_forced: 47.000000
ASR VERIFY PROGRESS: 80%
ramrod_display_set_granular_progress_forced: 47.000000
ASR VERIFY PROGRESS: 82%
ramrod_display_set_granular_progress_forced: 48.000000
recv(14, 4) failed: connection closed
unable to read message size: -1
unrecognized request 'ValidatePair'
could not receive message
ASR VERIFY PROGRESS: 84%
ramrod_display_set_granular_progress_forced: 48.000000
ASR VERIFY PROGRESS: 86%
ramrod_display_set_granular_progress_forced: 48.000000
ASR VERIFY PROGRESS: 88%
ramrod_display_set_granular_progress_forced: 49.000000
ASR VERIFY PROGRESS: 90%
ramrod_display_set_granular_progress_forced: 49.000000
ASR VERIFY PROGRESS: 92%
ramrod_display_set_granular_progress_forced: 49.000000
ASR VERIFY PROGRESS: 94%
ramrod_display_set_granular_progress_forced: 50.000000
ASR VERIFY PROGRESS: 96%
ramrod_display_set_granular_progress_forced: 50.000000
ASR VERIFY PROGRESS: 98%
ramrod_display_set_granular_progress_forced: 51.000000
ASR VERIFY PROGRESS: 100%
ramrod_display_set_granular_progress_forced: 51.000000
ASR: Calculated SHA-1 checksum 3898081280 bytes in 9.75 seconds, 390246.66 KiB/s
ASR STATUS: finish
entering 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
ASR: Mounting outer volume (/dev/disk0s1 s1)...
ASR: nx_mount:846: Container cleanly-unmounted flag set.
ASR: spaceman_metazone_init:326: spaceman metazone of size 524288 blocks (encrypted: 15100701-15362845 unencrypted: 15362845-15624989)
ASR: nx_mount:965: checkpoint search: largest xid 15, best xid 15 @ 29
ASR: nx_mount:1027: sanity checking all nx state... please be patient.
ASR: nx_check:549: skipping check of fs[1] oid 1030 fs root tree because it's encrypted
ASR: Getting image dstream info...
ASR: apfs_invert_asr_img: dstream_id=16, size=3898081280
ASR: Mounting inner volume (apfs_invert_asr_img)...
ASR: spaceman_metazone_init:262: not creating spaceman metazone, container is only 3898081280 bytes, block_size 4096 block count 951680
ASR: nx_mount:965: checkpoint search: largest xid 7, best xid 7 @ 13
ASR: nx_mount:1027: sanity checking all nx state... please be patient.
ASR: Copying inner volume extentref tree into outer volume...
ASR: Copied 17388 original extents and created 3218 new extents
ASR: Copying inner volume fsroot tree into outer volume...
recv(13, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR: Copied 502973 fs_root records plus 3218 new file extents
ASR: Cleaning up unused blocks...
ASR: Freed 111720 data blocks and kept 839960 data blocks
ASR: Updating the superblock...
ASR: Deleting old fs_root...
ASR: Deleting old extentref tree...
ASR: Finishing transaction...
recv(8, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
recv(13, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
ASR: Unmounting...
ASR: Success!
invert_apfs_image : succeeded inverting : /System/Library/Filesystems/apfs.fs/apfs_invert /dev/disk0s1 0 apfs_invert_asr_img
[00:57:29.0828-GMT]{5>7} CHECKPOINT END: APFS_RELOAD:[0x0C05] restore_invert
restore-step-ids = {0x1103064F:49}
restore-step-names = {0x1103064F:check_apfs_format}
restore-step-uptime = 277
restore-step-user-progress = 51
[00:57:29.0831-GMT]{5>7} CHECKPOINT END: RESTORED:[0x064F] check_apfs_format
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 277
restore-step-user-progress = 51
[00:57:29.0833-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0626] mount_filesystem_if_fdr
restore-step-ids = {0x11030626:55}
restore-step-names = {0x11030626:mount_filesystem_if_fdr}
restore-step-uptime = 277
restore-step-user-progress = 51
entering fsck_filesystems
ramrod_display_set_granular_progress_forced: 51.000000
executing /System/Library/Filesystems/apfs.fs/fsck_apfs -dy /dev/disk0s1s1
Checking volume.
Checking the container superblock.
Checking the space manager.
Checking the object map.
Checking the APFS volume superblock.
warning: apfs_sb at apfs_fs_index (0): apfs_formatted_by.timestamp is invalid (1489569127000000000), is greater than current time (3449960443000)
Checking the object map.
Checking the fsroot tree.
recv(8, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
recv(14, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
recv(8, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
recv(14, 4) failed: connection closed
unable to read message size: -1
could not receive message
unrecognized request 'ValidatePair'
Checking the snapshot metadata tree.
Checking the extent ref tree.
Checking the snapshots.
The volume /dev/disk0s1s1 appears to be OK.
fsck succeeded on /dev/disk0s1s1
ramrod_display_set_granular_progress_forced: 51.000000
executing /System/Library/Filesystems/apfs.fs/fsck_apfs -dy /dev/disk0s1s2
Checking volume.
Checking the container superblock.
Checking the space manager.
Checking the object map.
Checking the APFS volume superblock.
Checking the object map.
dev_init:1470: Using /private/var/apfs and /private/var/nx for device-io.
Checking the fsroot tree.
Checking the snapshot metadata tree.
Checking the extent ref tree.
Checking the snapshots.
The volume /dev/disk0s1s2 appears to be OK.
fsck succeeded on /dev/disk0s1s2
ramrod_display_set_granular_progress_forced: 51.000000
executing /System/Library/Filesystems/apfs.fs/fsck_apfs -dy /dev/disk0s1s3
Checking volume.
Checking the container superblock.
Checking the space manager.
Checking the object map.
Checking the APFS volume superblock.
Checking the object map.
Checking the fsroot tree.
Checking the snapshot metadata tree.
Checking the extent ref tree.
Checking the snapshots.
** The volume /dev/disk0s1s3 appears to be OK.
fsck succeeded on /dev/disk0s1s3
leaving fsck_filesystems, returning 0
entering mount_filesystems
ramrod_display_set_granular_progress_forced: 52.000000
entering mount_partition
executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s1 /mnt1
/dev/disk0s1s1 mounted on /mnt1
System mounted read-write
ramrod_display_set_granular_progress_forced: 52.000000
entering mount_partition
executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s2 /mnt2
/dev/disk0s1s2 mounted on /mnt2
Data mounted read-write
ramrod_display_set_granular_progress_forced: 52.000000
entering mount_partition
executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s3 /mnt3
/dev/disk0s1s3 mounted on /mnt3
Baseband Data mounted read-write
entering set_system_vol_name
successfully renamed System partition after inverting it
[00:58:15.0638-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0626] mount_filesystem_if_fdr
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 323
restore-step-user-progress = 52
[00:58:15.0646-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0627] fdr_restore_saved_data
restore-step-ids = {0x11030627:56}
restore-step-names = {0x11030627:fdr_restore_saved_data}
restore-step-uptime = 323
restore-step-user-progress = 52
AMFDRDataVerifyInternal: AMFDRDataVerifyInternal: Success FSCl:F6G54771523G2MPBOW
AMFDRDataVerifyInternal: AMFDRDataVerifyInternal: Success hop0:F6G54771523G2MPBOW
AMFDRDataVerifyInternal: AMFDRDataVerifyInternal: Success NvMR:F6G54771523G2MPBOW
AMFDRDataVerifyInternal: AMFDRDataVerifyInternal: Success bbcl:0B02866F
AMFDRDataVerifyInternal: AMFDRDataVerifyInternal: Success bbpv:0B02866F
AMFDRSealedDataVerify: data for bbpc-0B02866F missing, but is not required for sealing.
AMFDRDataVerifyInternal: AMFDRDataVerifyInternal: Success scrt:00007000-0006690810798C26
AMFDRSealedDataCommit: Data for bbpc:0B02866F missing, continuing.
AMFDRSetOptions: amfdr is NULL
RestoredFDRRestoreSavedData: Restored FDR state
RestoredFDRRestoreSavedData success
[00:58:15.0747-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0627] fdr_restore_saved_data
restore-step-ids = {}
restore-step-names = {}
restore-step-uptime = 323
restore-step-user-progress = 52
[00:58:15.0750-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0628] install_kernel_cache
restore-step-ids = {0x11030628:57}
restore-step-names = {0x11030628:install_kernel_cache}
restore-step-uptime = 323
restore-step-user-progress = 52
[00:58:15.0752-GMT]{5>7} CHECKPOINT BEGIN: SYSTEM:[0x0E00] install_kernel_cache
restore-step-ids = {0x11030628:57;0x11030E00:58}
restore-step-names = {0x11030628:install_kernel_cache;0x11030E00:install_kernel_cache}
restore-step-uptime = 323
restore-step-user-progress = 52
entering install_kernel_cache
recv(9, 8073009) failed: connection closed
unable to read message: -1
unable to get kernelcache data from host
[00:58:16.0307-GMT]{5>7} CHECKPOINT FAILURE:(FAILURE:6) SYSTEM:[0x0E00] install_kernel_cache [0]D(failed to install kernel cache)
restore-step-results = {0x11070E00:{0:6}}
restore-step-codes = {0x11070E00:{0:6}}
restore-step-domains = {0x11070E00:{0:"AMRestoreErrorDomain"}}
restore-step-error = {0x11070E00:"[0]D(failed to install kernel cache)"}
restore-step-uptime = 324
restore-step-user-progress = 52
[00:58:16.0313-GMT]{5>7} CHECKPOINT NOTICE: (NVRAM set) restore-step-user-progress=52 [sync=true] (first failure)
[00:58:16.0314-GMT]{5>7} CHECKPOINT FAILURE:(FAILURE:6) RESTORED:[0x0628] install_kernel_cache [0]D(failed to install kernel cache)
restore-step-results = {0x11070628:{0:6};0x11070E00:{0:6}}
restore-step-codes = {0x11070628:{0:6};0x11070E00:{0:6}}
restore-step-domains = {0x11070628:{0:"AMRestoreErrorDomain"};0x11070E00:{0:"AMRestoreErrorDomain"}}
restore-step-error = {0x11070628:"[0]D(failed to install kernel cache)"}
restore-step-uptime = 324
restore-step-user-progress = 52
[00:58:16.0319-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0647] cleanup_check_result
restore-step-ids = {0x11030628:57;0x11030E00:58;0x11030647:59}
restore-step-names = {0x11030628:install_kernel_cache;0x11030E00:install_kernel_cache;0x11030647:cleanup_check_result}
restore-step-uptime = 324
restore-step-user-progress = 52
[00:58:16.0325-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0647] cleanup_check_result
restore-step-ids = {0x11030628:57;0x11030E00:58}
restore-step-names = {0x11030628:install_kernel_cache;0x11030E00:install_kernel_cache}
restore-step-uptime = 324
restore-step-user-progress = 52
[00:58:16.0330-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0648] cleanup_send_final_status
restore-step-ids = {0x11030628:57;0x11030E00:58;0x11030648:60}
restore-step-names = {0x11030628:install_kernel_cache;0x11030E00:install_kernel_cache;0x11030648:cleanup_send_final_status}
restore-step-uptime = 324
restore-step-user-progress = 52
restore failed with CFError:
0: AMRestoreErrorDomain/6: failed to install kernel cache
[00:58:16.0341-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0648] cleanup_send_final_status
restore-step-ids = {0x11030628:57;0x11030E00:58}
restore-step-names = {0x11030628:install_kernel_cache;0x11030E00:install_kernel_cache}
restore-step-uptime = 324
restore-step-user-progress = 52
[00:58:16.0346-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0649] cleanup_wait_status_received
restore-step-ids = {0x11030628:57;0x11030E00:58;0x11030649:61}
restore-step-names = {0x11030628:install_kernel_cache;0x11030E00:install_kernel_cache;0x11030649:cleanup_wait_status_received}
restore-step-uptime = 324
restore-step-user-progress = 52
waiting for host to acknowledge final status received...
recv(9, 4) failed: Connection reset by peer
unable to read message size: -1
received NULL message from host, expected final status
[00:58:16.0355-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0649] cleanup_wait_status_received
restore-step-ids = {0x11030628:57;0x11030E00:58}
restore-step-names = {0x11030628:install_kernel_cache;0x11030E00:install_kernel_cache}
restore-step-uptime = 324
restore-step-user-progress = 52
[00:58:16.0360-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x064A] cleanup_write_restore_log
restore-step-ids = {0x11030628:57;0x11030E00:58;0x1103064A:62}
restore-step-names = {0x11030628:install_kernel_cache;0x11030E00:install_kernel_cache;0x1103064A:cleanup_write_restore_log}
restore-step-uptime = 324
restore-step-user-progress = 52
attempting to dump restore log
writing log file: /mnt1/restore.log
Unmounting filesystems (29) Unmounting filesystems (29) Unmounting filesystems (29) Creating partition map (11) Creating filesystem (12) About to send filesystem... Connected to ASR Validating the filesystem Filesystem validated Sending filesystem now... [==================================================] 100.0% Done sending filesystem Verifying restore (14) [==================================================] 100.0% Checking filesystems (15) Checking filesystems (15) Checking filesystems (15) Mounting filesystems (16) Mounting filesystems (16) Mounting filesystems (16) About to send KernelCache... Extracting kernelcache.release.iphone7... Personalizing IMG4 component KernelCache... Sending KernelCache now... Done sending KernelCache Installing kernelcache (27) Flashing firmware (18) [==================================================] 100.0% Updating gas gauge software (47) Updating gas gauge software (47) Updating Stockholm (55) About to send FUD data... Sending FUD data now... Done sending FUD data About to send FUD data... Sending FUD data now... Done sending FUD data Updating baseband (19) About to send BasebandData... WARNING: Unable to find BbSkeyId node Sending Baseband TSS request... Request URL set to https://gs.apple.com/TSS/controller?action=2 Sending TSS request attempt 1... response successfully received Received Baseband SHSH blobs Sending BasebandData now... Done sending BasebandData Updating Baseband in progress... About to send BasebandData... WARNING: Unable to find BbSkeyId node Sending Baseband TSS request... Request URL set to https://gs.apple.com/TSS/controller?action=2 Sending TSS request attempt 1... response successfully received Received Baseband SHSH blobs Sending BasebandData now... Done sending BasebandData Updating Baseband completed. Updating SE Firmware (59) Fixing up /var (17) Creating system key bag (50) Modifying persistent boot-args (25) Unmounting filesystems (29) Unmounting filesystems (29) Unmounting filesystems (29) Got status message Status: Restore Finished Cleaning up... DONE
dmesg
[ 2093.630107] usb 1-9: new high-speed USB device number 12 using xhci_hcd [ 2093.758793] usb 1-9: New USB device found, idVendor=05ac, idProduct=1227 [ 2093.758800] usb 1-9: New USB device strings: Mfr=2, Product=3, SerialNumber=4 [ 2093.758805] usb 1-9: Product: Apple Mobile Device (DFU Mode) [ 2093.758809] usb 1-9: Manufacturer: Apple Inc. [ 2093.758813] usb 1-9: SerialNumber: CPID:7000 CPRV:11 CPFM:03 SCEP:01 BDID:06 ECID:0006690810798C26 IBFL:1D
i restored iPhone 5s and iPhone 6s back to ios 10.2.1 they restored successful, i dont know where i am doing wrong with ios 10.3
any help appreciated Thank you
@ankitrwt I'll look into this later tonight.
@OothecaPickleGNUrmsTUXFSF did you have a chance to try? I just got my baby yesterday so the last few days I was mostly in the hospital ;)
@nikias no, not yet, but i'll definitely do it today. :) also, congratulations on your baby. :D
congratulations @nikias
congratulations @nikias
Dear Sir ,i also facing same issue iphone goes in dfu mode after restore 10.3.1. @OothecaPickleGNUrmsTUXFSF
I have found a bug in restore.c file. Free'd value is used later in the code.
restore.c:1016 filename = strrchr(comppath, '/'); restore.c:1040 free(comppath); restore.c:1053 if (!strncmp("iBoot", filename, 4))
I will make more tests to see if this will fix the DFU issue. So far the same iPhone6 phone (re)flashing results were unpredictable.
@mallon735 good catch, however my experience tells me that if this is the issue here then it would have caused the same for earlier iOS versions too.
I know that this code was added on 27.Feb.2017 aaec66 - restore: Add support for 10.3+ firmware images (ipsw) Also I noticed, that this DFU effect was occurring randomly on S5 and 6 models. 6S and 7 upgrade were working always.
Patch file idevicerestore.txt
Oh heh. Already committed, which turns out to be the same, but I also fixed the strncmp string length :)
Another thing I realized is that @OothecaPickleGNUrmsTUXFSF reported that restoring 32-bit devices worked, and the code in question is used for all devices :)
Firstly congratulations on your baby @nikias This iOS 10.3.1 DFU issue is solved. I have made 10+ successful restores without seeing DFU mode.
I tried to restore to iOS 10.3 using idevicerestore, and I can't maybe this issues happen because the new format (APFS).
pd: Maybe my idevice is waiting some flag for changing the filesystem format?
pd1: iOS 10.3 < is working fine!