Skip to content

Instantly share code, notes, and snippets.

@rwjblue

rwjblue/Errored Test Cassandra DEBUG Log

Created May 24, 2011 01:02
Show Gist options
  • Save rwjblue/987968 to your computer and use it in GitHub Desktop.
Save rwjblue/987968 to your computer and use it in GitHub Desktop.
Download ZIP
DEBUG Log Output During Tests
Raw
Errored Test Cassandra DEBUG Log
DEBUG 21:01:27,166 computing ranges for 118913266174357844299523244095729587344
DEBUG 21:01:27,168 logged out: #<User allow_all groups=[]>
DEBUG 21:01:27,226 truncating Users in Twitter
DEBUG 21:01:27,227 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:01:27,227 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:01:27,227 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:01:27,227 Applying Truncation(keyspace='Twitter', cf='Users')
DEBUG 21:01:27,227 Truncating...
DEBUG 21:01:27,227 forceFlush requested but everything is clean
DEBUG 21:01:27,228 forceFlush requested but everything is clean
DEBUG 21:01:27,228 Truncation done.
DEBUG 21:01:27,228 Truncate operation succeeded at this host
DEBUG 21:01:27,228 Truncation(keyspace='Twitter', cf='Users') applied. Sending response to 443@localhost/127.0.0.1
DEBUG 21:01:27,229 Processing response on a callback from 443@localhost/127.0.0.1
DEBUG 21:01:27,229 truncate done
DEBUG 21:01:27,230 truncating UserRelationships in Twitter
DEBUG 21:01:27,230 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:01:27,230 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:01:27,230 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:01:27,230 Applying Truncation(keyspace='Twitter', cf='UserRelationships')
DEBUG 21:01:27,231 Truncating...
DEBUG 21:01:27,231 forceFlush requested but everything is clean
DEBUG 21:01:27,231 forceFlush requested but everything is clean
DEBUG 21:01:27,231 Truncation done.
DEBUG 21:01:27,231 Truncate operation succeeded at this host
DEBUG 21:01:27,232 Truncation(keyspace='Twitter', cf='UserRelationships') applied. Sending response to 444@localhost/127.0.0.1
DEBUG 21:01:27,232 Processing response on a callback from 444@localhost/127.0.0.1
DEBUG 21:01:27,232 truncate done
DEBUG 21:01:27,233 truncating Index in Twitter
DEBUG 21:01:27,233 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:01:27,233 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:01:27,233 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:01:27,234 Applying Truncation(keyspace='Twitter', cf='Index')
DEBUG 21:01:27,234 Truncating...
DEBUG 21:01:27,234 forceFlush requested but everything is clean
DEBUG 21:01:27,234 forceFlush requested but everything is clean
DEBUG 21:01:27,234 Truncation done.
DEBUG 21:01:27,234 Truncate operation succeeded at this host
DEBUG 21:01:27,234 Truncation(keyspace='Twitter', cf='Index') applied. Sending response to 445@localhost/127.0.0.1
DEBUG 21:01:27,235 Processing response on a callback from 445@localhost/127.0.0.1
DEBUG 21:01:27,235 truncate done
DEBUG 21:01:27,236 truncating StatusAudits in Twitter
DEBUG 21:01:27,236 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:01:27,236 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:01:27,236 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:01:27,236 Applying Truncation(keyspace='Twitter', cf='StatusAudits')
DEBUG 21:01:27,236 Truncating...
DEBUG 21:01:27,237 forceFlush requested but everything is clean
DEBUG 21:01:27,237 forceFlush requested but everything is clean
DEBUG 21:01:27,237 Truncation done.
DEBUG 21:01:27,237 Truncate operation succeeded at this host
DEBUG 21:01:27,237 Truncation(keyspace='Twitter', cf='StatusAudits') applied. Sending response to 446@localhost/127.0.0.1
DEBUG 21:01:27,237 Processing response on a callback from 446@localhost/127.0.0.1
DEBUG 21:01:27,237 truncate done
DEBUG 21:01:27,238 truncating StatusRelationships in Twitter
DEBUG 21:01:27,238 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:01:27,238 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:01:27,238 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:01:27,239 Applying Truncation(keyspace='Twitter', cf='StatusRelationships')
DEBUG 21:01:27,239 Truncating...
INFO 21:01:27,239 Enqueuing flush of Memtable-StatusRelationships@579409826(33 bytes, 2 operations)
INFO 21:01:27,239 Writing Memtable-StatusRelationships@579409826(33 bytes, 2 operations)
INFO 21:01:27,331 Completed flushing data/data/Twitter/StatusRelationships-f-10-Data.db (140 bytes)
DEBUG 21:01:27,332 adding data/data/Twitter/StatusRelationships-f-10 to list of files tracked for Twitter.StatusRelationships
DEBUG 21:01:27,332 discard completed log segments for CommitLogContext(file='/Users/rjackson/cassandra/cassandra-0.7/data/commitlog/CommitLog-1306197163812.log', position=31360), column family 1006.
DEBUG 21:01:27,332 Marking replay position 31360 on commit log CommitLogSegment(/Users/rjackson/cassandra/cassandra-0.7/data/commitlog/CommitLog-1306197163812.log)
DEBUG 21:01:27,332 Checking to see if compaction of StatusRelationships would be useful
DEBUG 21:01:27,333 forceFlush requested but everything is clean
DEBUG 21:01:27,471 Snapshot for Table(name='Twitter') keyspace data file data/data/Twitter/StatusRelationships-f-10-Data.db created in /Users/rjackson/cassandra/cassandra-0.7/data/data/Twitter/snapshots/1306198887333-before-truncate
DEBUG 21:01:27,472 removing data/data/Twitter/StatusRelationships-f-10 from list of files tracked for Twitter.StatusRelationships
DEBUG 21:01:27,472 Marking data/data/Twitter/StatusRelationships-f-10-Data.db compacted
DEBUG 21:01:27,472 Truncation done.
DEBUG 21:01:27,473 Truncate operation succeeded at this host
DEBUG 21:01:27,473 Truncation(keyspace='Twitter', cf='StatusRelationships') applied. Sending response to 447@localhost/127.0.0.1
DEBUG 21:01:27,473 Processing response on a callback from 447@localhost/127.0.0.1
DEBUG 21:01:27,473 truncate done
DEBUG 21:01:27,474 logged out: #<User allow_all groups=[]>
DEBUG 21:01:27,538 GC for ParNew: 3 ms, 15159384 reclaimed leaving 23594536 used; max is 1205731328
Raw
Successful Test Cassandra DEBUG Logs
DEBUG 21:00:02,908 computing ranges for 118913266174357844299523244095729587344
DEBUG 21:00:02,910 logged out: #<User allow_all groups=[]>
DEBUG 21:00:02,947 truncating Users in Twitter
DEBUG 21:00:02,947 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:00:02,947 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:02,948 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:02,948 Applying Truncation(keyspace='Twitter', cf='Users')
DEBUG 21:00:02,948 Truncating...
DEBUG 21:00:02,948 forceFlush requested but everything is clean
DEBUG 21:00:02,948 forceFlush requested but everything is clean
DEBUG 21:00:02,949 Truncation done.
DEBUG 21:00:02,949 Truncate operation succeeded at this host
DEBUG 21:00:02,949 Truncation(keyspace='Twitter', cf='Users') applied. Sending response to 430@localhost/127.0.0.1
DEBUG 21:00:02,949 Processing response on a callback from 430@localhost/127.0.0.1
DEBUG 21:00:02,949 truncate done
DEBUG 21:00:02,950 truncating UserRelationships in Twitter
DEBUG 21:00:02,951 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:00:02,951 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:02,951 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:02,951 Applying Truncation(keyspace='Twitter', cf='UserRelationships')
DEBUG 21:00:02,951 Truncating...
DEBUG 21:00:02,952 forceFlush requested but everything is clean
DEBUG 21:00:02,952 forceFlush requested but everything is clean
DEBUG 21:00:02,952 Truncation done.
DEBUG 21:00:02,952 Truncate operation succeeded at this host
DEBUG 21:00:02,952 Truncation(keyspace='Twitter', cf='UserRelationships') applied. Sending response to 431@localhost/127.0.0.1
DEBUG 21:00:02,952 Processing response on a callback from 431@localhost/127.0.0.1
DEBUG 21:00:02,952 truncate done
DEBUG 21:00:02,953 truncating Index in Twitter
DEBUG 21:00:02,953 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:00:02,953 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:02,954 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:02,954 Applying Truncation(keyspace='Twitter', cf='Index')
DEBUG 21:00:02,954 Truncating...
DEBUG 21:00:02,954 forceFlush requested but everything is clean
DEBUG 21:00:02,954 forceFlush requested but everything is clean
DEBUG 21:00:02,954 Truncation done.
DEBUG 21:00:02,954 Truncate operation succeeded at this host
DEBUG 21:00:02,954 Truncation(keyspace='Twitter', cf='Index') applied. Sending response to 432@localhost/127.0.0.1
DEBUG 21:00:02,955 Processing response on a callback from 432@localhost/127.0.0.1
DEBUG 21:00:02,955 truncate done
DEBUG 21:00:02,955 truncating StatusAudits in Twitter
DEBUG 21:00:02,956 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:00:02,956 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:02,956 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:02,956 Applying Truncation(keyspace='Twitter', cf='StatusAudits')
DEBUG 21:00:02,956 Truncating...
DEBUG 21:00:02,956 forceFlush requested but everything is clean
DEBUG 21:00:02,956 forceFlush requested but everything is clean
DEBUG 21:00:02,957 Truncation done.
DEBUG 21:00:02,957 Truncate operation succeeded at this host
DEBUG 21:00:02,957 Truncation(keyspace='Twitter', cf='StatusAudits') applied. Sending response to 433@localhost/127.0.0.1
DEBUG 21:00:02,957 Processing response on a callback from 433@localhost/127.0.0.1
DEBUG 21:00:02,957 truncate done
DEBUG 21:00:02,958 truncating StatusRelationships in Twitter
DEBUG 21:00:02,958 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:00:02,958 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:02,958 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:02,958 Applying Truncation(keyspace='Twitter', cf='StatusRelationships')
DEBUG 21:00:02,958 Truncating...
DEBUG 21:00:02,959 forceFlush requested but everything is clean
DEBUG 21:00:02,959 forceFlush requested but everything is clean
DEBUG 21:00:02,959 Truncation done.
DEBUG 21:00:02,959 Truncate operation succeeded at this host
DEBUG 21:00:02,960 Truncation(keyspace='Twitter', cf='StatusRelationships') applied. Sending response to 434@localhost/127.0.0.1
DEBUG 21:00:02,961 Processing response on a callback from 434@localhost/127.0.0.1
DEBUG 21:00:02,961 truncate done
DEBUG 21:00:02,962 truncating Statuses in Twitter
DEBUG 21:00:02,962 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:00:02,962 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:02,962 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:02,962 Applying Truncation(keyspace='Twitter', cf='Statuses')
DEBUG 21:00:02,962 Truncating...
DEBUG 21:00:02,963 forceFlush requested but everything is clean
DEBUG 21:00:02,963 forceFlush requested but everything is clean
DEBUG 21:00:02,963 Truncation done.
DEBUG 21:00:02,963 Truncate operation succeeded at this host
DEBUG 21:00:02,963 Truncation(keyspace='Twitter', cf='Statuses') applied. Sending response to 435@localhost/127.0.0.1
DEBUG 21:00:02,963 Processing response on a callback from 435@localhost/127.0.0.1
DEBUG 21:00:02,963 truncate done
DEBUG 21:00:02,964 truncating UserAudits in Twitter
DEBUG 21:00:02,964 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:00:02,964 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:02,964 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:02,965 Applying Truncation(keyspace='Twitter', cf='UserAudits')
DEBUG 21:00:02,965 Truncating...
DEBUG 21:00:02,965 forceFlush requested but everything is clean
DEBUG 21:00:02,965 forceFlush requested but everything is clean
DEBUG 21:00:02,965 Truncation done.
DEBUG 21:00:02,965 Truncate operation succeeded at this host
DEBUG 21:00:02,966 Truncation(keyspace='Twitter', cf='UserAudits') applied. Sending response to 436@localhost/127.0.0.1
DEBUG 21:00:02,966 Processing response on a callback from 436@localhost/127.0.0.1
DEBUG 21:00:02,966 truncate done
DEBUG 21:00:02,967 truncating Usernames in Twitter
DEBUG 21:00:02,967 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:00:02,967 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:02,967 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:02,967 Applying Truncation(keyspace='Twitter', cf='Usernames')
DEBUG 21:00:02,967 Truncating...
DEBUG 21:00:02,967 forceFlush requested but everything is clean
DEBUG 21:00:02,967 forceFlush requested but everything is clean
DEBUG 21:00:02,968 Truncation done.
DEBUG 21:00:02,968 Truncate operation succeeded at this host
DEBUG 21:00:02,968 Truncation(keyspace='Twitter', cf='Usernames') applied. Sending response to 437@localhost/127.0.0.1
DEBUG 21:00:02,968 Processing response on a callback from 437@localhost/127.0.0.1
DEBUG 21:00:02,968 truncate done
DEBUG 21:00:02,969 truncating TimelinishThings in Twitter
DEBUG 21:00:02,969 Starting a blocking truncate operation on keyspace Twitter, CF
DEBUG 21:00:02,969 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:02,969 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:02,969 Applying Truncation(keyspace='Twitter', cf='TimelinishThings')
DEBUG 21:00:02,969 Truncating...
DEBUG 21:00:02,969 forceFlush requested but everything is clean
DEBUG 21:00:02,970 forceFlush requested but everything is clean
DEBUG 21:00:02,970 Truncation done.
DEBUG 21:00:02,970 Truncate operation succeeded at this host
DEBUG 21:00:02,970 Truncation(keyspace='Twitter', cf='TimelinishThings') applied. Sending response to 438@localhost/127.0.0.1
DEBUG 21:00:02,970 Processing response on a callback from 438@localhost/127.0.0.1
DEBUG 21:00:02,970 truncate done
DEBUG 21:00:02,981 computing ranges for 118913266174357844299523244095729587344
DEBUG 21:00:02,982 logged out: #<User allow_all groups=[]>
DEBUG 21:00:03,045 truncating Comments in Multiblog
DEBUG 21:00:03,045 Starting a blocking truncate operation on keyspace Multiblog, CF
DEBUG 21:00:03,045 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:03,045 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:03,045 Applying Truncation(keyspace='Multiblog', cf='Comments')
DEBUG 21:00:03,046 Truncating...
DEBUG 21:00:03,046 forceFlush requested but everything is clean
DEBUG 21:00:03,046 forceFlush requested but everything is clean
DEBUG 21:00:03,046 Truncation done.
DEBUG 21:00:03,046 Truncate operation succeeded at this host
DEBUG 21:00:03,046 Truncation(keyspace='Multiblog', cf='Comments') applied. Sending response to 439@localhost/127.0.0.1
DEBUG 21:00:03,046 Processing response on a callback from 439@localhost/127.0.0.1
DEBUG 21:00:03,046 truncate done
DEBUG 21:00:03,047 truncating Blogs in Multiblog
DEBUG 21:00:03,047 Starting a blocking truncate operation on keyspace Multiblog, CF
DEBUG 21:00:03,047 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:03,047 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:03,048 Applying Truncation(keyspace='Multiblog', cf='Blogs')
DEBUG 21:00:03,048 Truncating...
DEBUG 21:00:03,048 forceFlush requested but everything is clean
DEBUG 21:00:03,048 forceFlush requested but everything is clean
DEBUG 21:00:03,048 Truncation done.
DEBUG 21:00:03,048 Truncate operation succeeded at this host
DEBUG 21:00:03,048 Truncation(keyspace='Multiblog', cf='Blogs') applied. Sending response to 440@localhost/127.0.0.1
DEBUG 21:00:03,049 Processing response on a callback from 440@localhost/127.0.0.1
DEBUG 21:00:03,049 truncate done
DEBUG 21:00:03,060 computing ranges for 118913266174357844299523244095729587344
DEBUG 21:00:03,061 logged out: #<User allow_all groups=[]>
DEBUG 21:00:03,075 truncating Comments in MultiblogLong
DEBUG 21:00:03,075 Starting a blocking truncate operation on keyspace MultiblogLong, CF
DEBUG 21:00:03,075 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:03,076 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:03,076 Applying Truncation(keyspace='MultiblogLong', cf='Comments')
DEBUG 21:00:03,076 Truncating...
DEBUG 21:00:03,076 forceFlush requested but everything is clean
DEBUG 21:00:03,077 forceFlush requested but everything is clean
DEBUG 21:00:03,077 Truncation done.
DEBUG 21:00:03,077 Truncate operation succeeded at this host
DEBUG 21:00:03,077 Truncation(keyspace='MultiblogLong', cf='Comments') applied. Sending response to 441@localhost/127.0.0.1
DEBUG 21:00:03,077 Processing response on a callback from 441@localhost/127.0.0.1
DEBUG 21:00:03,077 truncate done
DEBUG 21:00:03,078 truncating Blogs in MultiblogLong
DEBUG 21:00:03,078 Starting a blocking truncate operation on keyspace MultiblogLong, CF
DEBUG 21:00:03,078 Starting to send truncate messages to hosts [localhost/127.0.0.1]
DEBUG 21:00:03,078 Sent all truncate messages, now waiting for 1 responses
DEBUG 21:00:03,079 Applying Truncation(keyspace='MultiblogLong', cf='Blogs')
DEBUG 21:00:03,079 Truncating...
DEBUG 21:00:03,079 forceFlush requested but everything is clean
DEBUG 21:00:03,079 forceFlush requested but everything is clean
DEBUG 21:00:03,079 Truncation done.
DEBUG 21:00:03,079 Truncate operation succeeded at this host
DEBUG 21:00:03,079 Truncation(keyspace='MultiblogLong', cf='Blogs') applied. Sending response to 442@localhost/127.0.0.1
DEBUG 21:00:03,080 Processing response on a callback from 442@localhost/127.0.0.1
DEBUG 21:00:03,080 truncate done
DEBUG 21:00:03,083 batch_mutate
DEBUG 21:00:03,084 insert writing local RowMutation(keyspace='Twitter', key='746573745f72656d6f76655f73757065725f7375625f6b6579', modifications=[StatusRelationships])
DEBUG 21:00:03,085 applying mutation of row 746573745f72656d6f76655f73757065725f7375625f6b6579
DEBUG 21:00:03,087 remove
DEBUG 21:00:03,088 insert writing local RowMutation(keyspace='Twitter', key='746573745f72656d6f76655f73757065725f7375625f6b6579', modifications=[StatusRelationships])
DEBUG 21:00:03,089 applying mutation of row 746573745f72656d6f76655f73757065725f7375625f6b6579
DEBUG 21:00:03,091 multiget_slice
DEBUG 21:00:03,092 Command/ConsistencyLevel is SliceFromReadCommand(table='Twitter', key='746573745f72656d6f76655f73757065725f7375625f6b6579', column_parent='QueryPath(columnFamilyName='StatusRelationships', superColumnName='java.nio.HeapByteBuffer[pos=99 lim=113 cap=155]', columnName='null')', start='', finish='', reversed=false, count=100)/ONE
DEBUG 21:00:03,092 Blockfor/repair is 1/true; setting up requests to localhost/127.0.0.1
DEBUG 21:00:03,092 reading data locally
DEBUG 21:00:03,093 LocalReadRunnable reading SliceFromReadCommand(table='Twitter', key='746573745f72656d6f76655f73757065725f7375625f6b6579', column_parent='QueryPath(columnFamilyName='StatusRelationships', superColumnName='java.nio.HeapByteBuffer[pos=99 lim=113 cap=155]', columnName='null')', start='', finish='', reversed=false, count=100)
DEBUG 21:00:03,093 collecting 0 of 100: 13814000-4eff-11b3-86a8-f00704f6656d:false:2@1306198803081759
DEBUG 21:00:03,094 Read: 1 ms.
DEBUG 21:00:03,135 logged out: #<User allow_all groups=[]>
DEBUG 21:00:03,136 logged out: #<User allow_all groups=[]>
DEBUG 21:00:03,135 logged out: #<User allow_all groups=[]>
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment