Dolphin, the GameCube and Wii emulator - Forums

Dolphin, the GameCube and Wii emulator - Forums > Dolphin Emulator Discussion and Support > Support > Fixed: Help reading log of crash when loading IPL.bin
Full Version: Fixed: Help reading log of crash when loading IPL.bin
You're currently viewing a stripped down version of our content. View the full version with proper formatting.

themanuel

12-07-2023, 02:08 AM
Dolphin hangs into a black screen and stops responding when I try to load the Gamecube main menu.  It was working perfectly fine last week.

Does this log provide a clue?
For additional info, I have 187GB free on the drive from where Dolphin is running.

Code:
03:32:132 Core\Core.cpp:1023 N[COMMON]: Want determinism <- false
03:32:132 Core\AchievementManager.cpp:856 I[RetroAchievements]: Achievement Manager has been re-enabled.
03:32:132 Core\Core.cpp:259 I[BOOT]: Starting core = GameCube mode
03:32:132 Core\Core.cpp:261 I[BOOT]: CPU Thread separate = Yes
03:32:157 Common\GL\GLInterface\WGL.cpp:403 I[Video]: WGL: Created a GL 4.6 core context
03:32:162 VideoBackends\OGL\OGLConfig.cpp:741 I[Video]: Video Info: ATI Technologies Inc., AMD Radeon RX 5700, 4.6.0 Core Profile Context 23.12.1.231124
03:32:162 VideoBackends\OGL\OGLConfig.cpp:760 I[Video]: All used OGL Extensions are available.
03:32:371 AudioCommon\Mixer.cpp:42 I[AI]: Mixer is initialized
03:32:373 AudioCommon\CubebUtils.cpp:80 I[Audio]: Cubeb initialized using wasapi backend
03:32:374 cubeb\cubeb\src\cubeb_wasapi.cpp:1890 N[Audio]: default device period: 100000, minimum device period: 30000
03:32:375 cubeb\cubeb\src\cubeb_wasapi.cpp:1905 N[Audio]: Minimum latency in frames: 480
03:32:375 AudioCommon\CubebStream.cpp:92 I[Audio]: Minimum latency: 480 frames
03:32:375 cubeb\cubeb\src\cubeb_wasapi.cpp:2556 N[Audio]: (0000024A7E0CED70) Setup render: device=0000000000000000
03:32:382 cubeb\cubeb\src\cubeb_wasapi.cpp:2008 N[Audio]: Requested format accepted by WASAPI.
03:32:383 cubeb\cubeb\src\cubeb_wasapi.cpp:2291 N[Audio]: Setup requested=[f=0 r=48000 c=2 l=3] mix=[f=0 r=48000 c=2 l=3]
03:32:404 cubeb\cubeb\src\cubeb_wasapi.cpp:2370 N[Audio]: Buffer size is: 1056 for render

