2023-09-07 13:16:56 Stage2 hook starts 2023-09-07 13:16:56 2023-09-07 13:16:56 GoodData.CN Community Edition 2023-09-07 13:16:56 2023-09-07 13:16:56 Copyright (c) 2021 GoodData Corporation. All rights reserved. 2023-09-07 13:16:56 2023-09-07 13:16:56 /usr/share/nginx/html/License.txt: OK 2023-09-07 13:16:56 Terms of use accepted by environment variable. 2023-09-07 13:16:57 Resource check is unsupported on aarch64 architecture. Proceeding anyway. 2023-09-07 13:16:57 Using http://data-analytics.local.promptly.health as public URL 2023-09-07 13:16:57 Disabling service quiver 2023-09-07 13:16:57 Stage2 hook ends 2023-09-07 13:16:57 Keeping previous database. If you want to start from scratch, 2023-09-07 13:16:57 delete this container and respective docker volume attached to it. 2023-09-07 13:16:57 Replacing old directory with symlink to data directory 2023-09-07 13:16:57 122:C 07 Sep 2023 12:16:57.761 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo 2023-09-07 13:16:57 122:C 07 Sep 2023 12:16:57.761 # Redis version=6.0.16, bits=64, commit=00000000, modified=0, pid=122, just started 2023-09-07 13:16:57 122:C 07 Sep 2023 12:16:57.761 # Configuration loaded 2023-09-07 13:16:57 122:M 07 Sep 2023 12:16:57.764 * Running mode=standalone, port=6379. 2023-09-07 13:16:57 122:M 07 Sep 2023 12:16:57.764 # Server initialized 2023-09-07 13:16:57 122:M 07 Sep 2023 12:16:57.769 * Ready to accept connections 2023-09-07 13:16:57 2023-09-07 12:16:57.832 UTC [127] LOG: starting PostgreSQL 13.11 (Debian 13.11-0+deb11u1) on aarch64-unknown-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit 2023-09-07 13:16:57 2023-09-07 12:16:57.832 UTC [127] LOG: listening on IPv4 address "0.0.0.0", port 5432 2023-09-07 13:16:57 2023-09-07 12:16:57.832 UTC [127] LOG: listening on IPv6 address "::", port 5432 2023-09-07 13:16:57 2023-09-07 12:16:57.834 UTC [127] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432" 2023-09-07 13:16:57 2023-09-07 12:16:57.839 UTC [161] LOG: database system was shut down at 2023-09-07 12:16:16 UTC 2023-09-07 13:16:57 2023-09-07 12:16:57.852 UTC [127] LOG: database system is ready to accept connections 2023-09-07 13:16:58 Health check passed 2023-09-07 13:16:58 Dex: ready 2023-09-07 13:16:58 PONG 2023-09-07 13:16:58 2023-09-07 13:16:58 Redis: ready 2023-09-07 13:16:58 /var/run/postgresql:5432 - accepting connections 2023-09-07 13:16:58 2023-09-07 13:16:58 PostgreSQL Server: ready 2023-09-07 13:16:58 Keep existing demo schema ecommerce-demo 2023-09-07 13:16:59 WARNING: An illegal reflective access operation has occurred 2023-09-07 13:16:59 WARNING: Illegal reflective access by io.netty.util.internal.ReflectionUtil (file:/opt/apache-pulsar/lib/io.netty-netty-common-4.1.87.Final.jar) to constructor java.nio.DirectByteBuffer(long,int) 2023-09-07 13:16:59 WARNING: Please consider reporting this to the maintainers of io.netty.util.internal.ReflectionUtil 2023-09-07 13:16:59 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations 2023-09-07 13:16:59 WARNING: All illegal access operations will be denied in a future release 2023-09-07 13:16:59 2023-09-07T12:16:59,327+0000 [main] WARN org.apache.zookeeper.server.ServerCnxnFactory - maxCnxns is not configured, using default value 0. 2023-09-07 13:16:59 2023-09-07T12:16:59,820+0000 [main] ERROR org.apache.bookkeeper.bookie.Journal - Problems reading from data/standalone/bookkeeper0/current/lastMark (this is okay if it is the first time starting this bookie 2023-09-07 13:17:00 2023-09-07T12:17:00,400+0000 [main] WARN org.apache.pulsar.broker.protocol.ProtocolHandlerUtils - Protocol handler directory not found 2023-09-07 13:17:00 2023-09-07T12:17:00,529+0000 [main] WARN org.apache.bookkeeper.client.TopologyAwareEnsemblePlacementPolicy - Failed to resolve network location for 172.22.0.5, using default rack for it : /default-rack. 2023-09-07 13:17:00 2023-09-07T12:17:00,539+0000 [pulsar-registration-client-29-1] WARN org.apache.bookkeeper.client.TopologyAwareEnsemblePlacementPolicy - Failed to resolve network location for 127.0.0.1, using default rack for it : /default-rack. 2023-09-07 13:17:00 2023-09-07T12:17:00,687+0000 [main] WARN org.apache.bookkeeper.client.TopologyAwareEnsemblePlacementPolicy - Failed to resolve network location for 172.22.0.5, using default rack for it : /default-rack. 2023-09-07 13:17:00 2023-09-07T12:17:00,697+0000 [pulsar-registration-client-45-1] WARN org.apache.bookkeeper.client.TopologyAwareEnsemblePlacementPolicy - Failed to resolve network location for 127.0.0.1, using default rack for it : /default-rack. 2023-09-07 13:17:00 2023-09-07T12:17:00,712+0000 [main] WARN org.apache.pulsar.broker.intercept.BrokerInterceptorUtils - Pulsar broker interceptors directory not found 2023-09-07 13:17:01 Sep 07, 2023 12:17:01 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:17:01 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:17:01 Sep 07, 2023 12:17:01 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:17:01 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:17:01 Sep 07, 2023 12:17:01 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:17:01 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:17:01 Sep 07, 2023 12:17:01 PM org.glassfish.jersey.internal.Errors logErrors 2023-09-07 13:17:01 WARNING: The following warnings have been detected: WARNING: Return type, java.util.Map, of method, public java.util.Map org.apache.pulsar.broker.admin.impl.ClustersBase.getNamespaceIsolationPolicies(java.lang.String) throws java.lang.Exception, is not resolvable to a concrete type. 2023-09-07 13:17:01 2023-09-07 13:17:02 Sep 07, 2023 12:17:02 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:17:02 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:17:02 Sep 07, 2023 12:17:02 PM org.glassfish.jersey.internal.Errors logErrors 2023-09-07 13:17:02 WARNING: The following warnings have been detected: WARNING: Return type, java.util.Map, of method, public java.util.Map org.apache.pulsar.broker.admin.impl.ClustersBase.getNamespaceIsolationPolicies(java.lang.String) throws java.lang.Exception, is not resolvable to a concrete type. 2023-09-07 13:17:02 2023-09-07 13:17:02 Sep 07, 2023 12:17:02 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:17:02 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:16:57 s6-rc: info: service s6rc-oneshot-runner: starting 2023-09-07 13:16:57 s6-rc: info: service s6rc-oneshot-runner successfully started 2023-09-07 13:16:57 s6-rc: info: service fix-attrs: starting 2023-09-07 13:16:57 s6-rc: info: service fix-attrs successfully started 2023-09-07 13:16:57 s6-rc: info: service legacy-cont-init: starting 2023-09-07 13:16:57 s6-rc: info: service legacy-cont-init successfully started 2023-09-07 13:16:57 s6-rc: info: service init-data: starting 2023-09-07 13:16:57 s6-rc: info: service goss: starting 2023-09-07 13:16:57 s6-rc: info: service dex: starting 2023-09-07 13:16:57 s6-rc: info: service goss successfully started 2023-09-07 13:16:57 s6-rc: info: service init-data successfully started 2023-09-07 13:16:57 s6-rc: info: service redis: starting 2023-09-07 13:16:57 s6-rc: info: service pulsar: starting 2023-09-07 13:16:57 s6-rc: info: service postgres: starting 2023-09-07 13:16:58 s6-rc: info: service dex successfully started 2023-09-07 13:16:58 s6-rc: info: service redis successfully started 2023-09-07 13:16:58 s6-rc: info: service postgres successfully started 2023-09-07 13:16:58 s6-rc: info: service init-demo-data: starting 2023-09-07 13:16:58 s6-rc: info: service init-demo-data successfully started 2023-09-07 13:17:12 s6-rc: info: service pulsar successfully started 2023-09-07 13:17:12 s6-rc: info: service result-cache: starting 2023-09-07 13:17:12 s6-rc: info: service metadata-api: starting 2023-09-07 13:17:12 s6-rc: info: service cache-garbage-collector: starting 2023-09-07 13:17:12 s6-rc: info: service auth-service: starting 2023-09-07 13:17:12 s6-rc: info: service cache-garbage-collector successfully started 2023-09-07 13:18:29 s6-rc: info: service s6rc-oneshot-runner: starting 2023-09-07 13:18:29 s6-rc: info: service s6rc-oneshot-runner successfully started 2023-09-07 13:18:29 s6-rc: info: service fix-attrs: starting 2023-09-07 13:18:29 s6-rc: info: service fix-attrs successfully started 2023-09-07 13:18:29 s6-rc: info: service legacy-cont-init: starting 2023-09-07 13:18:29 s6-rc: info: service legacy-cont-init successfully started 2023-09-07 13:18:29 s6-rc: info: service init-data: starting 2023-09-07 13:18:29 s6-rc: info: service goss: starting 2023-09-07 13:18:29 s6-rc: info: service dex: starting 2023-09-07 13:18:29 s6-rc: info: service goss successfully started 2023-09-07 13:18:29 s6-rc: info: service init-data successfully started 2023-09-07 13:18:29 s6-rc: info: service redis: starting 2023-09-07 13:18:29 s6-rc: info: service pulsar: starting 2023-09-07 13:18:29 s6-rc: info: service postgres: starting 2023-09-07 13:17:02 Sep 07, 2023 12:17:02 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:17:02 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:17:02 Sep 07, 2023 12:17:02 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:17:02 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:17:12 Apache Pulsar: ready 2023-09-07 13:17:16 WARNING: An illegal reflective access operation has occurred 2023-09-07 13:17:16 WARNING: Illegal reflective access by org.apache.pulsar.common.util.netty.DnsResolverUtil (file:/opt/apache-pulsar/lib/org.apache.pulsar-pulsar-common-2.10.4.jar) to method sun.net.InetAddressCachePolicy.get() 2023-09-07 13:17:16 WARNING: Please consider reporting this to the maintainers of org.apache.pulsar.common.util.netty.DnsResolverUtil 2023-09-07 13:17:16 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations 2023-09-07 13:17:16 WARNING: All illegal access operations will be denied in a future release 2023-09-07 13:17:16 2023-09-07T12:17:16,488+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ConnectionPool - [[id: 0xd70833d1, L:/127.0.0.1:41648 - R:localhost/127.0.0.1:6650]] Connected to server 2023-09-07 13:17:17 2023-09-07T12:17:17,085+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerStatsRecorderImpl - Starting Pulsar producer perf with config: {"topicName":"persistent://public/default/caches.garbage-collect","producerName":null,"sendTimeoutMs":30000,"blockIfQueueFull":false,"maxPendingMessages":1000,"maxPendingMessagesAcrossPartitions":50000,"messageRoutingMode":"RoundRobinPartition","hashingScheme":"JavaStringHash","cryptoFailureAction":"FAIL","batchingMaxPublishDelayMicros":1000,"batchingPartitionSwitchFrequencyByPublishDelay":10,"batchingMaxMessages":1000,"batchingMaxBytes":131072,"batchingEnabled":true,"chunkingEnabled":false,"compressionType":"NONE","initialSequenceId":null,"autoUpdatePartitions":true,"autoUpdatePartitionsIntervalSeconds":60,"multiSchema":true,"accessMode":"Shared","lazyStartPartitionedProducers":false,"properties":{},"initialSubscriptionName":null} 2023-09-07 13:17:17 2023-09-07T12:17:17,185+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerStatsRecorderImpl - Pulsar client config: {"serviceUrl":"pulsar://localhost:6650","authPluginClassName":null,"authParams":null,"authParamMap":null,"operationTimeoutMs":30000,"lookupTimeoutMs":30000,"statsIntervalSeconds":60,"numIoThreads":1,"numListenerThreads":1,"connectionsPerBroker":1,"useTcpNoDelay":true,"useTls":false,"tlsTrustCertsFilePath":"","tlsAllowInsecureConnection":false,"tlsHostnameVerificationEnable":false,"concurrentLookupRequest":5000,"maxLookupRequest":50000,"maxLookupRedirects":20,"maxNumberOfRejectedRequestPerConnection":50,"keepAliveIntervalSeconds":30,"connectionTimeoutMs":10000,"requestTimeoutMs":60000,"initialBackoffIntervalNanos":100000000,"maxBackoffIntervalNanos":60000000000,"enableBusyWait":false,"listenerName":null,"useKeyStoreTls":false,"sslProvider":null,"tlsTrustStoreType":"JKS","tlsTrustStorePath":"","tlsTrustStorePassword":"*****","tlsCiphers":[],"tlsProtocols":[],"memoryLimitBytes":0,"proxyServiceUrl":null,"proxyProtocol":null,"enableTransaction":false,"dnsLookupBindAddress":null,"dnsLookupBindPort":0,"socks5ProxyAddress":null,"socks5ProxyUsername":null,"socks5ProxyPassword":null} 2023-09-07 13:17:17 2023-09-07T12:17:17,652+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Creating producer on cnx [id: 0xd70833d1, L:/127.0.0.1:41648 - R:localhost/127.0.0.1:6650] 2023-09-07 13:17:17 __ __ __ ____ _ _ ___ ____ ____ _ _ ____ ___ ____ 2023-09-07 13:17:17 /__\ ( )( )(_ _)( )_( ) / __)( ___)( _ \( \/ )(_ _) / __)( ___) 2023-09-07 13:17:17 /(__)\ )(__)( )( ) _ ( \__ \ )__) ) / \ / _)(_ ( (__ )__) 2023-09-07 13:17:17 (__)(__)(______) (__) (_) (_) (___/(____)(_)\_) \/ (____) \___)(____) 2023-09-07 13:17:17 :: Spring Boot (v2.6.15) :: HTTP port = 9050/9051 :: PID: 553 2023-09-07 13:17:17 :: Build from: 72bfbc1 :: 2023-07-24 11:46:23+0000 2023-09-07 13:17:17 2023-09-07 13:17:17 2023-09-07 13:17:18 ______ ____________________________ ________ _______ _______________ 2023-09-07 13:17:18 ___ |/ /___ ____/___ __/___ |___ __ \___ |___ __/___ | 2023-09-07 13:17:18 __ /|_/ / __ __/ __ / __ /| |__ / / /__ /| |__ / __ /| | 2023-09-07 13:17:18 _ / / / _ /___ _ / _ ___ |_ /_/ / _ ___ |_ / _ ___ | 2023-09-07 13:17:18 /_/ /_/ /_____/ /_/ /_/ |_|/_____/ /_/ |_|/_/ /_/ |_| 2023-09-07 13:17:18 2023-09-07 13:17:18 :: Spring Boot (v2.6.15) :: HTTP port = 9007/9008 :: PID: 555 2023-09-07 13:17:18 :: Build from: 72bfbc1 :: 2023-07-24 11:46:23+0000 2023-09-07 13:17:18 2023-09-07 13:17:18 2023-09-07 13:17:18 ______ _____ _____ _ _ _ _____ _____ ___ _____ _ _ _____ 2023-09-07 13:17:18 | ___ \| ___|/ ___|| | | || | |_ _| / __ \ / _ \ / __ \| | | || ___| 2023-09-07 13:17:18 | |_/ /| |__ \ `--. | | | || | | | | / \// /_\ \| / \/| |_| || |__ 2023-09-07 13:17:18 | / | __| `--. \| | | || | | | | | | _ || | | _ || __| 2023-09-07 13:17:18 | |\ \ | |___ /\__/ /| |_| || |____ | | | \__/\| | | || \__/\| | | || |___ 2023-09-07 13:17:18 \_| \_|\____/ \____/ \___/ \_____/ \_/ \____/\_| |_/ \____/\_| |_/\____/ 2023-09-07 13:17:18 :: Spring Boot (v2.6.15) :: HTTP port = 9040/9041 :: PID: 548 2023-09-07 13:17:18 :: Build from: 72bfbc1 :: 2023-07-24 11:46:23+0000 2023-09-07 13:17:18 2023-09-07 13:18:28 Stage2 hook starts 2023-09-07 13:18:28 2023-09-07 13:18:28 GoodData.CN Community Edition 2023-09-07 13:18:28 2023-09-07 13:18:28 Copyright (c) 2021 GoodData Corporation. All rights reserved. 2023-09-07 13:18:28 2023-09-07 13:18:28 /usr/share/nginx/html/License.txt: OK 2023-09-07 13:18:28 Terms of use accepted by environment variable. 2023-09-07 13:18:29 Resource check is unsupported on aarch64 architecture. Proceeding anyway. 2023-09-07 13:18:29 Using http://data-analytics.local.promptly.health as public URL 2023-09-07 13:18:29 Disabling service quiver 2023-09-07 13:18:29 Stage2 hook ends 2023-09-07 13:18:29 Keeping previous database. If you want to start from scratch, 2023-09-07 13:18:29 delete this container and respective docker volume attached to it. 2023-09-07 13:18:29 119:C 07 Sep 2023 12:18:29.716 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo 2023-09-07 13:18:29 119:C 07 Sep 2023 12:18:29.717 # Redis version=6.0.16, bits=64, commit=00000000, modified=0, pid=119, just started 2023-09-07 13:18:29 119:C 07 Sep 2023 12:18:29.717 # Configuration loaded 2023-09-07 13:18:29 119:M 07 Sep 2023 12:18:29.718 * Running mode=standalone, port=6379. 2023-09-07 13:18:29 119:M 07 Sep 2023 12:18:29.719 # Server initialized 2023-09-07 13:18:29 119:M 07 Sep 2023 12:18:29.722 * Ready to accept connections 2023-09-07 13:18:29 2023-09-07 12:18:29.762 UTC [132] LOG: starting PostgreSQL 13.11 (Debian 13.11-0+deb11u1) on aarch64-unknown-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit 2023-09-07 13:18:29 2023-09-07 12:18:29.762 UTC [132] LOG: listening on IPv4 address "0.0.0.0", port 5432 2023-09-07 13:18:29 2023-09-07 12:18:29.762 UTC [132] LOG: listening on IPv6 address "::", port 5432 2023-09-07 13:18:29 2023-09-07 12:18:29.764 UTC [132] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432" 2023-09-07 13:18:29 2023-09-07 12:18:29.770 UTC [164] LOG: database system was interrupted; last known up at 2023-09-07 12:16:57 UTC 2023-09-07 13:18:29 2023-09-07 12:18:29.926 UTC [164] LOG: database system was not properly shut down; automatic recovery in progress 2023-09-07 13:18:29 2023-09-07 12:18:29.929 UTC [164] LOG: redo starts at 0/887E9C0 2023-09-07 13:18:29 2023-09-07 12:18:29.929 UTC [164] LOG: invalid record length at 0/887E9F8: wanted 24, got 0 2023-09-07 13:18:29 2023-09-07 12:18:29.929 UTC [164] LOG: redo done at 0/887E9C0 2023-09-07 13:18:29 2023-09-07 12:18:29.943 UTC [132] LOG: database system is ready to accept connections 2023-09-07 13:18:30 Health check passed 2023-09-07 13:18:30 Dex: ready 2023-09-07 13:18:30 PONG 2023-09-07 13:18:30 2023-09-07 13:18:30 Redis: ready 2023-09-07 13:18:30 /var/run/postgresql:5432 - accepting connections 2023-09-07 13:18:30 2023-09-07 13:18:30 PostgreSQL Server: ready 2023-09-07 13:18:30 Keep existing demo schema ecommerce-demo 2023-09-07 13:18:30 WARNING: An illegal reflective access operation has occurred 2023-09-07 13:18:30 WARNING: Illegal reflective access by io.netty.util.internal.ReflectionUtil (file:/opt/apache-pulsar/lib/io.netty-netty-common-4.1.87.Final.jar) to constructor java.nio.DirectByteBuffer(long,int) 2023-09-07 13:18:30 WARNING: Please consider reporting this to the maintainers of io.netty.util.internal.ReflectionUtil 2023-09-07 13:18:30 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations 2023-09-07 13:18:30 WARNING: All illegal access operations will be denied in a future release 2023-09-07 13:18:31 2023-09-07T12:18:31,002+0000 [main] WARN org.apache.zookeeper.server.ServerCnxnFactory - maxCnxns is not configured, using default value 0. 2023-09-07 13:18:31 2023-09-07T12:18:31,894+0000 [main] WARN org.apache.pulsar.broker.protocol.ProtocolHandlerUtils - Protocol handler directory not found 2023-09-07 13:18:31 2023-09-07T12:18:31,993+0000 [main] WARN org.apache.bookkeeper.client.TopologyAwareEnsemblePlacementPolicy - Failed to resolve network location for 172.22.0.5, using default rack for it : /default-rack. 2023-09-07 13:18:32 2023-09-07T12:18:32,001+0000 [pulsar-registration-client-29-1] WARN org.apache.bookkeeper.client.TopologyAwareEnsemblePlacementPolicy - Failed to resolve network location for 127.0.0.1, using default rack for it : /default-rack. 2023-09-07 13:18:32 2023-09-07T12:18:32,074+0000 [main] WARN org.apache.bookkeeper.client.TopologyAwareEnsemblePlacementPolicy - Failed to resolve network location for 172.22.0.5, using default rack for it : /default-rack. 2023-09-07 13:18:32 2023-09-07T12:18:32,079+0000 [pulsar-registration-client-45-1] WARN org.apache.bookkeeper.client.TopologyAwareEnsemblePlacementPolicy - Failed to resolve network location for 127.0.0.1, using default rack for it : /default-rack. 2023-09-07 13:18:32 2023-09-07T12:18:32,089+0000 [main] WARN org.apache.pulsar.broker.intercept.BrokerInterceptorUtils - Pulsar broker interceptors directory not found 2023-09-07 13:18:32 Sep 07, 2023 12:18:32 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:18:32 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:18:32 Sep 07, 2023 12:18:32 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:18:32 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:18:32 Sep 07, 2023 12:18:32 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:18:32 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:18:33 Sep 07, 2023 12:18:33 PM org.glassfish.jersey.internal.Errors logErrors 2023-09-07 13:18:33 WARNING: The following warnings have been detected: WARNING: Return type, java.util.Map, of method, public java.util.Map org.apache.pulsar.broker.admin.impl.ClustersBase.getNamespaceIsolationPolicies(java.lang.String) throws java.lang.Exception, is not resolvable to a concrete type. 2023-09-07 13:18:33 2023-09-07 13:18:33 Sep 07, 2023 12:18:33 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:18:33 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:18:33 Sep 07, 2023 12:18:33 PM org.glassfish.jersey.internal.Errors logErrors 2023-09-07 13:18:33 WARNING: The following warnings have been detected: WARNING: Return type, java.util.Map, of method, public java.util.Map org.apache.pulsar.broker.admin.impl.ClustersBase.getNamespaceIsolationPolicies(java.lang.String) throws java.lang.Exception, is not resolvable to a concrete type. 2023-09-07 13:18:33 2023-09-07 13:18:33 Sep 07, 2023 12:18:33 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:18:33 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:18:33 Sep 07, 2023 12:18:33 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:18:33 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:18:33 Sep 07, 2023 12:18:33 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 13:18:33 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 13:18:34 2023-09-07T12:18:34,218+0000 [pulsar-web-48-3] WARN org.apache.pulsar.broker.admin.impl.ClustersBase - [null] Failed to create already existing cluster global 2023-09-07 13:18:34 2023-09-07T12:18:34,219+0000 [pulsar-web-48-3] ERROR org.apache.pulsar.broker.admin.impl.ClustersBase - [null] Failed to create cluster global 2023-09-07 13:18:34 org.apache.pulsar.broker.web.RestException: Cluster already exists 2023-09-07 13:18:34 at org.apache.pulsar.broker.admin.impl.ClustersBase.createCluster(ClustersBase.java:171) ~[org.apache.pulsar-pulsar-broker-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?] 2023-09-07 13:18:34 at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?] 2023-09-07 13:18:34 at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] 2023-09-07 13:18:34 at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] 2023-09-07 13:18:34 at org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory.lambda$static$0(ResourceMethodInvocationHandlerFactory.java:52) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:124) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 13:18:30 s6-rc: info: service dex successfully started 2023-09-07 13:18:30 s6-rc: info: service redis successfully started 2023-09-07 13:18:30 s6-rc: info: service postgres successfully started 2023-09-07 13:18:30 s6-rc: info: service init-demo-data: starting 2023-09-07 13:18:30 s6-rc: info: service init-demo-data successfully started 2023-09-07 13:18:34 at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:167) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$VoidOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:159) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:79) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:475) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:397) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:81) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.server.ServerRuntime$1.run(ServerRuntime.java:255) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors.process(Errors.java:292) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors.process(Errors.java:274) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors.process(Errors.java:244) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:265) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:234) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:680) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:394) ~[org.glassfish.jersey.containers-jersey-container-servlet-core-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:346) ~[org.glassfish.jersey.containers-jersey-container-servlet-core-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:366) ~[org.glassfish.jersey.containers-jersey-container-servlet-core-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:319) ~[org.glassfish.jersey.containers-jersey-container-servlet-core-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:205) ~[org.glassfish.jersey.containers-jersey-container-servlet-core-2.34.jar:?] 2023-09-07 13:18:34 at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[org.eclipse.jetty-jetty-servlet-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1656) ~[org.eclipse.jetty-jetty-servlet-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.apache.pulsar.broker.web.ResponseHandlerFilter.doFilter(ResponseHandlerFilter.java:67) ~[org.apache.pulsar-pulsar-broker-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[org.eclipse.jetty-jetty-servlet-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1626) ~[org.eclipse.jetty-jetty-servlet-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:552) ~[org.eclipse.jetty-jetty-servlet-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505) ~[org.eclipse.jetty-jetty-servlet-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:234) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:146) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.handler.StatisticsHandler.handle(StatisticsHandler.java:181) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) ~[org.eclipse.jetty-jetty-io-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[org.eclipse.jetty-jetty-io-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) ~[org.eclipse.jetty-jetty-io-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) ~[org.eclipse.jetty-jetty-util-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) ~[org.eclipse.jetty-jetty-util-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) ~[org.eclipse.jetty-jetty-util-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) ~[org.eclipse.jetty-jetty-util-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) ~[org.eclipse.jetty-jetty-util-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 13:18:34 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?] 2023-09-07 13:18:34 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at java.lang.Thread.run(Thread.java:829) ~[?:?] 2023-09-07 13:18:34 2023-09-07T12:18:34,226+0000 [AsyncHttpClient-59-1] WARN org.apache.pulsar.client.admin.internal.BaseResource - [http://localhost:8080/admin/v2/clusters/global] Failed to perform http put request: javax.ws.rs.ClientErrorException: HTTP 409 Cluster already exists 2023-09-07 13:18:34 2023-09-07T12:18:34,227+0000 [main] WARN org.apache.pulsar.PulsarStandalone - Cluster already exists 2023-09-07 13:18:34 org.apache.pulsar.client.admin.PulsarAdminException$ConflictException: Cluster already exists 2023-09-07 13:18:34 at org.apache.pulsar.client.admin.PulsarAdminException.wrap(PulsarAdminException.java:252) ~[org.apache.pulsar-pulsar-client-admin-api-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at org.apache.pulsar.client.admin.internal.BaseResource.sync(BaseResource.java:302) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at org.apache.pulsar.client.admin.internal.ClustersImpl.createCluster(ClustersImpl.java:102) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at org.apache.pulsar.PulsarStandalone.createSampleNameSpace(PulsarStandalone.java:401) ~[org.apache.pulsar-pulsar-broker-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at org.apache.pulsar.PulsarStandalone.start(PulsarStandalone.java:320) ~[org.apache.pulsar-pulsar-broker-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at org.apache.pulsar.PulsarStandaloneStarter.main(PulsarStandaloneStarter.java:143) ~[org.apache.pulsar-pulsar-broker-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 Suppressed: org.apache.pulsar.client.admin.PulsarAdminException$ConflictException: Cluster already exists 2023-09-07 13:18:34 at org.apache.pulsar.client.admin.internal.BaseResource.getApiException(BaseResource.java:238) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at org.apache.pulsar.client.admin.internal.BaseResource$1.failed(BaseResource.java:134) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at org.glassfish.jersey.client.JerseyInvocation$1.failed(JerseyInvocation.java:882) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.JerseyInvocation$1.completed(JerseyInvocation.java:863) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.ClientRuntime.processResponse(ClientRuntime.java:229) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.ClientRuntime.access$200(ClientRuntime.java:62) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.ClientRuntime$2.lambda$response$0(ClientRuntime.java:173) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors.process(Errors.java:292) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors.process(Errors.java:274) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors.process(Errors.java:244) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:288) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.ClientRuntime$2.response(ClientRuntime.java:173) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$apply$1(AsyncHttpConnector.java:247) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:2073) ~[?:?] 2023-09-07 13:18:34 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$retryOperation$4(AsyncHttpConnector.java:289) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:2073) ~[?:?] 2023-09-07 13:18:34 at org.asynchttpclient.netty.NettyResponseFuture.loadContent(NettyResponseFuture.java:222) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 13:18:34 at org.asynchttpclient.netty.NettyResponseFuture.done(NettyResponseFuture.java:257) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 13:18:34 at org.asynchttpclient.netty.handler.AsyncHttpClientHandler.finishUpdate(AsyncHttpClientHandler.java:241) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 13:18:34 at org.asynchttpclient.netty.handler.HttpHandler.handleChunk(HttpHandler.java:114) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 13:18:34 at org.asynchttpclient.netty.handler.HttpHandler.handleRead(HttpHandler.java:143) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 13:18:34 at org.asynchttpclient.netty.handler.AsyncHttpClientHandler.channelRead(AsyncHttpClientHandler.java:78) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) ~[io.netty-netty-codec-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:346) ~[io.netty-netty-codec-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:318) ~[io.netty-netty-codec-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:442) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:440) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:166) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:788) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at java.lang.Thread.run(Thread.java:829) ~[?:?] 2023-09-07 13:18:34 Caused by: javax.ws.rs.ClientErrorException: HTTP 409 Cluster already exists 2023-09-07 13:18:34 at org.glassfish.jersey.client.JerseyInvocation.createExceptionForFamily(JerseyInvocation.java:985) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.JerseyInvocation.convertToException(JerseyInvocation.java:967) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.JerseyInvocation.access$700(JerseyInvocation.java:82) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 ... 54 more 2023-09-07 13:18:34 Caused by: javax.ws.rs.ClientErrorException: HTTP 409 Cluster already exists 2023-09-07 13:18:34 at org.glassfish.jersey.client.JerseyInvocation.createExceptionForFamily(JerseyInvocation.java:985) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.JerseyInvocation.convertToException(JerseyInvocation.java:967) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.JerseyInvocation.access$700(JerseyInvocation.java:82) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.JerseyInvocation$1.completed(JerseyInvocation.java:863) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.ClientRuntime.processResponse(ClientRuntime.java:229) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.ClientRuntime.access$200(ClientRuntime.java:62) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.ClientRuntime$2.lambda$response$0(ClientRuntime.java:173) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors.process(Errors.java:292) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors.process(Errors.java:274) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.internal.Errors.process(Errors.java:244) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:288) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 13:18:34 at org.glassfish.jersey.client.ClientRuntime$2.response(ClientRuntime.java:173) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 13:18:34 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$apply$1(AsyncHttpConnector.java:247) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:2073) ~[?:?] 2023-09-07 13:18:34 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$retryOperation$4(AsyncHttpConnector.java:289) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) ~[?:?] 2023-09-07 13:18:34 at java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:2073) ~[?:?] 2023-09-07 13:18:34 at org.asynchttpclient.netty.NettyResponseFuture.loadContent(NettyResponseFuture.java:222) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 13:18:34 at org.asynchttpclient.netty.NettyResponseFuture.done(NettyResponseFuture.java:257) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 13:18:34 at org.asynchttpclient.netty.handler.AsyncHttpClientHandler.finishUpdate(AsyncHttpClientHandler.java:241) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 13:18:34 at org.asynchttpclient.netty.handler.HttpHandler.handleChunk(HttpHandler.java:114) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 13:18:34 at org.asynchttpclient.netty.handler.HttpHandler.handleRead(HttpHandler.java:143) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 13:18:34 at org.asynchttpclient.netty.handler.AsyncHttpClientHandler.channelRead(AsyncHttpClientHandler.java:78) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) ~[io.netty-netty-codec-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:346) ~[io.netty-netty-codec-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:318) ~[io.netty-netty-codec-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:442) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:440) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:166) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:788) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 13:18:34 at java.lang.Thread.run(Thread.java:829) ~[?:?] 2023-09-07 13:18:44 Apache Pulsar: ready 2023-09-07 13:18:44 s6-rc: info: service pulsar successfully started 2023-09-07 13:18:44 s6-rc: info: service result-cache: starting 2023-09-07 13:18:44 s6-rc: info: service metadata-api: starting 2023-09-07 13:18:44 s6-rc: info: service cache-garbage-collector: starting 2023-09-07 13:18:44 s6-rc: info: service auth-service: starting 2023-09-07 13:18:44 s6-rc: info: service cache-garbage-collector successfully started 2023-09-07 15:26:21 s6-rc: info: service s6rc-oneshot-runner: starting 2023-09-07 15:26:21 s6-rc: info: service s6rc-oneshot-runner successfully started 2023-09-07 15:26:21 s6-rc: info: service fix-attrs: starting 2023-09-07 15:26:21 s6-rc: info: service fix-attrs successfully started 2023-09-07 15:26:21 s6-rc: info: service legacy-cont-init: starting 2023-09-07 15:26:21 s6-rc: info: service legacy-cont-init successfully started 2023-09-07 15:26:21 s6-rc: info: service init-data: starting 2023-09-07 15:26:21 s6-rc: info: service goss: starting 2023-09-07 15:26:21 s6-rc: info: service dex: starting 2023-09-07 15:26:21 s6-rc: info: service goss successfully started 2023-09-07 15:26:21 s6-rc: info: service init-data successfully started 2023-09-07 15:26:21 s6-rc: info: service redis: starting 2023-09-07 15:26:21 s6-rc: info: service pulsar: starting 2023-09-07 15:26:21 s6-rc: info: service postgres: starting 2023-09-07 15:26:22 s6-rc: info: service dex successfully started 2023-09-07 15:26:22 s6-rc: info: service redis successfully started 2023-09-07 15:26:22 s6-rc: info: service postgres successfully started 2023-09-07 15:26:22 s6-rc: info: service init-demo-data: starting 2023-09-07 15:26:22 s6-rc: info: service init-demo-data successfully started 2023-09-07 15:26:20 Stage2 hook starts 2023-09-07 15:26:20 2023-09-07 15:26:20 GoodData.CN Community Edition 2023-09-07 15:26:20 2023-09-07 15:26:20 Copyright (c) 2021 GoodData Corporation. All rights reserved. 2023-09-07 15:26:20 2023-09-07 15:26:20 /usr/share/nginx/html/License.txt: OK 2023-09-07 15:26:20 Terms of use accepted by environment variable. 2023-09-07 15:26:21 Resource check is unsupported on aarch64 architecture. Proceeding anyway. 2023-09-07 15:26:21 Using http://data-analytics.local.promptly.health as public URL 2023-09-07 15:26:21 Disabling service quiver 2023-09-07 15:26:21 Stage2 hook ends 2023-09-07 15:26:21 Keeping previous database. If you want to start from scratch, 2023-09-07 15:26:21 delete this container and respective docker volume attached to it. 2023-09-07 15:26:21 117:C 07 Sep 2023 14:26:21.375 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo 2023-09-07 15:26:21 117:C 07 Sep 2023 14:26:21.375 # Redis version=6.0.16, bits=64, commit=00000000, modified=0, pid=117, just started 2023-09-07 15:26:21 117:C 07 Sep 2023 14:26:21.375 # Configuration loaded 2023-09-07 15:26:21 117:M 07 Sep 2023 14:26:21.378 * Running mode=standalone, port=6379. 2023-09-07 15:26:21 117:M 07 Sep 2023 14:26:21.378 # Server initialized 2023-09-07 15:26:21 117:M 07 Sep 2023 14:26:21.384 * Ready to accept connections 2023-09-07 15:26:21 2023-09-07 14:26:21.439 UTC [118] LOG: starting PostgreSQL 13.11 (Debian 13.11-0+deb11u1) on aarch64-unknown-linux-gnu, compiled by gcc (Debian 10.2.1-6) 10.2.1 20210110, 64-bit 2023-09-07 15:26:21 2023-09-07 14:26:21.439 UTC [118] LOG: listening on IPv4 address "0.0.0.0", port 5432 2023-09-07 15:26:21 2023-09-07 14:26:21.439 UTC [118] LOG: listening on IPv6 address "::", port 5432 2023-09-07 15:26:21 2023-09-07 14:26:21.442 UTC [118] LOG: listening on Unix socket "/var/run/postgresql/.s.PGSQL.5432" 2023-09-07 15:26:21 2023-09-07 14:26:21.447 UTC [158] LOG: database system was interrupted; last known up at 2023-09-07 12:18:29 UTC 2023-09-07 15:26:21 2023-09-07 14:26:21.593 UTC [158] LOG: database system was not properly shut down; automatic recovery in progress 2023-09-07 15:26:21 2023-09-07 14:26:21.596 UTC [158] LOG: redo starts at 0/887EA70 2023-09-07 15:26:21 2023-09-07 14:26:21.596 UTC [158] LOG: invalid record length at 0/887EAA8: wanted 24, got 0 2023-09-07 15:26:21 2023-09-07 14:26:21.596 UTC [158] LOG: redo done at 0/887EA70 2023-09-07 15:26:21 2023-09-07 14:26:21.613 UTC [118] LOG: database system is ready to accept connections 2023-09-07 15:26:22 Health check passed 2023-09-07 15:26:22 Dex: ready 2023-09-07 15:26:22 PONG 2023-09-07 15:26:22 2023-09-07 15:26:22 Redis: ready 2023-09-07 15:26:22 /var/run/postgresql:5432 - accepting connections 2023-09-07 15:26:22 2023-09-07 15:26:22 PostgreSQL Server: ready 2023-09-07 15:26:22 Keep existing demo schema ecommerce-demo 2023-09-07 15:26:22 WARNING: An illegal reflective access operation has occurred 2023-09-07 15:26:22 WARNING: Illegal reflective access by io.netty.util.internal.ReflectionUtil (file:/opt/apache-pulsar/lib/io.netty-netty-common-4.1.87.Final.jar) to constructor java.nio.DirectByteBuffer(long,int) 2023-09-07 15:26:22 WARNING: Please consider reporting this to the maintainers of io.netty.util.internal.ReflectionUtil 2023-09-07 15:26:22 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations 2023-09-07 15:26:22 WARNING: All illegal access operations will be denied in a future release 2023-09-07 15:26:22 2023-09-07T14:26:22,797+0000 [main] WARN org.apache.zookeeper.server.ServerCnxnFactory - maxCnxns is not configured, using default value 0. 2023-09-07 15:26:23 2023-09-07T14:26:23,823+0000 [main] WARN org.apache.pulsar.broker.protocol.ProtocolHandlerUtils - Protocol handler directory not found 2023-09-07 15:26:23 2023-09-07T14:26:23,928+0000 [main] WARN org.apache.bookkeeper.client.TopologyAwareEnsemblePlacementPolicy - Failed to resolve network location for 172.22.0.5, using default rack for it : /default-rack. 2023-09-07 15:26:23 2023-09-07T14:26:23,938+0000 [pulsar-registration-client-29-1] WARN org.apache.bookkeeper.client.TopologyAwareEnsemblePlacementPolicy - Failed to resolve network location for 127.0.0.1, using default rack for it : /default-rack. 2023-09-07 15:26:24 2023-09-07T14:26:24,022+0000 [main] WARN org.apache.bookkeeper.client.TopologyAwareEnsemblePlacementPolicy - Failed to resolve network location for 172.22.0.5, using default rack for it : /default-rack. 2023-09-07 15:26:24 2023-09-07T14:26:24,027+0000 [pulsar-registration-client-45-1] WARN org.apache.bookkeeper.client.TopologyAwareEnsemblePlacementPolicy - Failed to resolve network location for 127.0.0.1, using default rack for it : /default-rack. 2023-09-07 15:26:24 2023-09-07T14:26:24,038+0000 [main] WARN org.apache.pulsar.broker.intercept.BrokerInterceptorUtils - Pulsar broker interceptors directory not found 2023-09-07 15:26:24 Sep 07, 2023 2:26:24 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 15:26:24 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 15:26:24 Sep 07, 2023 2:26:24 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 15:26:24 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 15:26:24 Sep 07, 2023 2:26:24 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 15:26:24 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 15:26:25 Sep 07, 2023 2:26:25 PM org.glassfish.jersey.internal.Errors logErrors 2023-09-07 15:26:25 WARNING: The following warnings have been detected: WARNING: Return type, java.util.Map, of method, public java.util.Map org.apache.pulsar.broker.admin.impl.ClustersBase.getNamespaceIsolationPolicies(java.lang.String) throws java.lang.Exception, is not resolvable to a concrete type. 2023-09-07 15:26:25 2023-09-07 15:26:25 Sep 07, 2023 2:26:25 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 15:26:25 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 15:26:25 Sep 07, 2023 2:26:25 PM org.glassfish.jersey.internal.Errors logErrors 2023-09-07 15:26:25 WARNING: The following warnings have been detected: WARNING: Return type, java.util.Map, of method, public java.util.Map org.apache.pulsar.broker.admin.impl.ClustersBase.getNamespaceIsolationPolicies(java.lang.String) throws java.lang.Exception, is not resolvable to a concrete type. 2023-09-07 15:26:25 2023-09-07 15:26:25 Sep 07, 2023 2:26:25 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 15:26:25 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 15:26:25 Sep 07, 2023 2:26:25 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 15:26:25 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 15:26:25 Sep 07, 2023 2:26:25 PM org.glassfish.jersey.server.wadl.WadlFeature configure 2023-09-07 15:26:25 WARNING: JAXBContext implementation could not be found. WADL feature is disabled. 2023-09-07 15:26:26 2023-09-07T14:26:26,241+0000 [pulsar-web-48-4] WARN org.apache.pulsar.broker.admin.impl.ClustersBase - [null] Failed to create already existing cluster global 2023-09-07 15:26:26 2023-09-07T14:26:26,242+0000 [pulsar-web-48-4] ERROR org.apache.pulsar.broker.admin.impl.ClustersBase - [null] Failed to create cluster global 2023-09-07 15:26:26 org.apache.pulsar.broker.web.RestException: Cluster already exists 2023-09-07 15:26:26 at org.apache.pulsar.broker.admin.impl.ClustersBase.createCluster(ClustersBase.java:171) ~[org.apache.pulsar-pulsar-broker-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?] 2023-09-07 15:26:26 at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?] 2023-09-07 15:26:26 at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?] 2023-09-07 15:26:26 at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?] 2023-09-07 15:26:26 at org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory.lambda$static$0(ResourceMethodInvocationHandlerFactory.java:52) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:124) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:167) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$VoidOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:159) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:79) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:475) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:397) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:81) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.server.ServerRuntime$1.run(ServerRuntime.java:255) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors.process(Errors.java:292) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors.process(Errors.java:274) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors.process(Errors.java:244) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:265) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:234) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:680) ~[org.glassfish.jersey.core-jersey-server-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:394) ~[org.glassfish.jersey.containers-jersey-container-servlet-core-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:346) ~[org.glassfish.jersey.containers-jersey-container-servlet-core-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:366) ~[org.glassfish.jersey.containers-jersey-container-servlet-core-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:319) ~[org.glassfish.jersey.containers-jersey-container-servlet-core-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:205) ~[org.glassfish.jersey.containers-jersey-container-servlet-core-2.34.jar:?] 2023-09-07 15:26:26 at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[org.eclipse.jetty-jetty-servlet-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1656) ~[org.eclipse.jetty-jetty-servlet-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.apache.pulsar.broker.web.ResponseHandlerFilter.doFilter(ResponseHandlerFilter.java:67) ~[org.apache.pulsar-pulsar-broker-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[org.eclipse.jetty-jetty-servlet-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1626) ~[org.eclipse.jetty-jetty-servlet-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:552) ~[org.eclipse.jetty-jetty-servlet-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1440) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:505) ~[org.eclipse.jetty-jetty-servlet-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1355) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:234) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:146) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.handler.StatisticsHandler.handle(StatisticsHandler.java:181) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:487) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:732) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:479) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) ~[org.eclipse.jetty-jetty-server-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) ~[org.eclipse.jetty-jetty-io-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) ~[org.eclipse.jetty-jetty-io-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) ~[org.eclipse.jetty-jetty-io-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) ~[org.eclipse.jetty-jetty-util-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) ~[org.eclipse.jetty-jetty-util-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) ~[org.eclipse.jetty-jetty-util-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) ~[org.eclipse.jetty-jetty-util-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) ~[org.eclipse.jetty-jetty-util-9.4.48.v20220622.jar:9.4.48.v20220622] 2023-09-07 15:26:26 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?] 2023-09-07 15:26:26 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at java.lang.Thread.run(Thread.java:829) ~[?:?] 2023-09-07 15:26:26 2023-09-07T14:26:26,248+0000 [AsyncHttpClient-59-1] WARN org.apache.pulsar.client.admin.internal.BaseResource - [http://localhost:8080/admin/v2/clusters/global] Failed to perform http put request: javax.ws.rs.ClientErrorException: HTTP 409 Cluster already exists 2023-09-07 15:26:26 2023-09-07T14:26:26,249+0000 [main] WARN org.apache.pulsar.PulsarStandalone - Cluster already exists 2023-09-07 15:26:26 org.apache.pulsar.client.admin.PulsarAdminException$ConflictException: Cluster already exists 2023-09-07 15:26:26 at org.apache.pulsar.client.admin.PulsarAdminException.wrap(PulsarAdminException.java:252) ~[org.apache.pulsar-pulsar-client-admin-api-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at org.apache.pulsar.client.admin.internal.BaseResource.sync(BaseResource.java:302) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at org.apache.pulsar.client.admin.internal.ClustersImpl.createCluster(ClustersImpl.java:102) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at org.apache.pulsar.PulsarStandalone.createSampleNameSpace(PulsarStandalone.java:401) ~[org.apache.pulsar-pulsar-broker-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at org.apache.pulsar.PulsarStandalone.start(PulsarStandalone.java:320) ~[org.apache.pulsar-pulsar-broker-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at org.apache.pulsar.PulsarStandaloneStarter.main(PulsarStandaloneStarter.java:143) ~[org.apache.pulsar-pulsar-broker-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 Suppressed: org.apache.pulsar.client.admin.PulsarAdminException$ConflictException: Cluster already exists 2023-09-07 15:26:26 at org.apache.pulsar.client.admin.internal.BaseResource.getApiException(BaseResource.java:238) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at org.apache.pulsar.client.admin.internal.BaseResource$1.failed(BaseResource.java:134) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at org.glassfish.jersey.client.JerseyInvocation$1.failed(JerseyInvocation.java:882) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.JerseyInvocation$1.completed(JerseyInvocation.java:863) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.ClientRuntime.processResponse(ClientRuntime.java:229) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.ClientRuntime.access$200(ClientRuntime.java:62) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.ClientRuntime$2.lambda$response$0(ClientRuntime.java:173) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors.process(Errors.java:292) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors.process(Errors.java:274) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors.process(Errors.java:244) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:288) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.ClientRuntime$2.response(ClientRuntime.java:173) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$apply$1(AsyncHttpConnector.java:247) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:2073) ~[?:?] 2023-09-07 15:26:26 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$retryOperation$4(AsyncHttpConnector.java:289) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:2073) ~[?:?] 2023-09-07 15:26:26 at org.asynchttpclient.netty.NettyResponseFuture.loadContent(NettyResponseFuture.java:222) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 15:26:26 at org.asynchttpclient.netty.NettyResponseFuture.done(NettyResponseFuture.java:257) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 15:26:26 at org.asynchttpclient.netty.handler.AsyncHttpClientHandler.finishUpdate(AsyncHttpClientHandler.java:241) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 15:26:26 at org.asynchttpclient.netty.handler.HttpHandler.handleChunk(HttpHandler.java:114) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 15:26:26 at org.asynchttpclient.netty.handler.HttpHandler.handleRead(HttpHandler.java:143) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 15:26:26 at org.asynchttpclient.netty.handler.AsyncHttpClientHandler.channelRead(AsyncHttpClientHandler.java:78) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) ~[io.netty-netty-codec-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:346) ~[io.netty-netty-codec-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:318) ~[io.netty-netty-codec-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:442) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:440) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:166) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:788) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at java.lang.Thread.run(Thread.java:829) ~[?:?] 2023-09-07 15:26:26 Caused by: javax.ws.rs.ClientErrorException: HTTP 409 Cluster already exists 2023-09-07 15:26:26 at org.glassfish.jersey.client.JerseyInvocation.createExceptionForFamily(JerseyInvocation.java:985) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.JerseyInvocation.convertToException(JerseyInvocation.java:967) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.JerseyInvocation.access$700(JerseyInvocation.java:82) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 ... 54 more 2023-09-07 15:26:26 Caused by: javax.ws.rs.ClientErrorException: HTTP 409 Cluster already exists 2023-09-07 15:26:26 at org.glassfish.jersey.client.JerseyInvocation.createExceptionForFamily(JerseyInvocation.java:985) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.JerseyInvocation.convertToException(JerseyInvocation.java:967) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.JerseyInvocation.access$700(JerseyInvocation.java:82) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.JerseyInvocation$1.completed(JerseyInvocation.java:863) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.ClientRuntime.processResponse(ClientRuntime.java:229) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.ClientRuntime.access$200(ClientRuntime.java:62) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.ClientRuntime$2.lambda$response$0(ClientRuntime.java:173) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors.process(Errors.java:292) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors.process(Errors.java:274) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.internal.Errors.process(Errors.java:244) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:288) ~[org.glassfish.jersey.core-jersey-common-2.34.jar:?] 2023-09-07 15:26:26 at org.glassfish.jersey.client.ClientRuntime$2.response(ClientRuntime.java:173) ~[org.glassfish.jersey.core-jersey-client-2.34.jar:?] 2023-09-07 15:26:26 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$apply$1(AsyncHttpConnector.java:247) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:2073) ~[?:?] 2023-09-07 15:26:26 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$retryOperation$4(AsyncHttpConnector.java:289) ~[org.apache.pulsar-pulsar-client-admin-original-2.10.4.jar:2.10.4] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) ~[?:?] 2023-09-07 15:26:26 at java.util.concurrent.CompletableFuture.complete(CompletableFuture.java:2073) ~[?:?] 2023-09-07 15:26:26 at org.asynchttpclient.netty.NettyResponseFuture.loadContent(NettyResponseFuture.java:222) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 15:26:26 at org.asynchttpclient.netty.NettyResponseFuture.done(NettyResponseFuture.java:257) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 15:26:26 at org.asynchttpclient.netty.handler.AsyncHttpClientHandler.finishUpdate(AsyncHttpClientHandler.java:241) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 15:26:26 at org.asynchttpclient.netty.handler.HttpHandler.handleChunk(HttpHandler.java:114) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 15:26:26 at org.asynchttpclient.netty.handler.HttpHandler.handleRead(HttpHandler.java:143) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 15:26:26 at org.asynchttpclient.netty.handler.AsyncHttpClientHandler.channelRead(AsyncHttpClientHandler.java:78) ~[org.asynchttpclient-async-http-client-2.12.1.jar:?] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) ~[io.netty-netty-codec-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:346) ~[io.netty-netty-codec-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:318) ~[io.netty-netty-codec-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:442) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:440) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:166) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:788) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562) ~[io.netty-netty-transport-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:26:26 at java.lang.Thread.run(Thread.java:829) ~[?:?] 2023-09-07 15:26:36 Apache Pulsar: ready 2023-09-07 15:26:36 s6-rc: info: service pulsar successfully started 2023-09-07 15:26:36 s6-rc: info: service result-cache: starting 2023-09-07 15:26:36 s6-rc: info: service metadata-api: starting 2023-09-07 15:26:36 s6-rc: info: service cache-garbage-collector: starting 2023-09-07 15:26:36 s6-rc: info: service auth-service: starting 2023-09-07 15:26:36 s6-rc: info: service cache-garbage-collector successfully started 2023-09-07 15:26:38 WARNING: An illegal reflective access operation has occurred 2023-09-07 15:26:38 WARNING: Illegal reflective access by org.apache.pulsar.common.util.netty.DnsResolverUtil (file:/opt/apache-pulsar/lib/org.apache.pulsar-pulsar-common-2.10.4.jar) to method sun.net.InetAddressCachePolicy.get() 2023-09-07 15:26:38 WARNING: Please consider reporting this to the maintainers of org.apache.pulsar.common.util.netty.DnsResolverUtil 2023-09-07 15:26:38 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations 2023-09-07 15:26:38 WARNING: All illegal access operations will be denied in a future release 2023-09-07 15:26:38 2023-09-07T14:26:38,300+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ConnectionPool - [[id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650]] Connected to server 2023-09-07 15:26:38 2023-09-07T14:26:38,611+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerStatsRecorderImpl - Starting Pulsar producer perf with config: {"topicName":"persistent://public/default/caches.garbage-collect","producerName":null,"sendTimeoutMs":30000,"blockIfQueueFull":false,"maxPendingMessages":1000,"maxPendingMessagesAcrossPartitions":50000,"messageRoutingMode":"RoundRobinPartition","hashingScheme":"JavaStringHash","cryptoFailureAction":"FAIL","batchingMaxPublishDelayMicros":1000,"batchingPartitionSwitchFrequencyByPublishDelay":10,"batchingMaxMessages":1000,"batchingMaxBytes":131072,"batchingEnabled":true,"chunkingEnabled":false,"compressionType":"NONE","initialSequenceId":null,"autoUpdatePartitions":true,"autoUpdatePartitionsIntervalSeconds":60,"multiSchema":true,"accessMode":"Shared","lazyStartPartitionedProducers":false,"properties":{},"initialSubscriptionName":null} 2023-09-07 15:26:38 2023-09-07T14:26:38,637+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerStatsRecorderImpl - Pulsar client config: {"serviceUrl":"pulsar://localhost:6650","authPluginClassName":null,"authParams":null,"authParamMap":null,"operationTimeoutMs":30000,"lookupTimeoutMs":30000,"statsIntervalSeconds":60,"numIoThreads":1,"numListenerThreads":1,"connectionsPerBroker":1,"useTcpNoDelay":true,"useTls":false,"tlsTrustCertsFilePath":"","tlsAllowInsecureConnection":false,"tlsHostnameVerificationEnable":false,"concurrentLookupRequest":5000,"maxLookupRequest":50000,"maxLookupRedirects":20,"maxNumberOfRejectedRequestPerConnection":50,"keepAliveIntervalSeconds":30,"connectionTimeoutMs":10000,"requestTimeoutMs":60000,"initialBackoffIntervalNanos":100000000,"maxBackoffIntervalNanos":60000000000,"enableBusyWait":false,"listenerName":null,"useKeyStoreTls":false,"sslProvider":null,"tlsTrustStoreType":"JKS","tlsTrustStorePath":"","tlsTrustStorePassword":"*****","tlsCiphers":[],"tlsProtocols":[],"memoryLimitBytes":0,"proxyServiceUrl":null,"proxyProtocol":null,"enableTransaction":false,"dnsLookupBindAddress":null,"dnsLookupBindPort":0,"socks5ProxyAddress":null,"socks5ProxyUsername":null,"socks5ProxyPassword":null} 2023-09-07 15:26:38 2023-09-07T14:26:38,667+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Creating producer on cnx [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] 2023-09-07 15:26:38 2023-09-07T14:26:38,681+0000 [pulsar-io-18-1] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:38 2023-09-07T14:26:38,689+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:38 2023-09-07T14:26:38,691+0000 [pulsar-client-io-1-1] ERROR org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Failed to create producer: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797527995, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} 2023-09-07 15:26:38 2023-09-07T14:26:38,692+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797527995, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} -- Will try again in 0.1 s 2023-09-07 15:26:38 2023-09-07T14:26:38,796+0000 [pulsar-timer-6-1] INFO org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Reconnecting after connection was closed 2023-09-07 15:26:38 2023-09-07T14:26:38,805+0000 [pulsar-timer-6-1] INFO org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Creating producer on cnx [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] 2023-09-07 15:26:38 2023-09-07T14:26:38,813+0000 [pulsar-io-18-1] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:38 2023-09-07T14:26:38,814+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:38 2023-09-07T14:26:38,814+0000 [pulsar-client-io-1-1] ERROR org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Failed to create producer: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797527997, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} 2023-09-07 15:26:38 2023-09-07T14:26:38,815+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797527997, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} -- Will try again in 0.183 s 2023-09-07 15:26:38 ______ _____ _____ _ _ _ _____ _____ ___ _____ _ _ _____ 2023-09-07 15:26:38 | ___ \| ___|/ ___|| | | || | |_ _| / __ \ / _ \ / __ \| | | || ___| 2023-09-07 15:26:38 | |_/ /| |__ \ `--. | | | || | | | | / \// /_\ \| / \/| |_| || |__ 2023-09-07 15:26:38 | / | __| `--. \| | | || | | | | | | _ || | | _ || __| 2023-09-07 15:26:38 | |\ \ | |___ /\__/ /| |_| || |____ | | | \__/\| | | || \__/\| | | || |___ 2023-09-07 15:26:38 \_| \_|\____/ \____/ \___/ \_____/ \_/ \____/\_| |_/ \____/\_| |_/\____/ 2023-09-07 15:26:38 :: Spring Boot (v2.6.15) :: HTTP port = 9040/9041 :: PID: 544 2023-09-07 15:26:38 :: Build from: 72bfbc1 :: 2023-07-24 11:46:23+0000 2023-09-07 15:26:38 2023-09-07 15:26:38 __ __ __ ____ _ _ ___ ____ ____ _ _ ____ ___ ____ 2023-09-07 15:26:38 /__\ ( )( )(_ _)( )_( ) / __)( ___)( _ \( \/ )(_ _) / __)( ___) 2023-09-07 15:26:38 /(__)\ )(__)( )( ) _ ( \__ \ )__) ) / \ / _)(_ ( (__ )__) 2023-09-07 15:26:38 (__)(__)(______) (__) (_) (_) (___/(____)(_)\_) \/ (____) \___)(____) 2023-09-07 15:26:38 :: Spring Boot (v2.6.15) :: HTTP port = 9050/9051 :: PID: 550 2023-09-07 15:26:38 :: Build from: 72bfbc1 :: 2023-07-24 11:46:23+0000 2023-09-07 15:26:38 2023-09-07 15:26:38 2023-09-07 15:26:38 2023-09-07T14:26:38,999+0000 [pulsar-timer-6-1] INFO org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Reconnecting after connection was closed 2023-09-07 15:26:39 2023-09-07T14:26:39,001+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Creating producer on cnx [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] 2023-09-07 15:26:39 2023-09-07T14:26:39,012+0000 [pulsar-io-18-1] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:39 2023-09-07T14:26:39,013+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:39 2023-09-07T14:26:39,014+0000 [pulsar-client-io-1-1] ERROR org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Failed to create producer: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797527999, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} 2023-09-07 15:26:39 2023-09-07T14:26:39,014+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797527999, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} -- Will try again in 0.38 s 2023-09-07 15:26:39 ______ ____________________________ ________ _______ _______________ 2023-09-07 15:26:39 ___ |/ /___ ____/___ __/___ |___ __ \___ |___ __/___ | 2023-09-07 15:26:39 __ /|_/ / __ __/ __ / __ /| |__ / / /__ /| |__ / __ /| | 2023-09-07 15:26:39 _ / / / _ /___ _ / _ ___ |_ /_/ / _ ___ |_ / _ ___ | 2023-09-07 15:26:39 /_/ /_/ /_____/ /_/ /_/ |_|/_____/ /_/ |_|/_/ /_/ |_| 2023-09-07 15:26:39 2023-09-07 15:26:39 :: Spring Boot (v2.6.15) :: HTTP port = 9007/9008 :: PID: 547 2023-09-07 15:26:39 :: Build from: 72bfbc1 :: 2023-07-24 11:46:23+0000 2023-09-07 15:26:39 2023-09-07 15:26:39 2023-09-07 15:26:39 2023-09-07T14:26:39,395+0000 [pulsar-timer-6-1] INFO org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Reconnecting after connection was closed 2023-09-07 15:26:39 2023-09-07T14:26:39,396+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Creating producer on cnx [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] 2023-09-07 15:26:39 2023-09-07T14:26:39,418+0000 [pulsar-io-18-1] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:39 2023-09-07T14:26:39,419+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:39 2023-09-07T14:26:39,419+0000 [pulsar-client-io-1-1] ERROR org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Failed to create producer: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797528001, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} 2023-09-07 15:26:39 2023-09-07T14:26:39,419+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797528001, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} -- Will try again in 0.77 s 2023-09-07 15:26:40 2023-09-07T14:26:40,190+0000 [pulsar-timer-6-1] INFO org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Reconnecting after connection was closed 2023-09-07 15:26:40 2023-09-07T14:26:40,193+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Creating producer on cnx [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] 2023-09-07 15:26:40 2023-09-07T14:26:40,198+0000 [pulsar-io-18-1] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:40 2023-09-07T14:26:40,199+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:40 2023-09-07T14:26:40,224+0000 [pulsar-client-io-1-1] ERROR org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Failed to create producer: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797528003, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} 2023-09-07 15:26:40 2023-09-07T14:26:40,225+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797528003, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} -- Will try again in 1.584 s 2023-09-07 15:26:41 2023-09-07T14:26:41,811+0000 [pulsar-timer-6-1] INFO org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Reconnecting after connection was closed 2023-09-07 15:26:41 2023-09-07T14:26:41,813+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Creating producer on cnx [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] 2023-09-07 15:26:41 2023-09-07T14:26:41,823+0000 [pulsar-io-18-1] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:41 2023-09-07T14:26:41,826+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:41 2023-09-07T14:26:41,827+0000 [pulsar-client-io-1-1] ERROR org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Failed to create producer: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797528005, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} 2023-09-07 15:26:41 2023-09-07T14:26:41,827+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797528005, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} -- Will try again in 3.198 s 2023-09-07 15:26:42 Metadata API: not ready 2023-09-07 15:26:42 Result Cache: not ready 2023-09-07 15:26:42 Auth. service: not ready 2023-09-07 15:26:42 WARNING: An illegal reflective access operation has occurred 2023-09-07 15:26:42 WARNING: Illegal reflective access by org.apache.pulsar.common.util.netty.DnsResolverUtil (file:/opt/result-cache/BOOT-INF/lib/pulsar-common-2.10.4.jar) to method sun.net.InetAddressCachePolicy.get() 2023-09-07 15:26:42 WARNING: Please consider reporting this to the maintainers of org.apache.pulsar.common.util.netty.DnsResolverUtil 2023-09-07 15:26:42 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations 2023-09-07 15:26:42 WARNING: All illegal access operations will be denied in a future release 2023-09-07 15:26:42 WARNING: An illegal reflective access operation has occurred 2023-09-07 15:26:42 WARNING: Illegal reflective access by org.apache.pulsar.common.util.netty.DnsResolverUtil (file:/opt/auth-service/BOOT-INF/lib/pulsar-common-2.10.4.jar) to method sun.net.InetAddressCachePolicy.get() 2023-09-07 15:26:42 WARNING: Please consider reporting this to the maintainers of org.apache.pulsar.common.util.netty.DnsResolverUtil 2023-09-07 15:26:42 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations 2023-09-07 15:26:42 WARNING: All illegal access operations will be denied in a future release 2023-09-07 15:26:44 2023-09-07T14:26:44,546+0000 [metadata-store-23-1] WARN org.apache.bookkeeper.proto.checksum.CRC32CDigestManager - Sse42Crc32C is not supported, will use a slower CRC32C implementation. 2023-09-07 15:26:44 2023-09-07T14:26:44,576+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:44 2023-09-07T14:26:44,587+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.ServerCnx - [/127.0.0.1:55146][persistent://public/default/__change_events][reader-838e1e079e] Failed to create consumer: consumerId=0, Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:44 2023-09-07T14:26:44,594+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x08bd4aa6, L:/127.0.0.1:55146 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:44 2023-09-07T14:26:44,595+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConsumerImpl - [persistent://public/default/__change_events][reader-838e1e079e] Failed to subscribe to topic on localhost/127.0.0.1:6650 2023-09-07 15:26:44 2023-09-07T14:26:44,596+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/__change_events] [reader-838e1e079e] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1565991639725088316, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:55146"} -- Will try again in 0.1 s 2023-09-07 15:26:44 2023-09-07T14:26:44,634+0000 [pulsar-16-1] WARN org.apache.pulsar.client.util.RetryUtil - Execution with retry fail, because of Topic policies cache have not init., will retry in 500 ms 2023-09-07 15:26:44 2023-09-07T14:26:44,642+0000 [pulsar-16-4] WARN org.apache.pulsar.client.util.RetryUtil - Execution with retry fail, because of Topic policies cache have not init., will retry in 500 ms 2023-09-07 15:26:44 2023-09-07T14:26:44,714+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:44 2023-09-07T14:26:44,715+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.ServerCnx - [/127.0.0.1:55146][persistent://public/default/__change_events][reader-838e1e079e] Failed to create consumer: consumerId=0, Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:44 2023-09-07T14:26:44,715+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x08bd4aa6, L:/127.0.0.1:55146 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:44 2023-09-07T14:26:44,715+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConsumerImpl - [persistent://public/default/__change_events][reader-838e1e079e] Failed to subscribe to topic on localhost/127.0.0.1:6650 2023-09-07 15:26:44 WARNING: An illegal reflective access operation has occurred 2023-09-07 15:26:44 WARNING: Illegal reflective access by org.apache.pulsar.common.util.netty.DnsResolverUtil (file:/opt/metadata-api/BOOT-INF/lib/pulsar-common-2.10.4.jar) to method sun.net.InetAddressCachePolicy.get() 2023-09-07 15:26:44 WARNING: Please consider reporting this to the maintainers of org.apache.pulsar.common.util.netty.DnsResolverUtil 2023-09-07 15:26:44 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations 2023-09-07 15:26:44 WARNING: All illegal access operations will be denied in a future release2023-09-07T14:26:44,715+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/__change_events] [reader-838e1e079e] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1565991639725088318, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:55146"} -- Will try again in 0.187 s 2023-09-07 15:26:44 2023-09-07 15:26:44 2023-09-07T14:26:44,906+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:44 2023-09-07T14:26:44,907+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.ServerCnx - [/127.0.0.1:55146][persistent://public/default/__change_events][reader-838e1e079e] Failed to create consumer: consumerId=0, Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:44 2023-09-07T14:26:44,908+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x08bd4aa6, L:/127.0.0.1:55146 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:44 2023-09-07T14:26:44,908+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConsumerImpl - [persistent://public/default/__change_events][reader-838e1e079e] Failed to subscribe to topic on localhost/127.0.0.1:6650 2023-09-07 15:26:44 2023-09-07T14:26:44,908+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/__change_events] [reader-838e1e079e] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1565991639725088320, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:55146"} -- Will try again in 0.383 s 2023-09-07 15:26:45 2023-09-07T14:26:45,027+0000 [pulsar-timer-6-1] INFO org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Reconnecting after connection was closed 2023-09-07 15:26:45 2023-09-07T14:26:45,029+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Creating producer on cnx [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] 2023-09-07 15:26:45 2023-09-07T14:26:45,029+0000 [pulsar-io-18-1] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:45 2023-09-07T14:26:45,030+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:45 2023-09-07T14:26:45,030+0000 [pulsar-client-io-1-1] ERROR org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Failed to create producer: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797528007, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} 2023-09-07 15:26:45 2023-09-07T14:26:45,030+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797528007, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} -- Will try again in 6.194 s 2023-09-07 15:26:45 2023-09-07T14:26:45,135+0000 [pulsar-16-3] WARN org.apache.pulsar.client.util.RetryUtil - Execution with retry fail, because of Topic policies cache have not init., will retry in 963 ms 2023-09-07 15:26:45 2023-09-07T14:26:45,142+0000 [pulsar-16-2] WARN org.apache.pulsar.client.util.RetryUtil - Execution with retry fail, because of Topic policies cache have not init., will retry in 927 ms 2023-09-07 15:26:45 2023-09-07T14:26:45,295+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:45 2023-09-07T14:26:45,295+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.ServerCnx - [/127.0.0.1:55146][persistent://public/default/__change_events][reader-838e1e079e] Failed to create consumer: consumerId=0, Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:45 2023-09-07T14:26:45,296+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x08bd4aa6, L:/127.0.0.1:55146 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:45 2023-09-07T14:26:45,296+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConsumerImpl - [persistent://public/default/__change_events][reader-838e1e079e] Failed to subscribe to topic on localhost/127.0.0.1:6650 2023-09-07 15:26:45 2023-09-07T14:26:45,296+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/__change_events] [reader-838e1e079e] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1565991639725088322, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:55146"} -- Will try again in 0.765 s 2023-09-07 15:26:45 ts="2023-09-07 14:26:45.448" level=WARN msg="[http://localhost:8080/admin/v2/persistent/public/default/data-source.change] Failed to perform http put request: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5" logger=org.apache.pulsar.client.admin.internal.BaseResource thread=AsyncHttpClient-12-1 2023-09-07 15:26:45 ts="2023-09-07 14:26:45.478" level=ERROR msg="Application run failed" logger=org.springframework.boot.SpringApplication thread=main exc="org.apache.pulsar.client.admin.PulsarAdminException: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:45 at org.apache.pulsar.client.admin.PulsarAdminException.wrap(PulsarAdminException.java:252) 2023-09-07 15:26:45 at org.apache.pulsar.client.admin.internal.BaseResource.sync(BaseResource.java:302) 2023-09-07 15:26:45 at org.apache.pulsar.client.admin.internal.TopicsImpl.createNonPartitionedTopic(TopicsImpl.java:340) 2023-09-07 15:26:45 at org.apache.pulsar.client.admin.Topics.createNonPartitionedTopic(Topics.java:482) 2023-09-07 15:26:45 at com.gooddata.tiger.pulsar.PulsarAutoConfiguration.producerBeanFactory$lambda-1(PulsarAutoConfiguration.kt:129) 2023-09-07 15:26:45 at org.springframework.context.support.PostProcessorRegistrationDelegate.invokeBeanFactoryPostProcessors(PostProcessorRegistrationDelegate.java:325) 2023-09-07 15:26:45 at org.springframework.context.support.PostProcessorRegistrationDelegate.invokeBeanFactoryPostProcessors(PostProcessorRegistrationDelegate.java:198) 2023-09-07 15:26:45 at org.springframework.context.support.AbstractApplicationContext.invokeBeanFactoryPostProcessors(AbstractApplicationContext.java:748) 2023-09-07 15:26:45 at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:564) 2023-09-07 15:26:45 at org.springframework.boot.web.reactive.context.ReactiveWebServerApplicationContext.refresh(ReactiveWebServerApplicationContext.java:64) 2023-09-07 15:26:45 at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:745) 2023-09-07 15:26:45 at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:423) 2023-09-07 15:26:45 at org.springframework.boot.SpringApplication.run(SpringApplication.java:307) 2023-09-07 15:26:45 at org.springframework.boot.SpringApplication.run(SpringApplication.java:1317) 2023-09-07 15:26:45 at org.springframework.boot.SpringApplication.run(SpringApplication.java:1306) 2023-09-07 15:26:45 at com.gooddata.tiger.metadata.MetadataApplicationKt.main(MetadataApplication.kt:175) 2023-09-07 15:26:45 at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 2023-09-07 15:26:45 at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 2023-09-07 15:26:45 at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) 2023-09-07 15:26:45 at java.base/java.lang.reflect.Method.invoke(Method.java:566) 2023-09-07 15:26:45 at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:49) 2023-09-07 15:26:45 at org.springframework.boot.loader.Launcher.launch(Launcher.java:108) 2023-09-07 15:26:45 at org.springframework.boot.loader.Launcher.launch(Launcher.java:58) 2023-09-07 15:26:45 at org.springframework.boot.loader.JarLauncher.main(JarLauncher.java:88) 2023-09-07 15:26:45 Suppressed: org.apache.pulsar.client.admin.PulsarAdminException: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:45 at org.apache.pulsar.client.admin.internal.BaseResource.getApiException(BaseResource.java:251) 2023-09-07 15:26:45 at org.apache.pulsar.client.admin.internal.BaseResource$1.failed(BaseResource.java:134) 2023-09-07 15:26:45 at org.glassfish.jersey.client.JerseyInvocation$1.failed(JerseyInvocation.java:882) 2023-09-07 15:26:45 at org.glassfish.jersey.client.ClientRuntime.processFailure(ClientRuntime.java:247) 2023-09-07 15:26:45 at org.glassfish.jersey.client.ClientRuntime.processFailure(ClientRuntime.java:242) 2023-09-07 15:26:45 at org.glassfish.jersey.client.ClientRuntime.access$100(ClientRuntime.java:62) 2023-09-07 15:26:45 at org.glassfish.jersey.client.ClientRuntime$2.lambda$failure$1(ClientRuntime.java:178) 2023-09-07 15:26:45 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248) 2023-09-07 15:26:45 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244) 2023-09-07 15:26:45 at org.glassfish.jersey.internal.Errors.process(Errors.java:292) 2023-09-07 15:26:45 at org.glassfish.jersey.internal.Errors.process(Errors.java:274) 2023-09-07 15:26:45 at org.glassfish.jersey.internal.Errors.process(Errors.java:244) 2023-09-07 15:26:45 at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:288) 2023-09-07 15:26:45 at org.glassfish.jersey.client.ClientRuntime$2.failure(ClientRuntime.java:178) 2023-09-07 15:26:45 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$apply$1(AsyncHttpConnector.java:223) 2023-09-07 15:26:45 at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) 2023-09-07 15:26:45 at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) 2023-09-07 15:26:45 at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) 2023-09-07 15:26:45 at java.base/java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2088) 2023-09-07 15:26:45 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$retryOperation$4(AsyncHttpConnector.java:282) 2023-09-07 15:26:45 at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) 2023-09-07 15:26:45 at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) 2023-09-07 15:26:45 at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) 2023-09-07 15:26:45 at java.base/java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2088) 2023-09-07 15:26:45 at org.asynchttpclient.netty.NettyResponseFuture.abort(NettyResponseFuture.java:273) 2023-09-07 15:26:45 at org.asynchttpclient.netty.request.NettyRequestSender.abort(NettyRequestSender.java:473) 2023-09-07 15:26:45 at org.asynchttpclient.netty.handler.HttpHandler.readFailed(HttpHandler.java:161) 2023-09-07 15:26:45 at org.asynchttpclient.netty.handler.HttpHandler.handleRead(HttpHandler.java:154) 2023-09-07 15:26:45 at org.asynchttpclient.netty.handler.AsyncHttpClientHandler.channelRead(AsyncHttpClientHandler.java:78) 2023-09-07 15:26:45 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) 2023-09-07 15:26:45 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:45 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) 2023-09-07 15:26:45 at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) 2023-09-07 15:26:45 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) 2023-09-07 15:26:45 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:45 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) 2023-09-07 15:26:45 at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436) 2023-09-07 15:26:45 at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:346) 2023-09-07 15:26:45 at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:318) 2023-09-07 15:26:45 at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251) 2023-09-07 15:26:45 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:442) 2023-09-07 15:26:45 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:45 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) 2023-09-07 15:26:45 at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410) 2023-09-07 15:26:45 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:440) 2023-09-07 15:26:45 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:45 at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919) 2023-09-07 15:26:45 at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:166) 2023-09-07 15:26:45 at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:788) 2023-09-07 15:26:45 at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724) 2023-09-07 15:26:45 at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650) 2023-09-07 15:26:45 at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562) 2023-09-07 15:26:45 at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) 2023-09-07 15:26:45 at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) 2023-09-07 15:26:45 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) 2023-09-07 15:26:45 at java.base/java.lang.Thread.run(Thread.java:829) 2023-09-07 15:26:45 Caused by: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:45 at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331) 2023-09-07 15:26:45 at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346) 2023-09-07 15:26:45 at java.base/java.util.concurrent.CompletableFuture$OrApply.tryFire(CompletableFuture.java:1503) 2023-09-07 15:26:45 ... 39 more 2023-09-07 15:26:45 Caused by: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:45 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$retryOperation$4(AsyncHttpConnector.java:284) 2023-09-07 15:26:45 ... 36 more 2023-09-07 15:26:45 Caused by: org.asynchttpclient.handler.MaxRedirectException: Maximum redirect reached: 5 2023-09-07 15:26:45 Caused by: [CIRCULAR REFERENCE: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5] 2023-09-07 15:26:45 " 2023-09-07 15:26:45 Metadata API: not ready 2023-09-07 15:26:45 Auth. service: not ready 2023-09-07 15:26:45 Result Cache: not ready 2023-09-07 15:26:46 2023-09-07T14:26:46,068+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:46 2023-09-07T14:26:46,069+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.ServerCnx - [/127.0.0.1:55146][persistent://public/default/__change_events][reader-838e1e079e] Failed to create consumer: consumerId=0, Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:46 2023-09-07T14:26:46,071+0000 [pulsar-16-2] WARN org.apache.pulsar.client.util.RetryUtil - Execution with retry fail, because of Topic policies cache have not init., will retry in 1948 ms 2023-09-07 15:26:46 2023-09-07T14:26:46,074+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x08bd4aa6, L:/127.0.0.1:55146 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:46 2023-09-07T14:26:46,074+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConsumerImpl - [persistent://public/default/__change_events][reader-838e1e079e] Failed to subscribe to topic on localhost/127.0.0.1:6650 2023-09-07 15:26:46 2023-09-07T14:26:46,074+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/__change_events] [reader-838e1e079e] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1565991639725088324, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:55146"} -- Will try again in 1.536 s 2023-09-07 15:26:46 2023-09-07T14:26:46,100+0000 [pulsar-16-1] WARN org.apache.pulsar.client.util.RetryUtil - Execution with retry fail, because of Topic policies cache have not init., will retry in 1995 ms 2023-09-07 15:26:47 Exception in thread "main" java.lang.reflect.InvocationTargetException 2023-09-07 15:26:47 at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 2023-09-07 15:26:47 at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 2023-09-07 15:26:47 at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) 2023-09-07 15:26:47 at java.base/java.lang.reflect.Method.invoke(Method.java:566) 2023-09-07 15:26:47 at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:49) 2023-09-07 15:26:47 at org.springframework.boot.loader.Launcher.launch(Launcher.java:108) 2023-09-07 15:26:47 at org.springframework.boot.loader.Launcher.launch(Launcher.java:58) 2023-09-07 15:26:47 at org.springframework.boot.loader.JarLauncher.main(JarLauncher.java:88) 2023-09-07 15:26:47 Caused by: java.lang.reflect.UndeclaredThrowableException 2023-09-07 15:26:47 at org.springframework.util.ReflectionUtils.rethrowRuntimeException(ReflectionUtils.java:147) 2023-09-07 15:26:47 at org.springframework.boot.SpringApplication.handleRunFailure(SpringApplication.java:809) 2023-09-07 15:26:47 at org.springframework.boot.SpringApplication.run(SpringApplication.java:317) 2023-09-07 15:26:47 at org.springframework.boot.SpringApplication.run(SpringApplication.java:1317) 2023-09-07 15:26:47 at org.springframework.boot.SpringApplication.run(SpringApplication.java:1306) 2023-09-07 15:26:47 at com.gooddata.tiger.metadata.MetadataApplicationKt.main(MetadataApplication.kt:175) 2023-09-07 15:26:47 ... 8 more 2023-09-07 15:26:47 Caused by: org.apache.pulsar.client.admin.PulsarAdminException: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:47 at org.apache.pulsar.client.admin.PulsarAdminException.wrap(PulsarAdminException.java:252) 2023-09-07 15:26:47 at org.apache.pulsar.client.admin.internal.BaseResource.sync(BaseResource.java:302) 2023-09-07 15:26:47 at org.apache.pulsar.client.admin.internal.TopicsImpl.createNonPartitionedTopic(TopicsImpl.java:340) 2023-09-07 15:26:47 at org.apache.pulsar.client.admin.Topics.createNonPartitionedTopic(Topics.java:482) 2023-09-07 15:26:47 at com.gooddata.tiger.pulsar.PulsarAutoConfiguration.producerBeanFactory$lambda-1(PulsarAutoConfiguration.kt:129) 2023-09-07 15:26:47 at org.springframework.context.support.PostProcessorRegistrationDelegate.invokeBeanFactoryPostProcessors(PostProcessorRegistrationDelegate.java:325) 2023-09-07 15:26:47 at org.springframework.context.support.PostProcessorRegistrationDelegate.invokeBeanFactoryPostProcessors(PostProcessorRegistrationDelegate.java:198) 2023-09-07 15:26:47 at org.springframework.context.support.AbstractApplicationContext.invokeBeanFactoryPostProcessors(AbstractApplicationContext.java:748) 2023-09-07 15:26:47 at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:564) 2023-09-07 15:26:47 at org.springframework.boot.web.reactive.context.ReactiveWebServerApplicationContext.refresh(ReactiveWebServerApplicationContext.java:64) 2023-09-07 15:26:47 at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:745) 2023-09-07 15:26:47 at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:423) 2023-09-07 15:26:47 at org.springframework.boot.SpringApplication.run(SpringApplication.java:307) 2023-09-07 15:26:47 ... 11 more 2023-09-07 15:26:47 Suppressed: org.apache.pulsar.client.admin.PulsarAdminException: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:47 at org.apache.pulsar.client.admin.internal.BaseResource.getApiException(BaseResource.java:251) 2023-09-07 15:26:47 at org.apache.pulsar.client.admin.internal.BaseResource$1.failed(BaseResource.java:134) 2023-09-07 15:26:47 at org.glassfish.jersey.client.JerseyInvocation$1.failed(JerseyInvocation.java:882) 2023-09-07 15:26:47 at org.glassfish.jersey.client.ClientRuntime.processFailure(ClientRuntime.java:247) 2023-09-07 15:26:47 at org.glassfish.jersey.client.ClientRuntime.processFailure(ClientRuntime.java:242) 2023-09-07 15:26:47 at org.glassfish.jersey.client.ClientRuntime.access$100(ClientRuntime.java:62) 2023-09-07 15:26:47 at org.glassfish.jersey.client.ClientRuntime$2.lambda$failure$1(ClientRuntime.java:178) 2023-09-07 15:26:47 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248) 2023-09-07 15:26:47 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244) 2023-09-07 15:26:47 at org.glassfish.jersey.internal.Errors.process(Errors.java:292) 2023-09-07 15:26:47 at org.glassfish.jersey.internal.Errors.process(Errors.java:274) 2023-09-07 15:26:47 at org.glassfish.jersey.internal.Errors.process(Errors.java:244) 2023-09-07 15:26:47 at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:288) 2023-09-07 15:26:47 at org.glassfish.jersey.client.ClientRuntime$2.failure(ClientRuntime.java:178) 2023-09-07 15:26:47 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$apply$1(AsyncHttpConnector.java:223) 2023-09-07 15:26:47 at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) 2023-09-07 15:26:47 at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) 2023-09-07 15:26:47 at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) 2023-09-07 15:26:47 at java.base/java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2088) 2023-09-07 15:26:47 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$retryOperation$4(AsyncHttpConnector.java:282) 2023-09-07 15:26:47 at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) 2023-09-07 15:26:47 at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) 2023-09-07 15:26:47 at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) 2023-09-07 15:26:47 at java.base/java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2088) 2023-09-07 15:26:47 at org.asynchttpclient.netty.NettyResponseFuture.abort(NettyResponseFuture.java:273) 2023-09-07 15:26:47 at org.asynchttpclient.netty.request.NettyRequestSender.abort(NettyRequestSender.java:473) 2023-09-07 15:26:47 at org.asynchttpclient.netty.handler.HttpHandler.readFailed(HttpHandler.java:161) 2023-09-07 15:26:47 at org.asynchttpclient.netty.handler.HttpHandler.handleRead(HttpHandler.java:154) 2023-09-07 15:26:47 at org.asynchttpclient.netty.handler.AsyncHttpClientHandler.channelRead(AsyncHttpClientHandler.java:78) 2023-09-07 15:26:47 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) 2023-09-07 15:26:47 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:47 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) 2023-09-07 15:26:47 at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) 2023-09-07 15:26:47 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) 2023-09-07 15:26:47 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:47 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) 2023-09-07 15:26:47 at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436) 2023-09-07 15:26:47 at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:346) 2023-09-07 15:26:47 at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:318) 2023-09-07 15:26:47 at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251) 2023-09-07 15:26:47 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:442) 2023-09-07 15:26:47 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:47 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) 2023-09-07 15:26:47 at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410) 2023-09-07 15:26:47 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:440) 2023-09-07 15:26:47 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:47 at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919) 2023-09-07 15:26:47 at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:166) 2023-09-07 15:26:47 at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:788) 2023-09-07 15:26:47 at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724) 2023-09-07 15:26:47 at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650) 2023-09-07 15:26:47 at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562) 2023-09-07 15:26:47 at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) 2023-09-07 15:26:47 at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) 2023-09-07 15:26:47 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) 2023-09-07 15:26:47 at java.base/java.lang.Thread.run(Thread.java:829) 2023-09-07 15:26:47 Caused by: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:47 at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331) 2023-09-07 15:26:47 at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346) 2023-09-07 15:26:47 at java.base/java.util.concurrent.CompletableFuture$OrApply.tryFire(CompletableFuture.java:1503) 2023-09-07 15:26:47 ... 39 more 2023-09-07 15:26:47 Caused by: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:47 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$retryOperation$4(AsyncHttpConnector.java:284) 2023-09-07 15:26:47 ... 36 more 2023-09-07 15:26:47 Caused by: org.asynchttpclient.handler.MaxRedirectException: Maximum redirect reached: 5 2023-09-07 15:26:47 Caused by: [CIRCULAR REFERENCE: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5] 2023-09-07 15:26:47 2023-09-07T14:26:47,614+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:47 2023-09-07T14:26:47,615+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.ServerCnx - [/127.0.0.1:55146][persistent://public/default/__change_events][reader-838e1e079e] Failed to create consumer: consumerId=0, Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:47 2023-09-07T14:26:47,615+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x08bd4aa6, L:/127.0.0.1:55146 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:47 2023-09-07T14:26:47,615+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConsumerImpl - [persistent://public/default/__change_events][reader-838e1e079e] Failed to subscribe to topic on localhost/127.0.0.1:6650 2023-09-07 15:26:47 2023-09-07T14:26:47,616+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/__change_events] [reader-838e1e079e] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1565991639725088326, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:55146"} -- Will try again in 2.97 s 2023-09-07 15:26:48 2023-09-07T14:26:48,020+0000 [pulsar-16-2] WARN org.apache.pulsar.client.util.RetryUtil - Execution with retry fail, because of Topic policies cache have not init., will retry in 3932 ms 2023-09-07 15:26:48 2023-09-07T14:26:48,096+0000 [pulsar-16-3] WARN org.apache.pulsar.client.util.RetryUtil - Execution with retry fail, because of Topic policies cache have not init., will retry in 3788 ms 2023-09-07 15:26:48 Result Cache: not ready 2023-09-07 15:26:48 Metadata API: not ready 2023-09-07 15:26:48 Auth. service: not ready 2023-09-07 15:26:50 ______ ____________________________ ________ _______ _______________ 2023-09-07 15:26:50 ___ |/ /___ ____/___ __/___ |___ __ \___ |___ __/___ | 2023-09-07 15:26:50 __ /|_/ / __ __/ __ / __ /| |__ / / /__ /| |__ / __ /| | 2023-09-07 15:26:50 _ / / / _ /___ _ / _ ___ |_ /_/ / _ ___ |_ / _ ___ | 2023-09-07 15:26:50 /_/ /_/ /_____/ /_/ /_/ |_|/_____/ /_/ |_|/_/ /_/ |_| 2023-09-07 15:26:50 2023-09-07 15:26:50 :: Spring Boot (v2.6.15) :: HTTP port = 9007/9008 :: PID: 751 2023-09-07 15:26:50 :: Build from: 72bfbc1 :: 2023-07-24 11:46:23+0000 2023-09-07 15:26:50 2023-09-07 15:26:50 2023-09-07 15:26:50 2023-09-07T14:26:50,590+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:50 2023-09-07T14:26:50,590+0000 [pulsar-io-18-6] WARN org.apache.pulsar.broker.service.ServerCnx - [/127.0.0.1:55146][persistent://public/default/__change_events][reader-838e1e079e] Failed to create consumer: consumerId=0, Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:50 2023-09-07T14:26:50,591+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x08bd4aa6, L:/127.0.0.1:55146 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:50 2023-09-07T14:26:50,591+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConsumerImpl - [persistent://public/default/__change_events][reader-838e1e079e] Failed to subscribe to topic on localhost/127.0.0.1:6650 2023-09-07 15:26:50 2023-09-07T14:26:50,591+0000 [pulsar-io-18-5] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/__change_events] [reader-838e1e079e] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/__change_events) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1565991639725088328, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:55146"} -- Will try again in 6.232 s 2023-09-07 15:26:51 2023-09-07T14:26:51,225+0000 [pulsar-timer-6-1] INFO org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Reconnecting after connection was closed 2023-09-07 15:26:51 2023-09-07T14:26:51,226+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Creating producer on cnx [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] 2023-09-07 15:26:51 2023-09-07T14:26:51,228+0000 [pulsar-io-18-1] WARN org.apache.pulsar.broker.service.BrokerService - Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:51 2023-09-07T14:26:51,229+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ClientCnx - [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] Received error from server: Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default 2023-09-07 15:26:51 2023-09-07T14:26:51,230+0000 [pulsar-client-io-1-1] ERROR org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Failed to create producer: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797528009, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} 2023-09-07 15:26:51 2023-09-07T14:26:51,232+0000 [pulsar-client-io-1-1] WARN org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Could not get connection to broker: {"errorMsg":"Namespace bundle for topic (persistent://public/default/caches.garbage-collect) not served by this instance. Please redo the lookup. Request is denied: namespace=public/default","reqId":1725092762797528009, "remote":"localhost/127.0.0.1:6650", "local":"/127.0.0.1:33570"} -- Will try again in 11.766 s 2023-09-07 15:26:51 Result Cache: not ready 2023-09-07 15:26:51 Auth. service: not ready 2023-09-07 15:26:51 2023-09-07T14:26:51,885+0000 [pulsar-16-3] WARN org.apache.pulsar.client.util.RetryUtil - Execution with retry fail, because of Topic policies cache have not init., will retry in 7257 ms 2023-09-07 15:26:51 2023-09-07T14:26:51,953+0000 [pulsar-16-1] WARN org.apache.pulsar.client.util.RetryUtil - Execution with retry fail, because of Topic policies cache have not init., will retry in 7977 ms 2023-09-07 15:26:52 WARNING: An illegal reflective access operation has occurred 2023-09-07 15:26:52 WARNING: Illegal reflective access by org.apache.pulsar.common.util.netty.DnsResolverUtil (file:/opt/metadata-api/BOOT-INF/lib/pulsar-common-2.10.4.jar) to method sun.net.InetAddressCachePolicy.get() 2023-09-07 15:26:52 WARNING: Please consider reporting this to the maintainers of org.apache.pulsar.common.util.netty.DnsResolverUtil 2023-09-07 15:26:52 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations 2023-09-07 15:26:52 WARNING: All illegal access operations will be denied in a future release 2023-09-07 15:26:53 ts="2023-09-07 14:26:53.182" level=WARN msg="[http://localhost:8080/admin/v2/persistent/public/default/data-source.change] Failed to perform http put request: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5" logger=org.apache.pulsar.client.admin.internal.BaseResource thread=AsyncHttpClient-12-1 2023-09-07 15:26:53 ts="2023-09-07 14:26:53.202" level=ERROR msg="Application run failed" logger=org.springframework.boot.SpringApplication thread=main exc="org.apache.pulsar.client.admin.PulsarAdminException: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:53 at org.apache.pulsar.client.admin.PulsarAdminException.wrap(PulsarAdminException.java:252) 2023-09-07 15:26:53 at org.apache.pulsar.client.admin.internal.BaseResource.sync(BaseResource.java:302) 2023-09-07 15:26:53 at org.apache.pulsar.client.admin.internal.TopicsImpl.createNonPartitionedTopic(TopicsImpl.java:340) 2023-09-07 15:26:53 at org.apache.pulsar.client.admin.Topics.createNonPartitionedTopic(Topics.java:482) 2023-09-07 15:26:53 at com.gooddata.tiger.pulsar.PulsarAutoConfiguration.producerBeanFactory$lambda-1(PulsarAutoConfiguration.kt:129) 2023-09-07 15:26:53 at org.springframework.context.support.PostProcessorRegistrationDelegate.invokeBeanFactoryPostProcessors(PostProcessorRegistrationDelegate.java:325) 2023-09-07 15:26:53 at org.springframework.context.support.PostProcessorRegistrationDelegate.invokeBeanFactoryPostProcessors(PostProcessorRegistrationDelegate.java:198) 2023-09-07 15:26:53 at org.springframework.context.support.AbstractApplicationContext.invokeBeanFactoryPostProcessors(AbstractApplicationContext.java:748) 2023-09-07 15:26:53 at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:564) 2023-09-07 15:26:53 at org.springframework.boot.web.reactive.context.ReactiveWebServerApplicationContext.refresh(ReactiveWebServerApplicationContext.java:64) 2023-09-07 15:26:53 at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:745) 2023-09-07 15:26:53 at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:423) 2023-09-07 15:26:53 at org.springframework.boot.SpringApplication.run(SpringApplication.java:307) 2023-09-07 15:26:53 at org.springframework.boot.SpringApplication.run(SpringApplication.java:1317) 2023-09-07 15:26:53 at org.springframework.boot.SpringApplication.run(SpringApplication.java:1306) 2023-09-07 15:26:53 at com.gooddata.tiger.metadata.MetadataApplicationKt.main(MetadataApplication.kt:175) 2023-09-07 15:26:53 at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 2023-09-07 15:26:53 at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 2023-09-07 15:26:53 at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) 2023-09-07 15:26:53 at java.base/java.lang.reflect.Method.invoke(Method.java:566) 2023-09-07 15:26:53 at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:49) 2023-09-07 15:26:53 at org.springframework.boot.loader.Launcher.launch(Launcher.java:108) 2023-09-07 15:26:53 at org.springframework.boot.loader.Launcher.launch(Launcher.java:58) 2023-09-07 15:26:53 at org.springframework.boot.loader.JarLauncher.main(JarLauncher.java:88) 2023-09-07 15:26:53 Suppressed: org.apache.pulsar.client.admin.PulsarAdminException: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:53 at org.apache.pulsar.client.admin.internal.BaseResource.getApiException(BaseResource.java:251) 2023-09-07 15:26:53 at org.apache.pulsar.client.admin.internal.BaseResource$1.failed(BaseResource.java:134) 2023-09-07 15:26:53 at org.glassfish.jersey.client.JerseyInvocation$1.failed(JerseyInvocation.java:882) 2023-09-07 15:26:53 at org.glassfish.jersey.client.ClientRuntime.processFailure(ClientRuntime.java:247) 2023-09-07 15:26:53 at org.glassfish.jersey.client.ClientRuntime.processFailure(ClientRuntime.java:242) 2023-09-07 15:26:53 at org.glassfish.jersey.client.ClientRuntime.access$100(ClientRuntime.java:62) 2023-09-07 15:26:53 at org.glassfish.jersey.client.ClientRuntime$2.lambda$failure$1(ClientRuntime.java:178) 2023-09-07 15:26:53 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248) 2023-09-07 15:26:53 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244) 2023-09-07 15:26:53 at org.glassfish.jersey.internal.Errors.process(Errors.java:292) 2023-09-07 15:26:53 at org.glassfish.jersey.internal.Errors.process(Errors.java:274) 2023-09-07 15:26:53 at org.glassfish.jersey.internal.Errors.process(Errors.java:244) 2023-09-07 15:26:53 at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:288) 2023-09-07 15:26:53 at org.glassfish.jersey.client.ClientRuntime$2.failure(ClientRuntime.java:178) 2023-09-07 15:26:53 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$apply$1(AsyncHttpConnector.java:223) 2023-09-07 15:26:53 at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) 2023-09-07 15:26:53 at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) 2023-09-07 15:26:53 at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) 2023-09-07 15:26:53 at java.base/java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2088) 2023-09-07 15:26:53 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$retryOperation$4(AsyncHttpConnector.java:282) 2023-09-07 15:26:53 at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) 2023-09-07 15:26:53 at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) 2023-09-07 15:26:53 at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) 2023-09-07 15:26:53 at java.base/java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2088) 2023-09-07 15:26:53 at org.asynchttpclient.netty.NettyResponseFuture.abort(NettyResponseFuture.java:273) 2023-09-07 15:26:53 at org.asynchttpclient.netty.request.NettyRequestSender.abort(NettyRequestSender.java:473) 2023-09-07 15:26:53 at org.asynchttpclient.netty.handler.HttpHandler.readFailed(HttpHandler.java:161) 2023-09-07 15:26:53 at org.asynchttpclient.netty.handler.HttpHandler.handleRead(HttpHandler.java:154) 2023-09-07 15:26:53 at org.asynchttpclient.netty.handler.AsyncHttpClientHandler.channelRead(AsyncHttpClientHandler.java:78) 2023-09-07 15:26:53 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) 2023-09-07 15:26:53 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:53 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) 2023-09-07 15:26:53 at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) 2023-09-07 15:26:53 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) 2023-09-07 15:26:53 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:53 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) 2023-09-07 15:26:53 at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436) 2023-09-07 15:26:53 at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:346) 2023-09-07 15:26:53 at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:318) 2023-09-07 15:26:53 at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251) 2023-09-07 15:26:53 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:442) 2023-09-07 15:26:53 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:53 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) 2023-09-07 15:26:53 at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410) 2023-09-07 15:26:53 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:440) 2023-09-07 15:26:53 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:53 at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919) 2023-09-07 15:26:53 at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:166) 2023-09-07 15:26:53 at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:788) 2023-09-07 15:26:53 at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724) 2023-09-07 15:26:53 at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650) 2023-09-07 15:26:53 at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562) 2023-09-07 15:26:53 at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) 2023-09-07 15:26:53 at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) 2023-09-07 15:26:53 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) 2023-09-07 15:26:53 at java.base/java.lang.Thread.run(Thread.java:829) 2023-09-07 15:26:53 Caused by: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:53 at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331) 2023-09-07 15:26:53 at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346) 2023-09-07 15:26:53 at java.base/java.util.concurrent.CompletableFuture$OrApply.tryFire(CompletableFuture.java:1503) 2023-09-07 15:26:53 ... 39 more 2023-09-07 15:26:53 Caused by: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:53 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$retryOperation$4(AsyncHttpConnector.java:284) 2023-09-07 15:26:53 ... 36 more 2023-09-07 15:26:53 Caused by: org.asynchttpclient.handler.MaxRedirectException: Maximum redirect reached: 5 2023-09-07 15:26:53 Caused by: [CIRCULAR REFERENCE: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5] 2023-09-07 15:26:53 " 2023-09-07 15:26:54 Result Cache: not ready 2023-09-07 15:26:54 Auth. service: not ready 2023-09-07 15:26:55 Exception in thread "main" java.lang.reflect.InvocationTargetException 2023-09-07 15:26:55 at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) 2023-09-07 15:26:55 at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) 2023-09-07 15:26:55 at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) 2023-09-07 15:26:55 at java.base/java.lang.reflect.Method.invoke(Method.java:566) 2023-09-07 15:26:55 at org.springframework.boot.loader.MainMethodRunner.run(MainMethodRunner.java:49) 2023-09-07 15:26:55 at org.springframework.boot.loader.Launcher.launch(Launcher.java:108) 2023-09-07 15:26:55 at org.springframework.boot.loader.Launcher.launch(Launcher.java:58) 2023-09-07 15:26:55 at org.springframework.boot.loader.JarLauncher.main(JarLauncher.java:88) 2023-09-07 15:26:55 Caused by: java.lang.reflect.UndeclaredThrowableException 2023-09-07 15:26:55 at org.springframework.util.ReflectionUtils.rethrowRuntimeException(ReflectionUtils.java:147) 2023-09-07 15:26:55 at org.springframework.boot.SpringApplication.handleRunFailure(SpringApplication.java:809) 2023-09-07 15:26:55 at org.springframework.boot.SpringApplication.run(SpringApplication.java:317) 2023-09-07 15:26:55 at org.springframework.boot.SpringApplication.run(SpringApplication.java:1317) 2023-09-07 15:26:55 at org.springframework.boot.SpringApplication.run(SpringApplication.java:1306) 2023-09-07 15:26:55 at com.gooddata.tiger.metadata.MetadataApplicationKt.main(MetadataApplication.kt:175) 2023-09-07 15:26:55 ... 8 more 2023-09-07 15:26:55 Caused by: org.apache.pulsar.client.admin.PulsarAdminException: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:55 at org.apache.pulsar.client.admin.PulsarAdminException.wrap(PulsarAdminException.java:252) 2023-09-07 15:26:55 at org.apache.pulsar.client.admin.internal.BaseResource.sync(BaseResource.java:302) 2023-09-07 15:26:55 at org.apache.pulsar.client.admin.internal.TopicsImpl.createNonPartitionedTopic(TopicsImpl.java:340) 2023-09-07 15:26:55 at org.apache.pulsar.client.admin.Topics.createNonPartitionedTopic(Topics.java:482) 2023-09-07 15:26:55 at com.gooddata.tiger.pulsar.PulsarAutoConfiguration.producerBeanFactory$lambda-1(PulsarAutoConfiguration.kt:129) 2023-09-07 15:26:55 at org.springframework.context.support.PostProcessorRegistrationDelegate.invokeBeanFactoryPostProcessors(PostProcessorRegistrationDelegate.java:325) 2023-09-07 15:26:55 at org.springframework.context.support.PostProcessorRegistrationDelegate.invokeBeanFactoryPostProcessors(PostProcessorRegistrationDelegate.java:198) 2023-09-07 15:26:55 at org.springframework.context.support.AbstractApplicationContext.invokeBeanFactoryPostProcessors(AbstractApplicationContext.java:748) 2023-09-07 15:26:55 at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:564) 2023-09-07 15:26:55 at org.springframework.boot.web.reactive.context.ReactiveWebServerApplicationContext.refresh(ReactiveWebServerApplicationContext.java:64) 2023-09-07 15:26:55 at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:745) 2023-09-07 15:26:55 at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:423) 2023-09-07 15:26:55 at org.springframework.boot.SpringApplication.run(SpringApplication.java:307) 2023-09-07 15:26:55 ... 11 more 2023-09-07 15:26:55 Suppressed: org.apache.pulsar.client.admin.PulsarAdminException: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:55 at org.apache.pulsar.client.admin.internal.BaseResource.getApiException(BaseResource.java:251) 2023-09-07 15:26:55 at org.apache.pulsar.client.admin.internal.BaseResource$1.failed(BaseResource.java:134) 2023-09-07 15:26:55 at org.glassfish.jersey.client.JerseyInvocation$1.failed(JerseyInvocation.java:882) 2023-09-07 15:26:55 at org.glassfish.jersey.client.ClientRuntime.processFailure(ClientRuntime.java:247) 2023-09-07 15:26:55 at org.glassfish.jersey.client.ClientRuntime.processFailure(ClientRuntime.java:242) 2023-09-07 15:26:55 at org.glassfish.jersey.client.ClientRuntime.access$100(ClientRuntime.java:62) 2023-09-07 15:26:55 at org.glassfish.jersey.client.ClientRuntime$2.lambda$failure$1(ClientRuntime.java:178) 2023-09-07 15:26:55 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:248) 2023-09-07 15:26:55 at org.glassfish.jersey.internal.Errors$1.call(Errors.java:244) 2023-09-07 15:26:55 at org.glassfish.jersey.internal.Errors.process(Errors.java:292) 2023-09-07 15:26:55 at org.glassfish.jersey.internal.Errors.process(Errors.java:274) 2023-09-07 15:26:55 at org.glassfish.jersey.internal.Errors.process(Errors.java:244) 2023-09-07 15:26:55 at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:288) 2023-09-07 15:26:55 at org.glassfish.jersey.client.ClientRuntime$2.failure(ClientRuntime.java:178) 2023-09-07 15:26:55 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$apply$1(AsyncHttpConnector.java:223) 2023-09-07 15:26:55 at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) 2023-09-07 15:26:55 at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) 2023-09-07 15:26:55 at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) 2023-09-07 15:26:55 at java.base/java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2088) 2023-09-07 15:26:55 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$retryOperation$4(AsyncHttpConnector.java:282) 2023-09-07 15:26:55 at java.base/java.util.concurrent.CompletableFuture.uniWhenComplete(CompletableFuture.java:859) 2023-09-07 15:26:55 at java.base/java.util.concurrent.CompletableFuture$UniWhenComplete.tryFire(CompletableFuture.java:837) 2023-09-07 15:26:55 at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) 2023-09-07 15:26:55 at java.base/java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2088) 2023-09-07 15:26:55 at org.asynchttpclient.netty.NettyResponseFuture.abort(NettyResponseFuture.java:273) 2023-09-07 15:26:55 at org.asynchttpclient.netty.request.NettyRequestSender.abort(NettyRequestSender.java:473) 2023-09-07 15:26:55 at org.asynchttpclient.netty.handler.HttpHandler.readFailed(HttpHandler.java:161) 2023-09-07 15:26:55 at org.asynchttpclient.netty.handler.HttpHandler.handleRead(HttpHandler.java:154) 2023-09-07 15:26:55 at org.asynchttpclient.netty.handler.AsyncHttpClientHandler.channelRead(AsyncHttpClientHandler.java:78) 2023-09-07 15:26:55 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) 2023-09-07 15:26:55 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:55 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) 2023-09-07 15:26:55 at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103) 2023-09-07 15:26:55 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:444) 2023-09-07 15:26:55 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:55 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) 2023-09-07 15:26:55 at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:436) 2023-09-07 15:26:55 at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:346) 2023-09-07 15:26:55 at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:318) 2023-09-07 15:26:55 at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:251) 2023-09-07 15:26:55 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:442) 2023-09-07 15:26:55 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:55 at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) 2023-09-07 15:26:55 at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1410) 2023-09-07 15:26:55 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:440) 2023-09-07 15:26:55 at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) 2023-09-07 15:26:55 at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:919) 2023-09-07 15:26:55 at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:166) 2023-09-07 15:26:55 at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:788) 2023-09-07 15:26:55 at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724) 2023-09-07 15:26:55 at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650) 2023-09-07 15:26:55 at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562) 2023-09-07 15:26:55 at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) 2023-09-07 15:26:55 at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) 2023-09-07 15:26:55 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) 2023-09-07 15:26:55 at java.base/java.lang.Thread.run(Thread.java:829) 2023-09-07 15:26:55 Caused by: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:55 at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331) 2023-09-07 15:26:55 at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346) 2023-09-07 15:26:55 at java.base/java.util.concurrent.CompletableFuture$OrApply.tryFire(CompletableFuture.java:1503) 2023-09-07 15:26:55 ... 39 more 2023-09-07 15:26:55 Caused by: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5 2023-09-07 15:26:55 at org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector.lambda$retryOperation$4(AsyncHttpConnector.java:284) 2023-09-07 15:26:55 ... 36 more 2023-09-07 15:26:55 Caused by: org.asynchttpclient.handler.MaxRedirectException: Maximum redirect reached: 5 2023-09-07 15:26:55 Caused by: [CIRCULAR REFERENCE: java.util.concurrent.CompletionException: org.apache.pulsar.client.admin.internal.http.AsyncHttpConnector$RetryException: Could not complete the operation. Number of retries has been exhausted. Failed reason: Maximum redirect reached: 5] 2023-09-07 15:26:56 2023-09-07T14:26:56,896+0000 [BookKeeperClientWorker-OrderedExecutor-3-0] WARN com.scurrilous.circe.checksum.Crc32cIntChecksum - Failed to load Circe JNI library. Falling back to Java based CRC32c provider 2023-09-07 15:26:57 ______ ____________________________ ________ _______ _______________ 2023-09-07 15:26:57 ___ |/ /___ ____/___ __/___ |___ __ \___ |___ __/___ | 2023-09-07 15:26:57 __ /|_/ / __ __/ __ / __ /| |__ / / /__ /| |__ / __ /| | 2023-09-07 15:26:57 _ / / / _ /___ _ / _ ___ |_ /_/ / _ ___ |_ / _ ___ | 2023-09-07 15:26:57 /_/ /_/ /_____/ /_/ /_/ |_|/_____/ /_/ |_|/_/ /_/ |_| 2023-09-07 15:26:57 2023-09-07 15:26:57 :: Spring Boot (v2.6.15) :: HTTP port = 9007/9008 :: PID: 818 2023-09-07 15:26:57 :: Build from: 72bfbc1 :: 2023-07-24 11:46:23+0000 2023-09-07 15:26:57 2023-09-07 15:26:57 2023-09-07 15:26:57 Auth. service: not ready 2023-09-07 15:26:57 Result Cache: not ready 2023-09-07 15:26:59 WARNING: An illegal reflective access operation has occurred 2023-09-07 15:26:59 WARNING: Illegal reflective access by org.apache.pulsar.common.util.netty.DnsResolverUtil (file:/opt/metadata-api/BOOT-INF/lib/pulsar-common-2.10.4.jar) to method sun.net.InetAddressCachePolicy.get() 2023-09-07 15:26:59 WARNING: Please consider reporting this to the maintainers of org.apache.pulsar.common.util.netty.DnsResolverUtil 2023-09-07 15:26:59 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations 2023-09-07 15:26:59 WARNING: All illegal access operations will be denied in a future release 2023-09-07 15:27:00 Auth. service: not ready 2023-09-07 15:27:00 Result Cache: not ready 2023-09-07 15:27:02 Metadata API: not ready 2023-09-07 15:27:03 2023-09-07T14:27:03,000+0000 [pulsar-timer-6-1] INFO org.apache.pulsar.client.impl.ConnectionHandler - [persistent://public/default/caches.garbage-collect] [null] Reconnecting after connection was closed 2023-09-07 15:27:03 2023-09-07T14:27:03,001+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [null] Creating producer on cnx [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] 2023-09-07 15:27:03 2023-09-07T14:27:03,039+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [standalone-2-16] Created producer on cnx [id: 0x27468b59, L:/127.0.0.1:33570 - R:localhost/127.0.0.1:6650] 2023-09-07 15:27:03 2023-09-07T14:27:03,073+0000 [main] WARN com.scurrilous.circe.checksum.Crc32cIntChecksum - Failed to load Circe JNI library. Falling back to Java based CRC32c provider 2023-09-07 15:27:03 2023-09-07T14:27:03,082+0000 [main] INFO org.apache.pulsar.client.impl.PulsarClientImpl - Client closing. URL: pulsar://localhost:6650 2023-09-07 15:27:03 2023-09-07T14:27:03,087+0000 [main] INFO org.apache.pulsar.client.impl.ProducerStatsRecorderImpl - [persistent://public/default/caches.garbage-collect] [standalone-2-16] Pending messages: 0 --- Publish throughput: 0.04 msg/s --- 0.00 Mbit/s --- Latency: med: 30.000 ms - 95pct: 30.000 ms - 99pct: 30.000 ms - 99.9pct: 30.000 ms - max: 30.000 ms --- BatchSize: med: 1.000 - 95pct: 1.000 - 99pct: 1.000 - 99.9pct: 1.000 - max: 1.000 --- MsgSize: med: 2.000 bytes - 95pct: 2.000 bytes - 99pct: 2.000 bytes - 99.9pct: 2.000 bytes - max: 2.000 bytes --- Ack received rate: 0.04 ack/s --- Failed messages: 0 --- Pending messages: 0 2023-09-07 15:27:03 2023-09-07T14:27:03,088+0000 [main] INFO org.apache.pulsar.client.impl.ProducerImpl - [persistent://public/default/caches.garbage-collect] [standalone-2-16] Closed Producer 2023-09-07 15:27:03 2023-09-07T14:27:03,108+0000 [pulsar-client-io-1-1] INFO org.apache.pulsar.client.impl.ClientCnx - [id: 0x27468b59, L:/127.0.0.1:33570 ! R:localhost/127.0.0.1:6650] Disconnected 2023-09-07 15:27:03 Auth. service: not ready 2023-09-07 15:27:03 Result Cache: not ready 2023-09-07 15:27:05 2023-09-07T14:27:05,120+0000 [main] INFO org.apache.pulsar.client.cli.PulsarClientTool - 1 messages successfully produced 2023-09-07 15:27:05 Metadata API: not ready 2023-09-07 15:27:07 Result Cache: ready 2023-09-07 15:27:08 Metadata API: not ready 2023-09-07 15:27:08 ts="2023-09-07 14:27:08.718" level=ERROR msg="Error during GRPC Healthcheck call" logger=com.gooddata.tiger.grpc.healthcheck.GrpcHealthCheck thread=boundedElastic-3 action=grpcHealthCheck spanId=fcfbba8de1eb3621 traceId=fcfbba8de1eb3621 exc="io.grpc.StatusRuntimeException: UNAVAILABLE: io exception 2023-09-07 15:27:08 at io.grpc.Status.asRuntimeException(Status.java:535) 2023-09-07 15:27:08 at io.grpc.stub.ClientCalls$StreamObserverToCallListenerAdapter.onClose(ClientCalls.java:487) 2023-09-07 15:27:08 at brave.grpc.TracingClientInterceptor$TracingClientCallListener.onClose(TracingClientInterceptor.java:202) 2023-09-07 15:27:08 at io.grpc.PartialForwardingClientCallListener.onClose(PartialForwardingClientCallListener.java:39) 2023-09-07 15:27:08 at io.grpc.ForwardingClientCallListener.onClose(ForwardingClientCallListener.java:23) 2023-09-07 15:27:08 at io.grpc.ForwardingClientCallListener$SimpleForwardingClientCallListener.onClose(ForwardingClientCallListener.java:40) 2023-09-07 15:27:08 at io.micrometer.core.instrument.binder.grpc.MetricCollectingClientCallListener.onClose(MetricCollectingClientCallListener.java:57) 2023-09-07 15:27:08 at io.grpc.internal.DelayedClientCall$DelayedListener$3.run(DelayedClientCall.java:468) 2023-09-07 15:27:08 at io.grpc.internal.DelayedClientCall$DelayedListener.delayOrExecute(DelayedClientCall.java:432) 2023-09-07 15:27:08 at io.grpc.internal.DelayedClientCall$DelayedListener.onClose(DelayedClientCall.java:465) 2023-09-07 15:27:08 at io.grpc.internal.ClientCallImpl.closeObserver(ClientCallImpl.java:562) 2023-09-07 15:27:08 at io.grpc.internal.ClientCallImpl.access$300(ClientCallImpl.java:70) 2023-09-07 15:27:08 at io.grpc.internal.ClientCallImpl$ClientStreamListenerImpl$1StreamClosed.runInternal(ClientCallImpl.java:743) 2023-09-07 15:27:08 at io.grpc.internal.ClientCallImpl$ClientStreamListenerImpl$1StreamClosed.runInContext(ClientCallImpl.java:722) 2023-09-07 15:27:08 at io.grpc.internal.ContextRunnable.run(ContextRunnable.java:37) 2023-09-07 15:27:08 at io.grpc.internal.SerializingExecutor.run(SerializingExecutor.java:133) 2023-09-07 15:27:08 at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) 2023-09-07 15:27:08 at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) 2023-09-07 15:27:08 at java.base/java.lang.Thread.run(Thread.java:829) 2023-09-07 15:27:08 Caused by: io.netty.channel.AbstractChannel$AnnotatedConnectException: Connection refused: localhost/127.0.0.1:6572 2023-09-07 15:27:08 Caused by: java.net.ConnectException: Connection refused 2023-09-07 15:27:08 at java.base/sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) 2023-09-07 15:27:08 at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:777) 2023-09-07 15:27:08 at io.netty.channel.socket.nio.NioSocketChannel.doFinishConnect(NioSocketChannel.java:337) 2023-09-07 15:27:08 at io.netty.channel.nio.AbstractNioChannel$AbstractNioUnsafe.finishConnect(AbstractNioChannel.java:334) 2023-09-07 15:27:08 at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:776) 2023-09-07 15:27:08 at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724) 2023-09-07 15:27:08 at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650) 2023-09-07 15:27:08 at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562) 2023-09-07 15:27:08 at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) 2023-09-07 15:27:08 at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) 2023-09-07 15:27:08 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) 2023-09-07 15:27:08 at java.base/java.lang.Thread.run(Thread.java:829) 2023-09-07 15:27:08 " 2023-09-07 15:27:08 Auth. service: not ready 2023-09-07 15:27:09 Running Changeset: db/changelog/updates/db.changelog-plsql-v2.4.0.yaml::403-stored-procedures::tiger@gooddata.com 2023-09-07 15:27:09 2023-09-07 15:27:09 UPDATE SUMMARY 2023-09-07 15:27:09 Run: 1 2023-09-07 15:27:09 Previously run: 102 2023-09-07 15:27:09 Filtered out: 0 2023-09-07 15:27:09 ------------------------------- 2023-09-07 15:27:09 Total change sets: 103 2023-09-07 15:27:09 2023-09-07 15:27:09 Liquibase: Update has been successful. 2023-09-07 15:27:10 ts="2023-09-07 14:27:10.226" level=ERROR msg="Error during GRPC Healthcheck call" logger=com.gooddata.tiger.grpc.healthcheck.GrpcHealthCheck thread=boundedElastic-4 action=grpcHealthCheck spanId=a50bc3b9d730c7f8 traceId=a50bc3b9d730c7f8 exc="io.grpc.StatusRuntimeException: UNAVAILABLE: io exception 2023-09-07 15:27:10 at io.grpc.Status.asRuntimeException(Status.java:535) 2023-09-07 15:27:10 at io.grpc.stub.ClientCalls$StreamObserverToCallListenerAdapter.onClose(ClientCalls.java:487) 2023-09-07 15:27:10 at brave.grpc.TracingClientInterceptor$TracingClientCallListener.onClose(TracingClientInterceptor.java:202) 2023-09-07 15:27:10 at io.grpc.PartialForwardingClientCallListener.onClose(PartialForwardingClientCallListener.java:39) 2023-09-07 15:27:10 at io.grpc.ForwardingClientCallListener.onClose(ForwardingClientCallListener.java:23) 2023-09-07 15:27:10 at io.grpc.ForwardingClientCallListener$SimpleForwardingClientCallListener.onClose(ForwardingClientCallListener.java:40) 2023-09-07 15:27:10 at io.micrometer.core.instrument.binder.grpc.MetricCollectingClientCallListener.onClose(MetricCollectingClientCallListener.java:57) 2023-09-07 15:27:10 at io.grpc.internal.ClientCallImpl.closeObserver(ClientCallImpl.java:562) 2023-09-07 15:27:10 at io.grpc.internal.ClientCallImpl.access$300(ClientCallImpl.java:70) 2023-09-07 15:27:10 at io.grpc.internal.ClientCallImpl$ClientStreamListenerImpl$1StreamClosed.runInternal(ClientCallImpl.java:743) 2023-09-07 15:27:10 at io.grpc.internal.ClientCallImpl$ClientStreamListenerImpl$1StreamClosed.runInContext(ClientCallImpl.java:722) 2023-09-07 15:27:10 at io.grpc.internal.ContextRunnable.run(ContextRunnable.java:37) 2023-09-07 15:27:10 at io.grpc.internal.SerializingExecutor.run(SerializingExecutor.java:133) 2023-09-07 15:27:10 at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) 2023-09-07 15:27:10 at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) 2023-09-07 15:27:10 at java.base/java.lang.Thread.run(Thread.java:829) 2023-09-07 15:27:10 Caused by: io.netty.channel.AbstractChannel$AnnotatedConnectException: Connection refused: localhost/127.0.0.1:6572 2023-09-07 15:27:10 Caused by: java.net.ConnectException: Connection refused 2023-09-07 15:27:10 at java.base/sun.nio.ch.SocketChannelImpl.checkConnect(Native Method) 2023-09-07 15:27:10 at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:777) 2023-09-07 15:27:10 at io.netty.channel.socket.nio.NioSocketChannel.doFinishConnect(NioSocketChannel.java:337) 2023-09-07 15:27:10 at io.netty.channel.nio.AbstractNioChannel$AbstractNioUnsafe.finishConnect(AbstractNioChannel.java:334) 2023-09-07 15:27:10 at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:776) 2023-09-07 15:27:10 at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724) 2023-09-07 15:27:10 at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650) 2023-09-07 15:27:10 at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562) 2023-09-07 15:27:10 at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) 2023-09-07 15:27:10 at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) 2023-09-07 15:27:10 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) 2023-09-07 15:27:10 at java.base/java.lang.Thread.run(Thread.java:829) 2023-09-07 15:27:10 " 2023-09-07 15:27:10 Auth. service: not ready 2023-09-07 15:27:10 Result Cache: ready 2023-09-07 15:27:10 s6-rc: info: service result-cache successfully started 2023-09-07 15:27:13 Auth. service: ready 2023-09-07 15:27:13 s6-rc: info: service auth-service successfully started 2023-09-07 15:27:13 Metadata API: ready 2023-09-07 15:27:15 Metadata API: ready 2023-09-07 15:27:15 s6-rc: info: service metadata-api successfully started 2023-09-07 15:27:15 s6-rc: info: service bootstrap-org: starting 2023-09-07 15:27:15 s6-rc: info: service sql-executor: starting 2023-09-07 15:27:15 s6-rc: info: service calcique: starting 2023-09-07 15:27:15 + : 80 2023-09-07 15:27:15 + : data-analytics.local.promptly.health 2023-09-07 15:27:15 + H_JSON='Content-type: application/json' 2023-09-07 15:27:15 + H_GDC_JSON='Content-type: application/vnd.gooddata.api+json' 2023-09-07 15:27:15 + FAKE_HOST='X-Forwarded-Host: data-analytics.local.promptly.health:80' 2023-09-07 15:27:15 + DEFINITION_FILE= 2023-09-07 15:27:15 + METADATA_HOST=localhost 2023-09-07 15:27:15 + METADATA_ACTUATOR_PORT=9008 2023-09-07 15:27:15 + METADATA_GRPC_PORT=6572 2023-09-07 15:27:15 + METADATA_HTTP_PORT=9007 2023-09-07 15:27:15 + AUTH_SERVICE_HOST=localhost 2023-09-07 15:27:15 + AUTH_SERVICE_ACTUATOR_PORT=9051 2023-09-07 15:27:15 + AUTH_SERVICE_HTTP_PORT=9050 2023-09-07 15:27:15 + GRPC_PASSWORD=SuperSecretPassword 2023-09-07 15:27:15 + TOKEN_SECRET=admin123 2023-09-07 15:27:15 + getopts f:m:p:g:j:a:q:r:w:U:P:T: o 2023-09-07 15:27:15 + case "${o}" in 2023-09-07 15:27:15 + DEFINITION_FILE=/opt/bootstrap/bootstrap.json 2023-09-07 15:27:15 + getopts f:m:p:g:j:a:q:r:w:U:P:T: o 2023-09-07 15:27:15 + case "${o}" in 2023-09-07 15:27:15 + USERNAME=demo@example.com 2023-09-07 15:27:15 + getopts f:m:p:g:j:a:q:r:w:U:P:T: o 2023-09-07 15:27:15 + case "${o}" in 2023-09-07 15:27:15 + PASSWORD=demo123 2023-09-07 15:27:15 + getopts f:m:p:g:j:a:q:r:w:U:P:T: o 2023-09-07 15:27:15 + case "${o}" in 2023-09-07 15:27:15 + TOKEN_SECRET=admin123 2023-09-07 15:27:15 + getopts f:m:p:g:j:a:q:r:w:U:P:T: o 2023-09-07 15:27:15 + shift 8 2023-09-07 15:27:15 + '[' -z /opt/bootstrap/bootstrap.json ']' 2023-09-07 15:27:15 + curl --output /dev/null -fs http://localhost:9008/actuator/health/readiness 2023-09-07 15:27:15 ++ base64 2023-09-07 15:27:15 ++ tr -d '\n' 2023-09-07 15:27:15 ++ printf bootstrap:%s SuperSecretPassword 2023-09-07 15:27:15 + AUTHORIZATION=Ym9vdHN0cmFwOlN1cGVyU2VjcmV0UGFzc3dvcmQ= 2023-09-07 15:27:15 ++ echo -n admin:bootstrap:admin123 2023-09-07 15:27:15 ++ base64 2023-09-07 15:27:15 + BOOTSTRAP_TOKEN=YWRtaW46Ym9vdHN0cmFwOmFkbWluMTIz 2023-09-07 15:27:15 ++ python3 -c 'import crypt; print(crypt.crypt('\''admin123'\'', crypt.mksalt(crypt.METHOD_SHA256)))' 2023-09-07 15:27:15 :1: DeprecationWarning: 'crypt' is deprecated and slated for removal in Python 3.13 2023-09-07 15:27:15 + TOKEN_HASH='$5$FsRaPODp7gu692nI$Z318owfMB1Az5CvGAThQTm3TkZC9nNwOx21/eyMqNp5' 2023-09-07 15:27:15 + jq '.organizations[0].hostname="data-analytics.local.promptly.health" | 2023-09-07 15:27:15 .organizations[0].initialToken.cryptedToken="$5$FsRaPODp7gu692nI$Z318owfMB1Az5CvGAThQTm3TkZC9nNwOx21/eyMqNp5"' /opt/bootstrap/bootstrap.json 2023-09-07 15:27:15 + grpcurl -H 'Authorization: Basic Ym9vdHN0cmFwOlN1cGVyU2VjcmV0UGFzc3dvcmQ=' -plaintext -d @ localhost:6572 tiger.MetadataStorePrivilegedService/ensureOrganizationsExists 2023-09-07 15:27:15 ts="2023-09-07 14:27:15.628" level=ERROR msg="gRPC server call" logger=com.gooddata.tiger.metadata.grpc.MetadataStorePrivilegedGrpcService thread=DefaultDispatcher-worker-3 action=grpcServerCall spanId=5b096f8b0e245155 traceId=5b096f8b0e245155 exc="com.gooddata.data.stock.StockEntityNotExistsException: Some of given referenced 'userGroups' entities do not exist.Not existing IDs: 2023-09-07 15:27:15 at com.gooddata.data.persistence.exposed.PersistBuilderSupport.checkAllReferencedIdsExist(PersistBuilderSupport.kt:494) 2023-09-07 15:27:15 at com.gooddata.data.persistence.exposed.PersistBuilderSupport.selectRelatedEntityIds(PersistBuilderSupport.kt:474) 2023-09-07 15:27:15 at com.gooddata.data.persistence.exposed.PersistBuilderSupport.insertManyToManyRelationshipInner(PersistBuilderSupport.kt:443) 2023-09-07 15:27:15 at com.gooddata.data.persistence.exposed.PersistBuilderSupport.insertManyToManyRelationship(PersistBuilderSupport.kt:408) 2023-09-07 15:27:15 at com.gooddata.data.persistence.exposed.UpdateQueryBuilder$updateExistingEntity$7$1.invoke(UpdateQueryBuilder.kt:78) 2023-09-07 15:27:15 at com.gooddata.data.persistence.exposed.UpdateQueryBuilder$updateExistingEntity$7$1.invoke(UpdateQueryBuilder.kt:78) 2023-09-07 15:27:15 at com.gooddata.data.persistence.exposed.UpdateQueryBuilder.updateManyToManyTable(UpdateQueryBuilder.kt:181) 2023-09-07 15:27:15 at com.gooddata.data.persistence.exposed.UpdateQueryBuilder.updateExistingEntity(UpdateQueryBuilder.kt:78) 2023-09-07 15:27:15 at com.gooddata.data.persistence.exposed.UpdateQueryBuilder.buildUpdate(UpdateQueryBuilder.kt:57) 2023-09-07 15:27:15 at com.gooddata.data.persistence.exposed.ExposedApiObjectRepository.update(ExposedApiObjectRepository.kt:195) 2023-09-07 15:27:15 at com.gooddata.data.persistence.exposed.ExposedApiObjectRepository$$FastClassBySpringCGLIB$$7acde1ca.invoke() 2023-09-07 15:27:15 at org.springframework.cglib.proxy.MethodProxy.invoke(MethodProxy.java:218) 2023-09-07 15:27:15 at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.invokeJoinpoint(CglibAopProxy.java:793) 2023-09-07 15:27:15 at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:163) 2023-09-07 15:27:15 at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:763) 2023-09-07 15:27:15 at org.springframework.dao.support.PersistenceExceptionTranslationInterceptor.invoke(PersistenceExceptionTranslationInterceptor.java:137) 2023-09-07 15:27:15 at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186) 2023-09-07 15:27:15 at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:763) 2023-09-07 15:27:15 at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:123) 2023-09-07 15:27:15 at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:388) 2023-09-07 15:27:15 at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:119) 2023-09-07 15:27:15 at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186) 2023-09-07 15:27:15 at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:763) 2023-09-07 15:27:15 at org.springframework.aop.framework.CglibAopProxy$DynamicAdvisedInterceptor.intercept(CglibAopProxy.java:708) 2023-09-07 15:27:15 at com.gooddata.tiger.metadata.repository.exposed.organization.ExposedUserRepository$$EnhancerBySpringCGLIB$$601ef0a.update() 2023-09-07 15:27:15 at com.gooddata.data.stock.BasicApiStock$update$2.invoke(BasicApiStock.kt:129) 2023-09-07 15:27:15 at com.gooddata.data.stock.BasicApiStock$update$2.invoke(BasicApiStock.kt:119) 2023-09-07 15:27:15 at com.gooddata.data.stock.BasicApiStock.withDispatch(BasicApiStock.kt:225) 2023-09-07 15:27:15 at com.gooddata.data.stock.BasicApiStock.update$suspendImpl(BasicApiStock.kt:119) 2023-09-07 15:27:15 at com.gooddata.data.stock.BasicApiStock.update(BasicApiStock.kt) 2023-09-07 15:27:15 at com.gooddata.data.stock.ApiStock$DefaultImpls.update$default(ApiStock.kt:144) 2023-09-07 15:27:15 at com.gooddata.tiger.metadata.service.OrganizationBootstrapService.bootstrapUser(OrganizationBootstrapService.kt:224) 2023-09-07 15:27:15 at com.gooddata.tiger.metadata.service.OrganizationBootstrapService.access$bootstrapUser(OrganizationBootstrapService.kt:41) 2023-09-07 15:27:15 at com.gooddata.tiger.metadata.service.OrganizationBootstrapService$bootstrap$2.invokeSuspend(OrganizationBootstrapService.kt:57) 2023-09-07 15:27:15 at com.gooddata.tiger.metadata.service.OrganizationBootstrapService$bootstrap$2.invoke(OrganizationBootstrapService.kt) 2023-09-07 15:27:15 at com.gooddata.tiger.metadata.service.OrganizationBootstrapService$bootstrap$2.invoke(OrganizationBootstrapService.kt) 2023-09-07 15:27:15 at com.gooddata.tiger.metadata.transaction.SuspendedTransactionKt$coTransaction$2$1.invokeSuspend(SuspendedTransaction.kt:31) 2023-09-07 15:27:15 at com.gooddata.tiger.metadata.transaction.SuspendedTransactionKt$coTransaction$2$1.invoke(SuspendedTransaction.kt) 2023-09-07 15:27:15 at com.gooddata.tiger.metadata.transaction.SuspendedTransactionKt$coTransaction$2$1.invoke(SuspendedTransaction.kt) 2023-09-07 15:27:15 at org.jetbrains.exposed.sql.transactions.experimental.SuspendedKt$suspendedTransactionAsyncInternal$1.invokeSuspend(Suspended.kt:130) 2023-09-07 15:27:15 at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:33) 2023-09-07 15:27:15 at kotlinx.coroutines.DispatchedTask.run(DispatchedTask.kt:56) 2023-09-07 15:27:15 at kotlinx.coroutines.scheduling.CoroutineScheduler.runSafely(CoroutineScheduler.kt:571) 2023-09-07 15:27:15 at kotlinx.coroutines.scheduling.CoroutineScheduler$Worker.executeTask(CoroutineScheduler.kt:738) 2023-09-07 15:27:15 at kotlinx.coroutines.scheduling.CoroutineScheduler$Worker.runWorker(CoroutineScheduler.kt:678) 2023-09-07 15:27:15 at kotlinx.coroutines.scheduling.CoroutineScheduler$Worker.run(CoroutineScheduler.kt:665) 2023-09-07 15:27:15 " 2023-09-07 15:27:15 ERROR: 2023-09-07 15:27:15 Code: Internal 2023-09-07 15:27:15 Message: com.gooddata.data.stock.StockEntityNotExistsException: Some of given referenced 'userGroups' entities do not exist.Not existing IDs: 2023-09-07 15:27:15 s6-rc: warning: unable to start service bootstrap-org: command exited 77 2023-09-07 15:27:16 ____ ____ _ ____ _ ____ _ _____ 2023-09-07 15:27:16 / _\/ _ \/ \ / _\/ \/ _ \/ \ /\/ __/ 2023-09-07 15:27:16 | / | / \|| | | / | || / \|| | ||| \ 2023-09-07 15:27:16 | \__| |-||| |_/\| \_ | || \_\|| \_/|| /_ 2023-09-07 15:27:16 \____/\_/ \|\____/\____/\_/\____\\____/\____\ 2023-09-07 15:27:16 2023-09-07 15:27:16 :: Spring Boot (v2.6.15) :: HTTP port = 9011/9012 :: PID: 1093 2023-09-07 15:27:16 :: Build from: 72bfbc1 :: 2023-07-24 11:46:23+0000 2023-09-07 15:27:16 2023-09-07 15:27:16 2023-09-07 15:27:16 ( ( ) ) ( 2023-09-07 15:27:16 )\ ) ( )\ ) ( /( ( * ) ( /( )\ ) 2023-09-07 15:27:16 (()/( ( )\ (()/( ( )\()) ( )\ ( ` ) /( )\()) (()/( 2023-09-07 15:27:16 /(_)) )((_) /(_)) (\ ((_)\ )\ ((_) )\ ( )(_)) ((_)\ /(_)) 2023-09-07 15:27:16 (_)) ((_)_ (_)) ((_) __((_) ((_) )\___ _ ((_) (_(_()) ((_) (_)) 2023-09-07 15:27:16 / __| / _ \ | | | __| \ \/ / | __| / __| | | | | |_ _| / _ \ | _ \ 2023-09-07 15:27:16 \__ \ | (_) | | |__ | _| > < | _| | (__ | |_| | | | | (_) | | / 2023-09-07 15:27:16 |___/ \__\_\ |____| |___| /_/\_\ |___| \___| \___/ |_| \___/ |_|_\ 2023-09-07 15:27:16 :: Spring Boot (v2.6.15) :: HTTP port = 9100/9101 :: PID: 1092 2023-09-07 15:27:16 :: Build from: 72bfbc1 :: 2023-07-24 11:46:23+0000 2023-09-07 15:27:16 2023-09-07 15:27:16 2023-09-07 15:27:18 WARNING: An illegal reflective access operation has occurred 2023-09-07 15:27:18 WARNING: Illegal reflective access by org.apache.pulsar.common.util.netty.DnsResolverUtil (file:/opt/calcique/BOOT-INF/lib/pulsar-common-2.10.4.jar) to method sun.net.InetAddressCachePolicy.get() 2023-09-07 15:27:18 WARNING: Please consider reporting this to the maintainers of org.apache.pulsar.common.util.netty.DnsResolverUtil 2023-09-07 15:27:18 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations 2023-09-07 15:27:18 WARNING: All illegal access operations will be denied in a future release 2023-09-07 15:27:19 WARNING: An illegal reflective access operation has occurred 2023-09-07 15:27:19 WARNING: Illegal reflective access by org.apache.pulsar.common.util.netty.DnsResolverUtil (file:/opt/sql-executor/BOOT-INF/lib/pulsar-common-2.10.4.jar) to method sun.net.InetAddressCachePolicy.get() 2023-09-07 15:27:19 WARNING: Please consider reporting this to the maintainers of org.apache.pulsar.common.util.netty.DnsResolverUtil 2023-09-07 15:27:19 WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations 2023-09-07 15:27:19 WARNING: All illegal access operations will be denied in a future release 2023-09-07 15:27:21 SQL Executor: not ready 2023-09-07 15:27:21 Calcique: not ready 2023-09-07 15:27:23 ts="2023-09-07 14:27:23.695" level=WARN msg="Unable to patch Guava classes: [source error] addCallback(com.google.common.util.concurrent.ListenableFuture,com.google.common.util.concurrent.FutureCallback,java.util.concurrent.Executor) not found in oadd.com.google.common.util.concurrent.Futures" logger=oadd.org.apache.drill.common.util.GuavaPatcher thread=main 2023-09-07 15:27:24 SQL Executor: not ready 2023-09-07 15:27:24 Database is up to date, no changesets to execute 2023-09-07 15:27:24 2023-09-07 15:27:24 UPDATE SUMMARY 2023-09-07 15:27:24 Run: 0 2023-09-07 15:27:24 Previously run: 4 2023-09-07 15:27:24 Filtered out: 0 2023-09-07 15:27:24 ------------------------------- 2023-09-07 15:27:24 Total change sets: 4 2023-09-07 15:27:24 2023-09-07 15:27:24 Calcique: ready 2023-09-07 15:27:27 SQL Executor: ready 2023-09-07 15:27:27 Calcique: ready 2023-09-07 15:27:27 s6-rc: info: service calcique successfully started 2023-09-07 15:27:30 SQL Executor: ready 2023-09-07 15:27:30 s6-rc: info: service sql-executor successfully started 2023-09-07 15:27:30 s6-rc: info: service scan-model: starting 2023-09-07 15:27:31 _____ __ __ _ _ 2023-09-07 15:27:31 / ____| | \/ | | | | | 2023-09-07 15:27:31 | (___ ___ __ _ _ __ | \ / | ___ __| | ___| | 2023-09-07 15:27:31 \___ \ / __/ _` | '_ \| |\/| |/ _ \ / _` |/ _ \ | 2023-09-07 15:27:31 ____) | (_| (_| | | | | | | | (_) | (_| | __/ | 2023-09-07 15:27:31 |_____/ \___\__,_|_| |_|_| |_|\___/ \__,_|\___|_| 2023-09-07 15:27:31 2023-09-07 15:27:31 :: Spring Boot (v2.6.15) :: HTTP port = 9060/9061 :: PID: 1345 2023-09-07 15:27:31 :: Build from: 72bfbc1 :: 2023-07-24 11:46:23+0000 2023-09-07 15:27:31 2023-09-07 15:27:31 2023-09-07 15:27:37 Scan Model: ready 2023-09-07 15:27:40 Scan Model: ready 2023-09-07 15:27:40 s6-rc: info: service scan-model successfully started 2023-09-07 15:27:40 /run/s6/basedir/scripts/rc.init: warning: s6-rc failed to properly bring all the services up! Check your logs (in /run/uncaught-logs/current if you have in-container logging) for more information. 2023-09-07 15:27:40 /run/s6/basedir/scripts/rc.init: fatal: stopping the container. 2023-09-07 15:27:40 s6-rc: info: service init-demo-data: stopping 2023-09-07 15:27:40 s6-rc: info: service scan-model: stopping 2023-09-07 15:27:40 s6-rc: info: service goss: stopping 2023-09-07 15:27:40 s6-rc: info: service cache-garbage-collector: stopping 2023-09-07 15:27:40 s6-rc: info: service auth-service: stopping 2023-09-07 15:27:40 s6-rc: info: service calcique: stopping 2023-09-07 15:27:40 s6-rc: info: service init-demo-data successfully stopped 2023-09-07 15:27:40 s6-rc: info: service goss successfully stopped 2023-09-07 15:27:40 s6-rc: info: service cache-garbage-collector successfully stopped 2023-09-07 15:27:42 s6-rc: info: service scan-model successfully stopped 2023-09-07 15:27:42 s6-rc: info: service sql-executor: stopping 2023-09-07 15:27:44 s6-rc: info: service calcique successfully stopped 2023-09-07 15:27:44 s6-rc: info: service auth-service successfully stopped 2023-09-07 15:27:44 s6-rc: info: service dex: stopping 2023-09-07 15:27:45 s6-rc: info: service dex successfully stopped 2023-09-07 15:27:46 s6-rc: info: service sql-executor successfully stopped 2023-09-07 15:27:46 s6-rc: info: service result-cache: stopping 2023-09-07 15:27:46 s6-rc: info: service metadata-api: stopping 2023-09-07 15:27:51 s6-rc: info: service result-cache successfully stopped 2023-09-07 15:27:51 s6-rc: info: service redis: stopping 2023-09-07 15:27:51 117:signal-handler (1694096871) Received SIGTERM scheduling shutdown... 2023-09-07 15:27:51 s6-rc: info: service metadata-api successfully stopped 2023-09-07 15:27:51 s6-rc: info: service pulsar: stopping 2023-09-07 15:27:51 s6-rc: info: service postgres: stopping 2023-09-07 15:27:51 2023-09-07 14:27:51.536 UTC [118] LOG: received fast shutdown request 2023-09-07 15:27:51 2023-09-07 14:27:51.539 UTC [118] LOG: aborting any active transactions 2023-09-07 15:27:51 2023-09-07 14:27:51.550 UTC [118] LOG: background worker "logical replication launcher" (PID 226) exited with exit code 1 2023-09-07 15:27:51 2023-09-07 14:27:51.550 UTC [219] LOG: shutting down 2023-09-07 15:27:51 2023-09-07 14:27:51.576 UTC [118] LOG: database system is shut down 2023-09-07 15:27:51 s6-rc: info: service postgres successfully stopped 2023-09-07 15:27:51 117:M 07 Sep 2023 14:27:51.582 # User requested shutdown... 2023-09-07 15:27:51 117:M 07 Sep 2023 14:27:51.582 # Redis is now ready to exit, bye bye... 2023-09-07 15:27:51 2023-09-07T14:27:51,583+0000 [main-SendThread(127.0.0.1:2181)] WARN org.apache.zookeeper.ClientCnxn - An exception was thrown while closing send thread for session 0x1000000327f0003. 2023-09-07 15:27:51 org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1000000327f0003, likely server has closed socket 2023-09-07 15:27:51 at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[org.apache.zookeeper-zookeeper-3.6.3.jar:3.6.3] 2023-09-07 15:27:51 at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[org.apache.zookeeper-zookeeper-3.6.3.jar:3.6.3] 2023-09-07 15:27:51 at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1290) ~[org.apache.zookeeper-zookeeper-3.6.3.jar:3.6.3] 2023-09-07 15:27:51 s6-rc: info: service redis successfully stopped 2023-09-07 15:27:51 2023-09-07T14:27:51,775+0000 [broker-client-shared-internal-executor-19-1] ERROR org.apache.pulsar.broker.service.SystemTopicBasedTopicPoliciesService - Read more topic policies exception, close the read now! 2023-09-07 15:27:51 java.util.concurrent.CompletionException: org.apache.pulsar.client.api.PulsarClientException$AlreadyClosedException: The consumer which subscribes the topic persistent://public/default/__change_events with subscription name reader-838e1e079e was already closed when cleaning and closing the consumers 2023-09-07 15:27:51 at java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:331) ~[?:?] 2023-09-07 15:27:51 at java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:346) ~[?:?] 2023-09-07 15:27:51 at java.util.concurrent.CompletableFuture$UniApply.tryFire(CompletableFuture.java:632) ~[?:?] 2023-09-07 15:27:51 at java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:506) ~[?:?] 2023-09-07 15:27:51 at java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2088) ~[?:?] 2023-09-07 15:27:51 at org.apache.pulsar.client.impl.ConsumerBase.failPendingReceives(ConsumerBase.java:274) ~[org.apache.pulsar-pulsar-client-original-2.10.4.jar:2.10.4] 2023-09-07 15:27:51 at org.apache.pulsar.client.impl.ConsumerBase.lambda$failPendingReceive$1(ConsumerBase.java:257) ~[org.apache.pulsar-pulsar-client-original-2.10.4.jar:2.10.4] 2023-09-07 15:27:51 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?] 2023-09-07 15:27:51 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?] 2023-09-07 15:27:51 at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[io.netty-netty-common-4.1.87.Final.jar:4.1.87.Final] 2023-09-07 15:27:51 at java.lang.Thread.run(Thread.java:829) ~[?:?] 2023-09-07 15:27:51 Caused by: org.apache.pulsar.client.api.PulsarClientException$AlreadyClosedException: The consumer which subscribes the topic persistent://public/default/__change_events with subscription name reader-838e1e079e was already closed when cleaning and closing the consumers 2023-09-07 15:27:51 at org.apache.pulsar.client.impl.ConsumerBase.failPendingReceives(ConsumerBase.java:276) ~[org.apache.pulsar-pulsar-client-original-2.10.4.jar:2.10.4] 2023-09-07 15:27:51 ... 5 more 2023-09-07 15:27:59 s6-rc: info: service pulsar successfully stopped 2023-09-07 15:27:59 s6-rc: info: service init-data: stopping 2023-09-07 15:27:59 s6-rc: info: service init-data successfully stopped 2023-09-07 15:27:59 s6-rc: info: service legacy-cont-init: stopping 2023-09-07 15:27:59 s6-rc: info: service legacy-cont-init successfully stopped 2023-09-07 15:27:59 s6-rc: info: service fix-attrs: stopping 2023-09-07 15:27:59 s6-rc: info: service fix-attrs successfully stopped 2023-09-07 15:27:59 s6-rc: info: service s6rc-oneshot-runner: stopping 2023-09-07 15:27:59 s6-rc: info: service s6rc-oneshot-runner successfully stopped