Page 1 of 1

[0.13.1][Pending] Reliable desync on server join

Posted: Thu Jun 30, 2016 9:27 am
by Jerub
I have a reproducable reliable desync, using the headless server on our save game (imported from 0.12.35, used on 0.13.0, and now launching on 0.13.1).

We would see desyncs in a continuous loop for 5-10 minutes on first join, until it finally let a player on the game.

There are no mods in use.

Here is a log of what it looks like on the server side.

Code: Select all

   0.000 2016-06-30 09:15:22; Factorio 0.13.1 (Build 22864, linux64, headless)
   0.000 Operating system: Linux
   0.000 Not rotating logs.
   0.000 Program arguments: "/opt/factorio/bin/x64/factorio" "--latency-ms" "100" "--autosave-interval" "2" "--autosave-slots" "100" "--start-server" "/opt/factorio/saves/save.zip" 
   0.000 Read data path: /opt/factorio/data
   0.000 Write data path: /opt/factorio
   0.000 Binaries path: /opt/factorio/bin
   0.010 Running in headless mode
   0.012 Loading mod core 0.0.0 (data.lua)
   0.016 Loading mod base 0.13.1 (data.lua)
   0.115 Checksum for core: 1399339455
   0.115 Checksum for mod base: 2679425721
   0.492 Custom inputs active: 0
   0.493 Factorio initialised
   0.494 Info Router.cpp:563: Router peerID(65535) shutting down.
   0.494 Info Router.cpp:594: Router state -> Disconnected
   0.576 Info MultiplayerManager.cpp:996: networkTick(0) mapTick(-1) changing state from(Ready) to(PreparedToHostGame)
   0.576 Info MultiplayerManager.cpp:996: networkTick(0) mapTick(-1) changing state from(PreparedToHostGame) to(CreatingGame)
   0.577 Loading map /opt/factorio/saves/save.zip
   0.593 Info Scenario.cpp:129: Map version 0.13.0-93
   2.563 Applying migration: Base Mod: 2016-06-28_Factorio_0.13.0.lua
   2.572 Checksum for script /opt/factorio/temp/currently-playing/control.lua: 900459546
   2.670 Info PosixUDPSocket.cpp:50: Opening socket at port 34197
   2.670 Info Router.cpp:594: Router state -> Connected
   2.670 Info Synchronizer.cpp:56: NetworkTick(0) initialized Synchronizer local peer(0) latency(6).
   2.670 Hosting game at port 34197, peerID 0, session magic 1720
   2.670 Info MultiplayerManager.cpp:996: networkTick(0) mapTick(22780801) changing state from(CreatingGame) to(InGame)
   2.670 Info NetworkInputHandler.cpp:45: mapTick(22780801) networkTick(0) initialized NetworkInputHandler local peer(0).
   2.787 Info MultiplayerManager.cpp:1527: Received peer info for peer(0) username(<server>).
   2.787 Info MultiplayerManager.cpp:1143: networkTick(6) mapTick(22780801) received stateChanged peerID(0) oldState(Ready) newState(CreatingGame)
   2.787 Info MultiplayerManager.cpp:1143: networkTick(6) mapTick(22780801) received stateChanged peerID(0) oldState(CreatingGame) newState(InGame)
  20.898 Connection request(type(ConnectionRequest) applicationVersion(0.13.1 (Build 22864, linux64, headless)) activeMods((base 0.13.1) ) hasPassword(no) connectionRequestID(51719)) from(10.48.0.1:55261)
  20.898 Info Router.cpp:683: NetworkTick(1089) replying to connectionRequest for address(10.48.0.1:55261).
  21.030 Info Router.cpp:905: networkTick(1095) adding peer(1) address(10.48.0.1:55261) sending connectionAccept(true)
  21.030 Info Router.cpp:717: networkTick(1095) sending connectionAccept to address(10.48.0.1:55261) connectionRequestID(51719) newPeerID(1)
  21.030 Info Synchronizer.cpp:522: networkTick(1095) adding peer(1) success(true).
  21.545 Info MultiplayerManager.cpp:1527: Received peer info for peer(0) username(<server>).
  21.545 Info MultiplayerManager.cpp:1143: networkTick(1101) mapTick(22780809) received stateChanged peerID(0) oldState(InGame) newState(InGame)
  21.545 Info MultiplayerManager.cpp:1527: Received peer info for peer(1) username(IsoSchiz).
  21.545 Info MultiplayerManager.cpp:1143: networkTick(1101) mapTick(22780809) received stateChanged peerID(1) oldState(Ready) newState(Connecting)
  21.545 Info NetworkInputHandler.cpp:524: expectedMapTick(22780809) adding peer(1).
  21.545 Info MultiplayerManager.cpp:1527: Received peer info for peer(1) username(IsoSchiz).
  21.545 Info MultiplayerManager.cpp:1143: networkTick(1101) mapTick(22780809) received stateChanged peerID(1) oldState(Connecting) newState(Connecting)
  21.546 Info MultiplayerManager.cpp:1143: networkTick(1101) mapTick(22780809) received stateChanged peerID(1) oldState(Connecting) newState(VerifyingConnection)
  21.734 Info MultiplayerManager.cpp:1143: networkTick(1114) mapTick(22780810) received stateChanged peerID(1) oldState(VerifyingConnection) newState(ConnectedWaitingForMap)
  21.734 Info MultiplayerManager.cpp:996: networkTick(1114) mapTick(22780810) changing state from(InGame) to(InGameAligning)
  21.734 Info MultiplayerManager.cpp:1818: networkTick(1114) mapTick(22780810) starting mapAlign
  21.892 Info MultiplayerManager.cpp:1143: networkTick(1120) mapTick(22780810) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
  21.892 Info MultiplayerManager.cpp:1752: networkTick(1120) mapTick(22780810) sending mapAlignAction
  22.005 Info MultiplayerManager.cpp:1390: networkTick(1126) mapTick(22780810) received MapAlignAction from peer(0), mapAlignTick(22780811) appliedTickClosuresCount(0)
  22.005 Info MultiplayerManager.cpp:1834: networkTick(1127) mapTick(22780810) mapAlign finished targetTick(22780811) updating map and running the tasks ...
  22.005 Info MultiplayerManager.cpp:1706: networkTick(1127) mapTick(22780810) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(22780811))
