By: Miguel Foo user 10 Nov 2017 at 4:08 p.m. CST

17 Responses
Miguel Foo gravatar
Hey All, Working on migration from 3.0.2 to 3.1.1 and all the steps went well up to restarting after importing of data This step: https://gluu.org/docs/ce/3.1.1/upgrade/#import-your-old-data The importing says it succeeded and simply says to restart the gluu server and all should be well. However, when the server restarts I get a 503 ![503 Jetty Identity Error](https://i.imgur.com/ofVLNwz.png "503 Jetty Identity Error") I checked the `/opt/gluu/jetty/identity/logs/2017_xx_xx.stderrout.log` and it tells me it can't parse the JSON ``` 2017-11-10 21:15:24,997 WARN [main] [org.gluu.oxtrust.ldap.service.SubversionService] (SubversionService.java:191) - The service which commit configuration files into SVN was disabled 2017-11-10 21:15:27,212 INFO [main] [org.gluu.oxtrust.ldap.service.ShibbolethInitializer] (ShibbolethInitializer.java:41) - IDP config generation is set to true 2017-11-10 21:15:27.226:WARN:oeja.ServletContainerInitializersStarter:main: org.gluu.site.ldap.persistence.exception.MappingException: Failed to convert json value '{"cacheProviderType": ""IN_MEMORY"", "memcachedConfiguration": {"servers":"localhost:11211", "maxOperationQueueLength":100000, "bufferSize":32768, "defaultPutExpiration":60, "connectionFactoryType": ""DEFAULT""}, "inMemoryConfiguration": {"defaultPutExpiration":60}}' to object at org.gluu.site.ldap.persistence.AbstractEntryManager.convertStringToJson(AbstractEntryManager.java:1115) at org.gluu.site.ldap.persistence.AbstractEntryManager.setPropertyValue(AbstractEntryManager.java:1100) at org.gluu.site.ldap.persistence.AbstractEntryManager.createEntities(AbstractEntryManager.java:655) at org.gluu.site.ldap.persistence.AbstractEntryManager.createEntities(AbstractEntryManager.java:588) at org.gluu.site.ldap.persistence.AbstractEntryManager.find(AbstractEntryManager.java:447) at org.gluu.site.ldap.persistence.AbstractEntryManager.find(AbstractEntryManager.java:381) at org.gluu.site.ldap.persistence.LdapEntryManager$Proxy$_$$_WeldClientProxy.find(Unknown Source) at org.gluu.oxtrust.ldap.service.ApplianceService.getAppliance(ApplianceService.java:113) at org.gluu.oxtrust.ldap.service.ApplianceService.getAppliance(ApplianceService.java:133) at org.gluu.oxtrust.ldap.service.ShibbolethInitializer.createShibbolethConfiguration(ShibbolethInitializer.java:49) at org.gluu.oxtrust.ldap.service.ShibbolethInitializer$Proxy$_$$_WeldClientProxy.createShibbolethConfiguration(Unknown Source) at org.gluu.oxtrust.ldap.service.AppInitializer.applicationInitialized(AppInitializer.java:221) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.jboss.weld.injection.StaticMethodInjectionPoint.invoke(StaticMethodInjectionPoint.java:95) at org.jboss.weld.injection.StaticMethodInjectionPoint.invoke(StaticMethodInjectionPoint.java:85) at org.jboss.weld.injection.MethodInvocationStrategy$SimpleMethodInvocationStrategy.invoke(MethodInvocationStrategy.java:129) at org.jboss.weld.event.ObserverMethodImpl.sendEvent(ObserverMethodImpl.java:330) at org.jboss.weld.event.ObserverMethodImpl.sendEvent(ObserverMethodImpl.java:308) at org.jboss.weld.event.ObserverMethodImpl.notify(ObserverMethodImpl.java:286) at javax.enterprise.inject.spi.ObserverMethod.notify(ObserverMethod.java:124) at org.jboss.weld.util.Observers.notify(Observers.java:166) at org.jboss.weld.event.ObserverNotifier.notifySyncObservers(ObserverNotifier.java:285) at org.jboss.weld.event.ObserverNotifier.notify(ObserverNotifier.java:273) at org.jboss.weld.event.ObserverNotifier.fireEvent(ObserverNotifier.java:177) at org.jboss.weld.bootstrap.BeanDeploymentModule.fireEvent(BeanDeploymentModule.java:93) at org.jboss.weld.module.web.servlet.HttpContextLifecycle.fireEventForApplicationScope(HttpContextLifecycle.java:161) at org.jboss.weld.module.web.servlet.HttpContextLifecycle.contextInitialized(HttpContextLifecycle.java:147) at org.jboss.weld.module.web.servlet.WeldInitialListener.contextInitialized(WeldInitialListener.java:105) at org.jboss.weld.servlet.api.helpers.ForwardingServletListener.contextInitialized(ForwardingServletListener.java:34) at org.jboss.weld.environment.servlet.EnhancedListener.onStartup(EnhancedListener.java:66) at org.eclipse.jetty.plus.annotation.ContainerInitializer.callStartup(ContainerInitializer.java:140) at org.eclipse.jetty.annotations.ServletContainerInitializersStarter.doStart(ServletContainerInitializersStarter.java:63) at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68) at org.eclipse.jetty.servlet.ServletContextHandler.startContext(ServletContextHandler.java:330) at org.eclipse.jetty.webapp.WebAppContext.startWebapp(WebAppContext.java:1404) at org.eclipse.jetty.webapp.WebAppContext.startContext(WebAppContext.java:1366) at org.eclipse.jetty.server.handler.ContextHandler.doStart(ContextHandler.java:778) at org.eclipse.jetty.servlet.ServletContextHandler.doStart(ServletContextHandler.java:262) at org.eclipse.jetty.webapp.WebAppContext.doStart(WebAppContext.java:520) at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68) at org.eclipse.jetty.deploy.bindings.StandardStarter.processBinding(StandardStarter.java:41) at org.eclipse.jetty.deploy.AppLifeCycle.runBindings(AppLifeCycle.java:188) at org.eclipse.jetty.deploy.DeploymentManager.requestAppGoal(DeploymentManager.java:499) at org.eclipse.jetty.deploy.DeploymentManager.addApp(DeploymentManager.java:147) at org.eclipse.jetty.deploy.providers.ScanningAppProvider.fileAdded(ScanningAppProvider.java:180) at org.eclipse.jetty.deploy.providers.WebAppProvider.fileAdded(WebAppProvider.java:452) at org.eclipse.jetty.deploy.providers.ScanningAppProvider$1.fileAdded(ScanningAppProvider.java:64) at org.eclipse.jetty.util.Scanner.reportAddition(Scanner.java:610) at org.eclipse.jetty.util.Scanner.reportDifferences(Scanner.java:529) at org.eclipse.jetty.util.Scanner.scan(Scanner.java:392) at org.eclipse.jetty.util.Scanner.doStart(Scanner.java:313) at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68) at org.eclipse.jetty.deploy.providers.ScanningAppProvider.doStart(ScanningAppProvider.java:150) at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68) at org.eclipse.jetty.deploy.DeploymentManager.startAppProvider(DeploymentManager.java:561) at org.eclipse.jetty.deploy.DeploymentManager.doStart(DeploymentManager.java:236) at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68) at org.eclipse.jetty.util.component.ContainerLifeCycle.start(ContainerLifeCycle.java:131) at org.eclipse.jetty.server.Server.start(Server.java:422) at org.eclipse.jetty.util.component.ContainerLifeCycle.doStart(ContainerLifeCycle.java:113) at org.eclipse.jetty.server.handler.AbstractHandler.doStart(AbstractHandler.java:61) at org.eclipse.jetty.server.Server.doStart(Server.java:389) at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68) at org.eclipse.jetty.xml.XmlConfiguration$1.run(XmlConfiguration.java:1516) at java.security.AccessController.doPrivileged(Native Method) at org.eclipse.jetty.xml.XmlConfiguration.main(XmlConfiguration.java:1441) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.eclipse.jetty.start.Main.invokeMain(Main.java:214) at org.eclipse.jetty.start.Main.start(Main.java:457) at org.eclipse.jetty.start.Main.main(Main.java:75) 2017-11-10 21:15:27.228:WARN:oejw.WebAppContext:main: Failed startup of context o.e.j.w.WebAppContext@13deb50e{/identity,[file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-4050313584968588816.dir/webapp/, jar:file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-4050313584968588816.dir/webapp/WEB-INF/lib/omnifaces-2.6.3.jar!/META-INF/resources, jar:file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-4050313584968588816.dir/webapp/WEB-INF/lib/oxtrust-static-3.1.1.Final.jar!/META-INF/resources, jar:file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-4050313584968588816.dir/webapp/WEB-INF/lib/jsf-impl-2.2.14.jar!/META-INF/resources, jar:file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-4050313584968588816.dir/webapp/WEB-INF/lib/richfaces-a4j-4.5.17.Final.jar!/META-INF/resources, jar:file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-4050313584968588816.dir/webapp/WEB-INF/lib/richfaces-core-4.5.17.Final.jar!/META-INF/resources, jar:file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-4050313584968588816.dir/webapp/WEB-INF/lib/richfaces-4.5.17.Final.jar!/META-INF/resources],UNAVAILABLE}{/identity.war} ``` It seems this is the where it is being taken from, and it seems to pass a simply JSON validator I found online. Here is the `application.ldif` where I see the string ``` dn: inum=@!037F.7407.DCC3.D36D!0002!4ED7.BB4C,ou=appliances,o=gluu objectClass: top objectClass: gluuAppliance inum: @!037F.7407.DCC3.D36D!0002!4ED7.BB4C gluuMaxLogSize: 200 gluuWhitePagesEnabled: disabled gluuOrgProfileMgt: disabled gluuManageIdentityPermission: enabled gluuFederationHostingEnabled: disabled gluuVdsCacheRefreshEnabled: disabled gluuScimEnabled: disabled oxAuthenticationMode: auth_ldap_server oxTrustAuthenticationMode: auth_ldap_server gluuPassportEnabled: disabled oxCacheConfiguration: {"cacheProviderType": "IN_MEMORY", "memcachedConfiguration": {"servers":"localhost:11211", "maxOperationQueueLength":100000, "bufferSize":32768, "defaultPutExpiration":60, "connectionFactoryType": "DEFAULT"}, "inMemoryConfiguration": {"defaultPutExpiration":60}, "redisConfiguration":{"servers":"localhost:6379", "defaultPutExpiration": 60}} oxTrustStoreConf: {"useJreCertificates":true} oxTrustCacheRefreshServerIpAddress: 255.255.255.255 oxIDPAuthentication: {"type": "auth", "name": null, "level": 0, "priority": 1, "enabled": true, "version": 0, "config": "{\"configId\": \"auth_ldap_server\", \"servers\": [\"localhost:1636\"], \"maxConnections\": 1000, \"bindDN\": \"cn=directory manager,o=gluu\", \"bindPassword\": \"<REDACTED>", \"useSSL\": \"true\", \"baseDNs\": [\"o=gluu\"], \"primaryKey\": \"uid\", \"localPrimaryKey\": \"uid\", \"useAnonymousBind\": false, \"enabled\": true}" ... } ``` Any ideas? EDIT: Just noticed this error in the import script even though it said completed. I'm not sure if this error is fatal? I dont mind losing the data, I'm just not sure if I should fix it ``` ------------------------------------------------------------ Gluu Server Community Edition Migration Tool ============================================================ INFO Stopping Webapps oxAuth and Identity. INFO Stopping OpenLDAP Server. INFO Copying the Certificates. INFO Updating the CA Certs Keystore. INFO Copying the custom pages and assets of webapps. INFO Exporting LDAP data. INFO Processing the LDIF data. INFO Importing Processed LDAP data. ERROR 5a061690 Entry (inum=@!037F.7407.DCC3.D36D!0001!38C4.7E97!0000!8C3B.39E5.2905.E53F,ou=people,o=@!037F.7407.DCC3.D36D!0001!38C4.7E97,o=gluu), attribute 'homePhoneNumberVerified' not allowed slapadd: dn="inum=@!037F.7407.DCC3.D36D!0001!38C4.7E97!0000!8C3B.39E5.2905.E53F,ou=people,o=@!037F.7407.DCC3.D36D!0001!38C4.7E97,o=gluu" (line=14305): (65) attribute 'homePhoneNumberVerified' not allowed Error, entries missing! entry 502: inum=@!037f.7407.dcc3.d36d!0001!38c4.7e97!0000!8c3b.39e5.2905.e53f,ou=people,o=@!037f.7407.dcc3.d36d!0001!38c4.7e97,o=gluu INFO Fixing permissions for files. INFO Starting OpenLDAP Server. ============================================================ The migration is complete. Gluu Server needs to be restarted. # exit # service gluu-server-x.x.x restart ------------------------------------------------------------ ```

