Jul 18 07:49:14 S2A1 systemd[1]: Stopping Services for Sen2Agri... Jul 18 07:49:14 S2A1 systemd[1]: Started Services for Sen2Agri. Jul 18 07:49:14 S2A1 systemd[1]: Starting Services for Sen2Agri... Jul 18 07:49:16 S2A1 start.sh[26183]: ,---. ,---. ,---. ,--. ,---. ,--. ,--. ,---. Jul 18 07:49:16 S2A1 start.sh[26183]: ' .-' ,---. ,--,--, '.-. \,-----. / O \ ,---. ,--.--.`--' ' .-' ,---. ,--.--.,--. ,--.`--' ,---. ,---. ,---. ,--. ,--./ | | o | Jul 18 07:49:16 S2A1 start.sh[26183]: `. `-. | .-. :| \ .-' .''-----'| .-. || .-. || .--',--. `. `-. | .-. :| .--' \ `' / ,--.| .--'| .-. :( .-' \ `' / `| | .' '. Jul 18 07:49:16 S2A1 start.sh[26183]: .-' |\ --.| || |/ '-. | | | |' '-' '| | | | .-' |\ --.| | \ / | |\ `--.\ --..-' `) \ / | |.--.| o | Jul 18 07:49:16 S2A1 start.sh[26183]: `-----' `----'`--''--''-----' `--' `--'.`- / `--' `--' `-----' `----'`--' `--' `--' `---' `----'`----' `--' `--''--' `---' Jul 18 07:49:16 S2A1 start.sh[26183]: `---' Jul 18 07:49:16 S2A1 start.sh[26183]: 2018-07-18 07:49:16.288 INFO 26188 --- [ main] org.esa.sen2agri.ServicesStartup : Starting ServicesStartup on S2A1 with PID 26188 (/usr/share/sen2agri/sen2agri-services/modules/sen2agri-startup-1.0.jar started by sen2agri-service in /usr/share/sen2agri/sen2agri-services/bin) Jul 18 07:49:16 S2A1 start.sh[26183]: 2018-07-18 07:49:16.297 INFO 26188 --- [ main] org.esa.sen2agri.ServicesStartup : No active profile set, falling back to default profiles: default Jul 18 07:49:16 S2A1 start.sh[26183]: 2018-07-18 07:49:16.397 INFO 26188 --- [ main] ationConfigEmbeddedWebApplicationContext : Refreshing org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@65d6b83b: startup date [Wed Jul 18 07:49:16 EET 2018]; root of context hierarchy Jul 18 07:49:17 S2A1 start.sh[26183]: 2018-07-18 07:49:17.191 INFO 26188 --- [ main] o.s.b.f.xml.XmlBeanDefinitionReader : Loading XML bean definitions from class path resource [downloader-service-context.xml] Jul 18 07:49:17 S2A1 start.sh[26183]: 2018-07-18 07:49:17.927 INFO 26188 --- [ main] o.s.b.f.s.DefaultListableBeanFactory : Overriding bean definition for bean 'org.springframework.transaction.config.internalTransactionAdvisor' with a different definition: replacing [Root bean: class [org.springframework.transaction.interceptor.BeanFactoryTransactionAttributeSourceAdvisor]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null] with [Root bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=org.springframework.transaction.annotation.ProxyTransactionManagementConfiguration; factoryMethodName=transactionAdvisor; initMethodName=null; destroyMethodName=(inferred); defined in class path resource [org/springframework/transaction/annotation/ProxyTransactionManagementConfiguration.class]] Jul 18 07:49:17 S2A1 start.sh[26183]: 2018-07-18 07:49:17.930 INFO 26188 --- [ main] a.ConfigurationClassBeanDefinitionReader : Skipping bean definition for [BeanMethod:name=transactionalEventListenerFactory,declaringClass=org.springframework.transaction.annotation.AbstractTransactionManagementConfiguration]: a definition for bean 'org.springframework.transaction.config.internalTransactionalEventListenerFactory' already exists. This top-level bean definition is considered as an override. Jul 18 07:49:19 S2A1 start.sh[26183]: 2018-07-18 07:49:19.054 INFO 26188 --- [ main] org.esa.sen2agri.startup.ServletConfig : Using server port 8081 Jul 18 07:49:19 S2A1 start.sh[26183]: 2018-07-18 07:49:19.659 INFO 26188 --- [ main] s.b.c.e.t.TomcatEmbeddedServletContainer : Tomcat initialized with port(s): 8081 (http) Jul 18 07:49:19 S2A1 start.sh[26183]: 2018-07-18 07:49:19.981 INFO 26188 --- [ost-startStop-1] o.s.web.context.ContextLoader : Root WebApplicationContext: initialization completed in 3591 ms Jul 18 07:49:20 S2A1 start.sh[26183]: 2018-07-18 07:49:20.161 INFO 26188 --- [ost-startStop-1] o.s.b.w.servlet.ServletRegistrationBean : Mapping servlet: 'dispatcherServlet' to [/] Jul 18 07:49:20 S2A1 start.sh[26183]: 2018-07-18 07:49:20.170 INFO 26188 --- [ost-startStop-1] o.s.b.w.servlet.FilterRegistrationBean : Mapping filter: 'characterEncodingFilter' to: [/*] Jul 18 07:49:20 S2A1 start.sh[26183]: 2018-07-18 07:49:20.295 INFO 26188 --- [g-Init-Reporter] com.mchange.v2.log.MLog : MLog clients using slf4j logging. Jul 18 07:49:20 S2A1 start.sh[26183]: 2018-07-18 07:49:20.351 INFO 26188 --- [ main] com.mchange.v2.c3p0.C3P0Registry : Initializing c3p0-0.9.5.2 [built 08-December-2015 22:06:04 -0800; debug? true; trace: 10] Jul 18 07:49:20 S2A1 start.sh[26183]: 2018-07-18 07:49:20.523 INFO 26188 --- [ main] c.m.v.c.i.AbstractPoolBackedDataSource : Initializing c3p0 pool... com.mchange.v2.c3p0.ComboPooledDataSource [ acquireIncrement -> 3, acquireRetryAttempts -> 30, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, contextClassLoaderSource -> caller, dataSourceName -> 1bqon0m9w1bn9w3z135cook|4e4efc1b, debugUnreturnedConnectionStackTraces -> false, description -> null, driverClass -> org.postgresql.Driver, extensions -> {}, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, forceSynchronousCheckins -> false, forceUseNamedDriverClass -> false, identityToken -> 1bqon0m9w1bn9w3z135cook|4e4efc1b, idleConnectionTestPeriod -> 3000, initialPoolSize -> 10, jdbcUrl -> jdbc:postgresql://localhost:5432/sen2agri?stringtype=unspecified, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 0, maxIdleTimeExcessConnections -> 0, maxPoolSize -> 50, maxStatements -> 100, maxStatementsPerConnection -> 0, minPoolSize -> 10, numHelperThreads -> 3, preferredTestQuery -> null, privilegeSpawnedThreads -> false, properties -> {user=******, password=******}, propertyCycle -> 0, statementCacheNumDeferredCloseThreads -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, userOverrides -> {}, usesTraditionalReflectiveProxies -> false ] Jul 18 07:49:20 S2A1 start.sh[26183]: 2018-07-18 07:49:20.952 INFO 26188 --- [ main] o.s.o.j.p.DefaultPersistenceUnitManager : Found explicit default unit with name 'sen2agri' in persistence.xml - overriding local default unit settings ('packagesToScan'/'mappingResources') Jul 18 07:49:20 S2A1 start.sh[26183]: 2018-07-18 07:49:20.953 INFO 26188 --- [ main] j.LocalContainerEntityManagerFactoryBean : Building JPA container EntityManagerFactory for persistence unit 'sen2agri' Jul 18 07:49:20 S2A1 start.sh[26183]: 2018-07-18 07:49:20.995 INFO 26188 --- [ main] o.hibernate.jpa.internal.util.LogHelper : HHH000204: Processing PersistenceUnitInfo [ Jul 18 07:49:21 S2A1 start.sh[26183]: name: sen2agri Jul 18 07:49:21 S2A1 start.sh[26183]: ...] Jul 18 07:49:21 S2A1 start.sh[26183]: 2018-07-18 07:49:21.172 INFO 26188 --- [ main] org.hibernate.Version : HHH000412: Hibernate Core {5.2.12.Final} Jul 18 07:49:21 S2A1 start.sh[26183]: 2018-07-18 07:49:21.175 INFO 26188 --- [ main] org.hibernate.cfg.Environment : HHH000206: hibernate.properties not found Jul 18 07:49:21 S2A1 start.sh[26183]: 2018-07-18 07:49:21.200 INFO 26188 --- [ main] o.h.spatial.integration.SpatialService : HHH80000001: hibernate-spatial integration enabled : true Jul 18 07:49:21 S2A1 start.sh[26183]: 2018-07-18 07:49:21.271 INFO 26188 --- [ main] o.hibernate.annotations.common.Version : HCANN000001: Hibernate Commons Annotations {5.0.1.Final} Jul 18 07:49:22 S2A1 start.sh[26183]: 2018-07-18 07:49:22.187 INFO 26188 --- [ main] org.hibernate.dialect.Dialect : HHH000400: Using dialect: org.hibernate.spatial.dialect.postgis.PostgisDialect Jul 18 07:49:22 S2A1 start.sh[26183]: 2018-07-18 07:49:22.586 INFO 26188 --- [ main] o.h.e.j.e.i.LobCreatorBuilderImpl : HHH000424: Disabling contextual LOB creation as createClob() method threw error : java.lang.reflect.InvocationTargetException Jul 18 07:49:22 S2A1 start.sh[26183]: 2018-07-18 07:49:22.591 INFO 26188 --- [ main] org.hibernate.type.BasicTypeRegistry : HHH000270: Type registration [java.util.UUID] overrides previous : org.hibernate.type.UUIDBinaryType@7a2b1eb4 Jul 18 07:49:25 S2A1 start.sh[26183]: 2018-07-18 07:49:25.788 INFO 26188 --- [ main] j.LocalContainerEntityManagerFactoryBean : Initialized JPA EntityManagerFactory for persistence unit 'sen2agri' Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.010 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/sources/],methods=[GET]}" onto public org.springframework.http.ResponseEntity> org.esa.sen2agri.web.DatasourceController.getConfigurations() Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.016 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/sources/{satellite}],methods=[GET]}" onto public org.springframework.http.ResponseEntity> org.esa.sen2agri.web.DatasourceController.getConfigurations(short) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.018 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/sources/{satellite}/{name}],methods=[GET]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.DatasourceController.getConfiguration(short,java.lang.String) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.020 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/sources/{satellite}/{name}],methods=[POST],produces=[application/json]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.DatasourceController.updateConfiguration(short,java.lang.String,org.esa.sen2agri.entities.DataSourceConfiguration) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.030 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/{id}/count],methods=[GET]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.DownloadController.getCount(short) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.032 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/{id}],methods=[GET]}" onto public org.springframework.http.ResponseEntity> org.esa.sen2agri.web.DownloadController.getInProgress(short) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.034 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/],methods=[GET]}" onto public org.springframework.http.ResponseEntity> org.esa.sen2agri.web.DownloadController.getInProgress() Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.036 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/{code}/{satellite}],methods=[GET]}" onto public org.springframework.http.ResponseEntity> org.esa.sen2agri.web.DownloadController.getOverallProgress(java.lang.String,java.lang.String) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.037 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/start/{id}],methods=[GET]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.DownloadController.start(short) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.038 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/start],methods=[GET]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.DownloadController.start() Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.039 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/start/{id}/{satelliteId}],methods=[GET]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.DownloadController.start(short,short) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.041 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/stop],methods=[GET]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.DownloadController.stop() Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.042 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/stop/{id}/{satelliteId}],methods=[GET]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.DownloadController.stop(short,short) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.043 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/downloader/stop/{id}],methods=[GET]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.DownloadController.stop(short) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.056 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/products/types/],methods=[GET]}" onto public org.springframework.http.ResponseEntity> org.esa.sen2agri.web.ProductController.getProductTypes() Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.058 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/products/enable/status/{satellite}/{id}],methods=[GET]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.ProductController.enableStatus(short,short) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.062 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/products/objectstorage/get],methods=[GET]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.ProductController.getProductTypesForObjectStorage() Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.064 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/products/objectstorage/set/{productTypeIds}],methods=[GET]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.ProductController.setProductTypesForObjectStorage(java.lang.String[]) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.065 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/products/enable/{satellite}/{id}],methods=[GET]}" onto public org.springframework.http.ResponseEntity> org.esa.sen2agri.web.ProductController.enable(short,short) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.066 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/products/disable/{satellite}/{id}],methods=[GET]}" onto public org.springframework.http.ResponseEntity> org.esa.sen2agri.web.ProductController.disable(short,short) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.070 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/refresh/],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.RefreshController.refresh() Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.071 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/refresh/info],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.RefreshController.info() Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.077 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/sites/seasons/{id}],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity> org.esa.sen2agri.web.SiteController.getSiteSeasons(java.lang.String) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.078 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/sites/],methods=[GET],produces=[application/json]}" onto public org.springframework.http.ResponseEntity> org.esa.sen2agri.web.SiteController.list() Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.080 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/sites/],methods=[DELETE]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.SiteController.delete(org.esa.sen2agri.web.SiteController$DeleteReqPayload) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.081 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerMapping : Mapped "{[/sites/],methods=[POST],produces=[application/json]}" onto public org.springframework.http.ResponseEntity org.esa.sen2agri.web.SiteController.create(org.esa.sen2agri.web.SiteController$CreateSitePayload) Jul 18 07:49:26 S2A1 start.sh[26183]: 2018-07-18 07:49:26.254 INFO 26188 --- [ main] s.w.s.m.m.a.RequestMappingHandlerAdapter : Looking for @ControllerAdvice: org.springframework.boot.context.embedded.AnnotationConfigEmbeddedWebApplicationContext@65d6b83b: startup date [Wed Jul 18 07:49:16 EET 2018]; root of context hierarchy Jul 18 07:49:29 S2A1 start.sh[26183]: 2018-07-18 07:49:29.386 INFO 26188 --- [ main] o.h.h.i.QueryTranslatorFactoryInitiator : HHH000397: Using ASTQueryTranslatorFactory Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.265 INFO 26188 --- [ main] org.quartz.impl.StdSchedulerFactory : Using default implementation for ThreadExecutor Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.276 INFO 26188 --- [ main] org.quartz.simpl.SimpleThreadPool : Job execution threads will use class loader of thread: main Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.325 INFO 26188 --- [ main] org.quartz.core.SchedulerSignalerImpl : Initialized Scheduler Signaller of type: class org.quartz.core.SchedulerSignalerImpl Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.326 INFO 26188 --- [ main] org.quartz.core.QuartzScheduler : Quartz Scheduler v.2.3.0 created. Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.330 INFO 26188 --- [ main] org.quartz.simpl.RAMJobStore : RAMJobStore initialized. Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.333 INFO 26188 --- [ main] org.quartz.core.QuartzScheduler : Scheduler meta-data: Quartz Scheduler (v2.3.0) 'DefaultQuartzScheduler' with instanceId 'NON_CLUSTERED' Jul 18 07:49:30 S2A1 start.sh[26183]: Scheduler class: 'org.quartz.core.QuartzScheduler' - running locally. Jul 18 07:49:30 S2A1 start.sh[26183]: NOT STARTED. Jul 18 07:49:30 S2A1 start.sh[26183]: Currently in standby mode. Jul 18 07:49:30 S2A1 start.sh[26183]: Number of jobs executed: 0 Jul 18 07:49:30 S2A1 start.sh[26183]: Using thread pool 'org.quartz.simpl.SimpleThreadPool' - with 10 threads. Jul 18 07:49:30 S2A1 start.sh[26183]: Using job-store 'org.quartz.simpl.RAMJobStore' - which does not support persistence. and is not clustered. Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.333 INFO 26188 --- [ main] org.quartz.impl.StdSchedulerFactory : Quartz scheduler 'DefaultQuartzScheduler' initialized from default resource file in Quartz package: 'quartz.properties' Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.333 INFO 26188 --- [ main] org.quartz.impl.StdSchedulerFactory : Quartz scheduler version: 2.3.0 Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.334 INFO 26188 --- [ main] org.quartz.core.QuartzScheduler : Scheduler DefaultQuartzScheduler_$_NON_CLUSTERED started. Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.350 INFO 26188 --- [ main] o.esa.sen2agri.services.ScheduleManager : Enabled sites: sakarya,mersin Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.358 INFO 26188 --- [ main] o.esa.sen2agri.services.ScheduleManager : Found scheduled job types: Retry,Lookup Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.363 WARN 26188 --- [ main] ationConfigEmbeddedWebApplicationContext : Exception encountered during context initialization - cancelling refresh attempt: org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'siteService': Unsatisfied dependency expressed through field 'scheduleManager'; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'scheduleManager': Invocation of init method failed; nested exception is java.lang.IllegalStateException: Duplicate key org.esa.sen2agri.entities.DataSourceConfiguration@37e8e064 Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.367 INFO 26188 --- [ main] j.LocalContainerEntityManagerFactoryBean : Closing JPA EntityManagerFactory for persistence unit 'sen2agri' Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.405 INFO 26188 --- [ main] utoConfigurationReportLoggingInitializer : Jul 18 07:49:30 S2A1 start.sh[26183]: Error starting ApplicationContext. To display the auto-configuration report re-run your application with 'debug' enabled. Jul 18 07:49:30 S2A1 start.sh[26183]: 2018-07-18 07:49:30.423 ERROR 26188 --- [ main] o.s.boot.SpringApplication : Application startup failed Jul 18 07:49:30 S2A1 start.sh[26183]: org.springframework.beans.factory.UnsatisfiedDependencyException: Error creating bean with name 'siteService': Unsatisfied dependency expressed through field 'scheduleManager'; nested exception is org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'scheduleManager': Invocation of init method failed; nested exception is java.lang.IllegalStateException: Duplicate key org.esa.sen2agri.entities.DataSourceConfiguration@37e8e064 Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:588) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:88) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessPropertyValues(AutowiredAnnotationBeanPostProcessor.java:366) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1264) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:553) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:483) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:306) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:302) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:197) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:761) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:867) ~[spring-context-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:543) ~[spring-context-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.boot.context.embedded.EmbeddedWebApplicationContext.refresh(EmbeddedWebApplicationContext.java:122) ~[spring-boot-1.5.7.RELEASE.jar:1.5.7.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:693) [spring-boot-1.5.7.RELEASE.jar:1.5.7.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:360) [spring-boot-1.5.7.RELEASE.jar:1.5.7.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.boot.SpringApplication.run(SpringApplication.java:303) [spring-boot-1.5.7.RELEASE.jar:1.5.7.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.boot.SpringApplication.run(SpringApplication.java:1118) [spring-boot-1.5.7.RELEASE.jar:1.5.7.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.esa.sen2agri.ServicesStartup.main(ServicesStartup.java:42) [sen2agri-startup-1.0.jar:na] Jul 18 07:49:30 S2A1 start.sh[26183]: Caused by: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'scheduleManager': Invocation of init method failed; nested exception is java.lang.IllegalStateException: Duplicate key org.esa.sen2agri.entities.DataSourceConfiguration@37e8e064 Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.annotation.InitDestroyAnnotationBeanPostProcessor.postProcessBeforeInitialization(InitDestroyAnnotationBeanPostProcessor.java:137) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.applyBeanPostProcessorsBeforeInitialization(AbstractAutowireCapableBeanFactory.java:409) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.initializeBean(AbstractAutowireCapableBeanFactory.java:1620) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:555) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:483) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:306) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:230) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:302) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:202) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.config.DependencyDescriptor.resolveCandidate(DependencyDescriptor.java:208) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:1138) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:1066) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:585) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: ... 18 common frames omitted Jul 18 07:49:30 S2A1 start.sh[26183]: Caused by: java.lang.IllegalStateException: Duplicate key org.esa.sen2agri.entities.DataSourceConfiguration@37e8e064 Jul 18 07:49:30 S2A1 start.sh[26183]: at java.util.stream.Collectors.lambda$throwingMerger$0(Collectors.java:133) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at java.util.HashMap.merge(HashMap.java:1254) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at java.util.stream.Collectors.lambda$toMap$58(Collectors.java:1320) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at java.util.stream.ReduceOps$3ReducingSink.accept(ReduceOps.java:169) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:175) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1382) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:481) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:471) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:708) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:499) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.esa.sen2agri.commons.Config.getDownloadConfigurations(Config.java:268) ~[services-core-1.0.jar:na] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.esa.sen2agri.services.ScheduleManager.schedule(ScheduleManager.java:99) ~[services-core-1.0.jar:na] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.esa.sen2agri.services.ScheduleManager.initialize(ScheduleManager.java:76) ~[services-core-1.0.jar:na] Jul 18 07:49:30 S2A1 start.sh[26183]: at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at java.lang.reflect.Method.invoke(Method.java:498) ~[na:1.8.0_171] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.annotation.InitDestroyAnnotationBeanPostProcessor$LifecycleElement.invoke(InitDestroyAnnotationBeanPostProcessor.java:366) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.annotation.InitDestroyAnnotationBeanPostProcessor$LifecycleMetadata.invokeInitMethods(InitDestroyAnnotationBeanPostProcessor.java:311) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: at org.springframework.beans.factory.annotation.InitDestroyAnnotationBeanPostProcessor.postProcessBeforeInitialization(InitDestroyAnnotationBeanPostProcessor.java:134) ~[spring-beans-4.3.11.RELEASE.jar:4.3.11.RELEASE] Jul 18 07:49:30 S2A1 start.sh[26183]: ... 30 common frames omitted