((peerID(1) state(ConnectedWaitingForMap) mapAlignTick(-1))
)
  22.011 Info MultiplayerManager.cpp:1853: maptick(22780811) crc before applying all ticks: 2004588791
  22.011 Info NetworkInputHandler.cpp:670: applyAllTickClosures: expectedMapTick(22780811) lastTickApplied(22780802) referenceMapTick(22780811) networkTick(1127)
  22.011 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780803) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  22.011 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780804) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  22.011 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780805) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  22.011 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780806) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  22.011 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780807) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  22.011 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780808) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  22.011 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780809) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  22.011 Info MultiplayerManager.cpp:1861: maptick(22780811) crc after applying all ticks: 2004588791
  22.011 Info MultiplayerManager.cpp:996: networkTick(1127) mapTick(22780811) changing state from(InGameAligning) to(InGameAligned)
  22.012 Info MultiplayerManager.cpp:996: networkTick(1127) mapTick(22780811) changing state from(InGameAligned) to(InGameSavingMap)
  22.272 Info MultiplayerManager.cpp:1143: networkTick(1133) mapTick(22780811) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
  22.272 Info MultiplayerManager.cpp:1143: networkTick(1133) mapTick(22780811) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
  23.332 Info MultiplayerManager.cpp:996: networkTick(1157) mapTick(22780811) changing state from(InGameSavingMap) to(InGameSendingMap)
  23.385 Info MultiplayerManager.cpp:1260: MapTick(22780811) Serving map(/opt/factorio/temp/mp-download.zip) for peer(1) size(16407571) crc(71721498)
  23.385 Info MultiplayerManager.cpp:1143: networkTick(1163) mapTick(22780811) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
  23.801 Info MultiplayerManager.cpp:1143: networkTick(1169) mapTick(22780811) received stateChanged peerID(1) oldState(ConnectedWaitingForMap) newState(ConnectedDownloadingMap)
  40.354 Info MultiplayerManager.cpp:1143: networkTick(1624) mapTick(22780811) received stateChanged peerID(1) oldState(ConnectedDownloadingMap) newState(ConnectedLoadingMap)
  40.354 Canceling serving for peer 1
  40.355 Info MultiplayerManager.cpp:996: networkTick(1624) mapTick(22780811) changing state from(InGameSendingMap) to(InGameWaitingForOthers)
  40.355 Info MultiplayerManager.cpp:1143: networkTick(1630) mapTick(22780811) received stateChanged peerID(0) oldState(InGameSendingMap) newState(InGameWaitingForOthers)
  42.045 Info MultiplayerManager.cpp:1143: networkTick(1687) mapTick(22780811) received stateChanged peerID(1) oldState(ConnectedLoadingMap) newState(InGameWaitingForOthers)
  42.045 Info MultiplayerManager.cpp:1342: networkTick(1687) mapTick(22780811) stopping mapAlign
  42.045 Info MultiplayerManager.cpp:996: networkTick(1687) mapTick(22780811) changing state from(InGameWaitingForOthers) to(InGame)
  42.045 Info MultiplayerManager.cpp:1706: networkTick(1687) mapTick(22780811) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(1) state(InGameWaitingForOthers) mapAlignTick(-1))
)
  42.046 Info MultiplayerManager.cpp:1143: networkTick(1693) mapTick(22780811) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
  42.046 Info MultiplayerManager.cpp:1143: networkTick(1693) mapTick(22780811) received stateChanged peerID(1) oldState(InGameWaitingForOthers) newState(InGame)
  42.356 Info NetworkInputHandler.cpp:608: assigning playerIndex(1) to peer(1)
  42.356 Info GameActionHandler.cpp:2611: MapTick(22780820) processed PlayerJoinGame peerID(1) playerIndex(1) mode(connect)
  42.363 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780812) peer(1) testCrc(693642384) testCrcPeerID(0) currentCrc(-147287803)
  42.363 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 693642384, reference peers = {0}, desynced group = {CRC = 4147679493, peers = {1 (IsoSchiz)}}
  42.369 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780813) peer(1) testCrc(1799719226) testCrcPeerID(0) currentCrc(-1008375630)
  42.370 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 1799719226, reference peers = {0}, desynced group = {CRC = 3286591666, peers = {1 (IsoSchiz)}}
  42.376 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780814) peer(1) testCrc(-1544287112) testCrcPeerID(0) currentCrc(-2122021685)
  42.377 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 2750680184, reference peers = {0}, desynced group = {CRC = 2172945611, peers = {1 (IsoSchiz)}}
  42.385 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780815) peer(1) testCrc(-1546457840) testCrcPeerID(0) currentCrc(-2126329399)
  42.385 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 2748509456, reference peers = {0}, desynced group = {CRC = 2168637897, peers = {1 (IsoSchiz)}}
  42.391 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780816) peer(1) testCrc(-9296801) testCrcPeerID(0) currentCrc(699043185)
  42.392 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 4285670495, reference peers = {0}, desynced group = {CRC = 699043185, peers = {1 (IsoSchiz)}}
  42.398 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780817) peer(1) testCrc(-1499715173) testCrcPeerID(0) currentCrc(605352049)
  42.398 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 2795252123, reference peers = {0}, desynced group = {CRC = 605352049, peers = {1 (IsoSchiz)}}
  42.404 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780818) peer(1) testCrc(1012465975) testCrcPeerID(0) currentCrc(-1760884958)
  42.404 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 1012465975, reference peers = {0}, desynced group = {CRC = 2534082338, peers = {1 (IsoSchiz)}}
  42.405 Info MultiplayerManager.cpp:1143: networkTick(1716) mapTick(22780827) received stateChanged peerID(1) oldState(InGame) newState(Desynced)
  42.405 Info MultiplayerManager.cpp:996: networkTick(1716) mapTick(22780827) changing state from(InGame) to(InGameAligning)
  42.405 Info MultiplayerManager.cpp:1818: networkTick(1716) mapTick(22780827) starting mapAlign
  42.595 Info MultiplayerManager.cpp:1143: networkTick(1722) mapTick(22780827) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
  42.595 Info MultiplayerManager.cpp:1143: networkTick(1722) mapTick(22780827) received stateChanged peerID(1) oldState(Desynced) newState(DesyncedAligning)
  42.595 Info MultiplayerManager.cpp:1752: networkTick(1722) mapTick(22780827) sending mapAlignAction
  42.644 Info MultiplayerManager.cpp:1390: networkTick(1728) mapTick(22780827) received MapAlignAction from peer(0), mapAlignTick(22780828) appliedTickClosuresCount(0)
  42.644 Info MultiplayerManager.cpp:1390: networkTick(1728) mapTick(22780827) received MapAlignAction from peer(1), mapAlignTick(22780822) appliedTickClosuresCount(23)
  42.644 Info MultiplayerManager.cpp:1834: networkTick(1729) mapTick(22780827) mapAlign finished targetTick(22780828) updating map and running the tasks ...
  42.644 Info MultiplayerManager.cpp:1706: networkTick(1729) mapTick(22780827) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(22780828))
