  INFO [Properties.<init>]                           VERSION: 1.2.2
  INFO [Properties.<init>]                           Classpath: data.jar
  INFO [Properties.<init>]                           Java VM: 14.0-b16
  INFO [Properties.<init>]                           Java Runtime: 1.6.0_14-b08
  INFO [Properties.<init>]                           OS: Windows 7 6.1 x86
  INFO [Properties.<init>]                           Java Library Path: lib
  INFO [Properties.<init>]                           Max Memory: 266403840
  INFO [Properties.<init>]                           Avaialble Processors: 4
  INFO [PropertiesBase.readFromFile]                 Reading Settings from file settings.txt
  INFO [Settings.<init>]                             Settings file not found: settings.txt (The system cannot find the file specified)
  INFO [SubstanceLoader.initLookAndFeel]             Window Skin: Default
  INFO [MasterClient.setServerAddress]               MasterClient: Address http://www.planetmule.com/server
  INFO [MasterClient.logSend]                        MasterClient: Sending Login
  INFO [MasterClient.apply]                          MasterClient: Session ID: 22b3121290e26cb17e76b1c909d78776
  INFO [Login$MasterListener$1.run]                  Logged in as "HexagonalBolts" (id 10730)
  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.create]                    LWJGL Version: 2.2.1
  INFO [MuleGameContainer.create]                    Desktop Display Mode: 1920 x 1080 x 32 @60Hz
  INFO [MuleGameContainer.create]                    Current Display Mode: 1920 x 1080 x 32 @60Hz
  INFO [MuleGameContainer.create]                    Display Adapter: RDPDD
  INFO [MuleGameContainer.create]                    Display Driver: null
  INFO [SlickLogSystem.info]                         Slick Build #269
  INFO [MuleGameContainer.<init>]                    TargetDisplayMode: 640 x 480 x 0 @0Hz
  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 [SlickLogSystem.info]                         Starting display 640x480
  INFO [SlickLogSystem.info]                         Use Java PNG Loader = true
  INFO [MuleGameContainer.<init>]                    Input controllers not available
  INFO [MuleGameContainer.setVSync]                  Setting vsync: true
  INFO [MuleGameContainer.<init>]                    Display finished
  INFO [SettingsDialog.applySettings]                Changing map skin to default
  INFO [Main.main]                                   Entering game loop
