Human frequently gets a wrongly colored piece

[anarchuser]

Version: 21.0.6 7ec4e7b
Platform: Windows (reproduced on Linux)
Game: Computer vs. Human

• Start
• BLUE places
• YELLOW places
• RED places
• GUI allows YELLOW moves only, although it's GREEN's turn

Notes:

• Occurred randomly
• Reproduced randomly on: pento-z, pento-w

[anarchuser]

It's possible this is related to #36
GUI and Server being out of sync suggests that the internal state of the GUI isn't getting cleared properly.

[xeruf]

Same issue encountered:

Log:

10:50:38 DEBUG sc.gui.controller.AppController - Requested View change from START -> GAME_CREATION
10:50:40 DEBUG sc.gui.controller.AppController - Requested View change from GAME_CREATION -> GAME
10:50:41 DEBUG sc.gui.view.PiecesFragment - Color: RED
10:50:41 DEBUG sc.gui.model.BoardModel - Blocksize changed 16.0 -> 0.0
10:50:41 DEBUG sc.gui.controller.ClientController - creating and observing
10:50:41 INFO  sc.networking.clients.LobbyClient - Creating TCP Network for localhost:13050
10:50:41 INFO  sc.server.network.NewClientListener - A Client connected...
10:50:41 INFO  sc.networking.clients.LobbyClient - Creating TCP Network for localhost:13050
10:50:41 INFO  sc.networking.clients.LobbyClient - Creating TCP Network for localhost:13050
10:50:41 INFO  sc.server.network.ClientManager - Delegating new client to ClientManager...
10:50:41 INFO  sc.server.network.ClientManager - Delegation done
10:50:41 INFO  sc.server.network.NewClientListener - Added Client sc.server.network.Client@26786261 to ReadyQueue.
10:50:41 INFO  sc.server.network.NewClientListener - A Client connected...
10:50:41 INFO  sc.server.network.NewClientListener - Added Client sc.server.network.Client@49e35ba5 to ReadyQueue.
10:50:41 INFO  sc.server.network.ClientManager - Delegating new client to ClientManager...
10:50:41 INFO  sc.server.network.ClientManager - Delegation done
10:50:41 INFO  sc.server.network.NewClientListener - A Client connected...
10:50:41 INFO  sc.server.network.NewClientListener - Added Client sc.server.network.Client@14d511fc to ReadyQueue.
10:50:41 INFO  sc.server.network.ClientManager - Delegating new client to ClientManager...
10:50:41 INFO  sc.server.network.ClientManager - Delegation done
10:50:41 DEBUG sc.gui.model.BoardModel - Blocksize changed 0.0 -> 24.0
10:50:41 INFO  sc.server.network.Client - Client authenticated as administrator
10:50:41 INFO  sc.server.gaming.GameRoomManager - Created new game of type swc_2021_blokus
10:50:41 WARN  sc.server.gaming.GameRoom - Dropped unnecessary PAUSE toggle from false to false.
10:50:41 DEBUG sc.gui.LobbyManager - lobby: game was prepared
10:50:41 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:41 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:41 DEBUG sc.gui.controller.ClientController - no controlling client yet
10:50:41 INFO  sc.server.gaming.ReservationManager - Reservation 3f02ee8e-16a3-419d-b8f2-be9af620169b was redeemed.
10:50:41 INFO  sc.server.gaming.GameRoom - Game isn't ready yet.
10:50:41 INFO  sc.server.gaming.ReservationManager - Reservation 7c22c302-120c-49ab-90be-d995161a43c0 was redeemed.
10:50:41 INFO  sc.server.gaming.GameRoom - Updating Status to ACTIVE (was: CREATED)
10:50:41 DEBUG sc.gui.LobbyManager - lobby: 22da4b1e-012f-4faa-a8e9-a0e565b69dee game was observed
10:50:41 DEBUG sc.gui.LobbyManager - lobby: new state for 22da4b1e-012f-4faa-a8e9-a0e565b69dee
10:50:41 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:41 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:41 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/0 -> BLUE (Start Piece: PENTO_V)
Security framework of XStream not explicitly initialized, using predefined black list on your own risk.
10:50:41 INFO  sc.server.gaming.GameRoom - Started the game.
10:50:41 DEBUG s.g.c.client.InternalGameHandler - Player One got new action request!
10:50:41 DEBUG sc.gui.LobbyManager - lobby: new state for 22da4b1e-012f-4faa-a8e9-a0e565b69dee
10:50:41 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:41 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:41 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/1 -> YELLOW (Start Piece: PENTO_V)
10:50:41 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:41 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:41 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/1 -> YELLOW (Start Piece: PENTO_V)
10:50:41 DEBUG s.g.c.client.InternalGameHandler - Player Two got new action request!
10:50:41 DEBUG sc.gui.controller.GameController - New game state
10:50:42 DEBUG sc.gui.controller.GameController - New game state
10:50:42 DEBUG sc.gui.controller.GameController - New game state
10:50:42 DEBUG sc.gui.controller.GameController - Human move request
10:50:42 DEBUG sc.gui.controller.GameController - Number of possible moves: 9
10:50:42 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
10:50:42 DEBUG sc.gui.view.PiecesFragment - Color: YELLOW
Clicked on pane 17, 0
10:50:44 DEBUG sc.gui.controller.BoardController - Set-Move seems valid from GUI at 17, 0
10:50:44 DEBUG sc.gui.LobbyManager - lobby: new state for 22da4b1e-012f-4faa-a8e9-a0e565b69dee
10:50:44 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:44 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:44 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/2 -> RED (Start Piece: PENTO_V)
10:50:44 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:44 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:44 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/2 -> RED (Start Piece: PENTO_V)
10:50:44 DEBUG sc.gui.controller.GameController - New game state
10:50:44 DEBUG sc.gui.controller.GameController - New game state
10:50:44 DEBUG s.g.c.client.InternalGameHandler - Player One got new action request!
10:50:44 DEBUG sc.gui.LobbyManager - lobby: new state for 22da4b1e-012f-4faa-a8e9-a0e565b69dee
10:50:44 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:44 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:44 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/3 -> GREEN (Start Piece: PENTO_V)
10:50:44 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:44 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:44 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/3 -> GREEN (Start Piece: PENTO_V)
10:50:44 DEBUG s.g.c.client.InternalGameHandler - Player Two got new action request!
10:50:44 DEBUG sc.gui.controller.GameController - New game state
10:50:44 DEBUG sc.gui.controller.GameController - New game state
10:50:44 DEBUG sc.gui.controller.GameController - Human move request
10:50:44 DEBUG sc.gui.controller.GameController - Number of possible moves: 3
10:50:44 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
10:50:44 DEBUG sc.gui.view.PiecesFragment - Color: GREEN
Clicked on pane 17, 17
10:50:49 DEBUG sc.gui.controller.BoardController - Set-Move seems valid from GUI at 17, 17
10:50:49 DEBUG sc.gui.LobbyManager - lobby: new state for 22da4b1e-012f-4faa-a8e9-a0e565b69dee
10:50:49 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:49 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:49 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/4 -> BLUE 
10:50:49 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:49 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:49 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/4 -> BLUE 
10:50:49 DEBUG sc.gui.controller.GameController - New game state
10:50:49 DEBUG sc.gui.controller.GameController - New game state
10:50:49 DEBUG s.g.c.client.InternalGameHandler - Player One got new action request!
10:50:49 DEBUG sc.gui.LobbyManager - lobby: new state for 22da4b1e-012f-4faa-a8e9-a0e565b69dee
10:50:49 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:49 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:49 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/5 -> YELLOW 
10:50:49 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:49 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:49 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/5 -> YELLOW 
10:50:49 DEBUG s.g.c.client.InternalGameHandler - Player Two got new action request!
10:50:49 DEBUG sc.gui.controller.GameController - New game state
10:50:49 DEBUG sc.gui.controller.GameController - New game state
10:50:49 DEBUG sc.gui.controller.GameController - Human move request
10:50:49 DEBUG sc.gui.controller.GameController - Number of possible moves: 185
10:50:49 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
10:50:49 DEBUG sc.gui.view.PiecesFragment - Color: YELLOW
Clicked on pane 17, 3
10:50:51 DEBUG sc.gui.controller.BoardController - Set-Move seems valid from GUI at 17, 3
10:50:51 DEBUG sc.gui.LobbyManager - lobby: new state for 22da4b1e-012f-4faa-a8e9-a0e565b69dee
10:50:51 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:51 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:51 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/6 -> RED 
10:50:51 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:51 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:51 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/6 -> RED 
10:50:51 DEBUG s.g.c.client.InternalGameHandler - Player One got new action request!
10:50:51 DEBUG sc.gui.controller.GameController - New game state
10:50:51 DEBUG sc.gui.controller.GameController - New game state
10:50:51 DEBUG sc.gui.LobbyManager - lobby: new state for 22da4b1e-012f-4faa-a8e9-a0e565b69dee
10:50:51 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:51 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:51 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/7 -> GREEN 
10:50:51 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:51 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:51 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/7 -> GREEN 
10:50:51 DEBUG s.g.c.client.InternalGameHandler - Player Two got new action request!
10:50:52 DEBUG sc.gui.controller.GameController - New game state
10:50:52 DEBUG sc.gui.controller.GameController - New game state
10:50:52 DEBUG sc.gui.controller.GameController - Human move request
10:50:52 DEBUG sc.gui.controller.GameController - Number of possible moves: 185
10:50:52 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
10:50:52 DEBUG sc.gui.view.PiecesFragment - Color: GREEN
10:50:53 DEBUG sc.gui.view.UndeployedPiecesFragment - Clicked on GREEN PENTO_L
10:50:53 DEBUG sc.gui.view.PiecesFragment - Color: GREEN
Clicked on pane 16, 15
10:50:55 DEBUG sc.gui.controller.BoardController - Set-Move seems valid from GUI at 16, 15
10:50:55 DEBUG sc.gui.LobbyManager - lobby: new state for 22da4b1e-012f-4faa-a8e9-a0e565b69dee
10:50:55 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:55 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:55 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/8 -> BLUE 
10:50:55 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:55 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:55 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/8 -> BLUE 
10:50:55 DEBUG sc.gui.controller.GameController - New game state
10:50:55 DEBUG sc.gui.controller.GameController - New game state
10:50:55 DEBUG s.g.c.client.InternalGameHandler - Player One got new action request!
10:50:55 DEBUG sc.gui.LobbyManager - lobby: new state for 22da4b1e-012f-4faa-a8e9-a0e565b69dee
10:50:55 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:55 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:55 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/9 -> YELLOW 
10:50:55 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:55 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:55 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/9 -> YELLOW 
10:50:55 DEBUG s.g.c.client.InternalGameHandler - Player Two got new action request!
10:50:55 DEBUG sc.gui.controller.GameController - New game state
10:50:55 DEBUG sc.gui.controller.GameController - New game state
10:50:55 DEBUG sc.gui.controller.GameController - Human move request
10:50:55 DEBUG sc.gui.controller.GameController - Number of possible moves: 248
10:50:55 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
10:50:55 DEBUG sc.gui.view.PiecesFragment - Color: YELLOW
10:50:58 DEBUG sc.gui.view.UndeployedPiecesFragment - Clicked on YELLOW MONO
10:50:58 DEBUG sc.gui.view.PiecesFragment - Color: YELLOW
Clicked on pane 19, 2
10:50:59 DEBUG sc.gui.controller.BoardController - Set-Move seems valid from GUI at 19, 2
10:50:59 DEBUG sc.gui.LobbyManager - lobby: new state for 22da4b1e-012f-4faa-a8e9-a0e565b69dee
10:50:59 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:59 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:59 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/10 -> RED 
10:50:59 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:50:59 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:50:59 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/10 -> RED 
10:51:00 DEBUG s.g.c.client.InternalGameHandler - Player One got new action request!
10:51:00 DEBUG sc.gui.controller.GameController - New game state
10:51:00 DEBUG s.g.c.client.InternalGameHandler - Player Two got new action request!
10:51:00 DEBUG sc.gui.LobbyManager - lobby: new state for 22da4b1e-012f-4faa-a8e9-a0e565b69dee
10:51:00 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:51:00 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:51:00 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/11 -> GREEN 
10:51:00 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
10:51:00 DEBUG sc.gui.controller.ClientController - ClientController got new update
10:51:00 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/11 -> GREEN 
10:51:00 DEBUG sc.gui.controller.GameController - New game state
10:51:00 DEBUG sc.gui.controller.GameController - Human move request
10:51:00 DEBUG sc.gui.controller.GameController - Number of possible moves: 183
10:51:00 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
10:51:00 DEBUG sc.gui.controller.GameController - New game state
10:51:00 DEBUG sc.gui.controller.GameController - New game state
Clicked on pane 16, 5
10:51:09 DEBUG sc.gui.controller.BoardController - Set-Move seems invalid from GUI at 16, 5
Clicked on pane 13, 11
10:51:13 DEBUG sc.gui.controller.BoardController - Set-Move seems invalid from GUI at 13, 11
Clicked on pane 15, 8
10:51:15 DEBUG sc.gui.controller.BoardController - Set-Move seems invalid from GUI at 15, 8