((peerID(1) state(DesyncedAligning) mapAlignTick(22780822))
)
  42.651 Info MultiplayerManager.cpp:1853: maptick(22780828) crc before applying all ticks: -1803209974
  42.651 Info NetworkInputHandler.cpp:670: applyAllTickClosures: expectedMapTick(22780828) lastTickApplied(22780819) referenceMapTick(22780828) networkTick(1729)
  42.651 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780820) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  42.651 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780820) inputActions((ChangeWalkingState) (CheckCRCHeuristic) ) ) for peer(1)
  42.651 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780821) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  42.651 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780822) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  42.651 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780823) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  42.651 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780824) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  42.651 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780825) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  42.651 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780826) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  42.652 Info MultiplayerManager.cpp:1861: maptick(22780828) crc after applying all ticks: -874620452
  42.652 Info MultiplayerManager.cpp:996: networkTick(1729) mapTick(22780828) changing state from(InGameAligning) to(InGameAligned)
  42.816 Info MultiplayerManager.cpp:1143: networkTick(1735) mapTick(22780828) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
  42.816 Info MultiplayerManager.cpp:1143: networkTick(1735) mapTick(22780828) received stateChanged peerID(1) oldState(DesyncedAligning) newState(DesyncedAligned)
  43.007 Info MultiplayerManager.cpp:1419: networkTick(1741) mapTick(22780828) received playerReconnect
  43.007 Info MultiplayerManager.cpp:2236: NetworkTick(1741) MapTick(22780828) disconnecting player(1) from the user.
  43.007 Info NetworkInputHandler.cpp:548: expectedMapTick(22780828) removing peer(1) success(true).
  43.007 Info NetworkInputHandler.cpp:524: expectedMapTick(22780828) adding peer(1).
  43.007 Info MultiplayerManager.cpp:1143: networkTick(1741) mapTick(22780828) received stateChanged peerID(1) oldState(DesyncedAligned) newState(DesyncedCreatingReport)
  44.990 Info MultiplayerManager.cpp:1143: networkTick(1806) mapTick(22780828) received stateChanged peerID(1) oldState(DesyncedCreatingReport) newState(DesyncedWaitingForMap)
  44.990 Info MultiplayerManager.cpp:996: networkTick(1806) mapTick(22780828) changing state from(InGameAligned) to(InGameSavingMap)
  44.990 Info MultiplayerManager.cpp:1143: networkTick(1812) mapTick(22780828) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
  46.380 Info MultiplayerManager.cpp:996: networkTick(1843) mapTick(22780828) changing state from(InGameSavingMap) to(InGameSendingMap)
  46.433 Info MultiplayerManager.cpp:1260: MapTick(22780828) Serving map(/opt/factorio/temp/mp-download.zip) for peer(1) size(16408141) crc(1492379601)
  46.433 Info MultiplayerManager.cpp:1143: networkTick(1849) mapTick(22780828) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
  46.552 Info MultiplayerManager.cpp:1143: networkTick(1855) mapTick(22780828) received stateChanged peerID(1) oldState(DesyncedWaitingForMap) newState(DesyncedDownloadingMap)
  64.824 Info MultiplayerManager.cpp:1143: networkTick(2365) mapTick(22780828) received stateChanged peerID(1) oldState(DesyncedDownloadingMap) newState(DesyncedLoadingMap)
  64.825 Canceling serving for peer 1
  64.825 Info MultiplayerManager.cpp:996: networkTick(2365) mapTick(22780828) changing state from(InGameSendingMap) to(InGameWaitingForOthers)
  64.825 Info MultiplayerManager.cpp:1143: networkTick(2371) mapTick(22780828) received stateChanged peerID(0) oldState(InGameSendingMap) newState(InGameWaitingForOthers)
  66.632 Info MultiplayerManager.cpp:1143: networkTick(2441) mapTick(22780828) received stateChanged peerID(1) oldState(DesyncedLoadingMap) newState(InGameWaitingForOthers)
  66.632 Info MultiplayerManager.cpp:1342: networkTick(2441) mapTick(22780828) stopping mapAlign
  66.632 Info MultiplayerManager.cpp:996: networkTick(2441) mapTick(22780828) changing state from(InGameWaitingForOthers) to(InGame)
  66.632 Info MultiplayerManager.cpp:1706: networkTick(2441) mapTick(22780828) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(1) state(InGameWaitingForOthers) mapAlignTick(-1))
)
  66.633 Info MultiplayerManager.cpp:1143: networkTick(2447) mapTick(22780828) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
  66.633 Info MultiplayerManager.cpp:1143: networkTick(2447) mapTick(22780828) received stateChanged peerID(1) oldState(InGameWaitingForOthers) newState(InGame)
  66.730 Info NetworkInputHandler.cpp:608: assigning playerIndex(1) to peer(1)
  66.731 Info GameActionHandler.cpp:2611: MapTick(22780837) processed PlayerJoinGame peerID(1) playerIndex(1) mode(connect)
  67.536 Info MultiplayerManager.cpp:1143: networkTick(2467) mapTick(22780837) received stateChanged peerID(1) oldState(InGame) newState(Desynced)
  67.536 Info MultiplayerManager.cpp:996: networkTick(2467) mapTick(22780837) changing state from(InGame) to(InGameAligning)
  67.536 Info MultiplayerManager.cpp:1818: networkTick(2467) mapTick(22780837) starting mapAlign
  67.536 Info MultiplayerManager.cpp:1143: networkTick(2473) mapTick(22780837) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
  67.536 Info MultiplayerManager.cpp:1143: networkTick(2473) mapTick(22780837) received stateChanged peerID(1) oldState(Desynced) newState(DesyncedAligning)
  67.536 Info MultiplayerManager.cpp:1752: networkTick(2473) mapTick(22780837) sending mapAlignAction
  67.619 Info MultiplayerManager.cpp:1390: networkTick(2479) mapTick(22780837) received MapAlignAction from peer(0), mapAlignTick(22780838) appliedTickClosuresCount(0)
  67.619 Info MultiplayerManager.cpp:1390: networkTick(2479) mapTick(22780837) received MapAlignAction from peer(1), mapAlignTick(22780839) appliedTickClosuresCount(16)
  67.619 Info MultiplayerManager.cpp:1834: networkTick(2480) mapTick(22780837) mapAlign finished targetTick(22780839) updating map and running the tasks ...
  67.619 Info MultiplayerManager.cpp:1706: networkTick(2480) mapTick(22780837) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(22780838))
