5s Restoring problem
Hi,
Hi my wife has a 5s. Few months ago, it's IMEI adress has been disappeared unexpectedly.
When I try to restore the device from recovery mode, in the initial process itunes recognizes and displays the serial number. (See also: pic -1)
However when it proceeds to next step, serial number turns into n/a. (See also: pic -2)
Finally, as you can see in the error log (at the bottom), in the middle of the restoring process system returns Error -1.
If you could help us, we would be so glad.
Best,
pic-1
pic -2
ERROR LOG
2015-11-02 21:58:06.823 [5816:1890]: restore library built Sep 28 2015 at 12:12:56
2015-11-02 21:58:06.823 [5816:1890]: iTunes: iTunes 12.3.1.23
2015-11-02 21:58:06.823 [5816:1890]: iTunes: Software payload version: 13B143
2015-11-02 21:58:06.823 [5816:1890]: iTunes: Using MobileRestore state machine
[19:59:05.0923] Changing state from '(null)' to 'Restoring'
[19:59:05.0925] requested restore behavior: Erase
[19:59:05.0926] requested restore behavior: Erase
[19:59:05.0926] requested variant: Erase
[19:59:05.0935] requested restore behavior: Erase
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: withApTicket is True
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "RestoreLogo" Digest = "<CFData 000000005FB5C900 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0x3c57f69ff783a50bf8b1472c9d07672e9f336266}"
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "RestoreDeviceTree" Digest = "<CFData 000000005FB5CA50 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0xbe69bbb9a963d902067bc7de976bf20ea93104b2}"
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "RestoreKernelCache" Digest = "<CFData 000000005FB5CBA0 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0xfdc9c47031f6fb5d824384b171d4a245d190e9e8}"
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "RestoreRamDisk" Digest = "<CFData 000000005FB5D620 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0xe495bbf17740b313c28a70d22a955e0ae6f9218e}"
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "iBEC" Digest = "<CFData 000000005FB5D3F0 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0x080e77d4de5dd3ca94c93064b2abba40e65f8648}"
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "iBSS" Digest = "<CFData 000000005FB5D460 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0x879ae1b84713334a6c9ef69ed0e06276c6936d4c}"
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "KernelCache" Digest = "<CFData 000000005FB5D150 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0xc3479ea2942e511e58ef104777a4d125d1d84d74}"
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "ftap" not part of manifest, skipping
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "rfta" not part of manifest, skipping
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "ftsp" not part of manifest, skipping
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "rfts" not part of manifest, skipping
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "Diags" not part of manifest, skipping
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "CFELoader" not part of manifest, skipping
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RBM" not part of manifest, skipping
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "PHLEET" not part of manifest, skipping
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "PERTOS" not part of manifest, skipping
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "PEHammer" not part of manifest, skipping
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "LLB" Digest = "<CFData 000000005FB5D1C0 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0x3055326b443fac9ef2f52c31b9a21cd17e5d82b4}"
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "AppleLogo" Digest = "<CFData 000000005FB5CEB0 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0xb1660491f866297aa43e24c958b669d57c936657}"
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "BatteryCharging1" Digest = "<CFData 000000005FB5CF20 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0x12bb2f6361cb6d952345187e9c49fc466c9023c8}"
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "SEP" Digest = "<CFData 000000005FE6A740 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0xb608557b79e877d013595f6671c1570d21a293f8}"
[19:59:06.0199] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "BatteryFull" Digest = "<CFData 000000005FE6A7B0 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0x1fd045f87e0da994edf19b93b683ff6d69a96456}"
[19:59:06.0200] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "BatteryPlugin" Digest = "<CFData 000000005FE6B540 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0xb4cf74f8b24bbad09a764599b8e13485997609bc}"
[19:59:06.0200] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "BatteryCharging0" Digest = "<CFData 000000005FE6B310 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0x1bc8973e2a570f29d9941a1ecb0d39b9878fd822}"
[19:59:06.0200] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "BatteryLow1" Digest = "<CFData 000000005FE6B2A0 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0x6cb1fa36e8151660050826720d9c2dce3392a8ed}"
[19:59:06.0200] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "RestoreSEP" Digest = "<CFData 000000005FE6B070 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0x0ee56669432cffec5a15da9f00679f79af634fd9}"
[19:59:06.0200] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "BatteryLow0" Digest = "<CFData 000000005FE6B000 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0xf0106bd40e44553480cbf8cc764aa5e6eeeb52dd}"
[19:59:06.0200] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "RecoveryMode" Digest = "<CFData 000000005FE6ADD0 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0xa8ebb63a394e455e03662c38f3bb3abb1e00b7df}"
[19:59:06.0200] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "DeviceTree" Digest = "<CFData 000000005FE6AD60 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0x03c8e65ff5c7942212329aecba25024f452baff5}"
[19:59:06.0200] amai: _AMAuthInstallBundleCreateServerRequestDictionary: personalizing "iBoot" Digest = "<CFData 000000005FE6AB30 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0xdeece83d7416339af403c1bcb1ce6d1a1838485b}"
[19:59:06.0200] amai: _AMAuthInstallBundleCreateServerRequestDictionary: using UniqueBuildID <CFData 0000000068DC2B40 [000007FEE2038100]>{length = 20, capacity = 20, bytes = 0x4e6d022e0ef8f203254bc67cd6d887337276a516}
[19:59:06.0201] amai: AMAuthInstallRequestSendSync: SSO function returned NULL and no SSO token was provided, SSO disabled.
[19:59:06.0203] amai: tss_submit_job_with_retry: TSS Connection attempt 1 of 3. (Will retry if TSS_ERR_SERVER_NOT_REACHABLE.)
[19:59:07.0286] amai: tss_submit_job: HttpQueryInfo returned 200
[19:59:07.0437] amai: AMAuthInstallRequestSendSync: received tss response (server version: 2.1.0)
[19:59:07.0555] amai: _AMAuthInstallBundlePopulatePersonalizedBundle: no entry in manifest found for "ftap"
[19:59:07.0555] amai: _AMAuthInstallBundlePopulatePersonalizedBundle: no entry in manifest found for "rfta"
[19:59:07.0555] amai: _AMAuthInstallBundlePopulatePersonalizedBundle: no entry in manifest found for "ftsp"
[19:59:07.0555] amai: _AMAuthInstallBundlePopulatePersonalizedBundle: no entry in manifest found for "rfts"
[19:59:07.0555] amai: _AMAuthInstallBundlePopulatePersonalizedBundle: no entry in manifest found for "Diags"
[19:59:07.0556] amai: _AMAuthInstallBundlePopulatePersonalizedBundle: no entry in manifest found for "CFELoader"
[19:59:07.0556] amai: _AMAuthInstallBundlePopulatePersonalizedBundle: no entry in manifest found for "RBM"
[19:59:07.0556] amai: _AMAuthInstallBundlePopulatePersonalizedBundle: no entry in manifest found for "PHLEET"
[19:59:07.0556] amai: _AMAuthInstallBundlePopulatePersonalizedBundle: no entry in manifest found for "PERTOS"
[19:59:07.0556] amai: _AMAuthInstallBundlePopulatePersonalizedBundle: no entry in manifest found for "PEHammer"
[19:59:07.0649] iBoot build-version = iBoot-2261.20.20
[19:59:07.0650] iBoot build-style = RELEASE
[19:59:07.0650] requested restore behavior: Erase
[19:59:07.0652] requested restore behavior: Erase
[19:59:07.0653] unable to open device_map.txt: No such file or directory
[19:59:07.0654] found device map entry for 0x00008960 0x00000000. boardConfig=n51ap platform=s5l8960x
[19:59:07.0654] _AMRestoreCopyDeviceMapPlistEntryForHardware: firmwareDirectory not in options
[19:59:07.0654] AMDeviceIoControl: GetOverlappedResult failed
[19:59:07.0654] AMDeviceIoControl: pipe stall
[19:59:07.0654] USBControlTransfer: error 31, usbd status c0000004
[19:59:07.0654] command device request for 'getenv radio-error' failed: 2008
[19:59:07.0654] radio-error not set
[19:59:07.0654] unable to open device_map.txt: No such file or directory
[19:59:07.0654] <Recovery Mode Device 0000000069335BA0>: production fused device
[19:59:07.0654] requested restore behavior: Erase
[19:59:07.0656] requested restore behavior: Erase
[19:59:07.0658] interface has 1 endpoints, file pipe = 1
[19:59:07.0658]
[19:59:07.0658] <Recovery Mode Device 0000000069335BA0>: operation 4 progress -1
[19:59:07.0660] bootstrapping restore with iBEC
[19:59:07.0660] requested restore behavior: Erase
[19:59:07.0686] <Recovery Mode Device 0000000069335BA0>: operation 31 progress -1
[19:59:08.0686] <Recovery Mode Device 0000000069335BA0>: Recovery mode succeeded
[19:59:08.0686] Finished Recovery Restore Phase: Successful
[19:59:08.0783] Recovery mode device disconnected
[19:59:08.0783] Device removed when in state Restoring, moving device to transition state
[19:59:08.0783] Changing state from 'Restoring' to 'Transitioning'
[19:59:08.0783] Creating timer to monitor transition
[19:59:57.0731] Recovery mode device connected
[19:59:57.0731] Transitioning device returned, continuing restore.
[19:59:57.0731] Canceling timer
[19:59:57.0731] Changing state from 'Transitioning' to 'Restoring'
[19:59:57.0731] requested restore behavior: Erase
[19:59:57.0762] amai: AMAuthInstallBundleCopyBuildIdentityForVariant: No baseband chipid reported. Will match Build Identity based on ap chipid and boardid only.
[19:59:57.0762] requested restore behavior: Erase
[19:59:57.0763] requested variant: Erase
[19:59:57.0763] requested restore behavior: Erase
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: withApTicket is False
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "RestoreLogo"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "RestoreDeviceTree"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "RestoreKernelCache"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "RestoreRamDisk"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "iBEC"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "iBSS"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "KernelCache"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "ftap" not part of manifest, skipping
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "rfta" not part of manifest, skipping
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "ftsp" not part of manifest, skipping
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "rfts" not part of manifest, skipping
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "Diags" not part of manifest, skipping
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "CFELoader" not part of manifest, skipping
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RBM" not part of manifest, skipping
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "PHLEET" not part of manifest, skipping
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "PERTOS" not part of manifest, skipping
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "PEHammer" not part of manifest, skipping
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "LLB"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "AppleLogo"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "BatteryCharging1"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "SEP"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "BatteryFull"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "BatteryPlugin"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "BatteryCharging0"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "BatteryLow1"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "RestoreSEP"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "BatteryLow0"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "RecoveryMode"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "DeviceTree"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "iBoot"
[19:59:58.0619] amai: _AMAuthInstallBundleCreateServerRequestDictionary: nothing to be done
[19:59:58.0620] iBoot build-version = iBoot-2817.10.34
[19:59:58.0620] iBoot build-style = RELEASE
[19:59:58.0620] requested restore behavior: Erase
[19:59:58.0624] requested restore behavior: Erase
[19:59:58.0628] unable to open device_map.txt: No such file or directory
[19:59:58.0658] found device map entry for 0x00008960 0x00000000. boardConfig=n51ap platform=s5l8960x
[19:59:58.0658] _AMRestoreCopyDeviceMapPlistEntryForHardware: firmwareDirectory not in options
[19:59:58.0659] AMDeviceIoControl: GetOverlappedResult failed
[19:59:58.0659] AMDeviceIoControl: pipe stall
[19:59:58.0659] USBControlTransfer: error 31, usbd status c0000004
[19:59:58.0659] command device request for 'getenv radio-error' failed: 2008
[19:59:58.0659] radio-error not set
[19:59:58.0659] unable to open device_map.txt: No such file or directory
[19:59:58.0660] <Recovery Mode Device 0000000069335A20>: production fused device
[19:59:58.0660] requested restore behavior: Erase
[19:59:58.0663] requested restore behavior: Erase
[19:59:58.0667] interface has 1 endpoints, file pipe = 1
[19:59:58.0667]
[19:59:58.0667] <Recovery Mode Device 0000000069335A20>: operation 4 progress -1
[19:59:58.0669] unable to open device_map.txt: No such file or directory
[19:59:58.0670] found device map entry for 0x00008960 0x00000000. boardConfig=n51ap platform=s5l8960x
[19:59:58.0670] _AMRestoreCopyDeviceMapPlistEntryForHardware: firmwareDirectory not in options
[19:59:58.0670] requested restore behavior: Erase
[19:59:58.0673] requested restore behavior: Erase
[19:59:58.0676] <Recovery Mode Device 0000000069335A20>: operation 42 progress -1
[19:59:58.0676] requested restore behavior: Erase
[19:59:59.0691] <Recovery Mode Device 0000000069335A20>: operation 5 progress -1
[20:00:00.0604] unable to open device_map.txt: No such file or directory
[20:00:00.0605] found device map entry for 0x00008960 0x00000000. boardConfig=n51ap platform=s5l8960x
[20:00:00.0605] _AMRestoreCopyDeviceMapPlistEntryForHardware: firmwareDirectory not in options
[20:00:00.0605] AMDeviceIoControl: GetOverlappedResult failed
[20:00:00.0605] AMDeviceIoControl: pipe stall
[20:00:00.0605] USBControlTransfer: error 31, usbd status c0000004
[20:00:00.0605] command device request for 'getenv ramdisk-delay' failed: 2008
[20:00:01.0876] <Recovery Mode Device 0000000069335A20>: operation 6 progress -1
[20:00:02.0894] <Recovery Mode Device 0000000069335A20>: operation 7 progress -1
[20:00:03.0298] <Recovery Mode Device 0000000069335A20>: operation 8 progress -1
[20:00:03.0298] unable to open device_map.txt: No such file or directory
[20:00:03.0311] found device map entry for 0x00008960 0x00000000. boardConfig=n51ap platform=s5l8960x
[20:00:03.0311] _AMRestoreCopyDeviceMapPlistEntryForHardware: firmwareDirectory not in options
[20:00:03.0312] <Recovery Mode Device 0000000069335A20>: operation 9 progress -1
[20:00:03.0312] <Recovery Mode Device 0000000069335A20>: Recovery mode succeeded
[20:00:03.0312] Finished Recovery Restore Phase: Successful
[20:00:04.0234] Recovery mode device disconnected
[20:00:04.0234] Device removed when in state Restoring, moving device to transition state
[20:00:04.0234] Changing state from 'Restoring' to 'Transitioning'
[20:00:04.0234] Creating timer to monitor transition
[20:01:03.0258] RestoreOS mode device connected
[20:01:03.0258] Transitioning device returned, continuing restore.
[20:01:03.0258] Canceling timer
[20:01:03.0258] Changing state from 'Transitioning' to 'Restoring'
[20:01:03.0270] requested restore behavior: Erase
[20:01:03.0304] amai: AMAuthInstallBundleCopyBuildIdentityForVariant: No baseband chipid reported. Will match Build Identity based on ap chipid and boardid only.
[20:01:03.0305] <Restore Device 000000005FCF14B0>: operation 44 progress -1
[20:01:03.0305] requested restore behavior: Erase
[20:01:03.0305] requested variant: Erase
[20:01:03.0305] requested restore behavior: Erase
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: withApTicket is False
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "RestoreLogo"
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "RestoreDeviceTree"
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "RestoreKernelCache"
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "RestoreRamDisk"
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "iBEC"
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "iBSS"
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "KernelCache"
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "ftap" not part of manifest, skipping
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "rfta" not part of manifest, skipping
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "ftsp" not part of manifest, skipping
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "rfts" not part of manifest, skipping
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "Diags" not part of manifest, skipping
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "CFELoader" not part of manifest, skipping
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RBM" not part of manifest, skipping
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "PHLEET" not part of manifest, skipping
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "PERTOS" not part of manifest, skipping
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "PEHammer" not part of manifest, skipping
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "LLB"
[20:01:04.0216] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "AppleLogo"
[20:01:04.0219] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "BatteryCharging1"
[20:01:04.0219] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "SEP"
[20:01:04.0219] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "BatteryFull"
[20:01:04.0219] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "BatteryPlugin"
[20:01:04.0219] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "BatteryCharging0"
[20:01:04.0219] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "BatteryLow1"
[20:01:04.0219] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "RestoreSEP"
[20:01:04.0219] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "BatteryLow0"
[20:01:04.0219] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "RecoveryMode"
[20:01:04.0219] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "DeviceTree"
[20:01:04.0219] amai: _AMAuthInstallBundleCreateServerRequestDictionary: not personalizing "iBoot"
[20:01:04.0219] amai: _AMAuthInstallBundleCreateServerRequestDictionary: nothing to be done
[20:01:04.0219] requested restore behavior: Erase
[20:01:04.0219] overwrite InstallDiags to false
[20:01:04.0222] device did not return saved USB log
[20:01:04.0222] device did not return saved panic log
[20:01:04.0222] previous restore failed with exit status 0x100
[20:01:04.0255] connected to service com.apple.mobile.restored
[20:01:04.0255] using protocol version 13
[20:01:04.0259] unable to open device_map.txt: No such file or directory
[20:01:04.0260] board config = n51ap
[20:01:04.0267] no value returned for BootArgs
[20:01:04.0268] _copyDeviceProperty() failed for restore bootargs
[20:01:04.0275] no value returned for MarketingPartNumber
[20:01:04.0275] _copyDeviceProperty() failed for mpn
[20:01:04.0275] requested restore behavior: Erase
[20:01:04.0280] value query for 'HardwareModel' returned 'N51AP'
[20:01:04.0308] Established proxy for device ID 1
[20:01:04.0450] <Restore Device 000000005FCF14B0>: operation 35 progress -1
[20:01:04.0450] requested restore behavior: Erase
[20:01:07.0002] <Restore Device 000000005FCF14B0>: operation 28 progress -1
[20:01:14.0980] previous restore failed: s 64KB 20 1MB 11 8MB 1
[20:01:14.0980] ASPStorage:XXXXXXXXXPEvent - Host protocol version 10 aspcore protocol version 10
[20:01:14.0980] ASPStorage:XXXXXXXXXeometry - NAND Geometry info:
[20:01:14.0980] ASPStorage:XXXXXXXXXeometry - Num LBAs : 3906250
[20:01:14.0980] ASPStorage:XXXXXXXXXeometry - Bytes per LBA : 4096
[20:01:14.0980] ASPStorage:XXXXXXXXXeometry - Preferred buffer size: 65536
[20:01:14.0980] ASPStorage:XXXXXXXXXeometry - lbaFormatted : YES
[20:01:14.0980] ASPStorage:XXXXXXXXXeometry - utilFormatted : YES
[20:01:14.0980] ASPSEPNotifier::start - Entry this = 0x<<<<<MEID>>>>>00 provider = 0x<<<<<MEID>>>>>00
[20:01:14.0980] ASPSEPNotifier::start - Exit Success
[20:01:14.0980] ASPSEPNotifier:XXXXXXXXXSP - Waiting for ASPStorage
[20:01:14.0980] AppleSamsungSerial: Identified Serial Port on ARM Device=uart4 at 0x20a0b0000(0x<<<<<MEID>>>>>00)
[20:01:14.0980] AppleSamsungSerial: Identified Serial Port on ARM Device=uart2 at 0x20a0a8000(0x<<<<<MEID>>>>>00)
[20:01:14.0980] AppleSamsungSerial: Identified Serial Port on ARM Device=uart1 at 0x20a0a4000(0x<<<<<MEID>>>>>00)
[20:01:14.0980] BSD root: md0, major 2, minor 0
[20:01:14.0980] com.apple.AppleFSCompressionTypeZlib kmod start
[20:01:14.0980] com.apple.AppleFSCompressionTypeZlib load succeeded
[20:01:14.0980] L2TP domain init
[20:01:14.0980] L2TP domain init complete
[20:01:14.0980] PPTP domain init
[20:01:14.0980] Found DPB Driver
[20:01:14.0980] Found DPB Driver
[20:01:14.0980] Dither pDeviceMemory->getLength()) = 0x8000
[20:01:14.0980] Found Dither Driver
[20:01:14.0980] virtual bool AppleH4DisplayPipe::start_hardware(IOService *), auto-detect chip
[20:01:14.0980] ASPStorage:XXXXXXXXXalizeTime - kern.monotonicclock at boot = 6443966
[20:01:14.0980] ASPStorageXXXXXXXXXXXXXXXXXXXXdisk - Root device is md0
[20:01:14.0980] [ANS syslog: nand] Push_PowerGovernorInit:Nand Die:2 MLC: 2 No SLC: 2 No Erase:2
[20:01:14.0980] [ANS syslog: nand] Band_BlankCheckPushWalkers:Push abandoning non-blank band:0133 flow:HST-DYN Validity 000006c0 Age 0 uncleanReboot yes
[20:01:14.0980] [ANS syslog: nand] Band_BlankCheckPushWalkers:Push abandoning non-blank band:0144 flow:HST_STA Validity 00000778 Age 0 uncleanReboot yes
[20:01:14.0980] [ANS syslog: nand] Band_BlankCheckPushWalkers:Push abandoning non-blank band:1030 flow:HST_MET Validity 000000df Age 0 uncleanReboot yes
[20:01:14.0980] [ANS syslog: nand] Push_AbortSkipXXXXXXXXX abort skip flow:HST_MET band:1030 abort_band0:1030 abort_band1:0000
[20:01:14.0980] [ANS syslog: nand] Push_AbortSkipXXXXXXXXX abort skip flow:HST_STA band:0144 abort_band0:0144 abort_band1:0000
[20:01:14.0980] [ANS syslog: nand] Push_AbortSkipXXXXXXXXX abort skip flow:HST-DYN band:0133 abort_band0:0133 abort_band1:0000
[20:01:14.0980] void AppleDisplayPipe:XXXXXXXXXequencies(): minimumXXXXXXXXXy property not found, using getClockFrequency(0) instead.
[20:01:14.0980] AGXk: AGXAcceleratorG3_B0: GPU firmware booted successfully (AGXFirmwareKextG3)
[20:01:14.0980] ASPBlockStorage:XXXXXXXXXvice - Creating block device of 3906250 sectors of 4096 bytes
[20:01:14.0980] AppleMultitouchN1SPI: successfully started
[20:01:14.0980] AppleH6CamIn::start - back camera expected
[20:01:14.0980] AppleH6CamIn::start - front camera expected
[20:01:14.0980] AppleH6CamIn::power_off_hardware
[20:01:14.0980] AppleH6CamIn::start - About to register service
[20:01:14.0980] AppleH6CamInXXXXXXXXXXXXXXXXXXXXX 0
[20:01:14.0980] AppleS5L8960XPerformanceController::start: Dynamic Framebuffer Caching is enabled
[20:01:14.0980] AppleOscarProcessorXXXXXXXXXXXXXXXXXXXXX setting oscar-state=0
[20:01:14.0980] AppleBaseband: Backpowering function: functionXXXXXXXXXring_pin0
[20:01:14.0980] AppleBaseband: Found 1 backpowering pins
[20:01:14.0980] AppleOscarProcessorXXXXXXXXXXXXXXXXXXXXX setting oscar-state=10001
[20:01:14.0980] AppleOscarProcessor:XXXXXXXXXtGated: Starting Oscar1, baudRate=1500000
[20:01:14.0980] ready - 00000000: 3f | ?
[20:01:14.0980] AppleARMPMUCharger: AppleUSBCableDetect 1
[20:01:14.0980] AppleARMPMUCharger: AppleUSBCableType USBHost
[20:01:14.0980] AppleSynopsysOTGDevice::gated_handleUSBCableConnect cable connected, but don't have device configuration yet
[20:01:14.0980] AppleSynopsysOTGDevice::gated_handleUSBCableConnect cable connected, but don't have device configuration yet
[20:01:14.0980] AppleARMPMUCharger: AppleUSBCableDetect 0
[20:01:14.0980] AppleARMPMUCharger: AppleUSBCableType Detached
[20:01:14.0980] AppleMultitouchN1SPI: detected HBPP. driver will be kept alive
[20:01:14.0980] AppleARMPMUCharger: AppleUSBCableDetect 1
[20:01:14.0980] AppleARMPMUCharger: AppleUSBCableType USBHost
[20:01:14.0980] AppleSynopsysOTGDevice::gated_handleUSBCableConnect cable connected, but don't have device configuration yet
[20:01:14.0980] LwVM::probe - failed to read header from media
[20:01:14.0980] [effaceable:INIT] found current generation, 67, in group 0
[20:01:14.0980] [effaceable:INIT] started
[20:01:14.0980] inverting UI colordisplay-scale = 2
[20:01:14.0980] displayXXXXXXXXX = 0
[20:01:14.0980] found applelogo at /usr/share/progressui/applelogo@2x.tga
[20:01:14.0980] [ANS syslog: nand] Util_HostXXXXXXXXXng to read element=PANICLOG not yet written, returning ERR_ABORT
[20:01:14.0980] LwVM::probe - failed to read header from media
[20:01:14.0980] [ANS syslog: nand] Util_HostXXXXXXXXXng to read element=PANICLOG not yet written, returning ERR_ABORT
[20:01:14.0980] [ANS syslog: nand] Util_HostXXXXXXXXXng to read element=PANICLOG not yet written, returning ERR_ABORT
[20:01:14.0980] [ANS syslog: nand] Util_HostXXXXXXXXXng to read element=PANICLOG not yet written, returning ERR_ABORT
[20:01:14.0980] found display: primary
[20:01:14.0980] display: 640 x 1136
[20:01:14.0980] Loading syscfg.
[20:01:14.0980] AppleSynopsysOTGDevice - Configuration: Apple Mobile Device
[20:01:14.0980] AppleSynopsysOTGDevice Interface: AppleUSBMux
[20:01:14.0980] waiting for matching IOKit service: <CFBasicHash 0x12d606c10 [0x100617f50]>{type = mutable dict, count = 1,
[20:01:14.0980] entries =>
[20:01:14.0980] 0 : <CFString 0x100832e10 [0x100617f50]>{contents = "IOProviderClass"} = <CFString 0x12d606c50 [0x100617f50]>{contents = "AppleUSBDeviceMux"}
[20:01:14.0980] }
[20:01:14.0980] virtual bool AppleUSBDeviceMux::start(IOService *) build: Mar 29 2015 19:37:06
[20:01:14.0980] AppleSynopsysOTGDevice::gated_registerFunction Register function AppleUSBMux
[20:01:14.0980] AppleSynopsysOTGDevice:XXXXXXXXXStack Starting usb stack
[20:01:14.0980] AppleBCMWLANCore::init IO80211-154.5 Mar 29 2015 19:44:15
[20:01:14.0980] 000158.105165 wlan0.A[0] AppleBCMWLANProvisioningManagerXXXXXXXXXXXXXXXXXXXXta(): WARNING, Wifi 'serial baud rate' does not exist in the device tree
[20:01:14.0980] 000158.111136 wlan0.N[1] AppleBCMWLANCore::start(): Starting with MAC Address: <<<mac address>>>
[20:01:14.0980] 000158.111821 wlan0.N[2] AppleBCMWLANCoreXXXXXXXXXXXXXXXXXXXX(): powerState 1, fStateFlags 0x20, dev 0x<<<<<MEID>>>>>00 (this 1, provider 0)
[20:01:14.0980] 000158.111840 wlan0.N[3] AppleBCMWLANCoreXXXXXXXXXXXXXXXXXXXX(): Received power state change before driver has initialized, ignoring
[20:01:14.0980] IO80211PeerManager:XXXXXXXXXInterface can't add monitoring timer
[20:01:14.0980] IO80211Interface::init peerManager=0x<<<<<MEID>>>>>00
[20:01:14.0980] IOReturn AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXX(OSObject *) setting debug level to 7
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>80
[20:01:14.0980] recv(12, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] could not receive message
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>00 established 62078<-lo0->49153 62078<-usb->5120
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>00 established 62078<-lo0->49154 62078<-usb->5376
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>00 established 62078<-lo0->49155 62078<-usb->5632
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>00
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>00
[20:01:14.0980] recv(12, 4) failed: connection closed
[20:01:14.0980] recv(13, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] could not receive message
[20:01:14.0980] could not receive message
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>80 established 62078<-lo0->49156 62078<-usb->5888
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>80 established 62078<-lo0->49157 62078<-usb->6144
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>00
[20:01:14.0980] recv(14, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] could not receive message
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>80 established 62078<-lo0->49158 62078<-usb->6400
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>80
[20:01:14.0980] recv(14, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] could not receive message
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>00 established 62078<-lo0->49159 62078<-usb->6656
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>00
[20:01:14.0980] recv(14, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] could not receive message
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>80
[20:01:14.0980] recv(13, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] could not receive message
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>80 established 62078<-lo0->49160 62078<-usb->6912
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>80
[20:01:14.0980] recv(13, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] could not receive message
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>00 established 62078<-lo0->49161 62078<-usb->7168
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>00
[20:01:14.0980] recv(13, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] could not receive message
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>00 established 62078<-lo0->49162 62078<-usb->7424
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>00
[20:01:14.0980] recv(13, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] could not receive message
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>00 established 62078<-lo0->49163 62078<-usb->7680
[20:01:14.0980] unrecognized key 'BootArgs' in value query
[20:01:14.0980] failed to handle value query for key 'BootArgs', sending back empty response
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>00
[20:01:14.0980] recv(13, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] could not receive message
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>80 established 62078<-lo0->49164 62078<-usb->7936
[20:01:14.0980] unrecognized key 'MarketingPartNumber' in value query
[20:01:14.0980] failed to handle value query for key 'MarketingPartNumber', sending back empty response
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>80
[20:01:14.0980] recv(13, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] could not receive message
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>80 established 62078<-lo0->49165 62078<-usb->8192
[20:01:14.0980] void AppleUSBDeviceMux:XXXXXXXXXxTCPInput(mbuf_t) received reset, closing 0x<<<<<MEID>>>>>80
[20:01:14.0980] recv(13, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] could not receive message
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>00 established 1082<-lo0->49166 1082<-usb->8448
[20:01:14.0980] client protocol version 13
[20:01:14.0980] *** UUID A5213275-8562-EB4B-AF57-E5993784E5BD ***
[20:01:14.0980] Restore options:
[20:01:14.0980] MinimumSystemPartition => <CFNumber 0x<<<<<MEID>>>>>c3 [0x100617f50]>{value = +2620, type = kCFNumberSInt64Type}
[20:01:14.0980] UUID => <CFString 0x12d51ecf0 [0x100617f50]>{contents = "A5213275-8562-EB4B-AF57-E5993784E5BD"}
[20:01:14.0980] SystemPartitionSize => <CFNumber 0x<<<<<MEID>>>>>c3 [0x100617f50]>{value = +2620, type = kCFNumberSInt64Type}
[20:01:14.0980] SystemPartitionPadding => <CFBasicHash 0x12d51ed80 [0x100617f50]>{type = mutable dict, count = 9,
[20:01:14.0980] entries =>
[20:01:14.0980] 0 : <CFString 0x12d51eaf0 [0x100617f50]>{contents = "512"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100617f50]>{value = +1280, type = kCFNumberSInt64Type}
[20:01:14.0980] 2 : <CFString 0x12d51f640 [0x100617f50]>{contents = "128"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100617f50]>{value = +1280, type = kCFNumberSInt64Type}
[20:01:14.0980] 3 : <CFString 0x12d51e260 [0x100617f50]>{contents = "16"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100617f50]>{value = +260, type = kCFNumberSInt64Type}
[20:01:14.0980] 4 : <CFString 0x12d51f680 [0x100617f50]>{contents = "1024"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100617f50]>{value = +1280, type = kCFNumberSInt64Type}
[20:01:14.0980] 5 : <CFString 0x12d51eb70 [0x100617f50]>{contents = "32"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100617f50]>{value = +320, type = kCFNumberSInt64Type}
[20:01:14.0980] 6 : <CFString 0x12d51ec50 [0x100617f50]>{contents = "768"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100617f50]>{value = +1280, type = kCFNumberSInt64Type}
[20:01:14.0980] 7 : <CFString 0x12d51f330 [0x100617f50]>{cont...
[20:01:14.0980] entering bootstrap_hoover
[20:01:14.0980] bootstrap_hoover: using factory firmware
[20:01:14.0980] bootstrap_hoover: hoover failed to bootstrap XXXXXXXXX5
[20:01:14.0980] entering load_sep_os
[20:01:14.0980] device has sep - getting firmware
[20:01:14.0980] entering copy_restore_sep
[20:01:14.0980] got sep firmware - making call to load it
[20:01:14.0980] entering ramrod_load_sep_os
[20:01:14.0980] entering ramrod_execute_command_with_input_data: /usr/libexec/seputil (0x1042e0000 - 4379677)
[20:01:14.0980] executing /usr/libexec/seputil
[20:01:14.0980] waiting for child to exit
[20:01:14.0980] child exited
[20:01:14.0980] exit status: 0
[20:01:14.0980] entering ramrod_ticket_update
[20:01:14.0980] looking up boot manifest hash
[20:01:14.0980] device tree ticket-hash: <CFData 0x12d602e90 [0x100617f50]>{length = 20, capacity = 20, bytes = 0x<<<<<<<<<<<<<<<<<<UDID>>>>>>>>>>>>>>>>>>}
[20:01:14.0980] ticket_hash: <<<<<<<<<<<<<<<<<<UDID>>>>>>>>>>>>>>>>>>
[20:01:14.0980] received valid ticket (4991 bytes)
[20:01:14.0980] entering partition_nand_device
[20:01:14.0980] No IOFlashController instance found
[20:01:14.0980] entering wait_for_storage_device
[20:01:14.0980] Searching for NAND service
[20:01:14.0980] Found NAND service: ASPStorage
[20:01:14.0980] NAND initialized. Waiting for devnode.
[20:01:14.0980] entering clear_remap_variable
[20:01:14.0980] executing /usr/sbin/nvram
[20:01:14.0980] entering ramrod_probe_media
[20:01:14.0980] device partitioning scheme is GPT
[20:01:14.0980] find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log=
[20:01:14.0980] executing /sbin/fsck_hfs
[20:01:14.0980] ASPStorageXXXXXXXXXXXXXXXXXXXXdisk - Root device is md0
[20:01:14.0980] ART: 30 47 02 01 00 30 20 02 02 7b c1 04 14 e0 93 38 30 3d dd 7a 0c 0a d5 00 36 ac b1 8f 4c 12 64 f4 8a 04 00 04 00 31 00 04 20 84 44 35 c5 ef af 57 15 1c 0a 61 81 c7 31 bf e6 78 40 dc 7a 2f 69 0a 24 b4 86 3c b4 e4 b6 4a 03
[20:01:14.0980] ** /dev/rdisk0s1s1
[20:01:14.0980] Executing fsck_hfs (version hfs-285).
[20:01:14.0980] ** Checking Journaled HFS Plus volume.
[20:01:14.0980] ** Detected a caseXXXXXXXXXe volume.
[20:01:14.0980] The volume name is Stowe12F70.N51OS
[20:01:14.0980] ** Checking extents overflow file.
[20:01:14.0980] ** Checking catalog file.
[20:01:14.0980] ** Checking multi-linked files.
[20:01:14.0980] ** Checking catalog hierarchy.
[20:01:14.0980] ** Checking extended attributes file.
[20:01:14.0980] ** Checking volume bitmap.
[20:01:14.0980] ** Checking volume information.
[20:01:14.0980] ** Trimming unused blocks.
[20:01:14.0980] ** The volume Stowe12F70.N51OS appears to be OK.
[20:01:14.0980] executing /sbin/mount_hfs
[20:01:14.0980] /dev/disk0s1s1 mounted on /mnt1
[20:01:14.0980] restored_fdr_initialize returned false
[20:01:14.0980] RestoredFDRCreate() returned 0
[20:01:14.0980] FDR is not supported on this device
[20:01:14.0980] RestoredFDRSaveData: restoredFDR is NULL, not able to preserve FDR data.
[20:01:14.0980] RestoredFDRSaveData success
[20:01:14.0980] entering check_for_restore_log
[20:01:14.0980] restore log does not exist
[20:01:14.0980] entering unmount_filesystems
[20:01:14.0980] error unmounting '/mnt2': Invalid argument
[20:01:14.0980] create_baseband_update_options: Got imeisv: 10
[20:01:14.0980] baseband updater options = <CFBasicHash 0x12d521fa0 [0x100617f50]>{type = mutable dict, count = 3,
[20:01:14.0980] entries =>
[20:01:14.0980] 0 : <CFString 0x100fc5420 [0x100617f50]>{contents = "autoSetupEnv"} = <CFBoolean 0x100618300 [0x100617f50]>{value = false}
[20:01:14.0980] 1 : <CFString 0x100fc5440 [0x100617f50]>{contents = "disablePing"} = <CFBoolean 0x1006182f0 [0x100617f50]>{value = true}
[20:01:14.0980] 2 : <CFString 0x100fc5300 [0x100617f50]>{contents = "IMEISwVersion"} = <CFNumber 0x<<<<<MEID>>>>>a2 [0x100617f50]>{value = +10, type = kCFNumberSInt32Type}
[20:01:14.0980] }
[20:01:14.0980] _baseband_update_requires_filesystems_once: querying baseband to determine if it requires system filesystem
[20:01:14.0980] bbupdater: cmdCopyRequirePartitionMount: supports flashless 1
[20:01:14.0980] bbupdater: cmdCopyRequirePartitionMount: supports REFS 0
[20:01:14.0980] bbupdater: cmdCopyRequirePartitionMount: need data partition for coredumps
[20:01:14.0980] _baseband_update_requires_filesystems_once: query returned <CFBasicHash 0x12d522050 [0x100617f50]>{type = mutable dict, count = 3,
[20:01:14.0980] entries =>
[20:01:14.0980] 0 : <CFString 0x100fc4da0 [0x100617f50]>{contents = "requireUserPartitionMount"} = <CFBoolean 0x1006182f0 [0x100617f50]>{value = true}
[20:01:14.0980] 1 : <CFString 0x100fc4dc0 [0x100617f50]>{contents = "requestedBasebandFSPartitionMountSize"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100617f50]>{value = +0, type = kCFNumberSInt64Type}
[20:01:14.0980] 2 : <CFString 0x100fc4d60 [0x100617f50]>{contents = "requireSystemPartitionMount"} = <CFBoolean 0x1006182f0 [0x100617f50]>{value = true}
[20:01:14.0980] }
[20:01:14.0980] entering clean_NAND
[20:01:14.0980] [ANS syslog: nand] Format_Send_Unmap_AllXXXXXXXXXg All LBAs
[20:01:14.0980] [ANS syslog: nand] Push_PowerGovernorInit:Nand Die:2 MLC: 2 No SLC: 2 No Erase:2
[20:01:14.0980] [ANS syslog: nand] Format_UserArea:*** FORMATTING ***
[20:01:14.0980] [ANS syslog: nand] Format_UserArea: indMemoryInHeap: 4096 KB
[20:01:14.0980] ASPStorage:XXXXXXXXXeometry - NAND Geometry info:
[20:01:14.0980] ASPStorage:XXXXXXXXXeometry - Num LBAs : 3906250
[20:01:14.0980] ASPStorage:XXXXXXXXXeometry - Bytes per LBA : 4096
[20:01:14.0980] ASPStorage:XXXXXXXXXeometry - Preferred buffer size: 65536
[20:01:14.0980] ASPStorage:XXXXXXXXXeometry - lbaFormatted : YES
[20:01:14.0980] ASPStorage:XXXXXXXXXeometry - utilFormatted : YES
[20:01:14.0980] NAND format complete
[20:01:14.0980] entering ramrod_probe_media
[20:01:14.0980] find_filesystem_partitions: storage=/dev/disk0 system= data= baseband data= log=
[20:01:14.0980] entering format_effaceable_storage
[20:01:14.0980] effaceable storage is formatted, clearing it
[20:01:14.0980] effaceable storaged cleared
[20:01:14.0980] entering format_storage_for_LwVM
[20:01:14.0980] no-lwvm-offset-mgmt property not found
[20:01:14.0980] entering ramrod_probe_media
[20:01:14.0980] device partitioning scheme is GPT
[20:01:14.0980] unexpected partition 'LwVM' - skipping
[20:01:14.0980] find_filesystem_partitions: storage=/dev/disk0s1 system= data= baseband data= log=
[20:01:14.0980] entering create_filesystem_partitions
[20:01:14.0980] User specified system partition size, skipping firmware extras size.
[20:01:14.0980] system partition padding size is: 272629760 bytes
[20:01:14.0980] unable to open : No such file or directory
[20:01:14.0980] creating 2 partitions
[20:01:14.0980] creating encrypted data partition
[20:01:14.0980] entering ramrod_probe_media
[20:01:14.0980] device partitioning scheme is GPT
[20:01:14.0980] find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log=
[20:01:14.0980] wipe entire partition: 1 (old = 0 new = 2880)
[20:01:14.0980] block size for /dev/disk0s1s1: 4096
[20:01:14.0980] /sbin/newfs_hfs -s -v System -J /dev/disk0s1s1
[20:01:14.0980] executing /sbin/newfs_hfs -s -v System -J /dev/disk0s1s1
[20:01:14.0980] Initialized /dev/rdisk0s1s1 as a 3 GB caseXXXXXXXXXe HFS Plus volume with a 8192k journal
[20:01:14.0980] block size for /dev/disk0s1s2: 4096
[20:01:14.0980] /sbin/newfs_hfs -s -v Data -J -P /dev/disk0s1s2
[20:01:14.0980] executing /sbin/newfs_hfs -s -v Data -J -P /dev/disk0s1s2
[20:01:14.0980] Initialized /dev/rdisk0s1s2 as a 12 GB caseXXXXXXXXXe HFS Plus volume with a 8192k journal
[20:01:14.0980] entering ramrod_probe_media
[20:01:14.0980] device partitioning scheme is GPT
[20:01:14.0980] find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log=
[20:01:14.0980] entering mount_filesystems
[20:01:14.0980] executing /sbin/fsck_hfs
[20:01:14.0980] ** /dev/rdisk0s1s1
[20:01:14.0980] Executing fsck_hfs (version hfs-285).
[20:01:14.0980] ** Checking Journaled HFS Plus volume.
[20:01:14.0980] ** Detected a caseXXXXXXXXXe volume.
[20:01:14.0980] The volume name is System
[20:01:14.0980] ** Checking extents overflow file.
[20:01:14.0980] ** Checking catalog file.
[20:01:14.0980] ** Checking multi-linked files.
[20:01:14.0980] ** Checking catalog hierarchy.
[20:01:14.0980] ** Checking extended attributes file.
[20:01:14.0980] ** Checking volume bitmap.
[20:01:14.0980] ** Checking volume information.
[20:01:14.0980] ** Trimming unused blocks.
[20:01:14.0980] ** The volume System appears to be OK.
[20:01:14.0980] executing /sbin/mount_hfs
[20:01:14.0980] /dev/disk0s1s1 mounted on /mnt1
[20:01:14.0980] executing /sbin/fsck_hfs
[20:01:14.0980] ** /dev/rdisk0s1s2
[20:01:14.0980] Executing fsck_hfs (version hfs-285).
[20:01:14.0980] ** Checking Journaled HFS Plus volume.
[20:01:14.0980] ** Detected a caseXXXXXXXXXe volume.
[20:01:14.0980] The volume name is Data
[20:01:14.0980] ** Checking extents overflow file.
[20:01:14.0980] ** Checking catalog file.
[20:01:14.0980] ** Checking multi-linked files.
[20:01:14.0980] ** Checking catalog hierarchy.
[20:01:14.0980] ** Checking extended attributes file.
[20:01:14.0980] ** Checking volume bitmap.
[20:01:14.0980] ** Checking volume information.
[20:01:14.0980] ** Trimming unused blocks.
[20:01:14.0980] ** The volume Data appears to be OK.
[20:01:14.0980] executing /sbin/mount_hfs
[20:01:14.0980] mount_hfs: Could not exec re-keying daemon /usr/libexec/rolld: No such file or directory
[20:01:14.0980] /dev/disk0s1s2 mounted on /mnt2
[20:01:14.0980] entering resize_system_partition_to_options
[20:01:14.0980] User specified system partition size, skipping firmware extras size.
[20:01:14.0980] Found SystemPartitionPadding value of 272629760 bytes
[20:01:14.0980] Erasing system partition prior to resize operation.
[20:01:14.0980] block size for /dev/disk0s1s1: 4096
[20:01:14.0980] /sbin/newfs_hfs -s -v System -J /dev/disk0s1s1
[20:01:14.0980] executing /sbin/newfs_hfs -s -v System -J /dev/disk0s1s1
[20:01:14.0980] Initialized /dev/rdisk0s1s1 as a 3 GB caseXXXXXXXXXe HFS Plus volume with a 8192k journal
[20:01:14.0980] executing /sbin/fsck_hfs
[20:01:14.0980] ** /dev/rdisk0s1s1
[20:01:14.0980] Executing fsck_hfs (version hfs-285).
[20:01:14.0980] ** Checking Journaled HFS Plus volume.
[20:01:14.0980] ** Detected a caseXXXXXXXXXe volume.
[20:01:14.0980] The volume name is System
[20:01:14.0980] ** Checking extents overflow file.
[20:01:14.0980] ** Checking catalog file.
[20:01:14.0980] ** Checking multi-linked files.
[20:01:14.0980] ** Checking catalog hierarchy.
[20:01:14.0980] ** Checking extended attributes file.
[20:01:14.0980] ** Checking volume bitmap.
[20:01:14.0980] ** Checking volume information.
[20:01:14.0980] ** Trimming unused blocks.
[20:01:14.0980] ** The volume System appears to be OK.
[20:01:14.0980] executing /sbin/mount_hfs
[20:01:14.0980] /dev/disk0s1s1 mounted on /mnt1
[20:01:14.0980] Trying to resize system partition to 3019898880 bytes aka 2880 MB
[20:01:14.0980] entering adjust_partition_preflight
[20:01:14.0980] partition:0 requiredSize=737280
[20:01:14.0980] no change in size
[20:01:14.0980] Successfully resized the system partition
[20:01:14.0980] entering maximize_data_partition
[20:01:14.0980] entering adjust_partition_preflight
[20:01:14.0980] partition:1 requiredSize=<<<<<IMEI>>>>>>6
[20:01:14.0980] maximizing data partition to 12834570239 bytes
[20:01:14.0980] entering adjust_partition_preflight
[20:01:14.0980] partition:1 requiredSize=3133440
[20:01:14.0980] no change in size
[20:01:14.0980] Successfully resized data partition to consume free blocks
[20:01:14.0980] The system partition now has a total HFS+ capacity of 2880 MB
[20:01:14.0980] entering ramrod_probe_media
[20:01:14.0980] device partitioning scheme is GPT
[20:01:14.0980] find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log=
[20:01:14.0980] entering unmount_filesystems
[20:01:14.0980] entering restore_images
[20:01:14.0980] Successfully marked device node /dev/rdisk0s1s1 as static data
[20:01:14.0980] executing /usr/sbin/asr -source asr://localhost:12345 -target /dev/disk0s1s1 -erase XXXXXXXXX -XXXXXXXXXcksum -XXXXXXXXXrings
[20:01:14.0980] void AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt(struct BulkUSBMuxSession *, errno_t) new session to port 12345 failed: 61
[20:01:14.0980] ASR: asr: Unable to disable idle sleep - XXXXXXXXX9
[20:01:14.0980] ASR STATUS: start 242.6 multicast-client
[20:01:14.0980] ASR: Waiting for connection attempt from server
[20:01:14.0980] AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt new session 0x<<<<<MEID>>>>>00 established 12345<-lo0->49169 12345<-usb->8960
[20:01:14.0980] ASR STATUS: setup
[20:01:14.0980] ASR: Validating target...
[20:01:14.0980] ASR: done
[20:01:14.0980] ASR STATUS: metadata
[20:01:14.0980] ASR: Validating source...
[20:01:14.0980] ASR: done
[20:01:14.0980] ASR: Retrieving scan information...
[20:01:14.0980] ASR: done
[20:01:14.0980] ASR: Validating sizes...
[20:01:14.0980] ASR: done
[20:01:14.0980] ASR STATUS: restore
[20:01:14.0980] ASR RESTORE PROGRESS: 2%
[20:01:14.0980] ASR RESTORE PROGRESS: 4%
[20:01:14.0980] ASR RESTORE PROGRESS: 6%
[20:01:14.0980] ASR RESTORE PROGRESS: 8%
[20:01:14.0980] ASR RESTORE PROGRESS: 10%
[20:01:14.0980] ASR RESTORE PROGRESS: 12%
[20:01:14.0980] ASR RESTORE PROGRESS: 14%
[20:01:14.0980] ASR RESTORE PROGRESS: 16%
[20:01:14.0980] ASR RESTORE PROGRESS: 18%
[20:01:14.0980] ASR RESTORE PROGRESS: 20%
[20:01:14.0980] ASR RESTORE PROGRESS: 22%
[20:01:14.0980] ASR RESTORE PROGRESS: 24%
[20:01:14.0980] ASR RESTORE PROGRESS: 26%
[20:01:14.0980] ASR RESTORE PROGRESS: 28%
[20:01:14.0980] ASR RESTORE PROGRESS: 30%
[20:01:14.0980] ASR RESTORE PROGRESS: 32%
[20:01:14.0980] ASR RESTORE PROGRESS: 34%
[20:01:14.0980] ASR RESTORE PROGRESS: 36%
[20:01:14.0980] ASR RESTORE PROGRESS: 38%
[20:01:14.0980] ASR RESTORE PROGRESS: 40%
[20:01:14.0980] ASR RESTORE PROGRESS: 42%
[20:01:14.0980] ASR RESTORE PROGRESS: 44%
[20:01:14.0980] ASR RESTORE PROGRESS: 46%
[20:01:14.0980] ASR RESTORE PROGRESS: 48%
[20:01:14.0980] ASR RESTORE PROGRESS: 50%
[20:01:14.0980] ASR RESTORE PROGRESS: 52%
[20:01:14.0980] ASR RESTORE PROGRESS: 54%
[20:01:14.0980] ASR RESTORE PROGRESS: 56%
[20:01:14.0980] ASR RESTORE PROGRESS: 58%
[20:01:14.0980] ASR RESTORE PROGRESS: 60%
[20:01:14.0980] ASR RESTORE PROGRESS: 62%
[20:01:14.0980] ASR RESTORE PROGRESS: 64%
[20:01:14.0980] ASR RESTORE PROGRESS: 66%
[20:01:14.0980] ASR RESTORE PROGRESS: 68%
[20:01:14.0980] ASR RESTORE PROGRESS: 70%
[20:01:14.0980] ASR RESTORE PROGRESS: 72%
[20:01:14.0980] ASR RESTORE PROGRESS: 74%
[20:01:14.0980] ASR RESTORE PROGRESS: 76%
[20:01:14.0980] ASR RESTORE PROGRESS: 78%
[20:01:14.0980] ASR RESTORE PROGRESS: 80%
[20:01:14.0980] ASR RESTORE PROGRESS: 82%
[20:01:14.0980] ASR RESTORE PROGRESS: 84%
[20:01:14.0980] ASR RESTORE PROGRESS: 86%
[20:01:14.0980] ASR RESTORE PROGRESS: 88%
[20:01:14.0980] ASR RESTORE PROGRESS: 90%
[20:01:14.0980] ASR RESTORE PROGRESS: 92%
[20:01:14.0980] ASR RESTORE PROGRESS: 94%
[20:01:14.0980] ASR RESTORE PROGRESS: 96%
[20:01:14.0980] ASR RESTORE PROGRESS: 98%
[20:01:14.0980] AppleUSBDeviceMux:XXXXXXXXXpcall socket is closed, session 0x<<<<<MEID>>>>>00 (12345<-lo0->49169 12345<-usb->8960)
[20:01:14.0980] ASR RESTORE PROGRESS: 100%
[20:01:14.0980] ASR: Copied 2746610176 bytes in 116.83 seconds, 22957.85 KiB/s
[20:01:14.0980] ASR STATUS: verify
[20:01:14.0980] ASR VERIFY PROGRESS: 2%
[20:01:14.0980] ASR VERIFY PROGRESS: 4%
[20:01:14.0980] ASR VERIFY PROGRESS: 6%
[20:01:14.0980] ASR VERIFY PROGRESS: 8%
[20:01:14.0980] ASR VERIFY PROGRESS: 10%
[20:01:14.0980] ASR VERIFY PROGRESS: 12%
[20:01:14.0980] ASR VERIFY PROGRESS: 14%
[20:01:14.0980] ASR VERIFY PROGRESS: 16%
[20:01:14.0980] ASR VERIFY PROGRESS: 18%
[20:01:14.0980] ASR VERIFY PROGRESS: 20%
[20:01:14.0980] ASR VERIFY PROGRESS: 22%
[20:01:14.0980] ASR VERIFY PROGRESS: 24%
[20:01:14.0980] ASR VERIFY PROGRESS: 26%
[20:01:14.0980] ASR VERIFY PROGRESS: 28%
[20:01:14.0980] ASR VERIFY PROGRESS: 30%
[20:01:14.0980] ASR VERIFY PROGRESS: 32%
[20:01:14.0980] ASR VERIFY PROGRESS: 34%
[20:01:14.0980] ASR VERIFY PROGRESS: 36%
[20:01:14.0980] ASR VERIFY PROGRESS: 38%
[20:01:14.0980] ASR VERIFY PROGRESS: 40%
[20:01:14.0980] ASR VERIFY PROGRESS: 42%
[20:01:14.0980] ASR VERIFY PROGRESS: 44%
[20:01:14.0980] ASR VERIFY PROGRESS: 46%
[20:01:14.0980] ASR VERIFY PROGRESS: 48%
[20:01:14.0980] ASR VERIFY PROGRESS: 50%
[20:01:14.0980] ASR VERIFY PROGRESS: 52%
[20:01:14.0980] ASR VERIFY PROGRESS: 54%
[20:01:14.0980] ASR VERIFY PROGRESS: 56%
[20:01:14.0980] ASR VERIFY PROGRESS: 58%
[20:01:14.0980] ASR VERIFY PROGRESS: 60%
[20:01:14.0980] ASR VERIFY PROGRESS: 62%
[20:01:14.0980] ASR VERIFY PROGRESS: 64%
[20:01:14.0980] ASR VERIFY PROGRESS: 66%
[20:01:14.0980] ASR VERIFY PROGRESS: 68%
[20:01:14.0980] ASR VERIFY PROGRESS: 70%
[20:01:14.0980] ASR VERIFY PROGRESS: 72%
[20:01:14.0980] ASR VERIFY PROGRESS: 74%
[20:01:14.0980] ASR VERIFY PROGRESS: 76%
[20:01:14.0980] ASR VERIFY PROGRESS: 78%
[20:01:14.0980] ASR VERIFY PROGRESS: 80%
[20:01:14.0980] ASR VERIFY PROGRESS: 82%
[20:01:14.0980] ASR VERIFY PROGRESS: 84%
[20:01:14.0980] ASR VERIFY PROGRESS: 86%
[20:01:14.0980] ASR VERIFY PROGRESS: 88%
[20:01:14.0980] ASR VERIFY PROGRESS: 90%
[20:01:14.0980] ASR VERIFY PROGRESS: 92%
[20:01:14.0980] ASR VERIFY PROGRESS: 94%
[20:01:14.0980] ASR VERIFY PROGRESS: 96%
[20:01:14.0980] ASR VERIFY PROGRESS: 98%
[20:01:14.0980] ASR VERIFY PROGRESS: 100%
[20:01:14.0980] ASR: Verified SHA-1 checksum 2746610176 bytes in 12.66 seconds, 211944.05 KiB/s
[20:01:14.0980] ASR STATUS: finish
[20:01:14.0980] entering ramrod_probe_media
[20:01:14.0980] device partitioning scheme is GPT
[20:01:14.0980] find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log=
[20:01:14.0980] entering mount_filesystems
[20:01:14.0980] executing /sbin/fsck_hfs
[20:01:14.0980] ** /dev/rdisk0s1s1
[20:01:14.0980] Executing fsck_hfs (version hfs-285).
[20:01:14.0980] ** Checking Journaled HFS Plus volume.
[20:01:14.0980] ** Detected a caseXXXXXXXXXe volume.
[20:01:14.0980] The volume name is Stowe12F70.N51OS
[20:01:14.0980] ** Checking extents overflow file.
[20:01:14.0980] ** Checking catalog file.
[20:01:14.0980] ** Checking multi-linked files.
[20:01:14.0980] ** Checking catalog hierarchy.
[20:01:14.0980] ** Checking extended attributes file.
[20:01:14.0980] ** Checking volume bitmap.
[20:01:14.0980] ** Checking volume information.
[20:01:14.0980] ** Trimming unused blocks.
[20:01:14.0980] ** The volume Stowe12F70.N51OS appears to be OK.
[20:01:14.0980] executing /sbin/mount_hfs
[20:01:14.0980] /dev/disk0s1s1 mounted on /mnt1
[20:01:14.0980] executing /sbin/fsck_hfs
[20:01:14.0980] ** /dev/rdisk0s1s2
[20:01:14.0980] Executing fsck_hfs (version hfs-285).
[20:01:14.0980] ** Checking Journaled HFS Plus volume.
[20:01:14.0980] ** Detected a caseXXXXXXXXXe volume.
[20:01:14.0980] The volume name is Data
[20:01:14.0980] ** Checking extents overflow file.
[20:01:14.0980] ** Checking catalog file.
[20:01:14.0980] ** Checking multi-linked files.
[20:01:14.0980] ** Checking catalog hierarchy.
[20:01:14.0980] ** Checking extended attributes file.
[20:01:14.0980] ** Checking volume bitmap.
[20:01:14.0980] ** Checking volume information.
[20:01:14.0980] ** Trimming unused blocks.
[20:01:14.0980] ** The volume Data appears to be OK.
[20:01:14.0980] executing /sbin/mount_hfs
[20:01:14.0980] mount_hfs: Could not exec re-keying daemon /usr/libexec/rolld: No such file or directory
[20:01:14.0980] /dev/disk0s1s2 mounted on /mnt2
[20:01:14.0980] RestoredFDRRestoreSavedData success
[20:01:14.0980] entering install_kernel_cache
[20:01:14.0980] writing kernelcache to /mnt1/System/Library/Caches/com.apple.kernelcaches/kernelcache
[20:01:14.0980] entering install_secure_vault_daemon
[20:01:14.0980] entering get_hardware_platform
[20:01:14.0980] platform-name = s5l8960x
[20:01:14.0980] linking /mnt1/usr/sbin/securekeyvaultd to /mnt1/usr/sbin/securekeyvaultd.s5l8960x
[20:01:14.0980] no securekeyvaultd for s5l8960x
[20:01:14.0980] entering ramrod_ticket_write
[20:01:14.0980] writing ticket to /mnt1/System/Library/Caches/apticket.der
[20:01:14.0980] entering RestoredFDRRecover
[20:01:14.0980] entering update_device_firmware
[20:01:14.0980] entering add_baseband_partition_options
[20:01:14.0980] entering ramrod_update_device_firmware
[20:01:14.0980] entering update_iBoot
[20:01:14.0980] entering copy_sep
[20:01:14.0980] found 'ASPStorage' object in IORegistry
[20:01:14.0980] entering update_iBoot_ASP_storage
[20:01:14.0980] entering wait_for_device: '/dev/disk1'
[20:01:14.0980] entering wait_for_device: '/dev/disk2'
[20:01:14.0980] writing 10 images to Firmware node '/dev/disk1' (preferred_block_size = 4096)
[20:01:14.0980] original data length: 640044; img4_restore_data length: 640081
[20:01:14.0980] write_data_to_fd: length=640081, preferred_block_size=4096
[20:01:14.0980] [ANS syslog: nand] Util_Host:element=FW written size=1093632, host request trimmed to end of written content
[20:01:14.0980] original data length: 48462; img4_restore_data length: 48499
[20:01:14.0980] write_data_to_fd: length=48499, preferred_block_size=4096
[20:01:14.0980] original data length: 6894; img4_restore_data length: 6931
[20:01:14.0980] write_data_to_fd: length=6931, preferred_block_size=4096
[20:01:14.0980] original data length: 11662; img4_restore_data length: 11699
[20:01:14.0980] write_data_to_fd: length=11699, preferred_block_size=4096
[20:01:14.0980] original data length: 152778; img4_restore_data length: 152815
[20:01:14.0980] write_data_to_fd: length=152815, preferred_block_size=4096
[20:01:14.0980] original data length: 39854; img4_restore_data length: 39891
[20:01:14.0980] write_data_to_fd: length=39891, preferred_block_size=4096
[20:01:14.0980] original data length: 8798; img4_restore_data length: 8835
[20:01:14.0980] write_data_to_fd: length=8835, preferred_block_size=4096
[20:01:14.0980] original data length: 88913; img4_restore_data length: 88950
[20:01:14.0980] write_data_to_fd: length=88950, preferred_block_size=4096
[20:01:14.0980] original data length: 78897; img4_restore_data length: 78934
[20:01:14.0980] write_data_to_fd: length=78934, preferred_block_size=4096
[20:01:14.0980] original data length: 14574; img4_restore_data length: 14611
[20:01:14.0980] write_data_to_fd: length=14611, preferred_block_size=4096
[20:01:14.0980] done with flash images - writing end of contents
[20:01:14.0980] write_data_to_fd: length=2, preferred_block_size=4096
[20:01:14.0980] [ANS syslog: nand] Util_FW_Host_Resize:---fwSize size 267 bytes
[20:01:14.0980] [ANS syslog: nand] Util_FW:[ 2, 4, 0] Wrote Firmware B with err=NO_ERROR
[20:01:14.0980] [ANS syslog: nand] Util_FW:[ 1, 1, 0] Wrote Firmware A with err=NO_ERROR
[20:01:14.0980] writing 1 boot images to boot block node '/dev/disk2' (preferred_block_size = 0x4096)
[20:01:14.0980] original data length: 160812; img4_restore_data length: 160849
[20:01:14.0980] write_data_to_fd: length=160849, preferred_block_size=4096
[20:01:14.0980] [ANS syslog: nand] Util_Host:element=LLB written size=167936, host request trimmed to end of written content
[20:01:14.0980] write_data_to_fd: length=2, preferred_block_size=4096
[20:01:14.0980] write_data_to_fd: length=3026, preferred_block_size=4096
[20:01:14.0980] done with boot images - writing end of contents
[20:01:14.0980] LwVM::probe - failed to read header from media
[20:01:14.0980] entering update_sep
[20:01:14.0980] writing sep firmware to /mnt1/usr/standalone/firmware/sepXXXXXXXXX.img4
[20:01:14.0980] write_data_to_fd: length=4379677, preferred_block_size=4096
[20:01:14.0980] update_sep returned: success
[20:01:14.0980] entering update_gas_gauge
[20:01:14.0980] entering update_ir_mcu
[20:01:14.0980] looking for mcu nub...no entry from path!
[20:01:14.0980] TiSerialFlasher not supported on this platform. Exiting.
[20:01:14.0980] entering update_baseband
[20:01:14.0980] update_baseband: registering for progress notifications
[20:01:14.0980] create_baseband_update_options: Got imeisv: 10
[20:01:14.0980] baseband updater options = <CFBasicHash 0x12d606450 [0x100617f50]>{type = mutable dict, count = 7,
[20:01:14.0980] entries =>
[20:01:14.0980] 2 : <CFString 0x100fc5200 [0x100617f50]>{contents = "SystemRoot"} = <CFString 0x12d6058e0 [0x100617f50]>{contents = "/mnt1"}
[20:01:14.0980] 4 : <CFString 0x100fc5120 [0x100617f50]>{contents = "DisallowFusiing"} = <CFBoolean 0x1006182f0 [0x100617f50]>{value = true}
[20:01:14.0980] 6 : <CFString 0x100fc5420 [0x100617f50]>{contents = "autoSetupEnv"} = <CFBoolean 0x100618300 [0x100617f50]>{value = false}
[20:01:14.0980] 7 : <CFString 0x100fc50c0 [0x100617f50]>{contents = "forceUpdate"} = <CFBoolean 0x1006182f0 [0x100617f50]>{value = true}
[20:01:14.0980] 8 : <CFString 0x100fc5440 [0x100617f50]>{contents = "disablePing"} = <CFBoolean 0x1006182f0 [0x100617f50]>{value = true}
[20:01:14.0980] 11 : <CFString 0x100fc5220 [0x100617f50]>{contents = "DataRoot"} = <CFString 0x12d6058c0 [0x100617f50]>{contents = "/mnt2"}
[20:01:14.0980] 12 : <CFString 0x100fc5300 [0x100617f50]>{contents = "IMEISwVersion"} = <CFNumber 0x<<<<<MEID>>>>>a2 [0x100617f50]>{value = +10, type = kCFNumberSInt32Type}
[20:01:14.0980] }
[20:01:14.0980] update_baseband: querying baseband info
[20:01:14.0980] bbupdater: BEGIN: Querying info at stage first
[20:01:14.0980] bbupdater: BEGIN: Preparing first stage
[20:01:14.0980] bbupdater: Requested boot mode: kBBUBootModeNone
[20:01:14.0980] bbupdater: Creating transport, attempts remaining 5...bbupdater: FAILED(kBBUReturnIOError)
[20:01:14.0980] bbupdater: powercycling modem
[20:01:14.0980] bbupdater: Creating transport, attempts remaining 4...bbupdater: FAILED(kBBUReturnIOError)
[20:01:14.0980] bbupdater: powercycling modem
[20:01:14.0980] bbupdater: Creating transport, attempts remaining 3...bbupdater: FAILED(kBBUReturnIOError)
[20:01:14.0980] bbupdater: powercycling modem
[20:01:14.0980] bbupdater: Creating transport, attempts remaining 2...bbupdater: FAILED(kBBUReturnIOError)
[20:01:14.0980] bbupdater: powercycling modem
[20:01:14.0980] bbupdater: Creating transport, attempts remaining 1...bbupdater: FAILED(kBBUReturnIOError)
[20:01:14.0980] bbupdater: powercycling modem
[20:01:14.0980] bbupdater: Creating transport, attempts remaining 0...bbupdater: FAILED(kBBUReturnIOError)
[20:01:14.0980] bbupdater: END(kBBUReturnFailCreateTransportFirst): Preparing first stage
[20:01:14.0980] bbupdater: END(kBBUReturnFailCreateTransportFirst): Querying info at stage first
[20:01:14.0980] send_bbupdate_status: sending baseband update status to host
[20:01:14.0980] update_baseband: calling CFNotificationCenterRemoveEveryObserver
[20:01:14.0980] update_baseband: bbupdater error: The operation couldn\U2019t be completed. (BBUpdater error 50 - Failed creating a communication transport to baseband.)
[20:01:14.0980] Update[Baseband] error - The operation couldn\U2019t be completed. (RamrodErrorDomain error 1004 - update_baseband: failed querying baseband info)
[20:01:14.0980] Update[Baseband] failed - 0 retries remaining...
[20:01:14.0980] 0: RamrodErrorDomain/3ec: update_baseband: failed querying baseband info
[20:01:14.0980] 1: BBUpdater/32: Failed creating a communication transport to baseband.
[20:01:14.0980] unable to convert ramrod error 1004
[20:01:14.0980] restore failed with CFError:
[20:01:14.0980] 0: AMRestoreErrorDomain/<<<<<MEID>>>>>ff: failed to update device firmware
[20:01:14.0980] 1: AMRestoreErrorDomain/<<<<<MEID>>>>>ff: ramrod firmware update failed
[20:01:14.0980] 2: RamrodErrorDomain/3ec: update_baseband: failed querying baseband info
[20:01:14.0980] 3: BBUpdater/32: Failed creating a communication transport to baseband.
[20:01:14.0980] waiting for host to acknowledge final status received...
[20:01:14.0980] AppleARMPMUCharger: AppleUSBCableDetect 0
[20:01:14.0980] AppleARMPMUCharger: AppleUSBCableType Detached
[20:01:14.0980] virtual IOReturn AppleUSBDeviceMux::message(UInt32, IOService *, void *) - kMessageInterfaceWasDeActivated
[20:01:14.0980] virtual IOReturn AppleUSBDeviceMux::message(UInt32, IOService *, void *) - closing session 0x<<<<<MEID>>>>>00, usb link went down
[20:01:14.0980] virtual IOReturn AppleUSBDeviceMux::message(UInt32, IOService *, void *) - closing session 0x<<<<<MEID>>>>>80, usb link went down
[20:01:14.0980] AppleUSBDeviceMux:XXXXXXXXXats: USB mux statistics:
[20:01:14.0980] USB mux: 68045 reads / 0 errors, 22975 writes / 0 errors
[20:01:14.0980] USB mux: 0 short packets, 0 dups
[20:01:14.0980] recv(12, 4) failed: connection closed
[20:01:14.0980] unable to read message size: -1
[20:01:14.0980] received NULL message from host, expected final status
[20:01:14.0980] attempting to dump restore log
[20:01:14.0980] writing log file: /mnt1/restore.log
[20:01:14.0980]
[20:01:14.0980] ==== end of previous restore log ====
[20:01:15.0169] <Restore Device 000000005FCF14B0>: operation 29 progress -1
[20:01:15.0169] <Restore Device 000000005FCF14B0>: operation 29 progress -1
[20:01:16.0195] <Restore Device 000000005FCF14B0>: operation 11 progress -1
[20:01:16.0333] <Restore Device 000000005FCF14B0>: operation 12 progress -1
[20:01:17.0400] <Restore Device 000000005FCF14B0>: operation 12 progress -1
[20:01:18.0857] <Restore Device 000000005FCF14B0>: operation 16 progress -1
[20:01:19.0251] <Restore Device 000000005FCF14B0>: operation 16 progress -1
[20:01:19.0981] <Restore Device 000000005FCF14B0>: operation 29 progress -1
[20:01:20.0007] <Restore Device 000000005FCF14B0>: operation 29 progress -1
[20:01:20.0037] <Restore Device 000000005FCF14B0>: operation 13 progress 0
[20:01:30.0043] <Restore Device 000000005FCF14B0>: operation 13 progress 1
[20:01:30.0043] ASR progress: Initialized communication with client
[20:01:31.0490] <Restore Device 000000005FCF14B0>: operation 13 progress 5
[20:01:31.0490] ASR progress: Starting to send payload
[20:01:32.0455] <Restore Device 000000005FCF14B0>: operation 13 progress 6
[20:01:32.0455] ASR progress: Sending payload
[20:01:33.0326] <Restore Device 000000005FCF14B0>: operation 13 progress 7
[20:01:33.0326] ASR progress: Sending payload
[20:01:35.0724] <Restore Device 000000005FCF14B0>: operation 13 progress 8
[20:01:35.0724] ASR progress: Sending payload
[20:01:36.0601] <Restore Device 000000005FCF14B0>: operation 13 progress 9
[20:01:36.0601] ASR progress: Sending payload
[20:01:37.0469] <Restore Device 000000005FCF14B0>: operation 13 progress 10
[20:01:37.0469] ASR progress: Sending payload
[20:01:38.0495] <Restore Device 000000005FCF14B0>: operation 13 progress 11
[20:01:38.0495] ASR progress: Sending payload
[20:01:39.0370] <Restore Device 000000005FCF14B0>: operation 13 progress 12
[20:01:39.0370] ASR progress: Sending payload
[20:01:40.0278] <Restore Device 000000005FCF14B0>: operation 13 progress 13
[20:01:40.0279] ASR progress: Sending payload
[20:01:41.0157] <Restore Device 000000005FCF14B0>: operation 13 progress 14
[20:01:41.0157] ASR progress: Sending payload
[20:01:42.0036] <Restore Device 000000005FCF14B0>: operation 13 progress 15
[20:01:42.0036] ASR progress: Sending payload
[20:01:42.0909] <Restore Device 000000005FCF14B0>: operation 13 progress 16
[20:01:42.0909] ASR progress: Sending payload
[20:01:43.0773] <Restore Device 000000005FCF14B0>: operation 13 progress 17
[20:01:43.0773] ASR progress: Sending payload
[20:01:44.0647] <Restore Device 000000005FCF14B0>: operation 13 progress 18
[20:01:44.0647] ASR progress: Sending payload
[20:01:45.0534] <Restore Device 000000005FCF14B0>: operation 13 progress 19
[20:01:45.0534] ASR progress: Sending payload
[20:01:46.0515] <Restore Device 000000005FCF14B0>: operation 13 progress 20
[20:01:46.0515] ASR progress: Sending payload
[20:01:47.0411] <Restore Device 000000005FCF14B0>: operation 13 progress 21
[20:01:47.0411] ASR progress: Sending payload
[20:01:48.0311] <Restore Device 000000005FCF14B0>: operation 13 progress 22
[20:01:48.0311] ASR progress: Sending payload
[20:01:49.0195] <Restore Device 000000005FCF14B0>: operation 13 progress 23
[20:01:49.0195] ASR progress: Sending payload
[20:01:50.0081] <Restore Device 000000005FCF14B0>: operation 13 progress 24
[20:01:50.0081] ASR progress: Sending payload
[20:01:50.0969] <Restore Device 000000005FCF14B0>: operation 13 progress 25
[20:01:50.0969] ASR progress: Sending payload
[20:01:51.0857] <Restore Device 000000005FCF14B0>: operation 13 progress 26
[20:01:51.0857] ASR progress: Sending payload
[20:01:52.0731] <Restore Device 000000005FCF14B0>: operation 13 progress 27
[20:01:52.0731] ASR progress: Sending payload
[20:01:53.0617] <Restore Device 000000005FCF14B0>: operation 13 progress 28
[20:01:53.0617] ASR progress: Sending payload
[20:01:54.0512] <Restore Device 000000005FCF14B0>: operation 13 progress 29
[20:01:54.0512] ASR progress: Sending payload
[20:01:55.0395] <Restore Device 000000005FCF14B0>: operation 13 progress 30
[20:01:55.0395] ASR progress: Sending payload
[20:01:56.0284] <Restore Device 000000005FCF14B0>: operation 13 progress 31
[20:01:56.0284] ASR progress: Sending payload
[20:01:57.0173] <Restore Device 000000005FCF14B0>: operation 13 progress 32
[20:01:57.0174] ASR progress: Sending payload
[20:01:58.0059] <Restore Device 000000005FCF14B0>: operation 13 progress 33
[20:01:58.0059] ASR progress: Sending payload
[20:01:58.0921] <Restore Device 000000005FCF14B0>: operation 13 progress 34
[20:01:58.0921] ASR progress: Sending payload
[20:01:59.0795] <Restore Device 000000005FCF14B0>: operation 13 progress 35
[20:01:59.0795] ASR progress: Sending payload
[20:02:00.0681] <Restore Device 000000005FCF14B0>: operation 13 progress 36
[20:02:00.0681] ASR progress: Sending payload
[20:02:01.0554] <Restore Device 000000005FCF14B0>: operation 13 progress 37
[20:02:01.0554] ASR progress: Sending payload
[20:02:02.0419] <Restore Device 000000005FCF14B0>: operation 13 progress 38
[20:02:02.0419] ASR progress: Sending payload
[20:02:03.0298] <Restore Device 000000005FCF14B0>: operation 13 progress 39
[20:02:03.0298] ASR progress: Sending payload
[20:02:04.0167] <Restore Device 000000005FCF14B0>: operation 13 progress 40
[20:02:04.0167] ASR progress: Sending payload
[20:02:05.0042] <Restore Device 000000005FCF14B0>: operation 13 progress 41
[20:02:05.0042] ASR progress: Sending payload
[20:02:05.0932] <Restore Device 000000005FCF14B0>: operation 13 progress 42
[20:02:05.0932] ASR progress: Sending payload
[20:02:06.0751] <Restore Device 000000005FCF14B0>: operation 13 progress 43
[20:02:06.0751] ASR progress: Sending payload
[20:02:07.0592] <Restore Device 000000005FCF14B0>: operation 13 progress 44
[20:02:07.0592] ASR progress: Sending payload
[20:02:08.0457] <Restore Device 000000005FCF14B0>: operation 13 progress 45
[20:02:08.0457] ASR progress: Sending payload
[20:02:09.0295] <Restore Device 000000005FCF14B0>: operation 13 progress 46
[20:02:09.0295] ASR progress: Sending payload
[20:02:10.0127] <Restore Device 000000005FCF14B0>: operation 13 progress 47
[20:02:10.0127] ASR progress: Sending payload
[20:02:10.0973] <Restore Device 000000005FCF14B0>: operation 13 progress 48
[20:02:10.0973] ASR progress: Sending payload
[20:02:11.0830] <Restore Device 000000005FCF14B0>: operation 13 progress 49
[20:02:11.0830] ASR progress: Sending payload
[20:02:12.0783] <Restore Device 000000005FCF14B0>: operation 13 progress 50
[20:02:12.0783] ASR progress: Sending payload
[20:02:13.0657] <Restore Device 000000005FCF14B0>: operation 13 progress 51
[20:02:13.0657] ASR progress: Sending payload
[20:02:14.0527] <Restore Device 000000005FCF14B0>: operation 13 progress 52
[20:02:14.0528] ASR progress: Sending payload
[20:02:15.0401] <Restore Device 000000005FCF14B0>: operation 13 progress 53
[20:02:15.0401] ASR progress: Sending payload
[20:02:16.0289] <Restore Device 000000005FCF14B0>: operation 13 progress 54
[20:02:16.0289] ASR progress: Sending payload
[20:02:17.0267] <Restore Device 000000005FCF14B0>: operation 13 progress 55
[20:02:17.0267] ASR progress: Sending payload
[20:02:18.0333] <Restore Device 000000005FCF14B0>: operation 13 progress 56
[20:02:18.0333] ASR progress: Sending payload
[20:02:19.0194] <Restore Device 000000005FCF14B0>: operation 13 progress 57
[20:02:19.0194] ASR progress: Sending payload
[20:02:20.0062] <Restore Device 000000005FCF14B0>: operation 13 progress 58
[20:02:20.0062] ASR progress: Sending payload
[20:02:20.0958] <Restore Device 000000005FCF14B0>: operation 13 progress 59
[20:02:20.0958] ASR progress: Sending payload
[20:02:21.0822] <Restore Device 000000005FCF14B0>: operation 13 progress 60
[20:02:21.0822] ASR progress: Sending payload
[20:02:22.0778] <Restore Device 000000005FCF14B0>: operation 13 progress 61
[20:02:22.0778] ASR progress: Sending payload
[20:02:23.0656] <Restore Device 000000005FCF14B0>: operation 13 progress 62
[20:02:23.0656] ASR progress: Sending payload
[20:02:24.0529] <Restore Device 000000005FCF14B0>: operation 13 progress 63
[20:02:24.0529] ASR progress: Sending payload
[20:02:25.0630] <Restore Device 000000005FCF14B0>: operation 13 progress 64
[20:02:25.0630] ASR progress: Sending payload
[20:02:26.0799] <Restore Device 000000005FCF14B0>: operation 13 progress 65
[20:02:26.0799] ASR progress: Sending payload
[20:02:27.0669] <Restore Device 000000005FCF14B0>: operation 13 progress 66
[20:02:27.0669] ASR progress: Sending payload
[20:02:28.0520] <Restore Device 000000005FCF14B0>: operation 13 progress 67
[20:02:28.0520] ASR progress: Sending payload
[20:02:29.0360] <Restore Device 000000005FCF14B0>: operation 13 progress 68
[20:02:29.0360] ASR progress: Sending payload
[20:02:30.0225] <Restore Device 000000005FCF14B0>: operation 13 progress 69
[20:02:30.0225] ASR progress: Sending payload
[20:02:31.0100] <Restore Device 000000005FCF14B0>: operation 13 progress 70
[20:02:31.0100] ASR progress: Sending payload
[20:02:31.0978] <Restore Device 000000005FCF14B0>: operation 13 progress 71
[20:02:31.0978] ASR progress: Sending payload
[20:02:32.0886] <Restore Device 000000005FCF14B0>: operation 13 progress 72
[20:02:32.0886] ASR progress: Sending payload
[20:02:33.0768] <Restore Device 000000005FCF14B0>: operation 13 progress 73
[20:02:33.0768] ASR progress: Sending payload
[20:02:34.0692] <Restore Device 000000005FCF14B0>: operation 13 progress 74
[20:02:34.0692] ASR progress: Sending payload
[20:02:35.0614] <Restore Device 000000005FCF14B0>: operation 13 progress 75
[20:02:35.0614] ASR progress: Sending payload
[20:02:36.0503] <Restore Device 000000005FCF14B0>: operation 13 progress 76
[20:02:36.0503] ASR progress: Sending payload
[20:02:37.0393] <Restore Device 000000005FCF14B0>: operation 13 progress 77
[20:02:37.0393] ASR progress: Sending payload
[20:02:38.0282] <Restore Device 000000005FCF14B0>: operation 13 progress 78
[20:02:38.0282] ASR progress: Sending payload
[20:02:39.0165] <Restore Device 000000005FCF14B0>: operation 13 progress 79
[20:02:39.0165] ASR progress: Sending payload
[20:02:40.0055] <Restore Device 000000005FCF14B0>: operation 13 progress 80
[20:02:40.0055] ASR progress: Sending payload
[20:02:40.0941] <Restore Device 000000005FCF14B0>: operation 13 progress 81
[20:02:40.0941] ASR progress: Sending payload
[20:02:41.0875] <Restore Device 000000005FCF14B0>: operation 13 progress 82
[20:02:41.0875] ASR progress: Sending payload
[20:02:42.0982] <Restore Device 000000005FCF14B0>: operation 13 progress 83
[20:02:42.0982] ASR progress: Sending payload
[20:02:44.0238] <Restore Device 000000005FCF14B0>: operation 13 progress 84
[20:02:44.0238] ASR progress: Sending payload
[20:02:45.0383] <Restore Device 000000005FCF14B0>: operation 13 progress 85
[20:02:45.0383] ASR progress: Sending payload
[20:02:46.0589] <Restore Device 000000005FCF14B0>: operation 13 progress 86
[20:02:46.0589] ASR progress: Sending payload
[20:02:47.0770] <Restore Device 000000005FCF14B0>: operation 13 progress 87
[20:02:47.0770] ASR progress: Sending payload
[20:02:49.0071] <Restore Device 000000005FCF14B0>: operation 13 progress 88
[20:02:49.0071] ASR progress: Sending payload
[20:02:50.0298] <Restore Device 000000005FCF14B0>: operation 13 progress 89
[20:02:50.0298] ASR progress: Sending payload
[20:02:52.0075] <Restore Device 000000005FCF14B0>: operation 13 progress 90
[20:02:52.0075] ASR progress: Sending payload
[20:02:54.0032] <Restore Device 000000005FCF14B0>: operation 13 progress 91
[20:02:54.0032] ASR progress: Sending payload
[20:02:56.0287] <Restore Device 000000005FCF14B0>: operation 13 progress 92
[20:02:56.0287] ASR progress: Sending payload
[20:02:57.0211] <Restore Device 000000005FCF14B0>: operation 13 progress 93
[20:02:57.0211] ASR progress: Sending payload
[20:02:58.0227] <Restore Device 000000005FCF14B0>: operation 13 progress 94
[20:02:58.0227] ASR progress: Sending payload
[20:02:59.0103] <Restore Device 000000005FCF14B0>: operation 13 progress 95
[20:02:59.0103] ASR progress: Sending payload
[20:03:00.0098] <Restore Device 000000005FCF14B0>: operation 13 progress 96
[20:03:00.0098] ASR progress: Sending payload
[20:03:01.0138] <Restore Device 000000005FCF14B0>: operation 13 progress 97
[20:03:01.0138] ASR progress: Sending payload
[20:03:02.0497] <Restore Device 000000005FCF14B0>: operation 13 progress 98
[20:03:02.0497] ASR progress: Sending payload
[20:03:04.0283] <Restore Device 000000005FCF14B0>: operation 13 progress 100
[20:03:04.0283] ASR progress: Finished
[20:03:05.0000] <Restore Device 000000005FCF14B0>: operation 14 progress 2
[20:03:05.0269] <Restore Device 000000005FCF14B0>: operation 14 progress 4
[20:03:05.0537] <Restore Device 000000005FCF14B0>: operation 14 progress 6
[20:03:05.0803] <Restore Device 000000005FCF14B0>: operation 14 progress 8
[20:03:06.0060] <Restore Device 000000005FCF14B0>: operation 14 progress 10
[20:03:06.0327] <Restore Device 000000005FCF14B0>: operation 14 progress 12
[20:03:06.0593] <Restore Device 000000005FCF14B0>: operation 14 progress 14
[20:03:06.0860] <Restore Device 000000005FCF14B0>: operation 14 progress 16
[20:03:07.0126] <Restore Device 000000005FCF14B0>: operation 14 progress 18
[20:03:07.0393] <Restore Device 000000005FCF14B0>: operation 14 progress 20
[20:03:07.0660] <Restore Device 000000005FCF14B0>: operation 14 progress 22
[20:03:07.0926] <Restore Device 000000005FCF14B0>: operation 14 progress 24
[20:03:08.0193] <Restore Device 000000005FCF14B0>: operation 14 progress 26
[20:03:08.0460] <Restore Device 000000005FCF14B0>: operation 14 progress 28
[20:03:08.0726] <Restore Device 000000005FCF14B0>: operation 14 progress 30
[20:03:08.0992] <Restore Device 000000005FCF14B0>: operation 14 progress 32
[20:03:09.0249] <Restore Device 000000005FCF14B0>: operation 14 progress 34
[20:03:09.0516] <Restore Device 000000005FCF14B0>: operation 14 progress 36
[20:03:09.0783] <Restore Device 000000005FCF14B0>: operation 14 progress 38
[20:03:10.0050] <Restore Device 000000005FCF14B0>: operation 14 progress 40
[20:03:10.0331] <Restore Device 000000005FCF14B0>: operation 14 progress 42
[20:03:10.0615] <Restore Device 000000005FCF14B0>: operation 14 progress 44
[20:03:10.0882] <Restore Device 000000005FCF14B0>: operation 14 progress 46
[20:03:11.0148] <Restore Device 000000005FCF14B0>: operation 14 progress 48
[20:03:11.0432] <Restore Device 000000005FCF14B0>: operation 14 progress 50
[20:03:11.0700] <Restore Device 000000005FCF14B0>: operation 14 progress 52
[20:03:11.0968] <Restore Device 000000005FCF14B0>: operation 14 progress 54
[20:03:12.0224] <Restore Device 000000005FCF14B0>: operation 14 progress 56
[20:03:12.0497] <Restore Device 000000005FCF14B0>: operation 14 progress 58
[20:03:12.0790] <Restore Device 000000005FCF14B0>: operation 14 progress 60
[20:03:13.0057] <Restore Device 000000005FCF14B0>: operation 14 progress 62
[20:03:13.0324] <Restore Device 000000005FCF14B0>: operation 14 progress 64
[20:03:13.0591] <Restore Device 000000005FCF14B0>: operation 14 progress 66
[20:03:13.0858] <Restore Device 000000005FCF14B0>: operation 14 progress 68
[20:03:14.0126] <Restore Device 000000005FCF14B0>: operation 14 progress 70
[20:03:14.0408] <Restore Device 000000005FCF14B0>: operation 14 progress 72
[20:03:14.0698] <Restore Device 000000005FCF14B0>: operation 14 progress 74
[20:03:14.0973] <Restore Device 000000005FCF14B0>: operation 14 progress 76
[20:03:15.0240] <Restore Device 000000005FCF14B0>: operation 14 progress 78
[20:03:15.0497] <Restore Device 000000005FCF14B0>: operation 14 progress 80
[20:03:15.0763] <Restore Device 000000005FCF14B0>: operation 14 progress 82
[20:03:16.0030] <Restore Device 000000005FCF14B0>: operation 14 progress 84
[20:03:16.0297] <Restore Device 000000005FCF14B0>: operation 14 progress 86
[20:03:16.0564] <Restore Device 000000005FCF14B0>: operation 14 progress 88
[20:03:16.0831] <Restore Device 000000005FCF14B0>: operation 14 progress 90
[20:03:17.0098] <Restore Device 000000005FCF14B0>: operation 14 progress 92
[20:03:17.0365] <Restore Device 000000005FCF14B0>: operation 14 progress 94
[20:03:17.0632] <Restore Device 000000005FCF14B0>: operation 14 progress 96
[20:03:17.0899] <Restore Device 000000005FCF14B0>: operation 14 progress 98
[20:03:18.0157] <Restore Device 000000005FCF14B0>: operation 14 progress 100
[20:03:18.0223] <Restore Device 000000005FCF14B0>: operation 16 progress -1
[20:03:25.0273] <Restore Device 000000005FCF14B0>: operation 16 progress -1
[20:03:25.0987] requested restore behavior: Erase
[20:03:25.0990] <Restore Device 000000005FCF14B0>: operation 27 progress -1
[20:03:27.0030] <Restore Device 000000005FCF14B0>: operation 27 progress -1
[20:03:27.0172] <Restore Device 000000005FCF14B0>: operation 18 progress 0
[20:03:28.0195] <Restore Device 000000005FCF14B0>: operation 18 progress 100
[20:03:28.0197] <Restore Device 000000005FCF14B0>: operation 46 progress -1
[20:03:28.0203] <Restore Device 000000005FCF14B0>: operation 46 progress -1
[20:03:28.0220] <Restore Device 000000005FCF14B0>: operation 19 progress -1
[20:03:41.0355] amai: AMAuthInstallBasebandHandleUpdaterStatus: commandAccepted: NO
[20:03:41.0383] device returned CFError with code -1
[20:03:41.0383] dumping CFError returned by restored:
[20:03:41.0383] CFError domain:AMRestoreErrorDomain code:-1 description:failed to update device firmware
[20:03:41.0383] CFError domain:AMRestoreErrorDomain code:-1 description:ramrod firmware update failed
[20:03:41.0383] CFError domain:RamrodErrorDomain code:1004 description:update_baseband: failed querying baseband info
[20:03:41.0383] CFError domain:BBUpdater code:50 description:Failed creating a communication transport to baseband.
[20:03:41.0383] Sending acknowledgement that final status was received
[20:03:41.0961]
[20:03:41.0961] ==== device restore output ====
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost bootlog[0] <Notice>: BOOT_TIME 23585575 0
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost kernel[0] <Notice>: MPMUCharger: AppleUSBCableType USBHost
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost kernel[0] <Notice>: AppleSynopsysOTGDevice::gated_handleUSBCableConnect cable connected, but don't have device configuration yet
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost syslogd[4] <Notice>: ASL Sender Statistics
[20:03:41.0961] unable to open /dev/klog: Resource busy
[20:03:41.0961] inverting UI colorSYSLOG: Sep 30 23:32:55 localhost restored_external[6] <Error>: libMobileGestalt MobileGestalt.c:149: couldn't load support library
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost restored_external[6] <Error>: libMobileGestalt MobileGestalt.c:515: _MGSCopyAnswerFromServer can't be loaded
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost restored_external[6] <Error>: libMobileGestalt MobileGestalt.c:119: called dummy function!
[20:03:41.0961] display-scale = 2
[20:03:41.0961] displayXXXXXXXXX = 0
[20:03:41.0961] found applelogo at /usr/share/progressui/applelogo@2x.tga
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost restored_external[6] <Error>: IOMFB: /System/Library/Frameworks/MediaToolbox.framework/MediaToolbox not found
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost restored_external[6] <Error>: IOMFB: /System/Library/PrivateFrameworks/MediaToolbox.framework/MediaToolbox not found
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost restored_external[6] <Error>: IOMFB: /System/Library/PrivateFrameworks/Celestial.framework/Celestial not found
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost restored_external[6] <Error>: IOMFB: FigInstallVirtualDisplay not found
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost restored_external[6] <Error>: CFPreferences could not connect to its daemon.
[20:03:41.0961] Preferences using the connection 0x0 will be volatile and will not be persisted to disk.
[20:03:41.0961] found display: primary
[20:03:41.0961] display: 640 x 1136
[20:03:41.0961] powering on display
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost kernel[0] <Notice>: Loading diags data region 1
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost kernel[0] <Notice>: AppleSynopsysOTGDevice - Configuration: Apple Mobile Device
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost kernel[0] <Notice>: AppleSynopsysOTGDevice Interface: AppleUSBMux
[20:03:41.0961] waiting for matching IOKit service: <CFBasicHash 0x15d516870 [0x100b667d8]>{type = mutable dict, count = 1,
[20:03:41.0961] entries =>
[20:03:41.0961] 0 : <CFString 0x100e02f10 [0x100b667d8]>{contents = "IOProviderClass"} = <CFString 0x15d5168b0 [0x100b667d8]>{contents = "AppleUSBDeviceMux"}
[20:03:41.0961] }
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost kernel[0] <Notice>: virtual bool AppleUSBDeviceMux::start(IOService *) build: Oct 2 2015 15:20:14
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost kernel[0] <Notice>: AppleSynopsysOTGDevice::gated_registerFunction Register function AppleUSBMux
[20:03:41.0961] SYSLOG: Sep 30 23:32:55 localhost kernel[0] <Notice>: AppleSynopsysOTGDevice:XXXXXXXXXStack Starting usb stack
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: AppleBCMWLANCore::init IO80211-176.1 "IO80211-176.1" Oct 2 2015 14:36:04
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: IO80211Controller:XXXXXXXXXReporters 0x<<<<<MEID>>>>>b3
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: CCFlags: 0x0, CCLevel: 5 ConsoleFlags: 0x0, ConsoleLevel: -1
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: No Service found 100000361
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: configureInterests - nElements <= 0!Failed to addSubscription for group Chip subgroup Bytes Transferred driver 0x<<<<<MEID>>>>>b3 - data underrun
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: IO80211ControllerMonitorXXXXXXXXXXXXXXXXXXXXions() failed to add subscriptionIO80211Controller::start _controller is 0x<<<<<MEID>>>>>b3, provider is 0x<<<<<MEID>>>>>b3
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: 000595.335601 wlan0.A[0] gatherDeviceTreeData@767: WARNING, Wifi 'serial baud rate' does not exist in the device tree
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: 000595.341790 wlan0.N[1] start@1178XXXXXXXXX with MAC Address: <<<mac address>>>
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: AppleBCMWLANCoreXXXXXXXXXXXXXXXXXXXXoctl type 0xc cmd GET
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: AppleBCMWLANCoreXXXXXXXXXXXXXXXXXXXXoctl type 0x50 cmd GET
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: 000595.342134 wlan0.N[2] setPowerStateGated@15249: powerState 1, fStateFlags 0x20, dev 0x<<<<<MEID>>>>>b3 (this 1, provider 0)
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: 000595.342156 wlan0.N[3] setPowerStateGated@15252: Received power state change before driver has initialized, ignoring
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: IO80211PeerManager:XXXXXXXXXInterface can't add monitoring timer
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: IO80211Interface::init peerManager=0x<<<<<MEID>>>>>b3
[20:03:41.0961] SYSLOG: Sep 30 23:32:56 localhost kernel[0] <Notice>: IO80211ControllerXXXXXXXXXXXXXXXXXXXXX Setting mac address on interface
[20:03:41.0961] SYSLOG: Sep 30 23:32:58 localhost kernel[0] <Notice>: IOReturn AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXX(OSObject *) setting debug level to 7
[20:03:41.0961] recv(9, 4) failed: connection closed
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] could not receive message
[20:03:41.0961] recv(9, 4) failed: connection closed
[20:03:41.0961] recv(10, 4) failed: connection closed
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] recv(11, 4) failed: connection closed
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] could not receive message
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] could not receive message
[20:03:41.0961] could not receive message
[20:03:41.0961] recv(9, 4) failed: connection closed
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] could not receive message
[20:03:41.0961] recv(10, 4) failed: connection closed
[20:03:41.0961] recv(9, 4) failed: connection closed
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] could not receive message
[20:03:41.0961] could not receive message
[20:03:41.0961] recv(9, 4) failed: connection closed
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] could not receive message
[20:03:41.0961] recv(10, 4) failed: connection closed
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] could not receive message
[20:03:41.0961] recv(9, 4) failed: connection closed
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] could not receive message
[20:03:41.0961] recv(9, 4) failed: connection closed
[20:03:41.0961] unrecognized key 'BootArgs' in value query
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] failed to handle value query for key 'BootArgs', sending back empty response
[20:03:41.0961] could not receive message
[20:03:41.0961] recv(10, 4) failed: connection closed
[20:03:41.0961] unrecognized key 'MarketingPartNumber' in value query
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] failed to handle value query for key 'MarketingPartNumber', sending back empty response
[20:03:41.0961] could not receive message
[20:03:41.0961] recv(9, 4) failed: connection closed
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] could not receive message
[20:03:41.0961] recv(10, 4) failed: connection closed
[20:03:41.0961] unable to read message size: -1
[20:03:41.0961] could not receive message
[20:03:41.0961] client protocol version 13
[20:03:41.0961] *** UUID F809955D-05CE-8D4F-BF01-69895F66FB39 ***
[20:03:41.0961] Restore options:
[20:03:41.0961] MinimumSystemPartition => <CFNumber 0x<<<<<MEID>>>>>63 [0x100b667d8]>{value = +2694, type = kCFNumberSInt64Type}
[20:03:41.0961] UUID => <CFString 0x15d510af0 [0x100b667d8]>{contents = "F809955D-05CE-8D4F-BF01-69895F66FB39"}
[20:03:41.0961] SystemPartitionSize => <CFNumber 0x<<<<<MEID>>>>>63 [0x100b667d8]>{value = +2694, type = kCFNumberSInt64Type}
[20:03:41.0961] SystemPartitionPadding => <CFBasicHash 0x15d601f20 [0x100b667d8]>{type = mutable dict, count = 9,
[20:03:41.0961] entries =>
[20:03:41.0961] 0 : <CFString 0x15d6021b0 [0x100b667d8]>{contents = "512"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100b667d8]>{value = +1280, type = kCFNumberSInt64Type}
[20:03:41.0961] 2 : <CFString 0x15d602040 [0x100b667d8]>{contents = "128"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100b667d8]>{value = +1280, type = kCFNumberSInt64Type}
[20:03:41.0961] 3 : <CFString 0x15d602080 [0x100b667d8]>{contents = "16"} = <CFNumber 0x<<<<<MEID>>>>>03 [0x100b667d8]>{value = +160, type = kCFNumberSInt64Type}
[20:03:41.0961] 4 : <CFString 0x15d601ee0 [0x100b667d8]>{contents = "1024"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100b667d8]>{value = +1280, type = kCFNumberSInt64Type}
[20:03:41.0961] 5 : <CFString 0x15d601d20 [0x100b667d8]>{contents = "32"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100b667d8]>{value = +320, type = kCFNumberSInt64Type}
[20:03:41.0961] 6 : <CFString 0x15d602230 [0x100b667d8]>{contents = "768"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100b667d8]>{value = +1280, type = kCFNumberSInt64Type}
[20:03:41.0961] 7 : <CFString 0x100b0e820 [0x100b667d8]>{cont...
[20:03:41.0961] entering load_sep_os
[20:03:41.0961] device has sep - getting firmware
[20:03:41.0961] entering copy_restore_sep
[20:03:41.0961] got sep firmware - making call to load it
[20:03:41.0961] entering ramrod_load_sep_os
[20:03:41.0961] entering ramrod_execute_command_with_input_data: /usr/libexec/seputil (0x104ebc000 - 4473885)
[20:03:41.0961] executing /usr/libexec/seputil
[20:03:41.0961] waiting for child to exit
[20:03:41.0961] SYSLOG: Sep 30 23:33:53 localhost kernel[0] <Notice>: bool AppleSEPFirmware::_initFromMemory(IOMemoryDescriptor *): loaded 4473885 bytes of firmware from client
[20:03:41.0961] SYSLOG: Sep 30 23:33:53 localhost kernel[0] <Notice>: AppleSEP:WARNING: Could not register SEP root shmcon (err=-1)
[20:03:41.0961] SYSLOG: Sep 30 23:33:53 localhost kernel[0] <Notice>: AppleSEP:WARNING: Could not register SEP debugger shmcon (err=-1)
[20:03:41.0961] SYSLOG: Sep 30 23:33:53 localhost kernel[0] <Notice>: IOReturn AppleSEPManager:XXXXXXXXXareBytes(IOMemoryDescriptor *, bool, bool): SEP Shared Memory Buffer at <ptr>
[20:03:41.0961] SYSLOG: Sep 30 23:33:53 localhost kernel[0] <Notice>: void AppleSEPBooter::_bootAction(void *, void *): SEP status: 1
[20:03:41.0961] SYSLOG: Sep 30 23:33:53 localhost kernel[0] <Notice>: void AppleSEPBooter::_bootAction(void *, void *): SEP accepted Tz0
[20:03:41.0961] SYSLOG: Sep 30 23:33:53 localhost kernel[0] <Notice>: void AppleSEPBooter::_bootAction(void *, void *): SEP status: 2
[20:03:41.0961] SYSLOG: Sep 30 23:33:53 localhost kernel[0] <Notice>: void AppleSEPBooter::_bootAction(void *, void *): SEP status: 2
[20:03:41.0961] SYSLOG: Sep 30 23:33:53 localhost kernel[0] <Notice>: IOReturn AppleSEPBooter::bootSEP(AppleSEPFirmware *, AppleSEPSharedMemoryBuffer *, bool): SEP booting
[20:03:41.0961] SYSLOG: Sep 30 23:33:53 localhost kernel[0] <Notice>: IOReturn AppleSEPBooter::bootSEP(AppleSEPFirmware *, AppleSEPSharedMemoryBuffer *, bool): Shmbuf for SEP: { paddr = 0x805530000, size = 0x10000 }
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: void AppleSEPBooter::_bootAction(void *, void *): SEP accepted IMG4
[20:03:41.0961] child exited
[20:03:41.0961] exit status: 0
[20:03:41.0961] entering ramrod_ticket_update
[20:03:41.0961] looking up boot manifest hash
[20:03:41.0961] crypto-hash-method found. Using SHA1
[20:03:41.0961] device tree ticket_hash: <<<<<<<<<<<<<<<<<<UDID>>>>>>>>>>>>>>>>>>
[20:03:41.0961] computed ticket_hash : <<<<<<<<<<<<<<<<<<UDID>>>>>>>>>>>>>>>>>>
[20:03:41.0961] received valid ticket (4991 bytes)
[20:03:41.0961] entering partition_nand_device
[20:03:41.0961] No IOFlashController instance found
[20:03:41.0961] entering wait_for_storage_device
[20:03:41.0961] Searching for NAND service
[20:03:41.0961] Found NAND service: ASPStorage
[20:03:41.0961] NAND initialized. Waiting for devnode.
[20:03:41.0961] entering clear_remap_variable
[20:03:41.0961] executing /usr/sbin/nvram
[20:03:41.0961] Service name : ASPStorage
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXdOnly - Ramdisk rooted. Returning readonly false
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: [ANS syslog: nand] Push_PowerGovernorInit:Nand Die:2 MLC: 2 No SLC: 2 No Erase:2
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: ASPStorageXXXXXXXXXXXXXXXXXXXXdisk - Root device is md0
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: ASPStorageXXXXXXXXXXXXXXXXXXXXdisk - Root device is a ramdisk
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: IOFirmwareDevice:XXXXXXXXXdiaParams - prev num blks 1024 new num blks 1024
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXdOnly - Ramdisk rooted. Returning readonly false
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXdOnly - Ramdisk rooted. Returning readonly false
[20:03:41.0961] Set ASP writable successfully
[20:03:41.0961] entering ramrod_reprobe_device_path
[20:03:41.0961] entering ramrod_probe_media
[20:03:41.0961] device partitioning scheme is GPT
[20:03:41.0961] find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log=
[20:03:41.0961] entering ramrod_reprobe_device_path
[20:03:41.0961] entering ramrod_probe_media
[20:03:41.0961] device partitioning scheme is GPT
[20:03:41.0961] find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log=
[20:03:41.0961] entering ramrod_probe_media
[20:03:41.0961] device partitioning scheme is GPT
[20:03:41.0961] find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log=
[20:03:41.0961] executing /sbin/fsck_hfs
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: void AppleSEPManager::_notifyOSActiveGated(): SEP/OS is alive
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: void AppleSEPManager::_updateDeviceTree(): updateDeviceTreeThread started
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: IOReturn AppleSEPARTRequests::handle_first_connected(): Configuring in buffer
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: static void AppleSEPManager::_updateDeviceTreeThread(void *): updateDeviceTree Success
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: IOReturn AppleSEPARTRequests::handle_first_connected(): Configuring out buffer
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: IOReturn AppleSEPARTStorage::handle_first_connected(): Sending MANIFEST with timeout
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: ASPSEPNotifier::message - msg = 1
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: ASPSEPNotifier::message - kSEPNotifyOK2Wrap
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: IOReturn AppleSEPARTStorage::handle_first_connected(): Sending ART_LOAD with timeout
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: ASPStorageXXXXXXXXXXXXXXXXXXXXdisk - Root device is md0
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: ASPStorageXXXXXXXXXXXXXXXXXXXXdisk - Root device is a ramdisk
[20:03:41.0961] SYSLOG: Sep 30 23:33:55 localhost kernel[0] <Notice>: bool AppleSEPARTStorage::save_incoming_art(const uint8_t *, const uint8_t *): Incoming art set and synched
[20:03:41.0961] journal_replay(/dev/disk0s1s1) returned 0
[20:03:41.0961] ** /dev/rdisk0s1s1
[20:03:41.0961] Using cacheBlockSize=32K cacheTotalBlock=4000 cacheSize=128000K.
[20:03:41.0961] Executing fsck_hfs (version hfs-305.10.1).
[20:03:41.0961] ** Checking Journaled HFS Plus volume.
[20:03:41.0961] ** Detected a caseXXXXXXXXXe volume.
[20:03:41.0961] The volume name is Stowe12F70.N51OS
[20:03:41.0961] ** Checking extents overflow file.
[20:03:41.0961] ** Checking catalog file.
[20:03:41.0961] ** Checking multi-linked files.
[20:03:41.0961] ** Checking catalog hierarchy.
[20:03:41.0961] ** Checking extended attributes file.
[20:03:41.0961] ** Checking volume bitmap.
[20:03:41.0961] ** Checking volume information.
[20:03:41.0961] ** Trimming unused blocks.
[20:03:41.0961] ** The volume Stowe12F70.N51OS appears to be OK.
[20:03:41.0961] CheckHFS returned 0, fsmodified = 0
[20:03:41.0961] executing /sbin/mount_hfs
[20:03:41.0961] /dev/disk0s1s1 mounted on /mnt1
[20:03:41.0961] restored_fdr_initialize returned false
[20:03:41.0961] RestoredFDRCreate() returned 0
[20:03:41.0961] FDR is not supported on this device
[20:03:41.0961] RestoredFDRSaveData: restoredFDR is NULL, not able to preserve FDR data.
[20:03:41.0961] RestoredFDRSaveData success
[20:03:41.0961] create_baseband_update_options: Got imeisv: 15
[20:03:41.0961] baseband updater options = <CFBasicHash 0x15d518e40 [0x100b667d8]>{type = mutable dict, count = 4,
[20:03:41.0961] entries =>
[20:03:41.0961] 0 : <CFString 0x101788ec0 [0x100b667d8]>{contents = "IMEISwVersion"} = <CFNumber 0x<<<<<MEID>>>>>f2 [0x100b667d8]>{value = +15, type = kCFNumberSInt32Type}
[20:03:41.0961] 1 : <CFString 0x101788e80 [0x100b667d8]>{contents = "restoredInternal"} = <CFBoolean 0x100b66d20 [0x100b667d8]>{value = false}
[20:03:41.0961] 2 : <CFString 0x101788fe0 [0x100b667d8]>{contents = "autoSetupEnv"} = <CFBoolean 0x100b66d20 [0x100b667d8]>{value = false}
[20:03:41.0961] 6 : <CFString 0x101789000 [0x100b667d8]>{contents = "disablePing"} = <CFBoolean 0x100b66d10 [0x100b667d8]>{value = true}
[20:03:41.0961] }
[20:03:41.0961] _baseband_update_requires_filesystems_once: querying baseband to determine if it requires system filesystem
[20:03:41.0961] bbupdater: cmdCopyRequirePartitionMount: supports flashless 1
[20:03:41.0961] bbupdater: cmdCopyRequirePartitionMount: supports REFS 0
[20:03:41.0961] bbupdater: cmdCopyRequirePartitionMount: need data partition for coredumps
[20:03:41.0961] _baseband_update_requires_filesystems_once: query returned <CFBasicHash 0x15d518f20 [0x100b667d8]>{type = mutable dict, count = 3,
[20:03:41.0961] entries =>
[20:03:41.0961] 0 : <CFString 0x101788880 [0x100b667d8]>{contents = "requireUserPartitionMount"} = <CFBoolean 0x100b66d10 [0x100b667d8]>{value = true}
[20:03:41.0961] 1 : <CFString 0x1017888a0 [0x100b667d8]>{contents = "requestedBasebandFSPartitionMountSize"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100b667d8]>{value = +0, type = kCFNumberSInt64Type}
[20:03:41.0961] 2 : <CFString 0x101788840 [0x100b667d8]>{contents = "requireSystemPartitionMount"} = <CFBoolean 0x100b66d10 [0x100b667d8]>{value = true}
[20:03:41.0961] }
[20:03:41.0961] bbupdater: cmdCopyBasebandMigrationData: copying migration data
[20:03:41.0961] entering check_for_restore_log
[20:03:41.0961] found restore log (size = 33286)
[20:03:41.0961] executing /sbin/fsck_hfs
[20:03:41.0961] journal_replay(/dev/disk0s1s2) returned 0
[20:03:41.0961] ** /dev/rdisk0s1s2
[20:03:41.0961] Using cacheBlockSize=32K cacheTotalBlock=4000 cacheSize=128000K.
[20:03:41.0961] Executing fsck_hfs (version hfs-305.10.1).
[20:03:41.0961] ** Checking Journaled HFS Plus volume.
[20:03:41.0961] ** Detected a caseXXXXXXXXXe volume.
[20:03:41.0961] The volume name is Data
[20:03:41.0961] ** Checking extents overflow file.
[20:03:41.0961] ** Checking catalog file.
[20:03:41.0961] ** Checking multi-linked files.
[20:03:41.0961] ** Checking catalog hierarchy.
[20:03:41.0961] ** Checking extended attributes file.
[20:03:41.0961] ** Checking volume bitmap.
[20:03:41.0961] ** Checking volume information.
[20:03:41.0961] ** Trimming unused blocks.
[20:03:41.0961] ** The volume Data appears to be OK.
[20:03:41.0961] CheckHFS returned 0, fsmodified = 0
[20:03:41.0961] executing /sbin/mount_hfs
[20:03:41.0961] mount_hfs: Could not exec re-keying daemon /usr/libexec/rolld: No such file or directory
[20:03:41.0961] /dev/disk0s1s2 mounted on /mnt2
[20:03:41.0961] entering dump_panic_logs
[20:03:41.0961] executing /System/Library/CoreServices/DumpPanic
[20:03:41.0961] SYSLOG: Sep 30 23:34:03 localhost kernel[0] <Notice>: [ANS syslog: nand] Util_HostXXXXXXXXXng to read element=PANICLOG not yet written, returning ERR_ABORT
[20:03:41.0961] SYSLOG: Sep 30 23:34:03 localhost kernel[0] <Notice>: disk3: invalid argument.
[20:03:41.0961] SYSLOG: Sep 30 23:34:03 localhost DumpPanic[16] <Error>: no panic data detected from /dev/rdisk3
[20:03:41.0961] entering check_for_ota_logs_with_mount_point
[20:03:41.0961] restore log does not exist
[20:03:41.0961] skipping log submission, failed to read user settings from: /mnt2/mobile/Library/ConfigurationProfiles/EffectiveUserSettings.plist
[20:03:41.0961] Diagnostic log submission disabled
[20:03:41.0961] Searching /mnt2/mobile/Library/Logs/CrashReporter/
[20:03:41.0961] check_for_ota_logs_with_mount_point complete
[20:03:41.0961] Successfully checked for OTA logs
[20:03:41.0961] __AMSupportHttpCopyProxySettings_block_invoke: Proxy available
[20:03:41.0961] __AMSupportHttpCopyProxySettings_block_invoke: AMSupportHttpCopyProxySettings: RPNotificationProxyAvailable
[20:03:41.0961] AMSupportHttpCopyProxySettings: proxyInfo = <CFBasicHash 0x15d604f00 [0x100b667d8]>{type = mutable dict, count = 2,
[20:03:41.0961] entries =>
[20:03:41.0961] 0 : <CFString 0x100b42860 [0x100b667d8]>{contents = "SOCKSProxy"} = <CFString 0x1005f8c40 [0x100b667d8]>{contents = "127.0.0.1"}
[20:03:41.0961] 1 : <CFString 0x100b42880 [0x100b667d8]>{contents = "SOCKSPort"} = <CFNumber 0xb<<<<<IMEI>>>>>> [0x100b667d8]>{value = +1081, type = kCFNumberSInt32Type}
[20:03:41.0961] }
[20:03:41.0961] calling UMEventShimSubmitEvents
[20:03:41.0961] SYSLOG: Sep 30 23:34:03 localhost restored_external[6] <Warning>: unable to create support directory: Error Domain=NSCocoaErrorDomain Code=642 "(null)" UserInfo={NSFilePath=/var/db/UpdateMetrics, NSUnderlyingError=0x15d60a3b0 {Error Domain=NSPOSIXErrorDomain Code=30 "Read-only file system"}}
[20:03:41.0961] UMEventShimSubmitEvents done
[20:03:41.0961] entering unmount_filesystems
[20:03:41.0961] entering clean_NAND
[20:03:41.0961] SYSLOG: Sep 30 23:34:03 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXdOnly - Ramdisk rooted. Returning readonly false
[20:03:41.0961] SYSLOG: Sep 30 23:34:03 localhost kernel[0] <Notice>: [ANS syslog: nand] Format_LBA:Format_LBA. Request size 0 lba, param 0
[20:03:41.0961] SYSLOG: Sep 30 23:34:03 localhost kernel[0] <Notice>: [ANS syslog: nand] Util_ClogClear:Format_LBA. Clear Clog. Major 15, Minor 1. Resetting to Major 255, Minor 255
[20:03:41.0961] SYSLOG: Sep 30 23:34:03 localhost kernel[0] <Notice>: [ANS syslog: nand] Format_Send_Unmap_AllXXXXXXXXXg All LBAs
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: [ANS syslog: nand] Push_PowerGovernorInit:Nand Die:2 MLC: 2 No SLC: 2 No Erase:2
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: [ANS syslog: nand] Format_UserArea:Format_UserArea. updateCapaticy: 1
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: [ANS syslog: nand] Format_UserArea:Format_UserArea. indMemoryInHeap: 4096 KB
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: [ANS syslog: nand] Format_LBA:Format_LBA. Format CLog
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: [ANS syslog: nand] Clog_Format:Format_LBA. Reset Clog. Major 255, Minor 255
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXeometry - NAND Geometry info:
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXeometry - Num LBAs : 3906250
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXeometry - Bytes per LBA : 4096
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXeometry - Preferred buffer size: 65536
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXeometry - lbaFormatted : YES
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXeometry - utilFormatted : YES
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXBAFormat - Ramdisk rooted. Sending kASPUpdateMediaParams
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXdOnly - Ramdisk rooted. Returning readonly false
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorageXXXXXXXXXXXXXXXXXXXXdisk - Root device is md0
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorageXXXXXXXXXXXXXXXXXXXXdisk - Root device is a ramdisk
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: IOFirmwareDevice:XXXXXXXXXdiaParams - prev num blks 1024 new num blks 1024
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXdOnly - Ramdisk rooted. Returning readonly false
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXdOnly - Ramdisk rooted. Returning readonly false
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXdOnly - Ramdisk rooted. Returning readonly false
[20:03:41.0961] SYSLOG: Sep 30 23:34:04 localhost kernel[0] <Notice>: ASPStorage:XXXXXXXXXdOnly - Ramdisk rooted. Returning readonly false
[20:03:41.0961] NAND format complete
[20:03:41.0961] entering ramrod_probe_media
[20:03:41.0961] find_filesystem_partitions: storage=/dev/disk0 system= data= baseband data= log=
[20:03:41.0961] entering format_effaceable_storage
[20:03:41.0961] effaceable storage is formatted, clearing it
[20:03:41.0961] effaceable storaged cleared
[20:03:41.0961] entering format_storage_for_LwVM
[20:03:41.0961] no-lwvm-offset-mgmt property not found
[20:03:41.0961] entering ramrod_probe_media
[20:03:41.0961] device partitioning scheme is GPT
[20:03:41.0961] unexpected partition 'LwVM' - skipping
[20:03:41.0961] find_filesystem_partitions: storage=/dev/disk0s1 system= data= baseband data= log=
[20:03:41.0961] entering create_filesystem_partitions
[20:03:41.0961] User specified system partition size, skipping firmware extras size.
[20:03:41.0961] system partition padding size is: 167772160 bytes
[20:03:41.0961] unable to open : No such file or directory
[20:03:41.0961] creating 2 partitions
[20:03:41.0961] creating encrypted data partition
[20:03:41.0961] entering ramrod_probe_media
[20:03:41.0961] device partitioning scheme is GPT
[20:03:41.0961] find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log=
[20:03:41.0961] wipe entire partition: 1 (old = 0 new = 2854)
[20:03:41.0961] block size for /dev/disk0s1s1: 4096
[20:03:41.0961] /sbin/newfs_hfs -s -v System -J /dev/disk0s1s1
[20:03:41.0961] executing /sbin/newfs_hfs -s -v System -J /dev/disk0s1s1
[20:03:41.0961] Initialized /dev/rdisk0s1s1 as a 3 GB caseXXXXXXXXXe HFS Plus volume with a 8192k journal
[20:03:41.0961] block size for /dev/disk0s1s2: 4096
[20:03:41.0961] /sbin/newfs_hfs -s -v Data -J -P /dev/disk0s1s2
[20:03:41.0961] executing /sbin/newfs_hfs -s -v Data -J -P /dev/disk0s1s2
[20:03:41.0961] Initialized /dev/rdisk0s1s2 as a 12 GB caseXXXXXXXXXe HFS Plus volume with a 8192k journal
[20:03:41.0961] entering ramrod_probe_media
[20:03:41.0961] device partitioning scheme is GPT
[20:03:41.0961] find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log=
[20:03:41.0961] bbupdater: cmdRestoreBasebandMigrationData: restoring migration data
[20:03:41.0961] bbupdater: restoring baseband migration data succeeded
[20:03:41.0961] entering mount_filesystems
[20:03:41.0961] executing /sbin/fsck_hfs
[20:03:41.0961] journal_replay(/dev/disk0s1s1) returned 0
[20:03:41.0961] ** /dev/rdisk0s1s1
[20:03:41.0961] Using cacheBlockSize=32K cacheTotalBlock=4000 cacheSize=128000K.
[20:03:41.0961] Executing fsck_hfs (version hfs-305.10.1).
[20:03:41.0961] ** Checking Journaled HFS Plus volume.
[20:03:41.0961] ** Detected a caseXXXXXXXXXe volume.
[20:03:41.0961] The volume name is System
[20:03:41.0961] ** Checking extents overflow file.
[20:03:41.0961] ** Checking catalog file.
[20:03:41.0961] ** Checking multi-linked files.
[20:03:41.0961] ** Checking catalog hierarchy.
[20:03:41.0961] ** Checking extended attributes file.
[20:03:41.0961] ** Checking volume bitmap.
[20:03:41.0961] ** Checking volume information.
[20:03:41.0961] ** Trimming unused blocks.
[20:03:41.0961] ** The volume System appears to be OK.
[20:03:41.0961] CheckHFS returned 0, fsmodified = 0
[20:03:41.0961] executing /sbin/mount_hfs
[20:03:41.0961] /dev/disk0s1s1 mounted on /mnt1
[20:03:41.0961] executing /sbin/fsck_hfs
[20:03:41.0961] journal_replay(/dev/disk0s1s2) returned 0
[20:03:41.0961] ** /dev/rdisk0s1s2
[20:03:41.0961] Using cacheBlockSize=32K cacheTotalBlock=4000 cacheSize=128000K.
[20:03:41.0961] Executing fsck_hfs (version hfs-305.10.1).
[20:03:41.0961] ** Checking Journaled HFS Plus volume.
[20:03:41.0961] ** Detected a caseXXXXXXXXXe volume.
[20:03:41.0961] The volume name is Data
[20:03:41.0961] ** Checking extents overflow file.
[20:03:41.0961] ** Checking catalog file.
[20:03:41.0961] ** Checking multi-linked files.
[20:03:41.0961] ** Checking catalog hierarchy.
[20:03:41.0961] ** Checking extended attributes file.
[20:03:41.0961] ** Checking volume bitmap.
[20:03:41.0961] ** Checking volume information.
[20:03:41.0961] ** Trimming unused blocks.
[20:03:41.0961] ** The volume Data appears to be OK.
[20:03:41.0961] CheckHFS returned 0, fsmodified = 0
[20:03:41.0961] executing /sbin/mount_hfs
[20:03:41.0961] mount_hfs: Could not exec re-keying daemon /usr/libexec/rolld: No such file or directory
[20:03:41.0961] /dev/disk0s1s2 mounted on /mnt2
[20:03:41.0961] entering resize_system_partition_to_options
[20:03:41.0961] entering unmount_filesystems
[20:03:41.0961] entering restore_images
[20:03:41.0961] Successfully marked device node /dev/rdisk0s1s1 as static data
[20:03:41.0961] executing /usr/sbin/asr -source asr://localhost:12345 XXXXXXXXXSLOG: Sep 30 23:34:08 localhost kernel[0] <Notice>: void AppleUSBDeviceMuxXXXXXXXXXXXXXXXXXXXXt(struct BulkUSBMuxSession *, errno_t) new session to port 12345 failed: 61
[20:03:41.0961] /dev/disk0s1s1 -erase XXXXXXXXX -XXXXXXXXXcksum -XXXXXXXXXrings
[20:03:41.0961] SYSLOG: Sep 30 23:34:08 localhost asr[25] <Error>: bootstrap_look_up2 failed with 0x44e
[20:03:41.0961] ASR: asr: Unable to disable idle sleep - XXXXXXXXX9
[20:03:41.0961] ASR STATUS: start 242.6 multicast-client
[20:03:41.0961] ASR: Waiting for connection attempt from server
[20:03:41.0961] SYSLOG: Sep 30 23:34:08 localhost asr[25] <Error>: __net_helper_get_connection_block_invoke_3 could not connect to networkd
[20:03:41.0961] ASR STATUS: setup
[20:03:41.0961] ASR: Validating target...
[20:03:41.0961] ASR: done
[20:03:41.0961] ASR STATUS: metadata
[20:03:41.0961] ASR: Validating source...
[20:03:41.0961] ASR: done
[20:03:41.0961] ASR: Retrieving scan information...
[20:03:41.0961] ASR: done
[20:03:41.0961] ASR: Validating sizes...
[20:03:41.0961] ASR: done
[20:03:41.0961] ASR STATUS: restore
[20:03:41.0961] ASR RESTORE PROGRESS: 2%
[20:03:41.0961] ASR RESTORE PROGRESS: 4%
[20:03:41.0961] ASR RESTORE PROGRESS: 6%
[20:03:41.0961] ASR RESTORE PROGRESS: 8%
[20:03:41.0961] ASR RESTORE PROGRESS: 10%
[20:03:41.0961] ASR RESTORE PROGRESS: 12%
[20:03:41.0961] ASR RESTORE PROGRESS: 14%
[20:03:41.0961] ASR RESTORE PROGRESS: 16%
[20:03:41.0961] ASR RESTORE PROGRESS: 18%
[20:03:41.0961] ASR RESTORE PROGRESS: 20%
[20:03:41.0961] ASR RESTORE PROGRESS: 22%
[20:03:41.0961] ASR RESTORE PROGRESS: 24%
[20:03:41.0961] ASR RESTORE PROGRESS: 26%
[20:03:41.0961] ASR RESTORE PROGRESS: 28%
[20:03:41.0961] ASR RESTORE PROGRESS: 30%
[20:03:41.0961] ASR RESTORE PROGRESS: 32%
[20:03:41.0961] ASR RESTORE PROGRESS: 34%
[20:03:41.0961] ASR RESTORE PROGRESS: 36%
[20:03:41.0961] ASR RESTORE PROGRESS: 38%
[20:03:41.0961] ASR RESTORE PROGRESS: 40%
[20:03:41.0961] ASR RESTORE PROGRESS: 42%
[20:03:41.0961] ASR RESTORE PROGRESS: 44%
[20:03:41.0961] ASR RESTORE PROGRESS: 46%
[20:03:41.0961] ASR RESTORE PROGRESS: 48%
[20:03:41.0961] ASR RESTORE PROGRESS: 50%
[20:03:41.0961] ASR RESTORE PROGRESS: 52%
[20:03:41.0961] ASR RESTORE PROGRESS: 54%
[20:03:41.0961] ASR RESTORE PROGRESS: 56%
[20:03:41.0961] ASR RESTORE PROGRESS: 58%
[20:03:41.0961] ASR RESTORE PROGRESS: 60%
[20:03:41.0961] ASR RESTORE PROGRESS: 62%
[20:03:41.0961] ASR RESTORE PROGRESS: 64%
[20:03:41.0961] ASR RESTORE PROGRESS: 66%
[20:03:41.0961] ASR RESTORE PROGRESS: 68%
[20:03:41.0961] ASR RESTORE PROGRESS: 70%
[20:03:41.0961] ASR RESTORE PROGRESS: 72%
[20:03:41.0961] ASR RESTORE PROGRESS: 74%
[20:03:41.0961] ASR RESTORE PROGRESS: 76%
[20:03:41.0961] ASR RESTORE PROGRESS: 78%
[20:03:41.0961] ASR RESTORE PROGRESS: 80%
[20:03:41.0961] ASR RESTORE PROGRESS: 82%
[20:03:41.0961] ASR RESTORE PROGRESS: 84%
[20:03:41.0961] ASR RESTORE PROGRESS: 86%
[20:03:41.0961] ASR RESTORE PROGRESS: 88%
[20:03:41.0961] ASR RESTORE PROGRESS: 90%
[20:03:41.0961] ASR RESTORE PROGRESS: 92%
[20:03:41.0961] ASR RESTORE PROGRESS: 94%
[20:03:41.0961] ASR RESTORE PROGRESS: 96%
[20:03:41.0961] ASR RESTORE PROGRESS: 98%
[20:03:41.0961] SYSLOG: Sep 30 23:35:52 localhost asr[25] <Error>: CFPreferences could not connect to its daemon.
[20:03:41.0961] Preferences using the connection 0x0 will be volatile and will not be persisted to disk.
[20:03:41.0961] SYSLOG: Sep 30 23:35:52 localhost asr[25] <Critical>: securityd_message_with_reply_sync Failed to talk to secd after 4 attempts.
[20:03:41.0961] SYSLOG: Sep 30 23:35:52 localhost asr[25] <Critical>: securityd_message_with_reply_sync Failed to talk to secd after 4 attempts.
[20:03:41.0961] SYSLOG: Sep 30 23:35:52 localhost asr[25] <Error>: SecTrustEvaluate
[20:03:41.0961] SYSLOG: Sep 30 23:35:52 localhost asr[25] <Critical>: securityd_message_with_reply_sync Failed to talk to secd after 4 attempts.
[20:03:41.0961] SYSLOG: Sep 30 23:35:52 localhost asr[25] <Critical>: securityd_message_with_reply_sync Failed to talk to secd after 4 attempts.
[20:03:41.0961] SYSLOG: Sep 30 23:35:52 localhost asr[25] <Error>: SecTrustEvaluate
[20:03:41.0961] ASR RESTORE PROGRESS: 100%
[20:03:41.0961] ASR: Copied 2822074880 bytes in 93.25 seconds, 29554.40 KiB/s
[20:03:41.0961] ASR STATUS: verify
[20:03:41.0961] ASR VERIFY PROGRESS: 2%
[20:03:41.0961] ASR VERIFY PROGRESS: 4%
[20:03:41.0961] ASR VERIFY PROGRESS: 6%
[20:03:41.0961] ASR VERIFY PROGRESS: 8%
[20:03:41.0961] ASR VERIFY PROGRESS: 10%
[20:03:41.0961] ASR VERIFY PROGRESS: 12%
[20:03:41.0961] ASR VERIFY PROGRESS: 14%
[20:03:41.0961] ASR VERIFY PROGRESS: 16%
[20:03:41.0961] ASR VERIFY PROGRESS: 18%
[20:03:41.0961] ASR VERIFY PROGRESS: 20%
[20:03:41.0961] ASR VERIFY PROGRESS: 22%
[20:03:41.0961] ASR VERIFY PROGRESS: 24%
[20:03:41.0961] ASR VERIFY PROGRESS: 26%
[20:03:41.0961] ASR VERIFY PROGRESS: 28%
[20:03:41.0961] ASR VERIFY PROGRESS: 30%
[20:03:41.0961] ASR VERIFY PROGRESS: 32%
[20:03:41.0961] ASR VERIFY PROGRESS: 34%
[20:03:41.0961] ASR VERIFY PROGRESS: 36%
[20:03:41.0961] ASR VERIFY PROGRESS: 38%
[20:03:41.0961] ASR VERIFY PROGRESS: 40%
[20:03:41.0961] ASR VERIFY PROGRESS: 42%
[20:03:41.0961] ASR VERIFY PROGRESS: 44%
[20:03:41.0961] ASR VERIFY PROGRESS: 46%
[20:03:41.0961] ASR VERIFY PROGRESS: 48%
[20:03:41.0961] ASR VERIFY PROGRESS: 50%
[20:03:41.0961] ASR VERIFY PROGRESS: 52%
[20:03:41.0961] ASR VERIFY PROGRESS: 54%
[20:03:41.0961] ASR VERIFY PROGRESS: 56%
[20:03:41.0961] ASR VERIFY PROGRESS: 58%
[20:03:41.0961] ASR VERIFY PROGRESS: 60%
[20:03:41.0961] ASR VERIFY PROGRESS: 62%
[20:03:41.0961] ASR VERIFY PROGRESS: 64%
[20:03:41.0961] ASR VERIFY PROGRESS: 66%
[20:03:41.0961] ASR VERIFY PROGRESS: 68%
[20:03:41.0961] ASR VERIFY PROGRESS: 70%
[20:03:41.0961] ASR VERIFY PROGRESS: 72%
[20:03:41.0961] ASR VERIFY PROGRESS: 74%
[20:03:41.0961] ASR VERIFY PROGRESS: 76%
[20:03:41.0961] ASR VERIFY PROGRESS: 78%
[20:03:41.0961] ASR VERIFY PROGRESS: 80%
[20:03:41.0961] ASR VERIFY PROGRESS: 82%
[20:03:41.0961] ASR VERIFY PROGRESS: 84%
[20:03:41.0961] ASR VERIFY PROGRESS: 86%
[20:03:41.0961] ASR VERIFY PROGRESS: 88%
[20:03:41.0961] ASR VERIFY PROGRESS: 90%
[20:03:41.0961] ASR VERIFY PROGRESS: 92%
[20:03:41.0961] ASR VERIFY PROGRESS: 94%
[20:03:41.0961] ASR VERIFY PROGRESS: 96%
[20:03:41.0961] ASR VERIFY PROGRESS: 98%
[20:03:41.0961] ASR VERIFY PROGRESS: 100%
[20:03:41.0961] ASR: Verified SHA-1 checksum 2822074880 bytes in 13.42 seconds, 205324.22 KiB/s
[20:03:41.0961] ASR STATUS: finish
[20:03:41.0961] entering ramrod_probe_media
[20:03:41.0961] device partitioning scheme is GPT
[20:03:41.0961] find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 baseband data= log=
[20:03:41.0961] entering mount_filesystems
[20:03:41.0961] executing /sbin/fsck_hfs
[20:03:41.0961] journal_replay(/dev/disk0s1s1) returned 0
[20:03:41.0961] ** /dev/rdisk0s1s1
[20:03:41.0961] Using cacheBlockSize=32K cacheTotalBlock=4000 cacheSize=128000K.
[20:03:41.0961] Executing fsck_hfs (version hfs-305.10.1).
[20:03:41.0961] ** Checking Journaled HFS Plus volume.
[20:03:41.0961] ** Detected a caseXXXXXXXXXe volume.
[20:03:41.0961] The volume name is Boulder13B143.N51OS
[20:03:41.0961] ** Checking extents overflow file.
[20:03:41.0961] ** Checking catalog file.
[20:03:41.0961] ** Checking multi-linked files.
[20:03:41.0961] ** Checking catalog hierarchy.
[20:03:41.0961] ** Checking extended attributes file.
[20:03:41.0961] ** Checking volume bitmap.
[20:03:41.0961] ** Checking volume information.
[20:03:41.0961] ** Trimming unused blocks.
[20:03:41.0961] ** The volume Boulder13B143.N51OS appears to be OK.
[20:03:41.0961] CheckHFS returned 0, fsmodified = 0
[20:03:41.0961] executing /sbin/mount_hfs
[20:03:41.0961] /dev/disk0s1s1 mounted on /mnt1
[20:03:41.0961] executing /sbin/fsck_hfs
[20:03:41.0961] journal_replay(/dev/disk0s1s2) returned 0
[20:03:41.0961] ** /dev/rdisk0s1s2
[20:03:41.0961] Using cacheBlockSize=32K cacheTotalBlock=4000 cacheSize=128000K.
[20:03:41.0961] Executing fsck_hfs (version hfs-305.10.1).
[20:03:41.0961] ** Checking Journaled HFS Plus volume.
[20:03:41.0961] ** Detected a caseXXXXXXXXXe volume.
[20:03:41.0961] The volume name is Data
[20:03:41.0961] ** Checking extents overflow file.
[20:03:41.0961] ** Checking catalog file.
[20:03:41.0961] ** Checking multi-linked files.
[20:03:41.0961] ** Checking catalog hierarchy.
[20:03:41.0961] ** Checking extended attributes file.
[20:03:41.0961] ** Checking volume bitmap.
[20:03:41.0961] ** Checking volume information.
[20:03:41.0961] ** Trimming unused blocks.
[20:03:41.0961] ** The volume Data appears to be OK.
[20:03:41.0961] CheckHFS returned 0, fsmodified = 0
[20:03:41.0961] executing /sbin/mount_hfs
[20:03:41.0961] mount_hfs: Could not exec re-keying daemon /usr/libexec/rolld: No such file or directory
[20:03:41.0961] /dev/disk0s1s2 mounted on /mnt2
[20:03:41.0961] RestoredFDRRestoreSavedData success
[20:03:41.0961] entering install_kernel_cache
[20:03:41.0961] writing kernelcache to /mnt1/System/Library/Caches/com.apple.kernelcaches/kernelcache
[20:03:41.0961] entering install_secure_vault_daemon
[20:03:41.0961] entering get_hardware_platform
[20:03:41.0961] platform-name = s5l8960x
[20:03:41.0961] linking /mnt1/usr/sbin/securekeyvaultd to /mnt1/usr/sbin/securekeyvaultd.s5l8960x
[20:03:41.0961] no securekeyvaultd for s5l8960x
[20:03:41.0961] entering ramrod_ticket_write
[20:03:41.0961] writing ticket to /mnt1/System/Library/Caches/apticket.der
[20:03:41.0961] entering RestoredFDRRecover
[20:03:41.0961] entering update_device_firmware
[20:03:41.0961] entering add_baseband_partition_options
[20:03:41.0961] entering ramrod_update_device_firmware
[20:03:41.0961] entering update_iBoot
[20:03:41.0961] entering copy_sep
[20:03:41.0961] found 'ASPStorage' object in IORegistry
[20:03:41.0961] entering update_iBoot_ASP_storage
[20:03:41.0961] entering wait_for_device: '/dev/disk1'
[20:03:41.0961] entering wait_for_device: '/dev/disk2'
[20:03:41.0961] writing 10 images to Firmware node '/dev/disk1' (preferred_block_size = 4096)
[20:03:41.0961] original data length: 644140; img4_restore_data length: 644177
[20:03:41.0961] write_data_to_fd: length=644177, preferred_block_size=4096
[20:03:41.0961] SYSLOG: Sep 30 23:36:15 localhost kernel[0] <Notice>: [ANS syslog: nand] Util_Host:element=FW written size=1093632, host request trimmed to end of written content
[20:03:41.0961] original data length: 6890; img4_restore_data length: 6927
[20:03:41.0961] write_data_to_fd: length=6927, preferred_block_size=4096
[20:03:41.0961] original data length: 156090; img4_restore_data length: 156127
[20:03:41.0961] write_data_to_fd: length=156127, preferred_block_size=4096
[20:03:41.0961] original data length: 8794; img4_restore_data length: 8831
[20:03:41.0961] write_data_to_fd: length=8831, preferred_block_size=4096
[20:03:41.0961] original data length: 14570; img4_restore_data length: 14607
[20:03:41.0961] write_data_to_fd: length=14607, preferred_block_size=4096
[20:03:41.0961] original data length: 48458; img4_restore_data length: 48495
[20:03:41.0961] write_data_to_fd: length=48495, preferred_block_size=4096
[20:03:41.0961] original data length: 88909; img4_restore_data length: 88946
[20:03:41.0961] write_data_to_fd: length=88946, preferred_block_size=4096
[20:03:41.0961] original data length: 39850; img4_restore_data length: 39887
[20:03:41.0961] write_data_to_fd: length=39887, preferred_block_size=4096
[20:03:41.0961] original data length: 11658; img4_restore_data length: 11695
[20:03:41.0961] write_data_to_fd: length=11695, preferred_block_size=4096
[20:03:41.0961] original data length: 78381; img4_restore_data length: 78418
[20:03:41.0961] write_data_to_fd: length=78418, preferred_block_size=4096
[20:03:41.0961] done with flash images - writing end of contents
[20:03:41.0961] write_data_to_fd: length=2, preferred_block_size=4096
[20:03:41.0961] SYSLOG: Sep 30 23:36:15 localhost kernel[0] <Notice>: [ANS syslog: nand] Util_FW_Host_Resize:---fwSize size 269 bytes
[20:03:41.0961] SYSLOG: Sep 30 23:36:15 localhost kernel[0] <Notice>: [ANS syslog: nand] Util_FW_Host_Resize:---fwSize size 269 bytes
[20:03:41.0961] SYSLOG: Sep 30 23:36:15 localhost kernel[0] <Notice>: [ANS syslog: nand] Util_FW_Ans:[ 2, 4, 0] Wrote Firmware B with err=NO_ERROR
[20:03:41.0961] SYSLOG: Sep 30 23:36:16 localhost kernel[0] <Notice>: [ANS syslog: nand] Util_FW_Ans:[ 1, 1, 0] Wrote Firmware A with err=NO_ERROR
[20:03:41.0961] writing 1 boot images to boot block node '/dev/disk2' (preferred_block_size = 0x4096)
[20:03:41.0961] original data length: 152620; img4_restore_data length: 152657
[20:03:41.0961] write_data_to_fd: length=152657, preferred_block_size=4096
[20:03:41.0961] SYSLOG: Sep 30 23:36:16 localhost kernel[0] <Notice>: [ANS syslog: nand] Util_Host:element=LLB written size=167936, host request trimmed to end of written content
[20:03:41.0961] write_data_to_fd: length=2, preferred_block_size=4096
[20:03:41.0961] write_data_to_fd: length=3026, preferred_block_size=4096
[20:03:41.0961] done with boot images - writing end of contents
[20:03:41.0961] SYSLOG: Sep 30 23:36:16 localhost kernel[0] <Notice>: LwVM::probe - failed to read header from media
[20:03:41.0961] entering update_sep
[20:03:41.0961] writing sep firmware to /mnt1/usr/standalone/firmware/sepXXXXXXXXX.img4
[20:03:41.0961] write_data_to_fd: length=4473885, preferred_block_size=4096
[20:03:41.0961] update_sep returned: success
[20:03:41.0961] entering update_gas_gauge
[20:03:41.0961] entering update_ir_mcu
[20:03:41.0961] looking for mcu nub...no entry from path!
[20:03:41.0961] TiSerialFlasher not supported on this platform. Exiting.
[20:03:41.0961] entering update_baseband
[20:03:41.0961] update_baseband: registering for progress notifications
[20:03:41.0961] create_baseband_update_options: Got imeisv: 15
[20:03:41.0961] baseband updater options = <CFBasicHash 0x15d611f30 [0x100b667d8]>{type = mutable dict, count = 8,
[20:03:41.0961] entries =>
[20:03:41.0961] 2 : <CFString 0x101788ce0 [0x100b667d8]>{contents = "SystemRoot"} = /mnt1
[20:03:41.0961] 4 : <CFString 0x101788c00 [0x100b667d8]>{contents = "DisallowFusiing"} = <CFBoolean 0x100b66d10 [0x100b667d8]>{value = true}
[20:03:41.0961] 6 : <CFString 0x101788fe0 [0x100b667d8]>{contents = "autoSetupEnv"} = <CFBoolean 0x100b66d20 [0x100b667d8]>{value = false}
[20:03:41.0961] 7 : <CFString 0x101788ba0 [0x100b667d8]>{contents = "forceUpdate"} = <CFBoolean 0x100b66d10 [0x100b667d8]>{value = true}
[20:03:41.0961] 8 : <CFString 0x101789000 [0x100b667d8]>{contents = "disablePing"} = <CFBoolean 0x100b66d10 [0x100b667d8]>{value = true}
[20:03:41.0961] 9 : <CFString 0x101788e80 [0x100b667d8]>{contents = "restoredInternal"} = <CFBoolean 0x100b66d20 [0x100b667d8]>{value = false}
[20:03:41.0961] 11 : <CFString 0x101788d00 [0x100b667d8]>{contents = "DataRoot"} = /mnt2
[20:03:41.0961] 12 : <CFString 0x101788ec0 [0x100b667d8]>{contents = "IMEISwVersion"} = <CFNumber 0x<<<<<MEID>>>>>f2 [0x100b667d8]>{value = +15, type = kCFNumberSInt32Type}
[20:03:41.0961] }
[20:03:41.0961] update_baseband: querying baseband info
[20:03:41.0961] bbupdater: BEGIN: Querying info at stage first
[20:03:41.0961] bbupdater: BEGIN: Preparing first stage
[20:03:41.0961] bbupdater: Requested boot mode: kBBUBootModeNone
[20:03:41.0961] bbupdater: Creating transport, attempts remaining 5...bbupdater: FAILED(kBBUReturnIOError)
[20:03:41.0961] bbupdater: powercycling modem
[20:03:41.0961] bbupdater: Creating transport, attempts remaining 4...bbupdater: FAILED(kBBUReturnIOError)
[20:03:41.0961] bbupdater: powercycling modem
[20:03:41.0961] bbupdater: Creating transport, attempts remaining 3...bbupdater: FAILED(kBBUReturnIOError)
[20:03:41.0961] bbupdater: powercycling modem
[20:03:41.0961] bbupdater: Creating transport, attempts remaining 2...bbupdater: FAILED(kBBUReturnIOError)
[20:03:41.0961] bbupdater: powercycling modem
[20:03:41.0961] bbupdater: Creating transport, attempts remaining 1...bbupdater: FAILED(kBBUReturnIOError)
[20:03:41.0961] bbupdater: powercycling modem
[20:03:41.0961] bbupdater: Creating transport, attempts remaining 0...bbupdater: FAILED(kBBUReturnIOError)
[20:03:41.0961] bbupdater: END(kBBUReturnFailCreateTransportFirst): Preparing first stage
[20:03:41.0961] bbupdater: END(kBBUReturnFailCreateTransportFirst): Querying info at stage first
[20:03:41.0961] send_bbupdate_status: sending baseband update status to host
[20:03:41.0961] update_baseband: calling CFNotificationCenterRemoveEveryObserver
[20:03:41.0961] update_baseband: bbupdater error: The operation couldn\U2019t be completed. (BBUpdater error 50 - Failed creating a communication transport to baseband.)
[20:03:41.0961] Update[Baseband] error - The operation couldn\U2019t be completed. (RamrodErrorDomain error 1004 - update_baseband: failed querying baseband info)
[20:03:41.0961] Update[Baseband] failed - 0 retries remaining...
[20:03:41.0961] 0: RamrodErrorDomain/3ec: update_baseband: failed querying baseband info
[20:03:41.0961] 1: BBUpdater/32: Failed creating a communication transport to baseband.
[20:03:41.0961] unable to convert ramrod error 1004
[20:03:41.0961]
[20:03:41.0961] ==== end of device restore output ====
[20:03:41.0962] AMRAuthInstallDeletePersonalizedBundle
[20:03:42.0224] Restore completed, status:-1
[20:03:42.0224] Failure Description:
[20:03:42.0224] Depth:0 Error:AMRestorePerformRestoreModeRestoreWithError failed with error: -1
[20:03:42.0224] Depth:1 Error:İşlem tamamlanamadı. (AMRestoreErrorDomain error -1 - Failed to handle message type StatusMsg)
[20:03:42.0224] Depth:2 Error:İşlem tamamlanamadı. (AMRestoreErrorDomain error -1 - failed to update device firmware)
[20:03:42.0224] Depth:3 Error:İşlem tamamlanamadı. (AMRestoreErrorDomain error -1 - ramrod firmware update failed)
[20:03:42.0224] Depth:4 Error:İşlem tamamlanamadı. (RamrodErrorDomain error 1004 - update_baseband: failed querying baseband info)
[20:03:42.0224] Depth:5 Error:İşlem tamamlanamadı. (BBUpdater hatası 50 - Failed creating a communication transport to baseband.)
iPhone 5s
