Mailing-List: contact commits-help@cassandra.apache.org; run by ezmlm
Precedence: bulk
Reply-To: dev@cassandra.apache.org
Date: Mon, 9 Jun 2014 20:13:03 +0000 (UTC)
From: "Jonathan Ellis (JIRA)" <jira@apache.org>
To: commits@cassandra.apache.org
Message-ID: <JIRA.12707271.1396994200774.96946.1402344783735@arcas>
In-Reply-To: <JIRA.12707271.1396994200774@arcas>
References: <JIRA.12707271.1396994200774@arcas>
Subject: [jira] [Updated] (CASSANDRA-7011) auth_test
 system_auth_ks_is_alterable_test dtest hangs in 2.1 and 2.0
MIME-Version: 1.0
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: quoted-printable


     [ https://issues.apache.org/jira/browse/CASSANDRA-7011?page=3Dcom.atla=
ssian.jira.plugin.system.issuetabpanels:all-tabpanel ]

Jonathan Ellis updated CASSANDRA-7011:
--------------------------------------

    Fix Version/s:     (was: 2.1.0)
                   2.0.9

> auth_test system_auth_ks_is_alterable_test dtest hangs in 2.1 and 2.0
> ---------------------------------------------------------------------
>
>                 Key: CASSANDRA-7011
>                 URL: https://issues.apache.org/jira/browse/CASSANDRA-7011
>             Project: Cassandra
>          Issue Type: Test
>          Components: Tests
>            Reporter: Michael Shuler
>            Assignee: Michael Shuler
>             Fix For: 2.0.9
>
>
> This test hangs forever. When I hit ctl-c after running the test, then th=
e ccm nodes actually continue running - I think ccm is looking for log line=
s that never occur until the test is killed(?).
> {noformat}
> $ export MAX_HEAP_SIZE=3D"1G"; export HEAP_NEWSIZE=3D"256M"; ENABLE_VNODE=
S=3Dtrue PRINT_DEBUG=3Dtrue nosetests --nocapture --nologcapture --verbosit=
y=3D3 auth_test.py:TestAuth.system_auth_ks_is_alterable_test
> nose.config: INFO: Ignoring files matching ['^\\.', '^_', '^setup\\.py$']
> system_auth_ks_is_alterable_test (auth_test.TestAuth) ... cluster ccm dir=
ectory: /tmp/dtest-O3AAJr
> ^C
> {noformat}
> Search for (hanging here) below - I typed this prior to hitting ctl-c. Th=
en the nodes start running again and I see "Listening for thrift clients" l=
ater on.
> {noformat}
> mshuler@hana:~$ tail -f /tmp/dtest-O3AAJr/test/node*/logs/system.log
> =3D=3D> /tmp/dtest-O3AAJr/test/node1/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:2] 2014-04-08 16:45:12,599 Memtable.java:344 -=
 Writing Memtable-schema_columnfamilies@1792243696(1627 serialized bytes, 2=
7 ops, 0%/0% of on/off-heap limit)
> INFO  [CompactionExecutor:2] 2014-04-08 16:45:12,603 CompactionTask.java:=
287 - Compacted 4 sstables to [/tmp/dtest-O3AAJr/test/node1/data/system/sch=
ema_columns-296e9c049bec3085827dc17d3df2122a/system-schema_columns-ka-13,].=
  14,454 bytes to 11,603 (~80% of original) in 105ms =3D 0.105386MB/s.  7 t=
