  INFO [Properties.<init>]                           VERSION: 1.3.7
  INFO [Properties.<init>]                           Classpath: c:\Users\Turborilla\Documents\PM1\MulesAI\build\main;c:\Users\Turborilla\Documents\PM1\MulesAI\lib\jogg-0.0.7.jar;c:\Users\Turborilla\Documents\PM1\MulesAI\lib\jorbis-0.0.15.jar;c:\Users\Turborilla\Documents\PM1\MulesAI\lib\lpsolve55j.jar;c:\Users\Turborilla\Documents\PM1\MulesAI\lib\lwjgl.jar;c:\Users\Turborilla\Documents\PM1\MulesAI\lib\slick.jar;c:\Users\Turborilla\Documents\PM1\MulesAI\lib\substance-lite.jar
  INFO [Properties.<init>]                           Java VM: 11.0.28+6-LTS
  INFO [Properties.<init>]                           Java Runtime: 11.0.28+6-LTS
  INFO [Properties.<init>]                           OS: Windows 10 10.0 amd64
  INFO [Properties.<init>]                           Java Library Path: C:\\Users\\Turborilla\\Documents\\PM1\\MulesAI/lib/natives/windows/data/lib
  INFO [Properties.<init>]                           Max Memory: 8529117184
  INFO [Properties.<init>]                           Avaialble Processors: 16
  INFO [PropertiesBase.readFromFile]                 Reading Settings from file settings.txt
  INFO [SubstanceLoader.initLookAndFeel]             Window Skin: Default
  SEVERE [Main.logError]                             java.nio.file.NoSuchFileException: lpsolve55_64.dll
	 at java.base/sun.nio.fs.WindowsException.translateToIOException(WindowsException.java:85)
	 at java.base/sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:103)
	 at java.base/sun.nio.fs.WindowsException.rethrowAsIOException(WindowsException.java:108)
	 at java.base/sun.nio.fs.WindowsFileCopy.copy(WindowsFileCopy.java:98)
	 at java.base/sun.nio.fs.WindowsFileSystemProvider.copy(WindowsFileSystemProvider.java:283)
	 at java.base/java.nio.file.Files.copy(Files.java:1295)
	 at com.turborilla.mule.Main.main(Main.java:96)
  WARNING [Main.main]                                Disabling Adam's AI
  INFO [SoundPlayer.initialize]                      Initializing Sound System
  INFO [SlickLogSystem.info]                         Initialising sounds..
  INFO [SlickLogSystem.info]                         - Sound works
  INFO [SlickLogSystem.info]                         - 64 OpenAL source available
  INFO [SlickLogSystem.info]                         - Sounds source generated
  INFO [MasterClient.setServerAddress]               MasterClient: Address http://www.planetmule.com/server
  INFO [MasterClient.logSend]                        MasterClient: Sending Login
  INFO [MasterClient.apply]                          MasterClient: Session ID: 08deabd0e43ce78af577665f0714c134
  INFO [Login$MasterListener$1.run]                  Logged in as "xitheta" (id 109210)
  INFO [MetaFrame.<init>]                            Creating Meta Frame
  INFO [MapSkinParser.findSkins]                     Found skin "default"
  INFO [MasterClient.logSend]                        MasterClient: Sending Enter Lobby
  INFO [Login.dispose]                               Disposing login
