2013-01-30 23:42:05
Cafe OS SDK Version 2.13.01 Build 68939 Branch sdk_2_13
Espresso:       0x0000700100000201
Latte:          0x0000409f
System mode:    0x100000
Boot flags:     0x84210000
Security Level: 0x1e
Key Type:       0xfffffffc
Prod Version:   0x5
Serial:         702985384
Model:          WUP-101(02)
Prod Area:      0x2
Game Area:      0x2
5GHz Code:      0x7
BOOT1:          0x20b9
BT:             0x205
DRH:            0x190c





----------- Starting System Log -----------
00:00:00:245: USB Trace: Activating root hubs @ uptime 0.245 s with options 0x40000.
00:00:00:255: UHS0 Trace: DevFsm(EHCI-0/L0/P0): Creating device, speed=HIGH.
00:00:00:257: UHS0 Trace: DevFsm(OHCI-0:0/L0/P0): Creating device, speed=FULL.
00:00:00:257: USB Info: UhsServerAddHc 0 OK.
00:00:00:266: UHS0 Trace: DevFsm(EHCI-1/L0/P0): Creating device, speed=HIGH.
00:00:00:268: UHS0 Trace: DevFsm(OHCI-1:0/L0/P0): Creating device, speed=FULL.
00:00:00:268: USB Info: UhsServerAddHc 1 OK.
00:00:00:272: TEST Info: crossProcessHeap OK.
00:00:00:273: AUXIL Info: crossProcessHeap OK.
00:00:00:282: UHS0 Trace: Powering on root hub group 0.
00:00:00:296: UHS0 Trace: Powering on root hub group 1.
00:00:00:297: AHCI_MGR Trace: Turning on drive power.
00:00:00:302: AHCI_MGR Trace: Initializing phy.
00:00:00:302: AHCI_DRV Trace: Initiating cold open.
00:00:00:302: AHCI_DRV Trace: Resetting HBA.
00:00:00:306: AUXIL Info: Net OK.
00:00:00:352: AHCI_DRV Trace: HBA Reset OK.
00:00:00:352: AHCI_DRV Trace: link ready, speed is GEN 1.
00:00:00:413: ISFS: FAT INFO (ch WUP): slot 19 / seq 92565 (Each FAT slot has been updated 1446 times in avr)
00:00:00:413: FSA: [uptime 0.413 s]: Attached volume to slc01 (isfs): Capacity 511 MB, 262016 logical blocks of size 2048 B.
00:00:00:503: ISFS: FAT INFO (ch RVL Compat): slot 0 / seq 65954 (Each FAT slot has been updated 4122 times in avr)
00:00:00:503: FSA: [uptime 0.503 s]: Attached volume to slccmpt01 (isfs): Capacity 511 MB, 261632 logical blocks of size 2048 B.
00:00:00:508: SCFM:Start init. BUILD_TIME:[19:23:46]
00:00:00:511: SCFM:FSAInit
00:00:00:511: SCFM:AddClient
00:00:00:512: SCFM:scfmMountSlc
00:00:00:512: SCFM:scfmLoad
00:00:00:512: SCFM:Done init.
00:00:00:513: PCFS: Disabled because we are in PROD mode.
00:00:00:513: MCP: booting from NAND
00:00:00:513: MCP: Boot PM flags - PON_COLDBOOT PON_POWER_BTN PON_SMC DDR_SREFRESH 
00:00:00:532: MCP: Cafe OS SDK Version 2.13.01 Build 68939 Branch sdk_2_13
00:00:00:532: MCP: Booting on Espresso (0x0000700100000201), Latte (0x25100028), RTC (0x01)
00:00:00:532: MCP: Platform - boardType(CF), boardRevision(11), devicePresence(0x00000000), sataDevice(3), consoleType(1)
00:00:00:534: ISFS: FAT block entries check start
00:00:00:538: ISFS: FAT block entries check finished.
00:00:00:544: FSA: [uptime 0.544 s]: Attached volume to ramdisk01 (raw): Capacity 127 MB, 131070 logical blocks of size 1024 B.
00:00:00:548: MCP: Formatting Device ramdisk to wfs
00:00:00:692: FSA: [uptime 0.692 s]: Attached volume to ramdisk01 (wfs): Capacity 127 MB, 131070 logical blocks of size 1024 B.
00:00:00:693: MCP: Format Complete
00:00:00:719: FSA: [uptime 0.719 s]: Attached volume to mlc01 (wfs): Capacity 29760 MB, 60948480 logical blocks of size 512 B.
00:00:00:832: ISFS: FAT block entries check start
00:00:00:836: ISFS: FAT block entries check finished.
00:00:00:907: AHCI_DRV Trace: device ready.
00:00:00:907: AHCI_DRV Trace: port operational.
00:00:00:912: ODM: ODM: Successfully configured preload cache
00:00:00:962: MCP: File loading from ramdisk cache is enabled
00:00:00:974: MCP: life time number of fatal errors - 64
00:00:00:974: MCP: storing previous logs as '/vol/system/logs/65.log'
00:00:01:750: MCP: Finished storing previous logs as '/vol/system/logs/65.dsc'
00:00:01:750: MCP: Master title 0005000010198c00 os 000500101000400a from mlc01 flags 0004
00:00:01:757: MCP: no valid title list found, rebuilding...
00:00:01:763: MCP: os version 10 NDEBUG (0x000500101000400a)
00:00:01:764: MCP: FSA volume on device /dev/mlc01 (wfs) attached @ uptime 1.764 s.
00:00:01:780: MCP: found master title 5000010198c00 on mlc01 at 1.780 s
00:00:01:784: MCP: found master title 5000010198c00 on mlc01 at 1.783 s
00:00:01:784: MCP: FSA volume on device /dev/ramdisk01 (wfs) attached @ uptime 1.784 s.
00:00:01:784: MCP: Titles scanned (dev_state 0006) on device /dev/ramdisk01 (wfs) @ uptime 1.784 s.
00:00:01:784: MCP: FSA volume on device /dev/slc01 (isfs) attached @ uptime 1.784 s.
00:00:01:785: MCP: FSA volume on device /dev/slccmpt01 (isfs) attached @ uptime 1.785 s.
00:00:01:785: MCP: Titles scanned (dev_state 0006) on device /dev/slccmpt01 (isfs) @ uptime 1.785 s.
00:00:01:875: ISFS: fs_ops.c(3733)Can not change the owner Id of a non-empty file
00:00:01:875: ISFS: fs_ops.c(6719)Could not set attribute, rc=-524312
00:00:02:044: MCP: Titles scanned (dev_state 000e) on device /dev/slc01 (isfs) @ uptime 2.044 s.
00:00:02:137: MCP: ECO mode is disabled
00:00:02:137: [+-* DK is ready for console input *-+]
00:00:02:137: MCP: syslog mask is set to 0xffffffff
00:00:02:142: W2C: Does not need W2C-reflection
00:00:02:450: UHS1 Trace: DevFsm(EHCI-2/L0/P0): Creating device, speed=HIGH.
00:00:02:452: UHS1 Trace: DevFsm(OHCI-2:0/L0/P0): Creating device, speed=FULL.
00:00:02:452: CCR_MAIN: activating root hub.
00:00:02:503: FS_MAIN: UMS Server Created.
00:00:02:505: AUXIL Info: resumed.
00:00:02:751: BT: BTA/BTE/STACK now operational 
00:00:02:755: UHS1 Trace: DevFsm(EHCI-2/L1/P1): Creating device, speed=HIGH.
00:00:02:755: UHS1 Trace: DevFsm: There are now a total of 1 connected devices.
00:00:02:787: UHS1 Trace: CltIfFsm(V057e|P0341|IF0): Device EHCI-2/L1/P1/A01 entering probing state @ uptime 2.787 s.
00:00:02:787: UHS1 Trace: CltIfFsm(V057e|P0341|IF1): Device EHCI-2/L1/P1/A01 entering probing state @ uptime 2.787 s.
00:00:02:787: UHS1 Trace: CltIfFsm(V057e|P0341|IF2): Device EHCI-2/L1/P1/A01 entering probing state @ uptime 2.787 s.
00:00:02:788: UHS1 Trace: CltIfFsm(V057e|P0341|IF3): Device EHCI-2/L1/P1/A01 entering probing state @ uptime 2.788 s.
00:00:02:788: UHS1 Trace: CltIfFsm(V057e|P0341|IF4): Device EHCI-2/L1/P1/A01 entering probing state @ uptime 2.788 s.
00:00:02:788: UHS1 Trace: CltIfFsm(V057e|P0341|IF5): Device EHCI-2/L1/P1/A01 entering probing state @ uptime 2.788 s.
00:00:02:788: UHS1 Trace: CltIfFsm(V057e|P0341|IF6): Device EHCI-2/L1/P1/A01 entering probing state @ uptime 2.788 s.
00:00:02:789: UHS1 Trace: CltIfFsm(V057e|P0341|IF7): Device EHCI-2/L1/P1/A01 entering probing state @ uptime 2.788 s.
00:00:02:789: UHS1 Trace: CltIfFsm(V057e|P0341|IF8): Device EHCI-2/L1/P1/A01 entering probing state @ uptime 2.789 s.
00:00:02:789: UHS1 Trace: CltIfFsm(V057e|P0341|IF9): Device EHCI-2/L1/P1/A01 entering probing state @ uptime 2.789 s.
00:00:02:789: UHS1 Trace: CltIfFsm(V057e|P0341|IF10): Device EHCI-2/L1/P1/A01 entering probing state @ uptime 2.789 s.
00:00:02:789: UHS1 Trace: CltIfFsm(V057e|P0341|IF11): Device EHCI-2/L1/P1/A01 entering probing state @ uptime 2.789 s.
00:00:02:790: UHS1 Trace: CltIfFsm(V057e|P0341|IF0): Acquired by client in pid 6.
00:00:02:790: UHS1 Trace: CltIfFsm(V057e|P0341|IF0): Enable endpoints 0x00080000.
00:00:02:830: __ccr_hid_attach_add_interface: idx 0 ep 3
00:00:02:830: UHS1 Trace: CltIfFsm(V057e|P0341|IF1): Acquired by client in pid 6.
00:00:02:831: UHS1 Trace: CltIfFsm(V057e|P0341|IF1): Enable endpoints 0x00100000.
00:00:02:870: __ccr_hid_attach_add_interface: idx 1 ep 4
00:00:02:871: UHS1 Trace: CltIfFsm(V057e|P0341|IF2): Acquired by client in pid 6.
00:00:02:871: UHS1 Trace: CltIfFsm(V057e|P0341|IF3): Acquired by client in pid 6.
00:00:02:872: UHS1 Trace: CltIfFsm(V057e|P0341|IF3): Activating bAlternateSetting 0.
00:00:02:874: UHS1 Trace: CltIfFsm(V057e|P0341|IF4): Acquired by client in pid 6.
00:00:02:875: UHS1 Trace: CltIfFsm(V057e|P0341|IF5): Acquired by client in pid 6.
00:00:02:875: UHS1 Trace: CltIfFsm(V057e|P0341|IF5): Activating bAlternateSetting 0.
00:00:02:877: UHS1 Trace: CltIfFsm(V057e|P0341|IF7): Acquired by client in pid 6.
00:00:02:877: UHS1 Trace: CltIfFsm(V057e|P0341|IF7): Enable endpoints 0x08001000.
00:00:02:878: UHS1 Trace: CltIfFsm(V057e|P0341|IF8): Acquired by client in pid 6.
00:00:02:879: UHS1 Trace: CltIfFsm(V057e|P0341|IF9): Acquired by client in pid 6.
00:00:02:879: UHS1 Trace: CltIfFsm(V057e|P0341|IF9): Activating bAlternateSetting 0.
00:00:02:881: UHS1 Trace: CltIfFsm(V057e|P0341|IF10): Acquired by client in pid 6.
00:00:02:882: UHS1 Trace: CltIfFsm(V057e|P0341|IF11): Acquired by client in pid 6.
00:00:02:882: UHS1 Trace: CltIfFsm(V057e|P0341|IF11): Activating bAlternateSetting 0.
00:00:02:884: DRH chip revision 00000040
00:00:02:884: DRH firmware version 190c0117
00:00:02:884: DRH cdc version 00080000
00:00:02:884: DRH wifi module id a8e502d0
00:00:02:885: DRH SPL id 00001c58
00:00:03:015: NET: Applied interface MTU (wl0:1400 eth0:1400).
00:00:03:031: USBETH Trace: Created interface 100.
00:00:03:081: MCP: boot1 version 2.11.09
00;00;02;568: COSREPORT: Set level:1 from syslog level
00;00;02;568: 
----------------------------------------------- BPERF: (0/2568305) PPC Init.
00;00;02;568: Cafe OS SDK Version 2.13.01 Build 68939 Branch sdk_2_13
00;00;02;568: PPC NDEBUG Kernel Build date - Jul 28 2015 19:55:58
00;00;02;568: BUILT AS OS_VERSION_MAJOR 000500101000800A MINOR 0x3D56
00;00;02;568: core=1 COLD BOOT
00;00;02;568: SOFT INTERRUPT MODE
00;00;02;568: -----------------------------------------------

