Skip to content
New issue

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

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

Already on GitHub? Sign in to your account

No qualifying bean of type [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore] using docker-compose #4009

Closed
tsaqova opened this issue Aug 20, 2016 · 19 comments
Milestone

Comments

@tsaqova
Copy link

tsaqova commented Aug 20, 2016

Overview of the issue

When i run application using docker-compose app.yml (default) with following config:

  • fresh jhipster 3.6.1 using gradle
  • monolith application
  • oauth2 for authentication.
  • mysql for datastore dev/prod profiles.

i get an error :
No qualifying bean of type [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore] inside UserService class

but if i run using gradle bootRun it just work as usual.

Motivation for or Use Case

Can''t running application inside docker.

Reproduce the error
  • Install jhipster 3.6.1
  • Generate entity (uml)
  • Run gradle builddocker task
  • Run docker-compose app.yml (default config).
    ERROR

I try to create a bean with type JdbcTokenStore but the error still exist.

JHipster Version(s)

fresh 3.6.1

JHipster configuration, a .yo-rc.json file generated in the root folder
{
  "generator-jhipster": {
    "jhipsterVersion": "3.6.1",
    "baseName": "myapp",
    "packageName": "com.myapp.myapp",
    "packageFolder": "com/myapp/myapp",
    "serverPort": "8080",
    "authenticationType": "oauth2",
    "hibernateCache": "hazelcast",
    "clusteredHttpSession": "hazelcast",
    "websocket": "spring-websocket",
    "databaseType": "sql",
    "devDatabaseType": "mysql",
    "prodDatabaseType": "mysql",
    "searchEngine": "elasticsearch",
    "buildTool": "gradle",
    "useSass": true,
    "applicationType": "monolith",
    "testFrameworks": [
      "gatling",
      "cucumber",
      "protractor"
    ],
    "jhiPrefix": "jhi",
    "enableTranslation": true,
    "nativeLanguage": "en",
    "languages": [
      "en"
    ]
  }
}

Operating System

Linux

@jdubois
Copy link
Member

jdubois commented Aug 20, 2016

I'm not sure of what you are doing: you want to develop within Docker? Are you using our jhipster/jhipster Docker image?

@tsaqova
Copy link
Author

tsaqova commented Aug 20, 2016

i want to test running application inside docker (use case updated).
ref : http://jhipster.github.io/docker-compose/

when i just run using gradle bootRun it just work, however when i use docker image generated from jhipster which located at src/main/docker (app.yml, mysql.yml etc) it can't wire JdbcTokenStore. All configuration within db is work and liquibase migration is success.

@jdubois
Copy link
Member

jdubois commented Aug 20, 2016

Thanks for updating your use case!

  • You have quite an uncommun configuration, so maybe you hit some edge case -> I need to test this, to see if I can reproduce (not sure when I will have time)
  • But there shouldn't be any differences between "bootRun" and running the app in Docker

-> could you send the whole stacktrace?

@pascalgrimaud
Copy link
Member

Unable to reproduce

  1. use the same .yo-rc.json
{
  "generator-jhipster": {
    "jhipsterVersion": "3.6.1",
    "baseName": "myapp",
    "packageName": "com.myapp.myapp",
    "packageFolder": "com/myapp/myapp",
    "serverPort": "8080",
    "authenticationType": "oauth2",
    "hibernateCache": "hazelcast",
    "clusteredHttpSession": "hazelcast",
    "websocket": "spring-websocket",
    "databaseType": "sql",
    "devDatabaseType": "mysql",
    "prodDatabaseType": "mysql",
    "searchEngine": "elasticsearch",
    "buildTool": "gradle",
    "useSass": true,
    "applicationType": "monolith",
    "testFrameworks": [
      "gatling",
      "cucumber",
      "protractor"
    ],
    "jhiPrefix": "jhi",
    "enableTranslation": true,
    "nativeLanguage": "en",
    "languages": [
      "en"
    ],
    "enableSocialSignIn": false
  }
}
  1. I package and build docker image with: ./gradlew bootRepackage -Pprod buildDocker
  2. I launch everything with Docker: docker-compose -f src/main/docker/app.yml up

See the log:

myapp-app_1            | 2016-08-20 19:15:14.468  INFO 8 --- [           main] com.myapp.myapp.MyappApp                 : Started MyappApp in 41.632 seconds (JVM running for 43.195)
myapp-app_1            | 2016-08-20 19:15:14.471  INFO 8 --- [           main] com.myapp.myapp.MyappApp                 : 
myapp-app_1            | ----------------------------------------------------------
myapp-app_1            |    Application 'myapp' is running! Access URLs:
myapp-app_1            |    Local:      http://127.0.0.1:8080
myapp-app_1            |    External:   http://172.18.0.3:8080
myapp-app_1            | ----------------------------------------------------------
myapp-app_1            | 2016-08-20 19:15:29.586  INFO 8 --- [nio-8080-exec-1] c.h.partition.InternalPartitionService   : [172.18.0.3]:5701 [dev] [3.6.4] Initializing cluster partition table arrangement...

@ruddell
Copy link
Member

ruddell commented Aug 20, 2016

I also can't reproduce it, I have the same output as @pascalgrimaud

@tsaqova
Copy link
Author

tsaqova commented Aug 20, 2016

Thanks for the response!
here are my error stack trace, in src/main/docker, i just change mysql.yml image version to 5.7.13. :

