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 80F3610705 for ; Mon, 17 Feb 2014 08:27:20 +0000 (UTC) Received: (qmail 12278 invoked by uid 500); 17 Feb 2014 08:27:17 -0000 Delivered-To: apmail-cassandra-user-archive@cassandra.apache.org Received: (qmail 11791 invoked by uid 500); 17 Feb 2014 08:27:16 -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 11782 invoked by uid 99); 17 Feb 2014 08:27:15 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 17 Feb 2014 08:27:15 +0000 X-ASF-Spam-Status: No, hits=1.5 required=5.0 tests=HTML_MESSAGE,RCVD_IN_DNSWL_LOW,SPF_PASS X-Spam-Check-By: apache.org Received-SPF: pass (athena.apache.org: domain of mishra.vivs@gmail.com designates 209.85.214.173 as permitted sender) Received: from [209.85.214.173] (HELO mail-ob0-f173.google.com) (209.85.214.173) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 17 Feb 2014 08:27:09 +0000 Received: by mail-ob0-f173.google.com with SMTP id vb8so16737929obc.18 for ; Mon, 17 Feb 2014 00:26:49 -0800 (PST) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20120113; h=mime-version:in-reply-to:references:date:message-id:subject:from:to :content-type; bh=gdCr5Q0a9QbJk1ZTZo82daYNEUvpqRFn8pgEROvKYNE=; b=CnWzzA9ZkOvpRC5zg/b1ij1JI9xDKL9Q78A6tGD5RWUR+P1pEshWmcONfwcsIP2F6/ 8DKzgGR7jj3qZtwGcj8/zvJesKzY1UqOr5z3PGWXP/MpT2nSUPo2bm9LFStXBG2dOPQL zfk4GC2euoSVLuh8t4afPAuOMgDQZHk7/JmNqCzScndYEMDsiI1vrFsbKw6r3eKp1sxX GNiOs9TJqkLvUtTNn1UIcE5qzO3PIetxh34SuQ8GQune/JypSXGfqxqg95VOTvvdtQFD xgQSWw3/F3FP1g7ntVlpY/piGap4N3agUydo6xmp7tdRLMc35JHte7qDqBBjUuxPt9MR J7nw== MIME-Version: 1.0 X-Received: by 10.182.144.136 with SMTP id sm8mr79201obb.63.1392625609208; Mon, 17 Feb 2014 00:26:49 -0800 (PST) Received: by 10.182.75.228 with HTTP; Mon, 17 Feb 2014 00:26:49 -0800 (PST) In-Reply-To: References: Date: Mon, 17 Feb 2014 13:56:49 +0530 Message-ID: Subject: Re: Exception in cassandra logs while processing the message From: Vivek Mishra To: user@cassandra.apache.org Content-Type: multipart/alternative; boundary=089e0149cd5ebdfcf204f295ea6f X-Virus-Checked: Checked by ClamAV on apache.org --089e0149cd5ebdfcf204f295ea6f Content-Type: text/plain; charset=ISO-8859-1 looks like thrift inter operability issue. Seems column family or data created via CQL3 and using Thrift based API to read it. Else, recreate your schema and try. -Vivek On Mon, Feb 17, 2014 at 1:50 PM, ankit tyagi wrote: > Hello, > > anyone has the idea regarding this exception. > > Regards, > Ankit Tyagi > > > On Fri, Feb 14, 2014 at 7:02 PM, ankit tyagi wrote: > >> Hello, >> >> I am seeing below exception in my cassandra >> logs(/var/log/cassandra/system.log). >> >> INFO [ScheduledTasks:1] 2014-02-13 13:13:57,641 GCInspector.java (line >> 119) GC for ParNew: 273 ms for 1 collections, 2319121816 used; max is 445 >> 6448000 >> INFO [ScheduledTasks:1] 2014-02-13 13:14:02,695 GCInspector.java (line >> 119) GC for ParNew: 214 ms for 1 collections, 2315368976 used; max is 445 >> 6448000 >> INFO [OptionalTasks:1] 2014-02-13 13:14:08,093 MeteredFlusher.java (line >> 64) flushing high-traffic column family CFS(Keyspace='comsdb', ColumnFa >> mily='product_update') (estimated 213624220 bytes) >> INFO [OptionalTasks:1] 2014-02-13 13:14:08,093 ColumnFamilyStore.java >> (line 626) Enqueuing flush of Memtable-product_update@1067619242 >> (31239028/ >> 213625108 serialized/live bytes, 222393 ops) >> INFO [FlushWriter:94] 2014-02-13 13:14:08,127 Memtable.java (line 400) >> Writing Memtable-product_update@1067619242(31239028/213625108 serialized/ >> live bytes, 222393 ops) >> INFO [ScheduledTasks:1] 2014-02-13 13:14:08,696 GCInspector.java (line >> 119) GC for ParNew: 214 ms for 1 collections, 2480175160 used; max is 445 >> 6448000 >> * INFO [FlushWriter:94] 2014-02-13 13:14:10,836 Memtable.java (line 438) >> Completed flushing /cassandra1/data/comsdb/product_update/comsdb-product_* >> *update-ic-416-Data.db (15707248 bytes) for commitlog position >> ReplayPosition(segmentId=1391568233618, position=13712751)* >> *ERROR [Thrift:13] 2014-02-13 13:15:45,694 CustomTThreadPoolServer.java >> (line 213) Thrift error occurred during processing of message.* >> *org.apache.thrift.TException: Negative length: -2147418111* >> at >> org.apache.thrift.protocol.TBinaryProtocol.checkReadLength(TBinaryProtocol.java:388) >> at >> org.apache.thrift.protocol.TBinaryProtocol.readBinary(TBinaryProtocol.java:363) >> at >> org.apache.cassandra.thrift.Cassandra$batch_mutate_args.read(Cassandra.java:20304) >> at >> org.apache.thrift.ProcessFunction.process(ProcessFunction.java:21) >> at >> org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:34) >> at >> org.apache.cassandra.thrift.CustomTThreadPoolServer$WorkerProcess.run(CustomTThreadPoolServer.java:199) >> at >> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146) >> at >> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) >> at java.lang.Thread.run(Thread.java:679) >> ERROR [Thrift:103] 2014-02-13 13:21:25,719 CustomTThreadPoolServer.java >> (line 213) Thrift error occurred during processing of message. >> org.apache.thrift.TException: Negative length: -2147418111 >> >> >> Below is my cassandra version and hector client version, which is being >> used currently. >> >> Cassandra-version: 1.2.11 >> Hector-client: 1.0-2 >> >> Any lead would be appreciated though we are planning to move cassandra >> 2.0 version with java-driver but it may take some time meanwhile need to >> find the root cause and resolve this issue. >> >> >> Regards, >> Ankit Tyagi >> > > --089e0149cd5ebdfcf204f295ea6f Content-Type: text/html; charset=ISO-8859-1 Content-Transfer-Encoding: quoted-printable
looks like thrift inter operability issue. Seems column fa= mily or data created via CQL3 and using Thrift based API to read it.=A0
Else, recreate your schema and try.
<= br>
-Vivek