03:32:404 cubeb\cubeb\src\cubeb_wasapi.cpp:2607 N[Audio]: Target sample rate: 48000
03:32:404 cubeb\cubeb\src\cubeb_resampler_internal.h:521 N[Audio]: Input and output sample-rate match, target rate of 48000Hz
03:32:404 cubeb\cubeb\src\cubeb_wasapi.cpp:2809 N[Audio]: Follow the system default input or/and output devices
03:32:412 cubeb\cubeb\src\cubeb_wasapi.cpp:2837 N[Audio]: Stream init successful (0000024A7E0CED70)
03:32:415 Core\HW\EXI\EXI_Channel.cpp:186 I[EXI]: Changing EXI channel 0, device 0 to type 8 (notify software: true)
03:32:415 Core\HW\EXI\EXI_Channel.cpp:186 I[EXI]: Changing EXI channel 1, device 0 to type 255 (notify software: true)
03:32:514 Core\HW\EXI\EXI_DeviceIPL.cpp:116 I[BOOT]: Loaded bootrom: (C) 1999-2003 Nintendo.  All rights reserved.(C) 1999 ArtX Inc.  All rights reserved.NTSC Revision 1.2 
03:32:514 Core\HW\EXI\EXI_Channel.cpp:186 I[EXI]: Changing EXI channel 0, device 1 to type 2 (notify software: true)
03:32:514 Core\HW\EXI\EXI_Channel.cpp:186 I[EXI]: Changing EXI channel 0, device 2 to type 255 (notify software: true)
03:32:514 Core\HW\EXI\EXI_Channel.cpp:186 I[EXI]: Changing EXI channel 2, device 0 to type 3 (notify software: true)
03:32:539 Core\HW\Memmap.cpp:158 I[MI]: Memory system initialized. RAM at 0x24a084e0000
03:33:720 Common\GL\GLInterface\WGL.cpp:403 I[Video]: WGL: Created a GL 4.6 core context
03:33:725 VideoBackends\OGL\OGLConfig.cpp:741 I[Video]: Video Info: ATI Technologies Inc., AMD Radeon RX 5700, 4.6.0 Core Profile Context 23.12.1.231124
03:33:725 VideoBackends\OGL\OGLConfig.cpp:760 I[Video]: All used OGL Extensions are available.
03:34:147 Common\GL\GLInterface\WGL.cpp:403 I[Video]: WGL: Created a GL 4.6 core context
03:34:152 VideoBackends\OGL\OGLConfig.cpp:741 I[Video]: Video Info: ATI Technologies Inc., AMD Radeon RX 5700, 4.6.0 Core Profile Context 23.12.1.231124
03:34:152 VideoBackends\OGL\OGLConfig.cpp:760 I[Video]: All used OGL Extensions are available.
03:34:266 Common\GL\GLInterface\WGL.cpp:403 I[Video]: WGL: Created a GL 4.6 core context
03:34:273 Common\GL\GLInterface\WGL.cpp:403 I[Video]: WGL: Created a GL 4.6 core context
03:34:615 Common\GL\GLInterface\WGL.cpp:403 I[Video]: WGL: Created a GL 4.6 core context
03:34:616 VideoCommon\ShaderCache.cpp:322 I[Video]: Loaded 0 cached pipelines from C:/Console/Wii & GCN/Dolphin/User/Cache/Shaders/OpenGL-specialized-pipeline-00000000-246FFF40.cache
03:34:619 VideoCommon\ShaderCache.cpp:322 I[Video]: Loaded 0 cached pipelines from C:/Console/Wii & GCN/Dolphin/User/Cache/Shaders/OpenGL-uber-pipeline-246FFF40.cache
03:34:619 VideoCommon\ShaderCache.cpp:993 I[Video]: Read 0 pipeline UIDs from C:/Console/Wii & GCN/Dolphin/User/Cache/00000000.uidcache
03:34:619 Core\HW\DSPHLE\UCodes\UCodes.cpp:254 I[DSPHLE]: Switching to ROM ucode
03:34:619 Core\HW\DSPHLE\UCodes\ROM.cpp:28 I[DSPHLE]: UCode_Rom - initialized
03:34:621 Core\Boot\Boot.cpp:607 N[BOOT]: Booting GC IPL: C:/Console/Wii & GCN/Dolphin/User/GC//USA/IPL.bin
03:35:122 Core\HW\MMIO.cpp:186 E[MI]: Trying to write 32 bits to an invalid MMIO (addr=0c003030, val=0245248a)
03:35:122 Core\HW\ProcessorInterface.cpp:126 I[PI]: Wrote PI_RESET_CODE: 00000001
03:35:122 Core\HW\ProcessorInterface.cpp:126 I[PI]: Wrote PI_RESET_CODE: 00000003
03:35:340 Core\PowerPC\Interpreter\Interpreter_SystemRegisters.cpp:466 I[PowerPC]: DBAT updated 543 fff00001 0
03:35:557 Core\PowerPC\Interpreter\Interpreter_SystemRegisters.cpp:466 I[PowerPC]: DBAT updated 542 fff0001f 0
03:35:767 Core\PowerPC\Interpreter\Interpreter_SystemRegisters.cpp:489 I[PowerPC]: IBAT updated 535 fff00001 0
03:35:958 Core\PowerPC\Interpreter\Interpreter_SystemRegisters.cpp:489 I[PowerPC]: IBAT updated 534 fff0001f 0
03:36:154 Core\HW\DSPHLE\UCodes\UCodes.cpp:254 I[DSPHLE]: Switching to ROM ucode
03:36:154 Core\HW\DSPHLE\UCodes\ROM.cpp:28 I[DSPHLE]: UCode_Rom - initialized
03:36:154 Core\HW\DSPHLE\UCodes\UCodes.cpp:258 I[DSPHLE]: Switching to INIT ucode
03:36:154 Core\HW\DSPHLE\UCodes\INIT.cpp:16 I[DSPHLE]: INITUCode - initialized
03:36:154 Core\HW\DSPHLE\DSPHLE.cpp:200 I[DSPHLE]: DSP_CONTROL halt bit changed: 0004 -> 0000
03:36:154 Core\HW\DSPHLE\DSPHLE.cpp:200 I[DSPHLE]: DSP_CONTROL halt bit changed: 0000 -> 0004
03:36:154 Core\HW\DSPHLE\UCodes\UCodes.cpp:254 I[DSPHLE]: Switching to ROM ucode
03:36:154 Core\HW\DSPHLE\UCodes\ROM.cpp:28 I[DSPHLE]: UCode_Rom - initialized
03:40:507 Core\PowerPC\Jit64\Jit.cpp:1196 W[PowerPC]: JIT ran out of space in near code region during code generation.
03:40:507 Core\PowerPC\Jit64\Jit.cpp:844 W[PowerPC]: flushing code caches, please report if this happens a lot
03:45:419 Core\PowerPC\Jit64\Jit.cpp:1196 W[PowerPC]: JIT ran out of space in near code region during code generation.
03:45:420 Core\PowerPC\Jit64\Jit.cpp:844 W[PowerPC]: flushing code caches, please report if this happens a lot
03:50:307 Core\PowerPC\Jit64\Jit.cpp:1196 W[PowerPC]: JIT ran out of space in near code region during code generation.
03:50:307 Core\PowerPC\Jit64\Jit.cpp:844 W[PowerPC]: flushing code caches, please report if this happens a lot
03:55:121 Core\PowerPC\Jit64\Jit.cpp:1196 W[PowerPC]: JIT ran out of space in near code region during code generation.
03:55:121 Core\PowerPC\Jit64\Jit.cpp:844 W[PowerPC]: flushing code caches, please report if this happens a lot
03:59:878 Core\PowerPC\Jit64\Jit.cpp:1196 W[PowerPC]: JIT ran out of space in near code region during code generation.
03:59:878 Core\PowerPC\Jit64\Jit.cpp:844 W[PowerPC]: flushing code caches, please report if this happens a lot
04:04:632 Core\PowerPC\Jit64\Jit.cpp:1196 W[PowerPC]: JIT ran out of space in near code region during code generation.
04:04:632 Core\PowerPC\Jit64\Jit.cpp:844 W[PowerPC]: flushing code caches, please report if this happens a lot