By Aliaksandr Samuseu staff 10 Nov 2017 at 5:18 p.m. CST

Aliaksandr Samuseu gravatar
Hi, Miguel. The last error doesn't seem fatal to me, most likely one of user entries was rejected due to attribute missing in 3.1.1 default schema. There were a lot of schema changes in 3.x, it's also possible one of your custom attributes wasn't migrated. Regarding your suspicion it may be related to `oxCacheConfiguration` attribute - could you run next commands inside container there and share the output? 1. `# echo YOUR_LDAP_PASS > /tmp/.pw` 2. `# ldapsearch -p 1636 -s sub -Z -X -D 'cn=directory manager,o=gluu' -j /tmp/.pw -b 'o=gluu' '&(oxCacheConfiguration=*)' oxCacheConfiguration` I'm assigning the ticket to the engineer responsible for migration scripts to look at. Please provide the requested data so he could look at more complete picture when he'll get to it. Thank you for detailed report.

By Miguel Foo user 11 Nov 2017 at 12:44 p.m. CST

Miguel Foo gravatar
Heya, Thanks for the fast reply. I've run the `ldapsearch` command and got this response: ``` dn: inum=@!037F.7407.DCC3.D36D!0002!4ED7.BB4C,ou=appliances,o=gluu oxCacheConfiguration: {"cacheProviderType": ""IN_MEMORY"", "memcachedConfigurati on": {"servers":"localhost:11211", "maxOperationQueueLength":100000, "bufferSiz e":32768, "defaultPutExpiration":60, "connectionFactoryType": ""DEFAULT""}, "in MemoryConfiguration": {"defaultPutExpiration":60}} ``` P.S. `ldapsearch` is not in the `$PATH` in the chroot It was found at `/opt/opendj/bin/ldapsearch` and requires `OPENDJ_JAVA_HOME` to be set. I'm sure whoever finds this will be linux savvy but incase anyone has trouble running `ldapsearch` Essentially, set the `OPENDJ_JAVA_HOME` env var by finding the version that's in the chroot with $ find / -name java <Will output where `java` binary is in the chroot to paste into the next line> $ export OPENDJ_JAVA_HOME="opt/jdk1.x.x_xxx" $ echo YOUR_LDAP_PASS > /tmp/.pw $ ./opt/opendj/bin/ldapsearch -p 1636 -s sub -Z -X -D 'cn=directory manager,o=gluu' -j /tmp/.pw -b 'o=gluu' '&(oxCacheConfiguration=*)' oxCacheConfiguration --- I'm sure you're going to point this out but the result of that search is an invalid JSON string as per online Json validator with ``` Error: Parse error on line 2: ...eProviderType": "" IN_MEMORY "", "memc ----------------------^ Expecting 'EOF', '}', ':', ',', ']', got 'undefined' ```

