Administrator reports: Add to log which items a playe ... #6366

Closed
opened 2024-02-27 00:42:25 +00:00 by yourland-report · 12 comments

Administrator reports a bug:

Add to log which items a player picks up from ground

Player position:

{
	x = -450.71801757813,
	y = 58.870002746582,
	z = 21498.12890625
}

Player look:

{
	x = 0.021808940917253,
	y = -0.21643961966038,
	z = -0.97605240345001
}

Player information:

{
	max_rtt = 0.52300000190735,
	ip_version = 6,
	min_rtt = 0.013000000268221,
	avg_rtt = 0.014999999664724,
	min_jitter = 0,
	max_jitter = 0.50800001621246,
	avg_jitter = 0.0010000010952353,
	connection_uptime = 6500,
	serialization_version = 29,
	patch = 0,
	lang_code = "de",
	minor = 9,
	major = 5,
	version_string = "5.9.0-dev-63a985381",
	state = "Active",
	protocol_version = 43,
	formspec_version = 7
}

Player meta:

{
	fields = {
		["stamina:level"] = "0",
		["stamina:poisoned"] = "no",
		["stamina:exhaustion"] = "111.5",
		lagometer = "1",
		["unified_inventory:bags"] = "return {\"unified_inventory:bag_large\", \"unified_inventory:bag_large\", \"unified_inventory:bag_large\", \"unified_inventory:bag_small\"}",
		repellant = "0",
		["3d_armor_inventory"] = "return {\"3d_armor:boots_crystal\", \"\", \"\", \"\", \"\", \"\"}",
		bitten = "0",
		played_time = "4737756",
		digged_nodes = "78275",
		placed_nodes = "130834",
		died = "185",
		punch_count = "1082",
		arenalib_infobox_arenaID = "0",
		inflicted_damage = "40220",
		xp = "0",
		["hud_manager:spawnit:hud_enabled"] = "y",
		yl_commons_player_joined = "1708988047",
		hud_state = "on",
		yl_church = "return {[\"last_death\"] = {[\"y\"] = 54, [\"x\"] = -3844, [\"z\"] = -5201}}",
		crafted = "216",
		["sethome:home"] = "(2218.833984375,40.9880027771,981.63397216797)",
		["ethereal:fly_timer"] = "-99",
		yl_commons_thankyou = "18",
		["ocean_build.ocean_built"] = "9",
		["signslib:pos"] = "(2005,3,1149)",
		["ocean_build.last_warning"] = "1708072099"
	}
}

Log identifier


[MOD] yl_report log identifier = LIcG13Opu4hCnsvjrU41lAOmguMBxbNX

Profiler save:

profile-20240227T004224.json_prettyEE

Status:

# Server: version: 5.8.0-yl-test | game: Minetest Game | uptime: 10h 18min 7s | max lag: 0.303s | clients (3/52): Administrator, AspireMint, daydream

Teleport command:

/teleport xyz -451 59 21498

Compass command:

/give_compass Construction LIcG13Opu4hCnsvjrU41lAOmguMBxbNX D2691E -451 59 21498
Administrator reports a bug: > Add to log which items a player picks up from ground Player position: ``` { x = -450.71801757813, y = 58.870002746582, z = 21498.12890625 } ``` Player look: ``` { x = 0.021808940917253, y = -0.21643961966038, z = -0.97605240345001 } ``` Player information: ``` { max_rtt = 0.52300000190735, ip_version = 6, min_rtt = 0.013000000268221, avg_rtt = 0.014999999664724, min_jitter = 0, max_jitter = 0.50800001621246, avg_jitter = 0.0010000010952353, connection_uptime = 6500, serialization_version = 29, patch = 0, lang_code = "de", minor = 9, major = 5, version_string = "5.9.0-dev-63a985381", state = "Active", protocol_version = 43, formspec_version = 7 } ``` Player meta: ``` { fields = { ["stamina:level"] = "0", ["stamina:poisoned"] = "no", ["stamina:exhaustion"] = "111.5", lagometer = "1", ["unified_inventory:bags"] = "return {\"unified_inventory:bag_large\", \"unified_inventory:bag_large\", \"unified_inventory:bag_large\", \"unified_inventory:bag_small\"}", repellant = "0", ["3d_armor_inventory"] = "return {\"3d_armor:boots_crystal\", \"\", \"\", \"\", \"\", \"\"}", bitten = "0", played_time = "4737756", digged_nodes = "78275", placed_nodes = "130834", died = "185", punch_count = "1082", arenalib_infobox_arenaID = "0", inflicted_damage = "40220", xp = "0", ["hud_manager:spawnit:hud_enabled"] = "y", yl_commons_player_joined = "1708988047", hud_state = "on", yl_church = "return {[\"last_death\"] = {[\"y\"] = 54, [\"x\"] = -3844, [\"z\"] = -5201}}", crafted = "216", ["sethome:home"] = "(2218.833984375,40.9880027771,981.63397216797)", ["ethereal:fly_timer"] = "-99", yl_commons_thankyou = "18", ["ocean_build.ocean_built"] = "9", ["signslib:pos"] = "(2005,3,1149)", ["ocean_build.last_warning"] = "1708072099" } } ``` Log identifier ``` [MOD] yl_report log identifier = LIcG13Opu4hCnsvjrU41lAOmguMBxbNX ``` Profiler save: ``` profile-20240227T004224.json_prettyEE ``` Status: ``` # Server: version: 5.8.0-yl-test | game: Minetest Game | uptime: 10h 18min 7s | max lag: 0.303s | clients (3/52): Administrator, AspireMint, daydream ``` Teleport command: ``` /teleport xyz -451 59 21498 ``` Compass command: ``` /give_compass Construction LIcG13Opu4hCnsvjrU41lAOmguMBxbNX D2691E -451 59 21498 ```
AliasAlreadyTaken was assigned by yourland-report 2024-02-27 00:42:25 +00:00
AliasAlreadyTaken added the
1. kind/enhancement
label 2024-02-27 01:15:11 +00:00
flux added the
4. step/ready to QA test
label 2024-03-04 21:03:03 +00:00
Member

