CoP mission "Dawn" crash

tourmaline3333 commented 2 years ago

[x] I have paid attention to this example and will edit again if need be to not break the formatting, or I will be ignored
[x] I have searched existing issues to see if the issue has already been opened
[x] I have checked the commit log to see if the issue has been resolved since my server was last updated
[x] I have read the Contributing Guide
[x] I have specified what branch this happens on branch: EDITME

Additional Information (Steps to reproduce/Expected behavior) :

Client : 30211204_0 Server : base(82de1757f7f6731166b8c1d3c1fffb2bcbe17b98)

1, Start CoP mission "Dawn" 2, check the "Transcendental Radiance", and freeze. // In case of check the Resume Point, same.

Is ths the #1031 same issue? Anyone know solution of the issue?

Aeisu commented 2 years ago

I encountered the same issue. Not sure what happened but i know that rebooting the server fixed the issue and i was able to continue on with the mission and do the BCNM

towbes commented 10 months ago

Just wanted to add that I didn't have to restart the server when I encountered this bug. I force closed the character, then logged them back in and was able to interact with the Transcendental Radiance as expected to get the cut scene.

To add a datapoint, I died on the promathia fight, and encountered the same issue when I zoned back over to Empyreal Paradox. Fixed with the same force close and log back in method.

zach2good commented 10 months ago

Does anything appear in the logs? Missing Entity ID messages etc?

TeoTwawki commented 10 months ago

several ways this can happen, one of them is if the NPCs name were to be changed (as from ID shifts or from someone renaming it to make it more readable) and another is frame rate changes or addons/plugins that interfere with cs events.

towbes commented 10 months ago

FPS was set to 30. I did have enternity on, but also a plethora of other addons/plugins that could have been doing it. These are logs of me zoning into empyreal paradox, freezing, then force closing, loading game back up, and entering bcnm successfully. (then dying and returning to bastok mines)


