2021-02-28 09:10:37
Cafe OS SDK Version 2.13.01 Build 68939 Branch sdk_2_13
Espresso:       0x0000700100000201
Latte:          0x0000409f
System mode:    0x100000
Boot flags:     0x84010000
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
+0x000500301001110a:0x00000064
+0x000500301001010a:0x000000c5
+0x0005000010198c00:0x00000000
-0x0005000010198c00:0x00000000
+0x0005001010040100:0x000000d5
d boot processing font CRCs on core:1
00:00:06:429: MCP: PID 14 requested to disable power transitions
00;00;06;309: DIAG:Issued KiPrepareTitleAsync(0x000500301001110A)
00:00:06:588: MCP: loaded and parsed 'preload.txt' at '/vol/storage_mlc01/sys/title/00050030/1001110a/code'
00:00:06:588: MCP: Preparing error.rpx
00:00:06:590: MCP: Mapping /vol/content to /vol/storage_mlc01/sys/title/00050030/1001110a/content
00;00;06;469: DIAG:Result PrepareTitle(0x000500301001110A) came back with cosxml(0x000500301001110A)
00;00;06;471: TitleId 0x000500301001110A Version 0x00000064 Built On SDK 21204
00:00:07:242: UMS Trace: Dev(0) - WaitReady: not yet ready with code=0x70, sense 0x2/0x4/0x1.
00:00:08:092: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:08:092: [WD]>>>> Linkup msg from Net
00:00:08:101: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:08:246: UMS Trace: Dev(0) - WaitReady: not yet ready with code=0x70, sense 0x2/0x4/0x1.
00:00:08:963: IFNET_ETH: Link DOWN.
00:00:09:105: NET: Change admin state (1 -> 2)(iface:0 link:2)
00:00:09:250: UMS Trace: Dev(0) - IdentifyCapacity: block size 512(0x200), max LBA 3906963455(0xe8df87ff).
00:00:09:252: UMS Trace: Dev(0) - IdentifyMode: medium type code=0x00, write protect=0.
00:00:09:326: FSA: [uptime 9.326 s]: Attached volume to usb01 (wfs): Capacity 1907697 MB, 3906963456 logical blocks of size 512 B.
00:00:09:326: MCP: FSA volume on device /dev/usb01 (wfs) attached @ uptime 9.326 s.
00;00;09;574: ------------------PROCESS 1 LOST FOREGROUND--------------------
00;00;09;575: DIAG: PhysAlloc(6, 0x00800000, 0x00000000, 0x00000000, 0x00140000, 0x00000001)
00;00;09;575: DIAG: InitAddrSpace
00;00;09;575: DIAG: Switch(New)
00;00;09;575: DIAG:+Loader(1, 6)
00;00;09;575: DIAG:+KiRPLLoaderSetup
00;00;09;575: DIAG:-KiRPLLoaderSetup: before no-return call to KiLoadContext (0xEFE09400) (0x01003FFC)
00;00;09;575: Title Loc is 0
00:00:09:776: MCP: PID 14 requested to enable power transitions
00;00;09;655: DIAG:-Loader(1, 6)
00;00;09;655: DIAG: SetFG(6)
00;00;09;655: KiCblRegisterBlocks() completed with status 0:-4.
00;00;09;655: ------------------PROCESS 6 GAIN FOREGROUND--------------------
00;00;09;657: SYSLOG_LEVEL 1: Warnings Enabled
00;00;09;657: UserMode Core & Thread Initialization (3 Cores)
00;00;09;657:   Core 1 Complete, Default Thread 0x100457E0
00;00;09;658:   Core 0 Complete, MSR 0x00005072, Default Thread 0x10045140
00;00;09;660:   Core 2 Complete, MSR 0x00005072, Default Thread 0x10045E80
00;00;09;682: UserMode OS library build date: Jul 28 2015 19:57:03
00;00;09;682: __AppFlags: 0x00002200

