1.14.4 - Server hangs then crashes on "...Launching target 'fmlserver'..."

[ArtemisFowl2nd]

Hello, all!

 

I have a simple issue, and I think I'm missing something important (but you don't know what you don't know, you know?). Here is my debug.log output:

 

[20Sep2019 15:19:31.357] [main/INFO] [cpw.mods.modlauncher.Launcher/MODLAUNCHER]: ModLauncher running: args [--gameDir, ., --launchTarget, fmlserver, --fml.forgeVersion, 28.1.10, --fml.mcpVersion, 20190829.143755, --fml.mcVersion, 1.14.4, --fml.forgeGroup, net.minecraftforge]
[20Sep2019 15:19:31.368] [main/INFO] [cpw.mods.modlauncher.Launcher/MODLAUNCHER]: ModLauncher 3.2.0+60+b86c1d4 starting: java version 1.8.0_212 by IcedTea
[20Sep2019 15:19:31.394] [main/DEBUG] [cpw.mods.modlauncher.LaunchServiceHandler/MODLAUNCHER]: Found launch services [minecraft,testharness,fmlclient,fmlserver]
[20Sep2019 15:19:31.419] [main/DEBUG] [cpw.mods.modlauncher.NameMappingServiceHandler/MODLAUNCHER]: Found naming services : []
[20Sep2019 15:19:31.443] [main/DEBUG] [cpw.mods.modlauncher.LaunchPluginHandler/MODLAUNCHER]: Found launch plugins: [eventbus,object_holder_definalize,runtime_enum_extender,accesstransformer,capability_inject_definalize,runtimedistcleaner]
[20Sep2019 15:19:31.462] [main/DEBUG] [cpw.mods.modlauncher.TransformationServicesHandler/MODLAUNCHER]: Discovering transformation services
[20Sep2019 15:19:31.484] [main/DEBUG] [cpw.mods.modlauncher.TransformationServicesHandler/MODLAUNCHER]: Found additional transformation services from discovery services: []
[20Sep2019 15:19:31.560] [main/DEBUG] [cpw.mods.modlauncher.TransformationServicesHandler/MODLAUNCHER]: Found transformer services : [fml]
[20Sep2019 15:19:31.561] [main/DEBUG] [cpw.mods.modlauncher.TransformationServicesHandler/MODLAUNCHER]: Transformation services loading
[20Sep2019 15:19:31.563] [main/DEBUG] [cpw.mods.modlauncher.TransformationServiceDecorator/MODLAUNCHER]: Loading service fml
[20Sep2019 15:19:31.564] [main/DEBUG] [net.minecraftforge.fml.loading.FMLServiceProvider/]: Injecting tracing printstreams for STDOUT/STDERR.
[20Sep2019 15:19:31.568] [main/DEBUG] [net.minecraftforge.fml.loading.LauncherVersion/CORE]: Found FMLLauncher version 28.1
[20Sep2019 15:19:31.569] [main/DEBUG] [net.minecraftforge.fml.loading.FMLLoader/CORE]: FML 28.1 loading
[20Sep2019 15:19:31.570] [main/DEBUG] [net.minecraftforge.fml.loading.FMLLoader/CORE]: FML found ModLauncher version : 3.2.0+60+b86c1d4
[20Sep2019 15:19:31.571] [main/DEBUG] [net.minecraftforge.fml.loading.FMLLoader/CORE]: Initializing modjar URL handler
[20Sep2019 15:19:31.576] [main/DEBUG] [net.minecraftforge.fml.loading.FMLLoader/CORE]: FML found AccessTransformer version : 1.0.0+45+b99295a
[20Sep2019 15:19:31.578] [main/DEBUG] [net.minecraftforge.fml.loading.FMLLoader/CORE]: FML found EventBus version : 1.0.0+54+3f83a9e
[20Sep2019 15:19:31.578] [main/DEBUG] [net.minecraftforge.fml.loading.FMLLoader/CORE]: Found Runtime Dist Cleaner
[20Sep2019 15:19:31.587] [main/DEBUG] [net.minecraftforge.fml.loading.FMLLoader/CORE]: FML found CoreMod version : 1.0.0+4+e6fed88
[20Sep2019 15:19:31.588] [main/DEBUG] [net.minecraftforge.fml.loading.FMLLoader/CORE]: Found ForgeSPI package implementation version 1.3.0+4+934b64a
[20Sep2019 15:19:31.589] [main/DEBUG] [net.minecraftforge.fml.loading.FMLLoader/CORE]: Found ForgeSPI package specification 3
[20Sep2019 15:19:31.921] [main/INFO] [net.minecraftforge.fml.loading.FixSSL/CORE]: Added Lets Encrypt root certificates as additional trust
[20Sep2019 15:19:31.924] [main/DEBUG] [cpw.mods.modlauncher.TransformationServiceDecorator/MODLAUNCHER]: Loaded service fml
[20Sep2019 15:19:31.931] [main/DEBUG] [cpw.mods.modlauncher.TransformationServicesHandler/MODLAUNCHER]: Configuring option handling for services
[20Sep2019 15:19:31.971] [main/DEBUG] [cpw.mods.modlauncher.TransformationServicesHandler/MODLAUNCHER]: Transformation services initializing
[20Sep2019 15:19:31.976] [main/DEBUG] [cpw.mods.modlauncher.TransformationServiceDecorator/MODLAUNCHER]: Initializing transformation service fml
[20Sep2019 15:19:31.977] [main/DEBUG] [net.minecraftforge.fml.loading.FMLServiceProvider/CORE]: Setting up basic FML game directories
[20Sep2019 15:19:31.990] [main/DEBUG] [net.minecraftforge.fml.loading.FileUtils/CORE]: Found existing GAMEDIR directory : /data
[20Sep2019 15:19:31.994] [main/DEBUG] [net.minecraftforge.fml.loading.FMLPaths/CORE]: Path GAMEDIR is /data
[20Sep2019 15:19:31.998] [main/DEBUG] [net.minecraftforge.fml.loading.FileUtils/CORE]: Found existing MODSDIR directory : /data/mods
[20Sep2019 15:19:31.999] [main/DEBUG] [net.minecraftforge.fml.loading.FMLPaths/CORE]: Path MODSDIR is /data/mods
[20Sep2019 15:19:32.003] [main/DEBUG] [net.minecraftforge.fml.loading.FileUtils/CORE]: Found existing CONFIGDIR directory : /data/config
[20Sep2019 15:19:32.004] [main/DEBUG] [net.minecraftforge.fml.loading.FMLPaths/CORE]: Path CONFIGDIR is /data/config
[20Sep2019 15:19:32.005] [main/DEBUG] [net.minecraftforge.fml.loading.FMLPaths/CORE]: Path FMLCONFIG is /data/config/fml.toml
[20Sep2019 15:19:32.007] [main/DEBUG] [net.minecraftforge.fml.loading.FMLServiceProvider/CORE]: Loading configuration
[20Sep2019 15:19:32.151] [main/DEBUG] [net.minecraftforge.fml.loading.FileUtils/CORE]: Found existing default config directory directory : /data/defaultconfigs
[20Sep2019 15:19:32.154] [main/DEBUG] [net.minecraftforge.fml.loading.FMLServiceProvider/CORE]: Preparing launch handler
[20Sep2019 15:19:32.156] [main/DEBUG] [net.minecraftforge.fml.loading.FMLLoader/CORE]: Using fmlserver as launch service
[20Sep2019 15:19:32.168] [main/DEBUG] [net.minecraftforge.fml.loading.FMLLoader/CORE]: Received command line version data  : MC Version: '1.14.4' MCP Version: '20190829.143755' Forge Version: '28.1.10' Forge group: 'net.minecraftforge'
[20Sep2019 15:19:32.171] [main/DEBUG] [net.minecraftforge.fml.loading.LibraryFinder/CORE]: Found JAR asm at path /data/libraries/org/ow2/asm/asm/6.2/asm-6.2.jar
[20Sep2019 15:19:32.173] [main/DEBUG] [net.minecraftforge.fml.loading.LibraryFinder/CORE]: Found probable library path /data/libraries
[20Sep2019 15:19:32.178] [main/DEBUG] [net.minecraftforge.fml.loading.LibraryFinder/CORE]: Found forge path /data/libraries/net/minecraftforge/forge/1.14.4-28.1.10/forge-1.14.4-28.1.10-universal.jar is present
[20Sep2019 15:19:32.181] [main/DEBUG] [net.minecraftforge.fml.loading.LibraryFinder/CORE]: SRG MC at /data/libraries/net/minecraft/server/1.14.4-20190829.143755/server-1.14.4-20190829.143755-srg.jar is present
[20Sep2019 15:19:32.183] [main/DEBUG] [net.minecraftforge.fml.loading.LibraryFinder/CORE]: MC Extras at /data/libraries/net/minecraft/server/1.14.4/server-1.14.4-extra.jar is present
[20Sep2019 15:19:32.184] [main/DEBUG] [net.minecraftforge.fml.loading.LibraryFinder/CORE]: Forge patches at /data/libraries/net/minecraftforge/forge/1.14.4-28.1.10/forge-1.14.4-28.1.10-server.jar is present
[20Sep2019 15:19:32.196] [main/DEBUG] [net.minecraftforge.fml.loading.LanguageLoadingProvider/CORE]: Found 0 language providers
[20Sep2019 15:19:32.198] [main/DEBUG] [net.minecraftforge.fml.loading.LanguageLoadingProvider/CORE]: Adding forge as a language from /data/libraries/net/minecraftforge/forge/1.14.4-28.1.10/forge-1.14.4-28.1.10-universal.jar
[20Sep2019 15:19:32.212] [main/DEBUG] [net.minecraftforge.fml.loading.LanguageLoadingProvider/CORE]: Adding file:/data/libraries/net/minecraftforge/forge/1.14.4-28.1.10/forge-1.14.4-28.1.10-universal.jar to languageloader classloader
[20Sep2019 15:19:32.330] [main/DEBUG] [net.minecraftforge.fml.loading.LanguageLoadingProvider/CORE]: Found 2 language providers
[20Sep2019 15:19:32.331] [main/DEBUG] [net.minecraftforge.fml.loading.LanguageLoadingProvider/CORE]: Found language provider javafml, version 28.1
[20Sep2019 15:19:32.337] [main/DEBUG] [net.minecraftforge.fml.loading.LanguageLoadingProvider/CORE]: Found language provider minecraft, version 1
[20Sep2019 15:19:32.341] [main/DEBUG] [cpw.mods.modlauncher.TransformationServiceDecorator/MODLAUNCHER]: Initialized transformation service fml
[20Sep2019 15:19:32.343] [main/DEBUG] [cpw.mods.modlauncher.NameMappingServiceHandler/MODLAUNCHER]: Current naming domain is 'srg'
[20Sep2019 15:19:32.345] [main/DEBUG] [cpw.mods.modlauncher.NameMappingServiceHandler/MODLAUNCHER]: Identified name mapping providers {}
[20Sep2019 15:19:32.346] [main/DEBUG] [cpw.mods.modlauncher.TransformationServicesHandler/MODLAUNCHER]: Transformation services begin scanning
[20Sep2019 15:19:32.348] [main/DEBUG] [cpw.mods.modlauncher.TransformationServiceDecorator/MODLAUNCHER]: Beginning scan trigger - transformation service fml
[20Sep2019 15:19:32.348] [main/DEBUG] [net.minecraftforge.fml.loading.FMLServiceProvider/CORE]: Initiating mod scan
[20Sep2019 15:19:32.375] [main/DEBUG] [net.minecraftforge.fml.loading.moddiscovery.ModListHandler/CORE]: Found mod coordinates from lists: []
[20Sep2019 15:19:32.467] [main/DEBUG] [net.minecraftforge.fml.loading.moddiscovery.ModDiscoverer/CORE]: Found Mod Locators : (mods folder:null),(maven libs:null),(exploded directory:null),(minecraft:null)
[20Sep2019 15:19:32.565] [main/DEBUG] [net.minecraftforge.fml.loading.moddiscovery.ModFileParser/LOADING]: Parsing mod file candidate /data/libraries/net/minecraftforge/forge/1.14.4-28.1.10/forge-1.14.4-28.1.10-universal.jar
[20Sep2019 15:19:32.687] [main/DEBUG] [net.minecraftforge.fml.loading.moddiscovery.ModFile/LOADING]: Loading mod file /data/libraries/net/minecraftforge/forge/1.14.4-28.1.10/forge-1.14.4-28.1.10-universal.jar with language javafml
[20Sep2019 15:19:32.774] [main/DEBUG] [net.minecraftforge.fml.loading.moddiscovery.ModFileParser/LOADING]: Found coremod fieldtomethodtransformers with Javascript path META-INF/fieldtomethodtransformers.js
[20Sep2019 15:19:32.776] [main/DEBUG] [net.minecraftforge.fml.loading.moddiscovery.ModFile/LOADING]: Found coremod META-INF/fieldtomethodtransformers.js
[20Sep2019 15:19:32.777] [main/DEBUG] [net.minecraftforge.fml.loading.moddiscovery.ModFileParser/LOADING]: Parsing mod file candidate /data/libraries/net/minecraftforge/forge/1.14.4-28.1.10/forge-1.14.4-28.1.10-server.jar
[20Sep2019 15:19:32.800] [main/DEBUG] [net.minecraftforge.fml.loading.moddiscovery.ModFile/LOADING]: Loading mod file /data/libraries/net/minecraftforge/forge/1.14.4-28.1.10/forge-1.14.4-28.1.10-server.jar with language minecraft
[20Sep2019 15:19:32.826] [main/DEBUG] [net.minecraftforge.fml.loading.ModSorter/LOADING]: Found 0 mandatory requirements
[20Sep2019 15:19:32.828] [main/DEBUG] [net.minecraftforge.fml.loading.ModSorter/LOADING]: Found 0 mandatory mod requirements missing
[20Sep2019 15:19:33.913] [main/DEBUG] [cpw.mods.modlauncher.TransformationServiceDecorator/MODLAUNCHER]: End scan trigger - transformation service fml
[20Sep2019 15:19:33.913] [main/DEBUG] [cpw.mods.modlauncher.TransformationServicesHandler/MODLAUNCHER]: Transformation services loading transformers
[20Sep2019 15:19:33.914] [main/DEBUG] [cpw.mods.modlauncher.TransformationServiceDecorator/MODLAUNCHER]: Initializing transformers for transformation service fml
[20Sep2019 15:19:33.915] [main/DEBUG] [net.minecraftforge.fml.loading.FMLServiceProvider/CORE]: Loading coremod transformers
[20Sep2019 15:19:33.916] [main/DEBUG] [net.minecraftforge.coremod.CoreModEngine/COREMOD]: Loading CoreMod from META-INF/fieldtomethodtransformers.js
[20Sep2019 15:19:34.349] [main/DEBUG] [net.minecraftforge.coremod.CoreModEngine/COREMOD]: CoreMod loaded successfully
[20Sep2019 15:19:34.387] [main/DEBUG] [cpw.mods.modlauncher.TransformStore/MODLAUNCHER]: Adding transformer net.minecraftforge.coremod.transformer.CoreModClassTransformer@422c3c7a to Target : CLASS {Lnet/minecraft/potion/EffectInstance;} {} {V}
[20Sep2019 15:19:34.397] [main/DEBUG] [cpw.mods.modlauncher.TransformStore/MODLAUNCHER]: Adding transformer net.minecraftforge.coremod.transformer.CoreModClassTransformer@60d1a32f to Target : CLASS {Lnet/minecraft/block/FlowingFluidBlock;} {} {V}
[20Sep2019 15:19:34.411] [main/DEBUG] [cpw.mods.modlauncher.TransformStore/MODLAUNCHER]: Adding transformer net.minecraftforge.coremod.transformer.CoreModClassTransformer@531c311e to Target : CLASS {Lnet/minecraft/item/BucketItem;} {} {V}
[20Sep2019 15:19:34.413] [main/DEBUG] [cpw.mods.modlauncher.TransformationServiceDecorator/MODLAUNCHER]: Initialized transformers for transformation service fml
[20Sep2019 15:19:34.462] [main/INFO] [cpw.mods.modlauncher.LaunchServiceHandler/MODLAUNCHER]: Launching target 'fmlserver' with arguments [--gameDir, .]
[20Sep2019 15:19:40.267] [main/DEBUG] [net.minecraftforge.coremod.transformer.CoreModBaseTransformer/COREMOD]: Transforming net/minecraft/block/FlowingFluidBlock
[20Sep2019 15:19:53.779] [main/DEBUG] [net.minecraftforge.coremod.transformer.CoreModBaseTransformer/COREMOD]: Transforming net/minecraft/item/BucketItem
[20Sep2019 15:19:55.046] [main/DEBUG] [net.minecraftforge.coremod.transformer.CoreModBaseTransformer/COREMOD]: Transforming net/minecraft/potion/EffectInstance

 