Starting myapp-elasticsearch
Recreating docker_myapp-app_1
Starting myapp-mysql
Attaching to myapp-elasticsearch, myapp-mysql, docker_myapp-app_1
myapp-mysql            | 2016-08-20T19:31:04.649213Z 0 [Note] mysqld (mysqld 5.7.13) starting as process 1 ...
myapp-mysql            | 2016-08-20T19:31:04.656331Z 0 [Note] InnoDB: PUNCH HOLE support available
myapp-mysql            | 2016-08-20T19:31:04.656365Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
myapp-mysql            | 2016-08-20T19:31:04.656371Z 0 [Note] InnoDB: Uses event mutexes
myapp-mysql            | 2016-08-20T19:31:04.656379Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
myapp-mysql            | 2016-08-20T19:31:04.656383Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.8
myapp-mysql            | 2016-08-20T19:31:04.656386Z 0 [Note] InnoDB: Using Linux native AIO
myapp-mysql            | 2016-08-20T19:31:04.656697Z 0 [Note] InnoDB: Number of pools: 1
myapp-app_1            | The application will start in 10s...
myapp-mysql            | 2016-08-20T19:31:04.656873Z 0 [Note] InnoDB: Using CPU crc32 instructions
myapp-mysql            | 2016-08-20T19:31:04.660114Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
myapp-mysql            | 2016-08-20T19:31:04.670325Z 0 [Note] InnoDB: Completed initialization of buffer pool
myapp-mysql            | 2016-08-20T19:31:04.672187Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
myapp-mysql            | 2016-08-20T19:31:04.684222Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
myapp-mysql            | 2016-08-20T19:31:04.685765Z 0 [Note] InnoDB: Log scan progressed past the checkpoint lsn 17669002
myapp-mysql            | 2016-08-20T19:31:04.685797Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 17669011
myapp-mysql            | 2016-08-20T19:31:04.686154Z 0 [Note] InnoDB: Doing recovery: scanned up to log sequence number 17669011
myapp-mysql            | 2016-08-20T19:31:04.686178Z 0 [Note] InnoDB: Database was not shutdown normally!
myapp-mysql            | 2016-08-20T19:31:04.686183Z 0 [Note] InnoDB: Starting crash recovery.
myapp-elasticsearch    | [2016-08-20 19:31:04,752][INFO ][node                     ] [Shadow King] version[2.3.5], pid[1], build[90f439f/2016-07-27T10:36:52Z]
myapp-elasticsearch    | [2016-08-20 19:31:04,753][INFO ][node                     ] [Shadow King] initializing ...
myapp-mysql            | 2016-08-20T19:31:04.939962Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
myapp-mysql            | 2016-08-20T19:31:04.940002Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
myapp-mysql            | 2016-08-20T19:31:04.940072Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
myapp-mysql            | 2016-08-20T19:31:05.125672Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
myapp-mysql            | 2016-08-20T19:31:05.127770Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
myapp-mysql            | 2016-08-20T19:31:05.127794Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
myapp-mysql            | 2016-08-20T19:31:05.128571Z 0 [Note] InnoDB: 5.7.13 started; log sequence number 17669011
myapp-mysql            | 2016-08-20T19:31:05.128778Z 0 [Note] Plugin 'FEDERATED' is disabled.
myapp-mysql            | 2016-08-20T19:31:05.130798Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
myapp-mysql            | 2016-08-20T19:31:05.131753Z 0 [Note] Server hostname (bind-address): '*'; port: 3306
myapp-mysql            | 2016-08-20T19:31:05.131810Z 0 [Note] IPv6 is available.
myapp-mysql            | 2016-08-20T19:31:05.131825Z 0 [Note]   - '::' resolves to '::';
myapp-mysql            | 2016-08-20T19:31:05.131851Z 0 [Note] Server socket created on IP: '::'.
myapp-mysql            | 2016-08-20T19:31:05.141718Z 0 [Note] InnoDB: Buffer pool(s) load completed at 160820 19:31:05
myapp-mysql            | 2016-08-20T19:31:05.168909Z 0 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-20T19:31:05.168942Z 0 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-20T19:31:05.214181Z 0 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-20T19:31:05.218125Z 0 [ERROR] Incorrect definition of table performance_schema.replication_connection_status: expected column 'RECEIVED_TRANSACTION_SET' at position 7 to have type text, found type longtext.
myapp-mysql            | 2016-08-20T19:31:05.218527Z 0 [ERROR] Incorrect definition of table performance_schema.replication_group_member_stats: expected column 'COUNT_TRANSACTIONS_VALIDATING' at position 6, found 'COUNT_TRANSACTIONS_ROWS_VALIDATING'.
myapp-mysql            | 2016-08-20T19:31:05.219231Z 0 [Note] Event Scheduler: Loaded 0 events
myapp-mysql            | 2016-08-20T19:31:05.219837Z 0 [Note] mysqld: ready for connections.
myapp-mysql            | Version: '5.7.13'  socket: '/var/run/mysqld/mysqld.sock'  port: 3306  MySQL Community Server (GPL)
myapp-elasticsearch    | [2016-08-20 19:31:05,301][INFO ][plugins                  ] [Shadow King] modules [reindex, lang-expression, lang-groovy], plugins [], sites []
myapp-elasticsearch    | [2016-08-20 19:31:05,322][INFO ][env                      ] [Shadow King] using [1] data paths, mounts [[/usr/share/elasticsearch/data (/dev/sda2)]], net usable_space [127.9gb], net total_space [261.4gb], spins? [possibly], types [ext4]
myapp-elasticsearch    | [2016-08-20 19:31:05,322][INFO ][env                      ] [Shadow King] heap size [990.7mb], compressed ordinary object pointers [true]
myapp-elasticsearch    | [2016-08-20 19:31:07,438][INFO ][node                     ] [Shadow King] initialized
myapp-elasticsearch    | [2016-08-20 19:31:07,438][INFO ][node                     ] [Shadow King] starting ...
myapp-elasticsearch    | [2016-08-20 19:31:07,592][INFO ][transport                ] [Shadow King] publish_address {172.18.0.2:9300}, bound_addresses {[::]:9300}
myapp-elasticsearch    | [2016-08-20 19:31:07,597][INFO ][discovery                ] [Shadow King] elasticsearch/5vBucYh5SQiUvxpHw7WckA
myapp-elasticsearch    | [2016-08-20 19:31:10,672][INFO ][cluster.service          ] [Shadow King] new_master {Shadow King}{5vBucYh5SQiUvxpHw7WckA}{172.18.0.2}{172.18.0.2:9300}, reason: zen-disco-join(elected_as_master, [0] joins received)
myapp-elasticsearch    | [2016-08-20 19:31:10,693][INFO ][http                     ] [Shadow King] publish_address {172.18.0.2:9200}, bound_addresses {[::]:9200}
myapp-elasticsearch    | [2016-08-20 19:31:10,695][INFO ][node                     ] [Shadow King] started
myapp-elasticsearch    | [2016-08-20 19:31:11,304][INFO ][gateway                  ] [Shadow King] recovered [4] indices into cluster_state
myapp-elasticsearch    | [2016-08-20 19:31:16,666][INFO ][cluster.routing.allocation] [Shadow King] Cluster health status changed from [RED] to [YELLOW] (reason: [shards started [[roleauthority][2], [roleauthority][0], [roleauthority][3], [roleauthority][1]] ...]).
myapp-app_1            | 19:31:17.566 [main] DEBUG org.springframework.beans.factory.config.YamlPropertiesFactoryBean - Loading from YAML: class path resource [config/application.yml]
myapp-app_1            | 19:31:17.688 [main] DEBUG org.springframework.beans.factory.config.YamlPropertiesFactoryBean - Merging document (no matchers set): {management={context-path=/management, health={mail={enabled=false}}}, spring={application={name=myapp}, profiles={active=prod}, jackson={serialization.write_dates_as_timestamps=false}, jpa={open-in-view=false, hibernate={ddl-auto=none, naming={strategy=org.springframework.boot.orm.jpa.hibernate.SpringNamingStrategy}}}, messages={basename=i18n/messages}, mvc={favicon={enabled=false}}, thymeleaf={mode=XHTML}}, security={basic={enabled=false}}, jhipster={async={corePoolSize=2, maxPoolSize=50, queueCapacity=10000}, mail={from=myapp@localhost}, swagger={title=myapp API, description=myapp API documentation, version=0.0.1, termsOfServiceUrl=null, contactName=null, contactUrl=null, contactEmail=null, license=null, licenseUrl=null}, ribbon={displayOnActiveProfiles=dev}}}
myapp-app_1            | 19:31:17.689 [main] DEBUG org.springframework.beans.factory.config.YamlPropertiesFactoryBean - Loaded 1 document from YAML resource: class path resource [config/application.yml]
myapp-app_1            | 
myapp-app_1            |         ██  ██    ██  ████████  ███████    ██████  ████████  ████████  ███████
myapp-app_1            |         ██  ██    ██     ██     ██    ██  ██          ██     ██        ██    ██
myapp-app_1            |         ██  ████████     ██     ███████    █████      ██     ██████    ███████
myapp-app_1            |   ██    ██  ██    ██     ██     ██             ██     ██     ██        ██   ██
myapp-app_1            |    ██████   ██    ██  ████████  ██        ██████      ██     ████████  ██    ██
myapp-app_1            | 
myapp-app_1            | :: JHipster 🤓  :: Running Spring Boot 1.4.0.RELEASE ::
myapp-app_1            | :: http://jhipster.github.io ::
myapp-app_1            | 
myapp-app_1            | 2016-08-20 19:31:21.404  INFO 8 --- [           main] i.k.myapp.myappApp           : Starting myappApp on a2b2c4c5f2ec with PID 8 (/app.war started by root in /)
myapp-app_1            | 2016-08-20 19:31:21.405  INFO 8 --- [           main] i.k.myapp.myappApp           : The following profiles are active: prod,swagger
myapp-app_1            | 2016-08-20 19:31:29.469  WARN 8 --- [           main] o.s.c.a.ConfigurationClassPostProcessor  : Cannot enhance @Configuration bean definition 'org.springframework.security.oauth2.config.annotation.web.configuration.AuthorizationServerEndpointsConfiguration$TokenKeyEndpointRegistrar' since its singleton instance has been created too early. The typical cause is a non-static @Bean method with a BeanDefinitionRegistryPostProcessor return type: Consider declaring such methods as 'static'.
myapp-app_1            | 2016-08-20 19:31:33.058  INFO 8 --- [           main] c.h.instance.DefaultAddressPicker        : [LOCAL] [dev] [3.6.4] Prefer IPv4 stack is true.
myapp-app_1            | 2016-08-20 19:31:33.082  INFO 8 --- [           main] c.h.instance.DefaultAddressPicker        : [LOCAL] [dev] [3.6.4] Picked Address[172.18.0.4]:5701, using socket ServerSocket[addr=/0:0:0:0:0:0:0:0,localport=5701], bind any local is true
myapp-app_1            | 2016-08-20 19:31:33.424  INFO 8 --- [           main] com.hazelcast.system                     : [172.18.0.4]:5701 [dev] [3.6.4] Hazelcast 3.6.4 (20160701 - 5b94d9f) starting at Address[172.18.0.4]:5701
myapp-app_1            | 2016-08-20 19:31:33.425  INFO 8 --- [           main] com.hazelcast.system                     : [172.18.0.4]:5701 [dev] [3.6.4] Copyright (c) 2008-2016, Hazelcast, Inc. All Rights Reserved.
myapp-app_1            | 2016-08-20 19:31:33.425  INFO 8 --- [           main] com.hazelcast.system                     : [172.18.0.4]:5701 [dev] [3.6.4] Configured Hazelcast Serialization version : 1
myapp-app_1            | 2016-08-20 19:31:33.822  INFO 8 --- [           main] com.hazelcast.spi.OperationService       : [172.18.0.4]:5701 [dev] [3.6.4] Backpressure is disabled
myapp-app_1            | 2016-08-20 19:31:33.902  INFO 8 --- [           main] c.h.s.i.o.c.ClassicOperationExecutor     : [172.18.0.4]:5701 [dev] [3.6.4] Starting with 2 generic operation threads and 4 partition operation threads.
myapp-app_1            | 2016-08-20 19:31:35.187  INFO 8 --- [           main] com.hazelcast.instance.Node              : [172.18.0.4]:5701 [dev] [3.6.4] Creating MulticastJoiner
myapp-app_1            | 2016-08-20 19:31:35.194  INFO 8 --- [           main] com.hazelcast.core.LifecycleService      : [172.18.0.4]:5701 [dev] [3.6.4] Address[172.18.0.4]:5701 is STARTING
myapp-app_1            | 2016-08-20 19:31:35.361  INFO 8 --- [           main] c.h.n.t.n.NonBlockingIOThreadingModel    : [172.18.0.4]:5701 [dev] [3.6.4] TcpIpConnectionManager configured with Non Blocking IO-threading model: 3 input threads and 3 output threads
myapp-app_1            | 2016-08-20 19:31:37.931  INFO 8 --- [           main] c.h.cluster.impl.MulticastJoiner         : [172.18.0.4]:5701 [dev] [3.6.4] 
myapp-app_1            | 
myapp-app_1            | 
myapp-app_1            | Members [1] {
myapp-app_1            |  Member [172.18.0.4]:5701 this
myapp-app_1            | }
myapp-app_1            | 
myapp-app_1            | 2016-08-20 19:31:38.037  INFO 8 --- [           main] com.hazelcast.core.LifecycleService      : [172.18.0.4]:5701 [dev] [3.6.4] Address[172.18.0.4]:5701 is STARTED
myapp-app_1            | 2016-08-20 19:31:39.265  WARN 8 --- [ost-startStop-1] org.apache.catalina.webresources.Cache   : Unable to add the resource at [/WEB-INF/lib/jackson-mapper-asl-1.9.13.jar] to the cache because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
myapp-app_1            | 2016-08-20 19:31:44.158  WARN 8 --- [ost-startStop-1] org.apache.catalina.webresources.Cache   : Unable to add the resource at [/WEB-INF/lib/jackson-mapper-asl-1.9.13.jar] to the cache because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
myapp-app_1            | 2016-08-20 19:31:45.303  INFO 8 --- [ost-startStop-1] i.k.myapp.config.WebConfigurer     : Web application configuration, using profiles: [prod, swagger]
myapp-app_1            | 2016-08-20 19:31:45.321  INFO 8 --- [ost-startStop-1] i.k.myapp.config.WebConfigurer     : Web application fully configured
myapp-app_1            | 2016-08-20 19:31:45.435  INFO 8 --- [ost-startStop-1] c.hazelcast.web.ClusteredSessionService  : Retrying the connection!!
myapp-app_1            | 2016-08-20 19:31:45.543  INFO 8 --- [ost-startStop-1] com.hazelcast.config.XmlConfigLocator    : Loading 'hazelcast-default.xml' from classpath.
myapp-app_1            | 2016-08-20 19:31:45.977  INFO 8 --- [ost-startStop-1] c.hazelcast.web.HazelcastInstanceLoader  : getOrCreateHazelcastInstance for session replication, using name 'myapp'
myapp-app_1            | 2016-08-20 19:31:46.000  INFO 8 --- [ost-startStop-1] c.hazelcast.web.ClusteredSessionService  : Successfully Connected!
myapp-app_1            | 2016-08-20 19:32:25.959  INFO 8 --- [           main] i.k.k.c.h.HazelcastCacheRegionFactory    : Starting up HazelcastCacheRegionFactory
myapp-app_1            | 2016-08-20 19:32:27.687  INFO 8 --- [           main] i.k.myapp.myappApp           : Running with Spring profile(s) : [prod, swagger]
myapp-app_1            | 2016-08-20 19:32:33.017  WARN 8 --- [           main] ationConfigEmbeddedWebApplicationContext : Exception encountered during context initialization - cancelling refresh attempt: org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'userService': Unsatisfied dependency expressed through field 'jdbcTokenStore': No qualifying bean of type [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore] found for dependency [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore]: expected at least 1 bean which qualifies as autowire candidate for this dependency. Dependency annotations: {@javax.inject.Inject()}; nested exception is org.springframework.beans.factory.NoSuchBeanDefinitionException: No qualifying bean of type [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore] found for dependency [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore]: expected at least 1 bean which qualifies as autowire candidate for this dependency. Dependency annotations: {@javax.inject.Inject()}
myapp-app_1            | 2016-08-20 19:32:33.108  INFO 8 --- [           main] i.k.k.c.h.HazelcastCacheRegionFactory    : Shutting down HazelcastCacheRegionFactory
myapp-app_1            | 2016-08-20 19:32:33.111  INFO 8 --- [           main] com.hazelcast.core.LifecycleService      : [172.18.0.4]:5701 [dev] [3.6.4] Address[172.18.0.4]:5701 is SHUTTING_DOWN
myapp-app_1            | 2016-08-20 19:32:33.115  INFO 8 --- [           main] com.hazelcast.instance.Node              : [172.18.0.4]:5701 [dev] [3.6.4] Shutting down multicast service...
myapp-app_1            | 2016-08-20 19:32:33.128  INFO 8 --- [           main] com.hazelcast.instance.Node              : [172.18.0.4]:5701 [dev] [3.6.4] Shutting down connection manager...
myapp-app_1            | 2016-08-20 19:32:33.135  INFO 8 --- [           main] com.hazelcast.instance.Node              : [172.18.0.4]:5701 [dev] [3.6.4] Shutting down node engine...
myapp-app_1            | 2016-08-20 19:32:33.168  INFO 8 --- [           main] com.hazelcast.instance.NodeExtension     : [172.18.0.4]:5701 [dev] [3.6.4] Destroying node NodeExtension.
myapp-app_1            | 2016-08-20 19:32:33.169  INFO 8 --- [           main] com.hazelcast.instance.Node              : [172.18.0.4]:5701 [dev] [3.6.4] Hazelcast Shutdown is completed in 54 ms.
myapp-app_1            | 2016-08-20 19:32:33.170  INFO 8 --- [           main] com.hazelcast.core.LifecycleService      : [172.18.0.4]:5701 [dev] [3.6.4] Address[172.18.0.4]:5701 is SHUTDOWN
myapp-app_1            | 2016-08-20 19:32:33.171  INFO 8 --- [           main] i.k.k.config.CacheConfiguration          : Closing Cache Manager
myapp-app_1            | 2016-08-20 19:32:33.275  WARN 8 --- [           main] o.s.boot.SpringApplication               : Error handling failed (Error creating bean with name 'delegatingApplicationListener' defined in class path resource [org/springframework/security/config/annotation/web/configuration/WebSecurityConfiguration.class]: BeanPostProcessor before instantiation of bean failed; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'metaDataSourceAdvisor': Cannot resolve reference to bean 'methodSecurityMetadataSource' while setting constructor argument; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'org.springframework.security.config.annotation.method.configuration.GlobalMethodSecurityConfiguration': Injection of autowired dependencies failed; nested exception is java.lang.RuntimeException: Could not postProcess org.springframework.security.access.expression.method.DefaultMethodSecurityExpressionHandler@25d0cb3a of type class org.springframework.security.access.expression.method.DefaultMethodSecurityExpressionHandler)
myapp-app_1            | 2016-08-20 19:32:33.294 ERROR 8 --- [           main] o.s.boot.SpringApplication               : Application startup failed
myapp-app_1            | 
myapp-app_1            | org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'userService': Unsatisfied dependency expressed through field 'jdbcTokenStore': No qualifying bean of type [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore] found for dependency [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore]: expected at least 1 bean which qualifies as autowire candidate for this dependency. Dependency annotations: {@javax.inject.Inject()}; nested exception is org.springframework.beans.factory.NoSuchBeanDefinitionException: No qualifying bean of type [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore] found for dependency [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore]: expected at least 1 bean which qualifies as autowire candidate for this dependency. Dependency annotations: {@javax.inject.Inject()}
myapp-app_1            |  at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:569)
myapp-app_1            |  at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:88)
myapp-app_1            |  at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessPropertyValues(AutowiredAnnotationBeanPostProcessor.java:349)
myapp-app_1            |  at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1214)
myapp-app_1            |  at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:543)
myapp-app_1            |  at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:482)
myapp-app_1            |  at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:306)
myapp-app_1            |  at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
myapp-app_1            |  at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:302)
myapp-app_1            |  at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:197)
myapp-app_1            |  at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:776)
myapp-app_1            |  at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:861)
myapp-app_1            |  at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:541)
myapp-app_1            |  at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.refresh(EmbeddedWebApplicationContext.java:122)
myapp-app_1            |  at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:759)
myapp-app_1            |  at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:369)
myapp-app_1            |  at org.springframework.boot.SpringApplication.run(SpringApplication.java:313)
myapp-app_1            |  at id.myapp.myapp.myappApp.main(myappApp.java:65)
myapp-app_1            |  at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
myapp-app_1            |  at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
myapp-app_1            |  at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
myapp-app_1            |  at java.lang.reflect.Method.invoke(Method.java:498)
myapp-app_1            |  at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:48)
myapp-app_1            |  at org.springframework.boot.loader.Launcher.launch(Launcher.java:87)
myapp-app_1            |  at org.springframework.boot.loader.Launcher.launch(Launcher.java:50)
myapp-app_1            |  at org.springframework.boot.loader.WarLauncher.main(WarLauncher.java:59)
myapp-app_1            | Caused by: org.springframework.beans.factory.NoSuchBeanDefinitionException: No qualifying bean of type [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore] found for dependency [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore]: expected at least 1 bean which qualifies as autowire candidate for this dependency. Dependency annotations: {@javax.inject.Inject()}
myapp-app_1            |  at org.springframework.beans.factory.support.DefaultListableBeanFactory.raiseNoMatchingBeanFound(DefaultListableBeanFactory.java:1406)
myapp-app_1            |  at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:1057)
myapp-app_1            |  at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:1019)
myapp-app_1            |  at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:566)
myapp-app_1            |  ... 25 common frames omitted
myapp-app_1            | 
docker_myapp-app_1 exited with code 1