00;00;09;683: OSBlockThreadsOnExit default for sdk version 21204
00:00:09:840: MCP: PID 14 requested to disable power transitions
00;00;09;718: ------------------PROCESS 6 LOST FOREGROUND--------------------
00;00;09;719: KiCblRegisterBlocks() completed with status 0:-4.
00;00;09;719: ------------------PROCESS 1 GAIN FOREGROUND--------------------
00;00;09;720: DIAG:Issued KiPrepareTitleAsync(0x000500301001010A)
00:00:10:325: MCP: loaded and parsed 'preload.txt' at '/vol/storage_mlc01/sys/title/00050030/1001010a/code'
00:00:10:325: MCP: Preparing hbm.rpx
00:00:10:329: MCP: Mapping /vol/content to /vol/storage_mlc01/sys/title/00050030/1001010a/content
00;00;10;208: DIAG:Result PrepareTitle(0x000500301001010A) came back with cosxml(0x000500301001010A)
00;00;10;214: TitleId 0x000500301001010A Version 0x000000C5 Built On SDK 21211
00;00;10;214: ------------------PROCESS 1 LOST FOREGROUND--------------------
00;00;10;215: DIAG: PhysAlloc(5, 0x08000000, 0x00000000, 0x00000000, 0x02000000, 0x00000001)
00;00;10;215: DIAG: InitAddrSpace
00;00;10;215: DIAG: Switch(New)
00;00;10;215: DIAG:+Loader(1, 5)
00;00;10;215: DIAG:+KiRPLLoaderSetup
00;00;10;215: DIAG:-KiRPLLoaderSetup: before no-return call to KiLoadContext (0xEFE09400) (0x01003FFC)
00;00;10;215: Title Loc is 0
00:00:10:543: MCP: PID 14 requested to enable power transitions
00;00;10;423: DIAG:-Loader(1, 5)
00;00;10;423: DIAG: SetFG(5)
00;00;10;423: KiCblRegisterBlocks() completed with status 0:-4.
00;00;10;423: ------------------PROCESS 5 GAIN FOREGROUND--------------------
00;00;10;424: SYSLOG_LEVEL 1: Warnings Enabled
00;00;10;425: UserMode Core & Thread Initialization (3 Cores)
00;00;10;425:   Core 1 Complete, Default Thread 0x100457E0
00;00;10;426:   Core 0 Complete, MSR 0x00005072, Default Thread 0x10045140
00;00;10;428:   Core 2 Complete, MSR 0x00005072, Default Thread 0x10045E80
00;00;10;450: UserMode OS library build date: Jul 28 2015 19:57:03
00;00;10;450: __AppFlags: 0x00000200

00;00;10;451: OSBlockThreadsOnExit default for sdk version 21211
00;00;10;785: SYSAPP: RPL Load
00;00;10;810: OSSetting_SynchronousPrinting DISABLED
00;00;10;815: OSSetting_CrashDumpType 0, MINI dump after crash
00;00;10;849: TCL(5): init DONE
00;00;10;898: VPADInit() depricated, no need to call
00;00;10;898: VPAD Init end, BPERF: (0/11019090) Title PID=5
00;00;10;904: __UVDReadOSRelaunchMode FAILED: returned 9
00;00;10;971:    ACP_AutoInit: start
00;00;10;972:    ACP_AutoInit: ACPInitialize complete
00;00;10;972:    ACP_AutoInit: ACPSaveDataInit complete
00;00;10;975:    ACP_AutoInit: ACPNotifyPlayEvent complete
00;00;10;977:    ACP_AutoInit: NDMInitialize complete
00;00;10;978: ACP: initialized
00;00;10;978: [barista-Process] Product {:program=>38956, :message=>43332, :programProj=>39097, :root=>39136, :sead=>37528, :resource=>39136}
00;00;10;978: [barista-Process] Cafe OS SDK Version 2.12.11 Build 66642 Branch 

00;00;11;120: WPAD: Application Init
00:00:11:717: MCP: PID 14 requested to disable power transitions
00;00;11;596: ProcUI: Received Release Foreground message
00;00;11;598: ProcUI: Releasing foreground
00:00:11:872: MCP: PID 14 requested to enable power transitions
00:00:11:873: MCP: PID 14 requested to disable power transitions
00;00;11;750: ------------------PROCESS 5 LOST FOREGROUND--------------------
00;00;11;751: KiCblRegisterBlocks() completed with status 0:-4.
00;00;11;751: ------------------PROCESS 1 GAIN FOREGROUND--------------------
00;00;11;751: 


-----------------------------
ROOT IS STARTING MASTER TITLE
-----------------------------

00;00;11;751: TEST COOKIE: YOSHI-1992-11-21-00-00-00
00;00;11;753: DIAG:Issued KiPrepareTitleAsync(0x0005000010198C00)
00:00:12:076: MCP: did not find 'preload.txt' at '/vol/storage_mlc01/usr/title/00050000/10198c00/code', rval -196631
00:00:12:076: MCP: Preparing hachihachi_ntr.rpx
00;00;11;961: DIAG:Result PrepareTitle(0x0005000010198C00) came back with cosxml(0x0005000010198C00)
00:00:12:107: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:12:107: [WD]>>>> Linkup msg from Net
00:00:12:109: MCP: Mapping /vol/content to /vol/storage_mlc01/usr/title/00050000/10198c00/content
00:00:12:110: MCP: 0005000010198C00 is not a demo title, skip increment demo launch count
00:00:12:110: MCP: Mapping /vol/content to /vol/storage_mlc01/usr/title/00050000/10198c00/content
00:00:12:120: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00;00;11;991: TitleId 0x0005000010198C00 Version 0x00000000 Built On SDK 21107
00;00;11;991: ------------------PROCESS 1 LOST FOREGROUND--------------------
00;00;11;991: DIAG: PhysAlloc(15, 0x40000000, 0x00000000, 0x02000000, 0x0C800000, 0x80000000)
00;00;11;991: DIAG: InitAddrSpace
00;00;11;992: DIAG: Switch(New)
00;00;11;992: DIAG:+Loader(1, 7)
00;00;11;992: DIAG:+KiRPLLoaderSetup
00;00;11;992: DIAG:-KiRPLLoaderSetup: before no-return call to KiLoadContext (0xEFE09400) (0x01003FFC)
00;00;11;992: Title Loc is 0
00:00:12:287: MCP: PID 14 requested to enable power transitions
00;00;12;166: DIAG:-Loader(1, 7)
00;00;12;166: DIAG: SetFG(7)
00;00;12;167: KiCblRegisterBlocks() completed with status 0:-4.
00;00;12;167: ------------------PROCESS 15 GAIN FOREGROUND--------------------
00;00;12;168: SYSLOG_LEVEL 1: Warnings Enabled
00;00;12;168: UserMode Core & Thread Initialization (3 Cores)
00;00;12;168:   Core 1 Complete, Default Thread 0x100457E0
00;00;12;170:   Core 0 Complete, MSR 0x00005072, Default Thread 0x10045140
00;00;12;170:   Core 2 Complete, MSR 0x00005072, Default Thread 0x10045E80
00;00;12;193: UserMode OS library build date: Jul 28 2015 19:57:03
00;00;12;193: __AppFlags: 0x00000200