[xeruf]

Again:

Log:

15:11:49 DEBUG sc.gui.controller.AppController - Requested View change from START -> GAME_CREATION
15:11:53 DEBUG sc.gui.controller.AppController - Requested View change from GAME_CREATION -> GAME
15:11:54 DEBUG sc.gui.view.PiecesFragment - Color: RED
15:11:54 DEBUG sc.gui.model.BoardModel - Blocksize changed 16.0 -> 0.0
15:11:54 DEBUG sc.gui.controller.ClientController - creating and observing
15:11:54 INFO  sc.networking.clients.LobbyClient - Creating TCP Network for localhost:13050
15:11:54 INFO  sc.server.network.NewClientListener - A Client connected...
15:11:54 DEBUG sc.gui.model.BoardModel - Blocksize changed 0.0 -> 24.0
15:11:54 INFO  sc.networking.clients.LobbyClient - Creating TCP Network for localhost:13050
15:11:54 INFO  sc.server.network.NewClientListener - Added Client sc.server.network.Client@34926085 to ReadyQueue.
15:11:54 INFO  sc.server.network.ClientManager - Delegating new client to ClientManager...
15:11:54 INFO  sc.server.network.ClientManager - Delegation done
15:11:54 INFO  sc.server.network.NewClientListener - A Client connected...
15:11:54 INFO  sc.server.network.NewClientListener - Added Client sc.server.network.Client@454aafaa to ReadyQueue.
15:11:54 INFO  sc.server.network.ClientManager - Delegating new client to ClientManager...
15:11:54 INFO  sc.server.network.ClientManager - Delegation done
15:11:54 INFO  sc.networking.clients.LobbyClient - Creating TCP Network for localhost:13050
15:11:54 INFO  sc.server.network.NewClientListener - A Client connected...
15:11:54 INFO  sc.server.network.NewClientListener - Added Client sc.server.network.Client@7389f566 to ReadyQueue.
15:11:54 INFO  sc.server.network.ClientManager - Delegating new client to ClientManager...
15:11:54 INFO  sc.server.network.ClientManager - Delegation done
Security framework of XStream not explicitly initialized, using predefined black list on your own risk.
15:11:54 INFO  sc.server.network.Client - Client authenticated as administrator
15:11:54 INFO  sc.server.gaming.GameRoomManager - Created new game of type swc_2021_blokus
15:11:54 WARN  sc.server.gaming.GameRoom - Dropped unnecessary PAUSE toggle from false to false.
15:11:54 DEBUG sc.gui.LobbyManager - lobby: game was prepared
15:11:54 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:11:54 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:11:54 DEBUG sc.gui.controller.ClientController - no controlling client yet
15:11:54 INFO  sc.server.gaming.ReservationManager - Reservation 89bfa2df-65a7-4d74-a784-2fe585412ecc was redeemed.
15:11:54 INFO  sc.server.gaming.GameRoom - Game isn't ready yet.
15:11:54 INFO  sc.server.gaming.ReservationManager - Reservation c1062668-d9d1-4ed7-a3f0-d21455072bfd was redeemed.
15:11:54 INFO  sc.server.gaming.GameRoom - Updating Status to ACTIVE (was: CREATED)
15:11:54 DEBUG sc.gui.LobbyManager - lobby: ec1183bb-777b-453c-b9cc-a03f4cd4ab14 game was observed
15:11:54 DEBUG s.g.c.client.InternalGameHandler - Player One got new action request!
15:11:54 INFO  sc.server.gaming.GameRoom - Started the game.
15:11:54 DEBUG sc.gui.LobbyManager - lobby: new state for ec1183bb-777b-453c-b9cc-a03f4cd4ab14
15:11:54 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:11:54 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:11:54 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/1 -> YELLOW (Start Piece: PENTO_R)
15:11:54 DEBUG s.g.c.client.InternalGameHandler - Player Two got new action request!
15:11:54 DEBUG sc.gui.controller.GameController - New game state
15:11:54 DEBUG sc.gui.controller.GameController - Human move request
15:11:54 DEBUG sc.gui.controller.GameController - Number of possible moves: 6
15:11:54 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
15:11:54 DEBUG sc.gui.view.PiecesFragment - Color: YELLOW
Clicked on pane 0, 17
15:11:57 DEBUG sc.gui.controller.BoardController - Set-Move seems valid from GUI at 0, 17
15:11:57 DEBUG sc.gui.LobbyManager - lobby: new state for ec1183bb-777b-453c-b9cc-a03f4cd4ab14
15:11:57 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:11:57 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:11:57 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/2 -> RED (Start Piece: PENTO_R)
15:11:57 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:11:57 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:11:57 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/2 -> RED (Start Piece: PENTO_R)
15:11:57 DEBUG s.g.c.client.InternalGameHandler - Player One got new action request!
15:11:57 DEBUG sc.gui.controller.GameController - New game state
15:11:57 DEBUG sc.gui.controller.GameController - New game state
15:11:57 DEBUG sc.gui.LobbyManager - lobby: new state for ec1183bb-777b-453c-b9cc-a03f4cd4ab14
15:11:57 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:11:57 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:11:57 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/3 -> GREEN (Start Piece: PENTO_R)
15:11:57 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:11:57 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:11:57 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/3 -> GREEN (Start Piece: PENTO_R)
15:11:57 DEBUG s.g.c.client.InternalGameHandler - Player Two got new action request!
15:11:57 DEBUG sc.gui.controller.GameController - New game state
15:11:57 DEBUG sc.gui.controller.GameController - New game state
15:11:57 DEBUG sc.gui.controller.GameController - Human move request
15:11:57 DEBUG sc.gui.controller.GameController - Number of possible moves: 2
15:11:57 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
15:11:57 DEBUG sc.gui.view.PiecesFragment - Color: GREEN
Clicked on pane 17, 0
15:12:06 DEBUG sc.gui.controller.BoardController - Set-Move seems valid from GUI at 17, 0
15:12:06 DEBUG sc.gui.LobbyManager - lobby: new state for ec1183bb-777b-453c-b9cc-a03f4cd4ab14
15:12:06 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:06 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:06 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/4 -> BLUE 
15:12:06 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:06 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:06 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/4 -> BLUE 
15:12:06 DEBUG s.g.c.client.InternalGameHandler - Player One got new action request!
15:12:06 DEBUG sc.gui.controller.GameController - New game state
15:12:06 DEBUG sc.gui.controller.GameController - New game state
15:12:06 DEBUG sc.gui.LobbyManager - lobby: new state for ec1183bb-777b-453c-b9cc-a03f4cd4ab14
15:12:06 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:06 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:06 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/5 -> YELLOW 
15:12:06 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:06 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:06 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/5 -> YELLOW 
15:12:06 DEBUG s.g.c.client.InternalGameHandler - Player Two got new action request!
15:12:06 DEBUG sc.gui.controller.GameController - New game state
15:12:06 DEBUG sc.gui.controller.GameController - New game state
15:12:06 DEBUG sc.gui.controller.GameController - Human move request
15:12:06 DEBUG sc.gui.controller.GameController - Number of possible moves: 237
15:12:06 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
15:12:06 DEBUG sc.gui.view.PiecesFragment - Color: YELLOW
Clicked on pane 2, 14
15:12:08 DEBUG sc.gui.controller.BoardController - Set-Move seems valid from GUI at 2, 14
15:12:08 DEBUG sc.gui.LobbyManager - lobby: new state for ec1183bb-777b-453c-b9cc-a03f4cd4ab14
15:12:08 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:08 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:08 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/6 -> RED 
15:12:08 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:08 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:08 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/6 -> RED 
15:12:08 DEBUG s.g.c.client.InternalGameHandler - Player One got new action request!
15:12:08 DEBUG sc.gui.controller.GameController - New game state
15:12:08 DEBUG sc.gui.controller.GameController - New game state
15:12:08 DEBUG sc.gui.LobbyManager - lobby: new state for ec1183bb-777b-453c-b9cc-a03f4cd4ab14
15:12:08 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:08 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:08 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/7 -> GREEN 
15:12:08 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:08 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:08 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/7 -> GREEN 
15:12:08 DEBUG s.g.c.client.InternalGameHandler - Player Two got new action request!
15:12:08 DEBUG sc.gui.controller.GameController - New game state
15:12:08 DEBUG sc.gui.controller.GameController - New game state
15:12:08 DEBUG sc.gui.controller.GameController - Human move request
15:12:08 DEBUG sc.gui.controller.GameController - Number of possible moves: 237
15:12:08 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
15:12:08 DEBUG sc.gui.view.PiecesFragment - Color: GREEN
15:12:11 DEBUG sc.gui.view.UndeployedPiecesFragment - Clicked on GREEN PENTO_P
15:12:11 DEBUG sc.gui.view.PiecesFragment - Color: GREEN
Clicked on pane 16, 3
15:12:12 DEBUG sc.gui.controller.BoardController - Set-Move seems valid from GUI at 16, 3
15:12:12 DEBUG s.g.c.client.InternalGameHandler - Player One got new action request!
15:12:12 DEBUG sc.gui.LobbyManager - lobby: new state for ec1183bb-777b-453c-b9cc-a03f4cd4ab14
15:12:12 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:12 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:12 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/8 -> BLUE 
15:12:12 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:12 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:12 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/8 -> BLUE 
15:12:12 DEBUG sc.gui.LobbyManager - lobby: new state for ec1183bb-777b-453c-b9cc-a03f4cd4ab14
15:12:12 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:12 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:12 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/9 -> YELLOW 
15:12:12 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:12 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:12 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/9 -> YELLOW 
15:12:12 DEBUG s.g.c.client.InternalGameHandler - Player Two got new action request!
15:12:12 DEBUG sc.gui.controller.GameController - New game state
15:12:12 DEBUG sc.gui.controller.GameController - New game state
15:12:12 DEBUG sc.gui.controller.GameController - New game state
15:12:12 DEBUG sc.gui.controller.GameController - New game state
15:12:12 DEBUG sc.gui.controller.GameController - Human move request
15:12:12 DEBUG sc.gui.controller.GameController - Number of possible moves: 290
15:12:12 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
15:12:12 DEBUG sc.gui.view.PiecesFragment - Color: YELLOW
Clicked on pane 4, 12
15:12:14 DEBUG sc.gui.controller.BoardController - Set-Move seems valid from GUI at 4, 12
15:12:14 DEBUG sc.gui.LobbyManager - lobby: new state for ec1183bb-777b-453c-b9cc-a03f4cd4ab14
15:12:14 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:14 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:14 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/10 -> RED 
15:12:14 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:14 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:14 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/10 -> RED 
15:12:14 DEBUG s.g.c.client.InternalGameHandler - Player One got new action request!
15:12:14 DEBUG sc.gui.controller.GameController - New game state
15:12:14 DEBUG s.g.c.client.InternalGameHandler - Player Two got new action request!
15:12:14 DEBUG sc.gui.LobbyManager - lobby: new state for ec1183bb-777b-453c-b9cc-a03f4cd4ab14
15:12:14 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:14 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:14 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/11 -> GREEN 
15:12:14 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
15:12:14 DEBUG sc.gui.controller.ClientController - ClientController got new update
15:12:14 DEBUG sc.gui.controller.ClientController - gamestate is GameState 3/11 -> GREEN 
15:12:14 DEBUG sc.gui.controller.GameController - New game state
15:12:14 DEBUG sc.gui.controller.GameController - Human move request
15:12:14 DEBUG sc.gui.controller.GameController - Number of possible moves: 313
15:12:14 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
15:12:14 DEBUG sc.gui.controller.GameController - New game state
15:12:14 DEBUG sc.gui.controller.GameController - New game state
Clicked on pane 8, 10
15:12:16 DEBUG sc.gui.controller.BoardController - Set-Move seems invalid from GUI at 8, 10