@pascalgrimaud
Copy link
Member

Generate entity (uml)

Can you provide us your entities plz ?

@jdubois
Copy link
Member

jdubois commented Aug 20, 2016

I don't believe Spring would behave differently in Docker than with 'gradle bootRun'.
Are you sure you built your image correctely, and that you run the right image?

@tsaqova
Copy link
Author

tsaqova commented Aug 20, 2016

@jdubois : I generate the project inside jhipster/jhipster image, then generate the entities (uml attached below), i run gradle bootRun on my localhost and connect to local mysql, everything fine. Just get the error when running the app using all generated docker image (app, mysql, elasticsearch, sonar).

@pascalgrimaud here are my schema, i change mysql version to 5.7.13 as workaround because 5.7.14 can't have 2 timestamp column (from zonedatetime) in one table by default :

deleted

@pascalgrimaud
Copy link
Member

Unable to reproduce with your entities.

All my steps:

  1. use the same .yo-rc.json, and your entities in hello.jh
  2. yo jhipster:import-jdl hello.jh
  3. I package and build docker image with: ./gradlew bootRepackage -Pprod buildDocker
  4. edit src/main/docker/mysql.yml -> mysql:5.7.13
  5. I launch everything with Docker: docker-compose -f src/main/docker/app.yml up
  6. I play with your application, I added a region, everything is OK