I believe this is a configuration error, not a Docker error, but in case it makes any difference, I'm using itzg/minecraft-server Docker image to launch the server. 

 

Here is my docker-compose.yaml:

version: '3.4'

services:
#=========================================
  minecraft:
    image: itzg/minecraft-server:latest
    ports:
      - "25565:25565"
    volumes:
      # - "mc:/data"
      - ./data:/data
    environment:
      EULA: "TRUE"
      VERSION: "1.14.4"
      MAX_MEMORY: 4G
      TYPE: "FORGE"
      FORGE_INSTALLER: "forge-1.14.4-28.1.10-installer.jar"
      ENABLE_RCON: "true"
      RCON_PASSWORD: "testing"
      RCON_PORT: 28016
    restart: always
#=========================================
  rcon:
    image: itzg/rcon
    ports:
      - "4326:4326"
      - "4327:4327"
    volumes:
      - "rcon:/opt/rcon-web-admin/db"

volumes:
  mc:
  rcon:

 

And here is the output of the docker-compose up command:

 

$ docker-compose up
Creating network "forge_default" with the default driver
Creating forge_minecraft_1 ... done
Creating forge_rcon_1      ... done
Attaching to forge_minecraft_1, forge_rcon_1
minecraft_1  | usermod: no changes
minecraft_1  | Running as uid=1000 gid=1000 with /data as 'drwxrwxr-x   23 1000     1000           782 Sep 20 15:05 /data'
minecraft_1  | Checking version information.
minecraft_1  | Checking type information.
minecraft_1  | server.properties already created, skipping
minecraft_1  | Checking for JSON files.
minecraft_1  | Copying any mods over...
minecraft_1  | Setting initial memory to 1G and max to 4G
minecraft_1  | Starting the Minecraft server...
minecraft_1  | 2019-09-20 15:09:08,411 main WARN Advanced terminal features are not available in this environment
minecraft_1  | [15:09:08] [main/INFO] [cp.mo.mo.Launcher/MODLAUNCHER]: ModLauncher running: args [--gameDir, ., --launchTarget, fmlserver, --fml.forgeVersion, 28.1.10, --fml.mcpVersion, 20190829.143755, --fml.mcVersion, 1.14.4, --fml.forgeGroup, net.minecraftforge]
minecraft_1  | [15:09:08] [main/INFO] [cp.mo.mo.Launcher/MODLAUNCHER]: ModLauncher 3.2.0+60+b86c1d4 starting: java version 1.8.0_212 by IcedTea
minecraft_1  | [15:09:09] [main/INFO] [ne.mi.fm.lo.FixSSL/CORE]: Added Lets Encrypt root certificates as additional trust
minecraft_1  | [15:09:12] [main/INFO] [cp.mo.mo.LaunchServiceHandler/MODLAUNCHER]: Launching target 'fmlserver' with arguments [--gameDir, .]
minecraft_1  | 2019/09/20 15:15:30 Done
minecraft_1  | Running as uid=1000 gid=1000 with /data as 'drwxrwxr-x   23 1000     1000           782 Sep 20 15:09 /data'
minecraft_1  | Checking version information.
minecraft_1  | Checking type information.
minecraft_1  | server.properties already created, skipping
minecraft_1  | Checking for JSON files.
minecraft_1  | Copying any mods over...
minecraft_1  | Setting initial memory to 1G and max to 4G
minecraft_1  | Starting the Minecraft server...
minecraft_1  | 2019-09-20 15:15:36,026 main WARN Advanced terminal features are not available in this environment
minecraft_1  | [15:15:36] [main/INFO] [cp.mo.mo.Launcher/MODLAUNCHER]: ModLauncher running: args [--gameDir, ., --launchTarget, fmlserver, --fml.forgeVersion, 28.1.10, --fml.mcpVersion, 20190829.143755, --fml.mcVersion, 1.14.4, --fml.forgeGroup, net.minecraftforge]
minecraft_1  | [15:15:36] [main/INFO] [cp.mo.mo.Launcher/MODLAUNCHER]: ModLauncher 3.2.0+60+b86c1d4 starting: java version 1.8.0_212 by IcedTea
minecraft_1  | [15:15:36] [main/INFO] [ne.mi.fm.lo.FixSSL/CORE]: Added Lets Encrypt root certificates as additional trust
minecraft_1  | [15:15:39] [main/INFO] [cp.mo.mo.LaunchServiceHandler/MODLAUNCHER]: Launching target 'fmlserver' with arguments [--gameDir, .]
Exception in thread Thread-7:
Traceback (most recent call last):
  File "site-packages/urllib3/connectionpool.py", line 384, in _make_request
  File "<string>", line 2, in raise_from
  File "site-packages/urllib3/connectionpool.py", line 380, in _make_request
  File "http/client.py", line 1331, in getresponse
  File "http/client.py", line 297, in begin
  File "http/client.py", line 258, in _read_status
  File "socket.py", line 586, in readinto
