From user-return-29215-apmail-cassandra-user-archive=cassandra.apache.org@cassandra.apache.org Mon Oct 1 01:12:58 2012 Return-Path: X-Original-To: apmail-cassandra-user-archive@www.apache.org Delivered-To: apmail-cassandra-user-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 4AA7FD319 for ; Mon, 1 Oct 2012 01:12:58 +0000 (UTC) Received: (qmail 92021 invoked by uid 500); 1 Oct 2012 01:12:55 -0000 Delivered-To: apmail-cassandra-user-archive@cassandra.apache.org Received: (qmail 91995 invoked by uid 500); 1 Oct 2012 01:12:55 -0000 Mailing-List: contact user-help@cassandra.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: user@cassandra.apache.org Delivered-To: mailing list user@cassandra.apache.org Received: (qmail 91987 invoked by uid 99); 1 Oct 2012 01:12:55 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 01 Oct 2012 01:12:55 +0000 X-ASF-Spam-Status: No, hits=2.2 required=5.0 tests=HTML_MESSAGE,RCVD_IN_DNSWL_NONE,SPF_PASS X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: local policy) Received: from [208.113.200.5] (HELO homiemail-a44.g.dreamhost.com) (208.113.200.5) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 01 Oct 2012 01:12:48 +0000 Received: from homiemail-a44.g.dreamhost.com (localhost [127.0.0.1]) by homiemail-a44.g.dreamhost.com (Postfix) with ESMTP id 5E7FC118060 for ; Sun, 30 Sep 2012 18:12:28 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha1; c=relaxed; d=thelastpickle.com; h=from :content-type:message-id:mime-version:subject:date:references:to :in-reply-to; s=thelastpickle.com; bh=aBrxYVb90vnYVIgQTa1yzqddDf U=; b=g3m4RgJx/Ppq8xqyfJqugcYJ1vexsfGWcD2Obh36iQ9X3eQtk1Z0dsfRrt LzwJufsbeDq8FJYy3QRy4yhkPwA8rZX7b/D/S9z38fYjdItKYk877VQYOx1dYkGi FKX8Q8u12r+Kbg5qhOvsz/5/LAFjTDNFTN9BpnuDjjh53BAWg= Received: from [192.168.2.77] (unknown [116.90.132.105]) (using TLSv1 with cipher AES128-SHA (128/128 bits)) (No client certificate requested) (Authenticated sender: aaron@thelastpickle.com) by homiemail-a44.g.dreamhost.com (Postfix) with ESMTPSA id B1B9E11805C for ; Sun, 30 Sep 2012 18:12:27 -0700 (PDT) From: aaron morton Content-Type: multipart/alternative; boundary="Apple-Mail=_B1BB3927-957A-4471-A429-9A245C0B4E4D" Message-Id: Mime-Version: 1.0 (Mac OS X Mail 6.1 \(1498\)) Subject: Re: any ideas on what these mean Date: Mon, 1 Oct 2012 14:12:26 +1300 References: To: user@cassandra.apache.org In-Reply-To: X-Mailer: Apple Mail (2.1498) --Apple-Mail=_B1BB3927-957A-4471-A429-9A245C0B4E4D Content-Transfer-Encoding: quoted-printable Content-Type: text/plain; charset=windows-1252 > ERROR [MigrationStage:1] 2012-09-26 09:51:03,128 = AbstractCassandraDaemon.java (line 134) Exception in thread = Thread[MigrationStage:1,5,main] I *think* this is due to concurrent schema modifications.=20 The found number is the one in the database for the matching KS / CF = name, the second number is the one in memory at the time. That's why the = reboot fixed it. If it happens again try nodetool resetlocalschema =20 > 2. Is it affecting my data put? (I have seen other weird validation = exceptions where my data is still put and I can read it from cassandra = and I get no exception client side) This write was dropped > ERROR [MutationStage:27379] 2012-09-26 09:50:57,558 = RowMutationVerbHandler.java (line 61) Error in row mutation > org.apache.cassandra.db.UnknownColumnFamilyException: Couldn't find = cfId=3D13740 I would make sure repair is running to resolve any inconsistencies = created during the period of wackyness. > 3. How do I reverse engineer what column familsy 13740 and 13739 are? = (ie. Their names?) so I can check for data corruption. On 1.1 X schema is stored in the schema_columnfamilies CF in t he system = KS. In previous versions it's in a different CF in the same KS.=20 Cheers ----------------- Aaron Morton Freelance Developer @aaronmorton http://www.thelastpickle.com On 27/09/2012, at 4:04 AM, "Hiller, Dean" wrote: > We were consistently getting this exception over and over as we put = data into the system. A reboot caused it to go away but we don't want = to be rebooting in the future=85. >=20 > 1. When does this occur? > 2. Is it affecting my data put? (I have seen other weird validation = exceptions where my data is still put and I can read it from cassandra = and I get no exception client side) > 3. How do I reverse engineer what column familsy 13740 and 13739 are? = (ie. Their names?) so I can check for data corruption. >=20 > ERROR [MigrationStage:1] 2012-09-26 09:51:03,128 = AbstractCassandraDaemon.java (line 134) Exception in thread = Thread[MigrationStage:1,5,main] > java.lang.RuntimeException: java.io.IOException: = org.apache.cassandra.config.ConfigurationException: Column family ID = mismatch (found 13740; expected 13739) > at = org.apache.cassandra.utils.FBUtilities.unchecked(FBUtilities.java:628) > at = org.apache.cassandra.utils.WrappedRunnable.run(WrappedRunnable.java:34) > at = java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) > at = java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) > at java.util.concurrent.FutureTask.run(FutureTask.java:138) > at = java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.= java:886) > at = java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java= :908) > at java.lang.Thread.run(Thread.java:662) > Caused by: java.io.IOException: = org.apache.cassandra.config.ConfigurationException: Column family ID = mismatch (found 13740; expected 13739) > at = org.apache.cassandra.config.CFMetaData.reload(CFMetaData.java:676) > at = org.apache.cassandra.db.DefsTable.updateColumnFamily(DefsTable.java:463) > at = org.apache.cassandra.db.DefsTable.mergeColumnFamilies(DefsTable.java:407) > at = org.apache.cassandra.db.DefsTable.mergeSchema(DefsTable.java:271) > at = org.apache.cassandra.db.DefsTable.mergeRemoteSchema(DefsTable.java:249) > at = org.apache.cassandra.db.DefinitionsUpdateVerbHandler$1.runMayThrow(Definit= ionsUpdateVerbHandler.java:48) > at = org.apache.cassandra.utils.WrappedRunnable.run(WrappedRunnable.java:30) > ... 6 more > Caused by: org.apache.cassandra.config.ConfigurationException: Column = family ID mismatch (found 13740; expected 13739) > at = org.apache.cassandra.config.CFMetaData.apply(CFMetaData.java:698) > at = org.apache.cassandra.config.CFMetaData.reload(CFMetaData.java:672) = ... 12 more >=20 > We also see this exception in the same log which is ironic considering = the above one says found 13740!!!!!! And this one says couldn't find = it=85. >=20 > ERROR [MutationStage:27379] 2012-09-26 09:50:57,558 = RowMutationVerbHandler.java (line 61) Error in row mutation > org.apache.cassandra.db.UnknownColumnFamilyException: Couldn't find = cfId=3D13740 > at = org.apache.cassandra.db.ColumnFamilySerializer.deserialize(ColumnFamilySer= ializer.java:126) > at = org.apache.cassandra.db.RowMutation$RowMutationSerializer.deserialize(RowM= utation.java:439) > at = org.apache.cassandra.db.RowMutation$RowMutationSerializer.deserialize(RowM= utation.java:447) > at = org.apache.cassandra.db.RowMutation.fromBytes(RowMutation.java:395) > at = org.apache.cassandra.db.RowMutationVerbHandler.doVerb(RowMutationVerbHandl= er.java:42) > at = org.apache.cassandra.net.MessageDeliveryTask.run(MessageDeliveryTask.java:= 59) > at = java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.= java:886) > at = java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java= :908) > at java.lang.Thread.run(Thread.java:662) --Apple-Mail=_B1BB3927-957A-4471-A429-9A245C0B4E4D Content-Transfer-Encoding: quoted-printable Content-Type: text/html; charset=windows-1252 ERROR [MigrationStage:1] 2012-09-26 = 09:51:03,128 AbstractCassandraDaemon.java (line 134) Exception in thread = Thread[MigrationStage:1,5,main]
I *think* this is due to = concurrent schema modifications. 
The found number is the = one in the database for the matching KS / CF name, the second number is = the one in memory at the time. That's why the reboot fixed = it.

