Apple Watch S2 iTunes Restore Error 53 / 54 / 9 / 56

[frx]

After a few more tries I was able to get a good stable connection, I got the firmware from fonefunmembers as I bought the iBus from FoneFunShop

[frx]

And I still get error 53 even with the iBus S2 and its firmware

EDIT:
I should note that error 53 at the end of the restore process, Is there a way to force the device out of recovery mode?

[.::Gsmdenis::.]

Originally Posted by frx

And I still get error 53 even with the iBus S2 and its firmware

EDIT:
I should note that error 53 at the end of the restore process, Is there a way to force the device out of recovery mode?

Error 53 that's not the end of the restore process, if Still stop at FDR.

You may read this logs then you can understand how is the procedure working when restore:

Apple Watch S2 42mm Done by iBUS S2 using MFC Dongle Version 2.2 - GSM-Forum

And about exit the recovery mode the device filesystem must be validated, so that's mean if the Filesystem is bad, there is no way to exit except to full restore & upgrade

[frx]

This is where it locks up

[21:20:29.9085] <Restore Device 0x6080003531f0>: operation 14 progress 98
[21:20:31.1649] <Restore Device 0x6080003531f0>: operation 14 progress 100
[21:21:18.0112] Completed checkpoint id: 0xC05
[21:21:18.0265] Completed checkpoint id: 0x64F
[21:21:18.0471] <Restore Device 0x6080003531f0>: operation 15 progress -1
[21:21:56.4086] <Restore Device 0x6080003531f0>: operation 15 progress -1
[21:21:56.8473] <Restore Device 0x6080003531f0>: operation 16 progress -1
[21:21:57.0445] <Restore Device 0x6080003531f0>: operation 16 progress -1
[21:21:57.2781] Completed checkpoint id: 0x626
[21:21:57.3425] Completed checkpoint id: 0x627
[21:21:57.3722] requested restore behavior: Erase
[21:21:57.3738] <Restore Device 0x6080003531f0>: operation 27 progress -1
[21:21:59.8336] <Restore Device 0x6080003531f0>: operation 27 progress -1
[21:22:00.0115] Completed checkpoint id: 0xE00
[21:22:00.0690] Completed checkpoint id: 0xE01
[21:22:00.1036] Completed checkpoint id: 0xE02
[21:22:00.1189] Completed checkpoint id: 0x628
[21:22:00.1459] Completed checkpoint id: 0x652
[21:22:04.5773] Completed checkpoint id: 0x634
[21:22:04.5774] Checkpoint 0x634 failed with result: 53
[21:22:04.6060] Completed checkpoint id: 0x647
[21:22:04.7010] device returned CFError with code 53
[21:22:04.7010] dumping CFError returned by restored:
[21:22:04.7011] CFError domain:AMRestoreErrorDomain code:53 description:failed to recover FDR data
[21:22:04.7012] CFError domain:AMRestoreErrorDomain code:53 description:Failed to recover FDR data
[21:22:04.7013] CFError domain:AMFDRError code:19 descriptionnull)
[21:22:04.7014] CFError domain:AMFDRError code:10 description:AMFDRSealedDataPopulate with amfdrRemote failed with a fatal error
[21:22:04.7015] CFError domain:AMFDRError code:10 description:AMFDRSealedDataPopulate failed to report what data is missing
[21:22:04.7015] CFError domain:AMFDRError code:8 description:Unexpected error for seal:00008002-001D48D024E9A526: 12
[21:22:04.7016] CFError domain:AMFDRError code:10 description:seal:00008002-001D48D024E9A526 get data failed - foundData is NULL
[21:22:04.7017] CFError domain:AMFDRError code:8 description:AMFDRDataHTTPLoadPersistent failed
[21:22:04.7018] CFError domain:AMFDRError code:12 description:Permissions request failed.
[21:22:04.7019] Sending acknowledgement that final status was received
[21:22:04.7327] regex matching failed for '[0-9a-fA-F]{40}': 0
[21:22:04.7327]
[21:22:04.7745] regex matching failed for '[0-9a-fA-F]{40}': 0
[21:22:04.7745]
[21:22:06.3507] regex matching failed for 'SerialNumber[^
[21:22:06.3507] ]* [a-zA-Z0-9]{12}': 0
[21:22:06.3507]
[21:22:06.3599] regex matching failed for 'SerialNumber[^
[21:22:06.3599] ]* [a-zA-Z0-9]{12}': 0
[21:22:06.3599]
[21:22:06.3695]
[21:22:06.3695] ==== device restore output ====
[21:22:06.3695] SYSLOG: Jan 1 11:32:44 localhost bootlog[0] <Notice>: BOOT_TIME 41564 883026
[21:22:06.3695] [11:32:48.0215-GMT]{5>7} CHECKPOINT NOTICE: Image4 device: AP nonce clearable
[21:22:06.3695] entering ramrod_clear_ap_nonce
[21:22:06.3695] [11:32:48.0252-GMT]{5>7} CHECKPOINT NOTICE: AP nonce consumed
[21:22:06.3695] No IOFlashController instance found
[21:22:06.3695] [11:32:48.0260-GMT]{5>7} CHECKPOINT NOTICE: Pre-existing NVRAM variable: auto-boot=false
[21:22:06.3695] [11:32:48.0263-GMT]{5>7} CHECKPOINT NOTICE: Pre-existing NVRAM variable: boot-command=fsboot
[21:22:06.3695] [11:32:48.0266-GMT]{5>7} CHECKPOINT NOTICE: Pre-existing NVRAM variable: restore-outcome=initial_monitor_no_return
[21:22:06.3695] [11:32:48.0273-GMT]{5>7} CHECKPOINT ANOMALY: [check_collection]restore-step-monitor(does_not_exist)
[21:22:06.3695] [11:32:48.0276-GMT]{5>7} CHECKPOINT PROGRESS: START (unknown) -> (initial_engine_no_return)
[21:22:06.3695] [11:32:48.0278-GMT]{5>7} CHECKPOINT NOTICE: NVRAM access available on initial check
[21:22:06.3695] restore-anomalies = {0x00000000:[check_collection]restore-step-monitor(does_not_exist)}
[21:22:06.3695] restore-outcome = initial_engine_no_return
[21:22:06.3695] executing /usr/sbin/nvram restore-outcome=initial_engine_no_return
[21:22:06.3695] [11:32:48.0402-GMT]{5>7} CHECKPOINT BEGIN: MAIN:[0x0400] umask
[21:22:06.3695] restore-step-ids = {0x11030400:1}
[21:22:06.3695] restore-step-names = {0x11030400:umask}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = -1
[21:22:06.3695] [11:32:48.0416-GMT]{5>7} CHECKPOINT END: MAIN:[0x0400] umask
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = -1
[21:22:06.3695] [11:32:48.0428-GMT]{5>7} CHECKPOINT BEGIN: MAIN:[0x0402] setvbuf
[21:22:06.3695] restore-step-ids = {0x11030402:2}
[21:22:06.3695] restore-step-names = {0x11030402:setvbuf}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = -1
[21:22:06.3695] [11:32:48.0441-GMT]{5>7} CHECKPOINT END: MAIN:[0x0402] setvbuf
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = -1
[21:22:06.3695] [11:32:48.0454-GMT]{5>7} CHECKPOINT BEGIN: MAIN:[0x0403] kernel_logger_thread
[21:22:06.3695] restore-step-ids = {0x11030403:3}
[21:22:06.3695] restore-step-names = {0x11030403:kernel_logger_thread}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = -1
[21:22:06.3695] [11:32:48.0467-GMT]{5>7} CHECKPOINT END: MAIN:[0x0403] kernel_logger_thread
[21:22:06.3695] unable to open /dev/klog: No such file or directory
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = -1
[21:22:06.3695] [11:32:48.0482-GMT]{5>7} CHECKPOINT BEGIN: MAIN:[0x0406] set_progress_0
[21:22:06.3695] restore-step-ids = {0x11030406:4}
[21:22:06.3695] restore-step-names = {0x11030406:set_progress_0}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = -1
[21:22:06.3695] display-scale = 2
[21:22:06.3695] display-rotation = 0
[21:22:06.3695] found applelogo at /usr/share/progressui/[email protected]
[21:22:06.3695] found display: primary
[21:22:06.3695] display: 312 x 390
[21:22:06.3695] powering on display
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 0.000000
[21:22:06.3695] [11:32:48.0748-GMT]{5>7} CHECKPOINT END: MAIN:[0x0406] set_progress_0
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:48.0761-GMT]{5>7} CHECKPOINT BEGIN: MAIN:[0x0407] start_gasgauge_thread
[21:22:06.3695] restore-step-ids = {0x11030407:5}
[21:22:06.3695] restore-step-names = {0x11030407:start_gasgauge_thread}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:48.0778-GMT]{5>7} CHECKPOINT END: MAIN:[0x0407] start_gasgauge_thread
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:48.0794-GMT]{5>7} CHECKPOINT BEGIN: MAIN:[0x0408] listen_for_log_client
[21:22:06.3695] restore-step-ids = {0x11030408:6}
[21:22:06.3695] restore-step-names = {0x11030408:listen_for_log_client}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:48.0808-GMT]{5>7} CHECKPOINT END: MAIN:[0x0408] listen_for_log_client
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:48.0822-GMT]{5>7} CHECKPOINT BEGIN: MAIN:[0x040D] create_listen_socket
[21:22:06.3695] restore-step-ids = {0x1103040D:7}
[21:22:06.3695] restore-step-names = {0x1103040D:create_listen_socket}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:48.0835-GMT]{5>7} CHECKPOINT END: MAIN:[0x040D] create_listen_socket
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:48.0848-GMT]{5>7} CHECKPOINT BEGIN: MAIN:[0x0404] update_root_mount
[21:22:06.3695] restore-step-ids = {0x11030404:8}
[21:22:06.3695] restore-step-names = {0x11030404:update_root_mount}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:48.0874-GMT]{5>7} CHECKPOINT END: MAIN:[0x0404] update_root_mount
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:48.0887-GMT]{5>7} CHECKPOINT BEGIN: MAIN:[0x0405] disable_watchdog
[21:22:06.3695] restore-step-ids = {0x11030405:9}
[21:22:06.3695] restore-step-names = {0x11030405:disable_watchdog}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:48.0904-GMT]{5>7} CHECKPOINT END: MAIN:[0x0405] disable_watchdog
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:48.0918-GMT]{5>7} CHECKPOINT BEGIN: MAIN:[0x040E] enable_usb
[21:22:06.3695] restore-step-ids = {0x1103040E:10}
[21:22:06.3695] restore-step-names = {0x1103040E:enable_usb}
[21:22:06.3695] restore-step-uptime = 4
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] waiting for matching IOKit service: <CFBasicHash 0x1764f9d0 [0xe716f0]>{type = mutable dict, count = 1,
[21:22:06.3695] entries =>
[21:22:06.3695] 2 : <CFString 0x10cdd00 [0xe716f0]>{contents = "IOProviderClass"} = <CFString 0x1764fa00 [0xe716f0]>{contents = "AppleUSBDeviceMux"}
[21:22:06.3695] }
[21:22:06.3695] [11:32:52.0003-GMT]{5>7} CHECKPOINT END: MAIN:[0x040E] enable_usb
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 8
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] waiting for host to trigger start of restore [timeout of 120 seconds]
[21:22:06.3695] recv(8, 4) failed: connection closed
[21:22:06.3695] recv(9, 4) failed: connection closed
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] recv(12, 4) failed: connection closed
[21:22:06.3695] could not receive message
[21:22:06.3695] recv(13, 4) failed: connection closed
[21:22:06.3695] recv(10, 4) failed: connection closed
[21:22:06.3695] recv(14, 4) failed: connection closed
[21:22:06.3695] recv(11, 4) failed: connection closed
[21:22:06.3695] could not receive message
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] recv(17, 4) failed: connection closed
[21:22:06.3695] recv(18, 4) failed: connection closed
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] recv(19, 4) failed: connection closed
[21:22:06.3695] recv(20, 4) failed: connection closed
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] recv(8, 4) failed: connection closed
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] recv(21, 4) failed: connection closed
[21:22:06.3695] recv(22, 4) failed: connection closed
[21:22:06.3695] recv(23, 4) failed: connection closed
[21:22:06.3695] could not receive message
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] recv(9, 4) failed: connection closed
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] unrecognized key 'BootArgs' in value query
[21:22:06.3695] could not receive message
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] could not receive message
[21:22:06.3695] could not receive message
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] could not receive message
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] could not receive message
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] could not receive message
[21:22:06.3695] failed to handle value query for key 'BootArgs', sending back empty response
[21:22:06.3695] could not receive message
[21:22:06.3695] could not receive message
[21:22:06.3695] could not receive message
[21:22:06.3695] could not receive message
[21:22:06.3695] could not receive message
[21:22:06.3695] could not receive message
[21:22:06.3695] could not receive message
[21:22:06.3695] recv(24, 4) failed: connection closed
[21:22:06.3695] unrecognized key 'MarketingPartNumber' in value query
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] failed to handle value query for key 'MarketingPartNumber', sending back empty response
[21:22:06.3695] could not receive message
[21:22:06.3695] recv(10, 4) failed: connection closed
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] recv(8, 4) failed: connection closed
[21:22:06.3695] could not receive message
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] could not receive message
[21:22:06.3695] [11:32:52.0405-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0600] client_protocol_version
[21:22:06.3695] restore-step-ids = {0x11030600:11}
[21:22:06.3695] restore-step-names = {0x11030600:client_protocol_version}
[21:22:06.3695] restore-step-uptime = 8
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] client protocol version 15
[21:22:06.3695] [11:32:52.0421-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0600] client_protocol_version
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 8
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:52.0434-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0601] copy_restore_options
[21:22:06.3695] restore-step-ids = {0x11030601:12}
[21:22:06.3695] restore-step-names = {0x11030601:copy_restore_options}
[21:22:06.3695] restore-step-uptime = 8
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] *** UUID C1DB6F91-FF3A-4CFB-8847-550B83A26B97 ***
[21:22:06.3695] Restore options:
[21:22:06.3695] FormatForLwVM => <CFBoolean 0xe71a00 [0xe716f0]>{value = false}
[21:22:06.3695] UUID => <CFString 0x176508c0 [0xe716f0]>{contents = "C1DB6F91-FF3A-4CFB-8847-550B83A26B97"}
[21:22:06.3695] MinimumSystemPartition => <CFNumber 0x17557c70 [0xe716f0]>{value = +1981, type = kCFNumberSInt64Type}
[21:22:06.3695] SystemPartitionSize => <CFNumber 0x17558090 [0xe716f0]>{value = +1981, type = kCFNumberSInt64Type}
[21:22:06.3695] SystemPartitionPadding => <CFBasicHash 0x17557dc0 [0xe716f0]>{type = mutable dict, count = 9,
[21:22:06.3695] entries =>
[21:22:06.3695] 0 : <CFString 0x17557f90 [0xe716f0]>{contents = "512"} = <CFNumber 0x17557fa0 [0xe716f0]>{value = +1280, type = kCFNumberSInt64Type}
[21:22:06.3695] 2 : <CFString 0x17557e70 [0xe716f0]>{contents = "128"} = <CFNumber 0x17557ea0 [0xe716f0]>{value = +1280, type = kCFNumberSInt64Type}
[21:22:06.3695] 3 : <CFString 0x17557eb0 [0xe716f0]>{contents = "16"} = <CFNumber 0x17557ed0 [0xe716f0]>{value = +160, type = kCFNumberSInt64Type}
[21:22:06.3695] 4 : <CFString 0x17557f20 [0xe716f0]>{contents = "32"} = <CFNumber 0x17557f80 [0xe716f0]>{value = +320, type = kCFNumberSInt64Type}
[21:22:06.3695] 5 : <CFString 0x17557fe0 [0xe716f0]>{contents = "768"} = <CFNumber 0x17558010 [0xe716f0]>{value = +1280, type = kCFNumberSInt64Type}
[21:22:06.3695] 6 : <CFString 0xe412e8 [0xe716f0]>{contents = "8"} = <CFNumber 0x17558030 [0xe716f0]>{value = +80, type = kCFNumberSInt64Type}
[21:22:06.3695] 7 : <CFString 0x17557910 [0xe716f0]>{contents = "1024"} = <CFNumber 0x175578f0 [0xe716f0]>{value = +1280, type = kCFNumberSInt64Type}
[21:22:06.3695] 8 : <CFStri...
[21:22:06.3695] CreateFilesystemPartitions => <CFBoolean 0xe719f8 [0xe716f0]>{value = true}
[21:22:06.3695] FormatForAPFS => <CFBoolean 0xe719f8 [0xe716f0]>{value = true}
[21:22:06.3695] [11:32:52.0472-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0601] copy_restore_options
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 8
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:52.0485-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0655] is_host_compatible
[21:22:06.3695] restore-step-ids = {0x11030655:13}
[21:22:06.3695] restore-step-names = {0x11030655:is_host_compatible}
[21:22:06.3695] restore-step-uptime = 8
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:52.0498-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0655] is_host_compatible
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 8
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:52.0512-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0604] set_weight_from_options
[21:22:06.3695] restore-step-ids = {0x11030604:14}
[21:22:06.3695] restore-step-names = {0x11030604:set_weight_from_options}
[21:22:06.3695] restore-step-uptime = 8
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:52.0528-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0604] set_weight_from_options
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 8
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:52.0543-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0609] load_sep_os
[21:22:06.3695] restore-step-ids = {0x11030609:15}
[21:22:06.3695] restore-step-names = {0x11030609:load_sep_os}
[21:22:06.3695] restore-step-uptime = 8
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] entering load_sep_os
[21:22:06.3695] device has sep - getting firmware
[21:22:06.3695] entering copy_restore_sep
[21:22:06.3695] got sep firmware - making call to load it
[21:22:06.3695] entering ramrod_load_sep_os
[21:22:06.3695] entering ramrod_execute_command_with_input_data: /usr/libexec/seputil (0x3c2c000 - 1926621)
[21:22:06.3695] executing /usr/libexec/seputil --restore -
[21:22:06.3695] waiting for child to exit
[21:22:06.3695] child exited
[21:22:06.3695] exit status: 0
[21:22:06.3695] [11:32:54.0782-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0609] load_sep_os
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 10
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:54.0798-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x060B] update_ticket
[21:22:06.3695] restore-step-ids = {0x1103060B:16}
[21:22:06.3695] restore-step-names = {0x1103060B:update_ticket}
[21:22:06.3695] restore-step-uptime = 10
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] entering ramrod_ticket_update
[21:22:06.3695] looking up boot manifest hash
[21:22:06.3695] crypto-hash-method found. Using SHA2-384
[21:22:06.3695] device tree ticket_hash: <<<<<<<<<<<<<<<<<<UDID>>>>>>>>>>>>>>>>>><<<<<<<<<< <<<<<<<<UDID>>>>>>>>>>>>>>>>>><<<<<MEID>>>>>00
[21:22:06.3695] computed ticket_hash : <<<<<<<<<<<<<<<<<<UDID>>>>>>>>>>>>>>>>>><<<<<<<<<< <<<<<<<<UDID>>>>>>>>>>>>>>>>>><<<<<MEID>>>>>00
[21:22:06.3695] received valid ticket (5439 bytes)
[21:22:06.3695] [11:32:54.0830-GMT]{5>7} CHECKPOINT END: RESTORED:[0x060B] update_ticket
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 10
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:54.0844-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x060C] partition_nand
[21:22:06.3695] restore-step-ids = {0x1103060C:17}
[21:22:06.3695] restore-step-names = {0x1103060Cartition_nand}
[21:22:06.3695] restore-step-uptime = 10
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] entering partition_nand_device
[21:22:06.3695] [11:32:54.0860-GMT]{5>7} CHECKPOINT END: RESTORED:[0x060C] partition_nand
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 10
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:54.0873-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x060D] wait_for_storage
[21:22:06.3695] restore-step-ids = {0x1103060D:18}
[21:22:06.3695] restore-step-names = {0x1103060D:wait_for_storage}
[21:22:06.3695] restore-step-uptime = 10
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] entering wait_for_storage_device
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 0.000000
[21:22:06.3695] Searching for NAND service
[21:22:06.3695] Found NAND service: ASPStorage
[21:22:06.3695] NAND initialized. Waiting for devnode.
[21:22:06.3695] [11:32:54.0911-GMT]{5>7} CHECKPOINT END: RESTORED:[0x060D] wait_for_storage
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 10
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:54.0925-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x060F] clear_remap_variable
[21:22:06.3695] restore-step-ids = {0x1103060F:19}
[21:22:06.3695] restore-step-names = {0x1103060F:clear_remap_variable}
[21:22:06.3695] restore-step-uptime = 10
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] entering clear_remap_variable
[21:22:06.3695] executing /usr/sbin/nvram -d enable-remap-mode
[21:22:06.3695] enable-remap-mode
[21:22:06.3695] [11:32:55.0040-GMT]{5>7} CHECKPOINT END: RESTORED:[0x060F] clear_remap_variable
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 11
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:55.0054-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x065C] print_nvram_variables
[21:22:06.3695] restore-step-ids = {0x1103065C:20}
[21:22:06.3695] restore-step-names = {0x1103065Crint_nvram_variables}
[21:22:06.3695] restore-step-uptime = 11
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] ramrod_print_NVRAM_variables
[21:22:06.3695] executing /usr/sbin/nvram -xp
[21:22:06.3695] <?xml version="1.0" encoding="UTF-8"?>
[21:22:06.3695] <!DOCTYPE plist PUBLIC "-//Apple//DTD PLIST 1.0//EN" "http://www.apple.com/DTDs/PropertyList-1.0.dtd">
[21:22:06.3695] <plist version="1.0">
[21:22:06.3695] <dict>
[21:22:06.3695] <key>auto-boot</key>
[21:22:06.3695] <data>
[21:22:06.3695] ZmFsc2U=
[21:22:06.3695] </data>
[21:22:06.3695] <key>backlight-level</key>
[21:22:06.3695] <data>
[21:22:06.3695] NTg3
[21:22:06.3695] </data>
[21:22:06.3695] <key>boot-args</key>
[21:22:06.3695] <string>serial=3 qrcode chg-c26-debug=1 pwr-path=1 spam chg-disable-toohot=1 chg-auth-req=0 allow_swd_updates=1</string>
[21:22:06.3695] <key>boot-command</key>
[21:22:06.3695] <string>fsboot</string>
[21:22:06.3695] <key>com.apple.System.tz0-size</key>
[21:22:06.3695] <data>
[21:22:06.3695] MHg4MDAwMDA=
[21:22:06.3695] </data>
[21:22:06.3695] <key>debug-uarts</key>
[21:22:06.3695] <data>
[21:22:06.3695] Mw==
[21:22:06.3695] </data>
[21:22:06.3695] <key>device-material</key>
[21:22:06.3695] <data>
[21:22:06.3695] MDEwMDAwMDAwMDAwMDAwMDAwMDAwMDAwMDYwMDAwMDAwMTAwMD AwMDAwMDAwMDAwMDAw
[21:22:06.3695] MDAwMDAwMTAwMDAwMDAxMDAwMDAwMDAwMDAwMDAwMDAwMDAwMD AxMDAwMDAwMDEwMDAw
[21:22:06.3695] MDAwMDAwMDAwMDAwMDAwMDAwMDIwMDAwMDAwMTAwMDAwMDAwMD AwMDAwMDAwMDAwMDAw
[21:22:06.3695] MTAwMDAwMDAxMDAwMDAwMDAwMDAwMDAwMDAwMDAwMDAxMDAwMD Aw
[21:22:06.3695] </data>
[21:22:06.3695] <key>display_EFI</key>
[21:22:06.3695] <data>
[21:22:06.3695] MTMw
[21:22:06.3695] </data>
[21:22:06.3695] <key>display_nonUI</key>
[21:22:06.3695] <data>
[21:22:06.3695] NTQ1MQ==
[21:22:06.3695] </data>
[21:22:06.3695] <key>oblit-begins</key>
[21:22:06.3695] <data>
[21:22:06.3695] T2JsaXRUeXBlOiBPYmxpdGVyYXRpb25UeXBlV2lwZUFuZEJyaW NrLiBSZWFzb246IE1v
[21:22:06.3695] YmlsZURpYWdub3N0aWNzUmVsYXkgZXhlY3V0ZWQgU0lPUC4=
[21:22:06.3695] </data>
[21:22:06.3695] <key>obliteration</key>
[21:22:06.3695] <data>
[21:22:06.3695] ZHJhd2luZ19yb3V0aW5lOiB0aW1lIHRvIGRpZS4gc2V0dGluZy B0byAxLjAwMDAwMC4g
[21:22:06.3695] NS41OTQ4MzQgdG90YWwgc2Vjb25kcyBlbGFwc2Vk
[21:22:06.3695] </data>
[21:22:06.3695] <key>restore-outcome</key>
[21:22:06.3695] <data>
[21:22:06.3695] aW5pdGlhbF9lbmdpbmVfbm9fcmV0dXJu
[21:22:06.3695] </data>
[21:22:06.3695] <key>restored-exit-status</key>
[21:22:06.3695] <data>
[21:22:06.3695] MHgxMDA=
[21:22:06.3695] </data>
[21:22:06.3695] </dict>
[21:22:06.3695] </plist>
[21:22:06.3695] [11:32:55.0165-GMT]{5>7} CHECKPOINT END: RESTORED:[0x065C] print_nvram_variables
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 11
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:55.0179-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0610] asp_nand_set_writable
[21:22:06.3695] restore-step-ids = {0x11030610:21}
[21:22:06.3695] restore-step-names = {0x11030610:asp_nand_set_writable}
[21:22:06.3695] restore-step-uptime = 11
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] Service name : ASPStorage
[21:22:06.3695] Set ASP writable successfully
[21:22:06.3695] [11:32:55.0206-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0610] asp_nand_set_writable
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 11
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:55.0219-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0611] reprobe_storage
[21:22:06.3695] restore-step-ids = {0x11030611:22}
[21:22:06.3695] restore-step-names = {0x11030611:reprobe_storage}
[21:22:06.3695] restore-step-uptime = 11
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] entering ramrod_reprobe_device_path
[21:22:06.3695] entering ramrod_probe_media
[21:22:06.3695] device partitioning scheme is GPT
[21:22:06.3695] APFS Container /dev/disk0s1
[21:22:06.3695] device is APFS formatted
[21:22:06.3695] find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log= update=
[21:22:06.3695] [11:32:55.0330-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0611] reprobe_storage
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 11
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:55.0344-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0612] reprobe_firmware
[21:22:06.3695] restore-step-ids = {0x11030612:23}
[21:22:06.3695] restore-step-names = {0x11030612:reprobe_firmware}
[21:22:06.3695] restore-step-uptime = 11
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] entering ramrod_reprobe_device_path
[21:22:06.3695] entering ramrod_probe_media
[21:22:06.3695] device partitioning scheme is GPT
[21:22:06.3695] APFS Container /dev/disk0s1
[21:22:06.3695] device is APFS formatted
[21:22:06.3695] find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log= update=
[21:22:06.3695] [11:32:55.0389-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0612] reprobe_firmware
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 11
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:32:55.0402-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0613] find_filesystem_partitions
[21:22:06.3695] restore-step-ids = {0x11030613:24}
[21:22:06.3695] restore-step-names = {0x11030613:find_filesystem_partitions}
[21:22:06.3695] restore-step-uptime = 11
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] entering ramrod_probe_media
[21:22:06.3695] device partitioning scheme is GPT
[21:22:06.3695] APFS Container /dev/disk0s1
[21:22:06.3695] device is APFS formatted
[21:22:06.3695] find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log= update=
[21:22:06.3695] entering fsck_filesystems
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 0.000000
[21:22:06.3695] executing /System/Library/Filesystems/apfs.fs/fsck_apfs -dy /dev/disk0s1s1
[21:22:06.3695] ** Checking volume.
[21:22:06.3695] ** Checking the container superblock.
[21:22:06.3695] checkpoint<->superblock mismatch on nx_omap_oid: 98139 19169
[21:22:06.3695] warning: checkpoint<->superblock disagree for xp 43
[21:22:06.3695] ** Checking the space manager.
[21:22:06.3695] ** Checking the object map.
[21:22:06.3695] ** Checking the APFS volume superblock.
[21:22:06.3695] warning: apfs_sb at apfs_fs_index (0): apfs_formatted_by.timestamp is invalid (<<<<<<<<ICCID>>>>>>), is greater than current time (<<<<<MEID>>>>>)
[21:22:06.3695] ** Checking the object map.
[21:22:06.3695] ** Checking the fsroot tree.
[21:22:06.3695] ** Checking the snapshot metadata tree.
[21:22:06.3695] ** Checking the extent ref tree.
[21:22:06.3695] ** Checking the snapshots.
[21:22:06.3695] ** The volume /dev/disk0s1s1 appears to be OK.
[21:22:06.3695] fsck succeeded on /dev/disk0s1s1
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 0.000000
[21:22:06.3695] executing /System/Library/Filesystems/apfs.fs/fsck_apfs -dy /dev/disk0s1s2
[21:22:06.3695] ** Checking volume.
[21:22:06.3695] ** Checking the container superblock.
[21:22:06.3695] checkpoint<->superblock mismatch on nx_omap_oid: 98139 19169
[21:22:06.3695] warning: checkpoint<->superblock disagree for xp 43
[21:22:06.3695] ** Checking the space manager.
[21:22:06.3695] ** Checking the object map.
[21:22:06.3695] ** Checking the APFS volume superblock.
[21:22:06.3695] ** Checking the object map.
[21:22:06.3695] dev_init:1470: Using /private/var/apfs and /private/var/nx for device-io.
[21:22:06.3695] ** Checking the fsroot tree.
[21:22:06.3695] ** Checking the snapshot metadata tree.
[21:22:06.3695] ** Checking the extent ref tree.
[21:22:06.3695] ** Checking the snapshots.
[21:22:06.3695] ** The volume /dev/disk0s1s2 appears to be OK.
[21:22:06.3695] fsck succeeded on /dev/disk0s1s2
[21:22:06.3695] leaving fsck_filesystems, returning 0
[21:22:06.3695] [11:33:33.0627-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0613] find_filesystem_partitions
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 49
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:33:33.0641-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0614] mount_system_partition
[21:22:06.3695] restore-step-ids = {0x11030614:25}
[21:22:06.3695] restore-step-names = {0x11030614:mount_system_partition}
[21:22:06.3695] restore-step-uptime = 49
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 0.000000
[21:22:06.3695] entering mount_partition
[21:22:06.3695] executing /System/Library/Filesystems/apfs.fs/mount_apfs -o rdonly /dev/disk0s1s1 /mnt1
[21:22:06.3695] /dev/disk0s1s1 mounted on /mnt1
[21:22:06.3695] System mounted read-only
[21:22:06.3695] [11:33:33.0829-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0614] mount_system_partition
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 49
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:33:33.0843-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0654] read_previous_os_build_version
[21:22:06.3695] restore-step-ids = {0x11030654:26}
[21:22:06.3695] restore-step-names = {0x11030654:read_previous_os_build_version}
[21:22:06.3695] restore-step-uptime = 49
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] ramrod_read_previous_os_build_version: previous OS version: 14V249
[21:22:06.3695] [11:33:33.0868-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0654] read_previous_os_build_version
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 49
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:33:33.0881-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0615] copy_hardware_info
[21:22:06.3695] restore-step-ids = {0x11030615:27}
[21:22:06.3695] restore-step-names = {0x11030615:copy_hardware_info}
[21:22:06.3695] restore-step-uptime = 49
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:33:33.0896-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0615] copy_hardware_info
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 49
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:33:33.0911-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0616] fdr_create
[21:22:06.3695] restore-step-ids = {0x11030616:28}
[21:22:06.3695] restore-step-names = {0x11030616:fdr_create}
[21:22:06.3695] restore-step-uptime = 49
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] restored_fdr_initialize: FDR is supported
[21:22:06.3695] CryptoAcceleratorEncrypt: perform aes => 0 (kIOReturnSuccess)
[21:22:06.3695] pseudo_ccrng_allocate: ccdrbg_init() -> 0
[21:22:06.3695] CryptoGenerateRSAKeys: ccrsa_generate_key() -> 0
[21:22:06.3695] CryptoGenerateRSAKeys: [RSA Public Key SHA1: 20 bytes]
[21:22:06.3695] : 3d d4 73 f4 4a 58 b9 d6 4b 95 44 0d 6c 79 d2 15
[21:22:06.3695] : f6 fa 5b 2c
[21:22:06.3695] : -----------------------------------------------
[21:22:06.3695] CryptoGenerateRSAKeys: SecKeyCreateRSAPrivateKey -> 0x1672f4
[21:22:06.3695] CryptoGenerateRSAKeys: SecKeyCreateRSAPublicKey -> 0x1672f0
[21:22:06.3695] created HTTP FDR client 0x17652c80
[21:22:06.3695] created local FDR client 0x17651590
[21:22:06.3695] Received response without expected RESTORED_FDR_TRUST_DATA
[21:22:06.3695] Found local trust object, computing digest..
[21:22:06.3695] Local trust object digest DOES match AP ticket.
[21:22:06.3695] Not enabling SSO for FDR
[21:22:06.3695] RestoredFDRCreate() returned 0
[21:22:06.3695] FDR is supported on this device
[21:22:06.3695] [11:33:43.0454-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0616] fdr_create
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 59
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:33:43.0468-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0617] fdr_save_data
[21:22:06.3695] restore-step-ids = {0x11030617:29}
[21:22:06.3695] restore-step-names = {0x11030617:fdr_save_data}
[21:22:06.3695] restore-step-uptime = 59
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] _AMFDRSealingMapCopyDataClassesAndInstances: amfdr data version changed. Old version: (null) new version 1
[21:22:06.3695] AMSupportPlatformCreateBufferFromNativeFilePath: open failed: No such file or directory
[21:22:06.3695] AMSupportPlatformCreateBufferFromNativeFilePath: /mnt1/System/Library/Caches/com.apple.factorydata/sealXXXXXXXXX-<<<<<MEID>>>>>26
[21:22:06.3695] AMFDRCreateError: AMFDRDataLocalCopy: Failed to read data: code=4
[21:22:06.3695] AMFDRCreateError: AMFDRDataCopy: sealXXXXXXXXX-<<<<<MEID>>>>>26 get data failed - foundData is NULL: code=10
[21:22:06.3695] AMFDRSealedDataPopulate: Failed to fetch data for sealXXXXXXXXX-<<<<<MEID>>>>>26.
[21:22:06.3695] AMSupportPlatformCreateBufferFromNativeFilePath: open failed: No such file or directory
[21:22:06.3695] AMSupportPlatformCreateBufferFromNativeFilePath: /mnt1/System/Library/Caches/com.apple.factorydata/certificate
[21:22:06.3695] AMSupportPlatformCreateBufferFromNativeFilePath: open failed: No such file or directory
[21:22:06.3695] AMSupportPlatformCreateBufferFromNativeFilePath: /mnt1/System/Library/Caches/com.apple.factorydata/scrtXXXXXXXXX-<<<<<MEID>>>>>26
[21:22:06.3695] AMFDRCreateError: AMFDRDataLocalCopy: Failed to read data: code=4
[21:22:06.3695] AMFDRCreateError: AMFDRDataCopy: scrtXXXXXXXXX-<<<<<MEID>>>>>26 get data failed - foundData is NULL: code=10
[21:22:06.3695] AMFDRSealedDataPopulate: Failed to fetch data for scrtXXXXXXXXX-<<<<<MEID>>>>>26, but it may be generated by the restore
[21:22:06.3695] AMSupportPlatformCreateBufferFromNativeFilePath: open failed: No such file or directory
[21:22:06.3695] AMSupportPlatformCreateBufferFromNativeFilePath: /mnt1/System/Library/Caches/com.apple.factorydata/ccrtXXXXXXXXX-<<<<<MEID>>>>>26
[21:22:06.3695] AMFDRCreateError: AMFDRDataLocalCopy: Failed to read data: code=4
[21:22:06.3695] AMFDRCreateError: AMFDRDataCopy: ccrtXXXXXXXXX-<<<<<MEID>>>>>26 get data failed - foundData is NULL: code=10
[21:22:06.3695] AMFDRSealedDataPopulate: Failed to fetch data for ccrtXXXXXXXXX-<<<<<MEID>>>>>26.
[21:22:06.3695] AMSupportPlatformCreateBufferFromNativeFilePath: open failed: No such file or directory
[21:22:06.3695] AMSupportPlatformCreateBufferFromNativeFilePath: /mnt1/System/Library/Caches/com.apple.factorydata/pcrt-<<<<<<<<<<<<<<<<<<UDID>>>>>>>>>>>>>>>>>>
[21:22:06.3695] AMFDRCreateError: AMFDRDataLocalCopy: Failed to read data: code=4
[21:22:06.3695] AMFDRCreateError: AMFDRDataCopy: pcrt:<<<<<<<<<<<<<<<<<<UDID>>>>>>>>>>>>>>>>>> get data failed - foundData is NULL: code=10
[21:22:06.3695] AMFDRSealedDataPopulate: Failed to fetch data for pcrt:<<<<<<<<<<<<<<<<<<UDID>>>>>>>>>>>>>>>>>>, but it may be generated by the restore
[21:22:06.3695] AMFDRCreateError: AMFDRSealedDataPopulate: kAMFDRServerErrorNotFound:Failed to fetch data for instances (
[21:22:06.3695] "scrtXXXXXXXXX-<<<<<MEID>>>>>26",
[21:22:06.3695] "ccrtXXXXXXXXX-<<<<<MEID>>>>>26",
[21:22:06.3695] "pcrt-<<<<<<<<<<<<<<<<<<UDID>>>>>>>>>>>>>>>>>>"
[21:22:06.3695] ): code=404
[21:22:06.3695] AMFDRDataErrorIsBenign: Required data missing from FDR DataStore but either 'Allow incomplete data' or 'Allow unsealed data' is present in AP or Sealing Manifests.
[21:22:06.3695] AMFDRSetOptions: amfdr is NULL
[21:22:06.3695] saved FDR persistent state
[21:22:06.3695] RestoredFDRSaveData success
[21:22:06.3695] [11:33:43.0560-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0617] fdr_save_data
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 59
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:33:43.0573-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0618] store_baseband_migration
[21:22:06.3695] restore-step-ids = {0x11030618:30}
[21:22:06.3695] restore-step-names = {0x11030618:store_baseband_migration}
[21:22:06.3695] restore-step-uptime = 59
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:33:43.0590-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0618] store_baseband_migration
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 59
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:33:43.0604-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0619] check_for_restore_log
[21:22:06.3695] restore-step-ids = {0x11030619:31}
[21:22:06.3695] restore-step-names = {0x11030619:check_for_restore_log}
[21:22:06.3695] restore-step-uptime = 59
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] entering check_for_restore_log
[21:22:06.3695] found restore log (size = 85589)
[21:22:06.3695] [11:33:43.0641-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0619] check_for_restore_log
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 59
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:33:43.0655-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x061A] check_for_ota_logs
[21:22:06.3695] restore-step-ids = {0x1103061A:32}
[21:22:06.3695] restore-step-names = {0x1103061A:check_for_ota_logs}
[21:22:06.3695] restore-step-uptime = 59
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] entering mount_partition
[21:22:06.3695] executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s2 /mnt2
[21:22:06.3695] /dev/disk0s1s2 mounted on /mnt2
[21:22:06.3695] Data mounted read-write
[21:22:06.3695] entering dump_panic_logs
[21:22:06.3695] executing /System/Library/CoreServices/DumpPanic -r/mnt2/mobile/Library/Logs/CrashReporter
[21:22:06.3695] entering check_for_ota_logs_with_mount_point
[21:22:06.3695] OTA logs will be preserved through the erase install
[21:22:06.3695] restore log does not exist
[21:22:06.3695] failed to read user settings from: /mnt2/mobile/Library/UserConfigurationProfiles/EffectiveUserSettings.plist. trying legacy path
[21:22:06.3695] restore log does not exist
[21:22:06.3695] skipping log submission, failed to read user settings from: /mnt2/mobile/Library/ConfigurationProfiles/EffectiveUserSettings.plist
[21:22:06.3695] Diagnostic log submission disabled
[21:22:06.3695] Searching /mnt2/mobile/Library/Logs/CrashReporter/
[21:22:06.3695] check_for_ota_logs_with_mount_point complete
[21:22:06.3695] Successfully checked for OTA logs
[21:22:06.3695] [11:33:44.0280-GMT]{5>7} CHECKPOINT END: RESTORED:[0x061A] check_for_ota_logs
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 60
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:33:44.0294-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x061B] submit_update_stats
[21:22:06.3695] restore-step-ids = {0x1103061B:33}
[21:22:06.3695] restore-step-names = {0x1103061B:submit_update_stats}
[21:22:06.3695] restore-step-uptime = 60
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] entering mount_partition
[21:22:06.3695] executing /sbin/mount -u -w /dev/disk0s1s1 /mnt1
[21:22:06.3695] Changed permissions on /dev/disk0s1s1 mounted at /mnt1 to readwrite
[21:22:06.3695] entering mount_partition
[21:22:06.3695] Data already mounted read-write (mount ignored)
[21:22:06.3695] AMSupportHttpCopyProxySettings_block_invoke: Proxy available
[21:22:06.3695] AMSupportHttpCopyProxySettings_block_invoke: AMSupportHttpCopyProxySettings: RPNotificationProxyAvailable
[21:22:06.3695] AMSupportHttpCopyProxySettings: proxyInfo = <CFBasicHash 0x17565a90 [0xe716f0]>{type = mutable dict, count = 2,
[21:22:06.3695] entries =>
[21:22:06.3695] 1 : <CFString 0xe5b568 [0xe716f0]>{contents = "SOCKSProxy"} = <CFString 0x1a8628 [0xe716f0]>{contents = "127.0.0.1"}
[21:22:06.3695] 2 : <CFString 0xe5b578 [0xe716f0]>{contents = "SOCKSPort"} = <CFNumber 0x175653f0 [0xe716f0]>{value = +1081, type = kCFNumberSInt32Type}
[21:22:06.3695] }
[21:22:06.3695] calling UMEventShimSubmitEvents
[21:22:06.3695] : start
[21:22:06.3695] : ignoring call to recordPostUpdateEvent when no install is in progress
[21:22:06.3695] UMEventShimSubmitEvents done
[21:22:06.3695] [11:33:44.0701-GMT]{5>7} CHECKPOINT END: RESTORED:[0x061B] submit_update_stats
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 60
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] [11:33:44.0715-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x061D] unmount_unconditionally
[21:22:06.3695] restore-step-ids = {0x1103061D:34}
[21:22:06.3695] restore-step-names = {0x1103061D:unmount_unconditionally}
[21:22:06.3695] restore-step-uptime = 60
[21:22:06.3695] restore-step-user-progress = 0
[21:22:06.3695] entering unmount_filesystems
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 2.000000
[21:22:06.3695] entering unmount_partition
[21:22:06.3695] System unmounted
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 2.000000
[21:22:06.3695] entering unmount_partition
[21:22:06.3695] Data unmounted
[21:22:06.3695] [11:33:44.0909-GMT]{5>7} CHECKPOINT END: RESTORED:[0x061D] unmount_unconditionally
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 60
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:44.0923-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x064E] clean_nand
[21:22:06.3695] restore-step-ids = {0x1103064E:35}
[21:22:06.3695] restore-step-names = {0x1103064E:clean_nand}
[21:22:06.3695] restore-step-uptime = 60
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] entering clean_NAND
[21:22:06.3695] NAND format complete
[21:22:06.3695] entering ramrod_probe_media
[21:22:06.3695] find_filesystem_partitions: storage=/dev/disk0 container= system= data= baseband data= log= update=
[21:22:06.3695] [11:33:46.0525-GMT]{5>7} CHECKPOINT END: RESTORED:[0x064E] clean_nand
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 62
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:46.0541-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0653] create_readwrite_ramdisk
[21:22:06.3695] restore-step-ids = {0x11030653:36}
[21:22:06.3695] restore-step-names = {0x11030653:create_readwrite_ramdisk}
[21:22:06.3695] restore-step-uptime = 62
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] executing /usr/sbin/hdik -nomount ram://5120
[21:22:06.3695] /dev/disk4
[21:22:06.3695] executing /sbin/newfs_hfs -J -v readwrite_ramdisk /dev/disk4
[21:22:06.3695] Initialized /dev/rdisk4 as a 3 MB case-insensitive HFS Plus volume with a 512k journal
[21:22:06.3695] executing /sbin/fsck_hfs -fdy /dev/disk4
[21:22:06.3695] journal_replay(/dev/disk4) returned 0
[21:22:06.3695] ** /dev/rdisk4
[21:22:06.3695] Using cacheBlockSize=32K cacheTotalBlock=1984 cacheSize=63488K.
[21:22:06.3695] Executing fsck_hfs (version hfs-366.50.19).
[21:22:06.3695] ** Checking Journaled HFS Plus volume.
[21:22:06.3695] The volume name is readwrite_ramdisk
[21:22:06.3695] ** Checking extents overflow file.
[21:22:06.3695] ** Checking catalog file.
[21:22:06.3695] ** Checking multi-linked files.
[21:22:06.3695] ** Checking catalog hierarchy.
[21:22:06.3695] ** Checking extended attributes file.
[21:22:06.3695] ** Checking volume bitmap.
[21:22:06.3695] ** Checking volume information.
[21:22:06.3695] ** The volume readwrite_ramdisk appears to be OK.
[21:22:06.3695] CheckHFS returned 0, fsmodified = 0
[21:22:06.3695] executing /sbin/mount_hfs /dev/disk4 /mnt5
[21:22:06.3695] mount_hfs: Could not create property for re-key environment check: No such file or directory
[21:22:06.3695] [11:33:47.0232-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0653] create_readwrite_ramdisk
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0247-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x061F] format_effaceable_storage
[21:22:06.3695] restore-step-ids = {0x1103061F:37}
[21:22:06.3695] restore-step-names = {0x1103061F:format_effaceable_storage}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] entering format_effaceable_storage
[21:22:06.3695] effaceable storage is formatted, clearing it
[21:22:06.3695] effaceable storaged cleared
[21:22:06.3695] [11:33:47.0304-GMT]{5>7} CHECKPOINT END: RESTORED:[0x061F] format_effaceable_storage
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0318-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0643] get_apfs_options
[21:22:06.3695] restore-step-ids = {0x11030643:38}
[21:22:06.3695] restore-step-names = {0x11030643:get_apfs_options}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0332-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0643] get_apfs_options
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0345-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0620] format_storage_for_lwvm
[21:22:06.3695] restore-step-ids = {0x11030620:39}
[21:22:06.3695] restore-step-names = {0x11030620:format_storage_for_lwvm}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0359-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0620] format_storage_for_lwvm
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0372-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0621] create_filesystem_partitions
[21:22:06.3695] restore-step-ids = {0x11030621:40}
[21:22:06.3695] restore-step-names = {0x11030621:create_filesystem_partitions}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] APFS Erase Install
[21:22:06.3695] entering create_partition_for_apfs
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 2.000000
[21:22:06.3695] [11:33:47.0714-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0621] create_filesystem_partitions
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0728-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0622] reload_filesystem_partitions
[21:22:06.3695] restore-step-ids = {0x11030622:41}
[21:22:06.3695] restore-step-names = {0x11030622:reload_filesystem_partitions}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] entering ramrod_probe_media
[21:22:06.3695] device partitioning scheme is GPT
[21:22:06.3695] APFS Container /dev/disk0s1
[21:22:06.3695] find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system= data= baseband data= log= update=
[21:22:06.3695] [11:33:47.0755-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0622] reload_filesystem_partitions
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0768-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x065D] delete_mobilebackups
[21:22:06.3695] restore-step-ids = {0x1103065D:42}
[21:22:06.3695] restore-step-names = {0x1103065D:delete_mobilebackups}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0782-GMT]{5>7} CHECKPOINT END: RESTORED:[0x065D] delete_mobilebackups
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0795-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0645] check_not_apfs_mount
[21:22:06.3695] restore-step-ids = {0x11030645:43}
[21:22:06.3695] restore-step-names = {0x11030645:check_not_apfs_mount}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0809-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0645] check_not_apfs_mount
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0822-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0624] restore_images
[21:22:06.3695] restore-step-ids = {0x11030624:44}
[21:22:06.3695] restore-step-names = {0x11030624:restore_images}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0836-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0624] restore_images
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0850-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x0625] find_partitions_after_images
[21:22:06.3695] restore-step-ids = {0x11030625:45}
[21:22:06.3695] restore-step-names = {0x11030625:find_partitions_after_images}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] entering ramrod_probe_media
[21:22:06.3695] device partitioning scheme is GPT
[21:22:06.3695] APFS Container /dev/disk0s1
[21:22:06.3695] find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system= data= baseband data= log= update=
[21:22:06.3695] [11:33:47.0876-GMT]{5>7} CHECKPOINT END: RESTORED:[0x0625] find_partitions_after_images
[21:22:06.3695] restore-step-ids = {}
[21:22:06.3695] restore-step-names = {}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0890-GMT]{5>7} CHECKPOINT BEGIN: RESTORED:[0x064F] check_apfs_format
[21:22:06.3695] restore-step-ids = {0x1103064F:46}
[21:22:06.3695] restore-step-names = {0x1103064F:check_apfs_format}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:47.0903-GMT]{5>7} CHECKPOINT BEGIN: APFS_RELOAD:[0x0C00] format_media
[21:22:06.3695] restore-step-ids = {0x1103064F:46;0x11030C00:47}
[21:22:06.3695] restore-step-names = {0x1103064F:check_apfs_format;0x11030C00:format_me dia}
[21:22:06.3695] restore-step-uptime = 63
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] entering create_apfs_filesystems
[21:22:06.3695] entering ramrod_probe_media
[21:22:06.3695] device partitioning scheme is GPT
[21:22:06.3695] APFS Container /dev/disk0s1
[21:22:06.3695] find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system= data= baseband data= log= update=
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 2.000000
[21:22:06.3695] /System/Library/Filesystems/apfs.fs/newfs_apfs -C /dev/disk0s1
[21:22:06.3695] executing /System/Library/Filesystems/apfs.fs/newfs_apfs -C /dev/disk0s1
[21:22:06.3695] spaceman_metazone_init:262: not creating spaceman metazone, container is only 4718546944 bytes, block_size 4096 block count 1151989
[21:22:06.3695] block size for /dev/disk0s1: 4096
[21:22:06.3695] /System/Library/Filesystems/apfs.fs/newfs_apfs -A -o fsindex=0 -v System /dev/disk0s1
[21:22:06.3695] executing /System/Library/Filesystems/apfs.fs/newfs_apfs -A -o fsindex=0 -v System /dev/disk0s1
[21:22:06.3695] creating encrypted data partition
[21:22:06.3695] block size for /dev/disk0s1: 4096
[21:22:06.3695] /System/Library/Filesystems/apfs.fs/newfs_apfs -A -o fsindex=1 -v Data -P /dev/disk0s1
[21:22:06.3695] executing /System/Library/Filesystems/apfs.fs/newfs_apfs -A -o fsindex=1 -v Data -P /dev/disk0s1
[21:22:06.3695] [11:33:49.0569-GMT]{5>7} CHECKPOINT END: APFS_RELOAD:[0x0C00] format_media
[21:22:06.3695] restore-step-ids = {0x1103064F:46}
[21:22:06.3695] restore-step-names = {0x1103064F:check_apfs_format}
[21:22:06.3695] restore-step-uptime = 65
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:49.0584-GMT]{5>7} CHECKPOINT BEGIN: APFS_RELOAD:[0x0C01] find_partitions
[21:22:06.3695] restore-step-ids = {0x1103064F:46;0x11030C01:48}
[21:22:06.3695] restore-step-names = {0x1103064F:check_apfs_format;0x11030C01:find_part itions}
[21:22:06.3695] restore-step-uptime = 65
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] entering ramrod_probe_media
[21:22:06.3695] device partitioning scheme is GPT
[21:22:06.3695] APFS Container /dev/disk0s1
[21:22:06.3695] device is APFS formatted
[21:22:06.3695] find_filesystem_partitions: storage=/dev/disk0 container=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log= update=
[21:22:06.3695] [11:33:49.0616-GMT]{5>7} CHECKPOINT END: APFS_RELOAD:[0x0C01] find_partitions
[21:22:06.3695] restore-step-ids = {0x1103064F:46}
[21:22:06.3695] restore-step-names = {0x1103064F:check_apfs_format}
[21:22:06.3695] restore-step-uptime = 65
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:49.0629-GMT]{5>7} CHECKPOINT BEGIN: APFS_RELOAD:[0x0C06] reserve_overprov_space
[21:22:06.3695] restore-step-ids = {0x1103064F:46;0x11030C06:49}
[21:22:06.3695] restore-step-names = {0x1103064F:check_apfs_format;0x11030C06:reserve_o verprov_space}
[21:22:06.3695] restore-step-uptime = 65
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s2 /mnt2
[21:22:06.3695] /dev/disk0s1s2 mounted on /mnt2
[21:22:06.3695] device_size = 4718592000 (4 GB)
[21:22:06.3695] file_size = 47185920 (0 GB)
[21:22:06.3695] [11:33:55.0889-GMT]{5>7} CHECKPOINT END: APFS_RELOAD:[0x0C06] reserve_overprov_space
[21:22:06.3695] restore-step-ids = {0x1103064F:46}
[21:22:06.3695] restore-step-names = {0x1103064F:check_apfs_format}
[21:22:06.3695] restore-step-uptime = 71
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:55.0903-GMT]{5>7} CHECKPOINT BEGIN: APFS_RELOAD:[0x0C03] baseband_migration_data
[21:22:06.3695] restore-step-ids = {0x1103064F:46;0x11030C03:50}
[21:22:06.3695] restore-step-names = {0x1103064F:check_apfs_format;0x11030C03:baseband_ migration_data}
[21:22:06.3695] restore-step-uptime = 71
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:55.0917-GMT]{5>7} CHECKPOINT END: APFS_RELOAD:[0x0C03] baseband_migration_data
[21:22:06.3695] restore-step-ids = {0x1103064F:46}
[21:22:06.3695] restore-step-names = {0x1103064F:check_apfs_format}
[21:22:06.3695] restore-step-uptime = 71
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] [11:33:55.0931-GMT]{5>7} CHECKPOINT BEGIN: APFS_RELOAD:[0x0C05] restore_invert
[21:22:06.3695] restore-step-ids = {0x1103064F:46;0x11030C05:51}
[21:22:06.3695] restore-step-names = {0x1103064F:check_apfs_format;0x11030C05:restore_i nvert}
[21:22:06.3695] restore-step-uptime = 71
[21:22:06.3695] restore-step-user-progress = 2
[21:22:06.3695] restore_apfs_image : started restoring an APFS image
[21:22:06.3695] entering mount_partition
[21:22:06.3695] executing /System/Library/Filesystems/apfs.fs/mount_apfs /dev/disk0s1s1 /mnt1
[21:22:06.3695] /dev/disk0s1s1 mounted on /mnt1
[21:22:06.3695] System mounted read-write
[21:22:06.3695] Successfully marked device node /dev/rdisk0s1s1 as static data
[21:22:06.3695] OS digest = <<<<<<<<<<<<<<<<<<UDID>>>>>>>>>>>>>>>>>><<<<<<<<<< <<<<<<<<UDID>>>>>>>>>>>>>>>>>><<<<<MEID>>>>>f5
[21:22:06.3695] Restoring to APFS Inverter Image = /mnt1/apfs_invert_asr_img
[21:22:06.3695] executing /usr/sbin/asr -source asr://localhost:12345 -target /mnt1/apfs_invert_asr_img --embed -erase -noprompt --chunkchecksum --puppetstrings --expectedhash <<<<<<<<<<<<<<<<<<UDID>>>>>>>>>>>>>>>>>><<<<<<<<<< <<<<<<<<UDID>>>>>>>>>>>>>>>>>><<<<<MEID>>>>>f5
[21:22:06.3695] ASR STATUS: start 404 multicast-client
[21:22:06.3695] ASR: Waiting for connection attempt from server
[21:22:06.3695] ASR STATUS: setup
[21:22:06.3695] ASR: Validating target...
[21:22:06.3695] ASR: done
[21:22:06.3695] ASR STATUS: metadata
[21:22:06.3695] ASR: Validating source...
[21:22:06.3695] ASR: done
[21:22:06.3695] ASR: Retrieving scan information...
[21:22:06.3695] ASR: done
[21:22:06.3695] ASR: Validating sizes...
[21:22:06.3695] ASR: done
[21:22:06.3695] ASR STATUS: restore
[21:22:06.3695] ASR RESTORE PROGRESS: 2%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 9.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 4%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 9.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 6%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 10.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 8%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 11.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 10%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 12.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 12%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 12.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 14%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 13.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 16%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 14.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 18%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 15.000000
[21:22:06.3695] recv(12, 4) failed: connection closed
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] could not receive message
[21:22:06.3695] recv(13, 4) failed: connection closed
[21:22:06.3695] unable to read message size: -1
[21:22:06.3695] could not receive message
[21:22:06.3695] ASR RESTORE PROGRESS: 20%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 15.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 22%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 16.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 24%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 17.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 26%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 18.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 28%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 18.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 30%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 19.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 32%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 20.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 34%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 21.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 36%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 21.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 38%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 22.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 40%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 23.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 42%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 24.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 44%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 24.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 46%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 25.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 48%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 26.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 50%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 27.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 52%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 27.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 54%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 28.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 56%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 29.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 58%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 30.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 60%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 30.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 62%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 31.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 64%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 32.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 66%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 33.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 68%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 33.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 70%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 34.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 72%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 35.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 74%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 36.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 76%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 36.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 78%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 37.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 80%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 38.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 82%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 39.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 84%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 39.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 86%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 40.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 88%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 41.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 90%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 42.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 92%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 42.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 94%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 43.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 96%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 44.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 98%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 45.000000
[21:22:06.3695] ASR RESTORE PROGRESS: 100%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 45.000000
[21:22:06.3695] ASR: Copied 2078273536 bytes in 292.78 seconds, 6932.14 KiB/s
[21:22:06.3695] ASR STATUS: verify
[21:22:06.3695] ASR VERIFY PROGRESS: 2%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 46.000000
[21:22:06.3695] ASR VERIFY PROGRESS: 4%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 46.000000
[21:22:06.3695] ASR VERIFY PROGRESS: 6%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 47.000000
[21:22:06.3695] ASR VERIFY PROGRESS: 8%
[21:22:06.3695] ramrod_display_set_granular_progress_forced: 48.000000
[21:22:06.3695] ASR VERIFY PROGR

