SOLVED Server - repeated 'Latency issues - collision detection will re-enable...' at start

Discussion in 'Bug Reports' started by MileSeven, Aug 10, 2022.

  1. MileSeven

    MileSeven Registered

    Joined:
    Apr 1, 2014
    Messages:
    650
    Likes Received:
    244
    v1130 server. Trans Am Golden Era mod (based on Dissenter) at Bridgeport with max 6 drivers.

    On *every* race start, we received warning messages along the lines of 'Driver X - latency issues - collision detection will re-enable in 123 seconds...)

    The Driver in question appeared to be random - or, more likely, it was reporting all drivers but the last one in the chat box was randomly chosen...

    Can't find anything directly recorded in the trace (level 3)

    Code:
    1
    193132.43s steward.cpp  9298: SessionName="Race"
    193132.43s slot.cpp     1567: AssignPitAndGarage() slot=12 name="Myles Davison" session=10 et=0.000 pobq=0 parking=255 pit=12 gar=0 qual=1 pld=4539.5
    193132.43s slot.cpp     1567: AssignPitAndGarage() slot=13 name="John Cook" session=10 et=0.000 pobq=0 parking=255 pit=13 gar=0 qual=4 pld=4539.4
    193132.43s slot.cpp     1567: AssignPitAndGarage() slot=14 name="Jim Carvalho" session=10 et=0.000 pobq=0 parking=255 pit=14 gar=0 qual=2 pld=4539.9
    193132.43s slot.cpp     1567: AssignPitAndGarage() slot=15 name="Giorgio Minga" session=10 et=0.000 pobq=0 parking=255 pit=0 gar=0 qual=5 pld=87.9
    193132.43s slot.cpp     1567: AssignPitAndGarage() slot=16 name="Rick Nauman [SR4L]" session=10 et=0.000 pobq=0 parking=255 pit=1 gar=0 qual=3 pld=79.1
    193136.81s GraphicsMain  176: RF2 Warning : Did not manage to load the same number of vertices from file: "C:\Games\rfactor2-dedicated\UserData\player_03\Settings\Bridgehampton_GP\AutoSave.rrbin" 
    193137.06s specialfx.cp 4416: Entered SpecialFX::Restart()
    193137.06s dynman.cpp    906: Entered DynMan::Restart()
    193137.06s setup.cpp    3416: Could not find Bridgehampton_GP.race.svm
    193137.06s setup.cpp    3416: Could not find Bridgehampton_GP.svm
    193137.06s setup.cpp    3416: Could not find Bridgehampton_GP.race.svm
    193137.06s setup.cpp    3416: Could not find Bridgehampton_GP.svm
    193137.06s setup.cpp    3416: Could not find Bridgehampton_GP.race.svm
    193137.06s setup.cpp    3416: Could not find Bridgehampton_GP.svm
    193137.06s setup.cpp    3416: Could not find Bridgehampton_GP.race.svm
    193137.06s setup.cpp    3416: Could not find Bridgehampton_GP.svm
    193137.06s setup.cpp    3416: Could not find Bridgehampton_GP.race.svm
    193137.06s setup.cpp    3416: Could not find Bridgehampton_GP.svm
    193137.06s slot.cpp      949: Entered Slot::Restart(104)
    193137.06s slot.cpp     1036: vi(1)
    193137.07s slot.cpp     1040: sr(1,1)
    193137.07s driver.cpp    549: unable to find RCD file for driver of NO_AI PaceCar class car
    193137.09s setup.cpp    3416: Could not find BRIDGEHAMPTON_A.ini
    193137.09s slot.cpp     1121: Exited Slot::Restart()
    193137.09s slot.cpp      949: Entered Slot::Restart(12)
    193137.09s slot.cpp     1036: vi(1)
    193137.10s slot.cpp     1040: sr(1,1)
    193137.10s driver.cpp    549: unable to find RCD file for driver of TrAm-FORD-MUSTANG class car
    193137.10s slot.cpp     1121: Exited Slot::Restart()
    193137.10s slot.cpp      949: Entered Slot::Restart(13)
    193137.10s slot.cpp     1036: vi(1)
    193137.12s slot.cpp     1040: sr(1,1)
    193137.12s driver.cpp    549: unable to find RCD file for driver of TrAm-DODGE-CHALL class car
    193137.12s slot.cpp     1121: Exited Slot::Restart()
    193137.12s slot.cpp      949: Entered Slot::Restart(14)
    193137.12s slot.cpp     1036: vi(1)
    193137.12s slot.cpp     1040: sr(1,1)
    193137.12s driver.cpp    549: unable to find RCD file for driver of TrAm-Plymouth-Cuda class car
    193137.14s slot.cpp     1121: Exited Slot::Restart()
    193137.14s slot.cpp      949: Entered Slot::Restart(15)
    193137.14s slot.cpp     1036: vi(1)
    193137.14s slot.cpp     1040: sr(1,1)
    193137.14s driver.cpp    549: unable to find RCD file for driver of TrAm-Plymouth-Cuda class car
    193137.15s slot.cpp     1121: Exited Slot::Restart()
    193137.15s slot.cpp      949: Entered Slot::Restart(16)
    193137.15s slot.cpp     1036: vi(1)
    193137.15s slot.cpp     1040: sr(1,1)
    193137.15s driver.cpp    549: unable to find RCD file for driver of TrAm-FORD-MUSTANG class car
    193137.15s slot.cpp     1121: Exited Slot::Restart()
    193137.18s steward.cpp  6147: InitializeMultiSessionRules():
    193137.18s steward.cpp  6151:  slot=12 name="Myles Davison" session=10 et=0.000 pobq=0 parking=255 pit=12 gar=0 qual=1
    193137.18s steward.cpp  6151:  slot=13 name="John Cook" session=10 et=0.000 pobq=0 parking=255 pit=13 gar=0 qual=4
    193137.18s steward.cpp  6151:  slot=14 name="Jim Carvalho" session=10 et=0.000 pobq=0 parking=255 pit=14 gar=0 qual=2
    193137.18s steward.cpp  6151:  slot=15 name="Giorgio Minga" session=10 et=0.000 pobq=0 parking=255 pit=0 gar=0 qual=5
    193137.18s steward.cpp  6151:  slot=16 name="Rick Nauman [SR4L]" session=10 et=0.000 pobq=0 parking=255 pit=1 gar=0 qual=3
    193137.18s camera.cpp   5642: Entered CamMan::Restart()
    193137.18s camera.cpp   5655: Entered CamMan::SyncWithDynMan()
    193137.18s camera.cpp   5659: LoadCamsFromVehicleData, MgrInx = 21, SlotID = 12
    193137.18s camera.cpp   5671: Entered CamMan::UpdateSuperShadowParameters()
    193137.18s camera.cpp   5674: Exit CamMan::Restart()
    193137.18s render.cpp   2506: Entered Render::Restart()
    193137.18s onscreen.cpp 4671: Entered OnScreen::Restart()
    193137.18s Masfile.cpp  2581: Error opening MAS file bridgehampton.mas
    193137.20s vidman.cpp   2329: Entered VidMan::Restart()
    193137.20s plrfile.cpp  2898: Entered PlayerFile::Restart()
    193137.20s NetCommDedic  705: HeadlessState update: STATE_ADVANCING_SESSION -> STATE_RUNNING_SESSION
    193137.20s JNILog.cpp     33: [main] SRLI: received 524 bytes of data
    193137.20s JNILog.cpp     33: [main] Putting on the event bus: SessionResultImpl [sessionType=WARMUP, entries=[SessionResultEntryImpl [steamId=76561197993782014, position=1, positionInClass=1, totalLaps=0, finishTime=0.0, fastestLapTime=-1.0, fastestLapEndTime=-2.0, finishStatus=FSTAT_NONE, vehicleName=Ford Mustang Boss 302 #24], SessionResultEntryImpl [steamId=76561198008881476, position=2, positionInClass=2, totalLaps=0, finishTime=0.0, fastestLapTime=-1.0, fastestLapEndTime=-2.0, finishStatus=FSTAT_NONE, vehicleName=Plymouth Barracuda #42], SessionResultEntryImpl [steamId=76561198004858814, position=3, positionInClass=3, totalLaps=0, finishTime=0.0, fastestLapTime=-1.0, fastestLapEndTime=-2.0, finishStatus=FSTAT_NONE, vehicleName=Ford Mustang Boss 302 #02], SessionResultEntryImpl [steamId=76561197984525695, position=4, positionInClass=4, totalLaps=0, finishTime=0.0, fastestLapTime=-1.0, fastestLapEndTime=-2.0, finishStatus=FSTAT_NONE, vehicleName=Dodge Challenger #42], SessionResultEntryImpl [steamId=76561198114577162, position=5, positionInClass=5, totalLaps=0, finishTime=0.0, fastestLapTime=-1.0, fastestLapEndTime=-2.0, finishStatus=FSTAT_NONE, vehicleName=Plymouth Barracuda #48]]]
    193137.20s NetCommDedic 8214: Chat message:Jim Carvalho: rdy : 0.00
    193137.20s NetComm.cpp  2392: Node 2 ("?") isMapped=1 isParticipant=0
    193137.20s NetComm.cpp  2582: No valid client slot, not assigning SteamID 2684354560
    193137.20s NetComm.cpp  2595: Player (id=2 SteamID=2684354560) ready (assigning slot? No (-1)) (et    0.000)
    193137.20s NetComm.cpp  2392: Node 3 ("?") isMapped=1 isParticipant=0
    193137.20s NetComm.cpp  2582: No valid client slot, not assigning SteamID 2684354560
    193137.20s NetComm.cpp  2595: Player (id=3 SteamID=2684354560) ready (assigning slot? No (-1)) (et    0.000)
    193137.20s NetComm.cpp  2392: Node 4 ("?") isMapped=0 isParticipant=0
    193137.20s steward.cpp  3176: FillRaceStateEvent2()
    193137.20s steward.cpp  3197: SetQualification() : sending 12,1
    193137.20s steward.cpp  3197: SetQualification() : sending 13,4
    193137.20s steward.cpp  3197: SetQualification() : sending 14,2
    193137.20s steward.cpp  3197: SetQualification() : sending 15,5
    193137.20s steward.cpp  3197: SetQualification() : sending 16,3
    193137.20s NetComm.cpp  2582: No valid client slot, not assigning SteamID 2684354560
    193137.20s NetComm.cpp  2595: Player (id=4 SteamID=2684354560) ready (assigning slot? No (-1)) (et    0.000)
    193137.20s NetComm.cpp  2392: Node 5 ("?") isMapped=1 isParticipant=0
    193137.20s NetComm.cpp  2582: No valid client slot, not assigning SteamID 2684354560
    193137.20s NetComm.cpp  2595: Player (id=5 SteamID=2684354560) ready (assigning slot? No (-1)) (et    0.000)
    193137.20s NetComm.cpp  2392: Node 1 ("?") isMapped=1 isParticipant=0
    193137.20s NetComm.cpp  2582: No valid client slot, not assigning SteamID 2684354560
    193137.20s NetComm.cpp  2595: Player (id=1 SteamID=2684354560) ready (assigning slot? No (-1)) (et    0.000)
    193137.20s NetComm.cpp  2392: Node 6 ("?") isMapped=1 isParticipant=0
    193137.20s NetComm.cpp  2582: No valid client slot, not assigning SteamID 2684354560
    193137.20s NetComm.cpp  2595: Player (id=6 SteamID=2684354560) ready (assigning slot? No (-1)) (et    0.000)
    193137.21s NetComm.cpp  7648: NetComm::SetSelfAsReady(): Setting self as ready
    193137.21s NetComm.cpp  7707: NetComm::SetSelfAsReady(): Setting self as non-participant server node
    193137.21s weather.cpp  2590: Sending event SessionName "Race"
    193137.21s queue.cpp    2442: Event SessionName "Race"
    
     
    Last edited: Aug 10, 2022
  2. DJCruicky

    DJCruicky Registered

    Joined:
    Jan 25, 2012
    Messages:
    1,592
    Likes Received:
    1,121
  3. MileSeven

    MileSeven Registered

    Joined:
    Apr 1, 2014
    Messages:
    650
    Likes Received:
    244
    Yep - that’s the kind of thing. Note to SR4L - the above image was taken from a second dedi on our server running the Caterham at Cadwell. Could be something specific to us but how would we check….?
     
  4. davehenrie

    davehenrie Registered

    Joined:
    Jul 6, 2016
    Messages:
    7,454
    Likes Received:
    4,369
    Isn't that latency warning a new feature? I think I read something similar in one of the Release Blogs.(but which one?) The idea was to prevent a laggy car from impacting other's racing. But why you are getting that for so many drivers?
     
  5. Bill Worrel

    Bill Worrel Registered

    Joined:
    May 1, 2019
    Messages:
    456
    Likes Received:
    325
    Yes, it's not a bug, it's a new feature:
    "Added chat message indicating when there are latency issues and collision is turned off."
    "Cars that lag too much or teleport will have collision disabled for 5 seconds."
     
  6. MileSeven

    MileSeven Registered

    Joined:
    Apr 1, 2014
    Messages:
    650
    Likes Received:
    244
    Ok, thanks.

    We only seemed to get this as the grid was forming - no issues (either in terms of the new message or in terms of actual lagging/teleport) during any of the races.

    Routinely, at race starts we do get a period of 10 or so seconds where the synchronising message is displayed - but it is routine for us and not build dependent.

    Indicative of wider issues with our server or is the anti+lag feature just a bit eager at the moment.

    FWIW (not a huge amount) both of our server instances usually show a ping in the region of 50-70ms when viewed at the server list screen from my client.
     
  7. Bill Worrel

    Bill Worrel Registered

    Joined:
    May 1, 2019
    Messages:
    456
    Likes Received:
    325
    It's a good question. It may be that we just weren't aware of minor and intermittent connection issues.
     
    MileSeven likes this.
  8. Bas van den Berg

    Bas van den Berg Member Staff Member

    Joined:
    Apr 21, 2022
    Messages:
    3
    Likes Received:
    5
    This happens when we detect cars are teleporting or when we don't receive messages for more than I believe a second or so from one of the clients. It indeed only reports the closest car, not all the cars that have issues (would be too noisy).
    It used to be that collision would be enabled again immediately when we do start receiving message again, which had the potential to cause crashes (of cars, not the game). So we delayed enabling collision detection for those cars that have issues and we show a message to inform the users.
     
    MileSeven likes this.
  9. Bill Worrel

    Bill Worrel Registered

    Joined:
    May 1, 2019
    Messages:
    456
    Likes Received:
    325
    Is there any adjustments that can be done by server admins?

    It can be very distracting when these pop up while you're qualifying or racing.
     
  10. MileSeven

    MileSeven Registered

    Joined:
    Apr 1, 2014
    Messages:
    650
    Likes Received:
    244
    I don’t think we’ve seen it during actual driving yet - just during the grid - but the messages do flicker away quite madly and are quite distracting even in that context.

    I’m not sure the ‘closest car’ is working in that context either - I’m pretty sure we’ve seen the named driver rapidly change during the grid formation (standing start by the way). We’ll test again on Wednesday - I’m not sure if this occurs only during the synchronisation message or not.
     
  11. Bas van den Berg

    Bas van den Berg Member Staff Member

    Joined:
    Apr 21, 2022
    Messages:
    3
    Likes Received:
    5
    We've made some changes that should make it pop up a bit less. Hopefully this will make it less annoying while still being useful when it needs to be.
     
  12. MileSeven

    MileSeven Registered

    Joined:
    Apr 1, 2014
    Messages:
    650
    Likes Received:
    244
    Thanks - just seen this reply (didn’t have notifications set on I guess)…

    Have the changes been propagated or will they be in a future update? Last night we could still see the messages while on the grid.
     
  13. Bas van den Berg

    Bas van den Berg Member Staff Member

    Joined:
    Apr 21, 2022
    Messages:
    3
    Likes Received:
    5
    This went in a hotfix and if I remember correctly that's already been released.
     
  14. delapecs

    delapecs Registered

    Joined:
    Feb 14, 2012
    Messages:
    237
    Likes Received:
    76
    Hi Bas, this weekend I continue seeing this latency messages, a little bit less than before for sure.:)

    I have questions about this: If I'm the only human player on the server plus 8 IA's, the server is in the same lan with 1gb net, why I have latency issues when restart race? with who? with the IA? And why my ping is going to 2000?

    Looking to task manager, cpu only spykes heavily to 100% when restarted, other than that the cpu usage is minimum.
    Dedicated Server needs a severe revision in my humble opinion, too many issues.:(
     
  15. Lazza

    Lazza Registered

    Joined:
    Oct 5, 2010
    Messages:
    12,346
    Likes Received:
    6,572
    Would be normal to see it at the start, if it persists then you'll need to check what's going on (performance, processes, plugins, ...) and maybe report a bug if you can't work it out. But seeing the message briefly at the start is fine.
     
  16. delapecs

    delapecs Registered

    Joined:
    Feb 14, 2012
    Messages:
    237
    Likes Received:
    76
    The latency messages only shows at the start of the race, but almost every restart.

    I made from scracth the 3 pc's involveds, my rig, the server on the same lan, and the rig of my brother in law. I've been playing computers for 30 years and I think I'm good at optimizing PCs, even if it's just a little bit. ;)
    Only plugin we use is from Simhub / Crew Chieff, and no other programs or processes are running in the background. The two rigs are dedicated PC's only for simracing, no other uses.
    We use Fanatec CSW v2 and CSL DD.
    My rig, the server, an the rig of my brother in law:
    i7 3770K @4,4 Ghz, GTX1080 G1 8Gb, 16 Gbs RAM, Acer 34p, some ssd's
    i5 3750K @4.0 Ghz, GTX 770 4Gb, 12 Gbs RAM, Benq XL2411T, some ssd's
    i5 3750k @4.0 Ghz, RTX 2080 Super 8Gb, 16 Gb RAM, Acer 35p, some ssd's

    Yes, they are not exactly brand new, but can play at 120 fps perfectly with settings almost all in HIGH (no night, no rain, no effects, no srs, reflections low, no pps..)
    In the server I make exclusions for all the exes and folders of the game in the antivirus.
     
    Last edited: Sep 27, 2022
  17. Lazza

    Lazza Registered

    Joined:
    Oct 5, 2010
    Messages:
    12,346
    Likes Received:
    6,572
    I think you don't have an issue. In those moments your client is putting you into the car it's doing all sorts of stuff - and sometimes will freeze up momentarily - before it actually starts running properly. That will cause the latency message (because there's probably a gap between your game telling the server, "I'm driving!" and actually giving position/status updates) but if it clears in a few seconds then it's fine.

    The recent races I've done online I'm seeing these messages for nearby drivers every time I jump in the car in the race session, but then the race has been flawless.

    You have nothing to fix :)
     
    delapecs likes this.
  18. delapecs

    delapecs Registered

    Joined:
    Feb 14, 2012
    Messages:
    237
    Likes Received:
    76
    True, the races are going well.
    The problem that I have been looking for for a long time is the message "syncronizing" and wheel shaking when restarting the race. I was hoping that this topic could be related and I wanted to contribute with my configuration in case it was useful to find a pattern.
    Thank you Lazza :)
     

Share This Page