((peerID(1) state(DesyncedAligning) mapAlignTick(22780839))
)
  67.630 Info MultiplayerManager.cpp:1853: maptick(22780839) crc before applying all ticks: -1395504616
  67.630 Info NetworkInputHandler.cpp:670: applyAllTickClosures: expectedMapTick(22780839) lastTickApplied(22780830) referenceMapTick(22780839) networkTick(2480)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780831) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780831) inputActions((CheckCRCHeuristic) ) ) for peer(1)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780832) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780832) inputActions((CheckCRCHeuristic) ) ) for peer(1)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780833) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780833) inputActions((CheckCRCHeuristic) ) ) for peer(1)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780834) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780834) inputActions((CheckCRCHeuristic) ) ) for peer(1)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780835) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780835) inputActions((CheckCRCHeuristic) ) ) for peer(1)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780836) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780836) inputActions((CheckCRCHeuristic) ) ) for peer(1)
  67.630 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780837) inputActions((CheckCRCHeuristic) ) ) for peer(1)
  67.631 Info MultiplayerManager.cpp:1861: maptick(22780839) crc after applying all ticks: -1395504616
  67.631 Info MultiplayerManager.cpp:996: networkTick(2480) mapTick(22780839) changing state from(InGameAligning) to(InGameAligned)
  67.732 Info MultiplayerManager.cpp:1143: networkTick(2486) mapTick(22780839) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
  67.732 Info MultiplayerManager.cpp:1143: networkTick(2486) mapTick(22780839) received stateChanged peerID(1) oldState(DesyncedAligning) newState(DesyncedAligned)
  67.841 Info MultiplayerManager.cpp:1419: networkTick(2492) mapTick(22780839) received playerReconnect
  67.841 Info MultiplayerManager.cpp:2236: NetworkTick(2492) MapTick(22780839) disconnecting player(1) from the user.
  67.841 Info NetworkInputHandler.cpp:548: expectedMapTick(22780839) removing peer(1) success(true).
  67.841 Info NetworkInputHandler.cpp:524: expectedMapTick(22780839) adding peer(1).
  67.841 Info MultiplayerManager.cpp:1143: networkTick(2492) mapTick(22780839) received stateChanged peerID(1) oldState(DesyncedAligned) newState(DesyncedCreatingReport)
  69.874 Info MultiplayerManager.cpp:1143: networkTick(2553) mapTick(22780839) received stateChanged peerID(1) oldState(DesyncedCreatingReport) newState(DesyncedWaitingForMap)
  69.874 Info MultiplayerManager.cpp:996: networkTick(2553) mapTick(22780839) changing state from(InGameAligned) to(InGameSavingMap)
  69.874 Info MultiplayerManager.cpp:1143: networkTick(2559) mapTick(22780839) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
  71.028 Info MultiplayerManager.cpp:996: networkTick(2575) mapTick(22780839) changing state from(InGameSavingMap) to(InGameSendingMap)
  71.081 Info MultiplayerManager.cpp:1260: MapTick(22780839) Serving map(/opt/factorio/temp/mp-download.zip) for peer(1) size(16412317) crc(-1360716145)
  71.081 Info MultiplayerManager.cpp:1143: networkTick(2581) mapTick(22780839) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
  71.154 Info MultiplayerManager.cpp:1143: networkTick(2587) mapTick(22780839) received stateChanged peerID(1) oldState(DesyncedWaitingForMap) newState(DesyncedDownloadingMap)
  87.426 Info MultiplayerManager.cpp:1143: networkTick(3109) mapTick(22780839) received stateChanged peerID(1) oldState(DesyncedDownloadingMap) newState(DesyncedLoadingMap)
  87.426 Canceling serving for peer 1
  87.426 Info MultiplayerManager.cpp:996: networkTick(3109) mapTick(22780839) changing state from(InGameSendingMap) to(InGameWaitingForOthers)
  87.777 Info MultiplayerManager.cpp:1143: networkTick(3115) mapTick(22780839) received stateChanged peerID(0) oldState(InGameSendingMap) newState(InGameWaitingForOthers)
  89.003 Info MultiplayerManager.cpp:1143: networkTick(3161) mapTick(22780839) received stateChanged peerID(1) oldState(DesyncedLoadingMap) newState(InGameWaitingForOthers)
  89.003 Info MultiplayerManager.cpp:1342: networkTick(3161) mapTick(22780839) stopping mapAlign
  89.003 Info MultiplayerManager.cpp:996: networkTick(3161) mapTick(22780839) changing state from(InGameWaitingForOthers) to(InGame)
  89.003 Info MultiplayerManager.cpp:1706: networkTick(3161) mapTick(22780839) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(1) state(InGameWaitingForOthers) mapAlignTick(-1))
)
  89.080 Info MultiplayerManager.cpp:1143: networkTick(3167) mapTick(22780845) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
  89.080 Info MultiplayerManager.cpp:1143: networkTick(3167) mapTick(22780845) received stateChanged peerID(1) oldState(InGameWaitingForOthers) newState(InGame)
  89.098 Info NetworkInputHandler.cpp:608: assigning playerIndex(1) to peer(1)
  89.098 Info GameActionHandler.cpp:2611: MapTick(22780848) processed PlayerJoinGame peerID(1) playerIndex(1) mode(connect)
  89.159 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780840) peer(1) testCrc(791511928) testCrcPeerID(0) currentCrc(-101373354)
  89.159 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 791511928, reference peers = {0}, desynced group = {CRC = 4193593942, peers = {1 (IsoSchiz)}}
  89.165 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780841) peer(1) testCrc(2132551117) testCrcPeerID(0) currentCrc(-959644365)
  89.165 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 2132551117, reference peers = {0}, desynced group = {CRC = 3335322931, peers = {1 (IsoSchiz)}}
  89.171 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780842) peer(1) testCrc(-1844153999) testCrcPeerID(0) currentCrc(-968621643)
  89.171 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 2450813297, reference peers = {0}, desynced group = {CRC = 3326345653, peers = {1 (IsoSchiz)}}
  89.177 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780843) peer(1) testCrc(-1633739511) testCrcPeerID(0) currentCrc(-892375603)
  89.177 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 2661227785, reference peers = {0}, desynced group = {CRC = 3402591693, peers = {1 (IsoSchiz)}}
  89.183 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780844) peer(1) testCrc(1633557039) testCrcPeerID(0) currentCrc(-1649505437)
  89.183 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 1633557039, reference peers = {0}, desynced group = {CRC = 2645461859, peers = {1 (IsoSchiz)}}
  89.214 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780845) peer(1) testCrc(-266204775) testCrcPeerID(0) currentCrc(-1536001699)
  89.214 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 4028762521, reference peers = {0}, desynced group = {CRC = 2758965597, peers = {1 (IsoSchiz)}}
  89.220 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780846) peer(1) testCrc(-2018427963) testCrcPeerID(0) currentCrc(-740209919)
  89.220 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 2276539333, reference peers = {0}, desynced group = {CRC = 3554757377, peers = {1 (IsoSchiz)}}
  89.226 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780847) peer(1) testCrc(-1302330079) testCrcPeerID(0) currentCrc(-1002373802)
  89.226 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 2992637217, reference peers = {0}, desynced group = {CRC = 3292593494, peers = {1 (IsoSchiz)}}
  89.384 Info MultiplayerManager.cpp:1143: networkTick(3179) mapTick(22780856) received stateChanged peerID(1) oldState(InGame) newState(Desynced)
  89.384 Info MultiplayerManager.cpp:996: networkTick(3179) mapTick(22780856) changing state from(InGame) to(InGameAligning)
  89.384 Info MultiplayerManager.cpp:1818: networkTick(3179) mapTick(22780856) starting mapAlign
  89.384 Info MultiplayerManager.cpp:1143: networkTick(3185) mapTick(22780856) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
  89.384 Info MultiplayerManager.cpp:1143: networkTick(3185) mapTick(22780856) received stateChanged peerID(1) oldState(Desynced) newState(DesyncedAligning)
  89.384 Info MultiplayerManager.cpp:1752: networkTick(3185) mapTick(22780856) sending mapAlignAction
  89.476 Info MultiplayerManager.cpp:1390: networkTick(3191) mapTick(22780856) received MapAlignAction from peer(0), mapAlignTick(22780857) appliedTickClosuresCount(0)
  89.476 Info MultiplayerManager.cpp:1390: networkTick(3191) mapTick(22780856) received MapAlignAction from peer(1), mapAlignTick(22780850) appliedTickClosuresCount(24)
  89.476 Info MultiplayerManager.cpp:1834: networkTick(3192) mapTick(22780856) mapAlign finished targetTick(22780857) updating map and running the tasks ...
  89.476 Info MultiplayerManager.cpp:1706: networkTick(3192) mapTick(22780856) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(22780857))
