search

ironmansoftware / powershell-universal

Issue tracker for PowerShell Universal
https://powershelluniversal.com
37 stars 4 forks source link

after db upgrade, unable to /login #3569

Closed Omzig closed 3 months ago

Omzig commented 3 months ago

Version

5.0.0-rc4

Severity

Critical

Environment

Nested IIS

Steps to Reproduce

after upgrade, we are unable to login

Expected behavior

allow login

Actual behavior

endless /login retries

2024-08-06 15:58:14.555 -05:00 [INF] User profile is available. Using 'C:\Users\svc-eisportal-dev\AppData\Local\ASP.NET\DataProtection-Keys' as key repository and Windows DPAPI to encrypt keys at rest.
2024-08-06 15:58:14.747 -05:00 [INF] Starting PowerShell Universal...
2024-08-06 15:58:15.265 -05:00 [INF] PowerShell Universal Version: 5.0.0-rc5
2024-08-06 15:58:15.267 -05:00 [INF] OS Version: Microsoft Windows NT 10.0.17763.0
2024-08-06 15:58:15.267 -05:00 [INF] OS Architecture: x64
2024-08-06 15:58:15.267 -05:00 [INF] Process Architecture: x64
2024-08-06 15:58:15.267 -05:00 [INF] Node Name: MYSERVERNAME
2024-08-06 15:58:15.267 -05:00 [INF] Running startup complete.
2024-08-06 15:58:15.304 -05:00 [INF] Starting PowerShell Universal...
2024-08-06 15:58:15.325 -05:00 [INF] Starting management service...
2024-08-06 15:58:15.538 -05:00 [INF] Loading app tokens...
2024-08-06 15:58:15.659 -05:00 [INF] Initializing database...
2024-08-06 15:58:15.811 -05:00 [INF] RPC services being provided by UniversalAutomation.IPublicSecretManagerService: 1
2024-08-06 15:58:15.821 -05:00 [INF] Loading configuration files...
2024-08-06 15:58:15.830 -05:00 [INF] RPC services being provided by PowerShellUniversal.IdentityClient: 6
2024-08-06 15:58:15.856 -05:00 [INF] RPC services being provided by PowerShellUniversal.JobDataService: 11
2024-08-06 15:58:15.866 -05:00 [WRN] Type cannot be serialized; ignoring: PowerShellUniversal.AuthenticationMethod
2024-08-06 15:58:15.866 -05:00 [WRN] Signature not recognized for PowerShellUniversal.IPublicClient.NewAuthenticationMethod; method will not be bound
2024-08-06 15:58:15.870 -05:00 [WRN] Type cannot be serialized; ignoring: PowerShellUniversal.Translation
2024-08-06 15:58:15.870 -05:00 [WRN] Signature not recognized for PowerShellUniversal.IPublicClient.NewTranslation; method will not be bound
2024-08-06 15:58:15.878 -05:00 [WRN] Signature not recognized for PowerShellUniversal.IPublicClient.SyncComponent; method will not be bound
2024-08-06 15:58:15.920 -05:00 [INF] RPC services being provided by PowerShellUniversal.PublicClient: 29
2024-08-06 15:58:15.931 -05:00 [INF] RPC services being provided by PowerShellUniversal.ComputerClient: 5
2024-08-06 15:58:15.943 -05:00 [INF] RPC services being provided by Universal.Server.Services.DashboardGrpcClient: 7
2024-08-06 15:58:15.945 -05:00 [WRN] Type cannot be serialized; ignoring: System.IDisposable
2024-08-06 15:58:15.946 -05:00 [WRN] Signature not recognized for PowerShellUniversal.IConfigurationSystemWatcher.Pause; method will not be bound
2024-08-06 15:58:15.946 -05:00 [WRN] Signature not recognized for PowerShellUniversal.IConfigurationSystemWatcher.CheckSum; method will not be bound
2024-08-06 15:58:15.948 -05:00 [INF] RPC services being provided by Universal.Server.Services.Configuration.ConfigurationSystemWatcher: 2
2024-08-06 15:58:15.958 -05:00 [INF] RPC services being provided by Universal.Server.Services.Configuration.PortalComponentService: 5
2024-08-06 15:58:15.967 -05:00 [INF] RPC services being provided by Universal.Server.Services.Configuration.PortalPageService: 5
2024-08-06 15:58:15.971 -05:00 [INF] RPC services being provided by Universal.Server.Services.Automation.JobInvokerService: 2
2024-08-06 15:58:15.980 -05:00 [INF] RPC services being provided by Universal.Server.Services.Authentication.AppTokenClient: 6
2024-08-06 15:58:15.988 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.ComputerGroupService: 5
2024-08-06 15:58:15.997 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.EndpointDocumentationService: 5
2024-08-06 15:58:16.008 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.EndpointService: 5
2024-08-06 15:58:16.016 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.EnvironmentConfigService: 5
2024-08-06 15:58:16.025 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.EventHubService: 5
2024-08-06 15:58:16.034 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.FolderService: 5
2024-08-06 15:58:16.043 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.HealthCheckConfigService: 5
2024-08-06 15:58:16.053 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.ModuleConfigService: 5
2024-08-06 15:58:16.063 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.PublishedFolderCommand: 5
2024-08-06 15:58:16.074 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.RoleService: 5
2024-08-06 15:58:16.084 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.ScriptService: 5
2024-08-06 15:58:16.093 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.TagService: 5
2024-08-06 15:58:16.104 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.TerminalService: 5
2024-08-06 15:58:16.115 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.TriggerService: 5
2024-08-06 15:58:16.124 -05:00 [INF] RPC services being provided by UniversalAutomation.Services.VariableService: 5
2024-08-06 15:58:16.130 -05:00 [INF] RPC services being provided by PowerShellUniversal.IGitSettingsService: 4
2024-08-06 15:58:16.200 -05:00 [INF] RPC services being provided by UniversalAutomation.IPublicGitService: 3
2024-08-06 15:58:16.206 -05:00 [INF] RPC services being provided by UniversalAutomation.IPublicApiService: 1
2024-08-06 15:58:16.215 -05:00 [INF] RPC services being provided by UniversalAutomation.IPublicGitSyncService: 5
2024-08-06 15:58:16.220 -05:00 [INF] RPC services being provided by PowerShellUniversal.IBrandingService: 1
2024-08-06 15:58:16.323 -05:00 [INF] Overriding endpoints defined via IConfiguration and/or UseKestrel() because PreferHostingUrls is set to true. Binding to address(es) 'http://127.0.0.1:6706' instead.
2024-08-06 15:58:16.331 -05:00 [INF] Now listening on: http://127.0.0.1:6706
2024-08-06 15:58:16.367 -05:00 [INF] Application started. Press Ctrl+C to shut down.
2024-08-06 15:58:16.367 -05:00 [INF] Hosting environment: Production
2024-08-06 15:58:16.367 -05:00 [INF] Content root path: E:\src\psu\psuSandbox
2024-08-06 15:58:16.442 -05:00 [INF] Request starting HTTP/1.1 GET http://localhost/psuSandbox/ - null null
2024-08-06 15:58:16.782 -05:00 [ERR] Invalid license specified.
2024-08-06 15:58:17.082 -05:00 [INF] Executing ChallengeResult with authentication schemes ([]).
2024-08-06 15:58:17.098 -05:00 [INF] AuthenticationScheme: Cookies was challenged.
2024-08-06 15:58:17.101 -05:00 [INF] Request finished HTTP/1.1 GET http://localhost/psuSandbox/ - 302 0 null 664.722ms
2024-08-06 15:58:29.334 -05:00 [INF] Request starting HTTP/1.1 POST http://dev.psu.domain.com/psuSandbox/api/custom/idn/users - application/x-www-form-urlencoded 0
2024-08-06 15:58:31.336 -05:00 [INF] Request finished HTTP/1.1 POST https://dev.psu.domain.com/psuSandbox/api/custom/idn/users - 200 null application/json 2001.6477ms
2024-08-06 15:58:31.559 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/api/custom/idn/list - null null
2024-08-06 15:58:32.300 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/api/custom/idn/list - 200 null application/json 740.5775ms
2024-08-06 15:58:34.764 -05:00 [INF] Request starting HTTP/1.1 POST http://dev.psu.domain.com/psuSandbox/api/custom/idn/users - application/x-www-form-urlencoded 0
2024-08-06 15:58:37.877 -05:00 [INF] Request finished HTTP/1.1 POST https://dev.psu.domain.com/psuSandbox/api/custom/idn/users - 200 null application/json 3112.8546ms
2024-08-06 15:58:37.981 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/api/custom/idn/list - null null
2024-08-06 15:58:38.872 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/api/custom/idn/list - 200 null application/json 891.6754ms
2024-08-06 15:58:41.169 -05:00 [INF] Configuring authentication methods...
2024-08-06 15:58:41.193 -05:00 [FTL] Failed to start PowerShell Universal.
System.Exception: License already exists.
   at Universal.Server.Services.LicenseService.Add(License license) in C:\actions-runner\_work\universal\universal\src\Universal.Server\Services\LicenseService.cs:line 166
   at UniversalAutomation.StartupService.LoadAuthenticationMethodsFromConfig() in C:\actions-runner\_work\universal\universal\src\Universal.Server\Services\StartupService.cs:line 115
   at UniversalAutomation.StartupService.RunStartup() in C:\actions-runner\_work\universal\universal\src\Universal.Server\Services\StartupService.cs:line 404
   at UniversalAutomation.StartupService.TryRunStartup() in C:\actions-runner\_work\universal\universal\src\Universal.Server\Services\StartupService.cs:line 288