00;00;12;193: OSBlockThreadsOnExit default for sdk version 21107
00;00;12;390: SYSAPP: RPL Load
00;00;12;390: mic_core0_thread: master init
00;00;12;414: OSSetting_SynchronousPrinting DISABLED
00;00;12;418: OSSetting_CrashDumpType 0, MINI dump after crash
00;00;12;451: TCL(15): init DONE
SPM: SetAutoModeForDebug
SPM: ResolveAutomatically: SUCCESS volumeId = wayfvh2
00:00:12:642: ACP: "find USB" flag is false.
00;00;12;501: VPADInit() depricated, no need to call
00;00;12;502: VPAD Init end, BPERF: (0/12622833) Title PID=15
00;00;12;503:    ACP_AutoInit: start
00;00;12;690:    ACP_AutoInit: ACPInitialize complete
00;00;12;691:    ACP_AutoInit: ACPSaveDataInit complete
00;00;12;693:    ACP_AutoInit: ACPNotifyPlayEvent complete
00;00;12;696:    ACP_AutoInit: NDMInitialize complete
00;00;12;699: ACP: initialized
00;00;12;757: WPAD: Application Init
00;00;12;981: rpl_entry
00;00;12;981: OSDynLoad_Acquire() use [2701568]
00;00;12;982: ErrEula: client version 3.
00;00;12;982: ErrEula: thread=0x100457e0, stackSize=      63 KB, stackBase=0x1079b878, stackEnd=0x1078b880 (ErrEulaCreate)
00:00:13:128: NET: Change admin state (1 -> 2)(iface:0 link:2)
00:00:13:304: ACP: Mounting "/vol/save/" on "/vol/storage_mlc01/usr/save/00050000/10198c00/user/"
00:00:13:305: ACP: Mount done (0)
00:00:13:609: [BOSS] BG stress mode is disabled.
00:00:13:731: ACP: Mounting "/vol/save/" on "/vol/storage_mlc01/usr/save/00050000/10198c00/user/"
00:00:13:732: ACP: Mount done (0)
00:00:16:321: MCP: PID 10 requested to enable power transitions
00:00:16:321: MCP_PowerButtonControl(1) returned 0
libpng warning: iCCP: known incorrect sRGB profile
libpng warning: iCCP: known incorrect sRGB profile
00;00;17;619: AXIST thread has stopped
00;00;17;622: ErrEula: thread=0x100457e0, stackSize=      63 KB, stackBase=0x1079b878, stackEnd=0x1078b880 (ErrEulaDestroy)
00;00;17;627: rpl_entry
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00;00;17;659: spauMemFree called with NULL !
00:00:17:869: ACP: Mounting "/vol/save/" on "/vol/storage_mlc01/usr/save/00050000/10198c00/user/"
00:00:17:869: FSA fsa_core_ops.c(1511): failed to issue command to FS (-196630)
00:00:17:870: ACP: Mount done (-196630)
00:00:18:022: ACP: Mounting "/vol/save/" on "/vol/storage_mlc01/usr/save/00050000/10198c00/user/"
00:00:18:022: ACP: Mount done (0)
00:00:18:953: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:18:954: [WD]>>>> Linkup msg from Net
00:00:18:962: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:18:970: NET: Change admin state (2 -> 2)(iface:1 link:2)
00:00:18:970: NET: Change admin state (1 -> 1)(iface:0 link:3)
00:00:19:087: [WD]>>>> Linkup msg from Net
00:00:19:096: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:20:101: NET: Change admin state (1 -> 2)(iface:0 link:2)
00:00:20:818: UHS0 Error: file uhs_main.c, function UhsAllocLocalEvent, line 339, status 33:-57, info 31457388(0x01e0006c)
00:00:20:852: UHS0 Error: file uhs_main.c, function UhsAllocLocalEvent, line 339, status 33:-57, info 31457388(0x01e0006c)
00:00:20:886: UHS0 Error: file uhs_main.c, function UhsAllocLocalEvent, line 339, status 33:-57, info 31457388(0x01e0006c)
00:00:20:920: UHS0 Error: file uhs_main.c, function UhsAllocLocalEvent, line 339, status 33:-57, info 31457388(0x01e0006c)
00:00:20:954: MCP: PID 14 requested to disable power transitions
00;00;20;944: ------------------PROCESS 15 LOST FOREGROUND--------------------
00;00;20;945: KiCblRegisterBlocks() completed with status 0:-4.
00;00;20;945: ------------------PROCESS 5 GAIN FOREGROUND--------------------
00;00;21;076: TCL(5): init DONE
00;00;21;192: VPADInit() depricated, no need to call
00:00:21:395: MCP: PID 14 requested to enable power transitions
00;00;21;275: ProcUI: Received Acquire Foreground message
00:00:21:752: IM: home button was pressed by type: IM_PAD_TYPE_NONE index: 0
00;00;21;631: ==== sysapp:dump data 126 ====
00;00;21;631:         <sys:caller_id size=8>Game<sys:caller_process size=4>mlc <sys:caller_device size=4><sys:pack size=91>    <mode size=4>
00:00:21:893: ACP: USB Storage: /vol/storage_usb01
00:00:21:907: ACP: USB storage mounted.
00:00:22:262: MCP: PID 14 requested to disable power transitions
00;00;22;141: 


