Ich habe mal entsprechende logs aufgezeichnet. Dafür habe ich den Code folgendermaßen verändert:

    admin.send(new StepRequest(room.getId()));
    // Wait for second players turn
    System.out.println("waiting for p2Listener.playerEventReceived");
    TestHelper.waitUntilTrue(() -> p2Listener.playerEventReceived, 200000000);
    System.out.println("received p2Listener.playerEventReceived");

Wenn der Test erfolgreich war:

    waiting for p2Listener.playerEventReceived
    15:29:49.823 [XStreamClient Receive Thread 17 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@4526f47d: Received RoomPacket{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, data=sc.protocol.responses.MementoPacket@58bf3be6} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=39387,localport=51966]}
    15:29:49.824 [XStreamClient Receive Thread 16 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@3a87a771: Sending RoomPacket{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, data=sc.protocol.responses.MementoPacket@6eb1b592} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=51968,localport=39387]}
    15:29:49.827 [XStreamClient Receive Thread 19 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@420efaa4: Received RoomPacket{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, data=sc.protocol.responses.MementoPacket@37032478} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=39387,localport=51968]}
    Player: RED requested a move
    15:29:49.852 [XStreamClient Receive Thread 16 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@2bb4554f: Sending RoomPacket{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, data=sc.server.plugins.TestTurnRequest@51986a6e} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=51966,localport=39387]}
    15:29:49.853 [XStreamClient Receive Thread 17 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@4526f47d: Received RoomPacket{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, data=sc.server.plugins.TestTurnRequest@11cf94c5} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=39387,localport=51966]}
    15:29:49.853 [XStreamClient Receive Thread 18 Client] DEBUG s.f.plugins.RoundBasedGameInstance - next round (0) for player sc.server.plugins.TestPlayer@38596191
    15:29:49.853 [XStreamClient Receive Thread 16 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@6eb6c6fd: Received StepRequest{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, forced=false} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=51964,localport=39387]}
    15:29:49.853 [XStreamClient Receive Thread 16 Client] INFO  sc.server.gaming.GameRoom - Stepping.
    15:29:49.853 [XStreamClient Receive Thread 16 Client] INFO  s.f.plugins.RoundBasedGameInstance - Sending MoveRequest to player sc.server.plugins.TestPlayer@38596191.
    15:29:49.853 [XStreamClient Receive Thread 16 Client] DEBUG s.f.plugins.RoundBasedGameInstance - notifying sc.server.gaming.GameRoom@4e7ac47 about new game state
    15:29:49.854 [XStreamClient Receive Thread 16 Client] DEBUG sc.server.gaming.GameRoom - sending state to observer sc.server.network.Client@6eb6c6fd
    15:29:49.854 [XStreamClient Receive Thread 16 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@6eb6c6fd: Sending RoomPacket{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, data=sc.protocol.responses.MementoPacket@79b78f82} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=51964,localport=39387]}
    15:29:49.855 [XStreamClient Receive Thread 16 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@2bb4554f: Sending RoomPacket{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, data=sc.protocol.responses.MementoPacket@cb97190} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=51966,localport=39387]}
    15:29:49.855 [XStreamClient Receive Thread 15 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@608dbf33: Received RoomPacket{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, data=sc.protocol.responses.MementoPacket@19c7582b} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=39387,localport=51964]}
    15:29:49.856 [XStreamClient Receive Thread 15 LobbyClient] DEBUG s.networking.clients.ObservingClient - sc.networking.clients.ObservingClient@59d4dc00 got new state
    15:29:49.856 [XStreamClient Receive Thread 15 LobbyClient] DEBUG s.networking.clients.ObservingClient - sc.networking.clients.ObservingClient@59d4dc00 saved observation class sc.server.plugins.TestGameState
    15:29:49.856 [XStreamClient Receive Thread 15 LobbyClient] DEBUG s.networking.clients.ObservingClient - Setting Position to 1 (requested 1)
    15:29:49.856 [XStreamClient Receive Thread 17 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@4526f47d: Received RoomPacket{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, data=sc.protocol.responses.MementoPacket@6514a619} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=39387,localport=51966]}
    15:29:49.856 [XStreamClient Receive Thread 16 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@3a87a771: Sending RoomPacket{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, data=sc.protocol.responses.MementoPacket@1f72aae1} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=51968,localport=39387]}
    15:29:49.858 [XStreamClient Receive Thread 19 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@420efaa4: Received RoomPacket{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, data=sc.protocol.responses.MementoPacket@c35b908} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=39387,localport=51968]}
    Player: BLUE requested a move
    15:29:49.879 [XStreamClient Receive Thread 16 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@3a87a771: Sending RoomPacket{roomId=2cec3c1f-1a23-4c79-8ade-1c238890dd8b, data=sc.server.plugins.TestTurnRequest@7d2d4474} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=51968,localport=39387]}
    received p2Listener.playerEventReceived

    waiting for p2Listener.playerEventReceived
    Player: RED requested a move
    15:29:36.364 [XStreamClient Receive Thread 15 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@2c9b29d3: Sending RoomPacket{roomId=0f65e2a5-983e-4982-a3c1-6a9f5d8b85e2, data=sc.server.plugins.TestTurnRequest@69b98a83} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=47100,localport=42277]}
    15:29:36.366 [XStreamClient Receive Thread 18 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@745cb7ff: Received RoomPacket{roomId=0f65e2a5-983e-4982-a3c1-6a9f5d8b85e2, data=sc.server.plugins.TestTurnRequest@d9c029f} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=42277,localport=47100]}
    15:29:36.366 [XStreamClient Receive Thread 17 Client] DEBUG s.f.plugins.RoundBasedGameInstance - next round (0) for player sc.server.plugins.TestPlayer@16c3e0d8
    15:29:36.367 [XStreamClient Receive Thread 15 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@2be4591d: Received StepRequest{roomId=0f65e2a5-983e-4982-a3c1-6a9f5d8b85e2, forced=false} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=47098,localport=42277]}
    15:29:36.367 [XStreamClient Receive Thread 15 Client] INFO  sc.server.gaming.GameRoom - Stepping.
    15:29:36.367 [XStreamClient Receive Thread 15 Client] INFO  s.f.plugins.RoundBasedGameInstance - Sending MoveRequest to player sc.server.plugins.TestPlayer@16c3e0d8.
    15:29:36.367 [XStreamClient Receive Thread 15 Client] DEBUG s.f.plugins.RoundBasedGameInstance - notifying sc.server.gaming.GameRoom@5729262e about new game state
    15:29:36.367 [XStreamClient Receive Thread 15 Client] DEBUG sc.server.gaming.GameRoom - sending state to observer sc.server.network.Client@2be4591d
    15:29:36.367 [XStreamClient Receive Thread 15 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@2be4591d: Sending RoomPacket{roomId=0f65e2a5-983e-4982-a3c1-6a9f5d8b85e2, data=sc.protocol.responses.MementoPacket@1c4375c5} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=47098,localport=42277]}
    15:29:36.369 [XStreamClient Receive Thread 15 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@2c9b29d3: Sending RoomPacket{roomId=0f65e2a5-983e-4982-a3c1-6a9f5d8b85e2, data=sc.protocol.responses.MementoPacket@6cb6284a} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=47100,localport=42277]}
    15:29:36.369 [XStreamClient Receive Thread 16 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@571111d: Received RoomPacket{roomId=0f65e2a5-983e-4982-a3c1-6a9f5d8b85e2, data=sc.protocol.responses.MementoPacket@7af76ecf} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=42277,localport=47098]}
    15:29:36.370 [XStreamClient Receive Thread 16 LobbyClient] DEBUG s.networking.clients.ObservingClient - sc.networking.clients.ObservingClient@4bc75d7d got new state
    15:29:36.370 [XStreamClient Receive Thread 16 LobbyClient] DEBUG s.networking.clients.ObservingClient - sc.networking.clients.ObservingClient@4bc75d7d saved observation class sc.server.plugins.TestGameState
    15:29:36.370 [XStreamClient Receive Thread 16 LobbyClient] DEBUG s.networking.clients.ObservingClient - Setting Position to 1 (requested 1)
    15:29:36.370 [XStreamClient Receive Thread 18 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@745cb7ff: Received RoomPacket{roomId=0f65e2a5-983e-4982-a3c1-6a9f5d8b85e2, data=sc.protocol.responses.MementoPacket@7c6c36f3} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=42277,localport=47100]}
    15:29:36.371 [XStreamClient Receive Thread 15 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@52955f33: Sending RoomPacket{roomId=0f65e2a5-983e-4982-a3c1-6a9f5d8b85e2, data=sc.protocol.responses.MementoPacket@79ac84b5} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=47102,localport=42277]}
    15:29:36.374 [XStreamClient Receive Thread 19 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@29636857: Received RoomPacket{roomId=0f65e2a5-983e-4982-a3c1-6a9f5d8b85e2, data=sc.protocol.responses.MementoPacket@35bbbcb9} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=42277,localport=47102]}
    Player: BLUE requested a move
    15:29:36.402 [XStreamClient Receive Thread 15 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@52955f33: Sending RoomPacket{roomId=0f65e2a5-983e-4982-a3c1-6a9f5d8b85e2, data=sc.server.plugins.TestTurnRequest@aba8fad} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=47102,localport=42277]}
    15:29:36.403 [XStreamClient Receive Thread 19 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@29636857: Received RoomPacket{roomId=0f65e2a5-983e-4982-a3c1-6a9f5d8b85e2, data=sc.server.plugins.TestTurnRequest@3f0cfcd6} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=42277,localport=47102]}
    received p2Listener.playerEventReceived

