[2024-02-21T13:29:26.083-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.authorization.providers.shib.ShibUtil] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMillis: 1 708550966083] [levelValue: 500] [CLASSNAME: edu.harvard.iq.dataverse.authorization.providers.shib.ShibUtil] [METHODNAME: printAttributes] [[ shib values: [Shib-Identity-Provider: urn:mace:incommon:berkeley.edu, eppn: jsundu@berkeley.edu, givenName: Jay, sn: Sundu, mail: jsundu@berkeley.edu]]] [2024-02-21T13:29:26.083-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.Shib] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMillis: 1708550966083] [levelValue: 500] [ CLASSNAME: edu.harvard.iq.dataverse.Shib] [METHODNAME: init] [[ JkEnvVar: null]] [2024-02-21T13:29:26.086-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMi llis: 1708550966086] [levelValue: 500] [CLASSNAME: edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean] [METHODNAME: getDevShibAccountType] [[ setting returned: null]] [2024-02-21T13:29:26.086-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMi llis: 1708550966086] [levelValue: 500] [CLASSNAME: edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean] [METHODNAME: getDevShibAccountType] [[ Shibboleth dev mode has not been configured. Returning a sane default: PRODUCTION]] [2024-02-21T13:29:26.086-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMi llis: 1708550966086] [levelValue: 500] [CLASSNAME: edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean] [METHODNAME: possiblyMutateRequestInDev] [[ Request will not be mutated]] [2024-02-21T13:29:26.088-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.Shib] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMillis: 1708550966088] [levelValue: 500] [ CLASSNAME: edu.harvard.iq.dataverse.Shib] [METHODNAME: getRequiredValueFromAssertion] [[ The SAML assertion for "Shib-Identity-Provider" (required) was "urn:mace:incommon:berkeley.edu" and was trimmed to "urn:mace:incommon:berkeley.edu".]] [2024-02-21T13:29:26.089-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.Shib] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMillis: 1708550966089] [levelValue: 500] [ CLASSNAME: edu.harvard.iq.dataverse.Shib] [METHODNAME: getRequiredValueFromAssertion] [[ The SAML assertion for "eppn" (required) was "jsundu@berkeley.edu" and was trimmed to "jsundu@berkeley.edu".]] [2024-02-21T13:29:26.090-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.Shib] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMillis: 1708550966090] [levelValue: 500] [ CLASSNAME: edu.harvard.iq.dataverse.Shib] [METHODNAME: getRequiredValueFromAssertion] [[ The SAML assertion for "givenName" (required) was "Jay" and was trimmed to "Jay".]] [2024-02-21T13:29:26.091-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.Shib] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMillis: 1708550966091] [levelValue: 500] [ CLASSNAME: edu.harvard.iq.dataverse.Shib] [METHODNAME: getRequiredValueFromAssertion] [[ The SAML assertion for "sn" (required) was "Sundu" and was trimmed to "Sundu".]] [2024-02-21T13:29:26.092-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.Shib] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMillis: 1708550966092] [levelValue: 500] [ CLASSNAME: edu.harvard.iq.dataverse.Shib] [METHODNAME: getRequiredValueFromAssertion] [[ The SAML assertion for "mail" (required) was "jsundu@berkeley.edu" and was trimmed to "jsundu@berkeley.edu".]] [2024-02-21T13:29:26.093-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.Shib] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMillis: 1708550966093] [levelValue: 500] [ CLASSNAME: edu.harvard.iq.dataverse.Shib] [METHODNAME: getValueFromAssertion] [[ The SAML assertion for "uid" (optional) was null.]] [2024-02-21T13:29:26.093-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.Shib] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMillis: 1708550966093] [levelValue: 500] [ CLASSNAME: edu.harvard.iq.dataverse.Shib] [METHODNAME: init] [[ friendly looking identifier (backend will enforce uniqueness): jsundu]] [2024-02-21T13:29:26.095-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMi llis: 1708550966095] [levelValue: 500] [CLASSNAME: edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean] [METHODNAME: getDevShibAccountType] [[ setting returned: null]] [2024-02-21T13:29:26.095-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMi llis: 1708550966095] [levelValue: 500] [CLASSNAME: edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean] [METHODNAME: getDevShibAccountType] [[ Shibboleth dev mode has not been configured. Returning a sane default: PRODUCTION]] [2024-02-21T13:29:26.096-0800] [Payara 6.2023.9] [FINE] [] [edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMi llis: 1708550966096] [levelValue: 500] [CLASSNAME: edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean] [METHODNAME: getAffiliation] [[ Trying to get affiliation from disco feed URL: http://datasets-staging.lib.berkeley.edu/Shibboleth.sso/DiscoFeed]] [2024-02-21T13:29:26.098-0800] [Payara 6.2023.9] [WARNING] [AS-EJB-00056] [jakarta.enterprise.ejb.container] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMillis: 1708550966098] [ levelValue: 900] [[ A system exception occurred during an invocation on EJB ShibServiceBean, method: public java.lang.String edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean.getAffiliation(java.lang.St ring,edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean$DevShibAccountType)]] [2024-02-21T13:29:26.098-0800] [Payara 6.2023.9] [WARNING] [] [jakarta.enterprise.ejb.container] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMillis: 1708550966098] [levelValue: 900] [[ jakarta.ejb.EJBException: com.google.gson.stream.MalformedJsonException: Use JsonReader.setLenient(true) to accept malformed JSON at line 1 column 12 path $ at com.sun.ejb.containers.EJBContainerTransactionManager.processSystemException(EJBContainerTransactionManager.java:723) at com.sun.ejb.containers.EJBContainerTransactionManager.completeNewTx(EJBContainerTransactionManager.java:652) at com.sun.ejb.containers.EJBContainerTransactionManager.postInvokeTx(EJBContainerTransactionManager.java:482) at com.sun.ejb.containers.BaseContainer.postInvokeTx(BaseContainer.java:4601) at com.sun.ejb.containers.BaseContainer.postInvoke(BaseContainer.java:2134) at com.sun.ejb.containers.BaseContainer.postInvoke(BaseContainer.java:2104) at com.sun.ejb.containers.EJBLocalObjectInvocationHandler.invoke(EJBLocalObjectInvocationHandler.java:220) at com.sun.ejb.containers.EJBLocalObjectInvocationHandlerDelegate.invoke(EJBLocalObjectInvocationHandlerDelegate.java:90) at jdk.proxy73/jdk.proxy73.$Proxy375.getAffiliation(Unknown Source) at edu.harvard.iq.dataverse.authorization.providers.shib.__EJB31_Generated__ShibServiceBean__Intf____Bean__.getAffiliation(Unknown Source) at edu.harvard.iq.dataverse.Shib.init(Shib.java:220) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:568) at org.glassfish.expressly.util.ReflectionUtil.invokeMethod(ReflectionUtil.java:186) at org.glassfish.expressly.parser.AstValue.invoke(AstValue.java:253) at org.glassfish.expressly.MethodExpressionImpl.invoke(MethodExpressionImpl.java:248) at org.jboss.weld.module.web.util.el.ForwardingMethodExpression.invoke(ForwardingMethodExpression.java:40) at org.jboss.weld.module.web.el.WeldMethodExpression.invoke(WeldMethodExpression.java:50) at com.sun.faces.facelets.el.TagMethodExpression.invoke(TagMethodExpression.java:70) at com.sun.faces.application.ActionListenerImpl.getNavigationOutcome(ActionListenerImpl.java:74) at com.sun.faces.application.ActionListenerImpl.processAction(ActionListenerImpl.java:62) at jakarta.faces.component.UIViewAction.broadcast(UIViewAction.java:506) at jakarta.faces.component.UIViewRoot.broadcastEvents(UIViewRoot.java:858) at jakarta.faces.component.UIViewRoot.processApplication(UIViewRoot.java:1332) at com.sun.faces.lifecycle.InvokeApplicationPhase.execute(InvokeApplicationPhase.java:56) at com.sun.faces.lifecycle.Phase.doPhase(Phase.java:72) at com.sun.faces.lifecycle.LifecycleImpl.execute(LifecycleImpl.java:159) at jakarta.faces.webapp.FacesServlet.executeLifecyle(FacesServlet.java:691) at jakarta.faces.webapp.FacesServlet.service(FacesServlet.java:449) at org.apache.catalina.core.StandardWrapper.service(StandardWrapper.java:1554) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:331) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:211) at org.glassfish.tyrus.servlet.TyrusServletFilter.doFilter(TyrusServletFilter.java:83) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:253) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:211) at org.ocpsoft.rewrite.servlet.RewriteFilter.doFilter(RewriteFilter.java:226) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:253) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:211) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:257) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:166) at org.apache.catalina.core.StandardPipeline.doInvoke(StandardPipeline.java:757) at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:577) at com.sun.enterprise.web.WebPipeline.invoke(WebPipeline.java:99) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:158) at org.apache.catalina.connector.CoyoteAdapter.doService(CoyoteAdapter.java:372) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:239) at com.sun.enterprise.v3.services.impl.ContainerMapper$HttpHandlerCallable.call(ContainerMapper.java:520) at com.sun.enterprise.v3.services.impl.ContainerMapper.service(ContainerMapper.java:217) at org.glassfish.grizzly.http.server.HttpHandler.runService(HttpHandler.java:174) at org.glassfish.grizzly.http.server.HttpHandler.doHandle(HttpHandler.java:153) at org.glassfish.grizzly.http.server.HttpServerFilter.handleRead(HttpServerFilter.java:196) at org.glassfish.grizzly.filterchain.ExecutorResolver$9.execute(ExecutorResolver.java:88) at org.glassfish.grizzly.filterchain.DefaultFilterChain.executeFilter(DefaultFilterChain.java:246) at org.glassfish.grizzly.filterchain.DefaultFilterChain.executeChainPart(DefaultFilterChain.java:178) at org.glassfish.grizzly.filterchain.DefaultFilterChain.execute(DefaultFilterChain.java:118) at org.glassfish.grizzly.filterchain.DefaultFilterChain.process(DefaultFilterChain.java:96) at org.glassfish.grizzly.ProcessorExecutor.execute(ProcessorExecutor.java:51) at org.glassfish.grizzly.nio.transport.TCPNIOTransport.fireIOEvent(TCPNIOTransport.java:510) at org.glassfish.grizzly.strategies.AbstractIOStrategy.fireIOEvent(AbstractIOStrategy.java:82) at org.glassfish.grizzly.strategies.WorkerThreadIOStrategy.run0(WorkerThreadIOStrategy.java:83) at org.glassfish.grizzly.strategies.WorkerThreadIOStrategy$WorkerThreadRunnable.run(WorkerThreadIOStrategy.java:101) at org.glassfish.grizzly.threadpool.AbstractThreadPool$Worker.doWork(AbstractThreadPool.java:535) at org.glassfish.grizzly.threadpool.AbstractThreadPool$Worker.run(AbstractThreadPool.java:515) at java.base/java.lang.Thread.run(Thread.java:840) Caused by: com.google.gson.JsonSyntaxException: com.google.gson.stream.MalformedJsonException: Use JsonReader.setLenient(true) to accept malformed JSON at line 1 column 12 path $ at com.google.gson.JsonParser.parseReader(JsonParser.java:66) at com.google.gson.JsonParser.parse(JsonParser.java:104) at edu.harvard.iq.dataverse.authorization.providers.shib.ShibServiceBean.getAffiliation(ShibServiceBean.java:209) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:568) at org.glassfish.ejb.security.application.EJBSecurityManager.runMethod(EJBSecurityManager.java:588) at org.glassfish.ejb.security.application.EJBSecurityManager.invoke(EJBSecurityManager.java:408) at com.sun.ejb.containers.BaseContainer.invokeBeanMethod(BaseContainer.java:4835) at com.sun.ejb.EjbInvocation.invokeBeanMethod(EjbInvocation.java:653) at com.sun.ejb.containers.interceptors.AroundInvokeChainImpl.invokeNext(InterceptorManager.java:834) at com.sun.ejb.EjbInvocation.proceed(EjbInvocation.java:603) at com.sun.ejb.containers.interceptors.SystemInterceptorProxy.doCall(SystemInterceptorProxy.java:163) at com.sun.ejb.containers.interceptors.SystemInterceptorProxy.aroundInvoke(SystemInterceptorProxy.java:140) at jdk.internal.reflect.GeneratedMethodAccessor224.invoke(Unknown Source) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:568) at com.sun.ejb.containers.interceptors.AroundInvokeInterceptor.intercept(InterceptorManager.java:888) at com.sun.ejb.containers.interceptors.AroundInvokeChainImpl.invokeNext(InterceptorManager.java:833) at com.sun.ejb.EjbInvocation.proceed(EjbInvocation.java:603) at org.jboss.weld.module.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:72) at org.jboss.weld.module.ejb.SessionBeanInterceptor.aroundInvoke(SessionBeanInterceptor.java:52) at jdk.internal.reflect.GeneratedMethodAccessor195.invoke(Unknown Source) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:568) at com.sun.ejb.containers.interceptors.AroundInvokeInterceptor.intercept(InterceptorManager.java:888) at com.sun.ejb.containers.interceptors.AroundInvokeChainImpl.invokeNext(InterceptorManager.java:833) at com.sun.ejb.containers.interceptors.InterceptorManager.intercept(InterceptorManager.java:375) at com.sun.ejb.containers.BaseContainer.__intercept(BaseContainer.java:4807) at com.sun.ejb.containers.BaseContainer.intercept(BaseContainer.java:4795) at com.sun.ejb.containers.EJBLocalObjectInvocationHandler.invoke(EJBLocalObjectInvocationHandler.java:212) ... 59 more Caused by: com.google.gson.stream.MalformedJsonException: Use JsonReader.setLenient(true) to accept malformed JSON at line 1 column 12 path $ at com.google.gson.stream.JsonReader.syntaxError(JsonReader.java:1562) at com.google.gson.stream.JsonReader.checkLenient(JsonReader.java:1403) at com.google.gson.stream.JsonReader.doPeek(JsonReader.java:541) at com.google.gson.stream.JsonReader.peek(JsonReader.java:424) at com.google.gson.JsonParser.parseReader(JsonParser.java:61) ... 90 more ]] [2024-02-21T13:29:26.099-0800] [Payara 6.2023.9] [WARNING] [] [jakarta.enterprise.resource.webcontainer.faces.lifecycle] [tid: _ThreadID=98 _ThreadName=http-thread-pool::jk-connector(4)] [timeMillis: 1708 550966099] [levelValue: 900] [[ #{Shib.init}: /shib.xhtml @14,50 action="#{Shib.init}": jakarta.ejb.EJBException: com.google.gson.stream.MalformedJsonException: Use JsonReader.setLenient(true) to accept malformed JSON at line 1 column 12 path $ jakarta.faces.FacesException: #{Shib.init}: /shib.xhtml @14,50 action="#{Shib.init}": jakarta.ejb.EJBException: com.google.gson.stream.MalformedJsonException: Use JsonReader.setLenient(true) to accept mal formed JSON at line 1 column 12 path $ at com.sun.faces.application.ActionListenerImpl.getNavigationOutcome(ActionListenerImpl.java:83) at com.sun.faces.application.ActionListenerImpl.processAction(ActionListenerImpl.java:62) at jakarta.faces.component.UIViewAction.broadcast(UIViewAction.java:506) at jakarta.faces.component.UIViewRoot.broadcastEvents(UIViewRoot.java:858) at jakarta.faces.component.UIViewRoot.processApplication(UIViewRoot.java:1332) at com.sun.faces.lifecycle.InvokeApplicationPhase.execute(InvokeApplicationPhase.java:56) at com.sun.faces.lifecycle.Phase.doPhase(Phase.java:72)