[0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Wed Feb 03, 2016 1:28 pm
by Loewchen
There is still a desync producing bug, I found it on different servers with and without mods.
I only present one desync report with a server w/o mods. If you want me to upload desync reports of moded servers as well, I would be happy to, but they are old and I can not guaranty that some of them might not be caused by a mod or by another bug already fixed, there is no way for me to tell.
How it manifests:
At some point in the game, some action seems to be not transferred in the map save that new players download when joining (T=0).
This has no effects for now as all players on the server already have the same information
Later on a new player joins the server (T=1), now two things are possible:
The new player instantly desyncs (this is the rare case) and will desync again if he tries to join again.
or still nothing happens (I assume this is caused, by the action not transferred, to not influence the CRC-Hash directly)
If the desync did not happen instantly it happens at some later point in the game (T=2) (I assume this is caused, by the action not transferred to have indirect influence on the CRC-Hash, e.g changing the production speed of a building not producing previously...) with:
all the players joining or rejoining after T=0 to desync (if they are the minority) and them having the same CRC-Hash among themselves while,
the players on the server previous to T=0 can still play, if they, plus the server are the majority.
If the players joining before T=0 are the minority they will be the ones to desync and as the server is one of them, it will rejoin itself or crash, if it manages to rejoin the game can go on, as all players (including the server) now have the T>0 state of the map.
0.003 2016-02-03 12:38:56; Factorio 0.12.20 (Build 17489, win64)
0.003 Operating system: Windows 7 Service Pack 1
0.003 Program arguments: "C:\Spiele\Factorio\Factorio_0.12.20_SethaVanilla\bin\x64\Factorio.exe"
0.003 Read data path: C:/Spiele/Factorio/Factorio_0.12.20_SethaVanilla/data
0.003 Write data path: C:/Spiele/Factorio/Factorio_0.12.20_SethaVanilla
0.003 Binaries path: C:/Spiele/Factorio/Factorio_0.12.20_SethaVanilla/bin
0.046 Available display adapters: 1
0.046 [0]: \\.\DISPLAY1 - NVIDIA GeForce GTX 560 Ti {0x05, [0,0], 1920x1080, 32bit, 60Hz}
0.046 Create display on adapter 0. Size 1280x720 at position [310, 162]. Monitor 0
0.590 Initialised Direct3D:[0] NVIDIA GeForce GTX 560 Ti ; driver: nvd3dumx.dll 9.18.13.4052
0.647 Desktop composition is active.
0.648 Graphics options: [FullScreen: true] [VSync: true] [UIScale: 95%] [MultiSampling: OFF] [Graphics quality: low] [Video memory usage: all] [Light scale: 20%] [Screen: 255]
0.763 Loading mod core 0.0.0 (data.lua)
0.771 Loading mod base 0.12.20 (data.lua)
1.455 Initial atlas bitmap size is 16384
1.457 Created atlas bitmap 16384x3396
12.978 Factorio initialised
22.013 Info Router.cpp:556: Router peerID(65535) shutting down.
22.014 Info Router.cpp:582: Router state -> Disconnected
22.014 Joining game 176.9.114.165:34197 at port 34197
22.014 Info WindowsUDPSocket.cpp:73: Opening socket at port (34197)
22.014 Info Router.cpp:582: Router state -> Connecting
22.020 Info MultiplayerManager.cpp:869: networkTick(0) mapTick(-1) changing state from(Ready) to(Connecting)
22.066 Info Router.cpp:582: Router state -> WaitingForAccept
22.167 Info Synchronizer.cpp:54: NetworkTick(53914084) initialized Synchronizer local peer(48) latency(6).
22.167 Info Synchronizer.cpp:494: networkTick(53914084) adding peer(0) success(true).
22.167 Info Router.cpp:582: Router state -> Connected
22.167 Info Router.cpp:767: ConnectionAccepted ownPeerID(48) nextPeerID(49)
22.167 Info MultiplayerManager.cpp:869: networkTick(53914084) mapTick(-1) changing state from(Connecting) to(VerifyingConnection)
22.183 Info Router.cpp:779: Received onPeerConnected for already connected peer(48)
22.283 Info MultiplayerManager.cpp:1331: Received peer info for peer(0) username(<server>).
22.283 Info MultiplayerManager.cpp:1006: networkTick(53914090) mapTick(-1) received stateChanged peerID(0) oldState(Ready) newState(InGame)
22.283 Info MultiplayerManager.cpp:1331: Received peer info for peer(48) username(Loewchen).
22.283 Info MultiplayerManager.cpp:1006: networkTick(53914090) mapTick(-1) received stateChanged peerID(48) oldState(Ready) newState(Connecting)
22.283 Info MultiplayerManager.cpp:1331: Received peer info for peer(48) username(Loewchen).
22.283 Info MultiplayerManager.cpp:1006: networkTick(53914090) mapTick(-1) received stateChanged peerID(48) oldState(Connecting) newState(Connecting)
22.283 Info MultiplayerManager.cpp:1006: networkTick(53914090) mapTick(-1) received stateChanged peerID(48) oldState(Connecting) newState(VerifyingConnection)
22.401 Info MultiplayerManager.cpp:869: networkTick(53914097) mapTick(-1) changing state from(VerifyingConnection) to(ConnectedWaitingForMap)
22.500 Info MultiplayerManager.cpp:1006: networkTick(53914103) mapTick(-1) received stateChanged peerID(48) oldState(VerifyingConnection) newState(ConnectedWaitingForMap)
22.600 Info MultiplayerManager.cpp:1006: networkTick(53914109) mapTick(-1) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
22.816 Info MultiplayerManager.cpp:1006: networkTick(53914122) mapTick(-1) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
22.816 Info MultiplayerManager.cpp:1006: networkTick(53914122) mapTick(-1) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
24.150 Info MultiplayerManager.cpp:1006: networkTick(53914199) mapTick(-1) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
24.150 Downloading file C:/Spiele\Factorio\Factorio_0.12.20_SethaVanilla\temp\mp-download.zip (17548990 B, 34889 blocks)
24.154 Info MultiplayerManager.cpp:869: networkTick(53914199) mapTick(-1) changing state from(ConnectedWaitingForMap) to(ConnectedDownloadingMap)
24.154 Info NetworkInputHandler.cpp:40: mapTick(10858887) networkTick(53914199) initialized NetworkInputHandler local peer(48).
24.154 Info NetworkInputHandler.cpp:491: expectedMapTick(10858887) adding peer(0).
24.154 Info Synchronizer.cpp:407: NetworkTick(53914199) mapTick(10858887) applying 12 pending tickClosures
24.154 Info NetworkInputHandler.cpp:570: assigning playerIndex(255) to peer(0)
24.154 Info NetworkInputHandler.cpp:570: assigning playerIndex(255) to peer(48)
24.155 Info MultiplayerManager.cpp:1518: networkTick(53914199) mapTick(-1) adding mapAlignTask(SendPlayerJoinGameAlignTask)
24.266 Info MultiplayerManager.cpp:1006: networkTick(53914205) mapTick(-1) received stateChanged peerID(48) oldState(ConnectedWaitingForMap) newState(ConnectedDownloadingMap)
24.269 Info TransferTarget.cpp:260: Received transfer block 0 that we didn't request from peer 0
24.269 Info TransferTarget.cpp:260: Received transfer block 1 that we didn't request from peer 0
24.269 Info TransferTarget.cpp:260: Received transfer block 2 that we didn't request from peer 0
24.269 Info TransferTarget.cpp:260: Received transfer block 3 that we didn't request from peer 0
24.269 Info TransferTarget.cpp:260: Received transfer block 4 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 5 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 6 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 7 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 8 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 9 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 10 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 11 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 12 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 13 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 14 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 15 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 16 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 17 that we didn't request from peer 0
24.270 Info TransferTarget.cpp:260: Received transfer block 18 that we didn't request from peer 0
24.461 Info TransferTarget.cpp:260: Received transfer block 99 that we didn't request from peer 0
24.462 Info TransferTarget.cpp:260: Received transfer block 98 that we didn't request from peer 0
24.462 Info TransferTarget.cpp:260: Received transfer block 97 that we didn't request from peer 0
24.462 Info TransferTarget.cpp:260: Received transfer block 96 that we didn't request from peer 0
24.462 Info TransferTarget.cpp:260: Received transfer block 95 that we didn't request from peer 0
24.462 Info TransferTarget.cpp:260: Received transfer block 94 that we didn't request from peer 0
24.462 Info TransferTarget.cpp:260: Received transfer block 93 that we didn't request from peer 0
24.462 Info TransferTarget.cpp:260: Received transfer block 92 that we didn't request from peer 0
24.462 Info TransferTarget.cpp:260: Received transfer block 8 that we didn't request from peer 0
24.578 Info TransferTarget.cpp:260: Received transfer block 66 that we didn't request from peer 0
24.578 Info TransferTarget.cpp:260: Received transfer block 67 that we didn't request from peer 0
24.578 Info TransferTarget.cpp:260: Received transfer block 68 that we didn't request from peer 0
24.578 Info TransferTarget.cpp:260: Received transfer block 69 that we didn't request from peer 0
24.578 Info TransferTarget.cpp:260: Received transfer block 70 that we didn't request from peer 0
24.579 Info TransferTarget.cpp:260: Received transfer block 71 that we didn't request from peer 0
24.579 Info TransferTarget.cpp:260: Received transfer block 72 that we didn't request from peer 0
24.579 Info TransferTarget.cpp:260: Received transfer block 73 that we didn't request from peer 0
24.579 Info TransferTarget.cpp:260: Received transfer block 74 that we didn't request from peer 0
24.579 Info TransferTarget.cpp:260: Received transfer block 75 that we didn't request from peer 0
24.579 Info TransferTarget.cpp:260: Received transfer block 76 that we didn't request from peer 0
24.579 Info TransferTarget.cpp:260: Received transfer block 77 that we didn't request from peer 0
24.600 Info TransferTarget.cpp:260: Received transfer block 78 that we didn't request from peer 0
24.601 Info TransferTarget.cpp:260: Received transfer block 79 that we didn't request from peer 0
24.601 Info TransferTarget.cpp:260: Received transfer block 80 that we didn't request from peer 0
24.601 Info TransferTarget.cpp:260: Received transfer block 114 that we didn't request from peer 0
24.602 Info TransferTarget.cpp:260: Received transfer block 102 that we didn't request from peer 0
24.602 Info TransferTarget.cpp:260: Received transfer block 101 that we didn't request from peer 0
24.602 Info TransferTarget.cpp:260: Received transfer block 100 that we didn't request from peer 0
24.602 Info TransferTarget.cpp:260: Received transfer block 0 that we didn't request from peer 0
24.602 Info TransferTarget.cpp:260: Received transfer block 1 that we didn't request from peer 0
24.602 Info TransferTarget.cpp:260: Received transfer block 2 that we didn't request from peer 0
24.602 Info TransferTarget.cpp:260: Received transfer block 3 that we didn't request from peer 0
24.602 Info TransferTarget.cpp:260: Received transfer block 4 that we didn't request from peer 0
24.602 Info TransferTarget.cpp:260: Received transfer block 5 that we didn't request from peer 0
24.602 Info TransferTarget.cpp:260: Received transfer block 6 that we didn't request from peer 0
24.602 Info TransferTarget.cpp:260: Received transfer block 7 that we didn't request from peer 0
24.608 Info TransferTarget.cpp:260: Received transfer block 128 that we didn't request from peer 0
24.608 Info TransferTarget.cpp:260: Received transfer block 127 that we didn't request from peer 0
24.608 Info TransferTarget.cpp:260: Received transfer block 124 that we didn't request from peer 0
24.608 Info TransferTarget.cpp:260: Received transfer block 123 that we didn't request from peer 0
24.608 Info TransferTarget.cpp:260: Received transfer block 122 that we didn't request from peer 0
24.608 Info TransferTarget.cpp:260: Received transfer block 121 that we didn't request from peer 0
24.608 Info TransferTarget.cpp:260: Received transfer block 120 that we didn't request from peer 0
24.608 Info TransferTarget.cpp:260: Received transfer block 119 that we didn't request from peer 0
24.608 Info TransferTarget.cpp:260: Received transfer block 118 that we didn't request from peer 0
24.619 Info TransferTarget.cpp:260: Received transfer block 117 that we didn't request from peer 0
24.620 Info TransferTarget.cpp:260: Received transfer block 116 that we didn't request from peer 0
24.620 Info TransferTarget.cpp:260: Received transfer block 115 that we didn't request from peer 0
24.620 Info TransferTarget.cpp:260: Received transfer block 138 that we didn't request from peer 0
24.620 Info TransferTarget.cpp:260: Received transfer block 139 that we didn't request from peer 0
24.620 Info TransferTarget.cpp:260: Received transfer block 140 that we didn't request from peer 0
24.620 Info TransferTarget.cpp:260: Received transfer block 141 that we didn't request from peer 0
24.620 Info TransferTarget.cpp:260: Received transfer block 142 that we didn't request from peer 0
24.620 Info TransferTarget.cpp:260: Received transfer block 143 that we didn't request from peer 0
24.632 Info TransferTarget.cpp:260: Received transfer block 18 that we didn't request from peer 0
24.632 Info TransferTarget.cpp:260: Received transfer block 17 that we didn't request from peer 0
24.650 Info TransferTarget.cpp:260: Received transfer block 16 that we didn't request from peer 0
24.656 Info TransferTarget.cpp:260: Received transfer block 15 that we didn't request from peer 0
24.656 Info TransferTarget.cpp:260: Received transfer block 14 that we didn't request from peer 0
24.729 Info TransferTarget.cpp:260: Received transfer block 13 that we didn't request from peer 0
24.729 Info TransferTarget.cpp:260: Received transfer block 12 that we didn't request from peer 0
24.729 Info TransferTarget.cpp:260: Received transfer block 11 that we didn't request from peer 0
24.729 Info TransferTarget.cpp:260: Received transfer block 10 that we didn't request from peer 0
24.729 Info TransferTarget.cpp:260: Received transfer block 9 that we didn't request from peer 0
24.729 Info TransferTarget.cpp:260: Received transfer block 147 that we didn't request from peer 0
24.729 Info TransferTarget.cpp:260: Received transfer block 148 that we didn't request from peer 0
24.729 Info TransferTarget.cpp:260: Received transfer block 149 that we didn't request from peer 0
24.729 Info TransferTarget.cpp:260: Received transfer block 137 that we didn't request from peer 0
24.729 Info TransferTarget.cpp:260: Received transfer block 8 that we didn't request from peer 0
24.730 Info TransferTarget.cpp:260: Received transfer block 112 that we didn't request from peer 0
24.730 Info TransferTarget.cpp:260: Received transfer block 113 that we didn't request from peer 0
24.730 Info TransferTarget.cpp:260: Received transfer block 153 that we didn't request from peer 0
24.730 Info TransferTarget.cpp:260: Received transfer block 146 that we didn't request from peer 0
24.730 Info TransferTarget.cpp:260: Received transfer block 145 that we didn't request from peer 0
24.730 Info TransferTarget.cpp:260: Received transfer block 144 that we didn't request from peer 0
24.730 Info TransferTarget.cpp:260: Received transfer block 129 that we didn't request from peer 0
24.730 Info TransferTarget.cpp:260: Received transfer block 130 that we didn't request from peer 0
24.759 Info TransferTarget.cpp:260: Received transfer block 131 that we didn't request from peer 0
24.759 Info TransferTarget.cpp:260: Received transfer block 132 that we didn't request from peer 0
24.759 Info TransferTarget.cpp:260: Received transfer block 133 that we didn't request from peer 0
24.759 Info TransferTarget.cpp:260: Received transfer block 134 that we didn't request from peer 0
24.771 Info TransferTarget.cpp:260: Received transfer block 138 that we didn't request from peer 0
24.772 Info TransferTarget.cpp:260: Received transfer block 159 that we didn't request from peer 0
24.776 Info TransferTarget.cpp:260: Received transfer block 152 that we didn't request from peer 0
24.776 Info TransferTarget.cpp:260: Received transfer block 151 that we didn't request from peer 0
24.776 Info TransferTarget.cpp:260: Received transfer block 150 that we didn't request from peer 0
24.776 Info TransferTarget.cpp:260: Received transfer block 155 that we didn't request from peer 0
24.788 Info TransferTarget.cpp:260: Received transfer block 154 that we didn't request from peer 0
24.794 Info TransferTarget.cpp:260: Received transfer block 158 that we didn't request from peer 0
24.794 Info TransferTarget.cpp:260: Received transfer block 157 that we didn't request from peer 0
24.794 Info TransferTarget.cpp:260: Received transfer block 156 that we didn't request from peer 0
24.815 Info TransferTarget.cpp:260: Received transfer block 162 that we didn't request from peer 0
24.819 Info TransferTarget.cpp:260: Received transfer block 161 that we didn't request from peer 0
24.833 Info TransferTarget.cpp:260: Received transfer block 160 that we didn't request from peer 0
82.517 Finished download (58 s, 18 MB, 301 kB/s)
82.532 Info MultiplayerManager.cpp:1065: networkTick(53917630) mapTick(-1) map download finished creating scenario
82.532 Info MultiplayerManager.cpp:869: networkTick(53917630) mapTick(-1) changing state from(ConnectedDownloadingMap) to(ConnectedLoadingMap)
82.533 Loading map C:/Spiele\Factorio\Factorio_0.12.20_SethaVanilla\temp\mp-download.zip
82.658 Info MultiplayerManager.cpp:1006: networkTick(53917636) mapTick(-1) received stateChanged peerID(48) oldState(ConnectedDownloadingMap) newState(ConnectedLoadingMap)
82.688 Info Scenario.cpp:123: Map version 0.12.20-1
82.760 Info MultiplayerManager.cpp:1006: networkTick(53917642) mapTick(-1) received stateChanged peerID(0) oldState(InGameSendingMap) newState(InGameWaitingForOthers)
83.593 Info MultiplayerManager.cpp:869: networkTick(53917693) mapTick(10858887) changing state from(ConnectedLoadingMap) to(InGameWaitingForOthers)
84.125 Info MultiplayerManager.cpp:1006: networkTick(53917699) mapTick(10858887) received stateChanged peerID(48) oldState(ConnectedLoadingMap) newState(InGameWaitingForOthers)
84.125 Info MultiplayerManager.cpp:1177: networkTick(53917699) mapTick(10858887) stopping mapAlign
84.125 Info MultiplayerManager.cpp:1670: performing map align task (SendPlayerJoinGameAlignTask)
84.125 Info MultiplayerManager.cpp:869: networkTick(53917699) mapTick(10858887) changing state from(InGameWaitingForOthers) to(InGame)
84.125 Info MultiplayerManager.cpp:1492: networkTick(53917699) mapTick(10858887) peerID(48) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(48) state(InGameWaitingForOthers) mapAlignTick(-1))
)
84.243 Info MultiplayerManager.cpp:1006: networkTick(53917705) mapTick(10858887) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
84.243 Info MultiplayerManager.cpp:1006: networkTick(53917705) mapTick(10858887) received stateChanged peerID(48) oldState(InGameWaitingForOthers) newState(InGame)
84.378 Info NetworkInputHandler.cpp:712: mapTick(10858896) networkTick(53917714) connecting to player(Loewchen).
84.378 Info NetworkInputHandler.cpp:570: assigning playerIndex(65) to peer(48)
84.378 Info GameActionHandler.cpp:1940: MapTick(10858896) processed PlayerJoinGame peerID(48) playerIndex(65) mode(create)
85.168 Error NetworkInputHandler.cpp:343: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(10858920) peer(48) testCrc(-75747199) testCrcPeerID(0) currentCrc(722806128)
85.168 Info NetworkInputHandler.cpp:419: Desync specification: Reference CRC = 4219220097, reference peers = {0}; desynced CRC = 722806128, desynced peers = {48 (Loewchen)}
85.168 Info MultiplayerManager.cpp:869: networkTick(53917757) mapTick(10858929) changing state from(InGame) to(Desynced)
85.282 Info MultiplayerManager.cpp:1006: networkTick(53917763) mapTick(10858929) received stateChanged peerID(48) oldState(InGame) newState(Desynced)
85.282 Info MultiplayerManager.cpp:869: networkTick(53917763) mapTick(10858929) changing state from(Desynced) to(DesyncedAligning)
85.282 Info MultiplayerManager.cpp:1604: networkTick(53917763) mapTick(10858929) starting mapAlign
85.382 Info MultiplayerManager.cpp:1006: networkTick(53917769) mapTick(10858929) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
85.382 Info MultiplayerManager.cpp:1006: networkTick(53917769) mapTick(10858929) received stateChanged peerID(48) oldState(Desynced) newState(DesyncedAligning)
85.382 Info MultiplayerManager.cpp:1538: networkTick(53917769) mapTick(10858929) sending mapAlignAction
85.482 Info MultiplayerManager.cpp:1225: networkTick(53917775) mapTick(10858929) received MapAlignAction from peer(0), mapAlignTick(10858935) appliedTickClosuresCount(12)
85.482 Info MultiplayerManager.cpp:1225: networkTick(53917775) mapTick(10858929) received MapAlignAction from peer(48), mapAlignTick(10858930) appliedTickClosuresCount(0)
85.482 Info MultiplayerManager.cpp:1620: networkTick(53917776) mapTick(10858929) mapAlign finished targetTick(10858935) updating map and running the tasks ...
85.483 Info MultiplayerManager.cpp:1492: networkTick(53917776) mapTick(10858929) peerID(48) fullStateLog: local state(DesyncedAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(10858935))
((peerID(48) state(DesyncedAligning) mapAlignTick(10858930))
)
85.493 Info MultiplayerManager.cpp:1639: maptick(10858935) crc before applying all ticks: -1293930703
85.493 Info NetworkInputHandler.cpp:632: applyAllTickClosures: expectedMapTick(10858935) lastTickApplied(10858926) referenceMapTick(10858935) networkTick(53917776)
85.493 Info MultiplayerManager.cpp:1647: maptick(10858935) crc after applying all ticks: -1293930703
85.493 Info MultiplayerManager.cpp:869: networkTick(53917776) mapTick(10858935) changing state from(DesyncedAligning) to(DesyncedAligned)
85.611 Info MultiplayerManager.cpp:1006: networkTick(53917782) mapTick(10858935) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
85.611 Info MultiplayerManager.cpp:1006: networkTick(53917782) mapTick(10858935) received stateChanged peerID(48) oldState(DesyncedAligning) newState(DesyncedAligned)
85.611 Info MultiplayerManager.cpp:1834: NetworkTick(53917782) reconnecting after map desync.
85.611 Info MultiplayerManager.cpp:1981: NetworkTick(53917782) MapTick(10858935) disconnecting player(65) from the user.
85.645 Info MultiplayerManager.cpp:869: networkTick(53917782) mapTick(10858935) changing state from(DesyncedAligned) to(DesyncedCreatingReport)
85.711 Info MultiplayerManager.cpp:1006: networkTick(53917788) mapTick(10858935) received stateChanged peerID(48) oldState(DesyncedAligned) newState(DesyncedCreatingReport)
87.062 Info MultiplayerManager.cpp:869: networkTick(53917870) mapTick(-1) changing state from(DesyncedCreatingReport) to(DesyncedWaitingForMap)
87.078 Info NetworkInputHandler.cpp:729: mapTick(10858935) networkTick(53917870) disconnecting from player.
87.452 Info MultiplayerManager.cpp:1006: networkTick(53917876) mapTick(-1) received stateChanged peerID(48) oldState(DesyncedCreatingReport) newState(DesyncedWaitingForMap)
87.554 Info MultiplayerManager.cpp:1006: networkTick(53917882) mapTick(-1) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
88.898 Info MultiplayerManager.cpp:1006: networkTick(53917958) mapTick(-1) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
88.898 Downloading file C:/Spiele\Factorio\Factorio_0.12.20_SethaVanilla\temp\mp-download.zip (17557779 B, 34907 blocks)
88.903 Info MultiplayerManager.cpp:869: networkTick(53917958) mapTick(-1) changing state from(DesyncedWaitingForMap) to(DesyncedDownloadingMap)
88.903 Info NetworkInputHandler.cpp:40: mapTick(10858935) networkTick(53917958) initialized NetworkInputHandler local peer(48).
88.903 Info NetworkInputHandler.cpp:491: expectedMapTick(10858935) adding peer(0).
88.903 Info Synchronizer.cpp:407: NetworkTick(53917958) mapTick(10858935) applying 12 pending tickClosures
88.903 Info NetworkInputHandler.cpp:570: assigning playerIndex(255) to peer(0)
88.903 Info NetworkInputHandler.cpp:570: assigning playerIndex(255) to peer(48)
88.903 Info MultiplayerManager.cpp:1518: networkTick(53917958) mapTick(-1) adding mapAlignTask(SendPlayerJoinGameAlignTask)
88.998 Info MultiplayerManager.cpp:1006: networkTick(53917964) mapTick(-1) received stateChanged peerID(48) oldState(DesyncedWaitingForMap) newState(DesyncedDownloadingMap)
145.051 Finished download (56 s, 18 MB, 313 kB/s)
145.063 Info MultiplayerManager.cpp:1065: networkTick(53921267) mapTick(-1) map download finished creating scenario
145.063 Info MultiplayerManager.cpp:869: networkTick(53921267) mapTick(-1) changing state from(DesyncedDownloadingMap) to(DesyncedLoadingMap)
145.064 Loading map C:/Spiele\Factorio\Factorio_0.12.20_SethaVanilla\temp\mp-download.zip
145.181 Info MultiplayerManager.cpp:1006: networkTick(53921273) mapTick(-1) received stateChanged peerID(48) oldState(DesyncedDownloadingMap) newState(DesyncedLoadingMap)
145.218 Info Scenario.cpp:123: Map version 0.12.20-1
145.283 Info MultiplayerManager.cpp:1006: networkTick(53921279) mapTick(-1) received stateChanged peerID(0) oldState(InGameSendingMap) newState(InGameWaitingForOthers)
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Fri Feb 05, 2016 4:09 pm
by Yttrium
Why are you using 12.20 instead of 12.22?
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Fri Feb 05, 2016 5:17 pm
by Loewchen
Yttrium wrote:Why are you using 12.20 instead of 12.22?
I am not the host, I can only assume he prefers the stable update channel.
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Fri Feb 05, 2016 6:33 pm
by kovarex
The newer version (0.12.22) is supposed to be more stable.
Anyway, I spend some time trying to figure it out, but I had no success. I will let the save run in a heavy mode during the weekend, it might (or not) reveal something.
If there is some reproducible way to get desync or any other hint, I would appreciate it.
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Fri Feb 05, 2016 7:44 pm
by Loewchen
kovarex wrote:If there is some reproducible way to get desync or any other hint, I would appreciate it.
We literally spend weeks trying to reproduce it after we knew the Beacons affect Steel furnace bughttps://forums.factorio.com/forum/viewtop ... 11&t=16634 was fixed, but since we have no server of our own we had to wait for it to get restarted every time it crashed or desync-looped, so we gave up at some point.
Some things that might help:
over all desync-events there was no comprehensive action noticeable (like: always when somebody builds X...)
the time between T=0 and T=2 could be seconds, minutes or over an hour, evenly distributed
while trying to isolate the cause we cut the power of parts of the base (in the window between T=0 and T=2) (we thought it would be caused by some productions building like the bug before) and we even had a desync while the whole base was without electricity.
every server on the multiplayer-subforum (that I could join) has had this issue, the busy ones about all 1...2 days the not so busy ones once a week (counted the times the server crashed or got stuck in a desync loop because of it, not just the times just a simple desync happend).
when you test this you obviously need to have new players to join in between the game if you want to actually observe the desync
Let me know if you want those other reports as well.
Gratefully, Loewchen
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Wed Feb 10, 2016 2:20 am
by Loewchen
We had the issue again (on a server with 12.22) and managed to obtain the report of the player desyncing first (the one joining at T1) and the 4 reports after re-downloading/rejoining.
observations:
the player was removing logic wire from a combinator by ordering robots to do it when he desynced the first time (might be coincidence)
the player was the last to join (as always)
the player was on the server for 67 minutes
the player was on the server before T1 but got disconnected for unknown reason and had to rejoin
after T2 the player was able to join again for several minutes (before desyncing again) several times
all players connecting after T2 desynced as well (as always) some instantly some after 1...2 minutes
After all players choose to leave and rejoin again to get the server itself to desync, the server successfully downloaded the map from the players but desynced itself again after a about one minute (first time I saw that happening)
Autosaves prior to T1 and the logged data from the server should be available as well if needed.
54602.211 Info MultiplayerManager.cpp:1492: networkTick(3212369) mapTick(10216179) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(27) state(InGame) mapAlignTick(-1))
)
54640.027 Connection request from 79.225.86.32:34197
54640.027 Info Router.cpp:647: NetworkTick(3214601) replying to connectionRequest for address(79.225.86.32:34197).
54640.227 Info Router.cpp:799: networkTick(3214613) adding peer(28) address(79.225.86.32:34197) sending connectionAccept(true)
54640.227 Info Router.cpp:670: networkTick(3214613) sending connectionAccept to address(79.225.86.32:34197) connectionRequestID(42000) newPeerID(28)
54640.227 Info Synchronizer.cpp:494: networkTick(3214613) adding peer(28) success(true).
54640.493 Info MultiplayerManager.cpp:1331: Received peer info for peer(0) username(<server>).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(0) oldState(InGame) newState(InGame)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(13) username(PandaGOD).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(13) oldState(InGame) newState(InGame)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(16) username(Masterhots).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(16) oldState(InGame) newState(InGame)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(18) username(Loewchen).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(18) oldState(InGame) newState(InGame)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(24) username(judos).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(24) oldState(InGame) newState(InGame)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(25) username(Joshuacoops).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(25) oldState(InGame) newState(InGame)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(27) username(blakemw).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(27) oldState(InGame) newState(InGame)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(28) username(kaZ).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(28) oldState(Ready) newState(Connecting)
54640.494 Info NetworkInputHandler.cpp:491: expectedMapTick(10218402) adding peer(28).
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(28) username(kaZ).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(28) oldState(Connecting) newState(Connecting)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(28) username(kaZ).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(28) oldState(Connecting) newState(Connecting)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(28) username(kaZ).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(28) oldState(Connecting) newState(Connecting)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(28) username(kaZ).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(28) oldState(Connecting) newState(Connecting)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(28) username(kaZ).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(28) oldState(Connecting) newState(Connecting)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(28) username(kaZ).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(28) oldState(Connecting) newState(Connecting)
54640.494 Info MultiplayerManager.cpp:1331: Received peer info for peer(28) username(kaZ).
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(28) oldState(Connecting) newState(Connecting)
54640.494 Info MultiplayerManager.cpp:1006: networkTick(3214625) mapTick(10218402) received stateChanged peerID(28) oldState(Connecting) newState(VerifyingConnection)
54640.960 Info MultiplayerManager.cpp:1006: networkTick(3214650) mapTick(10218402) received stateChanged peerID(28) oldState(VerifyingConnection) newState(ConnectedWaitingForMap)
54640.960 Info MultiplayerManager.cpp:869: networkTick(3214650) mapTick(10218402) changing state from(InGame) to(InGameAligning)
54640.960 Info MultiplayerManager.cpp:1604: networkTick(3214650) mapTick(10218402) starting mapAlign
54641.160 Info MultiplayerManager.cpp:1006: networkTick(3214662) mapTick(10218402) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
54641.160 Info MultiplayerManager.cpp:1006: networkTick(3214662) mapTick(10218402) received stateChanged peerID(13) oldState(InGame) newState(InGameAligning)
54641.160 Info MultiplayerManager.cpp:1006: networkTick(3214662) mapTick(10218402) received stateChanged peerID(16) oldState(InGame) newState(InGameAligning)
54641.160 Info MultiplayerManager.cpp:1006: networkTick(3214662) mapTick(10218402) received stateChanged peerID(18) oldState(InGame) newState(InGameAligning)
54641.160 Info MultiplayerManager.cpp:1006: networkTick(3214662) mapTick(10218402) received stateChanged peerID(24) oldState(InGame) newState(InGameAligning)
54641.160 Info MultiplayerManager.cpp:1006: networkTick(3214662) mapTick(10218402) received stateChanged peerID(25) oldState(InGame) newState(InGameAligning)
54641.160 Info MultiplayerManager.cpp:1006: networkTick(3214662) mapTick(10218402) received stateChanged peerID(27) oldState(InGame) newState(InGameAligning)
54641.160 Info MultiplayerManager.cpp:1538: networkTick(3214662) mapTick(10218402) sending mapAlignAction
54641.360 Info MultiplayerManager.cpp:1225: networkTick(3214674) mapTick(10218402) received MapAlignAction from peer(0), mapAlignTick(10218403) appliedTickClosuresCount(0)
54641.360 Info MultiplayerManager.cpp:1225: networkTick(3214674) mapTick(10218402) received MapAlignAction from peer(13), mapAlignTick(10218404) appliedTickClosuresCount(98)
54641.360 Info MultiplayerManager.cpp:1225: networkTick(3214674) mapTick(10218402) received MapAlignAction from peer(16), mapAlignTick(10218402) appliedTickClosuresCount(112)
54641.360 Info MultiplayerManager.cpp:1225: networkTick(3214674) mapTick(10218402) received MapAlignAction from peer(18), mapAlignTick(10218403) appliedTickClosuresCount(105)
54641.360 Info MultiplayerManager.cpp:1225: networkTick(3214674) mapTick(10218402) received MapAlignAction from peer(24), mapAlignTick(10218402) appliedTickClosuresCount(112)
54641.360 Info MultiplayerManager.cpp:1225: networkTick(3214674) mapTick(10218402) received MapAlignAction from peer(25), mapAlignTick(10218403) appliedTickClosuresCount(105)
54641.360 Info MultiplayerManager.cpp:1225: networkTick(3214674) mapTick(10218402) received MapAlignAction from peer(27), mapAlignTick(10218403) appliedTickClosuresCount(105)
54641.360 Info MultiplayerManager.cpp:1620: networkTick(3214675) mapTick(10218402) mapAlign finished targetTick(10218404) updating map and running the tasks ...
54641.360 Info MultiplayerManager.cpp:1492: networkTick(3214675) mapTick(10218402) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(10218403))
((peerID(13) state(InGameAligning) mapAlignTick(10218404))
((peerID(16) state(InGameAligning) mapAlignTick(10218402))
((peerID(18) state(InGameAligning) mapAlignTick(10218403))
((peerID(24) state(InGameAligning) mapAlignTick(10218402))
((peerID(25) state(InGameAligning) mapAlignTick(10218403))
((peerID(27) state(InGameAligning) mapAlignTick(10218403))
((peerID(28) state(ConnectedWaitingForMap) mapAlignTick(-1))
)
54641.365 Info MultiplayerManager.cpp:1639: maptick(10218404) crc before applying all ticks: 194621932
54641.365 Info NetworkInputHandler.cpp:632: applyAllTickClosures: expectedMapTick(10218404) lastTickApplied(10218389) referenceMapTick(10218404) networkTick(3214675)
54641.365 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10218390) inputActions((SelectedEntityChanged) ) ) for peer(27)
54641.365 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10218392) inputActions((SelectedEntityChanged) ) ) for peer(27)
54641.365 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10218393) inputActions((CursorTransfer) ) ) for peer(24)
54641.365 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10218393) inputActions((OpenGui) ) ) for peer(25)
54641.365 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10218394) inputActions((SelectedEntityChanged) ) ) for peer(27)
54641.365 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10218395) inputActions((ChangeWalkingState) ) ) for peer(13)
54641.365 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10218396) inputActions((SelectedEntityChanged) ) ) for peer(16)
54641.365 Info MultiplayerManager.cpp:1647: maptick(10218404) crc after applying all ticks: -308490167
54641.365 Info MultiplayerManager.cpp:869: networkTick(3214675) mapTick(10218404) changing state from(InGameAligning) to(InGameAligned)
54641.365 Info MultiplayerManager.cpp:869: networkTick(3214675) mapTick(10218404) changing state from(InGameAligned) to(InGameSavingMap)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(13) oldState(InGameAligning) newState(InGameAligned)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(13) oldState(InGameAligned) newState(InGameWaitingForOthers)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(16) oldState(InGameAligning) newState(InGameAligned)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(16) oldState(InGameAligned) newState(InGameWaitingForOthers)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(18) oldState(InGameAligning) newState(InGameAligned)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(18) oldState(InGameAligned) newState(InGameWaitingForOthers)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(24) oldState(InGameAligning) newState(InGameAligned)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(24) oldState(InGameAligned) newState(InGameWaitingForOthers)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(25) oldState(InGameAligning) newState(InGameAligned)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(25) oldState(InGameAligned) newState(InGameWaitingForOthers)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(27) oldState(InGameAligning) newState(InGameAligned)
54641.627 Info MultiplayerManager.cpp:1006: networkTick(3214687) mapTick(10218404) received stateChanged peerID(27) oldState(InGameAligned) newState(InGameWaitingForOthers)
54642.727 Info MultiplayerManager.cpp:869: networkTick(3214711) mapTick(10218404) changing state from(InGameSavingMap) to(InGameSendingMap)
54642.770 Info MultiplayerManager.cpp:1106: MapTick(10218404) Serving map(/factorio/temp/mp-download.zip) for peer(28) size(12250523) crc(1821362982)
54643.093 Info MultiplayerManager.cpp:1006: networkTick(3214723) mapTick(10218404) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
54643.643 Info MultiplayerManager.cpp:1006: networkTick(3214735) mapTick(10218404) received stateChanged peerID(28) oldState(ConnectedWaitingForMap) newState(ConnectedDownloadingMap)
54655.309 Info MultiplayerManager.cpp:1006: networkTick(3215363) mapTick(10218404) received stateChanged peerID(28) oldState(ConnectedDownloadingMap) newState(ConnectedLoadingMap)
54655.309 Canceling serving for peer 28
54655.310 Info MultiplayerManager.cpp:869: networkTick(3215363) mapTick(10218404) changing state from(InGameSendingMap) to(InGameWaitingForOthers)
54655.509 Info MultiplayerManager.cpp:1006: networkTick(3215375) mapTick(10218404) received stateChanged peerID(0) oldState(InGameSendingMap) newState(InGameWaitingForOthers)
54658.393 Info MultiplayerManager.cpp:1006: networkTick(3215476) mapTick(10218404) received stateChanged peerID(28) oldState(ConnectedLoadingMap) newState(InGameWaitingForOthers)
54658.393 Info MultiplayerManager.cpp:1177: networkTick(3215476) mapTick(10218404) stopping mapAlign
54658.393 Info MultiplayerManager.cpp:869: networkTick(3215476) mapTick(10218404) changing state from(InGameWaitingForOthers) to(InGame)
54658.393 Info MultiplayerManager.cpp:1492: networkTick(3215476) mapTick(10218404) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(13) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(16) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(18) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(24) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(25) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(27) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(28) state(InGameWaitingForOthers) mapAlignTick(-1))
)
54658.609 Info MultiplayerManager.cpp:1006: networkTick(3215488) mapTick(10218417) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
54658.609 Info MultiplayerManager.cpp:1006: networkTick(3215488) mapTick(10218417) received stateChanged peerID(13) oldState(InGameWaitingForOthers) newState(InGame)
54658.609 Info MultiplayerManager.cpp:1006: networkTick(3215488) mapTick(10218417) received stateChanged peerID(16) oldState(InGameWaitingForOthers) newState(InGame)
54658.609 Info MultiplayerManager.cpp:1006: networkTick(3215488) mapTick(10218417) received stateChanged peerID(18) oldState(InGameWaitingForOthers) newState(InGame)
54658.609 Info MultiplayerManager.cpp:1006: networkTick(3215488) mapTick(10218417) received stateChanged peerID(24) oldState(InGameWaitingForOthers) newState(InGame)
54658.610 Info MultiplayerManager.cpp:1006: networkTick(3215488) mapTick(10218417) received stateChanged peerID(25) oldState(InGameWaitingForOthers) newState(InGame)
54658.610 Info MultiplayerManager.cpp:1006: networkTick(3215488) mapTick(10218417) received stateChanged peerID(27) oldState(InGameWaitingForOthers) newState(InGame)
54658.610 Info MultiplayerManager.cpp:1006: networkTick(3215488) mapTick(10218417) received stateChanged peerID(28) oldState(InGameWaitingForOthers) newState(InGame)
54658.830 Info NetworkInputHandler.cpp:570: assigning playerIndex(17) to peer(28)
54658.830 Info GameActionHandler.cpp:1940: MapTick(10218419) processed PlayerJoinGame peerID(28) playerIndex(17) mode(connect)
54902.216 Info MultiplayerManager.cpp:1492: networkTick(3229375) mapTick(10231640) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(27) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
55202.221 Info MultiplayerManager.cpp:1492: networkTick(3247274) mapTick(10248784) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(27) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
55502.225 Info MultiplayerManager.cpp:1492: networkTick(3264161) mapTick(10264864) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(27) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
55802.247 Info MultiplayerManager.cpp:1492: networkTick(3282113) mapTick(10282093) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(27) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
56102.251 Info MultiplayerManager.cpp:1492: networkTick(3300043) mapTick(10299454) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(27) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
56402.256 Info MultiplayerManager.cpp:1492: networkTick(3316501) mapTick(10315209) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(27) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
56702.261 Info MultiplayerManager.cpp:1492: networkTick(3334190) mapTick(10332220) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(27) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
56840.955 Info MultiplayerManager.cpp:1364: Disconnect notification for peer (27)
56840.956 Info MultiplayerManager.cpp:956: networkTick(3342217) mapTick(10339916) removing peer(27) dropout(false)
56840.990 Info MultiplayerManager.cpp:869: networkTick(3342217) mapTick(10339916) changing state from(InGame) to(InGameAligning)
56840.990 Info MultiplayerManager.cpp:1604: networkTick(3342217) mapTick(10339916) starting mapAlign
56840.990 Info MultiplayerManager.cpp:1518: networkTick(3342217) mapTick(10339916) adding mapAlignTask(RemovePlayerAlignTask)
56841.155 Info MultiplayerManager.cpp:1006: networkTick(3342229) mapTick(10339916) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
56841.155 Info MultiplayerManager.cpp:1006: networkTick(3342229) mapTick(10339916) received stateChanged peerID(13) oldState(InGame) newState(InGameAligning)
56841.155 Info MultiplayerManager.cpp:1006: networkTick(3342229) mapTick(10339916) received stateChanged peerID(16) oldState(InGame) newState(InGameAligning)
56841.155 Info MultiplayerManager.cpp:1006: networkTick(3342229) mapTick(10339916) received stateChanged peerID(18) oldState(InGame) newState(InGameAligning)
56841.155 Info MultiplayerManager.cpp:1006: networkTick(3342229) mapTick(10339916) received stateChanged peerID(24) oldState(InGame) newState(InGameAligning)
56841.155 Info MultiplayerManager.cpp:1006: networkTick(3342229) mapTick(10339916) received stateChanged peerID(25) oldState(InGame) newState(InGameAligning)
56841.155 Info MultiplayerManager.cpp:1006: networkTick(3342229) mapTick(10339916) received stateChanged peerID(27) oldState(InGame) newState(InGameAligning)
56841.155 Info MultiplayerManager.cpp:1006: networkTick(3342229) mapTick(10339916) received stateChanged peerID(28) oldState(InGame) newState(InGameAligning)
56841.155 Info MultiplayerManager.cpp:1538: networkTick(3342229) mapTick(10339916) sending mapAlignAction
56841.355 Info MultiplayerManager.cpp:1225: networkTick(3342241) mapTick(10339916) received MapAlignAction from peer(0), mapAlignTick(10339917) appliedTickClosuresCount(0)
56841.355 Info MultiplayerManager.cpp:1225: networkTick(3342241) mapTick(10339916) received MapAlignAction from peer(13), mapAlignTick(10339917) appliedTickClosuresCount(116)
56841.355 Info MultiplayerManager.cpp:1225: networkTick(3342241) mapTick(10339916) received MapAlignAction from peer(16), mapAlignTick(10339918) appliedTickClosuresCount(108)
56841.355 Info MultiplayerManager.cpp:1225: networkTick(3342241) mapTick(10339916) received MapAlignAction from peer(18), mapAlignTick(10339917) appliedTickClosuresCount(116)
56841.355 Info MultiplayerManager.cpp:1225: networkTick(3342241) mapTick(10339916) received MapAlignAction from peer(24), mapAlignTick(10339917) appliedTickClosuresCount(116)
56841.355 Info MultiplayerManager.cpp:1225: networkTick(3342241) mapTick(10339916) received MapAlignAction from peer(25), mapAlignTick(10339918) appliedTickClosuresCount(108)
56841.355 Info MultiplayerManager.cpp:1225: networkTick(3342241) mapTick(10339916) received MapAlignAction from peer(27), mapAlignTick(10339917) appliedTickClosuresCount(116)
56841.355 Info MultiplayerManager.cpp:1225: networkTick(3342241) mapTick(10339916) received MapAlignAction from peer(28), mapAlignTick(10339918) appliedTickClosuresCount(116)
56841.356 Info MultiplayerManager.cpp:1620: networkTick(3342242) mapTick(10339916) mapAlign finished targetTick(10339918) updating map and running the tasks ...
56841.356 Info MultiplayerManager.cpp:1492: networkTick(3342242) mapTick(10339916) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(10339917))
((peerID(13) state(InGameAligning) mapAlignTick(10339917))
((peerID(16) state(InGameAligning) mapAlignTick(10339918))
((peerID(18) state(InGameAligning) mapAlignTick(10339917))
((peerID(24) state(InGameAligning) mapAlignTick(10339917))
((peerID(25) state(InGameAligning) mapAlignTick(10339918))
((peerID(27) state(InGameAligning) mapAlignTick(10339917))
((peerID(28) state(InGameAligning) mapAlignTick(10339918))
)
56841.361 Info MultiplayerManager.cpp:1639: maptick(10339918) crc before applying all ticks: -479901257
56841.361 Info NetworkInputHandler.cpp:632: applyAllTickClosures: expectedMapTick(10339918) lastTickApplied(10339903) referenceMapTick(10339918) networkTick(3342242)
56841.361 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10339904) inputActions((PlayerLeaveGame) ) ) for peer(27)
56841.361 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10339907) inputActions((SelectedEntityChanged) ) ) for peer(13)
56841.361 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10339908) inputActions((SelectedEntityChanged) ) ) for peer(13)
56841.361 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10339912) inputActions((ChangeWalkingState) ) ) for peer(13)
56841.361 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10339913) inputActions((SelectedEntityChanged) ) ) for peer(13)
56841.361 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10339913) inputActions((SelectedEntityChanged) ) ) for peer(18)
56841.361 Info NetworkInputHandler.cpp:515: expectedMapTick(10339918) removing peer(27) success(true).
56841.361 Info GameActionHandler.cpp:1962: MapTick(10339918) processed PlayerLeaveGame peerID(27) playerIndex(21)
56841.362 Info MultiplayerManager.cpp:1647: maptick(10339918) crc after applying all ticks: 1896560205
56841.362 Info MultiplayerManager.cpp:869: networkTick(3342242) mapTick(10339918) changing state from(InGameAligning) to(InGameAligned)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(13) oldState(InGameAligning) newState(InGameAligned)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(13) oldState(InGameAligned) newState(InGameWaitingForOthers)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(16) oldState(InGameAligning) newState(InGameAligned)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(16) oldState(InGameAligned) newState(InGameWaitingForOthers)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(18) oldState(InGameAligning) newState(InGameAligned)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(18) oldState(InGameAligned) newState(InGameWaitingForOthers)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(24) oldState(InGameAligning) newState(InGameAligned)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(24) oldState(InGameAligned) newState(InGameWaitingForOthers)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(25) oldState(InGameAligning) newState(InGameAligned)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(25) oldState(InGameAligned) newState(InGameWaitingForOthers)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(27) oldState(InGameAligning) newState(InGameAligned)
56841.939 Info NetworkInputHandler.cpp:491: expectedMapTick(10339918) adding peer(27).
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(27) oldState(InGameAligned) newState(InGameWaitingForOthers)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(28) oldState(InGameAligning) newState(InGameAligned)
56841.939 Info MultiplayerManager.cpp:869: networkTick(3342254) mapTick(10339918) changing state from(InGameAligned) to(InGameWaitingForOthers)
56841.939 Info MultiplayerManager.cpp:1006: networkTick(3342254) mapTick(10339918) received stateChanged peerID(28) oldState(InGameAligned) newState(InGameWaitingForOthers)
56842.539 Info MultiplayerManager.cpp:1006: networkTick(3342266) mapTick(10339918) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameWaitingForOthers)
56842.539 Info MultiplayerManager.cpp:1177: networkTick(3342266) mapTick(10339918) stopping mapAlign
56842.539 Info MultiplayerManager.cpp:1670: performing map align task (RemovePlayerAlignTask)
56842.539 Info MultiplayerManager.cpp:1694: networkTick(3342266) mapTick(10339918) finishing removing peer(27) hasPeer(true)
56842.539 Info NetworkInputHandler.cpp:515: expectedMapTick(10339918) removing peer(27) success(true).
56842.539 Info Synchronizer.cpp:510: networkTick(3342266) removing peer(27) success(true).
56842.539 Info MultiplayerManager.cpp:869: networkTick(3342266) mapTick(10339918) changing state from(InGameWaitingForOthers) to(InGame)
56842.539 Info MultiplayerManager.cpp:1492: networkTick(3342266) mapTick(10339918) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(13) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(16) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(18) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(24) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(25) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(28) state(InGameWaitingForOthers) mapAlignTick(-1))
)
56842.955 Info MultiplayerManager.cpp:1006: networkTick(3342278) mapTick(10339933) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
56842.955 Info MultiplayerManager.cpp:1006: networkTick(3342278) mapTick(10339933) received stateChanged peerID(13) oldState(InGameWaitingForOthers) newState(InGame)
56842.955 Info MultiplayerManager.cpp:1006: networkTick(3342278) mapTick(10339933) received stateChanged peerID(16) oldState(InGameWaitingForOthers) newState(InGame)
56842.955 Info MultiplayerManager.cpp:1006: networkTick(3342278) mapTick(10339933) received stateChanged peerID(18) oldState(InGameWaitingForOthers) newState(InGame)
56842.955 Info MultiplayerManager.cpp:1006: networkTick(3342278) mapTick(10339933) received stateChanged peerID(24) oldState(InGameWaitingForOthers) newState(InGame)
56842.955 Info MultiplayerManager.cpp:1006: networkTick(3342278) mapTick(10339933) received stateChanged peerID(25) oldState(InGameWaitingForOthers) newState(InGame)
56842.955 Info MultiplayerManager.cpp:1006: networkTick(3342278) mapTick(10339933) received stateChanged peerID(28) oldState(InGameWaitingForOthers) newState(InGame)
57002.265 Info MultiplayerManager.cpp:1492: networkTick(3351416) mapTick(10348913) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
57302.270 Info MultiplayerManager.cpp:1492: networkTick(3369231) mapTick(10366467) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
57602.275 Info MultiplayerManager.cpp:1492: networkTick(3387203) mapTick(10384169) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
57902.279 Info MultiplayerManager.cpp:1492: networkTick(3405158) mapTick(10401984) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
58202.284 Info MultiplayerManager.cpp:1492: networkTick(3422709) mapTick(10419217) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
58502.289 Info MultiplayerManager.cpp:1492: networkTick(3440687) mapTick(10437023) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
58663.118 Error NetworkInputHandler.cpp:343: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(10446360) peer(28) testCrc(-1495573077) testCrcPeerID(0) currentCrc(-1755845976)
58663.118 Info NetworkInputHandler.cpp:419: Desync specification: Reference CRC = 2799394219, reference peers = {0, 13 (PandaGOD), 16 (Masterhots), 18 (Loewchen), 24 (judos), 25 (Joshuacoops)}; desynced CRC = 2539121320, desynced peers = {28 (kaZ)}
58663.332 Info MultiplayerManager.cpp:1006: networkTick(3450331) mapTick(10446387) received stateChanged peerID(28) oldState(InGame) newState(Desynced)
58663.332 Info MultiplayerManager.cpp:869: networkTick(3450331) mapTick(10446387) changing state from(InGame) to(InGameAligning)
58663.332 Info MultiplayerManager.cpp:1604: networkTick(3450331) mapTick(10446387) starting mapAlign
58663.532 Info MultiplayerManager.cpp:1006: networkTick(3450343) mapTick(10446387) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
58663.532 Info MultiplayerManager.cpp:1006: networkTick(3450343) mapTick(10446387) received stateChanged peerID(13) oldState(InGame) newState(InGameAligning)
58663.532 Info MultiplayerManager.cpp:1006: networkTick(3450343) mapTick(10446387) received stateChanged peerID(16) oldState(InGame) newState(InGameAligning)
58663.532 Info MultiplayerManager.cpp:1006: networkTick(3450343) mapTick(10446387) received stateChanged peerID(18) oldState(InGame) newState(InGameAligning)
58663.532 Info MultiplayerManager.cpp:1006: networkTick(3450343) mapTick(10446387) received stateChanged peerID(24) oldState(InGame) newState(InGameAligning)
58663.532 Info MultiplayerManager.cpp:1006: networkTick(3450343) mapTick(10446387) received stateChanged peerID(25) oldState(InGame) newState(InGameAligning)
58663.532 Info MultiplayerManager.cpp:1006: networkTick(3450343) mapTick(10446387) received stateChanged peerID(28) oldState(Desynced) newState(DesyncedAligning)
58663.532 Info MultiplayerManager.cpp:1538: networkTick(3450343) mapTick(10446387) sending mapAlignAction
58663.732 Info MultiplayerManager.cpp:1225: networkTick(3450355) mapTick(10446387) received MapAlignAction from peer(0), mapAlignTick(10446388) appliedTickClosuresCount(0)
58663.732 Info MultiplayerManager.cpp:1225: networkTick(3450355) mapTick(10446387) received MapAlignAction from peer(13), mapAlignTick(10446388) appliedTickClosuresCount(89)
58663.733 Info MultiplayerManager.cpp:1225: networkTick(3450355) mapTick(10446387) received MapAlignAction from peer(16), mapAlignTick(10446389) appliedTickClosuresCount(82)
58663.733 Info MultiplayerManager.cpp:1225: networkTick(3450355) mapTick(10446387) received MapAlignAction from peer(18), mapAlignTick(10446389) appliedTickClosuresCount(82)
58663.733 Info MultiplayerManager.cpp:1225: networkTick(3450355) mapTick(10446387) received MapAlignAction from peer(24), mapAlignTick(10446388) appliedTickClosuresCount(89)
58663.733 Info MultiplayerManager.cpp:1225: networkTick(3450355) mapTick(10446387) received MapAlignAction from peer(25), mapAlignTick(10446388) appliedTickClosuresCount(89)
58663.733 Info MultiplayerManager.cpp:1225: networkTick(3450355) mapTick(10446387) received MapAlignAction from peer(28), mapAlignTick(10446376) appliedTickClosuresCount(173)
58663.733 Info MultiplayerManager.cpp:1620: networkTick(3450356) mapTick(10446387) mapAlign finished targetTick(10446389) updating map and running the tasks ...
58663.733 Info MultiplayerManager.cpp:1492: networkTick(3450356) mapTick(10446387) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(10446388))
((peerID(13) state(InGameAligning) mapAlignTick(10446388))
((peerID(16) state(InGameAligning) mapAlignTick(10446389))
((peerID(18) state(InGameAligning) mapAlignTick(10446389))
((peerID(24) state(InGameAligning) mapAlignTick(10446388))
((peerID(25) state(InGameAligning) mapAlignTick(10446388))
((peerID(28) state(DesyncedAligning) mapAlignTick(10446376))
)
58663.738 Info MultiplayerManager.cpp:1639: maptick(10446389) crc before applying all ticks: 589986585
58663.739 Info NetworkInputHandler.cpp:632: applyAllTickClosures: expectedMapTick(10446389) lastTickApplied(10446374) referenceMapTick(10446389) networkTick(3450356)
58663.739 Info MultiplayerManager.cpp:1647: maptick(10446389) crc after applying all ticks: 589986585
58663.739 Info MultiplayerManager.cpp:869: networkTick(3450356) mapTick(10446389) changing state from(InGameAligning) to(InGameAligned)
58663.949 Info MultiplayerManager.cpp:1006: networkTick(3450368) mapTick(10446389) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
58663.949 Info MultiplayerManager.cpp:1006: networkTick(3450368) mapTick(10446389) received stateChanged peerID(13) oldState(InGameAligning) newState(InGameAligned)
58663.949 Info MultiplayerManager.cpp:1006: networkTick(3450368) mapTick(10446389) received stateChanged peerID(13) oldState(InGameAligned) newState(InGameWaitingForOthers)
58663.949 Info MultiplayerManager.cpp:1006: networkTick(3450368) mapTick(10446389) received stateChanged peerID(16) oldState(InGameAligning) newState(InGameAligned)
58663.949 Info MultiplayerManager.cpp:1006: networkTick(3450368) mapTick(10446389) received stateChanged peerID(16) oldState(InGameAligned) newState(InGameWaitingForOthers)
58663.949 Info MultiplayerManager.cpp:1006: networkTick(3450368) mapTick(10446389) received stateChanged peerID(18) oldState(InGameAligning) newState(InGameAligned)
58663.949 Info MultiplayerManager.cpp:1006: networkTick(3450368) mapTick(10446389) received stateChanged peerID(18) oldState(InGameAligned) newState(InGameWaitingForOthers)
58663.949 Info MultiplayerManager.cpp:1006: networkTick(3450368) mapTick(10446389) received stateChanged peerID(24) oldState(InGameAligning) newState(InGameAligned)
58663.949 Info MultiplayerManager.cpp:1006: networkTick(3450368) mapTick(10446389) received stateChanged peerID(24) oldState(InGameAligned) newState(InGameWaitingForOthers)
58663.949 Info MultiplayerManager.cpp:1006: networkTick(3450368) mapTick(10446389) received stateChanged peerID(25) oldState(InGameAligning) newState(InGameAligned)
58663.949 Info MultiplayerManager.cpp:1006: networkTick(3450368) mapTick(10446389) received stateChanged peerID(25) oldState(InGameAligned) newState(InGameWaitingForOthers)
58663.949 Info MultiplayerManager.cpp:1006: networkTick(3450368) mapTick(10446389) received stateChanged peerID(28) oldState(DesyncedAligning) newState(DesyncedAligned)
58664.149 Info MultiplayerManager.cpp:1242: networkTick(3450380) mapTick(10446389) received playerReconnect
58664.149 Info MultiplayerManager.cpp:1981: NetworkTick(3450380) MapTick(10446389) disconnecting player(17) from the user.
58664.149 Info NetworkInputHandler.cpp:515: expectedMapTick(10446389) removing peer(28) success(true).
58664.149 Info NetworkInputHandler.cpp:491: expectedMapTick(10446389) adding peer(28).
58664.149 Info MultiplayerManager.cpp:1006: networkTick(3450380) mapTick(10446389) received stateChanged peerID(28) oldState(DesyncedAligned) newState(DesyncedCreatingReport)
58667.566 Info MultiplayerManager.cpp:1006: networkTick(3450518) mapTick(10446389) received stateChanged peerID(28) oldState(DesyncedCreatingReport) newState(DesyncedWaitingForMap)
58667.566 Info MultiplayerManager.cpp:869: networkTick(3450518) mapTick(10446389) changing state from(InGameAligned) to(InGameSavingMap)
58667.782 Info MultiplayerManager.cpp:1006: networkTick(3450530) mapTick(10446389) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
58668.915 Info MultiplayerManager.cpp:869: networkTick(3450569) mapTick(10446389) changing state from(InGameSavingMap) to(InGameSendingMap)
58668.963 Info MultiplayerManager.cpp:1106: MapTick(10446389) Serving map(/factorio/temp/mp-download.zip) for peer(28) size(13205181) crc(339016235)
58669.565 Info MultiplayerManager.cpp:1006: networkTick(3450581) mapTick(10446389) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
58670.015 Info MultiplayerManager.cpp:1006: networkTick(3450593) mapTick(10446389) received stateChanged peerID(28) oldState(DesyncedWaitingForMap) newState(DesyncedDownloadingMap)
58687.448 Info MultiplayerManager.cpp:1006: networkTick(3451461) mapTick(10446389) received stateChanged peerID(28) oldState(DesyncedDownloadingMap) newState(DesyncedLoadingMap)
58687.448 Canceling serving for peer 28
58687.448 Info MultiplayerManager.cpp:869: networkTick(3451461) mapTick(10446389) changing state from(InGameSendingMap) to(InGameWaitingForOthers)
58687.648 Info MultiplayerManager.cpp:1006: networkTick(3451473) mapTick(10446389) received stateChanged peerID(0) oldState(InGameSendingMap) newState(InGameWaitingForOthers)
58690.498 Info MultiplayerManager.cpp:1006: networkTick(3451572) mapTick(10446389) received stateChanged peerID(28) oldState(DesyncedLoadingMap) newState(InGameWaitingForOthers)
58690.498 Info MultiplayerManager.cpp:1177: networkTick(3451572) mapTick(10446389) stopping mapAlign
58690.498 Info MultiplayerManager.cpp:869: networkTick(3451572) mapTick(10446389) changing state from(InGameWaitingForOthers) to(InGame)
58690.498 Info MultiplayerManager.cpp:1492: networkTick(3451572) mapTick(10446389) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(13) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(16) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(18) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(24) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(25) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(28) state(InGameWaitingForOthers) mapAlignTick(-1))
)
58690.731 Info MultiplayerManager.cpp:1006: networkTick(3451584) mapTick(10446403) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
58690.731 Info MultiplayerManager.cpp:1006: networkTick(3451584) mapTick(10446403) received stateChanged peerID(13) oldState(InGameWaitingForOthers) newState(InGame)
58690.731 Info MultiplayerManager.cpp:1006: networkTick(3451584) mapTick(10446403) received stateChanged peerID(16) oldState(InGameWaitingForOthers) newState(InGame)
58690.731 Info MultiplayerManager.cpp:1006: networkTick(3451584) mapTick(10446403) received stateChanged peerID(18) oldState(InGameWaitingForOthers) newState(InGame)
58690.731 Info MultiplayerManager.cpp:1006: networkTick(3451584) mapTick(10446403) received stateChanged peerID(24) oldState(InGameWaitingForOthers) newState(InGame)
58690.731 Info MultiplayerManager.cpp:1006: networkTick(3451584) mapTick(10446403) received stateChanged peerID(25) oldState(InGameWaitingForOthers) newState(InGame)
58690.731 Info MultiplayerManager.cpp:1006: networkTick(3451584) mapTick(10446403) received stateChanged peerID(28) oldState(InGameWaitingForOthers) newState(InGame)
58690.965 Info NetworkInputHandler.cpp:570: assigning playerIndex(17) to peer(28)
58690.965 Info GameActionHandler.cpp:1940: MapTick(10446404) processed PlayerJoinGame peerID(28) playerIndex(17) mode(connect)
58802.293 Info MultiplayerManager.cpp:1492: networkTick(3458196) mapTick(10452943) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGame) mapAlignTick(-1))
((peerID(13) state(InGame) mapAlignTick(-1))
((peerID(16) state(InGame) mapAlignTick(-1))
((peerID(18) state(InGame) mapAlignTick(-1))
((peerID(24) state(InGame) mapAlignTick(-1))
((peerID(25) state(InGame) mapAlignTick(-1))
((peerID(28) state(InGame) mapAlignTick(-1))
)
58907.743 Error NetworkInputHandler.cpp:343: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(10459200) peer(28) testCrc(-1198677320) testCrcPeerID(0) currentCrc(-871019721)
58907.743 Info NetworkInputHandler.cpp:419: Desync specification: Reference CRC = 3096289976, reference peers = {0, 13 (PandaGOD), 16 (Masterhots), 18 (Loewchen), 24 (judos), 25 (Joshuacoops)}; desynced CRC = 3423947575, desynced peers = {28 (kaZ)}
58907.973 Info MultiplayerManager.cpp:1006: networkTick(3464523) mapTick(10459228) received stateChanged peerID(28) oldState(InGame) newState(Desynced)
58907.973 Info MultiplayerManager.cpp:869: networkTick(3464523) mapTick(10459228) changing state from(InGame) to(InGameAligning)
58907.973 Info MultiplayerManager.cpp:1604: networkTick(3464523) mapTick(10459228) starting mapAlign
58908.172 Info MultiplayerManager.cpp:1006: networkTick(3464535) mapTick(10459228) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
58908.173 Info MultiplayerManager.cpp:1006: networkTick(3464535) mapTick(10459228) received stateChanged peerID(13) oldState(InGame) newState(InGameAligning)
58908.173 Info MultiplayerManager.cpp:1006: networkTick(3464535) mapTick(10459228) received stateChanged peerID(16) oldState(InGame) newState(InGameAligning)
58908.173 Info MultiplayerManager.cpp:1006: networkTick(3464535) mapTick(10459228) received stateChanged peerID(18) oldState(InGame) newState(InGameAligning)
58908.173 Info MultiplayerManager.cpp:1006: networkTick(3464535) mapTick(10459228) received stateChanged peerID(24) oldState(InGame) newState(InGameAligning)
58908.173 Info MultiplayerManager.cpp:1006: networkTick(3464535) mapTick(10459228) received stateChanged peerID(25) oldState(InGame) newState(InGameAligning)
58908.173 Info MultiplayerManager.cpp:1006: networkTick(3464535) mapTick(10459228) received stateChanged peerID(28) oldState(Desynced) newState(DesyncedAligning)
58908.173 Info MultiplayerManager.cpp:1538: networkTick(3464535) mapTick(10459228) sending mapAlignAction
58908.373 Info MultiplayerManager.cpp:1225: networkTick(3464547) mapTick(10459228) received MapAlignAction from peer(0), mapAlignTick(10459229) appliedTickClosuresCount(0)
58908.373 Info MultiplayerManager.cpp:1225: networkTick(3464547) mapTick(10459228) received MapAlignAction from peer(13), mapAlignTick(10459228) appliedTickClosuresCount(90)
58908.373 Info MultiplayerManager.cpp:1225: networkTick(3464547) mapTick(10459228) received MapAlignAction from peer(16), mapAlignTick(10459228) appliedTickClosuresCount(90)
58908.373 Info MultiplayerManager.cpp:1225: networkTick(3464547) mapTick(10459228) received MapAlignAction from peer(18), mapAlignTick(10459229) appliedTickClosuresCount(83)
58908.373 Info MultiplayerManager.cpp:1225: networkTick(3464547) mapTick(10459228) received MapAlignAction from peer(24), mapAlignTick(10459229) appliedTickClosuresCount(83)
58908.373 Info MultiplayerManager.cpp:1225: networkTick(3464547) mapTick(10459228) received MapAlignAction from peer(25), mapAlignTick(10459228) appliedTickClosuresCount(90)
58908.373 Info MultiplayerManager.cpp:1225: networkTick(3464547) mapTick(10459228) received MapAlignAction from peer(28), mapAlignTick(10459216) appliedTickClosuresCount(174)
58908.373 Info MultiplayerManager.cpp:1620: networkTick(3464548) mapTick(10459228) mapAlign finished targetTick(10459229) updating map and running the tasks ...
58908.373 Info MultiplayerManager.cpp:1492: networkTick(3464548) mapTick(10459228) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(10459229))
((peerID(13) state(InGameAligning) mapAlignTick(10459228))
((peerID(16) state(InGameAligning) mapAlignTick(10459228))
((peerID(18) state(InGameAligning) mapAlignTick(10459229))
((peerID(24) state(InGameAligning) mapAlignTick(10459229))
((peerID(25) state(InGameAligning) mapAlignTick(10459228))
((peerID(28) state(DesyncedAligning) mapAlignTick(10459216))
)
58908.378 Info MultiplayerManager.cpp:1639: maptick(10459229) crc before applying all ticks: 972289137
58908.378 Info NetworkInputHandler.cpp:632: applyAllTickClosures: expectedMapTick(10459229) lastTickApplied(10459214) referenceMapTick(10459229) networkTick(3464548)
58908.378 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10459215) inputActions((ChangeRidingState) ) ) for peer(24)
58908.378 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10459216) inputActions((ChangeRidingState) ) ) for peer(24)
58908.378 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10459217) inputActions((SelectedEntityChanged) ) ) for peer(18)
58908.378 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10459217) inputActions((ChangeRidingState) ) ) for peer(24)
58908.378 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10459218) inputActions((ChangeRidingState) ) ) for peer(24)
58908.378 Info NetworkInputHandler.cpp:656: applyAllTickClosures: applying tickClosure(tick(10459219) inputActions((ChangeRidingState) ) ) for peer(24)
The desync happened at around midnight, I then restarted the server with the latest autosave. Around 2:30am Loewchen sent me this PM:
Der Server läuft und es sind auch noch spieler drauf aber jeder der neu dazu kommt desynced nach kurzer Zeit. Könnte sein das das Savgame den Bug in sich hat!
The server is running with players on it but every player who rejoins desyncs after a short time. Maybe the savegame (already) has a bug in it.
Right before posting I checked the server and no players were on it (except peer(0) <server>) but joining lead to immediate desync. Shortly after my attempt KaZ tried with the same result, which I witnessed from the console output. After restarting the server with the last autosave I could join again.
I've also attached the complete log if needed.
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Wed Feb 10, 2016 1:02 pm
by posila
Do you have desync-report from your latest desync immediately after joining? We tried to analyze some of the reports Loewchen provided, we saw random number generator was out of sync, small differences in contents of fluid boxes and positions of smoke particles. All of which is pretty usual when desync happens, but we couldn't see any indication of what caused it. What we are searching for are differences in active entities, items on belts, contents of inventories. Because game does not check whole map, but just some parts of the state, it is possible actual desync happened several seconds or even minutes before "butterfly effect" got big enough to be detected. So we would be interested to see desync report from desync that happened basically immediately after downloading a map.
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Wed Feb 10, 2016 1:50 pm
by daniel34
posila wrote:Do you have desync-report from your latest desync immediately after joining? We tried to analyze some of the reports Loewchen provided, we saw random number generator was out of sync, small differences in contents of fluid boxes and positions of smoke particles. All of which is pretty usual when desync happens, but we couldn't see any indication of what caused it. What we are searching for are differences in active entities, items on belts, contents of inventories. Because game does not check whole map, but just some parts of the state, it is possible actual desync happened several seconds or even minutes before "butterfly effect" got big enough to be detected. So we would be interested to see desync report from desync that happened basically immediately after downloading a map.
I only have logs from the desync that happened immediately after joining as the only user on the server, no desync-report was created on client or server:
0.001 2016-02-10 06:35:15; Factorio 0.12.22 (Build 17589, win64)
0.001 Operating system: Windows 10
0.001 Program arguments: "C:\Games\Factorio\bin\x64\Factorio.exe"
0.001 Read data path: C:/Games/Factorio/data
0.001 Write data path: C:/Users/Daniel/AppData/Roaming/Factorio
0.001 Binaries path: C:/Games/Factorio/bin
0.029 Available display adapters: 1
0.029 [0]: \\.\DISPLAY1 - Intel(R) HD Graphics 4000 {0x05, [0,0], 1920x1080, 32bit, 60Hz}
0.029 Create display on adapter 0. Size 1280x720 at position [310, 162]. Monitor 0
0.152 Initialised Direct3D:[0] AMD Radeon HD 8500M Series; driver: aticfx64.dll 8.17.10.1404
0.195 Desktop composition is active.
0.195 Graphics options: [FullScreen: false] [VSync: true] [UIScale: 100%] [MultiSampling: OFF] [Graphics quality: normal] [Video memory usage: high] [Light scale: 20%] [Screen: 255]
0.344 Loading mod core 0.0.0 (data.lua)
0.358 Loading mod base 0.12.22 (data.lua)
1.441 Initial atlas bitmap size is 16384
1.443 Created atlas bitmap 16384x5511
23.087 Info Updater.cpp:752: Downloading https://www.factorio.com/updater/get-available-versions?username=daniel34&token=<private>&apiVersion=2
24.264 0 packages available to download (experimental updates enabled).
24.335 Factorio initialised
58.845 Info Router.cpp:556: Router peerID(65535) shutting down.
58.845 Info Router.cpp:582: Router state -> Disconnected
58.845 Joining game 188.165.250.160:34197 at port 34197
58.845 Info WindowsUDPSocket.cpp:73: Opening socket at port (34197)
58.846 Info Router.cpp:582: Router state -> Connecting
58.849 Info MultiplayerManager.cpp:869: networkTick(0) mapTick(-1) changing state from(Ready) to(Connecting)
58.896 Info Router.cpp:582: Router state -> WaitingForAccept
59.107 Info Synchronizer.cpp:54: NetworkTick(1286630) initialized Synchronizer local peer(11) latency(12).
59.108 Info Synchronizer.cpp:494: networkTick(1286630) adding peer(0) success(true).
59.108 Info Router.cpp:582: Router state -> Connected
59.108 Info Router.cpp:767: ConnectionAccepted ownPeerID(11) nextPeerID(12)
59.108 Info MultiplayerManager.cpp:869: networkTick(1286630) mapTick(-1) changing state from(Connecting) to(VerifyingConnection)
59.124 Info Router.cpp:779: Received onPeerConnected for already connected peer(11)
59.334 Info MultiplayerManager.cpp:1331: Received peer info for peer(0) username(<server>).
59.334 Info MultiplayerManager.cpp:1006: networkTick(1286642) mapTick(-1) received stateChanged peerID(0) oldState(Ready) newState(InGame)
59.334 Info MultiplayerManager.cpp:1331: Received peer info for peer(11) username(daniel34).
59.334 Info MultiplayerManager.cpp:1006: networkTick(1286642) mapTick(-1) received stateChanged peerID(11) oldState(Ready) newState(Connecting)
59.334 Info MultiplayerManager.cpp:1331: Received peer info for peer(11) username(daniel34).
59.334 Info MultiplayerManager.cpp:1006: networkTick(1286642) mapTick(-1) received stateChanged peerID(11) oldState(Connecting) newState(Connecting)
59.334 Info MultiplayerManager.cpp:1006: networkTick(1286642) mapTick(-1) received stateChanged peerID(11) oldState(Connecting) newState(VerifyingConnection)
59.544 Info MultiplayerManager.cpp:869: networkTick(1286655) mapTick(-1) changing state from(VerifyingConnection) to(ConnectedWaitingForMap)
59.733 Info MultiplayerManager.cpp:1006: networkTick(1286667) mapTick(-1) received stateChanged peerID(11) oldState(VerifyingConnection) newState(ConnectedWaitingForMap)
59.943 Info MultiplayerManager.cpp:1006: networkTick(1286679) mapTick(-1) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
60.364 Info MultiplayerManager.cpp:1006: networkTick(1286704) mapTick(-1) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
60.364 Info MultiplayerManager.cpp:1006: networkTick(1286704) mapTick(-1) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
61.587 Info MultiplayerManager.cpp:1006: networkTick(1286778) mapTick(-1) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
61.587 Downloading file C:/Users\Daniel\AppData\Roaming\Factorio\temp\mp-download.zip (11778257 B, 23417 blocks)
61.590 Info MultiplayerManager.cpp:869: networkTick(1286778) mapTick(-1) changing state from(ConnectedWaitingForMap) to(ConnectedDownloadingMap)
61.590 Info NetworkInputHandler.cpp:40: mapTick(11159866) networkTick(1286778) initialized NetworkInputHandler local peer(11).
61.590 Info NetworkInputHandler.cpp:491: expectedMapTick(11159866) adding peer(0).
61.591 Info Synchronizer.cpp:407: NetworkTick(1286778) mapTick(11159866) applying 18 pending tickClosures
61.591 Info NetworkInputHandler.cpp:570: assigning playerIndex(255) to peer(0)
61.591 Info NetworkInputHandler.cpp:570: assigning playerIndex(255) to peer(11)
61.591 Info MultiplayerManager.cpp:1518: networkTick(1286778) mapTick(-1) adding mapAlignTask(SendPlayerJoinGameAlignTask)
61.797 Info MultiplayerManager.cpp:1006: networkTick(1286790) mapTick(-1) received stateChanged peerID(11) oldState(ConnectedWaitingForMap) newState(ConnectedDownloadingMap)
67.616 Info TransferTarget.cpp:260: Received transfer block 1714 that we didn't request from peer 0
67.619 Info TransferTarget.cpp:260: Received transfer block 1715 that we didn't request from peer 0
67.664 Info TransferTarget.cpp:260: Received transfer block 1716 that we didn't request from peer 0
69.412 Info TransferTarget.cpp:260: Received transfer block 2041 that we didn't request from peer 0
69.412 Info TransferTarget.cpp:260: Received transfer block 2042 that we didn't request from peer 0
69.452 Info TransferTarget.cpp:260: Received transfer block 2045 that we didn't request from peer 0
69.453 Info TransferTarget.cpp:260: Received transfer block 2044 that we didn't request from peer 0
69.454 Info TransferTarget.cpp:260: Received transfer block 2043 that we didn't request from peer 0
69.711 Info TransferTarget.cpp:260: Received transfer block 2079 that we didn't request from peer 0
69.730 Info TransferTarget.cpp:260: Received transfer block 2082 that we didn't request from peer 0
69.731 Info TransferTarget.cpp:260: Received transfer block 2081 that we didn't request from peer 0
69.751 Info TransferTarget.cpp:260: Received transfer block 2088 that we didn't request from peer 0
[[ removed another 300+ lines like this due to posting size limitations ]]
180.686 Info TransferTarget.cpp:260: Received transfer block 20329 that we didn't request from peer 0
180.688 Info TransferTarget.cpp:260: Received transfer block 20328 that we didn't request from peer 0
180.690 Info TransferTarget.cpp:260: Received transfer block 20327 that we didn't request from peer 0
182.351 Info TransferTarget.cpp:260: Received transfer block 20595 that we didn't request from peer 0
183.532 Info TransferTarget.cpp:260: Received transfer block 20795 that we didn't request from peer 0
183.579 Info TransferTarget.cpp:260: Received transfer block 20803 that we didn't request from peer 0
183.580 Info TransferTarget.cpp:260: Received transfer block 20802 that we didn't request from peer 0
183.581 Info TransferTarget.cpp:260: Received transfer block 20801 that we didn't request from peer 0
183.584 Info TransferTarget.cpp:260: Received transfer block 20800 that we didn't request from peer 0
183.585 Info TransferTarget.cpp:260: Received transfer block 20799 that we didn't request from peer 0
183.586 Info TransferTarget.cpp:260: Received transfer block 20798 that we didn't request from peer 0
183.588 Info TransferTarget.cpp:260: Received transfer block 20797 that we didn't request from peer 0
183.590 Info TransferTarget.cpp:260: Received transfer block 20796 that we didn't request from peer 0
184.357 Info TransferTarget.cpp:260: Received transfer block 20928 that we didn't request from peer 0
184.400 Info TransferTarget.cpp:260: Received transfer block 20930 that we didn't request from peer 0
184.401 Info TransferTarget.cpp:260: Received transfer block 20929 that we didn't request from peer 0
192.545 Info TransferTarget.cpp:260: Received transfer block 22627 that we didn't request from peer 0
192.549 Info TransferTarget.cpp:260: Received transfer block 22628 that we didn't request from peer 0
192.560 Info TransferTarget.cpp:260: Received transfer block 22631 that we didn't request from peer 0
192.561 Info TransferTarget.cpp:260: Received transfer block 22632 that we didn't request from peer 0
192.568 Info TransferTarget.cpp:260: Received transfer block 22629 that we didn't request from peer 0
192.599 Info TransferTarget.cpp:260: Received transfer block 22637 that we didn't request from peer 0
192.600 Info TransferTarget.cpp:260: Received transfer block 22636 that we didn't request from peer 0
192.601 Info TransferTarget.cpp:260: Received transfer block 22635 that we didn't request from peer 0
192.601 Info TransferTarget.cpp:260: Received transfer block 22634 that we didn't request from peer 0
192.602 Info TransferTarget.cpp:260: Received transfer block 22633 that we didn't request from peer 0
196.891 Finished download (135 s, 12 MB, 87.1 kB/s)
196.892 Info MultiplayerManager.cpp:1065: networkTick(1294762) mapTick(-1) map download finished creating scenario
196.892 Info MultiplayerManager.cpp:869: networkTick(1294762) mapTick(-1) changing state from(ConnectedDownloadingMap) to(ConnectedLoadingMap)
196.894 Loading map C:/Users\Daniel\AppData\Roaming\Factorio\temp\mp-download.zip
197.046 Info Scenario.cpp:123: Map version 0.12.22-0
197.113 Info MultiplayerManager.cpp:1006: networkTick(1294774) mapTick(-1) received stateChanged peerID(11) oldState(ConnectedDownloadingMap) newState(ConnectedLoadingMap)
197.295 Info MultiplayerManager.cpp:1006: networkTick(1294786) mapTick(-1) received stateChanged peerID(0) oldState(InGameSendingMap) newState(InGameWaitingForOthers)
198.140 Info MultiplayerManager.cpp:869: networkTick(1294836) mapTick(11159866) changing state from(ConnectedLoadingMap) to(InGameWaitingForOthers)
198.708 Info MultiplayerManager.cpp:1006: networkTick(1294848) mapTick(11159866) received stateChanged peerID(11) oldState(ConnectedLoadingMap) newState(InGameWaitingForOthers)
198.708 Info MultiplayerManager.cpp:1177: networkTick(1294848) mapTick(11159866) stopping mapAlign
198.708 Info MultiplayerManager.cpp:1670: performing map align task (SendPlayerJoinGameAlignTask)
198.708 Info MultiplayerManager.cpp:869: networkTick(1294848) mapTick(11159866) changing state from(InGameWaitingForOthers) to(InGame)
198.708 Info MultiplayerManager.cpp:1492: networkTick(1294848) mapTick(11159866) peerID(11) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(11) state(InGameWaitingForOthers) mapAlignTick(-1))
)
198.709 Info MultiplayerManager.cpp:1006: networkTick(1294860) mapTick(11159866) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
198.709 Info MultiplayerManager.cpp:1006: networkTick(1294860) mapTick(11159866) received stateChanged peerID(11) oldState(InGameWaitingForOthers) newState(InGame)
199.015 Info NetworkInputHandler.cpp:712: mapTick(11159881) networkTick(1294874) connecting to player(daniel34).
199.015 Info NetworkInputHandler.cpp:570: assigning playerIndex(0) to peer(11)
199.015 Info GameActionHandler.cpp:1940: MapTick(11159881) processed PlayerJoinGame peerID(11) playerIndex(0) mode(connect)
199.262 Error NetworkInputHandler.cpp:343: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(11159880) peer(11) testCrc(-1080939878) testCrcPeerID(0) currentCrc(1403630597)
199.262 Info NetworkInputHandler.cpp:419: Desync specification: Reference CRC = 3214027418, reference peers = {0}; desynced CRC = 1403630597, desynced peers = {11 (daniel34)}
199.262 Info MultiplayerManager.cpp:869: networkTick(1294885) mapTick(11159895) changing state from(InGame) to(Desynced)
199.507 Info MultiplayerManager.cpp:1006: networkTick(1294897) mapTick(11159895) received stateChanged peerID(11) oldState(InGame) newState(Desynced)
199.507 Info MultiplayerManager.cpp:869: networkTick(1294897) mapTick(11159895) changing state from(Desynced) to(DesyncedAligning)
199.507 Info MultiplayerManager.cpp:1604: networkTick(1294897) mapTick(11159895) starting mapAlign
199.706 Info MultiplayerManager.cpp:1006: networkTick(1294909) mapTick(11159895) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
199.706 Info MultiplayerManager.cpp:1006: networkTick(1294909) mapTick(11159895) received stateChanged peerID(11) oldState(Desynced) newState(DesyncedAligning)
199.706 Info MultiplayerManager.cpp:1538: networkTick(1294909) mapTick(11159895) sending mapAlignAction
199.901 Info MultiplayerManager.cpp:1225: networkTick(1294921) mapTick(11159895) received MapAlignAction from peer(0), mapAlignTick(11159900) appliedTickClosuresCount(25)
199.901 Info MultiplayerManager.cpp:1225: networkTick(1294921) mapTick(11159895) received MapAlignAction from peer(11), mapAlignTick(11159896) appliedTickClosuresCount(0)
199.901 Info MultiplayerManager.cpp:1620: networkTick(1294922) mapTick(11159895) mapAlign finished targetTick(11159900) updating map and running the tasks ...
199.901 Info MultiplayerManager.cpp:1492: networkTick(1294922) mapTick(11159895) peerID(11) fullStateLog: local state(DesyncedAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(11159900))
((peerID(11) state(DesyncedAligning) mapAlignTick(11159896))
)
199.912 Info MultiplayerManager.cpp:1639: maptick(11159900) crc before applying all ticks: 260569251
199.912 Info NetworkInputHandler.cpp:632: applyAllTickClosures: expectedMapTick(11159900) lastTickApplied(11159885) referenceMapTick(11159900) networkTick(1294922)
199.912 Info MultiplayerManager.cpp:1647: maptick(11159900) crc after applying all ticks: 260569251
199.912 Info MultiplayerManager.cpp:869: networkTick(1294922) mapTick(11159900) changing state from(DesyncedAligning) to(DesyncedAligned)
200.097 Info MultiplayerManager.cpp:1006: networkTick(1294934) mapTick(11159900) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
200.097 Info MultiplayerManager.cpp:1006: networkTick(1294934) mapTick(11159900) received stateChanged peerID(11) oldState(DesyncedAligning) newState(DesyncedAligned)
200.097 Info MultiplayerManager.cpp:1834: NetworkTick(1294934) reconnecting after map desync.
200.097 Info MultiplayerManager.cpp:1981: NetworkTick(1294934) MapTick(11159900) disconnecting player(0) from the user.
200.107 Info MultiplayerManager.cpp:869: networkTick(1294934) mapTick(11159900) changing state from(DesyncedAligned) to(DesyncedCreatingReport)
200.296 Info MultiplayerManager.cpp:1006: networkTick(1294946) mapTick(11159900) received stateChanged peerID(11) oldState(DesyncedAligned) newState(DesyncedCreatingReport)
201.387 Info MultiplayerManager.cpp:869: networkTick(1295011) mapTick(-1) changing state from(DesyncedCreatingReport) to(DesyncedWaitingForMap)
201.419 Info NetworkInputHandler.cpp:729: mapTick(11159900) networkTick(1295012) disconnecting from player.
201.966 Info MultiplayerManager.cpp:1006: networkTick(1295023) mapTick(-1) received stateChanged peerID(11) oldState(DesyncedCreatingReport) newState(DesyncedWaitingForMap)
201.967 Info MultiplayerManager.cpp:1006: networkTick(1295035) mapTick(-1) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
203.054 Info MultiplayerManager.cpp:1006: networkTick(1295092) mapTick(-1) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
203.054 Downloading file C:/Users\Daniel\AppData\Roaming\Factorio\temp\mp-download.zip (11780138 B, 23420 blocks)
203.056 Info MultiplayerManager.cpp:869: networkTick(1295092) mapTick(-1) changing state from(DesyncedWaitingForMap) to(DesyncedDownloadingMap)
203.056 Info NetworkInputHandler.cpp:40: mapTick(11159900) networkTick(1295092) initialized NetworkInputHandler local peer(11).
203.056 Info NetworkInputHandler.cpp:491: expectedMapTick(11159900) adding peer(0).
203.056 Info Synchronizer.cpp:407: NetworkTick(1295092) mapTick(11159900) applying 18 pending tickClosures
203.056 Info NetworkInputHandler.cpp:570: assigning playerIndex(255) to peer(0)
203.056 Info NetworkInputHandler.cpp:570: assigning playerIndex(255) to peer(11)
203.056 Info MultiplayerManager.cpp:1518: networkTick(1295092) mapTick(-1) adding mapAlignTask(SendPlayerJoinGameAlignTask)
203.268 Info MultiplayerManager.cpp:1006: networkTick(1295104) mapTick(-1) received stateChanged peerID(11) oldState(DesyncedWaitingForMap) newState(DesyncedDownloadingMap)
206.420 Info TransferTarget.cpp:260: Received transfer block 839 that we didn't request from peer 0
207.384 Info TransferTarget.cpp:260: Received transfer block 995 that we didn't request from peer 0
209.657 Info TransferTarget.cpp:260: Received transfer block 1392 that we didn't request from peer 0
209.694 Info TransferTarget.cpp:260: Received transfer block 1394 that we didn't request from peer 0
209.710 Info TransferTarget.cpp:260: Received transfer block 1395 that we didn't request from peer 0
209.710 Info TransferTarget.cpp:260: Received transfer block 1393 that we didn't request from peer 0
210.378 Info TransferTarget.cpp:260: Received transfer block 1510 that we didn't request from peer 0
210.426 Info TransferTarget.cpp:260: Received transfer block 1516 that we didn't request from peer 0
210.427 Info TransferTarget.cpp:260: Received transfer block 1515 that we didn't request from peer 0
210.428 Info TransferTarget.cpp:260: Received transfer block 1514 that we didn't request from peer 0
210.430 Info TransferTarget.cpp:260: Received transfer block 1513 that we didn't request from peer 0
210.430 Info TransferTarget.cpp:260: Received transfer block 1512 that we didn't request from peer 0
210.431 Info TransferTarget.cpp:260: Received transfer block 1511 that we didn't request from peer 0
210.438 Info TransferTarget.cpp:260: Received transfer block 1517 that we didn't request from peer 0
210.628 Info TransferTarget.cpp:260: Received transfer block 1539 that we didn't request from peer 0
210.674 Info TransferTarget.cpp:260: Received transfer block 1543 that we didn't request from peer 0
210.676 Info TransferTarget.cpp:260: Received transfer block 1542 that we didn't request from peer 0
210.677 Info TransferTarget.cpp:260: Received transfer block 1541 that we didn't request from peer 0
210.678 Info TransferTarget.cpp:260: Received transfer block 1540 that we didn't request from peer 0
210.685 Info TransferTarget.cpp:260: Received transfer block 1545 that we didn't request from peer 0
210.688 Info TransferTarget.cpp:260: Received transfer block 1544 that we didn't request from peer 0
211.667 Info TransferTarget.cpp:260: Received transfer block 1675 that we didn't request from peer 0
211.746 Info TransferTarget.cpp:260: Received transfer block 1681 that we didn't request from peer 0
211.747 Info TransferTarget.cpp:260: Received transfer block 1680 that we didn't request from peer 0
211.748 Info TransferTarget.cpp:260: Received transfer block 1679 that we didn't request from peer 0
211.749 Info TransferTarget.cpp:260: Received transfer block 1678 that we didn't request from peer 0
211.750 Info TransferTarget.cpp:260: Received transfer block 1677 that we didn't request from peer 0
211.752 Info TransferTarget.cpp:260: Received transfer block 1676 that we didn't request from peer 0
211.756 Info TransferTarget.cpp:260: Received transfer block 1682 that we didn't request from peer 0
212.272 Info TransferTarget.cpp:260: Received transfer block 1738 that we didn't request from peer 0
212.296 Info TransferTarget.cpp:260: Received transfer block 1740 that we didn't request from peer 0
212.297 Info TransferTarget.cpp:260: Received transfer block 1739 that we didn't request from peer 0
212.307 Info TransferTarget.cpp:260: Received transfer block 1744 that we didn't request from peer 0
212.308 Info TransferTarget.cpp:260: Received transfer block 1743 that we didn't request from peer 0
212.310 Info TransferTarget.cpp:260: Received transfer block 1742 that we didn't request from peer 0
212.311 Info TransferTarget.cpp:260: Received transfer block 1741 that we didn't request from peer 0
214.938 Info TransferTarget.cpp:260: Received transfer block 2088 that we didn't request from peer 0
217.630 Info TransferTarget.cpp:260: Received transfer block 2470 that we didn't request from peer 0
220.848 Info TransferTarget.cpp:260: Received transfer block 2906 that we didn't request from peer 0
220.898 Info TransferTarget.cpp:260: Received transfer block 2914 that we didn't request from peer 0
220.899 Info TransferTarget.cpp:260: Received transfer block 2913 that we didn't request from peer 0
220.900 Info TransferTarget.cpp:260: Received transfer block 2912 that we didn't request from peer 0
220.901 Info TransferTarget.cpp:260: Received transfer block 2911 that we didn't request from peer 0
220.902 Info TransferTarget.cpp:260: Received transfer block 2910 that we didn't request from peer 0
220.903 Info TransferTarget.cpp:260: Received transfer block 2909 that we didn't request from peer 0
220.904 Info TransferTarget.cpp:260: Received transfer block 2908 that we didn't request from peer 0
220.904 Info TransferTarget.cpp:260: Received transfer block 2907 that we didn't request from peer 0
221.050 Info TransferTarget.cpp:260: Received transfer block 2935 that we didn't request from peer 0
221.090 Info TransferTarget.cpp:260: Received transfer block 2937 that we didn't request from peer 0
221.092 Info TransferTarget.cpp:260: Received transfer block 2936 that we didn't request from peer 0
224.857 Info TransferTarget.cpp:260: Received transfer block 3559 that we didn't request from peer 0
224.858 Info TransferTarget.cpp:260: Received transfer block 3560 that we didn't request from peer 0
224.925 Info TransferTarget.cpp:260: Received transfer block 3567 that we didn't request from peer 0
224.925 Info TransferTarget.cpp:260: Received transfer block 3566 that we didn't request from peer 0
224.926 Info TransferTarget.cpp:260: Received transfer block 3565 that we didn't request from peer 0
224.928 Info TransferTarget.cpp:260: Received transfer block 3564 that we didn't request from peer 0
224.928 Info TransferTarget.cpp:260: Received transfer block 3563 that we didn't request from peer 0
224.929 Info TransferTarget.cpp:260: Received transfer block 3562 that we didn't request from peer 0
224.930 Info TransferTarget.cpp:260: Received transfer block 3561 that we didn't request from peer 0
228.217 Info TransferTarget.cpp:260: Received transfer block 4188 that we didn't request from peer 0
228.219 Info TransferTarget.cpp:260: Received transfer block 4189 that we didn't request from peer 0
239.074 Info TransferTarget.cpp:260: Received transfer block 6533 that we didn't request from peer 0
239.074 Info TransferTarget.cpp:260: Received transfer block 6534 that we didn't request from peer 0
239.110 Info TransferTarget.cpp:260: Received transfer block 6538 that we didn't request from peer 0
239.112 Info TransferTarget.cpp:260: Received transfer block 6537 that we didn't request from peer 0
239.112 Info TransferTarget.cpp:260: Received transfer block 6536 that we didn't request from peer 0
239.113 Info TransferTarget.cpp:260: Received transfer block 6535 that we didn't request from peer 0
259.610 Info MultiplayerManager.cpp:156: NetworkTick(1298475) quitting multiplayer connection.
259.610 Info MultiplayerManager.cpp:869: networkTick(1298475) mapTick(-1) changing state from(DesyncedDownloadingMap) to(Disconnected)
260.655 Info Router.cpp:556: Router peerID(11) shutting down.
260.655 Info WindowsUDPSocket.cpp:160: Socket closed
260.655 Info Router.cpp:582: Router state -> Disconnected
260.780 Goodbye
21706.609 Connection request from 91.119.17.185:60299
21706.609 Info Router.cpp:647: NetworkTick(1286618) replying to connectionRequest for address(91.119.17.185:60299).
21706.809 Info Router.cpp:799: networkTick(1286630) adding peer(11) address(91.119.17.185:60299) sending connectionAccept(true)
21706.809 Info Router.cpp:670: networkTick(1286630) sending connectionAccept to address(91.119.17.185:60299) connectionRequestID(24958) newPeerID(11)
21706.809 Info Synchronizer.cpp:494: networkTick(1286630) adding peer(11) success(true).
21707.076 Info MultiplayerManager.cpp:1331: Received peer info for peer(0) username(<server>).
21707.076 Info MultiplayerManager.cpp:1006: networkTick(1286642) mapTick(11159864) received stateChanged peerID(0) oldState(InGame) newState(InGame)
21707.076 Info MultiplayerManager.cpp:1331: Received peer info for peer(11) username(daniel34).
21707.076 Info MultiplayerManager.cpp:1006: networkTick(1286642) mapTick(11159864) received stateChanged peerID(11) oldState(Ready) newState(Connecting)
21707.076 Info NetworkInputHandler.cpp:491: expectedMapTick(11159864) adding peer(11).
21707.076 Info MultiplayerManager.cpp:1331: Received peer info for peer(11) username(daniel34).
21707.076 Info MultiplayerManager.cpp:1006: networkTick(1286642) mapTick(11159864) received stateChanged peerID(11) oldState(Connecting) newState(Connecting)
21707.076 Info MultiplayerManager.cpp:1006: networkTick(1286642) mapTick(11159864) received stateChanged peerID(11) oldState(Connecting) newState(VerifyingConnection)
21707.492 Info MultiplayerManager.cpp:1006: networkTick(1286667) mapTick(11159865) received stateChanged peerID(11) oldState(VerifyingConnection) newState(ConnectedWaitingForMap)
21707.493 Info MultiplayerManager.cpp:869: networkTick(1286667) mapTick(11159865) changing state from(InGame) to(InGameAligning)
21707.493 Info MultiplayerManager.cpp:1604: networkTick(1286667) mapTick(11159865) starting mapAlign
21707.692 Info MultiplayerManager.cpp:1006: networkTick(1286679) mapTick(11159865) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
21707.693 Info MultiplayerManager.cpp:1538: networkTick(1286679) mapTick(11159865) sending mapAlignAction
21707.892 Info MultiplayerManager.cpp:1225: networkTick(1286691) mapTick(11159865) received MapAlignAction from peer(0), mapAlignTick(11159866) appliedTickClosuresCount(0)
21707.893 Info MultiplayerManager.cpp:1620: networkTick(1286692) mapTick(11159865) mapAlign finished targetTick(11159866) updating map and running the tasks ...
21707.893 Info MultiplayerManager.cpp:1492: networkTick(1286692) mapTick(11159865) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(11159866))
((peerID(11) state(ConnectedWaitingForMap) mapAlignTick(-1))
)
21707.895 Info MultiplayerManager.cpp:1639: maptick(11159866) crc before applying all ticks: 1587151115
21707.895 Info NetworkInputHandler.cpp:632: applyAllTickClosures: expectedMapTick(11159866) lastTickApplied(11159851) referenceMapTick(11159866) networkTick(1286692)
21707.895 Info MultiplayerManager.cpp:1647: maptick(11159866) crc after applying all ticks: 1587151115
21707.895 Info MultiplayerManager.cpp:869: networkTick(1286692) mapTick(11159866) changing state from(InGameAligning) to(InGameAligned)
21707.895 Info MultiplayerManager.cpp:869: networkTick(1286692) mapTick(11159866) changing state from(InGameAligned) to(InGameSavingMap)
21708.109 Info MultiplayerManager.cpp:1006: networkTick(1286704) mapTick(11159866) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
21708.109 Info MultiplayerManager.cpp:1006: networkTick(1286704) mapTick(11159866) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
21709.126 Info MultiplayerManager.cpp:869: networkTick(1286766) mapTick(11159866) changing state from(InGameSavingMap) to(InGameSendingMap)
21709.167 Info MultiplayerManager.cpp:1106: MapTick(11159866) Serving map(/factorio/temp/mp-download.zip) for peer(11) size(11778257) crc(-1759074479)
21709.342 Info MultiplayerManager.cpp:1006: networkTick(1286778) mapTick(11159866) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
21709.542 Info MultiplayerManager.cpp:1006: networkTick(1286790) mapTick(11159866) received stateChanged peerID(11) oldState(ConnectedWaitingForMap) newState(ConnectedDownloadingMap)
21844.937 Info MultiplayerManager.cpp:1006: networkTick(1294774) mapTick(11159866) received stateChanged peerID(11) oldState(ConnectedDownloadingMap) newState(ConnectedLoadingMap)
21844.937 Canceling serving for peer 11
21844.937 Info MultiplayerManager.cpp:869: networkTick(1294774) mapTick(11159866) changing state from(InGameSendingMap) to(InGameWaitingForOthers)
21845.137 Info MultiplayerManager.cpp:1006: networkTick(1294786) mapTick(11159866) received stateChanged peerID(0) oldState(InGameSendingMap) newState(InGameWaitingForOthers)
21846.170 Info MultiplayerManager.cpp:1006: networkTick(1294848) mapTick(11159866) received stateChanged peerID(11) oldState(ConnectedLoadingMap) newState(InGameWaitingForOthers)
21846.170 Info MultiplayerManager.cpp:1177: networkTick(1294848) mapTick(11159866) stopping mapAlign
21846.170 Info MultiplayerManager.cpp:869: networkTick(1294848) mapTick(11159866) changing state from(InGameWaitingForOthers) to(InGame)
21846.170 Info MultiplayerManager.cpp:1492: networkTick(1294848) mapTick(11159866) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(11) state(InGameWaitingForOthers) mapAlignTick(-1))
)
21846.687 Info MultiplayerManager.cpp:1006: networkTick(1294860) mapTick(11159881) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
21846.687 Info MultiplayerManager.cpp:1006: networkTick(1294860) mapTick(11159881) received stateChanged peerID(11) oldState(InGameWaitingForOthers) newState(InGame)
21846.987 Info NetworkInputHandler.cpp:570: assigning playerIndex(0) to peer(11)
21846.987 Info GameActionHandler.cpp:1940: MapTick(11159881) processed PlayerJoinGame peerID(11) playerIndex(0) mode(connect)
21847.222 Error NetworkInputHandler.cpp:343: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(11159880) peer(11) testCrc(-1080939878) testCrcPeerID(0) currentCrc(1403630597)
21847.222 Info NetworkInputHandler.cpp:419: Desync specification: Reference CRC = 3214027418, reference peers = {0}; desynced CRC = 1403630597, desynced peers = {11 (daniel34)}
21847.370 Info MultiplayerManager.cpp:1006: networkTick(1294897) mapTick(11159899) received stateChanged peerID(11) oldState(InGame) newState(Desynced)
21847.370 Info MultiplayerManager.cpp:869: networkTick(1294897) mapTick(11159899) changing state from(InGame) to(InGameAligning)
21847.370 Info MultiplayerManager.cpp:1604: networkTick(1294897) mapTick(11159899) starting mapAlign
21847.570 Info MultiplayerManager.cpp:1006: networkTick(1294909) mapTick(11159899) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
21847.570 Info MultiplayerManager.cpp:1006: networkTick(1294909) mapTick(11159899) received stateChanged peerID(11) oldState(Desynced) newState(DesyncedAligning)
21847.570 Info MultiplayerManager.cpp:1538: networkTick(1294909) mapTick(11159899) sending mapAlignAction
21847.770 Info MultiplayerManager.cpp:1225: networkTick(1294921) mapTick(11159899) received MapAlignAction from peer(0), mapAlignTick(11159900) appliedTickClosuresCount(0)
21847.770 Info MultiplayerManager.cpp:1225: networkTick(1294921) mapTick(11159899) received MapAlignAction from peer(11), mapAlignTick(11159896) appliedTickClosuresCount(33)
21847.770 Info MultiplayerManager.cpp:1620: networkTick(1294922) mapTick(11159899) mapAlign finished targetTick(11159900) updating map and running the tasks ...
21847.770 Info MultiplayerManager.cpp:1492: networkTick(1294922) mapTick(11159899) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(11159900))
((peerID(11) state(DesyncedAligning) mapAlignTick(11159896))
)
21847.773 Info MultiplayerManager.cpp:1639: maptick(11159900) crc before applying all ticks: 110450910
21847.773 Info NetworkInputHandler.cpp:632: applyAllTickClosures: expectedMapTick(11159900) lastTickApplied(11159885) referenceMapTick(11159900) networkTick(1294922)
21847.773 Info MultiplayerManager.cpp:1647: maptick(11159900) crc after applying all ticks: 110450910
21847.773 Info MultiplayerManager.cpp:869: networkTick(1294922) mapTick(11159900) changing state from(InGameAligning) to(InGameAligned)
21847.987 Info MultiplayerManager.cpp:1006: networkTick(1294934) mapTick(11159900) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
21847.987 Info MultiplayerManager.cpp:1006: networkTick(1294934) mapTick(11159900) received stateChanged peerID(11) oldState(DesyncedAligning) newState(DesyncedAligned)
21848.187 Info MultiplayerManager.cpp:1242: networkTick(1294946) mapTick(11159900) received playerReconnect
21848.187 Info MultiplayerManager.cpp:1981: NetworkTick(1294946) MapTick(11159900) disconnecting player(0) from the user.
21848.187 Info NetworkInputHandler.cpp:515: expectedMapTick(11159900) removing peer(11) success(true).
21848.187 Info NetworkInputHandler.cpp:491: expectedMapTick(11159900) adding peer(11).
21848.187 Info MultiplayerManager.cpp:1006: networkTick(1294946) mapTick(11159900) received stateChanged peerID(11) oldState(DesyncedAligned) newState(DesyncedCreatingReport)
21849.470 Info MultiplayerManager.cpp:1006: networkTick(1295023) mapTick(11159900) received stateChanged peerID(11) oldState(DesyncedCreatingReport) newState(DesyncedWaitingForMap)
21849.470 Info MultiplayerManager.cpp:869: networkTick(1295023) mapTick(11159900) changing state from(InGameAligned) to(InGameSavingMap)
21849.953 Info MultiplayerManager.cpp:1006: networkTick(1295035) mapTick(11159900) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
21850.720 Info MultiplayerManager.cpp:869: networkTick(1295080) mapTick(11159900) changing state from(InGameSavingMap) to(InGameSendingMap)
21850.765 Info MultiplayerManager.cpp:1106: MapTick(11159900) Serving map(/factorio/temp/mp-download.zip) for peer(11) size(11780138) crc(2004794338)
21850.937 Info MultiplayerManager.cpp:1006: networkTick(1295092) mapTick(11159900) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
21851.137 Info MultiplayerManager.cpp:1006: networkTick(1295104) mapTick(11159900) received stateChanged peerID(11) oldState(DesyncedWaitingForMap) newState(DesyncedDownloadingMap)
21901.785 Info MultiplayerManager.cpp:1492: networkTick(1298134) mapTick(11159900) peerID(0) fullStateLog: local state(InGameSendingMap) local peers(((peerID(0) state(InGameSendingMap) mapAlignTick(-1))
((peerID(11) state(DesyncedDownloadingMap) mapAlignTick(-1))
)
21909.001 Info Synchronizer.cpp:438: NetworkTick(1298487) peer(11) drop detection state(100/900).
21910.668 Info Synchronizer.cpp:438: NetworkTick(1298487) peer(11) drop detection state(200/900).
21912.334 Info Synchronizer.cpp:438: NetworkTick(1298487) peer(11) drop detection state(300/900).
21914.001 Info Synchronizer.cpp:438: NetworkTick(1298487) peer(11) drop detection state(400/900).
21915.667 Info Synchronizer.cpp:438: NetworkTick(1298487) peer(11) drop detection state(500/900).
21917.334 Info Synchronizer.cpp:438: NetworkTick(1298487) peer(11) drop detection state(600/900).
21919.001 Info Synchronizer.cpp:438: NetworkTick(1298487) peer(11) drop detection state(700/900).
21920.667 Info Synchronizer.cpp:438: NetworkTick(1298487) peer(11) drop detection state(800/900).
21922.334 Info Synchronizer.cpp:438: NetworkTick(1298487) peer(11) drop detection state(900/900).
21922.350 Info Synchronizer.cpp:584: NetworkTick(1298487) peer(11) peerHeartbeatsEmpty(true) is not responding, dropping.
21922.567 Info MultiplayerManager.cpp:1382: Peer dropout for peer (11) by peer (0) -- removing now
I killed the client at the second map download and the server after the attempt from KaZ which he did after me.
Full server log is attached.
From the server I only have one desync report, when the server desynced while 5 other players were on the map around midnight: (note that the servers timezone is GMT-5, i should really change that) http://188.165.250.160:34197/desync-rep ... -14.tar.gz [29 MB]
Since the server restart at 7am we only had one desync about 1 1/2 hours ago:
On a sidenote: There is no loading bar anymore when a new client connects, we had this problem with mods before, but not on vanilla. The game window just freezes. What's up with that?
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Wed Feb 10, 2016 9:26 pm
by posila
daniel34 wrote:I only have logs from the desync that happened immediately after joining as the only user on the server, no desync-report was created on client or server
Hmm, that is weird ... but thank you. Another approach we can try is to use replay from Leowchen's first desync report to try to reproduce the desync. Unfortunately, the replay crashes the game, so we need to fix that first.
daniel34 wrote:On a sidenote: There is no loading bar anymore when a new client connects, we had this problem with mods before, but not on vanilla. The game window just freezes. What's up with that?
I don't know, we will check it out, thanks for letting us know.
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Wed Feb 10, 2016 9:32 pm
by Kirk
Indeed, now that you mention it, we were missing the loading bar as well.
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Wed Feb 10, 2016 9:42 pm
by daniel34
posila wrote:
daniel34 wrote:I only have logs from the desync that happened immediately after joining as the only user on the server, no desync-report was created on client or server
Hmm, that is weird ... but thank you. Another approach we can try is to use replay from Leowchen's first desync report to try to reproduce the desync. Unfortunately, the replay crashes the game, so we need to fix that first.
I sometimes delete the replay before restarting the server after a crash to reduce file size (helps greatly on a public server with people constantly joining), this might be the reason.
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Thu Feb 11, 2016 1:58 am
by Loewchen
posila wrote:
daniel34 wrote:I only have logs from the desync that happened immediately after joining as the only user on the server, no desync-report was created on client or server
Hmm, that is weird ... but thank you. Another approach we can try is to use replay from Leowchen's first desync report to try to reproduce the desync. Unfortunately, the replay crashes the game, so we need to fix that first.
Reviewing my logs, it seems, that to actually create a report the map has to be downloaded again completely. But usually (and understandably) the player closes factorio when desyncing instantly, as he expects to be in a desync-loop anyway.
One of the five desync-report-logs even shows an instant desync, but the player disconnected a few seconds before the report for it got written....jöofajsdöofjaoiasroiherjhbbhzbt9 g6
Edit: Looking at the log from: desync-report-2016-02-09-23-11-06, I see:
4331.190 Info NetworkInputHandler.cpp:712: mapTick(10459244) networkTick(3465606) connecting to player(kaZ).
4331.191 Info NetworkInputHandler.cpp:570: assigning playerIndex(17) to peer(28)
4331.191 Info GameActionHandler.cpp:1940: MapTick(10459244) processed PlayerJoinGame peerID(28) playerIndex(17) mode(connect)
4333.873 Error NetworkInputHandler.cpp:343: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(10459320) peer(28) testCrc(-923531364) testCrcPeerID(0) currentCrc(-2143315178)
4333.873 Info NetworkInputHandler.cpp:419: Desync specification: Reference CRC = 3371435932, reference peers = {0, 13 (PandaGOD), 16 (Masterhots), 18 (Loewchen), 24 (judos), 25 (Joshuacoops)}; desynced CRC = 2151652118, desynced peers = {28 (kaZ)}
4333.873 Info MultiplayerManager.cpp:869: networkTick(3465700) mapTick(10459335) changing state from(InGame) to(Desynced)
2.7 seconds was still not fast enough?
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Thu Feb 11, 2016 6:01 am
by roy7
daniel34 wrote:
On a sidenote: There is no loading bar anymore when a new client connects, we had this problem with mods before, but not on vanilla. The game window just freezes. What's up with that?
Funny you mention this, I've never once seen a loading bar when people are connecting to a headless server. The game just freezes up for everyone until the map download is done, and you just learn from experience that is why the game has frozen. Showing players a loading bar would be awesome.
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Thu Feb 11, 2016 12:23 pm
by daniel34
Loewchen wrote:Reviewing my logs, it seems, that to actually create a report the map has to be downloaded again completely. But usually (and understandably) the player closes factorio when desyncing instantly, as he expects to be in a desync-loop anyway.
One of the five desync-report-logs even shows an instant desync, but the player disconnected a few seconds before the report for it got written....jöofajsdöofjaoiasroiherjhbbhzbt9 g6
After I made my post I checked other desync-logs again and you're right. Otherwise, how could a client include the two differing maps if he only has his map?
Right now I just tried to connect to my server and got an immediate desync. I waited until the map was downloaded again and the client created this desync report: http://188.165.250.160:34197/desync-rep ... -04-08.zip [38 MB], although the replay might be incomplete.
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Thu Feb 11, 2016 5:39 pm
by kovarex
I found an desync issue related to shooting while someone is connecting and fixed it in the upcoming 0.12.23, it might be related.
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Mon Feb 15, 2016 2:07 pm
by posila
Loewchen wrote:Looking at the log from: desync-report-2016-02-09-23-11-06, I see: ... 2.7 seconds was still not fast enough?
Thanks for pointing me to this I haven't inspected all the debug reports you sent, just the one in first post and the first one from the others you sent. Unfortunately desync-report-2016-02-09-23-11-06 did not yield any more clues about cause of the desyncs.
daniel34 wrote:Right now I just tried to connect to my server and got an immediate desync. I waited until the map was downloaded again and the client created this desync report: ..., although the replay might be incomplete.
In this desync report, we found some differences that don't depend on random number generator. It were machines near one of the beacons. The difference is pretty big and probably would get sync up in few seconds, so it makes me think Loewchen's reports are cause by something else than daniel's one.
Desynced state:
The attachment desync_15104961.jpg is no longer available
Reference state:
Reference state
reference_15104961.jpg (112.36 KiB) Viewed 26034 times
It looks like green circuit assembling machine was faster in desynchronised state than in reference state. We tried bunch of tests with beacon and speed and productivity modules, but didn't find any desyncs .
EDIT: Rseding had another idea for test - order and immediately cancel deconstruction of the beacon. That finally caused a desync. Working on a fix now.
Re: [0.12.20] [kovarex] Desync in Multiplayer w/ headl. Server w/o mods.
Posted: Tue Feb 16, 2016 12:47 pm
by posila
Fixed several issues with deconstruction of beacon. Hopefully that fixes majority of desync issues everybody in this thread is experiencing.
Fix will be in 0.12.23. Thanks for all the reports and help with this issue!