search

libimobiledevice / idevicerestore

Restore/upgrade firmware of iOS devices
https://libimobiledevice.org
GNU Lesser General Public License v3.0
1.32k stars 391 forks source link

Cannot restore iphone 7 to iOS 10.1.1 #128

Open kxj0o opened 7 years ago

kxj0o commented 7 years ago

NOTE: using cached version data Found device in Normal mode Identified device as d10ap, iPhone9,1 Extracting BuildManifest from IPSW Product Version: 10.0.2 Product Build: 14A456 Major: 14 Device supports Image4: true Variant: Customer Upgrade Install (IPSW) This restore will update your device without losing data. Found ECID 2825273046270010 Getting ApNonce in normal mode... 7c 52 81 0e 7f 0e 6e 2c cf af d3 e7 b5 bb e7 f7 e8 54 fb e0 41 0c 13 c1 a4 98 02 d4 1a d9 8f 1a Trying to fetch new SHSH blob Getting SepNonce in normal mode... af c8 83 e8 43 88 77 a2 9d aa df ad 54 9b 2772 6b 26 51 bc 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 Entering recovery mode... INFO: device serial number is C6KSCVTDHG70 Extracting iBEC.d10.RELEASE.im4p... Personalizing IMG4 component iBEC... Sending iBEC (399668 bytes)... Recovery Mode Environment: iBoot build-version=iBoot-3406.1.79 iBoot build-style=RELEASE Sending AppleLogo... Extracting applelogo@2x~iphone.t8010.im4p... Personalizing IMG4 component AppleLogo... Sending AppleLogo (12820 bytes)... ramdisk-size=0x10000000 Extracting 058-50460-076.dmg... Personalizing IMG4 component RestoreRamDisk... Sending RestoreRamDisk (41121971 bytes)... Extracting DeviceTree.d10ap.im4p... Personalizing IMG4 component RestoreDeviceTree... Sending RestoreDeviceTree (149616 bytes)... Extracting kernelcache.release.d10... Personalizing IMG4 component RestoreKernelCache... Sending RestoreKernelCache (10236870 bytes)... About to restore device... Waiting for device... Device ffffffffffffffffffffffffffffffff0000002a is now connected in restore mode... Connecting now... Connected to com.apple.mobile.restored, version 14 Device ffffffffffffffffffffffffffffffff0000002a has successfully entered restore mode Hardware Information: BoardID: 8 ChipID: 32784 UniqueChipID: 2825273046270010 ProductionMode: true Starting FDR listener thread About to send NORData... Found firmware path Firmware/all_flash/all_flash.d10ap.production Getting firmware manifest Firmware/all_flash/all_flash.d10ap.production/manifest

Extracting LLB.d10.RELEASE.im4p... Personalizing IMG4 component LLB... Extracting iBoot.d10.RELEASE.im4p... Personalizing IMG4 component iBoot... Extracting DeviceTree.d10ap.im4p... Personalizing IMG4 component DeviceTree... Extracting applelogo@2x~iphone.t8010.im4p... Personalizing IMG4 component AppleLogo... Extracting recoverymode@1334~iphone-lightning.t8010.im4p... Personalizing IMG4 component RecoveryMode... Extracting batterylow0@2x~iphone.t8010.im4p... Personalizing IMG4 component BatteryLow0... Extracting batterylow1@2x~iphone.t8010.im4p... Personalizing IMG4 component BatteryLow1... Extracting batterycharging0@2x~iphone.t8010.im4p... Personalizing IMG4 component BatteryCharging0... Extracting batterycharging1@2x~iphone.t8010.im4p... Personalizing IMG4 component BatteryCharging1... Extracting glyphplugin@1334~iphone-lightning.t8010.im4p... Personalizing IMG4 component BatteryPlugin... Extracting batteryfull@2x~iphone.t8010.im4p... Personalizing IMG4 component BatteryFull... Extracting liquiddetect@1334~iphone-lightning.t8010.im4p... Personalizing IMG4 component Liquid... Extracting sep-firmware.d10.RELEASE.im4p... Personalizing IMG4 component RestoreSEP... Extracting sep-firmware.d10.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) Updating S3E Firmware (58) Checking filesystems (15) About to send FDR Trust data... Sending FDR Trust data now... Done sending FDR Trust Data Unmounting filesystems (29) Unmounting filesystems (29) Unmounting filesystems (29) Mounting filesystems (16) Mounting filesystems (16) Mounting filesystems (16) Resizing system partition (52) Unmounting filesystems (29) Unmounting filesystems (29) Unmounting filesystems (29) About to send filesystem... Connected to ASR Validating the filesystem ERROR: Unable to read OOB data from filesystem offset: No such file or directory