socket.timeout: timed out

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "site-packages/requests/adapters.py", line 449, in send
  File "site-packages/urllib3/connectionpool.py", line 638, in urlopen
  File "site-packages/urllib3/util/retry.py", line 368, in increment
  File "site-packages/urllib3/packages/six.py", line 686, in reraise
  File "site-packages/urllib3/connectionpool.py", line 600, in urlopen
  File "site-packages/urllib3/connectionpool.py", line 386, in _make_request
  File "site-packages/urllib3/connectionpool.py", line 306, in _raise_timeout
urllib3.exceptions.ReadTimeoutError: UnixHTTPConnectionPool(host='localhost', port=None): Read timed out. (read timeout=60)

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "threading.py", line 916, in _bootstrap_inner
  File "threading.py", line 864, in run
  File "compose/cli/log_printer.py", line 214, in watch_events
  File "compose/project.py", line 493, in yield_loop
  File "compose/project.py", line 461, in build_container_event
  File "compose/container.py", line 48, in from_id
  File "site-packages/docker/utils/decorators.py", line 19, in wrapped
  File "site-packages/docker/api/container.py", line 755, in inspect_container
  File "site-packages/docker/utils/decorators.py", line 46, in inner
  File "site-packages/docker/api/client.py", line 230, in _get
  File "site-packages/requests/sessions.py", line 537, in get
  File "site-packages/requests/sessions.py", line 524, in request
  File "site-packages/requests/sessions.py", line 637, in send
  File "site-packages/requests/adapters.py", line 529, in send
requests.exceptions.ReadTimeout: UnixHTTPConnectionPool(host='localhost', port=None): Read timed out. (read timeout=60)

minecraft_1  | 2019/09/20 15:19:22 Done

 

It always crashes on "done"