Querying BSP for Hardware Information...
00;00;22;141: 	It is not Holly/Bolly-Wood
00;00;22;141: 	It is Latte A2x or later
00;00;22;141: using DSP memory parameters:
00;00;22;141:     DROM_BASE: 0x3000
00;00;22;141:     IRAM_SIZE: 0x2000
00;00;22;141:     DRAM_SIZE: 0x3000
00;00;22;142: DIAG:Issued KiPrepareTitleAsync(0xFFFFFFFFFFFFFFFD)
00:00:22:380: MCP: loaded and parsed 'preload.txt' at '/vol/storage_mlc01/sys/title/00050010/10040100/code'
00:00:22:380: MCP: Preparing men.rpx
00;00;22;262: DIAG:Result PrepareTitle(0xFFFFFFFFFFFFFFFD) came back with cosxml(0x0005001010040100)
00;00;22;262: 
***PPC FAST CYCLE***
00;00;22;262: SHUTINFO: RAMPID 5 is in Foreground
00;00;22;262: SHUTINFO: RAMPID 0 is also alive.
00;00;22;262: SHUTINFO: RAMPID 1 is also alive.
00;00;22;262: SHUTINFO: RAMPID 6 is also alive.
00;00;22;262: SHUTINFO: RAMPID 7 is also alive.
00;00;22;262: p15c2,nt EXIT message pushed to system queue.
00;00;22;264: 

!!WARNING - main() exited with an active thread 0x10B86F38 [prio = 80] "hax0thread"
00;00;22;264: 

!!WARNING - main() exited with an active thread 0x10B87610 [prio = 80] "hax2thread"
00;00;22;264: +OSDrivers_Done(p15,c1)
00;00;22;264: +OSDrivers_Done(p15,c2)
00;00;22;264: __OSShutdownIdleBegin()
00;00;22;264: __OSSetMaxSchedulingBand 1
00;00;22;264: +OSDrivers_Done(p15,c0)
00;00;22;265: ProcUI: Received Release Foreground message (shutdown has begun)
00;00;22;315: ABANDONED ORPHANED IPC REPLY FOR RAMPID 7
00;00;22;315: -OSDrivers_Done(p15,c1)
00;00;22;315: -OSDrivers_Done(p15,c0)
00;00;22;315: -OSDrivers_Done(p15,c2)
00:00:22:458: AUXIL Info: fast relaunch.
00;00;22;336: +IPCKDriver_Cleanup(7)
00;00;22;336: -IPCKDriver_Cleanup(7)
00;00;22;446: ProcUI: Releasing foreground
00;00;22;602: ------------------PROCESS 5 LOST FOREGROUND--------------------
00;00;22;603: KiCblRegisterBlocks() completed with status 0:-4.
00;00;22;603: ------------------PROCESS 1 GAIN FOREGROUND--------------------
00;00;22;645: TCL(1): init DONE
00;00;22;700: VPADInit() depricated, no need to call
00;00;22;714: sMasterTitleSwitchedToExit
00;00;22;714: ROOT: Issuing reboot.
00;00;22;720: ROOT: Waiting for EXIT message or RECYCLE message
00;00;22;720: ---Root Invoking Recycle---
00:00:22:856: MCP: PID 14 requested to enable power transitions
00:00:22:856: MCP: PID 10 requested to disable power transitions
00:00:22:857: MCP_PowerButtonControl(0) returned 0
00;00;22;735: 