ERROR: ASR was unable to validate the filesystem ERROR: Unable to send filesystem ERROR: Unable to successfully restore device Got status message Status: Fail Log is available: SYSLOG: Oct 31 09:39:39 localhost bootlog[0] : BOOT_TIME 1477906779 726501 [09:39:43.0138-GMT]{4>6} CHECKPOINT NOTICE: Image4 device: AP nonce clearableentering ramrod_clear_ap_nonce [09:39:43.0153-GMT]{4>6} CHECKPOINT NOTICE: AP nonce consumed No IOFlashController instance found waiting for matching IOKit service: <CFBasicHash 0x103608d60 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x1036089b0 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:39:46.0161-GMT]{4>6} CHECKPOINT WARNING: NVRAM access is not currently available [09:39:46.0163-GMT]{4>6} CHECKPOINT PROGRESS: START (unknown) -> (presumed_initial_engine_no_return) [09:39:46.0164-GMT]{4>6} CHECKPOINT BEGIN: MAIN:[0x0400] umask [09:39:46.0165-GMT]{4>6} CHECKPOINT END: MAIN:[0x0400] umask waiting for matching IOKit service: <CFBasicHash 0x103608d60 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x1036088b0 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:39:49.0174-GMT]{4>6} CHECKPOINT BEGIN: MAIN:[0x0402] setvbuf [09:39:49.0175-GMT]{4>6} CHECKPOINT END: MAIN:[0x0402] setvbuf waiting for matching IOKit service: <CFBasicHash 0x103609ab0 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x10360a4a0 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:39:52.0183-GMT]{4>6} CHECKPOINT BEGIN: MAIN:[0x0403] kernel_logger_thread [09:39:52.0185-GMT]{4>6} CHECKPOINT END: MAIN:[0x0403] kernel_logger_threadunable to open /dev/klog: No such file or directory waiting for matching IOKit service: <CFBasicHash 0x103609af0 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103609f90 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:39:55.0195-GMT]{4>6} CHECKPOINT BEGIN: MAIN:[0x0406] set_progress_0 inverting UI colordisplay-scale = 2 display-rotation = 0 found applelogo at /usr/share/progressui/applelogo@2x.tga found display: primary display: 750 x 1334 powering on display [09:39:55.0270-GMT]{4>6} CHECKPOINT END: MAIN:[0x0406] set_progress_0 waiting for matching IOKit service: <CFBasicHash 0x10360cc00 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x10360d730 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:39:58.0277-GMT]{4>6} CHECKPOINT BEGIN: MAIN:[0x0407] start_gasgauge_thread [09:39:58.0284-GMT]{4>6} CHECKPOINT END: MAIN:[0x0407] start_gasgauge_thread waiting for matching IOKit service: <CFBasicHash 0x10360e670 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x1036101b0 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:01.0300-GMT]{4>6} CHECKPOINT BEGIN: MAIN:[0x0408] listen_for_log_client [09:40:01.0302-GMT]{4>6} CHECKPOINT END: MAIN:[0x0408] listen_for_log_client waiting for matching IOKit service: <CFBasicHash 0x1036106e0 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103610640 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:04.0310-GMT]{4>6} CHECKPOINT BEGIN: MAIN:[0x040D] create_listen_socket [09:40:04.0312-GMT]{4>6} CHECKPOINT END: MAIN:[0x040D] create_listen_socket waiting for matching IOKit service: <CFBasicHash 0x103610a90 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103610bf0 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:07.0320-GMT]{4>6} CHECKPOINT BEGIN: MAIN:[0x0404] update_root_mount [09:40:07.0327-GMT]{4>6} CHECKPOINT END: MAIN:[0x0404] update_root_mount waiting for matching IOKit service: <CFBasicHash 0x103610ad0 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103610bf0 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:10.0336-GMT]{4>6} CHECKPOINT BEGIN: MAIN:[0x0405] disable_watchdog [09:40:10.0339-GMT]{4>6} CHECKPOINT END: MAIN:[0x0405] disable_watchdog waiting for matching IOKit service: <CFBasicHash 0x103611210 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103610bf0 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:13.0347-GMT]{4>6} CHECKPOINT BEGIN: MAIN:[0x040E] enable_usb waiting for matching IOKit service: <CFBasicHash 0x1036115d0 [0x100f8ecd8]>{type = mutable dict, count = 1, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103610fe0 [0x100f8ecd8]>{contents = "AppleUSBDeviceMux"} }

[09:40:16.0378-GMT]{4>6} CHECKPOINT END: MAIN:[0x040E] enable_usb waiting for matching IOKit service: <CFBasicHash 0x10360eda0 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103611170 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

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 recv(9, 4) failed: connection closed recv(10, 4) failed: connection closed recv(12, 4) failed: connection closed unable to read message size: -1 recv(11, 4) failed: connection closed recv(14, 4) failed: connection closed recv(15, 4) failed: connection closed recv(13, 4) failed: connection closed unable to read message size: -1 recv(16, 4) failed: connection closed unable to read message size: -1 could not receive message unable to read message size: -1 unable to read message size: -1 unable to read message size: -1 recv(17, 4) failed: connection closed unable to read message size: -1 could not receive message recv(18, 4) failed: connection closed unable to read message size: -1 could not receive message recv(19, 4) failed: connection closed could not receive message could not receive message could not receive message recv(9, 4) failed: connection closed unable to read message size: -1 could not receive message unable to read message size: -1 recv(20, 4) failed: connection closed could not receive message unable to read message size: -1 unable to read message size: -1 could not receive message could not receive message unable to read message size: -1 could not receive message could not receive message could not receive message waiting for matching IOKit service: <CFBasicHash 0x1036138c0 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103610df0 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:22.0665-GMT]{4>6} CHECKPOINT WARNING: NVRAM access is not currently avail able [09:40:22.0667-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0600] client_protocol_ver sion client protocol version 14 [09:40:22.0669-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0600] client_protocol_versi on waiting for matching IOKit service: <CFBasicHash 0x103614870 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103614150 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:25.0676-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0601] copy_restore_option s UUID B2C19881-A13B-FEE7-DCC1-202AB7AE57E7 Restore options: BBUpdaterState => <CFBasicHash 0x103613640 [0x100f8ecd8]

{type = mutable dict, count = 7, entries => 0 : CertID = <CFNumber 0xb00000089ff7c593 [0x100f8ecd8]>{value = +231522 2105, type = kCFNumberSInt64Type} 1 : ChipID = <CFNumber 0xb000000009540e13 [0x100f8ecd8]>{value = +978147 3, type = kCFNumberSInt64Type} 2 : <CFString 0x103613be0 [0x100f8ecd8]>{contents = "SKeyStatus"} = <CFN umber 0xb000000000000002 [0x100f8ecd8]>{value = +0, type = kCFNumberSInt32Type} 3 : Nonce = <CFData 0x103613a60 [0x100f8ecd8]>{length = 20, capacity = 2 0, bytes = 0x9adb6a32237000eeaaa7f458380182b81a44cb48} 4 : VendorID = <CFNumber 0xb000000000000033 [0x100f8ecd8]>{value = +3, t ype = kCFNumberSInt64Type} 10 : SKeyHash = <CFData 0x103613bb0 [0x100f8ecd8]>{length = 32, capacity = 32, bytes = 0xbbefed702c2f690fb563db78d08e327a ... 6913a1688546056a} 11 : <CFString 0x103613780 [0x100f8ecd8]>{contents = "ChipSerialNo"} = < CFData 0x10360ed40 [0x100f8ecd8]>{length = 4, capacity = 4, bytes = 0xae4cf680} }

    MinimumSystemPartition         => <CFNumber 0xb00000000000cee3 [0x100f8e

cd8]>{value = +3310, type = kCFNumberSInt64Type} UUID => <CFString 0x103614170 [0x100f8ecd8]>{c ontents = "B2C19881-A13B-FEE7-DCC1-202AB7AE57E7"} SystemPartitionSize => <CFNumber 0xb00000000000cee3 [0x100f8e cd8]>{value = +3310, type = kCFNumberSInt64Type} SystemPartitionPadding => <CFBasicHash 0x103612b70 [0x100f8ecd8]