++++++++ END OF INITIALIZATION +++++++++++++++++++++++++++++++++++++++++++
  INFO [MetaFrame.startGame]                         Starting game number 2
  INFO [MetaFrame.startGame]                         Entering Game - ID: 0, Name: BIT-6916, Address: 147.185.221.211:58971, Is Host: false
  INFO [MuleGameContainer.init]                      *** Starting Game ***
  INFO [MuleGameContainer.init]                      Display: Created=true Active=true Visible=true
  INFO [MuleGameContainer.init]                      Making display the current GL context
  INFO [View.<init>]                                 Creating view...
  INFO [View.<init>]                                 Finished creating view
  INFO [Client.connect]                              Client connects...
  INFO [Client.connectUDP]                           Client UDP connects...
  WARNING [Client.connectUDP]                        Client couldn't connect with UDP port 6261 (Address already in use: bind)
  INFO [Client.connectUDP]                           Client UDP local address: 0.0.0.0/0.0.0.0:6262
  INFO [Client.connectUDP]                           Client: Connects UDP:  UDP: /147.185.221.211:58971
  INFO [Client.connectTCP]                           Client TCP connects...
  INFO [Client.connectTCP]                           Client: Connects TCP: /147.185.221.211:58971
  INFO [Client.connectTCP]                           Client: TCP receive buffer: 65536 send buffer: 65536
  INFO [GameController.setModel]                     ++++++++ New Model: CONNECT #0 +++++++++++++++++++++++++++++++++++++++++++
  INFO [MuleGameContainer.setWindowedMode]           Setting windowed mode: 640 x 480 x 0 @0Hz
  INFO [Client.finishConnect]                        Client finishes connection
  INFO [ConnectPhase.update]                         my User Number: 11
  INFO [ConnectPhase.update]                         connection token: 890b38c7ce87312f85208185b14b1def3558e638d9e9132a1b99b10d8da6ff2a
  INFO [ConnectPhase.sendRejoin]                     Attempt reconnect join: sent request for user 11
  INFO [Client.receiveTCPMessages]                   Client: ServerInfo (0) 35 bytes
  INFO [Client.receiveTCPMessages]                   Client: ReconnectRejected (0) 16 bytes
  INFO [UserController.apply]                        My client id is 7aabb2a3-e59f-45a1-adcd-288c4303e51e
  INFO [ConnectPhase.sendJoin]                       Attempt join: sent request for user...
  INFO [Client.initUDP]                              Sending UDP initialization message id 7aabb2a3-e59f-45a1-adcd-288c4303e51e
  INFO [Client.receiveTCPMessages]                   Client: ServerInfo (0) 35 bytes
  INFO [Client.receiveTCPMessages]                   Client: Join (11) 39 bytes
  INFO [Client.receiveTCPMessages]                   Client: Increasing TCP recieve buffer for GameModel (0) 7182 bytes
  INFO [Client.receiveTCPMessages]                   Client: GameModel (0) 7182 bytes
  INFO [Client.receiveTCPMessages]                   Client: ReconnectInfo (0) 120 bytes
  INFO [UserController.apply]                        My client id is 7aabb2a3-e59f-45a1-adcd-288c4303e51e
  INFO [Player.<init>]                               Creating (P0)Spectator
  INFO [GameModel.setMyUser]                         (S11)xitheta is my user
  INFO [GameModel.setLocalUser]                      (S11)xitheta is a local Spectator
  INFO [GameModel.setRemoteUser]                     (S10)xitheta4 is a remote Spectator
  INFO [GameModel.setMyUser]                         (S11)xitheta is my user
  INFO [GameModel.setLocalUser]                      (S11)xitheta is a local Spectator
  INFO [GameController.setModel]                     ++++++++ New Model: GAME_LOBBY #0 +++++++++++++++++++++++++++++++++++++++++++
  INFO [GameLobbyPhase.getMasterUserInfo]            Fetching user info for (S10)xitheta4 (ID: 109244)
  INFO [GameLobbyPhase.getMasterUserInfo]            Fetching user info for (S11)xitheta (ID: 109210)
  INFO [GameLobbyPhase$MasterListener.apply]         Got user info for (S10)xitheta4 (ID 109244)
  INFO [GameLobbyPhase$MasterListener.apply]         No statistics available for (S10)xitheta4 (ID 109244)
  INFO [GameLobbyPhase$MasterListener.apply]         Got user info for (S11)xitheta (ID 109210)
  INFO [Client.receiveTCPMessages]                   Client: PlayerJoin (11) 0 bytes
  INFO [UserController.apply]                        (S11)xitheta joins the players
  INFO [Player.<init>]                               Creating (P11)xitheta
  INFO [GameModel.setMyUser]                         (P11)xitheta is my user
  INFO [GameModel.setLocalUser]                      (P11)xitheta is a local Player
  INFO [Client.receiveTCPMessages]                   Client: PlayerJoin (10) 0 bytes
  INFO [UserController.apply]                        (S10)xitheta4 joins the players
  INFO [Player.<init>]                               Creating (P10)xitheta4
  INFO [GameModel.setServerUser]                     (P10)xitheta4 is hosting the server
  INFO [GameModel.setRemoteUser]                     (P10)xitheta4 is a remote Player
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: AIJoin (12) 12 bytes
  INFO [Player.<init>]                               Creating (P12)<J.I.M.>
  INFO [UserController.setAiPlayer]                  (P12)<J.I.M.> is an AI player
  INFO [GameModel.setRemoteUser]                     (P12)<J.I.M.> is a remote Player
  INFO [Client.receiveTCPMessages]                   Client: Continue (12) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: AIJoin (13) 14 bytes
  INFO [Player.<init>]                               Creating (P13)<A.L.A.N.>
  INFO [UserController.setAiPlayer]                  (P13)<A.L.A.N.> is an AI player
  INFO [GameModel.setRemoteUser]                     (P13)<A.L.A.N.> is a remote Player
  INFO [Client.receiveTCPMessages]                   Client: Continue (13) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 0 bytes
  INFO [GameLobbyPhase.apply]                        Starting Game - All players want to continue
  INFO [GameModel.updatePlayerRankOrder]             Random rank order: (P12)<J.I.M.> (P13)<A.L.A.N.> (P11)xitheta (P10)xitheta4 
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 2
  INFO [GameController.beginNextPhase]               --------- INTRO #0 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 64 ms
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- SUMMARY #0 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 64 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -189137416115907057
  INFO [GameModel.updatePlayerRankOrder]             Random rank order: (P13)<A.L.A.N.> (P12)<J.I.M.> (P11)xitheta (P10)xitheta4 
  INFO [SummaryPhase2.begin]                         Total Memory: 50331648
  INFO [SummaryPhase2.begin]                         Free Memory: 21581696
  INFO [SummaryPhase2.begin]                         (P13)<A.L.A.N.> Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P13)<A.L.A.N.> Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P12)<J.I.M.> Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P12)<J.I.M.> Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11)xitheta Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P11)xitheta Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10)xitheta4 Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P10)xitheta4 Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [Shop.buildMules]                             Shop built 0 mules for 0 smithore and now has 14 mules
  INFO [Shop.buildMules]                             A mule now costs $100
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $48
  INFO [SummaryPhase2.begin]                         Shop Smithore: 8 units $50
  INFO [SummaryPhase2.begin]                         Shop Food: 8 units $30
  INFO [SummaryPhase2.begin]                         Shop Energy: 8 units $25
  INFO [SummaryPhase2.begin]                         Shop mules: 14 $100
  INFO [SummaryPhase2.begin]                         Colony total: 4680
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  WARNING [Client.initUDP]                           UDP messages timed out. Sending new UDP initialization message id 7aabb2a3-e59f-45a1-adcd-288c4303e51e
  SEVERE [GameController.checkMessageTimeout]        Your network connection timed out
  INFO [Client.connect]                              Client connects...
  INFO [Client.connectUDP]                           Client UDP connects...
  WARNING [Client.connectUDP]                        Client couldn't connect with UDP port 6261 (Address already in use: bind)
  INFO [Client.connectUDP]                           Client UDP local address: 0.0.0.0/0.0.0.0:6262
  INFO [Client.connectUDP]                           Client: Connects UDP:  UDP: /147.185.221.211:58971
  INFO [Client.connectTCP]                           Client TCP connects...
  INFO [Client.connectTCP]                           Client: Connects TCP: /147.185.221.211:58971
  INFO [Client.connectTCP]                           Client: TCP receive buffer: 65536 send buffer: 65536
  INFO [SlickLogSystem.info]                         Too much real time passed since last frame. Breaking sync with real time.
  INFO [Client.finishConnect]                        Client finishes connection
  INFO [ConnectPhase.update]                         my User Number: 11
  INFO [ConnectPhase.update]                         connection token: cf6399a1340cd50255e082b263e933a92e8b849f46a900a121197d063a594ed6
  INFO [ConnectPhase.sendRejoin]                     Attempt reconnect join: sent request for user 11
  INFO [Client.receiveTCPMessages]                   Client: ServerInfo (0) 35 bytes
  INFO [Client.receiveTCPMessages]                   Client: ReconnectJoin (11) 2244 bytes
  INFO [UserController.apply]                        My client id is 8a4568f8-b93b-431b-b8cb-cbf0e06fb2a4
  INFO [UserController.apply]                        (P11)xitheta reconnects.
  INFO [GameController.enterPausePhase]              -GAME PAUSED-
  INFO [Client.receiveTCPMessages]                   Client: Increasing TCP recieve buffer for ReconnectReady (0) 7368 bytes
  INFO [Client.receiveTCPMessages]                   Client: ReconnectReady (0) 7368 bytes
  INFO [GameController.setModel]                     ++++++++ New Model: SUMMARY #0 +++++++++++++++++++++++++++++++++++++++++++
  INFO [GameModel.setMyUser]                         (P11)xitheta is my user
  INFO [GameModel.setLocalUser]                      (P11)xitheta is a local Player
  INFO [GameModel.setRemoteUser]                     (P10)xitheta4 is a remote Player
  INFO [GameModel.setRemoteUser]                     (P12)<J.I.M.> is a remote Player
  INFO [GameModel.setRemoteUser]                     (P13)<A.L.A.N.> is a remote Player
  INFO [UserController.setAiPlayer]                  (P12)<J.I.M.> is taken over by an AI player
  INFO [UserController.setAiPlayer]                  (P13)<A.L.A.N.> is taken over by an AI player
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -189137416115907057
  INFO [GameModel.updatePlayerRankOrder]             Random rank order: (P13)<A.L.A.N.> (P12)<J.I.M.> (P11)xitheta (P10)xitheta4 
  INFO [SummaryPhase2.begin]                         Total Memory: 50331648
  INFO [SummaryPhase2.begin]                         Free Memory: 29166648
  INFO [SummaryPhase2.begin]                         (P13)<A.L.A.N.> Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P13)<A.L.A.N.> Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P12)<J.I.M.> Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P12)<J.I.M.> Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11)xitheta Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P11)xitheta Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10)xitheta4 Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P10)xitheta4 Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [Shop.buildMules]                             Shop built 0 mules for 0 smithore and now has 14 mules
  INFO [Shop.buildMules]                             A mule now costs $100
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $48
  INFO [SummaryPhase2.begin]                         Shop Smithore: 8 units $50
  INFO [SummaryPhase2.begin]                         Shop Food: 8 units $30
  INFO [SummaryPhase2.begin]                         Shop Energy: 8 units $25
  INFO [SummaryPhase2.begin]                         Shop mules: 14 $100
  INFO [SummaryPhase2.begin]                         Colony total: 4680
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  INFO [GameController.setModel]                     ++++++++ New Model: SUMMARY #0 +++++++++++++++++++++++++++++++++++++++++++
  INFO [Client.initUDP]                              Sending UDP initialization message id 8a4568f8-b93b-431b-b8cb-cbf0e06fb2a4
  SEVERE [GameController.checkMessageTimeout]        Your network connection timed out
  INFO [Client.connect]                              Client connects...
  INFO [Client.connectUDP]                           Client UDP connects...
  WARNING [Client.connectUDP]                        Client couldn't connect with UDP port 6261 (Address already in use: bind)
  INFO [Client.connectUDP]                           Client UDP local address: 0.0.0.0/0.0.0.0:6262
  INFO [Client.connectUDP]                           Client: Connects UDP:  UDP: /147.185.221.211:58971
  INFO [Client.connectTCP]                           Client TCP connects...
  INFO [Client.connectTCP]                           Client: Connects TCP: /147.185.221.211:58971
  INFO [Client.connectTCP]                           Client: TCP receive buffer: 65536 send buffer: 65536
  INFO [SlickLogSystem.info]                         Too much real time passed since last frame. Breaking sync with real time.
  INFO [Client.finishConnect]                        Client finishes connection
  INFO [ConnectPhase.update]                         my User Number: 11
  INFO [ConnectPhase.update]                         connection token: cf6399a1340cd50255e082b263e933a92e8b849f46a900a121197d063a594ed6
  INFO [ConnectPhase.sendRejoin]                     Attempt reconnect join: sent request for user 11
  INFO [Client.receiveTCPMessages]                   Client: ServerInfo (0) 35 bytes
  INFO [Client.receiveTCPMessages]                   Client: ReconnectJoin (11) 2245 bytes
  INFO [UserController.apply]                        My client id is 5e15a0a0-5ad5-4afc-99c0-35409ce9cb38
  INFO [UserController.apply]                        (P11)xitheta reconnects.
  INFO [GameController.enterPausePhase]              -GAME PAUSED-
  INFO [Client.receiveTCPMessages]                   Client: ReconnectReady (0) 7368 bytes
  INFO [GameController.setModel]                     ++++++++ New Model: SUMMARY #0 +++++++++++++++++++++++++++++++++++++++++++
  INFO [GameModel.setMyUser]                         (P11)xitheta is my user
  INFO [GameModel.setLocalUser]                      (P11)xitheta is a local Player
  INFO [GameModel.setRemoteUser]                     (P10)xitheta4 is a remote Player
  INFO [GameModel.setRemoteUser]                     (P12)<J.I.M.> is a remote Player
  INFO [GameModel.setRemoteUser]                     (P13)<A.L.A.N.> is a remote Player
  INFO [UserController.setAiPlayer]                  (P12)<J.I.M.> is taken over by an AI player
  INFO [UserController.setAiPlayer]                  (P13)<A.L.A.N.> is taken over by an AI player
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -189137416115907057
  INFO [GameModel.updatePlayerRankOrder]             Random rank order: (P13)<A.L.A.N.> (P12)<J.I.M.> (P11)xitheta (P10)xitheta4 
  INFO [SummaryPhase2.begin]                         Total Memory: 50331648
  INFO [SummaryPhase2.begin]                         Free Memory: 29560744
  INFO [SummaryPhase2.begin]                         (P13)<A.L.A.N.> Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P13)<A.L.A.N.> Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P12)<J.I.M.> Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P12)<J.I.M.> Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11)xitheta Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P11)xitheta Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10)xitheta4 Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P10)xitheta4 Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [Shop.buildMules]                             Shop built 0 mules for 0 smithore and now has 14 mules
  INFO [Shop.buildMules]                             A mule now costs $100
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $48
  INFO [SummaryPhase2.begin]                         Shop Smithore: 8 units $50
  INFO [SummaryPhase2.begin]                         Shop Food: 8 units $30
  INFO [SummaryPhase2.begin]                         Shop Energy: 8 units $25
  INFO [SummaryPhase2.begin]                         Shop mules: 14 $100
  INFO [SummaryPhase2.begin]                         Colony total: 4680
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  SEVERE [SummaryPainter3.start]                     Player sprites not empty at start of summary animation.
  INFO [GameController.setModel]                     ++++++++ New Model: SUMMARY #0 +++++++++++++++++++++++++++++++++++++++++++
  INFO [Client.receiveTCPMessages]                   Client: Continue (13) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (12) 0 bytes
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [SlickLogSystem.info]                         Too much real time passed since last frame. Breaking sync with real time.
  INFO [Client.initUDP]                              Sending UDP initialization message id 5e15a0a0-5ad5-4afc-99c0-35409ce9cb38
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 18
  INFO [GameController.beginNextPhase]               --------- INTRO #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 75 ms
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 75 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 75ms, max 75ms
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 75ms, max 75ms
  INFO [LandGrantPhase.apply]                        Grace duration 10 frames
  INFO [LandGrantPhase.apply]                        Starting land grant
  INFO [LandGrantPhase.claimPlot]                    (P12)<J.I.M.> is granted tile 22: Plain (5,2)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 75ms, max 75ms
  INFO [LandGrantPhase.claimPlot]                    (P13)<A.L.A.N.> is granted tile 30: River (4,3)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 75ms, max 75ms
  INFO [LandGrantPhase.moveToNextTile]               Land grant ends
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 75 ms
  INFO [PlotSeller.generateNextColonyAuction]        Colony doesn't sell a plot
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 75 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 4890677531752162596
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  INFO [PlayerEventGenerator.nextEvent]              No event frist round
  INFO [PlayerEventPhase.begin]                      No player event for (P13)<A.L.A.N.> (rank 1)
  INFO [Player.useFood]                              (P13)<A.L.A.N.> has 4 food and needs 3 food
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- FAST_DEVELOPMENT #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 75 ms
  INFO [Wampus.<init>]                               Wampus has 20 mountains, reward is $100
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (13) 16 bytes
  INFO [GameModel.developLand]                       (P13)<A.L.A.N.> develops tile River (4,3) with Food factory
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (13) 16 bytes
  INFO [GameModel.gamble]                            (P13)<A.L.A.N.> gambles $52, max: 47.5, left: 23.75, fraction 0.5
  INFO [DevelopmentMessenger.showMessage]            <A.L.A.N.> won $52 in the pub
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 75 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -36614218435944586
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventGenerator.nextEvent]              No event frist round
  INFO [PlayerEventPhase.begin]                      No player event for (P12)<J.I.M.> (rank 2)
  INFO [Player.useFood]                              (P12)<J.I.M.> has 4 food and needs 3 food
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- FAST_DEVELOPMENT #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 75 ms
  INFO [Wampus.<init>]                               Wampus has 20 mountains, reward is $100
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (12) 16 bytes
  INFO [GameModel.developLand]                       (P12)<J.I.M.> develops tile Plain (5,2) with Energy factory
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (12) 16 bytes
  INFO [GameModel.gamble]                            (P12)<J.I.M.> gambles $111, max: 47.5, left: 23.75, fraction 0.5
  INFO [DevelopmentMessenger.showMessage]            <J.I.M.> won $111 in the pub
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 74 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -6064685061669564036
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventGenerator.nextEvent]              No event frist round
  INFO [PlayerEventPhase.begin]                      No player event for (P11)xitheta (rank 3)
  INFO [Player.useFood]                              (P11)xitheta has 4 food and needs 3 food
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 73 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11)xitheta
  INFO [Wampus.<init>]                               Wampus has 20 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 2.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.08000134 (owner 10)
  INFO [GameModel.sellMule]                          (P11)xitheta sold a mule for $100
  INFO [GameModel.gamble]                            (P11)xitheta gambles $145, max: 47.5, left: 27.83955, fraction 0.5860958
  INFO [DevelopmentMessenger.showMessage]            xitheta won $145 in the pub
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P11)xitheta finishes on update 983
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 3
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 72 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -1716451093735521039
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventGenerator.nextEvent]              No event frist round
  INFO [PlayerEventPhase.begin]                      No player event for (P10)xitheta4 (rank 4)
  INFO [Player.useFood]                              (P10)xitheta4 has 4 food and needs 3 food
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 71 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10)xitheta4
  INFO [Wampus.<init>]                               Wampus has 20 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 2.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 1.3411045E-6 (owner 10)
  INFO [AbstractDevelopmentPhase.apply]              Remote (P10)xitheta4 finishes on update 222
  INFO [GameModel.gamble]                            (P10)xitheta4 gambles $102, max: 47.5, left: 43.0599, fraction 0.9065242
  INFO [DevelopmentMessenger.showMessage]            xitheta4 won $102 in the pub
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_A #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 71 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: ACID_RAIN_STORM
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -3547958668145666584
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 71 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 1891375823140583661
  INFO [Client.receiveTCPMessages]                   Client: Continue (12) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (13) 0 bytes
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [ProductionPhase.finishPower]                 (P11)xitheta has 2 energy and needs 0 energy
  INFO [ProductionPhase.finishPower]                 (P10)xitheta4 has 2 energy and needs 0 energy
  INFO [ProductionPhase.finishPower]                 (P12)<J.I.M.> has 2 energy and needs 0 energy
  INFO [ProductionPhase.finishPower]                 (P13)<A.L.A.N.> has 2 energy and needs 1 energy
  INFO [ProductionPhase.logProduction]               (P11)xitheta expects 0 food, 0 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10)xitheta4 expects 0 food, 0 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12)<J.I.M.> expects 0 food, 1 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13)<A.L.A.N.> expects 5 food, 0 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11)xitheta produced 0 food, 0 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10)xitheta4 produced 0 food, 0 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12)<J.I.M.> produced 0 food, 2 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13)<A.L.A.N.> produced 6 food, 0 energy, 0 smithore, 0 crystite
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_B #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 71 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 71 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -7760737345748649917
  INFO [Shop.calcBuySellPrice]                       Current Smithore price $50
  INFO [Shop.calcBuySellPrice]                       Smithore random variation $6
  INFO [Shop.printPriceFactor]                       Smithore shop: 8, mules potentially available: 16, mules needed: 4
  INFO [Shop.printPriceFactor]                       Smithore is decreased to 44%
  INFO [Shop.printPriceFactor]                       Smithore buy price $56 sell price $91
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [CollectionPhase.goToNextPhase]               (P11)xitheta Smithore: 0 critical: 0 surplus: 0 money: $1145
  INFO [CollectionPhase.goToNextPhase]               (P10)xitheta4 Smithore: 0 critical: 0 surplus: 0 money: $1102
  INFO [CollectionPhase.goToNextPhase]               (P12)<J.I.M.> Smithore: 0 critical: 0 surplus: 0 money: $961
  INFO [CollectionPhase.goToNextPhase]               (P13)<A.L.A.N.> Smithore: 0 critical: 0 surplus: 0 money: $927
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_SMITHORE #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 71 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 2
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 5.155802E-6 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 45 bytes
  INFO [DelayedMessageQueue.add]                     StartAuction is delivered in 0 frames, rountrip 69ms, max 69ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11)xitheta (P10)xitheta4 (P13)<A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at -0.26748946 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Shop.setAveragePrice]                        Smithore price set to average $56 (0 units for $0)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 67 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -3704058345909549610
  INFO [Shop.calcBuySellPrice]                       Current Crystite price $48
  INFO [Shop.calcBuySellPrice]                       Crystite buy price $56 sell price $196
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [CollectionPhase.goToNextPhase]               (P11)xitheta Crystite: 0 critical: 0 surplus: 0 money: $1145
  INFO [CollectionPhase.goToNextPhase]               (P10)xitheta4 Crystite: 0 critical: 0 surplus: 0 money: $1102
  INFO [CollectionPhase.goToNextPhase]               (P12)<J.I.M.> Crystite: 0 critical: 0 surplus: 0 money: $961
  INFO [CollectionPhase.goToNextPhase]               (P13)<A.L.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $927
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_CRYSTITE #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 67 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 3
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Shop.setAveragePrice]                        Crystite price set to average $56 (0 units for $0)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_FOOD #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 67 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 4669038945606582169
  INFO [Shop.calcBuySellPrice]                       Current Food price $30
  INFO [Shop.printPriceFactor]                       Food shop: 8, total: 18, needed: 12
  INFO [Shop.printPriceFactor]                       Food is decreased to 75%
  INFO [Shop.printPriceFactor]                       Food buy price $15 sell price $50
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [CollectionPhase.goToNextPhase]               (P11)xitheta Food: 1 critical: 3 surplus: -2 money: $1145
  INFO [CollectionPhase.goToNextPhase]               (P10)xitheta4 Food: 1 critical: 3 surplus: -2 money: $1102
  INFO [CollectionPhase.goToNextPhase]               (P12)<J.I.M.> Food: 1 critical: 3 surplus: -2 money: $961
  INFO [CollectionPhase.goToNextPhase]               (P13)<A.L.A.N.> Food: 7 critical: 3 surplus: 4 money: $927
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_FOOD #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 67 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 4
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 5.155802E-6 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 45 bytes
  INFO [DelayedMessageQueue.add]                     StartAuction is delivered in 0 frames, rountrip 66ms, max 66ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11)xitheta (P10)xitheta4 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P13)<A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #1
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #2
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at -1.5550107 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Shop.setAveragePrice]                        Food price set to average $49 (2 units for $98)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 66 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -3238091941992565415
  INFO [Shop.calcBuySellPrice]                       Current Energy price $25
  INFO [Shop.printPriceFactor]                       Energy shop: 8, total: 17, needed: 5
  INFO [Shop.printPriceFactor]                       Energy is decreased to 47%
  INFO [Shop.printPriceFactor]                       Energy buy price $10 sell price $45
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [CollectionPhase.goToNextPhase]               (P11)xitheta Energy: 2 critical: 1 surplus: 1 money: $1145
  INFO [CollectionPhase.goToNextPhase]               (P10)xitheta4 Energy: 2 critical: 1 surplus: 1 money: $1102
  INFO [CollectionPhase.goToNextPhase]               (P12)<J.I.M.> Energy: 4 critical: 1 surplus: 3 money: $863
  INFO [CollectionPhase.goToNextPhase]               (P13)<A.L.A.N.> Energy: 1 critical: 2 surplus: -1 money: $1025
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_ENERGY #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 66 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 5
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 5.155802E-6 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 45 bytes
  INFO [DelayedMessageQueue.add]                     StartAuction is delivered in 0 frames, rountrip 66ms, max 66ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P13)<A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P11)xitheta (P10)xitheta4 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #1
  INFO [SlickLogSystem.info]                         Transaction #2
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at -0.034744114 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Shop.setAveragePrice]                        Energy price set to average $28 (2 units for $56)
  INFO [GameController.beginNextPhase]               --------- SUMMARY #1 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 66 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -2523218071539825435
  INFO [SummaryPhase2.begin]                         Total Memory: 50331648
  INFO [SummaryPhase2.begin]                         Free Memory: 25379088
  INFO [SummaryPhase2.begin]                         (P13)<A.L.A.N.> Money: $969 Plots: 1 Assets: 389 Total: 1858
  INFO [SummaryPhase2.begin]                         (P13)<A.L.A.N.> Food: 5 Energy: 3 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P12)<J.I.M.> Money: $919 Plots: 1 Assets: 288 Total: 1707
  INFO [SummaryPhase2.begin]                         (P12)<J.I.M.> Food: 3 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11)xitheta Money: $1145 Plots: 0 Assets: 105 Total: 1250
  INFO [SummaryPhase2.begin]                         (P11)xitheta Food: 1 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10)xitheta4 Money: $1102 Plots: 0 Assets: 105 Total: 1207
  INFO [SummaryPhase2.begin]                         (P10)xitheta4 Food: 1 Energy: 2 Smithore: 0 Crystite: 0
  INFO [Shop.buildMules]                             Shop built 2 mules for 4 smithore and now has 14 mules
  INFO [Shop.buildMules]                             A mule now costs $110
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $56
  INFO [SummaryPhase2.begin]                         Shop Smithore: 4 units $56
  INFO [SummaryPhase2.begin]                         Shop Food: 8 units $49
  INFO [SummaryPhase2.begin]                         Shop Energy: 8 units $28
  INFO [SummaryPhase2.begin]                         Shop mules: 14 $110
  INFO [SummaryPhase2.begin]                         Colony total: 6022
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  INFO [Client.receiveTCPMessages]                   Client: Continue (12) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (13) 0 bytes
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [SlickLogSystem.info]                         Too much real time passed since last frame. Breaking sync with real time.
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 6
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #2 --------------------------------------------------------
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 67 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 67ms, max 67ms
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 67ms, max 67ms
  INFO [LandGrantPhase.apply]                        Grace duration 10 frames
  INFO [LandGrantPhase.apply]                        Starting land grant
  INFO [MetaFrame$6.actionPerformed]                 User chooses to disconnect
  INFO [MuleGameContainer.release]                   Closing game.
  INFO [GameController.close]                        -------- CLOSING GAME CONTROLLER ----------------------------------
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  INFO [Client.receiveTCPMessages]                   Client: UserLeft (11) 0 bytes
  INFO [GameController.close]                        Got my UserLeft (11) message.
  INFO [GameController.close]                        Left server in 158 ms
  INFO [MuleGameContainer.release]                   *** Game Over ***
