Bug: Player.Log infinite size?

Started by Mussels, November 13, 2021, 07:49:49 AM

Previous topic - Next topic

Mussels

1. Playing with some mods (Most notably, ZombieLand), and the game would freeze on quitting.

2. Recently, upon quitting the game it would always crash the game. The mod is fun, so i just dealt with it  - a few mouse clicks and i'd get a not-responding error and end task the game. This time, i forgot to end-task the game and got distracted only to come back to warnings about low disk space

3. I didn't expect task manager to show the rimworld.exe hammering my C: drive with non-stop writes until it filled. Luckily it was a smaller 120GB SSD and not my 4TB NVME, or it would have seriously hurt its lifespan.
The file should have some kind of safety net, with a maximum size?

4. Unfortunately 75GB was a little large to upload, and opening in text editors simply wasn't happening.

5. See attached images.


Side note: after deleting the log file, i can load the game and exit without it freezing - which is a fix, but also means i cant reproduce the problem.

Edit: Damn, not the issue. Got a different set of errors by cancelling fast and keeping it under 1GB


UnloadTime: 0.826100 ms
RimWorld 1.3.3159 rev754
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E717FC0030.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E717DC4530.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E717EC9420.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E717E8E120.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E717DC6D50.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E717F09450.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E6EAB70A20.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E717F5E190.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E717F72030.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E7181CF300.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E717F74A40.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E6EAA66370.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E7182B4D40.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E6EAA6DF80.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E7183F1010.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E7183F2C20.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E717F0F860.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E6EAD580D0.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E6EAD3C060.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E71841AA70.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E71843F4A0.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E71845BAC0.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E7184BB6E0.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E718562180.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E6EAD74140.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E71863D1D0.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E718640BE0.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E71896D020.dll
Fallback handler could not load library D:/Games/Steam/steamapps/common/RimWorld/RimWorldWin64_Data/Mono/data-000001E718B9A130.dll



Aaaaand then it goes on again with the older error

C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(152) : Error executing m_FMODChannel->isPlaying(playing) (The specified channel has been reused to play another sound. )
(Filename:  Line: 15)

C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(418) : Error executing m_FMODChannel->setFrequency(m_Pitch * m_DopplerPitch * m_BaseFrequency) (The specified channel has been reused to play another sound. )
(Filename:  Line: 15)

C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(152) : Error executing m_FMODChannel->isPlaying(playing) (The specified channel has been reused to play another sound. )


Canute

Hi,
yep some mod authors add some debug messages to their mod, but they forget to remove these once the mod is working.
But everytime you start rimworld the logfile get newly created.
Start the game
load the savegame
play a minute then pause.
Look at the log (enable developer mode at the option, then the top left icon).
Maybe you can identify the mod from the messages that appear there.

If you don't have any clue, you need to use the try&error hammer.
At first try it out at a new colony, just use the hugslib quickstart feature to 1 click start a test colony (top right icon shift leftclick).
Check the log if you still got log spam.
Deactivate some of your last mods and repeat it, until the log spam is gone.
Don't forget to leave a message for the author at the mod discussion.

I saw you have a red "!" before Scar Removal Plus.
That shouldn't be. Unsubscribe that mod, that would remove all loal data, then resubscribe.
Or maybe use the autosort button, i don't saw any requirement from that mod, but who knows.

Mussels

#2
Quote from: Canute on November 13, 2021, 08:12:06 AM
Hi,
yep some mod authors add some debug messages to their mod, but they forget to remove these once the mod is working.
But everytime you start rimworld the logfile get newly created.
Start the game
load the savegame
play a minute then pause.
Look at the log (enable developer mode at the option, then the top left icon).
Maybe you can identify the mod from the messages that appear there.

If you don't have any clue, you need to use the try&error hammer.
At first try it out at a new colony, just use the hugslib quickstart feature to 1 click start a test colony (top right icon shift leftclick).
Check the log if you still got log spam.
Deactivate some of your last mods and repeat it, until the log spam is gone.
Don't forget to leave a message for the author at the mod discussion.

I saw you have a red "!" before Scar Removal Plus.
That shouldn't be. Unsubscribe that mod, that would remove all loal data, then resubscribe.
Or maybe use the autosort button, i don't saw any requirement from that mod, but who knows.

Somethings odd with the scar removal because it's got 1.3 support but in game it says it doesnt. I'll delete and re-add it, and then try and find what was causing the oddities.

Oh, i validated files "133 files failed to validate"


Well.... that might be related.


It's... audio files?
This is spammed over and over

C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(152) : Error executing m_FMODChannel->isPlaying(playing) (The specified channel has been reused to play another sound. )
(Filename:  Line: 15)

Mussels

#3
Yeah, scanned for file changes, verified the mods were all good...


7GB logfile of this
(I do not have a folder by that name)


C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(393) : Error executing m_FMODChannel->stop() (The specified channel has been reused to play another sound. )
(Filename:  Line: 15)

C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(393) : Error executing m_FMODChannel->stop() (The specified channel has been reused to play another sound. )
(Filename:  Line: 15)

C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(393) : Error executing m_FMODChannel->stop() (The specified channel has been reused to play another sound. )
(Filename:  Line: 15)

C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(393) : Error executing m_FMODChannel->stop() (The specified channel has been reused to play another sound. )
(Filename:  Line: 15)

C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(393) : Error executing m_FMODChannel->stop() (The specified channel has been reused to play another sound. )
(Filename:  Line: 15)

C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(393) : Error executing m_FMODChannel->stop() (The specified channel has been reused to play another sound. )
(Filename:  Line: 15)

C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(393) : Error executing m_FMODChannel->stop() (The specified channel has been reused to play another sound. )
(Filename:  Line: 15)

C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(393) : Error executing m_FMODChannel->stop() (The specified channel has been reused to play another sound. )
(Filename:  Line: 15)

C:\buildslave\unity\build\Modules/Audio/Public/sound/SoundChannel.cpp(393) : Error executing m_FMODChannel->stop() (The specified channel has been reused to play another sound. )




searching has yielded others with the same issue

https://www.reddit.com/r/RimWorld/comments/enl278/output_logtxt_gets_filled_with/
https://ludeon.com/forums/index.php?topic=50080.0

Mussels

#4
Ugh i think i edited the wrong post and added confusion here: I could not find the source of the issue, but i DO recall hearing audio looping at some point and just saving and loading to fix it


currently set an empty log file to read only to workaround, but a serious and long standing bug :/

Canute

I think the audio issue is based on your mashine not mods.
Do you use any audio software for enhanced options or this kind or maybe headsets.
Maybe deactivate/uninstal these, that windows just use the plain audio driver.

Alternativ, you should disable all mods beside harmony/hugslib check if the problem happen on a vanilla gameplay too.
When you don't got error's half of your mods, check again until you found the mod with that strange behavior.
Then you could just delete the Sounds folder from that mod and maybe contact the mod author and ask for help.

Mussels

I went back through some save games back to before it happened and the issues gone early enough, so its not the mods specifically that cause the issue directly


At some point a sound could get stuck on loop, i remember a caravan leaving base that could hear gunfire and noises on loop from the main home base - saving, loading, issue was gone
And then the crash on exit started

It seems like that save game (and all after it) contain whatever is glitched out, causing the logspam to reappear. New saves are unaffected.

With the file read only i can ignore it until i finish this playthrough, but it feels like something  a mod should not be able to trigger to happen, especially when i looked it up on reddit and found its a ~2 year old bug.