From 00359e7ae828f5fb63b0778ad53dc82d7b84075e Mon Sep 17 00:00:00 2001
From: binarycat <binarycat@envs.net>
Date: Sun, 17 Dec 2023 14:57:06 -0500
Subject: [PATCH] add profiling debug prints

---
 mesecons_mvps/init.lua | 20 +++++++++++++++++---
 1 file changed, 17 insertions(+), 3 deletions(-)

diff --git a/mesecons_mvps/init.lua b/mesecons_mvps/init.lua
index 5cb9c8f..07c6e04 100644
--- a/mesecons_mvps/init.lua
+++ b/mesecons_mvps/init.lua
@@ -195,10 +195,13 @@ end
 --  - empty string means legacy MVPS, actual check depends on configuration
 --  - "$unknown" is a sentinel for forcing the check
 function mesecon.mvps_push_or_pull(pos, stackdir, movedir, maximum, all_pull_sticky, player_name)
+	local time_start = minetest.get_us_time()
 	local nodes = mesecon.mvps_get_stack(pos, movedir, maximum, all_pull_sticky)
+	local time_get_stack = minetest.get_us_time()
 
 	if not nodes then return end
 
+	local time_protection_check_start = minetest.get_us_time()
 	local protection_check_set = {}
 	if vector.equals(stackdir, movedir) then -- pushing
 		add_pos(protection_check_set, pos)
@@ -214,7 +217,9 @@ function mesecon.mvps_push_or_pull(pos, stackdir, movedir, maximum, all_pull_sti
 	if are_protected(protection_check_set, player_name) then
 		return false, "protected"
 	end
+	local time_protection_check_end = minetest.get_us_time()
 
+	local time_remove_nodes_start = minetest.get_us_time()
 	-- remove all nodes
 	for _, n in ipairs(nodes) do
 		n.meta = minetest.get_meta(n.pos):to_table()
@@ -224,7 +229,7 @@ function mesecon.mvps_push_or_pull(pos, stackdir, movedir, maximum, all_pull_sti
 		end
 		minetest.remove_node(n.pos)
 	end
-
+	local time_remove_nodes_end = minetest.get_us_time()
 	local oldstack = mesecon.tablecopy(nodes)
 
 	-- update mesecons for removed nodes ( has to be done after all nodes have been removed )
@@ -232,6 +237,7 @@ function mesecon.mvps_push_or_pull(pos, stackdir, movedir, maximum, all_pull_sti
 		mesecon.on_dignode(n.pos, n.node)
 	end
 
+	local time_set_nodes_start = minetest.get_us_time()
 	-- add nodes
 	for _, n in ipairs(nodes) do
 		local np = vector.add(n.pos, movedir)
@@ -248,7 +254,9 @@ function mesecon.mvps_push_or_pull(pos, stackdir, movedir, maximum, all_pull_sti
 			minetest.get_node_timer(np):set(unpack(n.node_timer))
 		end
 	end
+	local time_set_nodes_end = minetest.get_us_time()
 
+	local time_moved_nodes_start = minetest.get_us_time()
 	local moved_nodes = {}
 	for i in ipairs(nodes) do
 		moved_nodes[i] = {}
@@ -259,9 +267,15 @@ function mesecon.mvps_push_or_pull(pos, stackdir, movedir, maximum, all_pull_sti
 		moved_nodes[i].meta = nodes[i].meta
 		moved_nodes[i].node_timer = nodes[i].node_timer
 	end
+	local time_moved_nodes_end = minetest.get_us_time()
+	
 
-	on_mvps_move(moved_nodes)
-
+	on_mvps_move(moved_nodes)	
+	minetest.debug("get_stack time", time_get_stack - time_start)
+	minetest.debug("remove_nodes time", time_remove_nodes_end - time_remove_nodes_start)
+	minetest.debug("set_nodes time", time_set_nodes_end - time_set_nodes_start)
+	minetest.debug("moved_nodes time", time_moved_nodes_end - time_moved_nodes_start)
+	minetest.debug("full time", minetest.get_us_time() - time_start)
 	return true, nodes, oldstack
 end