[10/19/23 20:39:24:765][map][debug] CZone:: Empyreal_Paradox IncreaseZoneCounter <1> Kawaii (CZoneEntities::InsertPC:103)
[10/19/23 20:39:24:804][map][warn] [PacketGuard] Caught mismatch between player substate and recieved packet: Player: Kawaii - Packet: 001 (parse:779)
[10/19/23 20:40:48:968][map][debug] Message: Received message MSG_WORLD2MAP_REGIONAL_EVENT (20) from message server (message::parse:90)
[10/19/23 20:43:13:109][map][debug] Message: Received message MSG_WORLD2MAP_REGIONAL_EVENT (20) from message server (message::parse:90)
[10/19/23 20:45:37:235][map][debug] Message: Received message MSG_WORLD2MAP_REGIONAL_EVENT (20) from message server (message::parse:90)
[10/19/23 20:45:39:173][map][info] CTaskMgr Active Tasks: 198 (map_garbage_collect:1248)
[10/19/23 20:45:39:579][map][info] Garbage Collected (Full) (luautils::garbageCollectFull:370)
[10/19/23 20:45:39:579][map][info] Current State Top: 0, Total Memory Used: 91063kb -> 63817kb (luautils::garbageCollectFull:371)
[10/19/23 20:46:44:050][map][debug] CZone:: Bastok_Mines DecreaseZoneCounter <0> Shokunin (CZoneEntities::DecreaseZoneCounter:459)
[10/19/23 20:46:46:783][map][debug] CZone:: Windurst_Woods IncreaseZoneCounter <1> Shokunin (CZoneEntities::InsertPC:103)
[10/19/23 20:46:46:838][map][warn] parse: Unhandled game packet 001 from user: Shokunin (SmallPacket0x000:218)
[10/19/23 20:47:04:170][map][debug] map_cleanup: Kawaii timed out, closing session (map_cleanup:1145)
[10/19/23 20:47:04:170][map][debug] CZone:: Empyreal_Paradox DecreaseZoneCounter <0> Kawaii (CZoneEntities::DecreaseZoneCounter:459)
[10/19/23 20:47:09:166][map][debug] Clearing map server session for player: Kawaii in zone: None (On other map server = No) (map_cleanup:1177)
[10/19/23 20:47:37:563][map][info] SmallPacket0x0AA: Player 'Shokunin' purchased 12 of itemID 819 [from GUILD]  (SmallPacket0x0AA:5085)
[10/19/23 20:48:00:760][map][debug] SpawnMob: 17260613 <Backoo> is already spawned (CLuaBaseEntity::spawn:15143)
[10/19/23 20:48:00:913][map][debug] Message: Received message MSG_WORLD2MAP_REGIONAL_EVENT (20) from message server (message::parse:90)
[10/19/23 20:48:04:699][map][error] Invalid conquest region passed to function (21) (ConquestData::getRegionOwner:96)
[10/19/23 20:48:18:342][map][debug] Player <Kawaii> logging in to zone <36> (charutils::LoadChar:719)
[10/19/23 20:48:18:365][map][debug] CZone:: Empyreal_Paradox IncreaseZoneCounter <1> Kawaii (CZoneEntities::InsertPC:103)
[10/19/23 20:48:18:640][map][warn] parse: Unhandled game packet 001 from user: Kawaii (SmallPacket0x000:218)
[10/19/23 20:50:23:063][map][info] SmallPacket0x0AA: Player 'Shokunin' purchased 2 of itemID 818 [from GUILD]  (SmallPacket0x0AA:5085)
[10/19/23 20:50:25:019][map][debug] Message: Received message MSG_WORLD2MAP_REGIONAL_EVENT (20) from message server (message::parse:90)
[10/19/23 20:50:25:128][map][info] SmallPacket0x0AA: Player 'Shokunin' purchased 2 of itemID 825 [from GUILD]  (SmallPacket0x0AA:5085)
[10/19/23 20:51:15:406][map][debug] AH Action (04) (SmallPacket0x04E:3096)
[10/19/23 20:51:16:228][map][debug] AH Action (0b) (SmallPacket0x04E:3096)
[10/19/23 20:51:21:371][map][debug] AH Action (0e) (SmallPacket0x04E:3096)
[10/19/23 20:52:04:610][map][debug] Message: Received message MSG_PT_RELOAD (10) from message server (message::parse:90)
[10/19/23 20:52:49:158][map][debug] Message: Received message MSG_WORLD2MAP_REGIONAL_EVENT (20) from message server (message::parse:90)
[10/19/23 20:52:49:632][map][debug] AH Action (0e) (SmallPacket0x04E:3096)
[10/19/23 20:55:12:773][map][debug] Message: Received message MSG_WORLD2MAP_REGIONAL_EVENT (20) from message server (message::parse:90)
[10/19/23 20:55:35:423][map][warn] [PacketGuard] Caught mismatch between player substate and recieved packet: Player: Kawaii - Packet: 050 (parse:779)
[10/19/23 20:55:37:432][map][warn] [PacketGuard] Caught mismatch between player substate and recieved packet: Player: Kawaii - Packet: 050 (parse:779)
[10/19/23 20:55:39:501][map][warn] [PacketGuard] Caught mismatch between player substate and recieved packet: Player: Kawaii - Packet: 050 (parse:779)
[10/19/23 20:55:41:574][map][warn] [PacketGuard] Caught mismatch between player substate and recieved packet: Player: Kawaii - Packet: 050 (parse:779)
[10/19/23 20:55:43:639][map][warn] [PacketGuard] Caught mismatch between player substate and recieved packet: Player: Kawaii - Packet: 050 (parse:779)
[10/19/23 20:55:44:766][map][debug] [CBattlefieldHandler]HandleBattlefields cleaned up Battlefield dawn (CBattlefieldHandler::HandleBattlefields:79)
[10/19/23 20:55:45:714][map][warn] [PacketGuard] Caught mismatch between player substate and recieved packet: Player: Kawaii - Packet: 050 (parse:779)
[10/19/23 20:55:55:855][map][debug] CZone:: Empyreal_Paradox DecreaseZoneCounter <0> Kawaii (CZoneEntities::DecreaseZoneCounter:459)
[10/19/23 20:55:58:729][map][debug] CZone:: Bastok_Mines IncreaseZoneCounter <1> Kawaii (CZoneEntities::InsertPC:103)```

zach2good commented 10 months ago

[10/19/23 20:55:35:423][map][warn] [PacketGuard] Caught mismatch between player substate and recieved packet: Player: Kawaii - Packet: 050 (parse:779)
[10/19/23 20:55:37:432][map][warn] [PacketGuard] Caught mismatch between player substate and recieved packet: Player: Kawaii - Packet: 050 (parse:779)
[10/19/23 20:55:39:501][map][warn] [PacketGuard] Caught mismatch between player substate and recieved packet: Player: Kawaii - Packet: 050 (parse:779)
[10/19/23 20:55:41:574][map][warn] [PacketGuard] Caught mismatch between player substate and recieved packet: Player: Kawaii - Packet: 050 (parse:779)

Likely these are the culprits, packet guard blocks packets if it thinks they're suspicious. Sounds like its firing on a false positive.

Try again with packet guard turned off and see if it still happens:

https://github.com/LandSandBoat/server/blob/base/settings/default/map.lua#L34

towbes commented 10 months ago

This was the second time, and yea looks like there was another packetguard. I can try pressing the battlefield again but not sure if I'll be able to reproduce after finishing it on that char. Will test it out again when I make a new char and fast forward their missions hopefully. At least have some more information for next time! Thank you for taking a look

[10/19/23 20:55:55:855][map][debug] CZone:: Empyreal_Paradox DecreaseZoneCounter <0> Kawaii (CZoneEntities::DecreaseZoneCounter:459)
[10/19/23 20:55:58:729][map][debug] CZone:: Bastok_Mines IncreaseZoneCounter <1> Kawaii (CZoneEntities::InsertPC:103)
[10/19/23 20:55:58:773][map][warn] parse: Unhandled game packet 001 from user: Kawaii (SmallPacket0x000:218)
[10/19/23 20:57:36:996][map][debug] Message: Received message MSG_WORLD2MAP_REGIONAL_EVENT (20) from message server (message::parse:90)
[10/19/23 20:57:44:041][map][debug] AH Action (0e) (SmallPacket0x04E:3096)
[10/19/23 20:58:11:133][map][debug] CZone:: Bastok_Mines DecreaseZoneCounter <0> Kawaii (CZoneEntities::DecreaseZoneCounter:459)
[10/19/23 20:58:13:984][map][debug] CZone:: The_Garden_of_RuHmet IncreaseZoneCounter <1> Kawaii (CZoneEntities::InsertPC:103)
[10/19/23 20:58:14:037][map][warn] parse: Unhandled game packet 001 from user: Kawaii (SmallPacket0x000:218)
[10/19/23 20:58:30:677][map][debug] CZone:: The_Garden_of_RuHmet DecreaseZoneCounter <0> Kawaii (CZoneEntities::DecreaseZoneCounter:459)
[10/19/23 20:58:33:564][map][debug] CZone:: Empyreal_Paradox IncreaseZoneCounter <1> Kawaii (CZoneEntities::InsertPC:103)
[10/19/23 20:58:33:607][map][warn] [PacketGuard] Caught mismatch between player substate and recieved packet: Player: Kawaii - Packet: 001 (parse:779)
[10/19/23 21:00:01:042][map][debug] Message: Received message MSG_WORLD2MAP_REGIONAL_EVENT (20) from message server (message::parse:90)
[10/19/23 21:00:39:169][map][info] CTaskMgr Active Tasks: 198 (map_garbage_collect:1248)
[10/19/23 21:00:39:279][map][info] Garbage Collected (Full) (luautils::garbageCollectFull:370)
[10/19/23 21:00:39:279][map][info] Current State Top: 0, Total Memory Used: 49692kb -> 47184kb (luautils::garbageCollectFull:371)
[10/19/23 21:01:24:183][map][debug] map_cleanup: Kawaii timed out, closing session (map_cleanup:1145)
[10/19/23 21:01:24:183][map][debug] CZone:: Empyreal_Paradox DecreaseZoneCounter <0> Kawaii (CZoneEntities::DecreaseZoneCounter:459)
[10/19/23 21:01:29:163][map][debug] Clearing map server session for player: Kawaii in zone: None (On other map server = No) (map_cleanup:1177)
[10/19/23 21:01:50:606][map][debug] Player <Kawaii> logging in to zone <36> (charutils::LoadChar:719)
[10/19/23 21:01:50:628][map][debug] CZone:: Empyreal_Paradox IncreaseZoneCounter <1> Kawaii (CZoneEntities::InsertPC:103)

TeoTwawki commented 10 months ago

there is a player on my own server tripping packet guard on various cs, one of their addon/plugins trips it but we haven't narrowed it down yet. they tripped it on a non-battlefield one. once they logged in without any addon/plugin they got past the cs event they needed.

towbes commented 10 months ago

Hm actually my map server is showing a warning for just about every time I enter a zone on all my chars...

[10/19/23 16:52:49:950][map][debug] CZone:: Grand_Palace_of_HuXzoi IncreaseZoneCounter <1> Kawaii (CZoneEntities::InsertPC:103)
[10/19/23 16:52:50:061][map][warn] parse: Unhandled game packet 001 from user: Kawaii (SmallPacket0x000:218)
[10/19/23 16:53:36:155][map][debug] CZone:: Grand_Palace_of_HuXzoi DecreaseZoneCounter <0> Kawaii (CZoneEntities::DecreaseZoneCounter:459)
[10/19/23 16:53:38:998][map][debug] CZone:: The_Garden_of_RuHmet IncreaseZoneCounter <1> Kawaii (CZoneEntities::InsertPC:103)
[10/19/23 16:53:39:136][map][warn] parse: Unhandled game packet 001 from user: Kawaii (SmallPacket0x000:218)
[10/19/23 16:54:36:150][map][info] Zoning from zone 35 to zone 34: Kawaii (SmallPacket0x05E:3980)
[10/19/23 16:54:36:564][map][debug] CZone:: The_Garden_of_RuHmet DecreaseZoneCounter <0> Kawaii (CZoneEntities::DecreaseZoneCounter:459)
[10/19/23 16:54:39:445][map][debug] CZone:: Grand_Palace_of_HuXzoi IncreaseZoneCounter <1> Kawaii (CZoneEntities::InsertPC:103)
[10/19/23 16:54:39:487][map][warn] parse: Unhandled game packet 001 from user: Kawaii (SmallPacket0x000:218)
[10/19/23 16:55:13:226][map][debug] Message: Received message MSG_WORLD2MAP_REGIONAL_EVENT (20) from message server (message::parse:90)
[10/19/23 16:55:22:975][map][info] Zoning from zone 34 to zone 34: Kawaii (SmallPacket0x05E:3980)
[10/19/23 16:55:23:401][map][debug] CZone:: Grand_Palace_of_HuXzoi DecreaseZoneCounter <0> Kawaii (CZoneEntities::DecreaseZoneCounter:459)
[10/19/23 16:55:26:221][map][debug] CZone:: Grand_Palace_of_HuXzoi IncreaseZoneCounter <1> Kawaii (CZoneEntities::InsertPC:103)
[10/19/23 16:55:26:264][map][warn] parse: Unhandled game packet 001 from user: Kawaii (SmallPacket0x000:218)
[10/19/23 16:56:57:047][map][info] Zoning from zone 34 to zone 33: Kawaii (SmallPacket0x05E:3980)
[10/19/23 16:56:57:466][map][debug] CZone:: Grand_Palace_of_HuXzoi DecreaseZoneCounter <0> Kawaii (CZoneEntities::DecreaseZoneCounter:459)
[10/19/23 16:57:00:227][map][debug] CZone:: AlTaieu IncreaseZoneCounter <1> Kawaii (CZoneEntities::InsertPC:103)
[10/19/23 16:57:00:267][map][warn] parse: Unhandled game packet 001 from user: Kawaii (SmallPacket0x000:218)

Wasn't getting that error anymore once I reloaded with clean default.txt from ashita site https://github.com/AshitaXI/Ashita-v4beta/blob/main/scripts/default.txt

Going to load one by one and see...

Update: Started getting the error again when I loaded dynamic_entity_renamer addon (1.0.0.0) . I think that's in there as an artifact of a previous horizon install or something...Was that intended to be used with default client or only for specific use case?

zach2good commented 10 months ago

I can only assume Horizon turned off packet guard or doesn't look at their logs if the dynamic renamer comes with their default install. Or they didn't tell us there was this problem 🤷

LandSandBoat / server

CoP mission "Dawn" crash #1162