2015-07-13T15:49:54,120 INFO [main] io.druid.guice.PropertiesModule - Loading properties from common.runtime.properties 2015-07-13T15:49:54,124 INFO [main] io.druid.guice.PropertiesModule - Loading properties from runtime.properties Jul 13, 2015 3:49:54 PM org.hibernate.validator.internal.util.Version INFO: HV000001: Hibernate Validator 5.1.3.Final 2015-07-13T15:49:54,793 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.guice.ExtensionsConfig] from props[druid.extensions.] as [ExtensionsConfig{searchCurrentClassloader=true, coordinates=[io.druid.extensions:druid-examples, io.druid.extensions:druid-kafka-eight, io.druid.extensions:mysql-metadata-storage], defaultVersion='0.8.0-rc2-SNAPSHOT', localRepository='/home/ssubrama/.m2/repository', remoteRepositories=[https://repo1.maven.org/maven2/, https://metamx.artifactoryonline.com/metamx/pub-libs-releases-local]}] 2015-07-13T15:49:54,989 INFO [main] io.druid.initialization.Initialization - Loading extension[io.druid.extensions:druid-examples] for class[io.druid.cli.CliCommandCreator] 2015-07-13T15:49:58,931 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/io/druid/extensions/druid-examples/0.8.0-rc2-SNAPSHOT/druid-examples-0.8.0-rc2-SNAPSHOT.jar] 2015-07-13T15:49:58,931 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/org/twitter4j/twitter4j-core/3.0.3/twitter4j-core-3.0.3.jar] 2015-07-13T15:49:58,931 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/org/twitter4j/twitter4j-async/3.0.3/twitter4j-async-3.0.3.jar] 2015-07-13T15:49:58,932 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/org/twitter4j/twitter4j-stream/3.0.3/twitter4j-stream-3.0.3.jar] 2015-07-13T15:49:58,932 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/commons-validator/commons-validator/1.4.0/commons-validator-1.4.0.jar] 2015-07-13T15:49:58,932 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/commons-beanutils/commons-beanutils/1.8.3/commons-beanutils-1.8.3.jar] 2015-07-13T15:49:58,932 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/commons-digester/commons-digester/1.8/commons-digester-1.8.jar] 2015-07-13T15:49:58,932 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/commons-logging/commons-logging/1.1.1/commons-logging-1.1.1.jar] 2015-07-13T15:49:58,934 INFO [main] io.druid.initialization.Initialization - Loading extension[io.druid.extensions:druid-kafka-eight] for class[io.druid.cli.CliCommandCreator] 2015-07-13T15:49:59,409 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/io/druid/extensions/druid-kafka-eight/0.8.0-rc2-SNAPSHOT/druid-kafka-eight-0.8.0-rc2-SNAPSHOT.jar] 2015-07-13T15:49:59,409 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/org/apache/kafka/kafka_2.10/0.8.2.1/kafka_2.10-0.8.2.1.jar] 2015-07-13T15:49:59,409 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/com/yammer/metrics/metrics-core/2.2.0/metrics-core-2.2.0.jar] 2015-07-13T15:49:59,409 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/org/scala-lang/scala-library/2.10.4/scala-library-2.10.4.jar] 2015-07-13T15:49:59,410 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/org/apache/kafka/kafka-clients/0.8.2.1/kafka-clients-0.8.2.1.jar] 2015-07-13T15:49:59,410 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/net/jpountz/lz4/lz4/1.3.0/lz4-1.3.0.jar] 2015-07-13T15:49:59,410 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/org/xerial/snappy/snappy-java/1.1.1.6/snappy-java-1.1.1.6.jar] 2015-07-13T15:49:59,410 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/org/apache/zookeeper/zookeeper/3.4.6/zookeeper-3.4.6.jar] 2015-07-13T15:49:59,410 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/org/slf4j/slf4j-log4j12/1.6.1/slf4j-log4j12-1.6.1.jar] 2015-07-13T15:49:59,410 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/log4j/log4j/1.2.16/log4j-1.2.16.jar] 2015-07-13T15:49:59,411 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/jline/jline/0.9.94/jline-0.9.94.jar] 2015-07-13T15:49:59,411 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/io/netty/netty/3.7.0.Final/netty-3.7.0.Final.jar] 2015-07-13T15:49:59,411 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/net/sf/jopt-simple/jopt-simple/3.2/jopt-simple-3.2.jar] 2015-07-13T15:49:59,411 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/com/101tec/zkclient/0.3/zkclient-0.3.jar] 2015-07-13T15:49:59,415 INFO [main] io.druid.initialization.Initialization - Loading extension[io.druid.extensions:mysql-metadata-storage] for class[io.druid.cli.CliCommandCreator] 2015-07-13T15:50:00,194 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/io/druid/extensions/mysql-metadata-storage/0.8.0-rc2-SNAPSHOT/mysql-metadata-storage-0.8.0-rc2-SNAPSHOT.jar] 2015-07-13T15:50:00,194 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/mysql/mysql-connector-java/5.1.34/mysql-connector-java-5.1.34.jar] 2015-07-13T15:50:00,194 INFO [main] io.druid.initialization.Initialization - Added URL[file:/home/ssubrama/.m2/repository/org/jdbi/jdbi/2.32/jdbi-2.32.jar] 2015-07-13T15:50:00,387 INFO [main] io.druid.initialization.Initialization - Loading extension[io.druid.extensions:druid-examples] for class[io.druid.initialization.DruidModule] 2015-07-13T15:50:00,388 INFO [main] io.druid.initialization.Initialization - Adding remote extension module[io.druid.examples.ExamplesDruidModule] for class[io.druid.initialization.DruidModule] 2015-07-13T15:50:00,389 INFO [main] io.druid.initialization.Initialization - Loading extension[io.druid.extensions:druid-kafka-eight] for class[io.druid.initialization.DruidModule] 2015-07-13T15:50:00,390 INFO [main] io.druid.initialization.Initialization - Adding remote extension module[io.druid.firehose.kafka.KafkaEightDruidModule] for class[io.druid.initialization.DruidModule] 2015-07-13T15:50:00,390 INFO [main] io.druid.initialization.Initialization - Loading extension[io.druid.extensions:mysql-metadata-storage] for class[io.druid.initialization.DruidModule] 2015-07-13T15:50:00,392 INFO [main] io.druid.initialization.Initialization - Adding remote extension module[io.druid.metadata.storage.mysql.MySQLMetadataStorageModule] for class[io.druid.initialization.DruidModule] 2015-07-13T15:50:01,074 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.server.metrics.DruidMonitorSchedulerConfig] from props[druid.monitoring.] as [io.druid.server.metrics.DruidMonitorSchedulerConfig@143ec35a] 2015-07-13T15:50:01,086 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.server.metrics.MonitorsConfig] from props[druid.monitoring.] as [MonitorsConfig{monitors=[]}] 2015-07-13T15:50:01,106 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.server.DruidNode] from props[druid.] as [DruidNode{serviceName='overlord', host='ssubrama-ld1.linkedin.biz', port=8100}] 2015-07-13T15:50:01,132 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.server.initialization.ServerConfig] from props[druid.server.http.] as [ServerConfig{numThreads=44, maxIdleTime=PT5M}] 2015-07-13T15:50:01,144 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.indexing.common.config.TaskConfig] from props[druid.indexer.task.] as [io.druid.indexing.common.config.TaskConfig@1a984a63] 2015-07-13T15:50:01,151 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.guice.http.DruidHttpClientConfig] from props[druid.global.http.] as [io.druid.guice.http.DruidHttpClientConfig@7997511b] 2015-07-13T15:50:01,226 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.client.indexing.IndexingServiceSelectorConfig] from props[druid.selectors.indexing.] as [io.druid.client.indexing.IndexingServiceSelectorConfig@418194ac] 2015-07-13T15:50:01,232 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.curator.CuratorConfig] from props[druid.zk.service.] as [io.druid.curator.CuratorConfig@7f760654] 2015-07-13T15:50:01,238 WARN [main] org.apache.curator.retry.ExponentialBackoffRetry - maxRetries too large (30). Pinning to 29 2015-07-13T15:50:01,270 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.server.initialization.CuratorDiscoveryConfig] from props[druid.discovery.curator.] as [io.druid.server.initialization.CuratorDiscoveryConfig@15c4bc6] 2015-07-13T15:50:01,424 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.indexing.common.RetryPolicyConfig] from props[druid.peon.taskActionClient.retry.] as [io.druid.indexing.common.RetryPolicyConfig@71dae591] 2015-07-13T15:50:01,428 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.segment.loading.LocalDataSegmentPusherConfig] from props[druid.storage.] as [io.druid.segment.loading.LocalDataSegmentPusherConfig@74b1e4b8] 2015-07-13T15:50:01,428 INFO [main] io.druid.segment.loading.LocalDataSegmentPusher - Configured local filesystem as deep storage 2015-07-13T15:50:01,445 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.client.DruidServerConfig] from props[druid.server.] as [io.druid.client.DruidServerConfig@31f7add8] 2015-07-13T15:50:01,450 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.server.initialization.BatchDataSegmentAnnouncerConfig] from props[druid.announcer.] as [io.druid.server.initialization.BatchDataSegmentAnnouncerConfig@57f8858c] 2015-07-13T15:50:01,465 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.server.initialization.ZkPathsConfig] from props[druid.zk.paths.] as [io.druid.server.initialization.ZkPathsConfig@58d3f4be] 2015-07-13T15:50:01,470 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[interface io.druid.server.coordination.DataSegmentAnnouncerProvider] from props[druid.announcer.] as [io.druid.server.coordination.BatchDataSegmentAnnouncerProvider@5fddbf47] 2015-07-13T15:50:01,475 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[interface io.druid.client.FilteredServerViewProvider] from props[druid.announcer.] as [io.druid.client.FilteredBatchServerViewProvider@23d63abc] 2015-07-13T15:50:01,483 INFO [main] org.skife.config.ConfigurationObjectFactory - Assigning value [100000000] for [druid.computation.buffer.size] on [io.druid.query.DruidProcessingConfig#intermediateComputeSizeBytes()] 2015-07-13T15:50:01,485 INFO [main] org.skife.config.ConfigurationObjectFactory - Assigning value [1] for [druid.processing.numThreads] on [io.druid.query.DruidProcessingConfig#getNumThreads()] 2015-07-13T15:50:01,485 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [${base_path}.columnCache.sizeBytes] on [io.druid.query.DruidProcessingConfig#columnCacheSizeBytes()] 2015-07-13T15:50:01,487 INFO [main] org.skife.config.ConfigurationObjectFactory - Assigning default value [processing-%s] for [${base_path}.formatString] on [com.metamx.common.concurrent.ExecutorServiceConfig#getFormatString()] 2015-07-13T15:50:01,572 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.query.search.search.SearchQueryConfig] from props[druid.query.search.] as [io.druid.query.search.search.SearchQueryConfig@213e7e70] 2015-07-13T15:50:01,577 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.query.groupby.GroupByQueryConfig] from props[druid.query.groupBy.] as [io.druid.query.groupby.GroupByQueryConfig@2dea2d4] 2015-07-13T15:50:01,582 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.query.topn.TopNQueryConfig] from props[druid.query.topN.] as [io.druid.query.topn.TopNQueryConfig@66b9915a] 2015-07-13T15:50:01,587 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[interface io.druid.server.log.RequestLoggerProvider] from props[druid.request.logging.] as [io.druid.server.log.NoopRequestLoggerProvider@3db6e91d] 2015-07-13T15:50:01,596 INFO [main] org.eclipse.jetty.util.log - Logging initialized @8220ms 2015-07-13T15:50:01,667 INFO [main] com.metamx.common.lifecycle.Lifecycle$AnnotationBasedHandler - Invoking start method[public void com.metamx.emitter.service.ServiceEmitter.start()] on object[com.metamx.emitter.service.ServiceEmitter@6da0cbb2]. 2015-07-13T15:50:01,667 INFO [main] com.metamx.common.lifecycle.Lifecycle$AnnotationBasedHandler - Invoking start method[public void com.metamx.metrics.MonitorScheduler.start()] on object[com.metamx.metrics.MonitorScheduler@579d4b58]. 2015-07-13T15:50:01,668 INFO [main] com.metamx.common.lifecycle.Lifecycle$AnnotationBasedHandler - Invoking start method[public void com.metamx.http.client.NettyHttpClient.start()] on object[com.metamx.http.client.NettyHttpClient@42f3b50d]. 2015-07-13T15:50:01,669 INFO [main] io.druid.curator.CuratorModule - Starting Curator 2015-07-13T15:50:01,669 INFO [main] org.apache.curator.framework.imps.CuratorFrameworkImpl - Starting 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:zookeeper.version=3.4.6-1569965, built on 02/20/2014 09:09 GMT 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:host.name=ssubrama-ld1.linkedin.biz 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.version=1.7.0_51 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.vendor=Oracle Corporation 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.home=/export/apps/jdk/JDK-1_7_0_51/jre 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.class.path=config/_common:config/overlord:lib/maven-model-builder-3.1.1.jar:lib/antlr4-runtime-4.0.jar:lib/jackson-datatype-joda-2.4.4.jar:lib/jersey-server-1.17.1.jar:lib/druid-indexing-service-0.8.0-rc2-SNAPSHOT.jar:lib/javax.inject-1.jar:lib/http-client-1.0.2.jar:lib/mx4j-3.0.2.jar:lib/jets3t-0.9.3.jar:lib/jackson-dataformat-smile-2.4.4.jar:lib/commons-codec-1.7.jar:lib/jetty-servlets-9.2.5.v20141112.jar:lib/derbyclient-10.11.1.1.jar:lib/jackson-mapper-asl-1.9.13.jar:lib/disruptor-3.3.0.jar:lib/jetty-http-9.2.5.v20141112.jar:lib/aopalliance-1.0.jar:lib/lz4-1.3.0.jar:lib/compress-lzf-1.0.3.jar:lib/commons-cli-1.2.jar:lib/commons-pool-1.6.jar:lib/maven-settings-builder-3.1.1.jar:lib/jetty-proxy-9.2.5.v20141112.jar:lib/commons-dbcp2-2.0.1.jar:lib/aether-connector-file-0.9.0.M2.jar:lib/mail-1.4.7.jar:lib/curator-recipes-2.7.0.jar:lib/aether-api-0.9.0.M2.jar:lib/netty-3.9.5.Final.jar:lib/druid-indexing-hadoop-0.8.0-rc2-SNAPSHOT.jar:lib/rhino-1.7R5.jar:lib/okhttp-1.0.2.jar:lib/airline-0.6.jar:lib/log4j-1.2-api-2.2.jar:lib/maven-settings-3.1.1.jar:lib/google-http-client-jackson2-1.15.0-rc.jar:lib/jersey-guice-1.17.1.jar:lib/jetty-server-9.2.5.v20141112.jar:lib/geoip2-0.4.0.jar:lib/httpcore-4.2.jar:lib/activation-1.1.1.jar:lib/google-http-client-1.15.0-rc.jar:lib/jline-0.9.94.jar:lib/plexus-utils-3.0.15.jar:lib/classmate-1.0.0.jar:lib/druid-common-0.8.0-rc2-SNAPSHOT.jar:lib/zookeeper-3.4.6.jar:lib/xpp3-1.1.4c.jar:lib/org.abego.treelayout.core-1.0.1.jar:lib/curator-x-discovery-2.7.0.jar:lib/wagon-provider-api-2.4.jar:lib/jackson-jaxrs-json-provider-2.4.4.jar:lib/guice-multibindings-4.0-beta.jar:lib/log4j-core-2.2.jar:lib/derby-10.11.1.1.jar:lib/javax.el-3.0.0.jar:lib/guice-4.0-beta.jar:lib/maven-repository-metadata-3.1.1.jar:lib/log4j-slf4j-impl-2.2.jar:lib/bytebuffer-collections-0.1.6.jar:lib/maven-aether-provider-3.1.1.jar:lib/druid-server-0.8.0-rc2-SNAPSHOT.jar:lib/guava-16.0.1.jar:lib/hibernate-validator-5.1.3.Final.jar:lib/aws-java-sdk-core-1.8.11.jar:lib/java-xmlbuilder-0.4.jar:lib/jcl-over-slf4j-1.7.10.jar:lib/httpclient-4.2.jar:lib/irc-api-1.0-0011.jar:lib/jetty-security-9.2.5.v20141112.jar:lib/icu4j-4.8.1.jar:lib/druid-services-0.8.0-rc2-SNAPSHOT.jar:lib/log4j-api-2.2.jar:lib/maxminddb-0.2.0.jar:lib/jetty-io-9.2.5.v20141112.jar:lib/validation-api-1.1.0.Final.jar:lib/jetty-client-9.2.5.v20141112.jar:lib/extendedset-1.3.9.jar:lib/jackson-module-jaxb-annotations-2.4.4.jar:lib/javax.el-api-3.0.0.jar:lib/jetty-servlet-9.2.5.v20141112.jar:lib/aether-impl-0.9.0.M2.jar:lib/aether-connector-okhttp-0.0.9.jar:lib/plexus-interpolation-1.19.jar:lib/mapdb-1.0.7.jar:lib/slf4j-api-1.6.4.jar:lib/jdbi-2.32.jar:lib/commons-lang-2.6.jar:lib/asm-3.1.jar:lib/aws-java-sdk-1.8.11.jar:lib/jboss-logging-3.1.3.GA.jar:lib/protobuf-java-2.5.0.jar:lib/jetty-util-9.2.5.v20141112.jar:lib/commons-pool2-2.2.jar:lib/druid-api-0.3.8.jar:lib/spymemcached-2.11.7.jar:lib/tesla-aether-0.0.5.jar:lib/javax.servlet-api-3.1.0.jar:lib/bcprov-jdk15on-1.51.jar:lib/server-metrics-0.2.0.jar:lib/config-magic-0.9.jar:lib/derbynet-10.11.1.1.jar:lib/jackson-jaxrs-base-2.4.4.jar:lib/commons-io-2.0.1.jar:lib/jsr305-2.0.1.jar:lib/curator-framework-2.7.0.jar:lib/jackson-core-2.4.4.jar:lib/jackson-annotations-2.4.4.jar:lib/curator-client-2.7.0.jar:lib/jetty-continuation-9.2.5.v20141112.jar:lib/jackson-jaxrs-smile-provider-2.4.4.jar:lib/jackson-core-asl-1.9.12.jar:lib/jersey-servlet-1.17.1.jar:lib/emitter-0.3.1.jar:lib/jackson-databind-2.4.4.jar:lib/opencsv-2.3.jar:lib/druid-aws-common-0.8.0-rc2-SNAPSHOT.jar:lib/jackson-datatype-guava-2.4.4.jar:lib/joda-time-2.6.jar:lib/aether-spi-0.9.0.M2.jar:lib/java-util-0.27.0.jar:lib/RoaringBitmap-0.4.5.jar:lib/jersey-core-1.17.1.jar:lib/druid-processing-0.8.0-rc2-SNAPSHOT.jar:lib/commons-logging-1.1.1.jar:lib/log4j-jul-2.2.jar:lib/maven-model-3.1.1.jar:lib/aether-util-0.9.0.M2.jar:lib/guice-servlet-4.0-beta.jar:/home/ssubrama/hadoop/hadoop-2.3.0.35/share/hadoop/common/hadoop-common-2.3.0.35.jar 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.library.path=/export/apps/xtools/oracle-instant-client_10.2:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.io.tmpdir=/tmp 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:java.compiler= 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:os.name=Linux 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:os.arch=amd64 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:os.version=2.6.32-358.6.2.el6.x86_64 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:user.name=ssubrama 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:user.home=/home/ssubrama 2015-07-13T15:50:01,677 INFO [main] org.apache.zookeeper.ZooKeeper - Client environment:user.dir=/home/ssubrama/Documents/druid/services/target/druid-0.8.0-rc2-SNAPSHOT 2015-07-13T15:50:01,678 INFO [main] org.apache.zookeeper.ZooKeeper - Initiating client connection, connectString=localhost sessionTimeout=30000 watcher=org.apache.curator.ConnectionState@3e94b7f2 2015-07-13T15:50:01,692 INFO [main] com.metamx.common.lifecycle.Lifecycle$AnnotationBasedHandler - Invoking start method[public void io.druid.curator.discovery.ServerDiscoverySelector.start() throws java.lang.Exception] on object[io.druid.curator.discovery.ServerDiscoverySelector@4064f299]. 2015-07-13T15:50:01,694 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Opening socket connection to server ssubrama-ld1.linkedin.biz/0:0:0:0:0:0:0:1:2181. Will not attempt to authenticate using SASL (unknown error) 2015-07-13T15:50:01,699 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Socket connection established to ssubrama-ld1.linkedin.biz/0:0:0:0:0:0:0:1:2181, initiating session 2015-07-13T15:50:01,708 WARN [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxnSocket - Connected to an old server; r-o mode will be unavailable 2015-07-13T15:50:01,708 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Session establishment complete on server ssubrama-ld1.linkedin.biz/0:0:0:0:0:0:0:1:2181, sessionid = 0x14e79f3d5e1000f, negotiated timeout = 60000 2015-07-13T15:50:01,713 INFO [main-EventThread] org.apache.curator.framework.state.ConnectionStateManager - State change: CONNECTED 2015-07-13T15:50:02,817 INFO [main] com.metamx.common.lifecycle.Lifecycle$AnnotationBasedHandler - Invoking start method[public void io.druid.curator.announcement.Announcer.start()] on object[io.druid.curator.announcement.Announcer@3b7b464]. 2015-07-13T15:50:02,818 INFO [main] com.metamx.common.lifecycle.Lifecycle$AnnotationBasedHandler - Invoking start method[public void io.druid.client.ServerInventoryView.start() throws java.lang.Exception] on object[io.druid.client.BatchServerInventoryView@6983b0c]. 2015-07-13T15:50:02,823 INFO [main] org.eclipse.jetty.server.Server - jetty-9.2.5.v20141112 2015-07-13T15:50:02,872 INFO [ServerInventoryView-0] io.druid.curator.inventory.CuratorInventoryManager - Created new InventoryCacheListener for /druid/segments/ssubrama-ld1.linkedin.biz:8083 2015-07-13T15:50:02,873 INFO [ServerInventoryView-0] io.druid.client.BatchServerInventoryView - New Server[DruidServerMetadata{name='ssubrama-ld1.linkedin.biz:8083', host='ssubrama-ld1.linkedin.biz:8083', maxSize=10000000000, tier='_default_tier', type='historical', priority='0'}] 2015-07-13T15:50:02,878 INFO [ServerInventoryView-0] io.druid.client.BatchServerInventoryView - Inventory Initialized Jul 13, 2015 3:50:02 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register INFO: Registering com.fasterxml.jackson.jaxrs.json.JacksonJsonProvider as a provider class Jul 13, 2015 3:50:02 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory register INFO: Registering io.druid.server.StatusResource as a root resource class Jul 13, 2015 3:50:02 PM com.sun.jersey.server.impl.application.WebApplicationImpl _initiate INFO: Initiating Jersey application, version 'Jersey: 1.17.1 02/28/2013 12:47 PM' Jul 13, 2015 3:50:03 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory getComponentProvider INFO: Binding com.fasterxml.jackson.jaxrs.json.JacksonJsonProvider to GuiceManagedComponentProvider with the scope "Singleton" Jul 13, 2015 3:50:03 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory getComponentProvider INFO: Binding io.druid.server.QueryResource to GuiceInstantiatedComponentProvider Jul 13, 2015 3:50:03 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory getComponentProvider INFO: Binding io.druid.segment.realtime.firehose.ChatHandlerResource to GuiceInstantiatedComponentProvider Jul 13, 2015 3:50:03 PM com.sun.jersey.guice.spi.container.GuiceComponentProviderFactory getComponentProvider INFO: Binding io.druid.server.StatusResource to GuiceManagedComponentProvider with the scope "Undefined" 2015-07-13T15:50:03,397 INFO [main] org.eclipse.jetty.server.handler.ContextHandler - Started o.e.j.s.ServletContextHandler@c97b2fb{/,null,AVAILABLE} 2015-07-13T15:50:03,403 INFO [main] org.eclipse.jetty.server.ServerConnector - Started ServerConnector@722bfe37{HTTP/1.1}{0.0.0.0:8100} 2015-07-13T15:50:03,403 INFO [main] org.eclipse.jetty.server.Server - Started @10031ms 2015-07-13T15:50:03,403 INFO [main] com.metamx.common.lifecycle.Lifecycle$AnnotationBasedHandler - Invoking start method[public void io.druid.server.coordination.AbstractDataSegmentAnnouncer.start()] on object[io.druid.server.coordination.BatchDataSegmentAnnouncer@6e6b2081]. 2015-07-13T15:50:03,403 INFO [main] io.druid.server.coordination.AbstractDataSegmentAnnouncer - Announcing self[DruidServerMetadata{name='ssubrama-ld1.linkedin.biz:8100', host='ssubrama-ld1.linkedin.biz:8100', maxSize=0, tier='_default_tier', type='indexer-executor', priority='0'}] at [/druid/announcements/ssubrama-ld1.linkedin.biz:8100] 2015-07-13T15:50:03,440 INFO [main] com.metamx.common.lifecycle.Lifecycle$AnnotationBasedHandler - Invoking start method[public void io.druid.indexing.worker.executor.ExecutorLifecycle.start()] on object[io.druid.indexing.worker.executor.ExecutorLifecycle@2520e3a6]. 2015-07-13T15:50:03,446 INFO [ServerInventoryView-0] io.druid.curator.inventory.CuratorInventoryManager - Created new InventoryCacheListener for /druid/segments/ssubrama-ld1.linkedin.biz:8100 2015-07-13T15:50:03,446 INFO [ServerInventoryView-0] io.druid.client.BatchServerInventoryView - New Server[DruidServerMetadata{name='ssubrama-ld1.linkedin.biz:8100', host='ssubrama-ld1.linkedin.biz:8100', maxSize=0, tier='_default_tier', type='indexer-executor', priority='0'}] 2015-07-13T15:50:03,601 INFO [main] io.druid.guice.PropertiesModule - Loading properties from common.runtime.properties 2015-07-13T15:50:03,602 INFO [main] io.druid.guice.PropertiesModule - Loading properties from runtime.properties 2015-07-13T15:50:03,608 INFO [main] org.skife.config.ConfigurationObjectFactory - Assigning value [100000000] for [druid.computation.buffer.size] on [io.druid.query.DruidProcessingConfig#intermediateComputeSizeBytes()] 2015-07-13T15:50:03,608 INFO [main] org.skife.config.ConfigurationObjectFactory - Assigning value [1] for [druid.processing.numThreads] on [io.druid.query.DruidProcessingConfig#getNumThreads()] 2015-07-13T15:50:03,609 INFO [main] org.skife.config.ConfigurationObjectFactory - Using method itself for [${base_path}.columnCache.sizeBytes] on [io.druid.query.DruidProcessingConfig#columnCacheSizeBytes()] 2015-07-13T15:50:03,609 INFO [main] org.skife.config.ConfigurationObjectFactory - Assigning default value [processing-%s] for [${base_path}.formatString] on [com.metamx.common.concurrent.ExecutorServiceConfig#getFormatString()] 2015-07-13T15:50:03,614 INFO [main] io.druid.guice.JsonConfigurator - Loaded class[interface io.druid.segment.data.BitmapSerdeFactory] from props[druid.processing.bitmap.] as [ConciseBitmapSerdeFactory{}] 2015-07-13T15:50:03,654 INFO [main] io.druid.indexing.worker.executor.ExecutorLifecycle - Running with task: { "type" : "index", "id" : "index_wikipedia_2015-07-13T15:49:53.261Z", "spec" : { "dataSchema" : { "dataSource" : "wikipedia", "parser" : { "type" : "string", "parseSpec" : { "format" : "json", "timestampSpec" : { "column" : "timestamp", "format" : "auto", "missingValue" : null }, "dimensionsSpec" : { "dimensions" : [ "anonymous", "city", "continent", "country", "language", "namespace", "newPage", "page", "region", "robot", "unpatrolled", "user" ], "dimensionExclusions" : [ "timestamp", "added", "delta", "deleted" ], "spatialDimensions" : [ ] } } }, "metricsSpec" : [ { "type" : "count", "name" : "count" }, { "type" : "doubleSum", "name" : "added", "fieldName" : "added" }, { "type" : "doubleSum", "name" : "deleted", "fieldName" : "deleted" }, { "type" : "doubleSum", "name" : "delta", "fieldName" : "delta" } ], "granularitySpec" : { "type" : "uniform", "segmentGranularity" : "DAY", "queryGranularity" : { "type" : "none" }, "intervals" : [ "2013-08-31T00:00:00.000Z/2013-09-01T00:00:00.000Z" ] } }, "ioConfig" : { "type" : "index", "firehose" : { "type" : "local", "baseDir" : "/home/ssubrama/Documents/druid/services/target/druid-0.8.0-rc2-SNAPSHOT/examples/indexing", "filter" : "wikipedia_data.json", "parser" : null } }, "tuningConfig" : { "type" : "index", "targetPartitionSize" : 5000000, "rowFlushBoundary" : 500000, "numShards" : -1, "indexSpec" : { "bitmap" : { "type" : "concise" }, "dimensionCompression" : null, "metricCompression" : null } } }, "groupId" : "index_wikipedia_2015-07-13T15:49:53.261Z", "dataSource" : "wikipedia", "interval" : "2013-08-31T00:00:00.000Z/2013-09-01T00:00:00.000Z", "resource" : { "availabilityGroup" : "index_wikipedia_2015-07-13T15:49:53.261Z", "requiredCapacity" : 1 } } 2015-07-13T15:50:03,657 INFO [main] io.druid.indexing.common.actions.RemoteTaskActionClient - Performing action for task[index_wikipedia_2015-07-13T15:49:53.261Z]: LockTryAcquireAction{interval=2013-08-31T00:00:00.000Z/2013-09-01T00:00:00.000Z} 2015-07-13T15:50:03,664 INFO [main] io.druid.indexing.common.actions.RemoteTaskActionClient - Submitting action for task[index_wikipedia_2015-07-13T15:49:53.261Z] to overlord[http://ssubrama-ld1.linkedin.biz:8090/druid/indexer/v1/action]: LockTryAcquireAction{interval=2013-08-31T00:00:00.000Z/2013-09-01T00:00:00.000Z} 2015-07-13T15:50:03,678 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,708 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,708 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,709 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,709 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,709 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,709 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,709 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,710 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,711 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,711 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,712 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,712 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,712 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,713 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,713 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,714 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,714 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,714 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,715 INFO [main] com.metamx.http.client.pool.ChannelResourceFactory - Generating: http://ssubrama-ld1.linkedin.biz:8090 2015-07-13T15:50:03,796 INFO [task-runner-0] io.druid.indexing.overlord.ThreadPoolTaskRunner - Running task: index_wikipedia_2015-07-13T15:49:53.261Z 2015-07-13T15:50:03,797 INFO [task-runner-0] io.druid.indexing.common.actions.RemoteTaskActionClient - Performing action for task[index_wikipedia_2015-07-13T15:49:53.261Z]: LockListAction{} 2015-07-13T15:50:03,799 INFO [task-runner-0] io.druid.indexing.common.actions.RemoteTaskActionClient - Submitting action for task[index_wikipedia_2015-07-13T15:49:53.261Z] to overlord[http://ssubrama-ld1.linkedin.biz:8090/druid/indexer/v1/action]: LockListAction{} 2015-07-13T15:50:03,812 INFO [task-runner-0] io.druid.segment.realtime.firehose.LocalFirehoseFactory - Searching for all [wikipedia_data.json] in and beneath [/home/ssubrama/Documents/druid/services/target/druid-0.8.0-rc2-SNAPSHOT/examples/indexing] 2015-07-13T15:50:03,824 INFO [task-runner-0] io.druid.segment.realtime.firehose.LocalFirehoseFactory - Found files: [/home/ssubrama/Documents/druid/services/target/druid-0.8.0-rc2-SNAPSHOT/examples/indexing/wikipedia_data.json] 2015-07-13T15:50:03,839 INFO [task-runner-0] io.druid.indexing.common.task.IndexTask - Determining partitions for interval[2013-08-31T00:00:00.000Z/2013-09-01T00:00:00.000Z] with targetPartitionSize[5000000] 2015-07-13T15:50:03,860 INFO [task-runner-0] io.druid.segment.realtime.firehose.LocalFirehoseFactory - Searching for all [wikipedia_data.json] in and beneath [/home/ssubrama/Documents/druid/services/target/druid-0.8.0-rc2-SNAPSHOT/examples/indexing] 2015-07-13T15:50:03,861 INFO [task-runner-0] io.druid.segment.realtime.firehose.LocalFirehoseFactory - Found files: [/home/ssubrama/Documents/druid/services/target/druid-0.8.0-rc2-SNAPSHOT/examples/indexing/wikipedia_data.json] 2015-07-13T15:50:03,872 INFO [task-runner-0] io.druid.guice.PropertiesModule - Loading properties from common.runtime.properties 2015-07-13T15:50:03,872 INFO [task-runner-0] io.druid.guice.PropertiesModule - Loading properties from runtime.properties 2015-07-13T15:50:03,896 INFO [task-runner-0] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.guice.ExtensionsConfig] from props[druid.extensions.] as [ExtensionsConfig{searchCurrentClassloader=true, coordinates=[io.druid.extensions:druid-examples, io.druid.extensions:druid-kafka-eight, io.druid.extensions:mysql-metadata-storage], defaultVersion='0.8.0-rc2-SNAPSHOT', localRepository='/home/ssubrama/.m2/repository', remoteRepositories=[https://repo1.maven.org/maven2/, https://metamx.artifactoryonline.com/metamx/pub-libs-releases-local]}] 2015-07-13T15:50:03,899 INFO [task-runner-0] io.druid.initialization.Initialization - Loading extension[io.druid.extensions:druid-examples] for class[io.druid.initialization.DruidModule] 2015-07-13T15:50:03,900 INFO [task-runner-0] io.druid.initialization.Initialization - Adding remote extension module[io.druid.examples.ExamplesDruidModule] for class[io.druid.initialization.DruidModule] 2015-07-13T15:50:03,901 INFO [task-runner-0] io.druid.initialization.Initialization - Loading extension[io.druid.extensions:druid-kafka-eight] for class[io.druid.initialization.DruidModule] 2015-07-13T15:50:03,902 INFO [task-runner-0] io.druid.initialization.Initialization - Adding remote extension module[io.druid.firehose.kafka.KafkaEightDruidModule] for class[io.druid.initialization.DruidModule] 2015-07-13T15:50:03,904 INFO [task-runner-0] io.druid.initialization.Initialization - Loading extension[io.druid.extensions:mysql-metadata-storage] for class[io.druid.initialization.DruidModule] 2015-07-13T15:50:03,905 INFO [task-runner-0] io.druid.initialization.Initialization - Adding remote extension module[io.druid.metadata.storage.mysql.MySQLMetadataStorageModule] for class[io.druid.initialization.DruidModule] Jul 13, 2015 3:50:04 PM com.google.inject.servlet.GuiceFilter setPipeline WARNING: Multiple Servlet injectors detected. This is a warning indicating that you have more than one GuiceFilter running in your web application. If this is deliberate, you may safely ignore this message. If this is NOT deliberate however, your application may not work as expected. 2015-07-13T15:50:04,067 INFO [task-runner-0] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.server.metrics.DruidMonitorSchedulerConfig] from props[druid.monitoring.] as [io.druid.server.metrics.DruidMonitorSchedulerConfig@320f0ea0] 2015-07-13T15:50:04,073 INFO [task-runner-0] io.druid.guice.JsonConfigurator - Loaded class[class io.druid.server.metrics.MonitorsConfig] from props[druid.monitoring.] as [MonitorsConfig{monitors=[]}] 2015-07-13T15:50:04,091 INFO [task-runner-0] io.druid.indexing.common.task.IndexTask - Estimated approximately [5.006113] rows of data. 2015-07-13T15:50:04,091 INFO [task-runner-0] io.druid.indexing.common.task.IndexTask - Will require [1] shard(s). 2015-07-13T15:50:04,092 INFO [task-runner-0] io.druid.segment.realtime.firehose.LocalFirehoseFactory - Searching for all [wikipedia_data.json] in and beneath [/home/ssubrama/Documents/druid/services/target/druid-0.8.0-rc2-SNAPSHOT/examples/indexing] 2015-07-13T15:50:04,092 INFO [task-runner-0] io.druid.segment.realtime.firehose.LocalFirehoseFactory - Found files: [/home/ssubrama/Documents/druid/services/target/druid-0.8.0-rc2-SNAPSHOT/examples/indexing/wikipedia_data.json] 2015-07-13T15:50:04,134 INFO [task-runner-0] io.druid.indexing.common.index.YeOldePlumberSchool - Spilling index[0] with rows[5] to: /tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z_0/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z/spill0 2015-07-13T15:50:04,138 INFO [task-runner-0] io.druid.guice.PropertiesModule - Loading properties from common.runtime.properties 2015-07-13T15:50:04,138 INFO [task-runner-0] io.druid.guice.PropertiesModule - Loading properties from runtime.properties 2015-07-13T15:50:04,145 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting persist for interval[2013-08-31T00:00:00.000Z/2013-08-31T12:41:27.001Z], rows[5] 2015-07-13T15:50:04,159 INFO [task-runner-0] io.druid.segment.IndexMerger - outDir[/tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z_0/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z/spill0/v8-tmp] completed index.drd in 4 millis. 2015-07-13T15:50:04,186 INFO [task-runner-0] io.druid.segment.IndexMerger - outDir[/tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z_0/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z/spill0/v8-tmp] completed dim conversions in 27 millis. 2015-07-13T15:50:04,224 INFO [task-runner-0] io.druid.segment.IndexMerger - outDir[/tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z_0/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z/spill0/v8-tmp] completed walk through of 5 rows in 38 millis. 2015-07-13T15:50:04,225 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting dimension[anonymous] with cardinality[1] 2015-07-13T15:50:04,232 INFO [task-runner-0] io.druid.segment.IndexMerger - Completed dimension[anonymous] in 8 millis. 2015-07-13T15:50:04,232 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting dimension[city] with cardinality[5] 2015-07-13T15:50:04,233 INFO [task-runner-0] io.druid.segment.IndexMerger - Completed dimension[city] in 1 millis. 2015-07-13T15:50:04,233 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting dimension[continent] with cardinality[3] 2015-07-13T15:50:04,234 INFO [task-runner-0] io.druid.segment.IndexMerger - Completed dimension[continent] in 1 millis. 2015-07-13T15:50:04,235 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting dimension[country] with cardinality[5] 2015-07-13T15:50:04,236 INFO [task-runner-0] io.druid.segment.IndexMerger - Completed dimension[country] in 2 millis. 2015-07-13T15:50:04,236 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting dimension[language] with cardinality[4] 2015-07-13T15:50:04,237 INFO [task-runner-0] io.druid.segment.IndexMerger - Completed dimension[language] in 1 millis. 2015-07-13T15:50:04,237 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting dimension[namespace] with cardinality[2] 2015-07-13T15:50:04,238 INFO [task-runner-0] io.druid.segment.IndexMerger - Completed dimension[namespace] in 1 millis. 2015-07-13T15:50:04,239 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting dimension[newPage] with cardinality[2] 2015-07-13T15:50:04,240 INFO [task-runner-0] io.druid.segment.IndexMerger - Completed dimension[newPage] in 1 millis. 2015-07-13T15:50:04,240 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting dimension[page] with cardinality[5] 2015-07-13T15:50:04,241 INFO [task-runner-0] io.druid.segment.IndexMerger - Completed dimension[page] in 1 millis. 2015-07-13T15:50:04,241 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting dimension[region] with cardinality[5] 2015-07-13T15:50:04,242 INFO [task-runner-0] io.druid.segment.IndexMerger - Completed dimension[region] in 1 millis. 2015-07-13T15:50:04,243 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting dimension[robot] with cardinality[2] 2015-07-13T15:50:04,244 INFO [task-runner-0] io.druid.segment.IndexMerger - Completed dimension[robot] in 1 millis. 2015-07-13T15:50:04,244 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting dimension[unpatrolled] with cardinality[2] 2015-07-13T15:50:04,245 INFO [task-runner-0] io.druid.segment.IndexMerger - Completed dimension[unpatrolled] in 1 millis. 2015-07-13T15:50:04,245 INFO [task-runner-0] io.druid.segment.IndexMerger - Starting dimension[user] with cardinality[5] 2015-07-13T15:50:04,246 INFO [task-runner-0] io.druid.segment.IndexMerger - Completed dimension[user] in 1 millis. 2015-07-13T15:50:04,246 INFO [task-runner-0] io.druid.segment.IndexMerger - outDir[/tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z_0/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z/spill0/v8-tmp] completed inverted.drd in 22 millis. 2015-07-13T15:50:04,277 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Converting v8[/tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z_0/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z/spill0/v8-tmp] to v9[/tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z_0/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z/spill0] 2015-07-13T15:50:04,281 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[dim_anonymous.drd] 2015-07-13T15:50:04,284 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Dimension[anonymous] is single value, converting... 2015-07-13T15:50:04,301 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[dim_city.drd] 2015-07-13T15:50:04,301 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Dimension[city] is single value, converting... 2015-07-13T15:50:04,301 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[dim_continent.drd] 2015-07-13T15:50:04,302 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Dimension[continent] is single value, converting... 2015-07-13T15:50:04,302 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[dim_country.drd] 2015-07-13T15:50:04,303 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Dimension[country] is single value, converting... 2015-07-13T15:50:04,303 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[dim_language.drd] 2015-07-13T15:50:04,303 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Dimension[language] is single value, converting... 2015-07-13T15:50:04,304 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[dim_namespace.drd] 2015-07-13T15:50:04,304 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Dimension[namespace] is single value, converting... 2015-07-13T15:50:04,305 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[dim_newPage.drd] 2015-07-13T15:50:04,305 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Dimension[newPage] is single value, converting... 2015-07-13T15:50:04,306 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[dim_page.drd] 2015-07-13T15:50:04,306 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Dimension[page] is single value, converting... 2015-07-13T15:50:04,307 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[dim_region.drd] 2015-07-13T15:50:04,307 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Dimension[region] is single value, converting... 2015-07-13T15:50:04,308 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[dim_robot.drd] 2015-07-13T15:50:04,308 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Dimension[robot] is single value, converting... 2015-07-13T15:50:04,308 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[dim_unpatrolled.drd] 2015-07-13T15:50:04,309 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Dimension[unpatrolled] is single value, converting... 2015-07-13T15:50:04,309 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[dim_user.drd] 2015-07-13T15:50:04,310 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Dimension[user] is single value, converting... 2015-07-13T15:50:04,310 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[index.drd] 2015-07-13T15:50:04,310 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[inverted.drd] 2015-07-13T15:50:04,311 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[met_added_LITTLE_ENDIAN.drd] 2015-07-13T15:50:04,314 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[met_count_LITTLE_ENDIAN.drd] 2015-07-13T15:50:04,316 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[met_deleted_LITTLE_ENDIAN.drd] 2015-07-13T15:50:04,316 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[met_delta_LITTLE_ENDIAN.drd] 2015-07-13T15:50:04,317 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[spatial.drd] 2015-07-13T15:50:04,317 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Processing file[time_LITTLE_ENDIAN.drd] 2015-07-13T15:50:04,320 INFO [task-runner-0] io.druid.segment.IndexIO$DefaultIndexIOHandler - Skipped files[[index.drd, inverted.drd, spatial.drd]] 2015-07-13T15:50:04,321 INFO [task-runner-0] io.druid.segment.ReferenceCountingSegment - Closing wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z 2015-07-13T15:50:04,321 INFO [task-runner-0] io.druid.segment.ReferenceCountingSegment - Closing wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z, numReferences: 0 2015-07-13T15:50:04,354 INFO [task-runner-0] io.druid.segment.loading.LocalDataSegmentPusher - Copying segment[wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z] to local filesystem at location[/tmp/druid/localStorage/wikipedia/2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z/2015-07-13T15:49:53.267Z/0] 2015-07-13T15:50:04,354 INFO [task-runner-0] io.druid.segment.loading.LocalDataSegmentPusher - Compressing files from[/tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z_0/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z/spill0] to [/tmp/druid/localStorage/wikipedia/2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z/2015-07-13T15:49:53.267Z/0/index.zip] 2015-07-13T15:50:04,357 INFO [task-runner-0] com.metamx.common.CompressionUtils - Adding file[/tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z_0/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z/spill0/meta.smoosh] with size[365]. Total size so far[0] 2015-07-13T15:50:04,358 INFO [task-runner-0] com.metamx.common.CompressionUtils - Adding file[/tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z_0/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z/spill0/00000.smoosh] with size[9,483]. Total size so far[365] 2015-07-13T15:50:04,359 INFO [task-runner-0] com.metamx.common.CompressionUtils - Adding file[/tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z_0/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z/spill0/version.bin] with size[4]. Total size so far[9,848] 2015-07-13T15:50:04,360 INFO [task-runner-0] io.druid.segment.loading.LocalDataSegmentPusher - Creating descriptor file at[/tmp/druid/localStorage/wikipedia/2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z/2015-07-13T15:49:53.267Z/0/descriptor.json] 2015-07-13T15:50:04,366 INFO [task-runner-0] io.druid.indexing.common.index.YeOldePlumberSchool - Uploaded segment[wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z] 2015-07-13T15:50:04,367 INFO [task-runner-0] io.druid.indexing.common.index.YeOldePlumberSchool - Deleting Index File[/tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z_0/wikipedia_2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z_2015-07-13T15:49:53.267Z/spill0] 2015-07-13T15:50:04,367 INFO [task-runner-0] io.druid.indexing.common.task.IndexTask - Task[index_wikipedia_2015-07-13T15:49:53.261Z] interval[2013-08-31T00:00:00.000Z/2013-09-01T00:00:00.000Z] partition[0] took in 5 rows (5 processed, 0 unparseable, 0 thrown away) and output 5 rows 2015-07-13T15:50:04,373 INFO [task-runner-0] io.druid.indexing.common.actions.RemoteTaskActionClient - Performing action for task[index_wikipedia_2015-07-13T15:49:53.261Z]: SegmentInsertAction{segments=[DataSegment{size=9852, shardSpec=NoneShardSpec, metrics=[count, added, deleted, delta], dimensions=[anonymous, city, continent, country, language, namespace, newPage, page, region, robot, unpatrolled, user], version='2015-07-13T15:49:53.267Z', loadSpec={type=local, path=/tmp/druid/localStorage/wikipedia/2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z/2015-07-13T15:49:53.267Z/0/index.zip}, interval=2013-08-31T00:00:00.000Z/2013-09-01T00:00:00.000Z, dataSource='wikipedia', binaryVersion='9'}]} 2015-07-13T15:50:04,376 INFO [task-runner-0] io.druid.indexing.common.actions.RemoteTaskActionClient - Submitting action for task[index_wikipedia_2015-07-13T15:49:53.261Z] to overlord[http://ssubrama-ld1.linkedin.biz:8090/druid/indexer/v1/action]: SegmentInsertAction{segments=[DataSegment{size=9852, shardSpec=NoneShardSpec, metrics=[count, added, deleted, delta], dimensions=[anonymous, city, continent, country, language, namespace, newPage, page, region, robot, unpatrolled, user], version='2015-07-13T15:49:53.267Z', loadSpec={type=local, path=/tmp/druid/localStorage/wikipedia/2013-08-31T00:00:00.000Z_2013-09-01T00:00:00.000Z/2015-07-13T15:49:53.267Z/0/index.zip}, interval=2013-08-31T00:00:00.000Z/2013-09-01T00:00:00.000Z, dataSource='wikipedia', binaryVersion='9'}]} 2015-07-13T15:50:04,443 INFO [task-runner-0] io.druid.indexing.overlord.ThreadPoolTaskRunner - Removing task directory: /tmp/persistent/task/index_wikipedia_2015-07-13T15:49:53.261Z/work 2015-07-13T15:50:04,447 INFO [task-runner-0] io.druid.indexing.worker.executor.ExecutorLifecycle - Task completed with status: { "id" : "index_wikipedia_2015-07-13T15:49:53.261Z", "status" : "SUCCESS", "duration" : 648 } 2015-07-13T15:50:04,450 INFO [main] com.metamx.common.lifecycle.Lifecycle$AnnotationBasedHandler - Invoking stop method[public void io.druid.server.coordination.AbstractDataSegmentAnnouncer.stop()] on object[io.druid.server.coordination.BatchDataSegmentAnnouncer@6e6b2081]. 2015-07-13T15:50:04,450 INFO [main] io.druid.server.coordination.AbstractDataSegmentAnnouncer - Stopping class io.druid.server.coordination.BatchDataSegmentAnnouncer with config[io.druid.server.initialization.ZkPathsConfig@58d3f4be] 2015-07-13T15:50:04,450 INFO [main] io.druid.curator.announcement.Announcer - unannouncing [/druid/announcements/ssubrama-ld1.linkedin.biz:8100] 2015-07-13T15:50:51,588 WARN [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Session 0x14e79f3d5e1000f for server ssubrama-ld1.linkedin.biz/0:0:0:0:0:0:0:1:2181, unexpected error, closing socket connection and attempting reconnect java.io.IOException: Xid out of order. Got Xid 35 with err 0 expected Xid 34 for a packet with details: clientPath:null serverPath:null finished:false header:: 34,14 replyHeader:: 0,0,-4 request:: org.apache.zookeeper.MultiTransactionRecord@731bdbc5 response:: org.apache.zookeeper.MultiResponse@0 at org.apache.zookeeper.ClientCnxn$SendThread.readResponse(ClientCnxn.java:798) ~[zookeeper-3.4.6.jar:3.4.6-1569965] at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:94) ~[zookeeper-3.4.6.jar:3.4.6-1569965] at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:366) ~[zookeeper-3.4.6.jar:3.4.6-1569965] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081) [zookeeper-3.4.6.jar:3.4.6-1569965] 2015-07-13T15:50:51,696 INFO [main-EventThread] org.apache.curator.framework.state.ConnectionStateManager - State change: SUSPENDED 2015-07-13T15:50:52,366 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Opening socket connection to server ssubrama-ld1.linkedin.biz/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown error) 2015-07-13T15:50:52,367 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Socket connection established to ssubrama-ld1.linkedin.biz/127.0.0.1:2181, initiating session 2015-07-13T15:50:52,370 WARN [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxnSocket - Connected to an old server; r-o mode will be unavailable 2015-07-13T15:50:52,370 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Session establishment complete on server ssubrama-ld1.linkedin.biz/127.0.0.1:2181, sessionid = 0x14e79f3d5e1000f, negotiated timeout = 60000 2015-07-13T15:50:52,370 INFO [main-EventThread] org.apache.curator.framework.state.ConnectionStateManager - State change: RECONNECTED 2015-07-13T15:50:52,697 WARN [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Session 0x14e79f3d5e1000f for server ssubrama-ld1.linkedin.biz/127.0.0.1:2181, unexpected error, closing socket connection and attempting reconnect java.io.IOException: Xid out of order. Got Xid 56 with err 0 expected Xid 55 for a packet with details: clientPath:null serverPath:null finished:false header:: 55,14 replyHeader:: 0,0,-4 request:: org.apache.zookeeper.MultiTransactionRecord@731bdbc5 response:: org.apache.zookeeper.MultiResponse@0 at org.apache.zookeeper.ClientCnxn$SendThread.readResponse(ClientCnxn.java:798) ~[zookeeper-3.4.6.jar:3.4.6-1569965] at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:94) ~[zookeeper-3.4.6.jar:3.4.6-1569965] at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:366) ~[zookeeper-3.4.6.jar:3.4.6-1569965] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081) [zookeeper-3.4.6.jar:3.4.6-1569965] 2015-07-13T15:50:52,798 INFO [main-EventThread] org.apache.curator.framework.state.ConnectionStateManager - State change: SUSPENDED 2015-07-13T15:50:53,643 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Opening socket connection to server ssubrama-ld1.linkedin.biz/0:0:0:0:0:0:0:1:2181. Will not attempt to authenticate using SASL (unknown error) 2015-07-13T15:50:53,643 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Socket connection established to ssubrama-ld1.linkedin.biz/0:0:0:0:0:0:0:1:2181, initiating session 2015-07-13T15:50:53,645 WARN [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxnSocket - Connected to an old server; r-o mode will be unavailable 2015-07-13T15:50:53,645 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Session establishment complete on server ssubrama-ld1.linkedin.biz/0:0:0:0:0:0:0:1:2181, sessionid = 0x14e79f3d5e1000f, negotiated timeout = 60000 2015-07-13T15:50:53,645 INFO [main-EventThread] org.apache.curator.framework.state.ConnectionStateManager - State change: RECONNECTED 2015-07-13T15:50:54,372 WARN [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Session 0x14e79f3d5e1000f for server ssubrama-ld1.linkedin.biz/0:0:0:0:0:0:0:1:2181, unexpected error, closing socket connection and attempting reconnect java.io.IOException: Xid out of order. Got Xid 77 with err 0 expected Xid 76 for a packet with details: clientPath:null serverPath:null finished:false header:: 76,14 replyHeader:: 0,0,-4 request:: org.apache.zookeeper.MultiTransactionRecord@731bdbc5 response:: org.apache.zookeeper.MultiResponse@0 at org.apache.zookeeper.ClientCnxn$SendThread.readResponse(ClientCnxn.java:798) ~[zookeeper-3.4.6.jar:3.4.6-1569965] at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:94) ~[zookeeper-3.4.6.jar:3.4.6-1569965] at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:366) ~[zookeeper-3.4.6.jar:3.4.6-1569965] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081) [zookeeper-3.4.6.jar:3.4.6-1569965] 2015-07-13T15:50:54,473 INFO [main-EventThread] org.apache.curator.framework.state.ConnectionStateManager - State change: SUSPENDED 2015-07-13T15:50:55,380 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Opening socket connection to server ssubrama-ld1.linkedin.biz/127.0.0.1:2181. Will not attempt to authenticate using SASL (unknown error) 2015-07-13T15:50:55,380 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Socket connection established to ssubrama-ld1.linkedin.biz/127.0.0.1:2181, initiating session 2015-07-13T15:50:55,382 WARN [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxnSocket - Connected to an old server; r-o mode will be unavailable 2015-07-13T15:50:55,382 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Session establishment complete on server ssubrama-ld1.linkedin.biz/127.0.0.1:2181, sessionid = 0x14e79f3d5e1000f, negotiated timeout = 60000 2015-07-13T15:50:55,382 INFO [main-EventThread] org.apache.curator.framework.state.ConnectionStateManager - State change: RECONNECTED 2015-07-13T15:50:56,383 WARN [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Session 0x14e79f3d5e1000f for server ssubrama-ld1.linkedin.biz/127.0.0.1:2181, unexpected error, closing socket connection and attempting reconnect java.io.IOException: Xid out of order. Got Xid 110 with err 0 expected Xid 109 for a packet with details: clientPath:null serverPath:null finished:false header:: 109,14 replyHeader:: 0,0,-4 request:: org.apache.zookeeper.MultiTransactionRecord@731bdbc5 response:: org.apache.zookeeper.MultiResponse@0 at org.apache.zookeeper.ClientCnxn$SendThread.readResponse(ClientCnxn.java:798) ~[zookeeper-3.4.6.jar:3.4.6-1569965] at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:94) ~[zookeeper-3.4.6.jar:3.4.6-1569965] at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:366) ~[zookeeper-3.4.6.jar:3.4.6-1569965] at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1081) [zookeeper-3.4.6.jar:3.4.6-1569965] 2015-07-13T15:50:56,484 INFO [main-EventThread] org.apache.curator.framework.state.ConnectionStateManager - State change: SUSPENDED 2015-07-13T15:50:56,558 INFO [main-SendThread(ssubrama-ld1.linkedin.biz:2181)] org.apache.zookeeper.ClientCnxn - Opening socket connection to server ssubrama-ld1.linkedin.biz/0:0:0:0:0:0:0:1:2181. Will not attempt to authenticate using SASL (unknown error)