  INFO [Properties.<init>]                           VERSION: 1.0.0
  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 XP 5.1 x86
  INFO [Properties.<init>]                           Java Library Path: lib
  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 [MasterClient.logSend]                        MasterClient: Sending Login
  INFO [MasterClient$1.run]                          MasterClient: Address http://www.planetmule.com/server
  INFO [MasterClient.apply]                          MasterClient: Session ID: e4f2b2d119eba5fc50a83be59163736a
  INFO [Login$MasterListener$1.run]                  Logged in as "pliuo" (id 4043)
  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]                    OriginalDisplayMode: 1280 x 800 x 32 @60Hz
  INFO [MuleGameContainer.create]                    Adapter: nv4_disp
  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
  INFO [MetaFrame$MasterListener$1.run]              Entered master server game lobby
  INFO [MuleGameContainer.init]                      *** Starting Game ***
  INFO [MuleGameContainer.init]                      Display Created=true, Active=true, Visible=true
  INFO [MuleGameContainer.init]                      Making display the current GL context
  INFO [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: localhost/127.0.0.1:6260 UDP: localhost/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 db8e2475-c552-443a-b8ba-c2b4ec325926
  INFO [Client.finishConnect]                        Client finishes connection
  INFO [ServerMessageHandler.processJoin]            Server: Client 127.0.0.1 joins and gets spectator number 10
  INFO [Client.receiveTCPMessages]                   Client: ServerInfo (0) 35 bytes
  INFO [Client.receiveTCPMessages]                   Client: Join (10) 37 bytes
  INFO [UserController.apply]                        My client id is db8e2475-c552-443a-b8ba-c2b4ec325926
  INFO [Player.<init>]                               Creating (P0) spectator
  INFO [GameModel.setMyUser]                         (S10) pliuo is my user
  INFO [GameModel.setLocalUser]                      (S10) pliuo is a local Spectator
  INFO [GameModel.setServerUser]                     (S10) pliuo is hosting the server
  INFO [GameController.beginNextPhase]               (S10) pliuo 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 [MasterClient.logSend]                        MasterClient: Sending New Game
  INFO [GameLobbyPhase.getMasterUserInfo]            Fetching user info for (S10) pliuo (ID: 4043)
  INFO [Client.initUDP]                              Sending UDP initialization message id db8e2475-c552-443a-b8ba-c2b4ec325926
  INFO [Server.initUdp]                              Server: Client 127.0.0.1 <S10> requests UDP address /127.0.0.1:6261
  INFO [GameLobbyPhase$MasterListener.apply]         Master server gives game ID 10219
  INFO [MasterClient.logSend]                        MasterClient: Sending User Join
  INFO [GameLobbyPhase$MasterListener.apply]         Got user info for (S10) pliuo (ID 4043)
  INFO [GameLobbyPhase$MasterListener.apply]         No statistics available for (S10) pliuo (ID 4043)
  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) pliuo joins the players
  INFO [Player.<init>]                               Creating (P10) pliuo
  INFO [GameModel.setServerUser]                     (P10) pliuo is hosting the server
  INFO [GameModel.setMyUser]                         (P10) pliuo is my user
  INFO [GameModel.setLocalUser]                      (P10) pliuo is a local Player
  INFO [ServerMessageHandler.processAIJoin]          Server: AI gets user number 11
  INFO [Client.receiveTCPMessages]                   Client: AIJoin (11) 12 bytes
  INFO [Player.<init>]                               Creating (P11) <D.A.N.>
  INFO [UserController.setAiPlayer]                  (P11) <D.A.N.> is an AI player
  INFO [GameModel.setLocalUser]                      (P11) <D.A.N.> is a local Player
  INFO [MasterClient.logSend]                        MasterClient: Sending User Join
  INFO [Client.receiveTCPMessages]                   Client: Continue (11) 0 bytes
  INFO [ServerMessageHandler.processAIJoin]          Server: AI gets user number 12
  INFO [Client.receiveTCPMessages]                   Client: AIJoin (12) 14 bytes
  INFO [Player.<init>]                               Creating (P12) <B.I.L.L.>
  INFO [UserController.setAiPlayer]                  (P12) <B.I.L.L.> is an AI player
  INFO [GameModel.setLocalUser]                      (P12) <B.I.L.L.> is a local Player
  INFO [MasterClient.logSend]                        MasterClient: Sending User Join
  INFO [Client.receiveTCPMessages]                   Client: Continue (12) 0 bytes
  INFO [ServerMessageHandler.processAIJoin]          Server: AI gets user number 13
  INFO [Client.receiveTCPMessages]                   Client: AIJoin (13) 12 bytes
  INFO [Player.<init>]                               Creating (P13) <J.I.M.>
  INFO [UserController.setAiPlayer]                  (P13) <J.I.M.> is an AI player
  INFO [GameModel.setLocalUser]                      (P13) <J.I.M.> is a local Player
  INFO [MasterClient.logSend]                        MasterClient: Sending User Join
  INFO [Client.receiveTCPMessages]                   Client: Continue (13) 0 bytes
  INFO [ServerMessageHandler.processAIJoin]          Server: AI Join failed. No more players allowed.
  INFO [ServerMessageHandler.processAIJoin]          Server: AI Join failed. No more players allowed.
  INFO [ServerMessageHandler.processAIJoin]          Server: AI Join failed. No more players allowed.
  INFO [ServerMessageHandler.processAIJoin]          Server: AI Join failed. No more players allowed.
  INFO [ServerMessageHandler.processAIJoin]          Server: AI Join failed. No more players allowed.
  INFO [ServerMessageHandler.processAIJoin]          Server: AI Join failed. No more players allowed.
  INFO [ServerMessageHandler.processAIJoin]          Server: AI Join failed. No more players allowed.
  INFO [ServerMessageHandler.processAIJoin]          Server: AI Join failed. No more players allowed.
  INFO [ServerMessageHandler.processAIJoin]          Server: AI Join failed. No more players allowed.
  INFO [ServerMessageHandler.processAIJoin]          Server: AI Join failed. No more players allowed.
  INFO [Client.receiveTCPMessages]                   Client: Continue (10) 0 bytes
  INFO [GameLobbyPhase.apply]                        Starting game - All users want to continue
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [SummaryPhase2.begin]                         (P10) pliuo Money: $1000 Plots: 0 Assets: 220 Total: 1220
  INFO [SummaryPhase2.begin]                         (P11) <D.A.N.> Money: $1000 Plots: 0 Assets: 220 Total: 1220
  INFO [SummaryPhase2.begin]                         (P12) <B.I.L.L.> Money: $1000 Plots: 0 Assets: 220 Total: 1220
  INFO [SummaryPhase2.begin]                         (P13) <J.I.M.> Money: $1000 Plots: 0 Assets: 220 Total: 1220
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [PlanManager.startPlanSearch]                 AI: <D.A.N.> prefers energy
  INFO [PlanManager.startPlanSearch]                 AI: <B.I.L.L.> prefers energy
  INFO [PlanManager.startPlanSearch]                 AI: <J.I.M.> prefers smithore
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 9504 tiles in 357078343ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Desert energy <claim> (+4) 1485 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain energy <claim> (+3) 1995 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Desert energy <claim> (+4) 1485 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain energy <claim> (+3) 1995 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Desert energy <claim> (+4) 1485 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain energy <claim> (+3) 1995 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain energy <claim> (+3) 1460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Desert energy <claim> (+4) 1970 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants Desert (5,0)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 4 plans testing 9504 tiles in 402846172ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 smithore <claim> (+4) 1535 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 2135 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 smithore <claim> (+4) 1535 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2085 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 smithore <claim> (+4) 1535 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2085 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 smithore <claim> (+4) 1535 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2085 points
  INFO [LandGrantActuator.update]                    AI: <J.I.M.> wants Mountain3 (6,2)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 9504 tiles in 452631829ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Desert energy <claim> (+4) 1485 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain energy <claim> (+3) 1995 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Desert energy <claim> (+4) 1485 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain energy <claim> (+3) 1995 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Desert energy <claim> (+4) 1485 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain energy <claim> (+3) 1995 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain energy <claim> (+3) 1460 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Desert energy <claim> (+4) 1970 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants Desert (5,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 Desert (5,0) and wants Desert (3,0) instead
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase$2.finished]                   Land grant roundtrip 0ms, 5 frames (5 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.update]                       Sending claim tile 3
  INFO [LandGrantPhase.apply]                        (P11) <D.A.N.> claims tile 3 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P11) <D.A.N.> is granted tile 3: Desert (3,0)
  INFO [LandGrantPhase.update]                       Sending claim tile 5
  INFO [LandGrantPhase.apply]                        (P12) <B.I.L.L.> claims tile 5 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P12) <B.I.L.L.> is granted tile 5: Desert (5,0)
  INFO [LandGrantPhase.update]                       Sending claim tile 23
  INFO [LandGrantPhase.apply]                        (P13) <J.I.M.> claims tile 23 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P13) <J.I.M.> is granted tile 23: Mountain3 (6,2)
  INFO [LandGrantPhase.update]                       Sending claim tile 26
  INFO [LandGrantPhase.apply]                        (P10) pliuo claims tile 26 at time 8
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P10) pliuo is granted tile 26: Plain (0,3)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [LandAuction.generateColonyAuctions]          Colony land auctions: 0
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  INFO [PlayerEventPhase.begin]                      Event player: (P10) pliuo (rank 1)
  INFO [PlayerEventPhase.begin]                      Event: FlyingCatBugs
  INFO [PlayerEventPhase$1.finished]                 Event Message: Flying Cat-Bugs ate the roof off your house. Repairs cost $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) pliuo has 4 food and needs 3 food
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10) pliuo
  INFO [Wampus.<init>]                               Wumpus has 18 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 [AbstractDevelopmentPhase.timerFinished]      (P10) pliuo start frame: 5854
  INFO [NetworkTimer.start]                          Started "player 10 timer" at 47.5 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [GameModel.developLand]                       (P10) pliuo develops tile Plain (0,3) with food factory
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 2 "player 10 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 [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventPhase.begin]                      No player event for (P11) <D.A.N.> (rank 2)
  INFO [Player.useFood]                              (P11) <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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11) <D.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 18 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 201 tiles in 8898058ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Desert energy (+4) 1485 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2020 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: (P11) <D.A.N.> plans to develop Desert energy (+4) 1485 points
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <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 [AbstractDevelopmentPhase.timerFinished]      (P11) <D.A.N.> start frame: 8985
  INFO [NetworkTimer.start]                          Started "player 11 timer" at 47.5 (owner 11)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (11) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: GoToOutfitStore
  INFO [MuleGameContainer.setFullscreen]             Setting fullscreen mode
  INFO [MuleGameContainer.setFullscreen]             Fullscreen mode: 1280 x 800 x 32 @60Hz Scale: 1 Height-Diff: 160 Aspect-Diff: 0.26666665 Snap Scale: true
  INFO [MuleGameContainer.setFullscreen]             Viewport: x=320 y=160 width=640 height=480
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P11) <D.A.N.> develops tile Desert (3,0) with energy factory
  INFO [DevelopmentActuator.enqueueAssay]            AI: (P11) <D.A.N.> want's to assay Plain (8,2) with 35.73973 time left.
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToAssayStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: Assay
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: GoToAssayStore
  INFO [GameModel.buyAssay]                          (P11) <D.A.N.> bought an assay bot for $50
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: Assay
  INFO [AbstractDevelopmentPhase.assay]              (P11) <D.A.N.> assayed and found 0 crystite
  INFO [DevelopmentActuator.enqueueAssay]            AI: (P11) <D.A.N.> want's to assay Plain (3,4) with 25.21949 time left.
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToAssayStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: Assay
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: GoToAssayStore
  INFO [GameModel.buyAssay]                          (P11) <D.A.N.> bought an assay bot for $50
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: Assay
  INFO [AbstractDevelopmentPhase.assay]              (P11) <D.A.N.> assayed and found 1 crystite
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P11) <D.A.N.> gambles $60, max: 47.5, left: 11.280003, fraction 0.23747374
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (11) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 3 "player 11 timer" at 0.0 (owner 11)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventPhase.begin]                      Event player: (P12) <B.I.L.L.> (rank 3)
  INFO [PlayerEventPhase.begin]                      Event: MuseumBoughtComputer
  INFO [PlayerEventPhase$1.finished]                 Event Message: The museum bought your antique personal computer for $200
  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]                              (P12) <B.I.L.L.> has 4 food and needs 3 food
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12) <B.I.L.L.>
  INFO [Wampus.<init>]                               Wumpus has 18 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 201 tiles in 16349006ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Desert energy (+4) 1685 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2220 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: (P12) <B.I.L.L.> plans to develop Desert energy (+4) 1685 points
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <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 [AbstractDevelopmentPhase.timerFinished]      (P12) <B.I.L.L.> start frame: 11869
  INFO [NetworkTimer.start]                          Started "player 12 timer" at 47.5 (owner 12)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (12) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P12) <B.I.L.L.> develops tile Desert (5,0) with energy factory
  INFO [DevelopmentActuator.enqueueAssay]            AI: (P12) <B.I.L.L.> want's to assay Mountain2 (6,1) with 36.919758 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: (P12) <B.I.L.L.> Action: GoToAssayStore
  INFO [GameModel.buyAssay]                          (P12) <B.I.L.L.> bought an assay bot for $50
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: Assay
  INFO [AbstractDevelopmentPhase.assay]              (P12) <B.I.L.L.> assayed and found 0 crystite
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToPub
  INFO [GameModel.gamble]                            (P12) <B.I.L.L.> gambles $127, max: 47.5, left: 23.74, fraction 0.49978948
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (12) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 4 "player 12 timer" at 0.0 (owner 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 [GameController.beginNextPhase]               --------- PLAYER_EVENT #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventPhase.begin]                      No player event for (P13) <J.I.M.> (rank 4)
  INFO [Player.useFood]                              (P13) <J.I.M.> 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13) <J.I.M.>
  INFO [Wampus.<init>]                               Wumpus has 18 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 1 plans testing 201 tiles in 10441271ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 smithore (+4) 1535 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 2135 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: (P13) <J.I.M.> plans to develop Mountain3 smithore (+4) 1535 points
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: GoToMuleStore
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractDevelopmentPhase.timerFinished]      (P13) <J.I.M.> start frame: 13730
  INFO [NetworkTimer.start]                          Started "player 13 timer" at 47.5 (owner 13)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (13) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P13) <J.I.M.> develops tile Mountain3 (6,2) with smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: GoToPub
  INFO [GameModel.gamble]                            (P13) <J.I.M.> gambles $75, max: 47.5, left: 32.64, fraction 0.68715787
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (13) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 5 "player 13 timer" at 0.0 (owner 13)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: ACID_RAIN_STORM
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ProductionPhase.update]                      (P10) pliuo has 4 energy and needs 1 energy
  INFO [ProductionPhase.update]                      (P11) <D.A.N.> has 4 energy and needs 0 energy
  INFO [ProductionPhase.update]                      (P12) <B.I.L.L.> has 4 energy and needs 0 energy
  INFO [ProductionPhase.update]                      (P13) <J.I.M.> has 4 energy and needs 1 energy
  INFO [ProductionPhase.logProduction]               (P10) pliuo expects 3 food, 0 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <D.A.N.> expects 0 food, 2 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <B.I.L.L.> expects 0 food, 2 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <J.I.M.> expects 0 food, 0 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10) pliuo produced 2 food, 0 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <D.A.N.> produced 0 food, 1 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <B.I.L.L.> produced 0 food, 2 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <J.I.M.> produced 0 food, 0 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 #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  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 $50 sell price $85
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo smithore: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> smithore: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> smithore: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> smithore: 3 critical: 0 surplus: 3
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 1
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 1
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 1
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 1
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [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]         (P13) <J.I.M.> auction teleport count: 2
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (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]               --------- COLLECTION_CRYSTITE #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.calcBuySellPrice]                       crystite buy price $132 sell price $272
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> crystite: 0 critical: 0 surplus: 0
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 2
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 2
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 2
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 3
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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]               --------- COLLECTION_FOOD #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Food shop: 8, total: 14, needed: 12
  INFO [Shop.printPriceFactor]                       food is decreased to 89%
  INFO [Shop.printPriceFactor]                       food buy price $15 sell price $50
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo food: 3 critical: 3 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> food: 1 critical: 3 surplus: -2
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> food: 1 critical: 3 surplus: -2
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> food: 1 critical: 3 surplus: -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]               --------- AUCTION_FOOD #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 3
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 3
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 3
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 4
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $127 ratio 3.2
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $127 ratio 3.2
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $127 ratio 3.2
  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: <D.A.N.> target price $115 ratio 2.8666666
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $115 ratio 2.8666666
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $109 ratio 2.7
  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 $103 ratio 2.5333333
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $103 ratio 2.5333333
  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 $92 ratio 2.2
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $86 ratio 2.0333333
  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 $80 ratio 1.8666667
  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 $62 ratio 1.3666667
  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: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Energy shop: 8, total: 21, needed: 6
  INFO [Shop.printPriceFactor]                       energy is decreased to 46%
  INFO [Shop.printPriceFactor]                       energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo energy: 2 critical: 2 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> energy: 4 critical: 1 surplus: 3
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> energy: 5 critical: 1 surplus: 4
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> energy: 2 critical: 2 surplus: 0
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 4
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 4
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 4
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 5
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $25 ratio 0.43333334
  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 $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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 $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.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: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- SUMMARY #1 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [SummaryPhase2.begin]                         (P12) <B.I.L.L.> Money: $1073 Plots: 1 Assets: 225 Total: 1798
  INFO [SummaryPhase2.begin]                         (P13) <J.I.M.> Money: $924 Plots: 1 Assets: 275 Total: 1699
  INFO [SummaryPhase2.begin]                         (P10) pliuo Money: $875 Plots: 1 Assets: 200 Total: 1575
  INFO [SummaryPhase2.begin]                         (P11) <D.A.N.> Money: $730 Plots: 1 Assets: 225 Total: 1455
  INFO [SummaryPhase2.begin]                         Colony total: 6527
  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 1 mules for 2 smithore and now has 11 mules
  INFO [Shop.buildMules]                             A mule now costs $100
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 11 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 7957 tiles in 319104244ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain3 smithore <claim> (+4) 2218 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2928 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain3 smithore <claim> (+4) 2218 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2928 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain3 smithore <claim> (+4) 2218 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2928 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River food <claim> (+4) 2198 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2888 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants Mountain3 (2,1)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 7957 tiles in 357392908ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 smithore <claim> (+4) 1875 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2585 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 smithore <claim> (+4) 1875 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2585 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 smithore <claim> (+4) 1875 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2585 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: River food <claim> (+4) 1855 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2545 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants Mountain3 (2,1)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 4 plans testing 7957 tiles in 402385219ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain2 smithore <claim> (+3) 2089 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2949 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 smithore <claim> (+4) 2039 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 2899 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 smithore <claim> (+4) 2039 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 2899 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 smithore <claim> (+4) 2039 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 2899 points
  INFO [LandGrantActuator.update]                    AI: <J.I.M.> wants Mountain2 (6,1)
  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 Mountain3 (2,1) and wants Mountain3 (2,4) instead
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase$2.finished]                   Land grant roundtrip 0ms, 5 frames (5 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.update]                       Sending claim tile 9
  INFO [LandGrantPhase.apply]                        (P11) <D.A.N.> claims tile 9 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P11) <D.A.N.> is granted tile 9: Mountain3 (2,1)
  INFO [LandGrantPhase.update]                       Sending claim tile 13
  INFO [LandGrantPhase.apply]                        (P13) <J.I.M.> claims tile 13 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P13) <J.I.M.> is granted tile 13: Mountain2 (6,1)
  INFO [LandGrantPhase.update]                       Sending claim tile 23
  INFO [LandGrantPhase.apply]                        (P10) pliuo claims tile 23 at time 8
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P10) pliuo is granted tile 23: Desert (1,3)
  INFO [LandGrantPhase.update]                       Sending claim tile 33
  INFO [LandGrantPhase.apply]                        (P12) <B.I.L.L.> claims tile 33 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P12) <B.I.L.L.> is granted tile 33: Mountain3 (2,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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [LandAuction.generateColonyAuctions]          Colony land auctions: 2
  INFO [LandAuction.beginAuction]                    Land auction price: $160
  INFO [AbstractLandAuctionPhase.begin]              Starting land auction
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $232
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $616
  INFO [LandAuctionActuator.beginLandAuction]        AI: <J.I.M.> targets price $472
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 5
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 5
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 5
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> 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 [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 $280 (changed $56)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $428 (changed $-44)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $244 (changed $-36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $616 (changed $0)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $440 (changed $12)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $600 (changed $-16)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $608 (changed $8)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $620 (changed $48)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $416 (changed $-24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $412 (changed $-4)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $596 (changed $-24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $408 (changed $-4)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $620 (changed $28)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $428 (changed $20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <B.I.L.L.> new price $620 (changed $4)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $368 (changed $-60)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $368 (changed $0)
  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]              (P12) <B.I.L.L.> bought a land for $444
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [AbstractLandAuctionPhase.begin]              Player land auctions: 0
  INFO [LandAuction.beginAuction]                    Land auction price: $384
  INFO [AbstractLandAuctionPhase.begin]              Starting land auction
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $280
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $28
  INFO [LandAuctionActuator.beginLandAuction]        AI: <J.I.M.> targets price $472
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 6
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 6
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 6
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 7
  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 [NetworkTimer.start]                          Started "phase timer" at 8.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $444 (changed $-28)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $396 (changed $-48)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $380 (changed $-16)
  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) pliuo bought a land for $384
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  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) <B.I.L.L.> (rank 1)
  INFO [Player.useFood]                              (P12) <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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12) <B.I.L.L.>
  INFO [Wampus.<init>]                               Wumpus has 10 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 1 plans testing 818 tiles in 47581745ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain3 smithore (+4) River food (+4) 2339 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 3204 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: PlaceMule
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: (P12) <B.I.L.L.> plans to develop Mountain3 smithore (+4) River food (+4) 2339 points
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <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 [AbstractDevelopmentPhase.timerFinished]      (P12) <B.I.L.L.> start frame: 33619
  INFO [NetworkTimer.start]                          Started "player 12 timer" at 47.5 (owner 12)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (12) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P12) <B.I.L.L.> develops tile Mountain3 (2,4) with smithore factory
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToMuleStore
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P12) <B.I.L.L.> develops tile River (4,3) with food factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToPub
  INFO [GameModel.gamble]                            (P12) <B.I.L.L.> gambles $87, max: 47.5, left: 22.42, fraction 0.472
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (12) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 4 "player 12 timer" at 0.0 (owner 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 [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventPhase.begin]                      No player event for (P13) <J.I.M.> (rank 2)
  INFO [Player.useFood]                              (P13) <J.I.M.> has 3 food and needs 3 food
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13) <J.I.M.>
  INFO [Wampus.<init>]                               Wumpus has 10 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 1 plans testing 205 tiles in 8931302ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain2 smithore (+3) 2089 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2949 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: (P13) <J.I.M.> plans to develop Mountain2 smithore (+3) 2089 points
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: GoToMuleStore
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractDevelopmentPhase.timerFinished]      (P13) <J.I.M.> start frame: 35546
  INFO [NetworkTimer.start]                          Started "player 13 timer" at 47.5 (owner 13)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (13) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P13) <J.I.M.> develops tile Mountain2 (6,1) with smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: GoToPub
  INFO [GameModel.gamble]                            (P13) <J.I.M.> gambles $119, max: 47.5, left: 32.12, fraction 0.6762105
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (13) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 5 "player 13 timer" at 0.0 (owner 13)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventPhase.begin]                      No player event for (P10) pliuo (rank 3)
  INFO [Player.useFood]                              (P10) pliuo 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10) pliuo
  INFO [Wampus.<init>]                               Wumpus has 10 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 [AbstractDevelopmentPhase.timerFinished]      (P10) pliuo start frame: 36988
  INFO [NetworkTimer.start]                          Started "player 10 timer" at 47.5 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [GameModel.developLand]                       (P10) pliuo develops tile Desert (1,3) with energy factory
  INFO [GameModel.gamble]                            (P10) pliuo gambles $67, max: 47.5, left: 5.260002, fraction 0.110736884
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 2 "player 10 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 [GameController.beginNextPhase]               --------- PLAYER_EVENT #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventPhase.begin]                      No player event for (P11) <D.A.N.> (rank 4)
  INFO [Player.useFood]                              (P11) <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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11) <D.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 10 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 171 tiles in 7215163ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 smithore (+4) 1875 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 2585 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: (P11) <D.A.N.> plans to develop Mountain3 smithore (+4) 1875 points
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <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 [AbstractDevelopmentPhase.timerFinished]      (P11) <D.A.N.> start frame: 39773
  INFO [NetworkTimer.start]                          Started "player 11 timer" at 47.5 (owner 11)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (11) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P11) <D.A.N.> develops tile Mountain3 (2,1) with smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P11) <D.A.N.> gambles $178, max: 47.5, left: 32.46, fraction 0.6833684
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (11) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 3 "player 11 timer" at 0.0 (owner 11)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ProductionPhase.update]                      (P10) pliuo has 2 energy and needs 1 energy
  INFO [ProductionPhase.update]                      (P11) <D.A.N.> has 2 energy and needs 1 energy
  INFO [ProductionPhase.update]                      (P12) <B.I.L.L.> has 2 energy and needs 2 energy
  INFO [ProductionPhase.update]                      (P13) <J.I.M.> has 3 energy and needs 2 energy
  INFO [ProductionPhase.logProduction]               (P10) pliuo expects 2 food, 4 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <D.A.N.> expects 0 food, 4 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <B.I.L.L.> expects 4 food, 4 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <J.I.M.> expects 0 food, 0 energy, 9 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10) pliuo produced 5 food, 4 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <D.A.N.> produced 0 food, 5 energy, 3 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <B.I.L.L.> produced 4 food, 3 energy, 4 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <J.I.M.> produced 0 food, 0 energy, 9 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: PIRATE_SHIP
  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]               --------- COLLECTION_SMITHORE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Smithore shop: 0, mules potentially available: 6, mules needed: 5
  INFO [Shop.printPriceFactor]                       smithore is decreased to 88%
  INFO [Shop.printPriceFactor]                       smithore buy price $50 sell price $85
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo smithore: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> smithore: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> smithore: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> smithore: 0 critical: 0 surplus: 0
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 7
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 7
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 7
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 8
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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]               --------- COLLECTION_CRYSTITE #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.calcBuySellPrice]                       crystite buy price $88 sell price $228
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> crystite: 0 critical: 0 surplus: 0
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 8
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 8
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 8
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 9
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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]               --------- COLLECTION_FOOD #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Food shop: 2, total: 11, needed: 12
  INFO [Shop.printPriceFactor]                       food is increased to 107%
  INFO [Shop.printPriceFactor]                       food buy price $15 sell price $50
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo food: 5 critical: 3 surplus: 2
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> food: 0 critical: 3 surplus: -3
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> food: 4 critical: 3 surplus: 1
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> food: 0 critical: 3 surplus: -3
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 9
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 9
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 9
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 10
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $118 ratio 2.9666667
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $50 ratio 1.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $118 ratio 2.9666667
  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 $106 ratio 2.6
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $50 ratio 1.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $111 ratio 2.7666667
  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 $93 ratio 2.2333333
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $50 ratio 1.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $104 ratio 2.5666666
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 10
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $97 ratio 2.3666666
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 10
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $80 ratio 1.8666667
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $62 ratio 1.3666667
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Energy shop: 12, total: 27, needed: 10
  INFO [Shop.printPriceFactor]                       energy is decreased to 53%
  INFO [Shop.printPriceFactor]                       energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo energy: 5 critical: 2 surplus: 3
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> energy: 6 critical: 2 surplus: 4
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> energy: 3 critical: 3 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> energy: 1 critical: 3 surplus: -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]               --------- AUCTION_ENERGY #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 11
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 10
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 11
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 11
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $48 ratio 1.1
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $60 ratio 1.4333333
  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 $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $41 ratio 0.9
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $47 ratio 1.0666667
  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 $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $34 ratio 0.7
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $34 ratio 0.7
  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 $39 ratio 0.8333333
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- SUMMARY #2 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [SummaryPhase2.begin]                         (P10) pliuo Money: $692 Plots: 3 Assets: 309 Total: 2501
  INFO [SummaryPhase2.begin]                         (P12) <B.I.L.L.> Money: $437 Plots: 3 Assets: 451 Total: 2388
  INFO [SummaryPhase2.begin]                         (P13) <J.I.M.> Money: $625 Plots: 2 Assets: 416 Total: 2041
  INFO [SummaryPhase2.begin]                         (P11) <D.A.N.> Money: $671 Plots: 2 Assets: 366 Total: 2037
  INFO [SummaryPhase2.begin]                         Colony total: 8967
  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 6 mules
  INFO [Shop.buildMules]                             A mule now costs $90
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.setPlayerOrder]                  Reverse development order. 6 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 5710 tiles in 272577381ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain3 smithore <claim> (+4) 2979 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain1 smithore <claim> (+2) 4039 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain1 smithore <claim> (+2) 2979 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 4039 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain2 smithore <claim> (+3) 2929 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 3939 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain2 smithore <claim> (+3) 2929 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 3939 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants Mountain3 (1,0)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 5710 tiles in 285221167ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 smithore <claim> (+4) 2561 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain smithore <claim> (+1) 3511 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain smithore <claim> (+1) 2511 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 3461 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain smithore <claim> (+1) 2511 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 3461 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain2 smithore <claim> (+3) 2511 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 3411 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants Mountain3 (1,0)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 4 plans testing 5710 tiles in 315245373ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain3 smithore <claim> (+4) 2670 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain energy <claim> (+3) 3625 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain2 smithore <claim> (+3) 2620 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 3550 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain2 smithore <claim> (+3) 2620 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 3550 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain1 smithore <claim> (+2) 2570 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain3 smithore <claim> (+4) 3450 points
  INFO [LandGrantActuator.update]                    AI: <J.I.M.> wants Mountain3 (1,0)
  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 Mountain3 (1,0) and wants Mountain1 (2,3) instead
  INFO [LandGrantActuator.agreeOnClaims]             AI: <J.I.M.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <J.I.M.> can't claim Mountain3 (1,0) and wants Mountain2 (8,1) instead
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase$2.finished]                   Land grant roundtrip 0ms, 5 frames (5 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.update]                       Sending claim tile 0
  INFO [LandGrantPhase.apply]                        (P11) <D.A.N.> claims tile 0 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P11) <D.A.N.> is granted tile 0: Mountain3 (1,0)
  INFO [LandGrantPhase.update]                       Sending claim tile 6
  INFO [LandGrantPhase.apply]                        (P10) pliuo claims tile 6 at time 14
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P10) pliuo is granted tile 6: Mountain1 (0,1)
  INFO [LandGrantPhase.update]                       Sending claim tile 12
  INFO [LandGrantPhase.apply]                        (P13) <J.I.M.> claims tile 12 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P13) <J.I.M.> is granted tile 12: Mountain2 (8,1)
  INFO [LandGrantPhase.update]                       Sending claim tile 20
  INFO [LandGrantPhase.apply]                        (P12) <B.I.L.L.> claims tile 20 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P12) <B.I.L.L.> is granted tile 20: Mountain1 (2,3)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- LAND_AUCTION #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [LandAuction.generateColonyAuctions]          Colony land auctions: 1
  INFO [LandAuction.beginAuction]                    Land auction price: $352
  INFO [AbstractLandAuctionPhase.begin]              Starting land auction
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $248
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $16
  INFO [LandAuctionActuator.beginLandAuction]        AI: <J.I.M.> targets price $204
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 12
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 11
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 12
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 12
  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 [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 [AbstractLandAuctionPhase.apply]              (P10) pliuo bought a land for $352
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.setPlayerOrder]                  Reverse development order. 6 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventPhase.begin]                      No player event for (P11) <D.A.N.> (rank 4)
  INFO [Player.useFood]                              (P11) <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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11) <D.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 3 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 1 plans testing 146 tiles in 8228141ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain3 smithore (+4) 2561 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain smithore <claim> (+1) 3511 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: (P11) <D.A.N.> plans to develop Mountain3 smithore (+4) 2561 points
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <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 [AbstractDevelopmentPhase.timerFinished]      (P11) <D.A.N.> start frame: 54185
  INFO [NetworkTimer.start]                          Started "player 11 timer" at 47.5 (owner 11)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (11) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P11) <D.A.N.> develops tile Mountain3 (1,0) with smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: GoToPub
  INFO [GameModel.gamble]                            (P11) <D.A.N.> gambles $95, max: 47.5, left: 29.9, fraction 0.6294737
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (11) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 3 "player 11 timer" at 0.0 (owner 11)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventPhase.begin]                      Event player: (P13) <J.I.M.> (rank 3)
  INFO [PlayerEventPhase.begin]                      Event: MuseumBoughtComputer
  INFO [PlayerEventPhase$1.finished]                 Event Message: The museum bought your antique personal computer for $200
  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]                              (P13) <J.I.M.> has 3 food and needs 3 food
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13) <J.I.M.>
  INFO [Wampus.<init>]                               Wumpus has 3 mountains, reward is $100
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 1 plans testing 146 tiles in 6947252ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain2 smithore (+3) 2820 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain energy <claim> (+3) 3725 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: (P13) <J.I.M.> plans to develop Mountain2 smithore (+3) 2820 points
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: GoToMuleStore
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractDevelopmentPhase.timerFinished]      (P13) <J.I.M.> start frame: 56138
  INFO [NetworkTimer.start]                          Started "player 13 timer" at 47.5 (owner 13)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (13) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P13) <J.I.M.> develops tile Mountain2 (8,1) with smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: CatchWampus
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: CatchWampus
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: GoToPub
  INFO [GameModel.gamble]                            (P13) <J.I.M.> gambles $126, max: 47.5, left: 27.16, fraction 0.57178944
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (13) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 5 "player 13 timer" at 0.0 (owner 13)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventPhase.begin]                      Event player: (P12) <B.I.L.L.> (rank 2)
  INFO [PlayerEventPhase.begin]                      Event: FlyingCatBugs
  INFO [PlayerEventPhase$1.finished]                 Event Message: Flying Cat-Bugs ate the roof off your house. Repairs cost $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]                              (P12) <B.I.L.L.> has 3 food and needs 3 food
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12) <B.I.L.L.>
  INFO [Wampus.<init>]                               Wumpus has 3 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 146 tiles in 6428471ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain1 smithore (+2) 2879 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 3889 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: (P12) <B.I.L.L.> plans to develop Mountain1 smithore (+2) 2879 points
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <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 [AbstractDevelopmentPhase.timerFinished]      (P12) <B.I.L.L.> start frame: 58228
  INFO [NetworkTimer.start]                          Started "player 12 timer" at 47.5 (owner 12)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (12) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P12) <B.I.L.L.> develops tile Mountain1 (2,3) with smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToPub
  INFO [GameModel.gamble]                            (P12) <B.I.L.L.> gambles $64, max: 47.5, left: 32.68, fraction 0.688
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (12) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 4 "player 12 timer" at 0.0 (owner 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 [GameController.beginNextPhase]               --------- PLAYER_EVENT #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  INFO [PlayerEventPhase.begin]                      No player event for (P10) pliuo (rank 1)
  INFO [Player.useFood]                              (P10) pliuo has 2 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10) pliuo
  INFO [Wampus.<init>]                               Wumpus has 3 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 [AbstractDevelopmentPhase.timerFinished]      (P10) pliuo start frame: 59642
  INFO [NetworkTimer.start]                          Started "player 10 timer" at 33.333336 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [GameModel.developLand]                       (P10) pliuo develops tile River (4,1) with energy factory
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 2 "player 10 timer" at 0.0 (owner 10)
  INFO [Mule.runAway]                                Mule runs away for (P10) pliuo
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ProductionPhase.update]                      (P10) pliuo has 4 energy and needs 1 energy
  INFO [ProductionPhase.update]                      (P11) <D.A.N.> has 3 energy and needs 2 energy
  INFO [ProductionPhase.update]                      (P12) <B.I.L.L.> has 4 energy and needs 3 energy
  INFO [ProductionPhase.update]                      (P13) <J.I.M.> has 4 energy and needs 3 energy
  INFO [ProductionPhase.logProduction]               (P10) pliuo expects 2 food, 6 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <D.A.N.> expects 0 food, 4 energy, 8 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <B.I.L.L.> expects 4 food, 4 energy, 8 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <J.I.M.> expects 0 food, 0 energy, 15 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10) pliuo produced 1 food, 3 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <D.A.N.> produced 0 food, 1 energy, 5 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <B.I.L.L.> produced 4 food, 3 energy, 7 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <J.I.M.> produced 0 food, 0 energy, 14 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: PEST_ATTACK
  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]               --------- COLLECTION_SMITHORE #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Smithore shop: 0, mules potentially available: 1, mules needed: 6
  INFO [Shop.printPriceFactor]                       smithore is increased to 250%
  INFO [Shop.printPriceFactor]                       smithore buy price $125 sell price $160
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo smithore: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> smithore: 5 critical: 0 surplus: 5
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> smithore: 7 critical: 0 surplus: 7
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> smithore: 14 critical: 0 surplus: 14
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 13
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 12
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 13
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 13
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 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 [Client.receiveTCPMessages]                   Client: Transaction (0) 24 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 [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 13
  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]         (P12) <B.I.L.L.> auction teleport count: 14
  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]         (P13) <J.I.M.> auction teleport count: 14
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.calcBuySellPrice]                       crystite buy price $104 sell price $244
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> crystite: 0 critical: 0 surplus: 0
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 14
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 14
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 15
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 15
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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]               --------- COLLECTION_FOOD #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Food shop: 0, total: 4, needed: 12
  INFO [Shop.printPriceFactor]                       food is increased to 250%
  INFO [Shop.printPriceFactor]                       food buy price $65 sell price $100
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo food: 0 critical: 3 surplus: -3
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> food: 0 critical: 3 surplus: -3
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> food: 4 critical: 3 surplus: 1
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> food: 0 critical: 3 surplus: -3
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 15
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 15
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 16
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 16
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $213 ratio 4.233333
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $354 ratio 8.266666
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $213 ratio 4.233333
  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 [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Energy shop: 12, total: 24, needed: 13
  INFO [Shop.printPriceFactor]                       energy is decreased to 66%
  INFO [Shop.printPriceFactor]                       energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo energy: 5 critical: 2 surplus: 3
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> energy: 2 critical: 3 surplus: -1
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> energy: 4 critical: 4 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> energy: 1 critical: 4 surplus: -3
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 16
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 16
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 17
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 17
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $96 ratio 2.4666667
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $90 ratio 2.3
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $108 ratio 2.8
  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: <D.A.N.> target price $78 ratio 1.9666667
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $78 ratio 1.9666667
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $96 ratio 2.4666667
  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 $78 ratio 1.9666667
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $96 ratio 2.4666667
  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 $67 ratio 1.6333333
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $78 ratio 1.9666667
  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 $55 ratio 1.3
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $61 ratio 1.4666667
  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 $43 ratio 0.96666664
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $43 ratio 0.96666664
  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 $43 ratio 0.96666664
  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: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- SUMMARY #3 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [SummaryPhase2.begin]                         (P13) <J.I.M.> Money: $2456 Plots: 3 Assets: 455 Total: 4411
  INFO [SummaryPhase2.begin]                         (P12) <B.I.L.L.> Money: $1066 Plots: 4 Assets: 810 Total: 3876
  INFO [SummaryPhase2.begin]                         (P11) <D.A.N.> Money: $1136 Plots: 3 Assets: 405 Total: 3041
  INFO [SummaryPhase2.begin]                         (P10) pliuo Money: $20 Plots: 5 Assets: 330 Total: 2850
  INFO [SummaryPhase2.begin]                         Colony total: 14178
  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 $250
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 9 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 4145 tiles in 235191064ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain2 smithore <claim> (+3) 5366 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain smithore <claim> (+1) 7676 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain1 smithore <claim> (+2) 5241 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 7426 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain smithore <claim> (+1) 5241 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 7426 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain smithore <claim> (+1) 5241 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 7426 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants Mountain2 (7,4)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 4145 tiles in 258344286ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain smithore <claim> (+1) 4611 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 6736 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain smithore <claim> (+1) 4611 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 6736 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Mountain2 smithore <claim> (+3) 4486 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain smithore <claim> (+1) 6611 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain smithore <claim> (+1) 4486 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 6486 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants Plain (2,0)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 4 plans testing 4145 tiles in 265027538ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain2 smithore <claim> (+3) 6186 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain smithore <claim> (+1) 8541 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain smithore <claim> (+1) 6186 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 8541 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain smithore <claim> (+1) 6186 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 8541 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain smithore <claim> (+1) 6186 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Mountain2 smithore <claim> (+3) 8541 points
  INFO [LandGrantActuator.update]                    AI: <J.I.M.> wants Mountain2 (7,4)
  INFO [LandGrantActuator.agreeOnClaims]             AI: Agrees on tiles to claim.
  INFO [LandGrantActuator.agreeOnClaims]             AI: <J.I.M.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <J.I.M.> can't claim Mountain2 (7,4) and wants Plain (8,2) instead
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase$2.finished]                   Land grant roundtrip 0ms, 5 frames (5 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.update]                       Sending claim tile 0
  INFO [LandGrantPhase.apply]                        (P11) <D.A.N.> claims tile 0 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P11) <D.A.N.> is granted tile 0: Plain (2,0)
  INFO [LandGrantPhase.update]                       Sending claim tile 5
  INFO [LandGrantPhase.apply]                        (P10) pliuo claims tile 5 at time 13
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P10) pliuo is granted tile 5: Plain (1,1)
  INFO [LandGrantPhase.update]                       Sending claim tile 15
  INFO [LandGrantPhase.apply]                        (P13) <J.I.M.> claims tile 15 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P13) <J.I.M.> is granted tile 15: Plain (8,2)
  INFO [LandGrantPhase.update]                       Sending claim tile 27
  INFO [LandGrantPhase.apply]                        (P12) <B.I.L.L.> claims tile 27 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P12) <B.I.L.L.> is granted tile 27: Mountain2 (7,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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [LandAuction.generateColonyAuctions]          Colony land auctions: 0
  INFO [GameController.beginNextPhase]               --------- PLAYER_EVENT #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 9 mules.
  INFO [Development.nextPlayer]                      Development moving to next player: 0
  INFO [PlayerEventPhase.begin]                      No player event for (P13) <J.I.M.> (rank 1)
  INFO [Player.useFood]                              (P13) <J.I.M.> has 0 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13) <J.I.M.>
  INFO [Wampus.<init>]                               Wumpus has 1 mountains, reward is $200
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentActuator.begin]                   AI: <J.I.M.> is short on development time and goes to the pub
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: GoToPub
  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 [AbstractDevelopmentPhase.timerFinished]      (P13) <J.I.M.> start frame: 76030
  INFO [NetworkTimer.start]                          Started "player 13 timer" at 5.0 (owner 13)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (13) 16 bytes
  INFO [GameModel.gamble]                            (P13) <J.I.M.> gambles $109, max: 47.5, left: 3.8200002, fraction 0.08042105
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (13) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 5 "player 13 timer" at 0.0 (owner 13)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventPhase.begin]                      No player event for (P12) <B.I.L.L.> (rank 2)
  INFO [Player.useFood]                              (P12) <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 #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12) <B.I.L.L.>
  INFO [Wampus.<init>]                               Wumpus has 1 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 126 tiles in 5685918ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain2 smithore (+3) 5366 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain smithore <claim> (+1) 7676 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: (P12) <B.I.L.L.> plans to develop Mountain2 smithore (+3) 5366 points
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <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 [AbstractDevelopmentPhase.timerFinished]      (P12) <B.I.L.L.> start frame: 76762
  INFO [NetworkTimer.start]                          Started "player 12 timer" at 47.5 (owner 12)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (12) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P12) <B.I.L.L.> develops tile Mountain2 (7,4) with smithore factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToPub
  INFO [GameModel.gamble]                            (P12) <B.I.L.L.> gambles $204, max: 47.5, left: 29.58, fraction 0.6227368
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (12) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 4 "player 12 timer" at 0.0 (owner 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 [GameController.beginNextPhase]               --------- PLAYER_EVENT #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventPhase.begin]                      No player event for (P11) <D.A.N.> (rank 3)
  INFO [Player.useFood]                              (P11) <D.A.N.> has 0 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11) <D.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 1 mountains, reward is $200
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentActuator.begin]                   AI: <D.A.N.> is short on development time and goes to the pub
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: GoToPub
  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 [AbstractDevelopmentPhase.timerFinished]      (P11) <D.A.N.> start frame: 78331
  INFO [NetworkTimer.start]                          Started "player 11 timer" at 5.0 (owner 11)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (11) 16 bytes
  INFO [GameModel.gamble]                            (P11) <D.A.N.> gambles $103, max: 47.5, left: 3.8200002, fraction 0.08042105
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (11) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 3 "player 11 timer" at 0.0 (owner 11)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventPhase.begin]                      No player event for (P10) pliuo (rank 4)
  INFO [Player.useFood]                              (P10) pliuo has 0 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10) pliuo
  INFO [Wampus.<init>]                               Wumpus has 1 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 [AbstractDevelopmentPhase.timerFinished]      (P10) pliuo start frame: 79063
  INFO [NetworkTimer.start]                          Started "player 10 timer" at 5.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 2 "player 10 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 [GameController.beginNextPhase]               --------- COLONY_EVENT_A #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: RADIATION
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ProductionPhase.update]                      (P10) pliuo has 4 energy and needs 1 energy
  INFO [ProductionPhase.update]                      (P11) <D.A.N.> has 4 energy and needs 2 energy
  INFO [ProductionPhase.update]                      (P12) <B.I.L.L.> has 5 energy and needs 4 energy
  INFO [ProductionPhase.update]                      (P13) <J.I.M.> has 5 energy and needs 3 energy
  INFO [ProductionPhase.logProduction]               (P10) pliuo expects 2 food, 6 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <D.A.N.> expects 0 food, 4 energy, 8 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <B.I.L.L.> expects 4 food, 4 energy, 14 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <J.I.M.> expects 0 food, 0 energy, 11 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10) pliuo produced 1 food, 10 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <D.A.N.> produced 0 food, 6 energy, 6 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <B.I.L.L.> produced 3 food, 3 energy, 12 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <J.I.M.> produced 0 food, 0 energy, 12 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Smithore shop: 10, mules potentially available: 13, mules needed: 8
  INFO [Shop.printPriceFactor]                       smithore is decreased to 71%
  INFO [Shop.printPriceFactor]                       smithore buy price $85 sell price $120
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo smithore: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> smithore: 6 critical: 0 surplus: 6
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> smithore: 12 critical: 0 surplus: 12
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> smithore: 12 critical: 0 surplus: 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 [GameController.beginNextPhase]               --------- AUCTION_SMITHORE #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 17
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 17
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 18
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 18
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 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 [Client.receiveTCPMessages]                   Client: Transaction (0) 24 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 [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.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) 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]         (P12) <B.I.L.L.> auction teleport count: 19
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 19
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.calcBuySellPrice]                       crystite buy price $84 sell price $224
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> crystite: 0 critical: 0 surplus: 0
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 18
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 19
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 20
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 20
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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]               --------- COLLECTION_FOOD #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Food shop: 0, total: 5, needed: 16
  INFO [Shop.printPriceFactor]                       food is increased to 265%
  INFO [Shop.printPriceFactor]                       food buy price $195 sell price $230
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo food: 1 critical: 4 surplus: -3
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> food: 0 critical: 4 surplus: -4
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> food: 4 critical: 4 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> food: 0 critical: 4 surplus: -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]               --------- AUCTION_FOOD #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 19
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 20
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 21
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 21
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" 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 [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Energy shop: 6, total: 32, needed: 14
  INFO [Shop.printPriceFactor]                       energy is decreased to 58%
  INFO [Shop.printPriceFactor]                       energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo energy: 12 critical: 2 surplus: 10
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> energy: 8 critical: 3 surplus: 5
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> energy: 4 critical: 5 surplus: -1
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> energy: 2 critical: 4 surplus: -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]               --------- AUCTION_ENERGY #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 20
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 21
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 22
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 22
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $28 ratio 0.53333336
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $34 ratio 0.7
  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 $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $15 ratio 0.16666667
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $27 ratio 0.5
  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 $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $32 ratio 0.6333333
  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 $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $19 ratio 0.26666668
  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 $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> 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 $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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 $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) 20 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- SUMMARY #4 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [SummaryPhase2.begin]                         (P12) <B.I.L.L.> Money: $1911 Plots: 5 Assets: 1473 Total: 5884
  INFO [SummaryPhase2.begin]                         (P13) <J.I.M.> Money: $3504 Plots: 4 Assets: 345 Total: 5849
  INFO [SummaryPhase2.begin]                         (P11) <D.A.N.> Money: $1823 Plots: 4 Assets: 405 Total: 4228
  INFO [SummaryPhase2.begin]                         (P10) pliuo Money: $111 Plots: 6 Assets: 642 Total: 3753
  INFO [SummaryPhase2.begin]                         Colony total: 19714
  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 6 mules for 12 smithore and now has 14 mules
  INFO [Shop.buildMules]                             A mule now costs $170
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 3049 tiles in 197050183ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River food <claim> (+4) 7443 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River food <claim> (+4) 11439 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: River food <claim> (+4) 7019 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River food <claim> (+4) 11015 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain food <claim> (+2) 7019 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River food <claim> (+4) 10803 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Mountain1 food <claim> (+1) 6807 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River food <claim> (+4) 10379 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants River (4,4)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 14570 tiles in 700375606ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain food (+2) River food <claim> (+4) 5327 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River food <claim> (+4) 8100 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain food (+2) River food <claim> (+4) 5327 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River food <claim> (+4) 8100 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain food (+2) Plain food <claim> (+2) 4903 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River food <claim> (+4) 7676 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain food (+2) Plain food <claim> (+2) 4903 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River food <claim> (+4) 7676 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants River (4,4)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 4 plans testing 82371 tiles in 4968492085ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain2 food (+1) Plain food (+2) River food <claim> (+4) 7875 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River food <claim> (+4) 11311 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain2 food (+1) Plain food (+2) River food <claim> (+4) 7875 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River food <claim> (+4) 11311 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain2 food (+1) Plain food (+2) Plain food <claim> (+2) 7663 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River food <claim> (+4) 10887 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Mountain2 food (+1) Plain food (+2) Plain food <claim> (+2) 7663 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: River food <claim> (+4) 10887 points
  INFO [LandGrantActuator.update]                    AI: <J.I.M.> wants River (4,4)
  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 River (4,4) and wants River (4,0) instead
  INFO [LandGrantActuator.agreeOnClaims]             AI: <J.I.M.> must change tile to claim.
  INFO [LandGrantActuator.pickNextTile]              AI: <J.I.M.> can't claim River (4,4) and wants River (4,0) 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,0) and wants Plain (3,3) instead
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase$2.finished]                   Land grant roundtrip 0ms, 5 frames (5 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.update]                       Sending claim tile 0
  INFO [LandGrantPhase.apply]                        (P13) <J.I.M.> claims tile 0 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P13) <J.I.M.> is granted tile 0: River (4,0)
  INFO [LandGrantPhase.update]                       Sending claim tile 7
  INFO [LandGrantPhase.apply]                        (P10) pliuo claims tile 7 at time 20
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P10) pliuo is granted tile 7: Plain (0,2)
  INFO [LandGrantPhase.update]                       Sending claim tile 13
  INFO [LandGrantPhase.apply]                        (P12) <B.I.L.L.> claims tile 13 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P12) <B.I.L.L.> is granted tile 13: Plain (3,3)
  INFO [LandGrantPhase.update]                       Sending claim tile 21
  INFO [LandGrantPhase.apply]                        (P11) <D.A.N.> claims tile 21 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P11) <D.A.N.> is granted tile 21: River (4,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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [LandAuction.generateColonyAuctions]          Colony land auctions: 1
  INFO [LandAuction.beginAuction]                    Land auction price: $292
  INFO [AbstractLandAuctionPhase.begin]              Starting land auction
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $308
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $52
  INFO [LandAuctionActuator.beginLandAuction]        AI: <J.I.M.> targets price $368
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 21
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 22
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 23
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 23
  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 [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 $344 (changed $36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $344 (changed $0)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $336 (changed $-32)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $352 (changed $8)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $360 (changed $8)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $356 (changed $20)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $324 (changed $-36)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $348 (changed $-8)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $408 (changed $60)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $432 (changed $24)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <D.A.N.> new price $284 (changed $-40)
  INFO [LandAuctionActuator.changeTargetPrice]       AI: <J.I.M.> new price $376 (changed $-56)
  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]              (P13) <J.I.M.> bought a land for $296
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  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) <B.I.L.L.> (rank 1)
  INFO [Player.useFood]                              (P12) <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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12) <B.I.L.L.>
  INFO [Wampus.<init>]                               Wumpus has 1 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 101 tiles in 4739988ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain food (+2) 7019 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 10379 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: (P12) <B.I.L.L.> plans to develop Plain food (+2) 7019 points
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <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 [AbstractDevelopmentPhase.timerFinished]      (P12) <B.I.L.L.> start frame: 97789
  INFO [NetworkTimer.start]                          Started "player 12 timer" at 47.5 (owner 12)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (12) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P12) <B.I.L.L.> develops tile Plain (3,3) with food factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToPub
  INFO [GameModel.gamble]                            (P12) <B.I.L.L.> gambles $194, max: 47.5, left: 35.06, fraction 0.7381053
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (12) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 4 "player 12 timer" at 0.0 (owner 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 [GameController.beginNextPhase]               --------- PLAYER_EVENT #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventPhase.begin]                      No player event for (P13) <J.I.M.> (rank 2)
  INFO [Player.useFood]                              (P13) <J.I.M.> has 0 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13) <J.I.M.>
  INFO [Wampus.<init>]                               Wumpus has 1 mountains, reward is $200
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentActuator.begin]                   AI: <J.I.M.> is short on development time and goes to the pub
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: GoToPub
  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 [AbstractDevelopmentPhase.timerFinished]      (P13) <J.I.M.> start frame: 99084
  INFO [NetworkTimer.start]                          Started "player 13 timer" at 5.0 (owner 13)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (13) 16 bytes
  INFO [GameModel.gamble]                            (P13) <J.I.M.> gambles $104, max: 47.5, left: 3.8200002, fraction 0.08042105
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (13) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 5 "player 13 timer" at 0.0 (owner 13)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventPhase.begin]                      No player event for (P11) <D.A.N.> (rank 3)
  INFO [Player.useFood]                              (P11) <D.A.N.> has 0 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11) <D.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 1 mountains, reward is $200
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentActuator.begin]                   AI: <D.A.N.> is short on development time and goes to the pub
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: GoToPub
  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 [AbstractDevelopmentPhase.timerFinished]      (P11) <D.A.N.> start frame: 99816
  INFO [NetworkTimer.start]                          Started "player 11 timer" at 5.0 (owner 11)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (11) 16 bytes
  INFO [GameModel.gamble]                            (P11) <D.A.N.> gambles $109, max: 47.5, left: 3.8200002, fraction 0.08042105
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (11) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 3 "player 11 timer" at 0.0 (owner 11)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventPhase.begin]                      No player event for (P10) pliuo (rank 4)
  INFO [Player.useFood]                              (P10) pliuo has 1 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10) pliuo
  INFO [Wampus.<init>]                               Wumpus has 1 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 [AbstractDevelopmentPhase.timerFinished]      (P10) pliuo start frame: 100548
  INFO [NetworkTimer.start]                          Started "player 10 timer" at 15.625 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [GameModel.gamble]                            (P10) pliuo gambles $114, max: 47.5, left: 8.684999, fraction 0.18284209
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 2 "player 10 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 [GameController.beginNextPhase]               --------- COLONY_EVENT_A #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- PRODUCTION #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ProductionPhase.update]                      (P10) pliuo has 8 energy and needs 1 energy
  INFO [ProductionPhase.update]                      (P11) <D.A.N.> has 4 energy and needs 2 energy
  INFO [ProductionPhase.update]                      (P12) <B.I.L.L.> has 6 energy and needs 5 energy
  INFO [ProductionPhase.update]                      (P13) <J.I.M.> has 5 energy and needs 3 energy
  INFO [ProductionPhase.logProduction]               (P10) pliuo expects 2 food, 6 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <D.A.N.> expects 0 food, 4 energy, 8 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <B.I.L.L.> expects 8 food, 4 energy, 14 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <J.I.M.> expects 0 food, 0 energy, 11 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10) pliuo produced 2 food, 6 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <D.A.N.> produced 0 food, 5 energy, 10 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <B.I.L.L.> produced 5 food, 2 energy, 16 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <J.I.M.> produced 0 food, 0 energy, 14 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: PLANET_QUAKE
  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]               --------- COLLECTION_SMITHORE #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Smithore shop: 28, mules potentially available: 27, mules needed: 8
  INFO [Shop.printPriceFactor]                       smithore is decreased to 47%
  INFO [Shop.printPriceFactor]                       smithore buy price $50 sell price $85
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo smithore: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> smithore: 5 critical: 0 surplus: 5
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> smithore: 7 critical: 0 surplus: 7
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> smithore: 6 critical: 0 surplus: 6
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 22
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 23
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 24
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 24
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 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 [Client.receiveTCPMessages]                   Client: Transaction (0) 24 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 [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 24
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 20 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 25
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 25
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.calcBuySellPrice]                       crystite buy price $100 sell price $240
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> crystite: 0 critical: 0 surplus: 0
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 23
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 25
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 26
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 26
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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]               --------- COLLECTION_FOOD #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Food shop: 0, total: 7, needed: 16
  INFO [Shop.printPriceFactor]                       food is increased to 196%
  INFO [Shop.printPriceFactor]                       food buy price $215 sell price $250
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo food: 2 critical: 4 surplus: -2
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> food: 0 critical: 4 surplus: -4
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> food: 5 critical: 4 surplus: 1
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> food: 0 critical: 4 surplus: -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]               --------- AUCTION_FOOD #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 24
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 26
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 27
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 27
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $380 ratio 4.733333
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $541 ratio 9.333333
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $380 ratio 4.733333
  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 [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Energy shop: 9, total: 32, needed: 15
  INFO [Shop.printPriceFactor]                       energy is decreased to 60%
  INFO [Shop.printPriceFactor]                       energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo energy: 11 critical: 2 surplus: 9
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> energy: 7 critical: 3 surplus: 4
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> energy: 3 critical: 6 surplus: -3
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> energy: 2 critical: 4 surplus: -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]               --------- AUCTION_ENERGY #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 25
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 27
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 28
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 28
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $45 ratio 1.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $73 ratio 1.8
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $67 ratio 1.6333333
  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 [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $66 ratio 1.6
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $54 ratio 1.2666667
  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 $24 ratio 0.4
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $59 ratio 1.4
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $41 ratio 0.9
  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 $28 ratio 0.53333336
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $63 ratio 1.5333333
  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 $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $50 ratio 1.1666666
  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 $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $38 ratio 0.8
  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 $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $25 ratio 0.43333334
  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 $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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 $10 ratio 0.0
  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 $10 ratio 0.0
  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: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- SUMMARY #5 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [SummaryPhase2.begin]                         (P12) <B.I.L.L.> Money: $2116 Plots: 6 Assets: 1860 Total: 6976
  INFO [SummaryPhase2.begin]                         (P13) <J.I.M.> Money: $3504 Plots: 6 Assets: 345 Total: 6849
  INFO [SummaryPhase2.begin]                         (P11) <D.A.N.> Money: $2212 Plots: 5 Assets: 405 Total: 5117
  INFO [SummaryPhase2.begin]                         (P10) pliuo Money: $477 Plots: 7 Assets: 790 Total: 4767
  INFO [SummaryPhase2.begin]                         Colony total: 23709
  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 1 mules for 2 smithore and now has 14 mules
  INFO [Shop.buildMules]                             A mule now costs $100
  INFO [GameController.beginNextPhase]               --------- LAND_GRANT #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.setPlayerOrder]                  Normal development order. 14 mules.
  INFO [DevelopmentPlanFinder$1.run]                 AI: <B.I.L.L.> found 4 plans testing 1939 tiles in 119013729ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain food <claim> (+2) 8956 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 12906 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain food <claim> (+2) 8956 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 12906 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain food <claim> (+2) 8726 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 12676 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain food <claim> (+2) 8726 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 12676 points
  INFO [LandGrantActuator.update]                    AI: <B.I.L.L.> wants Plain (3,2)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <D.A.N.> found 4 plans testing 43076 tiles in 2912162579ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain food (+2) River food (+4) Plain food <claim> (+2) 7612 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 11212 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain food (+2) River food (+4) Plain food <claim> (+2) 7612 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 11212 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain food (+2) River food (+4) Plain food <claim> (+2) 7152 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 10752 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <D.A.N.> Plan: Plain food (+2) River food (+4) Plain food <claim> (+2) 7152 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 10522 points
  INFO [LandGrantActuator.update]                    AI: <D.A.N.> wants Plain (5,4)
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 4 plans testing 1082036 tiles in 155566297672ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain food (+2) River food (+4) Mountain2 food (+1) Plain food (+2) Plain food <claim> (+2) 10534 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 16859 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain food (+2) River food (+4) Mountain2 food (+1) Plain food (+2) Plain food <claim> (+2) 10304 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 16629 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain food (+2) River food (+4) Mountain2 food (+1) Plain food (+2) Plain food <claim> (+2) 10304 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 16629 points
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain food (+2) River food (+4) Mountain2 food (+1) Plain food (+2) Plain food <claim> (+2) 10304 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 16629 points
  INFO [LandGrantActuator.update]                    AI: <J.I.M.> wants Plain (1,4)
  INFO [LandGrantActuator.agreeOnClaims]             AI: Agrees on tiles to claim.
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     Continue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase$2.finished]                   Land grant roundtrip 0ms, 5 frames (5 grace frames)
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 25 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantStart is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [MuleGameContainer.setWindowedMode]           Setting windowed mode: 640 x 480 x 0 @0Hz
  INFO [LandGrantPhase.update]                       Sending claim tile 8
  INFO [LandGrantPhase.apply]                        (P12) <B.I.L.L.> claims tile 8 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P12) <B.I.L.L.> is granted tile 8: Plain (3,2)
  INFO [LandGrantPhase.update]                       Sending claim tile 9
  INFO [LandGrantPhase.apply]                        (P10) pliuo claims tile 9 at time 5
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P10) pliuo is granted tile 9: Plain (5,2)
  INFO [LandGrantPhase.update]                       Sending claim tile 15
  INFO [LandGrantPhase.apply]                        (P13) <J.I.M.> claims tile 15 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P13) <J.I.M.> is granted tile 15: Plain (1,4)
  INFO [LandGrantPhase.update]                       Sending claim tile 17
  INFO [LandGrantPhase.apply]                        (P11) <D.A.N.> claims tile 17 at time 26
  INFO [Client.receiveTCPMessages]                   Client: LandGranted (0) 4 bytes
  INFO [Client.receiveTCPMessages]                   Client: Synchronized (0) 21 bytes
  INFO [DelayedMessageQueue.add]                     LandGrantContinue is delivered in 0 frames, rountrip 0ms, max 0ms
  INFO [LandGrantPhase.apply]                        (P11) <D.A.N.> is granted tile 17: 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 #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [LandAuction.generateColonyAuctions]          Colony land auctions: 1
  INFO [LandAuction.beginAuction]                    Land auction price: $236
  INFO [AbstractLandAuctionPhase.begin]              Starting land auction
  INFO [LandAuctionActuator.beginLandAuction]        AI: <D.A.N.> targets price $116
  INFO [LandAuctionActuator.beginLandAuction]        AI: <B.I.L.L.> targets price $132
  INFO [LandAuctionActuator.beginLandAuction]        AI: <J.I.M.> targets price $248
  INFO [NetworkTimer.start]                          Started "countdown" at 6.0 (owner 10)
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 26
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 28
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 29
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> 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 [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 [AbstractLandAuctionPhase.apply]              (P13) <J.I.M.> bought a land for $240
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  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) <B.I.L.L.> (rank 1)
  INFO [Player.useFood]                              (P12) <B.I.L.L.> 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 #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P12) <B.I.L.L.>
  INFO [Wampus.<init>]                               Wumpus has 1 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 76 tiles in 4968788ns
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder.logPlans]              AI: <B.I.L.L.> Plan: Plain food (+2) 8956 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 12906 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: (P12) <B.I.L.L.> plans to develop Plain food (+2) 8956 points
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <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 [AbstractDevelopmentPhase.timerFinished]      (P12) <B.I.L.L.> start frame: 125281
  INFO [NetworkTimer.start]                          Started "player 12 timer" at 47.5 (owner 12)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (12) 16 bytes
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToOutfitStore
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: PlaceMule
  INFO [GameModel.developLand]                       (P12) <B.I.L.L.> develops tile Plain (3,2) with food factory
  INFO [DevelopmentActionQueue2.addAction]           AI: <B.I.L.L.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P12) <B.I.L.L.> Action: GoToPub
  INFO [GameModel.gamble]                            (P12) <B.I.L.L.> gambles $187, max: 47.5, left: 34.4, fraction 0.72421056
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (12) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 4 "player 12 timer" at 0.0 (owner 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 [GameController.beginNextPhase]               --------- PLAYER_EVENT #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 1
  INFO [PlayerEventGenerator.nextEvent]              Player event pitys player without food.
  INFO [PlayerEventPhase.begin]                      Event player: (P13) <J.I.M.> (rank 2)
  INFO [PlayerEventPhase.begin]                      Event: HomeWorldPackage
  INFO [PlayerEventPhase$1.finished]                 Event Message: You just received a package from your home-world relatives containing 3 food and 2 energy units
  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]                              (P13) <J.I.M.> has 3 food and needs 4 food
  INFO [GameController.beginNextPhase]               --------- DEVELOPMENT #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P13) <J.I.M.>
  INFO [Wampus.<init>]                               Wumpus has 1 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 [AbstractDevelopmentPhase.timerFinished]      (P13) <J.I.M.> start frame: 127143
  INFO [NetworkTimer.start]                          Started "player 13 timer" at 36.875 (owner 13)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (13) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (13) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 5 "player 13 timer" at 0.0 (owner 13)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 2
  INFO [PlayerEventPhase.begin]                      No player event for (P11) <D.A.N.> (rank 3)
  INFO [Player.useFood]                              (P11) <D.A.N.> has 0 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P11) <D.A.N.>
  INFO [Wampus.<init>]                               Wumpus has 1 mountains, reward is $200
  INFO [NetworkTimer.start]                          Started "countdown" at 4.0 (owner 10)
  INFO [DevelopmentActuator.begin]                   AI: <D.A.N.> is short on development time and goes to the pub
  INFO [DevelopmentActionQueue2.addAction]           AI: <D.A.N.> Queue: GoToPub
  INFO [DevelopmentActionQueue2.update]              AI: (P11) <D.A.N.> Action: GoToPub
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [DevelopmentPlanFinder$1.run]                 AI: <J.I.M.> found 1 plans testing 188166 tiles in 44473595107ns
  INFO [DevelopmentPlanFinder.logPlans]              AI: <J.I.M.> Plan: Plain food (+2) Plain food (+2) Plain food (+2) River food (+4) Mountain2 food (+1) Plain food (+2) 12754 points
  INFO [DevelopmentPlanFinder.logPlans]              AI:	Next: Plain food <claim> (+2) 20184 points
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: PlaceMule
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: PlaceMule
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: PlaceMule
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: PlaceMule
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: PlaceMule
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToMuleStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: GoToOutfitStore
  INFO [DevelopmentActionQueue2.addAction]           AI: <J.I.M.> Queue: PlaceMule
  INFO [DevelopmentActuator.enqueueBestPlan]         AI: (P13) <J.I.M.> plans to develop Plain food (+2) Plain food (+2) Plain food (+2) River food (+4) Mountain2 food (+1) Plain food (+2) 12754 points
  INFO [DevelopmentActionQueue2.update]              AI: (P13) <J.I.M.> Action: GoToMuleStore
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 0 "countdown" at 0.0 (owner 10)
  INFO [AbstractDevelopmentPhase.timerFinished]      (P11) <D.A.N.> start frame: 129659
  INFO [NetworkTimer.start]                          Started "player 11 timer" at 5.0 (owner 11)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (11) 16 bytes
  INFO [GameModel.gamble]                            (P11) <D.A.N.> gambles $114, max: 47.5, left: 3.8200002, fraction 0.08042105
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (11) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 3 "player 11 timer" at 0.0 (owner 11)
  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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Development.nextPlayer]                      Development moving to next player: 3
  INFO [PlayerEventPhase.begin]                      No player event for (P10) pliuo (rank 4)
  INFO [Player.useFood]                              (P10) pliuo has 2 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> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [DevelopmentPhaseSingle.begin]                Developing player: (P10) pliuo
  INFO [Wampus.<init>]                               Wumpus has 1 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 [AbstractDevelopmentPhase.timerFinished]      (P10) pliuo start frame: 130391
  INFO [NetworkTimer.start]                          Started "player 10 timer" at 26.25 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [GameModel.developLand]                       (P10) pliuo develops tile Plain (1,1) with food factory
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 2 "player 10 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 [GameController.beginNextPhase]               --------- COLONY_EVENT_A #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      ColonyEvent: SUNSPOT_ACTIVITY
  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 #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ProductionPhase.update]                      (P10) pliuo has 4 energy and needs 2 energy
  INFO [ProductionPhase.update]                      (P11) <D.A.N.> has 4 energy and needs 2 energy
  INFO [ProductionPhase.update]                      (P12) <B.I.L.L.> has 7 energy and needs 6 energy
  INFO [ProductionPhase.update]                      (P13) <J.I.M.> has 7 energy and needs 3 energy
  INFO [ProductionPhase.logProduction]               (P10) pliuo expects 4 food, 12 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <D.A.N.> expects 0 food, 7 energy, 8 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <B.I.L.L.> expects 14 food, 7 energy, 14 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <J.I.M.> expects 0 food, 0 energy, 11 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P10) pliuo produced 5 food, 9 energy, 0 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P11) <D.A.N.> produced 0 food, 8 energy, 9 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P12) <B.I.L.L.> produced 11 food, 8 energy, 17 smithore, 0 crystite
  INFO [ProductionPhase.logProduction]               (P13) <J.I.M.> produced 0 food, 0 energy, 9 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 #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [ColonyEventPhase.begin]                      No colony event. Skipping phase.
  INFO [GameController.beginNextPhase]               --------- COLLECTION_SMITHORE #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Smithore shop: 44, mules potentially available: 34, mules needed: 8
  INFO [Shop.printPriceFactor]                       smithore is decreased to 44%
  INFO [Shop.printPriceFactor]                       smithore buy price $50 sell price $85
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo smithore: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> smithore: 9 critical: 0 surplus: 9
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> smithore: 17 critical: 0 surplus: 17
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> smithore: 9 critical: 0 surplus: 9
  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 #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 27
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 29
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 30
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 30
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 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 [Client.receiveTCPMessages]                   Client: Transaction (0) 24 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 [Client.receiveTCPMessages]                   Client: Transaction (0) 24 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 [Client.receiveTCPMessages]                   Client: Transaction (0) 24 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 [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 30
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 31
  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) <B.I.L.L.> auction teleport count: 31
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLLECTION_CRYSTITE #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.calcBuySellPrice]                       crystite buy price $68 sell price $208
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> crystite: 0 critical: 0 surplus: 0
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> crystite: 0 critical: 0 surplus: 0
  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 #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 28
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 31
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 32
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 32
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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]               --------- COLLECTION_FOOD #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Food shop: 0, total: 17, needed: 16
  INFO [Shop.printPriceFactor]                       food is decreased to 96%
  INFO [Shop.printPriceFactor]                       food buy price $205 sell price $240
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo food: 5 critical: 4 surplus: 1
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> food: 0 critical: 4 surplus: -4
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> food: 12 critical: 4 surplus: 8
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> food: 0 critical: 4 surplus: -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]               --------- AUCTION_FOOD #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 29
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 32
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 33
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 33
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $310 ratio 3.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $387 ratio 5.2
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $310 ratio 3.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 $297 ratio 2.6333334
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $354 ratio 4.266667
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $303 ratio 2.8
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 30
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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 $279 ratio 2.1333334
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $317 ratio 3.2
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $291 ratio 2.4666667
  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 $262 ratio 1.6333333
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $279 ratio 2.1333334
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $279 ratio 2.1333334
  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 $242 ratio 1.0666667
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $268 ratio 1.8
  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 $205 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $250 ratio 1.3
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 31
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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 $205 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $237 ratio 0.93333334
  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 $205 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $224 ratio 0.56666666
  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 $205 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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 $205 ratio 0.0
  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 $205 ratio 0.0
  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 $205 ratio 0.0
  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 $205 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 16 bytes
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 34
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [Client.receiveTCPMessages]                   Client: TimerFinished (10) 16 bytes
  INFO [NetworkTimerManager.apply]                   Finished Timer 1 "phase timer" at 0.0 (owner 10)
  INFO [Client.receiveTCPMessages]                   Client: Ready (10) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (11) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (12) 8 bytes
  INFO [Client.receiveTCPMessages]                   Client: Ready (13) 8 bytes
  INFO [GameController.beginNextPhase]               --------- COLLECTION_ENERGY #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Shop.printPriceFactor]                       Energy shop: 12, total: 46, needed: 17
  INFO [Shop.printPriceFactor]                       energy is decreased to 53%
  INFO [Shop.printPriceFactor]                       energy buy price $10 sell price $45
  INFO [CollectionPhase.goToNextPhase]               (P10) pliuo energy: 11 critical: 3 surplus: 8
  INFO [CollectionPhase.goToNextPhase]               (P11) <D.A.N.> energy: 10 critical: 3 surplus: 7
  INFO [CollectionPhase.goToNextPhase]               (P12) <B.I.L.L.> energy: 9 critical: 7 surplus: 2
  INFO [CollectionPhase.goToNextPhase]               (P13) <J.I.M.> energy: 4 critical: 4 surplus: 0
  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 #6 --------------------------------------------------------
  INFO [Server.logRoundtrip]                         Roundtrips: <P10 P11 P12 P13> 0ms 
  INFO [Client.logRoundtripTime]                     Client: Roundtrip time is 0 ms
  INFO [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 32
  INFO [Player.increaseAuctionTeleportCount]         (P11) <D.A.N.> auction teleport count: 33
  INFO [Player.increaseAuctionTeleportCount]         (P12) <B.I.L.L.> auction teleport count: 35
  INFO [Player.increaseAuctionTeleportCount]         (P13) <J.I.M.> auction teleport count: 34
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [NetworkTimer.start]                          Started "countdown" 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 0 "countdown" at 0.0 (owner 10)
  INFO [NetworkTimer.start]                          Started "phase timer" at 10.0 (owner 10)
  INFO [AuctionActuator.calcTargetPrice]             AI: <D.A.N.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <J.I.M.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: TimerStarted (10) 16 bytes
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.checkCancelTransactions] Seller pliuo rank 4 takes over trade from seller <D.A.N.> rank 3
  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 $10 ratio 0.0
  INFO [AuctionActuator.calcTargetPrice]             AI: <B.I.L.L.> target price $10 ratio 0.0
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [AbstractAuctionPhase.checkCancelTransactions] Buyer left auction line.
  INFO [Client.receiveTCPMessages]                   Client: BeginTransaction (0) 0 bytes
  INFO [Client.receiveTCPMessages]                   Client: Transaction (0) 24 bytes
  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 $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 $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 $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 $10 ratio 0.0
  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 [Player.increaseAuctionTeleportCount]         (P10) pliuo auction teleport count: 33
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  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]         (P10) pliuo auction teleport count: 34
  INFO [Client.receiveTCPMessages]                   Client: EndTransaction (0) 0 bytes
  INFO [AbstractAuctionPhase.calcRoundtripTime]      Auction uses roundtrip: 0ms
  INFO [MetaFrame$15.windowClosing]                  Meta frame closing
  INFO [MuleGameContainer.release]                   Closing game.
  INFO [GameController.close]                        -------- CLOSING GAME CONTROLLER ----------------------------------
  INFO [MasterClient.logSend]                        MasterClient: Sending User Left ID 4043 user number 10
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Chat Log
  INFO [MasterClient.logSend]                        MasterClient: Sending Game Log
  INFO [Client.receiveTCPMessages]                   Client: UserLeft (10) 0 bytes
  INFO [GameController.close]                        Got my UserLeft (10) message.
  INFO [Server.removeClient]                         Server: Removing client 127.0.0.1 <P10 P11 P12 P13> with id db8e2475-c552-443a-b8ba-c2b4ec325926
  INFO [ConnectedClient.removeUser]                  Server: Removing user 10 from 127.0.0.1 <P10 P11 P12 P13>
  INFO [ConnectedClient.removeUser]                  Server: Removing user 11 from 127.0.0.1 <P11 P12 P13>
  INFO [ConnectedClient.removeUser]                  Server: Removing user 12 from 127.0.0.1 <P12 P13>
  INFO [ConnectedClient.removeUser]                  Server: Removing user 13 from 127.0.0.1 <P13>
  INFO [Server.sendMessages]                         Server: Closing connection to removed client 127.0.0.1 <>
  INFO [GameController.close]                        Left server in 16 ms
  INFO [Server.close]                                Server: Closing
  INFO [MetaFrame$15.windowClosing]                  Meta frame closing done
  INFO [MuleGameContainer.release]                   *** Game Over ***
  INFO [Main.main]                                   Leaving game loop
  INFO [MasterClient.close]                          MasterServer: Closing...