---PPC USER-LEVEL FAST RESTART---


00;00;22;735: New master title is a CafeMenu(0x90000001)
00;00;22;735: MASTER TITLE ON   FLASH
00:00:23:052: MCP: PID 14 requested to disable power transitions
00;00;22;928: 


-----------------------------
ROOT IS STARTING MASTER TITLE
-----------------------------

00;00;22;928: TEST COOKIE: YOSHI-1992-11-21-00-00-00
00;00;22;932: DIAG:Issued KiPrepareTitleAsync(0xFFFFFFFFFFFFFFFD)
00:00:23:118: MCP: loaded and parsed 'preload.txt' at '/vol/storage_mlc01/sys/title/00050010/10040100/code'
00:00:23:118: MCP: Preparing men.rpx
00:00:23:119: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:23:119: [WD]>>>> Linkup msg from Net
00:00:23:120: MCP: Titles scanned (dev_state 000c) on device /dev/usb01 (wfs) @ uptime 23.120 s.
00;00;23;004: DIAG:Result PrepareTitle(0xFFFFFFFFFFFFFFFD) came back with cosxml(0x0005001010040100)
00:00:23:130: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:23:139: MCP: Mapping /vol/content to /vol/storage_mlc01/sys/title/00050010/10040100/content
00:00:23:140: MCP: Warning title:0x0005001010040100 does not have ramdisk read capability (forcing)
00:00:24:136: NET: Change admin state (1 -> 2)(iface:0 link:2)
00:00:24:536: MCP: Titles scanned (dev_state 0006) on device /dev/usb01 (wfs) @ uptime 24.536 s.
00:00:25:274: MCP: Copying file:/vol/storage_mlc01/sys/title/00050010/10040100/content/Common/Package/Men.pack to ramdisk:/vol/system_ram/proc/cache/Men.pack
00:00:25:275: MCP: Link title:0x0005001010040100 file:/vol/content/Common/Package/Men.pack to ramdisk:/vol/sys/proc_ram/cache/Men.pack
00:00:25:275: MCP: 0005001010040100 is not a demo title, skip increment demo launch count
00:00:25:275: MCP: Mapping /vol/content to /vol/storage_mlc01/sys/title/00050010/10040100/content
00;00;25;156: TitleId 0x0005001010040100 Version 0x000000D5 Built On SDK 21211
00;00;25;307: ------------------PROCESS 1 LOST FOREGROUND--------------------
00;00;25;308: DIAG: PhysAlloc(2, 0x40000000, 0x00000000, 0x00000000, 0x02300000, 0x00000001)
00;00;25;308: DIAG: InitAddrSpace
00;00;25;309: DIAG: Switch(New)
00;00;25;309: DIAG:+Loader(1, 7)
00;00;25;309: DIAG:+KiRPLLoaderSetup
00;00;25;309: DIAG:-KiRPLLoaderSetup: before no-return call to KiLoadContext (0xEFE09400) (0x01003FFC)
00;00;25;309: Title Loc is 0
00:00:25:838: MCP: PID 14 requested to enable power transitions
00;00;25;717: DIAG:-Loader(1, 7)
00;00;25;717: DIAG: SetFG(7)
00;00;25;718: KiCblRegisterBlocks() completed with status 0:-4.
00;00;25;718: ------------------PROCESS 2 GAIN FOREGROUND--------------------
00;00;25;719: SYSLOG_LEVEL 1: Warnings Enabled
00;00;25;719: UserMode Core & Thread Initialization (3 Cores)
00;00;25;719:   Core 1 Complete, Default Thread 0x100457E0
00;00;25;721:   Core 0 Complete, MSR 0x00005072, Default Thread 0x10045140
00;00;25;722:   Core 2 Complete, MSR 0x00005072, Default Thread 0x10045E80
00;00;25;744: UserMode OS library build date: Jul 28 2015 19:57:03
00;00;25;745: __AppFlags: 0x00000200

00;00;25;745: OSBlockThreadsOnExit default for sdk version 21211
00;00;26;223: SYSAPP: RPL Load
00;00;26;267: OSSetting_SynchronousPrinting DISABLED
00;00;26;273: OSSetting_CrashDumpType 0, MINI dump after crash
00;00;26;307: TCL(2): init DONE
00;00;26;364: VPADInit() depricated, no need to call
00;00;26;365: VPAD Init end, BPERF: (0/26486176) Title PID=2
SPM: SetManualMode
00;00;26;401:    ACP_AutoInit: start
00;00;26;553:    ACP_AutoInit: ACPInitialize complete
00;00;26;553:    ACP_AutoInit: ACPSaveDataInit complete
00;00;26;556:    ACP_AutoInit: ACPNotifyPlayEvent complete
00;00;26;559:    ACP_AutoInit: NDMInitialize complete
00:00:26:795: ACP: Mounting "/vol/save/" on "/vol/storage_mlc01/usr/save/00050010/10040100/user/"
00:00:26:796: ACP: Mount done (0)
00;00;26;667: ACP: initialized
00;00;26;668: [barista] Product {:program=>38956, :message=>43332, :programProj=>39097, :root=>39136, :sead=>37528, :resource=>39136}
00;00;26;668: [barista] Cafe OS SDK Version 2.12.11 Build 66642 Branch 

