Jump to content
  • 0

Long delay with server authentication after a restart

Anarior

Asked by Anarior,

 Share

Question

Anarior Newbie

Anarior

Posted
Posted

I have a recent problem with our Epoch server that is causing me some headache

After a server restart it takes 15-20 mins for the server to authenticate players logging in, this is something that seems to have started happening randomly after a server restart several days ago. The server PBO and mission file hadn't been changed for several days before this starting happening. I have since made several changes to the machine it is running on to try and fix the problem, adjusted SQL settings and moved the DB onto a SSD. But none of that seems to have helped. I would blame us switching to 'Overpoch' but the problem didn't start for at least a week after that.
 

We have quite a few scripts running on the server, but they have never been an issue before. I completely rebuilt the server PBO and mission folder from scratch for 1.0.5.1. I also test the scripts extensively on a test server before making them live.

 

The server rpt is below and clearly shows the very long delay.

=====================================================================
== B:\DayZ OverPoch Server\arma2oaserver.exe
== "arma2oaserver.exe"  -port=2302 -nologs "-config=instance_11_Chernarus\config.cfg" "-cfg=instance_11_Chernarus\basic.cfg" "-profiles=instance_11_Chernarus" -name=instance_11_Chernarus "-mod=@DayZOverwatch;@DayZ_Epoch;@DayZ_Epoch_Server;"
=====================================================================
Exe timestamp: 2014/06/18 06:21:23
Current time:  2014/06/27 09:59:21

Version 1.63.124879
 9:59:21 "DayZ Epoch: PRELOAD Functions\init [[<No group>:0 (FunctionsManager)],any]"
 9:59:21 "DayZ Epoch: MPframework inited"
 9:59:56 "Res3tting B!S effects..."
