2017-10-08 17:28:05.579976 | server | 1 | app | Stonehearth Version 0.22.5.763 (x64)
2017-10-08 17:28:05.581944 | server | 1 | core.config | user id is 3680d8c2-a943-11e7-9cd1-fcaa14582be5
2017-10-08 17:28:05.581944 | server | 1 | core.config | session id is 9268f78a-ac6f-11e7-884a-fcaa14582be5
2017-10-08 17:28:05.583949 | server | 0 | core.system | Initializing crash reporter
2017-10-08 17:28:06.093282 | server | 0 | lua.data | lua jit is disabled
2017-10-08 17:28:06.098283 | server | 1 | resources | Could not find hash for module “better_stockpiles”.
2017-10-08 17:28:06.248792 | server | 1 | resources | Modules have been modified by the user!
2017-10-08 17:28:06.286828 | server | 1 | resources | could not find resource for settlementdecor/entities/resources/ember_plant_pollen/ember_plant_pollen_ghost.json while processing mixintos for settlementdecor_bs. ignoring.
2017-10-08 17:28:06.286828 | server | 1 | resources | could not find resource for settlementdecor/entities/resources/foxfire_slime/foxfire_slime_ghost.json while processing mixintos for settlementdecor_bs. ignoring.
2017-10-08 17:28:06.288877 | server | 1 | app | Running Stonehearth server on port 40420
2017-10-08 17:28:06.289829 | server | 0 | physics.navgrid | subspace pathfinder is enabled
2017-10-08 17:28:06.293794 | client | 1 | game_engine | Initializing OpenGL
2017-10-08 17:28:06.306794 | server | 1 | mod radiant | lua controller lifetime tracking set to "nil"
2017-10-08 17:28:06.344857 | server | 0 | mod stonehearth.ai.service | [] enable_cpp_compound_action is on
2017-10-08 17:28:06.352824 | client | 1 | game_engine | Determining window placement
2017-10-08 17:28:06.353837 | client | 1 | game_engine | Selecting monitor at 800, 450
2017-10-08 17:28:06.353837 | client | 1 | game_engine | Creating OpenGL Window
2017-10-08 17:28:07.587430 | client | 1 | game_engine | Creating OpenGL Context
2017-10-08 17:28:07.589462 | client | 1 | game_engine | Finished OpenGL Initialization
2017-10-08 17:28:07.589462 | client | 1 | horde.general | Initializing GL2 backend using OpenGL driver ‘4.5.0 NVIDIA 376.53’ by ‘NVIDIA Corporation’ on 'GeForce GTX 960/PCIe/SSE2’
2017-10-08 17:28:07.788048 | client | 1 | browser | cef started.
2017-10-08 17:28:07.789515 | client | 1 | browser | Navigate called without an existing browser!
2017-10-08 17:28:07.793529 | thread8976 | 1 | browser | Doing a buffered navigation…
2017-10-08 17:28:07.854560 | client | 0 | physics.navgrid | subspace pathfinder is enabled
2017-10-08 17:28:07.874593 | client | 1 | mod radiant | lua controller lifetime tracking set to "nil"
2017-10-08 17:28:07.898095 | client | 1 | network | client allocating new send buffer (total: 0)
2017-10-08 17:28:07.937625 | client | 1 | client.core | user feedback is on
2017-10-08 17:28:09.789323 | server | 1 | network | server allocating new send buffer (total: 0)
2017-10-08 17:28:12.792028 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:28:17.211127 | client | 0 | client.core | loading save “auto_save”:
2017-10-08 17:28:17.211127 | client | 0 | client.core | {
2017-10-08 17:28:17.211127 | client | 0 | client.core | “name” : "Auto save: ",
2017-10-08 17:28:17.211127 | client | 0 | client.core | “town_name” : “Dorne”,
2017-10-08 17:28:17.211127 | client | 0 | client.core | “game_date” : {
2017-10-08 17:28:17.211628 | client | 0 | client.core | “day” : 15,
2017-10-08 17:28:17.211628 | client | 0 | client.core | “month” : 2,
2017-10-08 17:28:17.211628 | client | 0 | client.core | “year” : 1000,
2017-10-08 17:28:17.211628 | client | 0 | client.core | “hour” : 14,
2017-10-08 17:28:17.211628 | client | 0 | client.core | “minute” : 58
2017-10-08 17:28:17.211628 | client | 0 | client.core | },
2017-10-08 17:28:17.211628 | client | 0 | client.core | “timestamp” : 1507498036386,
2017-10-08 17:28:17.211628 | client | 0 | client.core | “time” : “10/8/2017, 5:27:16 PM”,
2017-10-08 17:28:17.211628 | client | 0 | client.core | “jobs” : {
2017-10-08 17:28:17.211628 | client | 0 | client.core | “crafters” : 4,
2017-10-08 17:28:17.211628 | client | 0 | client.core | “workers” : 5,
2017-10-08 17:28:17.211628 | client | 0 | client.core | “soldiers” : 3
2017-10-08 17:28:17.211628 | client | 0 | client.core | },
2017-10-08 17:28:17.211628 | client | 0 | client.core | “version” : “0.22.5.763”,
2017-10-08 17:28:17.211628 | client | 0 | client.core | “save_version” : 12
2017-10-08 17:28:17.211628 | client | 0 | client.core | }
2017-10-08 17:28:17.365471 | client | 1 | resources | Could not find hash for module “better_stockpiles”.
2017-10-08 17:28:17.501010 | client | 1 | resources | Modules have been modified by the user!
2017-10-08 17:28:17.540258 | client | 1 | resources | could not find resource for settlementdecor/entities/resources/ember_plant_pollen/ember_plant_pollen_ghost.json while processing mixintos for settlementdecor_bs. ignoring.
2017-10-08 17:28:17.540258 | client | 1 | resources | could not find resource for settlementdecor/entities/resources/foxfire_slime/foxfire_slime_ghost.json while processing mixintos for settlementdecor_bs. ignoring.
2017-10-08 17:28:17.585724 | cef | 1 | browser | Load error: -3
2017-10-08 17:28:17.619388 | client | 0 | core.system | not tested C:\rb\ihome\root\SH-OB0-BUILD\stonehearth\source\game_engine\legacy_game_engine\horde_render_node.cpp 159
2017-10-08 17:28:17.626313 | client | 1 | lua.code | Shutting down script host.
2017-10-08 17:28:17.754449 | server | 1 | simulation.remote_client | started buffering client updates. (seq:144 ack:134)
2017-10-08 17:28:17.846956 | client | 1 | lua.code | Script host destroyed.
2017-10-08 17:28:17.857485 | client | 0 | physics.navgrid | subspace pathfinder is enabled
2017-10-08 17:28:17.908039 | server | 0 | simulation.core | Restarting game.
2017-10-08 17:28:17.918541 | server | 0 | om.entity | Reference still exists to (1) after destroy_entity was called
2017-10-08 17:28:17.977099 | server | 1 | simulation.core | All entities have been destroyed.
2017-10-08 17:28:17.977099 | server | 1 | simulation.core | Shutting down traces.
2017-10-08 17:28:17.977099 | server | 1 | simulation.core | Shutting down game objects.
2017-10-08 17:28:17.977599 | server | 1 | simulation.core | All entities and datastores have been destroyed.
2017-10-08 17:28:17.977599 | server | 1 | lua.code | Shutting down script host.
2017-10-08 17:28:18.049232 | server | 1 | lua.code | Script host destroyed.
2017-10-08 17:28:18.049719 | server | 1 | simulation.core | Shutting down data objects.
2017-10-08 17:28:18.089784 | server | 1 | resources | Could not find hash for module “better_stockpiles”.
2017-10-08 17:28:18.267423 | server | 1 | resources | Modules have been modified by the user!
2017-10-08 17:28:18.319486 | server | 1 | resources | could not find resource for settlementdecor/entities/resources/ember_plant_pollen/ember_plant_pollen_ghost.json while processing mixintos for settlementdecor_bs. ignoring.
2017-10-08 17:28:18.319486 | server | 1 | resources | could not find resource for settlementdecor/entities/resources/foxfire_slime/foxfire_slime_ghost.json while processing mixintos for settlementdecor_bs. ignoring.
2017-10-08 17:28:18.319985 | server | 0 | physics.navgrid | subspace pathfinder is enabled
2017-10-08 17:28:18.329487 | server | 0 | simulation.core | Loading save
2017-10-08 17:28:20.000463 | server | 1 | dm.store | load progress 31%…
2017-10-08 17:28:20.001462 | client | 1 | client.core | server reported load progress of 31%
2017-10-08 17:28:21.999644 | server | 1 | network | server allocating new send buffer (total: 0)
2017-10-08 17:28:22.001145 | server | 1 | dm.store | load progress 77%…
2017-10-08 17:28:22.001658 | client | 1 | client.core | server reported load progress of 77%
2017-10-08 17:28:23.999902 | server | 1 | network | server allocating new send buffer (total: 0)
2017-10-08 17:28:24.000902 | server | 1 | dm.store | load progress 96%…
2017-10-08 17:28:24.001992 | client | 1 | client.core | server reported load progress of 96%
2017-10-08 17:28:24.235125 | server | 1 | dm.store | load objects finished!
2017-10-08 17:28:24.256628 | server | 0 | dm.store | took 5.927 seconds to load
2017-10-08 17:28:24.256628 | server | 1 | network | server allocating new send buffer (total: 0)
2017-10-08 17:28:24.259162 | client | 0 | client.core | server load save file finished. loading client state.
2017-10-08 17:28:24.259642 | client | 0 | client.core | starting loading… "A:\Program Files (x86)\Steam\steamapps\common\Stonehearth\saved_games\auto_save"
2017-10-08 17:28:24.283169 | client | 1 | dm.store | load progress 99%…
2017-10-08 17:28:24.283169 | client | 1 | dm.store | load objects finished!
2017-10-08 17:28:24.283683 | client | 0 | dm.store | took 0.024 seconds to load
2017-10-08 17:28:24.301861 | client | 1 | client.core | restoring datastores…
2017-10-08 17:28:24.302884 | client | 1 | client.core | recreating data objects…
2017-10-08 17:28:24.302884 | client | 1 | client.core | creating error browser…
2017-10-08 17:28:24.302884 | client | 1 | client.core | initializing data object traces…
2017-10-08 17:28:24.302884 | client | 1 | client.core | requiring radiant.client…
2017-10-08 17:28:24.307916 | client | 1 | mod radiant | lua controller lifetime tracking set to "nil"
2017-10-08 17:28:24.311915 | client | 1 | client.core | loading script host…
2017-10-08 17:28:24.388243 | server | 1 | mod radiant | lua controller lifetime tracking set to "nil"
2017-10-08 17:28:24.434479 | client | 0 | sysinfo | Memory Stats: Finished Loading Client
2017-10-08 17:28:24.434479 | client | 0 | sysinfo | Total System Memory: 7.191 GB (7721431040 bytes)
2017-10-08 17:28:24.434479 | client | 0 | sysinfo | Current Memory Usage: 834.094 MB (874610688 bytes)
2017-10-08 17:28:24.434479 | client | 0 | sysinfo | Total Address Space: 128.000 TB (140737488224256 bytes)
2017-10-08 17:28:24.434479 | client | 0 | sysinfo | Available Address Space: 127.999 TB (140736084570112 bytes)
2017-10-08 17:28:24.434959 | client | 0 | sysinfo | Used Address Space: 1.307 GB (1403654144 bytes)
2017-10-08 17:28:25.540279 | server | 0 | mod stonehearth | ai slow start is enabled
2017-10-08 17:28:25.540779 | server | 0 | mod ai | cpp ai is enabled.
2017-10-08 17:28:27.788059 | server | 0 | mod stonehearth.ai.service | [] enable_cpp_compound_action is on
2017-10-08 17:28:27.796075 | server | 0 | mod stonehearth.terrain | [] goblins explored region contains 57 rects
2017-10-08 17:28:27.796573 | server | 0 | mod stonehearth.terrain | [] rabbit explored region contains 0 rects
2017-10-08 17:28:27.796573 | server | 0 | mod stonehearth.terrain | [] undead explored region contains 36 rects
2017-10-08 17:28:27.796573 | server | 0 | mod stonehearth.terrain | [] animals explored region contains 0 rects
2017-10-08 17:28:27.796573 | server | 0 | mod stonehearth.terrain | [] player_1 explored region contains 111 rects
2017-10-08 17:28:27.796573 | server | 0 | mod stonehearth.terrain | [] orcs explored region contains 0 rects
2017-10-08 17:28:27.797060 | server | 0 | mod stonehearth.terrain | [] human_npcs explored region contains 0 rects
2017-10-08 17:28:27.797060 | server | 0 | mod stonehearth.terrain | [] forest explored region contains 78 rects
2017-10-08 17:28:32.049789 | server | 1 | network | server allocating new send buffer (total: 0)
2017-10-08 17:28:32.721743 | server | 1 | network | server allocating new send buffer (total: 1)
2017-10-08 17:28:33.449634 | server | 1 | network | server allocating new send buffer (total: 2)
2017-10-08 17:28:33.743530 | server | 0 | simulation.core | took 1693 ms to flush streamer
2017-10-08 17:28:33.826271 | server | 1 | simulation.core | finished loading game
2017-10-08 17:28:33.826271 | server | 0 | sysinfo | Memory Stats: Finished Loading Simulation
2017-10-08 17:28:33.826271 | server | 0 | sysinfo | Total System Memory: 7.191 GB (7721431040 bytes)
2017-10-08 17:28:33.826780 | server | 0 | sysinfo | Current Memory Usage: 1.209 GB (1298472960 bytes)
2017-10-08 17:28:33.826780 | server | 0 | sysinfo | Total Address Space: 128.000 TB (140737488224256 bytes)
2017-10-08 17:28:33.826780 | server | 0 | sysinfo | Available Address Space: 127.998 TB (140735673421824 bytes)
2017-10-08 17:28:33.826780 | server | 0 | sysinfo | Used Address Space: 1.690 GB (1814802432 bytes)
2017-10-08 17:28:34.612633 | client | 0 | client.core | reporting client load progress 77.750%…
2017-10-08 17:28:35.061308 | client | 0 | client.core | reporting client load progress 80.500%…
2017-10-08 17:28:35.488417 | client | 0 | client.core | reporting client load progress 83.250%…
2017-10-08 17:28:36.040579 | client | 0 | client.core | reporting client load progress 86.000%…
2017-10-08 17:28:36.487541 | client | 0 | client.core | reporting client load progress 88.750%…
2017-10-08 17:28:36.955626 | client | 0 | client.core | reporting client load progress 91.500%…
2017-10-08 17:28:37.494729 | client | 0 | client.core | reporting client load progress 94.250%…
2017-10-08 17:28:38.416870 | client | 0 | client.core | reporting client load progress 97.000%…
2017-10-08 17:28:39.486591 | client | 0 | client.core | reporting client load progress 99.750%…
2017-10-08 17:28:39.527115 | client | 0 | client.core | SetCurrentUIScreen to restart
2017-10-08 17:28:43.095100 | client | 0 | client.core | Changed UI Screen after load
2017-10-08 17:28:46.101325 | server | 0 | ai.execution_frame | (2423146 stonehearth:rabbit) [stonehearth:top] ai slow start is 0
2017-10-08 17:28:46.233502 | server | 0 | mod radiant.timer | [] timer open space observer with duration 180 has no callback. killing.
2017-10-08 17:28:46.797579 | server | 0 | simulation.core | took 1680 ms to update game state
2017-10-08 17:28:46.797579 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:28:46.875854 | server | 1 | simulation.remote_client | stopped buffering client updates. (seq:148 ack:148)
2017-10-08 17:28:46.939032 | server | 1 | simulation.remote_client | started buffering client updates. (seq:369 ack:148)
2017-10-08 17:28:47.147324 | server | 1 | simulation.remote_client | stopped buffering client updates. (seq:369 ack:369)
2017-10-08 17:29:03.974784 | server | 0 | simulation.core | took 213 ms to update game state
2017-10-08 17:29:03.974784 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:04.286586 | server | 0 | mod radiant.timer | [] timer open space observer with duration 180 has no callback. killing.
2017-10-08 17:29:04.286586 | server | 0 | mod radiant.timer | [] timer open space observer with duration 180 has no callback. killing.
2017-10-08 17:29:04.605277 | server | 0 | mod radiant.timer | [] timer open space observer with duration 180 has no callback. killing.
2017-10-08 17:29:04.605277 | server | 0 | mod radiant.timer | [] timer open space observer with duration 180 has no callback. killing.
2017-10-08 17:29:04.605277 | server | 0 | mod radiant.timer | [] timer open space observer with duration 180 has no callback. killing.
2017-10-08 17:29:04.605277 | server | 0 | mod radiant.timer | [] timer open space observer with duration 180 has no callback. killing.
2017-10-08 17:29:04.605277 | server | 0 | mod radiant.timer | [] timer open space observer with duration 180 has no callback. killing.
2017-10-08 17:29:04.605277 | server | 0 | mod radiant.timer | [] timer open space observer with duration 180 has no callback. killing.
2017-10-08 17:29:04.605798 | server | 0 | mod radiant.timer | [] timer open space observer with duration 180 has no callback. killing.
2017-10-08 17:29:04.605798 | server | 0 | mod radiant.timer | [] timer open space observer with duration 180 has no callback. killing.
2017-10-08 17:29:04.605798 | server | 0 | mod radiant.timer | [] timer open space observer with duration 180 has no callback. killing.
2017-10-08 17:29:04.806732 | server | 0 | mod radiant.timer | [] timer darkness observer with duration 1800 has no callback. killing.
2017-10-08 17:29:04.806732 | server | 0 | mod radiant.timer | [] timer darkness observer with duration 1800 has no callback. killing.
2017-10-08 17:29:04.806732 | server | 0 | mod radiant.timer | [] timer darkness observer with duration 1800 has no callback. killing.
2017-10-08 17:29:04.806732 | server | 0 | mod radiant.timer | [] timer darkness observer with duration 1800 has no callback. killing.
2017-10-08 17:29:04.807246 | server | 0 | mod radiant.timer | [] timer darkness observer with duration 1800 has no callback. killing.
2017-10-08 17:29:04.807246 | server | 0 | mod radiant.timer | [] timer darkness observer with duration 1800 has no callback. killing.
2017-10-08 17:29:04.807246 | server | 0 | mod radiant.timer | [] timer darkness observer with duration 1800 has no callback. killing.
2017-10-08 17:29:05.012537 | server | 0 | mod radiant.timer | [] timer darkness observer with duration 1800 has no callback. killing.
2017-10-08 17:29:06.124625 | server | 0 | mod radiant.timer | [] timer darkness observer with duration 1800 has no callback. killing.
2017-10-08 17:29:08.164483 | server | 0 | mod radiant.timer | [] timer darkness observer with duration 1800 has no callback. killing.
2017-10-08 17:29:09.790771 | server | 0 | mod radiant.timer | [] timer darkness observer with duration 1800 has no callback. killing.
2017-10-08 17:29:10.366573 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:11.038042 | server | 0 | mod radiant.timer | [] timer darkness observer with duration 1800 has no callback. killing.
2017-10-08 17:29:13.326469 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:13.452653 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:13.824877 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:14.033454 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:21.900137 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:23.424615 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:31.682460 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:33.109901 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:33.755608 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:39.400861 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:40.412849 | server | 0 | simulation.core | took 118 ms to update game state
2017-10-08 17:29:40.413336 | server | 1 | simulation.core | game loop exhausted before any jobs processed!
2017-10-08 17:29:41.237829 | server | 0 | mod stonehearth.game_master.choose_location_outside_town | [choose loc outside city] choose_location_outside_town took 3365ms to complete. Perimeter points searched: 1. Result: found a location