----------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P12 P13> 12ms (14ms) <P11> 71ms (72ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 12 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8294 bytes
  INFO [AbstractAuctionController.begin]             Begin auction id 2
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.93 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 45 bytes
  INFO [DelayedMessageQueue.add]                     StartAuction is delivered in 1 frames, rountrip 11ms, max 69ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 71ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P10)xitheta4 (P12)<J.I.M.> (P13)<A.L.A.N.> (P11)xitheta 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $58
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $56
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Shop.setAveragePrice]                        Smithore price set to average $56 (0 units for $0)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 2
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P12 P13> 9ms (12ms) <P11> 67ms (71ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 10 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8290 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -3704058345909549610
  INFO [Shop.calcBuySellPrice]                       Current Crystite price $48
  INFO [Shop.calcBuySellPrice]                       Crystite buy price $56 sell price $196
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [CollectionPhase.goToNextPhase]               (P11)xitheta Crystite: 0 critical: 0 surplus: 0 money: $1145
  INFO [CollectionPhase.goToNextPhase]               (P10)xitheta4 Crystite: 0 critical: 0 surplus: 0 money: $1102
  INFO [CollectionPhase.goToNextPhase]               (P12)<J.I.M.> Crystite: 0 critical: 0 surplus: 0 money: $961
  INFO [CollectionPhase.goToNextPhase]               (P13)<A.L.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $927
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_CRYSTITE #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P12 P13> 9ms (12ms) <P11> 67ms (71ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8305 bytes
  INFO [AbstractAuctionController.begin]             Begin auction id 3
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Shop.setAveragePrice]                        Crystite price set to average $56 (0 units for $0)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_FOOD #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P12 P13> 9ms (12ms) <P11> 67ms (71ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8288 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 4669038945606582169
  INFO [Shop.calcBuySellPrice]                       Current Food price $30
  INFO [Shop.printPriceFactor]                       Food shop: 8, total: 18, needed: 12
  INFO [Shop.printPriceFactor]                       Food is decreased to 75%
  INFO [Shop.printPriceFactor]                       Food buy price $15 sell price $50
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [CollectionPhase.goToNextPhase]               (P11)xitheta Food: 1 critical: 3 surplus: -2 money: $1145
  INFO [CollectionPhase.goToNextPhase]               (P10)xitheta4 Food: 1 critical: 3 surplus: -2 money: $1102
  INFO [CollectionPhase.goToNextPhase]               (P12)<J.I.M.> Food: 1 critical: 3 surplus: -2 money: $961
  INFO [CollectionPhase.goToNextPhase]               (P13)<A.L.A.N.> Food: 7 critical: 3 surplus: 4 money: $927
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_FOOD #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P12 P13> 7ms (10ms) <P11> 67ms (69ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8290 bytes
  INFO [AbstractAuctionController.begin]             Begin auction id 4
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.934 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 45 bytes
  INFO [DelayedMessageQueue.add]                     StartAuction is delivered in 1 frames, rountrip 6ms, max 66ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 67ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P10)xitheta4 (P12)<J.I.M.> (P11)xitheta 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P13)<A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $102 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $50 ratio 1.0
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #1
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $102 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $50 ratio 1.0
  INFO [SlickLogSystem.info]                         Transaction #2
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $50 ratio 1.0
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 67ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Shop.setAveragePrice]                        Food price set to average $49 (2 units for $98)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 2
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P12 P13> 6ms (8ms) <P11> 66ms (67ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 6 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8315 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -3238091941992565415
  INFO [Shop.calcBuySellPrice]                       Current Energy price $25
  INFO [Shop.printPriceFactor]                       Energy shop: 8, total: 17, needed: 5
  INFO [Shop.printPriceFactor]                       Energy is decreased to 47%
  INFO [Shop.printPriceFactor]                       Energy buy price $10 sell price $45
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [CollectionPhase.goToNextPhase]               (P11)xitheta Energy: 2 critical: 1 surplus: 1 money: $1145
  INFO [CollectionPhase.goToNextPhase]               (P10)xitheta4 Energy: 2 critical: 1 surplus: 1 money: $1102
  INFO [CollectionPhase.goToNextPhase]               (P12)<J.I.M.> Energy: 4 critical: 1 surplus: 3 money: $863
  INFO [CollectionPhase.goToNextPhase]               (P13)<A.L.A.N.> Energy: 1 critical: 2 surplus: -1 money: $1025
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_ENERGY #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P12 P13> 5ms (6ms) <P11> 66ms (67ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 6 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8305 bytes
  INFO [AbstractAuctionController.begin]             Begin auction id 5
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.934 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 45 bytes
  INFO [DelayedMessageQueue.add]                     StartAuction is delivered in 1 frames, rountrip 4ms, max 66ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 67ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P13)<A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10)xitheta4 (P12)<J.I.M.> (P11)xitheta 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $28 ratio 0.54
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $70 ratio 1.7333333
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #1
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $14 ratio 0.14000002
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $53 ratio 1.24
  INFO [SlickLogSystem.info]                         Transaction #2
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 75ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Shop.setAveragePrice]                        Energy price set to average $28 (2 units for $56)
  INFO [GameController.beginNextPhase]               --------- SUMMARY #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P12 P13> 3ms (6ms) <P11> 66ms (75ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 3 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8287 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -2523218071539825435
  INFO [SummaryPhase2.begin]                         Total Memory: 56623104
  INFO [SummaryPhase2.begin]                         Free Memory: 28255248
  INFO [SummaryPhase2.begin]                         (P13)<A.L.A.N.> Money: $969 Plots: 1 Assets: 389 Total: 1858
  INFO [SummaryPhase2.begin]                         (P13)<A.L.A.N.> Food: 5 Energy: 3 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P12)<J.I.M.> Money: $919 Plots: 1 Assets: 288 Total: 1707
  INFO [SummaryPhase2.begin]                         (P12)<J.I.M.> Food: 3 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11)xitheta Money: $1145 Plots: 0 Assets: 105 Total: 1250
  INFO [SummaryPhase2.begin]                         (P11)xitheta Food: 1 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10)xitheta4 Money: $1102 Plots: 0 Assets: 105 Total: 1207
  INFO [SummaryPhase2.begin]                         (P10)xitheta4 Food: 1 Energy: 2 Smithore: 0 Crystite: 0
  INFO [Shop.buildMules]                             Shop built 2 mules for 4 smithore and now has 14 mules
  INFO [Shop.buildMules]                             A mule now costs $110
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $56
  INFO [SummaryPhase2.begin]                         Shop Smithore: 4 units $56
  INFO [SummaryPhase2.begin]                         Shop Food: 8 units $49
  INFO [SummaryPhase2.begin]                         Shop Energy: 8 units $28
  INFO [SummaryPhase2.begin]                         Shop mules: 14 $110
  INFO [SummaryPhase2.begin]                         Colony total: 6022
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Finish Round
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Chat Log
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  INFO [GameController.apply]                        Client Synchronization OK
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 5
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P12 P13> 2ms (3ms) <P11> 67ms (76ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 2 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8303 bytes
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 4 plans testing 6933 tiles in 42641500ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: River Food <claim> (+4) 2034 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2802 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: River Food <claim> (+4) 2034 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2802 points
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 4 plans testing 6933 tiles in 46539700ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: River Food <claim> (+4) 2034 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 2732 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 Smithore <claim> (+4) 2002 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 2573 points
  INFO [LandGrantActuator.update]                    AI: <J.I.M.> wants River (4,1)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: River Food <claim> (+4) 2154 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3025 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: River Food <claim> (+4) 2056 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2927 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: River Food <claim> (+4) 2056 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2927 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain3 Smithore <claim> (+4) 2084 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 2878 points
  INFO [LandGrantActuator.update]                    AI: <A.L.A.N.> wants River (4,4)
  INFO [LandGrantActuator.agreeOnClaims]             AI: Agrees on tiles to claim.
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 1 frames, rountrip 2ms, max 67ms
  INFO [LandGrantPhase$2.finished]                   Grace duration 76ms 10 frames (6 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 1 frames, rountrip 2ms, max 67ms
  INFO [LandGrantPhase.apply]                        Starting land grant
  INFO [Client.receiveTCPMessages]                   Client: UserLeft (11) 0 bytes
  INFO [GameController.enterPausePhase]              -GAME PAUSED-
  INFO [Client.receiveTCPMessages]                   Client: KickUser (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: UserDisconnected (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: KickUser (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Increasing TCP recieve buffer for ReconnectReady (0) 8323 bytes
  INFO [Client.receiveTCPMessages]                   Client: ReconnectReady (0) 8323 bytes
  INFO [GameController.setModel]                     ++++++++ New Model: LAND_GRANT #2 +++++++++++++++++++++++++++++++++++++++++++
  INFO [GameModel.setRemoteUser]                     (P11)xitheta is a remote Player
  INFO [GameModel.setMyUser]                         (P10)xitheta4 is my user
  INFO [GameModel.setLocalUser]                      (P10)xitheta4 is a local Player
  INFO [GameModel.setRemoteUser]                     (P12)<J.I.M.> is a remote Player
  INFO [GameModel.setRemoteUser]                     (P13)<A.L.A.N.> is a remote Player
  INFO [MasterClient.logSend]                        MasterClient: Sending User Left ID 109210 user number 11
  INFO [ConnectedClient.removeUser]                  Server: Removing user 11 from /127.64.151.111 <P11>
  INFO [Server.removeClient]                         Server: Removing client /127.64.151.111 <> with id 5e15a0a0-5ad5-4afc-99c0-35409ce9cb38
  INFO [UserController.setAiPlayer]                  (P11)xitheta is taken over by an AI player
  INFO [UserController.setAiPlayer]                  xitheta is using the Search AI
  INFO [Server.addUserToClient]                      Server: /127.0.0.1 <P10 P12 P13> gets user number 11
  INFO [PlanManager.startPlanSearch]                 AI: xitheta prefers Food
  INFO [GameModel.setLocalUser]                      (P11)xitheta is a local Player
  INFO [MasterClient.logSend]                        MasterClient: Sending User Left ID 0 user number 12
  INFO [DevelopmentPlanFinder$1.run]                 AI: xitheta found 1 plans testing 42 tiles in 725800ns
  INFO [ConnectedClient.removeUser]                  Server: Removing user 12 from /127.0.0.1 <P10 P12 P13 P11>
  INFO [UserController.setAiPlayer]                  (P12)<J.I.M.> is taken over by an AI player
  INFO [UserController.setAiPlayer]                  <J.I.M.> is using the Search AI
  INFO [Server.addUserToClient]                      Server: /127.0.0.1 <P10 P13 P11> gets user number 12
  INFO [GameModel.setLocalUser]                      (P12)<J.I.M.> is a local Player
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 1 plans testing 42 tiles in 731200ns
  INFO [MasterClient.logSend]                        MasterClient: Sending User Left ID 0 user number 13
  INFO [ConnectedClient.removeUser]                  Server: Removing user 13 from /127.0.0.1 <P10 P13 P11 P12>
  INFO [UserController.setAiPlayer]                  (P13)<A.L.A.N.> is taken over by an AI player
  INFO [UserController.setAiPlayer]                  <A.L.A.N.> is using the Search AI
  INFO [Server.addUserToClient]                      Server: /127.0.0.1 <P10 P11 P12> gets user number 13
  INFO [GameModel.setLocalUser]                      (P13)<A.L.A.N.> is a local Player
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 1 plans testing 42 tiles in 398000ns
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [GameController.setModel]                     ++++++++ New Model: LAND_GRANT #2 +++++++++++++++++++++++++++++++++++++++++++
  INFO [DevelopmentPlanFinder.logPlans]              AI: xitheta Plan: No tile 828 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 1088 points
  INFO [LandGrantActuator.update]                    AI: xitheta wants null
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: No tile 1409 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 1724 points
  INFO [LandGrantActuator.update]                    AI: <J.I.M.> wants null
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: No tile 1531 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2107 points
  INFO [LandGrantActuator.update]                    AI: <A.L.A.N.> wants null
  INFO [LandGrantActuator.agreeOnClaims]             AI: Agrees on tiles to claim.
  INFO [LandGrantActuator.agreeOnClaims]             AI: <J.I.M.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <J.I.M.> panics in land grant!
  INFO [LandGrantActuator.agreeOnClaims]             AI: <A.L.A.N.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <A.L.A.N.> panics in land grant!
  INFO [LandGrantActuator.agreeOnClaims]             AI: <A.L.A.N.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <A.L.A.N.> panics in land grant!
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 2ms, max 2ms
  INFO [LandGrantPhase$2.finished]                   Grace duration 3ms 10 frames (6 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 2ms, max 2ms
  INFO [LandGrantPhase.apply]                        Starting land grant
  INFO [LandGrantPhase.update]                       (P12)<J.I.M.> pressed at plot 0 frame 1
  INFO [LandGrantPhase.claimPlot]                    (P12)<J.I.M.> is granted tile 0: Plain (0,0)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 2ms, max 2ms
  INFO [LandGrantPhase.update]                       (P13)<A.L.A.N.> pressed at plot 1 frame 1
  INFO [LandGrantPhase.claimPlot]                    (P13)<A.L.A.N.> is granted tile 1: Mountain3 (1,0)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 2ms, max 2ms
  INFO [LandGrantPhase.moveToNextTile]               Land grant ends
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 4
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 2ms (14ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 2 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8313 bytes
  INFO [PlotSeller.generateNextColonyAuction]        Colony sells plot #1
  INFO [PlotSeller.beginAuction]                     Starting auction set
  INFO [PlotSeller.beginAuction]                     Land auction price: $160
  INFO [LandAuctionActuator.beginLandAuction]        AI: xitheta targets price $476
  INFO [LandAuctionActuator.beginLandAuction]        AI: <J.I.M.> targets price $560
  INFO [LandAuctionActuator.beginLandAuction]        AI: <A.L.A.N.> targets price $648
  INFO [AbstractAuctionController.begin]             Begin auction id 6
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (12) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (13) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (10) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandAuctionStart is delivered in 0 frames, rountrip 11ms, max 11ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P10)xitheta4 (P11)xitheta (P12)<J.I.M.> (P13)<A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: 
  INFO [NetworkTimer.start]                          Started "phase timer" at 34.25 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $648 (changed $36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $572 (changed $12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: xitheta new price $532 (changed $56)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $648 (changed $28)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $580 (changed $8)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $628 (changed $-20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $536 (changed $-44)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $576 (changed $40)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: xitheta new price $552 (changed $20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: xitheta new price $500 (changed $-52)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $576 (changed $-52)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $548 (changed $-28)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $520 (changed $-28)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: xitheta new price $516 (changed $16)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $544 (changed $-32)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $568 (changed $24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $532 (changed $12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $628 (changed $60)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: xitheta new price $492 (changed $-24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $556 (changed $24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $568 (changed $-60)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $588 (changed $20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $568 (changed $12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $572 (changed $-16)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $560 (changed $-8)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $596 (changed $24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $560 (changed $0)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $628 (changed $32)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $496 (changed $-64)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: xitheta new price $444 (changed $-48)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $440 (changed $-56)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: xitheta new price $412 (changed $-32)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $648 (changed $56)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $648 (changed $24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $396 (changed $-44)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $356 (changed $-40)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $612 (changed $-36)
  INFO [SlickLogSystem.info]                         Local players finished auction
  INFO [AbstractLandAuctionPhase.update]             Reached final auction state
  INFO [Client.receiveTCPMessages]                   Client: BuyLand (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [AbstractLandAuctionPhase.buyLand]            (P13)<A.L.A.N.> bought the plot for $436
  INFO [PlotSeller.generateNextColonyAuction]        Colony doesn't sell a plot
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 11ms (14ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 11 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8340 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 940661122253077953
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  INFO [PlayerEventGenerator.nextEvent]              Event random chance: 0.5452526
  INFO [PlayerEventPhase.begin]                      No player event for (P13)<A.L.A.N.> (rank 1)
  INFO [Player.useFood]                              (P13)<A.L.A.N.> has 5 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- FAST_DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 11ms (14ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 11 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8344 bytes
  INFO [Wampus.<init>]                               Wampus has 17 mountains, reward is $100
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 1 plans testing 626 tiles in 3750600ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain3 Smithore (+4) Plain Energy (+3) 2217 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3113 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: PlaceMule
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <A.L.A.N.> plans to develop Mountain3 Smithore (+4) Plain Energy (+3) 2217 points
  INFO [FastDevelopmentPhase.nextAction]             Next action: GoToMuleStore
  INFO [FastDevelopmentPhase.nextAction]             Next action: GoToOutfitStore
  INFO [FastDevelopmentPhase.nextAction]             Next action: PlaceMule
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (13) 16 bytes
  INFO [GameModel.developLand]                       (P13)<A.L.A.N.> develops tile Mountain3 (1,0) with Smithore factory
  INFO [FastDevelopmentPhase.nextAction]             Next action: GoToMuleStore
  INFO [FastDevelopmentPhase.nextAction]             Next action: GoToOutfitStore
  INFO [FastDevelopmentPhase.nextAction]             Next action: PlaceMule
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (13) 16 bytes
  INFO [GameModel.developLand]                       (P13)<A.L.A.N.> develops tile Plain (2,4) with Energy factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToPub
  INFO [FastDevelopmentPhase.nextAction]             Next action: GoToPub
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (13) 16 bytes
  INFO [GameModel.gamble]                            (P13)<A.L.A.N.> gambles $50, max: 47.5, left: 23.75, fraction 0.5
  INFO [DevelopmentMessenger.showMessage]            <A.L.A.N.> won $50 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P13)<A.L.A.N.> gambled and can't do more actions.
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 11ms (14ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 11 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8409 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 4207236842271443002
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventGenerator.nextEvent]              Event random chance: 0.44124007
  INFO [PlayerEventPhase.begin]                      No player event for (P12)<J.I.M.> (rank 2)
  INFO [Player.useFood]                              (P12)<J.I.M.> has 3 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- FAST_DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 11ms (14ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 11 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8415 bytes
  INFO [Wampus.<init>]                               Wampus has 17 mountains, reward is $100
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 1 plans testing 157 tiles in 686800ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain Energy (+3) 1918 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 2528 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <J.I.M.> plans to develop Plain Energy (+3) 1918 points
  INFO [FastDevelopmentPhase.nextAction]             Next action: GoToMuleStore
  INFO [FastDevelopmentPhase.nextAction]             Next action: GoToOutfitStore
  INFO [FastDevelopmentPhase.nextAction]             Next action: PlaceMule
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (12) 16 bytes
  INFO [GameModel.developLand]                       (P12)<J.I.M.> develops tile Plain (0,0) with Energy factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: CatchWampus
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToPub
  INFO [FastDevelopmentPhase.nextAction]             Next action: CatchWampus
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (12) 16 bytes
  INFO [DevelopmentMessenger.showMessage]            <J.I.M.> caught the Mountain Wampus and got $100
  INFO [FastDevelopmentPhase.nextAction]             Next action: GoToPub
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (12) 16 bytes
  INFO [GameModel.gamble]                            (P12)<J.I.M.> gambles $120, max: 47.5, left: 23.75, fraction 0.5
  INFO [DevelopmentMessenger.showMessage]            <J.I.M.> won $120 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P12)<J.I.M.> gambled and can't do more actions.
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms (11ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 10 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8410 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 5212434658381429032
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventGenerator.nextEvent]              Event random chance: 0.15808076
  INFO [PlayerEventPhase.begin]                      Event player: (P11)xitheta (rank 3)
  INFO [PlayerEventPhase.begin]                      Event: ExtraPlot
  INFO [PlayerEventPhase$1.finished]                 Event Message: You received an extra plot of land to encourage colony development
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Player.useFood]                              (P11)xitheta has 1 food and needs 3 food
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 2
  INFO [GameController.beginNextPhase]               --------- FAST_DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms (11ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8439 bytes
  INFO [Wampus.<init>]                               Wampus has 17 mountains, reward is $100
  INFO [DevelopmentPlanFinder$1.run]                 AI: xitheta found 1 plans testing 153 tiles in 629100ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: xitheta Plan: Plain Energy (+3) 1475 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 1977 points
  INFO [DevelopmentActionQueue2.addAction]           AI: xitheta Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: xitheta Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: xitheta Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: xitheta plans to develop Plain Energy (+3) 1475 points
  INFO [FastDevelopmentPhase.nextAction]             Next action: GoToMuleStore
  INFO [FastDevelopmentPhase.nextAction]             Next action: GoToOutfitStore
  INFO [FastDevelopmentPhase.nextAction]             Next action: PlaceMule
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (11) 16 bytes
  INFO [GameModel.developLand]                       (P11)xitheta develops tile Plain (1,4) with Energy factory
  INFO [DevelopmentActionQueue2.addAction]           AI: xitheta Queue: CatchWampus
  INFO [DevelopmentActionQueue2.addAction]           AI: xitheta Queue: GoToPub
  INFO [FastDevelopmentPhase.nextAction]             Next action: CatchWampus
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (11) 16 bytes
  INFO [DevelopmentMessenger.showMessage]            xitheta caught the Mountain Wampus and got $100
  INFO [FastDevelopmentPhase.nextAction]             Next action: GoToPub
  INFO [Client.receiveTCPMessages]                   Client: FastDevelopment (11) 16 bytes
  INFO [GameModel.gamble]                            (P11)xitheta gambles $86, max: 47.5, left: 9.583334, fraction 0.2017544
  INFO [DevelopmentMessenger.showMessage]            xitheta won $86 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P11)xitheta gambled and can't do more actions.
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms (10ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8452 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 6197833194216360308
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventGenerator.nextEvent]              Event random chance: 0.9258181
  INFO [PlayerEventPhase.begin]                      No player event for (P10)xitheta4 (rank 4)
  INFO [Player.useFood]                              (P10)xitheta4 has 1 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 7ms (8ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 7 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8447 bytes
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10)xitheta4
  INFO [Wampus.<init>]                               Wampus has 17 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 2.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [GameModel.gamble]                            (P10)xitheta4 gambles $54, max: 47.5, left: 14.566563, fraction 0.30666447
  INFO [DevelopmentMessenger.showMessage]            xitheta4 won $54 in the pub
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P10)xitheta4 finishes on update 230
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_A #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 5ms (8ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 6 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8471 bytes
  INFO [ColonyEventPhase.begin]                      ColonyEvent: ACID_RAIN_STORM
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 3840780029709992740
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 5ms (8ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 6 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8469 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 7826157822479573139
  INFO [ProductionPhase.finishPower]                 (P11)xitheta has 2 energy and needs 0 energy
  INFO [ProductionPhase.finishPower]                 (P10)xitheta4 has 2 energy and needs 0 energy
  INFO [ProductionPhase.finishPower]                 (P12)<J.I.M.> has 2 energy and needs 0 energy
  INFO [ProductionPhase.finishPower]                 (P13)<A.L.A.N.> has 3 energy and needs 2 energy
  INFO [ProductionPhase.logProduction]               (P11)xitheta expects 0 food, 2 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10)xitheta4 expects 0 food, 0 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12)<J.I.M.> expects 0 food, 4 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13)<A.L.A.N.> expects 5 food, 2 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.apply]                       Skipping production
  INFO [ProductionPhase.logProduction]               (P11)xitheta produced 0 food, 2 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10)xitheta4 produced 0 food, 0 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12)<J.I.M.> produced 0 food, 5 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13)<A.L.A.N.> produced 6 food, 3 energy, 3 smithore, 0 crystite
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_B #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 5ms (8ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 5 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8485 bytes
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 5ms (8ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 5 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8487 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -7439077356745522096
  INFO [Shop.calcBuySellPrice]                       Current Smithore price $56
  INFO [Shop.calcBuySellPrice]                       Smithore random variation $8
  INFO [Shop.printPriceFactor]                       Smithore shop: 4, mules potentially available: 12, mules needed: 4
  INFO [Shop.printPriceFactor]                       Smithore is decreased to 50%
  INFO [Shop.printPriceFactor]                       Smithore buy price $58 sell price $93
  INFO [CollectionPhase.goToNextPhase]               (P11)xitheta Smithore: 0 critical: 0 surplus: 0 money: $1171
  INFO [CollectionPhase.goToNextPhase]               (P10)xitheta4 Smithore: 0 critical: 0 surplus: 0 money: $1156
  INFO [CollectionPhase.goToNextPhase]               (P12)<J.I.M.> Smithore: 0 critical: 0 surplus: 0 money: $979
  INFO [CollectionPhase.goToNextPhase]               (P13)<A.L.A.N.> Smithore: 3 critical: 0 surplus: 3 money: $238
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_SMITHORE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 3ms (7ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 5 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8470 bytes
  INFO [AbstractAuctionController.begin]             Begin auction id 7
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.997 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 45 bytes
  INFO [DelayedMessageQueue.add]                     StartAuction is delivered in 0 frames, rountrip 3ms, max 3ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 6ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P10)xitheta4 (P11)xitheta (P12)<J.I.M.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P13)<A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: xitheta target price $62
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $64
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $58
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #1
  INFO [SlickLogSystem.info]                         Transaction #2
  INFO [SlickLogSystem.info]                         Transaction #3
  INFO [Player.increaseAuctionTeleportCount]         (P13)<A.L.A.N.> auction teleport count: 1
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 6ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Smithore price set to average $58 (3 units for $174)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 3ms (5ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 3 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8495 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -8718274170156243001
  INFO [Shop.calcBuySellPrice]                       Current Crystite price $56
  INFO [Shop.calcBuySellPrice]                       Crystite buy price $84 sell price $224
  INFO [CollectionPhase.goToNextPhase]               (P11)xitheta Crystite: 0 critical: 0 surplus: 0 money: $1171
  INFO [CollectionPhase.goToNextPhase]               (P10)xitheta4 Crystite: 0 critical: 0 surplus: 0 money: $1156
  INFO [CollectionPhase.goToNextPhase]               (P12)<J.I.M.> Crystite: 0 critical: 0 surplus: 0 money: $979
  INFO [CollectionPhase.goToNextPhase]               (P13)<A.L.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $412
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_CRYSTITE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 3ms (5ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 3 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8475 bytes
  INFO [AbstractAuctionController.begin]             Begin auction id 8
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Crystite price set to average $84 (0 units for $0)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_FOOD #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 3ms (3ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 3 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8458 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -4390025354863952333
  INFO [Shop.calcBuySellPrice]                       Current Food price $49
  INFO [Shop.printPriceFactor]                       Food shop: 8, total: 15, needed: 12
  INFO [Shop.printPriceFactor]                       Food is decreased to 85%
  INFO [Shop.printPriceFactor]                       Food buy price $27 sell price $62
  INFO [CollectionPhase.goToNextPhase]               (P11)xitheta Food: 0 critical: 3 surplus: -3 money: $1171
  INFO [CollectionPhase.goToNextPhase]               (P10)xitheta4 Food: 0 critical: 3 surplus: -3 money: $1156
  INFO [CollectionPhase.goToNextPhase]               (P12)<J.I.M.> Food: 0 critical: 3 surplus: -3 money: $979
  INFO [CollectionPhase.goToNextPhase]               (P13)<A.L.A.N.> Food: 7 critical: 3 surplus: 4 money: $412
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_FOOD #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 2ms (3ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 3 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8454 bytes
  INFO [AbstractAuctionController.begin]             Begin auction id 9
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.999 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 45 bytes
  INFO [DelayedMessageQueue.add]                     StartAuction is delivered in 0 frames, rountrip 1ms, max 1ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 3ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P10)xitheta4 (P11)xitheta (P12)<J.I.M.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P13)<A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: xitheta target price $114 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $114 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $62 ratio 1.0
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #1
  INFO [AuctionActuator.calcTargetPrice]             AI: xitheta target price $114 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $114 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $62 ratio 1.0
  INFO [SlickLogSystem.info]                         Transaction #2
  INFO [AuctionActuator.calcTargetPrice]             AI: xitheta target price $114 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $114 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $62 ratio 1.0
  INFO [SlickLogSystem.info]                         Transaction #3
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $114 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $62 ratio 1.0
  INFO [AbstractAuctionPhase.checkCancelTransactions] Buyer left auction line.
  INFO [SlickLogSystem.info]                         Transaction #4
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $114 ratio 2.5
  INFO [Player.increaseAuctionTeleportCount]         (P13)<A.L.A.N.> auction teleport count: 1
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 3ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #5
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $114 ratio 2.5
  INFO [SlickLogSystem.info]                         Transaction #6
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 3ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Food price set to average $61 (6 units for $368)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (2ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8505 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 7344923952431421887
  INFO [Shop.calcBuySellPrice]                       Current Energy price $28
  INFO [Shop.printPriceFactor]                       Energy shop: 8, total: 25, needed: 6
  INFO [Shop.printPriceFactor]                       Energy is decreased to 43%
  INFO [Shop.printPriceFactor]                       Energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P11)xitheta Energy: 4 critical: 1 surplus: 3 money: $988
  INFO [CollectionPhase.goToNextPhase]               (P10)xitheta4 Energy: 2 critical: 1 surplus: 1 money: $1156
  INFO [CollectionPhase.goToNextPhase]               (P12)<J.I.M.> Energy: 7 critical: 1 surplus: 6 money: $794
  INFO [CollectionPhase.goToNextPhase]               (P13)<A.L.A.N.> Energy: 4 critical: 3 surplus: 1 money: $656
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_ENERGY #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8478 bytes
  INFO [AbstractAuctionController.begin]             Begin auction id 10
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 1.0 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 45 bytes
  INFO [DelayedMessageQueue.add]                     StartAuction is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P13)<A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10)xitheta4 (P11)xitheta (P12)<J.I.M.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: xitheta target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #1
  INFO [AuctionActuator.calcTargetPrice]             AI: xitheta target price $10 ratio 0.02000001
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $10 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #2
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $10 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #3
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $11 ratio 0.04000002
  INFO [SlickLogSystem.info]                         Transaction #4
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $14 ratio 0.14000002
  INFO [SlickLogSystem.info]                         Transaction #5
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Energy price set to average $25 (7 units for $70)
  INFO [GameController.beginNextPhase]               --------- SUMMARY #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8480 bytes
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -3739035205418273757
  INFO [SummaryPhase2.begin]                         Total Memory: 56623104
  INFO [SummaryPhase2.begin]                         Free Memory: 24819344
  INFO [SummaryPhase2.begin]                         (P13)<A.L.A.N.> Money: $656 Plots: 3 Assets: 538 Total: 2694
  INFO [SummaryPhase2.begin]                         (P13)<A.L.A.N.> Food: 3 Energy: 4 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P12)<J.I.M.> Money: $844 Plots: 2 Assets: 403 Total: 2247
  INFO [SummaryPhase2.begin]                         (P12)<J.I.M.> Food: 3 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11)xitheta Money: $1008 Plots: 1 Assets: 318 Total: 1826
  INFO [SummaryPhase2.begin]                         (P11)xitheta Food: 3 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10)xitheta4 Money: $1156 Plots: 0 Assets: 50 Total: 1206
  INFO [SummaryPhase2.begin]                         (P10)xitheta4 Food: 0 Energy: 2 Smithore: 0 Crystite: 0
  INFO [Shop.buildMules]                             Shop built 3 mules for 6 smithore and now has 13 mules
  INFO [Shop.buildMules]                             A mule now costs $110
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $84
  INFO [SummaryPhase2.begin]                         Shop Smithore: 1 units $58
  INFO [SummaryPhase2.begin]                         Shop Food: 6 units $61
  INFO [SummaryPhase2.begin]                         Shop Energy: 15 units $25
  INFO [SummaryPhase2.begin]                         Shop mules: 13 $110
  INFO [SummaryPhase2.begin]                         Colony total: 7973
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Finish Round
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Chat Log
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 5
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8489 bytes
  INFO [Development.setPlayerOrder]                  Normal development order. 13 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: xitheta found 4 plans testing 5665 tiles in 13725300ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 4 plans testing 5665 tiles in 13671500ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 4 plans testing 5665 tiles in 14871800ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: xitheta Plan: River Food <claim> (+4) 2129 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2920 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: xitheta Plan: River Food <claim> (+4) 2129 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2920 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: xitheta Plan: River Food <claim> (+4) 2129 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2798 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: xitheta Plan: Plain Food <claim> (+2) 1987 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2595 points
  INFO [LandGrantActuator.update]                    AI: xitheta wants River (4,1)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: River Food <claim> (+4) 2625 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3491 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: River Food <claim> (+4) 2625 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3491 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: River Food <claim> (+4) 2625 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 3375 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain Food <claim> (+2) 2483 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3166 points
  INFO [LandGrantActuator.update]                    AI: <J.I.M.> wants River (4,1)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: River Food <claim> (+4) 3315 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 4585 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: River Food <claim> (+4) 3193 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 4463 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: River Food <claim> (+4) 3193 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 4463 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain2 Smithore <claim> (+3) 3239 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 4412 points
  INFO [LandGrantActuator.update]                    AI: <A.L.A.N.> wants River (4,4)
  INFO [LandGrantActuator.agreeOnClaims]             AI: Agrees on tiles to claim.
  INFO [LandGrantActuator.agreeOnClaims]             AI: <J.I.M.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <J.I.M.> can't claim River (4,1) and wants River (4,0) instead
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase$2.finished]                   Grace duration 0ms 10 frames (6 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        Starting land grant
  INFO [LandGrantPhase.update]                       (P12)<J.I.M.> pressed at plot 2 frame 3
  INFO [LandGrantPhase.claimPlot]                    (P12)<J.I.M.> is granted tile 2: River (4,0)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.update]                       (P11)xitheta pressed at plot 11 frame 2
  INFO [LandGrantPhase.claimPlot]                    (P11)xitheta is granted tile 11: River (4,1)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.update]                       (P13)<A.L.A.N.> pressed at plot 33 frame 1
  INFO [LandGrantPhase.claimPlot]                    (P13)<A.L.A.N.> is granted tile 33: River (4,4)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.moveToNextTile]               Land grant ends
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameModelSerializer.serializeModel]          Packed game model to 8475 bytes
  INFO [PlotSeller.generateNextColonyAuction]        Colony doesn't sell a plot
  INFO [MetaFrame$6.actionPerformed]                 User chooses to disconnect
  INFO [MuleGameContainer.release]                   Closing game.
  INFO [GameController.close]                        -------- CLOSING GAME CONTROLLER ----------------------------------
  INFO [MasterClient.logSend]                        MasterClient: Sending User Left ID 109244 user number 10
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Chat Log
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
