Page 1 of 1
Unable to establish server connection
Posted: Mon May 16, 2016 3:41 am
by webnet
I'm running a Factorio server within a docker container via
zopanix/factorio. I'm redirecting port
3000 on the host to
34197 within the container but am unable to establish a connection. I'm having trouble determining the cause of the issue. It keeps saying connection is already in progress. Is this an issue that has been seen on running servers? Any ideas on how I can go about debugging this? I've verified that traffic is coming into the container via port
34197
Code: Select all
5/15/2016 11:12:24 PM 487.271 Connection request from 74.134.247.255:34197
5/15/2016 11:12:24 PM 487.272 Refusing connection for address(74.134.247.255:34197). Another connection from this peer is in progress.
5/15/2016 11:12:25 PM 488.005 Info Synchronizer.cpp:444: NetworkTick(29138) peer(1) drop detection state(100/600).
5/15/2016 11:12:26 PM 489.671 Info Synchronizer.cpp:444: NetworkTick(29138) peer(1) drop detection state(200/600).
5/15/2016 11:12:28 PM 491.338 Info Synchronizer.cpp:444: NetworkTick(29138) peer(1) drop detection state(300/600).
5/15/2016 11:12:30 PM 493.004 Info Synchronizer.cpp:444: NetworkTick(29138) peer(1) drop detection state(400/600).
5/15/2016 11:12:31 PM 494.671 Info Synchronizer.cpp:444: NetworkTick(29138) peer(1) drop detection state(500/600).
5/15/2016 11:12:33 PM 496.338 Info Synchronizer.cpp:444: NetworkTick(29138) peer(1) drop detection state(600/600).
5/15/2016 11:12:33 PM 496.354 Info Synchronizer.cpp:590: NetworkTick(29138) peer(1) peerHeartbeatsEmpty(true) is not responding, dropping.
5/15/2016 11:12:33 PM 496.454 Info MultiplayerManager.cpp:1433: Received peer info for peer(0) username(<server>).
5/15/2016 11:12:33 PM 496.454 Info MultiplayerManager.cpp:1060: networkTick(29143) mapTick(34) received stateChanged peerID(0) oldState(InGame) newState(InGame)
5/15/2016 11:12:33 PM 496.471 Info Router.cpp:741: Received onPeerConnected for already connected peer(1)
5/15/2016 11:12:33 PM 496.471 Info MultiplayerManager.cpp:1495: Peer dropout for peer (1) by peer (0) -- removing now
5/15/2016 11:12:33 PM 496.471 Info MultiplayerManager.cpp:1010: networkTick(29144) mapTick(35) removing peer(1) dropout(true)
5/15/2016 11:12:33 PM 496.471 Info Synchronizer.cpp:516: networkTick(29144) removing peer(1) success(false).
5/15/2016 11:12:33 PM 496.471 Info MultiplayerManager.cpp:913: networkTick(29144) mapTick(35) changing state from(InGame) to(InGameAligning)
5/15/2016 11:12:33 PM 496.471 Info MultiplayerManager.cpp:1719: networkTick(29144) mapTick(35) starting mapAlign
5/15/2016 11:12:33 PM 496.471 Info MultiplayerManager.cpp:1633: networkTick(29144) mapTick(35) adding mapAlignTask(RemovePlayerAlignTask)
5/15/2016 11:12:33 PM 496.571 Info MultiplayerManager.cpp:1060: networkTick(29150) mapTick(35) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
5/15/2016 11:12:33 PM 496.571 Info MultiplayerManager.cpp:1653: networkTick(29150) mapTick(35) sending mapAlignAction
5/15/2016 11:12:33 PM 496.671 Info MultiplayerManager.cpp:1307: networkTick(29156) mapTick(35) received MapAlignAction from peer(0), mapAlignTick(36) appliedTickClosuresCount(0)
5/15/2016 11:12:33 PM 496.671 Info MultiplayerManager.cpp:1735: networkTick(29157) mapTick(35) mapAlign finished targetTick(36) updating map and running the tasks ...
5/15/2016 11:12:33 PM 496.671 Info MultiplayerManager.cpp:1607: networkTick(29157) mapTick(35) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(36))
5/15/2016 11:12:33 PM)
5/15/2016 11:12:33 PM 496.673 Info MultiplayerManager.cpp:1754: maptick(36) crc before applying all ticks: -486121007
5/15/2016 11:12:33 PM 496.673 Info NetworkInputHandler.cpp:661: applyAllTickClosures: expectedMapTick(36) lastTickApplied(27) referenceMapTick(36) networkTick(29157)
5/15/2016 11:12:33 PM 496.673 Info MultiplayerManager.cpp:1762: maptick(36) crc after applying all ticks: -486121007
5/15/2016 11:12:33 PM 496.673 Info MultiplayerManager.cpp:913: networkTick(29157) mapTick(36) changing state from(InGameAligning) to(InGameAligned)
5/15/2016 11:12:33 PM 496.788 Info MultiplayerManager.cpp:1060: networkTick(29163) mapTick(36) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
5/15/2016 11:12:33 PM 496.789 Info MultiplayerManager.cpp:913: networkTick(29163) mapTick(36) changing state from(InGameAligned) to(InGameWaitingForOthers)
5/15/2016 11:12:34 PM 496.888 Info MultiplayerManager.cpp:1060: networkTick(29169) mapTick(36) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameWaitingForOthers)
5/15/2016 11:12:34 PM 496.888 Info MultiplayerManager.cpp:1259: networkTick(29169) mapTick(36) stopping mapAlign
5/15/2016 11:12:34 PM 496.888 Info MultiplayerManager.cpp:1785: performing map align task (RemovePlayerAlignTask)
5/15/2016 11:12:34 PM 496.888 Info MultiplayerManager.cpp:1809: networkTick(29169) mapTick(36) finishing removing peer(1) hasPeer(false)
5/15/2016 11:12:34 PM 496.888 Info NetworkInputHandler.cpp:544: expectedMapTick(36) removing peer(1) success(false).
5/15/2016 11:12:34 PM 496.888 Info Synchronizer.cpp:516: networkTick(29169) removing peer(1) success(false).
5/15/2016 11:12:34 PM 496.888 Info MultiplayerManager.cpp:913: networkTick(29169) mapTick(36) changing state from(InGameWaitingForOthers) to(InGame)
5/15/2016 11:12:34 PM 496.888 Info MultiplayerManager.cpp:1607: networkTick(29169) mapTick(36) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
5/15/2016 11:12:34 PM)
5/15/2016 11:12:34 PM 496.988 Info MultiplayerManager.cpp:1060: networkTick(29175) mapTick(36) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
Re: Unable to establish server connection
Posted: Mon May 16, 2016 8:21 am
by jmyeom
stand by, ill test on my docker server
EDIT:
Tested, i used the repo you sent me, maped just like it said, host and container as port 34197, i could access my docker server from my desktop no issues...
what settings are you using/changing? is your container port and host port set right?
can you reboot the docker, try to connect, then paste the complete log file please?
Re: Unable to establish server connection
Posted: Tue May 17, 2016 11:24 pm
by webnet
I'm using Rancher to manage my docker containers. It essentially wraps a UI on top of docker and has it's own version of Docker swarm. With that in mind, I'd be happy to give you temporary access to it so you can experiment with the factorio container.
For simplicity, I'm no longer using port 3000 and am using the default port.
The
docker-compose rancher generates is here:
Code: Select all
factorio-1:
ports:
- 34197:34197/udp
environment:
FACTORIO_AUTOSAVE_INTERVAL: '5'
log_driver: ''
labels:
io.rancher.container.pull_image: always
io.rancher.scheduler.affinity:host_label: factorio=1
tty: true
log_opt: {}
image: zopanix/factorio:0.12.33
volumes:
- /saves/factorio/factorio-1:/opt/factorio/saves
stdin_open: true
Here's the full log output showing the server booting up:
Code: Select all
5/17/2016 7:22:22 PM###
5/17/2016 7:22:22 PM# Using existing map [save.zip]
5/17/2016 7:22:22 PM###
5/17/2016 7:22:22 PM###
5/17/2016 7:22:22 PM# Finding latest map
5/17/2016 7:22:22 PM###
5/17/2016 7:22:22 PM###
5/17/2016 7:22:22 PM# Launching Game
5/17/2016 7:22:22 PM###
5/17/2016 7:22:22 PM 0.000 2016-05-17 23:22:22; Factorio 0.12.33 (Build 18092, linux64, headless)
5/17/2016 7:22:22 PM 0.000 Operating system: Linux
5/17/2016 7:22:22 PM 0.000 Program arguments: "/opt/factorio/bin/x64/factorio" "--disallow-commands" "--autosave-interval" "5" "--autosave-slots" "3" "--start-server" "save.zip"
5/17/2016 7:22:22 PM 0.000 Read data path: /opt/factorio/data
5/17/2016 7:22:22 PM 0.000 Write data path: /opt/factorio
5/17/2016 7:22:22 PM 0.000 Binaries path: /opt/factorio/bin
5/17/2016 7:22:22 PM 0.025 Running in headless mode
5/17/2016 7:22:22 PM 0.031 Loading mod core 0.0.0 (data.lua)
5/17/2016 7:22:22 PM 0.034 Loading mod base 0.12.33 (data.lua)
5/17/2016 7:22:22 PM 0.185 Checksum for core: 3711505753
5/17/2016 7:22:22 PM 0.186 Checksum for mod base: 2829195351
5/17/2016 7:22:22 PM 0.708 Factorio initialised
5/17/2016 7:22:22 PM 0.709 Info Router.cpp:509: Router peerID(65535) shutting down.
5/17/2016 7:22:22 PM 0.709 Info Router.cpp:536: Router state -> Disconnected
5/17/2016 7:22:22 PM 0.709 Info MultiplayerManager.cpp:913: networkTick(0) mapTick(-1) changing state from(Ready) to(PreparedToHostGame)
5/17/2016 7:22:22 PM 0.709 Info MultiplayerManager.cpp:913: networkTick(0) mapTick(-1) changing state from(PreparedToHostGame) to(CreatingGame)
5/17/2016 7:22:22 PM 0.710 Loading map /opt/factorio/saves/save.zip
5/17/2016 7:22:22 PM 0.739 Info Scenario.cpp:124: Map version 0.12.33-0
5/17/2016 7:22:22 PM 0.770 Checksum for script /opt/factorio/temp/currently-playing/control.lua: 393467729
5/17/2016 7:22:22 PM 0.790 Info MultiplayerManager.cpp:282: 0x7fce0c45bbf0, (nil)
5/17/2016 7:22:22 PM 0.790 Info PosixUDPSocket.cpp:34: Opening socket at port 34197
5/17/2016 7:22:22 PM 0.791 Info Router.cpp:536: Router state -> Connected
5/17/2016 7:22:22 PM 0.791 Info Synchronizer.cpp:54: NetworkTick(0) initialized Synchronizer local peer(0) latency(6).
5/17/2016 7:22:22 PM 0.791 Hosting game at port 34197, peerID 0, session magic 708
5/17/2016 7:22:22 PM 0.791 Info MultiplayerManager.cpp:304: 0x7fce0c45bbf0, 0x7fce0c45bbf0
5/17/2016 7:22:22 PM 0.791 Info MultiplayerManager.cpp:913: networkTick(0) mapTick(96) changing state from(CreatingGame) to(InGame)
5/17/2016 7:22:22 PM 0.791 Info NetworkInputHandler.cpp:45: mapTick(96) networkTick(0) initialized NetworkInputHandler local peer(0).
5/17/2016 7:22:22 PM 0.911 Info MultiplayerManager.cpp:1433: Received peer info for peer(0) username(<server>).
5/17/2016 7:22:22 PM 0.911 Info MultiplayerManager.cpp:1060: networkTick(6) mapTick(96) received stateChanged peerID(0) oldState(Ready) newState(CreatingGame)
5/17/2016 7:22:22 PM 0.911 Info MultiplayerManager.cpp:1060: networkTick(6) mapTick(96) received stateChanged peerID(0) oldState(CreatingGame) newState(InGame)
And then me trying to connect:
Code: Select all
5/17/2016 7:23:31 PM 69.923 Connection request from 74.134.247.255:34197
5/17/2016 7:23:31 PM 69.923 Info Router.cpp:609: NetworkTick(4147) replying to connectionRequest for address(74.134.247.255:34197).
5/17/2016 7:23:32 PM 70.022 Info Router.cpp:761: networkTick(4153) adding peer(1) address(74.134.247.255:34197) sending connectionAccept(true)
5/17/2016 7:23:32 PM 70.023 Info Router.cpp:632: networkTick(4153) sending connectionAccept to address(74.134.247.255:34197) connectionRequestID(16973) newPeerID(1)
5/17/2016 7:23:32 PM 70.023 Info Synchronizer.cpp:500: networkTick(4153) adding peer(1) success(true).
5/17/2016 7:23:32 PM 70.422 Connection request from 74.134.247.255:34197
5/17/2016 7:23:32 PM 70.423 Info Router.cpp:609: NetworkTick(4154) replying to connectionRequest for address(74.134.247.255:34197).
5/17/2016 7:23:32 PM 70.939 Connection request from 74.134.247.255:34197
5/17/2016 7:23:32 PM 70.939 Refusing connection for address(74.134.247.255:34197). Another connection from this peer is in progress.
5/17/2016 7:23:33 PM 71.672 Info Synchronizer.cpp:444: NetworkTick(4154) peer(1) drop detection state(100/600).
5/17/2016 7:23:35 PM 73.339 Info Synchronizer.cpp:444: NetworkTick(4154) peer(1) drop detection state(200/600).
5/17/2016 7:23:37 PM 75.006 Info Synchronizer.cpp:444: NetworkTick(4154) peer(1) drop detection state(300/600).
5/17/2016 7:23:38 PM 76.672 Info Synchronizer.cpp:444: NetworkTick(4154) peer(1) drop detection state(400/600).
5/17/2016 7:23:40 PM 78.339 Info Synchronizer.cpp:444: NetworkTick(4154) peer(1) drop detection state(500/600).
5/17/2016 7:23:42 PM 80.005 Info Synchronizer.cpp:444: NetworkTick(4154) peer(1) drop detection state(600/600).
5/17/2016 7:23:42 PM 80.022 Info Synchronizer.cpp:590: NetworkTick(4154) peer(1) peerHeartbeatsEmpty(true) is not responding, dropping.
5/17/2016 7:23:42 PM 80.122 Info MultiplayerManager.cpp:1433: Received peer info for peer(0) username(<server>).
5/17/2016 7:23:42 PM 80.122 Info MultiplayerManager.cpp:1060: networkTick(4159) mapTick(106) received stateChanged peerID(0) oldState(InGame) newState(InGame)
5/17/2016 7:23:42 PM 80.139 Info Router.cpp:741: Received onPeerConnected for already connected peer(1)
5/17/2016 7:23:42 PM 80.139 Info MultiplayerManager.cpp:1495: Peer dropout for peer (1) by peer (0) -- removing now
5/17/2016 7:23:42 PM 80.139 Info MultiplayerManager.cpp:1010: networkTick(4160) mapTick(107) removing peer(1) dropout(true)
5/17/2016 7:23:42 PM 80.139 Info Synchronizer.cpp:516: networkTick(4160) removing peer(1) success(false).
5/17/2016 7:23:42 PM 80.139 Info MultiplayerManager.cpp:913: networkTick(4160) mapTick(107) changing state from(InGame) to(InGameAligning)
5/17/2016 7:23:42 PM 80.139 Info MultiplayerManager.cpp:1719: networkTick(4160) mapTick(107) starting mapAlign
5/17/2016 7:23:42 PM 80.139 Info MultiplayerManager.cpp:1633: networkTick(4160) mapTick(107) adding mapAlignTask(RemovePlayerAlignTask)
5/17/2016 7:23:42 PM 80.239 Info MultiplayerManager.cpp:1060: networkTick(4166) mapTick(107) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
5/17/2016 7:23:42 PM 80.239 Info MultiplayerManager.cpp:1653: networkTick(4166) mapTick(107) sending mapAlignAction
5/17/2016 7:23:42 PM 80.339 Info MultiplayerManager.cpp:1307: networkTick(4172) mapTick(107) received MapAlignAction from peer(0), mapAlignTick(108) appliedTickClosuresCount(0)
5/17/2016 7:23:42 PM 80.339 Info MultiplayerManager.cpp:1735: networkTick(4173) mapTick(107) mapAlign finished targetTick(108) updating map and running the tasks ...
5/17/2016 7:23:42 PM 80.339 Info MultiplayerManager.cpp:1607: networkTick(4173) mapTick(107) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(108))
5/17/2016 7:23:42 PM)
5/17/2016 7:23:42 PM 80.340 Info MultiplayerManager.cpp:1754: maptick(108) crc before applying all ticks: -790189856
5/17/2016 7:23:42 PM 80.340 Info NetworkInputHandler.cpp:661: applyAllTickClosures: expectedMapTick(108) lastTickApplied(99) referenceMapTick(108) networkTick(4173)
5/17/2016 7:23:42 PM 80.341 Info MultiplayerManager.cpp:1762: maptick(108) crc after applying all ticks: -790189856
5/17/2016 7:23:42 PM 80.341 Info MultiplayerManager.cpp:913: networkTick(4173) mapTick(108) changing state from(InGameAligning) to(InGameAligned)
5/17/2016 7:23:42 PM 80.455 Info MultiplayerManager.cpp:1060: networkTick(4179) mapTick(108) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
5/17/2016 7:23:42 PM 80.456 Info MultiplayerManager.cpp:913: networkTick(4179) mapTick(108) changing state from(InGameAligned) to(InGameWaitingForOthers)
5/17/2016 7:23:42 PM 80.555 Info MultiplayerManager.cpp:1060: networkTick(4185) mapTick(108) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameWaitingForOthers)
5/17/2016 7:23:42 PM 80.556 Info MultiplayerManager.cpp:1259: networkTick(4185) mapTick(108) stopping mapAlign
5/17/2016 7:23:42 PM 80.556 Info MultiplayerManager.cpp:1785: performing map align task (RemovePlayerAlignTask)
5/17/2016 7:23:42 PM 80.556 Info MultiplayerManager.cpp:1809: networkTick(4185) mapTick(108) finishing removing peer(1) hasPeer(false)
5/17/2016 7:23:42 PM 80.556 Info NetworkInputHandler.cpp:544: expectedMapTick(108) removing peer(1) success(false).
5/17/2016 7:23:42 PM 80.556 Info Synchronizer.cpp:516: networkTick(4185) removing peer(1) success(false).
5/17/2016 7:23:42 PM 80.556 Info MultiplayerManager.cpp:913: networkTick(4185) mapTick(108) changing state from(InGameWaitingForOthers) to(InGame)
5/17/2016 7:23:42 PM 80.556 Info MultiplayerManager.cpp:1607: networkTick(4185) mapTick(108) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
5/17/2016 7:23:42 PM)
5/17/2016 7:23:42 PM 80.655 Info MultiplayerManager.cpp:1060: networkTick(4191) mapTick(108) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
Re: Unable to establish server connection
Posted: Sat May 21, 2016 6:59 pm
by jmyeom
sorry no reply, been busy
i will take a look at rancher, see if i can replicate the issue
FYI i used unraid as the docker server, had to make my own wrapper but it worked...
if you can pm me access to the rancher server, that would be a great help, if not, dont worry as i will be testing anyway
EDIT;
Tried using rancher, could not get it to work, support was useless,
unless you willing to talk me though it via TS or steam or something, i will be unable to test this locally