In 4.16 I was using the FTextCrash unit test as a basis for a multiple minimal client test following the instruction in this AnswerHub post
Everything seemed to work until I took this knowledge to 4.17. It appears without modification the FTextCrash Unit test doesn’t function any longer, and other network related unit tests also fail. I’m attempting to use the 4.17 ShooterGame project as a base with the only modification being an new level ‘UnitTestWorld’ instead of ‘Sanctuary’.
Is this known, what can I do to debug it further?
Command:
UE4Editor.exe ShooterGame -run=NetcodeUnitTest.UnitTestCommandlet -UnitTest=FTextCrash -UnitTestNoAutoClose -Log=FTextCrash.txt
Error:
[SERVER][2017.08.22-21.30.41:312][ 0]LogInit: Display: Starting Game.
[SERVER][2017.08.22-21.30.41:382][ 0]LogPackageName: SearchForPackageOnDisk took 0.070s to resolve UnitTestWorld.umap.
[SERVER][2017.08.22-21.30.41:383][ 0]LogNet: Browse: /Game/Maps/UnitTestWorld?Name=Player?bIsLanMatch
[SERVER][2017.08.22-21.30.41:393][ 0]LogLoad: LoadMap: /Game/Maps/UnitTestWorld?Name=Player?bIsLanMatch
[SERVER][2017.08.22-21.30.41:436][ 0]LogMemory: Platform Memory Stats for Windows
[SERVER][2017.08.22-21.30.41:443][ 0]LogMemory: Process Physical Memory: 833.57 MB used, 833.57 MB peak
[SERVER][2017.08.22-21.30.41:453][ 0]LogMemory: Process Virtual Memory: 742.34 MB used, 743.48 MB peak
[SERVER][2017.08.22-21.30.41:463][ 0]LogMemory: Physical Memory: 9810.05 MB used, 6474.16 MB free, 16284.21 MB total
[SERVER][2017.08.22-21.30.41:473][ 0]LogMemory: Virtual Memory: 1305.95 MB used, 6474.16 MB free, 8388608.00 MB total
[SERVER][2017.08.22-21.30.41:483][ 0]LogMemory:
[SERVER][2017.08.22-21.30.41:493][ 0]Allocator Stats for TBB: (not implemented)
[SERVER][2017.08.22-21.30.41:634][ 0]LogAIModule: Creating AISystem for world UnitTestWorld
[SERVER][2017.08.22-21.30.41:643][ 0]LogLoad: Game class is 'ShooterGame_FreeForAll'
[SERVER][2017.08.22-21.30.41:705][ 0]LogOnline: STEAM: [AppId: 0] Game Server API initialized 0
[SERVER][2017.08.22-21.30.41:713][ 0]LogOnline: Warning: STEAM: Failed to initialize Steam, this could be due to a Steam server and client running on the same machine. Try running with -NOSTEAM on the cmdline to disable.
[SERVER][2017.08.22-21.30.41:723][ 0]LogOnline: Display: STEAM: OnlineSubsystemSteam::Shutdown()
[SERVER][2017.08.22-21.30.41:733][ 0]LogOnline: Warning: STEAM: Steam API failed to initialize!
[SERVER][2017.08.22-21.30.41:743][ 0]LogOnline: Display: STEAM: OnlineSubsystemSteam::Shutdown()
[SERVER][2017.08.22-21.30.41:753][ 0]LogTemp: Display: ParseSettings for GameNetDriver
[SERVER][2017.08.22-21.30.41:763][ 0]LogTemp: Display: ParseSettings for IpNetDriver_0
[SERVER][2017.08.22-21.30.41:773][ 0]LogTemp: Display: ParseSettings for GameNetDriver
[SERVER][2017.08.22-21.30.41:783][ 0]LogInit: WinSock: Socket queue 131072 / 131072
[SERVER][2017.08.22-21.30.41:795][ 0]LogInit: WinSock: I am US2-WW-152 (10.175.58.46:0)
[SERVER][2017.08.22-21.30.42:163][ 0]PacketHandlerLog: Loaded PacketHandler component: Engine.EngineHandlerComponentFactory (StatelessConnectHandlerComponent)
[SERVER][2017.08.22-21.30.42:173][ 0]LogNet: GameNetDriver IpNetDriver_0 IpNetDriver listening on port 7837
[SERVER][2017.08.22-21.30.42:184][ 0]LogWorld: Bringing World /Game/Maps/UnitTestWorld.UnitTestWorld up for play (max tick rate 30) at 2017.08.22-16.30.42
[SERVER][2017.08.22-21.30.42:193][ 0]LogNet: Spawning: /Script/NetcodeUnitTest.NUTActor
[SERVER][2017.08.22-21.30.42:203][ 0]LogOnline: Warning: OSS: AutoLogin missing AUTH_LOGIN=<login id>.
[SERVER][2017.08.22-21.30.42:213][ 0]LogNetVersion: ShooterGame 1.0.0.0, NetCL: 0, EngineNetVer: 2, GameNetVer: 0 (Checksum: 2729408564)
[SERVER][2017.08.22-21.30.42:233][ 0]LogWorld: Bringing up level for play took: 0.049876
[2017.08.22-21.30.42:254][ 0]LogUnitTest: Detected successful server startup, launching fake client.
[2017.08.22-21.30.42:254][ 0]LogUnitTest: Detected successful server startup, launching fake client.
[2017.08.22-21.30.42:257][ 0]LogUnitTest: Warning: Failed to find HookOrigin in active unit test list
[2017.08.22-21.30.42:259][ 0]LogUnitTest: Warning: Failed to find HookOrigin in active unit test list
[2017.08.22-21.30.42:261][ 0]LogTemp: Display: ParseSettings for GameNetDriver
[2017.08.22-21.30.42:261][ 0]LogTemp: Display: ParseSettings for UnitTestNetDriver_0
[2017.08.22-21.30.42:263][ 0]LogTemp: Display: ParseSettings for UnitTestNetDriver_0
[2017.08.22-21.30.42:264][ 0]LogUnitTest: CreateUnitTestNetDriver: Created named net driver: UnitTestNetDriver /Engine/Transient.UnitTestNetDriver_0, NetDriverName: UnitTestNetDriver_0, for World: World /Temp/Untitled_2.Untitled
[2017.08.22-21.30.42:267][ 0]LogInit: WinSock: Socket queue 32768 / 32768
[2017.08.22-21.30.42:272][ 0]LogInit: WinSock: I am US2-WW-152 (10.175.58.46:0)
[2017.08.22-21.30.42:273][ 0]PacketHandlerLog: Loaded PacketHandler component: Engine.EngineHandlerComponentFactory (StatelessConnectHandlerComponent)
[2017.08.22-21.30.42:274][ 0]LogNet: Game client on port 7837, rate 10000
[2017.08.22-21.30.42:275][ 0]LogUnitTest: Successfully created minimal client connection to IP '127.0.0.1:7837'
[2017.08.22-21.30.42:276][ 0]LogUnitTest: Successfully created minimal client connection to IP '127.0.0.1:7837'
[2017.08.22-21.30.42:437][ 0]LogVoiceEncode: Display: EncoderVersion: libopus 1.1-beta
[SERVER][2017.08.22-21.30.42:243][ 0]LogGameMode: Display: Match State Changed from EnteringMap to WaitingToStart
[SERVER][2017.08.22-21.30.42:253][ 0]LogBlueprintUserMessages: [UnitTestWorld_C_0] UnitTestWorld BeginPlay
[SERVER][2017.08.22-21.30.42:263][ 0]LogGameState: Match State Changed from EnteringMap to WaitingToStart
[SERVER][2017.08.22-21.30.42:273][ 0]LogLoad: Took 0.879941 seconds to LoadMap(/Game/Maps/UnitTestWorld)
[SERVER][2017.08.22-21.30.42:283][ 0]LogOnline: GotoState: NewState: Playing
[SERVER][2017.08.22-21.30.42:436][ 0]LogLoad: (Engine Initialization) Total time: 13.69 seconds
[SERVER][2017.08.22-21.30.42:443][ 0]LogLoad: (Engine Initialization) Total Blueprint compile time: 0.00 seconds
[SERVER][2017.08.22-21.30.42:453][ 0]LogExternalProfiler: No external profilers were discovered. External profiling features will not be available.
[SERVER][2017.08.22-21.30.42:534][ 0]LogNet: NotifyAcceptingConnection accepted from: 127.0.0.1:58064
[SERVER][2017.08.22-21.30.42:543][ 0]LogHandshake: SendConnectChallenge. Timestamp: 0.032858, Cookie: 252088035183019130251125147036172119094040222088215214100231
[2017.08.22-21.30.42:563][ 0]LogHandshake: SendChallengeResponse. Timestamp: 0.032858, Cookie: 252088035183019130251125147036172119094040222088215214100231
[SERVER][2017.08.22-21.30.42:580][ 1]LogNet: NotifyAcceptingConnection accepted from: 127.0.0.1:58064
[SERVER][2017.08.22-21.30.42:583][ 1]LogHandshake: SendChallengeAck. InCookie: 252088035183019130251125147036172119094040222088215214100231
[2017.08.22-21.30.42:595][ 0]LogNetVersion: ShooterGame 1.0.0.0, NetCL: 0, EngineNetVer: 2, GameNetVer: 0 (Checksum: 2729408564)
[SERVER][2017.08.22-21.30.42:593][ 1]LogNet: Server accepting post-challenge connection from: 127.0.0.1:58064
[SERVER][2017.08.22-21.30.42:603][ 1]PacketHandlerLog: Loaded PacketHandler component: Engine.EngineHandlerComponentFactory (StatelessConnectHandlerComponent)
[SERVER][2017.08.22-21.30.42:613][ 1]LogNet: NotifyAcceptedConnection: Name: UnitTestWorld, TimeStamp: 08/22/17 16:30:42, [UNetConnection] RemoteAddr: 127.0.0.1:58064, Name: IpConnection_0, Driver: GameNetDriver IpNetDriver_0, IsServer: YES, PC: NULL, Owner: NULL
[SERVER][2017.08.22-21.30.42:623][ 1]LogNet: AddClientConnection: Added client connection: [UNetConnection] RemoteAddr: 127.0.0.1:58064, Name: IpConnection_0, Driver: GameNetDriver IpNetDriver_0, IsServer: YES, PC: NULL, Owner: NULL
[SERVER][2017.08.22-21.30.42:633][ 1]LogNet: NotifyAcceptingChannel Control 0 server World /Game/Maps/UnitTestWorld.UnitTestWorld: Accepted
[SERVER][2017.08.22-21.30.42:643][ 1]LogNet: Remote platform little endian=1
[SERVER][2017.08.22-21.30.42:653][ 1]LogNet: This platform little endian=1
[SERVER][2017.08.22-21.30.42:663][ 1]LogNetSerialization: Error: FBitReader::SetOverflowed() called! (ReadLen: 32, Remaining: 8, Max: 224)
[SERVER][2017.08.22-21.30.42:673][ 1]LogNet: Login request: userId: Dud
[SERVER][2017.08.22-21.30.42:683][ 1]LogNet: Error: Failed to read control channel message 'Login'
[SERVER][2017.08.22-21.30.42:693][ 1]LogNet: Error: UControlChannel::ReceivedBunch: Failed to read control channel message
[SERVER][2017.08.22-21.30.42:703][ 1]LogNet: UNetConnection::Close: [UNetConnection] RemoteAddr: 127.0.0.1:58064, Name: IpConnection_0, Driver: GameNetDriver IpNetDriver_0, IsServer: YES, PC: NULL, Owner: NULL, Channels: 2, Time: 2017.08.22-21.30.42
[SERVER][2017.08.22-21.30.42:713][ 1]LogNet: UChannel::Close: Sending CloseBunch. ChIndex == 0. Name: [UChannel] ChIndex: 0, Closing: 0 [UNetConnection] RemoteAddr: 127.0.0.1:58064, Name: IpConnection_0, Driver: GameNetDriver IpNetDriver_0, IsServer: YES, PC: NULL, Owner: NULL
[2017.08.22-21.30.42:724][ 0]LogNet: UChannel::ReceivedSequencedBunch: Bunch.bClose == true. ChIndex == 0. Calling ConditionalCleanUp.
[2017.08.22-21.30.42:725][ 0]LogNet: UChannel::CleanUp: ChIndex == 0. Closing connection. [UChannel] ChIndex: 0, Closing: 0 [UNetConnection] RemoteAddr: 127.0.0.1:7837, Name: UnitTestNetConnection_0, Driver: UnitTestNetDriver_0 UnitTestNetDriver_0, IsServer: NO, PC: NULL, Owner: NULL
[2017.08.22-21.30.42:726][ 0]LogNet: UNetConnection::Close: [UNetConnection] RemoteAddr: 127.0.0.1:7837, Name: UnitTestNetConnection_0, Driver: UnitTestNetDriver_0 UnitTestNetDriver_0, IsServer: NO, PC: NULL, Owner: NULL, Channels: 2, Time: 2017.08.22-21.30.42
[2017.08.22-21.30.42:727][ 0]LogNet: UChannel::Close: Sending CloseBunch. ChIndex == 0. Name: [UChannel] ChIndex: 0, Closing: 0 [UNetConnection] RemoteAddr: 127.0.0.1:7837, Name: UnitTestNetConnection_0, Driver: UnitTestNetDriver_0 UnitTestNetDriver_0, IsServer: NO, PC: NULL, Owner: NULL
[2017.08.22-21.30.42:728][ 0]LogNet: NetworkFailure: ConnectionLost, Error: 'Your connection to the host has been lost.'
[SERVER][2017.08.22-21.30.42:723][ 1]LogNetTraffic: Error: UChannel::ReceivedRawBunch: Bunch.IsError() after ReceivedNextBunch 1
[SERVER][2017.08.22-21.30.42:733][ 1]LogNetTraffic: Error: Received corrupted packet data from client 127.0.0.1. Disconnecting.
[2017.08.22-21.30.42:740][ 0]LogNet: NetworkFailure: ConnectionLost, Error: 'Your connection to the host has been lost.'