((peerID(1) state(DesyncedAligning) mapAlignTick(22780850))
)
  89.483 Info MultiplayerManager.cpp:1853: maptick(22780857) crc before applying all ticks: -483089958
  89.483 Info NetworkInputHandler.cpp:670: applyAllTickClosures: expectedMapTick(22780857) lastTickApplied(22780848) referenceMapTick(22780857) networkTick(3192)
  89.483 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780849) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  89.483 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780850) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  89.483 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780851) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  89.483 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780852) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  89.483 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780853) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  89.483 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780854) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  89.483 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780855) inputActions((CheckCRCHeuristic) ) ) for peer(0)
  89.484 Info MultiplayerManager.cpp:1861: maptick(22780857) crc after applying all ticks: -483089958
  89.484 Info MultiplayerManager.cpp:996: networkTick(3192) mapTick(22780857) changing state from(InGameAligning) to(InGameAligned)
  89.859 Info MultiplayerManager.cpp:1143: networkTick(3198) mapTick(22780857) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
  89.859 Info MultiplayerManager.cpp:1143: networkTick(3198) mapTick(22780857) received stateChanged peerID(1) oldState(DesyncedAligning) newState(DesyncedAligned)
  90.640 Info MultiplayerManager.cpp:1419: networkTick(3204) mapTick(22780857) received playerReconnect
  90.640 Info MultiplayerManager.cpp:2236: NetworkTick(3204) MapTick(22780857) disconnecting player(1) from the user.
  90.640 Info NetworkInputHandler.cpp:548: expectedMapTick(22780857) removing peer(1) success(true).
  90.640 Info NetworkInputHandler.cpp:524: expectedMapTick(22780857) adding peer(1).
  90.640 Info MultiplayerManager.cpp:1143: networkTick(3204) mapTick(22780857) received stateChanged peerID(1) oldState(DesyncedAligned) newState(DesyncedCreatingReport)
  91.944 Info MultiplayerManager.cpp:1143: networkTick(3238) mapTick(22780857) received stateChanged peerID(1) oldState(DesyncedCreatingReport) newState(DesyncedWaitingForMap)
  91.944 Info MultiplayerManager.cpp:996: networkTick(3238) mapTick(22780857) changing state from(InGameAligned) to(InGameSavingMap)
  91.944 Info MultiplayerManager.cpp:1143: networkTick(3244) mapTick(22780857) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
  93.154 Info MultiplayerManager.cpp:996: networkTick(3290) mapTick(22780857) changing state from(InGameSavingMap) to(InGameSendingMap)
  93.206 Info MultiplayerManager.cpp:1260: MapTick(22780857) Serving map(/opt/factorio/temp/mp-download.zip) for peer(1) size(16411318) crc(1243484598)
  93.428 Info MultiplayerManager.cpp:1143: networkTick(3296) mapTick(22780857) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
  93.428 Info MultiplayerManager.cpp:1143: networkTick(3302) mapTick(22780857) received stateChanged peerID(1) oldState(DesyncedWaitingForMap) newState(DesyncedDownloadingMap)
 104.193 Info Synchronizer.cpp:465: NetworkTick(3568) peer(1) drop detection state(100/900).
 107.613 Info MultiplayerManager.cpp:1143: networkTick(3642) mapTick(22780857) received stateChanged peerID(1) oldState(DesyncedDownloadingMap) newState(DesyncedLoadingMap)
 107.613 Canceling serving for peer 1
 107.613 Info MultiplayerManager.cpp:996: networkTick(3642) mapTick(22780857) changing state from(InGameSendingMap) to(InGameWaitingForOthers)
 107.822 Info MultiplayerManager.cpp:1143: networkTick(3648) mapTick(22780857) received stateChanged peerID(0) oldState(InGameSendingMap) newState(InGameWaitingForOthers)
 110.361 Info MultiplayerManager.cpp:1143: networkTick(3693) mapTick(22780857) received stateChanged peerID(1) oldState(DesyncedLoadingMap) newState(InGameWaitingForOthers)
 110.361 Info MultiplayerManager.cpp:1342: networkTick(3693) mapTick(22780857) stopping mapAlign
 110.361 Info MultiplayerManager.cpp:996: networkTick(3693) mapTick(22780857) changing state from(InGameWaitingForOthers) to(InGame)
 110.361 Info MultiplayerManager.cpp:1706: networkTick(3693) mapTick(22780857) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(1) state(InGameWaitingForOthers) mapAlignTick(-1))
)
 110.362 Info MultiplayerManager.cpp:1143: networkTick(3699) mapTick(22780857) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
 110.362 Info MultiplayerManager.cpp:1143: networkTick(3699) mapTick(22780857) received stateChanged peerID(1) oldState(InGameWaitingForOthers) newState(InGame)
 110.807 Info NetworkInputHandler.cpp:608: assigning playerIndex(1) to peer(1)
 110.807 Info GameActionHandler.cpp:2611: MapTick(22780866) processed PlayerJoinGame peerID(1) playerIndex(1) mode(connect)
 110.813 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780858) peer(1) testCrc(2110370111) testCrcPeerID(0) currentCrc(-1424943087)
 110.813 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 2110370111, reference peers = {0}, desynced group = {CRC = 2870024209, peers = {1 (IsoSchiz)}}
 110.819 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780859) peer(1) testCrc(-1585081689) testCrcPeerID(0) currentCrc(-1951552827)
 110.819 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 2709885607, reference peers = {0}, desynced group = {CRC = 2343414469, peers = {1 (IsoSchiz)}}
 110.825 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780860) peer(1) testCrc(-1347615239) testCrcPeerID(0) currentCrc(-2054865509)
 110.825 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 2947352057, reference peers = {0}, desynced group = {CRC = 2240101787, peers = {1 (IsoSchiz)}}
 110.831 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780861) peer(1) testCrc(1051337589) testCrcPeerID(0) currentCrc(-1034243527)
 110.831 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 1051337589, reference peers = {0}, desynced group = {CRC = 3260723769, peers = {1 (IsoSchiz)}}
 110.838 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780862) peer(1) testCrc(1936939065) testCrcPeerID(0) currentCrc(1499160667)
 110.841 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 1936939065, reference peers = {0}, desynced group = {CRC = 1499160667, peers = {1 (IsoSchiz)}}
 110.874 Error NetworkInputHandler.cpp:375: Multiplayer desynchronisation: crc test(CheckCRCHeuristic) failed for mapTick(22780864) peer(1) testCrc(-145128271) testCrcPeerID(0) currentCrc(-1994273769)
 110.874 Info NetworkInputHandler.cpp:442: Desync specification: Reference CRC = 4149839025, reference peers = {0}, desynced group = {CRC = 2300693527, peers = {1 (IsoSchiz)}}
 111.373 Info MultiplayerManager.cpp:1143: networkTick(3731) mapTick(22780874) received stateChanged peerID(1) oldState(InGame) newState(Desynced)
 111.373 Info MultiplayerManager.cpp:996: networkTick(3731) mapTick(22780874) changing state from(InGame) to(InGameAligning)
 111.373 Info MultiplayerManager.cpp:1818: networkTick(3731) mapTick(22780874) starting mapAlign
 111.373 Info MultiplayerManager.cpp:1143: networkTick(3737) mapTick(22780874) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
 111.373 Info MultiplayerManager.cpp:1143: networkTick(3737) mapTick(22780874) received stateChanged peerID(1) oldState(Desynced) newState(DesyncedAligning)
 111.373 Info MultiplayerManager.cpp:1752: networkTick(3737) mapTick(22780874) sending mapAlignAction
 111.708 Info MultiplayerManager.cpp:1390: networkTick(3743) mapTick(22780874) received MapAlignAction from peer(0), mapAlignTick(22780875) appliedTickClosuresCount(0)
 111.708 Info MultiplayerManager.cpp:1390: networkTick(3743) mapTick(22780874) received MapAlignAction from peer(1), mapAlignTick(22780868) appliedTickClosuresCount(24)
 111.708 Info MultiplayerManager.cpp:1834: networkTick(3744) mapTick(22780874) mapAlign finished targetTick(22780875) updating map and running the tasks ...
 111.708 Info MultiplayerManager.cpp:1706: networkTick(3744) mapTick(22780874) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(22780875))