[xeruf]

Seems to be the same issue:

http://wollw.de/pus/sc21/pus_client-Mensch.mp4
Kai: Unser Client, manuel gestartet gegen Mensch: Von einem bestimmten Moment an bekommt der Mensch nicht mehr die aktuelle Farbe zur Auswahl und kann deshalb nicht mehr setzen. Dieser Problem tritt nicht auf, wenn der Beispiel-Computerspieler gegen Mensch spielt.

http://wollw.de/pus/sc21/server_log.txt
http://wollw.de/pus/sc21/server_err_log.txt

[xeruf]

Can't reproduce with latest backend (software-challenge/backend@727d7c9) and GUI 21.2.1

Was able to reproduce it again with GUI 21.2.1, but only once over multiple iterations

[SKoschnicke]

Human vs. manually started client (ruby client)

With GUI 21.2.1 and backend 727d7c9

Reproduced on first try, so seems to be still there.

[xeruf]

ah I see, may be only with a manually started client

[xeruf]

A log:

14:15:13 INFO  sc.server.network.NewClientListener - A Client connected...
14:15:13 INFO  sc.server.network.ClientManager - Delegating new client to ClientManager...
14:15:13 INFO  sc.server.network.NewClientListener - Added Client Client@70a93d15 to ReadyQueue.
14:15:13 INFO  sc.server.network.ClientManager - Delegation done
14:15:13 INFO  sc.server.gaming.GameRoomManager - Created new game of type swc_2021_blokus
14:15:13 WARN  sc.server.gaming.GameRoom - PAUSE is already false, dropping request
14:15:13 INFO  sc.server.gaming.GameRoomManager - Pause is set to false
14:15:13 INFO  sc.server.gaming.GameRoom - Game not ready yet: Game(players=[Team 1(Unknown)], gameState=GameState 1/0 -> Farbe Blau (Start Piece: PENTO_V))
14:15:13 DEBUG sc.gui.LobbyManager - LobbyManager started room caced53c-52a9-4bbd-bf4f-334fc6063fc1
14:15:13 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
14:15:13 DEBUG sc.gui.controller.ClientController - ClientController got new update
14:15:13 DEBUG sc.gui.controller.ClientController - no controlling client yet
14:15:13 DEBUG sc.gui.LobbyManager - lobby: caced53c-52a9-4bbd-bf4f-334fc6063fc1 game was joined ({caced53c-52a9-4bbd-bf4f-334fc6063fc1=1})
14:15:13 DEBUG sc.gui.LobbyManager - lobby: caced53c-52a9-4bbd-bf4f-334fc6063fc1 game was observed
14:15:13 INFO  sc.server.gaming.GameRoom - Updating Status to ACTIVE (was: CREATED)
14:15:13 DEBUG sc.gui.LobbyManager - lobby: new state for caced53c-52a9-4bbd-bf4f-334fc6063fc1
14:15:13 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
14:15:13 DEBUG sc.gui.controller.ClientController - ClientController got new update
14:15:13 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/0 -> Farbe Blau (Start Piece: PENTO_V)
14:15:13 INFO  sc.server.gaming.GameRoom - Started Game(players=[Team 1(Unknown), Team 2(Unknown)], gameState=GameState 1/0 -> Farbe Blau (Start Piece: PENTO_V))
14:15:13 DEBUG sc.gui.LobbyManager - lobby: caced53c-52a9-4bbd-bf4f-334fc6063fc1 game was joined ({caced53c-52a9-4bbd-bf4f-334fc6063fc1=2})
14:15:13 DEBUG sc.gui.controller.AppController - Requested View change from GAME_LOADING -> GAME
14:15:13 INFO  sc.framework.plugins.AbstractGame - Time needed for move: 25
14:15:13 DEBUG sc.gui.LobbyManager - lobby: new state for caced53c-52a9-4bbd-bf4f-334fc6063fc1
14:15:13 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
14:15:13 DEBUG sc.gui.controller.ClientController - ClientController got new update
14:15:13 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/1 -> Farbe Gelb (Start Piece: PENTO_V)
14:15:13 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
14:15:13 DEBUG sc.gui.controller.ClientController - ClientController got new update
14:15:13 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/1 -> Farbe Gelb (Start Piece: PENTO_V)
14:15:13 DEBUG s.g.c.client.InternalGameHandler - Player Team 2 got new action request!
14:15:13 DEBUG sc.gui.model.BoardModel - Blocksize changed 16.0 -> 0.0
14:15:14 DEBUG sc.gui.controller.GameController - New game state
14:15:14 DEBUG sc.gui.controller.GameController - New game state
14:15:14 DEBUG sc.gui.controller.GameController - Human move request for Farbe Gelb
14:15:14 DEBUG sc.gui.controller.GameController - Number of possible moves: 9
14:15:14 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
14:15:14 DEBUG sc.gui.model.BoardModel - Blocksize changed 0.0 -> 30.725
Clicked on pane 17, 17
14:15:16 DEBUG sc.gui.controller.BoardController - Set-Move seems valid from GUI at 17, 17
14:15:16 INFO  sc.framework.plugins.AbstractGame - Time needed for move: 2710
14:15:16 DEBUG sc.gui.LobbyManager - lobby: new state for caced53c-52a9-4bbd-bf4f-334fc6063fc1
14:15:16 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
14:15:16 DEBUG sc.gui.controller.ClientController - ClientController got new update
14:15:16 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/2 -> Farbe Rot (Start Piece: PENTO_V)
14:15:16 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
14:15:16 DEBUG sc.gui.controller.ClientController - ClientController got new update
14:15:16 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/2 -> Farbe Rot (Start Piece: PENTO_V)
14:15:16 DEBUG sc.gui.controller.GameController - New game state
14:15:16 DEBUG sc.gui.controller.GameController - New game state
14:15:16 INFO  sc.framework.plugins.AbstractGame - Time needed for move: 4
14:15:16 DEBUG sc.gui.LobbyManager - lobby: new state for caced53c-52a9-4bbd-bf4f-334fc6063fc1
14:15:16 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
14:15:16 DEBUG sc.gui.controller.ClientController - ClientController got new update
14:15:16 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/3 -> Farbe Grün (Start Piece: PENTO_V)
14:15:16 DEBUG sc.gui.controller.GameController - New game state
14:15:16 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
14:15:16 DEBUG sc.gui.controller.ClientController - ClientController got new update
14:15:16 DEBUG sc.gui.controller.ClientController - gamestate is GameState 1/3 -> Farbe Grün (Start Piece: PENTO_V)
14:15:16 DEBUG sc.gui.controller.GameController - New game state
14:15:16 DEBUG s.g.c.client.InternalGameHandler - Player Team 2 got new action request!
14:15:16 DEBUG sc.gui.controller.GameController - Human move request for Farbe Grün
14:15:16 DEBUG sc.gui.controller.GameController - Number of possible moves: 3
14:15:16 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
Clicked on pane 17, 0
14:15:17 DEBUG sc.gui.controller.BoardController - Set-Move seems valid from GUI at 17, 0
14:15:17 INFO  sc.framework.plugins.AbstractGame - Time needed for move: 1540
14:15:17 DEBUG sc.gui.LobbyManager - lobby: new state for caced53c-52a9-4bbd-bf4f-334fc6063fc1
14:15:17 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
14:15:17 DEBUG sc.gui.controller.ClientController - ClientController got new update
14:15:17 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/4 -> Farbe Blau 
14:15:17 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
14:15:17 DEBUG sc.gui.controller.ClientController - ClientController got new update
14:15:17 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/4 -> Farbe Blau 
14:15:17 DEBUG sc.gui.controller.GameController - New game state
14:15:17 DEBUG sc.gui.controller.GameController - New game state
14:15:17 INFO  sc.framework.plugins.AbstractGame - Time needed for move: 36
14:15:18 DEBUG sc.gui.LobbyManager - lobby: new state for caced53c-52a9-4bbd-bf4f-334fc6063fc1
14:15:18 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
14:15:18 DEBUG s.g.c.client.InternalGameHandler - Player Team 2 got new action request!
14:15:18 DEBUG sc.gui.controller.ClientController - ClientController got new update
14:15:18 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/5 -> Farbe Gelb 
14:15:18 DEBUG sc.gui.controller.ClientController - game listener: onUpdate
14:15:18 DEBUG sc.gui.controller.ClientController - ClientController got new update
14:15:18 DEBUG sc.gui.controller.ClientController - gamestate is GameState 2/5 -> Farbe Gelb 
14:15:18 DEBUG sc.gui.controller.GameController - Human move request for Farbe Gelb
14:15:18 DEBUG sc.gui.controller.GameController - Number of possible moves: 185
14:15:18 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
14:15:18 DEBUG sc.gui.controller.GameController - New game state
14:15:18 DEBUG sc.gui.controller.GameController - New game state

(Don't mind the game end message)

[xeruf]

Interesting debug output:

8:36:47 DEBUG sc.gui.controller.GameController - Human move request for Farbe Gelb
18:36:47 DEBUG sc.gui.controller.GameController - Number of possible moves: 379
18:36:47 DEBUG sc.gui.controller.BoardController - Calculating isPlaceableBoard...
18:36:47 DEBUG sc.gui.controller.GameController - New game state
18:36:47 DEBUG sc.gui.view.UndeployedPiecesFragment - Current color Farbe Gelb can place []
18:36:47 DEBUG sc.gui.controller.GameController - New game state
Clicked on pane 4, 1

[MartinRe63]

I've the same issue very easy - Want to understand the game playing human vs. human. The first 5 moves are fine, but with the 6th move I can not select a yellow color. The gui still offers a blue piece to play.