  INFO [Properties.<init>]                           VERSION: 1.3.6
  INFO [Properties.<init>]                           Classpath: /tmp/.mount_planetARx7kk/data.jar
  INFO [Properties.<init>]                           Java VM: 24.80-b11
  INFO [Properties.<init>]                           Java Runtime: 1.7.0_80-b15
  INFO [Properties.<init>]                           OS: Linux 5.10.0-kali3-amd64 amd64
  INFO [Properties.<init>]                           Java Library Path: /tmp/.mount_planetARx7kk/lib
  INFO [Properties.<init>]                           Max Memory: 238551040
  INFO [Properties.<init>]                           Avaialble Processors: 2
  INFO [PropertiesBase.readFromFile]                 Reading Settings from file settings.txt
  INFO [Settings.<init>]                             Settings file not found: settings.txt (No such file or directory)
  INFO [SubstanceLoader.initLookAndFeel]             Window Skin: Default
  SEVERE [Main.logError]                             java.lang.UnsatisfiedLinkError: /tmp/.mount_planetARx7kk/lib/liblpsolve55j.so: /tmp/.mount_planetARx7kk/lib/liblpsolve55j.so: wrong ELF class: ELFCLASS32 (Possible cause: architecture word width mismatch)
	 at java.lang.ClassLoader$NativeLibrary.load(Native Method)
	 at java.lang.ClassLoader.loadLibrary1(Unknown Source)
	 at java.lang.ClassLoader.loadLibrary0(Unknown Source)
	 at java.lang.ClassLoader.loadLibrary(Unknown Source)
	 at java.lang.Runtime.loadLibrary0(Unknown Source)
	 at java.lang.System.loadLibrary(Unknown Source)
	 at lpsolve.LpSolve.<clinit>(LpSolve.java:275)
	 at com.turborilla.mule.Main.main(Main.java:63)
  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: sod6poppru33cdnok5nphkt0j4
  INFO [Login$MasterListener$1.run]                  Logged in as "El Chapo" (id 108167)
  INFO [MetaFrame.<init>]                            Creating Meta Frame
  INFO [MapSkinParser.findSkins]                     No extra skins found
  INFO [MasterClient.logSend]                        MasterClient: Sending Enter Lobby
  INFO [Login.dispose]                               Disposing login
  INFO [Main.main]                                   Creating game container
  INFO [MuleGameContainer.<init>]                    LWJGL Version: 2.4.2
  INFO [MuleGameContainer.<init>]                    Desktop Display Mode: 1400 x 1050 x 24 @60Hz
  INFO [MuleGameContainer.<init>]                    Current Display Mode: 1400 x 1050 x 24 @60Hz
  INFO [MuleGameContainer.<init>]                    Display Adapter: null
  INFO [MuleGameContainer.<init>]                    Display Driver: null
  INFO [MuleGameContainer.setVSync]                  Setting vsync: true
  INFO [MuleGameContainer.<init>]                    Setting pixel format
  INFO [MuleGameContainer$1.run]                     Pixel format: Samples=0 Alpha=0 Depth=8 Stencil=0
  INFO [MuleGameContainer.<init>]                    GL Vendor: Mesa/X.org
  INFO [MuleGameContainer.<init>]                    GL Renderer: llvmpipe (LLVM 11.0.1, 256 bits)
  INFO [MuleGameContainer.<init>]                    GL Version: 3.1 Mesa 20.3.4
  INFO [MuleGameContainer.<init>]                    GL Max Texture Size: null
  INFO [SlickLogSystem.info]                         Use Java PNG Loader = true
  INFO [MuleGameContainer.<init>]                    Display finished
  INFO [Main.main]                                   Entering game loop
