2011-09-28 08:07:17.531 [316:c50]: restore library built Feb 7 2011 at 06:28:58 2011-09-28 08:07:17.531 [316:c50]: iTunes: iTunes 10.4.1.10 2011-09-28 08:07:17.531 [316:c50]: iTunes: Software payload version: 8L1 (option key) 2011-09-28 08:10:16.703 [316:c94]: iTunes: Specifying UOI boot image 2011-09-28 08:10:16.703 [316:c94]: AppleDevice::GetDeviceID: failed for iBoot 2011-09-28 08:10:16.703 [316:c94]: _AMRUSBDeviceGetManufacturerStringIndex: unable to get device descriptor: 2001 2011-09-28 08:10:16.703 [316:c94]: unable to get manufacturer string index: 2001 2011-09-28 08:10:16.703 [316:c94]: *** UUID 3369741F-ABD3-5F4B-9615-4382DAC05794 *** 2011-09-28 08:10:16.703 [316:c94]: requested restore behavior: Erase 2011-09-28 08:10:16.765 [316:c94]: amai: AMAuthInstallDebugWriteObject: debug object written: C:\DOCUME~1\ahmad\LOCALS~1\Temp\Per9.tmp\amai\debug\tss-request.plist 2011-09-28 08:10:17.031 [316:c94]: amai: tss_submit_job: HttpQueryInfo returned 200 2011-09-28 08:10:17.078 [316:c94]: amai: AMAuthInstallRequestSendSync: received tss response (server version: 0.6.30-b2) 2011-09-28 08:10:17.078 [316:c94]: amai: AMAuthInstallDebugWriteObject: debug object written: C:\DOCUME~1\ahmad\LOCALS~1\Temp\Per9.tmp\amai\debug\tss-response.plist 2011-09-28 08:10:17.093 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "RestoreLogo" 2011-09-28 08:10:17.109 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "RestoreDeviceTree" 2011-09-28 08:10:17.515 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "RestoreKernelCache" 2011-09-28 08:10:18.546 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "RestoreRamDisk" 2011-09-28 08:10:19.234 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "iBEC" 2011-09-28 08:10:19.281 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "iBSS" 2011-09-28 08:10:19.281 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "BatteryLow1" 2011-09-28 08:10:19.296 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "BatteryLow0" 2011-09-28 08:10:19.328 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "LLB" 2011-09-28 08:10:19.328 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "iBoot" 2011-09-28 08:10:19.328 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "BatteryCharging1" 2011-09-28 08:10:19.328 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "BatteryCharging" 2011-09-28 08:10:19.343 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "BatteryPlugin" 2011-09-28 08:10:19.359 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "BatteryFull" 2011-09-28 08:10:19.375 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "BatteryCharging0" 2011-09-28 08:10:19.375 [316:c94]: amai: AMAuthInstallApCopyPersonalizedImg3Data: response missing entry "RecoveryMode" 2011-09-28 08:10:19.562 [316:bec]: AppleDevice::GetDeviceID: failed for iBoot 2011-09-28 08:10:19.562 [316:bec]: _AMRUSBDeviceGetManufacturerStringIndex: unable to get device descriptor: 2001 2011-09-28 08:10:19.562 [316:bec]: unable to get manufacturer string index: 2001 2011-09-28 08:10:19.562 [316:bec]: : production fused device 2011-09-28 08:10:19.562 [316:bec]: unable to open device_map.txt: No such file or directory 2011-09-28 08:10:19.578 [316:bec]: WinDFU::OpenDFUDevice: path: \\?\USB#VID_05AC&PID_1227#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#f2411396 2011-09-28 08:10:19.578 [316:bec]: WinDFU::OpenDeviceByPath: \\?\USB#VID_05AC&PID_1227#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#f2411396 2011-09-28 08:10:19.593 [316:bec]: : operation 0 progress 1 2011-09-28 08:10:19.609 [316:bec]: : operation 0 progress 2 2011-09-28 08:10:19.625 [316:bec]: : operation 0 progress 3 2011-09-28 08:10:19.640 [316:bec]: : operation 0 progress 5 2011-09-28 08:10:19.656 [316:bec]: : operation 0 progress 6 2011-09-28 08:10:19.671 [316:bec]: : operation 0 progress 7 2011-09-28 08:10:19.687 [316:bec]: : operation 0 progress 9 2011-09-28 08:10:19.703 [316:bec]: : operation 0 progress 10 2011-09-28 08:10:19.718 [316:bec]: : operation 0 progress 11 2011-09-28 08:10:19.734 [316:bec]: : operation 0 progress 12 2011-09-28 08:10:19.750 [316:bec]: : operation 0 progress 14 2011-09-28 08:10:19.781 [316:bec]: : operation 0 progress 15 2011-09-28 08:10:19.796 [316:bec]: : operation 0 progress 16 2011-09-28 08:10:19.812 [316:bec]: : operation 0 progress 18 2011-09-28 08:10:19.828 [316:bec]: : operation 0 progress 19 2011-09-28 08:10:19.843 [316:bec]: : operation 0 progress 20 2011-09-28 08:10:19.859 [316:bec]: : operation 0 progress 22 2011-09-28 08:10:19.875 [316:bec]: : operation 0 progress 23 2011-09-28 08:10:19.890 [316:bec]: : operation 0 progress 24 2011-09-28 08:10:19.906 [316:bec]: : operation 0 progress 25 2011-09-28 08:10:19.921 [316:bec]: : operation 0 progress 27 2011-09-28 08:10:19.937 [316:bec]: : operation 0 progress 28 2011-09-28 08:10:19.953 [316:bec]: : operation 0 progress 29 2011-09-28 08:10:19.968 [316:bec]: : operation 0 progress 31 2011-09-28 08:10:19.984 [316:bec]: : operation 0 progress 32 2011-09-28 08:10:20.000 [316:bec]: : operation 0 progress 33 2011-09-28 08:10:20.015 [316:bec]: : operation 0 progress 34 2011-09-28 08:10:20.046 [316:bec]: : operation 0 progress 36 2011-09-28 08:10:20.062 [316:bec]: : operation 0 progress 37 2011-09-28 08:10:20.078 [316:bec]: : operation 0 progress 38 2011-09-28 08:10:20.093 [316:bec]: : operation 0 progress 40 2011-09-28 08:10:20.109 [316:bec]: : operation 0 progress 41 2011-09-28 08:10:20.125 [316:bec]: : operation 0 progress 42 2011-09-28 08:10:20.140 [316:bec]: : operation 0 progress 44 2011-09-28 08:10:20.156 [316:bec]: : operation 0 progress 45 2011-09-28 08:10:20.171 [316:bec]: : operation 0 progress 46 2011-09-28 08:10:20.187 [316:bec]: : operation 0 progress 47 2011-09-28 08:10:20.203 [316:bec]: : operation 0 progress 49 2011-09-28 08:10:20.218 [316:bec]: : operation 0 progress 50 2011-09-28 08:10:20.234 [316:bec]: : operation 0 progress 51 2011-09-28 08:10:20.250 [316:bec]: : operation 0 progress 53 2011-09-28 08:10:20.265 [316:bec]: : operation 0 progress 54 2011-09-28 08:10:20.281 [316:bec]: : operation 0 progress 55 2011-09-28 08:10:20.312 [316:bec]: : operation 0 progress 57 2011-09-28 08:10:20.328 [316:bec]: : operation 0 progress 58 2011-09-28 08:10:20.343 [316:bec]: : operation 0 progress 59 2011-09-28 08:10:20.359 [316:bec]: : operation 0 progress 60 2011-09-28 08:10:20.375 [316:bec]: : operation 0 progress 62 2011-09-28 08:10:20.390 [316:bec]: : operation 0 progress 63 2011-09-28 08:10:20.406 [316:bec]: : operation 0 progress 64 2011-09-28 08:10:20.421 [316:bec]: : operation 0 progress 66 2011-09-28 08:10:20.437 [316:bec]: : operation 0 progress 67 2011-09-28 08:10:20.453 [316:bec]: : operation 0 progress 68 2011-09-28 08:10:20.468 [316:bec]: : operation 0 progress 69 2011-09-28 08:10:20.484 [316:bec]: : operation 0 progress 71 2011-09-28 08:10:20.500 [316:bec]: : operation 0 progress 72 2011-09-28 08:10:20.515 [316:bec]: : operation 0 progress 73 2011-09-28 08:10:20.531 [316:bec]: : operation 0 progress 75 2011-09-28 08:10:20.546 [316:bec]: : operation 0 progress 76 2011-09-28 08:10:20.578 [316:bec]: : operation 0 progress 77 2011-09-28 08:10:20.593 [316:bec]: : operation 0 progress 79 2011-09-28 08:10:20.609 [316:bec]: : operation 0 progress 80 2011-09-28 08:10:20.625 [316:bec]: : operation 0 progress 81 2011-09-28 08:10:20.640 [316:bec]: : operation 0 progress 82 2011-09-28 08:10:20.656 [316:bec]: : operation 0 progress 84 2011-09-28 08:10:20.671 [316:bec]: : operation 0 progress 85 2011-09-28 08:10:20.687 [316:bec]: : operation 0 progress 86 2011-09-28 08:10:20.703 [316:bec]: : operation 0 progress 88 2011-09-28 08:10:20.718 [316:bec]: : operation 0 progress 89 2011-09-28 08:10:20.734 [316:bec]: : operation 0 progress 90 2011-09-28 08:10:20.750 [316:bec]: : operation 0 progress 91 2011-09-28 08:10:20.765 [316:bec]: : operation 0 progress 93 2011-09-28 08:10:20.781 [316:bec]: : operation 0 progress 94 2011-09-28 08:10:20.796 [316:bec]: : operation 0 progress 95 2011-09-28 08:10:20.812 [316:bec]: : operation 0 progress 97 2011-09-28 08:10:20.843 [316:bec]: : operation 0 progress 98 2011-09-28 08:10:20.859 [316:bec]: : operation 0 progress 99 2011-09-28 08:10:20.859 [316:bec]: WinDFU::UploadData: EOF, cbRead: 404 2011-09-28 08:10:20.859 [316:bec]: : operation 0 progress 100 2011-09-28 08:10:20.859 [316:bec]: WinDFU::UploadData: ZLP 2011-09-28 08:10:20.859 [316:bec]: WinDFU::FinalizeDfuUpdate: GetStatus: status: 0, state: 6 2011-09-28 08:10:20.859 [316:bec]: WinDFU::ProcessUpdateState: status.bState == DFU_STATE_MANIFEST_SYNC 2011-09-28 08:10:20.859 [316:bec]: WinDFU::FinalizeDfuUpdate: GetStatus: status: 0, state: 7 2011-09-28 08:10:20.859 [316:bec]: WinDFU::ProcessUpdateState: status.bState == DFU_STATE_MANIFEST, PollTimeout: 3000 2011-09-28 08:10:23.859 [316:bec]: WinDFU::FinalizeDfuUpdate: GetStatus: status: 0, state: 8 2011-09-28 08:10:23.859 [316:bec]: WinDFU::ProcessUpdateState: status.bState == DFU_STATE_MANIFEST_WAIT_RESET 2011-09-28 08:10:23.859 [316:bec]: WinDFU::ResetDevice: resetting... 2011-09-28 08:10:43.890 [316:bec]: WinDFU::FinalizeDfuUpdate: success 2011-09-28 08:10:43.890 [316:bec]: : DFU succeeded 2011-09-28 08:10:45.328 [316:ec0]: WinAMRestore::OnInterfaceRemoval: \\?\USB#VID_05AC&PID_1227#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#f2411396 2011-09-28 08:10:45.343 [316:ec0]: WinAMRestore::AddAppleDeviceToDeviceList: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#8d796886 2011-09-28 08:10:45.437 [316:ec0]: AppleDevice::EnumerateHubPorts: DoesDriverNameMatchDeviceID failed 2011-09-28 08:10:45.593 [316:ec0]: AppleDevice::SetNotification: CONNECT, interface type: 1, id: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#8d796886, inst: 0x115961d8 2011-09-28 08:10:45.593 [316:ec0]: WinAMRestore::AddAppleDeviceToDeviceList, new device 2011-09-28 08:10:45.593 [316:ec0]: WinAMRestore::AddAppleDeviceToDeviceList: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#8d796886 2011-09-28 08:10:45.656 [316:ec0]: AppleDevice::EnumerateHubPorts: DoesDriverNameMatchDeviceID failed 2011-09-28 08:10:45.718 [316:ec0]: WinAMRestore::AddAppleDeviceToDeviceList, device already connected, new interface 2011-09-28 08:10:45.718 [316:ec0]: AppleDevice::SetDeviceID: AMD_INTERFACE_IBOOT \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#8d796886, inst: 0x115961d8 2011-09-28 08:10:45.718 [316:ec0]: AppleDevice::SetNotification: CONNECT, interface type: 1, id: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#8d796886, inst: 0x115961d8 2011-09-28 08:10:45.718 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x0f7d8c98, notify: 2, connected: 1 2011-09-28 08:10:45.718 [316:ec0]: AppleDevice::NotifyDisconnect: DFU \\?\USB#VID_05AC&PID_1227#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#f2411396, inst: 0xf7d8c98 2011-09-28 08:10:45.718 [316:ec0]: _AMDFUModeDeviceFinalize: 03859F18 2011-09-28 08:10:45.718 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x115961d8, notify: 1, connected: 0 2011-09-28 08:10:45.718 [316:ec0]: AppleDevice::NotifyConnect: Device type: 2, Interfaces total: 2, arrived: 2 2011-09-28 08:10:45.718 [316:ec0]: AppleDevice::NotifyConnect: IBOOT, IBOOT \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#8d796886, inst: 0x115961d8 2011-09-28 08:10:45.718 [316:ec0]: IBOOT, DFU \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#8d796886, inst: 0x115961d8 2011-09-28 08:10:45.875 [316:c50]: device software does not support nonce generation 2011-09-28 08:10:45.875 [316:c50]: device software does not support nonce generation 2011-09-28 08:10:45.875 [316:c50]: iTunes: SCEP 2 2011-09-28 08:10:46.234 [316:d28]: iBoot build-version = iBoot-1072.61 2011-09-28 08:10:46.234 [316:d28]: iBoot build-style = RELEASE 2011-09-28 08:10:46.234 [316:d28]: AMDeviceIoControl: GetOverlappedResult failed 2011-09-28 08:10:46.234 [316:d28]: AMDeviceIoControl: pipe stall 2011-09-28 08:10:46.234 [316:d28]: USBControlTransfer: error 31, usbd status c0000004 2011-09-28 08:10:46.234 [316:d28]: command device request for 'getenv radio-error' failed: 2008 2011-09-28 08:10:46.234 [316:d28]: radio-error not set 2011-09-28 08:10:46.250 [316:d28]: : operation 44 progress -1 2011-09-28 08:10:46.250 [316:d28]: requested restore behavior: Erase 2011-09-28 08:10:46.281 [316:d28]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: nothing to be done 2011-09-28 08:10:46.359 [316:d28]: unable to open device_map.txt: No such file or directory 2011-09-28 08:10:46.359 [316:d28]: : production fused device 2011-09-28 08:10:46.359 [316:d28]: interface has 1 endpoints, file pipe = 1 2011-09-28 08:10:46.359 [316:d28]: : operation 4 progress -1 2011-09-28 08:10:46.421 [316:d28]: : operation 42 progress -1 2011-09-28 08:10:46.656 [316:ec0]: WinAMRestore::OnInterfaceArrival: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#8d796886 2011-09-28 08:10:46.656 [316:ec0]: WinAMRestore::AddAppleDeviceToDeviceList: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#8d796886 2011-09-28 08:10:46.687 [316:ec0]: AppleDevice::EnumerateHubPorts: DoesDriverNameMatchDeviceID failed 2011-09-28 08:10:46.703 [316:ec0]: WinAMRestore::AddAppleDeviceToDeviceList, device already connected, new interface 2011-09-28 08:10:46.703 [316:ec0]: AppleDevice::SetDeviceID: AMD_INTERFACE_IBOOT \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#8d796886, inst: 0x115961d8 2011-09-28 08:10:46.703 [316:ec0]: AppleDevice::SetNotification: CONNECT, interface type: 1, id: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#8d796886, inst: 0x115961d8 2011-09-28 08:10:46.703 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x0f7d8c98, notify: 0, connected: 0 2011-09-28 08:10:46.703 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x115961d8, notify: 1, connected: 1 2011-09-28 08:10:46.703 [316:ec0]: AppleDevice::NotifyConnect: Device type: 2, Interfaces total: 2, arrived: 3 2011-09-28 08:10:46.703 [316:ec0]: AppleDevice::NotifyConnect: Interfaces total != arrived, no notification 2011-09-28 08:10:46.750 [316:ec0]: WinAMRestore::OnInterfaceArrival: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#8d796886 2011-09-28 08:10:46.750 [316:ec0]: WinAMRestore::AddAppleDeviceToDeviceList: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#8d796886 2011-09-28 08:10:46.781 [316:ec0]: AppleDevice::EnumerateHubPorts: DoesDriverNameMatchDeviceID failed 2011-09-28 08:10:46.796 [316:ec0]: WinAMRestore::AddAppleDeviceToDeviceList, device already connected, new interface 2011-09-28 08:10:46.796 [316:ec0]: AppleDevice::SetDeviceID: AMD_INTERFACE_DFU \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#8d796886, inst: 0x115961d8 2011-09-28 08:10:46.796 [316:ec0]: AppleDevice::SetNotification: CONNECT, interface type: 1, id: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#8d796886, inst: 0x115961d8 2011-09-28 08:10:46.796 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x0f7d8c98, notify: 0, connected: 0 2011-09-28 08:10:46.796 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x115961d8, notify: 1, connected: 1 2011-09-28 08:10:46.796 [316:ec0]: AppleDevice::NotifyConnect: Device type: 2, Interfaces total: 2, arrived: 4 2011-09-28 08:10:46.796 [316:ec0]: AppleDevice::NotifyConnect: Interfaces total != arrived, no notification 2011-09-28 08:10:47.484 [316:d28]: : operation 5 progress -1 2011-09-28 08:10:48.390 [316:d28]: AMDeviceIoControl: GetOverlappedResult failed 2011-09-28 08:10:48.390 [316:d28]: AMDeviceIoControl: pipe stall 2011-09-28 08:10:48.390 [316:d28]: USBControlTransfer: error 31, usbd status c0000004 2011-09-28 08:10:48.390 [316:d28]: command device request for 'getenv ramdisk-delay' failed: 2008 2011-09-28 08:10:51.656 [316:d28]: : operation 6 progress -1 2011-09-28 08:10:52.671 [316:d28]: : operation 7 progress -1 2011-09-28 08:10:52.921 [316:d28]: : operation 8 progress -1 2011-09-28 08:10:52.921 [316:d28]: : operation 9 progress -1 2011-09-28 08:10:52.921 [316:d28]: : Recovery mode succeeded 2011-09-28 08:10:55.046 [316:ec0]: WinAMRestore::OnInterfaceRemoval: \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#8d796886 2011-09-28 08:10:55.078 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x0f7d8c98, notify: 2, connected: 0 2011-09-28 08:10:55.078 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x115961d8, notify: 2, connected: 1 2011-09-28 08:10:55.078 [316:ec0]: AppleDevice::NotifyDisconnect: IBOOT, IBOOT \\?\USB#VID_05AC&PID_1281#{ED82A167-D61A-4AF6-9AB6-11E52236C576}\IB0000#8d796886, inst: 0x115961d8 2011-09-28 08:10:55.078 [316:ec0]: IBOOT, DFU \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#8d796886, inst: 0x115961d8 2011-09-28 08:10:55.125 [316:ec0]: _AMRecoveryModeDeviceFinalize: 03801780 2011-09-28 08:10:55.718 [316:ec0]: WinAMRestore::OnInterfaceRemoval: \\?\USB#VID_05AC&PID_1281#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#8d796886 2011-09-28 08:10:55.718 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x0f7d8c98, notify: 2, connected: 0 2011-09-28 08:10:55.718 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x115961d8, notify: 2, connected: 0 2011-09-28 08:10:59.687 [316:c50]: afc> received kAMDeviceAttached action, device 116734F8, AFC error 0XE8000028 2011-09-28 08:10:59.687 [316:c50]: iTunes: Restore-mode device appeared, device 116734F8 2011-09-28 08:10:59.687 [316:c50]: iTunes: Creating restore mode device with usbMuxDeviceID 0X2 2011-09-28 08:10:59.687 [316:c50]: : operation 3 progress -1 2011-09-28 08:10:59.718 [316:c50]: : operation 4 progress -1 2011-09-28 08:10:59.734 [316:c50]: Supports value queries: 1 2011-09-28 08:10:59.750 [316:fac]: : operation 44 progress -1 2011-09-28 08:10:59.750 [316:fac]: requested restore behavior: Erase 2011-09-28 08:10:59.765 [316:fac]: amai: _AMAuthInstallBundleCreateServerRequestDictionary: nothing to be done 2011-09-28 08:10:59.765 [316:fac]: device did not return saved USB log 2011-09-28 08:10:59.765 [316:fac]: device did not return saved panic log 2011-09-28 08:10:59.781 [316:fac]: connected to service com.apple.mobile.restored 2011-09-28 08:10:59.781 [316:fac]: using protocol version 12 2011-09-28 08:10:59.781 [316:fac]: unable to open device_map.txt: No such file or directory 2011-09-28 08:10:59.796 [316:fac]: board config = n90ap 2011-09-28 08:11:29.812 [316:fac]: : operation 28 progress -1 2011-09-28 08:11:33.015 [316:fac]: : operation 28 progress -1 2011-09-28 08:11:35.812 [316:fac]: : operation 28 progress -1 2011-09-28 08:11:38.812 [316:fac]: : operation 28 progress -1 2011-09-28 08:11:41.812 [316:fac]: : operation 28 progress -1 2011-09-28 08:11:44.828 [316:fac]: : operation 28 progress -1 2011-09-28 08:11:47.828 [316:fac]: : operation 28 progress -1 2011-09-28 08:11:50.828 [316:fac]: : operation 28 progress -1 2011-09-28 08:11:53.828 [316:fac]: : operation 28 progress -1 2011-09-28 08:11:56.828 [316:fac]: : operation 28 progress -1 2011-09-28 08:11:59.843 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:02.843 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:05.843 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:08.843 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:11.843 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:14.843 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:17.843 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:20.859 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:23.859 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:26.859 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:29.859 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:32.859 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:35.859 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:38.875 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:41.875 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:44.875 [316:fac]: : operation 28 progress -1 2011-09-28 08:12:47.875 [316:fac]: : operation 28 progress -1 2011-09-28 08:13:00.031 [316:fac]: : operation 11 progress -1 2011-09-28 08:13:00.859 [316:fac]: : operation 12 progress -1 2011-09-28 08:13:02.171 [316:fac]: : operation 12 progress -1 2011-09-28 08:13:07.265 [316:fac]: : operation 13 progress 0 2011-09-28 08:13:17.265 [316:fac]: : operation 13 progress 1 2011-09-28 08:13:17.265 [316:fac]: ASR progress: Initialized communication with client 2011-09-28 08:13:20.578 [316:fac]: : operation 13 progress 5 2011-09-28 08:13:20.578 [316:fac]: ASR progress: Starting to send payload 2011-09-28 08:13:21.390 [316:fac]: : operation 13 progress 6 2011-09-28 08:13:21.390 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:22.140 [316:fac]: : operation 13 progress 7 2011-09-28 08:13:22.140 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:23.140 [316:fac]: : operation 13 progress 8 2011-09-28 08:13:23.140 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:23.937 [316:fac]: : operation 13 progress 9 2011-09-28 08:13:23.937 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:24.531 [316:fac]: : operation 13 progress 10 2011-09-28 08:13:24.531 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:25.250 [316:fac]: : operation 13 progress 11 2011-09-28 08:13:25.250 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:25.859 [316:fac]: : operation 13 progress 12 2011-09-28 08:13:25.859 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:26.421 [316:fac]: : operation 13 progress 13 2011-09-28 08:13:26.421 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:26.906 [316:fac]: : operation 13 progress 14 2011-09-28 08:13:26.906 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:27.468 [316:fac]: : operation 13 progress 15 2011-09-28 08:13:27.468 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:28.140 [316:fac]: : operation 13 progress 16 2011-09-28 08:13:28.140 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:28.750 [316:fac]: : operation 13 progress 17 2011-09-28 08:13:28.750 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:29.828 [316:fac]: : operation 13 progress 18 2011-09-28 08:13:29.828 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:30.734 [316:fac]: : operation 13 progress 19 2011-09-28 08:13:30.734 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:31.859 [316:fac]: : operation 13 progress 20 2011-09-28 08:13:31.859 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:32.734 [316:fac]: : operation 13 progress 21 2011-09-28 08:13:32.734 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:33.546 [316:fac]: : operation 13 progress 22 2011-09-28 08:13:33.546 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:34.203 [316:fac]: : operation 13 progress 23 2011-09-28 08:13:34.203 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:34.750 [316:fac]: : operation 13 progress 24 2011-09-28 08:13:34.750 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:35.328 [316:fac]: : operation 13 progress 25 2011-09-28 08:13:35.328 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:36.015 [316:fac]: : operation 13 progress 26 2011-09-28 08:13:36.015 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:36.625 [316:fac]: : operation 13 progress 27 2011-09-28 08:13:36.625 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:37.171 [316:fac]: : operation 13 progress 28 2011-09-28 08:13:37.171 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:38.000 [316:fac]: : operation 13 progress 29 2011-09-28 08:13:38.000 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:38.609 [316:fac]: : operation 13 progress 30 2011-09-28 08:13:38.609 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:39.281 [316:fac]: : operation 13 progress 31 2011-09-28 08:13:39.281 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:40.109 [316:fac]: : operation 13 progress 32 2011-09-28 08:13:40.109 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:40.843 [316:fac]: : operation 13 progress 33 2011-09-28 08:13:40.843 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:41.484 [316:fac]: : operation 13 progress 34 2011-09-28 08:13:41.484 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:43.203 [316:fac]: : operation 13 progress 35 2011-09-28 08:13:43.203 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:44.640 [316:fac]: : operation 13 progress 36 2011-09-28 08:13:44.640 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:45.343 [316:fac]: : operation 13 progress 37 2011-09-28 08:13:45.343 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:46.125 [316:fac]: : operation 13 progress 38 2011-09-28 08:13:46.125 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:46.796 [316:fac]: : operation 13 progress 39 2011-09-28 08:13:46.796 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:47.578 [316:fac]: : operation 13 progress 40 2011-09-28 08:13:47.578 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:48.343 [316:fac]: : operation 13 progress 41 2011-09-28 08:13:48.343 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:48.937 [316:fac]: : operation 13 progress 42 2011-09-28 08:13:48.937 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:49.937 [316:fac]: : operation 13 progress 43 2011-09-28 08:13:49.937 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:50.625 [316:fac]: : operation 13 progress 44 2011-09-28 08:13:50.625 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:51.453 [316:fac]: : operation 13 progress 45 2011-09-28 08:13:51.453 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:52.671 [316:fac]: : operation 13 progress 46 2011-09-28 08:13:52.671 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:53.828 [316:fac]: : operation 13 progress 47 2011-09-28 08:13:53.828 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:54.921 [316:fac]: : operation 13 progress 48 2011-09-28 08:13:54.921 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:56.046 [316:fac]: : operation 13 progress 49 2011-09-28 08:13:56.046 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:57.156 [316:fac]: : operation 13 progress 50 2011-09-28 08:13:57.156 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:58.250 [316:fac]: : operation 13 progress 51 2011-09-28 08:13:58.250 [316:fac]: ASR progress: Sending payload 2011-09-28 08:13:59.296 [316:fac]: : operation 13 progress 52 2011-09-28 08:13:59.296 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:00.500 [316:fac]: : operation 13 progress 53 2011-09-28 08:14:00.500 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:01.859 [316:fac]: : operation 13 progress 54 2011-09-28 08:14:01.859 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:03.328 [316:fac]: : operation 13 progress 55 2011-09-28 08:14:03.328 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:04.312 [316:fac]: : operation 13 progress 56 2011-09-28 08:14:04.312 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:05.062 [316:fac]: : operation 13 progress 57 2011-09-28 08:14:05.062 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:05.906 [316:fac]: : operation 13 progress 58 2011-09-28 08:14:05.906 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:06.625 [316:fac]: : operation 13 progress 59 2011-09-28 08:14:06.625 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:07.312 [316:fac]: : operation 13 progress 60 2011-09-28 08:14:07.312 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:08.156 [316:fac]: : operation 13 progress 61 2011-09-28 08:14:08.156 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:08.781 [316:fac]: : operation 13 progress 62 2011-09-28 08:14:08.781 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:09.421 [316:fac]: : operation 13 progress 63 2011-09-28 08:14:09.421 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:10.140 [316:fac]: : operation 13 progress 64 2011-09-28 08:14:10.140 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:10.750 [316:fac]: : operation 13 progress 65 2011-09-28 08:14:10.750 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:11.656 [316:fac]: : operation 13 progress 66 2011-09-28 08:14:11.656 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:12.531 [316:fac]: : operation 13 progress 67 2011-09-28 08:14:12.531 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:13.109 [316:fac]: : operation 13 progress 68 2011-09-28 08:14:13.109 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:13.671 [316:fac]: : operation 13 progress 69 2011-09-28 08:14:13.671 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:14.234 [316:fac]: : operation 13 progress 70 2011-09-28 08:14:14.234 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:15.015 [316:fac]: : operation 13 progress 71 2011-09-28 08:14:15.015 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:15.640 [316:fac]: : operation 13 progress 72 2011-09-28 08:14:15.640 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:16.312 [316:fac]: : operation 13 progress 73 2011-09-28 08:14:16.312 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:16.937 [316:fac]: : operation 13 progress 74 2011-09-28 08:14:16.937 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:17.484 [316:fac]: : operation 13 progress 75 2011-09-28 08:14:17.484 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:18.281 [316:fac]: : operation 13 progress 76 2011-09-28 08:14:18.281 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:19.031 [316:fac]: : operation 13 progress 77 2011-09-28 08:14:19.031 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:19.671 [316:fac]: : operation 13 progress 78 2011-09-28 08:14:19.671 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:20.406 [316:fac]: : operation 13 progress 79 2011-09-28 08:14:20.406 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:21.171 [316:fac]: : operation 13 progress 80 2011-09-28 08:14:21.171 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:22.250 [316:fac]: : operation 13 progress 81 2011-09-28 08:14:22.250 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:23.171 [316:fac]: : operation 13 progress 82 2011-09-28 08:14:23.171 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:23.906 [316:fac]: : operation 13 progress 83 2011-09-28 08:14:23.906 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:24.406 [316:fac]: : operation 13 progress 84 2011-09-28 08:14:24.406 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:24.984 [316:fac]: : operation 13 progress 85 2011-09-28 08:14:24.984 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:25.828 [316:fac]: : operation 13 progress 86 2011-09-28 08:14:25.828 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:26.500 [316:fac]: : operation 13 progress 87 2011-09-28 08:14:26.500 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:27.109 [316:fac]: : operation 13 progress 88 2011-09-28 08:14:27.109 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:27.875 [316:fac]: : operation 13 progress 89 2011-09-28 08:14:27.875 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:28.859 [316:fac]: : operation 13 progress 90 2011-09-28 08:14:28.859 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:29.750 [316:fac]: : operation 13 progress 91 2011-09-28 08:14:29.750 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:30.484 [316:fac]: : operation 13 progress 92 2011-09-28 08:14:30.484 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:31.359 [316:fac]: : operation 13 progress 93 2011-09-28 08:14:31.359 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:32.156 [316:fac]: : operation 13 progress 94 2011-09-28 08:14:32.156 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:32.765 [316:fac]: : operation 13 progress 95 2011-09-28 08:14:32.765 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:33.406 [316:fac]: : operation 13 progress 96 2011-09-28 08:14:33.421 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:34.000 [316:fac]: : operation 13 progress 97 2011-09-28 08:14:34.000 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:34.906 [316:fac]: : operation 13 progress 98 2011-09-28 08:14:34.906 [316:fac]: ASR progress: Sending payload 2011-09-28 08:14:35.734 [316:fac]: : operation 13 progress 100 2011-09-28 08:14:35.734 [316:fac]: ASR progress: Finished 2011-09-28 08:14:36.609 [316:fac]: : operation 14 progress 2 2011-09-28 08:14:37.062 [316:fac]: : operation 14 progress 4 2011-09-28 08:14:37.500 [316:fac]: : operation 14 progress 6 2011-09-28 08:14:37.937 [316:fac]: : operation 14 progress 8 2011-09-28 08:14:38.421 [316:fac]: : operation 14 progress 10 2011-09-28 08:14:38.875 [316:fac]: : operation 14 progress 12 2011-09-28 08:14:39.312 [316:fac]: : operation 14 progress 14 2011-09-28 08:14:39.750 [316:fac]: : operation 14 progress 16 2011-09-28 08:14:40.187 [316:fac]: : operation 14 progress 18 2011-09-28 08:14:40.687 [316:fac]: : operation 14 progress 20 2011-09-28 08:14:41.125 [316:fac]: : operation 14 progress 22 2011-09-28 08:14:41.562 [316:fac]: : operation 14 progress 24 2011-09-28 08:14:42.000 [316:fac]: : operation 14 progress 26 2011-09-28 08:14:42.437 [316:fac]: : operation 14 progress 28 2011-09-28 08:14:42.937 [316:fac]: : operation 14 progress 30 2011-09-28 08:14:43.390 [316:fac]: : operation 14 progress 32 2011-09-28 08:14:43.828 [316:fac]: : operation 14 progress 34 2011-09-28 08:14:44.265 [316:fac]: : operation 14 progress 36 2011-09-28 08:14:44.703 [316:fac]: : operation 14 progress 38 2011-09-28 08:14:45.187 [316:fac]: : operation 14 progress 40 2011-09-28 08:14:45.640 [316:fac]: : operation 14 progress 42 2011-09-28 08:14:46.078 [316:fac]: : operation 14 progress 44 2011-09-28 08:14:46.734 [316:fac]: : operation 14 progress 46 2011-09-28 08:14:46.953 [316:fac]: : operation 14 progress 48 2011-09-28 08:14:47.453 [316:fac]: : operation 14 progress 50 2011-09-28 08:14:47.890 [316:fac]: : operation 14 progress 52 2011-09-28 08:14:48.328 [316:fac]: : operation 14 progress 54 2011-09-28 08:14:48.765 [316:fac]: : operation 14 progress 56 2011-09-28 08:14:49.203 [316:fac]: : operation 14 progress 58 2011-09-28 08:14:49.703 [316:fac]: : operation 14 progress 60 2011-09-28 08:14:50.187 [316:fac]: : operation 14 progress 62 2011-09-28 08:14:50.625 [316:fac]: : operation 14 progress 64 2011-09-28 08:14:51.062 [316:fac]: : operation 14 progress 66 2011-09-28 08:14:51.500 [316:fac]: : operation 14 progress 68 2011-09-28 08:14:52.000 [316:fac]: : operation 14 progress 70 2011-09-28 08:14:52.437 [316:fac]: : operation 14 progress 72 2011-09-28 08:14:52.875 [316:fac]: : operation 14 progress 74 2011-09-28 08:14:53.328 [316:fac]: : operation 14 progress 76 2011-09-28 08:14:53.750 [316:fac]: : operation 14 progress 78 2011-09-28 08:14:54.250 [316:fac]: : operation 14 progress 80 2011-09-28 08:14:54.687 [316:fac]: : operation 14 progress 82 2011-09-28 08:14:55.125 [316:fac]: : operation 14 progress 84 2011-09-28 08:14:55.562 [316:fac]: : operation 14 progress 86 2011-09-28 08:14:56.015 [316:fac]: : operation 14 progress 88 2011-09-28 08:14:56.500 [316:fac]: : operation 14 progress 90 2011-09-28 08:14:56.953 [316:fac]: : operation 14 progress 92 2011-09-28 08:14:57.390 [316:fac]: : operation 14 progress 94 2011-09-28 08:14:57.828 [316:fac]: : operation 14 progress 96 2011-09-28 08:14:58.265 [316:fac]: : operation 14 progress 98 2011-09-28 08:14:58.703 [316:fac]: : operation 14 progress 100 2011-09-28 08:14:58.796 [316:fac]: : operation 15 progress -1 2011-09-28 08:15:11.953 [316:fac]: : operation 16 progress -1 2011-09-28 08:15:12.015 [316:fac]: : operation 15 progress -1 2011-09-28 08:15:17.328 [316:fac]: : operation 16 progress -1 2011-09-28 08:15:17.406 [316:fac]: : operation 27 progress -1 2011-09-28 08:15:19.343 [316:fac]: : operation 27 progress -1 2011-09-28 08:15:19.562 [316:fac]: : operation 17 progress -1 2011-09-28 08:15:20.140 [316:fac]: : operation 25 progress -1 2011-09-28 08:15:20.234 [316:fac]: : operation 35 progress -1 2011-09-28 08:15:21.312 [316:fac]: : operation 18 progress 0 2011-09-28 08:15:21.390 [316:fac]: : operation 18 progress 8 2011-09-28 08:15:21.468 [316:fac]: : operation 18 progress 16 2011-09-28 08:15:21.531 [316:fac]: : operation 18 progress 25 2011-09-28 08:15:21.593 [316:fac]: : operation 18 progress 33 2011-09-28 08:15:21.656 [316:fac]: : operation 18 progress 41 2011-09-28 08:15:21.734 [316:fac]: : operation 18 progress 50 2011-09-28 08:15:21.796 [316:fac]: : operation 18 progress 58 2011-09-28 08:15:21.859 [316:fac]: : operation 18 progress 66 2011-09-28 08:15:21.921 [316:fac]: : operation 18 progress 75 2011-09-28 08:15:21.968 [316:fac]: : operation 18 progress 83 2011-09-28 08:15:22.046 [316:fac]: : operation 18 progress 91 2011-09-28 08:15:22.125 [316:fac]: : operation 18 progress 100 2011-09-28 08:15:32.093 [316:fac]: : operation 46 progress -1 2011-09-28 08:15:32.093 [316:fac]: : operation 46 progress -1 2011-09-28 08:15:32.125 [316:fac]: : operation 19 progress -1 2011-09-28 08:15:42.515 [316:fac]: amai: AMAuthInstallBasebandSetParametersWithUpdaterOutput: baseband updater output: {type = mutable dict, count = 5, entries => 6 : {contents = "BcVersion"} = {value = +163, type = kCFNumberSInt64Type} 7 : {contents = "CertID"} = {value = +257, type = kCFNumberSInt64Type} 8 : {contents = "ChipSerialNo"} = {length = 12, capacity = 12, bytes = 0x415a8e97360504135a127c8c} 10 : {contents = "ChipID"} = {value = +80, type = kCFNumberSInt64Type} 12 : {contents = "done"} = {value = false} } 2011-09-28 08:15:42.515 [316:fac]: amai: AMAuthInstallBasebandSetParametersWithUpdaterOutput: manifest dict not found 2011-09-28 08:15:42.515 [316:fac]: requested restore behavior: Erase 2011-09-28 08:15:42.531 [316:fac]: amai: _AMAuthInstallBundleSetBasebandKeyHashes: setting (from build manifest): BbProvisioningManifestKeyHash = {length = 20, capacity = 20, bytes = 0x97ab34ac0693f5b03bfb726d4e2c254f14e270a1} 2011-09-28 08:15:42.562 [316:fac]: amai: AMAuthInstallDebugWriteObject: debug object written: C:\DOCUME~1\ahmad\LOCALS~1\Temp\Per9.tmp\amai\debug\tss-request.plist 2011-09-28 08:15:42.578 [316:fac]: amai: tss_submit_job: HttpQueryInfo returned 200 2011-09-28 08:15:42.578 [316:fac]: amai: AMAuthInstallRequestSendSync: received tss response (server version: 0.6.30-b2) 2011-09-28 08:15:42.578 [316:fac]: amai: AMAuthInstallDebugWriteObject: debug object written: C:\DOCUME~1\ahmad\LOCALS~1\Temp\Per9.tmp\amai\debug\tss-response.plist 2011-09-28 08:15:42.609 [316:fac]: amai: _AMAuthInstallBasebandStitchICE3Psi: response missing "BasebandFirmware" 2011-09-28 08:15:42.609 [316:fac]: amai: _StitchFirmware: failed to stitch "psi_ram.fls": Entry not found; continuing anyway 2011-09-28 08:15:42.625 [316:fac]: amai: _StitchFirmware: failed to stitch "ebl.fls": Entry not found; continuing anyway 2011-09-28 08:15:42.812 [316:fac]: amai: AMAuthInstallBasebandHandleUpdaterStatus: commandAccepted: NO 2011-09-28 08:15:42.812 [316:fac]: amai: AMAuthInstallBasebandHandleUpdaterStatus: outputDict: (null) 2011-09-28 08:15:42.828 [316:fac]: device returned AMR error 1013 2011-09-28 08:15:42.843 [316:fac]: ==== device restore output ==== ht part AppleEmbeddedI2CGyro::free .. why is someone calling free on me BSD root: md0, major 2, minor 0 com.apple.AppleFSCompressionTypeZlib kmod start com.apple.AppleFSCompressionTypeZlib load succeeded virtual bool AppleMobileFileIntegrity::start(IOService*): built Jul 20 2011 03:32:12 L2TP domain init L2TP domain init complete PPTP domain init AppleS5L8920XBasebandSPIController::loadConfiguration: NCLK Frequency 48857142, Prescaler 3 BasebandSPIDevice::exitLowPower: Invalid state inactive AppleSerialMultiplexer: adap::start: Frame size is 2048, Rx Buffer count is 16 AppleSerialMultiplexer: mux::start: created new mux (13) for spi-baseband with adapter BasebandSPIDevice AppleSerialMultiplexer: debugparams::init: Parsed flags "" ( 0 ) AppleSerialMultiplexer: !! mux::setPowerStateGated: Skipping power state change AppleS5L8900XSerial: Identified Serial Port on ARM Device=uart1 at 0x82600000(0xd20d5000) AppleS5L8900XSerial: Identified Serial Port on ARM Device=uart2 at 0x82700000(0xd20dd000) AppleS5L8900XSerial: Identified Serial Port on ARM Device=uart3 at 0x82800000(0xd2226000) AppleS5L8900XSerial: Identified Serial Port on ARM Device=uart6 at 0x82b00000(0xd2236000) AUD10::start: 0x82baf000, highland-park mIISNub: 0x802fcc80, sampleRate = 44100, ol=10, oi=12 AppleAP3GDL::probe found device with ID: 0xd3 AppleAP3GDL::handleStart _calibrationMatrix [68188 -275 -2036] [315 63821 467] [1273 -549 65949] _odr200HzSampleInterval = 4950 AppleAP3GDL::handleStart _calibrationInverseMatrix [62949 288 1941] [-300 67291 -485] [-1217 554 65084] AppleSynopsysOTGDevice::start : start finished AppleBaseband: inconsistent mux function setup (0 0 0 0 0 0) AppleMultitouchN1SPI: successfully started AppleRGBOUT: TVOUT device is detected virtual bool AppleCLCD::start_hardware(IOService*), clcdDitherCfg: 0x80000001 mIsDitherFor8Bits: 1 IOReturn AppleCLCD::set_ditherTable_state(bool), mIsDitherFor8Bits is true, no dynamic dither table. IOSurface: buffer allocation size is zero AppleM2ScalerCSCDriver: Added framebuffer device: AppleCLCD id: c7e5a000 AppleIOPFMI: Found Chip ID 0x3294E798 on FMI0:CE0 AppleIOPFMI: Found Chip ID 0x3294E798 on FMI0:CE1 AppleIOPFMI: Found Chip ID 0x3294E798 on FMI1:CE8 AppleIOPFMI: Found Chip ID 0x3294E798 on FMI1:CE9 This (0x82b5c000) Adding key channels (value 0x82cf3c00) to tree 0x82973b00 AUD10::start: 0x82baf000, highland-park mIICNub: 0x8297af00, mIISNub: 0x802fcc80, mSerialNub: 0x83113a00, sampleRate = 44100, ol=10, oi=12 AppleMultitouchN1SPI: detected HBPP. driver will be kept alive AppleD1815PMUPowerSource: AppleUSBCableDetect 1 AppleD1815PMUPowerSource: AppleUSBCableType USBHost AppleEmbeddedUSBArbitrator::handleUSBCableTypeChange : Connected to a USB host, entering device mode AppleSynopsysOTGDevice::handleUSBCableConnect cable connected, but don't have device configuration yet AppleNANDLegacyFTL started with IOFlashStoragePartition provider AppleNANDLegacyFTL located at physical nand block offset 16 metadata-whitening was found and it's set to 1 default-ftl-version was found and it's set to 1 diag-bits is supplied by AppleNANDFTL [FTL:MSG] Apple NAND Driver (AND) RW [FTL:MSG] FIL_Init [OK] [FTL:MSG] BUF_Init [OK] [FTL:MSG] FPart Init [OK] IOSDIOController::enumerateSlot(): Searching for SDIO device in slot: 0 IOSDIOController::enumerateSlot(): Found SDIO I/O device. Function count(1), memory(0) IOSDIOIoCardDevice::parseFn0CISTuple(): Device manufacturer ID 0x2d0, Product ID 0x4329 IOSDIOIoCardDevice::parseFn0CISTuple(): Manufacturer: "" IOSDIOIoCardDevice::parseFn0CISTuple(): Product: "" IOSDIOIoCardDevice::parseFn0CISTuple(): ProductInfo0: "s=B1" IOSDIOIoCardDevice::parseFn0CISTuple(): ProductInfo1: "P=N90 m=3.1 V=m" AppleBCMWLAN::init(): AppleBCMWLAN-84 Jul 9 2011 01:10:00 AppleBCMWLAN::init(): Starting with debug level: 4, debug flags: 00000000 AppleBCMWLAN::init(): AppleBCMWLAN-84 Jul 9 2011 01:10:00 AppleBCMWLAN::init(): Starting with debug level: 4, debug flags: 00000000 display-scale = 2 display-rotation = 0 found suitable IOMobileFramebuffer: AppleCLCD display: 640 x 960 found PTP interface 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: Jul 9 2011 01:12:10 init_waste AppleSynopsysOTGDevice::gated_registerFunction Register function AppleUSBMux AppleSynopsysOTGDevice::gated_registerFunction Register function AppleUSBEthernet AppleUSBEthernetDevice::start: Host MAC address = 26:ab:81:8a:5d:be AppleSynopsysOTGDevice::gated_registerFunction Register function PTP AppleSynopsysOTGDevice::gated_registerFunction all functions registered- we are ready to start usb stack AppleS5L8930XUSBPhy::powerUp : with_hsic: 0 AppleEmbeddedUSBArbitrator::enableDeviceClock : enable: 1, index: 0 AppleS5L8930XUSBPhy::enableDeviceMode : enable: 1 IOReturn AppleUSBDeviceMux::setPropertiesGated(OSObject*) setting debug level to 7 AppleSynopsysOTGDevice::handleUSBReset AppleSynopsysOTGDevice::handleUSBReset close(caller = 0xe327): remote port = 49152 close(caller = 0xe327): remote port = 49153 close(caller = 0xe327): remote port = 49154 close(caller = 0xe327): remote port = 49155 client protocol version 12 unable to open /usr/local/share/restore//options.n90.plist: No such file or directory *** UUID 3369741F-ABD3-5F4B-9615-4382DAC05794 *** Restore options: UUID => {contents = "3369741F-ABD3-5F4B-9615-4382DAC05794"} SystemPartitionSize => {value = +1024, type = kCFNumberSInt64Type} entering partition_nand_device device supports boot-from-NAND AppleUSBDeviceMux::handleConnectResult new session 0x836c8980 established 62078<-lo0->49152 62078<-usb->2560 AppleUSBDeviceMux::sessionUpcall socket is closed, session 0x836c8980 (62078<-lo0->49152 62078<-usb->2560) AppleUSBDeviceMux::handleConnectResult new session 0x84ae7180 established 62078<-lo0->49153 62078<-usb->2816 AppleIOPFMI: ERROR: First Failing CE: 0 AppleUSBDeviceMux::handleConnectResult new session 0x84ae9d00 established 62078<-lo0->49154 62078<-usb->3072 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleUSBDeviceMux::sessionUpcall socket is closed, session 0x84ae7180 (62078<-lo0->49153 62078<-usb->2816) AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 void AppleUSBDeviceMux::sendMuxSegment(BulkUSBMuxSession*) connection was closed (0x84ae9d00) AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleUSBDeviceMux::handleConnectResult new session 0x84ae9b80 established 62078<-lo0->49155 62078<-usb->3328 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 void AppleUSBDeviceMux::sendMuxSegment(BulkUSBMuxSession*) connection was closed (0x84ae9b80) AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 [FTL:MSG] VSVFL Register [OK] [FTL:MSG] VFL Init [OK] [WMR:INF] Formatting with metadata whitening [FTL:MSG] Calling VFL_FactoryReformat() AppleUSBDeviceMux::handleConnectResult new session 0x84ae9f80 established 62078<-lo0->49156 62078<-usb->3584 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 AppleIOPFMI: ERROR: First Failing CE: 0 [VFL:INF] BBT found for CS 0 [VFL:INF] BBT found for CS 1 unable to check is-bfn-partitioned property nand device is already partitioned entering wait_for_storage_device NAND driver status = format NAND driver status = format NAND driver status = format NAND driver status = format [VFL:INF] BBT found for CS 2 NAND driver status = format NAND driver status = format NAND driver status = format NAND driver status = format NAND driver status = format NAND driver status = format AppleBCMWLAN::handleIOKitBusyWatchdogTimeout(): Error, no successful firmware download after 60000 ms!! Giving up... [VFL:INF] BBT found for CS 3 NAND driver status = format NAND driver status = format NAND driver status = format NAND driver status = format NAND driver status = format NAND driver status = format NAND driver status = format [FTL:MSG] VFL_Format [OK] [FTL:MSG] YAFTL Register [OK] [FTL:MSG] FTL Init [OK] NAND driver status = format NAND driver status = format NAND driver status = format NAND driver status = format NAND driver status = format NAND driver status = format NAND driver status = recover [FTL:MSG] FTL Format [OK] [FTL:MSG] Write Signature OK 0x43313132 [FTL:MSG] Read back Signature OK [FTL:MSG] VFL_Open [OK] yaFTL::YAFTL_Open(l:3008): CXT is not valid . Performing full NAND R/O restore ... [FTL:MSG] FTL_Open [OK] [NAND] _borrowSpareBlocks:331 found 0 previously allocated NAND driver status = ready [NAND] _stressBlock:842 CE 2 Block 4097 PASSED [NAND] _stressBlock:842 CE 2 Block 4095 PASSED NAND driver status = ready [NAND] _stressBlock:842 CE 2 Block 4093 PASSED [NAND] _stressBlock:842 CE 2 Block 4091 PASSED [NAND] _stressBlock:842 CE 2 Block 4089 PASSED entering format_effaceable_storage waiting for matching IOKit service: {type = mutable dict, count = 1, entries => 2 : {contents = "IOProviderClass"} = {contents = "AppleEffaceableStorage"} } [NAND] _stressBlock:842 CE 2 Block 4087 PASSED AppleEmbeddedUSBArbitrator::_usbCableTypeNotificationGated : cableType has not changed AppleImage3NORAccess::start imageVersion: 3 effaceable storage formatted successfully entering ramrod_probe_media find_filesystem_partitions: storage=/dev/disk0 system= data= update= entering check_for_restore_log partition path has not been populated (partition may not exist) entering clean_NAND NAND format complete entering ramrod_probe_media find_filesystem_partitions: storage=/dev/disk0 system= data= update= entering format_storage_for_LwVM use-lwvm property not found entering create_filesystem_partitions 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/disk0 system=/dev/disk0s1 data=/dev/disk0s2s1 update= wipe entire partition: 1 (old = 0 new = 1024) block size for /dev/disk0s1: 8192 /sbin/newfs_hfs -s -v System -b 8192 -n a=8192,c=8192,e=8192 /dev/disk0s1 executing /sbin/newfs_hfs -s -v System -b 8192 -n a=8192,c=8192,e=8192 /dev/disk0s1 Initialized /dev/rdisk0s1 as a 1024 MB HFS Plus volume block size for /dev/disk0s2s1: 8192 /sbin/newfs_hfs -s -v Data -J -P -b 8192 -n a=8192,c=8192,e=8192 /dev/disk0s2s1 executing /sbin/newfs_hfs -s -v Data -J -P -b 8192 -n a=8192,c=8192,e=8192 /dev/disk0s2s1 Initialized /dev/rdisk0s2s1 as a 14 GB HFS Plus volume with a 8192k journal entering restore_images executing /usr/sbin/asr -source asr://localhost:12345 -target /dev/disk0s1 -erase -noprompt --chunkchecksum --puppetstrings ASR: asr: Unable to disable idle sleep - -536870199 ASR STATUS: start 228 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... ASR: done ASR STATUS: metadata ASR: Validating source... AppleUSBDeviceMux::handleConnectResult new session 0x84ae9d00 established 12345<-lo0->49159 12345<-usb->4352 ASR: Using Hardware AES ASR: Using Hardware AES ASR: Using Hardware AES ASR: done ASR: Using Hardware AES ASR: Warning: You may not be able to start up a computer with the target volume. ASR: Retrieving scan information...done ASR: Validating sizes...done ASR STATUS: restore 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% AppleUSBDeviceMux::sessionUpcall socket is closed, session 0x84ae9d00 (12345<-lo0->49159 12345<-usb->4352) ASR RESTORE PROGRESS: 100% ASR: Copied 860258816 bytes in 75.53 seconds, 11123.16 KiB/s ASR STATUS: verify 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 860258816 bytes in 22.64 seconds, 37113.61 KiB/s ASR STATUS: finish entering mount_filesystems executing /sbin/fsck_hfs -fy /dev/disk0s1 ** /dev/rdisk0s1 Executing fsck_hfs (version diskdev_cmds-528~307). ** Checking non-journaled HFS Plus Volume. ** Detected a case-sensitive volume. The volume name is Durango8L1.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. ** The volume Durango8L1.N90OS appears to be OK. executing /sbin/mount -t hfs /dev/disk0s1 /mnt1 executing /sbin/fsck_hfs -fy /dev/disk0s2s1 ** /dev/rdisk0s2s1 Executing fsck_hfs (version diskdev_cmds-528~307). ** Checking Journaled HFS Plus volume. ** Detected a case-sensitive volume. The volume name is Data ** Checking extents overflow file. Unused node is not erased (node = 2) ** Checking catalog file. Unused node is not erased (node = 2) ** Checking multi-linked files. ** Checking catalog hierarchy. ** Checking extended attributes file. Unused node is not erased (node = 2) ** Checking volume bitmap. ** Checking volume information. ** Repairing volume. ** Rechecking volume. ** 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. ** The volume Data was repaired successfully. executing /sbin/mount -t hfs /dev/disk0s2s1 /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 clear_persistent_boot_args executing /usr/sbin/nvram boot-args= entering update_device_firmware entering update_iBoot write_image3_data: flashing LLB data (length = 0x18984) write_image3_data: flashing NOR data (length = 0x36984) write_image3_data: flashing NOR data (length = 0xecc4) write_image3_data: flashing NOR data (length = 0x3b44) write_image3_data: flashing NOR data (length = 0x1fa44) write_image3_data: flashing NOR data (length = 0x29ac4) write_image3_data: flashing NOR data (length = 0x9c44) write_image3_data: flashing NOR data (length = 0x1cc4) write_image3_data: flashing NOR data (length = 0x1b84) write_image3_data: flashing NOR data (length = 0x2e44) write_image3_data: flashing NOR data (length = 0x9104) write_image3_data: flashing NOR data (length = 0x9ffc4) 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 perform_baseband_update: registering for progress notifications perform_baseband_update: fusing disabled perform_baseband_update: Got imei svn: 11 baseband updater options = {type = mutable dict, count = 6, entries => 0 : {contents = "EraseEFS"} = {value = true} 1 : {contents = "IMEISwVersion"} = {value = +11, type = kCFNumberSInt32Type} 2 : {contents = "autoSetupEnv"} = {value = false} 3 : {contents = "forceUpdate"} = {value = true} 5 : {contents = "disablePing"} = {value = true} 6 : {contents = "DisallowFusiing"} = {value = true} } perform_baseband_update: querying baseband info bbupdater: Opening device path /dev/cu.debug, using baud 115200 bbupdater: Configuring Hardware Mux...bbupdater: OK bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: [13.01]::: Polled 0 bbupdater: Opening device path /dev/cu.debug, using baud 115200 bbupdater: [13.01]::: Beginning boot bbupdater: ------------------------------------------------------------------------------- bbupdater: BEGINNING BOOT bbupdater: ------------------------------------------------------------------------------- bbupdater: Sending boot code...bbupdater: [13.00]::: powercycle bbupdater: - CertID: 0x101 bbupdater: OK bbupdater: [13.01]::: Boot OK bbupdater: [02.00]::: ChipInfo: snum: 0x415A8E97360504135A127C8C, chipid: 0x50, goldcertid: 0x101, nonce: 0x perform_baseband_update: 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 = 0x415a8e97360504135a127c8c} } perform_baseband_update: requesting data from host perform_baseband_update: received data from host (22 bytes) perform_baseband_update: performing next update stage bbupdater: Validating parameters...bbupdater: OK bbupdater: !!! bbupdater: [13.-1]::: Exception at :0: - kBBUReturnError(1)/7: unable to locate firmware file bbupdater: bbupdater: !!! bbupdater: Exception at :0: - kBBUReturnError(1)/7: unable to locate firmware file send_bbupdate_status: sending baseband update status to host perform_baseband_update: failed to perform next stage perform_baseband_update: bbupdater error: Exception at :0: - kBBUReturnError(1)/7: unable to locate firmware file attempting to dump restore log writing log file: /mnt1/restore.log ==== end of device restore output ==== 2011-09-28 08:15:42.843 [316:fac]: AMRAuthInstallDeletePersonalizedBundle 2011-09-28 08:15:43.453 [316:fac]: removed personalized bundle 2011-09-28 08:15:43.453 [316:fac]: : Restore failed (result = 1013) 2011-09-28 08:15:43.656 [316:c50]: iTunes: Restore error 1013 2011-09-28 08:15:46.718 [316:ec0]: WinAMRestore::OnInterfaceArrival: \\?\USB#VID_05AC&PID_1227#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#f2411396 2011-09-28 08:15:46.718 [316:ec0]: WinAMRestore::AddAppleDeviceToDeviceList: \\?\USB#VID_05AC&PID_1227#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#f2411396 2011-09-28 08:15:46.765 [316:ec0]: AppleDevice::EnumerateHubPorts: DoesDriverNameMatchDeviceID failed 2011-09-28 08:15:46.765 [316:ec0]: WinAMRestore::AddAppleDeviceToDeviceList, old disconnected device connects again 2011-09-28 08:15:46.765 [316:ec0]: AppleDevice::SetNotification: CONNECT, interface type: 1, id: \\?\USB#VID_05AC&PID_1227#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#f2411396, inst: 0xf7d8c98 2011-09-28 08:15:46.765 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x0f7d8c98, notify: 1, connected: 0 2011-09-28 08:15:46.765 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x115961d8, notify: 2, connected: 0 2011-09-28 08:15:46.765 [316:ec0]: AppleDevice::NotifyConnect: Device type: 1, Interfaces total: 1, arrived: 1 2011-09-28 08:15:46.765 [316:ec0]: AppleDevice::NotifyConnect: DFU \\?\USB#VID_05AC&PID_1227#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#f2411396, inst: 0xf7d8c98 2011-09-28 08:15:46.781 [316:c50]: AppleDevice::GetDeviceID: failed for iBoot 2011-09-28 08:15:46.781 [316:c50]: _AMRUSBDeviceGetManufacturerStringIndex: unable to get device descriptor: 2001 2011-09-28 08:15:46.781 [316:c50]: unable to get manufacturer string index: 2001 2011-09-28 08:15:46.781 [316:c50]: AppleDevice::GetDeviceID: failed for iBoot 2011-09-28 08:15:46.781 [316:c50]: _AMRUSBDeviceGetManufacturerStringIndex: unable to get device descriptor: 2001 2011-09-28 08:15:46.781 [316:c50]: unable to get manufacturer string index: 2001 2011-09-28 08:15:46.781 [316:c50]: iTunes: SCEP 1 2011-09-28 08:15:48.296 [316:ec0]: WinAMRestore::OnInterfaceRemoval: \\?\USB#VID_05AC&PID_1227#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#f2411396 2011-09-28 08:15:48.296 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x0f7d8c98, notify: 2, connected: 1 2011-09-28 08:15:48.296 [316:ec0]: AppleDevice::NotifyDisconnect: DFU \\?\USB#VID_05AC&PID_1227#{B8085869-FEB9-404B-8CB1-1E5C14FA8C54}\0000#f2411396, inst: 0xf7d8c98 2011-09-28 08:15:48.312 [316:ec0]: _AMDFUModeDeviceFinalize: 116421A8 2011-09-28 08:15:48.312 [316:ec0]: WinAMRestore::ProcessDevNodesChanges: device: 0x115961d8, notify: 2, connected: 0