themanuel

12-07-2023, 02:59 AM
More information:

Last working version for me was 5.0-20551.
Problem started with 5.0-20555:
Jit: Replace "msrBits" with "featureFlags" and use for performance monitor (PR #11988 from JosJuice)

Last night I asked somebody on Discord to do a test for me, which they were nice enough to do, and the Gamecube menu launched perfectly fine for them with latest beta (5.0-20560), so this issue might be limited to certain hardware sets.

Gamecube and Wii games, and the Wii menu run fine for me.  Only the Gamecube system menu hangs.

Craftyawesome

12-07-2023, 05:12 AM
Is your IPL not exactly 2MiB? Might be this problem
https://github.com/dolphin-emu/dolphin/pull/12357
https://bugs.dolphin-emu.org/issues/13414

themanuel

12-07-2023, 05:51 AM
(12-07-2023, 05:12 AM)Craftyawesome Wrote: [ -> ]Is your IPL not exactly 2MiB? Might be this problem
https://github.com/dolphin-emu/dolphin/pull/12357
https://bugs.dolphin-emu.org/issues/13414

That's a good link, thanks.  However, my IPL boot is exactly 2MB:
2.00 MB (2,097,152 bytes)

It was working perfectly fine two beta releases ago.
Maybe the commit you linked will fix it anyway.

JosJuice

12-07-2023, 06:07 AM
The log doesn't have much helpful information, but the fact that this can be reproduced by starting the IPL is helpful information. Thanks to that I've been able to reproduce the issue myself for the first time. Earlier I only had a report of it happening in a game I don't own. So now I should be able to investigate the issue in more depth.

EDIT: Odd. On my second try it worked normally...

EDIT2: Yeah, I can't reproduce this anymore :/

themanuel

12-07-2023, 06:53 AM
(12-07-2023, 06:07 AM)JosJuice Wrote: [ -> ]The log doesn't have much helpful information, but the fact that this can be reproduced by starting the IPL is helpful information. Thanks to that I've been able to reproduce the issue myself for the first time. Earlier I only had a report of it happening in a game I don't own. So now I should be able to investigate the issue in more depth.

EDIT: Odd. On my second try it worked normally...

EDIT2: Yeah, I can't reproduce this anymore :/

Hmm.  What could it possibly be...
How should I set up my logging to provide something more useful?

JosJuice

12-07-2023, 07:26 AM
No, there usually isn't much useful information you can get out of the log in a situation like this unless Dolphin is straight up crashing.

themanuel

12-07-2023, 09:12 AM
Closing this out for now: US BIOS version 1.2 is the one that hangs, but version 1.1 works.

themanuel

12-13-2023, 01:16 PM
In case anyone stumbles upon this topic in the future:

This issue was fixed as of 5.0-20705 or a few versions earlier.
Dolphin, the GameCube and Wii emulator - Forums > Dolphin Emulator Discussion and Support > Support > Fixed: Help reading log of crash when loading IPL.bin