test credentials seems not valid

MkLHX commented 1 year ago

Hi there, I manage to start georchestra (#126).

But now i can't login using readme.md credentials.

To login, use these credentials:

testuser / testuser
testadmin / testadmin

I think something is bad in my intance because i get 404 errors on routes:

/mapstore/#/
/console/account/new

And errors when try to use default credentials:

The container datafeeder exited with the error:

 2022-08-30 11:30:13.830  INFO 1 --- [           main] o.g.d.app.DataFeederApplication          : Starting DataFeederApplication on ceb73b27af07 with PID 1 (/opt/app/datafeeder.jar started by geo in /)
2022-08-30 11:30:13.833  INFO 1 --- [           main] o.g.d.app.DataFeederApplication          : The following profiles are active: georchestra
2022-08-30 11:30:15.084  INFO 1 --- [           main] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data JPA repositories in DEFAULT mode.
2022-08-30 11:30:15.155  INFO 1 --- [           main] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 62ms. Found 2 JPA repository interfaces.
2022-08-30 11:30:15.974  INFO 1 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.security.access.expression.method.DefaultMethodSecurityExpressionHandler@7e3060d8' of type [org.springframework.security.access.expression.method.DefaultMethodSecurityExpressionHandler] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2022-08-30 11:30:15.977  INFO 1 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'org.georchestra.datafeeder.api.DataFeederApiConfiguration' of type [org.georchestra.datafeeder.api.DataFeederApiConfiguration$$EnhancerBySpringCGLIB$$dd387787] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2022-08-30 11:30:15.989  INFO 1 --- [           main] trationDelegate$BeanPostProcessorChecker : Bean 'methodSecurityMetadataSource' of type [org.springframework.security.access.method.DelegatingMethodSecurityMetadataSource] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2022-08-30 11:30:16.304  INFO 1 --- [           main] o.s.b.w.embedded.tomcat.TomcatWebServer  : Tomcat initialized with port(s): 8080 (http)
2022-08-30 11:30:16.319  INFO 1 --- [           main] o.apache.catalina.core.StandardService   : Starting service [Tomcat]
2022-08-30 11:30:16.319  INFO 1 --- [           main] org.apache.catalina.core.StandardEngine  : Starting Servlet engine: [Apache Tomcat/9.0.37]
2022-08-30 11:30:16.411  INFO 1 --- [           main] o.a.c.c.C.[.[localhost].[/datafeeder]    : Initializing Spring embedded WebApplicationContext
2022-08-30 11:30:16.411  INFO 1 --- [           main] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 2517 ms
2022-08-30 11:30:16.488  WARN 1 --- [           main] JpaBaseConfiguration$JpaWebConfiguration : spring.jpa.open-in-view is enabled by default. Therefore, database queries may be performed during view rendering. Explicitly configure spring.jpa.open-in-view to disable this warning
2022-08-30 11:30:16.685  INFO 1 --- [           main] o.hibernate.jpa.internal.util.LogHelper  : HHH000204: Processing PersistenceUnitInfo [name: default]
2022-08-30 11:30:16.779  INFO 1 --- [           main] org.hibernate.Version                    : HHH000412: Hibernate ORM core version 5.4.20.Final
2022-08-30 11:30:16.977  INFO 1 --- [           main] o.hibernate.annotations.common.Version   : HCANN000001: Hibernate Commons Annotations {5.1.0.Final}
2022-08-30 11:30:17.146  WARN 1 --- [           main] com.zaxxer.hikari.HikariConfig           : datafeeder-connection-pool - idleTimeout is less than 10000ms, setting to default 600000ms.
2022-08-30 11:30:17.146  INFO 1 --- [           main] com.zaxxer.hikari.HikariDataSource       : datafeeder-connection-pool - Starting...
2022-08-30 11:30:17.302  INFO 1 --- [           main] com.zaxxer.hikari.HikariDataSource       : datafeeder-connection-pool - Start completed.
2022-08-30 11:30:17.351  INFO 1 --- [           main] org.hibernate.dialect.Dialect            : HHH000400: Using dialect: org.hibernate.dialect.PostgreSQL10Dialect
2022-08-30 11:30:18.306  INFO 1 --- [           main] o.h.e.t.j.p.i.JtaPlatformInitiator       : HHH000490: Using JtaPlatform implementation: [org.hibernate.engine.transaction.jta.platform.internal.NoJtaPlatform]
2022-08-30 11:30:18.315  INFO 1 --- [           main] j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'default'
2022-08-30 11:30:18.573  WARN 1 --- [           main] ConfigServletWebServerApplicationContext : Exception encountered during context initialization - cancelling refresh attempt: org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'fileUploadApiController': Unsatisfied dependency expressed through field 'storageService'; nested exception is org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'org.georchestra.datafeeder.service.DataFeederServiceConfiguration': Unsatisfied dependency expressed through field 'properties'; nested exception is org.springframework.boot.context.properties.ConfigurationPropertiesBindException: Error creating bean with name 'configProperties': Could not bind properties to 'DataFeederConfigurationProperties' : prefix=datafeeder, ignoreInvalidFields=false, ignoreUnknownFields=true; nested exception is org.springframework.boot.context.properties.bind.BindException: Failed to bind properties under 'datafeeder.publishing.geoserver.base-namespace-u-r-i' to java.lang.String
2022-08-30 11:30:18.574  INFO 1 --- [           main] j.LocalContainerEntityManagerFactoryBean : Closing JPA EntityManagerFactory for persistence unit 'default'
2022-08-30 11:30:18.578  INFO 1 --- [           main] com.zaxxer.hikari.HikariDataSource       : datafeeder-connection-pool - Shutdown initiated...
2022-08-30 11:30:18.589  INFO 1 --- [           main] com.zaxxer.hikari.HikariDataSource       : datafeeder-connection-pool - Shutdown completed.
2022-08-30 11:30:18.591  INFO 1 --- [           main] o.apache.catalina.core.StandardService   : Stopping service [Tomcat]
2022-08-30 11:30:18.606  INFO 1 --- [           main] ConditionEvaluationReportLoggingListener : 

Error starting ApplicationContext. To display the conditions report re-run your application with 'debug' enabled.
2022-08-30 11:30:18.609 ERROR 1 --- [           main] o.s.b.d.LoggingFailureAnalysisReporter   : 

***************************
APPLICATION FAILED TO START
***************************

Description:

Failed to bind properties under 'datafeeder.publishing.geoserver.base-namespace-u-r-i' to java.lang.String:

    Property: datafeeder.publishing.geoserver.base-namespace-u-r-i
    Value: ${publicUrl}/import
    Origin: class path resource [application.yml]:61:25
    Reason: Could not resolve placeholder 'scheme' in value "${scheme}://${domainName}"

Action:

Update your application's configuration

I don't know to help for 'scheme' placeholder.

fvanderbiest commented 1 year ago

For the scheme parameter, this should have been fixed with https://github.com/georchestra/datadir/pull/248 You need to update your config folder with the fix.

For the auth issue with the default ldap users, this is really weird and unexpected. I would advise you to start again with fresh volumes:

docker-compose down -v
git pull origin 22.0
docker-compose pull
git submodule update
docker-compose up

MkLHX commented 1 year ago

Hello, Thank you for the reply.

First i try in my existing fresh clone repo.

delete all config folder and subfolders
pull origin 22.0
update submodule
pull docker images Nothing different when try to connect with default credentials

So i decide to clone a fresh version from github. I repeat all update commands to be sure everything contains the #248

But when i run docker stack i get same errors 404 on viewer/mapstore and the default credentials continue to not work. The only thing change is the datafeeder container is working.

fvanderbiest commented 1 year ago

Can you share with us the full docker logs of the mapstore & ldap containers ?

MkLHX commented 1 year ago

of course.

mapstore container logs

OpenJDK 64-Bit Server VM warning: ignoring option MaxPermSize=128m; support was removed in 8.0
Sep 01, 2022 12:47:24 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Server version:        Apache Tomcat/7.0.94
Sep 01, 2022 12:47:24 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Server built:          Apr 10 2019 16:56:40 UTC
Sep 01, 2022 12:47:24 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Server number:         7.0.94.0
Sep 01, 2022 12:47:24 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: OS Name:               Linux
Sep 01, 2022 12:47:24 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: OS Version:            5.10.16.3-microsoft-standard-WSL2
Sep 01, 2022 12:47:24 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Architecture:          amd64
Sep 01, 2022 12:47:24 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Java Home:             /usr/lib/jvm/java-8-openjdk-amd64/jre
Sep 01, 2022 12:47:24 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: JVM Version:           1.8.0_212-8u212-b01-1~deb9u1-b01
Sep 01, 2022 12:47:24 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: JVM Vendor:            Oracle Corporation
Sep 01, 2022 12:47:24 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: CATALINA_BASE:         /usr/local/tomcat
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: CATALINA_HOME:         /usr/local/tomcat
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.util.logging.config.file=/usr/local/tomcat/conf/logging.properties
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Xms512m
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Xmx512m
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -XX:MaxPermSize=128m
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dgeorchestra.datadir=/etc/georchestra
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -DPRINT_BASE_URL=pdf
INFO 2022-09-01 12:48:09.530 org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean::createNativeEntityManagerFactory:221 - Building JPA container EntityManagerFactory for persistence unit 'geostore'
INFO 2022-09-01 12:48:09.655 org.hibernate.cfg.annotations.Version::<clinit>:15 - Hibernate Annotations 3.3.1.GA
INFO 2022-09-01 12:48:09.694 org.hibernate.cfg.Environment::<clinit>:514 - Hibernate 3.2.6
INFO 2022-09-01 12:48:09.697 org.hibernate.cfg.Environment::<clinit>:547 - hibernate.properties not found
INFO 2022-09-01 12:48:09.700 org.hibernate.cfg.Environment::buildBytecodeProvider:681 - Bytecode provider name : cglib
INFO 2022-09-01 12:48:09.745 org.hibernate.cfg.Environment::<clinit>:598 - using JDK 1.4 java.sql.Timestamp handling
INFO 2022-09-01 12:48:09.939 org.hibernate.ejb.Version::<clinit>:15 - Hibernate EntityManager 3.3.2.GA
INFO 2022-09-01 12:48:10.101 org.hibernate.ejb.Ejb3Configuration::configure:315 - Processing PersistenceUnitInfo [
name: geostore
...]
INFO 2022-09-01 12:48:11.090 org.hibernate.cfg.AnnotationBinder::bindClass:418 - Binding entity from annotated class: it.geosolutions.geostore.core.model.StoredData
INFO 2022-09-01 12:48:11.354 org.hibernate.cfg.annotations.EntityBinder::bindTable:424 - Bind entity it.geosolutions.geostore.core.model.StoredData on table gs_stored_data
INFO 2022-09-01 12:48:11.691 org.hibernate.cfg.AnnotationBinder::bindClass:418 - Binding entity from annotated class: it.geosolutions.geostore.core.model.Resource
INFO 2022-09-01 12:48:11.721 org.hibernate.cfg.annotations.EntityBinder::bindTable:424 - Bind entity it.geosolutions.geostore.core.model.Resource on table gs_resource
INFO 2022-09-01 12:48:11.826 org.hibernate.cfg.AnnotationBinder::bindClass:418 - Binding entity from annotated class: it.geosolutions.geostore.core.model.Attribute
INFO 2022-09-01 12:48:11.827 org.hibernate.cfg.annotations.EntityBinder::bindTable:424 - Bind entity it.geosolutions.geostore.core.model.Attribute on table gs_attribute
INFO 2022-09-01 12:48:11.888 org.hibernate.cfg.AnnotationBinder::bindClass:418 - Binding entity from annotated class: it.geosolutions.geostore.core.model.SecurityRule
INFO 2022-09-01 12:48:11.888 org.hibernate.cfg.annotations.EntityBinder::bindTable:424 - Bind entity it.geosolutions.geostore.core.model.SecurityRule on table gs_security
INFO 2022-09-01 12:48:11.986 org.hibernate.cfg.AnnotationBinder::bindClass:418 - Binding entity from annotated class: it.geosolutions.geostore.core.model.Category
INFO 2022-09-01 12:48:11.987 org.hibernate.cfg.annotations.EntityBinder::bindTable:424 - Bind entity it.geosolutions.geostore.core.model.Category on table gs_category
INFO 2022-09-01 12:48:12.008 org.hibernate.cfg.AnnotationBinder::bindClass:418 - Binding entity from annotated class: it.geosolutions.geostore.core.model.UserAttribute
INFO 2022-09-01 12:48:12.020 org.hibernate.cfg.annotations.EntityBinder::bindTable:424 - Bind entity it.geosolutions.geostore.core.model.UserAttribute on table gs_user_attribute
INFO 2022-09-01 12:48:12.021 org.hibernate.cfg.AnnotationBinder::bindClass:418 - Binding entity from annotated class: it.geosolutions.geostore.core.model.User
INFO 2022-09-01 12:48:12.028 org.hibernate.cfg.annotations.EntityBinder::bindTable:424 - Bind entity it.geosolutions.geostore.core.model.User on table gs_user
INFO 2022-09-01 12:48:12.038 org.hibernate.cfg.AnnotationBinder::bindClass:418 - Binding entity from annotated class: it.geosolutions.geostore.core.model.UserGroup
INFO 2022-09-01 12:48:12.071 org.hibernate.cfg.annotations.EntityBinder::bindTable:424 - Bind entity it.geosolutions.geostore.core.model.UserGroup on table gs_usergroup
INFO 2022-09-01 12:48:12.239 org.hibernate.cfg.annotations.CollectionBinder::bindOneToManySecondPass:651 - Mapping collection: it.geosolutions.geostore.core.model.Resource.attribute -> gs_attribute
INFO 2022-09-01 12:48:12.323 org.hibernate.cfg.annotations.CollectionBinder::bindOneToManySecondPass:651 - Mapping collection: it.geosolutions.geostore.core.model.Resource.security -> gs_security
INFO 2022-09-01 12:48:12.324 org.hibernate.cfg.annotations.CollectionBinder::bindOneToManySecondPass:651 - Mapping collection: it.geosolutions.geostore.core.model.Category.resource -> gs_resource
INFO 2022-09-01 12:48:12.331 org.hibernate.cfg.annotations.CollectionBinder::bindOneToManySecondPass:651 - Mapping collection: it.geosolutions.geostore.core.model.User.attribute -> gs_user_attribute
INFO 2022-09-01 12:48:12.332 org.hibernate.cfg.annotations.CollectionBinder::bindOneToManySecondPass:651 - Mapping collection: it.geosolutions.geostore.core.model.User.security -> gs_security
INFO 2022-09-01 12:48:12.333 org.hibernate.cfg.annotations.CollectionBinder::bindOneToManySecondPass:651 - Mapping collection: it.geosolutions.geostore.core.model.UserGroup.security -> gs_security
INFO 2022-09-01 12:48:12.335 org.hibernate.cfg.AnnotationConfiguration::secondPassCompile:365 - Hibernate Validator not found: ignoring
INFO 2022-09-01 12:48:12.415 org.hibernate.connection.ConnectionProviderFactory::newConnectionProvider:72 - Initializing connection provider: org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider
INFO 2022-09-01 12:48:12.426 org.hibernate.ejb.connection.InjectedDataSourceConnectionProvider::configure:38 - Using provided datasource
INFO 2022-09-01 12:48:12.783 org.hibernate.cfg.SettingsFactory::buildSettings:89 - RDBMS: PostgreSQL, version: 13.7 (Debian 13.7-1.pgdg110+1)
INFO 2022-09-01 12:48:12.804 org.hibernate.cfg.SettingsFactory::buildSettings:90 - JDBC driver: PostgreSQL Native Driver, version: PostgreSQL 8.4 JDBC3 (build 702)
INFO 2022-09-01 12:48:12.930 org.hibernate.dialect.Dialect::<init>:152 - Using dialect: org.hibernate.dialect.PostgreSQLDialect
INFO 2022-09-01 12:48:12.966 org.hibernate.transaction.TransactionFactoryFactory::buildTransactionFactory:34 - Transaction strategy: org.hibernate.transaction.JDBCTransactionFactory
INFO 2022-09-01 12:48:12.981 org.hibernate.transaction.TransactionManagerLookupFactory::getTransactionManagerLookup:33 - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djdk.tls.ephemeralDHKeySize=2048
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dignore.endorsed.dirs=
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dcatalina.base=/usr/local/tomcat
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dcatalina.home=/usr/local/tomcat
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.io.tmpdir=/usr/local/tomcat/temp
Sep 01, 2022 12:47:25 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: Loaded APR based Apache Tomcat Native library 1.2.21 using APR version 1.5.2.
Sep 01, 2022 12:47:25 PM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
Sep 01, 2022 12:47:25 PM org.apache.catalina.core.AprLifecycleListener initializeSSL
INFO: OpenSSL successfully initialized (OpenSSL 1.1.0j  20 Nov 2018)
Sep 01, 2022 12:47:25 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-apr-8080"]
Sep 01, 2022 12:47:25 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-apr-8009"]
Sep 01, 2022 12:47:25 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 4398 ms
Sep 01, 2022 12:47:25 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Sep 01, 2022 12:47:25 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.94
Sep 01, 2022 12:47:26 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /usr/local/tomcat/webapps/mapstore.war
Sep 01, 2022 12:47:29 PM org.apache.catalina.loader.WebappClassLoaderBase validateJarFile
INFO: validateJarFile(/usr/local/tomcat/webapps/mapstore/WEB-INF/lib/servlet-api-2.5-6.1.14.jar) - jar not loaded. See Servlet Spec 3.0, section 10.7.2. Offending class: javax/servlet/Servlet.class
Sep 01, 2022 12:47:29 PM org.apache.catalina.loader.WebappClassLoaderBase validateJarFile
INFO: validateJarFile(/usr/local/tomcat/webapps/mapstore/WEB-INF/lib/servlet-api-2.5.jar) - jar not loaded. See Servlet Spec 3.0, section 10.7.2. Offending class: javax/servlet/Servlet.class
Sep 01, 2022 12:47:52 PM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
log4j:WARN Failed to set property [rollingPolicy] to value "org.apache.log4j.rolling.TimeBasedRollingPolicy". 
log4j:WARN Please set a rolling policy for the RollingFileAppender named 'fileAppender'
log4j:ERROR No output stream or file set for the appender named [fileAppender].
INFO 2022-09-01 12:48:12.985 org.hibernate.cfg.SettingsFactory::buildSettings:143 - Automatic flush during beforeCompletion(): disabled
INFO 2022-09-01 12:48:12.988 org.hibernate.cfg.SettingsFactory::buildSettings:147 - Automatic session close at end of transaction: disabled
INFO 2022-09-01 12:48:12.992 org.hibernate.cfg.SettingsFactory::buildSettings:154 - JDBC batch size: 15
INFO 2022-09-01 12:48:12.993 org.hibernate.cfg.SettingsFactory::buildSettings:157 - JDBC batch updates for versioned data: disabled
INFO 2022-09-01 12:48:13.003 org.hibernate.cfg.SettingsFactory::buildSettings:162 - Scrollable result sets: enabled
INFO 2022-09-01 12:48:13.011 org.hibernate.cfg.SettingsFactory::buildSettings:170 - JDBC3 getGeneratedKeys(): disabled
INFO 2022-09-01 12:48:13.015 org.hibernate.cfg.SettingsFactory::buildSettings:178 - Connection release mode: auto
INFO 2022-09-01 12:48:13.026 org.hibernate.cfg.SettingsFactory::buildSettings:196 - Default schema: mapstore
INFO 2022-09-01 12:48:13.030 org.hibernate.cfg.SettingsFactory::buildSettings:205 - Default batch fetch size: 1
INFO 2022-09-01 12:48:13.031 org.hibernate.cfg.SettingsFactory::buildSettings:209 - Generate SQL with comments: disabled
INFO 2022-09-01 12:48:13.032 org.hibernate.cfg.SettingsFactory::buildSettings:213 - Order SQL updates by primary key: disabled
INFO 2022-09-01 12:48:13.032 org.hibernate.cfg.SettingsFactory::buildSettings:217 - Order SQL inserts for batching: disabled
INFO 2022-09-01 12:48:13.032 org.hibernate.cfg.SettingsFactory::createQueryTranslatorFactory:386 - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
INFO 2022-09-01 12:48:13.034 org.hibernate.hql.ast.ASTQueryTranslatorFactory::<init>:24 - Using ASTQueryTranslatorFactory
INFO 2022-09-01 12:48:13.034 org.hibernate.cfg.SettingsFactory::buildSettings:225 - Query language substitutions: {}
INFO 2022-09-01 12:48:13.034 org.hibernate.cfg.SettingsFactory::buildSettings:230 - JPA-QL strict compliance: enabled
INFO 2022-09-01 12:48:13.035 org.hibernate.cfg.SettingsFactory::buildSettings:235 - Second-level cache: enabled
INFO 2022-09-01 12:48:13.035 org.hibernate.cfg.SettingsFactory::buildSettings:239 - Query cache: disabled
INFO 2022-09-01 12:48:13.035 org.hibernate.cfg.SettingsFactory::createCacheProvider:373 - Cache provider: org.hibernate.cache.EhCacheProvider
INFO 2022-09-01 12:48:13.042 org.hibernate.cfg.SettingsFactory::buildSettings:254 - Optimize cache for minimal puts: disabled
INFO 2022-09-01 12:48:13.042 org.hibernate.cfg.SettingsFactory::buildSettings:263 - Structured second-level cache entries: disabled
INFO 2022-09-01 12:48:13.050 org.hibernate.cfg.SettingsFactory::buildSettings:290 - Statistics: disabled
INFO 2022-09-01 12:48:13.077 org.hibernate.cfg.SettingsFactory::buildSettings:294 - Deleted entity synthetic identifier rollback: disabled
INFO 2022-09-01 12:48:13.082 org.hibernate.cfg.SettingsFactory::buildSettings:309 - Default entity-mode: pojo
INFO 2022-09-01 12:48:13.082 org.hibernate.cfg.SettingsFactory::buildSettings:313 - Named query checking : enabled
INFO 2022-09-01 12:48:13.147 org.hibernate.impl.SessionFactoryImpl::<init>:161 - building session factory
INFO 2022-09-01 12:48:14.401 org.hibernate.impl.SessionFactoryObjectFactory::addInstance:82 - Not binding factory to JNDI, no JNDI name configured
INFO 2022-09-01 12:48:14.409 org.hibernate.tool.hbm2ddl.SchemaValidator::validate:98 - Running schema validator
INFO 2022-09-01 12:48:14.411 org.hibernate.tool.hbm2ddl.SchemaValidator::validate:106 - fetching database metadata
INFO 2022-09-01 12:48:14.612 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:39 - table found: gs_attribute
INFO 2022-09-01 12:48:14.615 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:40 - columns: [attribute_date, attribute_text, name, attribute_number, resource_id, attribute_type, id]
INFO 2022-09-01 12:48:14.628 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:39 - table found: gs_category
INFO 2022-09-01 12:48:14.631 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:40 - columns: [name, id]
INFO 2022-09-01 12:48:14.642 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:39 - table found: gs_resource
INFO 2022-09-01 12:48:14.652 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:40 - columns: [metadata, category_id, name, description, id, lastupdate, creation]
INFO 2022-09-01 12:48:14.684 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:39 - table found: gs_security
INFO 2022-09-01 12:48:14.690 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:40 - columns: [canread, group_id, user_id, canwrite, resource_id, id, groupname, username]
INFO 2022-09-01 12:48:14.694 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:39 - table found: gs_stored_data
INFO 2022-09-01 12:48:14.695 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:40 - columns: [resource_id, id, stored_data]
INFO 2022-09-01 12:48:14.707 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:39 - table found: gs_user
INFO 2022-09-01 12:48:14.722 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:40 - columns: [user_role, user_password, group_id, name, id, enabled]
INFO 2022-09-01 12:48:14.740 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:39 - table found: gs_user_attribute
INFO 2022-09-01 12:48:14.761 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:40 - columns: [string, user_id, name, id]
INFO 2022-09-01 12:48:14.766 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:39 - table found: gs_usergroup
INFO 2022-09-01 12:48:14.769 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:40 - columns: [description, id, groupname, enabled]
INFO 2022-09-01 12:48:14.774 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:39 - table found: gs_usergroup_members
INFO 2022-09-01 12:48:14.775 org.hibernate.tool.hbm2ddl.TableMetadata::<init>:40 - columns: [user_id, group_id]
INFO 2022-09-01 12:48:15.387 org.springframework.security.ldap.DefaultSpringSecurityContextSource::<init>:54 -  URL 'ldap://ldap:389/dc=georchestra,dc=org', root DN is 'dc=georchestra,dc=org'
INFO 2022-09-01 12:48:16.075 it.geosolutions.geostore.init.GeoStoreInit::afterPropertiesSet:81 - ===== Starting GeoStore services =====
INFO 2022-09-01 12:48:16.076 it.geosolutions.geostore.init.GeoStoreInit::initPasswordEncoding:126 - === Set up the security system   ====
INFO 2022-09-01 12:48:16.078 it.geosolutions.geostore.init.GeoStoreInit::initPasswordEncoding:127 - Encoding Type:DIGEST
INFO 2022-09-01 12:48:16.155 org.springframework.ldap.core.LdapTemplate::assureReturnObjFlagSet:1262 - The returnObjFlag of supplied SearchControls is not set but a ContextMapper is used - setting flag to true
INFO 2022-09-01 12:48:16.404 org.springframework.beans.factory.support.DefaultListableBeanFactory::destroySingletons:422 - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@10ad135d: defining beans [org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,log4jInitializer,mapPrinter,configFactory,geostoreInitializer,mapReaderFactoryFinder,wms-MapReaderFactory,mapServer-MapReaderFactory,tileCache-MapReaderFactory,osm-MapReaderFactory,xyz-MapReaderFactory,tms-MapReaderFactory,vector-MapReaderFactory,image-MapReaderFactory,tiledGoogle-MapReaderFactory,google-MapReaderFactory,kaMapCache-ReaderFactory,kaMap-ReaderFactory,wmts-ReaderFactory,outputFactory,imageMagickOutput,fileCachingJaiMosaicOutputFactory,inMemoryJaiMosaicOutputFactory,pdfOutputFactory,georchestra-properties,georchestraDataSource,georchestraEntityManagerFactory,georchestraVendorAdapter,org.springframework.beans.factory.config.PropertyPlaceholderConfigurer#0,org.springframework.beans.factory.config.PropertyOverrideConfigurer#0,geostoreDataSource,geostoreEntityManagerFactory,geostoreVendorAdapter,geostoreTransactionManager,org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0,org.springframework.aop.config.internalAutoProxyCreator,org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0,org.springframework.transaction.interceptor.TransactionInterceptor#0,org.springframework.transaction.config.internalTransactionAdvisor,geostoreSearchProcessor,geostoreMetadataUtil,storedDataDAO,resourceDAO,attributeDAO,categoryDAO,userAttributeDAO,userDAO,userGroupDAO,securityDAO,externalSecurityDAO,cxf,org.apache.cxf.bus.spring.BusApplicationListener,org.apache.cxf.bus.spring.BusWiringBeanFactoryPostProcessor,org.apache.cxf.bus.spring.Jsr250BeanPostProcessor,org.apache.cxf.bus.spring.BusExtensionPostProcessor,org.apache.cxf.resource.ResourceManager,org.apache.cxf.configuration.Configurer,org.apache.cxf.binding.BindingFactoryManager,org.apache.cxf.transport.DestinationFactoryManager,org.apache.cxf.transport.ConduitInitiatorManager,org.apache.cxf.wsdl.WSDLManager,org.apache.cxf.phase.PhaseManager,org.apache.cxf.workqueue.WorkQueueManager,org.apache.cxf.buslifecycle.BusLifeCycleManager,org.apache.cxf.endpoint.ServerRegistry,org.apache.cxf.endpoint.ServerLifeCycleManager,org.apache.cxf.endpoint.ClientLifeCycleManager,org.apache.cxf.transports.http.QueryHandlerRegistry,org.apache.cxf.endpoint.EndpointResolverRegistry,org.apache.cxf.headers.HeaderManager,org.apache.cxf.catalog.OASISCatalogManager,org.apache.cxf.service.factory.FactoryBeanListenerManager,org.apache.cxf.endpoint.ServiceContractResolverRegistry,org.apache.cxf.transport.http.policy.HTTPClientAssertionBuilder,org.apache.cxf.transport.http.policy.HTTPServerAssertionBuilder,org.apache.cxf.transport.http.policy.NoOpPolicyInterceptorProvider,org.apache.cxf.transport.http.ClientOnlyHTTPTransportFactory,org.apache.cxf.transport.servlet.ServletTransportFactory,restExtJsService,geoStoreRESTExtJs,org.apache.cxf.binding.xml.XMLBindingFactory,org.springframework.security.access.method.DelegatingMethodSecurityMetadataSource#0,org.springframework.security.access.vote.AffirmativeBased#0,org.springframework.security.access.intercept.aopalliance.MethodSecurityInterceptor#0,org.springframework.security.methodSecurityMetadataSourceAdvisor,org.springframework.security.web.PortMapperImpl#0,org.springframework.security.web.context.HttpSessionSecurityContextRepository#0,org.springframework.security.web.authentication.session.SessionFixationProtectionStrategy#0,org.springframework.security.authentication.ProviderManager#0,org.springframework.security.access.vote.AffirmativeBased#1,org.springframework.security.web.access.intercept.FilterSecurityInterceptor#0,org.springframework.security.web.access.DefaultWebInvocationPrivilegeEvaluator#0,org.springframework.security.authentication.AnonymousAuthenticationProvider#0,org.springframework.security.web.savedrequest.HttpSessionRequestCache#0,org.springframework.security.web.authentication.UsernamePasswordAuthenticationFilter#0,org.springframework.security.config.http.UserDetailsServiceInjectionBeanPostProcessor#0,org.springframework.security.filterChainProxy,restAuthenticationEntryPoint,geoStoreUserServiceAuthenticationProvider,authenticationTokenProcessingFilter,sessionTokenProcessingFilter,georchestraAuthenticationProvider,headersProcessingFilter,rolesMapper,org.springframework.security.authentication.DefaultAuthenticationEventPublisher#0,org.springframework.security.authenticationManager,ldap-context,ldapUserDAO,ldapUserGroupDAO,noCacheInterceptor,restStoredDataService,restResourceService,restCategoryService,restUserService,restMiscService,restBackupService,restUserGroupService,restSessionService,geoStoreRESTStoredData,geoStoreRESTResource,geoStoreRESTCategory,geoStoreRESTUser,geoStoreRESTUserGroup,geoStoreRESTMisc,geoStoreRESTBackup,geoStoreRESTSessionService,jaxbXmlProvider,jsonProvider,jaxbContextResolver,org.springframework.beans.factory.config.PropertyOverrideConfigurer#1,passwordProvider,keyStoreProvider,pbePasswordEncoder,strongPbePasswordEncoder,digestPasswordEncoder,geostoreEncoder,storedDataService,resourceService,categoryService,userService,userGroupService,userSessionService]; root of factory hierarchy
INFO 2022-09-01 12:48:16.405 org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean::destroy:406 - Closing JPA EntityManagerFactory for persistence unit 'geostore'
INFO 2022-09-01 12:48:16.405 org.hibernate.impl.SessionFactoryImpl::close:769 - closing
WARN 2022-09-01 12:48:16.546 org.springframework.beans.factory.support.DisposableBeanAdapter::invokeCustomDestroyMethod:283 - Invocation of destroy method 'shutdown' failed on bean with name 'cxf': org.springframework.beans.factory.BeanCreationNotAllowedException: Error creating bean with name 'log4jInitializer': Singleton bean creation not allowed while the singletons of this factory are in destruction (Do not request a bean from a BeanFactory in a destroy method implementation!)
ERROR 2022-09-01 12:48:16.550 org.springframework.web.context.ContextLoader::initWebApplicationContext:220 - Context initialization failed
org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'geostoreInitializer' defined in URL [file:/usr/local/tomcat/webapps/mapstore/WEB-INF/classes/applicationContext.xml]: Invocation of init method failed; nested exception is org.springframework.ldap.NameNotFoundException: [LDAP: error code 32 - No Such Object]; nested exception is javax.naming.NameNotFoundException: [LDAP: error code 32 - No Such Object]; remaining name 'ou=users'
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1420)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:519)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:456)
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:291)
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:222)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:288)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:190)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:580)
at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:895)
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:425)
at org.springframework.web.context.ContextLoader.createWebApplicationContext(ContextLoader.java:276)
at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:197)
at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:47)
at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:5197)
at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5720)
at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:145)
at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:1018)
at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:994)
at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652)
at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:1127)
at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:2020)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused by: org.springframework.ldap.NameNotFoundException: [LDAP: error code 32 - No Such Object]; nested exception is javax.naming.NameNotFoundException: [LDAP: error code 32 - No Such Object]; remaining name 'ou=users'
at org.springframework.ldap.support.LdapUtils.convertLdapException(LdapUtils.java:172)
at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:306)
at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:259)
at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:606)
at it.geosolutions.geostore.core.dao.ldap.impl.UserDAOImpl.ldapSearch(UserDAOImpl.java:147)
at it.geosolutions.geostore.core.dao.ldap.impl.UserDAOImpl.search(UserDAOImpl.java:123)
at it.geosolutions.geostore.services.UserServiceImpl.getAll(UserServiceImpl.java:317)
at it.geosolutions.geostore.init.GeoStoreInit.initPasswordEncoding(GeoStoreInit.java:132)
at it.geosolutions.geostore.init.GeoStoreInit.afterPropertiesSet(GeoStoreInit.java:83)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.invokeInitMethods(AbstractAutowireCapableBeanFactory.java:1477)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1417)
... 25 more
Caused by: javax.naming.NameNotFoundException: [LDAP: error code 32 - No Such Object]; remaining name 'ou=users'
at com.sun.jndi.ldap.LdapCtx.mapErrorCode(LdapCtx.java:3179)
at com.sun.jndi.ldap.LdapCtx.processReturnCode(LdapCtx.java:3100)
at com.sun.jndi.ldap.LdapCtx.processReturnCode(LdapCtx.java:2891)
at com.sun.jndi.ldap.LdapCtx.searchAux(LdapCtx.java:1846)
at com.sun.jndi.ldap.LdapCtx.c_search(LdapCtx.java:1769)
at com.sun.jndi.toolkit.ctx.ComponentDirContext.p_search(ComponentDirContext.java:392)
at com.sun.jndi.toolkit.ctx.PartialCompositeDirContext.search(PartialCompositeDirContext.java:358)
at com.sun.jndi.toolkit.ctx.PartialCompositeDirContext.search(PartialCompositeDirContext.java:341)
at javax.naming.directory.InitialDirContext.search(InitialDirContext.java:267)
at org.springframework.ldap.core.LdapTemplate$4.executeSearch(LdapTemplate.java:253)
at org.springframework.ldap.core.LdapTemplate.search(LdapTemplate.java:293)
... 34 more
Sep 01, 2022 12:48:16 PM org.apache.catalina.core.StandardContext startInternal
SEVERE: One or more listeners failed to start. Full details will be found in the appropriate container log file
Sep 01, 2022 12:48:16 PM org.apache.catalina.core.StandardContext startInternal
SEVERE: Context [/mapstore] startup failed due to previous errors
Sep 01, 2022 12:48:16 PM org.apache.catalina.loader.WebappClassLoaderBase clearReferencesJdbc
SEVERE: The web application [/mapstore] registered the JDBC driver [org.postgresql.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
Sep 01, 2022 12:48:16 PM org.apache.catalina.loader.WebappClassLoaderBase clearReferencesThreads
SEVERE: The web application [/mapstore] appears to have started a thread named [Thread-3] but has failed to stop it. This is very likely to create a memory leak.
Sep 01, 2022 12:48:16 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /usr/local/tomcat/webapps/mapstore.war has finished in 50,716 ms
Sep 01, 2022 12:48:16 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-apr-8080"]
Sep 01, 2022 12:48:16 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-apr-8009"]
Sep 01, 2022 12:48:17 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 51220 ms

ldap container logs:


Info: Container already configured, therefore ignoring SLAPD_xxx environment variables and preseed files
LDAP already initialized !
LDAP indexed
run-parts: executing /docker-entrypoint.d/00-init
run-parts: executing /docker-entrypoint.d/01-populate
run-parts: executing /docker-entrypoint.d/02-indexes
6310a9d3 @(#) $OpenLDAP: slapd 2.4.57+dfsg-3 (May 15 2021 23:03:34) $
        Debian OpenLDAP Maintainers <pkg-openldap-devel@lists.alioth.debian.org>
6310a9d3 slapd starting
6310ab22 slap_global_control: unrecognized control: 1.3.6.1.4.1.42.2.27.8.5.1
``

fvanderbiest commented 1 year ago

Mapstore says : ERROR 2022-09-01 12:48:16.550 org.springframework.web.context.ContextLoader::initWebApplicationContext:220 [...] [LDAP: error code 32 - No Such Object]; nested exception is javax.naming.NameNotFoundException: [LDAP: error code 32 - No Such Object]; remaining name 'ou=users'

There seems to be an issue with your LDAP setup: the 'ou=users' seems missing.

Confirmed by OpenLDAP which reports: "6310ab22 slap_global_control: unrecognized control: 1.3.6.1.4.1.42.2.27.8.5.1" Seems linked with the Password Policy module (see eg https://www.openldap.org/lists/openldap-software/200606/msg00021.html)

We'll have a deeper look.

fvanderbiest commented 1 year ago

Which branch are you using ? 22.0 or master ?

MkLHX commented 1 year ago

I'm using 22.0 branch following the documentation https://github.com/georchestra/docker#quick-start

ChiranthTL commented 1 year ago

This is still happening i guess...

Any help ?

fvanderbiest commented 1 year ago

LDAP container also warns about slap_global_control: unrecognized control: 1.3.6.1.4.1.42.2.27.8.5.1 here, (which means we have to investigate) but this does not prevent login. I cannot reproduce.

ppolicy was introduced 5 years ago with https://github.com/georchestra/georchestra/commit/be0f4e25c3aefd3bbc615c8c3258bb81a7e5a785#diff-79f10dc7ff957de5f6f7216942b591898ea5b6e3d64f08b3303c38dfc457b4dc (could help btw with https://github.com/georchestra/improvement-proposals/issues/3 & https://github.com/georchestra/improvement-proposals/issues/2)

georchestra / docker

test credentials seems not valid #131