otal partitions merged to 3.  Partition merge counts were {1:1, 2:1, 4:1, }
> INFO  [MemtableFlushWriter:2] 2014-04-08 16:45:12,668 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node1/flush/system/schema_column=
families-45f5b36024bc3f83a3631034ea4fa697/system-schema_columnfamilies-ka-1=
4-Data.db (956 bytes) for commitlog position ReplayPosition(segmentId=3D139=
6993504671, position=3D193292)
> INFO  [MigrationStage:1] 2014-04-08 16:45:12,669 ColumnFamilyStore.java:8=
53 - Enqueuing flush of schema_columns: 6806 (0%) on-heap, 0 (0%) off-heap
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:45:12,670 Memtable.java:344 -=
 Writing Memtable-schema_columns@352928691(1014 serialized bytes, 21 ops, 0=
%/0% of on/off-heap limit)
> INFO  [CompactionExecutor:1] 2014-04-08 16:45:12,672 CompactionTask.java:=
287 - Compacted 4 sstables to [/tmp/dtest-O3AAJr/test/node1/data/system/sch=
ema_keyspaces-b0f2235744583cdb9631c43e59ce3676/system-schema_keyspaces-ka-1=
7,].  710 bytes to 233 (~32% of original) in 70ms =3D 0.003174MB/s.  6 tota=
l partitions merged to 3.  Partition merge counts were {1:2, 4:1, }
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:45:12,721 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node1/flush/system/schema_column=
s-296e9c049bec3085827dc17d3df2122a/system-schema_columns-ka-14-Data.db (435=
 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504671, po=
sition=3D193830)
> WARN  [NonPeriodicTasks:1] 2014-04-08 16:45:20,566 FBUtilities.java:359 -=
 Trigger directory doesn't exist, please create it and try again.
> INFO  [NonPeriodicTasks:1] 2014-04-08 16:45:20,570 PasswordAuthenticator.=
java:220 - PasswordAuthenticator created default user 'cassandra'
> INFO  [NonPeriodicTasks:1] 2014-04-08 16:45:21,806 Auth.java:232 - Create=
d default superuser 'cassandra'
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [InternalResponseStage:4] 2014-04-08 16:45:12,214 ColumnFamilyStore=
.java:853 - Enqueuing flush of schema_keyspaces: 1004 (0%) on-heap, 0 (0%) =
off-heap
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:45:12,215 Memtable.java:344 -=
 Writing Memtable-schema_keyspaces@781373873(276 serialized bytes, 6 ops, 0=
%/0% of on/off-heap limit)
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:45:12,295 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node2/flush/system/schema_keyspa=
ces-b0f2235744583cdb9631c43e59ce3676/system-schema_keyspaces-ka-15-Data.db =
(179 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504760=
, position=3D243552)
> INFO  [InternalResponseStage:4] 2014-04-08 16:45:12,296 ColumnFamilyStore=
.java:853 - Enqueuing flush of schema_columnfamilies: 34190 (0%) on-heap, 0=
 (0%) off-heap