00;00;02;568: KiCblRegisterBlocks() completed with status 0:-4.
00;00;02;568: PROD Mode - don't even try to load kdebug.elf
00;00;02;569: title id: 5000010198c00, app type 80000000
00;00;02;569: 
00;00;02;569: ---------------------------------------
00;00;02;569: OS VERSION ID     0x000500101000400A
00;00;02;569: MASTER TITLE ON   FLASH
00;00;02;569: ---------------------------------------
00;00;02;569: 
00:00:03:124: MCP: loaded and parsed 'preload.txt' at '/vol/system/title/00050010/1000400a/code'
00:00:03:140: MCP: Wake 0 signal
00:00:03:293: ACT: Current account is #1.
00;00;03;153: DIAG:+KiRPLLoaderSetup
00;00;03;154: DIAG:-KiRPLLoaderSetup: before no-return call to KiLoadContext (0xEFE09400) (0x01003FFC)
00:00:03:434: DRH using runtime generated WLAN data
00:00:03:434: Setting DRH to Normal mode
00:00:03:435: setting DRH system time to 23:42:07
00:00:03:436: sending DRC WOWL wake
00:00:03:518: WUD_BCMFWCheck ==> chipname 20702A1 build 517 from SERIAL FLASH
00:00:03:520: BT: BTRM now Active (WUD Operational)
00:00:03:576: MCP: Titles scanned (dev_state 0004) on device /dev/mlc01 (wfs) @ uptime 3.576 s.
00:00:03:604: MCP: Titles scanned (dev_state 000c) on device /dev/mlc01 (wfs) @ uptime 3.604 s.
00:00:03:606: MCP: Titles scanned (dev_state 0006) on device /dev/mlc01 (wfs) @ uptime 3.606 s.
00;00;04;035: DIAG:+KiProcess_FinishInitAndPreload
00;00;04;035: KiCblRegisterBlocks() completed with status 0:-4.
00;00;04;052: Kernel is loading shared data...
00:00:04:284: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:04:285: [WD]>>>> Linkup msg from Net
00:00:04:297: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:04:301: NET: Change admin state (0 -> 2)(iface:1 link:2)
00:00:04:301: IFNET_ETH: Configuring link adminState to DOWN.
00:00:04:302: NET: Change admin state (1 -> 1)(iface:0 link:3)
00:00:04:407: [WD]>>>> Linkup msg from Net
00:00:04:416: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:04:639: FSA: ### DATA CORRUPTION ERROR ###, dev:mlc01, err:-1245211, cmd:11, path:(null)
00:00:04:639: failed to read file /vol/storage_mlc01/sys/title/0005001b/10042400/content/CafeCn.ttf, err -196635
00;00;04;519: ***LoadShared - WaitLoadComplete(0,8388608) failed with error -196635 on file "CafeCn.ttf".
00:00:05:348: ccr_admin_wowl_wake_drc() returned 0
00:00:05:422: NET: Change admin state (1 -> 2)(iface:0 link:2)
00:00:05:513: MCP: Heartbeat Enabled
00;00;05;393: DIAG:-KiProcess_FinishInitAndPreload: before no-return call to KiInitKernelFinish
00;00;05;393: DIAG:+KiInitKernelFinish
00;00;05;393: VVVVVVV----- PPC Kernel Waiting -----VVVVVVV
00;00;05;393: ^^^^^^^----- PPC Kernel Release -----^^^^^^^
00;00;05;393: DIAG:-KiInitKernelFinish: before no-return call to KiProcess_Launch
00;00;05;393: 
--------------------
00;00;05;393: PROCESS  1 STARTING
00;00;05;394: SYSLOG_LEVEL 1: Warnings Enabled
00;00;05;394: UserMode Core & Thread Initialization (3 Cores)
00;00;05;394:   Core 1 Complete, Default Thread 0x100457E0
00;00;05;395:   Core 0 Complete, MSR 0x00005072, Default Thread 0x10045140
00;00;05;396:   Core 2 Complete, MSR 0x00005072, Default Thread 0x10045E80
00;00;05;397: Clearing Process MEM2 Arena...
00;00;05;410: MEM2 Arena clear complete, size 23 MB, 13ms
00;00;05;436: UserMode OS library build date: Jul 28 2015 19:57:03
00;00;05;436: __AppFlags: 0x00000200

