2023-10-19 14:29:14 2023-10-19T12:29:14+00:00 startup service historical
2023-10-19 14:29:14 Setting druid.host=172.24.0.10 in /tmp/conf/druid/cluster/data/historical/runtime.properties
2023-10-19 14:29:14 Setting druid.coordinator.balancer.strategy=cachingCost in /tmp/conf/druid/cluster/data/historical/runtime.properties
2023-10-19 14:29:15 Setting druid.emitter.logging.logLevel=debug in /tmp/conf/druid/cluster/data/historical/runtime.properties
2023-10-19 14:29:20 2023-10-19T12:29:20,319 INFO [main] org.hibernate.validator.internal.util.Version - HV000001: Hibernate Validator 5.3.6.Final
2023-10-19 14:29:21 2023-10-19T12:29:21,520 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-datasketches], jars: druid-datasketches-27.0.0.jar, commons-math3-3.6.1.jar
2023-10-19 14:29:21 2023-10-19T12:29:21,539 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-kafka-indexing-service], jars: zstd-jni-1.5.2-3.jar, snappy-java-1.1.10.1.jar, lz4-java-1.8.0.jar, druid-kafka-indexing-service-27.0.0.jar, kafka-clients-3.4.0.jar
2023-10-19 14:29:21 2023-10-19T12:29:21,549 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [postgresql-metadata-storage], jars: postgresql-42.4.3.jar, checker-qual-3.5.0.jar, postgresql-metadata-storage-27.0.0.jar
2023-10-19 14:29:21 2023-10-19T12:29:21,555 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-lookups-cached-global], jars: mapdb-1.0.8.jar, druid-lookups-cached-global-27.0.0.jar
2023-10-19 14:29:21 2023-10-19T12:29:21,557 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-histogram], jars: druid-histogram-27.0.0.jar
2023-10-19 14:29:21 2023-10-19T12:29:21,558 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-time-min-max], jars: druid-time-min-max-27.0.0.jar
2023-10-19 14:29:21 2023-10-19T12:29:21,568 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-basic-security], jars: druid-basic-security-27.0.0.jar
2023-10-19 14:29:21 2023-10-19T12:29:21,598 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-avro-extensions], jars: common-config-5.5.12.jar, schema-repo-common-0.1.3.jar, snakeyaml-1.33.jar, slf4j-api-1.7.36.jar, avro-1.11.1.jar, hadoop-client-api-3.3.6.jar, jackson-dataformat-yaml-2.10.5.jar, schema-repo-avro-0.1.3.jar, zstd-jni-1.5.2-3.jar, gson-2.3.1.jar, schema-repo-api-0.1.3.jar, snappy-java-1.1.10.1.jar, commons-lang3-3.12.0.jar, lz4-java-1.8.0.jar, kafka-schema-registry-client-5.5.12.jar, avro-mapred-1.11.1.jar, swagger-core-1.6.2.jar, jersey-client-1.19.4.jar, javax.annotation-api-1.3.2.jar, swagger-models-1.6.2.jar, kafka-clients-5.5.12-ccs.jar, xz-1.8.jar, velocity-engine-core-2.3.jar, swagger-annotations-1.6.2.jar, avro-ipc-1.11.1.jar, guava-16.0.1.jar, schema-repo-client-0.1.3.jar, avro-ipc-jetty-1.11.1.jar, druid-avro-extensions-27.0.0.jar, common-utils-5.5.12.jar
2023-10-19 14:29:21 2023-10-19T12:29:21,616 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-tdigestsketch], jars: t-digest-3.2.jar, druid-tdigestsketch-27.0.0.jar
2023-10-19 14:29:22 2023-10-19T12:29:22,332 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-datasketches], jars: druid-datasketches-27.0.0.jar, commons-math3-3.6.1.jar
2023-10-19 14:29:22 2023-10-19T12:29:22,347 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-kafka-indexing-service], jars: zstd-jni-1.5.2-3.jar, snappy-java-1.1.10.1.jar, lz4-java-1.8.0.jar, druid-kafka-indexing-service-27.0.0.jar, kafka-clients-3.4.0.jar
2023-10-19 14:29:22 2023-10-19T12:29:22,356 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [postgresql-metadata-storage], jars: postgresql-42.4.3.jar, checker-qual-3.5.0.jar, postgresql-metadata-storage-27.0.0.jar
2023-10-19 14:29:22 2023-10-19T12:29:22,358 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-lookups-cached-global], jars: mapdb-1.0.8.jar, druid-lookups-cached-global-27.0.0.jar
2023-10-19 14:29:22 2023-10-19T12:29:22,359 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-histogram], jars: druid-histogram-27.0.0.jar
2023-10-19 14:29:22 2023-10-19T12:29:22,362 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-time-min-max], jars: druid-time-min-max-27.0.0.jar
2023-10-19 14:29:22 2023-10-19T12:29:22,365 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-basic-security], jars: druid-basic-security-27.0.0.jar
2023-10-19 14:29:22 2023-10-19T12:29:22,374 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-avro-extensions], jars: common-config-5.5.12.jar, schema-repo-common-0.1.3.jar, snakeyaml-1.33.jar, slf4j-api-1.7.36.jar, avro-1.11.1.jar, hadoop-client-api-3.3.6.jar, jackson-dataformat-yaml-2.10.5.jar, schema-repo-avro-0.1.3.jar, zstd-jni-1.5.2-3.jar, gson-2.3.1.jar, schema-repo-api-0.1.3.jar, snappy-java-1.1.10.1.jar, commons-lang3-3.12.0.jar, lz4-java-1.8.0.jar, kafka-schema-registry-client-5.5.12.jar, avro-mapred-1.11.1.jar, swagger-core-1.6.2.jar, jersey-client-1.19.4.jar, javax.annotation-api-1.3.2.jar, swagger-models-1.6.2.jar, kafka-clients-5.5.12-ccs.jar, xz-1.8.jar, velocity-engine-core-2.3.jar, swagger-annotations-1.6.2.jar, avro-ipc-1.11.1.jar, guava-16.0.1.jar, schema-repo-client-0.1.3.jar, avro-ipc-jetty-1.11.1.jar, druid-avro-extensions-27.0.0.jar, common-utils-5.5.12.jar
2023-10-19 14:29:22 2023-10-19T12:29:22,376 INFO [main] org.apache.druid.guice.ExtensionsLoader - Loading extension [druid-tdigestsketch], jars: t-digest-3.2.jar, druid-tdigestsketch-27.0.0.jar
2023-10-19 14:29:25 2023-10-19T12:29:25,782 INFO [main] org.apache.druid.guice.JsonConfigurator - Skipping druid.emitter.logging.logLevel property: one of it's prefixes is also used as a property key. Prefix: druid
2023-10-19 14:29:26 2023-10-19T12:29:26,058 INFO [main] org.apache.druid.server.emitter.EmitterModule - Using emitter [NoopEmitter{}] for metrics and alerts, with dimensions [{version=27.0.0}].
2023-10-19 14:29:26 2023-10-19T12:29:26,077 INFO [main] org.apache.druid.server.metrics.MetricsModule - Loaded 3 monitors: org.apache.druid.java.util.metrics.JvmMonitor, org.apache.druid.query.ExecutorServiceMonitor, org.apache.druid.server.initialization.jetty.JettyServerModule$JettyMonitor
2023-10-19 14:29:26 2023-10-19T12:29:26,124 INFO [main] org.apache.druid.cli.CliHistorical - Starting up with processors [12], memory [2,147,483,648], maxMemory [2,147,483,648], directMemory [4,294,967,296]. Properties follow.
2023-10-19 14:29:26 2023-10-19T12:29:26,415 INFO [main] org.eclipse.jetty.util.log - Logging initialized @10341ms to org.eclipse.jetty.util.log.Slf4jLog
2023-10-19 14:29:26 2023-10-19T12:29:26,430 INFO [main] org.apache.druid.server.initialization.jetty.JettyServerModule - Creating http connector with port [8083]
2023-10-19 14:29:26 2023-10-19T12:29:26,601 WARN [main] org.eclipse.jetty.server.handler.gzip.GzipHandler - minGzipSize of 0 is inefficient for short content, break even is size 23
2023-10-19 14:29:26 2023-10-19T12:29:26,611 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [druid.computation.buffer.poolCacheMaxCount, ${base_path}.buffer.poolCacheMaxCount] on [org.apache.druid.query.DruidProcessingConfig#poolCacheMaxCount()]
2023-10-19 14:29:26 2023-10-19T12:29:26,612 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [${base_path}.merge.pool.awaitShutdownMillis] on [org.apache.druid.query.DruidProcessingConfig#getMergePoolAwaitShutdownMillis()]
2023-10-19 14:29:26 2023-10-19T12:29:26,613 INFO [main] org.skife.config.ConfigurationObjectFactory - Assigning value [500MiB] for [druid.processing.buffer.sizeBytes] on [org.apache.druid.query.DruidProcessingConfig#intermediateComputeSizeBytesConfigured()]
2023-10-19 14:29:26 2023-10-19T12:29:26,613 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [druid.computation.buffer.poolCacheInitialCount, ${base_path}.buffer.poolCacheInitialCount] on [org.apache.druid.query.DruidProcessingConfig#getNumInitalBuffersForIntermediatePool()]
2023-10-19 14:29:26 2023-10-19T12:29:26,614 INFO [main] org.skife.config.ConfigurationObjectFactory - Assigning value [2] for [druid.processing.numThreads] on [org.apache.druid.query.DruidProcessingConfig#getNumThreadsConfigured()]
2023-10-19 14:29:26 2023-10-19T12:29:26,614 INFO [main] org.skife.config.ConfigurationObjectFactory - Assigning value [2] for [druid.processing.numMergeBuffers] on [org.apache.druid.query.DruidProcessingConfig#getNumMergeBuffersConfigured()]
2023-10-19 14:29:26 2023-10-19T12:29:26,614 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [${base_path}.indexes.skipValueRangeIndexScale] on [org.apache.druid.query.DruidProcessingConfig#skipValueRangeIndexScale()]
2023-10-19 14:29:26 2023-10-19T12:29:26,614 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [${base_path}.indexes.skipValuePredicateIndexScale] on [org.apache.druid.query.DruidProcessingConfig#skipValuePredicateIndexScale()]
2023-10-19 14:29:26 2023-10-19T12:29:26,614 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [${base_path}.fifo] on [org.apache.druid.query.DruidProcessingConfig#isFifo()]
2023-10-19 14:29:26 2023-10-19T12:29:26,614 INFO [main] org.skife.config.ConfigurationObjectFactory - Assigning value [var/druid/processing] for [druid.processing.tmpDir] on [org.apache.druid.query.DruidProcessingConfig#getTmpDir()]
2023-10-19 14:29:26 2023-10-19T12:29:26,614 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [${base_path}.merge.useParallelMergePool] on [org.apache.druid.query.DruidProcessingConfig#useParallelMergePoolConfigured()]
2023-10-19 14:29:26 2023-10-19T12:29:26,614 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [${base_path}.merge.pool.parallelism] on [org.apache.druid.query.DruidProcessingConfig#getMergePoolParallelismConfigured()]
2023-10-19 14:29:26 2023-10-19T12:29:26,615 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [${base_path}.merge.pool.defaultMaxQueryParallelism] on [org.apache.druid.query.DruidProcessingConfig#getMergePoolDefaultMaxQueryParallelism()]
2023-10-19 14:29:26 2023-10-19T12:29:26,615 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [${base_path}.merge.task.targetRunTimeMillis] on [org.apache.druid.query.DruidProcessingConfig#getMergePoolTargetTaskRunTimeMillis()]
2023-10-19 14:29:26 2023-10-19T12:29:26,615 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [${base_path}.merge.task.initialYieldNumRows] on [org.apache.druid.query.DruidProcessingConfig#getMergePoolTaskInitialYieldRows()]
2023-10-19 14:29:26 2023-10-19T12:29:26,615 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [${base_path}.merge.task.smallBatchNumRows] on [org.apache.druid.query.DruidProcessingConfig#getMergePoolSmallBatchRows()]
2023-10-19 14:29:26 2023-10-19T12:29:26,615 INFO [main] org.skife.config.ConfigurationObjectFactory - Assigning default value [processing-%s] for [${base_path}.formatString] on [org.apache.druid.java.util.common.concurrent.ExecutorServiceConfig#getFormatString()]
2023-10-19 14:29:26 2023-10-19T12:29:26,711 INFO [main] org.apache.druid.offheap.OffheapBufferGenerator - Allocating new intermediate processing buffer[0] of size[524,288,000]
2023-10-19 14:29:26 2023-10-19T12:29:26,861 INFO [main] org.apache.druid.offheap.OffheapBufferGenerator - Allocating new intermediate processing buffer[1] of size[524,288,000]
2023-10-19 14:29:27 2023-10-19T12:29:27,052 INFO [main] org.apache.druid.offheap.OffheapBufferGenerator - Allocating new result merging buffer[0] of size[524,288,000]
2023-10-19 14:29:27 2023-10-19T12:29:27,189 INFO [main] org.apache.druid.offheap.OffheapBufferGenerator - Allocating new result merging buffer[1] of size[524,288,000]
2023-10-19 14:29:27 2023-10-19T12:29:27,477 INFO [main] org.apache.druid.segment.loading.SegmentLocalCacheManager - Using storage location strategy: [LeastBytesUsedStorageLocationSelectorStrategy]
2023-10-19 14:29:27 2023-10-19T12:29:27,589 INFO [main] org.apache.druid.java.util.common.lifecycle.Lifecycle - Starting lifecycle [module] stage [INIT]
2023-10-19 14:29:27 2023-10-19T12:29:27,590 INFO [main] org.apache.druid.java.util.common.lifecycle.Lifecycle - Starting lifecycle [module] stage [NORMAL]
2023-10-19 14:29:27 2023-10-19T12:29:27,590 INFO [main] org.apache.curator.framework.imps.CuratorFrameworkImpl - Starting
2023-10-19 14:29:27 2023-10-19T12:29:27,607 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:zookeeper.version=3.5.10-a32c7183d42325b03e44a06aade6a0f16955bf13, built on 05/29/2022 16:59 GMT
2023-10-19 14:29:27 2023-10-19T12:29:27,607 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:host.name=8be2e443324b
2023-10-19 14:29:27 2023-10-19T12:29:27,607 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.version=11.0.18
2023-10-19 14:29:27 2023-10-19T12:29:27,607 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.vendor=Debian
2023-10-19 14:29:27 2023-10-19T12:29:27,607 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.home=/usr/lib/jvm/java-11-openjdk-amd64
2023-10-19 14:29:27 2023-10-19T12:29:27,607 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.class.path=/tmp/conf/druid/cluster/_common:/tmp/conf/druid/cluster/data/historical:lib/jackson-dataformat-cbor-2.10.5.jar:lib/druid-server-27.0.0.jar:lib/log4j-core-2.18.0.jar:lib/spymemcached-2.12.3.jar:lib/commons-beanutils-1.9.4.jar:lib/avatica-server-1.17.0.jar:lib/jetty-util-ajax-9.4.51.v20230217.jar:lib/asm-commons-9.3.jar:lib/okhttp-1.0.2.jar:lib/jakarta.el-3.0.4.jar:lib/aws-java-sdk-sts-1.12.497.jar:lib/javax.activation-1.2.0.jar:lib/aggdesigner-algorithm-6.0.jar:lib/commons-compiler-3.0.11.jar:lib/jetty-client-9.4.51.v20230217.jar:lib/aether-util-0.9.0.M2.jar:lib/ipaddress-5.3.4.jar:lib/jetty-continuation-9.4.51.v20230217.jar:lib/jackson-jaxrs-json-provider-2.10.5.jar:lib/jersey-server-1.19.4.jar:lib/fastutil-core-8.5.4.jar:lib/slf4j-api-1.7.36.jar:lib/accessors-smart-2.4.11.jar:lib/commons-codec-1.13.jar:lib/config-magic-0.9.jar:lib/hibernate-validator-5.3.6.Final.jar:lib/guice-assistedinject-4.1.0.jar:lib/jetty-security-9.4.51.v20230217.jar:lib/netty-resolver-dns-4.1.94.Final.jar:lib/guice-multibindings-4.1.0.jar:lib/netty-common-4.1.94.Final.jar:lib/aether-spi-0.9.0.M2.jar:lib/google-http-client-1.26.0.jar:lib/fastutil-extra-8.5.4.jar:lib/commons-io-2.11.0.jar:lib/validation-api-1.1.0.Final.jar:lib/jackson-jaxrs-base-2.10.5.jar:lib/zookeeper-3.5.10.jar:lib/log4j-slf4j-impl-2.18.0.jar:lib/netty-3.10.6.Final.jar:lib/jetty-servlet-9.4.51.v20230217.jar:lib/jakarta.inject-api-1.0.3.jar:lib/protobuf-java-3.21.7.jar:lib/netty-handler-proxy-4.1.94.Final.jar:lib/jmespath-java-1.12.497.jar:lib/metrics-core-4.0.0.jar:lib/jackson-core-2.10.5.jar:lib/janino-3.0.11.jar:lib/jetty-util-9.4.51.v20230217.jar:lib/jsr305-2.0.1.jar:lib/jackson-datatype-joda-2.10.5.jar:lib/jetty-rewrite-9.4.51.v20230217.jar:lib/druid-aws-common-27.0.0.jar:lib/airline-io-2.8.4.jar:lib/avatica-metrics-1.17.0.jar:lib/druid-sql-27.0.0.jar:lib/netty-buffer-4.1.94.Final.jar:lib/aether-connector-okhttp-0.0.9.jar:lib/icu4j-55.1.jar:lib/derby-10.14.2.0.jar:lib/javax.inject-1.jar:lib/resilience4j-core-1.3.1.jar:lib/avatica-core-1.17.0.jar:lib/netty-transport-classes-epoll-4.1.94.Final.jar:lib/curator-recipes-5.4.0.jar:lib/jcodings-1.0.43.jar:lib/json-path-2.3.0.jar:lib/aether-impl-0.9.0.M2.jar:lib/google-oauth-client-1.26.0.jar:lib/netty-codec-dns-4.1.94.Final.jar:lib/jackson-module-jaxb-annotations-2.10.5.jar:lib/oshi-core-6.4.2.jar:lib/zstd-jni-1.5.2-3.jar:lib/audience-annotations-0.5.0.jar:lib/caffeine-2.8.0.jar:lib/antlr4-runtime-4.5.1.jar:lib/zookeeper-jute-3.5.10.jar:lib/druid-indexing-service-27.0.0.jar:lib/google-http-client-jackson2-1.26.0.jar:lib/classmate-1.3.1.jar:lib/txw2-2.3.1.jar:lib/jetty-server-9.4.51.v20230217.jar:lib/jetty-io-9.4.51.v20230217.jar:lib/web-console-27.0.0.jar:lib/FastInfoset-1.2.15.jar:lib/resilience4j-bulkhead-1.3.1.jar:lib/airline-2.8.4.jar:lib/log4j-jul-2.18.0.jar:lib/curator-client-5.4.0.jar:lib/netty-handler-4.1.94.Final.jar:lib/jetty-servlets-9.4.51.v20230217.jar:lib/netty-resolver-4.1.94.Final.jar:lib/cron-scheduler-0.1.jar:lib/commons-lang3-3.12.0.jar:lib/jackson-datatype-guava-2.10.5.jar:lib/aopalliance-1.0.jar:lib/commons-dbcp2-2.0.1.jar:lib/calcite-linq4j-1.21.0.jar:lib/jackson-dataformat-smile-2.10.5.jar:lib/plexus-interpolation-1.19.jar:lib/javax.el-api-3.0.0.jar:lib/jna-platform-5.13.0.jar:lib/commons-pool2-2.2.jar:lib/log4j-api-2.18.0.jar:lib/async-http-client-netty-utils-2.5.3.jar:lib/lz4-java-1.8.0.jar:lib/httpcore-4.4.11.jar:lib/jetty-http-9.4.51.v20230217.jar:lib/maven-repository-metadata-3.1.1.jar:lib/json-smart-2.4.11.jar:lib/maven-settings-3.1.1.jar:lib/jakarta.activation-api-1.2.1.jar:lib/guice-servlet-4.1.0.jar:lib/netty-transport-native-epoll-4.1.94.Final-linux-x86_64.jar:lib/jaxb-api-2.3.1.jar:lib/aws-java-sdk-s3-1.12.497.jar:lib/joni-2.1.27.jar:lib/derbynet-10.14.2.0.jar:lib/sigar-1.6.5.132.jar:lib/jackson-jaxrs-smile-provider-2.10.5.jar:lib/vavr-match-0.10.2.jar:lib/asm-tree-9.3.jar:lib/esri-geometry-api-2.2.0.jar:lib/jaxb-runtime-2.3.1.jar:lib/netty-codec-http-4.1.94.Final.jar:lib/jcl-over-slf4j-1.7.36.jar:lib/commons-collections-3.2.2.jar:lib/plexus-utils-3.0.24.jar:lib/jakarta.xml.bind-api-2.3.2.jar:lib/async-http-client-2.5.3.jar:lib/asm-analysis-9.3.jar:lib/xz-1.8.jar:lib/jersey-guice-1.19.4.jar:lib/commons-compress-1.21.jar:lib/datasketches-memory-2.2.0.jar:lib/jersey-core-1.19.4.jar:lib/javax.servlet-api-3.1.0.jar:lib/stax-ex-1.8.jar:lib/druid-processing-27.0.0.jar:lib/curator-x-discovery-5.4.0.jar:lib/commons-text-1.10.0.jar:lib/aether-api-0.9.0.M2.jar:lib/jna-5.13.0.jar:lib/jackson-annotations-2.10.5.jar:lib/maven-model-builder-3.1.1.jar:lib/jsr311-api-1.1.1.jar:lib/aws-java-sdk-core-1.12.497.jar:lib/jackson-databind-2.10.5.1.jar:lib/curator-framework-5.4.0.jar:lib/checker-qual-2.5.7.jar:lib/guava-16.0.1.jar:lib/jackson-jq-0.0.10.jar:lib/shims-0.9.0.jar:lib/guice-4.1.0.jar:lib/netty-codec-socks-4.1.94.Final.jar:lib/maven-model-3.1.1.jar:lib/druid-indexing-hadoop-27.0.0.jar:lib/jackson-module-guice-2.10.5.jar:lib/calcite-core-1.21.0.jar:lib/maven-settings-builder-3.1.1.jar:lib/derbyclient-10.14.2.0.jar:lib/google-api-client-1.26.0.jar:lib/reactive-streams-1.0.2.jar:lib/istack-commons-runtime-3.0.7.jar:lib/disruptor-3.3.6.jar:lib/error_prone_annotations-2.20.0.jar:lib/aws-java-sdk-kms-1.12.497.jar:lib/netty-transport-native-unix-common-4.1.94.Final.jar:lib/jersey-servlet-1.19.4.jar:lib/vavr-0.10.2.jar:lib/maven-artifact-3.6.0.jar:lib/aether-connector-file-0.9.0.M2.jar:lib/log4j-1.2-api-2.18.0.jar:lib/wagon-provider-api-2.4.jar:lib/datasketches-java-4.1.0.jar:lib/tesla-aether-0.0.5.jar:lib/commons-collections4-4.2.jar:lib/commons-math3-3.6.1.jar:lib/rhino-1.7.11.jar:lib/druid-gcp-common-27.0.0.jar:lib/ion-java-1.0.2.jar:lib/aws-java-sdk-ec2-1.12.497.jar:lib/RoaringBitmap-0.9.0.jar:lib/commons-lang-2.6.jar:lib/javax.activation-api-1.2.0.jar:lib/commons-logging-1.1.1.jar:lib/compress-lzf-1.0.4.jar:lib/maven-aether-provider-3.1.1.jar:lib/jdbi-2.63.1.jar:lib/jetty-proxy-9.4.51.v20230217.jar:lib/j2objc-annotations-1.1.jar:lib/fastutil-8.5.4.jar:lib/druid-services-27.0.0.jar:lib/httpclient-4.5.13.jar:lib/jboss-logging-3.3.0.Final.jar:lib/netty-codec-4.1.94.Final.jar:lib/opencsv-4.6.jar:lib/netty-reactive-streams-2.0.0.jar:lib/joda-time-2.12.4.jar:lib/asm-9.3.jar:lib/netty-transport-4.1.94.Final.jar:lib/postgresql-42.4.3.jar:lib/postgresql-metadata-storage-27.0.0.jar:
2023-10-19 14:29:27 2023-10-19T12:29:27,608 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.library.path=/usr/java/packages/lib:/usr/lib/x86_64-linux-gnu/jni:/lib/x86_64-linux-gnu:/usr/lib/x86_64-linux-gnu:/usr/lib/jni:/lib:/usr/lib
2023-10-19 14:29:27 2023-10-19T12:29:27,608 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.io.tmpdir=var/tmp
2023-10-19 14:29:27 2023-10-19T12:29:27,608 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.compiler=<NA>
2023-10-19 14:29:27 2023-10-19T12:29:27,608 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:os.name=Linux
2023-10-19 14:29:27 2023-10-19T12:29:27,608 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:os.arch=amd64
2023-10-19 14:29:27 2023-10-19T12:29:27,608 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:os.version=6.4.16-linuxkit
2023-10-19 14:29:27 2023-10-19T12:29:27,608 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:user.name=druid
2023-10-19 14:29:27 2023-10-19T12:29:27,608 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:user.home=/opt/druid
2023-10-19 14:29:27 2023-10-19T12:29:27,608 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:user.dir=/opt/druid
2023-10-19 14:29:27 2023-10-19T12:29:27,608 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:os.memory.free=1853MB
2023-10-19 14:29:27 2023-10-19T12:29:27,608 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:os.memory.max=2048MB
2023-10-19 14:29:27 2023-10-19T12:29:27,608 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:os.memory.total=2048MB
2023-10-19 14:29:27 2023-10-19T12:29:27,610 INFO [main] org.apache.zookeeper.ZooKeeper - Initiating client connection, connectString=zookeeper sessionTimeout=30000 watcher=org.apache.curator.ConnectionState@13018f00
2023-10-19 14:29:27 2023-10-19T12:29:27,612 INFO [main] org.apache.zookeeper.common.X509Util - Setting -D jdk.tls.rejectClientInitiatedRenegotiation=true to disable client-initiated TLS renegotiation
2023-10-19 14:29:27 2023-10-19T12:29:27,620 INFO [main] org.apache.zookeeper.ClientCnxnSocket - jute.maxbuffer value is 4194304 Bytes
2023-10-19 14:29:27 2023-10-19T12:29:27,624 INFO [main] org.apache.zookeeper.ClientCnxn - zookeeper.request.timeout value is 0. feature enabled=
2023-10-19 14:29:27 2023-10-19T12:29:27,706 INFO [main] org.apache.curator.framework.imps.CuratorFrameworkImpl - Default schema
2023-10-19 14:29:27 2023-10-19T12:29:27,706 INFO [main-SendThread(zookeeper:2181)] org.apache.zookeeper.ClientCnxn - Opening socket connection to server zookeeper/172.24.0.2:2181. Will not attempt to authenticate using SASL (unknown error)
2023-10-19 14:29:27 2023-10-19T12:29:27,760 INFO [main-SendThread(zookeeper:2181)] org.apache.zookeeper.ClientCnxn - Socket connection established, initiating session, client: /172.24.0.10:60580, server: zookeeper/172.24.0.2:2181
2023-10-19 14:29:27 2023-10-19T12:29:27,780 INFO [main] org.apache.druid.security.basic.authentication.db.cache.CoordinatorPollingBasicAuthenticatorCacheManager - Starting CoordinatorPollingBasicAuthenticatorCacheManager.
2023-10-19 14:29:27 2023-10-19T12:29:27,781 INFO [main-SendThread(zookeeper:2181)] org.apache.zookeeper.ClientCnxn - Session establishment complete on server zookeeper/172.24.0.2:2181, sessionid = 0x100059f4acf0005, negotiated timeout = 30000
2023-10-19 14:29:27 2023-10-19T12:29:27,826 INFO [main-EventThread] org.apache.curator.framework.state.ConnectionStateManager - State change: CONNECTED
2023-10-19 14:29:27 2023-10-19T12:29:27,892 INFO [main-EventThread] org.apache.curator.framework.imps.EnsembleTracker - New config event received: {}
2023-10-19 14:29:27 2023-10-19T12:29:27,892 INFO [main-EventThread] org.apache.curator.framework.imps.EnsembleTracker - New config event received: {}
2023-10-19 14:29:27 2023-10-19T12:29:27,904 WARN [NodeRoleWatcher[COORDINATOR]] org.apache.druid.curator.discovery.CuratorDruidNodeDiscoveryProvider$NodeRoleWatcher - Ignored event type [CONNECTION_RECONNECTED] for node watcher of role [coordinator].
2023-10-19 14:29:27 2023-10-19T12:29:27,907 INFO [NodeRoleWatcher[COORDINATOR]] org.apache.druid.discovery.BaseNodeRoleWatcher - Node watcher of role [coordinator] is now initialized with 0 nodes.
2023-10-19 14:29:27 2023-10-19T12:29:27,910 WARN [main] org.apache.druid.java.util.common.RetryUtils - Retrying (1 of 9) in 1,044ms.
2023-10-19 14:29:27 org.apache.druid.java.util.common.IOE: No known server
2023-10-19 14:29:27 at org.apache.druid.discovery.DruidLeaderClient.getCurrentKnownLeader(DruidLeaderClient.java:267) ~[druid-server-27.0.0.jar:27.0.0]
2023-10-19 14:29:27 at org.apache.druid.discovery.DruidLeaderClient.makeRequest(DruidLeaderClient.java:122) ~[druid-server-27.0.0.jar:27.0.0]
2023-10-19 14:29:27 at org.apache.druid.security.basic.authentication.db.cache.CoordinatorPollingBasicAuthenticatorCacheManager.tryFetchUserMapFromCoordinator(CoordinatorPollingBasicAuthenticatorCacheManager.java:252) ~[?:?]
2023-10-19 14:29:27 at org.apache.druid.security.basic.authentication.db.cache.CoordinatorPollingBasicAuthenticatorCacheManager.lambda$fetchUserMapFromCoordinator$1(CoordinatorPollingBasicAuthenticatorCacheManager.java:192) ~[?:?]
2023-10-19 14:29:27 at org.apache.druid.java.util.common.RetryUtils.retry(RetryUtils.java:129) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:27 at org.apache.druid.java.util.common.RetryUtils.retry(RetryUtils.java:81) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:27 at org.apache.druid.java.util.common.RetryUtils.retry(RetryUtils.java:163) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:27 at org.apache.druid.java.util.common.RetryUtils.retry(RetryUtils.java:153) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:27 at org.apache.druid.security.basic.authentication.db.cache.CoordinatorPollingBasicAuthenticatorCacheManager.fetchUserMapFromCoordinator(CoordinatorPollingBasicAuthenticatorCacheManager.java:190) ~[?:?]
2023-10-19 14:29:27 at org.apache.druid.security.basic.authentication.db.cache.CoordinatorPollingBasicAuthenticatorCacheManager.initUserMaps(CoordinatorPollingBasicAuthenticatorCacheManager.java:289) ~[?:?]
2023-10-19 14:29:27 at org.apache.druid.security.basic.authentication.db.cache.CoordinatorPollingBasicAuthenticatorCacheManager.start(CoordinatorPollingBasicAuthenticatorCacheManager.java:108) ~[?:?]
2023-10-19 14:29:27 at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
2023-10-19 14:29:27 at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
2023-10-19 14:29:27 at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
2023-10-19 14:29:27 at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
2023-10-19 14:29:27 at org.apache.druid.java.util.common.lifecycle.Lifecycle$AnnotationBasedHandler.start(Lifecycle.java:446) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:27 at org.apache.druid.java.util.common.lifecycle.Lifecycle.start(Lifecycle.java:341) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:27 at org.apache.druid.guice.LifecycleModule$2.start(LifecycleModule.java:152) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:27 at org.apache.druid.cli.GuiceRunnable.initLifecycle(GuiceRunnable.java:136) ~[druid-services-27.0.0.jar:27.0.0]
2023-10-19 14:29:27 at org.apache.druid.cli.GuiceRunnable.initLifecycle(GuiceRunnable.java:94) ~[druid-services-27.0.0.jar:27.0.0]
2023-10-19 14:29:27 at org.apache.druid.cli.ServerRunnable.run(ServerRunnable.java:63) ~[druid-services-27.0.0.jar:27.0.0]
2023-10-19 14:29:27 at org.apache.druid.cli.Main.main(Main.java:112) ~[druid-services-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 2023-10-19T12:29:28,960 WARN [main] org.apache.druid.java.util.common.RetryUtils - Retrying (2 of 9) in 2,385ms.
2023-10-19 14:29:28 org.apache.druid.java.util.common.IOE: No known server
2023-10-19 14:29:28 at org.apache.druid.discovery.DruidLeaderClient.getCurrentKnownLeader(DruidLeaderClient.java:267) ~[druid-server-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 at org.apache.druid.discovery.DruidLeaderClient.makeRequest(DruidLeaderClient.java:122) ~[druid-server-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 at org.apache.druid.security.basic.authentication.db.cache.CoordinatorPollingBasicAuthenticatorCacheManager.tryFetchUserMapFromCoordinator(CoordinatorPollingBasicAuthenticatorCacheManager.java:252) ~[?:?]
2023-10-19 14:29:28 at org.apache.druid.security.basic.authentication.db.cache.CoordinatorPollingBasicAuthenticatorCacheManager.lambda$fetchUserMapFromCoordinator$1(CoordinatorPollingBasicAuthenticatorCacheManager.java:192) ~[?:?]
2023-10-19 14:29:28 at org.apache.druid.java.util.common.RetryUtils.retry(RetryUtils.java:129) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 at org.apache.druid.java.util.common.RetryUtils.retry(RetryUtils.java:81) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 at org.apache.druid.java.util.common.RetryUtils.retry(RetryUtils.java:163) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 at org.apache.druid.java.util.common.RetryUtils.retry(RetryUtils.java:153) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 at org.apache.druid.security.basic.authentication.db.cache.CoordinatorPollingBasicAuthenticatorCacheManager.fetchUserMapFromCoordinator(CoordinatorPollingBasicAuthenticatorCacheManager.java:190) ~[?:?]
2023-10-19 14:29:28 at org.apache.druid.security.basic.authentication.db.cache.CoordinatorPollingBasicAuthenticatorCacheManager.initUserMaps(CoordinatorPollingBasicAuthenticatorCacheManager.java:289) ~[?:?]
2023-10-19 14:29:28 at org.apache.druid.security.basic.authentication.db.cache.CoordinatorPollingBasicAuthenticatorCacheManager.start(CoordinatorPollingBasicAuthenticatorCacheManager.java:108) ~[?:?]
2023-10-19 14:29:28 at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
2023-10-19 14:29:28 at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
2023-10-19 14:29:28 at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
2023-10-19 14:29:28 at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
2023-10-19 14:29:28 at org.apache.druid.java.util.common.lifecycle.Lifecycle$AnnotationBasedHandler.start(Lifecycle.java:446) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 at org.apache.druid.java.util.common.lifecycle.Lifecycle.start(Lifecycle.java:341) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 at org.apache.druid.guice.LifecycleModule$2.start(LifecycleModule.java:152) ~[druid-processing-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 at org.apache.druid.cli.GuiceRunnable.initLifecycle(GuiceRunnable.java:136) ~[druid-services-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 at org.apache.druid.cli.GuiceRunnable.initLifecycle(GuiceRunnable.java:94) ~[druid-services-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 at org.apache.druid.cli.ServerRunnable.run(ServerRunnable.java:63) ~[druid-services-27.0.0.jar:27.0.0]
2023-10-19 14:29:28 at org.apache.druid.cli.Main.main(Main.java:112) ~[druid-services-27.0.0.jar:27.0.0]
2023-10-19 14:29:30 2023-10-19T12:29:30,567 INFO [NodeRoleWatcher[COORDINATOR]] org.apache.druid.discovery.BaseNodeRoleWatcher - Node [http://172.24.0.5:8081] of role [coordinator] detected.
2023-10-19 14:29:31 2023-10-19T12:29:31,605 INFO [main] org.apache.druid.security.basic.authentication.db.cache.CoordinatorPollingBasicAuthenticatorCacheManager - Started CoordinatorPollingBasicAuthenticatorCacheManager.
2023-10-19 14:29:31 2023-10-19T12:29:31,607 INFO [main] org.apache.druid.security.basic.authorization.db.cache.CoordinatorPollingBasicAuthorizerCacheManager - Starting CoordinatorPollingBasicAuthorizerCacheManager.
2023-10-19 14:29:31 2023-10-19T12:29:31,712 INFO [main] org.apache.druid.security.basic.authorization.db.cache.CoordinatorPollingBasicAuthorizerCacheManager - Started CoordinatorPollingBasicAuthorizerCacheManager.
2023-10-19 14:29:31 2023-10-19T12:29:31,716 INFO [main] org.apache.druid.server.coordination.SegmentLoadDropHandler - Starting...
2023-10-19 14:29:31 2023-10-19T12:29:31,719 INFO [main] org.apache.druid.server.coordination.SegmentLoadDropHandler - Loading segment cache file [1/4][var/druid/segment-cache/info_dir/6e53eb54-8bed-42df-9200-1bca097a5148-1_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:03:38.168Z].
2023-10-19 14:29:31 2023-10-19T12:29:31,780 INFO [main] org.apache.druid.server.coordination.SegmentLoadDropHandler - Loading segment cache file [2/4][var/druid/segment-cache/info_dir/6e53eb54-8bed-42df-9200-1bca097a5148_simplifiedEdge-1_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:03:38.290Z].
2023-10-19 14:29:31 2023-10-19T12:29:31,781 INFO [main] org.apache.druid.server.coordination.SegmentLoadDropHandler - Loading segment cache file [3/4][var/druid/segment-cache/info_dir/testdatasource-1_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:03:38.265Z].
2023-10-19 14:29:31 2023-10-19T12:29:31,782 INFO [main] org.apache.druid.server.coordination.SegmentLoadDropHandler - Loading segment cache file [4/4][var/druid/segment-cache/info_dir/6e53eb54-8bed-42df-9200-1bca097a5148_vertex-1_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:03:38.251Z].
2023-10-19 14:29:31 2023-10-19T12:29:31,788 INFO [Segment-Load-Startup-1] org.apache.druid.server.coordination.SegmentLoadDropHandler - Loading segment[2/4][6e53eb54-8bed-42df-9200-1bca097a5148_simplifiedEdge-1_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:03:38.290Z]
2023-10-19 14:29:31 2023-10-19T12:29:31,788 INFO [Segment-Load-Startup-0] org.apache.druid.server.coordination.SegmentLoadDropHandler - Loading segment[1/4][6e53eb54-8bed-42df-9200-1bca097a5148-1_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:03:38.168Z]
2023-10-19 14:29:32 2023-10-19T12:29:32,008 INFO [Segment-Load-Startup-1] org.apache.druid.server.coordination.SegmentLoadDropHandler - Loading segment[3/4][testdatasource-1_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:03:38.265Z]
2023-10-19 14:29:32 2023-10-19T12:29:32,008 INFO [Segment-Load-Startup-0] org.apache.druid.server.coordination.SegmentLoadDropHandler - Loading segment[4/4][6e53eb54-8bed-42df-9200-1bca097a5148_vertex-1_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:03:38.251Z]
2023-10-19 14:29:32 2023-10-19T12:29:32,053 INFO [main] org.apache.druid.server.coordination.BatchDataSegmentAnnouncer - Announcing segment[6e53eb54-8bed-42df-9200-1bca097a5148_simplifiedEdge-1_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:03:38.290Z] at path[/druid/segments/172.24.0.10:8083/172.24.0.10:8083_historical__default_tier_2023-10-19T12:29:32.021Z_8f74406b79c648f5a38461d9555063c90]
2023-10-19 14:29:32 2023-10-19T12:29:32,054 INFO [main] org.apache.druid.server.coordination.BatchDataSegmentAnnouncer - Announcing segment[6e53eb54-8bed-42df-9200-1bca097a5148-1_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:03:38.168Z] at path[/druid/segments/172.24.0.10:8083/172.24.0.10:8083_historical__default_tier_2023-10-19T12:29:32.021Z_8f74406b79c648f5a38461d9555063c90]
2023-10-19 14:29:32 2023-10-19T12:29:32,055 INFO [main] org.apache.druid.server.coordination.BatchDataSegmentAnnouncer - Announcing segment[testdatasource-1_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:03:38.265Z] at path[/druid/segments/172.24.0.10:8083/172.24.0.10:8083_historical__default_tier_2023-10-19T12:29:32.021Z_8f74406b79c648f5a38461d9555063c90]
2023-10-19 14:29:32 2023-10-19T12:29:32,055 INFO [main] org.apache.druid.server.coordination.BatchDataSegmentAnnouncer - Announcing segment[6e53eb54-8bed-42df-9200-1bca097a5148_vertex-1_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:03:38.251Z] at path[/druid/segments/172.24.0.10:8083/172.24.0.10:8083_historical__default_tier_2023-10-19T12:29:32.021Z_8f74406b79c648f5a38461d9555063c90]
2023-10-19 14:29:32 2023-10-19T12:29:32,057 INFO [main] org.apache.druid.server.coordination.SegmentLoadDropHandler$BackgroundSegmentAnnouncer - Completed background segment announcing
2023-10-19 14:29:32 2023-10-19T12:29:32,059 INFO [main] org.apache.druid.server.coordination.SegmentLoadDropHandler - Cache load took 341 ms
2023-10-19 14:29:32 2023-10-19T12:29:32,062 INFO [main] org.apache.druid.server.coordination.CuratorDataSegmentServerAnnouncer - Announcing self[DruidServerMetadata{name='172.24.0.10:8083', hostAndPort='172.24.0.10:8083', hostAndTlsPort='null', maxSize=300000000000, tier='_default_tier', type=historical, priority=0}] at [/druid/announcements/172.24.0.10:8083]
2023-10-19 14:29:32 2023-10-19T12:29:32,065 INFO [main] org.apache.druid.server.coordination.SegmentLoadDropHandler - Started.
2023-10-19 14:29:32 2023-10-19T12:29:32,066 INFO [main] org.apache.druid.server.coordination.ZkCoordinator - Starting zkCoordinator for server[172.24.0.10:8083]
2023-10-19 14:29:32 2023-10-19T12:29:32,083 INFO [main] org.apache.druid.java.util.common.lifecycle.Lifecycle - Starting lifecycle [module] stage [SERVER]
2023-10-19 14:29:32 2023-10-19T12:29:32,090 INFO [main] org.eclipse.jetty.server.Server - jetty-9.4.51.v20230217; built: 2023-02-17T08:19:37.309Z; git: b45c405e4544384de066f814ed42ae3dceacdd49; jvm 11.0.18+10-post-Debian-1deb11u1
2023-10-19 14:29:32 2023-10-19T12:29:32,111 INFO [main] org.eclipse.jetty.server.session - DefaultSessionIdManager workerName=node0
2023-10-19 14:29:32 2023-10-19T12:29:32,111 INFO [main] org.eclipse.jetty.server.session - No SessionScavenger set, using defaults
2023-10-19 14:29:32 2023-10-19T12:29:32,112 INFO [main] org.eclipse.jetty.server.session - node0 Scavenging every 600000ms
2023-10-19 14:29:32 2023-10-19T12:29:32,577 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Registering com.fasterxml.jackson.jaxrs.json.JacksonJsonProvider as a provider class
2023-10-19 14:29:32 2023-10-19T12:29:32,579 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Registering com.fasterxml.jackson.jaxrs.smile.JacksonSmileProvider as a provider class
2023-10-19 14:29:32 2023-10-19T12:29:32,579 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Registering org.apache.druid.server.initialization.jetty.CustomExceptionMapper as a provider class
2023-10-19 14:29:32 2023-10-19T12:29:32,579 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Registering org.apache.druid.server.initialization.jetty.ForbiddenExceptionMapper as a provider class
2023-10-19 14:29:32 2023-10-19T12:29:32,579 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Registering org.apache.druid.server.initialization.jetty.BadRequestExceptionMapper as a provider class
2023-10-19 14:29:32 2023-10-19T12:29:32,580 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Registering org.apache.druid.server.initialization.jetty.ServiceUnavailableExceptionMapper as a provider class
2023-10-19 14:29:32 2023-10-19T12:29:32,580 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Registering org.apache.druid.server.StatusResource as a root resource class
2023-10-19 14:29:32 2023-10-19T12:29:32,586 INFO [main] com.sun.jersey.server.impl.application.WebApplicationImpl - Initiating Jersey application, version 'Jersey: 1.19.4 05/24/2017 03:20 PM'
2023-10-19 14:29:32 2023-10-19T12:29:32,654 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.server.initialization.jetty.CustomExceptionMapper to GuiceManagedComponentProvider with the scope "Singleton"
2023-10-19 14:29:32 2023-10-19T12:29:32,659 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.server.initialization.jetty.ForbiddenExceptionMapper to GuiceManagedComponentProvider with the scope "Singleton"
2023-10-19 14:29:32 2023-10-19T12:29:32,661 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.server.initialization.jetty.BadRequestExceptionMapper to GuiceManagedComponentProvider with the scope "Singleton"
2023-10-19 14:29:32 2023-10-19T12:29:32,662 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.server.initialization.jetty.ServiceUnavailableExceptionMapper to GuiceManagedComponentProvider with the scope "Singleton"
2023-10-19 14:29:32 2023-10-19T12:29:32,663 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding com.fasterxml.jackson.jaxrs.json.JacksonJsonProvider to GuiceManagedComponentProvider with the scope "Singleton"
2023-10-19 14:29:32 2023-10-19T12:29:32,668 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding com.fasterxml.jackson.jaxrs.smile.JacksonSmileProvider to GuiceManagedComponentProvider with the scope "Singleton"
2023-10-19 14:29:33 2023-10-19T12:29:33,086 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.security.basic.BasicSecurityResourceFilter to GuiceInstantiatedComponentProvider
2023-10-19 14:29:33 2023-10-19T12:29:33,126 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.security.basic.authentication.endpoint.BasicAuthenticatorResource to GuiceInstantiatedComponentProvider
2023-10-19 14:29:33 2023-10-19T12:29:33,146 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.security.basic.authorization.endpoint.BasicAuthorizerResource to GuiceInstantiatedComponentProvider
2023-10-19 14:29:33 2023-10-19T12:29:33,151 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.server.QueryResource to GuiceInstantiatedComponentProvider
2023-10-19 14:29:33 2023-10-19T12:29:33,154 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.server.http.security.StateResourceFilter to GuiceInstantiatedComponentProvider
2023-10-19 14:29:33 2023-10-19T12:29:33,160 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.server.http.SegmentListerResource to GuiceInstantiatedComponentProvider
2023-10-19 14:29:33 2023-10-19T12:29:33,165 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.server.http.HistoricalResource to GuiceInstantiatedComponentProvider
2023-10-19 14:29:33 2023-10-19T12:29:33,166 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.server.http.SelfDiscoveryResource to GuiceInstantiatedComponentProvider
2023-10-19 14:29:33 2023-10-19T12:29:33,168 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.server.http.security.ConfigResourceFilter to GuiceInstantiatedComponentProvider
2023-10-19 14:29:33 2023-10-19T12:29:33,174 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.query.lookup.LookupListeningResource to GuiceInstantiatedComponentProvider
2023-10-19 14:29:33 2023-10-19T12:29:33,179 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.query.lookup.LookupIntrospectionResource to GuiceInstantiatedComponentProvider
2023-10-19 14:29:33 2023-10-19T12:29:33,180 INFO [main] com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory - Binding org.apache.druid.server.StatusResource to GuiceManagedComponentProvider with the scope "Singleton"
2023-10-19 14:29:33 2023-10-19T12:29:33,210 INFO [main] org.eclipse.jetty.server.handler.ContextHandler - Started o.e.j.s.ServletContextHandler@58cd6088{/,null,AVAILABLE}
2023-10-19 14:29:33 2023-10-19T12:29:33,233 INFO [main] org.eclipse.jetty.server.AbstractConnector - Started ServerConnector@ab5dde4{HTTP/1.1, (http/1.1)}{0.0.0.0:8083}
2023-10-19 14:29:33 2023-10-19T12:29:33,233 INFO [main] org.eclipse.jetty.server.Server - Started @17160ms
2023-10-19 14:29:33 2023-10-19T12:29:33,254 INFO [main] org.apache.druid.java.util.common.lifecycle.Lifecycle - Starting lifecycle [module] stage [ANNOUNCEMENTS]
2023-10-19 14:29:33 2023-10-19T12:29:33,353 INFO [main] org.apache.druid.curator.discovery.CuratorDruidNodeAnnouncer - Announced self [{"druidNode":{"service":"druid/historical","host":"172.24.0.10","bindOnHost":false,"plaintextPort":8083,"port":-1,"tlsPort":-1,"enablePlaintextPort":true,"enableTlsPort":false},"nodeType":"historical","services":{"dataNodeService":{"type":"dataNodeService","tier":"_default_tier","maxSize":300000000000,"type":"historical","serverType":"historical","priority":0},"lookupNodeService":{"type":"lookupNodeService","lookupTier":"__default"}},"startTime":"2023-10-19T12:29:27.572Z"}].
2023-10-19 14:29:33 2023-10-19T12:29:33,356 INFO [main] org.apache.druid.java.util.common.lifecycle.Lifecycle - Successfully started lifecycle [module]
2023-10-19 14:29:33 2023-10-19T12:29:33,924 DEBUG [qtp1298547491-76] org.apache.druid.jetty.RequestLog - 172.24.0.9 GET //172.24.0.10:8083/druid-internal/v1/segments?counter=-1&timeout=240000 HTTP/1.1 204
2023-10-19 14:29:33 2023-10-19T12:29:33,928 DEBUG [qtp1298547491-83] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid-internal/v1/segments?counter=-1&timeout=240000 HTTP/1.1 204
2023-10-19 14:29:34 2023-10-19T12:29:34,142 DEBUG [qtp1298547491-86] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:29:34 2023-10-19T12:29:34,306 WARN [qtp1298547491-85] org.apache.druid.query.lookup.namespace.JdbcExtractionNamespace - No pollPeriod configured for JdbcExtractionNamespace - entries will be loaded only once at startup
2023-10-19 14:29:34 2023-10-19T12:29:34,358 WARN [qtp1298547491-85] org.apache.druid.query.lookup.namespace.JdbcExtractionNamespace - No pollPeriod configured for JdbcExtractionNamespace - entries will be loaded only once at startup
2023-10-19 14:29:34 2023-10-19T12:29:34,403 DEBUG [qtp1298547491-85] org.apache.druid.jetty.RequestLog - 172.24.0.5 POST //172.24.0.10:8083/druid/listen/v1/lookups/updates HTTP/1.1 202
2023-10-19 14:29:34 2023-10-19T12:29:34,658 INFO [NamespaceExtractionCacheManager-0] org.apache.druid.server.lookup.namespace.JdbcCacheGenerator - Finished loading 0 values (0 bytes) for [namespace [JdbcExtractionNamespace{connectorConfig=DbConnectorConfig{createTables=false, connectURI='jdbc:postgresql://db-postgres:5432/xxxxx', user='xxxxx', passwordProvider=org.apache.druid.metadata.DefaultPasswordProvider, dbcpProperties=null}, table='"ttttt"', keyColumn='"id"', valueColumn='"is_completed"', tsColumn='null', filter='null', pollPeriod=PT0S, maxHeapPercentage=10}] : org.apache.druid.server.lookup.namespace.cache.CacheScheduler$EntryImpl@7686ecf] in 243562792 ns
2023-10-19 14:29:34 2023-10-19T12:29:34,658 INFO [NamespaceExtractionCacheManager-1] org.apache.druid.server.lookup.namespace.JdbcCacheGenerator - Finished loading 2 values (308 bytes) for [namespace [JdbcExtractionNamespace{connectorConfig=DbConnectorConfig{createTables=false, connectURI='jdbc:postgresql://db-postgres:5432/xxxxx', user='xxxx', passwordProvider=org.apache.druid.metadata.DefaultPasswordProvider, dbcpProperties=null}, table='"yyyyyy"', keyColumn='"id"', valueColumn='"name"', tsColumn='null', filter='null', pollPeriod=PT0S, maxHeapPercentage=10}] : org.apache.druid.server.lookup.namespace.cache.CacheScheduler$EntryImpl@7911975b] in 237528208 ns
2023-10-19 14:29:34 2023-10-19T12:29:34,703 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.9 POST //172.24.0.10:8083/druid/v2/ HTTP/1.1 204
2023-10-19 14:29:34 2023-10-19T12:29:34,799 DEBUG [qtp1298547491-86] org.apache.druid.jetty.RequestLog - 172.24.0.9 POST //172.24.0.10:8083/druid/v2/ HTTP/1.1 204
2023-10-19 14:29:34 2023-10-19T12:29:34,843 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.9 POST //172.24.0.10:8083/druid/v2/ HTTP/1.1 204
2023-10-19 14:29:34 2023-10-19T12:29:34,890 DEBUG [qtp1298547491-86] org.apache.druid.jetty.RequestLog - 172.24.0.9 POST //172.24.0.10:8083/druid/v2/ HTTP/1.1 204
2023-10-19 14:29:35 2023-10-19T12:29:35,450 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:29:36 2023-10-19T12:29:36,877 DEBUG [qtp1298547491-86] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:29:37 2023-10-19T12:29:37,937 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:29:38 2023-10-19T12:29:38,998 DEBUG [qtp1298547491-86] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:33:32 2023-10-19T12:33:32,877 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:33:33 2023-10-19T12:33:33,916 DEBUG [qtp1298547491-86] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:33:34 2023-10-19T12:33:34,073 DEBUG [qtp1298547491-73] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid-internal/v1/segments?counter=4&hash=1697718572055&timeout=240000 HTTP/1.1 204
2023-10-19 14:33:34 2023-10-19T12:33:34,211 DEBUG [qtp1298547491-80] org.apache.druid.jetty.RequestLog - 172.24.0.9 GET //172.24.0.10:8083/druid-internal/v1/segments?counter=4&hash=1697718572055&timeout=240000 HTTP/1.1 204
2023-10-19 14:33:34 2023-10-19T12:33:34,952 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:33:36 2023-10-19T12:33:36,008 DEBUG [qtp1298547491-86] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:33:37 2023-10-19T12:33:37,038 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:33:38 2023-10-19T12:33:38,071 DEBUG [qtp1298547491-86] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:33:39 2023-10-19T12:33:39,133 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:33:40 2023-10-19T12:33:40,160 DEBUG [qtp1298547491-86] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:33:41 2023-10-19T12:33:41,217 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:33:42 2023-10-19T12:33:42,258 DEBUG [qtp1298547491-86] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:33:43 2023-10-19T12:33:43,286 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:35:30 2023-10-19T12:35:30,939 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:35:31 2023-10-19T12:35:31,975 DEBUG [qtp1298547491-87] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:35:33 2023-10-19T12:35:33,083 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:35:33 2023-10-19T12:35:33,989 INFO [SimpleDataSegmentChangeHandler-0] org.apache.druid.server.coordination.SegmentLoadDropHandler - Loading segment testdatasource-3_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:35:12.369Z
2023-10-19 14:35:34 2023-10-19T12:35:34,058 INFO [SimpleDataSegmentChangeHandler-0] org.apache.druid.segment.loading.LocalDataSegmentPuller - Copied 2523 bytes from [/opt/data/segments/testdatasource-3/2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z/2023-10-19T12:35:12.369Z/0/index] to [/opt/druid/var/druid/segment-cache/testdatasource-3/2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z/2023-10-19T12:35:12.369Z/0]
2023-10-19 14:35:34 2023-10-19T12:35:34,086 INFO [SimpleDataSegmentChangeHandler-0] org.apache.druid.server.coordination.BatchDataSegmentAnnouncer - Announcing segment[testdatasource-3_2015-01-01T00:00:00.000Z_2016-01-01T00:00:00.000Z_2023-10-19T12:35:12.369Z] at new path[/druid/segments/172.24.0.10:8083/172.24.0.10:8083_historical__default_tier_2023-10-19T12:35:34.083Z_3c0209abfb5a4f0792dde0412a3af6461]
2023-10-19 14:35:34 2023-10-19T12:35:34,133 DEBUG [SegmentChangeRequestHistory] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid-internal/v1/segments?counter=4&hash=1697718572055&timeout=240000 HTTP/1.1 200
2023-10-19 14:35:34 2023-10-19T12:35:34,140 DEBUG [SegmentChangeRequestHistory] org.apache.druid.jetty.RequestLog - 172.24.0.9 GET //172.24.0.10:8083/druid-internal/v1/segments?counter=4&hash=1697718572055&timeout=240000 HTTP/1.1 200
2023-10-19 14:35:34 2023-10-19T12:35:34,166 DEBUG [qtp1298547491-78] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200
2023-10-19 14:35:34 2023-10-19T12:35:34,166 DEBUG [SimpleDataSegmentChangeHandler-0] org.apache.druid.jetty.RequestLog - 172.24.0.5 POST //172.24.0.10:8083/druid-internal/v1/segments/changeRequests?timeout=300000 HTTP/1.1 200
2023-10-19 14:35:35 2023-10-19T12:35:35,198 DEBUG [qtp1298547491-73] org.apache.druid.jetty.RequestLog - 172.24.0.5 GET //172.24.0.10:8083/druid/listen/v1/lookups HTTP/1.1 200