Attachment 902464 Details for Bug 1105032 – storage log from addional storage host

storage log from addional storage host

rhq-storage2.log (text/x-log), 53.44 KB, created by Filip Brychta on 2014-06-05 09:17:28 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Filip Brychta

Created: 2014-06-05 09:17:28 UTC

Size: 53.44 KB

patch

obsolete

> INFO [main] 2014-06-05 04:56:29,612 CassandraDaemon.java (line 118) Logging initialized
> INFO [main] 2014-06-05 04:56:29,633 CassandraDaemon.java (line 145) JVM vendor/version: OpenJDK 64-Bit Server VM/1.7.0_45
> WARN [main] 2014-06-05 04:56:29,633 CassandraDaemon.java (line 150) OpenJDK is not recommended. Please upgrade to the newest Oracle Java release
> INFO [main] 2014-06-05 04:56:29,633 CassandraDaemon.java (line 183) Heap size: 523501568/523501568
> INFO [main] 2014-06-05 04:56:29,634 CassandraDaemon.java (line 184) Classpath: ./../conf:./../build/classes/main:./../build/classes/thrift:./../lib/antlr-3.2.jar:./../lib/apache-cassandra-1.2.9.jar:./../lib/apache-cassandra-1.2.9-jboss-1.jar:./../lib/apache-cassandra-clientutil-1.2.9-jboss-1.jar:./../lib/apache-cassandra-thrift-1.2.9-jboss-1.jar:./../lib/avro-1.4.0-fixes.jar:./../lib/avro-1.4.0-sources-fixes.jar:./../lib/commons-cli-1.1.jar:./../lib/commons-codec-1.2.jar:./../lib/commons-lang-2.6.jar:./../lib/compress-lzf-0.8.4.jar:./../lib/concurrentlinkedhashmap-lru-1.3.jar:./../lib/guava-13.0.1.jar:./../lib/high-scale-lib-1.1.2.jar:./../lib/jackson-core-asl-1.9.2.jar:./../lib/jackson-mapper-asl-1.9.2.jar:./../lib/jamm-0.2.5.jar:./../lib/jbcrypt-0.3m.jar:./../lib/jline-1.0.jar:./../lib/json-simple-1.1.jar:./../lib/libthrift-0.7.0.jar:./../lib/log4j-1.2.16.jar:./../lib/lz4-1.1.0.jar:./../lib/metrics-core-2.0.3.jar:./../lib/netty-3.6.6.Final.jar:./../lib/rhq-cassandra-auth-4.12.0-SNAPSHOT.jar:./../lib/servlet-api-2.5-20081211.jar:./../lib/slf4j-api-1.7.2.jar:./../lib/slf4j-log4j12-1.7.2.jar:./../lib/snakeyaml-1.6.jar:./../lib/snappy-java-1.0.4.1-rhq-p1.jar:./../lib/snaptree-0.1.jar:./../lib/jamm-0.2.5.jar
> INFO [main] 2014-06-05 04:56:29,636 CLibrary.java (line 65) JNA not found. Native methods will be disabled.
> INFO [main] 2014-06-05 04:56:29,651 DatabaseDescriptor.java (line 132) Loading settings from file:/home/hudson/rhq-server-4.12.0-SNAPSHOT/rhq-storage/conf/cassandra.yaml
> INFO [main] 2014-06-05 04:56:30,368 DatabaseDescriptor.java (line 150) Data files directories: [../../../rhq-data/data]
> INFO [main] 2014-06-05 04:56:30,368 DatabaseDescriptor.java (line 151) Commit log directory: ../../../rhq-data/commit_log
> INFO [main] 2014-06-05 04:56:30,368 DatabaseDescriptor.java (line 191) DiskAccessMode 'auto' determined to be mmap, indexAccessMode is mmap
> INFO [main] 2014-06-05 04:56:30,369 DatabaseDescriptor.java (line 205) disk_failure_policy is stop
> INFO [main] 2014-06-05 04:56:30,383 DatabaseDescriptor.java (line 276) Global memtable threshold is enabled at 166MB
> INFO [main] 2014-06-05 04:56:30,589 DatabaseDescriptor.java (line 404) Not using multi-threaded compaction
> WARN [main] 2014-06-05 04:56:30,608 SnappyCompressor.java (line 38) Cannot initialize native Snappy library. Compression on new tables will be disabled.
> INFO [main] 2014-06-05 04:56:30,947 CacheService.java (line 111) Initializing key cache with capacity of 24 MBs.
> INFO [main] 2014-06-05 04:56:30,958 CacheService.java (line 140) Scheduling key cache save to each 14400 seconds (going to save all keys).
> INFO [main] 2014-06-05 04:56:30,962 CacheService.java (line 154) Initializing row cache with capacity of 0 MBs and provider org.apache.cassandra.cache.SerializingCacheProvider
> INFO [main] 2014-06-05 04:56:30,970 CacheService.java (line 166) Scheduling row cache save to each 0 seconds (going to save all keys).
> INFO [main] 2014-06-05 04:56:31,081 ColumnFamilyStore.java (line 226) Initializing system.batchlog
> INFO [main] 2014-06-05 04:56:31,119 ColumnFamilyStore.java (line 226) Initializing system.peer_events
> INFO [main] 2014-06-05 04:56:31,129 ColumnFamilyStore.java (line 226) Initializing system.hints
> INFO [main] 2014-06-05 04:56:31,145 ColumnFamilyStore.java (line 226) Initializing system.Schema
> INFO [main] 2014-06-05 04:56:31,163 ColumnFamilyStore.java (line 226) Initializing system.schema_keyspaces
> INFO [main] 2014-06-05 04:56:31,173 ColumnFamilyStore.java (line 226) Initializing system.range_xfers
> INFO [main] 2014-06-05 04:56:31,184 ColumnFamilyStore.java (line 226) Initializing system.HintsColumnFamily
> INFO [main] 2014-06-05 04:56:31,193 ColumnFamilyStore.java (line 226) Initializing system.schema_columnfamilies
> INFO [main] 2014-06-05 04:56:31,200 ColumnFamilyStore.java (line 226) Initializing system.NodeIdInfo
> INFO [main] 2014-06-05 04:56:31,212 ColumnFamilyStore.java (line 226) Initializing system.schema_columns
> INFO [main] 2014-06-05 04:56:31,219 ColumnFamilyStore.java (line 226) Initializing system.IndexInfo
> INFO [main] 2014-06-05 04:56:31,231 ColumnFamilyStore.java (line 226) Initializing system.Migrations
> INFO [main] 2014-06-05 04:56:31,238 ColumnFamilyStore.java (line 226) Initializing system.peers
> INFO [main] 2014-06-05 04:56:31,246 ColumnFamilyStore.java (line 226) Initializing system.local
> INFO [main] 2014-06-05 04:56:31,254 ColumnFamilyStore.java (line 226) Initializing system.LocationInfo
> INFO [main] 2014-06-05 04:56:31,595 DatabaseDescriptor.java (line 538) Couldn't detect any schema definitions in local storage.
> INFO [main] 2014-06-05 04:56:31,597 DatabaseDescriptor.java (line 543) To create keyspaces and column families, see 'help create' in cqlsh.
> INFO [main] 2014-06-05 04:56:31,746 ColumnFamilyStore.java (line 226) Initializing system_traces.sessions
> INFO [main] 2014-06-05 04:56:31,760 ColumnFamilyStore.java (line 226) Initializing system_traces.events
> INFO [main] 2014-06-05 04:56:31,788 CommitLog.java (line 120) No commitlog files found; skipping replay
> INFO [main] 2014-06-05 04:56:32,273 StorageService.java (line 456) Cassandra version: 1.2.9
> INFO [main] 2014-06-05 04:56:32,274 StorageService.java (line 457) Thrift API version: 19.36.0
> INFO [main] 2014-06-05 04:56:32,274 StorageService.java (line 458) CQL supported versions: 2.0.0,3.0.5 (default: 3.0.5)
> INFO [main] 2014-06-05 04:56:32,370 StorageService.java (line 483) Loading persisted ring state
> INFO [main] 2014-06-05 04:56:32,373 StorageService.java (line 564) Starting up server gossip
> WARN [main] 2014-06-05 04:56:32,382 SystemTable.java (line 656) No host ID found, created 9899b774-672b-426e-b6fa-f157c5e1044a (Note: This should happen exactly once per node).
> INFO [main] 2014-06-05 04:56:32,427 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-local@616363718(352/352 serialized/live bytes, 15 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:32,451 Memtable.java (line 461) Writing Memtable-local@616363718(352/352 serialized/live bytes, 15 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:32,750 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/local/system-local-ic-1-Data.db (460 bytes) for commitlog position ReplayPosition(segmentId=1401958591542, position=52202)
> INFO [main] 2014-06-05 04:56:33,024 MessagingService.java (line 453) Starting Messaging Service on port 7100
> INFO [main] 2014-06-05 04:56:33,076 StorageService.java (line 731) Generated random tokens. tokens are [-2930753824160563402, -8466838478684811987, -8654181028755618289, 2755651407127249137, -7093716851912054238, 3841709053204495136, -5386199225584875164, -7914111719329745565, -1288347335352423262, -4153941003844332283, -7403122706071957787, -3551733092595300351, -2512257002535536354, 2885647858627032933, -8704849660106156789, -5847842241108745816, 7957379830705890601, 5086412937204689005, 7971117685570074362, 4174502248478849621, 2479675117818665731, 4523438789357160760, -3213794990266114217, -5612575141578378636, 9219983626662671732, -9164066231125691303, -4970708253666468621, -3887597867080124809, -911073099360454477, 937188100320284289, 5857493141180758314, 2806902518956559915, -1322830091122660272, 1702533147305707575, 2897887034752509418, -2764952512778447820, -3746166940886971140, 6122261574640466929, 8986811468549755209, 4700059531151714943, 3358501753415589328, -682028802520610588, 1797086152486665552, 9150071659300803621, 6154021083958604602, -3739343566864103561, -804198950728269122, 5986362813791040426, -3449657836811501867, -5398861986038825591, -669466614884857499, 2985334901570952509, -315321243288971666, -5418090030848973456, 5475322704862417485, 3120722795019364682, -4602118971746153786, 4517461367318049183, 2495490879898562746, 7086098046221270800, 7975524430221814120, 356904708991163199, -2042348676757648598, -6714469729234160446, 7908838932662343819, 7947494125830729760, 5826972838222234103, 1786152359475705033, -2667523491722877102, -7016801829177670911, -67945505872277946, 3347933646692404048, -1699484578804863925, -3148668462492958536, 8351603002347370189, 2689702018039501457, 8781774929238268486, 6979907986575506432, 8399602646794809602, 9058766623517032566, 7804574567132167328, -5996130451441608472, -8477933143679409556, 2917126578160094040, 6598559375964303720, -3392745120769268938, 343481105149678976, -4564100291792445016, 8726747200786187296, 4957186264249720648, -4635812602138213635, -5941135289258849464, 3605013350668189134, 3436359795442106381, -3358387078001290668, 2912041908072590050, 6781430723011001316, -3149177888619930505, -4493562648355225300, 7901603784620539770, -2222093143663789794, 1501193406067161173, 2737116504406356950, -8817489578691129290, -598006390720075730, -4896367530728654003, -8007533111896273769, -5199360858997132899, -6444156922122305517, -7255159475284219669, -8104669947792666762, -1973902371581828198, -6429361674614840984, -3026005249220514622, -1645430492793208092, -8163773080727226628, -611506054688771277, 8728726301383756102, -2810046855934439980, 6429037412938608029, -8582467449846738268, 5799768019133805550, -8744009517756528115, -6158376052377935919, -2499543470069398415, -9059364735803547457, 4075898933718882560, 5219972000953468871, -4521569876337725721, 2176723196156679170, 9176974520941778735, -7939290849807275640, 5720125288003870161, -7600860201494489360, 7196260445184882553, -619731454356109449, 7738596222637144965, 3725617231834327916, -1807670128619633570, 7573939917272050036, 5808614866382224048, 4107449377683428377, -1602305483808636855, -187177526173848295, -5710293930295350657, -8622278587753220388, 8841523218974968927, -8246428009619864736, 7081217286996910763, 7338887056639998139, 3617463496358167099, 3067804839341813532, -4521330481388470771, 770708745091797277, -9118884696955973365, -5089270606085871350, 8648491575245901311, -5910623477707613966, -7789261080159809041, 7210609822424055974, 3773583360926652944, 8512768874665849071, 6781193341499645720, 1096966579686786904, 6230825500245743453, 3719108557906891682, -1737573852000905392, 7007305664568205615, -4044087800296493413, 5831096864470222360, -2213866110659960445, -8682968491909970364, 7938062273366724427, -4238682578269427152, 351628608181348608, -703774696565870431, 3007370034878577912, 5256917976246997836, -5293949244309535236, -6907429971374723335, -6138757359559909536, 3650426593271214753, -7716766116531953391, 3646933234071932168, -7632970690611308794, 8946941742055842765, 5175311538084851369, -5050962812114991733, -7678063301755914378, -4902114800285596075, -4448546892885960843, -2516038405137493355, 3251336091660039735, -2390192454642198551, 3584020959415138193, 4784730131862048406, 620717307197558892, -6496456963977661993, 5296594473086107446, 8381931908734883498, 6179304542457896297, 8373455575639226396, 4064199801807282570, -328521791934760930, -4940041066039841059, -1314912869376482039, -3262215070987633003, 8781519241851631384, 4053246765871826523, -55127653283828266, 6632098288848479534, -7964328987567426839, 6680839702615720523, -5272978548147066521, 1927064052125879908, -1792312799760188196, 5415076126037271849, -4929331569407567770, 8232973390177102089, 289052326968818583, -4632812535090936893, -6612819788790952964, 2609494149151708055, -464244252419138804, 3301487633889393565, 5704654136917462939, -6132865444265523191, -2744978926154471770, 7519225402397037499, 533364468484558979, -8770506921945750367, -244508967390770478, -5785645263079662039, 4487295265791832649, 8347744153421367916, 8963782374120773303, 4912135472833553579, -4200604039698544497, -4261051607647237432, -600647635964724541, 4337751216652605101, -5021826833233408077, 4089351317326277350, 8690189216558942642, 5806982643735085211, 6355970362954460250, -1703029047010102716, -6550334416662638328, 510725839953446135, 6654691833788654955, 5245283100883991550, 6667468287021128701, 4801214736230354376, -4088540208082301193, -7671233726885197804, 4544090420371081815]
> INFO [main] 2014-06-05 04:56:33,078 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-local@1683989171(84/84 serialized/live bytes, 4 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:33,078 Memtable.java (line 461) Writing Memtable-local@1683989171(84/84 serialized/live bytes, 4 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:33,330 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/local/system-local-ic-2-Data.db (136 bytes) for commitlog position ReplayPosition(segmentId=1401958591542, position=52477)
> INFO [main] 2014-06-05 04:56:33,421 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-local@742317945(10094/10094 serialized/live bytes, 257 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:33,422 Memtable.java (line 461) Writing Memtable-local@742317945(10094/10094 serialized/live bytes, 257 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:33,663 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/local/system-local-ic-3-Data.db (11959 bytes) for commitlog position ReplayPosition(segmentId=1401958591542, position=64480)
> INFO [main] 2014-06-05 04:56:33,760 StorageService.java (line 1354) Node fbr-st2.bc.jonqe.lab.eng.bos.redhat.com/10.16.23.96 state jump to normal
> INFO [main] 2014-06-05 04:56:33,782 StorageService.java (line 807) Startup completed! Now serving reads.
> INFO [main] 2014-06-05 04:56:33,784 MigrationManager.java (line 216) Create new Keyspace: system_auth, rep strategy:SimpleStrategy{}, strategy_options: {replication_factor=1}, durable_writes: true
> INFO [MigrationStage:1] 2014-06-05 04:56:33,864 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_keyspaces@874381537(389/389 serialized/live bytes, 11 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:33,865 Memtable.java (line 461) Writing Memtable-schema_keyspaces@874381537(389/389 serialized/live bytes, 11 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:34,069 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_keyspaces/system-schema_keyspaces-ic-1-Data.db (560 bytes) for commitlog position ReplayPosition(segmentId=1401958591542, position=64721)
> INFO [MigrationStage:1] 2014-06-05 04:56:34,072 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_columnfamilies@2118005927(21238/21238 serialized/live bytes, 369 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:34,073 Memtable.java (line 461) Writing Memtable-schema_columnfamilies@2118005927(21238/21238 serialized/live bytes, 369 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:34,271 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_columnfamilies/system-schema_columnfamilies-ic-1-Data.db (23878 bytes) for commitlog position ReplayPosition(segmentId=1401958591542, position=64721)
> INFO [MigrationStage:1] 2014-06-05 04:56:34,273 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_columns@1893704821(23741/23741 serialized/live bytes, 386 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:34,274 Memtable.java (line 461) Writing Memtable-schema_columns@1893704821(23741/23741 serialized/live bytes, 386 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:34,526 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_columns/system-schema_columns-ic-1-Data.db (26500 bytes) for commitlog position ReplayPosition(segmentId=1401958591542, position=64721)
> INFO [main] 2014-06-05 04:56:34,677 MigrationManager.java (line 230) Create new ColumnFamily: org.apache.cassandra.config.CFMetaData@4b44b95f[cfId=473588ad-9c79-38be-8b59-e06c10456ba0,ksName=system_auth,cfName=users,cfType=Standard,comparator=org.apache.cassandra.db.marshal.CompositeType(org.apache.cassandra.db.marshal.UTF8Type),subcolumncomparator=<null>,comment=,readRepairChance=0.1,dclocalReadRepairChance=0.0,replicateOnWrite=true,gcGraceSeconds=7776000,defaultValidator=org.apache.cassandra.db.marshal.BytesType,keyValidator=org.apache.cassandra.db.marshal.UTF8Type,minCompactionThreshold=4,maxCompactionThreshold=32,keyAliases=[java.nio.HeapByteBuffer[pos=0 lim=4 cap=4]],columnAliases=[],valueAlias=<null>,column_metadata={java.nio.HeapByteBuffer[pos=0 lim=5 cap=5]=ColumnDefinition{name=7375706572, validator=org.apache.cassandra.db.marshal.BooleanType, index_type=null, index_name='null', component_index=0}},compactionStrategyClass=class org.apache.cassandra.db.compaction.SizeTieredCompactionStrategy,compactionStrategyOptions={},compressionOptions={},bloomFilterFpChance=0.01,caching=KEYS_ONLY,populateIoCacheOnFlush=false]
> INFO [MigrationStage:1] 2014-06-05 04:56:34,735 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_columnfamilies@951175664(1043/1043 serialized/live bytes, 21 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:34,736 Memtable.java (line 461) Writing Memtable-schema_columnfamilies@951175664(1043/1043 serialized/live bytes, 21 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:34,961 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_columnfamilies/system-schema_columnfamilies-ic-2-Data.db (1227 bytes) for commitlog position ReplayPosition(segmentId=1401958591542, position=66492)
> INFO [MigrationStage:1] 2014-06-05 04:56:34,962 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_columns@954601705(278/278 serialized/live bytes, 6 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:34,963 Memtable.java (line 461) Writing Memtable-schema_columns@954601705(278/278 serialized/live bytes, 6 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:35,267 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_columns/system-schema_columns-ic-2-Data.db (357 bytes) for commitlog position ReplayPosition(segmentId=1401958591542, position=66492)
> INFO [MigrationStage:1] 2014-06-05 04:56:35,321 ColumnFamilyStore.java (line 226) Initializing system_auth.users
> INFO [main] 2014-06-05 04:56:35,377 MigrationManager.java (line 230) Create new ColumnFamily: org.apache.cassandra.config.CFMetaData@2e8ec518[cfId=1064e0a0-1f2c-3a17-b803-cb8e23d8458f,ksName=system_auth,cfName=credentials,cfType=Standard,comparator=org.apache.cassandra.db.marshal.CompositeType(org.apache.cassandra.db.marshal.UTF8Type,org.apache.cassandra.db.marshal.ColumnToCollectionType(6f7074696f6e73:org.apache.cassandra.db.marshal.MapType(org.apache.cassandra.db.marshal.UTF8Type,org.apache.cassandra.db.marshal.UTF8Type))),subcolumncomparator=<null>,comment=,readRepairChance=0.1,dclocalReadRepairChance=0.0,replicateOnWrite=true,gcGraceSeconds=7776000,defaultValidator=org.apache.cassandra.db.marshal.BytesType,keyValidator=org.apache.cassandra.db.marshal.UTF8Type,minCompactionThreshold=4,maxCompactionThreshold=32,keyAliases=[java.nio.HeapByteBuffer[pos=0 lim=8 cap=8]],columnAliases=[],valueAlias=<null>,column_metadata={java.nio.HeapByteBuffer[pos=0 lim=11 cap=11]=ColumnDefinition{name=73616c7465645f68617368, validator=org.apache.cassandra.db.marshal.UTF8Type, index_type=null, index_name='null', component_index=0}, java.nio.HeapByteBuffer[pos=0 lim=7 cap=7]=ColumnDefinition{name=6f7074696f6e73, validator=org.apache.cassandra.db.marshal.MapType(org.apache.cassandra.db.marshal.UTF8Type,org.apache.cassandra.db.marshal.UTF8Type), index_type=null, index_name='null', component_index=0}},compactionStrategyClass=class org.apache.cassandra.db.compaction.SizeTieredCompactionStrategy,compactionStrategyOptions={},compressionOptions={},bloomFilterFpChance=0.01,caching=KEYS_ONLY,populateIoCacheOnFlush=false]
> INFO [MigrationStage:1] 2014-06-05 04:56:35,445 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_columnfamilies@523092617(1367/1367 serialized/live bytes, 21 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:35,446 Memtable.java (line 461) Writing Memtable-schema_columnfamilies@523092617(1367/1367 serialized/live bytes, 21 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:35,672 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_columnfamilies/system-schema_columnfamilies-ic-3-Data.db (1551 bytes) for commitlog position ReplayPosition(segmentId=1401958591542, position=69103)
> INFO [MigrationStage:1] 2014-06-05 04:56:35,674 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_columns@2103424249(752/752 serialized/live bytes, 12 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:35,675 Memtable.java (line 461) Writing Memtable-schema_columns@2103424249(752/752 serialized/live bytes, 12 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:35,894 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_columns/system-schema_columns-ic-3-Data.db (873 bytes) for commitlog position ReplayPosition(segmentId=1401958591542, position=69103)
> INFO [MigrationStage:1] 2014-06-05 04:56:35,942 ColumnFamilyStore.java (line 226) Initializing system_auth.credentials
> INFO [main] 2014-06-05 04:56:36,002 MigrationManager.java (line 230) Create new ColumnFamily: org.apache.cassandra.config.CFMetaData@da7d681[cfId=2d324e48-3275-3517-8dd5-9a2c5b0856c5,ksName=system_auth,cfName=permissions,cfType=Standard,comparator=org.apache.cassandra.db.marshal.CompositeType(org.apache.cassandra.db.marshal.UTF8Type,org.apache.cassandra.db.marshal.UTF8Type,org.apache.cassandra.db.marshal.ColumnToCollectionType(7065726d697373696f6e73:org.apache.cassandra.db.marshal.SetType(org.apache.cassandra.db.marshal.UTF8Type))),subcolumncomparator=<null>,comment=,readRepairChance=0.1,dclocalReadRepairChance=0.0,replicateOnWrite=true,gcGraceSeconds=7776000,defaultValidator=org.apache.cassandra.db.marshal.BytesType,keyValidator=org.apache.cassandra.db.marshal.UTF8Type,minCompactionThreshold=4,maxCompactionThreshold=32,keyAliases=[java.nio.HeapByteBuffer[pos=0 lim=8 cap=8]],columnAliases=[java.nio.HeapByteBuffer[pos=0 lim=8 cap=8]],valueAlias=<null>,column_metadata={java.nio.HeapByteBuffer[pos=0 lim=11 cap=11]=ColumnDefinition{name=7065726d697373696f6e73, validator=org.apache.cassandra.db.marshal.SetType(org.apache.cassandra.db.marshal.UTF8Type), index_type=null, index_name='null', component_index=1}},compactionStrategyClass=class org.apache.cassandra.db.compaction.SizeTieredCompactionStrategy,compactionStrategyOptions={},compressionOptions={},bloomFilterFpChance=0.01,caching=KEYS_ONLY,populateIoCacheOnFlush=false]
> INFO [MigrationStage:1] 2014-06-05 04:56:36,042 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_columnfamilies@99857960(1385/1385 serialized/live bytes, 21 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:36,043 Memtable.java (line 461) Writing Memtable-schema_columnfamilies@99857960(1385/1385 serialized/live bytes, 21 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:37,007 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_columnfamilies/system-schema_columnfamilies-ic-4-Data.db (1569 bytes) for commitlog position ReplayPosition(segmentId=1401958591542, position=71326)
> INFO [MigrationStage:1] 2014-06-05 04:56:37,013 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_columns@1777130512(388/388 serialized/live bytes, 6 ops)
> INFO [FlushWriter:1] 2014-06-05 04:56:37,013 Memtable.java (line 461) Writing Memtable-schema_columns@1777130512(388/388 serialized/live bytes, 6 ops)
> INFO [CompactionExecutor:1] 2014-06-05 04:56:37,019 CompactionTask.java (line 105) Compacting [SSTableReader(path='../../../rhq-data/data/system/schema_columnfamilies/system-schema_columnfamilies-ic-2-Data.db'), SSTableReader(path='../../../rhq-data/data/system/schema_columnfamilies/system-schema_columnfamilies-ic-4-Data.db'), SSTableReader(path='../../../rhq-data/data/system/schema_columnfamilies/system-schema_columnfamilies-ic-1-Data.db'), SSTableReader(path='../../../rhq-data/data/system/schema_columnfamilies/system-schema_columnfamilies-ic-3-Data.db')]
> INFO [FlushWriter:1] 2014-06-05 04:56:37,355 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_columns/system-schema_columns-ic-4-Data.db (467 bytes) for commitlog position ReplayPosition(segmentId=1401958591542, position=71326)
> INFO [MigrationStage:1] 2014-06-05 04:56:37,553 ColumnFamilyStore.java (line 226) Initializing system_auth.permissions
> INFO [CompactionExecutor:1] 2014-06-05 04:56:37,828 CompactionTask.java (line 262) Compacted 4 sstables to [../../../rhq-data/data/system/schema_columnfamilies/system-schema_columnfamilies-ic-5,].  28,225 bytes to 28,151 (~99% of original) in 698ms = 0.038463MB/s.  5 total rows, 3 unique.  Row merge counts were {1:2, 2:0, 3:1, 4:0, }
> INFO [main] 2014-06-05 04:56:37,925 Server.java (line 138) Starting listening for CQL clients on fbr-st2.bc.jonqe.lab.eng.bos.redhat.com/10.16.23.96:9142...
> INFO [main] 2014-06-05 04:56:37,967 CassandraDaemon.java (line 402) Not starting RPC server as requested. Use JMX (StorageService->startRPCServer()) or nodetool (enablethrift) to start it
> INFO [NonPeriodicTasks:1] 2014-06-05 04:56:46,811 PasswordAuthenticator.java (line 228) PasswordAuthenticator created default user 'cassandra'
> INFO [NonPeriodicTasks:1] 2014-06-05 04:56:47,637 Auth.java (line 217) Created default superuser 'cassandra'
> INFO [main] 2014-06-05 04:57:21,741 CassandraDaemon.java (line 118) Logging initialized
> INFO [main] 2014-06-05 04:57:21,774 CassandraDaemon.java (line 145) JVM vendor/version: OpenJDK 64-Bit Server VM/1.7.0_45
> WARN [main] 2014-06-05 04:57:21,775 CassandraDaemon.java (line 150) OpenJDK is not recommended. Please upgrade to the newest Oracle Java release
> INFO [main] 2014-06-05 04:57:21,776 CassandraDaemon.java (line 183) Heap size: 523501568/523501568
> INFO [main] 2014-06-05 04:57:21,776 CassandraDaemon.java (line 184) Classpath: ./../conf:./../build/classes/main:./../build/classes/thrift:./../lib/antlr-3.2.jar:./../lib/apache-cassandra-1.2.9.jar:./../lib/apache-cassandra-1.2.9-jboss-1.jar:./../lib/apache-cassandra-clientutil-1.2.9-jboss-1.jar:./../lib/apache-cassandra-thrift-1.2.9-jboss-1.jar:./../lib/avro-1.4.0-fixes.jar:./../lib/avro-1.4.0-sources-fixes.jar:./../lib/commons-cli-1.1.jar:./../lib/commons-codec-1.2.jar:./../lib/commons-lang-2.6.jar:./../lib/compress-lzf-0.8.4.jar:./../lib/concurrentlinkedhashmap-lru-1.3.jar:./../lib/guava-13.0.1.jar:./../lib/high-scale-lib-1.1.2.jar:./../lib/jackson-core-asl-1.9.2.jar:./../lib/jackson-mapper-asl-1.9.2.jar:./../lib/jamm-0.2.5.jar:./../lib/jbcrypt-0.3m.jar:./../lib/jline-1.0.jar:./../lib/json-simple-1.1.jar:./../lib/libthrift-0.7.0.jar:./../lib/log4j-1.2.16.jar:./../lib/lz4-1.1.0.jar:./../lib/metrics-core-2.0.3.jar:./../lib/netty-3.6.6.Final.jar:./../lib/rhq-cassandra-auth-4.12.0-SNAPSHOT.jar:./../lib/servlet-api-2.5-20081211.jar:./../lib/slf4j-api-1.7.2.jar:./../lib/slf4j-log4j12-1.7.2.jar:./../lib/snakeyaml-1.6.jar:./../lib/snappy-java-1.0.4.1-rhq-p1.jar:./../lib/snaptree-0.1.jar:./../lib/jamm-0.2.5.jar
> INFO [main] 2014-06-05 04:57:21,780 CLibrary.java (line 65) JNA not found. Native methods will be disabled.
> INFO [main] 2014-06-05 04:57:21,800 DatabaseDescriptor.java (line 132) Loading settings from file:/home/hudson/rhq-server-4.12.0-SNAPSHOT/rhq-storage/conf/cassandra.yaml
> INFO [main] 2014-06-05 04:57:22,484 DatabaseDescriptor.java (line 150) Data files directories: [../../../rhq-data/data]
> INFO [main] 2014-06-05 04:57:22,484 DatabaseDescriptor.java (line 151) Commit log directory: ../../../rhq-data/commit_log
> INFO [main] 2014-06-05 04:57:22,485 DatabaseDescriptor.java (line 191) DiskAccessMode 'auto' determined to be mmap, indexAccessMode is mmap
> INFO [main] 2014-06-05 04:57:22,485 DatabaseDescriptor.java (line 205) disk_failure_policy is stop
> INFO [main] 2014-06-05 04:57:22,505 DatabaseDescriptor.java (line 276) Global memtable threshold is enabled at 166MB
> INFO [main] 2014-06-05 04:57:22,698 DatabaseDescriptor.java (line 404) Not using multi-threaded compaction
> WARN [main] 2014-06-05 04:57:22,716 SnappyCompressor.java (line 38) Cannot initialize native Snappy library. Compression on new tables will be disabled.
> INFO [main] 2014-06-05 04:57:23,011 CacheService.java (line 111) Initializing key cache with capacity of 24 MBs.
> INFO [main] 2014-06-05 04:57:23,028 CacheService.java (line 140) Scheduling key cache save to each 14400 seconds (going to save all keys).
> INFO [main] 2014-06-05 04:57:23,034 CacheService.java (line 154) Initializing row cache with capacity of 0 MBs and provider org.apache.cassandra.cache.SerializingCacheProvider
> INFO [main] 2014-06-05 04:57:23,041 CacheService.java (line 166) Scheduling row cache save to each 0 seconds (going to save all keys).
> INFO [main] 2014-06-05 04:57:23,133 ColumnFamilyStore.java (line 226) Initializing system.batchlog
> INFO [main] 2014-06-05 04:57:23,169 ColumnFamilyStore.java (line 226) Initializing system.peer_events
> INFO [main] 2014-06-05 04:57:23,179 ColumnFamilyStore.java (line 226) Initializing system.hints
> INFO [main] 2014-06-05 04:57:23,194 ColumnFamilyStore.java (line 226) Initializing system.Schema
> INFO [main] 2014-06-05 04:57:23,207 ColumnFamilyStore.java (line 226) Initializing system.schema_keyspaces
> INFO [main] 2014-06-05 04:57:23,217 ColumnFamilyStore.java (line 226) Initializing system.range_xfers
> INFO [main] 2014-06-05 04:57:23,228 ColumnFamilyStore.java (line 226) Initializing system.HintsColumnFamily
> INFO [main] 2014-06-05 04:57:23,239 ColumnFamilyStore.java (line 226) Initializing system.schema_columnfamilies
> INFO [main] 2014-06-05 04:57:23,246 ColumnFamilyStore.java (line 226) Initializing system.NodeIdInfo
> INFO [main] 2014-06-05 04:57:23,253 ColumnFamilyStore.java (line 226) Initializing system.schema_columns
> INFO [main] 2014-06-05 04:57:23,263 ColumnFamilyStore.java (line 226) Initializing system.IndexInfo
> INFO [main] 2014-06-05 04:57:23,275 ColumnFamilyStore.java (line 226) Initializing system.Migrations
> INFO [main] 2014-06-05 04:57:23,285 ColumnFamilyStore.java (line 226) Initializing system.peers
> INFO [main] 2014-06-05 04:57:23,290 ColumnFamilyStore.java (line 226) Initializing system.local
> INFO [main] 2014-06-05 04:57:23,303 ColumnFamilyStore.java (line 226) Initializing system.LocationInfo
> INFO [main] 2014-06-05 04:57:23,593 DatabaseDescriptor.java (line 538) Couldn't detect any schema definitions in local storage.
> INFO [main] 2014-06-05 04:57:23,615 DatabaseDescriptor.java (line 543) To create keyspaces and column families, see 'help create' in cqlsh.
> INFO [main] 2014-06-05 04:57:23,700 ColumnFamilyStore.java (line 226) Initializing system_traces.sessions
> INFO [main] 2014-06-05 04:57:23,710 ColumnFamilyStore.java (line 226) Initializing system_traces.events
> INFO [main] 2014-06-05 04:57:23,737 CommitLog.java (line 120) No commitlog files found; skipping replay
> INFO [main] 2014-06-05 04:57:24,207 StorageService.java (line 456) Cassandra version: 1.2.9
> INFO [main] 2014-06-05 04:57:24,208 StorageService.java (line 457) Thrift API version: 19.36.0
> INFO [main] 2014-06-05 04:57:24,208 StorageService.java (line 458) CQL supported versions: 2.0.0,3.0.5 (default: 3.0.5)
> INFO [main] 2014-06-05 04:57:24,301 StorageService.java (line 483) Loading persisted ring state
> INFO [main] 2014-06-05 04:57:24,304 StorageService.java (line 564) Starting up server gossip
> WARN [main] 2014-06-05 04:57:24,313 SystemTable.java (line 656) No host ID found, created fe3c4142-71b4-4e4c-aec8-72ef4067943d (Note: This should happen exactly once per node).
> INFO [main] 2014-06-05 04:57:24,356 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-local@2118989991(352/352 serialized/live bytes, 15 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:24,360 Memtable.java (line 461) Writing Memtable-local@2118989991(352/352 serialized/live bytes, 15 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:24,618 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/local/system-local-ic-1-Data.db (460 bytes) for commitlog position ReplayPosition(segmentId=1401958643566, position=52202)
> INFO [main] 2014-06-05 04:57:24,843 MessagingService.java (line 453) Starting Messaging Service on port 7100
> INFO [main] 2014-06-05 04:57:24,889 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-local@1145587356(86/86 serialized/live bytes, 4 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:24,890 Memtable.java (line 461) Writing Memtable-local@1145587356(86/86 serialized/live bytes, 4 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:25,099 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/local/system-local-ic-2-Data.db (138 bytes) for commitlog position ReplayPosition(segmentId=1401958643566, position=52479)
> INFO [main] 2014-06-05 04:57:25,102 StorageService.java (line 914) JOINING: waiting for ring information
> INFO [HANDSHAKE-fbr-tmp.bc.jonqe.lab.eng.bos.redhat.com/10.16.23.137] 2014-06-05 04:57:25,710 OutboundTcpConnection.java (line 399) Handshaking version with fbr-tmp.bc.jonqe.lab.eng.bos.redhat.com/10.16.23.137
> INFO [GossipStage:1] 2014-06-05 04:57:26,743 Gossiper.java (line 823) Node /10.16.23.69 is now part of the cluster
> INFO [GossipStage:1] 2014-06-05 04:57:26,745 Gossiper.java (line 789) InetAddress /10.16.23.69 is now UP
> INFO [GossipStage:1] 2014-06-05 04:57:26,858 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-peers@323072312(10141/10141 serialized/live bytes, 261 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:26,859 Memtable.java (line 461) Writing Memtable-peers@323072312(10141/10141 serialized/live bytes, 261 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:27,079 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/peers/system-peers-ic-1-Data.db (12019 bytes) for commitlog position ReplayPosition(segmentId=1401958643566, position=64728)
> INFO [GossipStage:1] 2014-06-05 04:57:27,086 Gossiper.java (line 823) Node /10.16.23.137 is now part of the cluster
> INFO [GossipStage:1] 2014-06-05 04:57:27,087 Gossiper.java (line 789) InetAddress /10.16.23.137 is now UP
> INFO [HANDSHAKE-fbr-tmp.bc.jonqe.lab.eng.bos.redhat.com/10.16.23.137] 2014-06-05 04:57:27,093 OutboundTcpConnection.java (line 399) Handshaking version with fbr-tmp.bc.jonqe.lab.eng.bos.redhat.com/10.16.23.137
> INFO [GossipStage:1] 2014-06-05 04:57:27,197 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-peers@1974074174(10301/10301 serialized/live bytes, 269 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:27,198 Memtable.java (line 461) Writing Memtable-peers@1974074174(10301/10301 serialized/live bytes, 269 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:27,367 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/peers/system-peers-ic-2-Data.db (12244 bytes) for commitlog position ReplayPosition(segmentId=1401958643566, position=77526)
> INFO [InternalResponseStage:1] 2014-06-05 04:57:27,410 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_keyspaces@543482925(527/527 serialized/live bytes, 14 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:27,411 Memtable.java (line 461) Writing Memtable-schema_keyspaces@543482925(527/527 serialized/live bytes, 14 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:27,569 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_keyspaces/system-schema_keyspaces-ic-1-Data.db (748 bytes) for commitlog position ReplayPosition(segmentId=1401958643566, position=98186)
> INFO [InternalResponseStage:1] 2014-06-05 04:57:27,570 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_columnfamilies@1400425795(36585/36585 serialized/live bytes, 603 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:27,571 Memtable.java (line 461) Writing Memtable-schema_columnfamilies@1400425795(36585/36585 serialized/live bytes, 603 ops)
> INFO [HANDSHAKE-fbr-tmp.bc.jonqe.lab.eng.bos.redhat.com/10.16.23.137] 2014-06-05 04:57:27,674 OutboundTcpConnection.java (line 399) Handshaking version with fbr-tmp.bc.jonqe.lab.eng.bos.redhat.com/10.16.23.137
> INFO [FlushWriter:1] 2014-06-05 04:57:27,903 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_columnfamilies/system-schema_columnfamilies-ic-1-Data.db (40929 bytes) for commitlog position ReplayPosition(segmentId=1401958643566, position=98186)
> INFO [InternalResponseStage:1] 2014-06-05 04:57:27,905 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_columns@1097877559(26010/26010 serialized/live bytes, 422 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:27,906 Memtable.java (line 461) Writing Memtable-schema_columns@1097877559(26010/26010 serialized/live bytes, 422 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:28,341 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_columns/system-schema_columns-ic-1-Data.db (29087 bytes) for commitlog position ReplayPosition(segmentId=1401958643566, position=98186)
> INFO [InternalResponseStage:1] 2014-06-05 04:57:28,556 ColumnFamilyStore.java (line 226) Initializing rhq.raw_metrics
> INFO [InternalResponseStage:1] 2014-06-05 04:57:28,562 ColumnFamilyStore.java (line 226) Initializing rhq.six_hour_metrics
> INFO [InternalResponseStage:1] 2014-06-05 04:57:28,565 ColumnFamilyStore.java (line 226) Initializing rhq.one_hour_metrics
> INFO [InternalResponseStage:1] 2014-06-05 04:57:28,568 ColumnFamilyStore.java (line 226) Initializing rhq.metrics_cache
> INFO [InternalResponseStage:1] 2014-06-05 04:57:28,581 ColumnFamilyStore.java (line 226) Initializing rhq.twenty_four_hour_metrics
> INFO [InternalResponseStage:1] 2014-06-05 04:57:28,592 ColumnFamilyStore.java (line 226) Initializing rhq.metrics_cache_index
> INFO [InternalResponseStage:1] 2014-06-05 04:57:28,598 ColumnFamilyStore.java (line 226) Initializing rhq.schema_version
> INFO [InternalResponseStage:1] 2014-06-05 04:57:28,615 ColumnFamilyStore.java (line 226) Initializing system_auth.users
> INFO [InternalResponseStage:1] 2014-06-05 04:57:28,618 ColumnFamilyStore.java (line 226) Initializing system_auth.permissions
> INFO [InternalResponseStage:1] 2014-06-05 04:57:28,622 ColumnFamilyStore.java (line 226) Initializing system_auth.credentials
> INFO [main] 2014-06-05 04:57:29,103 StorageService.java (line 914) JOINING: schema complete, ready to bootstrap
> INFO [main] 2014-06-05 04:57:29,104 StorageService.java (line 914) JOINING: getting bootstrap token
> INFO [main] 2014-06-05 04:57:29,168 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-local@1111987614(10147/10147 serialized/live bytes, 259 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:29,169 Memtable.java (line 461) Writing Memtable-local@1111987614(10147/10147 serialized/live bytes, 259 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:29,368 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/local/system-local-ic-3-Data.db (12019 bytes) for commitlog position ReplayPosition(segmentId=1401958643566, position=110343)
> INFO [main] 2014-06-05 04:57:29,547 StorageService.java (line 914) JOINING: sleeping 30000 ms for pending range setup
> INFO [GossipTasks:1] 2014-06-05 04:57:36,680 Gossiper.java (line 803) InetAddress /10.16.23.69 is now DOWN
> INFO [MigrationStage:1] 2014-06-05 04:57:51,462 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_keyspaces@167283382(138/138 serialized/live bytes, 3 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:51,477 Memtable.java (line 461) Writing Memtable-schema_keyspaces@167283382(138/138 serialized/live bytes, 3 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:51,791 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_keyspaces/system-schema_keyspaces-ic-2-Data.db (188 bytes) for commitlog position ReplayPosition(segmentId=1401958643566, position=112098)
> INFO [MigrationStage:1] 2014-06-05 04:57:52,146 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-schema_keyspaces@1690412913(138/138 serialized/live bytes, 3 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:52,149 Memtable.java (line 461) Writing Memtable-schema_keyspaces@1690412913(138/138 serialized/live bytes, 3 ops)
> INFO [FlushWriter:1] 2014-06-05 04:57:52,408 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/schema_keyspaces/system-schema_keyspaces-ic-3-Data.db (196 bytes) for commitlog position ReplayPosition(segmentId=1401958643566, position=112481)
> INFO [main] 2014-06-05 04:57:59,548 StorageService.java (line 914) JOINING: Starting to bootstrap...
> INFO [Thread-8] 2014-06-05 04:58:00,137 StreamInSession.java (line 199) Finished streaming session 7ea3f680-ec8f-11e3-8521-a5e02808e306 from /10.16.23.137
> INFO [Thread-24] 2014-06-05 04:58:03,075 StreamInSession.java (line 199) Finished streaming session 7ea492c0-ec8f-11e3-8521-a5e02808e306 from /10.16.23.137
> INFO [main] 2014-06-05 04:58:03,081 StorageService.java (line 950) Bootstrap completed! for the tokens [-5290539633950511352, 5289874102698422048, -8900819419281818748, -5942530231534415091, -4186282621452487993, -4219978960264225360, -1437922746704191206, 1576223736212001622, 6397561681861007860, -2399721366006463034, 1854433507143329687, 1615690021196437120, 5219680080869004672, -1721108849125336410, 8127796368984728057, -7589032628536525146, -7401317711463751431, -4400648712207994210, 851807248942218549, -140125917732862897, -9167580724541311122, 7220024109687371279, -5205608014952106729, -3129416362420749261, -3126671380320760108, -3214257854473367266, -8143478869874262376, -6221687404052731975, 4265868338535415547, 4346032629678357298, 6785704089778582068, 3889119495497490628, 2558726962210248161, -9051314990541756802, -3887636203506913236, -1462779784728073956, 2157178895705671015, 8111136409574197180, -1748837831618455369, -4670530418544797177, 7905908506465075524, 1639637481211682535, 8752830137453973432, 8945137239074830725, 6581257257863121029, 1968767884780639598, 6668298361200650466, -1337869160834700795, -5731440832563197737, -1034842767245497035, -2198901501741087915, 3135412614099984852, -6827433812085986256, 4667401042278741827, -1306564459577788437, 7595121400581827910, -4790479832313447769, -2503063219386542470, -30388494690585738, -7861010053518672652, -2434348234575132852, 3938641991456548457, -3201852905643610991, 3561819655089041117, 8510877059168939374, -8499934002472451296, 1843896166938383582, -838340307275895361, -3013892376822566600, -529297116256049461, -5627197886097703736, -7519634056861901794, -281893395555961490, 6871148199899401001, -8486260358895736191, 3660515391248478991, 1975493203247467953, -2653919487430334366, 8624082342340961757, 7557436778688991696, -1967586381705511057, -6629979785848659248, 4606550905812259105, 7238088871794764218, 4108805005926576057, 5137037661996954384, 1218914212800278117, -4234050566681294585, 77791399452116580, 5624956880029143295, 2597858965754831188, -3834375942466174904, -9021005083165308590, -2898158563293952426, 7758811080232979231, 4765772195157550093, -6018973634118036703, -6270665984389800078, 217049023504545800, 8204398730098114705, 4161463163437430515, -1905358261583366063, 3256077118826312264, 4980988119673324438, 580344433983975231, -9165759552221450031, -515877774093708274, -123719447976764187, -5951949843421138385, -7336227675879844225, -5187389823796077923, -5385110126300305610, -6008775542995214135, 5566263088616568116, -5734658601184110968, -3262163714198472576, -2187015620497723037, -8775690753073369935, -8051114423106501934, 4282313075612408881, 8720997085987893202, -4632852551235638397, -202060953792748347, 2523319161643092872, 7905469948469510258, -447299725203894290, -897239097088199990, 7818274544650968467, -4129915166482781176, -1453782672714185706, 6657836596668055478, 1496044715047244319, 2771340103379897316, -3152949302821123841, 3468081805113589347, -8972717724930802365, 8804943864614671337, -1214322677998794400, 3856569857023232208, 5161468675657225423, 2228074498996679895, 7906885169905488814, 4809260069403872464, -7187431590105043608, -3499841373715473423, -7060496622946564708, 1832356634094290833, -8728716982197980232, -8914485287064091767, 7623580344539631477, -3245553606930355296, -2933981496949342554, -5386434987998651116, 6471433859760122975, 1070081704124155030, -6811360529637972766, 4418880793957832267, 9206589883108017242, 8580749199609169212, 6390610739744557602, -5628782860728121975, -3063740383903902569, -4365494189251456783, 1525263284441468491, -3077674967791930799, -4905753192529986064, 208181938983853081, 809205132010227513, -8305151711571789794, 5074041563871044714, 4866261490724100285, -1702444501026128644, 3722125466682883961, -2079107309282325980, 7185824205512531024, 3839795355419227544, -4478753679015174503, -4933433119910259382, 2557868653888322624, 3334703155914392151, 8940292805823318990, 8709069541449648264, -5167091998612137796, -6204124291724322521, -104645207544357602, 7387989092489472460, -1564211669467866801, -3717024619389101402, -1619476598219914320, -2700754871645046949, -5710940418600633156, 2770108306606602192, -7483833873488360379, 2436514544872874392, -855789550501072684, 4538630145102149207, -8247144380940656537, 7909445315352700806, 9112770679834647721, 2279477766434776858, -1394437477786132010, 4528725206074491925, 6704105200166649024, 4274017444241487812, -2661991826284233807, -1573543920482092279, 7043841229526955940, -1415617724433563946, -866491304068263594, 8368485636359617313, 7863654388892657558, -7315480870348399516, 7303913319513357853, -6200160576049539595, 2941130879920697215, 9141429913302674459, -7958266551232485404, 4644115297522984093, 5524565164035944547, -5150936769028981005, 4721925530886429498, -6745949655574404659, 3382887199513954260, -402346303018654644, -2017006392050604043, -3658997058142558097, -2970770596428467001, 4055585808532828810, 7802304159061814034, -6863846166668031430, -7513866855033934603, 3043785606023658571, -5412573081828439289, 8237706136473315462, -6638637330897534851, -3004943408422871458, 1391830441458929784, -2533445740556429748, -1133844040219289314, 4648035271055946772, -2600905392101652032, -6257463396757613280, -91232871904802447, -5012288165101362675, 3170461608929072599, -8640947208505411612, 5126074296029541512, 7315686268281789326, -9209026102400327375, -5607833136694007316, -8267052932635313058, 8093722627221874605, 962339036300185734, 4269838584967603230, 1905463651478990545, -3039745489356674788]
> INFO [main] 2014-06-05 04:58:03,083 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-local@1943250237(84/84 serialized/live bytes, 6 ops)
> INFO [FlushWriter:1] 2014-06-05 04:58:03,083 Memtable.java (line 461) Writing Memtable-local@1943250237(84/84 serialized/live bytes, 6 ops)
> INFO [CompactionExecutor:1] 2014-06-05 04:58:03,087 CompactionTask.java (line 105) Compacting [SSTableReader(path='../../../rhq-data/data/rhq/raw_metrics/rhq-raw_metrics-ic-3-Data.db'), SSTableReader(path='../../../rhq-data/data/rhq/raw_metrics/rhq-raw_metrics-ic-2-Data.db'), SSTableReader(path='../../../rhq-data/data/rhq/raw_metrics/rhq-raw_metrics-ic-4-Data.db'), SSTableReader(path='../../../rhq-data/data/rhq/raw_metrics/rhq-raw_metrics-ic-1-Data.db')]
> INFO [FlushWriter:1] 2014-06-05 04:58:03,351 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/local/system-local-ic-4-Data.db (136 bytes) for commitlog position ReplayPosition(segmentId=1401958643566, position=112897)
> INFO [main] 2014-06-05 04:58:03,400 ColumnFamilyStore.java (line 629) Enqueuing flush of Memtable-local@251060720(10106/10106 serialized/live bytes, 257 ops)
> INFO [FlushWriter:1] 2014-06-05 04:58:03,401 Memtable.java (line 461) Writing Memtable-local@251060720(10106/10106 serialized/live bytes, 257 ops)
> INFO [FlushWriter:1] 2014-06-05 04:58:03,696 Memtable.java (line 495) Completed flushing ../../../rhq-data/data/system/local/system-local-ic-5-Data.db (11971 bytes) for commitlog position ReplayPosition(segmentId=1401958643566, position=124912)
> INFO [main] 2014-06-05 04:58:03,792 StorageService.java (line 1354) Node fbr-st2.bc.jonqe.lab.eng.bos.redhat.com/10.16.23.96 state jump to normal
> INFO [main] 2014-06-05 04:58:03,806 StorageService.java (line 807) Startup completed! Now serving reads.
> INFO [CompactionExecutor:1] 2014-06-05 04:58:03,847 CompactionTask.java (line 262) Compacted 4 sstables to [../../../rhq-data/data/rhq/raw_metrics/rhq-raw_metrics-ic-5,].  11,916 bytes to 11,779 (~98% of original) in 729ms = 0.015409MB/s.  489 total rows, 481 unique.  Row merge counts were {1:476, 2:2, 3:3, 4:0, }
> INFO [CompactionExecutor:1] 2014-06-05 04:58:03,859 CompactionTask.java (line 105) Compacting [SSTableReader(path='../../../rhq-data/data/rhq/metrics_cache/rhq-metrics_cache-ic-3-Data.db'), SSTableReader(path='../../../rhq-data/data/rhq/metrics_cache/rhq-metrics_cache-ic-1-Data.db'), SSTableReader(path='../../../rhq-data/data/rhq/metrics_cache/rhq-metrics_cache-ic-4-Data.db'), SSTableReader(path='../../../rhq-data/data/rhq/metrics_cache/rhq-metrics_cache-ic-2-Data.db')]
> INFO [main] 2014-06-05 04:58:04,077 Server.java (line 138) Starting listening for CQL clients on fbr-st2.bc.jonqe.lab.eng.bos.redhat.com/10.16.23.96:9142...
> INFO [main] 2014-06-05 04:58:04,131 CassandraDaemon.java (line 402) Not starting RPC server as requested. Use JMX (StorageService->startRPCServer()) or nodetool (enablethrift) to start it
> INFO [CompactionExecutor:1] 2014-06-05 04:58:04,352 CompactionTask.java (line 262) Compacted 4 sstables to [../../../rhq-data/data/rhq/metrics_cache/rhq-metrics_cache-ic-5,].  31,607 bytes to 19,176 (~60% of original) in 492ms = 0.037170MB/s.  128 total rows, 122 unique.  Row merge counts were {1:118, 2:2, 3:2, 4:0, }
> INFO [MemoryMeter:1] 2014-06-05 04:58:16,453 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='raw_metrics') liveRatio is 24.316901408450704 (just-counted was 24.316901408450704).  calculation took 88ms for 69 columns
> INFO [MemoryMeter:1] 2014-06-05 04:58:16,521 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='metrics_cache') liveRatio is 17.37037037037037 (just-counted was 17.37037037037037).  calculation took 66ms for 144 columns
> INFO [MemoryMeter:1] 2014-06-05 04:58:17,016 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='raw_metrics') liveRatio is 24.81782945736434 (just-counted was 24.81782945736434).  calculation took 22ms for 128 columns
> INFO [MemoryMeter:1] 2014-06-05 04:58:17,304 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='metrics_cache') liveRatio is 15.814185383952825 (just-counted was 14.258000397535282).  calculation took 46ms for 256 columns
> INFO [MemoryMeter:1] 2014-06-05 04:58:18,269 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='raw_metrics') liveRatio is 25.005859375 (just-counted was 25.005859375).  calculation took 16ms for 256 columns
> INFO [MemoryMeter:1] 2014-06-05 04:58:18,542 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='metrics_cache') liveRatio is 14.49286092890744 (just-counted was 13.171536473862055).  calculation took 17ms for 512 columns
> INFO [MemoryMeter:1] 2014-06-05 04:58:20,251 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='raw_metrics') liveRatio is 24.931846515352998 (just-counted was 24.857833655705996).  calculation took 32ms for 514 columns
> INFO [MemoryMeter:1] 2014-06-05 04:58:20,350 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='metrics_cache') liveRatio is 12.80490693943505 (just-counted was 11.11695294996266).  calculation took 26ms for 1026 columns
> INFO [MemoryMeter:1] 2014-06-05 04:59:58,066 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='one_hour_metrics') liveRatio is 12.825396825396826 (just-counted was 12.825396825396826).  calculation took 7ms for 72 columns
> INFO [MemoryMeter:1] 2014-06-05 04:59:58,210 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='metrics_cache_index') liveRatio is 2.677557081863746 (just-counted was 2.677557081863746).  calculation took 1ms for 100 columns
> INFO [MemoryMeter:1] 2014-06-05 04:59:58,276 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='one_hour_metrics') liveRatio is 12.036281179138323 (just-counted was 11.24716553287982).  calculation took 55ms for 136 columns
> INFO [MemoryMeter:1] 2014-06-05 04:59:58,710 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='one_hour_metrics') liveRatio is 11.857270248923541 (just-counted was 11.678259318708758).  calculation took 82ms for 260 columns
> INFO [MemoryMeter:1] 2014-06-05 04:59:58,721 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='metrics_cache_index') liveRatio is 2.558330146668088 (just-counted was 2.43910321147243).  calculation took 11ms for 182 columns
> INFO [MemoryMeter:1] 2014-06-05 04:59:59,759 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='one_hour_metrics') liveRatio is 11.650698904601775 (just-counted was 11.44412756028001).  calculation took 170ms for 531 columns
> INFO [MemoryMeter:1] 2014-06-05 04:59:59,770 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='metrics_cache_index') liveRatio is 2.6844782407645273 (just-counted was 2.6844782407645273).  calculation took 5ms for 355 columns
> INFO [MemoryMeter:1] 2014-06-05 05:00:01,871 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='one_hour_metrics') liveRatio is 11.09322957732387 (just-counted was 10.535760250045964).  calculation took 728ms for 1144 columns
> INFO [MemoryMeter:1] 2014-06-05 05:00:01,912 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='metrics_cache_index') liveRatio is 2.907967824463549 (just-counted was 2.907967824463549).  calculation took 41ms for 784 columns
> INFO [MemoryMeter:1] 2014-06-05 05:00:02,613 Memtable.java (line 238) CFS(Keyspace='rhq', ColumnFamily='metrics_cache') liveRatio is 10.352139954570589 (just-counted was 7.899372969706127).  calculation took 682ms for 2946 columns
> INFO [CompactionExecutor:2] 2014-06-05 05:02:23,748 CompactionTask.java (line 105) Compacting [SSTableReader(path='../../../rhq-data/data/system/local/system-local-ic-5-Data.db'), SSTableReader(path='../../../rhq-data/data/system/local/system-local-ic-3-Data.db'), SSTableReader(path='../../../rhq-data/data/system/local/system-local-ic-1-Data.db'), SSTableReader(path='../../../rhq-data/data/system/local/system-local-ic-2-Data.db'), SSTableReader(path='../../../rhq-data/data/system/local/system-local-ic-4-Data.db')]
> INFO [CompactionExecutor:2] 2014-06-05 05:02:23,944 CompactionTask.java (line 262) Compacted 5 sstables to [../../../rhq-data/data/system/local/system-local-ic-6,].  24,724 bytes to 12,386 (~50% of original) in 192ms = 0.061522MB/s.  5 total rows, 1 unique.  Row merge counts were {1:0, 2:0, 3:0, 4:0, 5:1, }

Actions: View

Attachments on bug 1105032: 902462 | 902463 | 902464