Hedius commented 1 year ago

Bug Report

System Information

Windows Server 2019
v2.4.6.6, built 04/10/2023 19:03
Mainline

I confirm:

[x] that I have searched for an existing bug report for this issue.
[x] that I am using the latest available version of AMP.
[x] that my operating system is up-to-date.

Symptoms

What are you trying to do? Start an upgraded or newly installed generic template with the versions mentioned above.
What are you expecting to happen? The instances should start.
What is actually happening? ('Nothing' is not an acceptable answer!)

It is not possible to start the instance. Tried Arma3, CS2 + the dev templates for BattleBit by Greelan. All 3 throw the same error about a missing source param.

[01:08:55] [Generic:hedius Info]  : Specified executable 'C:\AMPDatastore\Instances\bbr-0101\battlebit-remastered\671860\BattleBit.exe does not exist. Updating application...
[01:08:56] [Generic:hedius Error] : Error performing update stage BattleBit Download
[01:08:56] [Core:hedius Error]    : ArgumentNullException
[01:08:56] [Core:hedius Error]    : [0] (ArgumentNullException) : Value cannot be null.
Parameter name: source
[01:08:56] [Core:hedius Error]    :    at Linq.Enumerable.Where[TSource](IEnumerable`1 source, Func`2 predicate)
   at GenericModule.GenericModuleConfig.ApplicationConfig.GetSharedVariables()
   at GenericModule.GenericModuleConfig.ApplicationConfig.GetTemplatedValue(String input)
   at GenericModule.GenericApp.<PerformUpdateStage>()
   at GenericModule.GenericApp.<>c__DisplayClass90_0.<<Update>b__4>d.MoveNext()

Reproduction

Install AMP 2.4.6.6 on Windows, target mode in my case
Install Arma3 or BattleBit
Click Start in the instance.
Error pops up. Enter reproductions steps here.

Greelan commented 1 year ago

You could be facing the issue where the templates are not up to date on both controller and target?

For BattleBit, do you have the Server Branch setting under SteamCMD and Updates? If not, then the templates have not been downloaded and applied correctly.

Note that you need Git installed to fetch repo branches on windows.

Hedius commented 1 year ago

my controller runs on linux and the target is the windows server, both have git installed and I fetched the latest templates on both instances hmm

Greelan commented 1 year ago

Do you have the Server Branch setting?

Hedius commented 1 year ago

Yeah, it is set to Public (default), also fails with other settings

Hedius commented 1 year ago

I tried other generic templates from the default AMP repo and all fail with the same error, Arma, euro truck, CS2 etc

Hedius commented 1 year ago

The whole setup works fine with AMP 2.4.6.4, but as soon as you use 2.4.6.6 it is broken. (Note that other apps not using generic templates work totally fine)

p0t4t0sandwich commented 1 year ago

It might be worth looking around to see if you have any empty required fields.

From the update notes:

Implemented ‘Required’ fields that must have a value. These show up as red in the UI

Just an idea to tinker with until Mike is available to fix things.

Hedius commented 1 year ago

But from the notes required fields should be highlighted as red? Not seeing any. Another thing I noticed is that settings are not getting saved anymore.

My fault for not snapshotting the vm, but I would downgrade now.^^

Since it fails in the DL/update stage with steamCMD i would assume it is a steam cmd setting... but for battlebit the branch is set, and other games like CS2 don't have that option at all

Greelan commented 1 year ago

There are no "Required" fields in the config manifest for BB.

Looks like Mike has done his old trick and mistakenly made the presence of the field mandatory, which won't work because virtually no template will have it (given it was non-functional previously).

He previously had this issue with the client ID for steamcmd update stages.

Greelan commented 1 year ago

Either that or it's an issue with the changes he made to the SteamCMD update stages to deal with auth login.

Greelan commented 1 year ago

FWIW, I don't see this issue on Linux.

Hedius commented 1 year ago

Yes, I also have Linux targets and works fine there. I only have the issue on windows.

For the BB template also saving the settings fails with:

[01:52:08] [API:hedius Activity]  : Changing setting Meta.GenericModule.AntiCheat to eac
[01:52:08] [Core:hedius Warning]  : Returned exception from API call Core/SetConfig
[01:52:08] [Core:hedius Error]    : ArgumentNullException
[01:52:08] [Core:hedius Error]    : [0] (ArgumentNullException) : Value cannot be null.
Parameter name: source
[01:52:08] [Core:hedius Error]    :    at Linq.Enumerable.Where[TSource](IEnumerable`1 source, Func`2 predicate)
   at GenericModule.GenericModuleConfig.ApplicationConfig.GetSharedVariables()
   at GenericModule.ModuleMain.WriteMetaconfigs()
   at GenericModule.ModuleMain.SaveChanges(String key, String value)
   at GSMyAdmin.WebServer.WebMethods.UpdateSetting(String node, String value, SettingCacheEntry entry, SettingSpec cache)
   at GSMyAdmin.WebServer.WebMethods.SetConfig(String node, String value)
   at GSMyAdmin.WebServer.WebAttributes.<InvokeMethod>()
   at GSMyAdmin.WebServer.APIService.<InvokeAPI>()

it is already too late for me now, but I will check tomorrow on another clean windows server (which is standalone and not a target) [01:52:08] [Core:hedius Error] : at Linq.Enumerable.Where[TSource](IEnumerable1 source, Func2 predicate) at GenericModule.GenericModuleConfig.ApplicationConfig.GetSharedVariables()

Do not know why, but the .Where linq in GetSharedVariables() throws that NullException all the time. No idea why source is null though.

Seems more related to that one Method and not to steamcmd itself.

Thanks for your help btw :)

Greelan commented 1 year ago

That suggests the error is more likely to do with the Required field change

Mythotical commented 1 year ago

I can confirm this myself. I also get the following in Console when shutting down an instance to restart it.

