2011-12-31 14:32:01.823 [4724:1414]: restore library built Sep 14 2011 at 01:30:07 2011-12-31 14:32:01.823 [4724:1414]: iTunes: iTunes 10.5.2.11 2011-12-31 14:32:01.994 [4724:1414]: iTunes: Current software version: 9A334 2011-12-31 14:32:01.994 [4724:1414]: iTunes: Software payload version: 9A405 2011-12-31 14:32:49.123 [4724:12d8]: iTunes: Specifying UOI boot image 2011-12-31 14:33:04.770 [4724:12d8]: *** UUID 3EDB17E7-510D-E947-9F2F-4466AC0074BF *** 2011-12-31 14:33:04.785 [4724:12d8]: requested restore behavior: Erase 2011-12-31 14:33:04.801 [4724:12d8]: amai: AMAuthInstallDebugWriteObject: debug object written: C:\Users\LEVEL7~1\AppData\Local\Temp\PerDAD0.tmp\amai\debug\tss-request.plist 2011-12-31 14:33:05.144 [4724:12d8]: amai: tss_submit_job: HttpQueryInfo returned 200 2011-12-31 14:33:05.409 [4724:12d8]: amai: AMAuthInstallRequestSendSync: received tss response (server version: 2.0.0) 2011-12-31 14:33:05.409 [4724:12d8]: amai: AMAuthInstallDebugWriteObject: debug object written: C:\Users\LEVEL7~1\AppData\Local\Temp\PerDAD0.tmp\amai\debug\tss-response.plist 2011-12-31 14:33:05.534 [4724:12d8]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "KernelCache" has been previously personalized; skipping it 2011-12-31 14:33:05.550 [4724:12d8]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "DeviceTree" has been previously personalized; skipping it 2011-12-31 14:33:05.565 [4724:12d8]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "AppleLogo" has been previously personalized; skipping it 2011-12-31 14:33:05.612 [4724:1414]: iTunes: Entering recovery mode, device 0616BEF0 2011-12-31 14:33:26.126 [4724:b18]: received kAMDeviceDetached action, device 0616BEF0 2011-12-31 14:33:41.356 [4724:dd8]: WinAMRestore::OnInterfaceArrival: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#90b3c183 2011-12-31 14:33:41.356 [4724:dd8]: WinAMRestore::AddAppleDeviceToDeviceList: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#90b3c183 2011-12-31 14:33:41.613 [4724:dd8]: AppleDevice::EnumerateHubPorts: DoesDriverNameMatchDeviceID failed 2011-12-31 14:33:41.643 [4724:dd8]: AppleDevice::SetNotification: CONNECT, interface type: 1, id: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#90b3c183, inst: 0x635e8c0 2011-12-31 14:33:41.643 [4724:dd8]: WinAMRestore::AddAppleDeviceToDeviceList, empty list, new device 2011-12-31 14:33:41.643 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e8c0, notify: 1, connected: 0 2011-12-31 14:33:41.643 [4724:dd8]: AppleDevice::NotifyConnect: Device type: 2, Interfaces total: 2, arrived: 1 2011-12-31 14:33:41.643 [4724:dd8]: AppleDevice::NotifyConnect: Interfaces total != arrived, no notification 2011-12-31 14:33:41.653 [4724:dd8]: WinAMRestore::OnInterfaceArrival: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#90b3c183 2011-12-31 14:33:41.653 [4724:dd8]: WinAMRestore::AddAppleDeviceToDeviceList: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#90b3c183 2011-12-31 14:33:41.773 [4724:dd8]: AppleDevice::EnumerateHubPorts: DoesDriverNameMatchDeviceID failed 2011-12-31 14:33:41.803 [4724:dd8]: WinAMRestore::AddAppleDeviceToDeviceList, device already connected, new interface 2011-12-31 14:33:41.803 [4724:dd8]: AppleDevice::SetDeviceID: AMD_INTERFACE_DFU \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#90b3c183, inst: 0x635e8c0 2011-12-31 14:33:41.803 [4724:dd8]: AppleDevice::SetNotification: CONNECT, interface type: 1, id: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#90b3c183, inst: 0x635e8c0 2011-12-31 14:33:41.803 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e8c0, notify: 1, connected: 1 2011-12-31 14:33:41.803 [4724:dd8]: AppleDevice::NotifyConnect: Device type: 2, Interfaces total: 2, arrived: 2 2011-12-31 14:33:41.803 [4724:dd8]: AppleDevice::NotifyConnect: IBOOT, IBOOT \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#90b3c183, inst: 0x635e8c0 2011-12-31 14:33:41.803 [4724:dd8]: IBOOT, DFU \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#90b3c183, inst: 0x635e8c0 2011-12-31 14:33:41.813 [4724:1414]: iTunes: SCEP 2 2011-12-31 14:33:41.853 [4724:91c]: iBoot build-version = iBoot-1219.43.32 2011-12-31 14:33:41.853 [4724:91c]: iBoot build-style = RELEASE 2011-12-31 14:33:41.853 [4724:91c]: AMDeviceIoControl: GetOverlappedResult failed 2011-12-31 14:33:41.853 [4724:91c]: AMDeviceIoControl: pipe stall 2011-12-31 14:33:41.853 [4724:91c]: USBControlTransfer: error 31, usbd status c0000004 2011-12-31 14:33:41.853 [4724:91c]: command device request for 'getenv radio-error' failed: 2008 2011-12-31 14:33:41.853 [4724:91c]: radio-error not set 2011-12-31 14:33:41.853 [4724:91c]: : operation 44 progress -1 2011-12-31 14:33:41.853 [4724:91c]: requested restore behavior: Erase 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreLogo" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreDeviceTree" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreKernelCache" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreRamDisk" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBEC" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBSS" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "KernelCache" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryLow1" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryLow0" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "LLB" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBoot" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "DeviceTree" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging1" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "AppleLogo" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryPlugin" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryFull" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging0" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RecoveryMode" has been previously personalized; skipping it 2011-12-31 14:33:41.853 [4724:91c]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: nothing to be done 2011-12-31 14:33:41.863 [4724:91c]: unable to open device_map.txt: No such file or directory 2011-12-31 14:33:41.863 [4724:91c]: : production fused device 2011-12-31 14:33:41.863 [4724:91c]: interface has 1 endpoints, file pipe = 1 2011-12-31 14:33:41.863 [4724:91c]: : operation 4 progress -1 2011-12-31 14:33:41.913 [4724:91c]: bootstrapping restore with iBEC 2011-12-31 14:33:41.933 [4724:91c]: : operation 31 progress -1 2011-12-31 14:33:42.946 [4724:91c]: : Recovery mode succeeded 2011-12-31 14:33:43.367 [4724:dd8]: WinAMRestore::OnInterfaceRemoval: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#90b3c183 2011-12-31 14:33:43.367 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e8c0, notify: 2, connected: 1 2011-12-31 14:33:43.367 [4724:dd8]: AppleDevice::NotifyDisconnect: IBOOT, IBOOT \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#90b3c183, inst: 0x635e8c0 2011-12-31 14:33:43.367 [4724:dd8]: IBOOT, DFU \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#90b3c183, inst: 0x635e8c0 2011-12-31 14:33:43.367 [4724:dd8]: _AMRecoveryModeDeviceFinalize: 00A62080 2011-12-31 14:33:43.383 [4724:dd8]: WinAMRestore::OnInterfaceRemoval: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#90b3c183 2011-12-31 14:33:43.383 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e8c0, notify: 2, connected: 0 2011-12-31 14:34:00.963 [4724:dd8]: WinAMRestore::OnInterfaceArrival: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#5cfc3602 2011-12-31 14:34:00.963 [4724:dd8]: WinAMRestore::AddAppleDeviceToDeviceList: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#5cfc3602 2011-12-31 14:34:01.219 [4724:dd8]: AppleDevice::EnumerateHubPorts: DoesDriverNameMatchDeviceID failed 2011-12-31 14:34:01.249 [4724:dd8]: AppleDevice::SetNotification: CONNECT, interface type: 1, id: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#5cfc3602, inst: 0x635e820 2011-12-31 14:34:01.249 [4724:dd8]: WinAMRestore::AddAppleDeviceToDeviceList, new device 2011-12-31 14:34:01.249 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e8c0, notify: 2, connected: 0 2011-12-31 14:34:01.249 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e820, notify: 1, connected: 0 2011-12-31 14:34:01.249 [4724:dd8]: AppleDevice::NotifyConnect: Device type: 2, Interfaces total: 2, arrived: 1 2011-12-31 14:34:01.249 [4724:dd8]: AppleDevice::NotifyConnect: Interfaces total != arrived, no notification 2011-12-31 14:34:01.249 [4724:dd8]: WinAMRestore::OnInterfaceArrival: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#5cfc3602 2011-12-31 14:34:01.249 [4724:dd8]: WinAMRestore::AddAppleDeviceToDeviceList: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#5cfc3602 2011-12-31 14:34:01.349 [4724:dd8]: AppleDevice::EnumerateHubPorts: DoesDriverNameMatchDeviceID failed 2011-12-31 14:34:01.369 [4724:dd8]: WinAMRestore::AddAppleDeviceToDeviceList, device already connected, new interface 2011-12-31 14:34:01.369 [4724:dd8]: AppleDevice::SetDeviceID: AMD_INTERFACE_DFU \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#5cfc3602, inst: 0x635e820 2011-12-31 14:34:01.369 [4724:dd8]: AppleDevice::SetNotification: CONNECT, interface type: 1, id: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#5cfc3602, inst: 0x635e820 2011-12-31 14:34:01.369 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e8c0, notify: 2, connected: 0 2011-12-31 14:34:01.369 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e820, notify: 1, connected: 1 2011-12-31 14:34:01.369 [4724:dd8]: AppleDevice::NotifyConnect: Device type: 2, Interfaces total: 2, arrived: 2 2011-12-31 14:34:01.369 [4724:dd8]: AppleDevice::NotifyConnect: IBOOT, IBOOT \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#5cfc3602, inst: 0x635e820 2011-12-31 14:34:01.369 [4724:dd8]: IBOOT, DFU \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#5cfc3602, inst: 0x635e820 2011-12-31 14:34:01.379 [4724:1414]: iTunes: SCEP 2 2011-12-31 14:34:01.419 [4724:c08]: iBoot build-version = iBoot-1219.43.32 2011-12-31 14:34:01.419 [4724:c08]: iBoot build-style = RELEASE 2011-12-31 14:34:01.419 [4724:c08]: AMDeviceIoControl: GetOverlappedResult failed 2011-12-31 14:34:01.419 [4724:c08]: AMDeviceIoControl: pipe stall 2011-12-31 14:34:01.419 [4724:c08]: USBControlTransfer: error 31, usbd status c0000004 2011-12-31 14:34:01.419 [4724:c08]: command device request for 'getenv radio-error' failed: 2008 2011-12-31 14:34:01.419 [4724:c08]: radio-error not set 2011-12-31 14:34:01.419 [4724:c08]: : operation 44 progress -1 2011-12-31 14:34:01.419 [4724:c08]: requested restore behavior: Erase 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreLogo" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreDeviceTree" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreKernelCache" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreRamDisk" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBEC" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBSS" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "KernelCache" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryLow1" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryLow0" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "LLB" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBoot" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "DeviceTree" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging1" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "AppleLogo" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryPlugin" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryFull" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging0" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RecoveryMode" has been previously personalized; skipping it 2011-12-31 14:34:01.419 [4724:c08]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: nothing to be done 2011-12-31 14:34:01.429 [4724:c08]: unable to open device_map.txt: No such file or directory 2011-12-31 14:34:01.429 [4724:c08]: : production fused device 2011-12-31 14:34:01.429 [4724:c08]: interface has 1 endpoints, file pipe = 1 2011-12-31 14:34:01.429 [4724:c08]: : operation 4 progress -1 2011-12-31 14:34:01.479 [4724:c08]: : operation 42 progress -1 2011-12-31 14:34:02.510 [4724:c08]: : operation 5 progress -1 2011-12-31 14:34:03.477 [4724:c08]: AMDeviceIoControl: GetOverlappedResult failed 2011-12-31 14:34:03.477 [4724:c08]: AMDeviceIoControl: pipe stall 2011-12-31 14:34:03.477 [4724:c08]: USBControlTransfer: error 31, usbd status c0000004 2011-12-31 14:34:03.477 [4724:c08]: command device request for 'getenv ramdisk-delay' failed: 2008 2011-12-31 14:34:06.238 [4724:c08]: : operation 6 progress -1 2011-12-31 14:34:07.268 [4724:c08]: : operation 7 progress -1 2011-12-31 14:34:07.564 [4724:c08]: : operation 8 progress -1 2011-12-31 14:34:07.564 [4724:c08]: : operation 9 progress -1 2011-12-31 14:34:07.564 [4724:c08]: : Recovery mode succeeded 2011-12-31 14:34:42.867 [4724:dd8]: WinAMRestore::OnInterfaceRemoval: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#5cfc3602 2011-12-31 14:34:42.867 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e8c0, notify: 2, connected: 0 2011-12-31 14:34:42.867 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e820, notify: 2, connected: 1 2011-12-31 14:34:42.867 [4724:dd8]: AppleDevice::NotifyDisconnect: IBOOT, IBOOT \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#5cfc3602, inst: 0x635e820 2011-12-31 14:34:42.867 [4724:dd8]: IBOOT, DFU \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#5cfc3602, inst: 0x635e820 2011-12-31 14:34:42.867 [4724:dd8]: _AMRecoveryModeDeviceFinalize: 00A62080 2011-12-31 14:34:42.883 [4724:dd8]: WinAMRestore::OnInterfaceRemoval: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#5cfc3602 2011-12-31 14:34:42.883 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e8c0, notify: 2, connected: 0 2011-12-31 14:34:42.883 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e820, notify: 2, connected: 0 2011-12-31 14:34:46.274 [4724:b18]: received kAMDeviceAttached action, device 0618FE68, AFC error 0XE8000028 2011-12-31 14:34:46.274 [4724:b18]: iTunes: Restore-mode device appeared, device 0618FE68 2011-12-31 14:34:46.274 [4724:b18]: iTunes: Creating restore mode device with usbMuxDeviceID 0X6 2011-12-31 14:34:46.274 [4724:b18]: : operation 3 progress -1 2011-12-31 14:34:46.290 [4724:b18]: : operation 4 progress -1 2011-12-31 14:34:46.290 [4724:b18]: Supports value queries: 1 2011-12-31 14:34:46.418 [4724:1564]: : operation 44 progress -1 2011-12-31 14:34:46.418 [4724:1564]: requested restore behavior: Erase 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreLogo" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreDeviceTree" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreKernelCache" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreRamDisk" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBEC" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBSS" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "KernelCache" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryLow1" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryLow0" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "LLB" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBoot" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "DeviceTree" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging1" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "AppleLogo" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryPlugin" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryFull" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging0" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RecoveryMode" has been previously personalized; skipping it 2011-12-31 14:34:46.428 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: nothing to be done 2011-12-31 14:34:46.438 [4724:1564]: device did not return saved USB log 2011-12-31 14:34:46.438 [4724:1564]: device did not return saved panic log 2011-12-31 14:34:46.448 [4724:1564]: connected to service com.apple.mobile.restored 2011-12-31 14:34:46.448 [4724:1564]: using protocol version 12 2011-12-31 14:34:46.458 [4724:1564]: unable to open device_map.txt: No such file or directory 2011-12-31 14:34:46.458 [4724:1564]: board config = n90ap 2011-12-31 14:34:46.468 [4724:1564]: value query for 'HardwareModel' returned 'N90AP' 2011-12-31 14:34:46.498 [4724:1564]: : operation 28 progress -1 2011-12-31 14:35:07.864 [4724:1564]: : operation 11 progress -1 2011-12-31 14:35:08.581 [4724:1564]: : operation 12 progress -1 2011-12-31 14:35:09.845 [4724:1564]: : operation 12 progress -1 2011-12-31 14:35:15.398 [4724:1564]: : operation 15 progress -1 2011-12-31 14:35:16.178 [4724:1564]: : operation 16 progress -1 2011-12-31 14:35:16.272 [4724:1564]: : operation 15 progress -1 2011-12-31 14:35:22.122 [4724:1564]: : operation 16 progress -1 2011-12-31 14:35:22.340 [4724:1564]: : operation 51 progress -1 2011-12-31 14:35:23.791 [4724:1564]: : operation 29 progress -1 2011-12-31 14:35:23.838 [4724:1564]: : operation 29 progress -1 2011-12-31 14:35:23.916 [4724:1564]: : operation 13 progress 0 2011-12-31 14:35:33.947 [4724:1564]: : operation 13 progress 1 2011-12-31 14:35:33.947 [4724:1564]: ASR progress: Initialized communication with client 2011-12-31 14:35:36.302 [4724:1564]: : operation 13 progress 5 2011-12-31 14:35:36.302 [4724:1564]: ASR progress: Starting to send payload 2011-12-31 14:35:37.472 [4724:1564]: : operation 13 progress 6 2011-12-31 14:35:37.472 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:39.064 [4724:1564]: : operation 13 progress 7 2011-12-31 14:35:39.064 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:40.093 [4724:1564]: : operation 13 progress 8 2011-12-31 14:35:40.093 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:40.873 [4724:1564]: : operation 13 progress 9 2011-12-31 14:35:40.873 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:41.778 [4724:1564]: : operation 13 progress 10 2011-12-31 14:35:41.778 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:42.776 [4724:1564]: : operation 13 progress 11 2011-12-31 14:35:42.776 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:43.712 [4724:1564]: : operation 13 progress 12 2011-12-31 14:35:43.712 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:44.726 [4724:1564]: : operation 13 progress 13 2011-12-31 14:35:44.726 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:45.943 [4724:1564]: : operation 13 progress 14 2011-12-31 14:35:45.943 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:47.176 [4724:1564]: : operation 13 progress 15 2011-12-31 14:35:47.176 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:47.971 [4724:1564]: : operation 13 progress 16 2011-12-31 14:35:47.971 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:48.860 [4724:1564]: : operation 13 progress 17 2011-12-31 14:35:48.860 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:49.952 [4724:1564]: : operation 13 progress 18 2011-12-31 14:35:49.952 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:50.732 [4724:1564]: : operation 13 progress 19 2011-12-31 14:35:50.732 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:51.575 [4724:1564]: : operation 13 progress 20 2011-12-31 14:35:51.575 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:52.417 [4724:1564]: : operation 13 progress 21 2011-12-31 14:35:52.417 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:53.197 [4724:1564]: : operation 13 progress 22 2011-12-31 14:35:53.197 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:54.102 [4724:1564]: : operation 13 progress 23 2011-12-31 14:35:54.102 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:55.132 [4724:1564]: : operation 13 progress 24 2011-12-31 14:35:55.132 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:55.943 [4724:1564]: : operation 13 progress 25 2011-12-31 14:35:55.943 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:56.723 [4724:1564]: : operation 13 progress 26 2011-12-31 14:35:56.723 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:57.487 [4724:1564]: : operation 13 progress 27 2011-12-31 14:35:57.487 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:58.517 [4724:1564]: : operation 13 progress 28 2011-12-31 14:35:58.517 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:35:59.593 [4724:1564]: : operation 13 progress 29 2011-12-31 14:35:59.593 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:00.919 [4724:1564]: : operation 13 progress 30 2011-12-31 14:36:00.919 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:02.058 [4724:1564]: : operation 13 progress 31 2011-12-31 14:36:02.058 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:02.916 [4724:1564]: : operation 13 progress 32 2011-12-31 14:36:02.916 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:03.712 [4724:1564]: : operation 13 progress 33 2011-12-31 14:36:03.712 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:04.523 [4724:1564]: : operation 13 progress 34 2011-12-31 14:36:04.523 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:05.365 [4724:1564]: : operation 13 progress 35 2011-12-31 14:36:05.365 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:06.223 [4724:1564]: : operation 13 progress 36 2011-12-31 14:36:06.223 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:07.128 [4724:1564]: : operation 13 progress 37 2011-12-31 14:36:07.128 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:08.048 [4724:1564]: : operation 13 progress 38 2011-12-31 14:36:08.048 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:08.938 [4724:1564]: : operation 13 progress 39 2011-12-31 14:36:08.938 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:10.108 [4724:1564]: : operation 13 progress 40 2011-12-31 14:36:10.108 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:11.371 [4724:1564]: : operation 13 progress 41 2011-12-31 14:36:11.371 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:12.650 [4724:1564]: : operation 13 progress 42 2011-12-31 14:36:12.650 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:13.540 [4724:1564]: : operation 13 progress 43 2011-12-31 14:36:13.540 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:14.444 [4724:1564]: : operation 13 progress 44 2011-12-31 14:36:14.444 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:15.318 [4724:1564]: : operation 13 progress 45 2011-12-31 14:36:15.318 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:16.098 [4724:1564]: : operation 13 progress 46 2011-12-31 14:36:16.098 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:17.206 [4724:1564]: : operation 13 progress 47 2011-12-31 14:36:17.206 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:18.173 [4724:1564]: : operation 13 progress 48 2011-12-31 14:36:18.173 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:19.031 [4724:1564]: : operation 13 progress 49 2011-12-31 14:36:19.031 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:20.482 [4724:1564]: : operation 13 progress 50 2011-12-31 14:36:20.482 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:21.777 [4724:1564]: : operation 13 progress 51 2011-12-31 14:36:21.777 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:22.759 [4724:1564]: : operation 13 progress 52 2011-12-31 14:36:22.759 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:23.836 [4724:1564]: : operation 13 progress 53 2011-12-31 14:36:23.836 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:24.647 [4724:1564]: : operation 13 progress 54 2011-12-31 14:36:24.647 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:25.396 [4724:1564]: : operation 13 progress 55 2011-12-31 14:36:25.396 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:26.160 [4724:1564]: : operation 13 progress 56 2011-12-31 14:36:26.160 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:27.205 [4724:1564]: : operation 13 progress 57 2011-12-31 14:36:27.205 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:28.126 [4724:1564]: : operation 13 progress 58 2011-12-31 14:36:28.126 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:29.296 [4724:1564]: : operation 13 progress 59 2011-12-31 14:36:29.296 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:29.998 [4724:1564]: : operation 13 progress 60 2011-12-31 14:36:29.998 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:31.183 [4724:1564]: : operation 13 progress 61 2011-12-31 14:36:31.183 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:32.260 [4724:1564]: : operation 13 progress 62 2011-12-31 14:36:32.260 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:33.071 [4724:1564]: : operation 13 progress 63 2011-12-31 14:36:33.071 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:33.835 [4724:1564]: : operation 13 progress 64 2011-12-31 14:36:33.835 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:34.600 [4724:1564]: : operation 13 progress 65 2011-12-31 14:36:34.600 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:35.427 [4724:1564]: : operation 13 progress 66 2011-12-31 14:36:35.427 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:36.160 [4724:1564]: : operation 13 progress 67 2011-12-31 14:36:36.160 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:37.143 [4724:1564]: : operation 13 progress 68 2011-12-31 14:36:37.143 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:37.860 [4724:1564]: : operation 13 progress 69 2011-12-31 14:36:37.860 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:38.749 [4724:1564]: : operation 13 progress 70 2011-12-31 14:36:38.749 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:39.498 [4724:1564]: : operation 13 progress 71 2011-12-31 14:36:39.498 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:40.575 [4724:1564]: : operation 13 progress 72 2011-12-31 14:36:40.575 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:41.526 [4724:1564]: : operation 13 progress 73 2011-12-31 14:36:41.526 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:42.337 [4724:1564]: : operation 13 progress 74 2011-12-31 14:36:42.337 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:43.336 [4724:1564]: : operation 13 progress 75 2011-12-31 14:36:43.336 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:44.272 [4724:1564]: : operation 13 progress 76 2011-12-31 14:36:44.272 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:45.223 [4724:1564]: : operation 13 progress 77 2011-12-31 14:36:45.223 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:46.315 [4724:1564]: : operation 13 progress 78 2011-12-31 14:36:46.315 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:48.125 [4724:1564]: : operation 13 progress 79 2011-12-31 14:36:48.125 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:49.638 [4724:1564]: : operation 13 progress 80 2011-12-31 14:36:49.638 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:50.933 [4724:1564]: : operation 13 progress 81 2011-12-31 14:36:50.933 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:51.900 [4724:1564]: : operation 13 progress 82 2011-12-31 14:36:51.900 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:52.930 [4724:1564]: : operation 13 progress 83 2011-12-31 14:36:52.930 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:53.663 [4724:1564]: : operation 13 progress 84 2011-12-31 14:36:53.663 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:54.833 [4724:1564]: : operation 13 progress 85 2011-12-31 14:36:54.833 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:55.535 [4724:1564]: : operation 13 progress 86 2011-12-31 14:36:55.535 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:56.955 [4724:1564]: : operation 13 progress 87 2011-12-31 14:36:56.955 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:58.031 [4724:1564]: : operation 13 progress 88 2011-12-31 14:36:58.031 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:36:59.388 [4724:1564]: : operation 13 progress 89 2011-12-31 14:36:59.388 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:37:00.621 [4724:1564]: : operation 13 progress 90 2011-12-31 14:37:00.621 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:37:01.993 [4724:1564]: : operation 13 progress 91 2011-12-31 14:37:01.993 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:37:03.319 [4724:1564]: : operation 13 progress 92 2011-12-31 14:37:03.319 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:37:04.786 [4724:1564]: : operation 13 progress 93 2011-12-31 14:37:04.786 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:37:06.159 [4724:1564]: : operation 13 progress 94 2011-12-31 14:37:06.159 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:37:07.578 [4724:1564]: : operation 13 progress 95 2011-12-31 14:37:07.578 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:37:09.107 [4724:1564]: : operation 13 progress 96 2011-12-31 14:37:09.107 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:37:10.761 [4724:1564]: : operation 13 progress 97 2011-12-31 14:37:10.761 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:37:12.289 [4724:1564]: : operation 13 progress 98 2011-12-31 14:37:12.289 [4724:1564]: ASR progress: Sending payload 2011-12-31 14:37:14.193 [4724:1564]: : operation 13 progress 100 2011-12-31 14:37:14.193 [4724:1564]: ASR progress: Finished 2011-12-31 14:37:15.409 [4724:1564]: : operation 14 progress 2 2011-12-31 14:37:15.971 [4724:1564]: : operation 14 progress 4 2011-12-31 14:37:16.533 [4724:1564]: : operation 14 progress 6 2011-12-31 14:37:17.094 [4724:1564]: : operation 14 progress 8 2011-12-31 14:37:17.656 [4724:1564]: : operation 14 progress 10 2011-12-31 14:37:18.217 [4724:1564]: : operation 14 progress 12 2011-12-31 14:37:18.779 [4724:1564]: : operation 14 progress 14 2011-12-31 14:37:19.341 [4724:1564]: : operation 14 progress 16 2011-12-31 14:37:19.902 [4724:1564]: : operation 14 progress 18 2011-12-31 14:37:20.464 [4724:1564]: : operation 14 progress 20 2011-12-31 14:37:21.025 [4724:1564]: : operation 14 progress 22 2011-12-31 14:37:21.587 [4724:1564]: : operation 14 progress 24 2011-12-31 14:37:22.133 [4724:1564]: : operation 14 progress 26 2011-12-31 14:37:22.695 [4724:1564]: : operation 14 progress 28 2011-12-31 14:37:23.256 [4724:1564]: : operation 14 progress 30 2011-12-31 14:37:23.818 [4724:1564]: : operation 14 progress 32 2011-12-31 14:37:24.379 [4724:1564]: : operation 14 progress 34 2011-12-31 14:37:24.941 [4724:1564]: : operation 14 progress 36 2011-12-31 14:37:25.503 [4724:1564]: : operation 14 progress 38 2011-12-31 14:37:26.064 [4724:1564]: : operation 14 progress 40 2011-12-31 14:37:26.626 [4724:1564]: : operation 14 progress 42 2011-12-31 14:37:27.187 [4724:1564]: : operation 14 progress 44 2011-12-31 14:37:27.749 [4724:1564]: : operation 14 progress 46 2011-12-31 14:37:28.311 [4724:1564]: : operation 14 progress 48 2011-12-31 14:37:28.857 [4724:1564]: : operation 14 progress 50 2011-12-31 14:37:29.418 [4724:1564]: : operation 14 progress 52 2011-12-31 14:37:29.917 [4724:1564]: : operation 14 progress 54 2011-12-31 14:37:30.479 [4724:1564]: : operation 14 progress 56 2011-12-31 14:37:31.041 [4724:1564]: : operation 14 progress 58 2011-12-31 14:37:31.602 [4724:1564]: : operation 14 progress 60 2011-12-31 14:37:32.164 [4724:1564]: : operation 14 progress 62 2011-12-31 14:37:32.725 [4724:1564]: : operation 14 progress 64 2011-12-31 14:37:33.287 [4724:1564]: : operation 14 progress 66 2011-12-31 14:37:33.849 [4724:1564]: : operation 14 progress 68 2011-12-31 14:37:34.410 [4724:1564]: : operation 14 progress 70 2011-12-31 14:37:34.972 [4724:1564]: : operation 14 progress 72 2011-12-31 14:37:35.533 [4724:1564]: : operation 14 progress 74 2011-12-31 14:37:36.079 [4724:1564]: : operation 14 progress 76 2011-12-31 14:37:36.641 [4724:1564]: : operation 14 progress 78 2011-12-31 14:37:37.203 [4724:1564]: : operation 14 progress 80 2011-12-31 14:37:37.764 [4724:1564]: : operation 14 progress 82 2011-12-31 14:37:38.326 [4724:1564]: : operation 14 progress 84 2011-12-31 14:37:38.887 [4724:1564]: : operation 14 progress 86 2011-12-31 14:37:39.449 [4724:1564]: : operation 14 progress 88 2011-12-31 14:37:40.011 [4724:1564]: : operation 14 progress 90 2011-12-31 14:37:40.557 [4724:1564]: : operation 14 progress 92 2011-12-31 14:37:41.118 [4724:1564]: : operation 14 progress 94 2011-12-31 14:37:41.680 [4724:1564]: : operation 14 progress 96 2011-12-31 14:37:42.241 [4724:1564]: : operation 14 progress 98 2011-12-31 14:37:42.756 [4724:1564]: : operation 14 progress 100 2011-12-31 14:37:42.850 [4724:1564]: : operation 15 progress -1 2011-12-31 14:38:01.273 [4724:1564]: : operation 16 progress -1 2011-12-31 14:38:01.351 [4724:1564]: : operation 15 progress -1 2011-12-31 14:38:07.201 [4724:1564]: : operation 16 progress -1 2011-12-31 14:38:07.420 [4724:1564]: : operation 27 progress -1 2011-12-31 14:38:08.995 [4724:1564]: : operation 27 progress -1 2011-12-31 14:38:09.120 [4724:1564]: : operation 17 progress -1 2011-12-31 14:38:09.791 [4724:1564]: : operation 25 progress -1 2011-12-31 14:38:09.947 [4724:1564]: : operation 35 progress -1 2011-12-31 14:38:10.353 [4724:1564]: : operation 18 progress 0 2011-12-31 14:38:13.208 [4724:1564]: : operation 18 progress 15 2011-12-31 14:38:13.240 [4724:1564]: : operation 18 progress 23 2011-12-31 14:38:13.255 [4724:1564]: : operation 18 progress 30 2011-12-31 14:38:13.271 [4724:1564]: : operation 18 progress 38 2011-12-31 14:38:13.302 [4724:1564]: : operation 18 progress 46 2011-12-31 14:38:13.318 [4724:1564]: : operation 18 progress 53 2011-12-31 14:38:13.333 [4724:1564]: : operation 18 progress 61 2011-12-31 14:38:13.349 [4724:1564]: : operation 18 progress 69 2011-12-31 14:38:13.364 [4724:1564]: : operation 18 progress 76 2011-12-31 14:38:13.380 [4724:1564]: : operation 18 progress 84 2011-12-31 14:38:13.411 [4724:1564]: : operation 18 progress 92 2011-12-31 14:38:13.427 [4724:1564]: : operation 18 progress 100 2011-12-31 14:38:23.536 [4724:1564]: : operation 46 progress -1 2011-12-31 14:38:23.536 [4724:1564]: : operation 46 progress -1 2011-12-31 14:38:23.551 [4724:1564]: : operation 19 progress -1 2011-12-31 14:38:29.713 [4724:1564]: amai: AMAuthInstallBasebandSetParametersWithUpdaterOutput: manifest dict not found 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBasebandCheckForParameterChange: bbParameters is now non-NULL 2011-12-31 14:38:29.713 [4724:1564]: requested restore behavior: Erase 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleSetBasebandKeyHashes: setting (from build manifest): BbProvisioningManifestKeyHash = {length = 20, capacity = 20, bytes = 0x97ab34ac0693f5b03bfb726d4e2c254f14e270a1} 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreLogo" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreDeviceTree" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreKernelCache" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreRamDisk" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBEC" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBSS" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "KernelCache" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryLow1" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryLow0" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "LLB" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBoot" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "DeviceTree" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging1" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "AppleLogo" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryPlugin" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryFull" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging0" has been previously personalized; skipping it 2011-12-31 14:38:29.713 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RecoveryMode" has been previously personalized; skipping it 2011-12-31 14:38:29.729 [4724:1564]: amai: AMAuthInstallDebugWriteObject: debug object written: C:\Users\LEVEL7~1\AppData\Local\Temp\PerDAD0.tmp\amai\debug\tss-request.plist 2011-12-31 14:38:29.947 [4724:1564]: amai: tss_submit_job: HttpQueryInfo returned 200 2011-12-31 14:38:29.947 [4724:1564]: amai: AMAuthInstallRequestSendSync: received tss response (server version: 2.0.0) 2011-12-31 14:38:29.947 [4724:1564]: amai: AMAuthInstallDebugWriteObject: debug object written: C:\Users\LEVEL7~1\AppData\Local\Temp\PerDAD0.tmp\amai\debug\tss-response.plist 2011-12-31 14:38:29.947 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "RestoreLogo" has been previously personalized; skipping it 2011-12-31 14:38:29.947 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "RestoreDeviceTree" has been previously personalized; skipping it 2011-12-31 14:38:29.947 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "RestoreKernelCache" has been previously personalized; skipping it 2011-12-31 14:38:29.947 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "RestoreRamDisk" has been previously personalized; skipping it 2011-12-31 14:38:29.947 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "iBEC" has been previously personalized; skipping it 2011-12-31 14:38:29.947 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "iBSS" has been previously personalized; skipping it 2011-12-31 14:38:29.947 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "KernelCache" has been previously personalized; skipping it 2011-12-31 14:38:29.947 [4724:1564]: amai: AMAuthInstallBasebandStitchFirmwareFromBbfw: stitch function returned NULL for "ebl.fls"; continuing anyway 2011-12-31 14:38:29.963 [4724:1564]: amai: AMAuthInstallBasebandStitchFirmwareFromBbfw: stitch function returned NULL for "psi_flash.fls"; continuing anyway 2011-12-31 14:38:30.072 [4724:1564]: amai: AMAuthInstallBasebandStitchFirmwareFromBbfw: stitch function returned NULL for "stack.fls"; continuing anyway 2011-12-31 14:38:30.072 [4724:1564]: amai: BbfwIteratorFindAndCopyFileData: failed to find bbfw item "world.fls": File error 2011-12-31 14:38:30.088 [4724:1564]: amai: BbfwWriterAddFile: added bbfw file: psi_ram.fls 2011-12-31 14:38:30.088 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryLow1" has been previously personalized; skipping it 2011-12-31 14:38:30.088 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryLow0" has been previously personalized; skipping it 2011-12-31 14:38:30.088 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "LLB" has been previously personalized; skipping it 2011-12-31 14:38:30.088 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "iBoot" has been previously personalized; skipping it 2011-12-31 14:38:30.088 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "DeviceTree" has been previously personalized; skipping it 2011-12-31 14:38:30.088 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryCharging1" has been previously personalized; skipping it 2011-12-31 14:38:30.088 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryCharging" has been previously personalized; skipping it 2011-12-31 14:38:30.088 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "AppleLogo" has been previously personalized; skipping it 2011-12-31 14:38:30.088 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryPlugin" has been previously personalized; skipping it 2011-12-31 14:38:30.088 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryFull" has been previously personalized; skipping it 2011-12-31 14:38:30.088 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryCharging0" has been previously personalized; skipping it 2011-12-31 14:38:30.088 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "RecoveryMode" has been previously personalized; skipping it 2011-12-31 14:38:43.675 [4724:1564]: amai: AMAuthInstallBasebandHandleUpdaterStatus: commandAccepted: YES 2011-12-31 14:38:43.675 [4724:1564]: amai: AMAuthInstallBasebandSetParametersWithUpdaterOutput: manifest dict not found 2011-12-31 14:38:43.675 [4724:1564]: amai: _AMAuthInstallBasebandCheckForParameterChange: nonce changed 2011-12-31 14:38:43.675 [4724:1564]: amai: AMAuthInstallBasebandSetParametersWithUpdaterOutput: bbParameters changed, triggering bbfw re-personalization 2011-12-31 14:38:43.675 [4724:1564]: requested restore behavior: Erase 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleSetBasebandKeyHashes: setting (from build manifest): BbProvisioningManifestKeyHash = {length = 20, capacity = 20, bytes = 0x97ab34ac0693f5b03bfb726d4e2c254f14e270a1} 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleRemovePersonalizedBbfw: removing personalizedEntry amai/boot.bbfw 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreLogo" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreDeviceTree" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreKernelCache" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RestoreRamDisk" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBEC" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBSS" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "KernelCache" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryLow1" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryLow0" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "LLB" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "iBoot" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "DeviceTree" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging1" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "AppleLogo" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryPlugin" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryFull" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "BatteryCharging0" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: entry "RecoveryMode" has been previously personalized; skipping it 2011-12-31 14:38:43.691 [4724:1564]: amai: AMAuthInstallDebugWriteObject: debug object written: C:\Users\LEVEL7~1\AppData\Local\Temp\PerDAD0.tmp\amai\debug\tss-request.plist 2011-12-31 14:38:43.785 [4724:1564]: amai: tss_submit_job: HttpQueryInfo returned 200 2011-12-31 14:38:43.831 [4724:1564]: amai: AMAuthInstallRequestSendSync: received tss response (server version: 2.0.0) 2011-12-31 14:38:43.831 [4724:1564]: amai: AMAuthInstallDebugWriteObject: debug object written: C:\Users\LEVEL7~1\AppData\Local\Temp\PerDAD0.tmp\amai\debug\tss-response.plist 2011-12-31 14:38:43.831 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "RestoreLogo" has been previously personalized; skipping it 2011-12-31 14:38:43.831 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "RestoreDeviceTree" has been previously personalized; skipping it 2011-12-31 14:38:43.831 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "RestoreKernelCache" has been previously personalized; skipping it 2011-12-31 14:38:43.831 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "RestoreRamDisk" has been previously personalized; skipping it 2011-12-31 14:38:43.831 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "iBEC" has been previously personalized; skipping it 2011-12-31 14:38:43.831 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "iBSS" has been previously personalized; skipping it 2011-12-31 14:38:43.831 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "KernelCache" has been previously personalized; skipping it 2011-12-31 14:38:43.941 [4724:1564]: amai: BbfwIteratorFindAndCopyFileData: failed to find bbfw item "world.fls": File error 2011-12-31 14:38:43.956 [4724:1564]: amai: BbfwWriterAddFile: added bbfw file: psi_ram.fls 2011-12-31 14:38:45.033 [4724:1564]: amai: BbfwWriterAddFile: added bbfw file: stack.fls 2011-12-31 14:38:45.033 [4724:1564]: amai: BbfwWriterAddFile: added bbfw file: psi_flash.fls 2011-12-31 14:38:45.048 [4724:1564]: amai: BbfwWriterAddFile: added bbfw file: ebl.fls 2011-12-31 14:38:45.048 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryLow1" has been previously personalized; skipping it 2011-12-31 14:38:45.048 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryLow0" has been previously personalized; skipping it 2011-12-31 14:38:45.048 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "LLB" has been previously personalized; skipping it 2011-12-31 14:38:45.048 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "iBoot" has been previously personalized; skipping it 2011-12-31 14:38:45.048 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "DeviceTree" has been previously personalized; skipping it 2011-12-31 14:38:45.048 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryCharging1" has been previously personalized; skipping it 2011-12-31 14:38:45.048 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryCharging" has been previously personalized; skipping it 2011-12-31 14:38:45.048 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "AppleLogo" has been previously personalized; skipping it 2011-12-31 14:38:45.048 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryPlugin" has been previously personalized; skipping it 2011-12-31 14:38:45.048 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryFull" has been previously personalized; skipping it 2011-12-31 14:38:45.048 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "BatteryCharging0" has been previously personalized; skipping it 2011-12-31 14:38:45.048 [4724:1564]: amai: _AMAuthInstallBundleInstallPersonalizedEntry: entry "RecoveryMode" has been previously personalized; skipping it 2011-12-31 14:39:18.073 [4724:1564]: : operation 19 progress 1 2011-12-31 14:39:19.899 [4724:1564]: : operation 19 progress 2 2011-12-31 14:39:21.739 [4724:1564]: : operation 19 progress 3 2011-12-31 14:39:23.580 [4724:1564]: : operation 19 progress 4 2011-12-31 14:39:25.406 [4724:1564]: : operation 19 progress 5 2011-12-31 14:39:27.248 [4724:1564]: : operation 19 progress 6 2011-12-31 14:39:29.105 [4724:1564]: : operation 19 progress 7 2011-12-31 14:39:30.945 [4724:1564]: : operation 19 progress 8 2011-12-31 14:39:32.771 [4724:1564]: : operation 19 progress 9 2011-12-31 14:39:34.627 [4724:1564]: : operation 19 progress 10 2011-12-31 14:39:36.452 [4724:1564]: : operation 19 progress 11 2011-12-31 14:39:38.294 [4724:1564]: : operation 19 progress 12 2011-12-31 14:39:40.150 [4724:1564]: : operation 19 progress 13 2011-12-31 14:39:41.991 [4724:1564]: : operation 19 progress 14 2011-12-31 14:39:43.816 [4724:1564]: : operation 19 progress 15 2011-12-31 14:39:45.673 [4724:1564]: : operation 19 progress 16 2011-12-31 14:39:47.514 [4724:1564]: : operation 19 progress 17 2011-12-31 14:39:49.339 [4724:1564]: : operation 19 progress 18 2011-12-31 14:39:51.195 [4724:1564]: : operation 19 progress 19 2011-12-31 14:39:53.036 [4724:1564]: : operation 19 progress 20 2011-12-31 14:39:54.861 [4724:1564]: : operation 19 progress 21 2011-12-31 14:39:56.703 [4724:1564]: : operation 19 progress 22 2011-12-31 14:39:58.559 [4724:1564]: : operation 19 progress 23 2011-12-31 14:40:00.400 [4724:1564]: : operation 19 progress 24 2011-12-31 14:40:02.225 [4724:1564]: : operation 19 progress 25 2011-12-31 14:40:04.082 [4724:1564]: : operation 19 progress 26 2011-12-31 14:40:05.923 [4724:1564]: : operation 19 progress 27 2011-12-31 14:40:07.748 [4724:1564]: : operation 19 progress 28 2011-12-31 14:40:09.620 [4724:1564]: : operation 19 progress 29 2011-12-31 14:40:11.445 [4724:1564]: : operation 19 progress 30 2011-12-31 14:40:13.286 [4724:1564]: : operation 19 progress 31 2011-12-31 14:40:15.142 [4724:1564]: : operation 19 progress 32 2011-12-31 14:40:16.968 [4724:1564]: : operation 19 progress 33 2011-12-31 14:40:18.808 [4724:1564]: : operation 19 progress 34 2011-12-31 14:40:20.665 [4724:1564]: : operation 19 progress 35 2011-12-31 14:40:22.506 [4724:1564]: : operation 19 progress 36 2011-12-31 14:40:24.331 [4724:1564]: : operation 19 progress 37 2011-12-31 14:40:26.203 [4724:1564]: : operation 19 progress 38 2011-12-31 14:40:28.028 [4724:1564]: : operation 19 progress 39 2011-12-31 14:40:29.869 [4724:1564]: : operation 19 progress 40 2011-12-31 14:40:31.710 [4724:1564]: : operation 19 progress 41 2011-12-31 14:40:33.566 [4724:1564]: : operation 19 progress 42 2011-12-31 14:40:35.407 [4724:1564]: : operation 19 progress 43 2011-12-31 14:40:37.248 [4724:1564]: : operation 19 progress 44 2011-12-31 14:40:39.104 [4724:1564]: : operation 19 progress 45 2011-12-31 14:40:40.945 [4724:1564]: : operation 19 progress 46 2011-12-31 14:40:42.786 [4724:1564]: : operation 19 progress 47 2011-12-31 14:40:44.642 [4724:1564]: : operation 19 progress 48 2011-12-31 14:40:46.467 [4724:1564]: : operation 19 progress 49 2011-12-31 14:40:48.308 [4724:1564]: : operation 19 progress 50 2011-12-31 14:40:53.269 [4724:1564]: : operation 19 progress 53 2011-12-31 14:40:53.300 [4724:1564]: : operation 19 progress 56 2011-12-31 14:40:53.331 [4724:1564]: : operation 19 progress 59 2011-12-31 14:40:53.362 [4724:1564]: : operation 19 progress 62 2011-12-31 14:40:53.378 [4724:1564]: : operation 19 progress 65 2011-12-31 14:40:53.409 [4724:1564]: : operation 19 progress 68 2011-12-31 14:40:53.440 [4724:1564]: : operation 19 progress 71 2011-12-31 14:40:53.472 [4724:1564]: : operation 19 progress 75 2011-12-31 14:40:53.487 [4724:1564]: : operation 19 progress 78 2011-12-31 14:40:53.518 [4724:1564]: : operation 19 progress 81 2011-12-31 14:40:53.550 [4724:1564]: : operation 19 progress 84 2011-12-31 14:40:53.565 [4724:1564]: : operation 19 progress 87 2011-12-31 14:40:53.596 [4724:1564]: : operation 19 progress 90 2011-12-31 14:40:53.628 [4724:1564]: : operation 19 progress 93 2011-12-31 14:40:53.659 [4724:1564]: : operation 19 progress 96 2011-12-31 14:40:53.674 [4724:1564]: : operation 19 progress 100 2011-12-31 14:42:07.023 [4724:1564]: amai: AMAuthInstallBasebandHandleUpdaterStatus: commandAccepted: NO 2011-12-31 14:42:07.039 [4724:1564]: device returned AMR error -1 2011-12-31 14:42:07.148 [4724:1564]: ==== device restore output ==== ; instructions at AppleBCMWLANBusInterfaceSdio::start() WARNING AppleBCMWLANBusInterfaceSdio::start(): Started by: IOSDIOIoCardDevice, AppleBCMWLANV2-83.11 Nov 2 2011 20:13:29 AppleBCMWLANProvisioningManager::validateData(): Error, unable to obtain MAC address, can't proceed any further AppleBCMWLANProvisioningManager::processProvisionedData(): Invalid provisioning data, general error AppleBCMWLANBusInterfaceSdio::start(): Failed to process provisioning data: general error read new style signature 0x43313132 (line:389) [FTL:MSG] VSVFL Register [OK] [WMR:MSG] Metadata whitening is set in NAND signature [FTL:MSG] VFL Init [OK] display-scale = 2 display-rotation = 0 found suitable IOMobileFramebuffer: AppleCLCD display: 640 x 960 found PTP interface [FTL:MSG] VFL_Open [OK] [FTL:MSG] YAFTL Register [OK] AppleSynopsysOTGDevice - Configuration: PTP AppleSynopsysOTGDevice Interface: PTP AppleSynopsysOTGDevice - Configuration: iPod USB Interface AppleSynopsysOTGDevice Interface: USBAudioControl AppleSynopsysOTGDevice Interface: USBAudioStreaming AppleSynopsysOTGDevice Interface: IapOverUsbHid AppleSynopsysOTGDevice - Configuration: PTP + Apple Mobile Device AppleSynopsysOTGDevice Interface: PTP AppleSynopsysOTGDevice Interface: AppleUSBMux AppleSynopsysOTGDevice - Configuration: PTP + Apple Mobile Device + Apple USB Ethernet AppleSynopsysOTGDevice Interface: PTP AppleSynopsysOTGDevice Interface: AppleUSBMux AppleSynopsysOTGDevice Interface: AppleUSBEthernet AppleSynopsysOTGDevice::gated_registerFunction Register function USBAudioControl AppleSynopsysOTGDevice::gated_registerFunction Register function USBAudioStreaming IOAccessoryPortUSB::start AppleSynopsysOTGDevice::gated_registerFunction Register function IapOverUsbHid virtual bool AppleUSBDeviceMux::start(IOService*) build: Nov 2 2011 20:10:31 init_waste AppleSynopsysOTGDevice::gated_registerFunction Register function AppleUSBMux AppleSynopsysOTGDevice::gated_registerFunction Register function AppleUSBEthernet AppleUSBEthernetDevice::start: Host MAC address = 02:00:00:00:00:00 AppleSynopsysOTGDevice::gated_registerFunction Register function PTP AppleSynopsysOTGDevice::gated_registerFunction all functions registered- we are ready to start usb stack IOReturn AppleUSBDeviceMux::setPropertiesGated(OSObject*) setting debug level to 7 AppleSynopsysOTGDevice::handleUSBReset recv(10, 4) failed: connection closed unable to read message size: -1 could not receive message recv(10, 4) failed: connection closed unable to read message size: -1 could not receive message recv(10, 4) failed: connection closed unable to read message size: -1 could not receive message recv(8, 4) failed: connection closed unable to read message size: -1 could not receive message recv(8, 4) failed: connection closed unable to read message size: -1 could not receive message recv(8, 4) failed: connection closed unable to read message size: -1 could not receive message recv(8, 4) failed: connection closed unable to read message size: -1 could not receive message client protocol version 12 *** UUID 3EDB17E7-510D-E947-9F2F-4466AC0074BF *** Restore options: UUID => {contents = "3EDB17E7-510D-E947-9F2F-4466AC0074BF"} MinimumSystemPartition => {value = +996, type = kCFNumberSInt64Type} SystemPartitionSize => {value = +996, type = kCFNumberSInt64Type} SystemPartitionPadding => {type = mutable dict, count = 3, entries => 2 : {contents = "16"} = {value = +160, type = kCFNumberSInt64Type} 3 : {contents = "8"} = {value = +80, type = kCFNumberSInt64Type} 4 : {contents = "32"} = {value = +320, type = kCFNumberSInt64Type} } entering partition_nand_device device supports boot-from-NAND nand device is already partitioned entering wait_for_storage_device entering format_effaceable_storage effaceable storage is formatted, clearing it AppleSynopsysOTGDevice::handleUSBReset [FTL:MSG] FTL_Open [OK] AppleDiagnosticDataAccess started: 0x8481e370 AppleDiagnosticDataAccess started with AppleNANDConfigAccess AppleUSBDeviceMux::handleConnectResult new session 0x8481e268 established 62078<-lo0->49152 62078<-usb->36352 void AppleUSBDeviceMux::handleMuxTCPInput(__mbuf*) received reset, closing 0x8481e268 AppleUSBDeviceMux::handleConnectResult new session 0x8481e268 established 62078<-lo0->49153 62078<-usb->36608 void AppleUSBDeviceMux::handleMuxTCPInput(__mbuf*) received reset, closing 0x8481e268 AppleUSBDeviceMux::handleConnectResult new session 0x8481e268 established 62078<-lo0->49154 62078<-usb->36864 void AppleUSBDeviceMux::handleMuxTCPInput(__mbuf*) received reset, closing 0x8481e268 AppleUSBDeviceMux::handleConnectResult new session 0x8481e268 established 62078<-lo0->49155 62078<-usb->37120 [effaceable:INIT] found current generation, 28, in group 3 [effaceable:INIT] started [effaceable:INIT] started AppleUSBDeviceMux::handleConnectResult new session 0x8481e000 established 62078<-lo0->49156 62078<-usb->37376 LwVM::_partitionsFromConfig - loaded configuration for 2 partitions void AppleUSBDeviceMux::handleMuxTCPInput(__mbuf*) received reset, closing 0x8481e000 AppleUSBDeviceMux::handleConnectResult new session 0x8481e000 established 62078<-lo0->49157 62078<-usb->37632 void AppleUSBDeviceMux::handleMuxTCPInput(__mbuf*) received reset, closing 0x8481e000 AppleUSBDeviceMux::handleConnectResult new session 0x8481e000 established 62078<-lo0->49158 62078<-usb->37888 void AppleUSBDeviceMux::handleMuxTCPInput(__mbuf*) received reset, closing 0x8481e000 AppleUSBDeviceMux::handleConnectResult new session 0x8481e000 established 62078<-lo0->49159 62078<-usb->38144 void AppleUSBDeviceMux::handleMuxTCPInput(__mbuf*) received reset, closing 0x8481e000 effaceable storaged cleared entering ramrod_probe_media device partitioning scheme is GPT find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 entering check_for_restore_log executing /sbin/fsck_hfs -fy /dev/disk0s1s1 ** /dev/rdisk0s1s1 Executing fsck_hfs (version diskdev_cmds-547~162). ** Checking Journaled HFS Plus volume. ** Detected a case-sensitive volume. The volume name is Telluride9A334.N90OS ** Checking extents overflow file. ** Checking catalog file. ** Checking multi-linked files. ** Checking catalog hierarchy. ** Checking extended attributes file. ** Checking volume bitmap. ** Checking volume information. ** Trimming unused blocks. ** The volume Telluride9A334.N90OS appears to be OK. executing /sbin/mount -t hfs /dev/disk0s1s1 /mnt1 restore log does not exist entering clean_NAND NAND format complete entering ramrod_probe_media find_filesystem_partitions: storage=/dev/disk0 system= data= entering format_storage_for_LwVM entering ramrod_probe_media device partitioning scheme is GPT unexpected partition 'LwVM' - skipping find_filesystem_partitions: storage=/dev/disk0s1 system= data= entering create_filesystem_partitions system partition padding size is: 335544320 bytes unable to open : No such file or directory creating 2 partitions creating encrypted data partition entering ramrod_probe_media device partitioning scheme is GPT find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 wipe entire partition: 1 (old = 0 new = 1316) block size for /dev/disk0s1s1: 8192 /sbin/newfs_hfs -s -v System -J -b 8192 -n a=8192,c=8192,e=8192 /dev/disk0s1s1 executing /sbin/newfs_hfs -s -v System -J -b 8192 -n a=8192,c=8192,e=8192 /dev/disk0s1s1 LwVM::_partitionsFromConfig - loaded configuration for 1 partitions LwVM::_partitionsFromConfig - loaded configuration for 2 partitions Initialized /dev/rdisk0s1s1 as a 1 GB HFS Plus volume with a 8192k journal block size for /dev/disk0s1s2: 8192 /sbin/newfs_hfs -s -v Data -J -P -b 8192 -n a=8192,c=8192,e=8192 /dev/disk0s1s2 executing /sbin/newfs_hfs -s -v Data -J -P -b 8192 -n a=8192,c=8192,e=8192 /dev/disk0s1s2 Initialized /dev/rdisk0s1s2 as a 28 GB HFS Plus volume with a 8192k journal entering ramrod_probe_media device partitioning scheme is GPT find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 entering mount_filesystems executing /sbin/fsck_hfs -fy /dev/disk0s1s1 ** /dev/rdisk0s1s1 Executing fsck_hfs (version diskdev_cmds-547~162). ** Checking Journaled HFS Plus volume. ** Detected a case-sensitive volume. The volume name is System ** Checking extents overflow file. ** Checking catalog file. ** Checking multi-linked files. ** Checking catalog hierarchy. ** Checking extended attributes file. ** Checking volume bitmap. ** Checking volume information. ** Trimming unused blocks. ** The volume System appears to be OK. executing /sbin/mount -t hfs /dev/disk0s1s1 /mnt1 executing /sbin/fsck_hfs -fy /dev/disk0s1s2 ** /dev/rdisk0s1s2 Executing fsck_hfs (version diskdev_cmds-547~162). ** Checking Journaled HFS Plus volume. ** Detected a case-sensitive volume. The volume name is Data ** Checking extents overflow file. ** Checking catalog file. ** Checking multi-linked files. ** Checking catalog hierarchy. ** Checking extended attributes file. ** Checking volume bitmap. ** Checking volume information. ** Trimming unused blocks. ** The volume Data appears to be OK. executing /sbin/mount -t hfs /dev/disk0s1s2 /mnt2 entering resize_system_partition_to_options block size for /dev/disk0s1s1: 8192 /sbin/newfs_hfs -s -v System -J -b 8192 -n a=8192,c=8192,e=8192 /dev/disk0s1s1 executing /sbin/newfs_hfs -s -v System -J -b 8192 -n a=8192,c=8192,e=8192 /dev/disk0s1s1 Initialized /dev/rdisk0s1s1 as a 1 GB HFS Plus volume with a 8192k journal executing /sbin/mount -t hfs /dev/disk0s1s1 /mnt1 Found SystemPartitionPadding value of 335544320 bytes Trying to resize system partition to 1379926016 bytes aka 1316 MB entering adjust_partition partition: requiredSize=168448 no change in size Successfully resized the system partition entering maximize_data_partition entering adjust_partition partition: requiredSize=<<<<>>>>>8 <<<<>>>>>8 additional blocks needed maximizing data partition to 30597447679 bytes entering adjust_partition partition: requiredSize=3735040 no change in size Successfully resized data partition to consume free blocks The system partition now has a total HFS+ capacity of 1316 MB entering ramrod_probe_media device partitioning scheme is GPT find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 entering unmount_filesystems entering ramrod_ticket_update root-ticket-hash: {length = 20, capacity = 20, bytes = 0x<<<<<<<<<<<<<<<<<>>>>>>>>>>>>>>>>>} ticket_hash: <<<<<<<<<<<<<<<<<>>>>>>>>>>>>>>>>> received valid ticket (2767 bytes) entering restore_images Successfully marked device node /dev/rdisk0s1s1 as static data executing /usr/sbin/asr -source asr://localhost:12345 -target /dev/disk0s1s1 -erase -noprompt --chunkchecksum --puppetstrings ASR: asr: Unable to disable idle sleep - -536870199 ASR STATUS: start 241 multicast-client ASR: Waiting for connection attempt from server void AppleUSBDeviceMux::handleConnectResult(BulkUSBMuxSession*, errno_t) new session to port 12345 failed: 61 void AppleUSBDeviceMux::handleConnectResult(BulkUSBMuxSession*, errno_t) new session to port 12345 failed: 61 ASR STATUS: setup ASR: Validating target...done ASR STATUS: metadata ASR: Validating source... AppleUSBDeviceMux::handleConnectResult new session 0x84757d10 established 12345<-lo0->49162 12345<-usb->38912 ASR: done ASR: Retrieving scan information...done ASR: Validating sizes...done ASR STATUS: restore LwVM::_partitionsFromConfig - loaded configuration for 2 partitions ASR RESTORE PROGRESS: 2% ASR RESTORE PROGRESS: 4% ASR RESTORE PROGRESS: 6% ASR RESTORE PROGRESS: 8% ASR RESTORE PROGRESS: 10% ASR RESTORE PROGRESS: 12% ASR RESTORE PROGRESS: 14% ASR RESTORE PROGRESS: 16% ASR RESTORE PROGRESS: 18% ASR RESTORE PROGRESS: 20% ASR RESTORE PROGRESS: 22% ASR RESTORE PROGRESS: 24% ASR RESTORE PROGRESS: 26% ASR RESTORE PROGRESS: 28% ASR RESTORE PROGRESS: 30% ASR RESTORE PROGRESS: 32% ASR RESTORE PROGRESS: 34% ASR RESTORE PROGRESS: 36% ASR RESTORE PROGRESS: 38% ASR RESTORE PROGRESS: 40% ASR RESTORE PROGRESS: 42% ASR RESTORE PROGRESS: 44% ASR RESTORE PROGRESS: 46% ASR RESTORE PROGRESS: 48% ASR RESTORE PROGRESS: 50% ASR RESTORE PROGRESS: 52% ASR RESTORE PROGRESS: 54% ASR RESTORE PROGRESS: 56% ASR RESTORE PROGRESS: 58% ASR RESTORE PROGRESS: 60% ASR RESTORE PROGRESS: 62% ASR RESTORE PROGRESS: 64% ASR RESTORE PROGRESS: 66% ASR RESTORE PROGRESS: 68% ASR RESTORE PROGRESS: 70% ASR RESTORE PROGRESS: 72% ASR RESTORE PROGRESS: 74% ASR RESTORE PROGRESS: 76% ASR RESTORE PROGRESS: 78% ASR RESTORE PROGRESS: 80% ASR RESTORE PROGRESS: 82% ASR RESTORE PROGRESS: 84% ASR RESTORE PROGRESS: 86% ASR RESTORE PROGRESS: 88% ASR RESTORE PROGRESS: 90% ASR RESTORE PROGRESS: 92% ASR RESTORE PROGRESS: 94% ASR RESTORE PROGRESS: 96% ASR RESTORE PROGRESS: 98% ASR RESTORE PROGRESS: 100% ASR: Copied 1044660736 bytes in 98.52 seconds, 10354.63 KiB/s ASR STATUS: verify AppleUSBDeviceMux::sessionUpcall socket is closed, session 0x84757d10 (12345<-lo0->49162 12345<-usb->38912) ASR VERIFY PROGRESS: 2% ASR VERIFY PROGRESS: 4% ASR VERIFY PROGRESS: 6% ASR VERIFY PROGRESS: 8% ASR VERIFY PROGRESS: 10% ASR VERIFY PROGRESS: 12% ASR VERIFY PROGRESS: 14% ASR VERIFY PROGRESS: 16% ASR VERIFY PROGRESS: 18% ASR VERIFY PROGRESS: 20% ASR VERIFY PROGRESS: 22% ASR VERIFY PROGRESS: 24% ASR VERIFY PROGRESS: 26% ASR VERIFY PROGRESS: 28% ASR VERIFY PROGRESS: 30% ASR VERIFY PROGRESS: 32% ASR VERIFY PROGRESS: 34% ASR VERIFY PROGRESS: 36% ASR VERIFY PROGRESS: 38% ASR VERIFY PROGRESS: 40% ASR VERIFY PROGRESS: 42% ASR VERIFY PROGRESS: 44% ASR VERIFY PROGRESS: 46% ASR VERIFY PROGRESS: 48% ASR VERIFY PROGRESS: 50% ASR VERIFY PROGRESS: 52% ASR VERIFY PROGRESS: 54% ASR VERIFY PROGRESS: 56% ASR VERIFY PROGRESS: 58% ASR VERIFY PROGRESS: 60% ASR VERIFY PROGRESS: 62% ASR VERIFY PROGRESS: 64% ASR VERIFY PROGRESS: 66% ASR VERIFY PROGRESS: 68% ASR VERIFY PROGRESS: 70% ASR VERIFY PROGRESS: 72% ASR VERIFY PROGRESS: 74% ASR VERIFY PROGRESS: 76% ASR VERIFY PROGRESS: 78% ASR VERIFY PROGRESS: 80% ASR VERIFY PROGRESS: 82% ASR VERIFY PROGRESS: 84% ASR VERIFY PROGRESS: 86% ASR VERIFY PROGRESS: 88% ASR VERIFY PROGRESS: 90% ASR VERIFY PROGRESS: 92% ASR VERIFY PROGRESS: 94% ASR VERIFY PROGRESS: 96% ASR VERIFY PROGRESS: 98% ASR VERIFY PROGRESS: 100% ASR: Verified SHA-1 checksum 1044660736 bytes in 27.93 seconds, 36529.71 KiB/s ASR STATUS: finish entering ramrod_probe_media device partitioning scheme is GPT find_filesystem_partitions: storage=/dev/disk0s1 system=/dev/disk0s1s1 data=/dev/disk0s1s2 entering mount_filesystems executing /sbin/fsck_hfs -fy /dev/disk0s1s1 ** /dev/rdisk0s1s1 Executing fsck_hfs (version diskdev_cmds-547~162). ** Checking Journaled HFS Plus volume. ** Detected a case-sensitive volume. The volume name is Telluride9A405.N90OS ** Checking extents overflow file. ** Checking catalog file. ** Checking multi-linked files. ** Checking catalog hierarchy. ** Checking extended attributes file. ** Checking volume bitmap. ** Checking volume information. ** Trimming unused blocks. ** The volume Telluride9A405.N90OS appears to be OK. executing /sbin/mount -t hfs /dev/disk0s1s1 /mnt1 executing /sbin/fsck_hfs -fy /dev/disk0s1s2 ** /dev/rdisk0s1s2 Executing fsck_hfs (version diskdev_cmds-547~162). ** Checking Journaled HFS Plus volume. ** Detected a case-sensitive volume. The volume name is Data ** Checking extents overflow file. ** Checking catalog file. ** Checking multi-linked files. ** Checking catalog hierarchy. ** Checking extended attributes file. ** Checking volume bitmap. ** Checking volume information. ** Trimming unused blocks. ** The volume Data appears to be OK. executing /sbin/mount -t hfs /dev/disk0s1s2 /mnt2 entering install_kernel_cache writing kernelcache to /mnt1/System/Library/Caches/com.apple.kernelcaches/kernelcache entering install_secure_vault_daemon entering get_hardware_platform platform-name = s5l8930x linking /mnt1/usr/sbin/securekeyvaultd to /mnt1/usr/sbin/securekeyvaultd.s5l8930x no securekeyvaultd for s5l8930x entering fixup_var remove_path /mnt1/private/var executing /bin/rm -rf /mnt1/private/var creating directory (owner=0 mode=755) /mnt1/private/var entering modify_fstab entering ramrod_update_fstab HARD CODED FSTAB ENTRIES ARE BEING USED - 9925655 entering clear_persistent_boot_args executing /usr/sbin/nvram entering update_device_firmware create_baseband_update_options: Got imei svn: 13 baseband updater options = {type = mutable dict, count = 3, entries => 0 : {contents = "IMEISwVersion"} = {value = +13, type = kCFNumberSInt32Type} 1 : {contents = "autoSetupEnv"} = {value = false} 2 : {contents = "disablePing"} = {value = true} } _baseband_update_requires_system_filesystem_once: querying baseband to determine if it requires system filesystem _baseband_update_requires_system_filesystem_once: query returned {type = immutable dict, count = 1, entries => 0 : {contents = "requireSystemPartitionMount"} = {value = false} } entering update_iBoot write_image3_data: flashing LLB data (length = 0x1da19) write_image3_data: flashing NOR data (length = 0xb10) ticket updated write_image3_data: flashing NOR data (length = 0x3ba19) write_image3_data: flashing NOR data (length = 0xf259) write_image3_data: flashing NOR data (length = 0x3b59) write_image3_data: flashing NOR data (length = 0x29ad9) write_image3_data: flashing NOR data (length = 0x9b99) write_image3_data: flashing NOR data (length = 0x1cd9) write_image3_data: flashing NOR data (length = 0x2e59) write_image3_data: flashing NOR data (length = 0x9119) write_image3_data: flashing NOR data (length = 0x1b99) write_image3_data: flashing NOR data (length = 0x9fbd9) write_image3_data: flashing NOR data (length = 0x1fad9) AppleImage3NORAccess::_getSuperBlock imageVersion: 3 AppleImage3NORAccess::_getSuperBlock imageVersion: 3 entering update_gas_gauge entering update_ir_mcu looking for mcu nub...no entry from path! TiSerialFlasher not supported on this platform. Exiting. entering update_baseband update_baseband: registering for progress notifications create_baseband_update_options: Got imei svn: 13 baseband updater options = {type = mutable dict, count = 6, entries => 0 : {contents = "SystemRoot"} = {contents = "/mnt1"} 1 : {contents = "IMEISwVersion"} = {value = +13, type = kCFNumberSInt32Type} 2 : {contents = "autoSetupEnv"} = {value = false} 3 : {contents = "forceUpdate"} = {value = true} 5 : {contents = "disablePing"} = {value = true} 6 : {contents = "DisallowFusiing"} = {value = true} } update_baseband: querying baseband info bbupdater: Opening device path /dev/cu.debug, using baud 115200 bbupdater: Configuring Hardware Mux...bbupdater: OK bbupdater: [1325371103.816791 : 13.00]::: powercycle bbupdater: - Ping failed, trying again, 48 tries left bbupdater: - Ping failed, trying again, 47 tries left bbupdater: - Ping failed, trying again, 46 tries left bbupdater: - Ping failed, trying again, 45 tries left bbupdater: - Ping failed, trying again, 44 tries left bbupdater: - Ping OK bbupdater: [1325371109.687327 : 13.01]::: Polled 1 bbupdater: Opening device path /dev/cu.debug, using baud 115200 bbupdater: [1325371109.688309 : 13.01]::: Beginning boot bbupdater: ------------------------------------------------------------------------------- bbupdater: BEGINNING BOOT bbupdater: ------------------------------------------------------------------------------- bbupdater: Sending boot code...bbupdater: [1325371109.688692 : 13.00]::: powercycle bbupdater: - GoldCertID: 0x101 bbupdater: OK bbupdater: [1325371109.947477 : 13.01]::: Boot OK bbupdater: [1325371109.948165 : 02.00]::: ChipInfo: snum: 0x<<<<<<<>>>>>>B08C, chipid: 0x50, goldcertid: 0x101, nonce: 0x update_baseband: query returned {type = mutable dict, count = 5, entries => 0 : {contents = "done"} = {value = false} 2 : {contents = "ChipID"} = {value = +80, type = kCFNumberSInt32Type} 3 : {contents = "BcVersion"} = {value = +163, type = kCFNumberSInt32Type} 4 : {contents = "CertID"} = {value = +257, type = kCFNumberSInt32Type} 6 : {contents = "ChipSerialNo"} = {length = 12, capacity = 12, bytes = 0x<<<<<<<>>>>>>b08c} } update_baseband: requesting baseband firmware data update_baseband: received data from host (25711 bytes) update_baseband: performing next update stage bbupdater: Validating parameters...bbupdater: OK bbupdater: Validating parameters...bbupdater: OK bbupdater: Opening device path /dev/cu.debug, using baud 115200 bbupdater: Configuring Hardware Mux...bbupdater: OK bbupdater: [1325371110.359516 : 13.00]::: powercycle bbupdater: - Sleeping for 2.330 seconds bbupdater: - Ping failed, trying again, 48 tries left bbupdater: - Ping failed, trying again, 47 tries left bbupdater: - Ping failed, trying again, 46 tries left bbupdater: - Ping failed, trying again, 45 tries left bbupdater: - Ping OK bbupdater: [1325371117.621688 : 13.01]::: Polled 1 bbupdater: Opening device path /dev/cu.debug, using baud 115200 bbupdater: [1325371117.622884 : 13.01]::: Beginning boot bbupdater: ------------------------------------------------------------------------------- bbupdater: BEGINNING BOOT bbupdater: ------------------------------------------------------------------------------- bbupdater: Sending boot code...bbupdater: [1325371117.623178 : 13.00]::: powercycle bbupdater: - GoldCertID: 0x101 bbupdater: OK bbupdater: Reading Reference file...bbupdater: OK bbupdater: Sending EBL Loader...bbupdater: bbupdater: Sending EBL Loader Length...bbupdater: OK bbupdater: Sending EBL Loader Data...bbupdater: OK bbupdater: Sending EBL Loader Checksum...bbupdater: OK bbupdater: [1325371123.913313 : 02.00]::: Current Nonce from RAM PSI: 0x<<<<<<<<<<<<<<<<<>>>>>>>>>>>>>>>>> bbupdater: Sending EBL Loader -- All OK bbupdater: [1325371123.913556 : 13.01]::: Boot OK bbupdater: [1325371123.913757 : 02.00]::: ChipInfo: snum: 0x<<<<<<<>>>>>>B08C, chipid: 0x50, goldcertid: 0x101, nonce: 0x<<<<<<<<<<<<<<<<<>>>>>>>>>>>>>>>>> send_bbupdate_status: sending baseband update status to host update_baseband: querying baseband info bbupdater: [1325371123.917273 : 02.00]::: ChipInfo: snum: 0x<<<<<<<>>>>>>B08C, chipid: 0x50, goldcertid: 0x101, nonce: 0x<<<<<<<<<<<<<<<<<>>>>>>>>>>>>>>>>> update_baseband: query returned {type = mutable dict, count = 7, entries => 1 : {contents = "Nonce"} = {length = 20, capacity = 20, bytes = 0x<<<<<<<<<<<<<<<<<>>>>>>>>>>>>>>>>>} 6 : {contents = "BcVersion"} = {value = +163, type = kCFNumberSInt32Type} 7 : {contents = "CertID"} = {value = +257, type = kCFNumberSInt32Type} 8 : {contents = "ChipSerialNo"} = {length = 12, capacity = 12, bytes = 0x<<<<<<<>>>>>>b08c} 10 : {contents = "ChipID"} = {value = +80, type = kCFNumberSInt32Type} 11 : {contents = "CurrentTicket"} = {length = 953, capacity = 953, bytes = 0x<<<<<<<>>>>>>4886f70d0101 ... <<<<>>>>5f} 12 : {contents = "done"} = {value = false} } update_baseband: requesting baseband firmware data update_baseband: received data from host (3817863 bytes) update_baseband: performing next update stage bbupdater: Validating parameters...bbupdater: OK bbupdater: Validating parameters...bbupdater: OK bbupdater: Opening device path /dev/cu.debug, using baud 115200 bbupdater: Reading Reference file...bbupdater: OK bbupdater: Sending EBL...bbupdater: bbupdater: Sending EBL Length...bbupdater: OK bbupdater: Sending EBL Data and Checksum...bbupdater: OK bbupdater: Sending EBL -- All OK bbupdater: Getting EBL Version......bbupdater: OK bbupdater: - Boot Mode 0xCC bbupdater: - EBL Version Major/Minor: 2.13 bbupdater: - EBL Version 'ICE3_Bootloader_2.13' bbupdater: - Flashing Compression: 0, CRC Type: 0, CRC Method: 1 bbupdater: Validating parameters...bbupdater: OK bbupdater: Opening device path /dev/cu.debug, using baud 115200 bbupdater: Validating parameters...bbupdater: OK bbupdater: Opening device path /dev/cu.debug, using baud 115200 bbupdater: Reading Reference file...bbupdater: OK bbupdater: Sending Protocol configuration...bbupdater: OK bbupdater: Sending Flash ID...bbupdater: OK bbupdater: Doing CFI Stage 1...bbupdater: OK bbupdater: Doing CFI Stage 2...bbupdater: OK bbupdater: ------------------------------------------------------------------------------- bbupdater: DONE BOOT bbupdater: ------------------------------------------------------------------------------- bbupdater: Getting software version of filebbupdater: OK bbupdater: Increasing baud rate to 921600...bbupdater: OK bbupdater: ------------------------------------------------------------------------------- bbupdater: SENDING FLS FILE DATA: bbupdater: ------------------------------------------------------------------------------- bbupdater: Loading FLS file...bbupdater: OK bbupdater: Dynamic EEP is not in Memory Map, so don't touch it ...bbupdater: >> Sending Block of type CodeClass(0) from FLS file...bbupdater: bbupdater: Sending Security Block...bbupdater: OK bbupdater: Erasing Load Area from 0x40040000 to 0x40736E96 (this will take some time)...bbupdater: OK bbupdater: Sending data for mapping 0: bbupdater: -- OK bbupdater: Checking validation result...bbupdater: OK bbupdater: >> Sending Block of type CodeClass(0) from FLS file -- All OK bbupdater: ------------------------------------------------------------------------------- bbupdater: DONE SENDING FLS FILE bbupdater: ------------------------------------------------------------------------------- bbupdater: ------------------------------------------------------------------------------- bbupdater: SENDING FLS FILE DATA: bbupdater: ------------------------------------------------------------------------------- bbupdater: Loading FLS file...bbupdater: OK bbupdater: >> Sending Block of type BootCoreSLBClass(0) from FLS file...bbupdater: bbupdater: Sending Security Block...bbupdater: OK bbupdater: Erasing Load Area from 0x40FE0000 to 0x40FE7FFE ...bbupdater: OK bbupdater: Sending data for mapping 0: bbupdater: -- OK bbupdater: Checking validation result...bbupdater: OK bbupdater: >> Sending Block of type BootCoreSLBClass(0) from FLS file -- All OK bbupdater: ------------------------------------------------------------------------------- bbupdater: DONE SENDING FLS FILE bbupdater: ------------------------------------------------------------------------------- bbupdater: Validating parameters...bbupdater: OK bbupdater: Opening device path /dev/cu.debug, using baud 115200 bbupdater: Waiting for baseband power-up...bbupdater: bbupdater: Opening device path /dev/cu.debug, using baud 115200 bbupdater: - Ping failed, trying again, 56 tries left bbupdater: - Ping failed, trying again, 55 tries left bbupdater: - Ping failed, trying again, 54 tries left bbupdater: - Ping failed, trying again, 53 tries left bbupdater: - Ping failed, trying again, 52 tries left bbupdater: - Ping failed, trying again, 51 tries left bbupdater: - Ping failed, trying again, 50 tries left bbupdater: - Ping failed, trying again, 49 tries left bbupdater: - Ping failed, trying again, 48 tries left bbupdater: - Ping failed, trying again, 47 tries left bbupdater: - Ping failed, trying again, 46 tries left bbupdater: - Ping failed, trying again, 45 tries left bbupdater: - Ping failed, trying again, 44 tries left bbupdater: - Ping failed, trying again, 43 tries left bbupdater: - Ping failed, trying again, 42 tries left bbupdater: - Ping failed, trying again, 41 tries left bbupdater: - Ping failed, trying again, 40 tries left bbupdater: - Ping failed, trying again, 39 tries left bbupdater: - Ping failed, trying again, 38 tries left bbupdater: - Ping failed, trying again, 37 tries left bbupdater: - Ping failed, trying again, 36 tries left bbupdater: - Ping failed, trying again, 35 tries left bbupdater: - Resetting baseband bbupdater: - Ping failed, trying again, 51 tries left bbupdater: - Ping failed, trying again, 50 tries left bbupdater: - Ping failed, trying again, 49 tries left bbupdater: - Ping failed, trying again, 48 tries left bbupdater: - Ping failed, trying again, 47 tries left bbupdater: - Resetting baseband bbupdater: - Ping failed, trying again, 46 tries left bbupdater: - Ping failed, trying again, 45 tries left bbupdater: - Ping failed, trying again, 44 tries left bbupdater: - Ping failed, trying again, 43 tries left bbupdater: - Ping failed, trying again, 42 tries left bbupdater: - Resetting baseband bbupdater: - Ping failed, trying again, 41 tries left bbupdater: - Ping failed, trying again, 40 tries left bbupdater: - Ping failed, trying again, 39 tries left bbupdater: - Ping failed, trying again, 38 tries left bbupdater: - Ping failed, trying again, 37 tries left bbupdater: - Resetting baseband bbupdater: - Ping failed, trying again, 36 tries left bbupdater: - Ping failed, trying again, 35 tries left bbupdater: - Ping failed, trying again, 34 tries left bbupdater: - Ping failed, trying again, 33 tries left bbupdater: - Ping failed, trying again, 32 tries left bbupdater: - Powercycling baseband bbupdater: - Ping failed, trying again, 31 tries left bbupdater: - Ping failed, trying again, 30 tries left bbupdater: - Ping failed, trying again, 29 tries left bbupdater: - Ping failed, trying again, 28 tries left bbupdater: - Ping failed, trying again, 27 tries left bbupdater: - Powercycling baseband bbupdater: - Ping failed, trying again, 26 tries left bbupdater: - Ping failed, trying again, 25 tries left bbupdater: - Ping failed, trying again, 24 tries left bbupdater: - Ping failed, trying again, 23 tries left bbupdater: - Ping failed, trying again, 22 tries left bbupdater: - Powercycling baseband bbupdater: - Ping failed, trying again, 21 tries left bbupdater: - Ping failed, trying again, 20 tries left bbupdater: - Ping failed, trying again, 19 tries left bbupdater: - Ping failed, trying again, 18 tries left bbupdater: - Ping failed, trying again, 17 tries left bbupdater: !!! bbupdater: [1325371327.271944 : 13.-1]::: Exception at :0: - kBBUReturnTimedOut(8)/16: Pinging timed out bbupdater: bbupdater: !!! bbupdater: Exception at :0: - kBBUReturnTimedOut(8)/16: Pinging timed out send_bbupdate_status: sending baseband update status to host update_baseband: bbupdater error: Exception at :0: - kBBUReturnTimedOut(8)/16: Pinging timed out 0: RamrodErrorDomain/3ec: update_baseband: failed to perform next stage 1: BBUpdater/10 unable to convert ramrod error 1004 attempting to dump restore log writing log file: /mnt1/restore.log ==== end of device restore output ==== 2011-12-31 14:42:07.148 [4724:1564]: AMRAuthInstallDeletePersonalizedBundle 2011-12-31 14:42:07.335 [4724:1564]: : Restore failed (result = -1) 2011-12-31 14:42:07.600 [4724:1414]: iTunes: Restore error -1 2011-12-31 14:42:51.297 [4724:dd8]: WinAMRestore::OnInterfaceArrival: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#90b3c183 2011-12-31 14:42:51.297 [4724:dd8]: WinAMRestore::AddAppleDeviceToDeviceList: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#90b3c183 2011-12-31 14:42:51.534 [4724:dd8]: AppleDevice::EnumerateHubPorts: DoesDriverNameMatchDeviceID failed 2011-12-31 14:42:51.564 [4724:dd8]: WinAMRestore::AddAppleDeviceToDeviceList, old disconnected device connects again 2011-12-31 14:42:51.564 [4724:dd8]: AppleDevice::SetNotification: CONNECT, interface type: 1, id: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#90b3c183, inst: 0x635e8c0 2011-12-31 14:42:51.564 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e8c0, notify: 1, connected: 0 2011-12-31 14:42:51.564 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e820, notify: 2, connected: 0 2011-12-31 14:42:51.564 [4724:dd8]: AppleDevice::NotifyConnect: Device type: 2, Interfaces total: 2, arrived: 1 2011-12-31 14:42:51.564 [4724:dd8]: AppleDevice::NotifyConnect: Interfaces total != arrived, no notification 2011-12-31 14:42:51.574 [4724:dd8]: WinAMRestore::OnInterfaceArrival: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#90b3c183 2011-12-31 14:42:51.574 [4724:dd8]: WinAMRestore::AddAppleDeviceToDeviceList: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#90b3c183 2011-12-31 14:42:51.694 [4724:dd8]: AppleDevice::EnumerateHubPorts: DoesDriverNameMatchDeviceID failed 2011-12-31 14:42:51.724 [4724:dd8]: WinAMRestore::AddAppleDeviceToDeviceList, device already connected, new interface 2011-12-31 14:42:51.724 [4724:dd8]: AppleDevice::SetDeviceID: AMD_INTERFACE_DFU \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#90b3c183, inst: 0x635e8c0 2011-12-31 14:42:51.724 [4724:dd8]: AppleDevice::SetNotification: CONNECT, interface type: 1, id: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#90b3c183, inst: 0x635e8c0 2011-12-31 14:42:51.724 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e8c0, notify: 1, connected: 1 2011-12-31 14:42:51.724 [4724:dd8]: WinAMRestore::ProcessDevNodesChanges: device: 0x0635e820, notify: 2, connected: 0 2011-12-31 14:42:51.724 [4724:dd8]: AppleDevice::NotifyConnect: Device type: 2, Interfaces total: 2, arrived: 2 2011-12-31 14:42:51.724 [4724:dd8]: AppleDevice::NotifyConnect: IBOOT, IBOOT \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#90b3c183, inst: 0x635e8c0 2011-12-31 14:42:51.724 [4724:dd8]: IBOOT, DFU \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#90b3c183, inst: 0x635e8c0 2011-12-31 14:42:51.744 [4724:1414]: iTunes: SCEP 2