[.::Gsmdenis::.]

Post full logs, i only can saw half of this.

and i also recommend you use fresh system to do this.

[frx]

Here's a link to the full log
AppleWatch - iBus S2 LOG

Will try later with a fresh system

[.::Gsmdenis::.]

Please check the watch harddisk, as i check the logs from beginning can not detect SerialNumber correctly, you did something for that ?

[frx]

So the watch has no serial number? How can I check the harddisk?
I received them already in DFU, they told me they were Demo units (that's also written on the case) decommissioned from the store, because they switched to other models.

[.::Gsmdenis::.]

Originally Posted by frx

So the watch has no serial number? How can I check the harddisk?
I received them already in DFU, they told me they were Demo units (that's also written on the case) decommissioned from the store, because they switched to other models.

The (about the base info) big different between watch (Watch generation 0 1 2 and some of 3) & iPhone , watch only don't have IMEI (becoz no baseband), but other info, eg, Serial number , UDID etc all have.

So i am sure the case of yours , that's 100% hardware issue. and the harddisk if need replacement , there is no machine can reprogramming the Sn yet, so there is someway to replace the watch from apple store (but not easy )

[frx]

Thanks
Could you share an iTunes log of a successful restore?
Is there any difference in doing it with MFC?

[.::Gsmdenis::.]

Originally Posted by frx

Thanks
Could you share an iTunes log of a successful restore?
Is there any difference in doing it with MFC?

You can restore one of working watch and compare with.

There is no big difference in doing it with MFC, MFC just use same way under windows.

[frx]

Ok.
That's the only S2 watch I have so I don't have another one

[frx]

Can also try again with PurpleRestore if you want to, are there any flags/options I should check or uncheck?

[.::Gsmdenis::.]

Originally Posted by frx

Can also try again with PurpleRestore if you want to, are there any flags/options I should check or uncheck?

i did not use PurpleRestore, by default settings should be work if support

[Andrew.Pl]

Hello, i Have same problem with different errors.

Our clients came with problems after update and when i try to install version i have errors.
For example when client try update it and already installed new firmware for NFC in apple watch, and i try restore with older firmware for example with 3.2 i have error 56 because in version 3.2 i have older firmware for NFC.


What i do, i install new (4.0) version to my watch he is work normally, after then i try to install WatchOS 3.X i have errors and iTunes message on watch.

Error We always have in firmware sector, when update finished at 75-76%

I think we need wait new firmware.