Crashing PureEntities/Pocketmine (different error behaviour)

Sonnenwerk commented 7 years ago

Fri_May_19-22.48.27-CEST_2017.txt Sun_Jun_4-22.00.46-CEST_2017.txt Sun_Jun_4-22.16.39-CEST_2017.txt

[x] This issue isn't duplicated - you can check if it is by using the search bar located at the top left hand corner and select "Issues" on the left.
[x] This issue includes appropriate markdown for sections - e.g. code blocks for crash dumps.
[x] This issue is understandable - feel free to use your native language to write issues if you are not comfortable with English.

Issue description

When i log in, the Sky is flickering and stars move erratically.--> can´t move or see normal landscape. Sometimes it does not let me connect, sometimes it stucks while generating landscape Only once of 6-10 times i tried after a server/client/whatever restart, there was an entry in the log. I think it also generated a crashdump (Friday). This happend with devversion #118 of pocketmine. Now i installed #137 and enabled pureentities again. Server startet, generated a lot of server log entries and a crashdump

Reproducing the issue

...
...

Client information

PocketMine-MP_1.6.2dev-137_efea39e0_API-3.0.0-ALPHA5.phar
https://poggit.pmmp.io/ci/RevivalPMMP/PureEntitiesX/~/dev:87 /Loading PureEntitiesX v0.2.2_dev PHP version: 7.0.13 (default) ( i don´t know where to see the exact php Version..Main Version is 7)

Optional information

