Crashed, odd WatchDog entry in ClientLog

I crashed earlier today which is not a surprise at all. It happens multiple times per day normally. There usually isn’t anything of note in the ClientLog, but today I caught something interesting.

[2018-12-19 21:22:07Z #372482] [ID:0] DATAERROR: ResourceManager - RDBSource_c::ProcessRequest. Resource 1000622:0 does not exist in any available RDB sources.
[2018-12-19 21:22:07Z #372483] [ID:0] DATAERROR: ResourceManager - class RunningEffectPackage requested child resource 1000622:0 which does not exist in any RDB sources.
[2018-12-19 21:22:07Z #372486] [ID:0] DATAERROR: ResourceManager - RDBSource_c::ProcessRequest. Resource 1000622:4117453184 does not exist in any available RDB sources.
[2018-12-19 21:22:07Z #372487] [ID:0] DATAERROR: ResourceManager - class RunningEffectPackage requested child resource 1000622:4117453184 which does not exist in any RDB sources.
[2018-12-19 21:22:08Z #372497] [ID:0] DATAERROR: ResourceManager - RDBSource_c::ProcessRequest. Resource 1000622:9503605 does not exist in any available RDB sources.
[2018-12-19 21:22:08Z #372498] [ID:0] DATAERROR: ResourceManager - class RunningEffectPackage requested child resource 1000622:9503605 which does not exist in any RDB sources.
[2018-12-19 21:22:08Z #372503] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:08Z #372503] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:08Z #372537] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:08Z #372537] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:08Z #372543] [ID:0] ERROR: MagicCommand - Trying to prepone the execute timeline to the pass. Spell:6307512
[2018-12-19 21:22:09Z #372557] [ID:0] DATAERROR: ResourceManager - RDBSource_c::ProcessRequest. Resource 1000622:1403296488 does not exist in any available RDB sources.
[2018-12-19 21:22:09Z #372558] [ID:0] DATAERROR: ResourceManager - class RunningEffectPackage requested child resource 1000622:1403296488 which does not exist in any RDB sources.
[2018-12-19 21:22:09Z #372596] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:09Z #372596] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:10Z #372618] [ID:0] ERROR: MagicCommand - Trying to prepone the execute timeline to the pass. Spell:6307512
[2018-12-19 21:22:10Z #372623] [ID:0] WARNING: Visual.Effects - AddTracer: could find attractor 139 on src mesh
[2018-12-19 21:22:10Z #372625] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:10Z #372625] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:10Z #372633] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:10Z #372633] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:10Z #372663] [ID:0] WARNING: Visual.Effects - AddTracer: could find attractor 139 on src mesh
[2018-12-19 21:22:11Z #372683] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:11Z #372683] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:11Z #372718] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:11Z #372718] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:12Z #372764] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:12Z #372764] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:12Z #372774] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:12Z #372774] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:13Z #372795] [ID:0] WARNING: Visual.Effects - AddTracer: could find attractor 139 on src mesh
[2018-12-19 21:22:14Z #372854] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:14Z #372854] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:14Z #372865] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:14Z #372865] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:15Z #372911] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:15Z #372911] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:15Z #372952] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:15Z #372952] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:16Z #372984] [ID:0] WARNING: Visual.Effects - AddTracer: could find attractor 139 on src mesh
[2018-12-19 21:22:16Z #372988] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:16Z #372988] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:16Z #373012] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:16Z #373012] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:17Z #373056] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:17Z #373056] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:17Z #373056] [ID:0] DATAERROR: ResourceManager - RDBSource_c::ProcessRequest. Resource 1010030:7085468 does not exist in any available RDB sources.
[2018-12-19 21:22:17Z #373057] [ID:0] DATAERROR: ResourceManager - class RunningEffectPackage requested child resource 1010030:7085468 which does not exist in any RDB sources.
[2018-12-19 21:22:17Z #373094] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:17Z #373094] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:18Z #373104] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:18Z #373104] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:18Z #373129] [ID:0] WARNING: Visual.Effects - AddTracer: could find attractor 139 on src mesh
[2018-12-19 21:22:19Z #373174] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:19Z #373174] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:19Z #373182] [ID:0] ERROR: MagicCommand - Trying to prepone the execute timeline to the pass. Spell:6307512
[2018-12-19 21:22:19Z #373191] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:19Z #373191] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:20Z #373246] [ID:0] WARNING: Items - GameItem::LoadStaticData: resource data 1000621:0 not valid…
[2018-12-19 21:22:20Z #373246] [ID:0] WARNING: Items - GameItem::LoadStaticDataOld: resource data 1040005:0 not valid…
[2018-12-19 21:22:21Z #373275] [ID:0] DATAERROR: ResourceManager - RDBSource_c::ProcessRequest. Resource 1000622:1941194584 does not exist in any available RDB sources.
[2018-12-19 21:22:21Z #373276] [ID:0] DATAERROR: ResourceManager - class RunningEffectPackage requested child resource 1000622:1941194584 which does not exist in any RDB sources.
[2018-12-19 21:22:21Z #373280] [ID:0] DATAERROR: ResourceManager - RDBSource_c::ProcessRequest. Resource 1000622:1945088680 does not exist in any available RDB sources.
[2018-12-19 21:22:21Z #373281] [ID:0] DATAERROR: ResourceManager - class RunningEffectPackage requested child resource 1000622:1945088680 which does not exist in any RDB sources.
[2018-12-19 21:22:21Z #373290] [ID:0] DATAERROR: ResourceManager - RDBSource_c::ProcessRequest. Resource 1000622:0 does not exist in any available RDB sources.
[2018-12-19 21:22:21Z #373291] [ID:0] DATAERROR: ResourceManager - class RunningEffectPackage requested child resource 1000622:0 which does not exist in any RDB sources.
[2018-12-19 21:22:32Z #374000] [ID:0] ERROR: Lua - FC15E058
[string “GlobalLuaScripts.LUA_SCRIPT[29530]”]:2: attempt to call method ‘removebuff’ (a nil value)
stack traceback:
[string “GlobalLuaScripts.LUA_SCRIPT[29530]”]:2: in function <[string “GlobalLuaScripts.LUA_SCRIPT[29530]”]:1>
[2018-12-19 21:22:32Z #374000] [ID:0] ERROR: Lua.Callbacks - PCall failed for callback luascript:29530 registrered:0 on env:18454 for spell:0 owned by:[Identity 50000:16782194]
[2018-12-19 21:22:33Z #374051] [ID:0] ERROR: Scaleform.TSWACT - Out of combat - |Daestrus|Agnetha|Kasmodian|Radiac|Warzazath|
[2018-12-19 21:23:04Z #375666] [ID:0] ERROR: Scaleform.TSWACT - Enter combat - |Daestrus|Buffs:AP Boost:SP Boost|
[2018-12-19 21:23:12Z #376284] [ID:0] ERROR: Scaleform.TSWACT - Out of combat - |Daestrus|Agnetha|Kasmodian|Radiac|Warzazath|
[2018-12-19 21:23:15Z #376499] [ID:0] IMPORTANT: Test - Got voice handle 79069204 for knubotid 1097 and textinstance 13655
[2018-12-19 21:23:20Z #376864] [ID:0] ERROR: Scaleform.TSWACT - Enter combat - |Daestrus|Buffs:AP Boost:SP Boost|
[2018-12-19 21:23:20Z #376896] [ID:0] ERROR: MagicCommand - Trying to prepone the execute timeline to the pass. Spell:6961684
[2018-12-19 21:23:24Z #377224] [ID:0] ERROR: MagicCommand - Trying to prepone the execute timeline to the pass. Spell:7920433
[2018-12-19 21:23:26Z #377369] [ID:0] ERROR: Scaleform.TSWACT - Out of combat - |Daestrus|Agnetha|Kasmodian|Radiac|Warzazath|
[2018-12-19 21:23:27Z #377467] [ID:0] ERROR: MagicCommand - Trying to prepone the execute timeline to the pass. Spell:7664428
[2018-12-19 21:23:38Z #378206] [ID:0] ERROR: Scaleform.TSWACT - Enter combat - |Daestrus|Buffs:AP Boost:Enflamed:SP Boost|
[2018-12-19 21:23:40Z #378295] [ID:0] WARNING: Control.Anim - [Dynast of the Seep] Did not have any base animations to run ?
[2018-12-19 21:23:40Z #378326] [ID:0] ERROR: MagicCommand - Trying to prepone the execute timeline to the pass. Spell:7913185
[2018-12-19 21:23:44Z #378328] [ID:0] WARNING: Audio - UpdateMusic: music system has begun to starve
[2018-12-19 21:23:45Z #378328] [ID:0] WARNING: Audio - UpdateMusic: music system has ended starving
[2018-12-19 21:23:56Z #378328] [ID:0] ERROR: WatchDog - Too long since last pulse check: 11 seconds > 0 seconds. Thread starvation!

The interesting line is the very last one. If I’m not mistaken, the WatchDog is Funcom’s anti-cheat protocol. So it seems like the WatchDog thought something fishy was happening on my side so it disconnected me from the game. It goes without saying that I am not doing anything that should be construed as cheating. I’m not using any modified game files (besides run of the mill addons) and I’m not using anything that should be messing with memory or with network packets. I don’t know what a “pulse check” is, but I can guess. I certainly don’t know why it would fail to go off for 11 seconds.

We were in HE running through the gauntlet. We got to the well and I turned around to let the pack of mobs kill me so they would reset. I ran up to them and used Ground Pound once so at least some of them would hit me right away and the very instant I pressed the Ground Pound button the game crashed instantly to the crash dialog box.

Hopefully a FC dev can help with this one or at least find it somewhat useful?

If watchdogs is what I believe it is than it isn’t as much a anti-cheat software as an internal check if a program isstill running. A timer that resets every “pulsecheck” given by the game (or other program) if it isn’t reset/the program crashes the timer runs to a certain point (11 seconds) and the system assumes that the program has stopped working.

If we assume that the crash happened in these 11 seconds, given the timestamps it seems that it might be connected to the Audio - UpdateMusic subsystem crashing for some reason. Why this happened? No freaking clue.
I don’t even know what UpdateMusic does apart from the obvious or why it could be system critical.

Any devs around that can share some light into the inner workings of the game?

Have you tried to repair your RDB files ?

It’s definitely the anticheat system.

For the record, my game sound volume is almost always set to 0. It was set to 0 when the crash happened.

Those are normal entries in the ClientLog.

Got this one today. Game crashed as soon as I looted the final chest after Klein.

[2019-02-06 17:01:04Z #307231] [ID:0] WARNING: GameCode.Script - 'TheAnkh_ClientDialog' (7649347) 'onmessage' (2) 'dbprintf' (78) : "NONSENSE MESSAGE RECEIVED: CombatMusic_Normal" [2019-02-06 17:01:04Z #307236] [ID:0] IMPORTANT: Test - Got voice handle 59095073 for knubotid 1116 and textinstance 14907 [2019-02-06 17:01:05Z #307291] [ID:0] ERROR: GameCode.simpledynel - SimpleDynelVisualChangeIIR_c::SimpleDynelVisualChangeIIR_c(): e_FadeIn: SimpleDynel: Name: resurrection_shield_a has no mesh! [2019-02-06 17:01:19Z #307571] [ID:0] WARNING: WatchDog - Main: No contact for 5 seconds ++

Another Music reference and WatchDog warning.

I do love that error though. NONSENSE MESSAGE RECEIVED. Classic.

I’ve been getting crashes after looting chests a lot recently. Been happening in lairs a bunch. Will have to start looking at more ClientLogs.