If it happens again try  nodetool = resetlocalschema  

2.  Is it affecting my data put?  (I have seen = other weird validation exceptions where my data is still put and I can = read it from cassandra and I get no exception client = side)
This write was dropped
ERROR [MutationStage:27379] 2012-09-26 09:50:57,558 = RowMutationVerbHandler.java (line 61) Error in row = mutation
org.apache.cassandra.db.UnknownColumnFamilyException: = Couldn't find cfId=3D13740
I would make sure repair is = running to resolve any inconsistencies created during the period of = wackyness.

3.  How do = I reverse engineer what column familsy 13740 and 13739 are?  (ie. = Their names?) so I can check for data corruption.
On 1.1 = X schema is stored in the schema_columnfamilies CF in t he system KS. In = previous versions it's in a different CF in the same = KS. 

Cheers

http://www.thelastpickle.com

On 27/09/2012, at 4:04 AM, "Hiller, Dean" <Dean.Hiller@nrel.gov> = wrote:

We were consistently getting this exception over and over = as we put data into the system.  A reboot caused it to go away but = we don't want to be rebooting in the future=85.

1.  When = does this occur?
2.  Is it affecting my data put?  (I have = seen other weird validation exceptions where my data is still put and I = can read it from cassandra and I get no exception client side)
3. =  How do I reverse engineer what column familsy 13740 and 13739 are? =  (ie. Their names?) so I can check for data = corruption.