[22:14:25] [Server thread/NOTICE]: [PureEntitiesX] You're Running PureEntitiesX v0.2.2_dev
[22:14:25] [Server thread/INFO]: Preparing level "Pigland101"
[22:14:25] [Server thread/INFO]: Enabling essentialsTP v1.1.1
[22:14:25] [Server thread/INFO]: [essentialsTP] essentialsTP+ loading...
[22:14:25] [Server thread/INFO]: [essentialsTP] [INFO] loading [config.yml]....
[22:14:25] [Server thread/INFO]: [essentialsTP] [INFO] loading [config.yml] DONE
[22:14:25] [Server thread/INFO]: [essentialsTP] essentialsTP+ loaded!
[22:14:25] [Server thread/INFO]: Enabling SignPortal v1.1.3
[22:14:25] [Server thread/INFO]: Starting GS4 status listener
[22:14:25] [Server thread/INFO]: Setting query port to 19132
[22:14:25] [Server thread/INFO]: Query running on 81.30.154.111:19132
[22:14:25] [Server thread/INFO]: Default game type: Survival Mode
[22:14:25] [Server thread/INFO]: Done (1.495s)! For help, type "help" or "?"
[22:16:34] [Server thread/INFO]: Goldtaler[/84.168.199.124:56938] logged in with entity id 1 at (Pigland101, 385, 66, -30)
[22:16:38] [Server thread/DEBUG]: Level "Pigland101" took 84.84ms, setting tick rate to 2 ticks
[22:16:38] [Server thread/DEBUG]: Raising level "Pigland101" tick rate to 1 ticks
[22:16:39] [Server thread/DEBUG]: Level "Pigland101" took 106.21ms, setting tick rate to 2 ticks
[22:16:39] [Server thread/DEBUG]: Raising level "Pigland101" tick rate to 1 ticks
[22:16:39] [Server thread/INFO]: Goldtaler joined the game
[22:16:39] [Server thread/CRITICAL]: TypeError: "Argument 1 passed to pocketmine\utils\Binary::writeVarLong_64() must be of the type integer, null given, called in phar:///home/s1229630/pocketmine/PocketMine-MP.phar/src/pocketmine/utils/Binary.php on line 650" (EXCEPTION) in "/src/pocketmine/utils/Binary" at line 677
[22:16:39] [Server thread/DEBUG]: #0 /src/pocketmine/utils/Binary(650): pocketmine\utils\Binary::writeVarLong_64(NULL )
[22:16:39] [Server thread/DEBUG]: #1 /src/pocketmine/utils/BinaryStream__64bit(343): pocketmine\utils\Binary::writeVarLong(NULL )
[22:16:39] [Server thread/DEBUG]: #2 /src/pocketmine/network/mcpe/protocol/DataPacket(278): pocketmine\utils\BinaryStream->putVarLong(NULL )
[22:16:39] [Server thread/DEBUG]: #3 /src/pocketmine/network/mcpe/protocol/AddEntityPacket(78): pocketmine\network\mcpe\protocol\DataPacket->putEntityUniqueId(NULL )
[22:16:39] [Server thread/DEBUG]: #4 /src/pocketmine/network/mcpe/RakLibInterface(193): pocketmine\network\mcpe\protocol\AddEntityPacket->encode(boolean)
[22:16:39] [Server thread/DEBUG]: #5 /src/pocketmine/Player__64bit(1081): pocketmine\network\mcpe\RakLibInterface->putPacket(pocketmine\Player object, pocketmine\network\mcpe\protocol\AddEntityPacket object, boolean , boolean )
[22:16:39] [Server thread/DEBUG]: #6 /PureEntitiesX.phar/src/revivalpmmp/pureentities/entity/BaseEntity(218): pocketmine\Player->dataPacket(pocketmine\network\mcpe\protocol\AddEntityPacket object)
[22:16:39] [Server thread/DEBUG]: #7 /src/pocketmine/Player__64bit(913): revivalpmmp\pureentities\entity\BaseEntity->spawnTo(pocketmine\Player object)
[22:16:39] [Server thread/DEBUG]: #8 /src/pocketmine/Player__64bit(861): pocketmine\Player->doFirstSpawn(boolean)
[22:16:39] [Server thread/DEBUG]: #9 /src/pocketmine/Player__64bit(1743): pocketmine\Player->sendNextChunk(boolean)
[22:16:39] [Server thread/DEBUG]: #10 /src/pocketmine/Server__64bit(2420): pocketmine\Player->checkNetwork(boolean)
[22:16:39] [Server thread/DEBUG]: #11 /src/pocketmine/Server__64bit(2182): pocketmine\Server->tick(boolean)
[22:16:39] [Server thread/DEBUG]: #12 /src/pocketmine/Server__64bit(2064): pocketmine\Server->tickProcessor(boolean)
[22:16:39] [Server thread/DEBUG]: #13 /src/pocketmine/Server__64bit(1646): pocketmine\Server->start(boolean)
[22:16:39] [Server thread/DEBUG]: #14 /src/pocketmine/PocketMine(501): pocketmine\Server->__construct(BaseClassLoader object, pocketmine\utils\MainLogger object, string phar:///home/s1229630/pocketmine/PocketMine-MP.phar/, string /home/s1229630/pocketmine/, string /home/s1229630/pocketmine/plugins/)
[22:16:39] [Server thread/DEBUG]: #15 (1): require(string phar:///home/s1229630/pocketmine/PocketMine-MP.phar/src/pocketmine/PocketMine.php)
[22:16:39] [Server thread/EMERGENCY]: An unrecoverable error has occurred and the server has crashed. Creating a crash dump
[22:16:39] [Server thread/EMERGENCY]: Please upload the "/home/s1229630/pocketmine/crashdumps/Sun_Jun_4-22.16.39-CEST_2017.log" file to the Crash Archive and submit the link to the Bug Reporting page. Give as much info as you can.
[22:16:40] [Server thread/DEBUG]: Disabling all plugins
[22:16:40] [Server thread/INFO]: Disabling PureEntitiesX v0.2.2_dev
[22:16:40] [Server thread/NOTICE]: [PureEntitiesX] Disabled!
[22:16:40] [Server thread/INFO]: Disabling essentialsTP v1.1.1
[22:16:40] [Server thread/INFO]: [essentialsTP] essentialsTP+ Disabled
[22:16:40] [Server thread/INFO]: Disabling SignPortal v1.1.3
[22:16:40] [Server thread/INFO]: Goldtaler[/84.168.199.124:56938] logged out due to Server closed
[22:16:40] [Server thread/DEBUG]: Unloading all levels
[22:16:40] [Server thread/INFO]: Unloading level "Pigland101"
[22:16:40] [Server thread/DEBUG]: Removing event handlers
[22:16:40] [Server thread/DEBUG]: Stopping all tasks
[22:16:44] [Server thread/DEBUG]: Saving properties
[22:16:44] [Server thread/DEBUG]: Closing console
[22:16:44] [Server thread/DEBUG]: Stopping network interfaces

