Closed prismtank4 closed 2 months ago
true, have the same problem
same happens to me todays (19th May) commit e853fec below my log:
time=20:49:36 level=INFO msg="Waiting for character selection screen..."
time=20:49:51 level=INFO msg="Character selection screen found"
time=20:49:51 level=INFO msg="Selecting character..."
time=20:49:51 level=INFO msg="Character found"
time=20:49:55 level=DEBUG msg="Fetching map data..." seed=1502982702
time=20:49:56 level=DEBUG msg="Fetch completed" ms=1455
time=20:49:56 level=INFO msg="Starting Game #0. Run list: council, diablo, countess, andariel, baal, mephisto"
time=20:49:56 level=INFO msg="Running: council"
time=20:49:56 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone"
time=20:49:58 level=DEBUG msg="Load completed, continuing execution"
time=20:49:58 level=DEBUG msg="Checking for character corpse..."
time=20:49:58 level=DEBUG msg="Character corpse not found :D"
time=20:49:58 level=DEBUG msg="Checking for items to stash..."
time=20:49:58 level=DEBUG msg="Checking for items to identify..."
time=20:49:58 level=DEBUG msg="No items to identify..."
time=20:49:58 level=DEBUG msg="Belt Stats Health: 8/8 healing, 4/4 mana, 4/4 rejuv."
time=20:49:58 level=DEBUG msg="Checking for items to stash..."
time=20:49:58 level=DEBUG msg="Checking for items to stash..."
time=20:49:59 level=ERROR msg="error running event handler" error="HTTP 400 Bad Request, {\"message\": \"Cannot send an empty message\", \"code\": 50006}"
time=20:50:00 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone"
time=20:50:03 level=DEBUG msg="Load completed, continuing execution"
time=20:50:03 level=DEBUG msg="CTA found: swapping weapon and casting Battle Command / Battle Orders"
time=20:50:03 level=DEBUG msg=Buffing...
time=20:50:06 level=ERROR msg="fatal error detected, forcing shutdown" supervisor=IcedEarth error="runtime error: index out of range [381] with length 320" stacktrace="goroutine 143 [running]:\nruntime/debug.Stack()\n\truntime/debug/stack.go:24 +0x5e\ngithub.com/hectorgimenez/koolo/internal.(*SupervisorManager).Start.func2.1()\n\tgithub.com/hectorgimenez/koolo/internal/manager.go:76 +0x108\npanic({0x9f5040?, 0xc0008d64b0?})\n\truntime/panic.go:770 +0x132\ngithub.com/hectorgimenez/koolo/internal/pather.(*World).From(...)\n\tgithub.com/hectorgimenez/koolo/internal/pather/world.go:55\ngithub.com/hectorgimenez/koolo/internal/pather.(*PathFinder).GetPath(_, {{{0x1388, 0x6b8}, {0x0, 0x0, {0x0, 0x0}}, {0xc001344d88, 0xb, 0x11}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/pather/path_finder.go:138 +0xaff\ngithub.com/hectorgimenez/koolo/internal/action/step.(*MoveToStep).Run(_, {{{0x1388, 0x6b8}, {0x0, 0x0, {0x0, 0x0}}, {0xc001344d88, 0xb, 0x11}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/action/step/move_to.go:120 +0x874\ngithub.com/hectorgimenez/koolo/internal/action.(*StepChainAction).NextStep(_, {{{0x1388, 0x6b8}, {0x0, 0x0, {0x0, 0x0}}, {0xc001344d88, 0xb, 0x11}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/action/action_step_chain.go:47 +0x222\ngithub.com/hectorgimenez/koolo/internal/action.(*Chain).NextStep(_, {{{0x1388, 0x6b8}, {0x0, 0x0, {0x0, 0x0}}, {0xc001344d88, 0xb, 0x11}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/action/action_chain.go:48 +0x1f6\ngithub.com/hectorgimenez/koolo/internal.(*Bot).Run(0xc000d02000, {0xc5e000, 0xc0005da0f0}, 0x1, {0xc000ad2380, 0x6, 0x8})\n\tgithub.com/hectorgimenez/koolo/internal/bot.go:157 +0x1815\ngithub.com/hectorgimenez/koolo/internal.(*SinglePlayerSupervisor).Start(0xc00059e000)\n\tgithub.com/hectorgimenez/koolo/internal/single_supervisor.go:69 +0x2ae\ngithub.com/hectorgimenez/koolo/internal.(*SupervisorManager).Start.func2()\n\tgithub.com/hectorgimenez/koolo/internal/manager.go:82 +0xa2\ncreated by github.com/hectorgimenez/koolo/internal.(*SupervisorManager).Start in goroutine 146\n\tgithub.com/hectorgimenez/koolo/internal/manager.go:69 +0x21d\n"
time=20:50:06 level=INFO msg=Stopping... configuration=IcedEarth
time=20:50:06 level=INFO msg="Finished stopping" configuration=IcedEarth
I think this specific panic should be fixed now, please try again with the latest main
@hectorgimenez hello and thanks for all your help.
Do you know what level=ERROR msg="error running event handler" error="HTTP 400 Bad Request, {\"message\": \"Cannot send an empty message\", \"code\": 50006}
means?
Yes, it's working now. Thank you.
Details
I will describe three cases and add logs to them below. Those errors usually happened to me after successful first run. Character: Blizzard sorceress.
1)
2)
3)
Logs:
1)
time=21:31:58 level=INFO msg="Waiting for character selection screen..." time=21:32:19 level=INFO msg="Character selection screen found" time=21:32:19 level=INFO msg="Selecting character..." time=21:32:19 level=INFO msg="Character found" time=21:32:24 level=DEBUG msg="Fetching map data..." seed=317080975 time=21:32:28 level=DEBUG msg="Fetch completed" ms=4426 time=21:32:28 level=INFO msg="Starting Game #0. Run list: lower_kurast_chest, mephisto, ancient_tunnels" time=21:32:28 level=INFO msg="Running: lower_kurast_chest" time=21:32:30 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:32:33 level=DEBUG msg="Load completed, continuing execution" time=21:32:33 level=DEBUG msg="Checking for character corpse..." time=21:32:33 level=DEBUG msg="Character corpse not found :D" time=21:32:33 level=DEBUG msg="Checking for items to stash..." time=21:32:33 level=DEBUG msg="Checking for items to identify..." time=21:32:33 level=DEBUG msg="No items to identify..." time=21:32:33 level=DEBUG msg="Belt Stats Health: 8/8 healing, 4/4 mana, 4/4 rejuv." time=21:32:33 level=DEBUG msg="Checking for items to stash..." time=21:32:33 level=DEBUG msg="Checking for items to stash..." time=21:32:45 level=DEBUG msg=Buffing... time=21:32:55 level=DEBUG msg="Aborting item pickup, monster nearby" monster="{UnitID:303569518 Name:235 IsHovered:false Position:{X:5946 Y:2812} Stats:map[life:32768 maxlife:32768 level:50 velocitypercent:75 attackrate:100 otheranimrate:100 pierceidx:8760] Type:None}" time=21:32:56 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:32:57 level=DEBUG msg="Item Detected: WarScepter [4] at X:5941 Y:2816" time=21:32:57 level=DEBUG msg="Picking up: War Scepter [Magic]" time=21:32:57 level=INFO msg="Item picked up: War Scepter [Magic]" time=21:33:05 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:33:08 level=DEBUG msg="Load completed, continuing execution" time=21:33:14 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:33:16 level=DEBUG msg="Load completed, continuing execution" time=21:33:16 level=DEBUG msg="No items detected, waiting a bit and will try again" waitMs=1000 time=21:33:18 level=INFO msg="Run lower_kurast_chest finished, length: 49.33s" time=21:33:18 level=INFO msg="Running: mephisto" time=21:33:18 level=DEBUG msg="Checking for character corpse..." time=21:33:18 level=DEBUG msg="Character corpse not found :D" time=21:33:18 level=DEBUG msg="Checking for items to identify..." time=21:33:18 level=INFO msg="Identifying 1 items..." time=21:33:20 level=INFO msg="Visiting vendor..." forceRefill=false time=21:33:28 level=DEBUG msg="Buying: 0 Healing potions and 0 Mana potions" time=21:33:28 level=DEBUG msg="Vendor with keys detected, provisioning..." time=21:33:30 level=DEBUG msg="Item War Scepter [Magic] sold" time=21:33:31 level=DEBUG msg="Checking for items to stash..." time=21:33:31 level=DEBUG msg="Checking for items to stash..." time=21:33:40 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:33:42 level=DEBUG msg="Load completed, continuing execution" time=21:33:42 level=DEBUG msg=Buffing... time=21:34:16 level=DEBUG msg="Using HealingPotion potion on Mercenary [Column: 1]. HP: 85" time=21:34:16 level=DEBUG msg="Using ManaPotion potion [Column: 4]. HP: 95 MP: 19" time=21:34:17 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:34:18 level=DEBUG msg="Using HealingPotion potion [Column: 1]. HP: 83 MP: 54" time=21:34:20 level=DEBUG msg="Item Detected: ChainGloves [4] at X:17566 Y:8068" time=21:34:20 level=DEBUG msg="Picking up: Chain Gloves [Magic]" time=21:34:21 level=INFO msg="Item picked up: Chain Gloves [Magic]" time=21:34:21 level=DEBUG msg="Item Detected: DemonhideGloves [4] at X:17568 Y:8070" time=21:34:21 level=DEBUG msg="Picking up: Demonhide Gloves [Magic]" time=21:34:22 level=INFO msg="Item picked up: Demonhide Gloves [Magic]" time=21:34:22 level=DEBUG msg="Item Detected: GothicShield [6] at X:17570 Y:8068" time=21:34:22 level=DEBUG msg="Picking up: Gothic Shield [Rare]" time=21:34:26 level=DEBUG msg="Item could not be picked up, going back to town to sell junk and stash" time=21:34:30 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:34:33 level=DEBUG msg="Load completed, continuing execution" time=21:34:34 level=DEBUG msg="Checking for character corpse..." time=21:34:34 level=DEBUG msg="Character corpse not found :D" time=21:34:34 level=DEBUG msg="Checking for items to identify..." time=21:34:34 level=INFO msg="Identifying 2 items..." time=21:34:39 level=INFO msg="Visiting vendor..." forceRefill=false time=21:34:46 level=DEBUG msg="Buying: 2 Healing potions and 1 Mana potions" time=21:34:48 level=DEBUG msg="Purchased Greater Healing Potion [X:9 Y:4]" time=21:34:49 level=DEBUG msg="Purchased Greater Healing Potion [X:9 Y:4]" time=21:34:50 level=DEBUG msg="Purchased Greater Mana Potion [X:9 Y:7]" time=21:34:52 level=DEBUG msg="Item Chain Gloves [Magic] sold" time=21:34:53 level=DEBUG msg="Item Demonhide Gloves [Magic] sold" time=21:34:54 level=DEBUG msg="Checking for items to stash..." time=21:34:54 level=DEBUG msg="Checking for items to stash..." time=21:35:00 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:35:00 level=DEBUG msg="Load completed, continuing execution" time=21:35:01 level=DEBUG msg="Item Detected: GothicShield [6] at X:17570 Y:8068" time=21:35:02 level=DEBUG msg="Picking up: Gothic Shield [Rare]" time=21:35:02 level=INFO msg="Item picked up: Gothic Shield [Rare]" time=21:35:06 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:35:11 level=DEBUG msg="Load completed, continuing execution" time=21:35:11 level=INFO msg="Run mephisto finished, length: 113.72s" time=21:35:11 level=INFO msg="Running: ancient_tunnels" time=21:35:11 level=DEBUG msg="Checking for character corpse..." time=21:35:11 level=DEBUG msg="Character corpse not found :D" time=21:35:11 level=DEBUG msg="Checking for items to identify..." time=21:35:11 level=INFO msg="Identifying 1 items..." time=21:35:14 level=INFO msg="Visiting vendor..." forceRefill=false time=21:35:21 level=DEBUG msg="Buying: 0 Healing potions and 0 Mana potions" time=21:35:22 level=DEBUG msg="Item Gothic Shield [Rare] sold" time=21:35:24 level=DEBUG msg="Checking for items to stash..." time=21:35:24 level=DEBUG msg="Checking for items to stash..." time=21:35:32 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:35:38 level=DEBUG msg="Load completed, continuing execution" time=21:35:38 level=DEBUG msg=Buffing... time=21:35:47 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:35:48 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:35:49 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:35:50 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:35:52 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:35:54 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:35:57 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:35:58 level=DEBUG msg="Using HealingPotion potion [Column: 1]. HP: 84 MP: 92" time=21:35:59 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:36:04 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:36:12 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:36:12 level=DEBUG msg="Item Detected: SuperHealingPotion [2] at X:20103 Y:5098" time=21:36:13 level=DEBUG msg="Picking up: Super Healing Potion [Normal]" time=21:36:14 level=INFO msg="Item picked up: Super Healing Potion [Normal]" time=21:36:20 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:36:20 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:36:20 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:36:21 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:36:22 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:36:22 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:36:22 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:36:24 level=DEBUG msg="Using HealingPotion potion [Column: 1]. HP: 87 MP: 89" time=21:36:24 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:36:26 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:36:30 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:36:33 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:36:40 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:36:42 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:36:45 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:36:53 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:36:55 level=DEBUG msg="Item Detected: SuperHealingPotion [2] at X:20054 Y:5155" time=21:36:56 level=DEBUG msg="Picking up: Super Healing Potion [Normal]" time=21:36:56 level=INFO msg="Item picked up: Super Healing Potion [Normal]" time=21:36:57 level=DEBUG msg="Item Detected: Ring [4] at X:20020 Y:5128" time=21:36:59 level=DEBUG msg="Picking up: Ring [Magic]" time=21:37:00 level=INFO msg="Item picked up: Ring [Magic]" time=21:37:00 level=DEBUG msg="Item Detected: DemonhideGloves [4] at X:20034 Y:5083" time=21:37:01 level=DEBUG msg="Picking up: Demonhide Gloves [Magic]" time=21:37:02 level=INFO msg="Item picked up: Demonhide Gloves [Magic]" time=21:37:02 level=DEBUG msg="Item Detected: SharkskinGloves [4] at X:20032 Y:5078" time=21:37:02 level=DEBUG msg="Picking up: Sharkskin Gloves [Magic]" time=21:37:03 level=INFO msg="Item picked up: Sharkskin Gloves [Magic]" time=21:37:08 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:37:15 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:37:15 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:37:17 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:37:19 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:37:21 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:37:24 level=DEBUG msg="Using HealingPotion potion [Column: 2]. HP: 84 MP: 97" time=21:37:25 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:37:26 level=DEBUG msg="Item Detected: SuperHealingPotion [2] at X:20107 Y:5089" time=21:37:33 level=DEBUG msg="Picking up: Super Healing Potion [Normal]" time=21:37:33 level=INFO msg="Item picked up: Super Healing Potion [Normal]" time=21:37:50 level=DEBUG msg="Using HealingPotion potion on Mercenary [Column: 2]. HP: 80" time=21:37:50 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:37:53 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:37:53 level=DEBUG msg="Item Detected: SuperHealingPotion [2] at X:20142 Y:5087" time=21:37:54 level=DEBUG msg="Picking up: Super Healing Potion [Normal]" time=21:37:55 level=INFO msg="Item picked up: Super Healing Potion [Normal]" time=21:38:02 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:02 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:38:03 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:38:05 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:11 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:14 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:16 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:17 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:20 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:22 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:26 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:28 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:31 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:34 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:38:34 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:35 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:38:35 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:38:36 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:40 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:42 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:44 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:46 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:49 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:53 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:38:55 level=DEBUG msg="Item Detected: Ring [4] at X:20228 Y:5164" time=21:38:56 level=DEBUG msg="Picking up: Ring [Magic]" time=21:38:57 level=INFO msg="Item picked up: Ring [Magic]" time=21:39:01 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:39:03 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:39:03 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:39:04 level=DEBUG msg="Monster detected close to the player, casting Blizzard over it" time=21:39:05 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:39:07 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:39:10 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:39:11 level=DEBUG msg=Buffing... time=21:39:13 level=DEBUG msg="All the rooms for this level have been cleared, finishing run." time=21:39:13 level=DEBUG msg="Item Detected: LightBelt [4] at X:20271 Y:5194" time=21:39:13 level=DEBUG msg="Picking up: Light Belt [Magic]" time=21:39:14 level=INFO msg="Item picked up: Light Belt [Magic]" time=21:39:14 level=DEBUG msg="Item Detected: Ring [4] at X:20269 Y:5194" time=21:39:14 level=DEBUG msg="Picking up: Ring [Magic]" time=21:39:15 level=INFO msg="Item picked up: Ring [Magic]" time=21:39:15 level=DEBUG msg="Item Detected: Amulet [4] at X:20272 Y:5208" time=21:39:15 level=DEBUG msg="Picking up: Amulet [Magic]" time=21:39:16 level=INFO msg="Item picked up: Amulet [Magic]" time=21:39:16 level=DEBUG msg="Item Detected: Gauntlets [4] at X:20265 Y:5217" time=21:39:17 level=DEBUG msg="Picking up: Gauntlets [Magic]" time=21:39:21 level=DEBUG msg="Item could not be picked up, going back to town to sell junk and stash" time=21:39:25 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:39:27 level=DEBUG msg="Load completed, continuing execution" time=21:39:27 level=DEBUG msg="Checking for character corpse..." time=21:39:27 level=DEBUG msg="Character corpse not found :D" time=21:39:27 level=DEBUG msg="Checking for items to identify..." time=21:39:27 level=INFO msg="Identifying 7 items..." time=21:39:42 level=INFO msg="Visiting vendor..." forceRefill=false time=21:39:53 level=DEBUG msg="Buying: 0 Healing potions and 0 Mana potions" time=21:39:54 level=DEBUG msg="Item Ring [Magic] sold" time=21:39:56 level=DEBUG msg="Item Ring [Magic] sold" time=21:39:57 level=DEBUG msg="Item Amulet [Magic] sold" time=21:39:58 level=DEBUG msg="Item Light Belt [Magic] sold" time=21:39:59 level=DEBUG msg="Item Ring [Magic] sold" time=21:40:00 level=DEBUG msg="Item Demonhide Gloves [Magic] sold" time=21:40:01 level=DEBUG msg="Item Sharkskin Gloves [Magic] sold" time=21:40:03 level=DEBUG msg="Checking for items to stash..." time=21:40:03 level=DEBUG msg="Checking for items to stash..." time=21:40:10 level=DEBUG msg="Item Detected: Gauntlets [4] at X:20265 Y:5217" time=21:40:10 level=DEBUG msg="Picking up: Gauntlets [Magic]" time=21:40:11 level=INFO msg="Item picked up: Gauntlets [Magic]" time=21:40:11 level=INFO msg="Run ancient_tunnels finished, length: 300.01s" time=21:40:21 level=ERROR msg="Error creating new game: timeout" time=21:40:25 level=DEBUG msg="Fetching map data..." seed=1502982702 time=21:40:37 level=DEBUG msg="Fetch completed" ms=11749 time=21:40:37 level=INFO msg="Starting Game #1. Run list: lower_kurast_chest, mephisto, ancient_tunnels" time=21:40:37 level=INFO msg="Running: lower_kurastchest" time=21:40:38 level=DEBUG msg="Checking for character corpse..." time=21:40:38 level=DEBUG msg="Character corpse not found :D" time=21:40:38 level=DEBUG msg="Checking for items to identify..." time=21:40:38 level=INFO msg="Identifying 1 items..." time=21:40:40 level=INFO msg="Visiting vendor..." forceRefill=false time=21:40:57 level=DEBUG msg="Buying: 0 Healing potions and 0 Mana potions" time=21:40:59 level=DEBUG msg="Item Gauntlets [Magic] sold" time=21:41:00 level=DEBUG msg="Checking for items to stash..." time=21:41:00 level=DEBUG msg="Checking for items to stash..." time=21:41:08 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:41:12 level=DEBUG msg="Load completed, continuing execution" time=21:41:12 level=DEBUG msg=Buffing... time=21:41:13 level=ERROR msg="fatal error detected, forcing shutdown" supervisor=SuperSorcI error="runtime error: index out of range [656] with length 400" stacktrace="goroutine 42 [running]:\nruntime/debug.Stack()\n\truntime/debug/stack.go:24 +0x5e\ngithub.com/hectorgimenez/koolo/internal.(SupervisorManager).Start.func2.1()\n\tgithub.com/hectorgimenez/koolo/internal/manager.go:76 +0x108\npanic({0xf65f20?, 0xc0000aa8e8?})\n\truntime/panic.go:770 +0x132\ngithub.com/hectorgimenez/koolo/internal/pather.(World).From(...)\n\tgithub.com/hectorgimenez/koolo/internal/pather/world.go:55\ngithub.com/hectorgimenez/koolo/internal/pather.(*PathFinder).GetPath(, {{{0x1360, 0xac8}, {0x0, 0x0, {0x0, 0x0}}, {0xc001b31208, 0x16, 0x23}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/pather/pathfinder.go:138 +0xaff\ngithub.com/hectorgimenez/koolo/internal/action/step.(*MoveToStep).Run(, {{{0x1360, 0xac8}, {0x0, 0x0, {0x0, 0x0}}, {0xc001b31208, 0x16, 0x23}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/action/step/moveto.go:120 +0x874\ngithub.com/hectorgimenez/koolo/internal/action.(*StepChainAction).NextStep(, {{{0x1360, 0xac8}, {0x0, 0x0, {0x0, 0x0}}, {0xc001b31208, 0x16, 0x23}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/action/action_stepchain.go:47 +0x222\ngithub.com/hectorgimenez/koolo/internal/action.(*Chain).NextStep(, {{{0x1360, 0xac8}, {0x0, 0x0, {0x0, 0x0}}, {0xc001b31208, 0x16, 0x23}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/action/actionchain.go:48 +0x1f6\ngithub.com/hectorgimenez/koolo/internal/action.(*Chain).NextStep(, {{{0x1360, 0xac8}, {0x0, 0x0, {0x0, 0x0}}, {0xc001b31208, 0x16, 0x23}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/action/action_chain.go:48 +0x1f6\ngithub.com/hectorgimenez/koolo/internal.(Bot).Run(0xc0007c0000, {0x11ceee0, 0xc000bd6050}, 0x0, {0xc0008a4740, 0x3, 0x4})\n\tgithub.com/hectorgimenez/koolo/internal/bot.go:157 +0x1815\ngithub.com/hectorgimenez/koolo/internal.(SinglePlayerSupervisor).Start(0xc0000a04c8)\n\tgithub.com/hectorgimenez/koolo/internal/single_supervisor.go:69 +0x2ae\ngithub.com/hectorgimenez/koolo/internal.(SupervisorManager).Start.func2()\n\tgithub.com/hectorgimenez/koolo/internal/manager.go:82 +0xa2\ncreated by github.com/hectorgimenez/koolo/internal.(SupervisorManager).Start in goroutine 39\n\tgithub.com/hectorgimenez/koolo/internal/manager.go:69 +0x21d\n" time=21:41:13 level=INFO msg=Stopping... configuration=SuperSorcI time=21:41:13 level=INFO msg="Finished stopping" configuration=SuperSorcI
2)
time=21:50:40 level=INFO msg="Waiting for character selection screen..." time=21:51:03 level=INFO msg="Character selection screen found" time=21:51:03 level=INFO msg="Selecting character..." time=21:51:03 level=INFO msg="Character found" time=21:51:08 level=DEBUG msg="Fetching map data..." seed=1837341189 time=21:51:12 level=DEBUG msg="Fetch completed" ms=4667 time=21:51:12 level=INFO msg="Starting Game #0. Run list: mephisto" time=21:51:12 level=INFO msg="Running: mephisto" time=21:51:15 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:51:17 level=DEBUG msg="Load completed, continuing execution" time=21:51:17 level=DEBUG msg="Checking for character corpse..." time=21:51:17 level=DEBUG msg="Character corpse not found :D" time=21:51:17 level=DEBUG msg="Checking for items to stash..." time=21:51:17 level=DEBUG msg="Checking for items to identify..." time=21:51:17 level=DEBUG msg="No items to identify..." time=21:51:17 level=DEBUG msg="Belt Stats Health: 8/8 healing, 4/4 mana, 4/4 rejuv." time=21:51:17 level=DEBUG msg="Checking for items to stash..." time=21:51:17 level=DEBUG msg="Checking for items to stash..." time=21:51:29 level=DEBUG msg=Buffing... time=21:52:05 level=DEBUG msg="Using HealingPotion potion on Mercenary [Column: 2]. HP: 83" time=21:52:05 level=DEBUG msg="Using ManaPotion potion [Column: 4]. HP: 97 MP: 20" time=21:52:08 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:52:11 level=DEBUG msg="Item Detected: ClaspedOrb [4] at X:17559 Y:8068" time=21:52:12 level=DEBUG msg="Picking up: Clasped Orb [Magic]" time=21:52:12 level=INFO msg="Item picked up: Clasped Orb [Magic]" time=21:52:12 level=INFO msg="Run mephisto finished, length: 59.98s" time=21:52:23 level=ERROR msg="Error creating new game: timeout" time=21:52:26 level=DEBUG msg="Fetching map data..." seed=1502982702 time=21:52:30 level=DEBUG msg="Fetch completed" ms=4816 time=21:52:30 level=INFO msg="Starting Game #1. Run list: mephisto" time=21:52:30 level=INFO msg="Running: mephisto" time=21:52:33 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:52:34 level=DEBUG msg="Load completed, continuing execution" time=21:52:34 level=DEBUG msg="Checking for character corpse..." time=21:52:34 level=DEBUG msg="Character corpse not found :D" time=21:52:34 level=DEBUG msg="Checking for items to identify..." time=21:52:34 level=INFO msg="Identifying 1 items..." time=21:52:37 level=INFO msg="Visiting vendor..." forceRefill=false time=21:52:47 level=DEBUG msg="Buying: 1 Healing potions and 1 Mana potions" time=21:52:48 level=DEBUG msg="Purchased Greater Healing Potion [X:9 Y:4]" time=21:52:49 level=DEBUG msg="Purchased Greater Mana Potion [X:9 Y:7]" time=21:52:51 level=DEBUG msg="Item Clasped Orb [Magic] sold" time=21:52:53 level=DEBUG msg="Checking for items to stash..." time=21:52:53 level=DEBUG msg="Checking for items to stash..." time=21:53:01 level=DEBUG msg=Buffing... time=21:53:03 level=WARN msg="Game finished with errors, reason: unrecoverable error occurred, game can not continue: attempt limit reached on step: MoveToStep: path could not be calculated, maybe there is an obstacle or a flying platform (arcane sanctuary). Game total time: 32.29s" supervisor=SuperSorcI mapSeed=1502982702 time=21:53:11 level=ERROR msg="Error creating new game: timeout" time=21:53:14 level=DEBUG msg="Fetching map data..." seed=1502982702 time=21:53:19 level=DEBUG msg="Fetch completed" ms=5636 time=21:53:19 level=INFO msg="Starting Game #1. Run list: mephisto" time=21:53:19 level=INFO msg="Running: mephisto" time=21:53:21 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:53:24 level=DEBUG msg="Load completed, continuing execution" time=21:53:24 level=DEBUG msg="Checking for character corpse..." time=21:53:24 level=DEBUG msg="Character corpse not found :D" time=21:53:24 level=DEBUG msg="Checking for items to identify..." time=21:53:24 level=DEBUG msg="No items to identify..." time=21:53:24 level=DEBUG msg="Belt Stats Health: 8/8 healing, 4/4 mana, 4/4 rejuv." time=21:53:24 level=DEBUG msg="Checking for items to stash..." time=21:53:24 level=DEBUG msg="Checking for items to stash..." time=21:53:37 level=DEBUG msg=Buffing... time=21:53:38 level=WARN msg="Game finished with errors, reason: unrecoverable error occurred, game can not continue: attempt limit reached on step: MoveToStep: path could not be calculated, maybe there is an obstacle or a flying platform (arcane sanctuary). Game total time: 18.99s" supervisor=SuperSorcI mapSeed=1502982702 time=21:53:49 level=ERROR msg="Error creating new game: timeout" time=21:53:52 level=DEBUG msg="Fetching map data..." seed=842369306 time=21:53:57 level=DEBUG msg="Fetch completed" ms=4666 time=21:53:57 level=INFO msg="Starting Game #1. Run list: mephisto" time=21:53:57 level=INFO msg="Running: mephisto" time=21:53:59 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:54:00 level=DEBUG msg="Load completed, continuing execution" time=21:54:00 level=DEBUG msg="Checking for character corpse..." time=21:54:00 level=DEBUG msg="Character corpse not found :D" time=21:54:00 level=DEBUG msg="Checking for items to identify..." time=21:54:00 level=DEBUG msg="No items to identify..." time=21:54:00 level=DEBUG msg="Belt Stats Health: 8/8 healing, 4/4 mana, 4/4 rejuv." time=21:54:00 level=DEBUG msg="Checking for items to stash..." time=21:54:00 level=DEBUG msg="Checking for items to stash..." time=21:54:12 level=DEBUG msg=Buffing... time=21:54:24 level=DEBUG msg="Using HealingPotion potion [Column: 1]. HP: 84 MP: 79" time=21:54:28 level=DEBUG msg="Using HealingPotion potion [Column: 1]. HP: 88 MP: 81" time=21:54:45 level=DEBUG msg="Using HealingPotion potion on Mercenary [Column: 1]. HP: 81" time=21:54:48 level=DEBUG msg="Looks like monster is not reachable, reducing max attack distance" time=21:54:48 level=DEBUG msg="Using ManaPotion potion [Column: 4]. HP: 95 MP: 20" time=21:54:50 level=DEBUG msg="Item Detected: HornedHelm [4] at X:17566 Y:8061" time=21:54:50 level=DEBUG msg="Picking up: Horned Helm [Magic]" time=21:54:50 level=INFO msg="Item picked up: Horned Helm [Magic]" time=21:54:50 level=DEBUG msg="Item Detected: SuperHealingPotion [2] at X:17568 Y:8065" time=21:54:50 level=DEBUG msg="Picking up: Super Healing Potion [Normal]" time=21:54:51 level=INFO msg="Item picked up: Super Healing Potion [Normal]" time=21:54:51 level=INFO msg="Run mephisto finished, length: 54.51s" time=21:55:01 level=ERROR msg="Error creating new game: timeout" time=21:55:04 level=DEBUG msg="Fetching map data..." seed=1502982702 time=21:55:08 level=DEBUG msg="Fetch completed" ms=4452 time=21:55:08 level=INFO msg="Starting Game #1. Run list: mephisto" time=21:55:08 level=INFO msg="Running: mephisto" time=21:55:10 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=21:55:12 level=DEBUG msg="Load completed, continuing execution" time=21:55:12 level=DEBUG msg="Checking for character corpse..." time=21:55:12 level=DEBUG msg="Character corpse not found :D" time=21:55:12 level=DEBUG msg="Checking for items to identify..." time=21:55:12 level=INFO msg="Identifying 1 items..." time=21:55:15 level=INFO msg="Visiting vendor..." forceRefill=false time=21:55:26 level=DEBUG msg="Buying: 2 Healing potions and 1 Mana potions" time=21:55:28 level=DEBUG msg="Purchased Greater Healing Potion [X:9 Y:4]" time=21:55:29 level=DEBUG msg="Purchased Greater Healing Potion [X:9 Y:4]" time=21:55:30 level=DEBUG msg="Purchased Greater Mana Potion [X:9 Y:7]" time=21:55:32 level=DEBUG msg="Item Horned Helm [Magic] sold" time=21:55:33 level=DEBUG msg="Checking for items to stash..." time=21:55:33 level=DEBUG msg="Checking for items to stash..." time=21:55:41 level=DEBUG msg=Buffing... time=21:55:49 level=DEBUG msg="Using HealingPotion potion [Column: 2]. HP: 73 MP: 92" time=21:55:52 level=DEBUG msg="Using RejuvenationPotion potion [Column: 3]. HP: 59 MP: 84" time=21:55:54 level=DEBUG msg="Using HealingPotion potion [Column: 2]. HP: 68 MP: 98" time=21:55:58 level=DEBUG msg="Using RejuvenationPotion potion [Column: 3]. HP: 53 MP: 88" time=21:57:08 level=INFO msg=Stopping... configuration=SuperSorcI time=21:57:08 level=INFO msg="Finished stopping" configuration=SuperSorcI
3)
time=22:03:52 level=INFO msg="Waiting for character selection screen..." time=22:04:13 level=INFO msg="Character selection screen found" time=22:04:13 level=INFO msg="Selecting character..." time=22:04:13 level=INFO msg="Character found" time=22:04:18 level=DEBUG msg="Fetching map data..." seed=982680849 time=22:04:23 level=DEBUG msg="Fetch completed" ms=4673 time=22:04:23 level=INFO msg="Starting Game #0. Run list: lower_kurast_chest" time=22:04:23 level=INFO msg="Running: lower_kurast_chest" time=22:04:25 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=22:04:26 level=DEBUG msg="Load completed, continuing execution" time=22:04:26 level=DEBUG msg="Checking for character corpse..." time=22:04:26 level=DEBUG msg="Character corpse not found :D" time=22:04:26 level=DEBUG msg="Checking for items to stash..." time=22:04:26 level=DEBUG msg="Checking for items to identify..." time=22:04:26 level=DEBUG msg="No items to identify..." time=22:04:26 level=DEBUG msg="Belt Stats Health: 6/8 healing, 4/4 mana, 2/4 rejuv." time=22:04:26 level=DEBUG msg="Checking for items to stash..." time=22:04:26 level=DEBUG msg="Checking for items to stash..." time=22:04:33 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=22:04:38 level=DEBUG msg="Load completed, continuing execution" time=22:04:38 level=DEBUG msg=Buffing... time=22:04:42 level=DEBUG msg="Using HealingPotion potion [Column: 1]. HP: 71 MP: 57" time=22:04:46 level=DEBUG msg="Using HealingPotion potion [Column: 1]. HP: 73 MP: 68" time=22:04:48 level=DEBUG msg="Item Detected: SmokedSphere [4] at X:5027 Y:3017" time=22:04:48 level=DEBUG msg="Picking up: Smoked Sphere [Magic]" time=22:04:48 level=INFO msg="Item picked up: Smoked Sphere [Magic]" time=22:04:54 level=DEBUG msg="Item Detected: RageMask [4] at X:5013 Y:3022" time=22:04:54 level=DEBUG msg="Picking up: Rage Mask [Magic]" time=22:04:54 level=INFO msg="Item picked up: Rage Mask [Magic]" time=22:04:58 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=22:05:02 level=DEBUG msg="Load completed, continuing execution" time=22:05:07 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=22:05:09 level=DEBUG msg="Load completed, continuing execution" time=22:05:09 level=DEBUG msg="No items detected, waiting a bit and will try again" waitMs=1000 time=22:05:11 level=INFO msg="Run lower_kurast_chest finished, length: 47.90s" time=22:05:21 level=ERROR msg="Error creating new game: timeout" time=22:05:23 level=DEBUG msg="Fetching map data..." seed=1502982702 time=22:05:27 level=DEBUG msg="Fetch completed" ms=4184 time=22:05:27 level=INFO msg="Starting Game #1. Run list: lower_kurast_chest" time=22:05:27 level=INFO msg="Running: lower_kurastchest" time=22:05:29 level=DEBUG msg="Load completed, continuing execution" time=22:05:29 level=DEBUG msg="Checking for character corpse..." time=22:05:29 level=DEBUG msg="Character corpse not found :D" time=22:05:29 level=DEBUG msg="Checking for items to identify..." time=22:05:29 level=INFO msg="Identifying 2 items..." time=22:05:33 level=INFO msg="Visiting vendor..." forceRefill=false time=22:05:41 level=DEBUG msg="Buying: 4 Healing potions and 0 Mana potions" time=22:05:42 level=DEBUG msg="Purchased Super Healing Potion [X:9 Y:9]" time=22:05:43 level=DEBUG msg="Purchased Super Healing Potion [X:9 Y:9]" time=22:05:44 level=DEBUG msg="Purchased Super Healing Potion [X:9 Y:9]" time=22:05:44 level=DEBUG msg="Purchased Super Healing Potion [X:9 Y:9]" time=22:05:46 level=DEBUG msg="Item Smoked Sphere [Magic] sold" time=22:05:47 level=DEBUG msg="Item Rage Mask [Magic] sold" time=22:05:48 level=DEBUG msg="Checking for items to stash..." time=22:05:48 level=DEBUG msg="Checking for items to stash..." time=22:05:56 level=DEBUG msg="Loading screen detected, waiting until loading screen is gone" time=22:06:01 level=DEBUG msg="Load completed, continuing execution" time=22:06:01 level=DEBUG msg=Buffing... time=22:06:02 level=ERROR msg="fatal error detected, forcing shutdown" supervisor=SuperSorcI error="runtime error: index out of range [-104]" stacktrace="goroutine 102 [running]:\nruntime/debug.Stack()\n\truntime/debug/stack.go:24 +0x5e\ngithub.com/hectorgimenez/koolo/internal.(SupervisorManager).Start.func2.1()\n\tgithub.com/hectorgimenez/koolo/internal/manager.go:76 +0x108\npanic({0xf65f20?, 0xc000896000?})\n\truntime/panic.go:770 +0x132\ngithub.com/hectorgimenez/koolo/internal/pather.(World).From(...)\n\tgithub.com/hectorgimenez/koolo/internal/pather/world.go:55\ngithub.com/hectorgimenez/koolo/internal/pather.(*PathFinder).GetPath(, {{{0x1360, 0xac8}, {0x0, 0x0, {0x0, 0x0}}, {0xc00151db08, 0xc, 0x11}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/pather/pathfinder.go:138 +0xaff\ngithub.com/hectorgimenez/koolo/internal/action/step.(*MoveToStep).Run(, {{{0x1360, 0xac8}, {0x0, 0x0, {0x0, 0x0}}, {0xc00151db08, 0xc, 0x11}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/action/step/moveto.go:120 +0x874\ngithub.com/hectorgimenez/koolo/internal/action.(*StepChainAction).NextStep(, {{{0x1360, 0xac8}, {0x0, 0x0, {0x0, 0x0}}, {0xc00151db08, 0xc, 0x11}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/action/action_stepchain.go:47 +0x222\ngithub.com/hectorgimenez/koolo/internal/action.(*Chain).NextStep(, {{{0x1360, 0xac8}, {0x0, 0x0, {0x0, 0x0}}, {0xc00151db08, 0xc, 0x11}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/action/actionchain.go:48 +0x1f6\ngithub.com/hectorgimenez/koolo/internal/action.(*Chain).NextStep(, {{{0x1360, 0xac8}, {0x0, 0x0, {0x0, 0x0}}, {0xc00151db08, 0xc, 0x11}, ...}, ...}, ...)\n\tgithub.com/hectorgimenez/koolo/internal/action/action_chain.go:48 +0x1f6\ngithub.com/hectorgimenez/koolo/internal.(Bot).Run(0xc000b8e080, {0x11ceee0, 0xc0006ba1e0}, 0x0, {0xc0003a33d0, 0x1, 0x1})\n\tgithub.com/hectorgimenez/koolo/internal/bot.go:157 +0x1815\ngithub.com/hectorgimenez/koolo/internal.(SinglePlayerSupervisor).Start(0xc0007cc050)\n\tgithub.com/hectorgimenez/koolo/internal/single_supervisor.go:69 +0x2ae\ngithub.com/hectorgimenez/koolo/internal.(SupervisorManager).Start.func2()\n\tgithub.com/hectorgimenez/koolo/internal/manager.go:82 +0xa2\ncreated by github.com/hectorgimenez/koolo/internal.(SupervisorManager).Start in goroutine 27\n\tgithub.com/hectorgimenez/koolo/internal/manager.go:69 +0x21d\n" time=22:06:02 level=INFO msg=Stopping... configuration=SuperSorcI time=22:06:02 level=INFO msg="Finished stopping" configuration=SuperSorcI
Version
a051d83 (today's commit)