00;00;05;436: OSBlockThreadsOnExit default for sdk version 21301
00;00;05;619: OSSetting_SynchronousPrinting DISABLED
00;00;05;623: OSSetting_CrashDumpType 0, MINI dump after crash
00;00;05;626: TCL(1): init DONE
00;00;05;804: VPADInit() depricated, no need to call
00;00;05;804: VPAD Init end, BPERF: (0/5924652) Title PID=1
00;00;05;946: ROOT: Read restart value 0
00;00;05;947: ROOT: Read audio relaunch value 0
00;00;05;947: ROOT: OSID 0x000500101000400A
00;00;06;111: Info: Calculating cold boot font CRCs on core:0
00;00;06;111: *** KeGetSharedArea() - area not loaded.
00;00;06;111: Error: Root could not get shared font data for font 0
00;00;06;111: WPAD: Master Init
00;00;06;111: VPADInit() depricated, no need to call
COSREPORT: Using syslog level:1
COSREPORT: Set upid_mask:ffffffffffffffff module_mask:ffffffffffffffff level:1
00;00;06;191: TV mode before transition = 0
00;00;06;191: DRC mode before transition = 1
00;00;06;191: ROOT: Got audio buffer from foreground bucket @ e2c00000 len = 9437184
00;00;06;249: 