The above log shows a crash. The lower log shows another try.

21:59:20] [Server thread/INFO]: [PureEntitiesX] The Original Code for this Plugin was Written by milk0417. It is now being maintained by RevivalPMMP for PMMP 'Unleashed'.
[21:59:20] [Server thread/INFO]: [PureEntitiesX] Setting loglevel of logfile to warn
[21:59:20] [Server thread/INFO]: Loading essentialsTP v1.1.1
[21:59:20] [Server thread/INFO]: Loading SignPortal v1.1.3
[21:59:20] [Server thread/INFO]: Enabling PureEntitiesX v0.2.2_dev
[21:59:20] [Server thread/NOTICE]: [PureEntitiesX] Configuration loaded: [enableNBT:1] [enableSpawn:1] [enableAsyncTasks:1] [enableLookingTasks:1] [loggingEnabled:1] [findPartnerDistance:49] [interactDistance:6] [teleportTamedDistance:20] [tamedFollowDistance:14] [blockOfInterestTicks:100] [checkTargetSkipTicks:5] [pickupLootTicks:10] [interactiveButtonCorrection:1] [useBlockLight:] [useSkyLight:] [emitLoveParticles:] [xpEnabled:] [idleChance:20] [idleMin:100] [idleMax:500] [idleTimeBetween:60secs] [panicEnabled:1] [panicTicks:100] [entityMaxAge:72000]
[21:59:20] [Server thread/NOTICE]: [PureEntitiesX] Enabled!
[21:59:20] [Server thread/NOTICE]: [PureEntitiesX] You're Running PureEntitiesX v0.2.2_dev
[21:59:20] [Server thread/INFO]: Preparing level "Pigland101"
[21:59:20] [Server thread/INFO]: Enabling essentialsTP v1.1.1
[21:59:20] [Server thread/INFO]: [essentialsTP] essentialsTP+ loading...
[21:59:20] [Server thread/INFO]: [essentialsTP] [INFO] loading [config.yml]....
[21:59:20] [Server thread/INFO]: [essentialsTP] [INFO] loading [config.yml] DONE
[21:59:20] [Server thread/INFO]: [essentialsTP] essentialsTP+ loaded!
[21:59:20] [Server thread/INFO]: Enabling SignPortal v1.1.3
[21:59:20] [Server thread/INFO]: Starting GS4 status listener
[21:59:20] [Server thread/INFO]: Setting query port to 19132
[21:59:20] [Server thread/INFO]: Query running on 81.30.154.111:19132
[21:59:20] [Server thread/INFO]: Default game type: Survival Mode
[21:59:20] [Server thread/INFO]: Done (1.492s)! For help, type "help" or "?"
[21:59:37] [Server thread/INFO]: Goldtaler[/84.168.199.124:56938] logged in with entity id 1 at (Pigland101, 151.3471, 27, 228.4028)
[21:59:41] [Server thread/DEBUG]: Level "Pigland101" took 163.49ms, setting tick rate to 3 ticks
[21:59:41] [Server thread/DEBUG]: Raising level "Pigland101" tick rate to 2 ticks
[21:59:41] [Server thread/DEBUG]: Raising level "Pigland101" tick rate to 1 ticks
[21:59:41] [Server thread/INFO]: Goldtaler joined the game
[21:59:42] [Server thread/DEBUG]: Unhandled MobArmorEquipmentPacket received from Goldtaler: 0x2001e4040200000000e6040200000000e804020000000000
[21:59:47] [Server thread/WARNING]: Can't keep up! Is the server overloaded?
[21:59:57] [Server thread/CRITICAL]: Could not execute task revivalpmmp\pureentities\task\AutoSpawnTask: Argument 1 passed to pocketmine\utils\Binary::writeVarLong_64() must be of the type integer, null given, called in phar:///home/s1229630/pocketmine/PocketMine-MP.phar/src/pocketmine/utils/Binary.php on line 650
[21:59:57] [Server thread/CRITICAL]: TypeError: "Argument 1 passed to pocketmine\utils\Binary::writeVarLong_64() must be of the type integer, null given, called in phar:///home/s1229630/pocketmine/PocketMine-MP.phar/src/pocketmine/utils/Binary.php on line 650" (EXCEPTION) in "/src/pocketmine/utils/Binary" at line 677
[21:59:57] [Server thread/DEBUG]: #0 /src/pocketmine/utils/Binary(650): pocketmine\utils\Binary::writeVarLong_64(NULL )
[21:59:57] [Server thread/DEBUG]: #1 /src/pocketmine/utils/BinaryStream64bit(343): pocketmine\utils\Binary::writeVarLong(NULL )
[21:59:57] [Server thread/DEBUG]: #2 /src/pocketmine/network/mcpe/protocol/DataPacket(278): pocketmine\utils\BinaryStream->putVarLong(NULL )
[21:59:57] [Server thread/DEBUG]: #3 /src/pocketmine/network/mcpe/protocol/AddEntityPacket(78): pocketmine\network\mcpe\protocol\DataPacket->putEntityUniqueId(NULL )
[21:59:57] [Server thread/DEBUG]: #4 /src/pocketmine/network/mcpe/RakLibInterface(193): pocketmine\network\mcpe\protocol\AddEntityPacket->encode(boolean)
[21:59:57] [Server thread/DEBUG]: #5 /src/pocketmine/Player64bit(1081): pocketmine\network\mcpe\