myapp-app_1            | 2016-08-20 21:00:02.241  INFO 8 --- [           main] com.myapp.myapp.MyappApp                 : Started MyappApp in 44.143 seconds (JVM running for 45.291)
myapp-app_1            | 2016-08-20 21:00:02.242  INFO 8 --- [           main] com.myapp.myapp.MyappApp                 : 
myapp-app_1            | ----------------------------------------------------------
myapp-app_1            |    Application 'myapp' is running! Access URLs:
myapp-app_1            |    Local:      http://127.0.0.1:8080
myapp-app_1            |    External:   http://172.18.0.2:8080
myapp-app_1            | ----------------------------------------------------------
myapp-app_1            | 2016-08-20 21:00:13.661  INFO 8 --- [nio-8080-exec-1] c.h.partition.InternalPartitionService   : [172.18.0.2]:5701 [dev] [3.6.4] Initializing cluster partition table arrangement...
myapp-app_1            | 2016-08-20 21:00:33.219  WARN 8 --- [nio-8080-exec-5] o.h.h.internal.ast.QueryTranslatorImpl   : HHH000104: firstResult/maxResults specified with collection fetch; applying in memory!
myapp-elasticsearch    | [2016-08-20 21:00:55,442][INFO ][cluster.metadata         ] [Turner Century] [region] update_mapping [region]

@gmarziou
Copy link
Contributor

gmarziou commented Aug 20, 2016

@tsaqova , in your log, I see this error below, maybe it's worth investigating.
@pascalgrimaud do you see same thing in your log?

myapp-mysql            | 2016-08-20T19:31:05.218125Z 0 [ERROR] Incorrect definition of table performance_schema.replication_connection_status: expected column 'RECEIVED_TRANSACTION_SET' at position 7 to have type text, found type longtext.
myapp-mysql            | 2016-08-20T19:31:05.218527Z 0 [ERROR] Incorrect definition of table performance_schema.replication_group_member_stats: expected column 'COUNT_TRANSACTIONS_VALIDATING' at position 6, found 'COUNT_TRANSACTIONS_ROWS_VALIDATING'.

@pascalgrimaud
Copy link
Member

@gmarziou : no, these 2 lines are missing. Here my logs for myapp-mysql:

myapp-mysql            | 2016-08-20T21:21:20.681797Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
myapp-mysql            | 2016-08-20T21:21:20.684706Z 0 [Note] InnoDB: Buffer pool(s) load completed at 160820 21:21:20
myapp-mysql            | 2016-08-20T21:21:20.686793Z 0 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-20T21:21:20.686821Z 0 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-20T21:21:20.689833Z 0 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-20T21:21:20.704291Z 0 [Note] Event Scheduler: Loaded 0 events
myapp-mysql            | 2016-08-20T21:21:20.706252Z 0 [Note] mysqld: ready for connections.
myapp-mysql            | Version: '5.7.13'  socket: '/var/run/mysqld/mysqld.sock'  port: 3306  MySQL Community Server (GPL)

@pascalgrimaud
Copy link
Member

@tsaqova : I saw in your logs

Starting myapp-elasticsearch
Recreating docker_myapp-app_1
Starting myapp-mysql

Maybe it's connected to old containers. Can you delete these containers and let docker-compose recreate them? You should have this instead:

Creating docker_myapp-app_1
Creating myapp-elasticsearch
Creating myapp-mysql

@tsaqova
Copy link
Author

tsaqova commented Aug 21, 2016

I've delete the container.
i run again and it still reproduce the error.

All my steps:

  1. use the same .yo-rc.json, and entities in jhipster-jdl.jh (inside docker jhipster/jhipster, my Docker version 1.11.1, build 5604cbe)
  2. yo jhipster:import-jdl jhipster-jdl.jh (inside docker jhipster/jhipster)
  3. I package and build docker image with: ./gradlew bootRepackage -Pprod buildDocker (in my local, not inside docker)
  4. edit src/main/docker/mysql.yml -> mysql:5.7.13
  5. I launch everything with Docker: docker-compose -f src/main/docker/app.yml up (in my local, not inside docker)
  6. All migration db successfully created.
  7. ERROR.

But if i just run mysql container from mysql.yml, and point my datasource to it and gradle bootRun (in my local) dev/prod profile, it's work!

Here are my full stack trace :