done: fa78f3975c

note that the logic is a bit complicated, because technically items can define their own on_pickup logic which might replace one item with another.

done: https://gitea.your-land.de/your-land/yl_commons/commit/fa78f3975c3de86adcaa6e1a5383b8bd9f8192b5 note that the logic is a bit complicated, because technically items can define their own on_pickup logic which might replace one item with another.
AliasAlreadyTaken added this to the 1.1.123 milestone 2024-03-04 23:06:30 +00:00

QA

I can't see the new log messages. That's all I get:

2024-03-07 11:42:37: ACTION[Server]: [yl_commons] "AliasAlreadyTaken" dropped "bucket:bucket_empty 3" at pos "(2006.7409667969,14.5,1153.2839355469)"
2024-03-07 11:42:37: ACTION[Server]: [yl_commons] AliasAlreadyTaken takes "bucket:bucket_empty 3" from {"name":"AliasAlreadyTaken","type":"player"} [main]
2024-03-07 11:42:39: ACTION[Server]: [yl_commons] [__builtin:item] removing "ghost of bucket:bucket_empty 3" dropped by AliasAlreadyTaken @ (2007,15,1152) 1.9s ago
2024-03-07 11:42:39: ACTION[Server]: player AliasAlreadyTaken (id=2, hp=20) punched LuaEntitySAO "__builtin:item" at (2007,15,1152) (id=12350, hp=1), damage=0

QA I can't see the new log messages. That's all I get: 2024-03-07 11:42:37: ACTION[Server]: [yl_commons] "AliasAlreadyTaken" dropped "bucket:bucket_empty 3" at pos "(2006.7409667969,14.5,1153.2839355469)" 2024-03-07 11:42:37: ACTION[Server]: [yl_commons] AliasAlreadyTaken takes "bucket:bucket_empty 3" from {"name":"AliasAlreadyTaken","type":"player"} [main] 2024-03-07 11:42:39: ACTION[Server]: [yl_commons] [__builtin:item] removing "ghost of bucket:bucket_empty 3" dropped by AliasAlreadyTaken @ (2007,15,1152) 1.9s ago 2024-03-07 11:42:39: ACTION[Server]: player AliasAlreadyTaken (id=2, hp=20) punched LuaEntitySAO "__builtin:item" at (2007,15,1152) (id=12350, hp=1), damage=0
AliasAlreadyTaken added the
ugh/QA NOK
label 2024-03-07 11:43:23 +00:00
Member

I can't see the new log messages.

are you checking debug.txt and not the console output? you recently mentioned that as a reason why we saw different results.

> I can't see the new log messages. are you checking debug.txt and not the console output? you recently mentioned that as a reason why we saw different results.

I can't see the new log messages.

are you checking debug.txt and not the console output? you recently mentioned that as a reason why we saw different results.

That explains. It does appear in the debug.txt.

Unfortunately we don't store debug.txt, we only store terminal. Can you please make it that it appears in the console output?