++++++++ END OF INITIALIZATION +++++++++++++++++++++++++++++++++++++++++++
  INFO [MetaFrame.startGame]                         Starting game number 0
  INFO [MetaFrame.startGame]                         Entering Game - ID: 0, Name: newsystemtesting, Address: 127.0.0.1, Is Host: true
  INFO [MuleGameContainer.init]                      *** Starting Game ***
  INFO [MuleGameContainer.init]                      Display: Created=true Active=false Visible=true
  INFO [MuleGameContainer.init]                      Making display the current GL context
  INFO [View.<init>]                                 Creating view...
  INFO [View.<init>]                                 Finished creating view
  INFO [GameController.setModel]                     ++++++++ New Model: CONNECT #0 +++++++++++++++++++++++++++++++++++++++++++
  INFO [Server.<init>]                               Server: Creating
  INFO [Server.<init>]                               Server: Started on TCP address /0:0:0:0:0:0:0:0:6260
  INFO [Server.<init>]                               Server: Started on UDP address /0:0:0:0:0:0:0:0:6260
  INFO [Client.connect]                              Client connects...
  INFO [Client.connect]                              Client UDP local address: 0.0.0.0/0.0.0.0:6261
  INFO [Client.connect]                              Client: Connects TCP: /127.0.0.1:6260 UDP: /127.0.0.1:6260
  INFO [Client.connect]                              Client: TCP receive buffer: 65536 send buffer: 8192
  INFO [Server.acceptClients]                        Server: Accepted client /127.0.0.1 id b62398e8-e631-4b09-8b3a-73993998d234
  INFO [Client.finishConnect]                        Client finishes connection
  INFO [ServerMessageHandler.processJoin]            Server: Client /127.0.0.1 <S10> joins
  INFO [Client.receiveTCPMessages]                   Client: ServerInfo (0) 35 bytes
  INFO [Client.receiveTCPMessages]                   Client: Join (10) 40 bytes
  INFO [UserController.apply]                        My client id is b62398e8-e631-4b09-8b3a-73993998d234
  INFO [Player.<init>]                               Creating (P0)Spectator
  INFO [GameModel.setMyUser]                         (S10)El Chapo is my user
  INFO [GameModel.setLocalUser]                      (S10)El Chapo is a local Spectator
  INFO [GameModel.setServerUser]                     (S10)El Chapo is hosting the server
  INFO [GameController.beginNextPhase]               (S10)El Chapo is not accepted
  INFO [GameController.beginNextPhase]               --------- GAME_LOBBY #0 --------------------------------------------------------
  INFO [Server.acceptClientJoin]                     Server: Join accepted for /127.0.0.1 <S10>
  INFO [Server.logRoundtrip]                         Roundtrips: <S10> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameLobbyPhase.getMasterUserInfo]            Fetching user info for (S10)El Chapo (ID: 108167)
  INFO [Client.initUDP]                              Sending UDP initialization message id b62398e8-e631-4b09-8b3a-73993998d234
  INFO [Server.initUdp]                              Server: Client /127.0.0.1 <S10> requests UDP address /127.0.0.1:6261
  INFO [GameLobbyPhase.apply]                        My UDP connection is initialized
  INFO [MuleGameContainer.setWindowedMode]           Setting windowed mode: 640 x 480 x 0 @0Hz
  INFO [Server.acceptClients]                        Server: Accepted client /54.214.39.13 id 6a95a67e-a94a-4089-9fbe-e7b8f960d68b
  INFO [MasterClient.logSend]                        MasterClient: Sending New Game
  INFO [GameLobbyPhase$MasterListener.apply]         Got user info for (S10)El Chapo (ID 108167)
  INFO [GameLobbyPhase$MasterListener.apply]         Master server gives game ID 146738
  INFO [MasterClient.logSend]                        MasterClient: Sending User Join
  INFO [ServerMessageHandler.processAIJoin]          Server: AI gets user number 11
  INFO [Client.receiveTCPMessages]                   Client: AIJoin (11) 12 bytes
  INFO [Player.<init>]                               Creating (P11)<J.I.M.>
  INFO [UserController.setAiPlayer]                  (P11)<J.I.M.> is an AI player
  INFO [UserController.setAiPlayer]                  <J.I.M.> is using the Search AI
  INFO [GameModel.setLocalUser]                      (P11)<J.I.M.> is a local Player
  INFO [MasterClient.logSend]                        MasterClient: Sending User Join
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [ServerMessageHandler.processAIJoin]          Server: AI gets user number 12
  INFO [Client.receiveTCPMessages]                   Client: AIJoin (12) 14 bytes
  INFO [Player.<init>]                               Creating (P12)<B.I.L.L.>
  INFO [UserController.setAiPlayer]                  (P12)<B.I.L.L.> is an AI player
  INFO [UserController.setAiPlayer]                  <B.I.L.L.> is using the Search AI
  INFO [GameModel.setLocalUser]                      (P12)<B.I.L.L.> is a local Player
  INFO [MasterClient.logSend]                        MasterClient: Sending User Join
  INFO [Client.receiveTCPMessages]                   Client: Continue (12) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: KickUser (0) 4 bytes
  INFO [MasterClient.logSend]                        MasterClient: Sending User Left ID 0 user number 12
  INFO [ConnectedClient.removeUser]                  Server: Removing user 12 from /127.0.0.1 <S10 P11 P12>
  INFO [ServerMessageHandler.processPlayerJoin]      /127.0.0.1 <S10 P11> joins game as a player
  INFO [Client.receiveTCPMessages]                   Client: PlayerJoin (10) 0 bytes
  INFO [UserController.apply]                        (S10)El Chapo joins the players
  INFO [Player.<init>]                               Creating (P10)El Chapo
  INFO [GameModel.setServerUser]                     (P10)El Chapo is hosting the server
  INFO [GameModel.setMyUser]                         (P10)El Chapo is my user
  INFO [GameModel.setLocalUser]                      (P10)El Chapo is a local Player
  INFO [ServerMessageHandler.processAIJoin]          Server: AI gets user number 12
  INFO [Client.receiveTCPMessages]                   Client: AIJoin (12) 12 bytes
  INFO [Player.<init>]                               Creating (P12)<D.A.N.>
  INFO [UserController.setAiPlayer]                  (P12)<D.A.N.> is an AI player
  INFO [UserController.setAiPlayer]                  <D.A.N.> is using the Search AI
  INFO [GameModel.setLocalUser]                      (P12)<D.A.N.> is a local Player
  INFO [MasterClient.logSend]                        MasterClient: Sending User Join
  INFO [Client.receiveTCPMessages]                   Client: Continue (12) 0 bytes
  INFO [ServerMessageHandler.processAIJoin]          Server: AI gets user number 13
  INFO [Client.receiveTCPMessages]                   Client: AIJoin (13) 14 bytes
  INFO [Player.<init>]                               Creating (P13)<B.I.L.L.>
  INFO [UserController.setAiPlayer]                  (P13)<B.I.L.L.> is an AI player
  INFO [UserController.setAiPlayer]                  <B.I.L.L.> is using the Search AI
  INFO [GameModel.setLocalUser]                      (P13)<B.I.L.L.> is a local Player
  INFO [MasterClient.logSend]                        MasterClient: Sending User Join
  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: (P11)<J.I.M.> (P10)El Chapo (P12)<D.A.N.> (P13)<B.I.L.L.> 
  INFO [MasterClient.logSend]                        MasterClient: Sending Start Game
  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: 26
  INFO [GameController.beginNextPhase]               --------- INTRO #0 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 2ms (2ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 2 ms
  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: 11
  INFO [GameController.beginNextPhase]               --------- SUMMARY #0 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 2ms (5ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 2 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -4819427765242001173
  INFO [GameModel.updatePlayerRankOrder]             Random rank order: (P12)<D.A.N.> (P13)<B.I.L.L.> (P11)<J.I.M.> (P10)El Chapo 
  INFO [SummaryPhase2.begin]                         Total Memory: 121634816
  INFO [SummaryPhase2.begin]                         Free Memory: 85845064
  INFO [SummaryPhase2.begin]                         (P12)<D.A.N.> Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P12)<D.A.N.> Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P13)<B.I.L.L.> Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P13)<B.I.L.L.> Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11)<J.I.M.> Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P11)<J.I.M.> Food: 4 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10)El Chapo Money: $1000 Plots: 0 Assets: 170 Total: 1170
  INFO [SummaryPhase2.begin]                         (P10)El Chapo 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 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 [MuleGameContainer.setFullscreen]             Setting fullscreen mode
  INFO [MuleGameContainer.setFullscreen]             Fullscreen mode: 1400 x 1050 x 24 @60Hz Scale: 2 Height-Diff: 90 Aspect-Diff: 0.0 Snap Scale: true
  INFO [MuleGameContainer.setFullscreen]             Viewport: x=60 y=45 width=1280 height=960
  INFO [MuleGameContainer.setWindowedMode]           Setting windowed mode: 640 x 480 x 0 @0Hz
  INFO [MuleGameContainer.setFullscreen]             Setting fullscreen mode
  INFO [MuleGameContainer.setFullscreen]             Fullscreen mode: 1400 x 1050 x 24 @60Hz Scale: 2 Height-Diff: 90 Aspect-Diff: 0.0 Snap Scale: true
  INFO [MuleGameContainer.setFullscreen]             Viewport: x=60 y=45 width=1280 height=960
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1002
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1002
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1002
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1002
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1002
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1002
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1002
  INFO [MuleGameContainer.setWindowedMode]           Setting windowed mode: 640 x 480 x 0 @0Hz
  INFO [MuleGameContainer.setFullscreen]             Setting fullscreen mode
  INFO [MuleGameContainer.setFullscreen]             Fullscreen mode: 1400 x 1050 x 24 @60Hz Scale: 2 Height-Diff: 90 Aspect-Diff: 0.0 Snap Scale: true
  INFO [MuleGameContainer.setFullscreen]             Viewport: x=60 y=45 width=1280 height=960
  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: 722
  INFO [GameController.beginNextPhase]               --------- INTRO #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms (10ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  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: 407
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms (10ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [PlanManager.startPlanSearch]                 AI: <J.I.M.> prefers Energy
  INFO [PlanManager.startPlanSearch]                 AI: <D.A.N.> prefers Energy
  INFO [PlanManager.startPlanSearch]                 AI: <B.I.L.L.> prefers Food
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 7616 tiles in 689542928ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 4 plans testing 7616 tiles in 718406915ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 7616 tiles in 723152342ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain Energy <claim> (+3) 1435 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1920 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain Energy <claim> (+3) 1435 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1920 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain Energy <claim> (+3) 1435 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1920 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain Energy <claim> (+3) 1435 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1920 points
  INFO [LandGrantActuator.update]                    AI: <J.I.M.> wants Plain (3,2)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 1435 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1920 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 1435 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1920 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 1435 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1920 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 1435 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1920 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants Plain (3,2)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food <claim> (+4) 1450 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 1960 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food <claim> (+4) 1450 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 1960 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food <claim> (+4) 1450 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 1960 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food <claim> (+4) 1450 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 1960 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants River (4,3)
  INFO [LandGrantActuator.agreeOnClaims]             AI: Agrees on tiles to claim.
  INFO [LandGrantActuator.agreeOnClaims]             AI: <D.A.N.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <D.A.N.> can't claim Plain (3,2) and wants Plain (5,1) instead
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 8ms, max 8ms
  INFO [LandGrantPhase$2.finished]                   Grace duration 10ms 10 frames (6 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 8ms, max 8ms
  INFO [LandGrantPhase.apply]                        Starting land grant
  INFO [LandGrantPhase.update]                       (P10)El Chapo pressed at plot 12 frame 1
  INFO [LandGrantPhase.claimPlot]                    (P10)El Chapo is granted tile 12: Mountain3 (3,1)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 8ms, max 8ms
  INFO [LandGrantPhase.update]                       (P12)<D.A.N.> pressed at plot 14 frame 1
  INFO [LandGrantPhase.claimPlot]                    (P12)<D.A.N.> is granted tile 14: Plain (5,1)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 8ms, max 8ms
  INFO [LandGrantPhase.update]                       (P11)<J.I.M.> pressed at plot 21 frame 1
  INFO [LandGrantPhase.claimPlot]                    (P11)<J.I.M.> is granted tile 21: Plain (3,2)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 8ms, max 8ms
  INFO [LandGrantPhase.update]                       (P13)<B.I.L.L.> pressed at plot 30 frame 2
  INFO [LandGrantPhase.claimPlot]                    (P13)<B.I.L.L.> 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 8ms, max 8ms
  INFO [LandGrantPhase.moveToNextTile]               Land grant ends
  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: 943
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms (9ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [PlotSeller.generateNextColonyAuction]        Colony sells plot #1
  INFO [PlotSeller.beginAuction]                     Starting auction set
  INFO [PlotSeller.beginAuction]                     Land auction price: $160
  INFO [LandAuctionActuator.beginLandAuction]        AI: <J.I.M.> targets price $528
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $700
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $532
  INFO [AbstractAuctionController.begin]             Begin auction id 1
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 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 1ms, max 1ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11)<J.I.M.> (P10)El Chapo (P12)<D.A.N.> (P13)<B.I.L.L.> 
  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: <J.I.M.> new price $484 (changed $-44)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $656 (changed $-44)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $496 (changed $12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $536 (changed $4)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $644 (changed $-12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $496 (changed $0)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $496 (changed $-40)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $480 (changed $-16)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $612 (changed $-32)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $668 (changed $56)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $464 (changed $-16)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $460 (changed $-36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $460 (changed $0)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $512 (changed $52)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $452 (changed $-12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $648 (changed $-20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $452 (changed $0)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $476 (changed $-36)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  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]            (P10)El Chapo bought the plot for $580
  INFO [PlotSeller.generateNextColonyAuction]        Colony sells plot #2
  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: 441
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 1ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 1 ms
  INFO [PlotSeller.beginAuction]                     Land auction price: $520
  INFO [LandAuctionActuator.beginLandAuction]        AI: <J.I.M.> targets price $508
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $456
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $560
  INFO [AbstractAuctionController.begin]             Begin auction id 2
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 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 1ms, max 1ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11)<J.I.M.> (P10)El Chapo (P12)<D.A.N.> (P13)<B.I.L.L.> 
  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 [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  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)<B.I.L.L.> bought the plot for $524
  INFO [PlotSeller.generateNextColonyAuction]        Colony sells plot #3
  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: 532
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 1 ms
  INFO [PlotSeller.beginAuction]                     Land auction price: $464
  INFO [LandAuctionActuator.beginLandAuction]        AI: <J.I.M.> targets price $532
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $692
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $24
  INFO [AbstractAuctionController.begin]             Begin auction id 3
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 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 0ms, max 0ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11)<J.I.M.> (P10)El Chapo (P12)<D.A.N.> (P13)<B.I.L.L.> 
  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: <J.I.M.> new price $548 (changed $16)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $700 (changed $8)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $536 (changed $-12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $680 (changed $-20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $596 (changed $60)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $656 (changed $60)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $692 (changed $12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $636 (changed $-20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $692 (changed $0)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $584 (changed $-52)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $656 (changed $-36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $676 (changed $20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $560 (changed $-24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $652 (changed $-24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $548 (changed $-12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $512 (changed $-36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $488 (changed $-24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $668 (changed $16)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1002
  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]            (P12)<D.A.N.> bought the plot for $500
  INFO [PlotSeller.generateNextColonyAuction]        Colony doesn't sell a plot
  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: 526
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 9174572265769546324
  INFO [PlayerEventPhase.begin]                      Switching fast AI development
  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 (P12)<D.A.N.> (rank 1)
  INFO [Player.useFood]                              (P12)<D.A.N.> has 4 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: GameSettings (0) 1 bytes
  INFO [PlayerEventPhase.apply]                      Setting fast AI development: false
  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: 213
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12)<D.A.N.>
  INFO [Wampus.<init>]                               Wampus has 16 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 1 plans testing 594 tiles in 74421247ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy (+3) Mountain1 Energy (+1) 1445 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 2055 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: PlaceMule
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <D.A.N.> plans to develop Plain Energy (+3) Mountain1 Energy (+1) 1445 points
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToMuleStore
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P12)<D.A.N.> develops tile Plain (5,1) with Energy factory
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToMuleStore
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P12)<D.A.N.> develops tile Mountain1 (5,2) with Energy factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P12)<D.A.N.> gambles $145, max: 47.5, left: 27.639545, fraction 0.58188516
  INFO [DevelopmentMessenger.showMessage]            <D.A.N.> won $145 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P12)<D.A.N.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P12)<D.A.N.> finishes on update 993
  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: 950
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 7985168108900397847
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventGenerator.nextEvent]              No event frist round
  INFO [PlayerEventPhase.begin]                      No player event for (P13)<B.I.L.L.> (rank 2)
  INFO [Player.useFood]                              (P13)<B.I.L.L.> has 4 food and needs 3 food
  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: 215
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13)<B.I.L.L.>
  INFO [Wampus.<init>]                               Wampus has 16 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 1 plans testing 594 tiles in 93020679ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Energy (+3) River Food (+4) 1456 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 2121 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: PlaceMule
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <B.I.L.L.> plans to develop Plain Energy (+3) River Food (+4) 1456 points
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToMuleStore
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P13)<B.I.L.L.> develops tile Plain (1,3) with Energy factory
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToMuleStore
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P13)<B.I.L.L.> develops tile River (4,3) with Food factory
  INFO [DevelopmentActuator.enqueueAssay]            AI: (P13)<B.I.L.L.> want's to assay Plain (6,1) with 24.299469 time left.
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToAssayStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: Assay
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToAssayStore
  INFO [GameModel.buyAssay]                          (P13)<B.I.L.L.> got an assay bot
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: Assay
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1002
  INFO [DevelopmentMessenger.showMessage]            Found Low amounts of Crystite
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToPub
  INFO [GameModel.gamble]                            (P13)<B.I.L.L.> gambles $54, max: 47.5, left: 12.2191925, fraction 0.25724617
  INFO [DevelopmentMessenger.showMessage]            <B.I.L.L.> won $54 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P13)<B.I.L.L.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P13)<B.I.L.L.> finishes on update 1764
  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: 470
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 8498269707142172750
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventGenerator.nextEvent]              No event frist round
  INFO [PlayerEventPhase.begin]                      No player event for (P11)<J.I.M.> (rank 3)
  INFO [Player.useFood]                              (P11)<J.I.M.> has 4 food and needs 3 food
  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: 209
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11)<J.I.M.>
  INFO [Wampus.<init>]                               Wampus has 16 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 1 plans testing 149 tiles in 15910655ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain Energy (+3) 1435 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 1945 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) 1435 points
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: GoToMuleStore
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P11)<J.I.M.> develops tile Plain (3,2) with Energy factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: CatchWampus
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: CatchWampus
  INFO [DevelopmentMessenger.showMessage]            <J.I.M.> caught the Mountain Wampus and got $100
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: GoToPub
  INFO [GameModel.gamble]                            (P11)<J.I.M.> gambles $140, max: 47.5, left: 25.419495, fraction 0.53514725
  INFO [DevelopmentMessenger.showMessage]            <J.I.M.> won $140 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P11)<J.I.M.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P11)<J.I.M.> finishes on update 1130
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  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: 44
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -692190482638640267
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventGenerator.nextEvent]              No event frist round
  INFO [PlayerEventPhase.begin]                      No player event for (P10)El Chapo (rank 4)
  INFO [Player.useFood]                              (P10)El Chapo has 4 food and needs 3 food
  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: 216
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10)El Chapo
  INFO [Wampus.<init>]                               Wampus has 16 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.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.developLand]                       (P10)El Chapo develops tile Plain (7,3) with Food factory
  INFO [GameModel.developLand]                       (P10)El Chapo develops tile Mountain3 (3,1) with Energy factory
  INFO [GameModel.buyAssay]                          (P10)El Chapo got an assay bot
  INFO [DevelopmentMessenger.showMessage]            No Crystite found
  INFO [GameModel.buyAssay]                          (P10)El Chapo got an assay bot
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  INFO [DevelopmentMessenger.showMessage]            Found Medium amounts of Crystite
  INFO [DevelopmentMessenger.showMessage]            El Chapo caught the Mountain Wampus and got $100
  INFO [GameModel.buyAssay]                          (P10)El Chapo got an assay bot
  INFO [GameModel.sellAssay]                         (P10)El Chapo returned assay bot
  INFO [GameModel.gamble]                            (P10)El Chapo gambles $53, max: 47.5, left: 1.8791022, fraction 0.039560046
  INFO [DevelopmentMessenger.showMessage]            El Chapo won $53 in the pub
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P10)El Chapo finishes on update 2315
  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: 848
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_A #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: SUNSPOT_ACTIVITY
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -5952716762740824762
  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: 237
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -2472607713304772027
  INFO [ProductionPhase.finishPower]                 (P11)<J.I.M.> has 2 energy and needs 0 energy
  INFO [ProductionPhase.finishPower]                 (P10)El Chapo has 2 energy and needs 1 energy
  INFO [ProductionPhase.finishPower]                 (P12)<D.A.N.> has 2 energy and needs 0 energy
  INFO [ProductionPhase.finishPower]                 (P13)<B.I.L.L.> has 2 energy and needs 1 energy
  INFO [ProductionPhase.logProduction]               (P11)<J.I.M.> expects 0 food, 6 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10)El Chapo expects 2 food, 4 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12)<D.A.N.> expects 0 food, 12 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13)<B.I.L.L.> expects 4 food, 6 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.apply]                       Skipping production
  INFO [ProductionPhase.logProduction]               (P11)<J.I.M.> produced 0 food, 7 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10)El Chapo produced 0 food, 3 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12)<D.A.N.> produced 0 food, 11 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13)<B.I.L.L.> produced 5 food, 6 energy, 0 smithore, 0 crystite
  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: 775
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_B #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 7855421395719548163
  INFO [Shop.calcBuySellPrice]                       Current Smithore price $50
  INFO [Shop.calcBuySellPrice]                       Smithore random variation $0
  INFO [Shop.printPriceFactor]                       Smithore shop: 8, mules potentially available: 11, mules needed: 4
  INFO [Shop.printPriceFactor]                       Smithore is decreased to 52%
  INFO [Shop.printPriceFactor]                       Smithore buy price $50 sell price $85
  INFO [CollectionPhase.goToNextPhase]               (P11)<J.I.M.> Smithore: 0 critical: 0 surplus: 0 money: $1090
  INFO [CollectionPhase.goToNextPhase]               (P10)El Chapo Smithore: 0 critical: 0 surplus: 0 money: $298
  INFO [CollectionPhase.goToNextPhase]               (P12)<D.A.N.> Smithore: 0 critical: 0 surplus: 0 money: $345
  INFO [CollectionPhase.goToNextPhase]               (P13)<B.I.L.L.> Smithore: 0 critical: 0 surplus: 0 money: $255
  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: 151
  INFO [GameController.beginNextPhase]               --------- AUCTION_SMITHORE #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 1
  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: 1ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11)<J.I.M.> (P10)El Chapo (P12)<D.A.N.> (P13)<B.I.L.L.> 
  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 $65
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $60
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $55
  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 (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 [Shop.setAveragePrice]                        Smithore price set to average $50 (0 units for $0)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 579
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 1118672003686614025
  INFO [Shop.calcBuySellPrice]                       Current Crystite price $48
  INFO [Shop.calcBuySellPrice]                       Crystite buy price $132 sell price $272
  INFO [CollectionPhase.goToNextPhase]               (P11)<J.I.M.> Crystite: 0 critical: 0 surplus: 0 money: $1090
  INFO [CollectionPhase.goToNextPhase]               (P10)El Chapo Crystite: 0 critical: 0 surplus: 0 money: $298
  INFO [CollectionPhase.goToNextPhase]               (P12)<D.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $345
  INFO [CollectionPhase.goToNextPhase]               (P13)<B.I.L.L.> Crystite: 0 critical: 0 surplus: 0 money: $255
  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: 131
  INFO [GameController.beginNextPhase]               --------- AUCTION_CRYSTITE #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 1
  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 [Shop.setAveragePrice]                        Crystite price set to average $132 (0 units for $0)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 112
  INFO [GameController.beginNextPhase]               --------- COLLECTION_FOOD #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 7306713024156680023
  INFO [Shop.calcBuySellPrice]                       Current Food price $30
  INFO [Shop.printPriceFactor]                       Food shop: 8, total: 17, needed: 12
  INFO [Shop.printPriceFactor]                       Food is decreased to 78%
  INFO [Shop.printPriceFactor]                       Food buy price $15 sell price $50
  INFO [CollectionPhase.goToNextPhase]               (P11)<J.I.M.> Food: 1 critical: 3 surplus: -2 money: $1090
  INFO [CollectionPhase.goToNextPhase]               (P10)El Chapo Food: 1 critical: 3 surplus: -2 money: $298
  INFO [CollectionPhase.goToNextPhase]               (P12)<D.A.N.> Food: 1 critical: 3 surplus: -2 money: $345
  INFO [CollectionPhase.goToNextPhase]               (P13)<B.I.L.L.> Food: 6 critical: 3 surplus: 3 money: $255
  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: 601
  INFO [GameController.beginNextPhase]               --------- AUCTION_FOOD #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 1
  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: 1ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11)<J.I.M.> (P10)El Chapo (P12)<D.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P13)<B.I.L.L.> 
  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: <D.A.N.> target price $102 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> 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: <D.A.N.> target price $102 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $50 ratio 1.0
  INFO [SlickLogSystem.info]                         Transaction #2
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $102 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $50 ratio 1.0
  INFO [AbstractAuctionPhase.checkCancelTransactions] Buyer left auction line.
  INFO [SlickLogSystem.info]                         Transaction #3
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $92 ratio 2.2
  INFO [Player.increaseAuctionTeleportCount]         (P13)<B.I.L.L.> auction teleport count: 1
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #4
  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 (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 [Shop.setAveragePrice]                        Food price set to average $49 (4 units for $197)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 772
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -137302044298904939
  INFO [Shop.calcBuySellPrice]                       Current Energy price $25
  INFO [Shop.printPriceFactor]                       Energy shop: 8, total: 41, needed: 6
  INFO [Shop.printPriceFactor]                       Energy is decreased to 36%
  INFO [Shop.printPriceFactor]                       Energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P11)<J.I.M.> Energy: 9 critical: 1 surplus: 8 money: $992
  INFO [CollectionPhase.goToNextPhase]               (P10)El Chapo Energy: 4 critical: 2 surplus: 2 money: $298
  INFO [CollectionPhase.goToNextPhase]               (P12)<D.A.N.> Energy: 13 critical: 1 surplus: 12 money: $246
  INFO [CollectionPhase.goToNextPhase]               (P13)<B.I.L.L.> Energy: 7 critical: 2 surplus: 5 money: $402
  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: 660
  INFO [GameController.beginNextPhase]               --------- AUCTION_ENERGY #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 1
  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: 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P11)<J.I.M.> (P10)El Chapo (P12)<D.A.N.> (P13)<B.I.L.L.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> 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: <J.I.M.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> 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 [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #3
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #4
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #5
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #6
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #7
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #8
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #9
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #10
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $12 ratio 0.08000001
  INFO [SlickLogSystem.info]                         Transaction #11
  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 (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 [Shop.setAveragePrice]                        Energy price set to average $25 (22 units for $220)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 849
  INFO [GameController.beginNextPhase]               --------- SUMMARY #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 5148082421870072967
  INFO [SummaryPhase2.begin]                         Total Memory: 94371840
  INFO [SummaryPhase2.begin]                         Free Memory: 52268784
  INFO [SummaryPhase2.begin]                         (P11)<J.I.M.> Money: $1062 Plots: 1 Assets: 282 Total: 1844
  INFO [SummaryPhase2.begin]                         (P11)<J.I.M.> Food: 3 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P13)<B.I.L.L.> Money: $442 Plots: 2 Assets: 367 Total: 1809
  INFO [SummaryPhase2.begin]                         (P13)<B.I.L.L.> Food: 3 Energy: 3 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P12)<D.A.N.> Money: $356 Plots: 2 Assets: 367 Total: 1723
  INFO [SummaryPhase2.begin]                         (P12)<D.A.N.> Food: 3 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10)El Chapo Money: $298 Plots: 2 Assets: 294 Total: 1592
  INFO [SummaryPhase2.begin]                         (P10)El Chapo Food: 1 Energy: 4 Smithore: 0 Crystite: 0
  INFO [Shop.buildMules]                             Shop built 4 mules for 8 smithore and now has 11 mules
  INFO [Shop.buildMules]                             A mule now costs $100
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $132
  INFO [SummaryPhase2.begin]                         Shop Smithore: 0 units $50
  INFO [SummaryPhase2.begin]                         Shop Food: 7 units $49
  INFO [SummaryPhase2.begin]                         Shop Energy: 30 units $25
  INFO [SummaryPhase2.begin]                         Shop mules: 11 $100
  INFO [SummaryPhase2.begin]                         Colony total: 6968
  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 [MuleGameContainer.setWindowedMode]           Setting windowed mode: 640 x 480 x 0 @0Hz
  INFO [MuleGameContainer.setFullscreen]             Setting fullscreen mode
  INFO [MuleGameContainer.setFullscreen]             Fullscreen mode: 1400 x 1050 x 24 @60Hz Scale: 2 Height-Diff: 90 Aspect-Diff: 0.0 Snap Scale: true
  INFO [MuleGameContainer.setFullscreen]             Viewport: x=60 y=45 width=1280 height=960
  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: 449
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 11 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 4 plans testing 5404 tiles in 287753429ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 5404 tiles in 305840836ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 5404 tiles in 274246295ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: River Food <claim> (+4) 2181 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2959 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: River Food <claim> (+4) 2181 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2959 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: River Food <claim> (+4) 2181 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 2865 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain Food <claim> (+2) 2063 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2743 points
  INFO [LandGrantActuator.update]                    AI: <J.I.M.> wants River (4,1)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: River Food <claim> (+4) 2135 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2988 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: River Food <claim> (+4) 2135 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2988 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: River Food <claim> (+4) 2135 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 2919 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 2104 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2863 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants River (4,1)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food <claim> (+4) 2320 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3351 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food <claim> (+4) 2222 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3253 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food <claim> (+4) 2222 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3253 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Food <claim> (+2) 2222 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3204 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> 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]                       (P11)<J.I.M.> pressed at plot 4 frame 2
  INFO [LandGrantPhase.claimPlot]                    (P11)<J.I.M.> is granted tile 4: 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]                       (P12)<D.A.N.> pressed at plot 12 frame 3
  INFO [LandGrantPhase.claimPlot]                    (P12)<D.A.N.> is granted tile 12: 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]                       (P10)El Chapo pressed at plot 26 frame 1
  INFO [LandGrantPhase.claimPlot]                    (P10)El Chapo is granted tile 26: Mountain2 (6,3)
  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)<B.I.L.L.> pressed at plot 32 frame 2
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  INFO [LandGrantPhase.claimPlot]                    (P13)<B.I.L.L.> is granted tile 32: 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 (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: 203
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [PlotSeller.generateNextColonyAuction]        Colony sells plot #1
  INFO [PlotSeller.beginAuction]                     Starting auction set
  INFO [PlotSeller.beginAuction]                     Land auction price: $472
  INFO [LandAuctionActuator.beginLandAuction]        AI: <J.I.M.> targets price $420
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $56
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $140
  INFO [AbstractAuctionController.begin]             Begin auction id 4
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 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 0ms, max 0ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11)<J.I.M.> (P10)El Chapo (P12)<D.A.N.> (P13)<B.I.L.L.> 
  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 [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 [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: 818
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 6305022709334277941
  INFO [Development.setPlayerOrder]                  Normal development order. 11 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  INFO [PlayerEventGenerator.nextEvent]              Event random chance: 0.98619014
  INFO [PlayerEventPhase.begin]                      No player event for (P11)<J.I.M.> (rank 1)
  INFO [Player.useFood]                              (P11)<J.I.M.> has 3 food and needs 3 food
  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: 239
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11)<J.I.M.>
  INFO [Wampus.<init>]                               Wampus has 14 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 1 plans testing 133 tiles in 8470179ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: River Food (+4) 2181 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 2865 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 River Food (+4) 2181 points
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: GoToMuleStore
  INFO [MuleGameContainer.setWindowedMode]           Setting windowed mode: 640 x 480 x 0 @0Hz
  INFO [MuleGameContainer.setFullscreen]             Setting fullscreen mode
  INFO [MuleGameContainer.setFullscreen]             Fullscreen mode: 1400 x 1050 x 24 @60Hz Scale: 2 Height-Diff: 90 Aspect-Diff: 0.0 Snap Scale: true
  INFO [MuleGameContainer.setFullscreen]             Viewport: x=60 y=45 width=1280 height=960
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P11)<J.I.M.> develops tile River (4,0) with Food factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: GoToPub
  INFO [GameModel.gamble]                            (P11)<J.I.M.> gambles $111, max: 47.5, left: 34.85971, fraction 0.7338886
  INFO [DevelopmentMessenger.showMessage]            <J.I.M.> won $111 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P11)<J.I.M.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P11)<J.I.M.> finishes on update 632
  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: 752
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 2681481319847033947
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventGenerator.nextEvent]              Event random chance: 0.73779464
  INFO [PlayerEventPhase.begin]                      No player event for (P13)<B.I.L.L.> (rank 2)
  INFO [Player.useFood]                              (P13)<B.I.L.L.> has 3 food and needs 3 food
  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: 226
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13)<B.I.L.L.>
  INFO [Wampus.<init>]                               Wampus has 14 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 1 plans testing 133 tiles in 19287171ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food (+4) 2320 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Food <claim> (+2) 3302 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <B.I.L.L.> plans to develop River Food (+4) 2320 points
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToMuleStore
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P13)<B.I.L.L.> develops tile River (4,4) with Food factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToPub
  INFO [GameModel.gamble]                            (P13)<B.I.L.L.> gambles $196, max: 47.5, left: 35.059715, fraction 0.7380993
  INFO [DevelopmentMessenger.showMessage]            <B.I.L.L.> won $196 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P13)<B.I.L.L.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P13)<B.I.L.L.> finishes on update 622
  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: 746
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -3873327763447543032
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventGenerator.nextEvent]              Event random chance: 0.8340962
  INFO [PlayerEventPhase.begin]                      No player event for (P12)<D.A.N.> (rank 3)
  INFO [Player.useFood]                              (P12)<D.A.N.> has 3 food and needs 3 food
  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: 237
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12)<D.A.N.>
  INFO [Wampus.<init>]                               Wampus has 14 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 1 plans testing 133 tiles in 2209994ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: River Food (+4) 2135 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 2919 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <D.A.N.> plans to develop River Food (+4) 2135 points
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToMuleStore
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P12)<D.A.N.> develops tile River (4,1) with Food factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P12)<D.A.N.> gambles $147, max: 47.5, left: 36.15974, fraction 0.7612577
  INFO [DevelopmentMessenger.showMessage]            <D.A.N.> won $147 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P12)<D.A.N.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P12)<D.A.N.> finishes on update 567
  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: 700
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -5629759635259832629
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventGenerator.nextEvent]              Event random chance: 0.68194866
  INFO [PlayerEventPhase.begin]                      No player event for (P10)El Chapo (rank 4)
  INFO [Player.useFood]                              (P10)El Chapo has 1 food and needs 3 food
  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: 230
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10)El Chapo
  INFO [Wampus.<init>]                               Wampus has 14 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.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.developLand]                       (P10)El Chapo develops tile Mountain2 (6,3) with Food factory
  INFO [GameModel.buyAssay]                          (P10)El Chapo got an assay bot
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P10)El Chapo finishes on update 959
  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: 988
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_A #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: ACID_RAIN_STORM
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -2971033816137238855
  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: 350
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -8817626593328061283
  INFO [ProductionPhase.finishPower]                 (P11)<J.I.M.> has 2 energy and needs 1 energy
  INFO [ProductionPhase.finishPower]                 (P10)El Chapo has 4 energy and needs 2 energy
  INFO [ProductionPhase.finishPower]                 (P12)<D.A.N.> has 2 energy and needs 1 energy
  INFO [ProductionPhase.finishPower]                 (P13)<B.I.L.L.> has 3 energy and needs 2 energy
  INFO [ProductionPhase.logProduction]               (P11)<J.I.M.> expects 5 food, 2 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10)El Chapo expects 13 food, 0 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12)<D.A.N.> expects 5 food, 4 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13)<B.I.L.L.> expects 14 food, 1 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.apply]                       Skipping production
  INFO [ProductionPhase.logProduction]               (P11)<J.I.M.> produced 4 food, 1 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10)El Chapo produced 14 food, 1 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12)<D.A.N.> produced 4 food, 7 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13)<B.I.L.L.> produced 15 food, 2 energy, 0 smithore, 0 crystite
  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: 642
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_B #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -5969610224213407290
  INFO [Shop.calcBuySellPrice]                       Current Smithore price $50
  INFO [Shop.calcBuySellPrice]                       Smithore random variation $-4
  INFO [Shop.printPriceFactor]                       Smithore shop: 0, mules potentially available: 7, mules needed: 4
  INFO [Shop.printPriceFactor]                       Smithore is decreased to 68%
  INFO [Shop.printPriceFactor]                       Smithore buy price $46 sell price $81
  INFO [CollectionPhase.goToNextPhase]               (P11)<J.I.M.> Smithore: 0 critical: 0 surplus: 0 money: $1048
  INFO [CollectionPhase.goToNextPhase]               (P10)El Chapo Smithore: 0 critical: 0 surplus: 0 money: $173
  INFO [CollectionPhase.goToNextPhase]               (P12)<D.A.N.> Smithore: 0 critical: 0 surplus: 0 money: $378
  INFO [CollectionPhase.goToNextPhase]               (P13)<B.I.L.L.> Smithore: 0 critical: 0 surplus: 0 money: $513
  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: 149
  INFO [GameController.beginNextPhase]               --------- AUCTION_SMITHORE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 2
  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 [Shop.setAveragePrice]                        Smithore price set to average $46 (0 units for $0)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 119
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -5126922422192742787
  INFO [Shop.calcBuySellPrice]                       Current Crystite price $132
  INFO [Shop.calcBuySellPrice]                       Crystite buy price $84 sell price $224
  INFO [CollectionPhase.goToNextPhase]               (P11)<J.I.M.> Crystite: 0 critical: 0 surplus: 0 money: $1048
  INFO [CollectionPhase.goToNextPhase]               (P10)El Chapo Crystite: 0 critical: 0 surplus: 0 money: $173
  INFO [CollectionPhase.goToNextPhase]               (P12)<D.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $378
  INFO [CollectionPhase.goToNextPhase]               (P13)<B.I.L.L.> Crystite: 0 critical: 0 surplus: 0 money: $513
  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: 150
  INFO [GameController.beginNextPhase]               --------- AUCTION_CRYSTITE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 2
  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 [Shop.setAveragePrice]                        Crystite price set to average $84 (0 units for $0)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 116
  INFO [GameController.beginNextPhase]               --------- COLLECTION_FOOD #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -1506131028069240536
  INFO [Shop.calcBuySellPrice]                       Current Food price $49
  INFO [Shop.printPriceFactor]                       Food shop: 7, total: 44, needed: 12
  INFO [Shop.printPriceFactor]                       Food is decreased to 45%
  INFO [Shop.printPriceFactor]                       Food buy price $15 sell price $50
  INFO [CollectionPhase.goToNextPhase]               (P11)<J.I.M.> Food: 4 critical: 3 surplus: 1 money: $1048
  INFO [CollectionPhase.goToNextPhase]               (P10)El Chapo Food: 14 critical: 3 surplus: 11 money: $173
  INFO [CollectionPhase.goToNextPhase]               (P12)<D.A.N.> Food: 4 critical: 3 surplus: 1 money: $378
  INFO [CollectionPhase.goToNextPhase]               (P13)<B.I.L.L.> Food: 15 critical: 3 surplus: 12 money: $513
  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: 705
  INFO [GameController.beginNextPhase]               --------- AUCTION_FOOD #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 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 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: 1ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P11)<J.I.M.> (P10)El Chapo (P12)<D.A.N.> (P13)<B.I.L.L.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 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: <B.I.L.L.> target price $15 ratio 0.0
  INFO [Player.increaseAuctionTeleportCount]         (P12)<D.A.N.> auction teleport count: 1
  INFO [Player.increaseAuctionTeleportCount]         (P11)<J.I.M.> auction teleport count: 1
  INFO [SlickLogSystem.info]                         Transaction #2
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #3
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #4
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #5
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #6
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #7
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #8
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #9
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #10
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #11
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [SlickLogSystem.info]                         Transaction #12
  INFO [Player.increaseAuctionTeleportCount]         (P13)<B.I.L.L.> auction teleport count: 1
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 1ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #13
  INFO [SlickLogSystem.info]                         Transaction #14
  INFO [SlickLogSystem.info]                         Transaction #15
  INFO [SlickLogSystem.info]                         Transaction #16
  INFO [SlickLogSystem.info]                         Transaction #17
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 1ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #18
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 1ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1002
  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 [Shop.setAveragePrice]                        Food price set to average $30 (20 units for $300)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 64
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -7818043456434672005
  INFO [Shop.calcBuySellPrice]                       Current Energy price $25
  INFO [Shop.printPriceFactor]                       Energy shop: 30, total: 46, needed: 10
  INFO [Shop.printPriceFactor]                       Energy is decreased to 41%
  INFO [Shop.printPriceFactor]                       Energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P11)<J.I.M.> Energy: 2 critical: 2 surplus: 0 money: $1063
  INFO [CollectionPhase.goToNextPhase]               (P10)El Chapo Energy: 3 critical: 3 surplus: 0 money: $263
  INFO [CollectionPhase.goToNextPhase]               (P12)<D.A.N.> Energy: 8 critical: 2 surplus: 6 money: $393
  INFO [CollectionPhase.goToNextPhase]               (P13)<B.I.L.L.> Energy: 3 critical: 3 surplus: 0 money: $693
  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: 640
  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 [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 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: 1ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11)<J.I.M.> (P10)El Chapo (P13)<B.I.L.L.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P12)<D.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $50 ratio 1.16
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $50 ratio 1.16
  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 $52 ratio 1.2
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [AbstractAuctionPhase.checkCancelTransactions] Buyer left auction line.
  INFO [SlickLogSystem.info]                         Transaction #2
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 1ms
  INFO [AbstractAuctionPhase.checkCancelTransactions] Buyer left auction line.
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #3
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $13 ratio 0.10000001
  INFO [SlickLogSystem.info]                         Transaction #4
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $17 ratio 0.20000002
  INFO [SlickLogSystem.info]                         Transaction #5
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 1ms
  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 (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 [Shop.setAveragePrice]                        Energy price set to average $25 (5 units for $86)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 819
  INFO [GameController.beginNextPhase]               --------- SUMMARY #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -445095503716762208
  INFO [SummaryPhase2.begin]                         Total Memory: 88604672
  INFO [SummaryPhase2.begin]                         Free Memory: 68789880
  INFO [SummaryPhase2.begin]                         (P13)<B.I.L.L.> Money: $665 Plots: 3 Assets: 395 Total: 2560
  INFO [SummaryPhase2.begin]                         (P13)<B.I.L.L.> Food: 3 Energy: 4 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P12)<D.A.N.> Money: $479 Plots: 3 Assets: 395 Total: 2374
  INFO [SummaryPhase2.begin]                         (P12)<D.A.N.> Food: 3 Energy: 3 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11)<J.I.M.> Money: $1035 Plots: 2 Assets: 310 Total: 2345
  INFO [SummaryPhase2.begin]                         (P11)<J.I.M.> Food: 3 Energy: 3 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10)El Chapo Money: $263 Plots: 3 Assets: 520 Total: 2283
  INFO [SummaryPhase2.begin]                         (P10)El Chapo Food: 8 Energy: 3 Smithore: 0 Crystite: 0
  INFO [Shop.buildMules]                             Shop built 0 mules for 0 smithore and now has 7 mules
  INFO [Shop.buildMules]                             A mule now costs $90
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $84
  INFO [SummaryPhase2.begin]                         Shop Smithore: 0 units $46
  INFO [SummaryPhase2.begin]                         Shop Food: 27 units $30
  INFO [SummaryPhase2.begin]                         Shop Energy: 33 units $25
  INFO [SummaryPhase2.begin]                         Shop mules: 7 $90
  INFO [SummaryPhase2.begin]                         Colony total: 9562
  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 (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: 353
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.setPlayerOrder]                  Reverse development order. 7 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 4 plans testing 4289 tiles in 23389506ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain Energy <claim> (+3) 2805 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 3575 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 Smithore <claim> (+4) 2794 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 3548 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 Smithore <claim> (+4) 2794 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 3548 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 Smithore <claim> (+4) 2794 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 3548 points
  INFO [LandGrantActuator.update]                    AI: <J.I.M.> wants Plain (2,2)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 4289 tiles in 28879318ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 4289 tiles in 57543296ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 2959 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 3813 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 2959 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 3813 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 2959 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 3813 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 2959 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 3813 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants Plain (6,2)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Energy <claim> (+3) 3100 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 4000 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Energy <claim> (+3) 3100 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 4000 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Energy <claim> (+3) 3100 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 4000 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Energy <claim> (+3) 3050 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 3950 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants Plain (2,3)
  INFO [LandGrantActuator.agreeOnClaims]             AI: Agrees on tiles to claim.
  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 1ms 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]                       (P11)<J.I.M.> pressed at plot 16 frame 2
  INFO [LandGrantPhase.claimPlot]                    (P11)<J.I.M.> is granted tile 16: Plain (2,2)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.update]                       (P12)<D.A.N.> pressed at plot 17 frame 1
  INFO [LandGrantPhase.claimPlot]                    (P12)<D.A.N.> is granted tile 17: Plain (6,2)
  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)<B.I.L.L.> pressed at plot 21 frame 3
  INFO [LandGrantPhase.claimPlot]                    (P13)<B.I.L.L.> is granted tile 21: Plain (2,3)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  INFO [LandGrantPhase.update]                       (P10)El Chapo pressed at plot 31 frame 6
  INFO [LandGrantPhase.claimPlot]                    (P10)El Chapo is granted tile 31: Mountain3 (7,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 (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: 243
  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 [PlotSeller.generateNextColonyAuction]        Colony sells plot #1
  INFO [PlotSeller.beginAuction]                     Starting auction set
  INFO [PlotSeller.beginAuction]                     Land auction price: $228
  INFO [LandAuctionActuator.beginLandAuction]        AI: <J.I.M.> targets price $388
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $196
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $328
  INFO [AbstractAuctionController.begin]             Begin auction id 5
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 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 0ms, max 0ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11)<J.I.M.> (P10)El Chapo (P12)<D.A.N.> (P13)<B.I.L.L.> 
  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: <J.I.M.> new price $356 (changed $-32)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $328 (changed $0)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $340 (changed $-16)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $324 (changed $-16)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $284 (changed $-44)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $336 (changed $52)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $296 (changed $-28)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $332 (changed $-4)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $316 (changed $-16)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $292 (changed $-24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $232 (changed $-60)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $340 (changed $44)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $252 (changed $20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $324 (changed $-16)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $292 (changed $40)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $332 (changed $40)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $300 (changed $-32)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $272 (changed $-52)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $236 (changed $-36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $328 (changed $28)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $204 (changed $-32)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1002
  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)<B.I.L.L.> bought the plot for $232
  INFO [PlotSeller.generateNextColonyAuction]        Colony doesn't sell a plot
  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: 659
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 1994714250371250504
  INFO [Development.setPlayerOrder]                  Reverse development order. 7 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventGenerator.nextEvent]              Event random chance: 0.37102097
  INFO [PlayerEventPhase.begin]                      No player event for (P10)El Chapo (rank 4)
  INFO [Player.useFood]                              (P10)El Chapo has 8 food and needs 3 food
  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: 243
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10)El Chapo
  INFO [Wampus.<init>]                               Wampus has 11 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.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.developLand]                       (P10)El Chapo develops tile Mountain2 (6,3) with Smithore factory
  INFO [GameModel.developLand]                       (P10)El Chapo develops tile Mountain3 (3,1) with Smithore factory
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  INFO [GameModel.developLand]                       (P10)El Chapo develops tile Plain (7,3) with Energy factory
  INFO [GameModel.developLand]                       (P10)El Chapo develops tile Mountain3 (7,4) with Food factory
  INFO [DevelopmentMessenger.showMessage]            El Chapo caught the Mountain Wampus and got $100
  INFO [GameModel.developLand]                       (P10)El Chapo undevelops tile Mountain3 (7,4)
  INFO [GameModel.developLand]                       (P10)El Chapo develops tile Mountain2 (6,3) with Smithore factory
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P10)El Chapo finishes on update 2400
  INFO [Mule.runAway]                                Mule runs away for (P10)El Chapo
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  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: 135
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -6316308103356569650
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventGenerator.nextEvent]              Event random chance: 0.7346045
  INFO [PlayerEventPhase.begin]                      No player event for (P11)<J.I.M.> (rank 3)
  INFO [Player.useFood]                              (P11)<J.I.M.> has 3 food and needs 3 food
  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: 231
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11)<J.I.M.>
  INFO [Wampus.<init>]                               Wampus has 11 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 1 plans testing 113 tiles in 9062908ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain Energy (+3) 2805 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 3575 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) 2805 points
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: GoToMuleStore
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: PlaceMule
  INFO [MuleGameContainer.setWindowedMode]           Setting windowed mode: 640 x 480 x 0 @0Hz
  INFO [GameModel.developLand]                       (P11)<J.I.M.> develops tile Plain (2,2) with Energy factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <J.I.M.> Action: GoToPub
  INFO [GameModel.gamble]                            (P11)<J.I.M.> gambles $82, max: 47.5, left: 32.59966, fraction 0.6863086
  INFO [DevelopmentMessenger.showMessage]            <J.I.M.> won $82 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P11)<J.I.M.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P11)<J.I.M.> finishes on update 745
  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: 451
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 4463735933229425693
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventGenerator.nextEvent]              Event random chance: 0.1387791
  INFO [PlayerEventPhase.begin]                      Event player: (P12)<D.A.N.> (rank 2)
  INFO [PlayerEventPhase.begin]                      Event: RelativeDied
  INFO [PlayerEventPhase$1.finished]                 Event Message: A distant relative died and left you a vast fortune. But after taxes you only got $100
  INFO [LobbyChat.chatInputKeyPressed]               Lobby Chat Send: be there in a second guys
  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 [Player.useFood]                              (P12)<D.A.N.> has 3 food and needs 3 food
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 1ms (5ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 1 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12)<D.A.N.>
  INFO [Wampus.<init>]                               Wampus has 11 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 1 plans testing 113 tiles in 17803732ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy (+3) 3059 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 3913 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <D.A.N.> plans to develop Plain Energy (+3) 3059 points
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToMuleStore
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P12)<D.A.N.> develops tile Plain (6,2) with Energy factory
  INFO [DevelopmentActuator.enqueueAssay]            AI: (P12)<D.A.N.> want's to assay Plain (0,3) with 37.51977 time left.
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToAssayStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: Assay
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToAssayStore
  INFO [GameModel.buyAssay]                          (P12)<D.A.N.> got an assay bot
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: Assay
  INFO [DevelopmentMessenger.showMessage]            Found Medium amounts of Crystite
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P12)<D.A.N.> gambles $55, max: 47.5, left: 22.079418, fraction 0.46482986
  INFO [DevelopmentMessenger.showMessage]            <D.A.N.> won $55 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P12)<D.A.N.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P12)<D.A.N.> finishes on update 1271
  INFO [MuleGameContainer.setFullscreen]             Setting fullscreen mode
  INFO [MuleGameContainer.setFullscreen]             Fullscreen mode: 1400 x 1050 x 24 @60Hz Scale: 2 Height-Diff: 90 Aspect-Diff: 0.0 Snap Scale: true
  INFO [MuleGameContainer.setFullscreen]             Viewport: x=60 y=45 width=1280 height=960
  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: 88
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 4ms (5ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 4 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 1048007860671610081
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  INFO [PlayerEventGenerator.nextEvent]              Event random chance: 0.26889044
  INFO [PlayerEventPhase.begin]                      Event player: (P13)<B.I.L.L.> (rank 1)
  INFO [PlayerEventPhase.begin]                      Event: LostPlot
  INFO [PlayerEventPhase$1.finished]                 Event Message: You lost a plot of land because the claim was not recorded
  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 [Player.useFood]                              (P13)<B.I.L.L.> has 3 food and needs 3 food
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 511
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 4ms (5ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 4 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13)<B.I.L.L.>
  INFO [Wampus.<init>]                               Wampus has 11 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 1 plans testing 117 tiles in 13454531ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Energy (+3) 2818 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 3693 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <B.I.L.L.> plans to develop Plain Energy (+3) 2818 points
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToMuleStore
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P13)<B.I.L.L.> develops tile Plain (0,1) with Energy factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: CatchWampus
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: CatchWampus
  INFO [DevelopmentMessenger.showMessage]            <B.I.L.L.> caught the Mountain Wampus and got $100
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToPub
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 1001
  INFO [GameModel.gamble]                            (P13)<B.I.L.L.> gambles $106, max: 47.5, left: 22.959438, fraction 0.4833566
  INFO [DevelopmentMessenger.showMessage]            <B.I.L.L.> won $106 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P13)<B.I.L.L.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P13)<B.I.L.L.> finishes on update 1253
  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: 216
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_A #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 3ms (5ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 3 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 3ms (5ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 3 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -4854368588788861283
  INFO [ProductionPhase.apply]                       Skipping production
  INFO [ProductionPhase.finishPower]                 (P11)<J.I.M.> has 3 energy and needs 1 energy
  INFO [ProductionPhase.finishPower]                 (P10)El Chapo has 3 energy and needs 2 energy
  INFO [ProductionPhase.finishPower]                 (P12)<D.A.N.> has 3 energy and needs 1 energy
  INFO [ProductionPhase.finishPower]                 (P13)<B.I.L.L.> has 4 energy and needs 2 energy
  INFO [ProductionPhase.logProduction]               (P11)<J.I.M.> expects 4 food, 8 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10)El Chapo expects 0 food, 3 energy, 7 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12)<D.A.N.> expects 4 food, 13 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13)<B.I.L.L.> expects 10 food, 6 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11)<J.I.M.> produced 5 food, 9 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10)El Chapo produced 0 food, 4 energy, 7 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12)<D.A.N.> produced 5 food, 11 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13)<B.I.L.L.> produced 8 food, 2 energy, 0 smithore, 0 crystite
  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: 452
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_B #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 1ms (4ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 1 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: PEST_ATTACK
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 4551021882757627505
  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: 326
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (3ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 5610415462061175662
  INFO [Shop.calcBuySellPrice]                       Current Smithore price $46
  INFO [Shop.calcBuySellPrice]                       Smithore random variation $-10
  INFO [Shop.printPriceFactor]                       Smithore shop: 0, mules potentially available: 3, mules needed: 5
  INFO [Shop.printPriceFactor]                       Smithore is increased to 150%
  INFO [Shop.printPriceFactor]                       Smithore buy price $59 sell price $94
  INFO [CollectionPhase.goToNextPhase]               (P11)<J.I.M.> Smithore: 0 critical: 0 surplus: 0 money: $977
  INFO [CollectionPhase.goToNextPhase]               (P10)El Chapo Smithore: 7 critical: 0 surplus: 7 money: $98
  INFO [CollectionPhase.goToNextPhase]               (P12)<D.A.N.> Smithore: 0 critical: 0 surplus: 0 money: $494
  INFO [CollectionPhase.goToNextPhase]               (P13)<B.I.L.L.> Smithore: 0 critical: 0 surplus: 0 money: $499
  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: 291
  INFO [GameController.beginNextPhase]               --------- AUCTION_SMITHORE #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (1ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 3
  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: (P11)<J.I.M.> (P12)<D.A.N.> (P13)<B.I.L.L.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10)El Chapo 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $64
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $59
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $59
  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 [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 (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 [Shop.setAveragePrice]                        Smithore price set to average $59 (2 units for $118)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 718
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -2638843366809132060
  INFO [Shop.calcBuySellPrice]                       Current Crystite price $84
  INFO [Shop.calcBuySellPrice]                       Crystite buy price $64 sell price $204
  INFO [CollectionPhase.goToNextPhase]               (P11)<J.I.M.> Crystite: 0 critical: 0 surplus: 0 money: $977
  INFO [CollectionPhase.goToNextPhase]               (P10)El Chapo Crystite: 0 critical: 0 surplus: 0 money: $216
  INFO [CollectionPhase.goToNextPhase]               (P12)<D.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $494
  INFO [CollectionPhase.goToNextPhase]               (P13)<B.I.L.L.> Crystite: 0 critical: 0 surplus: 0 money: $499
  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: 139
  INFO [GameController.beginNextPhase]               --------- AUCTION_CRYSTITE #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 3
  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 [Shop.setAveragePrice]                        Crystite price set to average $64 (0 units for $0)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 114
  INFO [GameController.beginNextPhase]               --------- COLLECTION_FOOD #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 7246827703469585311
  INFO [Shop.calcBuySellPrice]                       Current Food price $30
  INFO [Shop.printPriceFactor]                       Food shop: 27, total: 45, needed: 12
  INFO [Shop.printPriceFactor]                       Food is decreased to 45%
  INFO [Shop.printPriceFactor]                       Food buy price $15 sell price $50
  INFO [CollectionPhase.goToNextPhase]               (P11)<J.I.M.> Food: 5 critical: 3 surplus: 2 money: $977
  INFO [CollectionPhase.goToNextPhase]               (P10)El Chapo Food: 3 critical: 3 surplus: 0 money: $216
  INFO [CollectionPhase.goToNextPhase]               (P12)<D.A.N.> Food: 5 critical: 3 surplus: 2 money: $494
  INFO [CollectionPhase.goToNextPhase]               (P13)<B.I.L.L.> Food: 5 critical: 3 surplus: 2 money: $499
  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: 624
  INFO [GameController.beginNextPhase]               --------- AUCTION_FOOD #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [AbstractAuctionController.begin]             Begin auction id 3
  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: (P10)El Chapo 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P11)<J.I.M.> (P12)<D.A.N.> (P13)<B.I.L.L.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 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: <J.I.M.> target price $17 ratio 0.08000001
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $15 ratio 0.01000002
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.01000002
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [SlickLogSystem.info]                         Transaction #2
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $19 ratio 0.14000002
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $19 ratio 0.14000002
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $19 ratio 0.14000002
  INFO [SlickLogSystem.info]                         Transaction #3
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $22 ratio 0.20000002
  INFO [Player.increaseAuctionTeleportCount]         (P12)<D.A.N.> auction teleport count: 1
  INFO [Player.increaseAuctionTeleportCount]         (P13)<B.I.L.L.> auction teleport count: 1
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [MuleGameContainer.setWindowedMode]           Setting windowed mode: 640 x 480 x 0 @0Hz
  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 (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 [Shop.setAveragePrice]                        Food price set to average $30 (5 units for $75)
  INFO [MuleGameContainer.logSkippedFrames]          Skipped frames: 501
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms (0ms) 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -5465060243976627128
  INFO [Shop.calcBuySellPrice]                       Current Energy price $25
  INFO [Shop.printPriceFactor]                       Energy shop: 33, total: 66, needed: 10
  INFO [Shop.printPriceFactor]                       Energy is decreased to 36%
  INFO [Shop.printPriceFactor]                       Energy buy price $10 sell price $45
  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 108167 user number 10
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Chat Log
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
