I'm running minetest 0.4.9 on a xubuntu based linux system.
I haven't been able to get mesecons to working, so I tried loading the folders one by one and found that I can load most of them just fine, but the following ones cause minetest to lock up:
mesecons_lamp
mesecons_lightstone
mesecons_mvps
mesecons_noteblock
mesecons_pressureplates
mesecons_random
mesecons_switch
(and not really tested because they depend upon mvps are mesecons_movestones and mesecons_pistons)
The progress bar gets almost to the end of "item textures", then my cpu jumps up to 99% and minetest just locks up. I tailed debug.txt while minetest was hung, and it WAS getting output, but very very slowly.
Comparing debug output from a run without mesecons_mvps and a run WITH mesecons_mvps I found the following differences which might be significant:
In the good run, the first debug line is at 11:01:29, and the SQLite3 database is opened 12 seconds later:
Code: Select all
11:01:44: ACTION[main]: .__ __ __
11:01:44: ACTION[main]: _____ |__| ____ _____/ |_ ____ _______/ |_
11:01:44: ACTION[main]: / \| |/ \_/ __ \ __\/ __ \ / ___/\ __\
11:01:44: ACTION[main]: | Y Y \ | | \ ___/| | \ ___/ \___ \ | |
11:01:44: ACTION[main]: |__|_| /__|___| /\___ >__| \___ >____ > |__|
11:01:44: ACTION[main]: \/ \/ \/ \/ \/
11:01:44: ACTION[main]: World at [/home/donald/.minetest/worlds/LearningWorld49]
11:01:44: ACTION[main]: Server for gameid="minetest" listening on 0.0.0.0:62952.
11:01:44: INFO[main]: Creating client
11:01:44: INFO[main]: Connecting to server at 127.0.0.1:62952
11:01:44: INFO[main]: Client::peerAdded(): peer->id=1
11:01:44: VERBOSE[ServerThread]: Server::peerAdded(): peer->id=2
11:01:44: INFO[main]: Client packetcounter (20s):
11:01:44: VERBOSE[ServerThread]: Server: Handling peer change: id=2, timeout=0
11:01:44: INFO[ServerThread]: Server: Maximum lag peaked to 2 s
11:01:45: INFO[ServerThread]: ServerMap: SQLite3 database opened
11:01:46: VERBOSE[ServerThread]: Server: Got TOSERVER_INIT from 127.0.0.1 (peer_id=2)
but when the mvps mod is installed, the first debug line is at 10:33:40, and we connect to the server only 8 seconds later, BUT, there is no "SQLite3 database opened" message:
Code: Select all
10:33:48: ACTION[main]: .__ __ __
10:33:48: ACTION[main]: _____ |__| ____ _____/ |_ ____ _______/ |_
10:33:48: ACTION[main]: / \| |/ \_/ __ \ __\/ __ \ / ___/\ __\
10:33:48: ACTION[main]: | Y Y \ | | \ ___/| | \ ___/ \___ \ | |
10:33:48: ACTION[main]: |__|_| /__|___| /\___ >__| \___ >____ > |__|
10:33:48: ACTION[main]: \/ \/ \/ \/ \/
10:33:48: ACTION[main]: World at [/home/donald/.minetest/worlds/LearningWorld49]
10:33:48: ACTION[main]: Server for gameid="minetest" listening on 0.0.0.0:51151.
10:33:48: INFO[main]: Creating client
10:33:48: INFO[main]: Connecting to server at 127.0.0.1:51151
10:33:48: INFO[main]: Client::peerAdded(): peer->id=1
10:33:48: INFO[main]: Client packetcounter (20s):
10:33:48: VERBOSE[ServerThread]: Server::peerAdded(): peer->id=2
10:33:48: VERBOSE[ServerThread]: Server: Handling peer change: id=2, timeout=0
10:33:48: INFO[ServerThread]: Server: Maximum lag peaked to 0.39 s
10:33:48: VERBOSE[ServerThread]: Server: Got TOSERVER_INIT from 127.0.0.1 (peer_id=2)
that message doesn't appear until over a minute later:
Code: Select all
10:34:52: INFO[EmergeThread0]: ServerMap: SQLite3 database opened
without mvps the log goes from creating texture/mesh for wool:white to wool:yellow in less than a second:
Code: Select all
11:01:55: INFO[main]: Lazily creating item texture and mesh for "wool:white"
11:01:55: INFO[main]: Lazily creating item texture and mesh for "wool:yellow"
11:01:55: INFO[main]: - Starting mesh update thread
but WITH mvps there is a huge delay:
Code: Select all
10:33:57: INFO[main]: Lazily creating item texture and mesh for "wool:white"
10:34:52: INFO[main]: Lazily creating item texture and mesh for "wool:yellow"
Both the logs with and without mvps show these lines:
Code: Select all
10:34:52: INFO[main]: Compiling high level shaders for plants_shader
10:34:52: INFO[MeshUpdateThread]: getTextureId(): Queued: name="disable_img.png"
10:34:53: INFO[main]: SourceImageCache::getOrLoad(): Loading path "/usr/share/minetest/textures/base/pack/disable_img.png"
But then the crash goes into a strange loop
Code: Select all
10:34:55: VERBOSE[main]: Client: time_of_day=6006 time_speed=72 dr=940
10:34:56: VERBOSE[ServerThread]: Server: MapEditEvents:
10:34:56: VERBOSE[ServerThread]: MEET_ADDNODE: - - - - - - - - - - - - - - 1
10:35:00: INFO[main]: Client: avg_rtt=0.002
10:35:00: INFO[ServerThread]: ServerMap: Written: 0 sector metadata files, 1 block files, 185 blocks in memory.
10:35:00: INFO[ServerThread]: ServerMap: Blocks modified by:
10:35:00: INFO[ServerThread]: setNode, setNodeNoCheck, setNode, setNode: 1
10:35:03: VERBOSE[main]: Client: time_of_day=6115 time_speed=72 dr=995
10:35:06: VERBOSE[ServerThread]: Server: MapEditEvents:
10:35:06: VERBOSE[ServerThread]: MEET_ADDNODE: - - - - - - - - - - - - - - 1
10:35:06: INFO[ServerThread]: ServerMap: Written: 0 sector metadata files, 1 block files, 222 blocks in memory.
10:35:06: INFO[ServerThread]: ServerMap: Blocks modified by:
10:35:06: INFO[ServerThread]: setNodeNoCheck: - - - - - - - - - - - - - 1
10:35:07: VERBOSE[main]: Client: time_of_day=6225 time_speed=72 dr=1000
10:35:11: INFO[main]: Client packetcounter (20s):
10:35:11: INFO[main]: cmd 16 count 1
10:35:11: INFO[main]: cmd 32 count 99
10:35:11: INFO[main]: cmd 39 count 1
10:35:11: INFO[main]: cmd 41 count 4
10:35:11: INFO[main]: cmd 49 count 1
10:35:11: INFO[main]: cmd 50 count 4
10:35:11: INFO[main]: cmd 51 count 1
10:35:11: INFO[main]: cmd 52 count 1
10:35:11: INFO[main]: cmd 58 count 1
10:35:11: INFO[main]: cmd 60 count 1
10:35:11: INFO[main]: cmd 61 count 1
10:35:11: INFO[main]: cmd 65 count 1
10:35:11: INFO[main]: cmd 66 count 2
10:35:11: INFO[main]: cmd 67 count 2
10:35:11: INFO[main]: cmd 69 count 1
10:35:11: INFO[main]: cmd 78 count 1
10:35:11: INFO[main]: Client: avg_rtt=0.002
10:35:16: VERBOSE[main]: Client: time_of_day=6338 time_speed=72 dr=1000
10:35:21: VERBOSE[main]: Client: time_of_day=6458 time_speed=72 dr=1000
10:35:24: INFO[ServerThread]: Players:
10:35:24: INFO[ServerThread]: * singleplayer RemoteClient 2: m_blocks_sent.size()=115, m_blocks_sending.size()=10, m_nearest_unsent_d=6, m_excess_gotblocks=0
10:35:24: INFO[main]: Client: avg_rtt=0.002
10:35:31: INFO[ServerThread]: ServerMap: Unloaded 8 blocks from memory, of which 0 were written, 415 blocks in memory.
10:35:35: VERBOSE[main]: Client: time_of_day=6578 time_speed=72 dr=1000
10:35:35: INFO[ServerThread]: ServerMap: Unloaded 16 blocks from memory, of which 0 were written, 399 blocks in memory.
10:35:42: VERBOSE[main]: Client: time_of_day=6698 time_speed=72 dr=1000
10:35:42: INFO[main]: Client packetcounter (20s):
10:35:42: INFO[main]: cmd 16 count 0
10:35:42: INFO[main]: cmd 32 count 53
10:35:42: INFO[main]: cmd 39 count 0
10:35:42: INFO[main]: cmd 41 count 4
10:35:42: INFO[main]: cmd 49 count 0
10:35:42: INFO[main]: cmd 50 count 0
10:35:42: INFO[main]: cmd 51 count 0
10:35:42: INFO[main]: cmd 52 count 0
10:35:42: INFO[main]: cmd 58 count 0
10:35:42: INFO[main]: cmd 60 count 0
10:35:42: INFO[main]: cmd 61 count 0
10:35:42: INFO[main]: cmd 65 count 0
10:35:42: INFO[main]: cmd 66 count 0
10:35:42: INFO[main]: cmd 67 count 0
10:35:42: INFO[main]: cmd 69 count 0
10:35:42: INFO[main]: cmd 78 count 0
10:35:42: INFO[main]: Client: avg_rtt=0.002
10:35:42: INFO[ServerThread]: ServerMap: Unloaded 12 blocks from memory, of which 0 were written, 420 blocks in memory.
10:35:46: INFO[ServerThread]: ServerMap: Unloaded 11 blocks from memory, of which 0 were written, 409 blocks in memory.
10:35:55: VERBOSE[main]: Client: time_of_day=6818 time_speed=72 dr=1000
10:35:55: INFO[ServerThread]: ServerMap: Unloaded 23 blocks from memory, of which 0 were written, 491 blocks in memory.
10:35:57: INFO[ServerThread]: ServerMap: Unloaded 11 blocks from memory, of which 0 were written, 505 blocks in memory.
10:35:57: INFO[MeshUpdateThread]: getTextureId(): Queued: name="default_torch_animated.png^[verticalframe:16:0"
10:36:00: INFO[main]: Client: avg_rtt=0.002
10:36:00: VERBOSE[ServerThread]: Server: MapEditEvents:
10:36:00: VERBOSE[ServerThread]: MEET_ADDNODE: - - - - - - - - - - - - - - 1
10:36:00: INFO[main]: Map::getNodeMetadata(): Need to emerge (51,0,-8)
10:36:00: INFO[main]: WARNING: Map::getNodeMetadata(): Block not found
10:36:00: INFO[main]: Client::addNode() took 1ms
10:36:01: INFO[ServerThread]: ServerMap: Unloaded 19 blocks from memory, of which 0 were written, 519 blocks in memory.
10:36:01: VERBOSE[main]: Client: time_of_day=6947 time_speed=72 dr=1000
10:36:04: INFO[ServerThread]: ServerMap: Unloaded 10 blocks from memory, of which 0 were written, 509 blocks in memory.
10:36:05: INFO[ServerThread]: ServerMap: Written: 0 sector metadata files, 1 block files, 509 blocks in memory.
10:36:05: INFO[ServerThread]: ServerMap: Blocks modified by:
10:36:05: INFO[ServerThread]: setNodeNoCheck: - - - - - - - - - - - - - 1
10:36:07: VERBOSE[main]: Client: time_of_day=7056 time_speed=72 dr=1000
10:36:07: INFO[ServerThread]: ServerMap: Unloaded 5 blocks from memory, of which 0 were written, 504 blocks in memory.
10:36:09: INFO[main]: Client packetcounter (20s):
10:36:09: INFO[main]: cmd 16 count 0
10:36:09: INFO[main]: cmd 32 count 93
10:36:09: INFO[main]: cmd 33 count 1
10:36:09: INFO[main]: cmd 39 count 0
10:36:09: INFO[main]: cmd 41 count 3
10:36:09: INFO[main]: cmd 49 count 0
10:36:09: INFO[main]: cmd 50 count 2
10:36:09: INFO[main]: cmd 51 count 0
10:36:09: INFO[main]: cmd 52 count 0
10:36:09: INFO[main]: cmd 58 count 0
10:36:09: INFO[main]: cmd 60 count 0
10:36:09: INFO[main]: cmd 61 count 0
10:36:09: INFO[main]: cmd 65 count 0
10:36:09: INFO[main]: cmd 66 count 0
10:36:09: INFO[main]: cmd 67 count 0
10:36:09: INFO[main]: cmd 69 count 0
10:36:09: INFO[main]: cmd 78 count 0
10:36:09: INFO[ServerThread]: ServerMap: Unloaded 11 blocks from memory, of which 0 were written, 526 blocks in memory.
10:36:09: INFO[ServerThread]: Players:
10:36:09: INFO[ServerThread]: * singleplayer RemoteClient 2: m_blocks_sent.size()=235, m_blocks_sending.size()=10, m_nearest_unsent_d=4, m_excess_gotblocks=0
10:36:11: INFO[main]: Client: avg_rtt=0.002
That just keeps going until I kill minetest. Which leaves something hung up so that the only way to run minetest again is to remove mvps from the mod folder and restart the computer.
Jeija mentioned deleting the contents of the mesecon_data file, but I don't find that file anywhere in my .minetest folder.
I'd love to be playing with the full mesecons set, so if anyone knows why I'm locking up, your help would be greatly appreciated.