By Miguel Foo user 11 Nov 2017 at 2:31 p.m. CST

Miguel Foo gravatar
So, to continue the story: ## Changing the oxCacheConfiguration: I made the appropriate changes to the `dn: inum=@!037F.7407.DCC3.D36D!0002!4ED7.BB4C,ou=appliances,o=gluu oxCacheConfiguration` entry and removed the double double-quotes("" instead of ") to ensure the JSON was valid. But after booting up, the next part... ## File Permission Errors Now the server booted up, but had a bunch of errors with file permissions. It seems the identity application is trying to write to `/opt/shibboleth-idp/conf/` and `/opt/shibboleth-idp/metadata` which fails since its owned by root. ``` 2017-11-11 19:52:18,078 ERROR [main] [org.gluu.oxtrust.ldap.service.TemplateService] (TemplateService.java:67) - Failed to write IDP configuration file '/opt/shibboleth-idp/metadata/037F7407DCC3D36D00024ED7BB4C0006EDB4141F-sp-metadata.xml' java.io.IOException: File '/opt/shibboleth-idp/metadata/037F7407DCC3D36D00024ED7BB4C0006EDB4141F-sp-metadata.xml' cannot be written to ``` and ``` 2017-11-11 20:07:05,847 ERROR [main] [org.gluu.oxtrust.ldap.service.TemplateService] (TemplateService.java:67) - Failed to write IDP configuration file '/opt/shibboleth-idp/metadata/037F7407DCC3D36D00024ED7BB4C0006EDB4141F-sp-metadata.xml' java.io.IOException: File '/opt/shibboleth-idp/metadata/037F7407DCC3D36D00024ED7BB4C0006EDB4141F-sp-metadata.xml' cannot be written to ``` I gave both the directories and all subdirectories ownership to `jetty:jetty` since I noticed some of the directories were owned by jetty but the rest (that couldn't be written to) were owned by `root:root` ``` $ chown -R jetty:jetty /opt/shibboleth-idp/metadata/ $ chown -R jetty:jetty /opt/shibboleth-idp/conf/ ``` ## Cryptic Errors Now it seems, the errors are related to this error: ``` 2017-11-11 20:13:44.314:INFO:oejsh.ContextHandler:main: Started o.e.j.w.WebAppContext@13deb50e{/identity,[file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any- 2442416198859590811.dir/webapp/, jar:file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-2442416198859590811.dir/webapp/WEB-INF/lib/richfaces-4.5.17.Final.jar !/META-INF/resources, jar:file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-2442416198859590811.dir/webapp/WEB-INF/lib/oxtrust-static-3.1.1.Final.jar!/META- INF/resources, jar:file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-2442416198859590811.dir/webapp/WEB-INF/lib/richfaces-a4j-4.5.17.Final.jar!/META-INF/res ources, jar:file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-2442416198859590811.dir/webapp/WEB-INF/lib/richfaces-core-4.5.17.Final.jar!/META-INF/resources , jar:file:///opt/jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-2442416198859590811.dir/webapp/WEB-INF/lib/omnifaces-2.6.3.jar!/META-INF/resources, jar:file:///opt/ jetty-9.3/temp/jetty-localhost-8082-identity.war-_identity-any-2442416198859590811.dir/webapp/WEB-INF/lib/jsf-impl-2.2.14.jar!/META-INF/resources],AVAILABLE}{/identity.war} 2017-11-11 20:13:44.324:INFO:oejsh.ContextHandler:main: Started o.e.j.s.h.ContextHandler@2868a883{/identity/ext/resources,null,AVAILABLE} 2017-11-11 20:13:44.342:INFO:oejs.AbstractConnector:main: Started ServerConnector@583d1199{HTTP/1.1,[http/1.1]}{localhost:8082} 2017-11-11 20:13:44.342:INFO:oejs.Server:main: Started @18965ms 2017-11-11 20:14:40,001 INFO [Thread-21] [gluu.oxtrust.service.status.ldap.LdapStatusTimer] (LdapStatusTimer.java:97) - connectionProvider statistics: LDAPConnectionPoolStatistics( numAvailableConnections=1, maxAvailableConnections=10, numSuccessfulConnectionAttempts=1, numFailedConnectionAttempts=0, numConnectionsClosedDefunct=0, numConnectionsClosedExpired=0 , numConnectionsClosedUnneeded=0, numSuccessfulCheckouts=33, numFailedCheckouts=0, numReleasedValid=33) org.gluu.site.ldap.persistence.exception.EntryPersistenceException: Failed to calculate count entries with baseDN: ou=people,o=@!037F.7407.DCC3.D36D!0001!38C4.7E97,o=gluu, filter: ( &(objectClass=top)(objectClass=gluuPerson)) at org.gluu.site.ldap.persistence.LdapEntryManager.countEntries(LdapEntryManager.java:724) at org.gluu.site.ldap.persistence.LdapEntryManager.countEntries(LdapEntryManager.java:690) at org.gluu.site.ldap.persistence.LdapEntryManager$Proxy$_$$_WeldClientProxy.countEntries(Unknown Source) at org.gluu.oxtrust.ldap.service.PersonService.countPersons(PersonService.java:319) at org.gluu.oxtrust.ldap.service.StatusCheckerDaily.setLdapAttributes(StatusCheckerDaily.java:146) at org.gluu.oxtrust.ldap.service.StatusCheckerDaily.processInt(StatusCheckerDaily.java:111) at org.gluu.oxtrust.ldap.service.StatusCheckerDaily.process(StatusCheckerDaily.java:81) at org.gluu.oxtrust.ldap.service.StatusCheckerDaily$Proxy$_$$_WeldSubclass.process$$super(Unknown Source) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.jboss.weld.interceptor.proxy.TerminalAroundInvokeInvocationContext.proceedInternal(TerminalAroundInvokeInvocationContext.java:51) at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:78) at org.xdi.service.cdi.async.AsynchronousInterceptor$1.get(AsynchronousInterceptor.java:36) at java.util.concurrent.CompletableFuture$AsyncSupply.run(CompletableFuture.java:1590) at java.lang.Thread.run(Thread.java:745) Caused by: LDAPSearchException(resultCode=2 (protocol error), numEntries=0, numReferences=0, errorMessage='paged results cookie is invalid') at com.unboundid.ldap.sdk.LDAPConnection.search(LDAPConnection.java:3650) at com.unboundid.ldap.sdk.AbstractConnectionPool.search(AbstractConnectionPool.java:2022) at org.gluu.site.ldap.OperationsFacade.search(OperationsFacade.java:293) at org.gluu.site.ldap.OperationsFacade.search(OperationsFacade.java:192) at org.gluu.site.ldap.OperationsFacade.search(OperationsFacade.java:187) at org.gluu.site.ldap.persistence.LdapEntryManager.countEntries(LdapEntryManager.java:718) ... 16 more 2017-11-11 20:15:40,000 INFO [Thread-34] [gluu.oxtrust.service.status.ldap.LdapStatusTimer] (LdapStatusTimer.java:97) - connectionProvider statistics: LDAPConnectionPoolStatistics(numAvailableConnections=2, maxAvailableConnections=10, numSuccessfulConnectionAttempts=3, numFailedConnectionAttempts=0, numConnectionsClosedDefunct=1, numConnectionsClosedExpired=0, numConnectionsClosedUnneeded=0, numSuccessfulCheckouts=50, numFailedCheckouts=0, numReleasedValid=49) 2017-11-11 20:16:02,987 INFO [qtp985655350-19] [org.ocpsoft.logging.JDKLogAdapter] (JDKLogAdapter.java:29) - Loaded [1] org.ocpsoft.rewrite.spi.GlobalParameterProvider [org.ocpsoft.rewrite.instance.WildcardParameterProvider<0>] 2017-11-11 20:16:04,258 INFO [qtp985655350-18] [org.gluu.oxtrust.service.OpenIdService] (OpenIdService.java:61) - Successfully loaded oxAuth configuration 2017-11-11 20:16:04,329 ERROR [qtp985655350-18] [org.gluu.oxtrust.exception.GlobalExceptionHandler] (GlobalExceptionHandler.java:54) - Committed javax.faces.FacesException: Committed at com.sun.faces.lifecycle.InvokeApplicationPhase.execute(InvokeApplicationPhase.java:89) ~[jsf-impl-2.2.14.jar:2.2.14] at com.sun.faces.lifecycle.Phase.doPhase(Phase.java:101) [jsf-impl-2.2.14.jar:2.2.14] at com.sun.faces.lifecycle.LifecycleImpl.execute(LifecycleImpl.java:198) [jsf-impl-2.2.14.jar:2.2.14] at javax.faces.webapp.FacesServlet.service(FacesServlet.java:658) [jsf-api-2.2.14.jar:2.2] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:837) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1772) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.ocpsoft.rewrite.servlet.RewriteFilter.doFilter(RewriteFilter.java:226) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:566) [jetty-security-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:199) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:74) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.ocpsoft.rewrite.servlet.impl.HttpRewriteResultHandler.handleResult(HttpRewriteResultHandler.java:42) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.ocpsoft.rewrite.servlet.RewriteFilter.rewrite(RewriteFilter.java:297) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.ocpsoft.rewrite.servlet.RewriteFilter.doFilter(RewriteFilter.java:198) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.ocpsoft.rewrite.servlet.RewriteFilter.doFilter(RewriteFilter.java:198) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [jetty-security-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:119) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Server.handle(Server.java:534) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [jetty-io-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:110) [jetty-io-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [jetty-io-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112] Caused by: java.lang.IllegalStateException: Committed at org.eclipse.jetty.server.HttpChannel.resetBuffer(HttpChannel.java:788) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.HttpOutput.resetBuffer(HttpOutput.java:885) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Response.resetBuffer(Response.java:1232) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Response.sendRedirect(Response.java:663) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Response.sendRedirect(Response.java:672) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at javax.servlet.http.HttpServletResponseWrapper.sendRedirect(HttpServletResponseWrapper.java:176) ~[servlet-api-3.1.jar:3.1.0] at org.ocpsoft.rewrite.servlet.impl.HttpRewriteWrappedResponse.sendRedirect(HttpRewriteWrappedResponse.java:479) ~[rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at com.sun.faces.context.ExternalContextImpl.redirect(ExternalContextImpl.java:678) ~[jsf-impl-2.2.14.jar:2.2.14] at javax.faces.context.ExternalContextWrapper.redirect(ExternalContextWrapper.java:578) ~[jsf-api-2.2.14.jar:2.2] at javax.faces.context.ExternalContextWrapper.redirect(ExternalContextWrapper.java:578) ~[jsf-api-2.2.14.jar:2.2] at com.sun.faces.application.NavigationHandlerImpl.handleNavigation(NavigationHandlerImpl.java:221) ~[jsf-impl-2.2.14.jar:2.2.14] at com.sun.faces.application.NavigationHandlerImpl.handleNavigation(NavigationHandlerImpl.java:183) ~[jsf-impl-2.2.14.jar:2.2.14] at com.ocpsoft.pretty.faces2.application.PrettyNavigationHandler.handleNavigation(PrettyNavigationHandler.java:65) ~[rewrite-config-prettyfaces-3.4.1.Final.jar:3.4.1.Final] at org.ocpsoft.rewrite.faces.RewriteNavigationHandler.handleNavigation(RewriteNavigationHandler.java:64) ~[rewrite-integration-faces-3.4.1.Final.jar:3.4.1.Final] at com.sun.faces.application.ActionListenerImpl.processAction(ActionListenerImpl.java:132) ~[jsf-impl-2.2.14.jar:2.2.14] at javax.faces.component.UIViewAction.broadcast(UIViewAction.java:562) ~[jsf-api-2.2.14.jar:2.2] at javax.faces.component.UIViewRoot.broadcastEvents(UIViewRoot.java:790) ~[jsf-api-2.2.14.jar:2.2] at javax.faces.component.UIViewRoot.processApplication(UIViewRoot.java:1282) ~[jsf-api-2.2.14.jar:2.2] at com.sun.faces.lifecycle.InvokeApplicationPhase.execute(InvokeApplicationPhase.java:81) ~[jsf-impl-2.2.14.jar:2.2.14] ... 46 more 2017-11-11 20:16:04,335 ERROR [qtp985655350-18] [org.gluu.oxtrust.exception.GlobalExceptionHandler] (GlobalExceptionHandler.java:77) - Can't perform redirect to viewId: /error java.lang.IllegalStateException: Committed at org.eclipse.jetty.server.HttpChannel.resetBuffer(HttpChannel.java:788) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.HttpOutput.resetBuffer(HttpOutput.java:885) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Response.resetBuffer(Response.java:1232) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Response.sendRedirect(Response.java:663) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Response.sendRedirect(Response.java:672) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at javax.servlet.http.HttpServletResponseWrapper.sendRedirect(HttpServletResponseWrapper.java:176) ~[servlet-api-3.1.jar:3.1.0] at org.ocpsoft.rewrite.servlet.impl.HttpRewriteWrappedResponse.sendRedirect(HttpRewriteWrappedResponse.java:479) ~[rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at com.sun.faces.context.ExternalContextImpl.redirect(ExternalContextImpl.java:678) ~[jsf-impl-2.2.14.jar:2.2.14] at javax.faces.context.ExternalContextWrapper.redirect(ExternalContextWrapper.java:578) ~[jsf-api-2.2.14.jar:2.2] at javax.faces.context.ExternalContextWrapper.redirect(ExternalContextWrapper.java:578) ~[jsf-api-2.2.14.jar:2.2] at org.gluu.oxtrust.exception.GlobalExceptionHandler.performRedirect(GlobalExceptionHandler.java:75) [classes/:?] at org.gluu.oxtrust.exception.GlobalExceptionHandler.handle(GlobalExceptionHandler.java:55) [classes/:?] at com.sun.faces.lifecycle.Phase.doPhase(Phase.java:119) [jsf-impl-2.2.14.jar:2.2.14] at com.sun.faces.lifecycle.LifecycleImpl.execute(LifecycleImpl.java:198) [jsf-impl-2.2.14.jar:2.2.14] at javax.faces.webapp.FacesServlet.service(FacesServlet.java:658) [jsf-api-2.2.14.jar:2.2] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:837) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1772) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.ocpsoft.rewrite.servlet.RewriteFilter.doFilter(RewriteFilter.java:226) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:566) [jetty-security-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:199) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:74) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.ocpsoft.rewrite.servlet.impl.HttpRewriteResultHandler.handleResult(HttpRewriteResultHandler.java:42) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.ocpsoft.rewrite.servlet.RewriteFilter.rewrite(RewriteFilter.java:297) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.ocpsoft.rewrite.servlet.RewriteFilter.doFilter(RewriteFilter.java:198) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [jetty-security-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:119) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Server.handle(Server.java:534) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [jetty-io-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:110) [jetty-io-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [jetty-io-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] ``` If i try to login, the page loads but the login button doesn't show up, as I'm sure it means the whole server just isn't working properly Any thoughts?