{type = mutable dict, count = 9, entries => 0 : 512 = <CFNumber 0xb000000000005003 [0x100f8ecd8]>{value = +1280, typ e = kCFNumberSInt64Type} 2 : 128 = <CFNumber 0xb000000000005003 [0x100f8ecd8]>{value = +1280, typ e = kCFNumberSInt64Type} 3 : 16 = <CFNumber 0xb000000000000a03 [0x100f8ecd8]>{value = +160, type = kCFNumberSInt64Type} 4 : 1024 = <CFNumber 0xb000000000005003 [0x100f8ecd8]>{value = +1280, ty pe = kCFNumberSInt64Type} 5 : 32 = <CFNumber 0xb000000000001403 [0x100f8ecd8]>{value = +320, type = kCFNumberSInt64Type} 6 : 768 = <CFNumber 0xb000000000005003 [0x100f8ecd8]>{value = +1280, typ e = kCFNumberSInt64Type} 7 : 8 = <CFNumber 0xb000000000000503 [0x100f8ecd8]>{value = +80, type = kCFNumberSInt64Type} 8 : 64 = <CFNumber 0xb000000000002803 [0x100f8ecd8]>{value = +640, type = kCFNumberSInt64Type} 10 : 256 = <CFNumber 0xb000000000005003 [0x100f8ecd8]>{value = +1280, ty pe = kCFNumberSInt64Type} }

    PersonalizedDuringPreflight    => <CFBoolean 0x100f8f230 [0x100f8ecd8]>{

value = true} [09:40:25.0694-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0601] copy_restore_options waiting for matching IOKit service: <CFBasicHash 0x103613c00 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x1036118d0 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:28.0702-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0655] is_host_compatible [09:40:28.0704-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0655] is_host_compatible waiting for matching IOKit service: <CFBasicHash 0x1036143f0 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103613180 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:31.0712-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0604] set_weight_from_opt ions [09:40:31.0714-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0604] set_weight_from_optio ns waiting for matching IOKit service: <CFBasicHash 0x103614060 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103614f90 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:34.0723-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0608] device_has_hoover [09:40:34.0727-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0608] device_has_hoover waiting for matching IOKit service: <CFBasicHash 0x103614470 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103615090 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:37.0736-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0609] load_sep_os 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 (0x1069100 00 - 5060902) executing /usr/libexec/seputil --restore+art - waiting for child to exit child exited exit status: 0 [09:40:39.0738-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0609] load_sep_os waiting for matching IOKit service: <CFBasicHash 0x103615750 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103613dd0 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:42.0746-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x060B] update_ticket entering ramrod_ticket_update looking up boot manifest hash crypto-hash-method found. Using SHA2-384 device tree ticket_hash: 9994287D95927D7DE6E997207B66736CB6A6FFC8FE43C90B72B78C2 94C4C3C3DB6A80228938EBC553E5FAFEC2A132573 computed ticket_hash : 9994287D95927D7DE6E997207B66736CB6A6FFC8FE43C90B72B78C2 94C4C3C3DB6A80228938EBC553E5FAFEC2A132573 received valid ticket (6280 bytes) [09:40:42.0772-GMT]{4>6} CHECKPOINT END: RESTORED:[0x060B] update_ticket waiting for matching IOKit service: <CFBasicHash 0x103615920 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103614f70 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:45.0778-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x060C] partition_nand entering partition_nand_device [09:40:45.0781-GMT]{4>6} CHECKPOINT END: RESTORED:[0x060C] partition_nand waiting for matching IOKit service: <CFBasicHash 0x103615960 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x1036137a0 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:48.0788-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x060D] wait_for_storage entering wait_for_storage_device Searching for NAND service Found NAND service: AppleEmbeddedNVMeController NAND initialized. Waiting for devnode. [09:40:48.0832-GMT]{4>6} CHECKPOINT END: RESTORED:[0x060D] wait_for_storage waiting for matching IOKit service: <CFBasicHash 0x103615fa0 [0x100f8ecd8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x1011b3630 [0x100f8ecd8]>{contents = "IOProviderClass"} = <CFString 0x103616200 [0x100f8ecd8]>{contents = "IOResources"} 2 : <CFString 0x100159800 [0x100f8ecd8]>{contents = "IOResourceMatch"} = IONVRAM }

[09:40:51.0840-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x060E] update_s3e_firmware

entering update_s3e executing /usr/local/bin/nvmefwupdater --query Non-override BFH case S3E/0000001002015095.pak S3E update file: "/usr/standalone/firmware/S3E/0000001002015095.pak" executing /usr/local/bin/nvmefwupdater --validate /usr/standalone/firmware/S3E/0 000001002015095.pak BFH params: phy tx eq=0x0 BFH params: phy rx eq=0x0 BFH params: host soc platform=0x4 BFH params: host soc revision=0x5 BFH params: indirection-size=0x8 BFH params: icc-20us-ma=0x2e4 BFH params: vcc-mv=0xb6d BFH params: imp-mohm=0xad BFH params: write-perf-mlc-mbps=0x0 BFH params: write-perf-tlc-mbps=0x0 BFH params: icc-1us-ma=0x44c BFH params: icc-5us-ma=0x384 BFH params: icc-duration-idx=0x1 BFH params: disable-pcie-phy-override=0x0 Performing BFH OFW stage... set_pci_port_state: result=0 set_pci_port_state: result=0 set_nvme_state: result=0 Validating FW... Current config: S3E, Rev B0 NANDDeviceID 0x5095 ECCVersionNANDRevision 0x0001 FTL Versions: CLog 23.1, DM 100 FTL Util FMT 8 Num configurations in update file: 1 Update config 0: S3E, Rev B0 NANDDeviceID 0x5095 ECCVersionNANDRevision 0x0001 FTL Versions: CLog 2 3.1, DM Min 100 FTL Util FMT Min 0 DriveConfig version : drive 2, update 2 Update found - regular update returning Regular update executing /usr/local/bin/nvmefwupdater --update /usr/standalone/firmware/S3E/000 0001002015095.pak Downloading FW... FW Revision from update file : 35.34.01 Activating FW... [09:40:58.0233-GMT]{4>6} CHECKPOINT END: RESTORED:[0x060E] update_s3e_firmware [09:40:58.0236-GMT]{4>6} CHECKPOINT NOTICE: Pre-existing NVRAM variable: auto-bo ot=false [09:40:58.0240-GMT]{4>6} CHECKPOINT PROGRESS: NVRAM_NO_RETURN (presumedinitial engine_no_return) -> (initial_engine_no_return) executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} executing /usr/sbin/nvram restore-step-warnings={0x11060100:{0:"NVRAM access is not currently available"}} executing /usr/sbin/nvram -s restore-outcome=initial_engine_noreturn [09:40:58.0380-GMT]{4>6} CHECKPOINT NOTICE: (NVRAM set) restore-outcome=initial engine_no_return [sync=true] (access now enabled) [09:40:58.0381-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x060F] clear_remap_variabl e executing /usr/sbin/nvram restore-step-ids={0x1103060F:21} executing /usr/sbin/nvram restore-step-names={0x1103060F:clear_remap_variable} entering clear_remap_variable executing /usr/sbin/nvram -d enable-remap-mode [09:40:58.0438-GMT]{4>6} CHECKPOINT END: RESTORED:[0x060F] clear_remap_variable executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:40:58.0471-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0610] asp_nand_set_writab le executing /usr/sbin/nvram restore-step-ids={0x11030610:22} executing /usr/sbin/nvram restore-step-names={0x11030610:asp_nand_set_writable} [09:40:58.0495-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0610] asp_nand_set_writable

executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:40:58.0520-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0611] reprobe_storage executing /usr/sbin/nvram restore-step-ids={0x11030611:23} executing /usr/sbin/nvram restore-step-names={0x11030611:reprobe_storage} entering ramrod_reprobe_device_path entering ramrod_probe_media device partitioning scheme is GPT find_filesystem_partitions: storage=/dev/disk0s1 container= system=/dev/disk0s1s 1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log= [09:40:58.0548-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0611] reprobe_storage executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:40:58.0568-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0612] reprobe_firmware executing /usr/sbin/nvram restore-step-ids={0x11030612:24} executing /usr/sbin/nvram restore-step-names={0x11030612:reprobe_firmware} entering ramrod_reprobe_device_path entering ramrod_probe_media device partitioning scheme is GPT find_filesystem_partitions: storage=/dev/disk0s1 container= system=/dev/disk0s1s 1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log= [09:40:58.0591-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0612] reprobe_firmware executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:40:58.0609-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0613] find_filesystem_par titions executing /usr/sbin/nvram restore-step-ids={0x11030613:25} executing /usr/sbin/nvram restore-step-names={0x11030613:find_filesystem_partiti ons} entering ramrod_probe_media device partitioning scheme is GPT find_filesystem_partitions: storage=/dev/disk0s1 container= system=/dev/disk0s1s 1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log= [09:40:58.0627-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0613] find_filesystem_parti tions executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:40:58.0644-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0614] mount_system_partit ion executing /usr/sbin/nvram restore-step-ids={0x11030614:26} executing /usr/sbin/nvram restore-step-names={0x11030614:mount_system_partition}

entering mount_partition executing /sbin/fsck_hfs -fdy /dev/disk0s1s1 journal_replay(/dev/disk0s1s1) returned 0 /dev/rdisk0s1s1 Using cacheBlockSize=32K cacheTotalBlock=8000 cacheSize=256000K. Executing fsck_hfs (version hfs-366). Checking Journaled HFS Plus volume. Detected a case-sensitive volume. The volume name is Whitetail14A456.D10D101OS Checking extents overflow file. Checking catalog file. Checking multi-linked files. Checking catalog hierarchy. Checking extended attributes file. Checking volume bitmap. Checking volume information. Trimming unused blocks. The volume Whitetail14A456.D10D101OS appears to be OK. CheckHFS returned 0, fsmodified = 0 executing /sbin/mount_hfs -o rdonly /dev/disk0s1s1 /mnt1 mount_hfs: Could not create property for re-key environment check: No such file or directory /dev/disk0s1s1 mounted on /mnt1 System mounted read-only [09:41:02.0523-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0614] mount_system_partitio n executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:02.0563-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0654] read_previous_os_bu ild_version executing /usr/sbin/nvram restore-step-ids={0x11030654:27} executing /usr/sbin/nvram restore-step-names={0x11030654:read_previous_osbuild version} ramrod_read_previous_os_build_version: previous OS version: (null) [09:41:02.0599-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0654] read_previous_os_buil d_version executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:02.0626-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0615] copy_hardware_info executing /usr/sbin/nvram restore-step-ids={0x11030615:28} executing /usr/sbin/nvram restore-step-names={0x11030615:copy_hardware_info} [09:41:02.0650-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0615] copy_hardware_info executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:02.0672-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0616] fdr_create executing /usr/sbin/nvram restore-step-ids={0x11030616:29} executing /usr/sbin/nvram restore-step-names={0x11030616:fdr_create} 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] : a6 81 5a 5b df 23 7d 7d e3 89 20 b0 ff 6f ae 1e : 09 50 3d 7f : ----------------------------------------------- CryptoGenerateRSAKeys: SecKeyCreateRSAPrivateKey -> 0x1001641b8 CryptoGenerateRSAKeys: SecKeyCreateRSAPublicKey -> 0x1001641b0 created HTTP FDR client 0x1040024d0 created local FDR client 0x103625fd0 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 [09:41:03.0648-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0616] fdr_create executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:03.0661-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0617] fdr_save_data executing /usr/sbin/nvram restore-step-ids={0x11030617:30} executing /usr/sbin/nvram restore-step-names={0x11030617:fdr_save_data} _AMFDRSealingMapCopyDataClassesAndInstances: amfdr data version changed. Old ver sion: (null) new version 1 AMFDRSealedDataPopulate: IM4M found, storing in options. AMFDRSetOptions: amfdr is NULL saved FDR persistent state RestoredFDRSaveData success [09:41:03.0701-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0617] fdr_save_data executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:03.0713-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0618] store_baseband_migr ation executing /usr/sbin/nvram restore-step-ids={0x11030618:31} executing /usr/sbin/nvram restore-step-names={0x11030618:store_baseband_migratio n} [09:41:03.0728-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0618] store_baseband_migrat ion executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:03.0742-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0619] check_for_restore_l og executing /usr/sbin/nvram restore-step-ids={0x11030619:32} executing /usr/sbin/nvram restore-step-names={0x11030619:check_for_restore_log} entering check_for_restore_log restore log does not exist [09:41:03.0757-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0619] check_for_restore_log

executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:03.0771-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x061A] check_for_ota_logs executing /usr/sbin/nvram restore-step-ids={0x1103061A:33} executing /usr/sbin/nvram restore-step-names={0x1103061A:check_for_ota_logs} entering mount_partition executing /sbin/fsck_hfs -fdy /dev/disk0s1s2 journal_replay(/dev/disk0s1s2) returned 0 /dev/rdisk0s1s2 Using cacheBlockSize=32K cacheTotalBlock=8000 cacheSize=256000K. Executing fsck_hfs (version hfs-366). Checking Journaled HFS Plus volume. Detected a case-sensitive volume. The volume name is Data Checking extents overflow file. Checking catalog file. Checking multi-linked files. Checking catalog hierarchy. Checking extended attributes file. Checking volume bitmap. Checking volume information. Trimming unused blocks. The volume Data appears to be OK. CheckHFS returned 0, fsmodified = 0 executing /sbin/mount_hfs /dev/disk0s1s2 /mnt2 mount_hfs: Could not create property for re-key environment check: No such file or directory /dev/disk0s1s2 mounted on /mnt2 Data mounted read-write entering dump_panic_logs executing /System/Library/CoreServices/DumpPanic -r/mnt2/mobile/Library/Logs/Cra shReporter entering check_for_ota_logs_with_mount_point Diagnostic log submission disabled Searching /mnt2/mobile/Library/Logs/CrashReporter/ check_for_ota_logs_with_mount_point complete Successfully checked for OTA logs [09:41:05.0822-GMT]{4>6} CHECKPOINT END: RESTORED:[0x061A] check_for_ota_logs executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:05.0862-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x061B] submit_update_stats

executing /usr/sbin/nvram restore-step-ids={0x1103061B:34} executing /usr/sbin/nvram restore-step-names={0x1103061B:submit_update_stats} entering mount_partition executing /sbin/mount -u -o -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: R PNotificationProxyAvailable AMSupportHttpCopyProxySettings: proxyInfo = <CFBasicHash 0x103627970 [0x100f8ecd 8]>{type = mutable dict, count = 2, entries => 0 : <CFString 0x100f68970 [0x100f8ecd8]>{contents = "SOCKSProxy"} = <CFS tring 0x100204c30 [0x100f8ecd8]>{contents = "127.0.0.1"} 1 : <CFString 0x100f68990 [0x100f8ecd8]>{contents = "SOCKSPort"} = <CFNu mber 0xb000000000004392 [0x100f8ecd8]>{value = +1081, type = kCFNumberSInt32Type } }