ERROR [MigrationStage:1] 2012-09-26 09:51:03,128 = AbstractCassandraDaemon.java (line 134) Exception in thread = Thread[MigrationStage:1,5,main]
java.lang.RuntimeException: = java.io.IOException: org.apache.cassandra.config.ConfigurationException: = Column family ID mismatch (found 13740; expected 13739)
=        at = org.apache.cassandra.utils.FBUtilities.unchecked(FBUtilities.java:628)
=        at = org.apache.cassandra.utils.WrappedRunnable.run(WrappedRunnable.java:34)        at = java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)        at = java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
=        at = java.util.concurrent.FutureTask.run(FutureTask.java:138)
=        at = java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.= java:886)
       at = java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java= :908)
       at = java.lang.Thread.run(Thread.java:662)
Caused by: java.io.IOException: = org.apache.cassandra.config.ConfigurationException: Column family ID = mismatch (found 13740; expected 13739)
=        at = org.apache.cassandra.config.CFMetaData.reload(CFMetaData.java:676)
=        at = org.apache.cassandra.db.DefsTable.updateColumnFamily(DefsTable.java:463)        at = org.apache.cassandra.db.DefsTable.mergeColumnFamilies(DefsTable.java:407)<= br>        at = org.apache.cassandra.db.DefsTable.mergeSchema(DefsTable.java:271)
=        at = org.apache.cassandra.db.DefsTable.mergeRemoteSchema(DefsTable.java:249)        at = org.apache.cassandra.db.DefinitionsUpdateVerbHandler$1.runMayThrow(Definit= ionsUpdateVerbHandler.java:48)
=        at = org.apache.cassandra.utils.WrappedRunnable.run(WrappedRunnable.java:30)        ... 6 more
Caused by: = org.apache.cassandra.config.ConfigurationException: Column family ID = mismatch (found 13740; expected 13739)
=        at = org.apache.cassandra.config.CFMetaData.apply(CFMetaData.java:698)
=        at = org.apache.cassandra.config.CFMetaData.reload(CFMetaData.java:672) =        ... 12 more

We also see = this exception in the same log which is ironic considering the above one = says found 13740!!!!!! And this one says couldn't find it=85.

ERROR= [MutationStage:27379] 2012-09-26 09:50:57,558 = RowMutationVerbHandler.java (line 61) Error in row = mutation
org.apache.cassandra.db.UnknownColumnFamilyException: = Couldn't find cfId=3D13740
=        at = org.apache.cassandra.db.ColumnFamilySerializer.deserialize(ColumnFamilySer= ializer.java:126)
       at = org.apache.cassandra.db.RowMutation$RowMutationSerializer.deserialize(RowM= utation.java:439)
       at = org.apache.cassandra.db.RowMutation$RowMutationSerializer.deserialize(RowM= utation.java:447)
       at = org.apache.cassandra.db.RowMutation.fromBytes(RowMutation.java:395)
=        at = org.apache.cassandra.db.RowMutationVerbHandler.doVerb(RowMutationVerbHandl= er.java:42)
       at = org.apache.cassandra.net.MessageDeliveryTask.run(MessageDeliveryTask.java:= 59)
       at = java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.= java:886)
       at = java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java= :908)
       at = java.lang.Thread.run(Thread.java:662)

= --Apple-Mail=_B1BB3927-957A-4471-A429-9A245C0B4E4D--