> > I can't see the new log messages. > > are you checking debug.txt and not the console output? you recently mentioned that as a reason why we saw different results. That explains. It does appear in the debug.txt. Unfortunately we don't store debug.txt, we only store terminal. Can you please make it that it appears in the console output?
AliasAlreadyTaken removed the
ugh/QA NOK
label 2024-03-12 00:09:52 +00:00

Looks like it IS logged to the terminal, but not always?

2024-03-12 00:22:30: ACTION[Server]: [yl_commons] poppyasdan picks up item "canned_food:canned_potato" dropped by poppyasdan @ (2016,23,1156)
2024-03-12 00:24:49: ACTION[Server]: [yl_commons] AliasAlreadyTaken picks up item "y_bows:slingshot_steel" dropped by AliasAlreadyTaken @ (2011,15,1151)
2024-03-12 00:24:54: ACTION[Server]: [yl_commons] poppyasdan picks up item "y_bows:arrow_diamond 82" dropped by poppyasdan @ (2025,15,1159)
2024-03-12 00:24:54: ACTION[Server]: [yl_commons] poppyasdan picks up item "y_bows:bow_steel 1 47826" dropped by poppyasdan @ (2025,15,1159)
2024-03-12 00:24:55: ACTION[Server]: [yl_commons] poppyasdan picks up item "moreores:enchanted_sword_mithril_sharp 1 8193" dropped by poppyasdan @ (2025,15,1159)
2024-03-12 00:24:55: ACTION[Server]: [yl_commons] poppyasdan picks up item "y_bows:slingshot_wood 1 20817" dropped by poppyasdan @ (2025,15,1159)
2024-03-12 00:24:55: ACTION[Server]: [yl_commons] poppyasdan picks up item "y_bows:bow_wood 1 7788" dropped by poppyasdan @ (2025,15,1159)
2024-03-12 00:24:56: ACTION[Server]: [yl_commons] poppyasdan picks up item "anvil:hammer 1 3900" dropped by poppyasdan @ (2025,15,1159)
2024-03-12 00:24:56: ACTION[Server]: [yl_commons] poppyasdan picks up item "y_bows:arrow_fire 85" dropped by poppyasdan @ (2025,15,1159)
2024-03-12 00:24:56: ACTION[Server]: [yl_commons] poppyasdan picks up item "canned_food:canned_potato 71" dropped by poppyasdan @ (2025,15,1159)
2024-03-12 00:24:56: ACTION[Server]: [yl_commons] poppyasdan picks up item "vessels:glass_bottle 42" dropped by poppyasdan @ (2025,15,1159)
2024-03-12 00:24:56: ACTION[Server]: [yl_commons] poppyasdan picks up item "moreores:enchanted_pick_mithril_fast 1 4401" dropped by poppyasdan @ (2025,15,1159)
2024-03-12 00:24:57: ACTION[Server]: [yl_commons] poppyasdan picks up item "default:mese_crystal" dropped by poppyasdan @ (2025,15,1159)
2024-03-12 00:25:45: ACTION[Server]: [yl_commons] daydream picks up item "y_bows:arrow_fire 53" dropped by daydream @ (2153,16,1029)
2024-03-12 00:25:45: ACTION[Server]: [yl_commons] daydream picks up item "y_bows:slingshot_steel" dropped by daydream @ (2154,16,1029)
2024-03-12 00:25:45: ACTION[Server]: [yl_commons] daydream picks up item "y_bows:ball_rock 20" dropped by daydream @ (2153,16,1029)
2024-03-12 00:25:45: ACTION[Server]: [yl_commons] daydream picks up item "farming:tomato 2" dropped by daydream @ (2153,16,1029)
Looks like it IS logged to the terminal, but not always? ``` 2024-03-12 00:22:30: ACTION[Server]: [yl_commons] poppyasdan picks up item "canned_food:canned_potato" dropped by poppyasdan @ (2016,23,1156) 2024-03-12 00:24:49: ACTION[Server]: [yl_commons] AliasAlreadyTaken picks up item "y_bows:slingshot_steel" dropped by AliasAlreadyTaken @ (2011,15,1151) 2024-03-12 00:24:54: ACTION[Server]: [yl_commons] poppyasdan picks up item "y_bows:arrow_diamond 82" dropped by poppyasdan @ (2025,15,1159) 2024-03-12 00:24:54: ACTION[Server]: [yl_commons] poppyasdan picks up item "y_bows:bow_steel 1 47826" dropped by poppyasdan @ (2025,15,1159) 2024-03-12 00:24:55: ACTION[Server]: [yl_commons] poppyasdan picks up item "moreores:enchanted_sword_mithril_sharp 1 8193" dropped by poppyasdan @ (2025,15,1159) 2024-03-12 00:24:55: ACTION[Server]: [yl_commons] poppyasdan picks up item "y_bows:slingshot_wood 1 20817" dropped by poppyasdan @ (2025,15,1159) 2024-03-12 00:24:55: ACTION[Server]: [yl_commons] poppyasdan picks up item "y_bows:bow_wood 1 7788" dropped by poppyasdan @ (2025,15,1159) 2024-03-12 00:24:56: ACTION[Server]: [yl_commons] poppyasdan picks up item "anvil:hammer 1 3900" dropped by poppyasdan @ (2025,15,1159) 2024-03-12 00:24:56: ACTION[Server]: [yl_commons] poppyasdan picks up item "y_bows:arrow_fire 85" dropped by poppyasdan @ (2025,15,1159) 2024-03-12 00:24:56: ACTION[Server]: [yl_commons] poppyasdan picks up item "canned_food:canned_potato 71" dropped by poppyasdan @ (2025,15,1159) 2024-03-12 00:24:56: ACTION[Server]: [yl_commons] poppyasdan picks up item "vessels:glass_bottle 42" dropped by poppyasdan @ (2025,15,1159) 2024-03-12 00:24:56: ACTION[Server]: [yl_commons] poppyasdan picks up item "moreores:enchanted_pick_mithril_fast 1 4401" dropped by poppyasdan @ (2025,15,1159) 2024-03-12 00:24:57: ACTION[Server]: [yl_commons] poppyasdan picks up item "default:mese_crystal" dropped by poppyasdan @ (2025,15,1159) 2024-03-12 00:25:45: ACTION[Server]: [yl_commons] daydream picks up item "y_bows:arrow_fire 53" dropped by daydream @ (2153,16,1029) 2024-03-12 00:25:45: ACTION[Server]: [yl_commons] daydream picks up item "y_bows:slingshot_steel" dropped by daydream @ (2154,16,1029) 2024-03-12 00:25:45: ACTION[Server]: [yl_commons] daydream picks up item "y_bows:ball_rock 20" dropped by daydream @ (2153,16,1029) 2024-03-12 00:25:45: ACTION[Server]: [yl_commons] daydream picks up item "farming:tomato 2" dropped by daydream @ (2153,16,1029) ```
AliasAlreadyTaken added the
ugh/QA NOK
label 2024-03-12 00:27:13 +00:00
AliasAlreadyTaken modified the milestone from 1.1.123 to 1.1.124 2024-03-12 00:27:39 +00:00