10:00:00 "\z\addons\dayz_code\system\REsec.sqf:Monitoring Remote Exe..."
10:00:00 "HIVE: Starting"
10:00:00 "HIVE: trying to get objects"
10:00:04 "HIVE: found 13339 objects"
10:00:04 "HIVE: Commence Object Streaming..."
10:00:06 "HIVE: got 7718 Epoch Objects and 5621 Vehicles"
10:02:32 "get: STRING (247195398), sent: STRING (247195398)"
10:02:32 "DISCONNECT: Anarior (247195398) Object: B 1-1-A:1 (Anarior) REMOTE, _characterID: 0 at loc [-7244.94,19534.5,0]"
10:02:32 "ERROR: Cannot Sync Character Anarior as no characterID"
10:06:54 "get: STRING (247195398), sent: STRING (247195398)"
10:06:54 "DISCONNECT: Anarior (247195398) Object: B 1-1-A:1 (Anarior) REMOTE, _characterID: 0 at loc [-7244.94,19534.5,0]"
10:06:54 "ERROR: Cannot Sync Character Anarior as no characterID"
10:10:44 "get: STRING (247195398), sent: STRING (247195398)"
10:10:44 "DISCONNECT: Anarior (247195398) Object: B 1-1-A:1 (Anarior) REMOTE, _characterID: 0 at loc [-7244.94,19534.5,0]"
10:10:44 "ERROR: Cannot Sync Character Anarior as no characterID"
10:13:16 "HIVE: Spawning # of Vehicles: 16"
10:13:16 "HIVE: Spawning # of Debris: 0"
10:13:16 "HIVE: Spawning # of Ammo Boxes: 8"
10:13:16 "HIVE: Spawning # of Veins: 50"
10:13:16 "Total Number of spawn locations 6"
10:13:16 "EPOCH EVENTS INIT"
10:13:23 "DEBUG VEIN: Too many objects at [3086.5,6885.08]"
10:13:23 "DEBUG VEIN: Too many objects at [11453.8,11385.2]"
10:13:24 "PUBLISH: Attempt 43def040# 1073134: suv.p3d"
10:13:24 "HIVE: WRITE: "CHILD:308:11:SUV_Red:0:0:[90,[5935.39,10222,0.0622864]]:[]:[["wheel_1_1_steering",0.925291],["wheel_1_2_steering",0.680903],["wheel_2_1_steering",0.658628],["wheel_2_2_steering",0.988802],["palivo",0.828379],["motor",0.784825],["glass1",0.850507],["glass2",0.701471],["glass3",0.829679],["glass4",0.623616],["sklo predni P",0.842],["sklo predni L",0.925661],["karoserie",0.949423],["wheel_1_4_steering",0.531483],["wheel_2_4_steering",0.563391],["wheel_1_3_steering",0.923039],["wheel_2_3_steering",0.938338]]:0.99975:59354102220190:""
10:13:24 "PUBLISH: Attempt 43dee080# 1073135: skodovka_blue.p3d"
10:13:24 "HIVE: WRITE: "CHILD:308:11:SkodaBlue:0:0:[159,[3215.8,5183.91,0.0473328]]:[]:[["motor",0.568713],["sklo predni P",0.793531],["sklo predni L",0.928621],["karoserie",0.957535],["palivo",0.702686],["wheel_1_1_steering",0.692031],["wheel_2_1_steering",0.953423],["wheel_1_4_steering",0.637333],["wheel_2_4_steering",0.784864],["wheel_1_3_steering",0.809639],["wheel_2_3_steering",0.634142],["wheel_1_2_steering",0.868423],["wheel_2_2_steering",0.819825],["glass1",0.847177],["glass2",0.542205],["glass3",0.685786],["glass4",0.518031]]:0.230373:32158518390159:""
10:13:24 "PUBLISH: Attempt 43ded0c0# 1073136: skodovka_green.p3d"
10:13:24 "HIVE: WRITE: "CHILD:308:11:SkodaGreen:0:0:[19,[2173.3,9207.9,0.00735474]]:[]:[["motor",0.720807],["sklo predni P",0.710125],["sklo predni L",0.944808],["karoserie",0.904076],["palivo",0.702108],["wheel_1_1_steering",0.96436],["wheel_2_1_steering",0.983852],["wheel_1_4_steering",0.70171],["wheel_2_4_steering",0.512446],["wheel_1_3_steering",0.591358],["wheel_2_3_steering",0.708005],["wheel_1_2_steering",0.972712],["wheel_2_2_steering",0.805429],["glass1",0.736866],["glass2",0.809957],["glass3",0.791873],["glass4",0.954663]]:0.895282:2173392079019:""
10:13:25 "PUBLISH: Attempt 43dec100# 1073137: uaz.p3d"
10:13:25 "HIVE: WRITE: "CHILD:308:11:UAZ_INS:0:0:[48,[12265,7344.85,0.0660095]]:[]:[["glass1",0.702537],["glass2",0.718938],["glass3",0.697757],["glass4",0.700044],["wheel_1_1_steering",0.82833],["wheel_1_2_steering",0.892482],["wheel_2_1_steering",0.93944],["wheel_2_2_steering",0.871727],["motor",0.58608],["sklo predni P",0.831095],["sklo predni L",0.95952],["karoserie",0.989395],["palivo",0.911349],["wheel_1_4_steering",0.611346],["wheel_2_4_steering",0.715607],["wheel_1_3_steering",0.518098],["wheel_2_3_steering",0.509302]]:0.430205:12265073449148:""
10:13:25 "WAI: AI Config File Loaded"
10:13:25 "PUBLISH: Attempt 32b090c0# 1073186: mi_8mt.p3d"
10:13:25 "HIVE: WRITE: "CHILD:308:11:Mi17_DZE:0:0:[40,[3881.33,9613.1,0.499573]]:[]:[["glass1",0.714556],["glass2",0.740469],["glass3",0.914701],["glass4",0.641308],["glass5",0.873321],["glass6",0.993197],["NEtrup",0.596003],["motor",0.764885],["elektronika",0.781864],["mala vrtule",0.560912],["velka vrtule",0.852288],["munice",0.844867],["sklo predni P",0.68619],["sklo predni L",0.619488]]:0.502612:3881396131540:""
10:13:25 "TIME SYNC: Local Time set to [2013,8,3,15,13]"
10:13:26 "PUBLISH: Attempt 32b0a080# 1073187: mi_8amt.p3d"
10:13:26 "HIVE: WRITE: "CHILD:308:11:Mi17_Civilian_DZ:0:0:[121,[11658.1,9561.36,0.491352]]:[]:[["glass1",0.819302],["glass2",0.732782],["glass3",0.700863],["glass4",0.521796],["glass5",0.997434],["glass6",0.791086],["NEtrup",0.647726],["motor",0.703032],["elektronika",0.519874],["mala vrtule",0.978721],["velka vrtule",0.991238],["munice",0.823848],["sklo predni P",0.674245],["sklo predni L",0.937262]]:0.250109:116581956145121:""
10:13:26 "WAI: AI Monitor Started"
10:13:27 "PUBLISH: Attempt 43f42080# 1073189: csj_gyrocover.p3d"
10:13:27 "HIVE: WRITE: "CHILD:308:11:CSJ_GyroCover:0:0:[135,[11599.2,15397.2,0.0239792]]:[]:[["telo",0.742629]]:0.533133:1159921539720135:""
10:13:27 "PUBLISH: Attempt 43f9d0c0# 1073192: jetskiyanahui.p3d"
10:13:27 "HIVE: WRITE: "CHILD:308:11:JetSkiYanahui_Case_Green:0:0:[81,[5771.46,1885.41,10.9102]]:[]:[["motor",0.851857]]:0.653906:577151885410981:""
10:13:27 "PUBLISH: Attempt 43ff7040# 1073194: tractor_2.p3d"
10:13:27 "HIVE: WRITE: "CHILD:308:11:tractor:0:0:[131,[4755.18,10347.8,0]]:[]:[["motor",0.911555],["sklo predni P",0.6005],["sklo predni L",0.520426],["karoserie",0.926345],["palivo",0.604403],["wheel_1_1_steering",0.61156],["wheel_2_1_steering",0.940801],["wheel_1_4_steering",0.731473],["wheel_2_4_steering",0.519159],["wheel_1_3_steering",0.641698],["wheel_2_3_steering",0.904197],["wheel_1_2_steering",0.700958],["wheel_2_2_steering",0.586907],["glass1",0.959405],["glass2",0.676696],["glass3",0.591617],["glass4",0.92714]]:0.3202:475521034780131:""
10:13:27 "WAI: Mission Config File Loaded"
10:13:28 "HIVE: WRITE: "CHILD:388:116581956145121:""
10:13:28 "CUSTOM: Selected "31690""
10:13:28 "HIVE: WRITE: "CHILD:388:12265073449148:""
10:13:28 "WAI: Starting AI Missions Moniter"
10:13:28 "WAI: Starting Mission any"
10:13:28 "HIVE: WRITE: "CHILD:388:3881396131540:""
10:13:28 "CUSTOM: Selected "31689""
10:13:29 "PUBLISH: Attempt 44084100# 1073197: smallboat_1.p3d"
10:13:29 "HIVE: WRITE: "CHILD:308:11:Smallboat_1:0:0:[56,[3488.82,1669.84,13.4349]]:[]:[["motor",0.815623]]:0.327691:348881669813456:""
10:13:29 "HIVE: WRITE: "CHILD:388:59354102220190:""
10:13:29 "CUSTOM: Selected "31685""
10:13:29 "HIVE: WRITE: "CHILD:388:1159921539720135:""
10:13:29 "CUSTOM: Selected "31691""
10:13:29 "PUBLISH: Created CSJ_GyroCover with ID "1159921539720135""
10:13:29 "HIVE: WRITE: "CHILD:388:2173392079019:""
10:13:29 "CUSTOM: Selected "31687""
10:13:29 "HIVE: WRITE: "CHILD:388:32158518390159:""
10:13:29 "CUSTOM: Selected "31686""
10:13:29 "HIVE: WRITE: "CHILD:388:577151885410981:""
10:13:29 "CUSTOM: Selected "31692""
10:13:29 "PUBLISH: Attempt 44030100# 1073198: lada.p3d"
10:13:29 "HIVE: WRITE: "CHILD:308:11:Lada1:0:0:[65,[6193.25,10373.5,0.000183105]]:[]:[["motor",0.754716],["sklo predni P",0.8027],["sklo predni L",0.961569],["karoserie",0.802878],["palivo",0.618941],["wheel_1_1_steering",0.837041],["wheel_2_1_steering",0.837207],["wheel_1_4_steering",0.794011],["wheel_2_4_steering",0.61584],["wheel_1_3_steering",0.72469],["wheel_2_3_steering",0.624703],["wheel_1_2_steering",0.629061],["wheel_2_2_steering",0.719395],["glass1",0.856712],["glass2",0.899522],["glass3",0.949356],["glass4",0.827914]]:0.767081:61932103735065:""
10:13:30 "CUSTOM: Selected "31688""
10:13:31 "PUBLISH: Attempt 4416f040# 1073200: lada.p3d"
10:13:31 "HIVE: WRITE: "CHILD:308:11:GLT_M300_LT:0:0:[31,[3915.1,8897.21,0.0279846]]:[]:[["motor",0.732098],["sklo predni P",0.633208],["sklo predni L",0.738383],["karoserie",0.666413],["palivo",0.890673],["wheel_1_1_steering",0.93326],["wheel_2_1_steering",0.591405],["wheel_1_4_steering",0.979028],["wheel_2_4_steering",0.943135],["wheel_1_3_steering",0.539999],["wheel_2_3_steering",0.51243],["wheel_1_2_steering",0.937476],["wheel_2_2_steering",0.935903],["glass1",0.773742],["glass2",0.787718],["glass3",0.947386],["glass4",0.673751]]:0.213451:3915188972031:""
10:13:31 "PUBLISH: Created JetSkiYanahui_Case_Green with ID "577151885410981""
10:13:31 "PUBLISH: Created Mi17_Civilian_DZ with ID "116581956145121""
10:13:31 "HIVE: WRITE: "CHILD:388:475521034780131:""
10:13:31 "CUSTOM: Selected "31693""
10:13:31 "PUBLISH: Created Mi17_DZE with ID "3881396131540""
10:13:31 "PUBLISH: Created SUV_Red with ID "59354102220190""
10:13:31 "HIVE: WRITE: "CHILD:388:348881669813456:""
10:13:31 "CUSTOM: Selected "31694""
10:13:32 "PUBLISH: Created Smallboat_1 with ID "348881669813456""
10:13:33 "PUBLISH: Created SkodaGreen with ID "2173392079019""
10:13:33 "PUBLISH: Created SkodaBlue with ID "32158518390159""
10:13:33 "PUBLISH: Created UAZ_INS with ID "12265073449148""
10:13:33 "HIVE: WRITE: "CHILD:388:61932103735065:""
10:13:33 "CUSTOM: Selected "31695""
10:13:34 "PUBLISH: Created tractor with ID "475521034780131""
10:13:34 "HIVE: WRITE: "CHILD:388:3915188972031:""
10:13:34 "CUSTOM: Selected "31696""
10:13:35 "PUBLISH: Created Lada1 with ID "61932103735065""
10:13:36 "PUBLISH: Created GLT_M300_LT with ID "3915188972031""
10:13:37 "WAI: Sapwned in 4 M2StaticMG"
10:13:46 "WAI: Sapwned in 4 M2StaticMG"
10:13:49 "WAI: Sapwned in 5 M2StaticMG"
10:13:50 "WAI: Sapwned in 3 M2StaticMG"
10:13:51 "WAI: Spawned a group of 3 Bandits at [4473.35,8569.33,9.15527e-005]"
10:13:53 "WAI: Spawned a group of 4 Bandits at [4414.82,8502.65,13.4563]"
10:13:55 "WAI: Spawned a group of 3 Bandits at [4372.95,8604.39,16.6437]"
10:13:56 "WAI: Spawned a group of 3 Bandits at [4074.42,8627.63,0]"
10:13:58 "WAI: Spawned a group of 3 Bandits at [4057.66,8591.54,0]"
10:13:59 "WAI: Spawned a group of 3 Bandits at [4065.57,8607.28,3.05176e-005]"
10:14:01 "WAI: Spawned a group of 4 Bandits at [4069.5,8618.46,6.10352e-005]"
10:14:02 "WAI: Spawned a group of 3 Bandits at [4034.11,8598.19,0]"
10:14:03 "WAI: Spawned a group of 3 Bandits at [4046.56,8583.2,3.05176e-005]"
10:14:04 "WAI: Spawned a group of 3 Bandits at [4079.74,8591.79,0]"
10:14:06 "WAI: Spawned a group of 3 Bandits at [4102.69,8618.41,-0.00012207]"
10:14:07 "WAI: Spawned a group of 3 Bandits at [4164.83,8626.2,-3.05176e-005]"
10:14:08 "WAI: Spawned a group of 3 Bandits at [4182.17,8569.63,9.15527e-005]"
10:14:09 "WAI: Spawned a group of 3 Bandits at [4389.47,8677.58,0]"
10:14:11 "WAI: Spawned a group of 5 Bandits at [4213.05,8436.2,-3.05176e-005]"
10:14:13 "WAI: Spawned a group of 4 Bandits at [4251.38,8591.6,-9.15527e-005]"
10:14:14 "WAI: Spawned a group of 3 Bandits at [4399.19,8519.48,0]"
10:14:16 "WAI: Spawned a group of 3 Bandits at [4406,8555.97,3.05176e-005]"
10:14:17 "WAI: Spawned a group of 3 Bandits at [4445.71,8499.4,0]"
10:14:18 "WAI: Spawned a group of 5 Bandits at [4513.37,8444.32,0]"
10:14:20 "WAI: Spawned a group of 3 Bandits at [4431.11,8652.11,9.15527e-005]"
10:14:25 [DZMS]: Starting DayZ Mission System.
10:14:25 [DZMS]: WickedAI Found! Using WickedAI's Relations!
10:14:25 [DZMS]: Currently Running Version: 1.1FIN
10:14:25 [DZMS]: Mission and Extended Configuration Loaded!
10:14:25 [DZMS]: chernarus Detected. Map Specific Settings Adjusted!
10:14:25 [DZMS]: DayZ Epoch Detected! Some Scripts Adjusted!
10:14:25 [DZMS]: Loading ExecVM Functions.
10:14:25 [DZMS]: Loading Compiled Functions.
10:14:25 [DZMS]: Loading All Other Functions.
10:14:25 [DZMS]: Mission Functions Script Loaded!
10:14:25 [DZMS]: Major Mission Clock Starting!
10:14:25 [DZMS]: Minor Mission Clock Starting!
10:14:25 [DZMS]: Mission Marker Loop for JIPs Starting!
10:15:10 "RUNNING EVENT: supply_drop on [2014,6,27,15,15]"
10:15:42 "68 Active ground units"
10:15:42 "16 Active emplacement units"
10:15:42 "0 Active chopper patrol units (Crew)"
10:15:42 "0 Active vehicle patrol units (Crew)"
10:17:00 "CLEANUP: INITIALIZING CLEANUP SCRIPT"
10:19:16 [DZMS]: Running Minor Mission SM2.
10:19:31 [DZMS]: (DZMSUnitsMinor) 2 AI Spawned, 2 units in mission.
10:19:39 [DZMS]: (DZMSUnitsMinor) 2 AI Spawned, 4 units in mission.
10:19:41 [DZMS]: (DZMSUnitsMinor) Waiting for 0/4 Units or Less to be Alive and a Player to be Near the Objective.
10:22:00 "TIME SYNC: Local Time set to [2013,8,3,15,22]"
10:24:06 [DZMS]: Running Major Mission SM6.
10:24:19 [DZMS]: (DZMSUnitsMajor) 6 AI Spawned, 6 units in mission.
10:24:30 [DZMS]: (DZMSUnitsMajor) 6 AI Spawned, 12 units in mission.
10:24:41 [DZMS]: (DZMSUnitsMajor) 4 AI Spawned, 16 units in mission.
10:24:52 [DZMS]: (DZMSUnitsMajor) 4 AI Spawned, 20 units in mission.
10:24:58 [DZMS]: (DZMSUnitsMajor) Waiting for 0/20 Units or Less to be Alive and a Player to be Near the Objective.


 