calling UMEventShimSubmitEvents UMEventShimSubmitEvents done [09:41:05.0975-GMT]{4>6} CHECKPOINT END: RESTORED:[0x061B] submit_update_stats executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:05.0998-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x061D] unmount_uncondition ally executing /usr/sbin/nvram restore-step-ids={0x1103061D:35} executing /usr/sbin/nvram restore-step-names={0x1103061D:unmount_unconditionally } entering unmount_filesystems entering unmount_partition System unmounted entering unmount_partition Data unmounted create_baseband_update_options: Got imeisv: 3 baseband updater options = <CFBasicHash 0x10362ede0 [0x100f8ecd8]>{type = mutabl e dict, count = 4, entries => 0 : <CFString 0x100397b80 [0x100f8ecd8]>{contents = "IMEISwVersion"} = < CFNumber 0xb000000000000032 [0x100f8ecd8]>{value = +3, type = kCFNumberSInt32Typ e} 1 : <CFString 0x100397b40 [0x100f8ecd8]>{contents = "restoredInternal"} = <CFBoolean 0x100f8f240 [0x100f8ecd8]>{value = false} 2 : <CFString 0x100397ca0 [0x100f8ecd8]>{contents = "autoSetupEnv"} = <C FBoolean 0x100f8f240 [0x100f8ecd8]>{value = false} 6 : <CFString 0x100397cc0 [0x100f8ecd8]>{contents = "disablePing"} = <CF Boolean 0x100f8f230 [0x100f8ecd8]>{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 0x104008 370 [0x100f8ecd8]>{type = mutable dict, count = 3, entries => 0 : <CFString 0x100397520 [0x100f8ecd8]>{contents = "requireUserPartitio nMount"} = <CFBoolean 0x100f8f230 [0x100f8ecd8]>{value = true} 1 : <CFString 0x100397540 [0x100f8ecd8]>{contents = "requestedBasebandFS PartitionMountSize"} = <CFNumber 0xb0000000000000a3 [0x100f8ecd8]>{value = +10, type = kCFNumberSInt64Type} 2 : <CFString 0x1003974e0 [0x100f8ecd8]>{contents = "requireSystemPartit ionMount"} = <CFBoolean 0x100f8f230 [0x100f8ecd8]>{value = true} }

entering unmount_partition Baseband Data not mounted (unmount ignored) [09:41:06.0043-GMT]{4>6} CHECKPOINT END: RESTORED:[0x061D] unmount_unconditional ly executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:06.0067-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x064E] clean_nand executing /usr/sbin/nvram restore-step-ids={0x1103064E:36} executing /usr/sbin/nvram restore-step-names={0x1103064E:clean_nand} entering clean_NAND [09:41:06.0086-GMT]{4>6} CHECKPOINT END: RESTORED:[0x064E] clean_nand executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:06.0106-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0653] create_readwrite_ra mdisk executing /usr/sbin/nvram restore-step-ids={0x11030653:37} executing /usr/sbin/nvram restore-step-names={0x11030653:create_readwrite_ramdis k} executing /usr/sbin/hdik -nomount ram://5120 /dev/disk3 executing /sbin/newfs_hfs -J -v readwrite_ramdisk /dev/disk3 Initialized /dev/rdisk3 as a 3 MB case-insensitive HFS Plus volume with a 512k j ournal executing /sbin/fsck_hfs -fdy /dev/disk3 journal_replay(/dev/disk3) returned 0 /dev/rdisk3 Using cacheBlockSize=32K cacheTotalBlock=8000 cacheSize=256000K. Executing fsck_hfs (version hfs-366). 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/disk3 /mnt5 mount_hfs: Could not create property for re-key environment check: No such file or directory [09:41:06.0189-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0653] create_readwrite_ramd isk executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:06.0200-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x061F] format_effaceable_s torage executing /usr/sbin/nvram restore-step-ids={0x1103061F:38} executing /usr/sbin/nvram restore-step-names={0x1103061F:format_effaceable_stora ge} entering format_effaceable_storage effaceable storage is formatted, nothing to do [09:41:06.0212-GMT]{4>6} CHECKPOINT END: RESTORED:[0x061F] format_effaceable_sto rage executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:06.0225-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0643] get_apfs_options executing /usr/sbin/nvram restore-step-ids={0x11030643:39} executing /usr/sbin/nvram restore-step-names={0x11030643:get_apfs_options} [09:41:06.0236-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0643] get_apfs_options executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:06.0247-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0620] format_storagefor lwvm executing /usr/sbin/nvram restore-step-ids={0x11030620:40} executing /usr/sbin/nvram restore-step-names={0x11030620:format_storage_for_lwvm } entering format_storage_for_LwVM [09:41:06.0259-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0620] format_storage_for_lw vm executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:06.0270-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0622] reload_filesystem_p artitions executing /usr/sbin/nvram restore-step-ids={0x11030622:41} executing /usr/sbin/nvram restore-step-names={0x11030622:reload_filesystem_parti tions} entering ramrod_probe_media device partitioning scheme is GPT find_filesystem_partitions: storage=/dev/disk0s1 container= system=/dev/disk0s1s 1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log= [09:41:06.0282-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0622] reload_filesystem_par titions executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:06.0293-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0645] check_not_apfs_moun t executing /usr/sbin/nvram restore-step-ids={0x11030645:42} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount} [09:41:06.0304-GMT]{4>6} CHECKPOINT BEGIN: NOT_APFS:[0x0900] basebandmigration data executing /usr/sbin/nvram restore-step-ids={0x11030645:42;0x11030900:43} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount;0x 11030900:baseband_migration_data} [09:41:06.0316-GMT]{4>6} CHECKPOINT END: NOT_APFS:[0x0900] baseband_migration_da ta executing /usr/sbin/nvram restore-step-ids={0x11030645:42} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount} [09:41:06.0327-GMT]{4>6} CHECKPOINT BEGIN: NOT_APFS:[0x0901] check_update executing /usr/sbin/nvram restore-step-ids={0x11030645:42;0x11030901:44} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount;0x 11030901:check_update} [09:41:06.0338-GMT]{4>6} CHECKPOINT BEGIN: NOT_APFS_UPDATE:[0x0A00] roll_media_k eys executing /usr/sbin/nvram restore-step-ids={0x11030645:42;0x11030901:44;0x11030A 00:45} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount;0x 11030901:check_update;0x11030A00:roll_media_keys} ramrod_roll_media_keys: data_partition = /dev/disk0s1s2 ramrod_roll_media_keys: storage_media = /dev/disk0s1 ramrod_roll_media_keys: data_partition_name = disk0s1s2 ramrod_roll_media_keys: data_partition_uuid = 72B14E27-3DC5-C948-B5B9-9BAB65F6B2 62 executing /usr/sbin/nvram enable-rolld-dev1=/dev/disk0s1s2 [09:41:06.0356-GMT]{4>6} CHECKPOINT END: NOT_APFS_UPDATE:[0x0A00] roll_media_key s executing /usr/sbin/nvram restore-step-ids={0x11030645:42;0x11030901:44} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount;0x 11030901:check_update} [09:41:06.0367-GMT]{4>6} CHECKPOINT BEGIN: NOT_APFS_UPDATE:[0x0A02] options_syst em_image executing /usr/sbin/nvram restore-step-ids={0x11030645:42;0x11030901:44;0x11030A 02:46} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount;0x 11030901:check_update;0x11030A02:options_system_image} [09:41:06.0378-GMT]{4>6} CHECKPOINT BEGIN: NOT_APFS_OPTIONS:[0x0B00] mount_files ystem executing /usr/sbin/nvram restore-step-ids={0x11030645:42;0x11030901:44;0x11030A 02:46;0x11030B00:47} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount;0x 11030901:check_update;0x11030A02:options_system_image;0x11030B00:mount_filesyste m} entering mount_filesystems entering mount_partition executing /sbin/fsck_hfs -fdy /dev/disk0s1s1 journal_replay(/dev/disk0s1s1) returned 0 /dev/rdisk0s1s1 Using cacheBlockSize=32K cacheTotalBlock=8000 cacheSize=256000K. Executing fsck_hfs (version hfs-366). Checking Journaled HFS Plus volume. Detected a case-sensitive volume. The volume name is Whitetail14A456.D10D101OS Checking extents overflow file. Checking catalog file. Checking multi-linked files. Checking catalog hierarchy. Checking extended attributes file. Checking volume bitmap. Checking volume information. Trimming unused blocks. The volume Whitetail14A456.D10D101OS appears to be OK. CheckHFS returned 0, fsmodified = 0 executing /sbin/mount_hfs /dev/disk0s1s1 /mnt1 /dev/disk0s1s1 mounted on /mnt1 System mounted read-write entering mount_partition executing /sbin/fsck_hfs -fdy /dev/disk0s1s2 journal_replay(/dev/disk0s1s2) returned 0 /dev/rdisk0s1s2 Using cacheBlockSize=32K cacheTotalBlock=8000 cacheSize=256000K. Executing fsck_hfs (version hfs-366). Checking Journaled HFS Plus volume. Detected a case-sensitive volume. The volume name is Data Checking extents overflow file. Checking catalog file. Checking multi-linked files. Checking catalog hierarchy. Checking extended attributes file. Checking volume bitmap. Checking volume information. Trimming unused blocks. The volume Data appears to be OK. CheckHFS returned 0, fsmodified = 0 executing /sbin/mount_hfs /dev/disk0s1s2 /mnt2 mount_hfs: Could not exec re-keying daemon /usr/libexec/rolld: No such file or d irectory /dev/disk0s1s2 mounted on /mnt2 Data mounted read-write entering mount_partition executing /sbin/fsck_hfs -fdy /dev/disk0s1s3 journal_replay(/dev/disk0s1s3) returned 0 /dev/rdisk0s1s3 Using cacheBlockSize=32K cacheTotalBlock=8000 cacheSize=256000K. Executing fsck_hfs (version hfs-366). Checking Journaled HFS Plus volume. Detected a case-sensitive volume. The volume name is Baseband Data Checking extents overflow file. Checking catalog file. Checking multi-linked files. Checking catalog hierarchy. Checking extended attributes file. Checking volume bitmap. Checking volume information. Trimming unused blocks. The volume Baseband Data appears to be OK. CheckHFS returned 0, fsmodified = 0 executing /sbin/mount_hfs /dev/disk0s1s3 /mnt3 /dev/disk0s1s3 mounted on /mnt3 Baseband Data mounted read-write [09:41:12.0257-GMT]{4>6} CHECKPOINT END: NOT_APFS_OPTIONS:[0x0B00] mount_filesys tem executing /usr/sbin/nvram restore-step-ids={0x11030645:42;0x11030901:44;0x11030A 02:46} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount;0x 11030901:check_update;0x11030A02:options_system_image} [09:41:12.0271-GMT]{4>6} CHECKPOINT BEGIN: NOT_APFS_OPTIONS:[0x0B01] resize_syst em_partition executing /usr/sbin/nvram restore-step-ids={0x11030645:42;0x11030901:44;0x11030A 02:46;0x11030B01:48} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount;0x 11030901:check_update;0x11030A02:options_system_image;0x11030B01:resize_system_p artition} entering resize_system_partition_to_options User specified system partition size, skipping firmware extras size. Found SystemPartitionPadding value of 335544320 bytes Erasing system partition prior to resize operation. entering unmount_partition System unmounted block size for /dev/disk0s1s1: 4096 /sbin/newfs_hfs -s -J -v System /dev/disk0s1s1 executing /sbin/newfs_hfs -s -J -v System /dev/disk0s1s1 Initialized /dev/rdisk0s1s1 as a 4 GB case-sensitive HFS Plus volume with a 8192 k journal entering mount_partition executing /sbin/fsck_hfs -fdy /dev/disk0s1s1 journal_replay(/dev/disk0s1s1) returned 0 /dev/rdisk0s1s1 Using cacheBlockSize=32K cacheTotalBlock=8000 cacheSize=256000K. Executing fsck_hfs (version hfs-366). Checking Journaled HFS Plus volume. Detected a case-sensitive volume. The volume name is System Checking extents overflow file. Checking catalog file. Checking multi-linked files. Checking catalog hierarchy. Checking extended attributes file. Checking volume bitmap. Checking volume information. Trimming unused blocks. The volume System appears to be OK. CheckHFS returned 0, fsmodified = 0 executing /sbin/mount_hfs /dev/disk0s1s1 /mnt1 /dev/disk0s1s1 mounted on /mnt1 System mounted read-write Trying to resize system partition to 3806330880 bytes aka 3630 MB entering adjust_partition_preflight partition:0 requiredSize=929280 scanning 6 filesystems for F0BC4E2D-B19C-4373-8CD8-2F582959A14D failed to find an IOService owning md0 (unexpected) device matching disk3 has no UUID (unexpected) UUID 72B14E27-3DC5-C948-B5B9-9BAB65F6B262 does not match UUID 837B0852-FE55-4140-8458-1A56ADB3939E does not match UUID F0BC4E2D-B19C-4373-8CD8-2F582959A14D match matched /dev/disk0s1s1 on /mnt1 scanning 6 filesystems for F0BC4E2D-B19C-4373-8CD8-2F582959A14D failed to find an IOService owning md0 (unexpected) device matching disk3 has no UUID (unexpected) UUID 72B14E27-3DC5-C948-B5B9-9BAB65F6B262 does not match UUID 837B0852-FE55-4140-8458-1A56ADB3939E does not match UUID F0BC4E2D-B19C-4373-8CD8-2F582959A14D match matched /dev/disk0s1s1 on /mnt1 Device disk0s1s1 has UUID F0BC4E2D-B19C-4373-8CD8-2F582959A14D scanning 6 filesystems for 72B14E27-3DC5-C948-B5B9-9BAB65F6B262 failed to find an IOService owning md0 (unexpected) device matching disk3 has no UUID (unexpected) UUID 72B14E27-3DC5-C948-B5B9-9BAB65F6B262 match matched /dev/disk0s1s2 on /mnt2 Device disk0s1s2 has UUID 72B14E27-3DC5-C948-B5B9-9BAB65F6B262 scanning 6 filesystems for 837B0852-FE55-4140-8458-1A56ADB3939E failed to find an IOService owning md0 (unexpected) device matching disk3 has no UUID (unexpected) UUID 72B14E27-3DC5-C948-B5B9-9BAB65F6B262 does not match UUID 837B0852-FE55-4140-8458-1A56ADB3939E match matched /dev/disk0s1s3 on /mnt3 Device disk0s1s3 has UUID 837B0852-FE55-4140-8458-1A56ADB3939E shrinking HFS filesystem on /mnt1 from 3880923136 to 3806330880 Resizing existing partition with GPT index 0 entering _unmount_lwvm_devices Unmounting filesystem: /mnt3 Unmounting filesystem: /mnt2 Unmounting filesystem: /mnt1 About to adjust with settings partitionIndex:0 size:3630 MB Wrote GPT header back to disk Remounting LwVM volumes that were previously mounted entering _mount_lwvm_devices libpartition, mounting '/dev/disk0s1s1' at '/mnt1' executing /sbin/mount -t hfs /dev/disk0s1s1 /mnt1 libpartition, mounting '/dev/disk0s1s2' at '/mnt2' executing /sbin/mount -t hfs /dev/disk0s1s2 /mnt2 mount_hfs: Could not exec re-keying daemon /usr/libexec/rolld: No such file or d irectory libpartition, mounting '/dev/disk0s1s3' at '/mnt3' executing /sbin/mount -t hfs /dev/disk0s1s3 /mnt3 Successfully resized the system partition entering maximize_data_partition entering adjust_partition_preflight partition:1 requiredSize=4503599627370496 maximizing data partition to 27892166655 bytes entering adjust_partition_preflight partition:1 requiredSize=6809611 scanning 6 filesystems for 72B14E27-3DC5-C948-B5B9-9BAB65F6B262 failed to find an IOService owning md0 (unexpected) device matching disk3 has no UUID (unexpected) UUID F0BC4E2D-B19C-4373-8CD8-2F582959A14D does not match UUID 72B14E27-3DC5-C948-B5B9-9BAB65F6B262 match matched /dev/disk0s1s2 on /mnt2 scanning 6 filesystems for F0BC4E2D-B19C-4373-8CD8-2F582959A14D failed to find an IOService owning md0 (unexpected) device matching disk3 has no UUID (unexpected) UUID F0BC4E2D-B19C-4373-8CD8-2F582959A14D match matched /dev/disk0s1s1 on /mnt1 Device disk0s1s1 has UUID F0BC4E2D-B19C-4373-8CD8-2F582959A14D scanning 6 filesystems for 72B14E27-3DC5-C948-B5B9-9BAB65F6B262 failed to find an IOService owning md0 (unexpected) device matching disk3 has no UUID (unexpected) UUID F0BC4E2D-B19C-4373-8CD8-2F582959A14D does not match UUID 72B14E27-3DC5-C948-B5B9-9BAB65F6B262 match matched /dev/disk0s1s2 on /mnt2 Device disk0s1s2 has UUID 72B14E27-3DC5-C948-B5B9-9BAB65F6B262 scanning 6 filesystems for 837B0852-FE55-4140-8458-1A56ADB3939E failed to find an IOService owning md0 (unexpected) device matching disk3 has no UUID (unexpected) UUID F0BC4E2D-B19C-4373-8CD8-2F582959A14D does not match UUID 72B14E27-3DC5-C948-B5B9-9BAB65F6B262 does not match UUID 837B0852-FE55-4140-8458-1A56ADB3939E match matched /dev/disk0s1s3 on /mnt3 Device disk0s1s3 has UUID 837B0852-FE55-4140-8458-1A56ADB3939E Resizing existing partition with GPT index 1 entering _unmount_lwvm_devices Unmounting filesystem: /mnt3 Unmounting filesystem: /mnt2 Unmounting filesystem: /mnt1 About to adjust with settings partitionIndex:1 size:26600 MB Wrote GPT header back to disk Remounting LwVM volumes that were previously mounted entering _mount_lwvm_devices libpartition, mounting '/dev/disk0s1s1' at '/mnt1' executing /sbin/mount -t hfs /dev/disk0s1s1 /mnt1 libpartition, mounting '/dev/disk0s1s2' at '/mnt2' executing /sbin/mount -t hfs /dev/disk0s1s2 /mnt2 mount_hfs: Could not exec re-keying daemon /usr/libexec/rolld: No such file or d irectory libpartition, mounting '/dev/disk0s1s3' at '/mnt3' executing /sbin/mount -t hfs /dev/disk0s1s3 /mnt3 expanding HFS filesystem on /mnt2 to 27892166656 Successfully resized data partition to consume free blocks The system partition now has a total HFS+ capacity of 3630 MB entering ramrod_probe_media device partitioning scheme is GPT find_filesystem_partitions: storage=/dev/disk0s1 container= system=/dev/disk0s1s 1 data=/dev/disk0s1s2 baseband data=/dev/disk0s1s3 log= [09:41:13.0796-GMT]{4>6} CHECKPOINT END: NOT_APFS_OPTIONS:[0x0B01] resize_system _partition executing /usr/sbin/nvram restore-step-ids={0x11030645:42;0x11030901:44;0x11030A 02:46} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount;0x 11030901:check_update;0x11030A02:options_system_image} [09:41:13.0815-GMT]{4>6} CHECKPOINT BEGIN: NOT_APFS_OPTIONS:[0x0B02] unmount_fil esystem executing /usr/sbin/nvram restore-step-ids={0x11030645:42;0x11030901:44;0x11030A 02:46;0x11030B02:49} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount;0x 11030901:check_update;0x11030A02:options_system_image;0x11030B02:unmount_filesys tem} entering unmount_filesystems entering unmount_partition System unmounted entering unmount_partition Data unmounted entering unmount_partition Baseband Data unmounted [09:41:13.0859-GMT]{4>6} CHECKPOINT END: NOT_APFS_OPTIONS:[0x0B02] unmount_files ystem executing /usr/sbin/nvram restore-step-ids={0x11030645:42;0x11030901:44;0x11030A 02:46} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount;0x 11030901:check_update;0x11030A02:options_system_image} [09:41:13.0880-GMT]{4>6} CHECKPOINT END: NOT_APFS_UPDATE:[0x0A02] options_system _image executing /usr/sbin/nvram restore-step-ids={0x11030645:42;0x11030901:44} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount;0x 11030901:check_update} [09:41:13.0900-GMT]{4>6} CHECKPOINT END: NOT_APFS:[0x0901] check_update executing /usr/sbin/nvram restore-step-ids={0x11030645:42} executing /usr/sbin/nvram restore-step-names={0x11030645:check_not_apfs_mount} [09:41:13.0919-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0645] check_not_apfs_mount executing /usr/sbin/nvram restore-step-ids={} executing /usr/sbin/nvram restore-step-names={} [09:41:13.0936-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0624] restore_images executing /usr/sbin/nvram restore-step-ids={0x11030624:50} executing /usr/sbin/nvram restore-step-names={0x11030624:restore_images} entering restore_images OS digest = 410e2bae06b114548e2b3cf2d4032d5a70652f2d12181d460f8aa351fef4c2babd24 d85f209b62f2736d9600b6daf4c2 Successfully marked device node /dev/rdisk0s1s1 as static data executing /usr/sbin/asr -source asr://localhost:12345 -target /dev/disk0s1s1 -er ase -noprompt --chunkchecksum --puppetstrings --expectedhash 410e2bae06b114548e2 b3cf2d4032d5a70652f2d12181d460f8aa351fef4c2babd24d85f209b62f2736d9600b6daf4c2 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: Can't gather image metadata ASR: Could not validate source - error 110 ASR STATUS: fail [09:41:16.0133-GMT]{4>6} CHECKPOINT FAILURE:(FAILURE:14) RESTORED:[0x0624] resto re_images [0]D(failed to restore image) executing /usr/sbin/nvram restore-step-results={0x11070624:{0:14}} executing /usr/sbin/nvram restore-step-codes={0x11070624:{0:14}} executing /usr/sbin/nvram restore-step-domains={0x11070624:{0:"AMRestoreErrorDom ain"}} executing /usr/sbin/nvram -s restore-step-error={0x11070624:"[0]D(failed to rest ore image)"} [09:41:16.0202-GMT]{4>6} CHECKPOINT NOTICE: (NVRAM set) restore-step-error={0x11 070624:"[0]D(failed to restore image)"} [sync=true] (first failure) [09:41:16.0203-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0647] cleanup_check_resul t executing /usr/sbin/nvram restore-step-ids={0x11030624:50;0x11030647:51} executing /usr/sbin/nvram restore-step-names={0x11030624:restore_images;0x110306 47:cleanup_check_result} [09:41:16.0229-GMT]{4>6} CHECKPOINT END: RESTORED:[0x0647] cleanup_check_result executing /usr/sbin/nvram restore-step-ids={0x11030624:50} executing /usr/sbin/nvram restore-step-names={0x11030624:restore_images} [09:41:16.0254-GMT]{4>6} CHECKPOINT BEGIN: RESTORED:[0x0648] cleanup_sendfinal status executing /usr/sbin/nvram restore-step-ids={0x11030624:50;0x11030648:52} executing /usr/sbin/nvram restore-step-names={0x11030624:restore_images;0x110306 48:cleanup_send_final_status}

ERROR: Unable to restore device

EdelMaks commented 7 years ago

I have same error: "ERROR: Unable to read OOB data from filesystem offset" with iPhone 5s (trying to flash the latest image, which is downloaded correctly).

nikias commented 7 years ago

Well it seems like fread() fails to read from the specified offset. It seems to me that for some reason idevicerestore wasn't configured with large file support so it might not be able to read the file properly. Check the autogen.sh (configure) output.