2024-08-06 15:59:01.576 -05:00 [INF] Starting groom job.
2024-08-06 15:59:01.579 -05:00 [INF] Script RunPesterTests.ps1 has a max history of 100
2024-08-06 15:59:01.629 -05:00 [INF] Script has 0 jobs to delete.
2024-08-06 15:59:01.629 -05:00 [INF] Script testHubConnection.ps1 has a max history of 100
2024-08-06 15:59:01.631 -05:00 [INF] Script has 0 jobs to delete.
2024-08-06 15:59:01.631 -05:00 [INF] Script testConnection.ps1 has a max history of 100
2024-08-06 15:59:01.632 -05:00 [INF] Script has 0 jobs to delete.
2024-08-06 15:59:01.632 -05:00 [INF] Script test job variables.ps1 has a max history of 100
2024-08-06 15:59:01.633 -05:00 [INF] Script has 0 jobs to delete.
2024-08-06 15:59:01.633 -05:00 [INF] Script test event hub.ps1 has a max history of 100
2024-08-06 15:59:01.634 -05:00 [INF] Script has 0 jobs to delete.
2024-08-06 15:59:01.637 -05:00 [INF] Groom date is: 7/7/2024 8:59:01 PM
2024-08-06 15:59:01.835 -05:00 [INF] Finished groom job.
2024-08-06 15:59:37.013 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:37.031 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:37.050 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:37.065 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:37.084 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:37.114 -05:00 [INF] Executed page /Login in 59.9262ms
2024-08-06 15:59:37.114 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:37.115 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 102.3076ms
2024-08-06 15:59:38.198 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:38.226 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:38.226 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:38.228 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:38.232 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:38.237 -05:00 [INF] Executed page /Login in 10.4056ms
2024-08-06 15:59:38.237 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:38.237 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 38.8104ms
2024-08-06 15:59:39.320 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:39.325 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:39.326 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:39.329 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:39.332 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:39.333 -05:00 [INF] Executed page /Login in 7.3656ms
2024-08-06 15:59:39.333 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:39.333 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 12.8468ms
2024-08-06 15:59:40.400 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:40.405 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:40.405 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:40.405 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:40.408 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:40.408 -05:00 [INF] Executed page /Login in 3.267ms
2024-08-06 15:59:40.408 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:40.408 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 8.1326ms
2024-08-06 15:59:41.479 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:41.484 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:41.484 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:41.484 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:41.487 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:41.487 -05:00 [INF] Executed page /Login in 3.1255ms
2024-08-06 15:59:41.487 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:41.488 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 8.6692ms
2024-08-06 15:59:42.557 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:42.562 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:42.563 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:42.563 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:42.565 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:42.566 -05:00 [INF] Executed page /Login in 3.1269ms
2024-08-06 15:59:42.566 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:42.566 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 8.57ms
2024-08-06 15:59:43.629 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:43.635 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:43.635 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:43.636 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:43.639 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:43.639 -05:00 [INF] Executed page /Login in 3.6931ms
2024-08-06 15:59:43.639 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:43.639 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 9.9809ms
2024-08-06 15:59:44.694 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:44.699 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:44.699 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:44.700 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:44.702 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:44.702 -05:00 [INF] Executed page /Login in 3.1368ms
2024-08-06 15:59:44.702 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:44.702 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 8.2469ms
2024-08-06 15:59:46.643 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:46.648 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:46.648 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:46.648 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:46.650 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:46.651 -05:00 [INF] Executed page /Login in 3.0937ms
2024-08-06 15:59:46.651 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:46.651 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 7.9703ms
2024-08-06 15:59:48.641 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:48.646 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:48.646 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:48.647 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:48.649 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:48.649 -05:00 [INF] Executed page /Login in 3.1279ms
2024-08-06 15:59:48.650 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:48.650 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 8.2942ms
2024-08-06 15:59:50.636 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:50.640 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:50.640 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:50.641 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:50.644 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:50.644 -05:00 [INF] Executed page /Login in 3.6434ms
2024-08-06 15:59:50.644 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:50.644 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 8.7069ms
2024-08-06 15:59:52.641 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:52.646 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:52.646 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:52.646 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:52.649 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:52.650 -05:00 [INF] Executed page /Login in 3.5696ms
2024-08-06 15:59:52.650 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:52.650 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 8.6745ms
2024-08-06 15:59:54.638 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:54.642 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:54.643 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:54.643 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:54.645 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:54.646 -05:00 [INF] Executed page /Login in 2.9761ms
2024-08-06 15:59:54.646 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:54.646 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 7.8379ms
2024-08-06 15:59:56.651 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:56.657 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:56.657 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:56.657 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:56.660 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:56.661 -05:00 [INF] Executed page /Login in 3.4711ms
2024-08-06 15:59:56.661 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:56.661 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 9.8876ms
2024-08-06 15:59:58.660 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 15:59:58.665 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 15:59:58.665 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 15:59:58.665 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 15:59:58.668 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 15:59:58.668 -05:00 [INF] Executed page /Login in 3.1039ms
2024-08-06 15:59:58.668 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 15:59:58.668 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 7.9625ms
2024-08-06 16:00:00.652 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 16:00:00.657 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 16:00:00.657 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 16:00:00.657 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 16:00:00.660 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 16:00:00.660 -05:00 [INF] Executed page /Login in 3.1346ms
2024-08-06 16:00:00.660 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 16:00:00.660 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 8.45ms
2024-08-06 16:00:01.591 -05:00 [INF] Starting groom job.
2024-08-06 16:00:01.593 -05:00 [INF] Script RunPesterTests.ps1 has a max history of 100
2024-08-06 16:00:01.594 -05:00 [INF] Script has 0 jobs to delete.
2024-08-06 16:00:01.594 -05:00 [INF] Script testHubConnection.ps1 has a max history of 100
2024-08-06 16:00:01.595 -05:00 [INF] Script has 0 jobs to delete.
2024-08-06 16:00:01.595 -05:00 [INF] Script testConnection.ps1 has a max history of 100
2024-08-06 16:00:01.596 -05:00 [INF] Script has 0 jobs to delete.
2024-08-06 16:00:01.596 -05:00 [INF] Script test job variables.ps1 has a max history of 100
2024-08-06 16:00:01.597 -05:00 [INF] Script has 0 jobs to delete.
2024-08-06 16:00:01.597 -05:00 [INF] Script test event hub.ps1 has a max history of 100
2024-08-06 16:00:01.599 -05:00 [INF] Script has 0 jobs to delete.
2024-08-06 16:00:01.599 -05:00 [INF] Starting module discovery using Process.
2024-08-06 16:00:01.599 -05:00 [INF] Groom date is: 7/7/2024 9:00:01 PM
2024-08-06 16:00:01.606 -05:00 [INF] Starting module discovery using Process.
2024-08-06 16:00:01.610 -05:00 [INF] Starting module discovery using Process.
2024-08-06 16:00:01.627 -05:00 [INF] Finished groom job.
2024-08-06 16:00:02.715 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 16:00:02.724 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 16:00:02.725 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 16:00:02.727 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 16:00:02.733 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 16:00:02.734 -05:00 [INF] Executed page /Login in 8.2836ms
2024-08-06 16:00:02.734 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 16:00:02.734 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 19.0929ms
2024-08-06 16:00:04.005 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 16:00:04.021 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 16:00:04.021 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 16:00:04.021 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 16:00:04.027 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 16:00:04.028 -05:00 [INF] Executed page /Login in 6.9422ms
2024-08-06 16:00:04.028 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 16:00:04.028 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 22.8335ms
2024-08-06 16:00:05.912 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 16:00:05.922 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 16:00:05.922 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 16:00:05.922 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 16:00:05.925 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 16:00:05.925 -05:00 [INF] Executed page /Login in 3.2992ms
2024-08-06 16:00:05.925 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 16:00:05.925 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 13.1619ms
2024-08-06 16:00:07.747 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 16:00:07.755 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 16:00:07.755 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 16:00:07.756 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 16:00:07.758 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 16:00:07.758 -05:00 [INF] Executed page /Login in 2.8602ms
2024-08-06 16:00:07.758 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 16:00:07.758 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 11.6005ms
2024-08-06 16:00:09.819 -05:00 [INF] Request starting HTTP/1.1 GET http://dev.psu.domain.com/psuSandbox/login - null null
2024-08-06 16:00:09.823 -05:00 [INF] Executing endpoint '/Login'
2024-08-06 16:00:09.823 -05:00 [INF] Route matched with {page = "/Login", action = "", controller = ""}. Executing page /Login
2024-08-06 16:00:09.824 -05:00 [INF] Executing handler method PowerShellUniversal.Login.OnGet - ModelState is "Valid"
2024-08-06 16:00:09.826 -05:00 [INF] Executed handler method OnGet, returned result Microsoft.AspNetCore.Mvc.RazorPages.PageResult.
2024-08-06 16:00:09.827 -05:00 [INF] Executed page /Login in 3.2331ms
2024-08-06 16:00:09.827 -05:00 [INF] Executed endpoint '/Login'
2024-08-06 16:00:09.827 -05:00 [INF] Request finished HTTP/1.1 GET https://dev.psu.domain.com/psuSandbox/login - 200 null text/html; charset=utf-8 7.9785ms

Additional Environment data

No response

Screenshots/Animations

image

adamdriscoll commented 3 months ago

Added some error handling for this. It shouldn't be throwing an exception in this critical startup path.

That said, it's trying to set the license from the env var and it already exists in the DB so it's failing. You should be able to delete the env var to get it going again.

Omzig commented 3 months ago

@adamdriscoll, 4.3.4 lets you login when there is on in the env and db. Should we prioritize one over the other?

Omzig commented 3 months ago

i deleted all the administrators from the db, and deleted all the licenses from the db, still having a little problem getting to /first-run

image

adamdriscoll commented 3 months ago

@Omzig - It should just let you login no matter the license status.

Do you have a dashboard set to a base URL of "/"? That's the dashboard Page Not Found page. I wonder if we need to fix the routing.

Omzig commented 3 months ago

yes, i had a / dashboard setup for the root site originally. the root app sometimes loads, but there are errors on it