00;00;26;668: ### Application Start Time [09:11:03] ###
00;00;26;668: =================================
 MEM_ARENA_2: total free size =  989763(KB),  966(MB)
=================================
00;00;26;706: WPAD: Application Init
00:00:27:138: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:27:138: [WD]>>>> Linkup msg from Net
00:00:27:147: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00;00;27;895: rpl_entry
00;00;27;895: OSDynLoad_Acquire() use [2701564]
00;00;27;895: ErrEula: client version 3.
00;00;27;895: ErrEula: thread=0x4c606540, stackSize=      64 KB, stackBase=0x4c60652c, stackEnd=0x4c5f652c (ErrEulaCreate)
00:00:28:152: NET: Change admin state (1 -> 2)(iface:0 link:2)
00;00;28;380: == sysapp == new path
00;00;28;380: ==== sysapp:dump data 91 ====
00;00;28;380:         <sys:caller_id size=8>Game<sys:caller_process size=4>mlc <sys:caller_device size=4>
00;00;28;380: ==== sysapp:dump data 0 ====
00;00;28;380: 
00;00;28;423: ==== sysapp:dump data 0 ====
00;00;28;423: 
DRMAPP: built Jul 28 2015 20:01:32
00:00:30:288: MCP: PID 10 requested to enable power transitions
00:00:30:288: MCP_PowerButtonControl(1) returned 0
00:00:31:912: CDC_SIGNAL_STA_ASSOCIATED_NOTIFY
00:00:31:922: ccr_hid_input_start: idx 0 controller: 0, ep 3 int req --> 0
00;00;31;801: port 0 attached
00;00;31;801: mic_drc_attach_cb: ch 0 reason 1 flags 0x00000100
00:00:33:973: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:33:973: [WD]>>>> Linkup msg from Net
00:00:33:982: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:33:987: NET: Change admin state (2 -> 2)(iface:1 link:2)
00:00:33:987: NET: Change admin state (1 -> 1)(iface:0 link:3)
00:00:34:103: [WD]>>>> Linkup msg from Net
00:00:34:112: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:35:119: NET: Change admin state (1 -> 2)(iface:0 link:2)
00:00:38:121: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:38:121: [WD]>>>> Linkup msg from Net
00:00:38:132: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:39:140: NET: Change admin state (1 -> 2)(iface:0 link:2)
00:00:42:143: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:42:144: [WD]>>>> Linkup msg from Net
00:00:42:154: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:43:166: NET: Change admin state (1 -> 2)(iface:0 link:2)
00:00:48:983: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:48:983: [WD]>>>> Linkup msg from Net
00:00:48:993: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:48:997: NET: Change admin state (2 -> 2)(iface:1 link:2)
00:00:48:997: NET: Change admin state (1 -> 1)(iface:0 link:3)
00:00:49:113: [WD]>>>> Linkup msg from Net
00:00:49:123: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:50:131: NET: Change admin state (1 -> 2)(iface:0 link:2)
00:00:53:132: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:53:133: [WD]>>>> Linkup msg from Net
00:00:53:142: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:53:547: ODM: Read disc serial ID done
00:00:53:638: ODM: Read LBA 0 done
00:00:53:647: FSA: [uptime 53.647 s]: Attached volume to odd01 (atfs)
00:00:53:647: MCP: FSA volume on device /dev/odd01 (atfs) attached @ uptime 53.647 s.
00:00:53:648: FSA: [uptime 53.648 s]: Attached volume to odd02 (atfs)
00:00:53:649: FSA: [uptime 53.649 s]: Attached volume to odd03 (atfs)
00:00:53:660: MCP: FSA volume on device /dev/odd02 (atfs) attached @ uptime 53.660 s.
00:00:53:666: MCP: Titles scanned (dev_state 000a) on device /dev/odd01 (atfs) @ uptime 53.666 s.
00:00:53:758: MCP: FSA volume on device /dev/odd03 (atfs) attached @ uptime 53.758 s.
00:00:54:148: NET: Change admin state (1 -> 2)(iface:0 link:2)
00:00:54:300: MCP: Titles scanned (dev_state 000a) on device /dev/odd02 (atfs) @ uptime 54.300 s.
00:00:54:306: MCP: Titles scanned (dev_state 000e) on device /dev/odd03 (atfs) @ uptime 54.306 s.
00:00:54:501: Copy /vol/storage_odd02/update/020807/update.inf.USA to /vol/system_slc/tmp/update.inf
00:00:54:900: MCP: Cached /vol/storage_odd02/update/020807/update.inf.USA to /vol/system_slc/tmp/update.inf
00:00:54:900: MCP: Use list "/vol/system_slc/tmp/update.inf"
00:00:55:774: ODM odm_methods_read.c(202): failed with rval=0xffeffdf1, senseKey=0x30
00:00:55:774: ODM odm_methods.c(427): Check Sense code
00:00:55:825: ODM odm_methods.c(439): senseCode:[30200]
00:00:55:825: ODM odm_methods.c(497): Error handler result: rval=-918031, nextState=ODM_STATE_NONE(0)
00:00:55:951: ODM odm_methods_read.c(202): failed with rval=0xffeffdf1, senseKey=0x30
00:00:55:952: ODM odm_methods.c(427): Check Sense code
00:00:56:002: ODM odm_methods.c(439): senseCode:[30200]
00:00:56:002: ODM odm_methods.c(497): Error handler result: rval=-917508, nextState=ODM_STATE_DIRTY_DISC(9)
00:00:56:002: ATFS atfs_df_interface.c(92): Converting device error (14:-4) to atfs error
00:00:56:003: ATFS atfs_security.c(1600): Media detached. Read fail.
00:00:56:003: ATFS atfs_security.c(1643): rval: (45:-64)
00:00:56:003: FSA: ### MEDIA NOT READY ERROR ###, dev:odd02, err:-2949184, cmd:11, path:(null)


