Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Active Organizations Authenticator fails on login #313

Open
Pooffick opened this issue Feb 4, 2025 · 1 comment
Open

Active Organizations Authenticator fails on login #313

Pooffick opened this issue Feb 4, 2025 · 1 comment
Assignees
@rtufisi

Comments

@Pooffick
Copy link

Pooffick commented Feb 4, 2025

Hello! I hope for your help.
I'm trying to implement Active Organizations Authenticator.
If user has two or more organizations, after entering the login and password, I get the error:

2025-02-04 12:01:15 2025-02-04 05:01:15,716 SEVERE [freemarker.runtime] (executor-thread-14) Error executing FreeMarker template: freemarker.core.InvalidReferenceException: The following has evaluated to null or missing:
2025-02-04 12:01:15 ==> user  [in template "select-organization.ftl" at line 8, column 77]
2025-02-04 12:01:15 
2025-02-04 12:01:15 ----
2025-02-04 12:01:15 Tip: If the failing expression is known to legally refer to something that's sometimes null or missing, either specify a default value like myOptionalVar!myDefault, or use <#if myOptionalVar??>when-present<#else>when-missing</#if>. (These only cover the last step of the expression; to cover the whole expression, use parenthesis: (myOptionalVar.foo)!myDefault, (myOptionalVar.foo)??
2025-02-04 12:01:15 ----
2025-02-04 12:01:15 
2025-02-04 12:01:15 ----
2025-02-04 12:01:15 FTL stack trace ("~" means nesting-related):
2025-02-04 12:01:15     - Failed at: #if user.organizations?size gt 3  [in template "select-organization.ftl" at line 8, column 72]
2025-02-04 12:01:15     ~ Reached through: #nested "form"  [in template "template.ftl" in macro "registrationLayout" at line 188, column 9]
2025-02-04 12:01:15     ~ Reached through: @layout.registrationLayout; section  [in template "select-organization.ftl" at line 2, column 1]
2025-02-04 12:01:15 ----
2025-02-04 12:01:15     at freemarker.core.InvalidReferenceException.getInstance(InvalidReferenceException.java:134)
2025-02-04 12:01:15     at freemarker.core.UnexpectedTypeException.newDescriptionBuilder(UnexpectedTypeException.java:85)
2025-02-04 12:01:15     at freemarker.core.UnexpectedTypeException.<init>(UnexpectedTypeException.java:48)
2025-02-04 12:01:15     at freemarker.core.NonHashException.<init>(NonHashException.java:49)
2025-02-04 12:01:15     at freemarker.core.Dot._eval(Dot.java:48)
2025-02-04 12:01:15     at freemarker.core.Expression.eval(Expression.java:101)
2025-02-04 12:01:15     at freemarker.core.BuiltInsForMultipleTypes$sizeBI._eval(BuiltInsForMultipleTypes.java:471)
2025-02-04 12:01:15     at freemarker.core.Expression.eval(Expression.java:101)
2025-02-04 12:01:15     at freemarker.core.EvalUtil.compare(EvalUtil.java:113)
2025-02-04 12:01:15     at freemarker.core.ComparisonExpression.evalToBoolean(ComparisonExpression.java:78)
2025-02-04 12:01:15     at freemarker.core.ConditionalBlock.accept(ConditionalBlock.java:48)
2025-02-04 12:01:15     at freemarker.core.Environment.visit(Environment.java:335)
2025-02-04 12:01:15     at freemarker.core.Environment.visit(Environment.java:377)
2025-02-04 12:01:15     at freemarker.core.Environment.invokeNestedContent(Environment.java:621)
2025-02-04 12:01:15     at freemarker.core.BodyInstruction.accept(BodyInstruction.java:60)
2025-02-04 12:01:15     at freemarker.core.Environment.visit(Environment.java:371)
2025-02-04 12:01:15     at freemarker.core.Environment.invokeMacroOrFunctionCommonPart(Environment.java:877)
2025-02-04 12:01:15     at freemarker.core.Environment.invokeMacro(Environment.java:813)
2025-02-04 12:01:15     at freemarker.core.UnifiedCall.accept(UnifiedCall.java:84)
2025-02-04 12:01:15     at freemarker.core.Environment.visit(Environment.java:335)
2025-02-04 12:01:15     at freemarker.core.Environment.visit(Environment.java:341)
2025-02-04 12:01:15     at freemarker.core.Environment.process(Environment.java:314)
2025-02-04 12:01:15     at freemarker.template.Template.process(Template.java:383)
2025-02-04 12:01:15     at org.keycloak.theme.freemarker.DefaultFreeMarkerProvider.processTemplate(DefaultFreeMarkerProvider.java:49)
2025-02-04 12:01:15     at org.keycloak.forms.login.freemarker.FreeMarkerLoginFormsProvider.processTemplate(FreeMarkerLoginFormsProvider.java:577)
2025-02-04 12:01:15     at org.keycloak.forms.login.freemarker.FreeMarkerLoginFormsProvider.createForm(FreeMarkerLoginFormsProvider.java:361)
2025-02-04 12:01:15     at io.phasetwo.service.auth.ActiveOrganizationAuthenticator.tryOrganizationSelectionChallenge(ActiveOrganizationAuthenticator.java:137)
2025-02-04 12:01:15     at io.phasetwo.service.auth.ActiveOrganizationAuthenticator.authenticate(ActiveOrganizationAuthenticator.java:40)
2025-02-04 12:01:15     at org.keycloak.authentication.DefaultAuthenticationFlow.processSingleFlowExecutionModel(DefaultAuthenticationFlow.java:465)
2025-02-04 12:01:15     at org.keycloak.authentication.DefaultAuthenticationFlow.processFlow(DefaultAuthenticationFlow.java:269)
2025-02-04 12:01:15     at org.keycloak.authentication.DefaultAuthenticationFlow.processSingleFlowExecutionModel(DefaultAuthenticationFlow.java:400)
2025-02-04 12:01:15     at org.keycloak.authentication.DefaultAuthenticationFlow.continueAuthenticationAfterSuccessfulAction(DefaultAuthenticationFlow.java:176)
2025-02-04 12:01:15     at org.keycloak.authentication.DefaultAuthenticationFlow.processAction(DefaultAuthenticationFlow.java:152)
2025-02-04 12:01:15     at org.keycloak.authentication.AuthenticationProcessor.authenticationAction(AuthenticationProcessor.java:1034)
2025-02-04 12:01:15     at org.keycloak.services.resources.LoginActionsService.processFlow(LoginActionsService.java:378)
2025-02-04 12:01:15     at org.keycloak.services.resources.LoginActionsService.processAuthentication(LoginActionsService.java:349)
2025-02-04 12:01:15     at org.keycloak.services.resources.LoginActionsService.authenticate(LoginActionsService.java:341)
2025-02-04 12:01:15     at org.keycloak.services.resources.LoginActionsService.authenticateForm(LoginActionsService.java:407)
2025-02-04 12:01:15     at org.keycloak.services.resources.LoginActionsService$quarkusrestinvoker$authenticateForm_13d428b70e62601585246c086a52f40868a3208e.invoke(Unknown Source)
2025-02-04 12:01:15     at org.jboss.resteasy.reactive.server.handlers.InvocationHandler.handle(InvocationHandler.java:29)
2025-02-04 12:01:15     at io.quarkus.resteasy.reactive.server.runtime.QuarkusResteasyReactiveRequestContext.invokeHandler(QuarkusResteasyReactiveRequestContext.java:141)
2025-02-04 12:01:15     at org.jboss.resteasy.reactive.common.core.AbstractResteasyReactiveContext.run(AbstractResteasyReactiveContext.java:147)
2025-02-04 12:01:15     at io.quarkus.vertx.core.runtime.VertxCoreRecorder$14.runWith(VertxCoreRecorder.java:635)
2025-02-04 12:01:15     at org.jboss.threads.EnhancedQueueExecutor$Task.doRunWith(EnhancedQueueExecutor.java:2516)
2025-02-04 12:01:15     at org.jboss.threads.EnhancedQueueExecutor$Task.run(EnhancedQueueExecutor.java:2495)
2025-02-04 12:01:15     at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1521)
2025-02-04 12:01:15     at org.jboss.threads.DelegatingRunnable.run(DelegatingRunnable.java:11)
2025-02-04 12:01:15     at org.jboss.threads.ThreadLocalResettingRunnable.run(ThreadLocalResettingRunnable.java:11)
2025-02-04 12:01:15     at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
2025-02-04 12:01:15     at java.base/java.lang.Thread.run(Thread.java:1583)
2025-02-04 12:01:15 
2025-02-04 12:01:15 2025-02-04 05:01:15,719 ERROR [org.keycloak.forms.login.freemarker.FreeMarkerLoginFormsProvider] (executor-thread-14) Failed to process template: org.keycloak.theme.FreeMarkerException: Failed to process template select-organization.ftl
2025-02-04 12:01:15     at org.keycloak.theme.freemarker.DefaultFreeMarkerProvider.processTemplate(DefaultFreeMarkerProvider.java:52)
2025-02-04 12:01:15     at org.keycloak.forms.login.freemarker.FreeMarkerLoginFormsProvider.processTemplate(FreeMarkerLoginFormsProvider.java:577)
2025-02-04 12:01:15     at org.keycloak.forms.login.freemarker.FreeMarkerLoginFormsProvider.createForm(FreeMarkerLoginFormsProvider.java:361)
2025-02-04 12:01:15     at io.phasetwo.service.auth.ActiveOrganizationAuthenticator.tryOrganizationSelectionChallenge(ActiveOrganizationAuthenticator.java:137)
2025-02-04 12:01:15     at io.phasetwo.service.auth.ActiveOrganizationAuthenticator.authenticate(ActiveOrganizationAuthenticator.java:40)
2025-02-04 12:01:15     at org.keycloak.authentication.DefaultAuthenticationFlow.processSingleFlowExecutionModel(DefaultAuthenticationFlow.java:465)
2025-02-04 12:01:15     at org.keycloak.authentication.DefaultAuthenticationFlow.processFlow(DefaultAuthenticationFlow.java:269)
2025-02-04 12:01:15     at org.keycloak.authentication.DefaultAuthenticationFlow.processSingleFlowExecutionModel(DefaultAuthenticationFlow.java:400)
2025-02-04 12:01:15     at org.keycloak.authentication.DefaultAuthenticationFlow.continueAuthenticationAfterSuccessfulAction(DefaultAuthenticationFlow.java:176)
2025-02-04 12:01:15     at org.keycloak.authentication.DefaultAuthenticationFlow.processAction(DefaultAuthenticationFlow.java:152)
2025-02-04 12:01:15     at org.keycloak.authentication.AuthenticationProcessor.authenticationAction(AuthenticationProcessor.java:1034)
2025-02-04 12:01:15     at org.keycloak.services.resources.LoginActionsService.processFlow(LoginActionsService.java:378)
2025-02-04 12:01:15     at org.keycloak.services.resources.LoginActionsService.processAuthentication(LoginActionsService.java:349)
2025-02-04 12:01:15     at org.keycloak.services.resources.LoginActionsService.authenticate(LoginActionsService.java:341)
2025-02-04 12:01:15     at org.keycloak.services.resources.LoginActionsService.authenticateForm(LoginActionsService.java:407)
2025-02-04 12:01:15     at org.keycloak.services.resources.LoginActionsService$quarkusrestinvoker$authenticateForm_13d428b70e62601585246c086a52f40868a3208e.invoke(Unknown Source)
2025-02-04 12:01:15     at org.jboss.resteasy.reactive.server.handlers.InvocationHandler.handle(InvocationHandler.java:29)
2025-02-04 12:01:15     at io.quarkus.resteasy.reactive.server.runtime.QuarkusResteasyReactiveRequestContext.invokeHandler(QuarkusResteasyReactiveRequestContext.java:141)
2025-02-04 12:01:15     at org.jboss.resteasy.reactive.common.core.AbstractResteasyReactiveContext.run(AbstractResteasyReactiveContext.java:147)
2025-02-04 12:01:15     at io.quarkus.vertx.core.runtime.VertxCoreRecorder$14.runWith(VertxCoreRecorder.java:635)
2025-02-04 12:01:15     at org.jboss.threads.EnhancedQueueExecutor$Task.doRunWith(EnhancedQueueExecutor.java:2516)
2025-02-04 12:01:15     at org.jboss.threads.EnhancedQueueExecutor$Task.run(EnhancedQueueExecutor.java:2495)
2025-02-04 12:01:15     at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1521)
2025-02-04 12:01:15     at org.jboss.threads.DelegatingRunnable.run(DelegatingRunnable.java:11)
2025-02-04 12:01:15     at org.jboss.threads.ThreadLocalResettingRunnable.run(ThreadLocalResettingRunnable.java:11)
2025-02-04 12:01:15     at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
2025-02-04 12:01:15     at java.base/java.lang.Thread.run(Thread.java:1583)
2025-02-04 12:01:15 Caused by: freemarker.core.InvalidReferenceException: The following has evaluated to null or missing:
2025-02-04 12:01:15 ==> user  [in template "select-organization.ftl" at line 8, column 77]
2025-02-04 12:01:15 
2025-02-04 12:01:15 ----
2025-02-04 12:01:15 Tip: If the failing expression is known to legally refer to something that's sometimes null or missing, either specify a default value like myOptionalVar!myDefault, or use <#if myOptionalVar??>when-present<#else>when-missing</#if>. (These only cover the last step of the expression; to cover the whole expression, use parenthesis: (myOptionalVar.foo)!myDefault, (myOptionalVar.foo)??
2025-02-04 12:01:15 ----
2025-02-04 12:01:15 
2025-02-04 12:01:15 ----
2025-02-04 12:01:15 FTL stack trace ("~" means nesting-related):
2025-02-04 12:01:15     - Failed at: #if user.organizations?size gt 3  [in template "select-organization.ftl" at line 8, column 72]
2025-02-04 12:01:15     ~ Reached through: #nested "form"  [in template "template.ftl" in macro "registrationLayout" at line 188, column 9]
2025-02-04 12:01:15     ~ Reached through: @layout.registrationLayout; section  [in template "select-organization.ftl" at line 2, column 1]
2025-02-04 12:01:15 ----
2025-02-04 12:01:15     at freemarker.core.InvalidReferenceException.getInstance(InvalidReferenceException.java:134)
2025-02-04 12:01:15     at freemarker.core.UnexpectedTypeException.newDescriptionBuilder(UnexpectedTypeException.java:85)
2025-02-04 12:01:15     at freemarker.core.UnexpectedTypeException.<init>(UnexpectedTypeException.java:48)
2025-02-04 12:01:15     at freemarker.core.NonHashException.<init>(NonHashException.java:49)
2025-02-04 12:01:15     at freemarker.core.Dot._eval(Dot.java:48)
2025-02-04 12:01:15     at freemarker.core.Expression.eval(Expression.java:101)
2025-02-04 12:01:15     at freemarker.core.BuiltInsForMultipleTypes$sizeBI._eval(BuiltInsForMultipleTypes.java:471)
2025-02-04 12:01:15     at freemarker.core.Expression.eval(Expression.java:101)
2025-02-04 12:01:15     at freemarker.core.EvalUtil.compare(EvalUtil.java:113)
2025-02-04 12:01:15     at freemarker.core.ComparisonExpression.evalToBoolean(ComparisonExpression.java:78)
2025-02-04 12:01:15     at freemarker.core.ConditionalBlock.accept(ConditionalBlock.java:48)
2025-02-04 12:01:15     at freemarker.core.Environment.visit(Environment.java:335)
2025-02-04 12:01:15     at freemarker.core.Environment.visit(Environment.java:377)
2025-02-04 12:01:15     at freemarker.core.Environment.invokeNestedContent(Environment.java:621)
2025-02-04 12:01:15     at freemarker.core.BodyInstruction.accept(BodyInstruction.java:60)
2025-02-04 12:01:15     at freemarker.core.Environment.visit(Environment.java:371)
2025-02-04 12:01:15     at freemarker.core.Environment.invokeMacroOrFunctionCommonPart(Environment.java:877)
2025-02-04 12:01:15     at freemarker.core.Environment.invokeMacro(Environment.java:813)
2025-02-04 12:01:15     at freemarker.core.UnifiedCall.accept(UnifiedCall.java:84)
2025-02-04 12:01:15     at freemarker.core.Environment.visit(Environment.java:335)
2025-02-04 12:01:15     at freemarker.core.Environment.visit(Environment.java:341)
2025-02-04 12:01:15     at freemarker.core.Environment.process(Environment.java:314)
2025-02-04 12:01:15     at freemarker.template.Template.process(Template.java:383)
2025-02-04 12:01:15     at org.keycloak.theme.freemarker.DefaultFreeMarkerProvider.processTemplate(DefaultFreeMarkerProvider.java:49)
2025-02-04 12:01:15     ... 26 more
2025-02-04 12:01:15 
2025-02-04 12:01:15 2025-02-04 05:01:15,720 ERROR [org.keycloak.headers.DefaultSecurityHeadersProvider] (executor-thread-14) MediaType not set on path /realms/testrealm/login-actions/authenticate, with response status 500
2025-02-04 12:01:15 2025-02-04 05:01:15,722 ERROR [org.keycloak.services.error.KeycloakErrorHandler] (executor-thread-14) Uncaught server error: jakarta.ws.rs.InternalServerErrorException: HTTP 500 Internal Server Error
2025-02-04 12:01:15     at org.keycloak.headers.DefaultSecurityHeadersProvider.addHeaders(DefaultSecurityHeadersProvider.java:75)
2025-02-04 12:01:15     at org.keycloak.services.filters.KeycloakSecurityHeadersFilter.filter(KeycloakSecurityHeadersFilter.java:45)
2025-02-04 12:01:15     at org.jboss.resteasy.reactive.server.handlers.ResourceResponseFilterHandler.handle(ResourceResponseFilterHandler.java:25)
2025-02-04 12:01:15     at io.quarkus.resteasy.reactive.server.runtime.QuarkusResteasyReactiveRequestContext.invokeHandler(QuarkusResteasyReactiveRequestContext.java:150)
2025-02-04 12:01:15     at org.jboss.resteasy.reactive.common.core.AbstractResteasyReactiveContext.run(AbstractResteasyReactiveContext.java:147)
2025-02-04 12:01:15     at io.quarkus.vertx.core.runtime.VertxCoreRecorder$14.runWith(VertxCoreRecorder.java:635)
2025-02-04 12:01:15     at org.jboss.threads.EnhancedQueueExecutor$Task.doRunWith(EnhancedQueueExecutor.java:2516)
2025-02-04 12:01:15     at org.jboss.threads.EnhancedQueueExecutor$Task.run(EnhancedQueueExecutor.java:2495)
2025-02-04 12:01:15     at org.jboss.threads.EnhancedQueueExecutor$ThreadBody.run(EnhancedQueueExecutor.java:1521)
2025-02-04 12:01:15     at org.jboss.threads.DelegatingRunnable.run(DelegatingRunnable.java:11)
2025-02-04 12:01:15     at org.jboss.threads.ThreadLocalResettingRunnable.run(ThreadLocalResettingRunnable.java:11)
2025-02-04 12:01:15     at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30)
2025-02-04 12:01:15     at java.base/java.lang.Thread.run(Thread.java:1583)
2025-02-04 12:01:15 
2025-02-04 12:01:15 2025-02-04 05:01:15,731 WARN  [io.agroal.pool] (executor-thread-14) Datasource '<default>': JDBC resources leaked: 1 ResultSet(s) and 1 Statement(s)

I tried the built-in Org Browser Flow and tried to create my own, the error is identical.
I have mappers to organization roles and active organization. And I'm using docker image based on phasetwo-keycloak:26.0.8

I don't exclude that I might have overlooked some obvious setting, so I ask for your help.
@xgp
Copy link
Member

xgp commented Feb 4, 2025

@MGLL Would you mind taking a look?

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@rtufisi rtufisi

Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@xgp @Pooffick @rtufisi