Querying BSP for Hardware Information...
00;00;06;249: 	It is not Holly/Bolly-Wood
00;00;06;249: 	It is Latte A2x or later
00;00;06;249: using DSP memory parameters:
00;00;06;249:     DROM_BASE: 0x3000
00;00;06;249:     IRAM_SIZE: 0x2000
00;00;06;249:     DRAM_SIZE: 0x3000
00:00:08:282: UHS0 Trace: HubPort(EHCI-1/L0/P0/A00)-P2: Device is not high speed, releasing port @ uptime 8.282 s.
00:00:08:424: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:08:424: [WD]>>>> Linkup msg from Net
00:00:08:432: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:08:534: UHS0 Trace: DevFsm(OHCI-1:0/L1/P2): Creating device, speed=FULL.
00:00:08:535: UHS0 Trace: DevFsm: There are now a total of 1 connected devices.
00:00:09:108: UHS0 Trace: DevFsm(OHCI-1:0/L1/P2/A01): Final teardown.
00:00:09:109: UHS0 Error: UhsFreeMem(pMem=0x102c0480) failed with error -4.
00:00:09:109: UHS0 Error: file uhs_device.c, function UhsDeviceHandleTeardown, line 432, status 0:-4, info 271320192(0x102c0480)
00:00:09:109: UHS0 Error: UhsFreeMem(pMem=0x102c0540) failed with error -4.
00:00:09:109: UHS0 Error: file uhs_device.c, function UhsDeviceHandleTeardown, line 432, status 0:-4, info 271320384(0x102c0540)
00:00:09:109: UHS0 Error: UhsFreeMem(pMem=0x102c0680) failed with error -4.
00:00:09:109: UHS0 Error: file uhs_device.c, function UhsDeviceHandleTeardown, line 432, status 0:-4, info 271320704(0x102c0680)
00:00:09:302: IFNET_ETH: Link DOWN.
00:00:09:437: NET: Change admin state (1 -> 2)(iface:0 link:2)
00:00:09:780: UHS0 Trace: HubPort(EHCI-1/L0/P0/A00)-P2: Device is not high speed, releasing port @ uptime 9.780 s.
00:00:10:033: UHS0 Trace: DevFsm(OHCI-1:0/L1/P2): Creating device, speed=FULL.
00:00:10:033: UHS0 Trace: DevFsm: There are now a total of 1 connected devices.
00:00:10:391: UHS0 Trace: DevFsm(OHCI-1:0/L1/P2/A02): Final teardown.
00:00:11:063: UHS0 Trace: HubPort(EHCI-1/L0/P0/A00)-P2: Device is not high speed, releasing port @ uptime 11.063 s.
00:00:11:316: UHS0 Trace: DevFsm(OHCI-1:0/L1/P2): Creating device, speed=FULL.
00:00:11:316: UHS0 Trace: DevFsm: There are now a total of 1 connected devices.