Creating docker_myapp-app_1
Creating myapp-mysql
Creating myapp-elasticsearch
Attaching to myapp-mysql, docker_myapp-app_1, myapp-elasticsearch
myapp-mysql            | Initializing database
myapp-app_1            | The application will start in 10s...
myapp-mysql            | 2016-08-21T02:28:10.282140Z 0 [Warning] InnoDB: New log files created, LSN=45790
myapp-mysql            | 2016-08-21T02:28:10.838187Z 0 [Warning] InnoDB: Creating foreign key constraint system tables.
myapp-mysql            | 2016-08-21T02:28:11.059671Z 0 [Warning] No existing UUID has been found, so we assume that this is the first time that this server has been started. Generating a new UUID: e77ec9e5-6746-11e6-bbcb-0242ac120002.
myapp-mysql            | 2016-08-21T02:28:11.116934Z 0 [Warning] Gtid table is not ready to be used. Table 'mysql.gtid_executed' cannot be opened.
myapp-mysql            | 2016-08-21T02:28:11.117593Z 1 [Warning] root@localhost is created with an empty password ! Please consider switching off the --initialize-insecure option.
myapp-elasticsearch    | [2016-08-21 02:28:40,102][INFO ][node                     ] [Rainbow] version[2.3.5], pid[1], build[90f439f/2016-07-27T10:36:52Z]
myapp-elasticsearch    | [2016-08-21 02:28:40,126][INFO ][node                     ] [Rainbow] initializing ...
myapp-mysql            | 2016-08-21T02:28:40.389889Z 1 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:28:40.389920Z 1 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:28:40.389932Z 1 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:28:40.389942Z 1 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:28:40.389984Z 1 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-elasticsearch    | [2016-08-21 02:28:50,266][INFO ][plugins                  ] [Rainbow] modules [reindex, lang-expression, lang-groovy], plugins [], sites []
myapp-elasticsearch    | [2016-08-21 02:28:51,511][INFO ][env                      ] [Rainbow] using [1] data paths, mounts [[/usr/share/elasticsearch/data (/dev/sda2)]], net usable_space [131.8gb], net total_space [261.4gb], spins? [possibly], types [ext4]
myapp-elasticsearch    | [2016-08-21 02:28:51,511][INFO ][env                      ] [Rainbow] heap size [990.7mb], compressed ordinary object pointers [true]
myapp-mysql            | Database initialized
myapp-mysql            | 2016-08-21T02:28:57.957053Z 0 [Note] mysqld (mysqld 5.7.13) starting as process 51 ...
myapp-mysql            | 2016-08-21T02:28:57.960129Z 0 [Note] InnoDB: PUNCH HOLE support available
myapp-mysql            | 2016-08-21T02:28:57.960151Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
myapp-mysql            | 2016-08-21T02:28:57.960155Z 0 [Note] InnoDB: Uses event mutexes
myapp-mysql            | 2016-08-21T02:28:57.960159Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
myapp-mysql            | 2016-08-21T02:28:57.960164Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.8
myapp-mysql            | 2016-08-21T02:28:57.960168Z 0 [Note] InnoDB: Using Linux native AIO
myapp-mysql            | 2016-08-21T02:28:57.960367Z 0 [Note] InnoDB: Number of pools: 1
myapp-mysql            | 2016-08-21T02:28:57.960455Z 0 [Note] InnoDB: Using CPU crc32 instructions
myapp-mysql            | 2016-08-21T02:28:57.961699Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
myapp-mysql            | 2016-08-21T02:28:57.968113Z 0 [Note] InnoDB: Completed initialization of buffer pool
myapp-mysql            | 2016-08-21T02:28:57.969466Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
myapp-mysql            | 2016-08-21T02:28:57.980895Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
myapp-mysql            | 2016-08-21T02:28:58.153464Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
myapp-mysql            | 2016-08-21T02:28:58.153542Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
myapp-mysql            | 2016-08-21T02:28:58.581907Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
myapp-mysql            | 2016-08-21T02:28:58.583258Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
myapp-mysql            | 2016-08-21T02:28:58.583280Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
myapp-mysql            | 2016-08-21T02:28:58.583784Z 0 [Note] InnoDB: Waiting for purge to start
myapp-mysql            | MySQL init process in progress...
myapp-mysql            | 2016-08-21T02:28:58.633938Z 0 [Note] InnoDB: 5.7.13 started; log sequence number 2528924
myapp-mysql            | 2016-08-21T02:28:58.634088Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
myapp-mysql            | 2016-08-21T02:28:58.634223Z 0 [Note] Plugin 'FEDERATED' is disabled.
myapp-mysql            | 2016-08-21T02:28:58.635415Z 0 [Note] InnoDB: Buffer pool(s) load completed at 160821  2:28:58
myapp-mysql            | 2016-08-21T02:28:58.775589Z 0 [Warning] 'user' entry 'root@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:28:58.775651Z 0 [Warning] 'user' entry 'mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:28:58.775682Z 0 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:28:58.775704Z 0 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:28:58.777107Z 0 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:28:58.781542Z 0 [Note] Event Scheduler: Loaded 0 events
myapp-mysql            | 2016-08-21T02:28:58.781685Z 0 [Note] mysqld: ready for connections.
myapp-mysql            | Version: '5.7.13'  socket: '/var/run/mysqld/mysqld.sock'  port: 0  MySQL Community Server (GPL)
myapp-app_1            | 02:29:02.319 [main] DEBUG org.springframework.beans.factory.config.YamlPropertiesFactoryBean - Loading from YAML: class path resource [config/application.yml]
myapp-app_1            | 02:29:02.893 [main] DEBUG org.springframework.beans.factory.config.YamlPropertiesFactoryBean - Merging document (no matchers set): {management={context-path=/management, health={mail={enabled=false}}}, spring={application={name=myapp}, profiles={active=prod}, jackson={serialization.write_dates_as_timestamps=false}, jpa={open-in-view=false, hibernate={ddl-auto=none, naming={strategy=org.springframework.boot.orm.jpa.hibernate.SpringNamingStrategy}}}, messages={basename=i18n/messages}, mvc={favicon={enabled=false}}, thymeleaf={mode=XHTML}}, security={basic={enabled=false}}, jhipster={async={corePoolSize=2, maxPoolSize=50, queueCapacity=10000}, mail={from=myapp@localhost}, swagger={title=myapp API, description=myapp API documentation, version=0.0.1, termsOfServiceUrl=null, contactName=null, contactUrl=null, contactEmail=null, license=null, licenseUrl=null}, ribbon={displayOnActiveProfiles=dev}}}
myapp-app_1            | 02:29:02.893 [main] DEBUG org.springframework.beans.factory.config.YamlPropertiesFactoryBean - Loaded 1 document from YAML resource: class path resource [config/application.yml]
myapp-mysql            | Warning: Unable to load '/usr/share/zoneinfo/Factory' as time zone. Skipping it.
myapp-mysql            | Warning: Unable to load '/usr/share/zoneinfo/iso3166.tab' as time zone. Skipping it.
myapp-mysql            | Warning: Unable to load '/usr/share/zoneinfo/leap-seconds.list' as time zone. Skipping it.
myapp-mysql            | Warning: Unable to load '/usr/share/zoneinfo/posix/Factory' as time zone. Skipping it.
myapp-elasticsearch    | [2016-08-21 02:29:16,218][INFO ][node                     ] [Rainbow] initialized
myapp-elasticsearch    | [2016-08-21 02:29:16,219][INFO ][node                     ] [Rainbow] starting ...
myapp-elasticsearch    | [2016-08-21 02:29:17,822][INFO ][transport                ] [Rainbow] publish_address {172.18.0.4:9300}, bound_addresses {[::]:9300}
myapp-elasticsearch    | [2016-08-21 02:29:17,875][INFO ][discovery                ] [Rainbow] elasticsearch/lA0z617iRBCAKaVYwXg7pQ
myapp-elasticsearch    | [2016-08-21 02:29:20,909][INFO ][cluster.service          ] [Rainbow] new_master {Rainbow}{lA0z617iRBCAKaVYwXg7pQ}{172.18.0.4}{172.18.0.4:9300}, reason: zen-disco-join(elected_as_master, [0] joins received)
myapp-elasticsearch    | [2016-08-21 02:29:20,918][INFO ][http                     ] [Rainbow] publish_address {172.18.0.4:9200}, bound_addresses {[::]:9200}
myapp-elasticsearch    | [2016-08-21 02:29:20,918][INFO ][node                     ] [Rainbow] started
myapp-mysql            | Warning: Unable to load '/usr/share/zoneinfo/right/Factory' as time zone. Skipping it.
myapp-elasticsearch    | [2016-08-21 02:29:21,804][INFO ][gateway                  ] [Rainbow] recovered [0] indices into cluster_state
myapp-app_1            | 
myapp-app_1            |         ██  ██    ██  ████████  ███████    ██████  ████████  ████████  ███████
myapp-app_1            |         ██  ██    ██     ██     ██    ██  ██          ██     ██        ██    ██
myapp-app_1            |         ██  ████████     ██     ███████    █████      ██     ██████    ███████
myapp-app_1            |   ██    ██  ██    ██     ██     ██             ██     ██     ██        ██   ██
myapp-app_1            |    ██████   ██    ██  ████████  ██        ██████      ██     ████████  ██    ██
myapp-app_1            | 
myapp-app_1            | :: JHipster 🤓  :: Running Spring Boot 1.4.0.RELEASE ::
myapp-app_1            | :: http://jhipster.github.io ::
myapp-app_1            | 
myapp-mysql            | Warning: Unable to load '/usr/share/zoneinfo/zone.tab' as time zone. Skipping it.
myapp-mysql            | 2016-08-21T02:29:26.490035Z 4 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:29:26.490051Z 4 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:29:26.490073Z 4 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 
myapp-app_1            | 2016-08-21 02:29:26.959  INFO 8 --- [           main] com.myapp.myapp.MyappApp                 : Starting MyappApp on 1adad63dd678 with PID 8 (/app.war started by root in /)
myapp-app_1            | 2016-08-21 02:29:26.960  INFO 8 --- [           main] com.myapp.myapp.MyappApp                 : The following profiles are active: prod,swagger
myapp-mysql            | /usr/local/bin/docker-entrypoint.sh: ignoring /docker-entrypoint-initdb.d/*
myapp-mysql            | 
myapp-mysql            | 2016-08-21T02:29:27.403706Z 0 [Note] Giving 0 client threads a chance to die gracefully
myapp-mysql            | 2016-08-21T02:29:27.403727Z 0 [Note] Shutting down slave threads
myapp-mysql            | 2016-08-21T02:29:27.403730Z 0 [Note] Forcefully disconnecting 0 remaining clients
myapp-mysql            | 2016-08-21T02:29:27.403789Z 0 [Note] Event Scheduler: Purging the queue. 0 events
myapp-mysql            | 2016-08-21T02:29:27.404370Z 0 [Note] Binlog end
myapp-mysql            | 2016-08-21T02:29:27.404896Z 0 [Note] Shutting down plugin 'ngram'
myapp-mysql            | 2016-08-21T02:29:27.404914Z 0 [Note] Shutting down plugin 'BLACKHOLE'
myapp-mysql            | 2016-08-21T02:29:27.404919Z 0 [Note] Shutting down plugin 'partition'
myapp-mysql            | 2016-08-21T02:29:27.404921Z 0 [Note] Shutting down plugin 'ARCHIVE'
myapp-mysql            | 2016-08-21T02:29:27.404924Z 0 [Note] Shutting down plugin 'MEMORY'
myapp-mysql            | 2016-08-21T02:29:27.404927Z 0 [Note] Shutting down plugin 'INNODB_SYS_VIRTUAL'
myapp-mysql            | 2016-08-21T02:29:27.404929Z 0 [Note] Shutting down plugin 'INNODB_SYS_DATAFILES'
myapp-mysql            | 2016-08-21T02:29:27.404932Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLESPACES'
myapp-mysql            | 2016-08-21T02:29:27.404935Z 0 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN_COLS'
myapp-mysql            | 2016-08-21T02:29:27.404942Z 0 [Note] Shutting down plugin 'INNODB_SYS_FOREIGN'
myapp-mysql            | 2016-08-21T02:29:27.404944Z 0 [Note] Shutting down plugin 'INNODB_SYS_FIELDS'
myapp-mysql            | 2016-08-21T02:29:27.404947Z 0 [Note] Shutting down plugin 'INNODB_SYS_COLUMNS'
myapp-mysql            | 2016-08-21T02:29:27.404949Z 0 [Note] Shutting down plugin 'INNODB_SYS_INDEXES'
myapp-mysql            | 2016-08-21T02:29:27.404952Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLESTATS'
myapp-mysql            | 2016-08-21T02:29:27.404954Z 0 [Note] Shutting down plugin 'INNODB_SYS_TABLES'
myapp-mysql            | 2016-08-21T02:29:27.404957Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_TABLE'
myapp-mysql            | 2016-08-21T02:29:27.404959Z 0 [Note] Shutting down plugin 'INNODB_FT_INDEX_CACHE'
myapp-mysql            | 2016-08-21T02:29:27.404962Z 0 [Note] Shutting down plugin 'INNODB_FT_CONFIG'
myapp-mysql            | 2016-08-21T02:29:27.404964Z 0 [Note] Shutting down plugin 'INNODB_FT_BEING_DELETED'
myapp-mysql            | 2016-08-21T02:29:27.404966Z 0 [Note] Shutting down plugin 'INNODB_FT_DELETED'
myapp-mysql            | 2016-08-21T02:29:27.404969Z 0 [Note] Shutting down plugin 'INNODB_FT_DEFAULT_STOPWORD'
myapp-mysql            | 2016-08-21T02:29:27.404971Z 0 [Note] Shutting down plugin 'INNODB_METRICS'
myapp-mysql            | 2016-08-21T02:29:27.404974Z 0 [Note] Shutting down plugin 'INNODB_TEMP_TABLE_INFO'
myapp-mysql            | 2016-08-21T02:29:27.404976Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_POOL_STATS'
myapp-mysql            | 2016-08-21T02:29:27.404979Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE_LRU'
myapp-mysql            | 2016-08-21T02:29:27.404982Z 0 [Note] Shutting down plugin 'INNODB_BUFFER_PAGE'
myapp-mysql            | 2016-08-21T02:29:27.404984Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX_RESET'
myapp-mysql            | 2016-08-21T02:29:27.404987Z 0 [Note] Shutting down plugin 'INNODB_CMP_PER_INDEX'
myapp-mysql            | 2016-08-21T02:29:27.404989Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM_RESET'
myapp-mysql            | 2016-08-21T02:29:27.404992Z 0 [Note] Shutting down plugin 'INNODB_CMPMEM'
myapp-mysql            | 2016-08-21T02:29:27.404994Z 0 [Note] Shutting down plugin 'INNODB_CMP_RESET'
myapp-mysql            | 2016-08-21T02:29:27.404996Z 0 [Note] Shutting down plugin 'INNODB_CMP'
myapp-mysql            | 2016-08-21T02:29:27.404999Z 0 [Note] Shutting down plugin 'INNODB_LOCK_WAITS'
myapp-mysql            | 2016-08-21T02:29:27.405001Z 0 [Note] Shutting down plugin 'INNODB_LOCKS'
myapp-mysql            | 2016-08-21T02:29:27.405004Z 0 [Note] Shutting down plugin 'INNODB_TRX'
myapp-mysql            | 2016-08-21T02:29:27.405006Z 0 [Note] Shutting down plugin 'InnoDB'
myapp-mysql            | 2016-08-21T02:29:27.405079Z 0 [Note] InnoDB: FTS optimize thread exiting.
myapp-mysql            | 2016-08-21T02:29:27.405204Z 0 [Note] InnoDB: Starting shutdown...
myapp-mysql            | 2016-08-21T02:29:27.505328Z 0 [Note] InnoDB: Dumping buffer pool(s) to /var/lib/mysql/ib_buffer_pool
myapp-mysql            | 2016-08-21T02:29:27.505543Z 0 [Note] InnoDB: Buffer pool(s) dump completed at 160821  2:29:27
myapp-mysql            | 2016-08-21T02:29:34.590743Z 0 [Note] InnoDB: Shutdown completed; log sequence number 12134537
myapp-mysql            | 2016-08-21T02:29:34.593550Z 0 [Note] InnoDB: Removed temporary tablespace data file: "ibtmp1"
myapp-mysql            | 2016-08-21T02:29:34.593590Z 0 [Note] Shutting down plugin 'MyISAM'
myapp-mysql            | 2016-08-21T02:29:34.593618Z 0 [Note] Shutting down plugin 'MRG_MYISAM'
myapp-mysql            | 2016-08-21T02:29:34.593630Z 0 [Note] Shutting down plugin 'CSV'
myapp-mysql            | 2016-08-21T02:29:34.593641Z 0 [Note] Shutting down plugin 'PERFORMANCE_SCHEMA'
myapp-mysql            | 2016-08-21T02:29:34.593672Z 0 [Note] Shutting down plugin 'sha256_password'
myapp-mysql            | 2016-08-21T02:29:34.593688Z 0 [Note] Shutting down plugin 'mysql_native_password'
myapp-mysql            | 2016-08-21T02:29:34.593847Z 0 [Note] Shutting down plugin 'binlog'
myapp-mysql            | 2016-08-21T02:29:34.594485Z 0 [Note] mysqld: Shutdown complete
myapp-mysql            | 
myapp-mysql            | 
myapp-mysql            | MySQL init process done. Ready for start up.
myapp-mysql            | 
myapp-mysql            | 2016-08-21T02:29:34.796483Z 0 [Note] mysqld (mysqld 5.7.13) starting as process 1 ...
myapp-mysql            | 2016-08-21T02:29:34.799295Z 0 [Note] InnoDB: PUNCH HOLE support available
myapp-mysql            | 2016-08-21T02:29:34.799313Z 0 [Note] InnoDB: Mutexes and rw_locks use GCC atomic builtins
myapp-mysql            | 2016-08-21T02:29:34.799316Z 0 [Note] InnoDB: Uses event mutexes
myapp-mysql            | 2016-08-21T02:29:34.799318Z 0 [Note] InnoDB: GCC builtin __atomic_thread_fence() is used for memory barrier
myapp-mysql            | 2016-08-21T02:29:34.799320Z 0 [Note] InnoDB: Compressed tables use zlib 1.2.8
myapp-mysql            | 2016-08-21T02:29:34.799324Z 0 [Note] InnoDB: Using Linux native AIO
myapp-mysql            | 2016-08-21T02:29:34.799515Z 0 [Note] InnoDB: Number of pools: 1
myapp-mysql            | 2016-08-21T02:29:34.799607Z 0 [Note] InnoDB: Using CPU crc32 instructions
myapp-mysql            | 2016-08-21T02:29:34.800768Z 0 [Note] InnoDB: Initializing buffer pool, total size = 128M, instances = 1, chunk size = 128M
myapp-mysql            | 2016-08-21T02:29:34.806560Z 0 [Note] InnoDB: Completed initialization of buffer pool
myapp-mysql            | 2016-08-21T02:29:34.807988Z 0 [Note] InnoDB: If the mysqld execution user is authorized, page cleaner thread priority can be changed. See the man page of setpriority().
myapp-mysql            | 2016-08-21T02:29:34.819534Z 0 [Note] InnoDB: Highest supported file format is Barracuda.
myapp-mysql            | 2016-08-21T02:29:35.180881Z 0 [Note] InnoDB: Creating shared tablespace for temporary tables
myapp-mysql            | 2016-08-21T02:29:35.180974Z 0 [Note] InnoDB: Setting file './ibtmp1' size to 12 MB. Physically writing the file full; Please wait ...
myapp-mysql            | 2016-08-21T02:29:35.836190Z 0 [Note] InnoDB: File './ibtmp1' size is now 12 MB.
myapp-mysql            | 2016-08-21T02:29:35.837099Z 0 [Note] InnoDB: 96 redo rollback segment(s) found. 96 redo rollback segment(s) are active.
myapp-mysql            | 2016-08-21T02:29:35.837135Z 0 [Note] InnoDB: 32 non-redo rollback segment(s) are active.
myapp-mysql            | 2016-08-21T02:29:35.837606Z 0 [Note] InnoDB: Waiting for purge to start
myapp-mysql            | 2016-08-21T02:29:35.887763Z 0 [Note] InnoDB: 5.7.13 started; log sequence number 12134537
myapp-mysql            | 2016-08-21T02:29:35.888353Z 0 [Note] InnoDB: Loading buffer pool(s) from /var/lib/mysql/ib_buffer_pool
myapp-mysql            | 2016-08-21T02:29:35.888521Z 0 [Note] Plugin 'FEDERATED' is disabled.
myapp-mysql            | 2016-08-21T02:29:35.890148Z 0 [Note] Server hostname (bind-address): '*'; port: 3306
myapp-mysql            | 2016-08-21T02:29:35.890300Z 0 [Note] IPv6 is available.
myapp-mysql            | 2016-08-21T02:29:35.891428Z 0 [Note]   - '::' resolves to '::';
myapp-mysql            | 2016-08-21T02:29:35.891482Z 0 [Note] Server socket created on IP: '::'.
myapp-mysql            | 2016-08-21T02:29:35.891641Z 0 [Note] InnoDB: Buffer pool(s) load completed at 160821  2:29:35
myapp-mysql            | 2016-08-21T02:29:36.167441Z 0 [Warning] 'db' entry 'sys mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:29:36.167461Z 0 [Warning] 'proxies_priv' entry '@ root@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:29:36.169604Z 0 [Warning] 'tables_priv' entry 'sys_config mysql.sys@localhost' ignored in --skip-name-resolve mode.
myapp-mysql            | 2016-08-21T02:29:36.174722Z 0 [Note] Event Scheduler: Loaded 0 events
myapp-mysql            | 2016-08-21T02:29:36.174833Z 0 [Note] mysqld: ready for connections.
myapp-mysql            | Version: '5.7.13'  socket: '/var/run/mysqld/mysqld.sock'  port: 3306  MySQL Community Server (GPL)
myapp-app_1            | 2016-08-21 02:29:55.152  WARN 8 --- [           main] o.s.c.a.ConfigurationClassPostProcessor  : Cannot enhance @Configuration bean definition 'org.springframework.security.oauth2.config.annotation.web.configuration.AuthorizationServerEndpointsConfiguration$TokenKeyEndpointRegistrar' since its singleton instance has been created too early. The typical cause is a non-static @Bean method with a BeanDefinitionRegistryPostProcessor return type: Consider declaring such methods as 'static'.
myapp-app_1            | 2016-08-21 02:29:58.492  INFO 8 --- [           main] c.h.instance.DefaultAddressPicker        : [LOCAL] [dev] [3.6.4] Prefer IPv4 stack is true.
myapp-app_1            | 2016-08-21 02:29:58.552  INFO 8 --- [           main] c.h.instance.DefaultAddressPicker        : [LOCAL] [dev] [3.6.4] Picked Address[172.18.0.3]:5701, using socket ServerSocket[addr=/0:0:0:0:0:0:0:0,localport=5701], bind any local is true
myapp-app_1            | 2016-08-21 02:29:58.850  INFO 8 --- [           main] com.hazelcast.system                     : [172.18.0.3]:5701 [dev] [3.6.4] Hazelcast 3.6.4 (20160701 - 5b94d9f) starting at Address[172.18.0.3]:5701
myapp-app_1            | 2016-08-21 02:29:58.850  INFO 8 --- [           main] com.hazelcast.system                     : [172.18.0.3]:5701 [dev] [3.6.4] Copyright (c) 2008-2016, Hazelcast, Inc. All Rights Reserved.
myapp-app_1            | 2016-08-21 02:29:58.851  INFO 8 --- [           main] com.hazelcast.system                     : [172.18.0.3]:5701 [dev] [3.6.4] Configured Hazelcast Serialization version : 1
myapp-app_1            | 2016-08-21 02:29:59.295  INFO 8 --- [           main] com.hazelcast.spi.OperationService       : [172.18.0.3]:5701 [dev] [3.6.4] Backpressure is disabled
myapp-app_1            | 2016-08-21 02:29:59.319  INFO 8 --- [           main] c.h.s.i.o.c.ClassicOperationExecutor     : [172.18.0.3]:5701 [dev] [3.6.4] Starting with 2 generic operation threads and 4 partition operation threads.
myapp-app_1            | 2016-08-21 02:29:59.838  INFO 8 --- [           main] com.hazelcast.instance.Node              : [172.18.0.3]:5701 [dev] [3.6.4] Creating MulticastJoiner
myapp-app_1            | 2016-08-21 02:29:59.842  INFO 8 --- [           main] com.hazelcast.core.LifecycleService      : [172.18.0.3]:5701 [dev] [3.6.4] Address[172.18.0.3]:5701 is STARTING
myapp-app_1            | 2016-08-21 02:29:59.975  INFO 8 --- [           main] c.h.n.t.n.NonBlockingIOThreadingModel    : [172.18.0.3]:5701 [dev] [3.6.4] TcpIpConnectionManager configured with Non Blocking IO-threading model: 3 input threads and 3 output threads
myapp-app_1            | 2016-08-21 02:30:02.385  INFO 8 --- [           main] c.h.cluster.impl.MulticastJoiner         : [172.18.0.3]:5701 [dev] [3.6.4] 
myapp-app_1            | 
myapp-app_1            | 
myapp-app_1            | Members [1] {
myapp-app_1            |    Member [172.18.0.3]:5701 this
myapp-app_1            | }
myapp-app_1            | 
myapp-app_1            | 2016-08-21 02:30:02.460  INFO 8 --- [           main] com.hazelcast.core.LifecycleService      : [172.18.0.3]:5701 [dev] [3.6.4] Address[172.18.0.3]:5701 is STARTED
myapp-app_1            | 2016-08-21 02:30:03.446  WARN 8 --- [ost-startStop-1] org.apache.catalina.webresources.Cache   : Unable to add the resource at [/WEB-INF/lib/jackson-mapper-asl-1.9.13.jar] to the cache because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
myapp-app_1            | 2016-08-21 02:30:06.057  WARN 8 --- [ost-startStop-1] org.apache.catalina.webresources.Cache   : Unable to add the resource at [/WEB-INF/lib/jackson-mapper-asl-1.9.13.jar] to the cache because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
myapp-app_1            | 2016-08-21 02:30:06.610  INFO 8 --- [ost-startStop-1] com.myapp.myapp.config.WebConfigurer     : Web application configuration, using profiles: [prod, swagger]
myapp-app_1            | 2016-08-21 02:30:06.626  INFO 8 --- [ost-startStop-1] com.myapp.myapp.config.WebConfigurer     : Web application fully configured
myapp-app_1            | 2016-08-21 02:30:06.716  INFO 8 --- [ost-startStop-1] c.hazelcast.web.ClusteredSessionService  : Retrying the connection!!
myapp-app_1            | 2016-08-21 02:30:06.760  INFO 8 --- [ost-startStop-1] com.hazelcast.config.XmlConfigLocator    : Loading 'hazelcast-default.xml' from classpath.
myapp-app_1            | 2016-08-21 02:30:07.218  INFO 8 --- [ost-startStop-1] c.hazelcast.web.HazelcastInstanceLoader  : getOrCreateHazelcastInstance for session replication, using name 'myapp'
myapp-app_1            | 2016-08-21 02:30:07.249  INFO 8 --- [ost-startStop-1] c.hazelcast.web.ClusteredSessionService  : Successfully Connected!
myapp-app_1            | 2016-08-21 02:30:41.544  INFO 8 --- [           main] c.m.m.c.h.HazelcastCacheRegionFactory    : Starting up HazelcastCacheRegionFactory
myapp-app_1            | 2016-08-21 02:30:42.780  INFO 8 --- [           main] com.myapp.myapp.MyappApp                 : Running with Spring profile(s) : [prod, swagger]
myapp-elasticsearch    | [2016-08-21 02:30:45,732][INFO ][cluster.metadata         ] [Rainbow] [roleauthority] creating index, cause [api], templates [], shards [5]/[1], mappings []
myapp-elasticsearch    | [2016-08-21 02:30:46,237][INFO ][cluster.metadata         ] [Rainbow] [roleauthority] create_mapping [roleauthority]
myapp-elasticsearch    | [2016-08-21 02:30:46,822][INFO ][cluster.routing.allocation] [Rainbow] Cluster health status changed from [RED] to [YELLOW] (reason: [shards started [[roleauthority][4], [roleauthority][4]] ...]).
myapp-elasticsearch    | [2016-08-21 02:30:46,918][INFO ][cluster.metadata         ] [Rainbow] [project] creating index, cause [api], templates [], shards [5]/[1], mappings []
myapp-elasticsearch    | [2016-08-21 02:30:47,198][INFO ][cluster.metadata         ] [Rainbow] [project] create_mapping [project]
myapp-elasticsearch    | [2016-08-21 02:30:47,656][INFO ][cluster.routing.allocation] [Rainbow] Cluster health status changed from [RED] to [YELLOW] (reason: [shards started [[project][4]] ...]).
myapp-elasticsearch    | [2016-08-21 02:30:47,729][INFO ][cluster.metadata         ] [Rainbow] [complaint] creating index, cause [api], templates [], shards [5]/[1], mappings []
myapp-elasticsearch    | [2016-08-21 02:30:47,947][INFO ][cluster.metadata         ] [Rainbow] [complaint] create_mapping [complaint]
myapp-elasticsearch    | [2016-08-21 02:30:48,429][INFO ][cluster.routing.allocation] [Rainbow] Cluster health status changed from [RED] to [YELLOW] (reason: [shards started [[complaint][4]] ...]).
myapp-elasticsearch    | [2016-08-21 02:30:48,497][INFO ][cluster.metadata         ] [Rainbow] [projectprogress] creating index, cause [api], templates [], shards [5]/[1], mappings []
myapp-elasticsearch    | [2016-08-21 02:30:48,738][INFO ][cluster.metadata         ] [Rainbow] [projectprogress] create_mapping [projectprogress]
myapp-elasticsearch    | [2016-08-21 02:30:49,274][INFO ][cluster.routing.allocation] [Rainbow] Cluster health status changed from [RED] to [YELLOW] (reason: [shards started [[projectprogress][4]] ...]).
myapp-app_1            | 2016-08-21 02:30:50.100  WARN 8 --- [           main] ationConfigEmbeddedWebApplicationContext : Exception encountered during context initialization - cancelling refresh attempt: org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'userService': Unsatisfied dependency expressed through field 'jdbcTokenStore': No qualifying bean of type [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore] found for dependency [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore]: expected at least 1 bean which qualifies as autowire candidate for this dependency. Dependency annotations: {@javax.inject.Inject()}; nested exception is org.springframework.beans.factory.NoSuchBeanDefinitionException: No qualifying bean of type [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore] found for dependency [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore]: expected at least 1 bean which qualifies as autowire candidate for this dependency. Dependency annotations: {@javax.inject.Inject()}
myapp-app_1            | 2016-08-21 02:30:50.160  INFO 8 --- [           main] c.m.m.c.h.HazelcastCacheRegionFactory    : Shutting down HazelcastCacheRegionFactory
myapp-app_1            | 2016-08-21 02:30:50.161  INFO 8 --- [           main] com.hazelcast.core.LifecycleService      : [172.18.0.3]:5701 [dev] [3.6.4] Address[172.18.0.3]:5701 is SHUTTING_DOWN
myapp-app_1            | 2016-08-21 02:30:50.163  INFO 8 --- [           main] com.hazelcast.instance.Node              : [172.18.0.3]:5701 [dev] [3.6.4] Shutting down multicast service...
myapp-app_1            | 2016-08-21 02:30:50.167  INFO 8 --- [           main] com.hazelcast.instance.Node              : [172.18.0.3]:5701 [dev] [3.6.4] Shutting down connection manager...
myapp-app_1            | 2016-08-21 02:30:50.170  INFO 8 --- [           main] com.hazelcast.instance.Node              : [172.18.0.3]:5701 [dev] [3.6.4] Shutting down node engine...
myapp-app_1            | 2016-08-21 02:30:50.185  INFO 8 --- [           main] com.hazelcast.instance.NodeExtension     : [172.18.0.3]:5701 [dev] [3.6.4] Destroying node NodeExtension.
myapp-app_1            | 2016-08-21 02:30:50.185  INFO 8 --- [           main] com.hazelcast.instance.Node              : [172.18.0.3]:5701 [dev] [3.6.4] Hazelcast Shutdown is completed in 22 ms.
myapp-app_1            | 2016-08-21 02:30:50.185  INFO 8 --- [           main] com.hazelcast.core.LifecycleService      : [172.18.0.3]:5701 [dev] [3.6.4] Address[172.18.0.3]:5701 is SHUTDOWN
myapp-app_1            | 2016-08-21 02:30:50.186  INFO 8 --- [           main] c.myapp.myapp.config.CacheConfiguration  : Closing Cache Manager
myapp-app_1            | 2016-08-21 02:30:50.242  WARN 8 --- [           main] o.s.boot.SpringApplication               : Error handling failed (Error creating bean with name 'delegatingApplicationListener' defined in class path resource [org/springframework/security/config/annotation/web/configuration/WebSecurityConfiguration.class]: BeanPostProcessor before instantiation of bean failed; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'metaDataSourceAdvisor': Cannot resolve reference to bean 'methodSecurityMetadataSource' while setting constructor argument; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'org.springframework.security.config.annotation.method.configuration.GlobalMethodSecurityConfiguration': Injection of autowired dependencies failed; nested exception is java.lang.RuntimeException: Could not postProcess org.springframework.security.access.expression.method.DefaultMethodSecurityExpressionHandler@31142d58 of type class org.springframework.security.access.expression.method.DefaultMethodSecurityExpressionHandler)
myapp-app_1            | 2016-08-21 02:30:50.255 ERROR 8 --- [           main] o.s.boot.SpringApplication               : Application startup failed
myapp-app_1            | 
myapp-app_1            | org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'userService': Unsatisfied dependency expressed through field 'jdbcTokenStore': No qualifying bean of type [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore] found for dependency [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore]: expected at least 1 bean which qualifies as autowire candidate for this dependency. Dependency annotations: {@javax.inject.Inject()}; nested exception is org.springframework.beans.factory.NoSuchBeanDefinitionException: No qualifying bean of type [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore] found for dependency [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore]: expected at least 1 bean which qualifies as autowire candidate for this dependency. Dependency annotations: {@javax.inject.Inject()}
myapp-app_1            |    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:569)
myapp-app_1            |    at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:88)
myapp-app_1            |    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessPropertyValues(AutowiredAnnotationBeanPostProcessor.java:349)
myapp-app_1            |    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1214)
myapp-app_1            |    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:543)
myapp-app_1            |    at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:482)
myapp-app_1            |    at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:306)
myapp-app_1            |    at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230)
myapp-app_1            |    at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:302)
myapp-app_1            |    at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:197)
myapp-app_1            |    at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:776)
myapp-app_1            |    at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:861)
myapp-app_1            |    at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:541)
myapp-app_1            |    at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.refresh(EmbeddedWebApplicationContext.java:122)
myapp-app_1            |    at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:759)
myapp-app_1            |    at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:369)
myapp-app_1            |    at org.springframework.boot.SpringApplication.run(SpringApplication.java:313)
myapp-app_1            |    at com.myapp.myapp.MyappApp.main(MyappApp.java:65)
myapp-app_1            |    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
myapp-app_1            |    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
myapp-app_1            |    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
myapp-app_1            |    at java.lang.reflect.Method.invoke(Method.java:498)
myapp-app_1            |    at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:48)
myapp-app_1            |    at org.springframework.boot.loader.Launcher.launch(Launcher.java:87)
myapp-app_1            |    at org.springframework.boot.loader.Launcher.launch(Launcher.java:50)
myapp-app_1            |    at org.springframework.boot.loader.WarLauncher.main(WarLauncher.java:59)
myapp-app_1            | Caused by: org.springframework.beans.factory.NoSuchBeanDefinitionException: No qualifying bean of type [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore] found for dependency [org.springframework.security.oauth2.provider.token.store.JdbcTokenStore]: expected at least 1 bean which qualifies as autowire candidate for this dependency. Dependency annotations: {@javax.inject.Inject()}
myapp-app_1            |    at org.springframework.beans.factory.support.DefaultListableBeanFactory.raiseNoMatchingBeanFound(DefaultListableBeanFactory.java:1406)
myapp-app_1            |    at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:1057)
myapp-app_1            |    at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:1019)
myapp-app_1            |    at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:566)
myapp-app_1            |    ... 25 common frames omitted
myapp-app_1            | 
docker_myapp-app_1 exited with code 1

@tsaqova
Copy link
Author

tsaqova commented Aug 21, 2016

i try to run using java -jar app.war in the build folder in my local and get the error too.
maybe there are library that not included in the war file while packaging ?

@tsaqova
Copy link
Author

tsaqova commented Aug 21, 2016

finally i can inject jdbctoken store by adding a Bean to OAuth2ServerConfiguration

@Bean
public JdbcTokenStore jdbcTokenStore() {
        return (JdbcTokenStore) tokenStore();
 }

or change the @Inject JdbcTokenStore at UserService to TokenStore and cast to JdbcTokenStore at deleteUser function.

Maybe JdbcTokenStore not readed as a bean but strange here is when running with gradle bootRun it just work.

@jdubois
Copy link
Member

jdubois commented Aug 21, 2016

@tsaqova you can't have a difference in the Spring configuration between "bootRun", running as a War, and running in Docker. They should all be the same, unless you have found a bug in our packaging.
As we had 2 members of the team who tried your configuration, and none of them could reproduce, I guess you are doing something that's breaking the packaging - I have no idea what, and I'd love to know, but if we can't reproduce we can't help you!
I'm happy to see you solved the issue, but I don't believe this is the root cause of the problem.

@jdubois jdubois closed this as completed Aug 21, 2016
@pascalgrimaud
Copy link
Member

@tsaqova : I try once again, with exact steps like you and everything is OK

  • run everything with docker -> OK
  • launch the war -> OK

2 possibilities:

  • you wrote specific code on your class, so there is no bug on generator-jhipster
  • some issue related to your system -> at least, give a try to our jhipster-devbox

@tsaqova
Copy link
Author

tsaqova commented Aug 21, 2016

@jdubois yeah that is not the root cause of the problem, i'll try to find the root cause later.
@pascalgrimaud ok, maybe later i'll try to reproduce it again and of course i'll share it here if I found anything is wrong.

Thanks all for your response. Great team.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

5 participants