Error handling request:[GET:http://api.ipify.org/] - [NotSupportedException]: System.NotSupportedException: The given path's format is not supported. at System.Security.Permissions.FileIOPermission.EmulateFileIOPermissionChecks(String fullPath) at System.Security.Permissions.FileIOPermission.QuickDemand(FileIOPermissionAccess access, String fullPath, Boolean checkForDuplicates, Boolean needFullPath) at m.Http.Handlers.StaticFileHandler.Handle(IHttpRequest req) at m.Http.Handlers.Handler.<>c__DisplayClass6_0.<From>b__0(IHttpRequest req) at m.Http.Router.<HandleRequest>d__25.MoveNext()

p0t4t0sandwich commented 1 year ago

That just looks like a file perms error, be sure to use AMP's SFTP or web file manager, because if you use the windows file explorer, you'll mess up the file perms.

Mythotical commented 1 year ago

That just looks like a file perms error, be sure to use AMP's SFTP or web file manager, because if you use the windows file explorer, you'll mess up the file perms.

That's just it, I didn't use windows file explorer for anything dealing with AMP. I never have SFTP enabled and will use the web file manager where needed but this is a fresh install and start up. Haven't used SFTP, web fie manager, or windows file explorer.

p0t4t0sandwich commented 1 year ago

Oh weird, that web address just returns your IP, so I wonder if it just errored out during that request for some off reason. (worked for me in my browser when I navigated to the site)

Mythotical commented 1 year ago

Oh weird, that web address just returns your IP, so I wonder if it just errored out during that request for some off reason. (worked for me in my browser when I navigated to the site)

Possible.

PhonicUK commented 1 year ago

I can confirm this myself. I also get the following in Console when shutting down an instance to restart it.

Error handling request:[GET:http://api.ipify.org/] - [NotSupportedException]: System.NotSupportedException: The given path's format is not supported. at System.Security.Permissions.FileIOPermission.EmulateFileIOPermissionChecks(String fullPath) at System.Security.Permissions.FileIOPermission.QuickDemand(FileIOPermissionAccess access, String fullPath, Boolean checkForDuplicates, Boolean needFullPath) at m.Http.Handlers.StaticFileHandler.Handle(IHttpRequest req) at m.Http.Handlers.Handler.<>c__DisplayClass6_0.<From>b__0(IHttpRequest req) at m.Http.Router.<HandleRequest>d__25.MoveNext()

This isn't anything to do with this issue. This is an attack attempt by a random 3rd party trying to see if they can perform a GET on an URL and get a response that makes sense. You can ignore this.

PhonicUK commented 1 year ago

I can't reproduce this - it looks like the circumstance it would happen under is if the ports data has been damaged and is consequently null.

Hedius commented 1 year ago

I can't reproduce this - it looks like the circumstance it would happen under is if the ports data has been damaged and is consequently null.

How would this get damaged? I am doing this on a newly installed AMP instance and creating new generic instances.

https://discourse.cubecoders.com/t/startup-issue-with-dayz-original-cannot-update-or-start-dayz-server/6133/1 https://discourse.cubecoders.com/t/steamcmd-error-installing-game-server/6134/1 https://discourse.cubecoders.com/t/startup-issue-with-valheim-after-amp-manager-update-valheim-server-will-not-start/6136/1

reported the same issue, maybe they have additional information. I am trying this again now on a fresh standalone instance (since the one I tested this with is a target)

Hedius commented 1 year ago

I did the following now @PhonicUK

Up to date Windows 10 Pro in vmware
Installed AMP 2.4.6.6 with Typical Settings
Initialized the instance as standalone
Fetched the latest amp templates
Created a CS2 instance which uses the generic module
Clicked Manage
Clicked the start button
Fails with the same error

Hedius commented 1 year ago

Doing the same with an older template CoD MW4 (last updated 4 months ago) Results in the sam e error:

Greelan commented 1 year ago

I see the issue. I just updated my Windows VM, and in GenericModule.kvp for one of the instances:

App.Ports=null

AMP is stripping out the ports information.

This is an AMP issue. If the kvp is being damaged, it is AMP causing it.

Greelan commented 1 year ago

I see this happen when the instance is managed. Rather than just managing it straight away, AMP reconfigures the instance, and in the process of doing so strips out the ports information.

Greelan commented 1 year ago

I don't even see it as a bad JSON issue. If I replace the App.Ports line with the default information from the relevant template (making sure port numbers match what AMP thinks the instance is on), AMP still strips it out.

PhonicUK commented 1 year ago

Looking into it

PhonicUK commented 1 year ago

Can someone affected by this get me a full debug log from startup to start?

Greelan commented 1 year ago

[23:41:51] [Program Info]         : Starting AMP version 2.4.6.6 (Decadeus), built 04/10/2023 19:03
[23:41:51] [Program Info]         : Stream: Mainline / Release - built by CUBECODERS/buildbot on CCL-DEV
[23:41:51] [Program Info]         : Running in a Xen environment.
[23:41:51] [Core Warning]         : Current time zone is set to AUS Eastern Standard Time - It is recommended you switch to UTC+0 to avoid time-zone related issues
[23:41:51] [Program Info]         : OS: Windows / x86_64
[23:41:51] [Program Info]         : CPU: Intel(R) Core(TM) i7-7700K CPU @ 4.20GHz (2C/2T)
[23:41:51] [Program Info]         : RAM: 16363MB
[23:41:51] [Program Info]         : AMP Instance ID: db22cf23-746c-4df6-95a7-3c36f62a870c
[23:41:51] [Core Debug]           : Primary network interface ID is {08CC34C6-A97A-46DA-9190-9FB70791743E} with physical address 00163EDF234C
[23:41:51] [Licencing Debug]      : Primary network interface ID is {08CC34C6-A97A-46DA-9190-9FB70791743E} with physical address 00163EDF234C
[23:41:51] [Core Debug]           : Using remote login server http://localhost:8080/
[23:41:51] [Loader Info]          : Loaded GenericModule version 1.0.0.0 by CubeCoders Limited
[23:41:51] [Core Info]            : Metrics publishing is enabled at udp://localhost:12820.
[23:41:51] [Loader Info]          : Loaded FileManagerPlugin by CubeCoders Limited
[23:41:51] [Loader Info]          : Loaded EmailSenderPlugin by CubeCoders Limited
[23:41:51] [Loader Info]          : Loaded WebRequestPlugin by CubeCoders Limited
[23:41:51] [FileBackup Debug]     : Read manifests for 0 backups.
[23:41:51] [Loader Info]          : Loaded LocalFileBackupPlugin by CubeCoders Limited
[23:41:51] [Loader Info]          : Loaded CommonCorePlugin by CubeCoders Limited
[23:41:51] [Loader Info]          : GenericModule requests dependency steamcmdplugin...
[23:41:51] [Loader Info]          : Loaded steamcmdplugin by CubeCoders Limited
[23:41:51] [Loader Info]          : GenericModule requests dependency RCONPlugin...
[23:41:51] [Loader Info]          : Loaded RCONPlugin by CubeCoders Limited
[23:41:51] [Core Info]            : Licence Present: AMP Black Edition
[23:41:51] [FileManager Notice]   : Using keypair with fingerprint w2htk2oEUExtujZssPja3pY8hxKKTThevnFgI+teRKU=
[23:41:51] [FileManager Info]     : SFTP Server started on 0.0.0.0:2225
[23:41:51] [Webserver Debug]      : Using webroot: C:\AMPDatastore\Instances\OpenRA-RedAlert01\WebRoot
[23:41:51] [Webserver Info]       : Websockets are enabled.
[23:41:51] [API Debug]            : Building setting specification cache...
[23:41:52] [API Debug]            : Setting spec cache built, 127 items added.
[23:41:52] [Webserver Info]       : RouterTimer@10Hz with 2 jobs started
[23:41:52] [Webserver Info]       : Webserver started on http://127.0.0.1:8082
[23:41:52] [Scheduler Debug]      : Scheduler timer will start in 7947ms.
[23:41:52] [Core Info]            : Checking for AMP updates...
[23:41:52] [Core Info]            : Startup mode is StartApplication.
[23:41:52] [Core Debug]           : Application state changed from Stopped to PreStart
[23:41:52] [Core Debug]           : Login request from 127.0.0.1 for admin
[23:41:52] [Core Activity]        : Authentication attempt for user admin from 127.0.0.1
[23:41:52] [Core Debug]           : Logging in via remote auth server http://localhost:8080/...
[23:41:53] [Core Error]           : ArgumentNullException
[23:41:53] [Core Error]           : [0] (ArgumentNullException) : Value cannot be null.
Parameter name: source
[23:41:53] [Core Error]           :    at Linq.Enumerable.Where[TSource](IEnumerable`1 source, Func`2 predicate)
   at GenericModule.GenericModuleConfig.ApplicationConfig.GetSharedVariables()
   at GenericModule.GenericModuleConfig.ApplicationConfig.GetTemplatedValue(String input)
   at GenericModule.GenericApp.SetupProcess()
   at GenericModule.GenericApp.DoAppStartup(Boolean IsUpdating)
   at GenericModule.GenericApp.Start()
[23:41:53] [Core Info]            : AMP is up to date.
[23:41:55] [Core Activity]        : Authentication success
[23:41:55] [API Debug]            : Creating new session for admin at 127.0.0.1 (AMP_ADS_REDIR/10) API Login
[23:41:55] [API Debug]            : Login success from 127.0.0.1 assigned session ID 3515f70b-dc5c-4742-9bce-9226653b324f
[23:41:55] [Core Warning]         : Slow method invocation: Login took 2674ms to complete.
[23:41:55] [Core Warning]         : Slow response: Core.Login took 2682ms to complete.

Greelan commented 1 year ago

Or if you want ADS:

[23:08:47] [AMPService Info]      : Starting AMPService version 2.4.6.6 (Decadeus), built 04/10/2023 19:03
[23:08:47] [AMPService Info]      : Stream: Mainline / Release - built by CUBECODERS/buildbot on CCL-DEV
[23:08:47] [AMPService Info]      : Running in a Xen environment.
[23:08:47] [GSMyAdmin Warning]    : Current time zone is set to AUS Eastern Standard Time - It is recommended you switch to UTC+0 to avoid time-zone related issues
[23:08:47] [AMPService Info]      : OS: Windows / x86_64
[23:08:47] [AMPService Info]      : CPU: Intel(R) Core(TM) i7-7700K CPU @ 4.20GHz (2C/2T)
[23:08:47] [AMPService Info]      : RAM: 16363MB
[23:08:47] [AMPService Info]      : AMP Instance ID: 03128a65-820e-4750-b64f-21475342df7f
[23:08:47] [GSMyAdmin Debug]      : Primary network interface ID is {08CC34C6-A97A-46DA-9190-9FB70791743E} with physical address 00163EDF234C
[23:08:47] [GSMyAdmin Debug]      : Using internal login provider
[23:08:47] [ADS Debug]            : Loaded 0 remote targets from Datastore
[23:08:47] [GSMyAdmin Info]       : Loaded ADSModule version 1.0.0.0 by CubeCoders Limited
[23:08:47] [GSMyAdmin Info]       : Loaded FileManagerPlugin by CubeCoders Limited
[23:08:47] [GSMyAdmin Info]       : Loaded EmailSenderPlugin by CubeCoders Limited
[23:08:47] [GSMyAdmin Info]       : Loaded WebRequestPlugin by CubeCoders Limited
[23:08:47] [FileBackup Debug]     : Read manifests for 0 backups.
[23:08:47] [GSMyAdmin Info]       : Loaded LocalFileBackupPlugin by CubeCoders Limited
[23:08:47] [GSMyAdmin Info]       : Loaded CommonCorePlugin by CubeCoders Limited
[23:08:47] [GSMyAdmin Info]       : ADSModule requests dependency InstanceManagerPlugin...
[23:08:47] [InstanceManager Debug] : Loading instances from C:\AMPDatastore\instances.json...
[23:08:47] [InstanceManager Debug] : Loaded 4 instances.
[23:08:47] [GSMyAdmin Info]       : Loaded InstanceManagerPlugin by CubeCoders Limited
[23:08:47] [GSMyAdmin Info]       : ADSModule requests dependency SystemUserManagerPlugin...
[23:08:47] [GSMyAdmin Info]       : Loaded SystemUserManagerPlugin by CubeCoders Limited
[23:08:47] [GSMyAdmin Info]       : Loaded steamcmdplugin by CubeCoders Limited
[23:08:47] [ADS Debug]            : Found configuration repository at CubeCoders-AMPTemplates, ec280171-c67b-4cf8-923f-dc27fea91ee1
[23:08:47] [ADS Debug]            : Found configuration repository at Greelan-AMPTemplates-dev, 0bb219d6-0255-4dff-b8e3-4e0c8139f635
[23:08:47] [ADS Debug]            : Found configuration repository at Greelan-AMPTemplates-fivemredm, ec280171-c67b-4cf8-923f-dc27fea91ee1
[23:08:47] [ADS Debug]            : Found configuration repository at IceOfWraith-AMPTemplates-dev, ec280171-c67b-4cf8-923f-ice7fea91ee1
[23:08:47] [Core Info]            : Metrics server started OK on port 12820
[23:08:47] [ADS Info]             : ADS startup complete in 127ms
[23:08:48] [FileManager Notice]   : Using keypair with fingerprint Ss4boZ9ufKccrcZPFFnlmV8vwlWRRk8SLDLPvWqd5Tc=
[23:08:48] [FileManager Info]     : SFTP Server started on 0.0.0.0:2223
[23:08:48] [GSMyAdmin Debug]      : Using webroot: C:\AMPDatastore\Instances\ADS01\WebRoot
[23:08:48] [GSMyAdmin Info]       : Websockets are enabled.
[23:08:48] [GSMyAdmin Debug]      : Building setting specification cache...
[23:08:48] [InstanceManager Debug] : Fetching metadata for ADS from C:\AMPDatastore\Instances\ADS01\Plugins\ADSModule\ADSModule.dll...
[23:08:48] [GSMyAdmin Debug]      : Setting spec cache built, 99 items added.
[23:08:48] [InstanceManager Debug] : Fetching metadata for FileManagerPlugin from C:\AMPDatastore\Instances\ADS01\Plugins\FileManagerPlugin\FileManagerPlugin.dll...
[23:08:48] [InstanceManager Debug] : Fetching metadata for EmailSenderPlugin from C:\AMPDatastore\Instances\ADS01\Plugins\EmailSenderPlugin\EmailSenderPlugin.dll...
[23:08:48] [InstanceManager Debug] : Fetching metadata for WebRequestPlugin from C:\AMPDatastore\Instances\ADS01\Plugins\WebRequestPlugin\WebRequestPlugin.dll...
[23:08:48] [InstanceManager Debug] : Fetching metadata for LocalFileBackupPlugin from C:\AMPDatastore\Instances\ADS01\Plugins\LocalFileBackupPlugin\LocalFileBackupPlugin.dll...
[23:08:48] [InstanceManager Debug] : Fetching metadata for CommonCorePlugin from C:\AMPDatastore\Instances\ADS01\Plugins\CommonCorePlugin\CommonCorePlugin.dll...
[23:08:48] [InstanceManager Debug] : Fetching metadata for GenericModule from C:\AMPDatastore\Instances\ADS01\Plugins\GenericModule\GenericModule.dll...
[23:08:48] [Webserver Info]       : RouterTimer@10Hz with 2 jobs started
[23:08:48] [GSMyAdmin Info]       : Webserver started on http://0.0.0.0:8080
[23:08:48] [GSMyAdmin Debug]      : Scheduler timer will start in 11667ms.
[23:08:48] [GSMyAdmin Info]       : Checking for AMP updates...
[23:08:48] [GSMyAdmin Info]       : Startup mode is StartApplication.
[23:08:48] [ADS Debug]            : Refreshing all remote targets...
[23:08:48] [ADS Debug]            : Refreshed all remote targets.
[23:08:48] [ADS Info]             : Updating remote source CubeCoders/AMPTemplates
[23:08:48] [ADS Info]             : Updating existing remote source https://github.com/CubeCoders/AMPTemplates.git...
[23:08:48] [Core Debug]           : Running command line: "C:\Program Files\Git\cmd\git.exe pull" from "Plugins/ADSModule/DeploymentTemplates\CubeCoders-AMPTemplates"
Already up to date.
[23:08:49] [ADS Info]             : Updating remote source IceOfWraith/AMPTemplates:dev
[23:08:49] [ADS Info]             : Updating existing remote source https://github.com/IceOfWraith/AMPTemplates.git...
[23:08:49] [Core Debug]           : Running command line: "C:\Program Files\Git\cmd\git.exe pull" from "Plugins/ADSModule/DeploymentTemplates\IceOfWraith-AMPTemplates-dev"
[23:08:51] [GSMyAdmin Info]       : AMP is up to date.
Already up to date.
[23:08:53] [ADS Info]             : Updating remote source Greelan/AMPTemplates:dev
[23:08:53] [ADS Info]             : Updating existing remote source https://github.com/Greelan/AMPTemplates.git...
[23:08:53] [Core Debug]           : Running command line: "C:\Program Files\Git\cmd\git.exe pull" from "Plugins/ADSModule/DeploymentTemplates\Greelan-AMPTemplates-dev"
Already up to date.
[23:08:53] [ADS Info]             : Updating remote source Greelan/AMPTemplates:fivemredm
[23:08:53] [ADS Info]             : Updating existing remote source https://github.com/Greelan/AMPTemplates.git...
[23:08:53] [Core Debug]           : Running command line: "C:\Program Files\Git\cmd\git.exe pull" from "Plugins/ADSModule/DeploymentTemplates\Greelan-AMPTemplates-fivemredm"
Already up to date.
[23:08:54] [GSMyAdmin Debug]      : Task Updating remote sources (Fetching latest configurations...) ended: Finished
[23:08:59] [GSMyAdmin Debug]      : Login request from 127.0.0.1 for admin
[23:08:59] [GSMyAdmin:admin Debug] : Creating new session for admin at 127.0.0.1 (Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/117.0.0.0 Safari/537.36) API Login
[23:09:00] [GSMyAdmin:admin Debug] : Login success from 127.0.0.1 assigned session ID 0499e153-273e-40a6-966f-f7749ddf994a
[23:09:00] [ADS Debug]            : Refreshing all remote targets...
[23:09:00] [ADS Debug]            : Refreshed all remote targets.
[23:09:02] [GSMyAdmin Debug]      : Login request from 127.0.0.1 for admin
[23:09:02] [GSMyAdmin:admin Debug] : Creating new session for admin at 127.0.0.1 (Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/117.0.0.0 Safari/537.36) API Login
[23:09:02] [GSMyAdmin:admin Debug] : Login success from 127.0.0.1 assigned session ID 6c33c39e-cddb-4d2a-bced-ec8def241d40
[23:09:02] [GSMyAdmin Debug]      : Session 6c33c39e-cddb-4d2a-bced-ec8def241d40 requested websockets upgrade.
[23:09:02] [ADS:admin Debug]      : Found configuration repository at CubeCoders-AMPTemplates, ec280171-c67b-4cf8-923f-dc27fea91ee1
[23:09:03] [ADS:admin Debug]      : Found configuration repository at Greelan-AMPTemplates-dev, 0bb219d6-0255-4dff-b8e3-4e0c8139f635
[23:09:03] [ADS:admin Debug]      : Found configuration repository at Greelan-AMPTemplates-fivemredm, ec280171-c67b-4cf8-923f-dc27fea91ee1
[23:09:03] [ADS:admin Debug]      : Found configuration repository at IceOfWraith-AMPTemplates-dev, ec280171-c67b-4cf8-923f-ice7fea91ee1
[23:09:07] [InstanceManager:admin Debug] : Running C:\AMPDatastore\Instances\BattleBitAPIRunner01\AMP.exe +Core.Webserver.Port "8083" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "446f5662-8981-43ac-8304-5d8281980605" +FileManagerPlugin.SFTP.SFTPPortNumber "2226" +GenericModule.App.Ports.$ServerPort "29294"
[23:09:07] [Core:admin Debug]     : Working Directory: C:\AMPDatastore\Instances\BattleBitAPIRunner01
[23:09:07] [Core:admin Debug]     : Arguments: +Core.Webserver.Port "8083" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "********-****-****-****-************" +FileManagerPlugin.SFTP.SFTPPortNumber "2226" +GenericModule.App.Ports.$ServerPort "29294"
[23:09:07] [InstanceManager:admin Info] : Waiting for AMP instance to start...
[23:09:07] [InstanceManager:admin Notice] : AMP instance BattleBitAPIRunner01 is now running.
[23:09:11] [InstanceManager:admin Activity] : Instance BattleBitAPIRunner01 has stopped
[23:09:11] [ADS:admin Info]       : ADS logged stopped instance BattleBitAPIRunner01 - exit code 32 (NO_LICENCE)
[23:09:11] [ADS:admin Info]       : Reactivating instance BattleBitAPIRunner01...
[23:09:11] [InstanceManager:admin Debug] : Updating Permissions...
[23:09:11] [InstanceManager:admin Debug] : Building arguments list...
[23:09:11] [InstanceManager:admin Debug] : Running AMP with configuration arguments...
[23:09:11] [Core:admin Debug]     : Working Directory: C:\AMPDatastore\Instances\BattleBitAPIRunner01
[23:09:11] [Core:admin Debug]     : Arguments: +Core.Webserver.Port "8083" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "********-****-****-****-************" +FileManagerPlugin.SFTP.SFTPPortNumber "2226" +GenericModule.App.Ports.$ServerPort "29294"
[23:09:12] [GSMyAdmin:admin Debug] : Task Starting Instance (Starting BattleBitAPIRunner (BattleBitAPIRunner01)...) ended: Finished
[23:09:14] [InstanceManager:admin Debug] : Saving instances to C:\AMPDatastore\instances.json...
[23:09:14] [InstanceManager:admin Debug] : Wrote 4 instances.
[23:09:14] [InstanceManager:admin Debug] : Running C:\AMPDatastore\Instances\BattleBitAPIRunner01\AMP.exe +Core.Webserver.Port "8083" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "446f5662-8981-43ac-8304-5d8281980605" +FileManagerPlugin.SFTP.SFTPPortNumber "2226" +GenericModule.App.Ports.$ServerPort "29294"
[23:09:14] [Core:admin Debug]     : Working Directory: C:\AMPDatastore\Instances\BattleBitAPIRunner01
[23:09:14] [Core:admin Debug]     : Arguments: +Core.Webserver.Port "8083" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "********-****-****-****-************" +FileManagerPlugin.SFTP.SFTPPortNumber "2226" +GenericModule.App.Ports.$ServerPort "29294"
[23:09:14] [InstanceManager:admin Info] : Waiting for AMP instance to start...
[23:09:14] [InstanceManager:admin Notice] : AMP instance BattleBitAPIRunner01 is now running.
[23:09:15] [ADS:admin Activity]   : Managed remote instance BattleBitAPIRunner01 at http://127.0.0.1:8083/
[23:09:15] [GSMyAdmin:admin Activity] : Authentication token for admin requested by ManageInstance on behalf of admin
[23:09:15] [GSMyAdmin:admin Warning] : Slow method invocation: ManageInstance took 3983ms to complete.
[23:09:15] [GSMyAdmin:admin Warning] : Slow response: ADSModule.ManageInstance took 3989ms to complete.
[23:09:15] [ADS:admin Activity]   : Managed remote instance BattleBitAPIRunner01 at http://127.0.0.1:8083/
[23:09:15] [GSMyAdmin:admin Activity] : Authentication token for admin requested by ManageInstance on behalf of admin
[23:09:18] [ADS:admin Activity]   : Managed remote instance BattleBitAPIRunner01 at http://127.0.0.1:8083/
[23:09:18] [GSMyAdmin:admin Activity] : Authentication token for admin requested by ManageInstance on behalf of admin
[23:09:19] [GSMyAdmin:admin Debug] : Task Configuring Instance (Configuring BattleBitAPIRunner (BattleBitAPIRunner01)...) ended: Finished
[23:09:19] [GSMyAdmin:admin Debug] : Task Starting Instance (Starting BattleBitAPIRunner (BattleBitAPIRunner01)...) ended: Finished
[23:09:19] [GSMyAdmin Debug]      : Login request from 127.0.0.1 for admin@446f5662-8981-43ac-8304-5d8281980605 via 446f5662-8981-43ac-8304-5d8281980605
[23:09:20] [GSMyAdmin:admin Debug] : Creating new session for admin at 127.0.0.1 (CCL/AMPAPI) Remote Server Login
[23:09:20] [GSMyAdmin:admin Debug] : Login success from 127.0.0.1 assigned session ID b132bb15-a371-41f3-8d0f-51c76b0cbf23
[23:09:20] [GSMyAdmin Warning]    : Slow method invocation: Servers took 2773ms to complete.
[23:09:20] [GSMyAdmin Warning]    : Slow response: ADSModule.Servers took 2774ms to complete.
[23:09:20] [GSMyAdmin Debug]      : Login request from 127.0.0.1 for admin@446f5662-8981-43ac-8304-5d8281980605 via 446f5662-8981-43ac-8304-5d8281980605
[23:09:21] [GSMyAdmin:admin Debug] : Creating new session for admin at 127.0.0.1 (CCL/AMPAPI) Remote Server Login
[23:09:21] [GSMyAdmin:admin Debug] : Login success from 127.0.0.1 assigned session ID a53a494b-81d3-4da5-9c78-19451b61593f
[23:09:21] [GSMyAdmin Warning]    : Slow method invocation: Servers took 2313ms to complete.
[23:09:21] [GSMyAdmin Warning]    : Slow response: ADSModule.Servers took 2314ms to complete.
[23:10:00] [ADS Debug]            : Refreshing all remote targets...
[23:10:00] [ADS Debug]            : Refreshed all remote targets.
[23:11:00] [ADS Debug]            : Refreshing all remote targets...
[23:11:00] [ADS Debug]            : Refreshed all remote targets.
[23:12:00] [ADS Debug]            : Refreshing all remote targets...
[23:12:00] [ADS Debug]            : Refreshed all remote targets.
[23:13:00] [ADS Debug]            : Refreshing all remote targets...
[23:13:00] [ADS Debug]            : Refreshed all remote targets.
[23:14:00] [ADS Debug]            : Refreshing all remote targets...
[23:14:00] [ADS Debug]            : Refreshed all remote targets.
[23:14:12] [FileManager:admin Activity] : Uploaded file __VDS__BattleBitAPIRunner01/GenericModule.kvp (API Mode)
[23:14:17] [InstanceManager:admin Info] : Stopping instance BattleBitAPIRunner01...
[23:14:17] [InstanceManager:admin Info] : Requesting soft-stop...
[23:14:17] [InstanceManager:admin Info] : Waiting for instance to stop...
[23:14:38] [InstanceManager:admin Info] : Soft-stop request was ignored after waiting for 20 seconds, force stopping...
[23:14:38] [InstanceManager:admin Info] : Instance BattleBitAPIRunner01 has stopped.
[23:14:38] [GSMyAdmin:admin Debug] : Task Stopping Instance (Stopping BattleBitAPIRunner (BattleBitAPIRunner01)...) ended: Finished
[23:15:00] [ADS Debug]            : Refreshing all remote targets...
[23:15:00] [ADS Debug]            : Refreshed all remote targets.
[23:16:00] [ADS Debug]            : Refreshing all remote targets...
[23:16:00] [ADS Debug]            : Refreshed all remote targets.
[23:16:41] [InstanceManager:admin Debug] : Running C:\AMPDatastore\Instances\Counter-Strike1601\AMP.exe +Core.Webserver.Port "8081" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "d36173ed-fa2f-44c5-a250-ea51520fe243" +FileManagerPlugin.SFTP.SFTPPortNumber "2224" +GenericModule.App.Ports.$GameRCONPort "27015"
[23:16:41] [Core:admin Debug]     : Working Directory: C:\AMPDatastore\Instances\Counter-Strike1601
[23:16:41] [Core:admin Debug]     : Arguments: +Core.Webserver.Port "8081" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "********-****-****-****-************" +FileManagerPlugin.SFTP.SFTPPortNumber "2224" +GenericModule.App.Ports.$GameRCONPort "27015"
[23:16:41] [InstanceManager:admin Info] : Waiting for AMP instance to start...
[23:16:42] [InstanceManager:admin Notice] : AMP instance Counter-Strike1601 is now running.
[23:16:46] [GSMyAdmin:admin Debug] : Task Starting Instance (Starting Counter-Strike16 (Counter-Strike1601)...) ended: Finished
[23:16:49] [InstanceManager:admin Activity] : Instance Counter-Strike1601 has stopped
[23:16:49] [ADS:admin Info]       : ADS logged stopped instance Counter-Strike1601 - exit code 32 (NO_LICENCE)
[23:16:49] [ADS:admin Info]       : Reactivating instance Counter-Strike1601...
[23:16:49] [InstanceManager:admin Debug] : Updating Permissions...
[23:16:49] [InstanceManager:admin Debug] : Building arguments list...
[23:16:49] [InstanceManager:admin Debug] : Running AMP with configuration arguments...
[23:16:49] [Core:admin Debug]     : Working Directory: C:\AMPDatastore\Instances\Counter-Strike1601
[23:16:49] [Core:admin Debug]     : Arguments: +Core.Webserver.Port "8081" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "********-****-****-****-************" +FileManagerPlugin.SFTP.SFTPPortNumber "2224" +GenericModule.App.Ports.$GameRCONPort "27015"
[23:16:53] [InstanceManager:admin Debug] : Saving instances to C:\AMPDatastore\instances.json...
[23:16:53] [InstanceManager:admin Debug] : Wrote 4 instances.
[23:16:53] [InstanceManager:admin Debug] : Running C:\AMPDatastore\Instances\Counter-Strike1601\AMP.exe +Core.Webserver.Port "8081" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "d36173ed-fa2f-44c5-a250-ea51520fe243" +FileManagerPlugin.SFTP.SFTPPortNumber "2224" +GenericModule.App.Ports.$GameRCONPort "27015"
[23:16:53] [Core:admin Debug]     : Working Directory: C:\AMPDatastore\Instances\Counter-Strike1601
[23:16:53] [Core:admin Debug]     : Arguments: +Core.Webserver.Port "8081" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "********-****-****-****-************" +FileManagerPlugin.SFTP.SFTPPortNumber "2224" +GenericModule.App.Ports.$GameRCONPort "27015"
[23:16:53] [InstanceManager:admin Info] : Waiting for AMP instance to start...
[23:16:53] [ADS:admin Activity]   : Managed remote instance Counter-Strike1601 at http://127.0.0.1:8081/
[23:16:53] [GSMyAdmin:admin Activity] : Authentication token for admin requested by ManageInstance on behalf of admin
[23:16:53] [GSMyAdmin:admin Warning] : Slow method invocation: ManageInstance took 3641ms to complete.
[23:16:53] [GSMyAdmin:admin Warning] : Slow response: ADSModule.ManageInstance took 3642ms to complete.
[23:16:53] [InstanceManager:admin Notice] : AMP instance Counter-Strike1601 is now running.
[23:16:55] [GSMyAdmin Warning]    : Slow method invocation: Servers took 2497ms to complete.
[23:16:55] [GSMyAdmin Warning]    : Slow response: ADSModule.Servers took 2498ms to complete.
[23:16:57] [GSMyAdmin:admin Debug] : Task Configuring Instance (Configuring Counter-Strike16 (Counter-Strike1601)...) ended: Finished
[23:16:58] [GSMyAdmin:admin Debug] : Task Starting Instance (Starting Counter-Strike16 (Counter-Strike1601)...) ended: Finished
[23:16:58] [GSMyAdmin Debug]      : Login request from 127.0.0.1 for admin@d36173ed-fa2f-44c5-a250-ea51520fe243 via d36173ed-fa2f-44c5-a250-ea51520fe243
[23:16:58] [GSMyAdmin:admin Debug] : Ending session a53a494b-81d3-4da5-9c78-19451b61593f (admin at 127.0.0.1, Remote Server Login) - No activity for 5 minutes.
[23:16:58] [GSMyAdmin:admin Debug] : Ending session b132bb15-a371-41f3-8d0f-51c76b0cbf23 (admin at 127.0.0.1, Remote Server Login) - No activity for 5 minutes.
[23:16:58] [GSMyAdmin:admin Debug] : Ending session 0499e153-273e-40a6-966f-f7749ddf994a (admin at 127.0.0.1, API Login) - No activity for 5 minutes.
[23:16:58] [GSMyAdmin:admin Debug] : Creating new session for admin at 127.0.0.1 (CCL/AMPAPI) Remote Server Login
[23:16:58] [GSMyAdmin:admin Debug] : Login success from 127.0.0.1 assigned session ID af316740-f1fc-4477-8a22-14b43b5cafd3
[23:16:58] [GSMyAdmin Warning]    : Slow method invocation: Servers took 2668ms to complete.
[23:16:58] [GSMyAdmin Warning]    : Slow response: ADSModule.Servers took 2668ms to complete.
[23:17:00] [ADS Debug]            : Refreshing all remote targets...
[23:17:00] [ADS Debug]            : Refreshed all remote targets.
[23:18:00] [ADS Debug]            : Refreshing all remote targets...
[23:18:00] [ADS Debug]            : Refreshed all remote targets.
[23:18:38] [InstanceManager:admin Debug] : Running C:\AMPDatastore\Instances\BattleBitAPIRunner01\AMP.exe +Core.Webserver.Port "8083" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "446f5662-8981-43ac-8304-5d8281980605" +FileManagerPlugin.SFTP.SFTPPortNumber "2226" +GenericModule.App.Ports.$ServerPort "29294"
[23:18:38] [Core:admin Debug]     : Working Directory: C:\AMPDatastore\Instances\BattleBitAPIRunner01
[23:18:38] [Core:admin Debug]     : Arguments: +Core.Webserver.Port "8083" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "********-****-****-****-************" +FileManagerPlugin.SFTP.SFTPPortNumber "2226" +GenericModule.App.Ports.$ServerPort "29294"
[23:18:38] [InstanceManager:admin Info] : Waiting for AMP instance to start...
[23:18:38] [InstanceManager:admin Notice] : AMP instance BattleBitAPIRunner01 is now running.
[23:18:42] [ADS:admin Activity]   : Managed remote instance BattleBitAPIRunner01 at http://127.0.0.1:8083/
[23:18:42] [GSMyAdmin:admin Activity] : Authentication token for admin requested by ManageInstance on behalf of admin
[23:18:43] [GSMyAdmin:admin Debug] : Task Starting Instance (Starting BattleBitAPIRunner (BattleBitAPIRunner01)...) ended: Finished
[23:18:45] [GSMyAdmin Debug]      : Login request from 127.0.0.1 for admin@446f5662-8981-43ac-8304-5d8281980605 via 446f5662-8981-43ac-8304-5d8281980605
[23:18:45] [GSMyAdmin:admin Debug] : Creating new session for admin at 127.0.0.1 (CCL/AMPAPI) Remote Server Login
[23:18:45] [GSMyAdmin:admin Debug] : Login success from 127.0.0.1 assigned session ID 4bcdd260-77f0-4064-8fcc-314afa40cad3
[23:18:45] [GSMyAdmin Warning]    : Slow method invocation: Servers took 2708ms to complete.
[23:18:45] [GSMyAdmin Warning]    : Slow response: ADSModule.Servers took 2708ms to complete.
[23:19:00] [ADS Debug]            : Refreshing all remote targets...
[23:19:00] [ADS Debug]            : Refreshed all remote targets.
[23:19:00] [InstanceManager:admin Info] : Stopping instance BattleBitAPIRunner01...
[23:19:00] [InstanceManager:admin Info] : Requesting soft-stop...
[23:19:00] [InstanceManager:admin Info] : Waiting for instance to stop...
[23:19:04] [InstanceManager:admin Info] : Stopping instance Counter-Strike1601...
[23:19:04] [InstanceManager:admin Info] : Requesting soft-stop...
[23:19:04] [InstanceManager:admin Info] : Waiting for instance to stop...
[23:19:21] [InstanceManager:admin Info] : Soft-stop request was ignored after waiting for 20 seconds, force stopping...
[23:19:21] [InstanceManager:admin Activity] : Instance BattleBitAPIRunner01 has stopped
[23:19:21] [InstanceManager:admin Info] : Instance BattleBitAPIRunner01 has stopped.
[23:19:21] [GSMyAdmin:admin Debug] : Task Stopping Instance (Stopping BattleBitAPIRunner (BattleBitAPIRunner01)...) ended: Finished
[23:19:25] [InstanceManager:admin Info] : Soft-stop request was ignored after waiting for 20 seconds, force stopping...
[23:19:25] [InstanceManager:admin Activity] : Instance Counter-Strike1601 has stopped
[23:19:25] [InstanceManager:admin Info] : Instance Counter-Strike1601 has stopped.
[23:19:25] [GSMyAdmin:admin Debug] : Task Stopping Instance (Stopping Counter-Strike16 (Counter-Strike1601)...) ended: Finished
[23:20:00] [ADS Debug]            : Refreshing all remote targets...
[23:20:00] [ADS Debug]            : Refreshed all remote targets.
[23:21:00] [ADS Debug]            : Refreshing all remote targets...
[23:21:00] [ADS Debug]            : Refreshed all remote targets.
[23:22:00] [ADS Debug]            : Refreshing all remote targets...
[23:22:00] [ADS Debug]            : Refreshed all remote targets.
[23:23:00] [ADS Debug]            : Refreshing all remote targets...
[23:23:00] [ADS Debug]            : Refreshed all remote targets.
[23:24:00] [ADS Debug]            : Refreshing all remote targets...
[23:24:00] [ADS Debug]            : Refreshed all remote targets.
[23:25:00] [ADS Debug]            : Refreshing all remote targets...
[23:25:00] [ADS Debug]            : Refreshed all remote targets.
[23:26:00] [ADS Debug]            : Refreshing all remote targets...
[23:26:00] [ADS Debug]            : Refreshed all remote targets.
[23:27:00] [ADS Debug]            : Refreshing all remote targets...
[23:27:00] [ADS Debug]            : Refreshed all remote targets.
[23:27:16] [ADS:admin Info]       : Updating remote source CubeCoders/AMPTemplates
[23:27:16] [ADS:admin Info]       : Updating existing remote source https://github.com/CubeCoders/AMPTemplates.git...
[23:27:16] [Core:admin Debug]     : Running command line: "C:\Program Files\Git\cmd\git.exe pull" from "Plugins/ADSModule/DeploymentTemplates\CubeCoders-AMPTemplates"
Already up to date.
[23:27:16] [ADS:admin Info]       : Updating remote source IceOfWraith/AMPTemplates:dev
[23:27:16] [ADS:admin Info]       : Updating existing remote source https://github.com/IceOfWraith/AMPTemplates.git...
[23:27:16] [Core:admin Debug]     : Running command line: "C:\Program Files\Git\cmd\git.exe pull" from "Plugins/ADSModule/DeploymentTemplates\IceOfWraith-AMPTemplates-dev"
Already up to date.
[23:27:17] [ADS:admin Info]       : Updating remote source Greelan/AMPTemplates:dev
[23:27:17] [ADS:admin Info]       : Updating existing remote source https://github.com/Greelan/AMPTemplates.git...
[23:27:17] [Core:admin Debug]     : Running command line: "C:\Program Files\Git\cmd\git.exe pull" from "Plugins/ADSModule/DeploymentTemplates\Greelan-AMPTemplates-dev"
Already up to date.
[23:27:18] [ADS:admin Info]       : Updating remote source Greelan/AMPTemplates:fivemredm
[23:27:18] [ADS:admin Info]       : Updating existing remote source https://github.com/Greelan/AMPTemplates.git...
[23:27:18] [Core:admin Debug]     : Running command line: "C:\Program Files\Git\cmd\git.exe pull" from "Plugins/ADSModule/DeploymentTemplates\Greelan-AMPTemplates-fivemredm"
Already up to date.
[23:27:19] [ADS:admin Debug]      : Found configuration repository at CubeCoders-AMPTemplates, ec280171-c67b-4cf8-923f-dc27fea91ee1
[23:27:19] [ADS:admin Debug]      : Found configuration repository at Greelan-AMPTemplates-dev, 0bb219d6-0255-4dff-b8e3-4e0c8139f635
[23:27:19] [ADS:admin Debug]      : Found configuration repository at Greelan-AMPTemplates-fivemredm, ec280171-c67b-4cf8-923f-dc27fea91ee1
[23:27:19] [ADS:admin Debug]      : Found configuration repository at IceOfWraith-AMPTemplates-dev, ec280171-c67b-4cf8-923f-ice7fea91ee1
[23:27:24] [GSMyAdmin:admin Debug] : Task Updating remote sources (Fetching latest configurations...) ended: Finished
[23:28:00] [ADS Debug]            : Refreshing all remote targets...
[23:28:00] [ADS Debug]            : Refreshed all remote targets.
[23:29:00] [ADS Debug]            : Refreshing all remote targets...
[23:29:00] [ADS Debug]            : Refreshed all remote targets.
[23:30:00] [ADS Debug]            : Refreshing all remote targets...
[23:30:00] [ADS Debug]            : Refreshed all remote targets.
[23:31:00] [ADS Debug]            : Refreshing all remote targets...
[23:31:00] [ADS Debug]            : Refreshed all remote targets.
[23:32:00] [ADS Debug]            : Refreshing all remote targets...
[23:32:00] [ADS Debug]            : Refreshed all remote targets.
[23:33:00] [ADS Debug]            : Refreshing all remote targets...
[23:33:00] [ADS Debug]            : Refreshed all remote targets.
[23:34:00] [ADS Debug]            : Refreshing all remote targets...
[23:34:00] [ADS Debug]            : Refreshed all remote targets.
[23:35:00] [ADS Debug]            : Refreshing all remote targets...
[23:35:00] [ADS Debug]            : Refreshed all remote targets.
[23:36:00] [ADS Debug]            : Refreshing all remote targets...
[23:36:00] [ADS Debug]            : Refreshed all remote targets.
[23:37:00] [ADS Debug]            : Refreshing all remote targets...
[23:37:00] [ADS Debug]            : Refreshed all remote targets.
[23:38:00] [ADS Debug]            : Refreshing all remote targets...
[23:38:00] [ADS Debug]            : Refreshed all remote targets.
[23:39:00] [ADS Debug]            : Refreshing all remote targets...
[23:39:00] [ADS Debug]            : Refreshed all remote targets.
[23:40:00] [ADS Debug]            : Refreshing all remote targets...
[23:40:00] [ADS Debug]            : Refreshed all remote targets.
[23:41:00] [ADS Debug]            : Refreshing all remote targets...
[23:41:00] [ADS Debug]            : Refreshed all remote targets.
[23:41:35] [FileManager:admin Activity] : Uploaded file __VDS__OpenRA-RedAlert01/AMPConfig.conf (API Mode)
[23:41:40] [InstanceManager:admin Debug] : Running C:\AMPDatastore\Instances\OpenRA-RedAlert01\AMP.exe +Core.Webserver.Port "8082" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "db22cf23-746c-4df6-95a7-3c36f62a870c" +FileManagerPlugin.SFTP.SFTPPortNumber "2225" +GenericModule.App.Ports.$ServerPort "1234"
[23:41:40] [Core:admin Debug]     : Working Directory: C:\AMPDatastore\Instances\OpenRA-RedAlert01
[23:41:40] [Core:admin Debug]     : Arguments: +Core.Webserver.Port "8082" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "********-****-****-****-************" +FileManagerPlugin.SFTP.SFTPPortNumber "2225" +GenericModule.App.Ports.$ServerPort "1234"
[23:41:40] [InstanceManager:admin Info] : Waiting for AMP instance to start...
[23:41:40] [InstanceManager:admin Notice] : AMP instance OpenRA-RedAlert01 is now running.
[23:41:45] [GSMyAdmin:admin Debug] : Task Starting Instance (Starting OpenRA-RedAlert (OpenRA-RedAlert01)...) ended: Finished
[23:41:46] [InstanceManager:admin Activity] : Instance OpenRA-RedAlert01 has stopped
[23:41:46] [ADS:admin Info]       : ADS logged stopped instance OpenRA-RedAlert01 - exit code 32 (NO_LICENCE)
[23:41:46] [ADS:admin Info]       : Reactivating instance OpenRA-RedAlert01...
[23:41:46] [InstanceManager:admin Debug] : Updating Permissions...
[23:41:46] [InstanceManager:admin Debug] : Building arguments list...
[23:41:46] [InstanceManager:admin Debug] : Running AMP with configuration arguments...
[23:41:46] [Core:admin Debug]     : Working Directory: C:\AMPDatastore\Instances\OpenRA-RedAlert01
[23:41:46] [Core:admin Debug]     : Arguments: +Core.Webserver.Port "8082" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "********-****-****-****-************" +FileManagerPlugin.SFTP.SFTPPortNumber "2225" +GenericModule.App.Ports.$ServerPort "1234"
[23:41:50] [InstanceManager:admin Debug] : Saving instances to C:\AMPDatastore\instances.json...
[23:41:50] [InstanceManager:admin Debug] : Wrote 4 instances.
[23:41:50] [InstanceManager:admin Debug] : Running C:\AMPDatastore\Instances\OpenRA-RedAlert01\AMP.exe +Core.Webserver.Port "8082" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "db22cf23-746c-4df6-95a7-3c36f62a870c" +FileManagerPlugin.SFTP.SFTPPortNumber "2225" +GenericModule.App.Ports.$ServerPort "1234"
[23:41:50] [Core:admin Debug]     : Working Directory: C:\AMPDatastore\Instances\OpenRA-RedAlert01
[23:41:50] [Core:admin Debug]     : Arguments: +Core.Webserver.Port "8082" +Core.Webserver.IPBinding "127.0.0.1" +Core.AMP.InstanceID "********-****-****-****-************" +FileManagerPlugin.SFTP.SFTPPortNumber "2225" +GenericModule.App.Ports.$ServerPort "1234"
[23:41:50] [InstanceManager:admin Info] : Waiting for AMP instance to start...
[23:41:50] [ADS:admin Activity]   : Managed remote instance OpenRA-RedAlert01 at http://127.0.0.1:8082/
[23:41:50] [GSMyAdmin:admin Activity] : Authentication token for admin requested by ManageInstance on behalf of admin
[23:41:50] [GSMyAdmin:admin Warning] : Slow method invocation: ManageInstance took 3606ms to complete.
[23:41:50] [GSMyAdmin:admin Warning] : Slow response: ADSModule.ManageInstance took 3607ms to complete.
[23:41:50] [InstanceManager:admin Notice] : AMP instance OpenRA-RedAlert01 is now running.
[23:41:54] [GSMyAdmin:admin Debug] : Task Configuring Instance (Configuring OpenRA-RedAlert (OpenRA-RedAlert01)...) ended: Finished
[23:41:55] [GSMyAdmin Debug]      : Login request from 127.0.0.1 for admin@db22cf23-746c-4df6-95a7-3c36f62a870c via db22cf23-746c-4df6-95a7-3c36f62a870c
[23:41:55] [GSMyAdmin:admin Debug] : Ending session 4bcdd260-77f0-4064-8fcc-314afa40cad3 (admin at 127.0.0.1, Remote Server Login) - No activity for 5 minutes.
[23:41:55] [GSMyAdmin:admin Debug] : Ending session af316740-f1fc-4477-8a22-14b43b5cafd3 (admin at 127.0.0.1, Remote Server Login) - No activity for 5 minutes.
[23:41:55] [GSMyAdmin:admin Debug] : Creating new session for admin at 127.0.0.1 (CCL/AMPAPI) Remote Server Login
[23:41:55] [GSMyAdmin:admin Debug] : Login success from 127.0.0.1 assigned session ID 3515f70b-dc5c-4742-9bce-9226653b324f
[23:41:55] [GSMyAdmin:admin Debug] : Task Starting Instance (Starting OpenRA-RedAlert (OpenRA-RedAlert01)...) ended: Finished
[23:41:55] [GSMyAdmin Warning]    : Slow method invocation: Servers took 2695ms to complete.
[23:41:55] [GSMyAdmin Warning]    : Slow response: ADSModule.Servers took 2695ms to complete.
[23:42:00] [ADS Debug]            : Refreshing all remote targets...
[23:42:00] [ADS Debug]            : Refreshed all remote targets.
[23:43:00] [ADS Debug]            : Refreshing all remote targets...
[23:43:00] [ADS Debug]            : Refreshed all remote targets.
[23:44:00] [ADS Debug]            : Refreshing all remote targets...
[23:44:00] [ADS Debug]            : Refreshed all remote targets.

Greelan commented 1 year ago

I see the reconfiguring that happens when the instance is managed is because of the "no licence" shenanigans... sheesh

PhonicUK commented 1 year ago

Aah I think I know what's up, it's to do with the new dictionary setters. Hold fire...

PhonicUK commented 1 year ago

Yeah dw I see what's up. Working on it :)

PhonicUK commented 1 year ago

Yeah dw I see what's up. Working on it :)

PhonicUK commented 1 year ago

Fixed in the patch that just dropped.

Greelan commented 1 year ago

So instances with no port information have to be fixed manually now?

CubeCoders / AMP

AMP 2.4.6.6 on Windows Server 2019: Startup of generic application no longer possible #933

Bug Report

System Information

I confirm:

Symptoms

Reproduction