On Mon, Feb 17, 2014 at 1:50 PM, ankit tyagi <ankittyagi.mnnit@gmail.com> wrote:
Hello,

a= nyone has the idea regarding this exception.

Regar= ds,
Ankit Tyagi


On Fri, Feb 14, 2014= at 7:02 PM, ankit tyagi <ankittyagi.mnnit@gmail.com> wrote:
Hello,

I am s= eeing below exception in my cassandra logs(/var/log/cassandra/system.log).<= /div>

INFO [ScheduledTa= sks:1] 2014-02-13 13:13:57,641 GCInspector.java (line 119) GC for ParNew: 2= 73 ms for 1 collections, 2319121816 used; max is 445
6448000
=A0INFO [ScheduledTasks:1] 2014-02-13 13:14:02,695 G= CInspector.java (line 119) GC for ParNew: 214 ms for 1 collections, 2315368= 976 used; max is 445
6448000
=A0INFO [OptionalTasks:1] = 2014-02-13 13:14:08,093 MeteredFlusher.java (line 64) flushing high-traffic= column family CFS(Keyspace=3D'comsdb', ColumnFa
mily=3D'product_update') (estimated 213624220 bytes)
=A0INFO [OptionalTasks:1] 2014-02-13 13:14:08,093 ColumnFamilyStore.java (= line 626) Enqueuing flush of Memtable-product_update@1067619242(31239028/
213625108 serialized/live bytes, 222393 ops)
=A0INFO [FlushW= riter:94] 2014-02-13 13:14:08,127 Memtable.java (line 400) Writing Memtable= -product_update@1067619242(31239028/213625108 serialized/
live by= tes, 222393 ops)
=A0INFO [ScheduledTasks:1] 2014-02-13 13:14:08,696 GCInspector.java (l= ine 119) GC for ParNew: 214 ms for 1 collections, 2480175160 used; max is 4= 45
6448000
=A0INFO [FlushWriter:94] 2014-02-13 13:14= :10,836 Memtable.java (line 438) Completed flushing /cassandra1/data/comsdb= /product_update/comsdb-product_
update-ic-416-Data.db (15707248 bytes) for commitlog position Repla= yPosition(segmentId=3D1391568233618, position=3D13712751)
= ERROR [Thrift:13] 2014-02-13 13:15:45,694 CustomTThreadPoolServer.java (lin= e 213) Thrift error occurred during processing of message.
org.apache.thrift.TException: Negative length: -2147418111
=A0 =A0 =A0 =A0 at org.apache.thrift.protocol.TBinaryProtocol.checkRe= adLength(TBinaryProtocol.java:388)
=A0 =A0 =A0 =A0 at org.apache.= thrift.protocol.TBinaryProtocol.readBinary(TBinaryProtocol.java:363)
=A0 =A0 =A0 =A0 at org.apache.cassandra.thrift.Cassandra$batch_mutate_= args.read(Cassandra.java:20304)
=A0 =A0 =A0 =A0 at org.apache.thr= ift.ProcessFunction.process(ProcessFunction.java:21)
=A0 =A0 =A0 = =A0 at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:34)
=A0 =A0 =A0 =A0 at org.apache.cassandra.thrift.CustomTThreadPoolServer= $WorkerProcess.run(CustomTThreadPoolServer.java:199)
=A0 =A0 =A0 = =A0 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor= .java:1146)
=A0 =A0 =A0 =A0 at java.util.concurrent.ThreadPoolExecutor$Worker.run(= ThreadPoolExecutor.java:615)
=A0 =A0 =A0 =A0 at java.lang.Thread.= run(Thread.java:679)
ERROR [Thrift:103] 2014-02-13 13:21:25,719 C= ustomTThreadPoolServer.java (line 213) Thrift error occurred during process= ing of message.
org.apache.thrift.TException: Negative length: -2147418111
=


Below is my cassandra version and hector client version, which is being use= d currently.

Cass= andra-version: 1.2.11
Hector-client: 1= .0-2

Any lead wou= ld be appreciated though we are planning to move cassandra 2.0 version with= java-driver but it may take some time meanwhile need to find the root caus= e and resolve this issue.


Regards,
Ankit Tyagi


--089e0149cd5ebdfcf204f295ea6f--