((peerID(1) state(DesyncedAligning) mapAlignTick(22780868))
)
 111.716 Info MultiplayerManager.cpp:1853: maptick(22780875) crc before applying all ticks: -354486164
 111.716 Info NetworkInputHandler.cpp:670: applyAllTickClosures: expectedMapTick(22780875) lastTickApplied(22780866) referenceMapTick(22780875) networkTick(3744)
 111.716 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780867) inputActions((CheckCRCHeuristic) ) ) for peer(0)
 111.716 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780868) inputActions((CheckCRCHeuristic) ) ) for peer(0)
 111.716 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780869) inputActions((CheckCRCHeuristic) ) ) for peer(0)
 111.716 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780870) inputActions((CheckCRCHeuristic) ) ) for peer(0)
 111.716 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780871) inputActions((CheckCRCHeuristic) ) ) for peer(0)
 111.716 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780872) inputActions((CheckCRCHeuristic) ) ) for peer(0)
 111.716 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780873) inputActions((CheckCRCHeuristic) ) ) for peer(0)
 111.716 Info MultiplayerManager.cpp:1861: maptick(22780875) crc after applying all ticks: -354486164
 111.716 Info MultiplayerManager.cpp:996: networkTick(3744) mapTick(22780875) changing state from(InGameAligning) to(InGameAligned)
 111.717 Info MultiplayerManager.cpp:1143: networkTick(3750) mapTick(22780875) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
 111.717 Info MultiplayerManager.cpp:1143: networkTick(3750) mapTick(22780875) received stateChanged peerID(1) oldState(DesyncedAligning) newState(DesyncedAligned)
 111.996 Info MultiplayerManager.cpp:1419: networkTick(3756) mapTick(22780875) received playerReconnect
 111.996 Info MultiplayerManager.cpp:2236: NetworkTick(3756) MapTick(22780875) disconnecting player(1) from the user.
 111.996 Info NetworkInputHandler.cpp:548: expectedMapTick(22780875) removing peer(1) success(true).
 111.996 Info NetworkInputHandler.cpp:524: expectedMapTick(22780875) adding peer(1).
 111.996 Info MultiplayerManager.cpp:1143: networkTick(3756) mapTick(22780875) received stateChanged peerID(1) oldState(DesyncedAligned) newState(DesyncedCreatingReport)
 114.613 Info MultiplayerManager.cpp:1143: networkTick(3808) mapTick(22780875) received stateChanged peerID(1) oldState(DesyncedCreatingReport) newState(DesyncedWaitingForMap)
 114.613 Info MultiplayerManager.cpp:996: networkTick(3808) mapTick(22780875) changing state from(InGameAligned) to(InGameSavingMap)
 114.613 Info MultiplayerManager.cpp:1143: networkTick(3814) mapTick(22780875) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
 115.769 Info MultiplayerManager.cpp:996: networkTick(3833) mapTick(22780875) changing state from(InGameSavingMap) to(InGameSendingMap)
 115.821 Info MultiplayerManager.cpp:1260: MapTick(22780875) Serving map(/opt/factorio/temp/mp-download.zip) for peer(1) size(16413382) crc(-143518240)
 116.239 Info MultiplayerManager.cpp:1143: networkTick(3839) mapTick(22780875) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
 116.240 Info MultiplayerManager.cpp:1143: networkTick(3845) mapTick(22780875) received stateChanged peerID(1) oldState(DesyncedWaitingForMap) newState(DesyncedDownloadingMap)
 130.841 Info MultiplayerManager.cpp:1143: networkTick(4347) mapTick(22780875) received stateChanged peerID(1) oldState(DesyncedDownloadingMap) newState(DesyncedLoadingMap)
 130.841 Canceling serving for peer 1
 130.842 Info MultiplayerManager.cpp:996: networkTick(4347) mapTick(22780875) changing state from(InGameSendingMap) to(InGameWaitingForOthers)
 131.348 Info MultiplayerManager.cpp:1143: networkTick(4353) mapTick(22780875) received stateChanged peerID(0) oldState(InGameSendingMap) newState(InGameWaitingForOthers)
 133.428 Info MultiplayerManager.cpp:1143: networkTick(4382) mapTick(22780875) received stateChanged peerID(1) oldState(DesyncedLoadingMap) newState(InGameWaitingForOthers)
 133.428 Info MultiplayerManager.cpp:1342: networkTick(4382) mapTick(22780875) stopping mapAlign
 133.428 Info MultiplayerManager.cpp:996: networkTick(4382) mapTick(22780875) changing state from(InGameWaitingForOthers) to(InGame)
 133.428 Info MultiplayerManager.cpp:1706: networkTick(4382) mapTick(22780875) peerID(0) fullStateLog: local state(InGame) local peers(((peerID(0) state(InGameWaitingForOthers) mapAlignTick(-1))
((peerID(1) state(InGameWaitingForOthers) mapAlignTick(-1))
)
 133.428 Info MultiplayerManager.cpp:1143: networkTick(4388) mapTick(22780875) received stateChanged peerID(0) oldState(InGameWaitingForOthers) newState(InGame)
 133.428 Info MultiplayerManager.cpp:1143: networkTick(4388) mapTick(22780875) received stateChanged peerID(1) oldState(InGameWaitingForOthers) newState(InGame)
 136.500 Info MultiplayerManager.cpp:1143: networkTick(4445) mapTick(22780882) received stateChanged peerID(1) oldState(InGame) newState(Desynced)
 136.500 Info MultiplayerManager.cpp:996: networkTick(4445) mapTick(22780882) changing state from(InGame) to(InGameAligning)
 136.500 Info MultiplayerManager.cpp:1818: networkTick(4445) mapTick(22780882) starting mapAlign
 136.500 Info MultiplayerManager.cpp:1143: networkTick(4451) mapTick(22780882) received stateChanged peerID(0) oldState(InGame) newState(InGameAligning)
 136.500 Info MultiplayerManager.cpp:1143: networkTick(4451) mapTick(22780882) received stateChanged peerID(1) oldState(Desynced) newState(DesyncedAligning)
 136.500 Info MultiplayerManager.cpp:1752: networkTick(4451) mapTick(22780882) sending mapAlignAction
 136.834 Info MultiplayerManager.cpp:1390: networkTick(4457) mapTick(22780882) received MapAlignAction from peer(0), mapAlignTick(22780883) appliedTickClosuresCount(0)
 136.841 Info MultiplayerManager.cpp:1390: networkTick(4457) mapTick(22780882) received MapAlignAction from peer(1), mapAlignTick(22780886) appliedTickClosuresCount(14)
 136.841 Info MultiplayerManager.cpp:1834: networkTick(4458) mapTick(22780882) mapAlign finished targetTick(22780886) updating map and running the tasks ...
 136.841 Info MultiplayerManager.cpp:1706: networkTick(4458) mapTick(22780882) peerID(0) fullStateLog: local state(InGameAligning) local peers(((peerID(0) state(InGameAligning) mapAlignTick(22780883))
((peerID(1) state(DesyncedAligning) mapAlignTick(22780886))
)
 136.851 Info NetworkInputHandler.cpp:608: assigning playerIndex(1) to peer(1)
 136.851 Info GameActionHandler.cpp:2611: MapTick(22780884) processed PlayerJoinGame peerID(1) playerIndex(1) mode(connect)
 136.861 Info MultiplayerManager.cpp:1853: maptick(22780886) crc before applying all ticks: 1478281635
 136.861 Info NetworkInputHandler.cpp:670: applyAllTickClosures: expectedMapTick(22780886) lastTickApplied(22780877) referenceMapTick(22780886) networkTick(4458)
 136.861 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780878) inputActions((CheckCRCHeuristic) ) ) for peer(0)
 136.861 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780878) inputActions((CheckCRCHeuristic) ) ) for peer(1)
 136.861 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780879) inputActions((CheckCRCHeuristic) ) ) for peer(0)
 136.861 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780879) inputActions((CheckCRCHeuristic) ) ) for peer(1)
 136.861 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780880) inputActions((CheckCRCHeuristic) ) ) for peer(0)
 136.861 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780880) inputActions((CheckCRCHeuristic) ) ) for peer(1)
 136.861 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780881) inputActions((CheckCRCHeuristic) ) ) for peer(0)
 136.861 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780881) inputActions((CheckCRCHeuristic) ) ) for peer(1)
 136.861 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780882) inputActions((CheckCRCHeuristic) ) ) for peer(1)
 136.861 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780883) inputActions((CheckCRCHeuristic) ) ) for peer(1)
 136.861 Info NetworkInputHandler.cpp:694: applyAllTickClosures: applying tickClosure(tick(22780884) inputActions((CheckCRCHeuristic) ) ) for peer(1)
 136.861 Info MultiplayerManager.cpp:1861: maptick(22780886) crc after applying all ticks: 1478281635
 136.861 Info MultiplayerManager.cpp:996: networkTick(4458) mapTick(22780886) changing state from(InGameAligning) to(InGameAligned)
 136.862 Info MultiplayerManager.cpp:1143: networkTick(4464) mapTick(22780886) received stateChanged peerID(0) oldState(InGameAligning) newState(InGameAligned)
 136.862 Info MultiplayerManager.cpp:1143: networkTick(4464) mapTick(22780886) received stateChanged peerID(1) oldState(DesyncedAligning) newState(DesyncedAligned)
 137.285 Info MultiplayerManager.cpp:1419: networkTick(4470) mapTick(22780886) received playerReconnect
 137.285 Info MultiplayerManager.cpp:2236: NetworkTick(4470) MapTick(22780886) disconnecting player(1) from the user.
 137.285 Info NetworkInputHandler.cpp:548: expectedMapTick(22780886) removing peer(1) success(true).
 137.285 Info NetworkInputHandler.cpp:524: expectedMapTick(22780886) adding peer(1).
 137.285 Info MultiplayerManager.cpp:1143: networkTick(4470) mapTick(22780886) received stateChanged peerID(1) oldState(DesyncedAligned) newState(DesyncedCreatingReport)
 140.402 Info MultiplayerManager.cpp:1143: networkTick(4509) mapTick(22780886) received stateChanged peerID(1) oldState(DesyncedCreatingReport) newState(DesyncedWaitingForMap)
 140.402 Info MultiplayerManager.cpp:996: networkTick(4509) mapTick(22780886) changing state from(InGameAligned) to(InGameSavingMap)
 140.402 Info MultiplayerManager.cpp:1143: networkTick(4515) mapTick(22780886) received stateChanged peerID(0) oldState(InGameAligned) newState(InGameSavingMap)
 140.403 Info Synchronizer.cpp:465: NetworkTick(4521) peer(1) drop detection state(100/900).
 141.973 Info MultiplayerManager.cpp:996: networkTick(4522) mapTick(22780886) changing state from(InGameSavingMap) to(InGameSendingMap)
 142.024 Info MultiplayerManager.cpp:1260: MapTick(22780886) Serving map(/opt/factorio/temp/mp-download.zip) for peer(1) size(16411083) crc(770936178)
 142.024 Info MultiplayerManager.cpp:1143: networkTick(4528) mapTick(22780886) received stateChanged peerID(0) oldState(InGameSavingMap) newState(InGameSendingMap)
 142.146 Info MultiplayerManager.cpp:1143: networkTick(4534) mapTick(22780886) received stateChanged peerID(1) oldState(DesyncedWaitingForMap) newState(DesyncedDownloadingMap)
 145.089 Info Synchronizer.cpp:465: NetworkTick(4587) peer(1) drop detection state(100/900).