Wenn der test fehlgeschlagen ist:

    waiting for p2Listener.playerEventReceived
    Player: RED requested a move
    15:29:56.433 [XStreamClient Receive Thread 15 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@512cb268: Sending RoomPacket{roomId=c936150e-aed2-4b84-a801-ee2182e3dde1, data=sc.server.plugins.TestTurnRequest@5d359776} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=53384,localport=40937]}
    15:29:56.434 [XStreamClient Receive Thread 15 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@2d20a6d6: Received StepRequest{roomId=c936150e-aed2-4b84-a801-ee2182e3dde1, forced=false} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=53382,localport=40937]}
    15:29:56.434 [XStreamClient Receive Thread 17 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@415be381: Received RoomPacket{roomId=c936150e-aed2-4b84-a801-ee2182e3dde1, data=sc.server.plugins.TestTurnRequest@c35b908} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=40937,localport=53384]}
    15:29:56.434 [XStreamClient Receive Thread 15 Client] INFO  sc.server.gaming.GameRoom - Stepping.
    15:29:56.435 [XStreamClient Receive Thread 15 Client] INFO  s.f.plugins.RoundBasedGameInstance - Sending MoveRequest to player sc.server.plugins.TestPlayer@62f6a412.
    15:29:56.435 [XStreamClient Receive Thread 15 Client] DEBUG s.f.plugins.RoundBasedGameInstance - notifying sc.server.gaming.GameRoom@77a00c64 about new game state
    15:29:56.435 [XStreamClient Receive Thread 15 Client] DEBUG sc.server.gaming.GameRoom - sending state to observer sc.server.network.Client@2d20a6d6
    15:29:56.435 [XStreamClient Receive Thread 15 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@2d20a6d6: Sending RoomPacket{roomId=c936150e-aed2-4b84-a801-ee2182e3dde1, data=sc.protocol.responses.MementoPacket@2745e903} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=53382,localport=40937]}
    15:29:56.436 [XStreamClient Receive Thread 15 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@512cb268: Sending RoomPacket{roomId=c936150e-aed2-4b84-a801-ee2182e3dde1, data=sc.protocol.responses.MementoPacket@584dbe9c} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=53384,localport=40937]}
    15:29:56.437 [XStreamClient Receive Thread 16 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@4d30a845: Received RoomPacket{roomId=c936150e-aed2-4b84-a801-ee2182e3dde1, data=sc.protocol.responses.MementoPacket@60cfad2a} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=40937,localport=53382]}
    15:29:56.438 [XStreamClient Receive Thread 17 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@415be381: Received RoomPacket{roomId=c936150e-aed2-4b84-a801-ee2182e3dde1, data=sc.protocol.responses.MementoPacket@79e8acc4} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=40937,localport=53384]}
    15:29:56.439 [XStreamClient Receive Thread 16 LobbyClient] DEBUG s.networking.clients.ObservingClient - sc.networking.clients.ObservingClient@5851a661 got new state
    15:29:56.439 [XStreamClient Receive Thread 15 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@5560f950: Sending RoomPacket{roomId=c936150e-aed2-4b84-a801-ee2182e3dde1, data=sc.protocol.responses.MementoPacket@7d65eec0} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=53388,localport=40937]}
    15:29:56.439 [XStreamClient Receive Thread 16 LobbyClient] DEBUG s.networking.clients.ObservingClient - sc.networking.clients.ObservingClient@5851a661 saved observation class sc.server.plugins.TestGameState
    15:29:56.440 [XStreamClient Receive Thread 16 LobbyClient] DEBUG s.networking.clients.ObservingClient - Setting Position to 1 (requested 1)
    15:29:56.440 [XStreamClient Receive Thread 19 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@6f47712b: Received RoomPacket{roomId=c936150e-aed2-4b84-a801-ee2182e3dde1, data=sc.protocol.responses.MementoPacket@5dc39320} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=40937,localport=53388]}
    Player: RED requested a move
    15:29:56.464 [XStreamClient Receive Thread 15 Client] DEBUG sc.networking.clients.XStreamClient - Client sc.server.network.Client@512cb268: Sending RoomPacket{roomId=c936150e-aed2-4b84-a801-ee2182e3dde1, data=sc.server.plugins.TestTurnRequest@7456fea0} via TcpNetwork{socket=Socket[addr=/127.0.0.1,port=53384,localport=40937]}
    15:29:56.465 [XStreamClient Receive Thread 17 LobbyClient] DEBUG sc.networking.clients.XStreamClient - Client sc.networking.clients.LobbyClient@415be381: Received RoomPacket{roomId=c936150e-aed2-4b84-a801-ee2182e3dde1, data=sc.server.plugins.TestTurnRequest@39b5dae1} via TcpNetwork{socket=Socket[addr=localhost/127.0.0.1,port=40937,localport=53384]}
    15:29:56.465 [XStreamClient Receive Thread 18 Client] DEBUG s.f.plugins.RoundBasedGameInstance - next round (0) for player sc.server.plugins.TestPlayer@4d216c1a

Unzuverlässige Tests #124

Description

Metadata

Metadata

Assignees

Labels

Type

Projects

Milestone

Relationships

Development

Issue actions