and i have (found) the crashdump too..

kniffo80 commented 7 years ago

Please try latest https://github.com/RevivalPMMP/PureEntitiesX/tree/damage-reduction together with latest master of PMMP. It should work ...

Sonnenwerk commented 7 years ago

I would give it a try, but i am unsure what to do with the link (code). Is there a automatic poggit build somewhere, i can download ? Or shall i use the .zip download function, rename it to .phar ?

Sonnenwerk commented 7 years ago

o/ I have seen, that there is a compiled version at poggit (dev #68). I tried this one with pocketmine dev #155. But it threw a lot of error on startup. Any suggestions, with what version i shall test the new pureentities ?

kniffo80 commented 7 years ago

You can try this one: https://github.com/RevivalPMMP/PureEntitiesX/releases/tag/0.2.2_dev2 (the pre-built phar)

Sonnenwerk commented 7 years ago

Thanks, Michael ! This version seems to work fine (at least it does work). We will test it more on our server.

Sonnenwerk commented 7 years ago

Ok, there are some error messages:

[13:22:10] [Server thread/DEBUG]: Tried to equip Item Diamond Sword (276:154)x1 but have Item Diamond Sword (276:155)x1 in target slot
[13:22:10] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Legolas: 0x1f01a80482e80400000000090000

Those seem to be the old ones with "MobEquipment" But there seem to be some new also. Which i have not encoundered before:

object, string phar:///home/s1229630/pocketmine/PocketMine-MP.phar/, string /home/s1229630/pocketmine/, string /home/s1229630/pocketmine/plugins/)
[13:20:48] [Server thread/DEBUG]: #11 (1): require(string phar:///home/s1229630/pocketmine/PocketMine-MP.phar/src/pocketmine/PocketMine.php)
[13:20:48] [Server thread/CRITICAL]: Could not tick level "Pigland101": Undefined offset: 0
[13:20:48] [Server thread/CRITICAL]: ErrorException: "Undefined offset: 0" (EXCEPTION) in "/PureEntitiesX.phar/src/revivalpmmp/pureentities/utils/MobDamageCalculator" at line 92
[13:20:48] [Server thread/DEBUG]: #0 /PureEntitiesX.phar/src/revivalpmmp/pureentities/utils/MobDamageCalculator(92): pocketmine\{closure}(integer 8, string Undefined offset: 0, string phar:///home/s1229630/pocketmine/plugins/PureEntitiesX.phar/src/revivalpmmp/pureentities/utils/MobDamageCalculator.php, integer 92, array Array())
[13:20:48] [Server thread/DEBUG]: #1 /PureEntitiesX.phar/src/revivalpmmp/pureentities/entity/monster/walking/Spider(62): revivalpmmp\pureentities\utils\MobDamageCalculator::calculateFinalDamage(pocketmine\Player object, double 2)
[13:20:48] [Server thread/DEBUG]: #2 /PureEntitiesX.phar/src/revivalpmmp/pureentities/entity/monster/WalkingMonster(67): revivalpmmp\pureentities\entity\monster\walking\Spider->attackEntity(pocketmine\Player object)
[13:20:48] [Server thread/DEBUG]: #3 /PureEntitiesX.phar/src/revivalpmmp/pureentities/entity/monster/WalkingMonster(196): revivalpmmp\pureentities\entity\monster\WalkingMonster->checkAndAttackEntity(pocketmine\Player object)

kniffo80 commented 7 years ago

Could you please try https://github.com/RevivalPMMP/PureEntitiesX/tree/damage-reduction (have not build a PHAR yet) - that should take care of your MobEquipment error (the second one). Although i don't know why the worn armor can have an ID of zero ...

Sonnenwerk commented 7 years ago

I downloaded it and installed it. Until now i had only a cow and sheeps and pigs. No Mob, which attacked me. Will see that later, but unhandled mob still exists.

[12:33:43] [Server thread/DEBUG]: Tried to equip Item Stone Sword (272:57)x1 but have Item Stone Sword (272:58)x1 in target slot [12:33:43] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Goldtaler: 0x1f01a00482e40100000000090000 [12:33:43] [Server thread/DEBUG]: Tried to equip Item Stone Sword (272:58)x1 but have Item Stone Sword (272:59)x1 in target slot [12:33:43] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Goldtaler: 0x1f01a00482e80100000000090000 [12:33:43] [Server thread/DEBUG]: Tried to equip Item Stone Sword (272:59)x1 but have Item Stone Sword (272:60)x1 in target slot [12:33:43] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Goldtaler: 0x1f01a00482ec0100000000090000

kniffo80 commented 7 years ago

Hmmm ... the mob equipment packet control has been changed a lot. I need to investigate a little more. The strange thing - it goes to the Player's method "handleMobEquipment" - which i don't understand.

Maybe someone else here can give me a clue how that works now? I am not up-to-date :smile:

For now, you can try to set the mob-equipment section all values to zero. Then, no mob equipment packet should be send. This would be very interesting ... When every property is set to zero - and the log still prints out those messages, it's not the plugin ...

kniffo80 commented 7 years ago

So I tested this on locally with the latest PMMP and the latest PureEntitiesX, changed config.yml:

mob-equipment: zombie: wear-pickup-chances: can-pickup-loot: easy: 100 armor: easy: 100 weapon: easy: 100

I started the server and summoned a zombie (which then had full armor and an iron sword). But my log of the server behave completely normal. So my personal opinion: it's not a problem of this plugin. You can also test it by disabling PureEntitiesX and run PMMP without it.

kniffo80 commented 7 years ago

Ah found another thing: maybe the "old" code is the problem (e.g. Skeleton, Stray, WitherSkeleton) ... I'll try those again (those which produce MobEquipment packets) ...

EDIT: spawned all entities without a problem seeing in the server log ... strange.

Sonnenwerk commented 7 years ago

I have some more errors, which may help. I cant tell exactly, which mobs created them, but the player told me, he had a lot of spiders (20) spawned.

[13:17:26] [Server thread/DEBUG]: Tried to equip Item Diamond Sword (276:169)x1 but have Item Diamond Sword (276:170)x1 in target slot [13:17:26] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Legolas: 0x1f25a80482a40500000000090000 [13:17:27] [Server thread/DEBUG]: Tried to equip Item Diamond Sword (276:170)x1 but have Item Diamond Sword (276:171)x1 in target slot [13:17:27] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Legolas: 0x1f25a80482a80500000000090000 [13:17:27] [Server thread/DEBUG]: Unhandled ContainerSetSlotPacket received from Legolas: 0x320048000000 [13:17:27] [Server thread/DEBUG]: Unhandled ContainerSetSlotPacket received from Legolas: 0x32004a000000 [13:17:27] [Server thread/DEBUG]: Unhandled ContainerSetSlotPacket received from Legolas: 0x32004c000000 [13:17:27] [Server thread/DEBUG]: Unhandled ContainerSetSlotPacket received from Legolas: 0x32004e000000 [13:17:26] [Server thread/DEBUG]: Tried to equip Item Diamond Sword (276:169)x1 but have Item Diamond Sword (276:170)x1 in target slot [13:17:26] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Legolas: 0x1f25a80482a40500000000090000 [13:17:27] [Server thread/DEBUG]: Tried to equip Item Diamond Sword (276:170)x1 but have Item Diamond Sword (276:171)x1 in target slot [13:17:27] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Legolas: 0x1f25a80482a80500000000090000 [13:17:27] [Server thread/DEBUG]: Unhandled ContainerSetSlotPacket received from Legolas: 0x320048000000 [13:17:27] [Server thread/DEBUG]: Unhandled ContainerSetSlotPacket received from Legolas: 0x32004a000000 [13:17:27] [Server thread/DEBUG]: Unhandled ContainerSetSlotPacket received from Legolas: 0x32004c000000 [13:17:27] [Server thread/DEBUG]: Unhandled ContainerSetSlotPacket received from Legolas: 0x32004e000000 [13:17:27] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Legolas: 0x1f25a80482ac0500000000090000

Sonnenwerk commented 7 years ago

[13:17:48] [Server thread/DEBUG]: Tried to equip Item Diamond Sword (276:184)x1 but have Item Diamond Sword (276:185)x1 in target slot [13:17:48] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Legolas: 0x1f25a80482e00500000000090000

And this seems to be systematiccaly. Always a "Tried to" first, then a "unhandeld" direct afterwards.

Sonnenwerk commented 7 years ago

and spawnlogfile from today... [37m14.6.2017 13:10:47 [INFO] ChickenSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=360.9104,y=68.7,z=-5.6417))[0m [37m14.6.2017 13:11:17 [INFO] CowSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=378.0185,y=70.3,z=3.2643))[0m [37m14.6.2017 13:11:32 [INFO] SpiderSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=358.8479,y=75.12,z=-16.7461))[0m [37m14.6.2017 13:11:32 [INFO] SpiderSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=358.8479,y=76.24,z=-16.7461))[0m [37m14.6.2017 13:11:32 [INFO] SpiderSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=358.8479,y=77.36,z=-16.7461))[0m [37m14.6.2017 13:11:32 [INFO] SpiderSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=358.8479,y=78.48,z=-16.7461))[0m [37m14.6.2017 13:11:47 [INFO] WearPickupChance successfully loaded for Zombie[0m [37m14.6.2017 13:11:47 [INFO] WearChances successfully loaded for Zombie[0m [37m14.6.2017 13:11:47 [INFO] ArmorTypeChances successfully loaded for Zombie[0m [37m14.6.2017 13:11:47 [INFO] ZombieSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=389.3,y=64.8,z=-48.7), baby: )[0m [37m14.6.2017 13:11:47 [INFO] ZombieSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=389.3,y=66.6,z=-48.7), baby: )[0m [37m14.6.2017 13:12:02 [INFO] CreeperSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=379.8377,y=66.8,z=-32.1726))[0m [37m14.6.2017 13:12:17 [INFO] CreeperSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=393.5866,y=67.8,z=-35.7118))[0m [37m14.6.2017 13:13:17 [INFO] SkeletonSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=354.5331,y=65.8,z=-32.8057))[0m [37m14.6.2017 13:14:17 [INFO] IronGolemSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=379.3014,y=74.1,z=-14.3821))[0m [37m14.6.2017 13:14:33 [INFO] ZombieSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=341.1226,y=64.8,z=-44.843), baby: )[0m [37m14.6.2017 13:14:33 [INFO] ZombieSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=341.1226,y=66.6,z=-44.843), baby: )[0m [37m14.6.2017 13:14:48 [INFO] CreeperSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=371.4332,y=79.7216,z=-10.8239))[0m [37m14.6.2017 13:14:48 [INFO] ZombieSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=368.4332,y=68.7216,z=-16.8239), baby: )[0m [37m14.6.2017 13:14:48 [INFO] ZombieSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=368.4332,y=70.5216,z=-16.8239), baby: )[0m [37m14.6.2017 13:17:48 [INFO] SkeletonSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=396.8512,y=69.8,z=-16.7691))[0m [37m14.6.2017 13:18:33 [INFO] SpiderSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=397.3143,y=68.12,z=-29.9399))[0m [37m14.6.2017 13:18:33 [INFO] SpiderSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=397.3143,y=69.24,z=-29.9399))[0m [37m14.6.2017 13:18:33 [INFO] SpiderSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=397.3143,y=70.36,z=-29.9399))[0m [37m14.6.2017 13:18:33 [INFO] SpiderSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=397.3143,y=71.48,z=-29.9399))[0m [37m14.6.2017 13:18:50 [INFO] SkeletonSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=252.9386,y=67.8,z=144.7149))[0m [37m14.6.2017 13:19:51 [INFO] CreeperSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=311.6885,y=66.8,z=32.9099))[0m [37m14.6.2017 13:20:51 [INFO] ChickenSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=360.3048,y=63.7,z=-47.5222))[0m [37m14.6.2017 13:21:06 [INFO] CowSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=394.7,y=68.4768,z=-29.9516))[0m [37m14.6.2017 13:21:21 [INFO] CowSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=378.2029,y=65.3,z=-27.5537))[0m [37m14.6.2017 13:21:21 [INFO] SheepSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=389.2029,y=65.8,z=-53.5537))[0m [37m14.6.2017 13:21:51 [INFO] PigSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=368.5547,y=67.12,z=-16.9855))[0m [37m14.6.2017 13:22:06 [INFO] ChickenSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=398.2165,y=67.7,z=-34.1655))[0m [37m14.6.2017 13:22:36 [INFO] SheepSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=426.3239,y=72.8,z=-102.8271))[0m [37m14.6.2017 13:22:36 [INFO] IronGolemSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=408.3239,y=65.1,z=-90.8271))[0m [37m14.6.2017 13:23:06 [INFO] SheepSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=374.5548,y=66.8,z=-64.7827))[0m [37m14.6.2017 13:23:21 [INFO] CowSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=380.4202,y=67.3,z=-71.134))[0m [37m14.6.2017 13:23:36 [INFO] RabbitSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=404.1541,y=67.5,z=-44.7645)) as adult[0m [37m14.6.2017 13:23:36 [INFO] RabbitSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=404.1541,y=68,z=-44.7645)) as baby[0m [37m14.6.2017 13:23:51 [INFO] SheepSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=402.3,y=66.8,z=-58.7))[0m [37m14.6.2017 13:24:06 [INFO] PigSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=403.5306,y=69.12,z=-39.8944))[0m [37m14.6.2017 13:24:38 [INFO] ChickenSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=350.4872,y=71.7,z=6.3228))[0m [37m14.6.2017 13:25:23 [INFO] CowSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=372.7633,y=70.3,z=-6.7999))[0m [37m14.6.2017 13:25:23 [INFO] PigSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=367.7633,y=64.12,z=-39.7999))[0m [37m14.6.2017 13:25:53 [INFO] ChickenSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=392.7633,y=67.7,z=-22.7999))[0m [37m14.6.2017 13:26:55 [INFO] PigSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=356.3853,y=64.12,z=-44.1229))[0m [37m14.6.2017 13:27:10 [INFO] SheepSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=406.0065,y=66.9205,z=-59.6524))[0m [37m14.6.2017 13:27:25 [INFO] ChickenSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=367.9464,y=63.7244,z=-45.4211))[0m [37m14.6.2017 13:27:25 [INFO] PigSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=394.9464,y=69.1444,z=-12.4211))[0m [37m14.6.2017 13:28:55 [INFO] PigSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=375.562,y=81.12,z=-2.9466))[0m [37m14.6.2017 13:29:10 [INFO] PigSpawner: scheduleCreatureSpawn (pos: Position(level=Pigland101,x=383.0838,y=66.12,z=-33.0333))[0m

kniffo80 commented 7 years ago

Hmmm ... i don't get those messages :disappointed:

Anyway - do you have any other plugins enabled? As I already wrote: I tried all mobs that are using MobEquipment / MobArmorEquipment packets. They're not the cause of this :confused:

Sonnenwerk commented 7 years ago

Yes, i use

[12:24:20] [Server thread/INFO]: Loading essentialsTP v1.1.1 [12:24:20] [Server thread/INFO]: Loading SignPortal v1.1.3 [12:24:20] [Server thread/INFO]: Enabling PureEntitiesX v0.2.2_dev

shall i try to run without the two others ?

BTW, does it help you, if i set the loglevel of pureentities to debug ? ATM it is at "warn".

kniffo80 commented 7 years ago

Nope - setting the loglevel to DEBUG is not a good idea - especially when having multiple players on the server. I'm now checking the plugins ...

Sonnenwerk commented 7 years ago

[15:25:02] [Server thread/DEBUG]: Tried to equip Item Stone Sword (272:71)x1 but have Item Stone Sword (272:72)x1 in target slot [15:25:02] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Goldtaler: 0x1f01a004829c0200000000090000

I disabled the both other plugins, restarted the server, killed a pig and had this message... (that also mean no other player connected atm)

kniffo80 commented 7 years ago

But stone sword is used nowhere in the code ... did you have a stone sword equipped?

Sonnenwerk commented 7 years ago

Yes i have a stone sword equipped (with german client)

kniffo80 commented 7 years ago

I have tried it:

[15:27:00] [Server thread/INFO]: If you would like to be kept informed about new Beta or Development builds, it is recommended that you change 'preferred-channel' in your pocketmine.yml to 'beta' or 'development'. [15:27:00] [Server thread/INFO]: ----- -------------------------- ----- [15:27:23] [Server thread/INFO]: Kniffman[/10.250.1.164:53527] logged in with entity id 2 at (generated, 249.8785, 67, 270.4084) [15:27:26] [Server thread/INFO]: Kniffman joined the game give kniffman stone_sword [15:27:32] [Server thread/INFO]: [CONSOLE: Given Stone Sword (272:0) * 1 to Kniffman] pesummon pig kniffman [15:27:46] [Server thread/INFO]: Spawned pig [15:27:57] [Server thread/INFO]: Kniffman left the game [15:27:57] [Server thread/INFO]: Kniffman[/10.250.1.164:53527] logged out due to client disconnect

As you can see, there's no such error spawned.

Another idea: create a new world (save to old one) and play around again. Sometimes - NBT tags are a little strange when entities are loaded from "old" worlds. Just an idea.

I tested with latest (master) of PMMP and latest (damage-reduction) from PureEntitiesX.

EDIT: I am german too - but it has nothing to do with the translation of the client :smirk:

Sonnenwerk commented 7 years ago

Made new world...same behaviour.

[15:32:50] [Server thread/DEBUG]: Unhandled MobArmorEquipmentPacket received from Goldtaler: 0x2001e4040200000000e6040200000000e804020000000000 [15:32:55] [Server thread/DEBUG]: Level "Pigland102" took 103.75ms, setting tick rate to 2 ticks [15:32:55] [Server thread/DEBUG]: Raising level "Pigland102" tick rate to 1 ticks [15:32:56] [Server thread/DEBUG]: Level "Pigland102" took 57.2ms, setting tick rate to 2 ticks [15:32:56] [Server thread/DEBUG]: Raising level "Pigland102" tick rate to 1 ticks [15:33:09] [Server thread/DEBUG]: Tried to equip Item Stone Sword (272:76)x1 but have Item Stone Sword (272:77)x1 in target slot [15:33:09] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Goldtaler: 0x1f01a00482b00200000000090000

[15:33:09] [Server thread/DEBUG]: Tried to equip Item Stone Sword (272:78)x1 but have Item Stone Sword (272:79)x1 in target slot [15:33:09] [Server thread/DEBUG]: Unhandled MobEquipmentPacket received from Goldtaler: 0x1f01a00482b80200000000090000

kniffo80 commented 7 years ago

Okay - now please disable ALL plugins and restart the server - and if it's gone (the message) - enable only PureEntitiesX - and so on. It's strange because I don't get those messages.

Anyone else who can test this stuff please?

Sonnenwerk commented 7 years ago

I get those "unhandled" errors too, if all stuff is disabled, but not the "tried to equip". Maybe the last one only occure, when i hit a mob, but state the same cause ? So it seems, i must test/try another version of pocketmine itself. I will do this on monday

[15:44:33] [Server thread/DEBUG]: Unhandled MobArmorEquipmentPacket received from Goldtaler: 0x2001e4040200000000e6040200000000e804020000000000

Sonnenwerk commented 7 years ago

Thanks for looking into it !

kniffo80 commented 7 years ago

No problem. When there's a bug - I like to fix it. But it's hard to fix it when this can't be reproduced. I think you should update to the latest master branch of PMMP. If you need a phar - just give me a short notice. I can give you the actual one.

RevivalPMMP / PureEntitiesX