====== ODM LOG ======
Logged at: 2021-02-28 09:11:33.004356 (Uptime 56.004377 s)

--- 1. AHCI driver Logs ---
ahciDrvPrintTxnLog(0):
	Transaction @ uptime 54.302779s, duration 0.000806s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=1835040, blocks=16
		size= 32768
		status=32768
	Transaction @ uptime 54.360666s, duration 0.110159s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=1064976, blocks=32
		size= 65536
		status=65536
	Transaction @ uptime 54.649093s, duration 0.101911s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=1838656, blocks=32
		size= 65536
		status=65536
	Transaction @ uptime 54.751460s, duration 0.004914s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=1838688, blocks=32
		size= 65536
		status=65536
	Transaction @ uptime 54.778234s, duration 0.038723s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=1835072, blocks=32
		size= 65536
		status=65536
	Transaction @ uptime 54.904527s, duration 0.107254s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=612816, blocks=32
		size= 65536
		status=65536
	Transaction @ uptime 55.017518s, duration 0.048358s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=616912, blocks=32
		size= 65536
		status=65536
	Transaction @ uptime 55.147532s, duration 0.059382s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=646640, blocks=32
		size= 65536
		status=65536
	Transaction @ uptime 55.210200s, duration 0.093079s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=686960, blocks=32
		size= 65536
		status=65536
	Transaction @ uptime 55.382061s, duration 0.060373s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=702096, blocks=32
		size= 65536
		status=65536
	Transaction @ uptime 55.445807s, duration 0.066737s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=742096, blocks=32
		size= 65536
		status=65536
	Transaction @ uptime 55.517617s, duration 0.063664s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=750256, blocks=32
		size= 65536
		status=65536
	Transaction @ uptime 55.631332s, duration 0.142691s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=758320, blocks=32
		size= 65536
		status=16:-527
		ataError=0x30
	Transaction @ uptime 55.775096s, duration 0.050605s:
		ATAPI_REQUEST_SENSE_CMD
		size= 32
		status=32
	Transaction @ uptime 55.826185s, duration 0.125553s:
		ATAPI_CF_READ_CMD subcode=0x00, cacheLineIndex=0x00, lba=758320, blocks=32
		size= 65536
		status=16:-527
		ataError=0x30
	Transaction @ uptime 55.952297s, duration 0.049782s:
		ATAPI_REQUEST_SENSE_CMD
		size= 32
		status=32
ahciDrvPrintStats(0):
	tfeIntCount       = 51
	hbfIntCount       = 0
	hbdIntCount       = 0
	ifIntCount        = 0
	infIntCount       = 0
	ofIntCount        = 0
	prcIntCount       = 0
	pcIntCount        = 0
	dpIntCount        = 87
	ufIntCount        = 0
	dhrIntCount       = 142
	sdbIntCount       = 0
	fatalTxnAmbiguity = 0
	stallAlarms       = 0
	txnCount          = 0
	dbgIntCount       = 0
	serrDiagX         = 0
	serrDiagF         = 0
	serrDiagT         = 0
	serrDiagS         = 0
	serrDiagH         = 0
	serrDiagC         = 0
	serrDiagD         = 0
	serrDiagB         = 0
	serrDiagW         = 0
	serrDiagI         = 0
	serrDiagN         = 0
	serrErrE          = 0
	serrErrP          = 0
	serrErrC          = 0
	serrErrT          = 0
	serrErrM          = 0
	serrErrI          = 0
