System Information
| Field | Value |
|---|---|
| Operating System | Windows - Windows Server 2022 on x86_64 |
| Product | AMP ‘Phobos’ v2.6.0.2 (Mainline) |
| Virtualization | VMware |
| Application | E.Y.E: Divine Cybermancy |
| Module | GenericModule |
| Running in Container | No |
| Current State | Ready |
Task
Working analytics
Problem Description
Issue
Analytics have been enabled, and I’ve waited a couple days for the data to propagate, but am still not seeing anything. I have connected and disconnected from the server (as I saw analytics will not be logged until the player disconnects), and it does not seem to have any effect. I have also verified other players have come and go with no data being logged. I checked the template’s regex and confirmed it is correct (AMPTemplates/eye-dc.kvp at main · CubeCoders/AMPTemplates · GitHub), and have also looked through the various Discord channels and seen no definitive solution for this issue.
I have seen people mentioning analytics rely on the ability to scrape the console, which should not be a problem as Source Engine games provide the required join / leave / IP / SteamID information in the console. Looking through the logs I do not see any errors, but will post one of the logs below (all PII has been replaced with dummy information)
[13:28:14] [Core Info/1] : Starting AMP version 2.6.0.2 (Phobos), built 30/10/2024 22:00
[13:28:14] [Core Info/1] : Stream: Mainline / Release - built by CUBECODERS/buildbot on CCL-DEV
[13:28:14] [Core Info/1] : Running in a VMware environment.
[13:28:14] [Core Warning/1] : Current time zone is set to Eastern Standard Time - It is recommended you switch to UTC+0 to avoid time-zone related issues
[13:28:14] [Core Info/1] : OS: Windows / x86_64
[13:28:14] [Core Info/1] : CPU: Intel(R) Xeon(R) CPU E5-2697 v2 @ 2.70GHz (4C/4T)
[13:28:14] [Core Info/1] : RAM: 16383MB
[13:28:14] [Core Info/1] : AMP Instance ID: 3a9196f2-61f5-41b9-8cc1-162f5f4718bb
[13:28:14] [Loader Info/1] : Loaded GenericModule version 2.6.0.2 by CubeCoders Limited
[13:28:14] [Core Info/1] : Metrics publishing is enabled at udp://localhost:12820.
[13:28:14] [Loader Info/1] : Loaded FileManagerPlugin by CubeCoders Limited
[13:28:14] [Loader Info/1] : Loaded EmailSenderPlugin by CubeCoders Limited
[13:28:14] [Loader Info/1] : Loaded WebRequestPlugin by CubeCoders Limited
[13:28:14] [Loader Info/1] : Loaded LocalFileBackupPlugin by CubeCoders Limited
[13:28:14] [Loader Info/1] : Loaded CommonCorePlugin by CubeCoders Limited
[13:28:14] [Loader Info/1] : Loaded AnalyticsPlugin by CubeCoders Limited
[13:28:14] [Loader Error/7] : Missing plugin/assembly: MaxMind.Db (C:\AMPDatastore\Instances\EYEServer01\Plugins\MaxMind.Db\MaxMind.Db.dll) for
[13:28:14] [Loader Info/1] : GenericModule requests dependency steamcmdplugin...
[13:28:14] [Loader Info/1] : Loaded steamcmdplugin by CubeCoders Limited
[13:28:14] [Loader Info/1] : GenericModule requests dependency RCONPlugin...
[13:28:14] [Loader Info/1] : Loaded RCONPlugin by CubeCoders Limited
[13:28:14] [Core Info/1] : Licence Present: AMP Advanced Edition - Lifetime Licence
[13:28:15] [FileManager Notice/1] : Using keypair with fingerprint ggqGD+d2A5utRf97NB4OCjRT5WFOILnzm0CEC4d5Hhs=
[13:28:15] [FileManager Info/1] : SFTP Server started on 0.0.0.0:2224
[13:28:15] [Webserver Info/1] : Webserver started on http://10.30.0.20:8081
[13:28:15] [Core Info/5] : Startup mode is UpdateAndStart.
[13:28:15] [Core Info/7] : Checking for AMP updates...
[13:28:15] [Core Info/13] : AMP is up to date.
[13:28:16] [Generic Info/13] : Merging config file ./eye-dc/91720/EYE/cfg/server.cfg (Template) using Unicode (UTF-8) encoding.
11/04/2024 - 08:28:23: [META] Loaded 1 plugin.
11/04/2024 - 08:28:23: Log file closed
11/04/2024 - 08:28:23: Log file started (file "logs\L1104003.log") (game "C:\AMPDatastore\Instances\EYEServer01\eye-dc\91720\EYE") (version "5183")
11/04/2024 - 08:28:23: Loading map "cm_cu_backstreets_v3"
11/04/2024 - 08:28:23: server cvars start
11/04/2024 - 08:28:23: "sourcemod_version" = "1.12.0.7165"
11/04/2024 - 08:28:23: "sm_nextmap" = ""
11/04/2024 - 08:28:23: "metamod_version" = "2.0.0-dev+1314V"
11/04/2024 - 08:28:23: "mp_teamplay" = "0"
11/04/2024 - 08:28:23: "mp_falldamage" = "0"
11/04/2024 - 08:28:23: "mp_weaponstay" = "0"
11/04/2024 - 08:28:23: "mp_forcerespawn" = "1"
11/04/2024 - 08:28:23: "mp_footsteps" = "1"
11/04/2024 - 08:28:23: "mp_flashlight" = "1"
11/04/2024 - 08:28:23: "mp_autocrosshair" = "1"
11/04/2024 - 08:28:23: "decalfrequency" = "10"
11/04/2024 - 08:28:23: "mp_teamlist" = "hgrunt;scientist"
11/04/2024 - 08:28:23: "mp_allowNPCs" = "1"
11/04/2024 - 08:28:23: "mp_friendly" = "0"
11/04/2024 - 08:28:23: "mp_friendlyfire" = "0"
11/04/2024 - 08:28:23: "mp_fadetoblack" = "0"
11/04/2024 - 08:28:23: "sv_gravity" = "600"
11/04/2024 - 08:28:23: "sv_stopspeed" = "100"
11/04/2024 - 08:28:23: "sv_noclipaccelerate" = "5"
11/04/2024 - 08:28:23: "sv_noclipspeed" = "5"
11/04/2024 - 08:28:23: "sv_specaccelerate" = "10"
11/04/2024 - 08:28:23: "sv_specspeed" = "3"
11/04/2024 - 08:28:23: "sv_specnoclip" = "1"
11/04/2024 - 08:28:23: "sv_maxspeed" = "3000"
11/04/2024 - 08:28:23: "sv_accelerate" = "10"
11/04/2024 - 08:28:23: "sv_airaccelerate" = "10"
11/04/2024 - 08:28:23: "sv_wateraccelerate" = "10"
11/04/2024 - 08:28:23: "sv_waterfriction" = "1"
11/04/2024 - 08:28:23: "sv_footsteps" = "1"
11/04/2024 - 08:28:23: "sv_rollspeed" = "200"
11/04/2024 - 08:28:23: "sv_rollangle" = "0"
11/04/2024 - 08:28:23: "sv_friction" = "4"
11/04/2024 - 08:28:23: "sv_bounce" = "0"
11/04/2024 - 08:28:23: "sv_stepsize" = "18"
11/04/2024 - 08:28:23: "r_VehicleViewDampen" = "1"
11/04/2024 - 08:28:23: "r_JeepViewDampenFreq" = "7.0"
11/04/2024 - 08:28:23: "r_JeepViewDampenDamp" = "1.0"
11/04/2024 - 08:28:23: "r_JeepViewZHeight" = "10.0"
11/04/2024 - 08:28:23: "r_AirboatViewDampenFreq" = "7.0"
11/04/2024 - 08:28:23: "r_AirboatViewDampenDamp" = "1.0"
11/04/2024 - 08:28:23: "r_AirboatViewZHeight" = "0.0"
11/04/2024 - 08:28:23: "mp_timelimit" = "0"
11/04/2024 - 08:28:23: "mp_fraglimit" = "0"
11/04/2024 - 08:28:23: "nextlevel" = ""
11/04/2024 - 08:28:23: "mp_respawnwavetime" = "10.0"
11/04/2024 - 08:28:23: "mp_tournament" = "0"
11/04/2024 - 08:28:23: "tf_arena_preround_time" = "10"
11/04/2024 - 08:28:23: "tf_arena_round_time" = "0"
11/04/2024 - 08:28:23: "tf_arena_max_streak" = "3"
11/04/2024 - 08:28:23: "tf_arena_use_queue" = "1"
11/04/2024 - 08:28:23: "mp_teams_unbalance_limit" = "1"
11/04/2024 - 08:28:23: "mp_maxrounds" = "0"
11/04/2024 - 08:28:23: "mp_winlimit" = "0"
11/04/2024 - 08:28:23: "mp_disable_respawn_times" = "0"
11/04/2024 - 08:28:23: "mp_stalemate_meleeonly" = "0"
11/04/2024 - 08:28:23: "mp_forceautoteam" = "0"
11/04/2024 - 08:28:23: "mp_autoteambalance" = "1"
11/04/2024 - 08:28:23: "mp_stalemate_enable" = "0"
11/04/2024 - 08:28:23: "mp_match_end_at_timelimit" = "0"
11/04/2024 - 08:28:23: "mp_scrambleteams_auto" = "1"
11/04/2024 - 08:28:23: "mp_scrambleteams_auto_windifference" = "2"
11/04/2024 - 08:28:23: "sv_alltalk" = "0"
11/04/2024 - 08:28:23: "sv_hl2mp_weapon_respawn_time" = "20"
11/04/2024 - 08:28:23: "sv_hl2mp_item_respawn_time" = "99999"
11/04/2024 - 08:28:23: "sv_report_client_settings" = "0"
11/04/2024 - 08:28:23: "sv_ultra_violence" = "1"
11/04/2024 - 08:28:23: "sv_spawn_pause" = "1"
11/04/2024 - 08:28:23: "sv_password" = ""
11/04/2024 - 08:28:23: "sv_tags" = ""
11/04/2024 - 08:28:23: "EYE_GameStyleCh" = "1"
11/04/2024 - 08:28:23: "sv_eye_difficulty_mp" = "1"
11/04/2024 - 08:28:23: "sv_eye_makeratio_mp" = "1"
11/04/2024 - 08:28:23: "sv_lanonly" = "0"
11/04/2024 - 08:28:23: "sv_eye_listen_mp" = "1"
11/04/2024 - 08:28:23: "sv_eye_viewlevel_mp" = "1"
11/04/2024 - 08:28:23: "sv_eye_reaction_mp" = "1"
11/04/2024 - 08:28:23: "sv_eye_accuracy_mp" = "1"
11/04/2024 - 08:28:23: "tv_relaypassword" = ""
11/04/2024 - 08:28:23: "tv_password" = ""
11/04/2024 - 08:28:23: "deathmatch" = "1"
11/04/2024 - 08:28:23: "coop" = "0"
11/04/2024 - 08:28:23: "sv_cheats" = "0"
11/04/2024 - 08:28:23: "sv_pausable" = "0"
11/04/2024 - 08:28:23: "sv_contact" = ""
11/04/2024 - 08:28:23: "sv_voiceenable" = "1"
11/04/2024 - 08:28:23: "tv_enable" = "0"
11/04/2024 - 08:28:23: server cvars end
11/04/2024 - 08:28:24: Started map "cm_cu_backstreets_v3" (CRC "-1041050002")
11/04/2024 - 08:28:32: server_cvar: "sv_tags" "increased_maxplayers"
11/04/2024 - 08:28:32: server_cvar: "sv_tags" "increased_maxplayers,custom_maps"
11/04/2024 - 08:28:32: server_cvar: "sm_nextmap" "cm_cu_test11"
[13:28:35] [Core Activity/5] : Authentication attempt for user admin from 10.30.0.20
[13:28:37] [Core Activity/5] : Authentication success
[13:28:37] [Core Warning/13] : Slow method invocation: Login took 2515ms to complete.
[13:28:37] [Core Warning/13] : Slow response: Core.Login took 2518ms to complete.
11/04/2024 - 08:29:51: "Admin<2><STEAM_0:1:99999999><>" connected, address "10.102.0.10:27005"
11/04/2024 - 08:29:51: "Admin<2><STEAM_0:1:99999999><>" STEAM USERID validated
11/04/2024 - 08:30:15: "Admin<2><STEAM_0:1:99999999><>" entered the game
11/04/2024 - 08:36:42: "Admin<2><STEAM_0:1:99999999><Culter>" disconnected (reason "Disconnect by user.")
[13:43:02] [Core Activity/23] : Authentication attempt for user admin from 10.30.0.20
[13:43:05] [Core Activity/21] : Authentication success
[13:43:05] [Core Warning/21] : Slow method invocation: Login took 2490ms to complete.
[13:43:05] [Core Warning/21] : Slow response: Core.Login took 2492ms to complete.
[13:49:31] [Core Activity/21] : Authentication attempt for user admin from 10.30.0.20
[13:49:33] [Core Activity/17] : Authentication success
[13:49:33] [Core Warning/9] : Slow method invocation: Login took 2467ms to complete.
[13:49:33] [Core Warning/9] : Slow response: Core.Login took 2468ms to complete.
[13:53:29] [Core Activity/20] : Authentication attempt for user admin from 10.30.0.20
[13:53:31] [Core Activity/20] : Authentication failure for user admin from 10.30.0.20 - TokenRejected
[13:53:31] [Core Warning/17] : Slow method invocation: Login took 2285ms to complete.
[13:53:31] [Core Warning/17] : Slow response: Core.Login took 2286ms to complete.
[13:53:50] [Core Activity/12] : Authentication attempt for user admin from 10.30.0.20
[13:53:51] [Core Activity/20] : Authentication success
[13:55:38] [Core Activity/20] : Authentication attempt for user admin from 10.30.0.20
[13:55:40] [Core Activity/12] : Authentication success
[13:55:41] [Core Warning/20] : Slow method invocation: Login took 2488ms to complete.
[13:55:41] [Core Warning/20] : Slow response: Core.Login took 2489ms to complete.
[13:56:19] [Core Activity/20] : Authentication attempt for user admin from 10.30.0.20
[13:56:20] [Core Activity/20] : Authentication success
[13:57:21] [API:admin Activity/3] : Changing setting FileManagerPlugin.FileManager.AdditionalVirtualDirectories to {}
[15:43:52] [Core Activity/22] : Authentication attempt for user admin from 10.30.0.20
[15:43:54] [Core Activity/26] : Authentication success
[15:43:54] [Core Warning/22] : Slow method invocation: Login took 2493ms to complete.
[15:43:54] [Core Warning/22] : Slow response: Core.Login took 2494ms to complete.
11/04/2024 - 12:35:16: "Player<3><STEAM_0:1:99999999><>" connected, address "198.51.100.10:27005"
11/04/2024 - 12:35:16: "Player<3><STEAM_0:1:99999999><>" STEAM USERID validated
11/04/2024 - 12:35:43: "Player<3><STEAM_0:1:99999999><>" entered the game
11/04/2024 - 12:42:36: "Player<3><STEAM_0:1:99999999><Culter>" disconnected (reason "Disconnect by user.")
[17:50:45] [Core Activity/25] : Authentication attempt for user admin from 10.30.0.20
[17:50:48] [Core Activity/24] : Authentication success
[17:50:48] [Core Warning/25] : Slow method invocation: Login took 2521ms to complete.
[17:50:48] [Core Warning/25] : Slow response: Core.Login took 2522ms to complete.
[18:11:59] [Core Info/25] : Checking for AMP updates...
[18:11:59] [Core Info/24] : AMP is up to date.
11/04/2024 - 13:21:11: "Player<4><STEAM_0:1:99999999><>" connected, address "198.51.100.10:27005"
11/04/2024 - 13:21:12: "Player<4><STEAM_0:1:99999999><>" STEAM USERID validated
11/04/2024 - 13:21:19: "Player<4><STEAM_0:1:99999999><>" entered the game
11/04/2024 - 13:27:14: "Player<4><STEAM_0:1:99999999><Culter>" disconnected (reason "Disconnect by user.")
[18:50:45] [Core Activity/20] : Authentication attempt for user admin from 10.30.0.20
[18:50:48] [Core Activity/20] : Authentication success
[18:50:48] [Core Warning/26] : Slow method invocation: Login took 2493ms to complete.
[18:50:48] [Core Warning/26] : Slow response: Core.Login took 2494ms to complete.
[20:04:37] [Core Activity/22] : Authentication attempt for user admin from 10.30.0.20
[20:04:40] [Core Activity/22] : Authentication success
[20:04:40] [Core Warning/14] : Slow method invocation: Login took 2474ms to complete.
[20:04:40] [Core Warning/14] : Slow response: Core.Login took 2475ms to complete.
Reproduction Steps
- Ensure analytics are enabled
- Restarted the application instance, the main ADS instance, and the VM running AMP. Also waited a couple days for data to propagate.
- Checked the AMP Template for regex errors