> INFO  [MemtableFlushWriter:2] 2014-04-08 16:45:12,297 Memtable.java:344 -=
 Writing Memtable-schema_columnfamilies@2077216447(5746 serialized bytes, 1=
08 ops, 0%/0% of on/off-heap limit)
> INFO  [MemtableFlushWriter:2] 2014-04-08 16:45:12,369 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node2/flush/system/schema_column=
families-45f5b36024bc3f83a3631034ea4fa697/system-schema_columnfamilies-ka-1=
2-Data.db (2088 bytes) for commitlog position ReplayPosition(segmentId=3D13=
96993504760, position=3D243552)
> INFO  [InternalResponseStage:4] 2014-04-08 16:45:12,370 ColumnFamilyStore=
.java:853 - Enqueuing flush of schema_columns: 37408 (0%) on-heap, 0 (0%) o=
ff-heap
> INFO  [CompactionExecutor:4] 2014-04-08 16:45:12,371 CompactionTask.java:=
131 - Compacting [SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node2/data/s=
ystem/schema_columnfamilies-45f5b36024bc3f83a3631034ea4fa697/system-schema_=
columnfamilies-ka-9-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/test=
/node2/flush/system/schema_columnfamilies-45f5b36024bc3f83a3631034ea4fa697/=
system-schema_columnfamilies-ka-11-Data.db'), SSTableReader(path=3D'/tmp/dt=
est-O3AAJr/test/node2/flush/system/schema_columnfamilies-45f5b36024bc3f83a3=
631034ea4fa697/system-schema_columnfamilies-ka-10-Data.db'), SSTableReader(=
path=3D'/tmp/dtest-O3AAJr/test/node2/flush/system/schema_columnfamilies-45f=
5b36024bc3f83a3631034ea4fa697/system-schema_columnfamilies-ka-12-Data.db')]
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:45:12,371 Memtable.java:344 -=
 Writing Memtable-schema_columns@2003573271(5173 serialized bytes, 119 ops,=
 0%/0% of on/off-heap limit)
> WARN  [NonPeriodicTasks:1] 2014-04-08 16:45:20,248 FBUtilities.java:359 -=
 Trigger directory doesn't exist, please create it and try again.
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:45:11,654 Memtable.java:344 -=
 Writing Memtable-schema_keyspaces@789549279(276 serialized bytes, 6 ops, 0=
%/0% of on/off-heap limit)
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:45:11,788 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node3/flush/system/schema_keyspa=
ces-b0f2235744583cdb9631c43e59ce3676/system-schema_keyspaces-ka-14-Data.db =
(179 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504533=
, position=3D193958)
> INFO  [InternalResponseStage:1] 2014-04-08 16:45:11,789 ColumnFamilyStore=
.java:853 - Enqueuing flush of schema_columnfamilies: 34192 (0%) on-heap, 0=
 (0%) off-heap
> INFO  [MemtableFlushWriter:2] 2014-04-08 16:45:11,790 Memtable.java:344 -=
 Writing Memtable-schema_columnfamilies@1695849916(5746 serialized bytes, 1=
08 ops, 0%/0% of on/off-heap limit)
> INFO  [MemtableFlushWriter:2] 2014-04-08 16:45:11,898 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node3/flush/system/schema_column=
families-45f5b36024bc3f83a3631034ea4fa697/system-schema_columnfamilies-ka-1=
1-Data.db (2087 bytes) for commitlog position ReplayPosition(segmentId=3D13=
96993504533, position=3D194091)
> INFO  [InternalResponseStage:1] 2014-04-08 16:45:11,899 ColumnFamilyStore=
.java:853 - Enqueuing flush of schema_columns: 37410 (0%) on-heap, 0 (0%) o=
ff-heap
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:45:11,900 Memtable.java:344 -=
 Writing Memtable-schema_columns@2090391222(5173 serialized bytes, 119 ops,=
 0%/0% of on/off-heap limit)
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:45:11,998 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node3/flush/system/schema_column=
s-296e9c049bec3085827dc17d3df2122a/system-schema_columns-ka-11-Data.db (170=
0 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504533, p=
osition=3D194091)
> INFO  [main] 2014-04-08 16:45:18,654 CassandraDaemon.java:533 - No gossip=
 backlog; proceeding
> WARN  [NonPeriodicTasks:1] 2014-04-08 16:45:20,131 FBUtilities.java:359 -=
 Trigger directory doesn't exist, please create it and try again.
> (hanging here)
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [NonPeriodicTasks:1] 2014-04-08 16:49:02,863 PasswordAuthenticator.=
java:220 - PasswordAuthenticator created default user 'cassandra'
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [NonPeriodicTasks:1] 2014-04-08 16:49:02,888 PasswordAuthenticator.=
java:220 - PasswordAuthenticator created default user 'cassandra'
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:02,919 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node2/flush/system/schema_column=
s-296e9c049bec3085827dc17d3df2122a/system-schema_columns-ka-12-Data.db (169=
9 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504760, p=
osition=3D243552)
> INFO  [CompactionExecutor:5] 2014-04-08 16:49:02,922 CompactionTask.java:=
131 - Compacting [SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node2/data/s=
ystem/schema_columns-296e9c049bec3085827dc17d3df2122a/system-schema_columns=
-ka-9-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node2/flush/s=
ystem/schema_columns-296e9c049bec3085827dc17d3df2122a/system-schema_columns=
-ka-11-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node2/flush/=
system/schema_columns-296e9c049bec3085827dc17d3df2122a/system-schema_column=
s-ka-10-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node2/flush=
/system/schema_columns-296e9c049bec3085827dc17d3df2122a/system-schema_colum=
ns-ka-12-Data.db')]
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [InternalResponseStage:3] 2014-04-08 16:49:02,959 ColumnFamilyStore=
.java:853 - Enqueuing flush of schema_keyspaces: 1006 (0%) on-heap, 0 (0%) =
off-heap
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:02,960 Memtable.java:344 -=
 Writing Memtable-schema_keyspaces@44265998(276 serialized bytes, 6 ops, 0%=
/0% of on/off-heap limit)
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [MigrationStage:1] 2014-04-08 16:49:02,970 ColumnFamilyStore.java:8=
53 - Enqueuing flush of schema_keyspaces: 501 (0%) on-heap, 0 (0%) off-heap
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:02,972 Memtable.java:344 -=
 Writing Memtable-schema_keyspaces@603519674(138 serialized bytes, 3 ops, 0=
%/0% of on/off-heap limit)
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [main] 2014-04-08 16:49:03,029 Server.java:159 - Starting listening=
 for CQL clients on /127.0.0.3:9042...
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [CompactionExecutor:4] 2014-04-08 16:49:03,064 CompactionTask.java:=
287 - Compacted 4 sstables to [/tmp/dtest-O3AAJr/test/node2/data/system/sch=
ema_columnfamilies-45f5b36024bc3f83a3631034ea4fa697/system-schema_columnfam=
ilies-ka-13,].  13,907 bytes to 7,643 (~54% of original) in 213ms =3D 0.034=
220MB/s.  9 total partitions merged to 3.  Partition merge counts were {1:1=
, 4:2, }
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,069 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node3/flush/system/schema_keyspa=
ces-b0f2235744583cdb9631c43e59ce3676/system-schema_keyspaces-ka-15-Data.db =
(179 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504533=
, position=3D213470)
> INFO  [InternalResponseStage:3] 2014-04-08 16:49:03,071 ColumnFamilyStore=
.java:853 - Enqueuing flush of schema_columnfamilies: 42850 (0%) on-heap, 0=
 (0%) off-heap
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,071 Memtable.java:344 -=
 Writing Memtable-schema_columnfamilies@666219518(7373 serialized bytes, 13=
5 ops, 0%/0% of on/off-heap limit)
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,087 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node2/flush/system/schema_keyspa=
ces-b0f2235744583cdb9631c43e59ce3676/system-schema_keyspaces-ka-16-Data.db =
(159 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504760=
, position=3D247475)
> INFO  [CompactionExecutor:5] 2014-04-08 16:49:03,090 CompactionTask.java:=
287 - Compacted 4 sstables to [/tmp/dtest-O3AAJr/test/node2/data/system/sch=
ema_columns-296e9c049bec3085827dc17d3df2122a/system-schema_columns-ka-13,].=
  16,411 bytes to 11,314 (~68% of original) in 165ms =3D 0.065393MB/s.  9 t=
otal partitions merged to 3.  Partition merge counts were {1:1, 4:2, }
> INFO  [CompactionExecutor:6] 2014-04-08 16:49:03,090 CompactionTask.java:=
131 - Compacting [SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node2/flush/=
system/schema_keyspaces-b0f2235744583cdb9631c43e59ce3676/system-schema_keys=
paces-ka-14-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node2/d=
ata/system/schema_keyspaces-b0f2235744583cdb9631c43e59ce3676/system-schema_=
keyspaces-ka-13-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/nod=
e2/flush/system/schema_keyspaces-b0f2235744583cdb9631c43e59ce3676/system-sc=
hema_keyspaces-ka-15-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/tes=
t/node2/flush/system/schema_keyspaces-b0f2235744583cdb9631c43e59ce3676/syst=
em-schema_keyspaces-ka-16-Data.db')]
> INFO  [MigrationStage:1] 2014-04-08 16:49:03,091 ColumnFamilyStore.java:8=
53 - Enqueuing flush of schema_columnfamilies: 8799 (0%) on-heap, 0 (0%) of=
f-heap
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [main] 2014-04-08 16:49:03,091 ThriftServer.java:119 - Binding thri=
ft service to /127.0.0.3:9160
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,091 Memtable.java:344 -=
 Writing Memtable-schema_columnfamilies@1821762369(1609 serialized bytes, 2=
7 ops, 0%/0% of on/off-heap limit)
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [Thread-8] 2014-04-08 16:49:03,098 ThriftServer.java:136 - Listenin=
g for thrift clients...
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,166 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node3/flush/system/schema_column=
families-45f5b36024bc3f83a3631034ea4fa697/system-schema_columnfamilies-ka-1=
2-Data.db (2536 bytes) for commitlog position ReplayPosition(segmentId=3D13=
96993504533, position=3D213470)
> INFO  [InternalResponseStage:3] 2014-04-08 16:49:03,167 ColumnFamilyStore=
.java:853 - Enqueuing flush of schema_columns: 44033 (0%) on-heap, 0 (0%) o=
ff-heap
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,168 Memtable.java:344 -=
 Writing Memtable-schema_columns@1052245443(6187 serialized bytes, 140 ops,=
 0%/0% of on/off-heap limit)
> INFO  [CompactionExecutor:8] 2014-04-08 16:49:03,168 CompactionTask.java:=
131 - Compacting [SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node3/flush/=
system/schema_columnfamilies-45f5b36024bc3f83a3631034ea4fa697/system-schema=
_columnfamilies-ka-11-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/te=
st/node3/flush/system/schema_columnfamilies-45f5b36024bc3f83a3631034ea4fa69=
7/system-schema_columnfamilies-ka-10-Data.db'), SSTableReader(path=3D'/tmp/=
dtest-O3AAJr/test/node3/flush/system/schema_columnfamilies-45f5b36024bc3f83=
a3631034ea4fa697/system-schema_columnfamilies-ka-12-Data.db'), SSTableReade=
r(path=3D'/tmp/dtest-O3AAJr/test/node3/data/system/schema_columnfamilies-45=
f5b36024bc3f83a3631034ea4fa697/system-schema_columnfamilies-ka-9-Data.db')]
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,203 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node2/flush/system/schema_column=
families-45f5b36024bc3f83a3631034ea4fa697/system-schema_columnfamilies-ka-1=
4-Data.db (949 bytes) for commitlog position ReplayPosition(segmentId=3D139=
6993504760, position=3D248454)
> INFO  [MigrationStage:1] 2014-04-08 16:49:03,204 ColumnFamilyStore.java:8=
53 - Enqueuing flush of schema_columns: 6833 (0%) on-heap, 0 (0%) off-heap
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,205 Memtable.java:344 -=
 Writing Memtable-schema_columns@1698728310(1041 serialized bytes, 21 ops, =
0%/0% of on/off-heap limit)
> INFO  [CompactionExecutor:6] 2014-04-08 16:49:03,218 CompactionTask.java:=
287 - Compacted 4 sstables to [/tmp/dtest-O3AAJr/test/node2/data/system/sch=
ema_keyspaces-b0f2235744583cdb9631c43e59ce3676/system-schema_keyspaces-ka-1=
7,].  750 bytes to 233 (~31% of original) in 125ms =3D 0.001778MB/s.  8 tot=
al partitions merged to 3.  Partition merge counts were {1:1, 3:1, 4:1, }
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,278 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node3/flush/system/schema_column=
s-296e9c049bec3085827dc17d3df2122a/system-schema_columns-ka-12-Data.db (199=
0 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504533, p=
osition=3D213470)
> INFO  [CompactionExecutor:5] 2014-04-08 16:49:03,279 CompactionTask.java:=
131 - Compacting [SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node3/flush/=
system/schema_columns-296e9c049bec3085827dc17d3df2122a/system-schema_column=
s-ka-10-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node3/flush=
/system/schema_columns-296e9c049bec3085827dc17d3df2122a/system-schema_colum=
ns-ka-11-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node3/data=
/system/schema_columns-296e9c049bec3085827dc17d3df2122a/system-schema_colum=
ns-ka-9-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node3/flush=
/system/schema_columns-296e9c049bec3085827dc17d3df2122a/system-schema_colum=
ns-ka-12-Data.db')]
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,310 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node2/flush/system/schema_column=
s-296e9c049bec3085827dc17d3df2122a/system-schema_columns-ka-14-Data.db (431=
 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504760, po=
sition=3D248454)
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [CompactionExecutor:8] 2014-04-08 16:49:03,313 CompactionTask.java:=
287 - Compacted 4 sstables to [/tmp/dtest-O3AAJr/test/node3/data/system/sch=
ema_columnfamilies-45f5b36024bc3f83a3631034ea4fa697/system-schema_columnfam=
ilies-ka-13,].  14,821 bytes to 8,115 (~54% of original) in 140ms =3D 0.055=
279MB/s.  9 total partitions merged to 3.  Partition merge counts were {1:1=
, 4:2, }
> INFO  [MigrationStage:1] 2014-04-08 16:49:03,313 ColumnFamilyStore.java:8=
53 - Enqueuing flush of schema_keyspaces: 502 (0%) on-heap, 0 (0%) off-heap
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,315 Memtable.java:344 -=
 Writing Memtable-schema_keyspaces@901379409(138 serialized bytes, 3 ops, 0=
%/0% of on/off-heap limit)
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [MigrationStage:1] 2014-04-08 16:49:03,332 ColumnFamilyStore.java:8=
53 - Enqueuing flush of schema_keyspaces: 501 (0%) on-heap, 0 (0%) off-heap
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,333 Memtable.java:344 -=
 Writing Memtable-schema_keyspaces@1181313624(138 serialized bytes, 3 ops, =
0%/0% of on/off-heap limit)
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,392 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node3/flush/system/schema_keyspa=
ces-b0f2235744583cdb9631c43e59ce3676/system-schema_keyspaces-ka-16-Data.db =
(159 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504533=
, position=3D217732)
> INFO  [MigrationStage:1] 2014-04-08 16:49:03,397 ColumnFamilyStore.java:8=
53 - Enqueuing flush of schema_columnfamilies: 8818 (0%) on-heap, 0 (0%) of=
f-heap
> INFO  [CompactionExecutor:7] 2014-04-08 16:49:03,397 CompactionTask.java:=
131 - Compacting [SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node3/flush/=
system/schema_keyspaces-b0f2235744583cdb9631c43e59ce3676/system-schema_keys=
paces-ka-14-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/node3/d=
ata/system/schema_keyspaces-b0f2235744583cdb9631c43e59ce3676/system-schema_=
keyspaces-ka-13-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/test/nod=
e3/flush/system/schema_keyspaces-b0f2235744583cdb9631c43e59ce3676/system-sc=
hema_keyspaces-ka-15-Data.db'), SSTableReader(path=3D'/tmp/dtest-O3AAJr/tes=
t/node3/flush/system/schema_keyspaces-b0f2235744583cdb9631c43e59ce3676/syst=
em-schema_keyspaces-ka-16-Data.db')]
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,400 Memtable.java:344 -=
 Writing Memtable-schema_columnfamilies@2077765501(1627 serialized bytes, 2=
7 ops, 0%/0% of on/off-heap limit)
> INFO  [CompactionExecutor:5] 2014-04-08 16:49:03,405 CompactionTask.java:=
287 - Compacted 4 sstables to [/tmp/dtest-O3AAJr/test/node3/data/system/sch=
ema_columns-296e9c049bec3085827dc17d3df2122a/system-schema_columns-ka-13,].=
  16,987 bytes to 11,600 (~68% of original) in 123ms =3D 0.089940MB/s.  9 t=
otal partitions merged to 3.  Partition merge counts were {1:1, 4:2, }
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,418 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node2/flush/system/schema_keyspa=
ces-b0f2235744583cdb9631c43e59ce3676/system-schema_keyspaces-ka-18-Data.db =
(159 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504760=
, position=3D252763)
> INFO  [MigrationStage:1] 2014-04-08 16:49:03,419 ColumnFamilyStore.java:8=
53 - Enqueuing flush of schema_columnfamilies: 8817 (0%) on-heap, 0 (0%) of=
f-heap
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,420 Memtable.java:344 -=
 Writing Memtable-schema_columnfamilies@495883972(1627 serialized bytes, 27=
 ops, 0%/0% of on/off-heap limit)
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,483 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node3/flush/system/schema_column=
families-45f5b36024bc3f83a3631034ea4fa697/system-schema_columnfamilies-ka-1=
4-Data.db (956 bytes) for commitlog position ReplayPosition(segmentId=3D139=
6993504533, position=3D217732)
> INFO  [MigrationStage:1] 2014-04-08 16:49:03,484 ColumnFamilyStore.java:8=
53 - Enqueuing flush of schema_columns: 6807 (0%) on-heap, 0 (0%) off-heap
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,485 Memtable.java:344 -=
 Writing Memtable-schema_columns@365627698(1014 serialized bytes, 21 ops, 0=
%/0% of on/off-heap limit)
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,519 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node2/flush/system/schema_column=
families-45f5b36024bc3f83a3631034ea4fa697/system-schema_columnfamilies-ka-1=
5-Data.db (956 bytes) for commitlog position ReplayPosition(segmentId=3D139=
6993504760, position=3D252896)
> INFO  [MigrationStage:1] 2014-04-08 16:49:03,520 ColumnFamilyStore.java:8=
53 - Enqueuing flush of schema_columns: 6806 (0%) on-heap, 0 (0%) off-heap
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,521 Memtable.java:344 -=
 Writing Memtable-schema_columns@1830789468(1014 serialized bytes, 21 ops, =
0%/0% of on/off-heap limit)
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [CompactionExecutor:7] 2014-04-08 16:49:03,523 CompactionTask.java:=
287 - Compacted 4 sstables to [/tmp/dtest-O3AAJr/test/node3/data/system/sch=
ema_keyspaces-b0f2235744583cdb9631c43e59ce3676/system-schema_keyspaces-ka-1=
7,].  750 bytes to 233 (~31% of original) in 122ms =3D 0.001821MB/s.  8 tot=
al partitions merged to 3.  Partition merge counts were {1:1, 3:1, 4:1, }
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,562 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node3/flush/system/schema_column=
s-296e9c049bec3085827dc17d3df2122a/system-schema_columns-ka-14-Data.db (435=
 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504533, po=
sition=3D218351)
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,581 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node2/flush/system/schema_column=
s-296e9c049bec3085827dc17d3df2122a/system-schema_columns-ka-15-Data.db (435=
 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504760, po=
sition=3D252896)
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [MigrationStage:1] 2014-04-08 16:49:03,586 ColumnFamilyStore.java:8=
53 - Enqueuing flush of schema_keyspaces: 502 (0%) on-heap, 0 (0%) off-heap
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,586 Memtable.java:344 -=
 Writing Memtable-schema_keyspaces@543512535(138 serialized bytes, 3 ops, 0=
%/0% of on/off-heap limit)
> =3D=3D> /tmp/dtest-O3AAJr/test/node2/logs/system.log <=3D=3D
> INFO  [MigrationStage:1] 2014-04-08 16:49:03,604 DefsTables.java:388 - Lo=
ading org.apache.cassandra.config.CFMetaData@1327e4ff[cfId=3D2d324e48-3275-=
3517-8dd5-9a2c5b0856c5,ksName=3Dsystem_auth,cfName=3Dpermissions,cfType=3DS=
tandard,comparator=3Dorg.apache.cassandra.db.marshal.CompositeType(org.apac=
he.cassandra.db.marshal.UTF8Type,org.apache.cassandra.db.marshal.UTF8Type,o=
rg.apache.cassandra.db.marshal.ColumnToCollectionType(7065726d697373696f6e7=
3:org.apache.cassandra.db.marshal.SetType(org.apache.cassandra.db.marshal.U=
TF8Type))),comment=3D,readRepairChance=3D0.1,dclocalReadRepairChance=3D0.0,=
gcGraceSeconds=3D7776000,defaultValidator=3Dorg.apache.cassandra.db.marshal=
.BytesType,keyValidator=3Dorg.apache.cassandra.db.marshal.UTF8Type,minCompa=
ctionThreshold=3D4,maxCompactionThreshold=3D32,columnMetadata=3D{java.nio.H=
eapByteBuffer[pos=3D0 lim=3D11 cap=3D11]=3DColumnDefinition{name=3Dpermissi=
ons, type=3Dorg.apache.cassandra.db.marshal.SetType(org.apache.cassandra.db=
.marshal.UTF8Type), kind=3DREGULAR, componentIndex=3D1, indexName=3Dnull, i=
ndexType=3Dnull}, java.nio.HeapByteBuffer[pos=3D0 lim=3D8 cap=3D8]=3DColumn=
Definition{name=3Dusername, type=3Dorg.apache.cassandra.db.marshal.UTF8Type=
, kind=3DPARTITION_KEY, componentIndex=3Dnull, indexName=3Dnull, indexType=
=3Dnull}, java.nio.HeapByteBuffer[pos=3D0 lim=3D8 cap=3D8]=3DColumnDefiniti=
on{name=3Dresource, type=3Dorg.apache.cassandra.db.marshal.UTF8Type, kind=
=3DCLUSTERING_COLUMN, componentIndex=3D0, indexName=3Dnull, indexType=3Dnul=
l}},compactionStrategyClass=3Dclass org.apache.cassandra.db.compaction.Size=
TieredCompactionStrategy,compactionStrategyOptions=3D{},compressionParamete=
rs=3D{sstable_compression=3Dorg.apache.cassandra.io.compress.LZ4Compressor}=
,bloomFilterFpChance=3D0.01,memtableFlushPeriod=3D0,caching=3D{"keys":"ALL"=
, "rows_per_partition":"NONE"},defaultTimeToLive=3D0,minIndexInterval=3D128=
,maxIndexInterval=3D2048,speculativeRetry=3D99.0PERCENTILE,populateIoCacheO=
nFlush=3Dfalse,droppedColumns=3D{},triggers=3D{}]
> INFO  [MigrationStage:1] 2014-04-08 16:49:03,610 ColumnFamilyStore.java:2=
83 - Initializing system_auth.permissions
> INFO  [main] 2014-04-08 16:49:03,622 CassandraDaemon.java:501 - Waiting f=
or gossip to settle before accepting client requests...
> INFO  [InternalResponseStage:5] 2014-04-08 16:49:03,625 ColumnFamilyStore=
.java:853 - Enqueuing flush of schema_keyspaces: 1004 (0%) on-heap, 0 (0%) =
off-heap
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,626 Memtable.java:344 -=
 Writing Memtable-schema_keyspaces@2064761560(276 serialized bytes, 6 ops, =
0%/0% of on/off-heap limit)
> =3D=3D> /tmp/dtest-O3AAJr/test/node3/logs/system.log <=3D=3D
> INFO  [MemtableFlushWriter:3] 2014-04-08 16:49:03,664 Memtable.java:378 -=
 Completed flushing /tmp/dtest-O3AAJr/test/node3/flush/system/schema_keyspa=
ces-b0f2235744583cdb9631c43e59ce3676/system-schema_keyspaces-ka-18-Data.db =
(159 bytes) for commitlog position ReplayPosition(segmentId=3D1396993504533=
, position=3D222660)
> INFO  [MigrationStage:1] 2014-04-08 16:49:03,665 ColumnFamilyStore.java:8=
53 - Enqueuing flush of schema_columnfamilies: 8818 (0%) on-heap, 0 (0%) of=
f-heap
> INFO  [MemtableFlushWriter:1] 2014-04-08 16:49:03,666 Memtable.java:344 -=
 Writing Memtable-schema_columnfamilies@1464552123(1627 serialized bytes, 2=
7 ops, 0%/0% of on/off-heap limit)
> <...>
> {noformat}


--
This message was sent by Atlassian JIRA
(v6.2#6252)