ahciDrvPrintRegisters(0):
	SATA_HCCFG_INT_REG     = 0x00000000
	SATA_HCCFG_INTMSK_REG  = 0x00000028
	DI2SATA_SR_IDX         = 0x00000000
	DI2SATA_CVR            = 0x00000000
	DI2SATA_CR             = 0x00000000
	AHCI_HBA_GHC_GHC       = 0x80000002
	AHCI_HBA_GHC_IS        = 0x00000000
	AHCI_HBA_PORT_IS       = 0x00000000
	AHCI_HBA_PORT_IE       = 0x7d400079
	AHCI_HBA_PORT_CMD      = 0x01046d17
	AHCI_HBA_PORT_TFD      = 0x00000050
	AHCI_HBA_PORT_SSTS     = 0x00000113
	AHCI_HBA_PORT_SERR     = 0x00000000

--- 2. ODM internal status ---
Current FSM state:ODM_STATE_DIRTY_DISC
Previous FSM state:ODM_STATE_CAFE_DISC
Last internal error code:0
Last request sense data:
 f0 00 03 00
 0b 92 30 18
 00 30 01 09
 02 00 00 00
 00 00 01 00
 00 00 00 00
 00 f6 49 f5
 00 c5 ee 80

--- 3. Most recently recognized valid disc information ---

Disc serial ID (Cafe disc only):
 55 53 03 05 13 bf 08 21 0c 00 01 7c 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00

DI disc information (RVL disc only):
Unavailable

First 256 bytes of LBA 0:
 57 55 50 2d 50 2d 41 50 4c 45 2d 30 30 2d 32 31
 31 55 53 41 2d 30 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
00:00:56:051: [ES] Failed to read 484 bytes, rv=-184
00:00:56:672: MCP: Discard cache /vol/system_slc/tmp/update.inf
00:00:57:150: NET: Change admin state (2 -> 1)(iface:0 link:2)
00:00:57:150: [WD]>>>> Linkup msg from Net
00:00:57:160: wlc_mlme_join_cmd called with Command 0x3 Length 0x21
00:00:58:164: NET: Change admin state (1 -> 2)(iface:0 link:2)
00:00:58:509: MCP: discarding ramdisk cache for '/vol/storage_odd01'
00:00:58:511: FSA: [uptime 58.511 s]: Attached volume to odd01 (raw)
00:00:58:512: MCP: FSA volume on device /dev/odd01 (atfs) detached @ uptime 58.512 s.
00:00:58:513: MCP: discarding ramdisk cache for '/vol/storage_odd03'
00:00:58:514: MCP: FSA volume on device /dev/odd03 (atfs) detached @ uptime 58.514 s.
00:00:58:515: MCP: discarding ramdisk cache for '/vol/storage_odd02'
00:00:58:516: MCP: FSA volume on device /dev/odd02 (atfs) detached @ uptime 58.516 s.
00:00:58:518: MCP: FSA volume on device /dev/odd01 (raw) attached @ uptime 58.518 s.
00:00:58:525: MCP: Titles scanned (dev_state 0006) on device /dev/odd01 (raw) @ uptime 58.525 s.
00;00;59;233: ErrEula: thread=0x100457e0, stackSize=      63 KB, stackBase=0x1069b138, stackEnd=0x1068b140 (ErrEulaAppearError)
00;00;59;233: ErrEula :ErrorCode = 1502031
00;00;59;233: ErrEula: thread=0x100457e0, stackSize=      63 KB, stackBase=0x1069b138, stackEnd=0x1068b140 (ErrEulaCalc)
00:00:59:420: CSC: Product Code: WUP-N-HASE, Title Version (major): 13
00:00:59:433: CSC: Repaired: FALSE, Wagon: FALSE
00:00:59:438: CSC: InitialLaunch stage: 2
00:00:59:441: CSC: USB Storage Connected: TRUE, Device Type: NAND
00:00:59:463: CSC: RCA Enabled: FALSE, KeepAlive Enabled: FALSE, ParentControl Enable: FALSE
00:00:59:471: CSC: Check : /vol/storage_mlc01/usr/boss/00050010/10040100/user/common/ (-196631)
00:00:59:472: CSC: Check : /vol/storage_mlc01/usr/boss/00050010/10040100/user/8000000b/ (0)
00:00:59:473: CSC: Boss Storage Exist: TRUE, NetworkID Assigned: TRUE, System Network Connected: FALSE
00:00:59:499: CSC: NIC Type: WIFI, DNS obtained by DHCP: FALSE, IP obtained by DHCP: TRUE
00:00:59:504: CSC: TV Port: HDMI, TV Aspect Ratio: 16:9
00:00:59:510: CSC: TV Resolution: TV_1080P
00:00:59:510: CSC: CODE: [HASE-0250-6175]
2021-02-28 09:11:36 - log collection event from pid 21
00;00;59;390: (1)99.99.99 U
00;00;59;390: (2)WUP-101(02)
00;00;59;390: (3)FW702985384
00;00;59;390: (4)HASE-0250-6175
00:00:59:514: MCP: life time number of fatal errors - 49