Link to comment
Share on other sites

4 answers to this question

Recommended Posts

  • 0
Richie Newbie

Richie

Posted
Posted 
10:00:04 "HIVE: found 13339 objects"
10:00:04 "HIVE: Commence Object Streaming..."
10:00:06 "HIVE: got 7718 Epoch Objects and 5621 Vehicles"

Wow :o That's a lot of objects, most likely the cause

Try cleaning up and see how that works, take a backup beforehand obviously.

 

Also i don't know any server using :

Version 1.63.124879

Try an earlier patch.

Link to comment
Share on other sites
  • 0
Anarior Newbie

Anarior

Posted
  • Author
Posted

The large number of 'vehicles' are objects for custom bases and an AI city we run on the server. This hasn't actually changed recently so I don't see it being the cause as things had been running perfectly with approx. that number for quite a while. But it the interest of ruling it out, I have just tried disabling those scripts and it didn't speed things up at all.

 

I am not really wanting to try going back to an old server version.  We've been running it for a while now and it has been nothing but a big improvement over 112555, also it's kind of necessary with the Gamespy shutdown.

Link to comment
Share on other sites
  • 0
TuBz Newbie

TuBz

Posted
Posted

Ah and I thought it was just me..

I'm having same issues authentication took seconds before the update from 1.0.4.2 to 1.0.5 and then 1.0.5.1 now it's even longer than yours even with an empty fresh Database..I have no doubt it's something I have done wrong but having completely reinstalled the server 3 times now and both gaming PC's now.. I'm at a loss.. and very tired.. 

For my sanity if anyone has the answer please shout!

I'm also running mine as I always have off an ssd quad core q6600 with buckets of ram even though arma can't use it.. nothing changed bar an update to the recent epoch versions.. the beta patch I have left at 103718...

Link to comment
Share on other sites

Please sign in to comment

You will be able to leave a comment after signing in



Sign In Now
 Share
Go to question listing
  • Advertisement

  • Discord

×
×
  • Create New...