++++++++ END OF INITIALIZATION +++++++++++++++++++++++++++++++++++++++++++
  INFO [MetaFrame.startGame]                         Starting game number 0
  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 [MuleGameContainer.setWindowedMode]           Setting windowed mode: 640 x 480 x 0 @0Hz
  INFO [View.<init>]                                 Creating view...
  INFO [SlickLogSystem.info]                         Initialising sounds..
  INFO [SlickLogSystem.info]                         - Sound works
  INFO [SlickLogSystem.info]                         - 64 OpenAL source available
  INFO [SlickLogSystem.info]                         - Sounds source generated
  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:6260
  INFO [Server.<init>]                               Server: Started on UDP address /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: 8192 send buffer: 8192
  INFO [MuleGameContainer.setWindowedMode]           Setting windowed mode: 640 x 480 x 0 @0Hz
  INFO [Server.acceptClients]                        Server: Accepted client 127.0.0.1 id 4e11b2b6-e3f7-411e-9376-3d919fc3c458
  INFO [Client.finishConnect]                        Client finishes connection
  INFO [MuleGameContainer.setWindowedMode]           Setting windowed mode: 640 x 480 x 0 @0Hz
  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) 46 bytes
  INFO [UserController.apply]                        My client id is 4e11b2b6-e3f7-411e-9376-3d919fc3c458
  INFO [Player.<init>]                               Creating (P0) spectator
  INFO [GameModel.setMyUser]                         (S10) HexagonalBolts is my user
  INFO [GameModel.setLocalUser]                      (S10) HexagonalBolts is a local Spectator
  INFO [GameModel.setServerUser]                     (S10) HexagonalBolts is hosting the server
  INFO [GameController.beginNextPhase]               (S10) HexagonalBolts 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 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [GameLobbyPhase.getMasterUserInfo]            Fetching user info for (S10) HexagonalBolts (ID: 10730)
  INFO [Client.initUDP]                              Sending UDP initialization message id 4e11b2b6-e3f7-411e-9376-3d919fc3c458
  INFO [Server.initUdp]                              Server: Client 127.0.0.1 <S10> requests UDP address /127.0.0.1:6261
  INFO [MasterClient.logSend]                        MasterClient: Sending New Game
  INFO [Server.acceptClients]                        Server: Accepted client 67.210.120.20 id ea381e59-b6a5-45aa-a26a-5c570fbf5ffc
  INFO [GameLobbyPhase$MasterListener.apply]         Got user info for (S10) HexagonalBolts (ID 10730)
  INFO [GameLobbyPhase$MasterListener.apply]         No statistics available for (S10) HexagonalBolts (ID 10730)
  INFO [GameLobbyPhase$MasterListener.apply]         Master server gives game ID 16696
  INFO [MasterClient.logSend]                        MasterClient: Sending User Join
  INFO [ServerMessageHandler.processPlayerJoin]      127.0.0.1 <S10> joins game as a player
  INFO [Client.receiveTCPMessages]                   Client: PlayerJoin (10) 0 bytes
  INFO [UserController.apply]                        (S10) HexagonalBolts joins the players
  INFO [Player.<init>]                               Creating (P10) HexagonalBolts
  INFO [GameModel.setServerUser]                     (P10) HexagonalBolts is hosting the server
  INFO [GameModel.setMyUser]                         (P10) HexagonalBolts is my user
  INFO [GameModel.setLocalUser]                      (P10) HexagonalBolts is a local Player
  INFO [ServerMessageHandler.processAIJoin]          Server: AI gets user number 11
  INFO [Client.receiveTCPMessages]                   Client: AIJoin (11) 14 bytes
  INFO [Player.<init>]                               Creating (P11) <B.I.L.L.>
  INFO [UserController.setAiPlayer]                  (P11) <B.I.L.L.> is an AI player
  INFO [GameModel.setLocalUser]                      (P11) <B.I.L.L.> 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) 12 bytes
  INFO [Player.<init>]                               Creating (P12) <D.A.N.>
  INFO [UserController.setAiPlayer]                  (P12) <D.A.N.> is an AI player
  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) <A.L.A.N.>
  INFO [UserController.setAiPlayer]                  (P13) <A.L.A.N.> is an AI player
  INFO [GameModel.setLocalUser]                      (P13) <A.L.A.N.> 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 users want to continue
  INFO [GameModel.updatePlayerRankOrder]             Random rank order: (P13) <A.L.A.N.> (P12) <D.A.N.> (P10) HexagonalBolts (P11) <B.I.L.L.> 
  INFO [MasterClient.logSend]                        MasterClient: Sending Start Game
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- INTRO #0 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 13ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 13 ms
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- SUMMARY #0 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 13 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 4966584123314915902
  INFO [GameModel.updatePlayerRankOrder]             Random rank order: (P12) <D.A.N.> (P11) <B.I.L.L.> (P13) <A.L.A.N.> (P10) HexagonalBolts 
  INFO [SummaryPhase2.begin]                         Total Memory: 66715648
  INFO [SummaryPhase2.begin]                         Free Memory: 36333608
  INFO [SummaryPhase2.begin]                         (P12) <D.A.N.> Money: $1000 Plots: 0 Assets: 220 Total: 1220
  INFO [SummaryPhase2.begin]                         (P12) <D.A.N.> Food: 4 Energy: 4 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11) <B.I.L.L.> Money: $1000 Plots: 0 Assets: 220 Total: 1220
  INFO [SummaryPhase2.begin]                         (P11) <B.I.L.L.> Food: 4 Energy: 4 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P13) <A.L.A.N.> Money: $1000 Plots: 0 Assets: 220 Total: 1220
  INFO [SummaryPhase2.begin]                         (P13) <A.L.A.N.> Food: 4 Energy: 4 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10) HexagonalBolts Money: $1000 Plots: 0 Assets: 220 Total: 1220
  INFO [SummaryPhase2.begin]                         (P10) HexagonalBolts Food: 4 Energy: 4 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $48
  INFO [SummaryPhase2.begin]                         Shop Smithore: 0 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: 4880
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Finish Round
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Chat Log
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.buildMules]                             Shop built 0 mules for 0 smithore and now has 14 mules
  INFO [Shop.buildMules]                             A mule now costs $100
  INFO [GameController.beginNextPhase]               --------- INTRO #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [PlanManager.startPlanSearch]                 AI: <B.I.L.L.> prefers Energy
  INFO [PlanManager.startPlanSearch]                 AI: <D.A.N.> prefers Smithore
  INFO [PlanManager.startPlanSearch]                 AI: <A.L.A.N.> prefers Energy
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 4 plans testing 7616 tiles in 81217959ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 7616 tiles in 82363073ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 7616 tiles in 85405075ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Energy <claim> (+3) 1460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1945 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Energy <claim> (+3) 1460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1945 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Energy <claim> (+3) 1460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1945 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Energy <claim> (+3) 1460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1945 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants Plain (5,2)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 Smithore <claim> (+4) 1560 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 2210 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 Smithore <claim> (+4) 1560 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 2210 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 Smithore <claim> (+4) 1560 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 2110 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain2 Smithore <claim> (+3) 1510 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 2010 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants Mountain3 (8,2)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Energy <claim> (+3) 1460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1945 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Energy <claim> (+3) 1460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1945 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Energy <claim> (+3) 1460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1945 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Energy <claim> (+3) 1460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 1945 points
  INFO [LandGrantActuator.update]                    AI: <A.L.A.N.> wants Plain (5,2)
  INFO [LandGrantActuator.agreeOnClaims]             AI: Agrees on tiles to claim.
  INFO [LandGrantActuator.agreeOnClaims]             AI: <B.I.L.L.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <B.I.L.L.> can't claim Plain (5,2) and wants Plain (3,2) 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]                   Land grant roundtrip 15ms, 5 frames (5 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 9ms, max 9ms
  INFO [LandGrantPhase.update]                       Sending claim tile 4
  INFO [LandGrantPhase.apply]                        (P10) HexagonalBolts claims tile 4 at time 25
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 14ms, max 14ms
  INFO [LandGrantPhase.apply]                        (P10) HexagonalBolts is granted tile 4: River (4,0)
  INFO [LandGrantPhase.update]                       Sending claim tile 21
  INFO [LandGrantPhase.apply]                        (P11) <B.I.L.L.> claims tile 21 at time 23
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [LandGrantPhase.apply]                        (P11) <B.I.L.L.> is granted tile 21: Plain (3,2)
  INFO [LandGrantPhase.update]                       Sending claim tile 22
  INFO [LandGrantPhase.apply]                        (P13) <A.L.A.N.> claims tile 22 at time 24
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [LandGrantPhase.apply]                        (P13) <A.L.A.N.> is granted tile 22: Plain (5,2)
  INFO [LandGrantPhase.update]                       Sending claim tile 25
  INFO [LandGrantPhase.apply]                        (P12) <D.A.N.> claims tile 25 at time 21
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [LandGrantPhase.apply]                        (P12) <D.A.N.> is granted tile 25: Mountain3 (8,2)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 14ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [LandAuction.generateColonyAuctions]          Generated 0 colony land auctions
  INFO [AbstractLandAuctionPhase.begin]              Land Auctions Player: 0 Colony: 0
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 14ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -3704339507035855210
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  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: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 14 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12) <D.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 17 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 161 tiles in 1878583ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 Smithore (+4) 1560 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 2210 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 Mountain3 Smithore (+4) 1560 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 Mountain3 (8,2) with Smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: CatchWampus
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: CatchWampus
  INFO [DevelopmentMessenger.showMessage]            <D.A.N.> caught the Mountain Wampus and got $100
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P12) <D.A.N.> gambles $137, max: 47.5, left: 26.52, fraction 0.5583158
  INFO [DevelopmentMessenger.showMessage]            <D.A.N.> won $137 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 1050
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 6ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 5 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 7139495452796944945
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventPhase.begin]                      No player event for (P11) <B.I.L.L.> (rank 2)
  INFO [Player.useFood]                              (P11) <B.I.L.L.> has 4 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 6ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 6 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11) <B.I.L.L.>
  INFO [Wampus.<init>]                               Wumpus has 17 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 161 tiles in 5986520ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Energy (+3) 1460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 1970 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) 1460 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]                       (P11) <B.I.L.L.> develops tile Plain (3,2) with Energy factory
  INFO [DevelopmentActuator.enqueueAssay]            AI: (P11) <B.I.L.L.> want's to assay Mountain3 (8,1) with 37.139763 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]                          (P11) <B.I.L.L.> got an assay bot
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: Assay
  INFO [DevelopmentMessenger.showMessage]            Found Medium amounts of Crystite
  INFO [DevelopmentActuator.enqueueAssay]            AI: (P11) <B.I.L.L.> want's to assay Plain (0,3) with 27.579544 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]                          (P11) <B.I.L.L.> got an assay bot
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: Assay
  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]                            (P11) <B.I.L.L.> gambles $58, max: 47.5, left: 10.440002, fraction 0.21978952
  INFO [DevelopmentMessenger.showMessage]            <B.I.L.L.> won $58 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P11) <B.I.L.L.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P11) <B.I.L.L.> finishes on update 1854
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 3ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 3 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 7182459705456700899
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventPhase.begin]                      No player event for (P13) <A.L.A.N.> (rank 3)
  INFO [Player.useFood]                              (P13) <A.L.A.N.> has 4 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 3 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13) <A.L.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 17 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 1 plans testing 161 tiles in 2307681ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Energy (+3) 1460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 1970 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <A.L.A.N.> plans to develop Plain Energy (+3) 1460 points
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.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: <A.L.A.N.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.A.N.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P13) <A.L.A.N.> develops tile Plain (5,2) with Energy factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P13) <A.L.A.N.> gambles $62, max: 47.5, left: 36.56, fraction 0.76968426
  INFO [DevelopmentMessenger.showMessage]            <A.L.A.N.> won $62 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P13) <A.L.A.N.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P13) <A.L.A.N.> finishes on update 548
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 10ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 6063982702313262830
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventPhase.begin]                      No player event for (P10) HexagonalBolts (rank 4)
  INFO [Player.useFood]                              (P10) HexagonalBolts has 4 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 10 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10) HexagonalBolts
  INFO [Wampus.<init>]                               Wumpus has 17 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) HexagonalBolts develops tile River (4,0) with Food factory
  INFO [GameModel.gamble]                            (P10) HexagonalBolts gambles $79, max: 47.5, left: 26.460001, fraction 0.5570527
  INFO [DevelopmentMessenger.showMessage]            HexagonalBolts won $79 in the pub
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P10) HexagonalBolts finishes on update 1053
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_A #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 10ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: ACID_RAIN_STORM
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 5157621299547373317
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 10 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -6630110878593412912
  INFO [ProductionPhase.finishPower]                 (P10) HexagonalBolts has 4 energy and needs 1 energy
  INFO [ProductionPhase.finishPower]                 (P11) <B.I.L.L.> has 4 energy and needs 0 energy
  INFO [ProductionPhase.finishPower]                 (P12) <D.A.N.> has 4 energy and needs 1 energy
  INFO [ProductionPhase.finishPower]                 (P13) <A.L.A.N.> has 4 energy and needs 0 energy
  INFO [ProductionPhase.logProduction]               (P10) HexagonalBolts expects 5 food, 0 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <B.I.L.L.> expects 0 food, 1 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <D.A.N.> expects 0 food, 0 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <A.L.A.N.> expects 0 food, 1 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10) HexagonalBolts produced 6 food, 0 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <B.I.L.L.> produced 0 food, 3 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <D.A.N.> produced 0 food, 0 energy, 7 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <A.L.A.N.> produced 0 food, 2 energy, 0 smithore, 0 crystite
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_B #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -7096573810002340651
  INFO [Shop.calcBuySellPrice]                       Current Smithore price $50
  INFO [Shop.calcBuySellPrice]                       Smithore random variation $-1
  INFO [Shop.printPriceFactor]                       Smithore shop: 0, mules potentially available: 10, mules needed: 4
  INFO [Shop.printPriceFactor]                       Smithore is decreased to 55%
  INFO [Shop.printPriceFactor]                       Smithore buy price $49 sell price $84
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Smithore: 0 critical: 0 surplus: 0 money: $954
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Smithore: 0 critical: 0 surplus: 0 money: $908
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Smithore: 7 critical: 0 surplus: 7 money: $1062
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Smithore: 0 critical: 0 surplus: 0 money: $912
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_SMITHORE #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 1
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 1
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 1
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 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 0.985 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 15ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P10) HexagonalBolts (P11) <B.I.L.L.> (P13) <A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P12) <D.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $60
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $65
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $62
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Smithore price set to average $49 (0 units for $0)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 14ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 14 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -2985424916230656867
  INFO [Shop.calcBuySellPrice]                       Current Crystite price $48
  INFO [Shop.calcBuySellPrice]                       Crystite buy price $128 sell price $268
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Crystite: 0 critical: 0 surplus: 0 money: $954
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Crystite: 0 critical: 0 surplus: 0 money: $908
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $1062
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $912
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_CRYSTITE #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 14ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 14 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 2
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 2
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 2
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 2
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Crystite price set to average $128 (0 units for $0)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_FOOD #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 14ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 14 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -2998071247914350304
  INFO [Shop.calcBuySellPrice]                       Current Food price $30
  INFO [Shop.printPriceFactor]                       Food shop: 8, total: 18, needed: 12
  INFO [Shop.printPriceFactor]                       Food is decreased to 75%
  INFO [Shop.printPriceFactor]                       Food buy price $15 sell price $50
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Food: 7 critical: 3 surplus: 4 money: $954
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Food: 1 critical: 3 surplus: -2 money: $908
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Food: 1 critical: 3 surplus: -2 money: $1062
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Food: 1 critical: 3 surplus: -2 money: $912
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_FOOD #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 3
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 3
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 3
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 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 0.985 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 17ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11) <B.I.L.L.> (P12) <D.A.N.> (P13) <A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10) HexagonalBolts 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $102 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $102 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $102 ratio 2.5
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $102 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $102 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $102 ratio 2.5
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $102 ratio 2.5
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $102 ratio 2.5
  INFO [AbstractAuctionPhase.checkCancelTransactions] Buyer left auction line.
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $86 ratio 2.04
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $91 ratio 2.1733332
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $77 ratio 1.7733333
  INFO [AbstractAuctionPhase.checkCancelTransactions] Buyer left auction line.
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $58 ratio 1.24
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 17ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Food price set to average $50 (6 units for $300)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 3387955474543825915
  INFO [Shop.calcBuySellPrice]                       Current Energy price $25
  INFO [Shop.printPriceFactor]                       Energy shop: 8, total: 25, needed: 6
  INFO [Shop.printPriceFactor]                       Energy is decreased to 43%
  INFO [Shop.printPriceFactor]                       Energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Energy: 3 critical: 2 surplus: 1 money: $954
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Energy: 6 critical: 1 surplus: 5 money: $808
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Energy: 3 critical: 2 surplus: 1 money: $962
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Energy: 5 critical: 1 surplus: 4 money: $812
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_ENERGY #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 4
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 4
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 4
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 4
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.985 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 17ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P10) HexagonalBolts (P12) <D.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P11) <B.I.L.L.> (P13) <A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $11 ratio 0.04000002
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $13 ratio 0.110000014
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.17000002
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 17ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Energy price set to average $25 (7 units for $70)
  INFO [GameController.beginNextPhase]               --------- SUMMARY #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 11ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 11 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -4242160768031499332
  INFO [SummaryPhase2.begin]                         Total Memory: 66715648
  INFO [SummaryPhase2.begin]                         Free Memory: 50367824
  INFO [SummaryPhase2.begin]                         (P12) <D.A.N.> Money: $962 Plots: 1 Assets: 678 Total: 2140
  INFO [SummaryPhase2.begin]                         (P12) <D.A.N.> Food: 3 Energy: 3 Smithore: 7 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10) HexagonalBolts Money: $954 Plots: 1 Assets: 485 Total: 1939
  INFO [SummaryPhase2.begin]                         (P10) HexagonalBolts Food: 7 Energy: 3 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11) <B.I.L.L.> Money: $848 Plots: 1 Assets: 285 Total: 1633
  INFO [SummaryPhase2.begin]                         (P11) <B.I.L.L.> Food: 3 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P13) <A.L.A.N.> Money: $842 Plots: 1 Assets: 285 Total: 1627
  INFO [SummaryPhase2.begin]                         (P13) <A.L.A.N.> Food: 3 Energy: 2 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $128
  INFO [SummaryPhase2.begin]                         Shop Smithore: 0 units $49
  INFO [SummaryPhase2.begin]                         Shop Food: 2 units $50
  INFO [SummaryPhase2.begin]                         Shop Energy: 15 units $25
  INFO [SummaryPhase2.begin]                         Shop mules: 10 $100
  INFO [SummaryPhase2.begin]                         Colony total: 7339
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Finish Round
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Chat Log
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.buildMules]                             Shop built 0 mules for 0 smithore and now has 10 mules
  INFO [Shop.buildMules]                             A mule now costs $90
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 10 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 10 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 6361 tiles in 72754331ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 6361 tiles in 73886249ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food <claim> (+4) 1978 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2773 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food <claim> (+4) 1978 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2773 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food <claim> (+4) 1978 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2673 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Food <claim> (+2) 1858 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2503 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants River (4,3)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: River Food <claim> (+4) 2426 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3192 points
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 4 plans testing 6361 tiles in 75422149ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: River Food <claim> (+4) 2426 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3192 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: River Food <claim> (+4) 2426 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 3186 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 Smithore <claim> (+4) 2460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 3093 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants River (4,3)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: River Food <claim> (+4) 1972 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2767 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: River Food <claim> (+4) 1972 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2767 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: River Food <claim> (+4) 1972 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2667 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain3 Crystite <claim> (+2) 1912 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2557 points
  INFO [LandGrantActuator.update]                    AI: <A.L.A.N.> 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 River (4,3) and wants River (4,4) instead
  INFO [LandGrantActuator.agreeOnClaims]             AI: <B.I.L.L.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <B.I.L.L.> can't claim River (4,3) and wants River (4,4) instead
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 9ms, max 9ms
  INFO [LandGrantPhase$2.finished]                   Land grant roundtrip 15ms, 5 frames (5 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 9ms, max 9ms
  INFO [LandGrantPhase.update]                       Sending claim tile 16
  INFO [LandGrantPhase.apply]                        (P10) HexagonalBolts claims tile 16 at time 17
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 10ms, max 10ms
  INFO [LandGrantPhase.apply]                        (P10) HexagonalBolts is granted tile 16: Mountain3 (8,1)
  INFO [LandGrantPhase.update]                       Sending claim tile 26
  INFO [LandGrantPhase.apply]                        (P13) <A.L.A.N.> claims tile 26 at time 23
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 10ms, max 10ms
  INFO [LandGrantPhase.apply]                        (P13) <A.L.A.N.> is granted tile 26: River (4,3)
  INFO [LandGrantPhase.update]                       Sending claim tile 35
  INFO [LandGrantPhase.update]                       Sending claim tile 35
  INFO [LandGrantPhase.apply]                        (P11) <B.I.L.L.> claims tile 35 at time 23
  INFO [LandGrantPhase.apply]                        Claim happend at same time but player (P12) <D.A.N.> has better rank 1 than current claimer 3
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 10ms, max 10ms
  INFO [LandGrantActuator.pickNextTile]              AI: <D.A.N.> panics in land grant!
  INFO [LandGrantPhase.apply]                        (P11) <B.I.L.L.> is granted tile 35: River (4,4)
  INFO [LandGrantPhase.update]                       Sending claim tile 36
  INFO [LandGrantPhase.apply]                        (P12) <D.A.N.> claims tile 36 at time 25
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 10ms, max 10ms
  INFO [LandGrantPhase.apply]                        (P12) <D.A.N.> is granted tile 36: Plain (5,4)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 10 ms
  INFO [LandAuction.generateColonyAuctions]          Generated 2 colony land auctions
  INFO [AbstractLandAuctionPhase.begin]              Land Auctions Player: 0 Colony: 2
  INFO [LandAuction.beginAuction]                    Land auction price: $160
  INFO [AbstractLandAuctionPhase.begin]              Starting land auction
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $428
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $540
  INFO [LandAuctionActuator.beginLandAuction]        AI: <A.L.A.N.> targets price $420
  INFO [NetworkTimer.start]                          Started "countdown" at 5.0 (owner 10)
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 5
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 5
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 5
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 5
  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 [Auction.setBuyersAndSellers]                 Auction buyers: (P10) HexagonalBolts (P11) <B.I.L.L.> (P12) <D.A.N.> (P13) <A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: 
  INFO [NetworkTimer.start]                          Started "phase timer" at 8.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $416 (changed $-4)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $520 (changed $-20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $404 (changed $-24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $428 (changed $44)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $540 (changed $36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $372 (changed $-56)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <A.L.A.N.> new price $420 (changed $4)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $528 (changed $-12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $540 (changed $56)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $480 (changed $-60)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $512 (changed $32)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $484 (changed $-28)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $524 (changed $40)
  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: BuyLand (10) 16 bytes
  INFO [AbstractLandAuctionPhase.apply]              (P10) HexagonalBolts bought the plot for $544
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 10ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [AbstractLandAuctionPhase.begin]              Land Auctions Player: 0 Colony: 1
  INFO [LandAuction.beginAuction]                    Land auction price: $484
  INFO [AbstractLandAuctionPhase.begin]              Starting land auction
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $428
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $444
  INFO [LandAuctionActuator.beginLandAuction]        AI: <A.L.A.N.> targets price $368
  INFO [NetworkTimer.start]                          Started "countdown" at 5.0 (owner 10)
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 6
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 6
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 6
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 6
  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 [Auction.setBuyersAndSellers]                 Auction buyers: (P10) HexagonalBolts (P11) <B.I.L.L.> (P12) <D.A.N.> (P13) <A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: 
  INFO [NetworkTimer.start]                          Started "phase timer" at 8.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: BuyLand (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 12ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 11 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -7654234254980360508
  INFO [Development.setPlayerOrder]                  Normal development order. 10 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  INFO [PlayerEventPhase.begin]                      No player event for (P12) <D.A.N.> (rank 1)
  INFO [Player.useFood]                              (P12) <D.A.N.> has 3 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 13ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 12 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12) <D.A.N.>
  INFO [Wampus.<init>]                               Wumpus 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 141 tiles in 1704475ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy (+3) 2371 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2827 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) 2371 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,4) 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 $146, max: 47.5, left: 34.36, fraction 0.7233684
  INFO [DevelopmentMessenger.showMessage]            <D.A.N.> won $146 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 658
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 16ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -6657033560513742059
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventPhase.begin]                      Event player: (P10) HexagonalBolts (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 [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Player.useFood]                              (P10) HexagonalBolts has 7 food and needs 3 food
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 16ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 16 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10) HexagonalBolts
  INFO [Wampus.<init>]                               Wumpus 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) HexagonalBolts develops tile Plain (2,4) with Energy factory
  INFO [GameModel.developLand]                       (P10) HexagonalBolts develops tile Mountain3 (8,1) with Smithore factory
  INFO [GameModel.gamble]                            (P10) HexagonalBolts gambles $71, max: 47.5, left: 9.100002, fraction 0.191579
  INFO [DevelopmentMessenger.showMessage]            HexagonalBolts won $71 in the pub
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P10) HexagonalBolts finishes on update 1921
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 16 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -4784778023031157465
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventPhase.begin]                      No player event for (P11) <B.I.L.L.> (rank 3)
  INFO [Player.useFood]                              (P11) <B.I.L.L.> has 3 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11) <B.I.L.L.>
  INFO [Wampus.<init>]                               Wumpus 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 141 tiles in 1802810ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food (+4) 1978 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2673 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) 1978 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]                       (P11) <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]                            (P11) <B.I.L.L.> gambles $55, max: 47.5, left: 35.08, fraction 0.73852634
  INFO [DevelopmentMessenger.showMessage]            <B.I.L.L.> won $55 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P11) <B.I.L.L.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P11) <B.I.L.L.> finishes on update 622
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -430995587089578599
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventPhase.begin]                      No player event for (P13) <A.L.A.N.> (rank 4)
  INFO [Player.useFood]                              (P13) <A.L.A.N.> has 3 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13) <A.L.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 14 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 1 plans testing 141 tiles in 1844954ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: River Food (+4) 1972 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 2667 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <A.L.A.N.> plans to develop River Food (+4) 1972 points
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.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: <A.L.A.N.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.A.N.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P13) <A.L.A.N.> develops tile River (4,3) with Food factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P13) <A.L.A.N.> gambles $164, max: 47.5, left: 36.440002, fraction 0.767158
  INFO [DevelopmentMessenger.showMessage]            <A.L.A.N.> won $164 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P13) <A.L.A.N.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P13) <A.L.A.N.> finishes on update 554
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_A #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: SUNSPOT_ACTIVITY
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 3731795542661222337
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 819293210208783248
  INFO [ProductionPhase.finishPower]                 (P10) HexagonalBolts has 3 energy and needs 2 energy
  INFO [ProductionPhase.finishPower]                 (P11) <B.I.L.L.> has 2 energy and needs 1 energy
  INFO [ProductionPhase.finishPower]                 (P12) <D.A.N.> has 3 energy and needs 1 energy
  INFO [ProductionPhase.finishPower]                 (P13) <A.L.A.N.> has 2 energy and needs 1 energy
  INFO [ProductionPhase.logProduction]               (P10) HexagonalBolts expects 4 food, 6 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <B.I.L.L.> expects 4 food, 6 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <D.A.N.> expects 0 food, 6 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <A.L.A.N.> expects 4 food, 6 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10) HexagonalBolts produced 3 food, 7 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <B.I.L.L.> produced 3 food, 8 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <D.A.N.> produced 0 food, 8 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <A.L.A.N.> produced 6 food, 4 energy, 0 smithore, 0 crystite
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_B #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 16 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 16 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -7363761073617149003
  INFO [Shop.calcBuySellPrice]                       Current Smithore price $49
  INFO [Shop.calcBuySellPrice]                       Smithore random variation $-3
  INFO [Shop.printPriceFactor]                       Smithore shop: 0, mules potentially available: 5, mules needed: 4
  INFO [Shop.printPriceFactor]                       Smithore is decreased to 85%
  INFO [Shop.printPriceFactor]                       Smithore buy price $47 sell price $82
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Smithore: 4 critical: 0 surplus: 4 money: $276
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Smithore: 0 critical: 0 surplus: 0 money: $788
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Smithore: 11 critical: 0 surplus: 11 money: $968
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Smithore: 0 critical: 0 surplus: 0 money: $891
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_SMITHORE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 7
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 7
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 7
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 7
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.985 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11) <B.I.L.L.> (P13) <A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10) HexagonalBolts (P12) <D.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $59
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $59
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $50
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Smithore price set to average $47 (0 units for $0)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 30ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 16 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 6826147230651181889
  INFO [Shop.calcBuySellPrice]                       Current Crystite price $128
  INFO [Shop.calcBuySellPrice]                       Crystite buy price $60 sell price $200
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Crystite: 0 critical: 0 surplus: 0 money: $276
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Crystite: 0 critical: 0 surplus: 0 money: $788
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $968
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $891
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_CRYSTITE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 33ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 8
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 8
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 8
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 8
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Crystite price set to average $60 (0 units for $0)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_FOOD #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 33ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 2817668039879087079
  INFO [Shop.calcBuySellPrice]                       Current Food price $50
  INFO [Shop.printPriceFactor]                       Food shop: 2, total: 16, needed: 12
  INFO [Shop.printPriceFactor]                       Food is decreased to 81%
  INFO [Shop.printPriceFactor]                       Food buy price $26 sell price $61
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Food: 5 critical: 3 surplus: 2 money: $276
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Food: 3 critical: 3 surplus: 0 money: $788
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Food: 0 critical: 3 surplus: -3 money: $968
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Food: 6 critical: 3 surplus: 3 money: $891
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_FOOD #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 29ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 9
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 9
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 9
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 9
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.971 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 29ms, max 29ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11) <B.I.L.L.> (P12) <D.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10) HexagonalBolts (P13) <A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $105 ratio 2.2666667
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $61 ratio 1.0
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $88 ratio 1.7733333
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $59 ratio 0.96999997
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $70 ratio 1.2800001
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $43 ratio 0.5
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 10
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $29 ratio 0.10000001
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Food price set to average $54 (3 units for $164)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 31ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 840899296999639932
  INFO [Shop.calcBuySellPrice]                       Current Energy price $25
  INFO [Shop.printPriceFactor]                       Energy shop: 15, total: 47, needed: 9
  INFO [Shop.printPriceFactor]                       Energy is decreased to 39%
  INFO [Shop.printPriceFactor]                       Energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Energy: 8 critical: 3 surplus: 5 money: $384
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Energy: 9 critical: 2 surplus: 7 money: $788
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Energy: 10 critical: 2 surplus: 8 money: $804
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Energy: 5 critical: 2 surplus: 3 money: $947
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_ENERGY #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 30ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 11
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 10
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 10
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 10
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.97 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 29ms, max 29ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10) HexagonalBolts (P11) <B.I.L.L.> (P12) <D.A.N.> (P13) <A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.checkCancelTransactions] New seller enters shop line
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $11 ratio 0.050000012
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 31ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 12
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 31ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Energy price set to average $25 (20 units for $200)
  INFO [GameController.beginNextPhase]               --------- SUMMARY #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 29ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 22 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -8744145680002369629
  INFO [SummaryPhase2.begin]                         Total Memory: 66715648
  INFO [SummaryPhase2.begin]                         Free Memory: 56242680
  INFO [SummaryPhase2.begin]                         (P12) <D.A.N.> Money: $874 Plots: 2 Assets: 949 Total: 2823
  INFO [SummaryPhase2.begin]                         (P12) <D.A.N.> Food: 3 Energy: 3 Smithore: 11 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10) HexagonalBolts Money: $434 Plots: 3 Assets: 680 Total: 2614
  INFO [SummaryPhase2.begin]                         (P10) HexagonalBolts Food: 3 Energy: 3 Smithore: 4 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P13) <A.L.A.N.> Money: $967 Plots: 2 Assets: 490 Total: 2457
  INFO [SummaryPhase2.begin]                         (P13) <A.L.A.N.> Food: 5 Energy: 3 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11) <B.I.L.L.> Money: $848 Plots: 2 Assets: 382 Total: 2230
  INFO [SummaryPhase2.begin]                         (P11) <B.I.L.L.> Food: 3 Energy: 3 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $60
  INFO [SummaryPhase2.begin]                         Shop Smithore: 0 units $47
  INFO [SummaryPhase2.begin]                         Shop Food: 2 units $54
  INFO [SummaryPhase2.begin]                         Shop Energy: 35 units $25
  INFO [SummaryPhase2.begin]                         Shop mules: 5 $90
  INFO [SummaryPhase2.begin]                         Colony total: 10124
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Finish Round
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Chat Log
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.buildMules]                             Shop built 0 mules for 0 smithore and now has 5 mules
  INFO [Shop.buildMules]                             A mule now costs $90
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 30ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [Development.setPlayerOrder]                  Reverse development order. 5 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 4830 tiles in 58589398ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 4830 tiles in 58232242ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 4 plans testing 4830 tiles in 58177753ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River Food <claim> (+4) 2663 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Food <claim> (+2) 3612 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Food <claim> (+2) 2663 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3612 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Food <claim> (+2) 2555 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3504 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Food <claim> (+2) 2555 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3504 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants River (4,1)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: River Food <claim> (+4) 3228 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Food <claim> (+2) 3987 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Food <claim> (+2) 3100 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3859 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Food <claim> (+2) 3100 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3859 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 Smithore <claim> (+4) 3140 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Smithore <claim> (+1) 3830 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants River (4,1)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: River Food <claim> (+4) 2836 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Food <claim> (+2) 3785 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Food <claim> (+2) 2836 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3785 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Food <claim> (+2) 2836 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3785 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Food <claim> (+2) 2728 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River Food <claim> (+4) 3677 points
  INFO [LandGrantActuator.update]                    AI: <A.L.A.N.> wants River (4,1)
  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 River (4,1) and wants Plain (5,1) instead
  INFO [LandGrantActuator.agreeOnClaims]             AI: <A.L.A.N.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <A.L.A.N.> can't claim River (4,1) and wants Plain (5,3) instead
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 30ms, max 30ms
  INFO [LandGrantPhase$2.finished]                   Land grant roundtrip 31ms, 6 frames (5 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 30ms, max 30ms
  INFO [LandGrantPhase.update]                       Sending claim tile 12
  INFO [LandGrantPhase.apply]                        (P10) HexagonalBolts claims tile 12 at time 25
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 29ms, max 29ms
  INFO [LandGrantActuator.pickNextTile]              AI: <B.I.L.L.> can't claim River (4,1) and wants Plain (3,4) instead
  INFO [LandGrantPhase.apply]                        (P10) HexagonalBolts is granted tile 12: River (4,1)
  INFO [LandGrantPhase.update]                       Sending claim tile 13
  INFO [LandGrantPhase.apply]                        (P12) <D.A.N.> claims tile 13 at time 24
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 29ms, max 29ms
  INFO [LandGrantPhase.apply]                        (P12) <D.A.N.> is granted tile 13: Plain (5,1)
  INFO [LandGrantPhase.update]                       Sending claim tile 25
  INFO [LandGrantPhase.apply]                        (P13) <A.L.A.N.> claims tile 25 at time 22
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 19ms, max 19ms
  INFO [LandGrantPhase.apply]                        (P13) <A.L.A.N.> is granted tile 25: Plain (5,3)
  INFO [LandGrantPhase.update]                       Sending claim tile 31
  INFO [LandGrantPhase.apply]                        (P11) <B.I.L.L.> claims tile 31 at time 22
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 17ms, max 17ms
  INFO [LandGrantPhase.apply]                        (P11) <B.I.L.L.> is granted tile 31: Plain (3,4)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 17ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [LandAuction.generateColonyAuctions]          Generated 0 colony land auctions
  INFO [AbstractLandAuctionPhase.begin]              Land Auctions Player: 0 Colony: 0
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 17ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 8746748442292663569
  INFO [Development.setPlayerOrder]                  Reverse development order. 5 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventPhase.begin]                      No player event for (P11) <B.I.L.L.> (rank 4)
  INFO [Player.useFood]                              (P11) <B.I.L.L.> has 3 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 17ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11) <B.I.L.L.>
  INFO [Wampus.<init>]                               Wumpus 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 125 tiles in 1769606ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Food (+2) 2663 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Food <claim> (+2) 3558 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 Food (+2) 2663 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]                       (P11) <B.I.L.L.> develops tile Plain (3,4) with Food factory
  INFO [DevelopmentActuator.enqueueAssay]            AI: (P11) <B.I.L.L.> want's to assay Plain (6,1) with 37.199764 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]                          (P11) <B.I.L.L.> got an assay bot
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: Assay
  INFO [DevelopmentMessenger.showMessage]            Found Medium 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]                            (P11) <B.I.L.L.> gambles $54, max: 47.5, left: 25.58, fraction 0.5385263
  INFO [DevelopmentMessenger.showMessage]            <B.I.L.L.> won $54 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P11) <B.I.L.L.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P11) <B.I.L.L.> finishes on update 1097
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 24ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 24 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -4036273957315025363
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventPhase.begin]                      No player event for (P13) <A.L.A.N.> (rank 3)
  INFO [Player.useFood]                              (P13) <A.L.A.N.> has 5 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 24ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 24 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13) <A.L.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 14 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 1 plans testing 125 tiles in 1738105ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Food (+2) 2836 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Food <claim> (+2) 3731 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <A.L.A.N.> plans to develop Plain Food (+2) 2836 points
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.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: <A.L.A.N.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.A.N.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P13) <A.L.A.N.> develops tile Plain (5,3) with Food factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P13) <A.L.A.N.> gambles $82, max: 47.5, left: 37.6, fraction 0.7915789
  INFO [DevelopmentMessenger.showMessage]            <A.L.A.N.> won $82 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P13) <A.L.A.N.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P13) <A.L.A.N.> finishes on update 496
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 17ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 2265367850102074460
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventPhase.begin]                      No player event for (P10) HexagonalBolts (rank 2)
  INFO [Player.useFood]                              (P10) HexagonalBolts has 3 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 17ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10) HexagonalBolts
  INFO [Wampus.<init>]                               Wumpus 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) HexagonalBolts develops tile River (4,1) with Food factory
  INFO [GameModel.buyAssay]                          (P10) HexagonalBolts got an assay bot
  INFO [DevelopmentMessenger.showMessage]            Found Medium amounts of Crystite
  INFO [GameModel.gamble]                            (P10) HexagonalBolts gambles $101, max: 47.5, left: 17.6, fraction 0.3705263
  INFO [DevelopmentMessenger.showMessage]            HexagonalBolts won $101 in the pub
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P10) HexagonalBolts finishes on update 1496
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 30ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -1676187879544520865
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  INFO [PlayerEventPhase.begin]                      No player event for (P12) <D.A.N.> (rank 1)
  INFO [Player.useFood]                              (P12) <D.A.N.> has 3 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 30ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12) <D.A.N.>
  INFO [Wampus.<init>]                               Wumpus 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 125 tiles in 1867515ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Food (+2) 3100 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Food <claim> (+2) 3751 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 Food (+2) 3100 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 Food factory
  INFO [DevelopmentActuator.enqueueAssay]            AI: (P12) <D.A.N.> want's to assay Plain (7,4) with 39.359814 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 Low amounts of Crystite
  INFO [DevelopmentActuator.enqueueAssay]            AI: (P12) <D.A.N.> want's to assay Mountain2 (6,4) with 28.419563 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]            No Crystite found
  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 $68, max: 47.5, left: 13.240002, fraction 0.2787369
  INFO [DevelopmentMessenger.showMessage]            <D.A.N.> won $68 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 1714
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_A #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 30ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: RADIATION
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 8705170532493402768
  INFO [ColonyEvent.applyEvent]                      Radiation strikes (P12) <D.A.N.> Mountain3 (8,2)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 30ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 6725332424963808973
  INFO [ProductionPhase.finishPower]                 (P10) HexagonalBolts has 3 energy and needs 3 energy
  INFO [ProductionPhase.finishPower]                 (P11) <B.I.L.L.> 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) <A.L.A.N.> has 3 energy and needs 2 energy
  INFO [ProductionPhase.logProduction]               (P10) HexagonalBolts expects 10 food, 3 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <B.I.L.L.> expects 8 food, 3 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <D.A.N.> expects 2 food, 3 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <A.L.A.N.> expects 8 food, 3 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10) HexagonalBolts produced 9 food, 5 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <B.I.L.L.> produced 10 food, 2 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <D.A.N.> produced 3 food, 3 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <A.L.A.N.> produced 9 food, 3 energy, 0 smithore, 0 crystite
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_B #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 17ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 17ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -3013684432702071415
  INFO [Shop.calcBuySellPrice]                       Current Smithore price $47
  INFO [Shop.calcBuySellPrice]                       Smithore random variation $-5
  INFO [Shop.printPriceFactor]                       Smithore shop: 0, mules potentially available: 1, mules needed: 5
  INFO [Shop.printPriceFactor]                       Smithore is increased to 250%
  INFO [Shop.printPriceFactor]                       Smithore buy price $113 sell price $148
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Smithore: 8 critical: 0 surplus: 8 money: $420
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Smithore: 0 critical: 0 surplus: 0 money: $787
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Smithore: 11 critical: 0 surplus: 11 money: $827
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Smithore: 0 critical: 0 surplus: 0 money: $934
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_SMITHORE #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 25ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 25 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 13
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 11
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 11
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 11
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.983 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 17ms, max 17ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 30ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11) <B.I.L.L.> (P13) <A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10) HexagonalBolts (P12) <D.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $113
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $113
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $113
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.checkCancelTransactions] New seller enters shop line
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 14
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 12
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 30ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Smithore price set to average $113 (19 units for $2147)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 17ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -741186729256667298
  INFO [Shop.calcBuySellPrice]                       Current Crystite price $60
  INFO [Shop.calcBuySellPrice]                       Crystite buy price $84 sell price $224
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Crystite: 0 critical: 0 surplus: 0 money: $1324
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Crystite: 0 critical: 0 surplus: 0 money: $787
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $2070
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $934
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_CRYSTITE #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 17ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 15
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 12
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 13
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 12
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Crystite price set to average $84 (0 units for $0)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_FOOD #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 16ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -5159733334151279618
  INFO [Shop.calcBuySellPrice]                       Current Food price $54
  INFO [Shop.printPriceFactor]                       Food shop: 2, total: 34, needed: 12
  INFO [Shop.printPriceFactor]                       Food is decreased to 51%
  INFO [Shop.printPriceFactor]                       Food buy price $15 sell price $50
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Food: 9 critical: 3 surplus: 6 money: $1324
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Food: 10 critical: 3 surplus: 7 money: $787
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Food: 3 critical: 3 surplus: 0 money: $2070
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Food: 10 critical: 3 surplus: 7 money: $934
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_FOOD #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 16ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 16 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 16
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 13
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 14
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 13
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.984 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 16ms, max 16ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 21ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P12) <D.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10) HexagonalBolts (P11) <B.I.L.L.> (P13) <A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.01000002
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.01000002
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $19 ratio 0.14000002
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $19 ratio 0.14000002
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 14
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 14
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 16ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 17
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 16ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Food price set to average $30 (20 units for $300)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 11ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 11 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -5986077422081091486
  INFO [Shop.calcBuySellPrice]                       Current Energy price $25
  INFO [Shop.printPriceFactor]                       Energy shop: 35, total: 52, needed: 12
  INFO [Shop.printPriceFactor]                       Energy is decreased to 42%
  INFO [Shop.printPriceFactor]                       Energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Energy: 5 critical: 4 surplus: 1 money: $1414
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Energy: 3 critical: 3 surplus: 0 money: $892
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Energy: 5 critical: 2 surplus: 3 money: $2070
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Energy: 4 critical: 3 surplus: 1 money: $1039
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_ENERGY #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 11ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 11 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 18
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 15
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 15
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 15
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.989 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 16ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11) <B.I.L.L.> (P13) <A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10) HexagonalBolts (P12) <D.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $53 ratio 1.24
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $12 ratio 0.07000001
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.checkCancelTransactions] Seller HexagonalBolts rank 2 takes over trade from seller <D.A.N.> rank 1
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $13 ratio 0.10000001
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 19
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 16ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Energy price set to average $28 (1 units for $28)
  INFO [GameController.beginNextPhase]               --------- SUMMARY #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 11 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -4448038063619762
  INFO [SummaryPhase2.begin]                         Total Memory: 66715648
  INFO [SummaryPhase2.begin]                         Free Memory: 51059560
  INFO [SummaryPhase2.begin]                         (P10) HexagonalBolts Money: $1442 Plots: 4 Assets: 517 Total: 3959
  INFO [SummaryPhase2.begin]                         (P10) HexagonalBolts Food: 3 Energy: 4 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P12) <D.A.N.> Money: $2070 Plots: 3 Assets: 375 Total: 3945
  INFO [SummaryPhase2.begin]                         (P12) <D.A.N.> Food: 3 Energy: 5 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P13) <A.L.A.N.> Money: $1039 Plots: 3 Assets: 407 Total: 2946
  INFO [SummaryPhase2.begin]                         (P13) <A.L.A.N.> Food: 3 Energy: 4 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11) <B.I.L.L.> Money: $864 Plots: 3 Assets: 407 Total: 2771
  INFO [SummaryPhase2.begin]                         (P11) <B.I.L.L.> Food: 3 Energy: 4 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $84
  INFO [SummaryPhase2.begin]                         Shop Smithore: 19 units $113
  INFO [SummaryPhase2.begin]                         Shop Food: 22 units $30
  INFO [SummaryPhase2.begin]                         Shop Energy: 35 units $28
  INFO [SummaryPhase2.begin]                         Shop mules: 1 $90
  INFO [SummaryPhase2.begin]                         Colony total: 13621
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Finish Round
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Chat Log
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.buildMules]                             Shop built 8 mules for 16 smithore and now has 9 mules
  INFO [Shop.buildMules]                             A mule now costs $220
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 9 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 3901 tiles in 56148475ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 4 plans testing 3901 tiles in 56830860ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain3 Smithore <claim> (+4) 3264 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 4298 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain2 Smithore <claim> (+3) 3151 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 4185 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain2 Smithore <claim> (+3) 3151 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 4185 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain2 Smithore <claim> (+3) 3151 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 4185 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants Mountain3 (7,3)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain3 Smithore <claim> (+4) 3439 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 4473 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain2 Smithore <claim> (+3) 3326 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 4360 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain2 Smithore <claim> (+3) 3326 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 4360 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain2 Smithore <claim> (+3) 3326 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 4360 points
  INFO [LandGrantActuator.update]                    AI: <A.L.A.N.> wants Mountain3 (7,3)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 15542 tiles in 175021530ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 Smithore (+4) Mountain3 Smithore <claim> (+4) 4601 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Smithore <claim> (+1) 6367 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 Smithore (+4) Plain Smithore <claim> (+1) 4488 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 6254 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 Smithore (+4) Plain Smithore <claim> (+1) 4488 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 6254 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 Smithore (+4) Mountain2 Smithore <claim> (+3) 4488 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 Smithore <claim> (+4) 6141 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants Mountain3 (7,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 Mountain3 (7,3) and wants Plain (7,2) instead
  INFO [LandGrantActuator.agreeOnClaims]             AI: <A.L.A.N.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <A.L.A.N.> can't claim Mountain3 (7,3) and wants Mountain2 (6,4) instead
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [LandGrantPhase$2.finished]                   Land grant roundtrip 16ms, 5 frames (5 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [LandGrantPhase.update]                       Sending claim tile 4
  INFO [LandGrantPhase.apply]                        (P10) HexagonalBolts claims tile 4 at time 11
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [LandGrantPhase.apply]                        (P10) HexagonalBolts is granted tile 4: Plain (5,0)
  INFO [LandGrantPhase.update]                       Sending claim tile 18
  INFO [LandGrantPhase.apply]                        (P12) <D.A.N.> claims tile 18 at time 21
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [LandGrantPhase.apply]                        (P12) <D.A.N.> is granted tile 18: Plain (7,2)
  INFO [LandGrantPhase.update]                       Sending claim tile 24
  INFO [LandGrantPhase.apply]                        (P11) <B.I.L.L.> claims tile 24 at time 21
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [LandGrantPhase.apply]                        (P11) <B.I.L.L.> is granted tile 24: Mountain3 (7,3)
  INFO [LandGrantPhase.update]                       Sending claim tile 28
  INFO [LandGrantPhase.apply]                        (P13) <A.L.A.N.> claims tile 28 at time 24
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 15ms, max 15ms
  INFO [LandGrantPhase.apply]                        (P13) <A.L.A.N.> is granted tile 28: Mountain2 (6,4)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [LandAuction.generateColonyAuctions]          Generated 1 colony land auctions
  INFO [AbstractLandAuctionPhase.begin]              Land Auctions Player: 0 Colony: 1
  INFO [LandAuction.beginAuction]                    Land auction price: $360
  INFO [AbstractLandAuctionPhase.begin]              Starting land auction
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $52
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $452
  INFO [LandAuctionActuator.beginLandAuction]        AI: <A.L.A.N.> targets price $228
  INFO [NetworkTimer.start]                          Started "countdown" at 5.0 (owner 10)
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 20
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 16
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 16
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 16
  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 [Auction.setBuyersAndSellers]                 Auction buyers: (P10) HexagonalBolts (P11) <B.I.L.L.> (P12) <D.A.N.> (P13) <A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: 
  INFO [NetworkTimer.start]                          Started "phase timer" at 8.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $492 (changed $40)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $488 (changed $-4)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $536 (changed $48)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $500 (changed $-36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $512 (changed $12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $476 (changed $-36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $496 (changed $20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $528 (changed $32)
  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: BuyLand (10) 16 bytes
  INFO [AbstractLandAuctionPhase.apply]              (P10) HexagonalBolts bought the plot for $536
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -2143620327647220074
  INFO [Development.setPlayerOrder]                  Normal development order. 9 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  INFO [PlayerEventPhase.begin]                      Event player: (P10) HexagonalBolts (rank 1)
  INFO [PlayerEventPhase.begin]                      Event: MischievousElves
  INFO [PlayerEventPhase$1.finished]                 Event Message: Mischievous Glac-Elves broke into your storage shed and stole half your food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Player.useFood]                              (P10) HexagonalBolts has 1 food and needs 3 food
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 11ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 14 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10) HexagonalBolts
  INFO [Wampus.<init>]                               Wumpus has 9 mountains, reward is $200
  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) HexagonalBolts develops tile Plain (3,3) with Food factory
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P10) HexagonalBolts finishes on update 959
  INFO [Mule.runAway]                                Mule runs away for (P10) HexagonalBolts
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 11 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -909425477676750290
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventPhase.begin]                      No player event for (P12) <D.A.N.> (rank 2)
  INFO [Player.useFood]                              (P12) <D.A.N.> has 3 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12) <D.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 9 mountains, reward is $200
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 1 plans testing 418 tiles in 4785215ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Smithore (+1) Mountain3 Smithore (+4) 4488 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 6028 points
  INFO [DevelopmentActuator.enqueueAssay]            AI: (P12) <D.A.N.> want's to assay Plain (7,2) with 47.5 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 [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [GameModel.buyAssay]                          (P12) <D.A.N.> got an assay bot
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: Assay
  INFO [DevelopmentMessenger.showMessage]            Found High amounts of Crystite
  INFO [DevelopmentActuator.actionComplete]          AI: (P12) <D.A.N.> did assay and now needs a new plan.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 1 plans testing 522 tiles in 5716205ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Smithore (+1) Mountain3 Smithore (+4) 4488 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 6028 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 Smithore (+1) Mountain3 Smithore (+4) 4488 points
  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 Plain (7,2) with Smithore 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 Mountain3 (8,2) with Smithore 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 $110, max: 47.5, left: 5.420002, fraction 0.11410531
  INFO [DevelopmentMessenger.showMessage]            <D.A.N.> won $110 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 2105
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 5509177938873968518
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventPhase.begin]                      No player event for (P13) <A.L.A.N.> (rank 3)
  INFO [Player.useFood]                              (P13) <A.L.A.N.> has 3 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13) <A.L.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 9 mountains, reward is $200
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 1 plans testing 131 tiles in 2018636ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain2 Smithore (+3) 3326 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 4247 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <A.L.A.N.> plans to develop Mountain2 Smithore (+3) 3326 points
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.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: <A.L.A.N.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.A.N.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P13) <A.L.A.N.> develops tile Mountain2 (6,4) with Smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: CatchWampus
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.A.N.> Action: CatchWampus
  INFO [DevelopmentMessenger.showMessage]            <A.L.A.N.> caught the Mountain Wampus and got $200
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P13) <A.L.A.N.> gambles $111, max: 47.5, left: 21.0, fraction 0.44210526
  INFO [DevelopmentMessenger.showMessage]            <A.L.A.N.> won $111 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P13) <A.L.A.N.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P13) <A.L.A.N.> finishes on update 1326
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 5392460054104642434
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventPhase.begin]                      No player event for (P11) <B.I.L.L.> (rank 4)
  INFO [Player.useFood]                              (P11) <B.I.L.L.> has 3 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11) <B.I.L.L.>
  INFO [Wampus.<init>]                               Wumpus has 9 mountains, reward is $200
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 1 plans testing 105 tiles in 1670419ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain3 Smithore (+4) 3264 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 4298 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 Mountain3 Smithore (+4) 3264 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]                       (P11) <B.I.L.L.> develops tile Mountain3 (7,3) with Smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToPub
  INFO [GameModel.gamble]                            (P11) <B.I.L.L.> gambles $196, max: 47.5, left: 30.6, fraction 0.6442105
  INFO [DevelopmentMessenger.showMessage]            <B.I.L.L.> won $196 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P11) <B.I.L.L.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P11) <B.I.L.L.> finishes on update 846
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_A #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: METEORITE_STRIKE
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -4535281944654959364
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -2008382196649542068
  INFO [ProductionPhase.finishPower]                 (P10) HexagonalBolts has 4 energy and needs 4 energy
  INFO [ProductionPhase.finishPower]                 (P11) <B.I.L.L.> has 4 energy and needs 3 energy
  INFO [ProductionPhase.finishPower]                 (P12) <D.A.N.> has 5 energy and needs 3 energy
  INFO [ProductionPhase.finishPower]                 (P13) <A.L.A.N.> has 4 energy and needs 3 energy
  INFO [ProductionPhase.logProduction]               (P10) HexagonalBolts expects 15 food, 3 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <B.I.L.L.> expects 8 food, 3 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <D.A.N.> expects 2 food, 3 energy, 7 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <A.L.A.N.> expects 8 food, 3 energy, 3 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10) HexagonalBolts produced 15 food, 2 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <B.I.L.L.> produced 11 food, 3 energy, 3 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <D.A.N.> produced 2 food, 5 energy, 6 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <A.L.A.N.> produced 7 food, 5 energy, 3 smithore, 0 crystite
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_B #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -7254463676286488838
  INFO [Shop.calcBuySellPrice]                       Current Smithore price $113
  INFO [Shop.calcBuySellPrice]                       Smithore random variation $2
  INFO [Shop.printPriceFactor]                       Smithore shop: 3, mules potentially available: 4, mules needed: 5
  INFO [Shop.printPriceFactor]                       Smithore is increased to 119%
  INFO [Shop.printPriceFactor]                       Smithore buy price $136 sell price $171
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Smithore: 4 critical: 0 surplus: 4 money: $441
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Smithore: 3 critical: 0 surplus: 3 money: $765
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Smithore: 6 critical: 0 surplus: 6 money: $1590
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Smithore: 3 critical: 0 surplus: 3 money: $1055
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_SMITHORE #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 10ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 21
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 17
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 17
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 17
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.99 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 8ms, max 8ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 12ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10) HexagonalBolts (P11) <B.I.L.L.> (P12) <D.A.N.> (P13) <A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $136
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $136
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $136
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.checkCancelTransactions] New seller enters shop line
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 28 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 28 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 18
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 18
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 18
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 12ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 22
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 12ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Smithore price set to average $136 (16 units for $2176)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 1343028227180081368
  INFO [Shop.calcBuySellPrice]                       Current Crystite price $84
  INFO [Shop.calcBuySellPrice]                       Crystite buy price $68 sell price $208
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Crystite: 0 critical: 0 surplus: 0 money: $985
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Crystite: 0 critical: 0 surplus: 0 money: $1173
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $2406
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $1463
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_CRYSTITE #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 23
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 19
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 19
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 19
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Crystite price set to average $68 (0 units for $0)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_FOOD #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 8ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 8 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 2048460058320264815
  INFO [Shop.calcBuySellPrice]                       Current Food price $30
  INFO [Shop.printPriceFactor]                       Food shop: 22, total: 57, needed: 16
  INFO [Shop.printPriceFactor]                       Food is decreased to 46%
  INFO [Shop.printPriceFactor]                       Food buy price $15 sell price $50
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Food: 15 critical: 4 surplus: 11 money: $985
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Food: 11 critical: 4 surplus: 7 money: $1173
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Food: 2 critical: 4 surplus: -2 money: $2406
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Food: 7 critical: 4 surplus: 3 money: $1463
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_FOOD #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 12ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 12 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 24
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 20
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 20
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 20
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.986 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 14ms, max 14ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 22ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P12) <D.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10) HexagonalBolts (P11) <B.I.L.L.> (P13) <A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $21 ratio 0.18
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $53 ratio 1.0933334
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $31 ratio 0.46000004
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $36 ratio 0.6
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 22ms
  INFO [AbstractAuctionPhase.checkCancelTransactions] Buyer left auction line.
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.checkCancelTransactions] New seller enters shop line
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 21
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 21
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 22ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 25ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Food price set to average $30 (20 units for $334)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 16ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 16 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -1866801992128367224
  INFO [Shop.calcBuySellPrice]                       Current Energy price $28
  INFO [Shop.printPriceFactor]                       Energy shop: 35, total: 54, needed: 17
  INFO [Shop.printPriceFactor]                       Energy is decreased to 49%
  INFO [Shop.printPriceFactor]                       Energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Energy: 2 critical: 5 surplus: -3 money: $1135
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Energy: 4 critical: 4 surplus: 0 money: $1312
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Energy: 7 critical: 4 surplus: 3 money: $2342
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Energy: 6 critical: 4 surplus: 2 money: $1508
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_ENERGY #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 16ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 16 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 25
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 22
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 21
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 22
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.984 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 16ms, max 16ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 22ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P10) HexagonalBolts (P11) <B.I.L.L.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P12) <D.A.N.> (P13) <A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $94 ratio 2.4
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $45 ratio 1.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $45 ratio 1.0
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $45 ratio 1.0
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 19ms
  INFO [AbstractAuctionPhase.checkCancelTransactions] Buyer left auction line.
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $45 ratio 1.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 19ms
  INFO [AbstractAuctionPhase.checkCancelTransactions] Buyer left auction line.
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 19ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Energy price set to average $44 (4 units for $177)
  INFO [GameController.beginNextPhase]               --------- SUMMARY #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 13ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 14 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 5441661559793261207
  INFO [SummaryPhase2.begin]                         Total Memory: 66715648
  INFO [SummaryPhase2.begin]                         Free Memory: 49583224
  INFO [SummaryPhase2.begin]                         (P12) <D.A.N.> Money: $2430 Plots: 4 Assets: 705 Total: 5135
  INFO [SummaryPhase2.begin]                         (P12) <D.A.N.> Food: 4 Energy: 5 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10) HexagonalBolts Money: $1002 Plots: 6 Assets: 745 Total: 4747
  INFO [SummaryPhase2.begin]                         (P10) HexagonalBolts Food: 5 Energy: 5 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P13) <A.L.A.N.> Money: $1552 Plots: 4 Assets: 655 Total: 4207
  INFO [SummaryPhase2.begin]                         (P13) <A.L.A.N.> Food: 4 Energy: 5 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11) <B.I.L.L.> Money: $1268 Plots: 4 Assets: 655 Total: 3923
  INFO [SummaryPhase2.begin]                         (P11) <B.I.L.L.> Food: 4 Energy: 5 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $68
  INFO [SummaryPhase2.begin]                         Shop Smithore: 19 units $136
  INFO [SummaryPhase2.begin]                         Shop Food: 40 units $30
  INFO [SummaryPhase2.begin]                         Shop Energy: 34 units $44
  INFO [SummaryPhase2.begin]                         Shop mules: 3 $220
  INFO [SummaryPhase2.begin]                         Colony total: 18012
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Finish Round
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Chat Log
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.buildMules]                             Shop built 8 mules for 16 smithore and now has 11 mules
  INFO [Shop.buildMules]                             A mule now costs $270
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 11 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 2726 tiles in 38202963ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 4 plans testing 2726 tiles in 39286777ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 2726 tiles in 39818893ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain2 Smithore <claim> (+3) 4472 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 6241 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain2 Smithore <claim> (+3) 4472 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 6241 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain2 Smithore <claim> (+3) 4472 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 6241 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Smithore <claim> (+1) 4472 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 6241 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants Mountain2 (7,0)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain2 Smithore <claim> (+3) 6280 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 8373 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain2 Smithore <claim> (+3) 6280 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 8373 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain2 Smithore <claim> (+3) 6280 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 8373 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain1 Smithore <claim> (+2) 6144 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain1 Smithore <claim> (+2) 8237 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants Mountain2 (7,0)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain2 Smithore <claim> (+3) 4620 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 6253 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain2 Smithore <claim> (+3) 4620 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 6253 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain2 Smithore <claim> (+3) 4620 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 6253 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Smithore <claim> (+1) 4620 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 Smithore <claim> (+3) 6253 points
  INFO [LandGrantActuator.update]                    AI: <A.L.A.N.> wants Mountain2 (7,0)
  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 Mountain2 (7,0) and wants Mountain2 (0,4) instead
  INFO [LandGrantActuator.agreeOnClaims]             AI: <A.L.A.N.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <A.L.A.N.> can't claim Mountain2 (7,0) and wants Mountain2 (0,4) instead
  INFO [LandGrantActuator.agreeOnClaims]             AI: <D.A.N.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <D.A.N.> can't claim Mountain2 (0,4) and wants Mountain2 (0,0) instead
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 9ms, max 9ms
  INFO [LandGrantPhase$2.finished]                   Land grant roundtrip 15ms, 5 frames (5 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 9ms, max 9ms
  INFO [LandGrantPhase.update]                       Sending claim tile 0
  INFO [LandGrantPhase.apply]                        (P12) <D.A.N.> claims tile 0 at time 24
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 9ms, max 9ms
  INFO [LandGrantPhase.apply]                        (P12) <D.A.N.> is granted tile 0: Mountain2 (0,0)
  INFO [LandGrantPhase.update]                       Sending claim tile 5
  INFO [LandGrantPhase.apply]                        (P11) <B.I.L.L.> claims tile 5 at time 22
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 9ms, max 9ms
  INFO [LandGrantPhase.apply]                        (P11) <B.I.L.L.> is granted tile 5: Mountain2 (7,0)
  INFO [LandGrantPhase.update]                       Sending claim tile 22
  INFO [LandGrantPhase.apply]                        (P13) <A.L.A.N.> claims tile 22 at time 24
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 9ms, max 9ms
  INFO [LandGrantPhase.apply]                        (P13) <A.L.A.N.> is granted tile 22: Mountain2 (0,4)
  INFO [LandGrantPhase.update]                       Sending claim tile 25
  INFO [LandGrantPhase.apply]                        (P10) HexagonalBolts claims tile 25 at time 16
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 9ms, max 9ms
  INFO [LandGrantPhase.apply]                        (P10) HexagonalBolts is granted tile 25: Crater (8,4)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [LandAuction.generateColonyAuctions]          Generated 0 colony land auctions
  INFO [AbstractLandAuctionPhase.begin]              Land Auctions Player: 0 Colony: 0
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -7299636167818096122
  INFO [Development.setPlayerOrder]                  Normal development order. 11 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  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 4 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12) <D.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 3 mountains, reward is $200
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 1 plans testing 89 tiles in 1466937ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain2 Smithore (+3) 6280 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Smithore <claim> (+1) 8373 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 Mountain2 Smithore (+3) 6280 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 Mountain2 (0,0) with Smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: CatchWampus
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: CatchWampus
  INFO [DevelopmentMessenger.showMessage]            <D.A.N.> caught the Mountain Wampus and got $200
  INFO [DevelopmentActionQueue2.update]              AI: <D.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P12) <D.A.N.> gambles $129, max: 47.5, left: 26.24, fraction 0.55242103
  INFO [DevelopmentMessenger.showMessage]            <D.A.N.> won $129 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 1064
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 554104348863891710
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventPhase.begin]                      No player event for (P10) HexagonalBolts (rank 2)
  INFO [Player.useFood]                              (P10) HexagonalBolts has 5 food and needs 4 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 9ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 9 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10) HexagonalBolts
  INFO [Wampus.<init>]                               Wumpus has 3 mountains, reward is $200
  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) HexagonalBolts develops tile Crater (8,4) with Crystite factory
  INFO [GameModel.developLand]                       (P10) HexagonalBolts develops tile Plain (5,0) with Crystite factory
  INFO [GameModel.gamble]                            (P10) HexagonalBolts gambles $122, max: 47.5, left: 8.080002, fraction 0.17010531
  INFO [DevelopmentMessenger.showMessage]            HexagonalBolts won $122 in the pub
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P10) HexagonalBolts finishes on update 1972
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 13ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 13 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 7014683218678708554
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventPhase.begin]                      No player event for (P13) <A.L.A.N.> (rank 3)
  INFO [Player.useFood]                              (P13) <A.L.A.N.> has 4 food and needs 4 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 14ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 13 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13) <A.L.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 3 mountains, reward is $200
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 1 plans testing 89 tiles in 2223395ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Mountain2 Smithore (+3) 4620 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Smithore <claim> (+1) 6253 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: <A.L.A.N.> plans to develop Mountain2 Smithore (+3) 4620 points
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.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: <A.L.A.N.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.A.N.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P13) <A.L.A.N.> develops tile Mountain2 (0,4) with Smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <A.L.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <A.L.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P13) <A.L.A.N.> gambles $193, max: 47.5, left: 28.78, fraction 0.60589474
  INFO [DevelopmentMessenger.showMessage]            <A.L.A.N.> won $193 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P13) <A.L.A.N.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P13) <A.L.A.N.> finishes on update 937
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 15ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -6483564626963718254
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventPhase.begin]                      No player event for (P11) <B.I.L.L.> (rank 4)
  INFO [Player.useFood]                              (P11) <B.I.L.L.> has 4 food and needs 4 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 16ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 15 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11) <B.I.L.L.>
  INFO [Wampus.<init>]                               Wumpus has 3 mountains, reward is $200
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 1 plans testing 89 tiles in 1492479ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain2 Smithore (+3) 4472 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Smithore <claim> (+1) 6241 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 Mountain2 Smithore (+3) 4472 points
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  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]                       (P11) <B.I.L.L.> develops tile Mountain2 (7,0) with Smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: <B.I.L.L.> Action: GoToPub
  INFO [GameModel.gamble]                            (P11) <B.I.L.L.> gambles $130, max: 47.5, left: 29.84, fraction 0.62821054
  INFO [DevelopmentMessenger.showMessage]            <B.I.L.L.> won $130 in the pub
  INFO [DevelopmentActuator.actionComplete]          AI: (P11) <B.I.L.L.> gambled and can't do more actions.
  INFO [AbstractDevelopmentPhase.updatePlayer]       My user (P11) <B.I.L.L.> finishes on update 884
  INFO [AbstractDevelopmentPhase.startOutroIfDone]   Starting outro
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_A #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 21ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 20 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: SUNSPOT_ACTIVITY
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -1112046004798866985
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 20ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 21 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -4591607421315108119
  INFO [ProductionPhase.finishPower]                 (P10) HexagonalBolts has 5 energy and needs 6 energy
  INFO [ProductionPhase.finishPower]                 (P11) <B.I.L.L.> has 5 energy and needs 4 energy
  INFO [ProductionPhase.finishPower]                 (P12) <D.A.N.> has 5 energy and needs 4 energy
  INFO [ProductionPhase.finishPower]                 (P13) <A.L.A.N.> has 5 energy and needs 4 energy
  INFO [ProductionPhase.logProduction]               (P10) HexagonalBolts expects 9 food, 6 energy, 4 smithore, 6 crystite
  INFO [ProductionPhase.logProduction]               (P11) <B.I.L.L.> expects 8 food, 6 energy, 7 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <D.A.N.> expects 2 food, 6 energy, 13 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <A.L.A.N.> expects 8 food, 6 energy, 6 smithore, 0 crystite
  INFO [ProductionPhase.apply]                       Skipping production
  INFO [ProductionPhase.logProduction]               (P10) HexagonalBolts produced 7 food, 6 energy, 5 smithore, 8 crystite
  INFO [ProductionPhase.logProduction]               (P11) <B.I.L.L.> produced 7 food, 5 energy, 8 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <D.A.N.> produced 2 food, 6 energy, 16 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <A.L.A.N.> produced 9 food, 5 energy, 5 smithore, 0 crystite
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLONY_EVENT_B #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 17ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 17ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -3561547097084199415
  INFO [Shop.calcBuySellPrice]                       Current Smithore price $136
  INFO [Shop.calcBuySellPrice]                       Smithore random variation $-4
  INFO [Shop.printPriceFactor]                       Smithore shop: 3, mules potentially available: 7, mules needed: 4
  INFO [Shop.printPriceFactor]                       Smithore is decreased to 68%
  INFO [Shop.printPriceFactor]                       Smithore buy price $88 sell price $123
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Smithore: 5 critical: 0 surplus: 5 money: $384
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Smithore: 8 critical: 0 surplus: 8 money: $1053
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Smithore: 16 critical: 0 surplus: 16 money: $2414
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Smithore: 5 critical: 0 surplus: 5 money: $1400
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_SMITHORE #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 17ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 17 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 26
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 23
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 22
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 23
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.983 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 17ms, max 17ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10) HexagonalBolts (P11) <B.I.L.L.> (P12) <D.A.N.> (P13) <A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $88
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $88
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $88
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.checkCancelTransactions] New seller enters shop line
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 28 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 28 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 28 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 28 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 24
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 27
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 24
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 23
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Smithore price set to average $88 (34 units for $2992)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 16ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 18 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 5745359349586307858
  INFO [Shop.calcBuySellPrice]                       Current Crystite price $68
  INFO [Shop.calcBuySellPrice]                       Crystite buy price $80 sell price $220
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Crystite: 8 critical: 0 surplus: 8 money: $824
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Crystite: 0 critical: 0 surplus: 0 money: $1757
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $3822
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Crystite: 0 critical: 0 surplus: 0 money: $1840
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_CRYSTITE #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 18ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 16 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 28
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 25
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 24
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 25
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.982 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 18ms, max 18ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P11) <B.I.L.L.> (P12) <D.A.N.> (P13) <A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10) HexagonalBolts 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $80
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $116
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $80
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 29
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Crystite price set to average $80 (8 units for $640)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_FOOD #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 20ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 19 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: -8419607074484068225
  INFO [Shop.calcBuySellPrice]                       Current Food price $30
  INFO [Shop.printPriceFactor]                       Food shop: 40, total: 66, needed: 16
  INFO [Shop.printPriceFactor]                       Food is decreased to 43%
  INFO [Shop.printPriceFactor]                       Food buy price $15 sell price $50
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Food: 8 critical: 4 surplus: 4 money: $1464
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Food: 7 critical: 4 surplus: 3 money: $1757
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Food: 2 critical: 4 surplus: -2 money: $3822
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Food: 9 critical: 4 surplus: 5 money: $1840
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_FOOD #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 25ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 25 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 30
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 26
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 25
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 26
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.978 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 22ms, max 22ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P12) <D.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P10) HexagonalBolts (P11) <B.I.L.L.> (P13) <A.L.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $40 ratio 0.73
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $65 ratio 1.4533333
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $35 ratio 0.59000003
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $24 ratio 0.26
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $48 ratio 0.96
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $19 ratio 0.120000005
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.checkCancelTransactions] New seller enters shop line
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $17 ratio 0.08000001
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $15 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $17 ratio 0.07000001
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 27
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <A.L.A.N.> target price $20 ratio 0.17000002
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 27
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Food price set to average $30 (11 units for $199)
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 25ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 25 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 706872631999390128
  INFO [Shop.calcBuySellPrice]                       Current Energy price $44
  INFO [Shop.printPriceFactor]                       Energy shop: 34, total: 59, needed: 22
  INFO [Shop.printPriceFactor]                       Energy is decreased to 53%
  INFO [Shop.printPriceFactor]                       Energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P10) HexagonalBolts Energy: 6 critical: 7 surplus: -1 money: $1543
  INFO [CollectionPhase.goToNextPhase]               (P11) <B.I.L.L.> Energy: 6 critical: 5 surplus: 1 money: $1802
  INFO [CollectionPhase.goToNextPhase]               (P12) <D.A.N.> Energy: 7 critical: 5 surplus: 2 money: $3758
  INFO [CollectionPhase.goToNextPhase]               (P13) <A.L.A.N.> Energy: 6 critical: 5 surplus: 1 money: $1915
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- AUCTION_ENERGY #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 30ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 31
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 28
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 26
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 28
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractAuctionPhase.timerFinished]          Starting auction in 0.97 seconds
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 30ms, max 30ms
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Auction.setBuyersAndSellers]                 Auction buyers: (P10) HexagonalBolts (P11) <B.I.L.L.> (P13) <A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: (P12) <D.A.N.> 
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $34 ratio 0.70000005
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [AbstractAuctionPhase.checkCancelTransactions] Buyer left auction line.
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [AbstractAuctionPhase.checkCancelTransactions] Buyer left auction line.
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 33ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.setAveragePrice]                        Energy price set to average $41 (3 units for $124)
  INFO [GameController.beginNextPhase]               --------- SUMMARY #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 30ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 5385068852457095086
  INFO [SummaryPhase2.begin]                         Total Memory: 66715648
  INFO [SummaryPhase2.begin]                         Free Memory: 51010872
  INFO [SummaryPhase2.begin]                         (P12) <D.A.N.> Money: $3792 Plots: 5 Assets: 841 Total: 7133
  INFO [SummaryPhase2.begin]                         (P12) <D.A.N.> Food: 4 Energy: 6 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P10) HexagonalBolts Money: $1419 Plots: 7 Assets: 1164 Total: 6083
  INFO [SummaryPhase2.begin]                         (P10) HexagonalBolts Food: 5 Energy: 9 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P13) <A.L.A.N.> Money: $1915 Plots: 5 Assets: 791 Total: 5206
  INFO [SummaryPhase2.begin]                         (P13) <A.L.A.N.> Food: 4 Energy: 6 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         (P11) <B.I.L.L.> Money: $1802 Plots: 5 Assets: 791 Total: 5093
  INFO [SummaryPhase2.begin]                         (P11) <B.I.L.L.> Food: 4 Energy: 6 Smithore: 0 Crystite: 0
  INFO [SummaryPhase2.begin]                         Shop Crystite: 0 units $80
  INFO [SummaryPhase2.begin]                         Shop Smithore: 37 units $88
  INFO [SummaryPhase2.begin]                         Shop Food: 49 units $30
  INFO [SummaryPhase2.begin]                         Shop Energy: 32 units $41
  INFO [SummaryPhase2.begin]                         Shop mules: 6 $270
  INFO [SummaryPhase2.begin]                         Colony total: 23515
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Round Results
  INFO [MasterClient.logSend]                        MasterClient: Sending Finish Round
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Chat Log
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [Shop.buildMules]                             Shop built 8 mules for 16 smithore and now has 14 mules
  INFO [Shop.buildMules]                             A mule now costs $170
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 31ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 30 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <A.L.A.N.> found 4 plans testing 1933 tiles in 32895423ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 1933 tiles in 33964764ns
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 1933 tiles in 35959561ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Smithore <claim> (+1) 5746 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 7314 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Smithore <claim> (+1) 5746 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 7314 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Smithore <claim> (+1) 5746 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 7314 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain Smithore <claim> (+1) 5746 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 7314 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants Plain (6,3)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 7871 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 9444 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 7871 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 9444 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 7871 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 9444 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy <claim> (+3) 7871 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 9444 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants Plain (3,1)
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Smithore <claim> (+1) 5771 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 7251 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Smithore <claim> (+1) 5771 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 7251 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Smithore <claim> (+1) 5771 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 7251 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <A.L.A.N.> Plan: Plain Smithore <claim> (+1) 5771 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 7251 points
  INFO [LandGrantActuator.update]                    AI: <A.L.A.N.> wants Plain (6,3)
  INFO [LandGrantActuator.agreeOnClaims]             AI: Agrees on tiles to claim.
  INFO [LandGrantActuator.agreeOnClaims]             AI: <A.L.A.N.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <A.L.A.N.> can't claim Plain (6,3) and wants Plain (1,4) instead
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 31ms, max 31ms
  INFO [LandGrantPhase$2.finished]                   Land grant roundtrip 33ms, 6 frames (5 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 31ms, max 31ms
  INFO [LandGrantPhase.update]                       Sending claim tile 8
  INFO [LandGrantPhase.apply]                        (P12) <D.A.N.> claims tile 8 at time 23
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 32ms, max 32ms
  INFO [LandGrantPhase.apply]                        (P12) <D.A.N.> is granted tile 8: Plain (3,1)
  INFO [LandGrantPhase.update]                       Sending claim tile 18
  INFO [LandGrantPhase.apply]                        (P11) <B.I.L.L.> claims tile 18 at time 24
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 32ms, max 32ms
  INFO [LandGrantPhase.apply]                        (P11) <B.I.L.L.> is granted tile 18: Plain (6,3)
  INFO [LandGrantPhase.update]                       Sending claim tile 20
  INFO [LandGrantPhase.apply]                        (P13) <A.L.A.N.> claims tile 20 at time 23
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 32ms, max 32ms
  INFO [LandGrantPhase.apply]                        (P13) <A.L.A.N.> is granted tile 20: Plain (1,4)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 32ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 32 ms
  INFO [LandAuction.generateColonyAuctions]          Generated 1 colony land auctions
  INFO [AbstractLandAuctionPhase.begin]              Land Auctions Player: 0 Colony: 1
  INFO [LandAuction.beginAuction]                    Land auction price: $476
  INFO [AbstractLandAuctionPhase.begin]              Starting land auction
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $804
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $440
  INFO [LandAuctionActuator.beginLandAuction]        AI: <A.L.A.N.> targets price $252
  INFO [NetworkTimer.start]                          Started "countdown" at 5.0 (owner 10)
  INFO [Player.increaseAuctionTeleportCount]         (P10) HexagonalBolts auction teleport count: 32
  INFO [Player.increaseAuctionTeleportCount]         (P11) <B.I.L.L.> auction teleport count: 29
  INFO [Player.increaseAuctionTeleportCount]         (P12) <D.A.N.> auction teleport count: 27
  INFO [Player.increaseAuctionTeleportCount]         (P13) <A.L.A.N.> auction teleport count: 29
  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 [Auction.setBuyersAndSellers]                 Auction buyers: (P10) HexagonalBolts (P11) <B.I.L.L.> (P12) <D.A.N.> (P13) <A.L.A.N.> 
  INFO [Auction.setBuyersAndSellers]                 Auction sellers: 
  INFO [NetworkTimer.start]                          Started "phase timer" at 8.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $752 (changed $-52)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $716 (changed $-36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $672 (changed $-44)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $644 (changed $-28)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $696 (changed $52)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $760 (changed $64)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $724 (changed $-36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $716 (changed $-8)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $704 (changed $-12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $724 (changed $20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $664 (changed $-60)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $640 (changed $-24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $668 (changed $28)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $648 (changed $-20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $700 (changed $52)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $760 (changed $60)
  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: BuyLand (10) 16 bytes
  INFO [AbstractLandAuctionPhase.apply]              (P11) <B.I.L.L.> bought the plot for $696
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 28ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 28 ms
  INFO [GameController.checkClientSynchronization]   Sending synchonization checksum: 1891416057406793955
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  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 4 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 28ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 28 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12) <D.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 3 mountains, reward is $200
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 1 plans testing 73 tiles in 1357534ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain Energy (+3) 7871 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain Energy <claim> (+3) 9444 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) 7871 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 [MetaFrame$5.actionPerformed]                 User chooses to disconnect
  INFO [MuleGameContainer.release]                   Closing game.
  INFO [GameController.close]                        -------- CLOSING GAME CONTROLLER ----------------------------------
  INFO [MasterClient.logSend]                        MasterClient: Sending User Left ID 10730 user number 10
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Chat Log
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