whosit discovered that when I pick items up with empty hand, I don't see the log message. If I pick it up with some item in hand, I do get the log message.

whosit discovered that when I pick items up with empty hand, I don't see the log message. If I pick it up with some item in hand, I do get the log message.
Member

whosit discovered that when I pick items up with empty hand, I don't see the log message. If I pick it up with some item in hand, I do get the log message.

that's... really weird. i can replicate the issue locally, let me see if i can figure it out.

> whosit discovered that when I pick items up with empty hand, I don't see the log message. If I pick it up with some item in hand, I do get the log message. that's... really weird. i can replicate the issue locally, let me see if i can figure it out.
Member

apparently the on_punch callback is never even called when i've got an empty hand?! oh, i have an idea....

apparently the `on_punch` callback is never even called when i've got an empty hand?! oh, i have an idea....
Member

fixed 4e0d2bf2f1, ready to QA again

fixed https://gitea.your-land.de/your-land/yl_commons/commit/4e0d2bf2f17eb4b04936fb9bbf62130ff74778c5, ready to QA again
whosit removed the
ugh/QA NOK
label 2024-03-12 22:57:44 +00:00
AliasAlreadyTaken modified the milestone from 1.1.124 to 1.1.123.1 2024-03-13 09:26:29 +00:00

QA

Now works with empty hand :)

QA Now works with empty hand :)
AliasAlreadyTaken added the
ugh/QA OK
label 2024-03-13 21:27:39 +00:00
Member

i can't check this on main due to lack of access

i can't check this on main due to lack of access

This works on main and was already used. Fixed!

This works on main and was already used. Fixed!
AliasAlreadyTaken added the
5. result/fixed
label 2024-03-28 23:36:29 +00:00
flux removed the
4. step/ready to QA test
label 2024-03-29 02:34:05 +00:00
Sign in to join this conversation.
No Milestone
No project
No Assignees
3 Participants
Notifications
Due Date
The due date is invalid or out of range. Please use the format 'yyyy-mm-dd'.

No due date set.

Dependencies

No dependencies set.

Reference: your-land/bugtracker#6366
No description provided.