By Miguel Foo user 11 Nov 2017 at 3:35 p.m. CST

Miguel Foo gravatar
So, i still get the error but I noticed that the script is being run as root (obviously), and all the files that were created were being set as root(obviously). So, I think the script probably needs to be changed to update the permissions on copy, namely these functions as far as I can tell: [https://github.com/GluuFederation/community-edition-setup/blob/master/static/scripts/import3031.py#L247](https://github.com/GluuFederation/community-edition-setup/blob/master/static/scripts/import3031.py#L247) and [https://github.com/GluuFederation/community-edition-setup/blob/master/static/scripts/import3031.py#L611](https://github.com/GluuFederation/community-edition-setup/blob/master/static/scripts/import3031.py#L611) After updating the permissions on all files affected by the 2 functions listed above, it seems I get a similar cryptic error below: ``` 2017-11-11 21:24:47,410 INFO [Thread-21] [gluu.oxtrust.service.status.ldap.LdapStatusTimer] (LdapStatusTimer.java:97) - connectionProvider statistics: LDAPConnectionPoolStatistics( numAvailableConnections=1, maxAvailableConnections=10, numSuccessfulConnectionAttempts=1, numFailedConnectionAttempts=0, numConnectionsClosedDefunct=0, numConnectionsClosedExpired=0 , numConnectionsClosedUnneeded=0, numSuccessfulCheckouts=33, numFailedCheckouts=0, numReleasedValid=33) 2017-11-11 21:25:47,408 INFO [Thread-33] [gluu.oxtrust.service.status.ldap.LdapStatusTimer] (LdapStatusTimer.java:97) - connectionProvider statistics: LDAPConnectionPoolStatistics( numAvailableConnections=2, maxAvailableConnections=10, numSuccessfulConnectionAttempts=2, numFailedConnectionAttempts=0, numConnectionsClosedDefunct=0, numConnectionsClosedExpired=0 , numConnectionsClosedUnneeded=0, numSuccessfulCheckouts=53, numFailedCheckouts=0, numReleasedValid=53) 2017-11-11 21:26:31,228 INFO [qtp985655350-16] [org.ocpsoft.logging.JDKLogAdapter] (JDKLogAdapter.java:29) - Loaded [1] org.ocpsoft.rewrite.spi.GlobalParameterProvider [org.ocpsoft .rewrite.instance.WildcardParameterProvider<0>] 2017-11-11 21:26:32,568 INFO [qtp985655350-17] [org.gluu.oxtrust.service.OpenIdService] (OpenIdService.java:61) - Successfully loaded oxAuth configuration 2017-11-11 21:26:32,647 ERROR [qtp985655350-17] [org.gluu.oxtrust.exception.GlobalExceptionHandler] (GlobalExceptionHandler.java:54) - Committed javax.faces.FacesException: Committed at com.sun.faces.lifecycle.InvokeApplicationPhase.execute(InvokeApplicationPhase.java:89) ~[jsf-impl-2.2.14.jar:2.2.14] at com.sun.faces.lifecycle.Phase.doPhase(Phase.java:101) [jsf-impl-2.2.14.jar:2.2.14] at com.sun.faces.lifecycle.LifecycleImpl.execute(LifecycleImpl.java:198) [jsf-impl-2.2.14.jar:2.2.14] at javax.faces.webapp.FacesServlet.service(FacesServlet.java:658) [jsf-api-2.2.14.jar:2.2] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:837) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1772) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.ocpsoft.rewrite.servlet.RewriteFilter.doFilter(RewriteFilter.java:226) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:566) [jetty-security-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:199) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:74) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.ocpsoft.rewrite.servlet.impl.HttpRewriteResultHandler.handleResult(HttpRewriteResultHandler.java:42) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.ocpsoft.rewrite.servlet.RewriteFilter.rewrite(RewriteFilter.java:297) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.ocpsoft.rewrite.servlet.RewriteFilter.doFilter(RewriteFilter.java:198) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [jetty-security-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:119) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Server.handle(Server.java:534) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [jetty-io-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:110) [jetty-io-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [jetty-io-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112] Caused by: java.lang.IllegalStateException: Committed at org.eclipse.jetty.server.HttpChannel.resetBuffer(HttpChannel.java:788) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.HttpOutput.resetBuffer(HttpOutput.java:885) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Response.resetBuffer(Response.java:1232) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Response.sendRedirect(Response.java:663) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Response.sendRedirect(Response.java:672) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at javax.servlet.http.HttpServletResponseWrapper.sendRedirect(HttpServletResponseWrapper.java:176) ~[servlet-api-3.1.jar:3.1.0] at org.ocpsoft.rewrite.servlet.impl.HttpRewriteWrappedResponse.sendRedirect(HttpRewriteWrappedResponse.java:479) ~[rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at com.sun.faces.context.ExternalContextImpl.redirect(ExternalContextImpl.java:678) ~[jsf-impl-2.2.14.jar:2.2.14] at javax.faces.context.ExternalContextWrapper.redirect(ExternalContextWrapper.java:578) ~[jsf-api-2.2.14.jar:2.2] at javax.faces.context.ExternalContextWrapper.redirect(ExternalContextWrapper.java:578) ~[jsf-api-2.2.14.jar:2.2] at com.sun.faces.application.NavigationHandlerImpl.handleNavigation(NavigationHandlerImpl.java:221) ~[jsf-impl-2.2.14.jar:2.2.14] at com.sun.faces.application.NavigationHandlerImpl.handleNavigation(NavigationHandlerImpl.java:183) ~[jsf-impl-2.2.14.jar:2.2.14] at com.ocpsoft.pretty.faces2.application.PrettyNavigationHandler.handleNavigation(PrettyNavigationHandler.java:65) ~[rewrite-config-prettyfaces-3.4.1.Final.jar:3.4.1.Final] at org.ocpsoft.rewrite.faces.RewriteNavigationHandler.handleNavigation(RewriteNavigationHandler.java:64) ~[rewrite-integration-faces-3.4.1.Final.jar:3.4.1.Final] at com.sun.faces.application.ActionListenerImpl.processAction(ActionListenerImpl.java:132) ~[jsf-impl-2.2.14.jar:2.2.14] at javax.faces.component.UIViewAction.broadcast(UIViewAction.java:562) ~[jsf-api-2.2.14.jar:2.2] at javax.faces.component.UIViewRoot.broadcastEvents(UIViewRoot.java:790) ~[jsf-api-2.2.14.jar:2.2] at javax.faces.component.UIViewRoot.processApplication(UIViewRoot.java:1282) ~[jsf-api-2.2.14.jar:2.2] at com.sun.faces.lifecycle.InvokeApplicationPhase.execute(InvokeApplicationPhase.java:81) ~[jsf-impl-2.2.14.jar:2.2.14] ... 46 more 2017-11-11 21:26:32,652 ERROR [qtp985655350-17] [org.gluu.oxtrust.exception.GlobalExceptionHandler] (GlobalExceptionHandler.java:77) - Can't perform redirect to viewId: /error java.lang.IllegalStateException: Committed at org.eclipse.jetty.server.HttpChannel.resetBuffer(HttpChannel.java:788) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.HttpOutput.resetBuffer(HttpOutput.java:885) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Response.resetBuffer(Response.java:1232) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Response.sendRedirect(Response.java:663) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Response.sendRedirect(Response.java:672) ~[jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at javax.servlet.http.HttpServletResponseWrapper.sendRedirect(HttpServletResponseWrapper.java:176) ~[servlet-api-3.1.jar:3.1.0] at org.ocpsoft.rewrite.servlet.impl.HttpRewriteWrappedResponse.sendRedirect(HttpRewriteWrappedResponse.java:479) ~[rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at com.sun.faces.context.ExternalContextImpl.redirect(ExternalContextImpl.java:678) ~[jsf-impl-2.2.14.jar:2.2.14] at javax.faces.context.ExternalContextWrapper.redirect(ExternalContextWrapper.java:578) ~[jsf-api-2.2.14.jar:2.2] at javax.faces.context.ExternalContextWrapper.redirect(ExternalContextWrapper.java:578) ~[jsf-api-2.2.14.jar:2.2] at org.gluu.oxtrust.exception.GlobalExceptionHandler.performRedirect(GlobalExceptionHandler.java:75) [classes/:?] at org.gluu.oxtrust.exception.GlobalExceptionHandler.handle(GlobalExceptionHandler.java:55) [classes/:?] at com.sun.faces.lifecycle.Phase.doPhase(Phase.java:119) [jsf-impl-2.2.14.jar:2.2.14] at com.sun.faces.lifecycle.LifecycleImpl.execute(LifecycleImpl.java:198) [jsf-impl-2.2.14.jar:2.2.14] at javax.faces.webapp.FacesServlet.service(FacesServlet.java:658) [jsf-api-2.2.14.jar:2.2] at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:837) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1772) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.ocpsoft.rewrite.servlet.RewriteFilter.doFilter(RewriteFilter.java:226) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:566) [jetty-security-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:199) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:74) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.ocpsoft.rewrite.servlet.impl.HttpRewriteResultHandler.handleResult(HttpRewriteResultHandler.java:42) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.ocpsoft.rewrite.servlet.RewriteFilter.rewrite(RewriteFilter.java:297) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.ocpsoft.rewrite.servlet.RewriteFilter.doFilter(RewriteFilter.java:198) [rewrite-servlet-3.4.1.Final.jar:3.4.1.Final] at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:548) [jetty-security-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:226) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512) [jetty-servlet-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:213) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:119) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.Server.handle(Server.java:534) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251) [jetty-server-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283) [jetty-io-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:110) [jetty-io-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93) [jetty-io-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589) [jetty-util-9.3.15.v20161220.jar:9.3.15.v20161220] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112] ```

By Arvind Tomar staff 13 Nov 2017 at 6:15 a.m. CST

Arvind Tomar gravatar
Hi Miguel We are working on some big number of changes in current scripts so please wait for it. Most probably I will complete it by tomorrow. Meanwhile if you can share backup dir (backup3031) created by export script that will help us investigating the issues. Thanks Arvind Tomar

By Miguel Foo user 13 Nov 2017 at 7:03 a.m. CST

Miguel Foo gravatar
Heya, sounds good and I don't mind sending the directory but prefer to send it via email as opposed to sharing it on this public forum. Is there an email I can reach you at? Cheers, Miguel

By Arvind Tomar staff 13 Nov 2017 at 10 p.m. CST

Arvind Tomar gravatar
hi Miguel Yes sure you can send me on arvind@gluu.org. Thanks Arvind Tomar

By Miguel Foo user 14 Nov 2017 at 8:32 a.m. CST

Miguel Foo gravatar
Sent

By Arvind Tomar staff 14 Nov 2017 at 10:35 p.m. CST

Arvind Tomar gravatar
yes I got It Thanks

By Arvind Tomar staff 16 Nov 2017 at 5:37 a.m. CST

Arvind Tomar gravatar
Hi Miguel I tried to import you data I also faced same issue and I have resolved them. Finally I am able to see oxTrust(admin panel). currently I am testing it little bit more and working on few other problems as well. So I will give you new scripts as soon as I complete this. Thank you for your support Arvind Tomar

By Arvind Tomar staff 17 Nov 2017 at 10:31 p.m. CST

Arvind Tomar gravatar
HI miguel can you try latest upgrade scripts I have resolved all of the known bugs... Thanks Arvind Tomar

By Marya Thomes user 18 Nov 2017 at 11:31 p.m. CST

Marya Thomes gravatar
Hello we'll go over the most common Jackson Exceptions – the JsonMappingException and UnrecognizedPropertyException. _____________________________ Web solution [Locus Rags](https://www.yell.com/biz/locus-rags-web-and-mobile-software-solutions-coventry-8955506/) in UK

By Miguel Foo user 20 Nov 2017 at 10:50 a.m. CST

Miguel Foo gravatar
Great,we will be trying it today, we'll let u know how it goes!

By Miguel Foo user 21 Nov 2017 at 12:27 p.m. CST

Miguel Foo gravatar
Hey guys, So, we tried the new scripts for the upgrade and many of the errors went away, however when starting up we still go the 503 error from the very first post. Examining the oxtrust logs we found this error ``` org.gluu.site.ldap.persistence.exception.EntryPersistenceException: Failed to calculate count entries with baseDN: ou=people,o=@!037F.7407.DCC3.D36D!0001!38C4.7E97,o=gluu, filter: (&(objectClass=top)(objectClass=gluuPerson)) at org.gluu.site.ldap.persistence.LdapEntryManager.countEntries(LdapEntryManager.java:722) ~[oxLdap-3.0.2.jar:?] at org.gluu.site.ldap.persistence.LdapEntryManager.countEntries(LdapEntryManager.java:688) ~[oxLdap-3.0.2.jar:?] at org.gluu.oxtrust.ldap.service.PersonService.countPersons(PersonService.java:326) ~[classes/:?] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_112] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_112] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_112] at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_112] at org.jboss.seam.util.Reflections.invoke(Reflections.java:22) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.RootInvocationContext.proceed(RootInvocationContext.java:32) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:56) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.transaction.RollbackInterceptor.aroundInvoke(RollbackInterceptor.java:28) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.core.BijectionInterceptor.aroundInvoke(BijectionInterceptor.java:79) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.core.MethodContextInterceptor.aroundInvoke(MethodContextInterceptor.java:44) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.RootInterceptor.invoke(RootInterceptor.java:107) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.JavaBeanInterceptor.interceptInvocation(JavaBeanInterceptor.java:196) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.JavaBeanInterceptor.invoke(JavaBeanInterceptor.java:114) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.gluu.oxtrust.ldap.service.PersonService_$$_javassist_seam_37.countPersons(PersonService_$$_javassist_seam_37.java) ~[classes/:?] at org.gluu.oxtrust.ldap.service.StatusCheckerDaily.setLdapAttributes(StatusCheckerDaily.java:151) ~[classes/:?] at org.gluu.oxtrust.ldap.service.StatusCheckerDaily.processInt(StatusCheckerDaily.java:116) ~[classes/:?] at org.gluu.oxtrust.ldap.service.StatusCheckerDaily.process(StatusCheckerDaily.java:86) ~[classes/:?] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_112] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_112] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_112] at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_112] at org.jboss.seam.util.Reflections.invoke(Reflections.java:22) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.RootInvocationContext.proceed(RootInvocationContext.java:32) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:56) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.transaction.RollbackInterceptor.aroundInvoke(RollbackInterceptor.java:28) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.core.BijectionInterceptor.aroundInvoke(BijectionInterceptor.java:79) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.core.MethodContextInterceptor.aroundInvoke(MethodContextInterceptor.java:44) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.async.AsynchronousInterceptor.aroundInvoke(AsynchronousInterceptor.java:52) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.SeamInvocationContext.proceed(SeamInvocationContext.java:68) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.RootInterceptor.invoke(RootInterceptor.java:107) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.JavaBeanInterceptor.interceptInvocation(JavaBeanInterceptor.java:196) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.intercept.JavaBeanInterceptor.invoke(JavaBeanInterceptor.java:114) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.gluu.oxtrust.ldap.service.StatusCheckerDaily_$$_javassist_seam_42.process(StatusCheckerDaily_$$_javassist_seam_42.java) ~[classes/:?] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_112] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_112] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_112] at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_112] at org.jboss.seam.util.Reflections.invoke(Reflections.java:22) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.util.Reflections.invokeAndWrap(Reflections.java:144) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.Component.callComponentMethod(Component.java:2313) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.core.Events.raiseEvent(Events.java:85) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.async.AsynchronousEvent$1.process(AsynchronousEvent.java:33) ~[jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.async.Asynchronous$ContextualAsynchronousRequest.run(Asynchronous.java:80) [jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.async.AsynchronousEvent.execute(AsynchronousEvent.java:27) [jboss-seam-2.3.1.Final.jar:2.3.1.Final] at org.jboss.seam.async.ThreadPoolDispatcher$RunnableAsynchronous.run(ThreadPoolDispatcher.java:142) [jboss-seam-2.3.1.Final.jar:2.3.1.Final] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [?:1.8.0_112] at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [?:1.8.0_112] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180) [?:1.8.0_112] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294) [?:1.8.0_112] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [?:1.8.0_112] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [?:1.8.0_112] at java.lang.Thread.run(Thread.java:745) [?:1.8.0_112] ``` and this later on, related? ``` Caused by: com.unboundid.ldap.sdk.LDAPSearchException: paged results cookie is invalid at com.unboundid.ldap.sdk.LDAPConnection.search(LDAPConnection.java:3648) ~[unboundid-ldapsdk-3.1.1.jar:3.1.1] at com.unboundid.ldap.sdk.AbstractConnectionPool.search(AbstractConnectionPool.java:2020) ~[unboundid-ldapsdk-3.1.1.jar:3.1.1] at org.gluu.site.ldap.OperationsFacade.search(OperationsFacade.java:280) ~[oxLdap-3.0.2.jar:?] at org.gluu.site.ldap.OperationsFacade.search(OperationsFacade.java:192) ~[oxLdap-3.0.2.jar:?] at org.gluu.site.ldap.OperationsFacade.search(OperationsFacade.java:187) ~[oxLdap-3.0.2.jar:?] at org.gluu.site.ldap.persistence.LdapEntryManager.countEntries(LdapEntryManager.java:716) ~[oxLdap-3.0.2.jar:?] ... 60 more ``` Any ideas on what may have caused it? None of the errors I reported previously were present by the way, so whatever you did, worked, for the most part Also, not sure if its relevant but my methodology is to take the folder that is created for export, move it off the computer. Drop the VM, remake the Ubuntu 16.04 image from scratch, and continue the instructions on the upgrade guide (ie, install 3.1.1, stop the service, and run the import script) Would that affect it?

By Yuriy Movchan staff 22 Nov 2017 at 6 a.m. CST

Yuriy Movchan gravatar
Hi Miguel, Thank you about stack traces. We fixed same issue in all search and batch job methods more than 4 monthes ago. We not updated count method. It's LDAP issue. This force us to use same connection to load data via paged search. I will fix this in upcoming 3.1.2 release: https://github.com/GluuFederation/oxCore/issues/59

By Miguel Foo user 23 Nov 2017 at 8:06 a.m. CST

Miguel Foo gravatar
Awesome, but does that mean its not a fatal error? I'm still having issues with the 503, and not sure if we should wait to upgrade to 3.1.2 or if I should keep digging? You said you tried it with the zip i sent, so in theory it should work correct?

By Yuriy Movchan staff 24 Nov 2017 at 2:37 a.m. CST

Yuriy Movchan gravatar
It's not fatal error. According to stack trace there is root case of it in status checker. Can you do next: 1. Stop identity service. 2. Clean up identity logs. 3. Try to open identity url. 4. Zip all logs from identity service and attach to this ticket.