Re: [0.13.1] Reliable desync on server join

Posted: Thu Jun 30, 2016 10:24 am
by Jerub
This bug is related to the same situation: viewtopic.php?f=7&t=27677

Re: [0.13.1] Reliable desync on server join

Posted: Thu Jun 30, 2016 12:24 pm
by Rseding91
Can you reproduce the desync when loading the 0.12 save in 0.13.1?

Re: [0.13.1] Reliable desync on server join

Posted: Thu Jun 30, 2016 12:39 pm
by Jerub
Attached a save file that's a 0.12.35 game that we took straight to 0.13.1

Re: [0.13.1] Reliable desync on server join

Posted: Thu Jun 30, 2016 12:40 pm
by Jerub
To be clear: yes, we can replicate the problem with a save that wasn't run with 0.13.0

Re: [0.13.1] Reliable desync on server join

Posted: Thu Jun 30, 2016 12:51 pm
by Jerub
Aaaaand now I realise I had the wrong save in that test. Yes: loading a 0.12.35 save from before we played on 0.13.0 always works.

Sorry about the noise.

Re: [0.13.1] Reliable desync on server join

Posted: Thu Jun 30, 2016 12:57 pm
by Rseding91
Jerub wrote:Aaaaand now I realise I had the wrong save in that test. Yes: loading a 0.12.35 save from before we played on 0.13.0 always works.

Sorry about the noise.
So you're saying no desync when 0.12 -> 0.13.1?