Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

YQL value too long INSERT INTO ybdemo.ugent(id, data) VALUES (?, ?) with overlong JSON data #2116

Closed
zopyx opened this issue Aug 21, 2019 · 12 comments
Assignees
Labels
community/request Issues created by external users kind/bug This issue is a bug

Comments

@zopyx
Copy link

zopyx commented Aug 21, 2019

Inserting a JSON document larger than 7 MB or so using a prepared statement through yb-cassandra-python causes an internal loop of messages

E0821 04:49:58.241580 18126 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
INSERT INTO ybdemo.ugent(id, data) VALUES (?, ?)

and does not cause a propagation of the error back to the Python client/driver

https://forum.yugabyte.com/t/insert-of-large-json-data-for-cassandra-driver-very-slow/469/17?u=zopyx

@yugabyte-ci yugabyte-ci added the community/request Issues created by external users label Aug 21, 2019
@kmuthukk
Copy link
Collaborator

For the sample I tried, I was able to insert larger values - like 24MB JSONB -- but it had a single large field.

Perhaps, in your case, there are many more fields, and the internal representation exceeds some threshold, and you are getting "YQL value too long" error.

Also, agreed that this error should be surfaced upstream to the program cleanly.

@kmuthukk kmuthukk added the kind/bug This issue is a bug label Aug 21, 2019
@zopyx
Copy link
Author

zopyx commented Aug 21, 2019

No, this happens with JSON with exactly one key

@OlegLoginov
Copy link
Contributor

@zopyx Please provide the used 7 MB value (or any similar) for testing purposes. Thanks!

@zopyx
Copy link
Author

zopyx commented Aug 23, 2019

Here is my script


import os
import json
import time
from cassandra.cluster import Cluster, ExecutionProfile

cluster = Cluster(['127.0.0.1'])
profile = ExecutionProfile()
profile.request_timeout = 5
cluster.add_execution_profile('standard', profile)
session = cluster.connect()
session.execute('CREATE KEYSPACE IF NOT EXISTS ybdemo;')

session.execute(
  """
  CREATE TABLE IF NOT EXISTS ybdemo.ugent (id int PRIMARY KEY,
                                           data jsonb);
  """)

st = session.prepare("INSERT INTO ybdemo.ugent(id, data) VALUES (?, ?)")

for i in range (0,100000):
    data = dict()
    for y in range(0, int(1.5**i)):
        data[y] = '**********'
    data = json.dumps(data, sort_keys=True)
    try:
        ts = time.time()                                        
        bound = st.bind((i, data))
        session.execute(bound, execution_profile=profile)
    except Exception as e:
        raise
    finally:
        print(len(data), time.time() - ts)
cluster.shutdown()

@zopyx
Copy link
Author

zopyx commented Aug 23, 2019

Oops...my initial claim is wrong...the data has a growing number of keys...not a single large key...anyway...the error must be propagated..yes, an artificial test.

@kmuthukk
Copy link
Collaborator

hi @zopyx -- thx for updated input. Yes that makes more sense that for 7MB you had a different payload with a larger number of keys and somehow the internal representation was making it cross the 32MB limit.

I agree that main/hi-pri issue is to cleanly catch the error and propagate to client.

@OlegLoginov
Copy link
Contributor

Attaching the detailed TS log:

INSERT INTO ybdemo.ugent(id, data) VALUES (?, ?)
       ^^^^
I0912 16:19:34.014470 137494528 cql_service.cc:228] DeletePreparedStatement: CQL prepared statement cache count = 0/0, memory usage = 8192
I0912 16:19:34.014493 137494528 inbound_call.cc:161] 0x00000001125cf9a0 -> CQL Call from 127.0.0.1:50223, stream id: 14: Completed handling


I0912 16:19:34.014566 155738112 tcp_stream.cc:412] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Added data queued_bytes_to_send_: 49
I0912 16:19:34.014657 155738112 tcp_stream.cc:199] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Queued writes 49 bytes. written 49 . Status OK sending_ .size() 1
I0912 16:19:34.015264 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.015321 155738112 inbound_call.cc:139] 0x00000001125cf9a0 -> CQL Call from 127.0.0.1:50223, stream id: 14: Received
I0912 16:19:34.015396 126763008 inbound_call.cc:148] 0x00000001125cf9a0 -> CQL Call from 127.0.0.1:50223, stream id: 15: Handling
I0912 16:19:34.015439 126763008 cql_service.cc:133] Handling CQL Call from 127.0.0.1:50223, stream id: 15
I0912 16:19:34.015457 126763008 cql_message.cc:204] CQL message version 0x4 flags 0x0 stream id 15 opcode 0x9 length 52
I0912 16:19:34.015471 126763008 cql_message.h:254] CQL int 48
I0912 16:19:34.015482 126763008 cql_message.h:266] CQL long string INSERT INTO ybdemo.ugent(id, data) VALUES (?, ?)
I0912 16:19:34.015496 126763008 cql_processor.cc:277] PREPARE INSERT INTO ybdemo.ugent(id, data) VALUES (?, ?)
I0912 16:19:34.015513 126763008 cql_service.cc:189] InsertPreparedStatement: CQL prepared statement cache count = 1/1, memory usage = 0
I0912 16:19:34.028440 126763008 parser.cc:50] Successfully parsed statement "INSERT INTO ybdemo.ugent(id, data) VALUES (?, ?)". Result = <0x112070780>
I0912 16:19:34.028481 126763008 pt_dml.cc:142] Loading table descriptor for ybdemo.ugent
I0912 16:19:34.028507 126763008 rpc_controller.cc:44] RpcController 0x113456478 constructed
I0912 16:19:34.028542 126763008 outbound_call.cc:210] OutboundCall 0x1167e9620 constructed with state_: READY and RPC timeout: 5.000s
I0912 16:19:34.028585 126763008 reactor.cc:695] TabletServer_R006: Queueing outbound call RPC call yb.master.MasterService.GetTableSchema -> { remote: 127.0.0.1:7100 idx: 4 protocol: 0x000000010ccbeec0 -> tcp } , state=READY. to remote 127.0.0.1:7100
I0912 16:19:34.028653 122470400 reactor.cc:419] TabletServer_R004: timer tick at 32671.6
I0912 16:19:34.028656 121397248 reactor.cc:419] TabletServer_R002: timer tick at 32671.6
I0912 16:19:34.028667 121933824 reactor.cc:419] TabletServer_R003: timer tick at 32671.6
I0912 16:19:34.028671 123543552 tcp_stream.cc:412] { local: 127.0.0.1:50205 remote: 127.0.0.1:7100 }: Added data queued_bytes_to_send_: 74
I0912 16:19:34.028684 120324096 reactor.cc:419] TabletServer_R000: timer tick at 32671.6
I0912 16:19:34.028697 120860672 reactor.cc:419] TabletServer_R001: timer tick at 32671.6
I0912 16:19:34.028699 123006976 reactor.cc:419] TabletServer_R005: timer tick at 32671.6
I0912 16:19:34.028717 124080128 reactor.cc:419] TabletServer_R007: timer tick at 32671.6
I0912 16:19:34.028798 123543552 outbound_call.cc:346] OutboundCall 0x1167e9620 (RPC call yb.master.MasterService.GetTableSchema -> { remote: 127.0.0.1:7100 idx: 4 protocol: 0x000000010ccbeec0 -> tcp } , state=READY.) switching from READY to ON_OUTBOUND_QUEUE
I0912 16:19:34.028929 123543552 tcp_stream.cc:199] { local: 127.0.0.1:50205 remote: 127.0.0.1:7100 }: Queued writes 74 bytes. written 74 . Status OK sending_ .size() 1
I0912 16:19:34.028956 123543552 outbound_call.cc:346] OutboundCall 0x1167e9620 (RPC call yb.master.MasterService.GetTableSchema -> { remote: 127.0.0.1:7100 idx: 4 protocol: 0x000000010ccbeec0 -> tcp } , state=ON_OUTBOUND_QUEUE.) switching from ON_OUTBOUND_QUEUE to SENT
I0912 16:19:34.028988 123543552 reactor.cc:419] TabletServer_R006: timer tick at 32671.6
I0912 16:19:34.029467 123543552 tcp_stream.cc:242] { local: 127.0.0.1:50205 remote: 127.0.0.1:7100 }: Handler(revents=1)
I0912 16:19:34.029495 123543552 yb_rpc.cc:492] Connection (0x0000000111693e78) client 127.0.0.1:50205 => 127.0.0.1:7100: Updated last_read_time_=32671.616s
I0912 16:19:34.029554 123543552 outbound_call.cc:346] OutboundCall 0x1167e9620 (RPC call yb.master.MasterService.GetTableSchema -> { remote: 127.0.0.1:7100 idx: 4 protocol: 0x000000010ccbeec0 -> tcp } , state=SENT.) switching from SENT to FINISHED_SUCCESS
I0912 16:19:34.029651 126226432 partition.cc:148] Using multi-column hash value for partitioning
I0912 16:19:34.029698 126763008 rpc_controller.cc:48] RpcController 0x113456478 destroyed
I0912 16:19:34.029726 126763008 outbound_call.cc:220] OutboundCall 0x1167e9620 destroyed with state_: FINISHED_SUCCESS
I0912 16:19:34.029760 126763008 rpc_controller.cc:44] RpcController 0x7000078e3340 constructed
I0912 16:19:34.029780 126763008 outbound_call.cc:210] OutboundCall 0x1167e85a0 constructed with state_: READY and RPC timeout: 5.000s
I0912 16:19:34.029808 126763008 reactor.cc:695] TabletServer_R007: Queueing outbound call RPC call yb.master.MasterService.GetTableLocations -> { remote: 127.0.0.1:7100 idx: 5 protocol: 0x000000010ccbeec0 -> tcp } , state=READY. to remote 127.0.0.1:7100
I0912 16:19:34.029853 124080128 tcp_stream.cc:412] { local: 127.0.0.1:50206 remote: 127.0.0.1:7100 }: Added data queued_bytes_to_send_: 102
I0912 16:19:34.029871 124080128 outbound_call.cc:346] OutboundCall 0x1167e85a0 (RPC call yb.master.MasterService.GetTableLocations -> { remote: 127.0.0.1:7100 idx: 5 protocol: 0x000000010ccbeec0 -> tcp } , state=READY.) switching from READY to ON_OUTBOUND_QUEUE
I0912 16:19:34.029906 124080128 tcp_stream.cc:199] { local: 127.0.0.1:50206 remote: 127.0.0.1:7100 }: Queued writes 102 bytes. written 102 . Status OK sending_ .size() 1
I0912 16:19:34.029947 124080128 outbound_call.cc:346] OutboundCall 0x1167e85a0 (RPC call yb.master.MasterService.GetTableLocations -> { remote: 127.0.0.1:7100 idx: 5 protocol: 0x000000010ccbeec0 -> tcp } , state=ON_OUTBOUND_QUEUE.) switching from ON_OUTBOUND_QUEUE to SENT
I0912 16:19:34.030419 124080128 tcp_stream.cc:242] { local: 127.0.0.1:50206 remote: 127.0.0.1:7100 }: Handler(revents=1)
I0912 16:19:34.030448 124080128 yb_rpc.cc:492] Connection (0x0000000111ed6018) client 127.0.0.1:50206 => 127.0.0.1:7100: Updated last_read_time_=32671.616s
I0912 16:19:34.030511 124080128 outbound_call.cc:346] OutboundCall 0x1167e85a0 (RPC call yb.master.MasterService.GetTableLocations -> { remote: 127.0.0.1:7100 idx: 5 protocol: 0x000000010ccbeec0 -> tcp } , state=SENT.) switching from SENT to FINISHED_SUCCESS
I0912 16:19:34.030607 126763008 rpc_controller.cc:48] RpcController 0x7000078e3340 destroyed
I0912 16:19:34.030639 126763008 outbound_call.cc:220] OutboundCall 0x1167e85a0 destroyed with state_: FINISHED_SUCCESS
I0912 16:19:34.030658 126763008 table.cc:250] Open Table ybdemo.ugent, found 2 tablets
I0912 16:19:34.030702 126763008 analyzer.cc:62] Successfully analyzed parse-tree <0x112070780>
I0912 16:19:34.030755 126763008 inbound_call.cc:161] 0x00000001125cf9a0 -> CQL Call from 127.0.0.1:50223, stream id: 15: Completed handling


I0912 16:19:34.030807 155738112 tcp_stream.cc:412] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Added data queued_bytes_to_send_: 82
I0912 16:19:34.030841 155738112 tcp_stream.cc:199] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Queued writes 82 bytes. written 82 . Status OK sending_ .size() 1
I0912 16:19:34.082587 157347840 reactor.cc:419] CQLServer_R003: timer tick at 32671.7
I0912 16:19:34.082587 158957568 reactor.cc:419] CQLServer_R006: timer tick at 32671.7
I0912 16:19:34.086442 156274688 reactor.cc:419] CQLServer_R001: timer tick at 32671.7
I0912 16:19:34.086449 158420992 reactor.cc:419] CQLServer_R005: timer tick at 32671.7
I0912 16:19:34.086442 155738112 reactor.cc:419] CQLServer_R000: timer tick at 32671.7
I0912 16:19:34.086514 157884416 reactor.cc:419] CQLServer_R004: timer tick at 32671.7
I0912 16:19:34.086536 156811264 reactor.cc:419] CQLServer_R002: timer tick at 32671.7
I0912 16:19:34.086541 159494144 reactor.cc:419] CQLServer_R007: timer tick at 32671.7
I0912 16:19:34.088804 134275072 pending_op_counter.cc:27] [0x112f07a70] Update(1), result = 0
I0912 16:19:34.088855 134275072 pending_op_counter.cc:27] [0x112f07a70] Update(-1), result = 1
I0912 16:19:34.088894 134275072 log.cc:713] T 035fb48b34094449bb71ef08b4780082 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-035fb48b34094449bb71ef08b4780082 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.088953 134275072 pending_op_counter.cc:27] [0x1169ead70] Update(1), result = 0
I0912 16:19:34.088985 134275072 pending_op_counter.cc:27] [0x1169ead70] Update(-1), result = 1
I0912 16:19:34.089010 134275072 log.cc:713] T 03d367d55bdb460c9487c464716c01d6 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-03d367d55bdb460c9487c464716c01d6 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.089056 134275072 pending_op_counter.cc:27] [0x1124d3670] Update(1), result = 0
I0912 16:19:34.089082 134275072 pending_op_counter.cc:27] [0x1124d3670] Update(-1), result = 1
I0912 16:19:34.089104 134275072 log.cc:713] T 073b711cacbc4c2f95995624e5ad336e P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-073b711cacbc4c2f95995624e5ad336e would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.089143 134275072 pending_op_counter.cc:27] [0x11163e470] Update(1), result = 0
I0912 16:19:34.089175 134275072 pending_op_counter.cc:27] [0x11163e470] Update(-1), result = 1
I0912 16:19:34.089195 134275072 log.cc:713] T 08b598d95d724cbca88b8a60ed34b85a P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-08b598d95d724cbca88b8a60ed34b85a would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.089227 134275072 pending_op_counter.cc:27] [0x1169ed170] Update(1), result = 0
I0912 16:19:34.089251 134275072 pending_op_counter.cc:27] [0x1169ed170] Update(-1), result = 1
I0912 16:19:34.089272 134275072 log.cc:713] T 12922aefce5a42bcb18c43830c77dca1 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-12922aefce5a42bcb18c43830c77dca1 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.089309 134275072 pending_op_counter.cc:27] [0x111c03670] Update(1), result = 0
I0912 16:19:34.089341 134275072 pending_op_counter.cc:27] [0x111c03670] Update(-1), result = 1
I0912 16:19:34.089363 134275072 log.cc:713] T 13137c836a644ff2b4bdd63db67b9704 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-13137c836a644ff2b4bdd63db67b9704 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.089399 134275072 pending_op_counter.cc:27] [0x1114d7170] Update(1), result = 0
I0912 16:19:34.089424 134275072 pending_op_counter.cc:27] [0x1114d7170] Update(-1), result = 1
I0912 16:19:34.089447 134275072 log.cc:713] T 17689ffda26d470b8489eb5bb7a16e82 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-17689ffda26d470b8489eb5bb7a16e82 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.089483 134275072 pending_op_counter.cc:27] [0x1110f5a70] Update(1), result = 0
I0912 16:19:34.089501 134275072 pending_op_counter.cc:27] [0x1110f5a70] Update(-1), result = 1
I0912 16:19:34.089519 134275072 log.cc:713] T 183c0aa263ee4a9aa08fa89bedb0f7b1 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-183c0aa263ee4a9aa08fa89bedb0f7b1 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.089560 134275072 pending_op_counter.cc:27] [0x1124d5170] Update(1), result = 0
I0912 16:19:34.089591 134275072 pending_op_counter.cc:27] [0x1124d5170] Update(-1), result = 1
I0912 16:19:34.089614 134275072 log.cc:713] T 2532a180889c493f85c4f10f9c47f356 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-95366513ff3e4fdea873b5cfe89b6833/tablet-2532a180889c493f85c4f10f9c47f356 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.089656 134275072 pending_op_counter.cc:27] [0x1125a1170] Update(1), result = 0
I0912 16:19:34.089691 134275072 pending_op_counter.cc:27] [0x1125a1170] Update(-1), result = 1
I0912 16:19:34.089762 134275072 log.cc:721] T 5cb6bf51f3b846bca39de498c36cf7ed P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 1 more.
I0912 16:19:34.089871 134275072 pending_op_counter.cc:27] [0x111c02d70] Update(1), result = 0
I0912 16:19:34.089908 134275072 pending_op_counter.cc:27] [0x111c02d70] Update(-1), result = 1
I0912 16:19:34.089951 134275072 log.cc:721] T 6099edbd08f642969b507401ae27d704 P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 2 more.
I0912 16:19:34.090037 134275072 pending_op_counter.cc:27] [0x1110f3670] Update(1), result = 0
I0912 16:19:34.090139 134275072 pending_op_counter.cc:27] [0x1110f3670] Update(-1), result = 1
I0912 16:19:34.090191 134275072 log.cc:713] T 651f232843f24e489590d00ee424a6e2 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-651f232843f24e489590d00ee424a6e2 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.090270 134275072 pending_op_counter.cc:27] [0x1110f2470] Update(1), result = 0
I0912 16:19:34.090318 134275072 pending_op_counter.cc:27] [0x1110f2470] Update(-1), result = 1
I0912 16:19:34.090406 134275072 log.cc:713] T 717cc00d1f3342cbb949e9de76a45773 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-717cc00d1f3342cbb949e9de76a45773 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.090528 134275072 pending_op_counter.cc:27] [0x1110f3f70] Update(1), result = 0
I0912 16:19:34.090605 134275072 pending_op_counter.cc:27] [0x1110f3f70] Update(-1), result = 1
I0912 16:19:34.090667 134275072 log.cc:713] T 77f3d502f9a349b09e381fd4470d0ab3 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-77f3d502f9a349b09e381fd4470d0ab3 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.090754 134275072 pending_op_counter.cc:27] [0x1124d3f70] Update(1), result = 0
I0912 16:19:34.090801 134275072 pending_op_counter.cc:27] [0x1124d3f70] Update(-1), result = 1
I0912 16:19:34.090829 134275072 log.cc:713] T 7be598840fa74d5db5df8df5b1e4700b P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-7be598840fa74d5db5df8df5b1e4700b would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.090912 134275072 pending_op_counter.cc:27] [0x111640870] Update(1), result = 0
I0912 16:19:34.090965 134275072 pending_op_counter.cc:27] [0x111640870] Update(-1), result = 1
I0912 16:19:34.091023 134275072 log.cc:713] T 97f40d1eb3e34a40808cc45d931f2539 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-97f40d1eb3e34a40808cc45d931f2539 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.091128 134275072 pending_op_counter.cc:27] [0x112f06870] Update(1), result = 0
I0912 16:19:34.091157 134275072 pending_op_counter.cc:27] [0x112f06870] Update(-1), result = 1
I0912 16:19:34.091183 134275072 log.cc:713] T 9eb5691dc0484592aef43c023d345bc5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-9eb5691dc0484592aef43c023d345bc5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.091226 134275072 pending_op_counter.cc:27] [0x1124d5a70] Update(1), result = 0
I0912 16:19:34.091272 134275072 pending_op_counter.cc:27] [0x1124d5a70] Update(-1), result = 1
I0912 16:19:34.091308 134275072 log.cc:713] T a193ebdbeed44a79952d5dfaeb91ae56 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-a193ebdbeed44a79952d5dfaeb91ae56 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.091364 134275072 pending_op_counter.cc:27] [0x11a01a470] Update(1), result = 0
I0912 16:19:34.091395 134275072 pending_op_counter.cc:27] [0x11a01a470] Update(-1), result = 1
I0912 16:19:34.091447 134275072 log.cc:721] T a5da4a8052884d89b098c08c7ba70f05 P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 6 more.
I0912 16:19:34.091497 134275072 pending_op_counter.cc:27] [0x111c05170] Update(1), result = 0
I0912 16:19:34.091519 134275072 pending_op_counter.cc:27] [0x111c05170] Update(-1), result = 1
I0912 16:19:34.091538 134275072 log.cc:713] T ba061293b88e4aad985c958cfa7457be P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-ba061293b88e4aad985c958cfa7457be would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.091580 134275072 pending_op_counter.cc:27] [0x111c04870] Update(1), result = 0
I0912 16:19:34.091609 134275072 pending_op_counter.cc:27] [0x111c04870] Update(-1), result = 1
I0912 16:19:34.091634 134275072 log.cc:713] T bd21577525644226a997f20fa54b6607 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-bd21577525644226a997f20fa54b6607 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.091675 134275072 pending_op_counter.cc:27] [0x112f05f70] Update(1), result = 0
I0912 16:19:34.091696 134275072 pending_op_counter.cc:27] [0x112f05f70] Update(-1), result = 1
I0912 16:19:34.091724 134275072 log.cc:713] T c003928bcaca464fbbacfe036ce004d0 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-c003928bcaca464fbbacfe036ce004d0 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.091774 134275072 pending_op_counter.cc:27] [0x111e8b170] Update(1), result = 0
I0912 16:19:34.091807 134275072 pending_op_counter.cc:27] [0x111e8b170] Update(-1), result = 1
I0912 16:19:34.091837 134275072 log.cc:713] T c57d94aace8b47f0b55fe8a7ab4793e5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-c57d94aace8b47f0b55fe8a7ab4793e5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.091888 134275072 pending_op_counter.cc:27] [0x1133c3170] Update(1), result = 0
I0912 16:19:34.091915 134275072 pending_op_counter.cc:27] [0x1133c3170] Update(-1), result = 1
I0912 16:19:34.091949 134275072 log.cc:721] T c644169d7c6a4ee5b3cc1421e3377834 P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 3 more.
I0912 16:19:34.092001 134275072 pending_op_counter.cc:27] [0x112f04d70] Update(1), result = 0
I0912 16:19:34.092041 134275072 pending_op_counter.cc:27] [0x112f04d70] Update(-1), result = 1
I0912 16:19:34.092160 134275072 log.cc:713] T d1256ac738c540cd8216d2eea583300c P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-d1256ac738c540cd8216d2eea583300c would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.092258 134275072 pending_op_counter.cc:27] [0x1110f5170] Update(1), result = 0
I0912 16:19:34.092345 134275072 pending_op_counter.cc:27] [0x1110f5170] Update(-1), result = 1
I0912 16:19:34.092438 134275072 log.cc:713] T d4d795a2a63e483bbfecdba99d518d0c P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-d4d795a2a63e483bbfecdba99d518d0c would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.092532 134275072 pending_op_counter.cc:27] [0x1124d2470] Update(1), result = 0
I0912 16:19:34.092586 134275072 pending_op_counter.cc:27] [0x1124d2470] Update(-1), result = 1
I0912 16:19:34.092630 134275072 log.cc:713] T d9ad9c163fe648749c475274d35aacc5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-d9ad9c163fe648749c475274d35aacc5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.092715 134275072 pending_op_counter.cc:27] [0x1133c0d70] Update(1), result = 0
I0912 16:19:34.092763 134275072 pending_op_counter.cc:27] [0x1133c0d70] Update(-1), result = 1
I0912 16:19:34.092818 134275072 log.cc:713] T ded208625a4845019d15e5140ed49b35 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-ded208625a4845019d15e5140ed49b35 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.092890 134275072 pending_op_counter.cc:27] [0x111e89f70] Update(1), result = 0
I0912 16:19:34.092916 134275072 pending_op_counter.cc:27] [0x111e89f70] Update(-1), result = 1
I0912 16:19:34.092942 134275072 log.cc:713] T f22b0b72f82d43599f1c7a217e7f67c5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-f22b0b72f82d43599f1c7a217e7f67c5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.092983 134275072 pending_op_counter.cc:27] [0x1110f4870] Update(1), result = 0
I0912 16:19:34.093019 134275072 pending_op_counter.cc:27] [0x1110f4870] Update(-1), result = 1
I0912 16:19:34.093050 134275072 log.cc:713] T fbf4ef0df3dd428ea2a6f5a1a3d67dc8 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-95366513ff3e4fdea873b5cfe89b6833/tablet-fbf4ef0df3dd428ea2a6f5a1a3d67dc8 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.093088 134275072 maintenance_manager.cc:219] No maintenance operations look worth doing.
I0912 16:19:34.098250 138567680 reactor.cc:419] RedisServer_R001: timer tick at 32671.7
I0912 16:19:34.098249 146616320 reactor.cc:419] RedisServer_R004: timer tick at 32671.7
I0912 16:19:34.098250 147689472 reactor.cc:419] RedisServer_R006: timer tick at 32671.7
I0912 16:19:34.098251 147152896 reactor.cc:419] RedisServer_R005: timer tick at 32671.7
I0912 16:19:34.098340 138031104 reactor.cc:419] RedisServer_R000: timer tick at 32671.7
I0912 16:19:34.098356 141250560 reactor.cc:419] RedisServer_R002: timer tick at 32671.7
I0912 16:19:34.098368 148226048 reactor.cc:419] RedisServer_R007: timer tick at 32671.7
I0912 16:19:34.098404 138567680 reactor.cc:428] RedisServer_R001: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.098481 146616320 reactor.cc:428] RedisServer_R004: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.098532 147689472 reactor.cc:428] RedisServer_R006: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.098588 147152896 reactor.cc:428] RedisServer_R005: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.098660 138031104 reactor.cc:428] RedisServer_R000: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.098714 141250560 reactor.cc:428] RedisServer_R002: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.098793 148226048 reactor.cc:428] RedisServer_R007: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.098985 142860288 reactor.cc:419] RedisServer_R003: timer tick at 32671.7
I0912 16:19:34.099227 142860288 reactor.cc:428] RedisServer_R003: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.129016 122470400 reactor.cc:419] TabletServer_R004: timer tick at 32671.7
I0912 16:19:34.129016 121933824 reactor.cc:419] TabletServer_R003: timer tick at 32671.7
I0912 16:19:34.129020 120324096 reactor.cc:419] TabletServer_R000: timer tick at 32671.7
I0912 16:19:34.129016 120860672 reactor.cc:419] TabletServer_R001: timer tick at 32671.7
I0912 16:19:34.129021 124080128 reactor.cc:419] TabletServer_R007: timer tick at 32671.7
I0912 16:19:34.129021 123006976 reactor.cc:419] TabletServer_R005: timer tick at 32671.7
I0912 16:19:34.130509 121397248 reactor.cc:419] TabletServer_R002: timer tick at 32671.7
I0912 16:19:34.130534 123543552 reactor.cc:419] TabletServer_R006: timer tick at 32671.7
I0912 16:19:34.182411 157884416 reactor.cc:419] CQLServer_R004: timer tick at 32671.8
I0912 16:19:34.183240 156811264 reactor.cc:419] CQLServer_R002: timer tick at 32671.8
I0912 16:19:34.183240 156274688 reactor.cc:419] CQLServer_R001: timer tick at 32671.8
I0912 16:19:34.183244 158420992 reactor.cc:419] CQLServer_R005: timer tick at 32671.8
I0912 16:19:34.183241 157347840 reactor.cc:419] CQLServer_R003: timer tick at 32671.8
I0912 16:19:34.183279 155738112 reactor.cc:419] CQLServer_R000: timer tick at 32671.8
I0912 16:19:34.183285 158957568 reactor.cc:419] CQLServer_R006: timer tick at 32671.8
I0912 16:19:34.183405 159494144 reactor.cc:419] CQLServer_R007: timer tick at 32671.8
I0912 16:19:34.195057 138031104 reactor.cc:419] RedisServer_R000: timer tick at 32671.8
I0912 16:19:34.195188 138031104 reactor.cc:428] RedisServer_R000: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.198556 138567680 reactor.cc:419] RedisServer_R001: timer tick at 32671.8
I0912 16:19:34.198568 141250560 reactor.cc:419] RedisServer_R002: timer tick at 32671.8
I0912 16:19:34.198613 147689472 reactor.cc:419] RedisServer_R006: timer tick at 32671.8
I0912 16:19:34.198578 147152896 reactor.cc:419] RedisServer_R005: timer tick at 32671.8
I0912 16:19:34.198642 148226048 reactor.cc:419] RedisServer_R007: timer tick at 32671.8
I0912 16:19:34.198580 146616320 reactor.cc:419] RedisServer_R004: timer tick at 32671.8
I0912 16:19:34.198596 142860288 reactor.cc:419] RedisServer_R003: timer tick at 32671.8
I0912 16:19:34.198657 138567680 reactor.cc:428] RedisServer_R001: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.198722 141250560 reactor.cc:428] RedisServer_R002: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.198772 147689472 reactor.cc:428] RedisServer_R006: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.198825 147152896 reactor.cc:428] RedisServer_R005: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.198868 148226048 reactor.cc:428] RedisServer_R007: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.198920 146616320 reactor.cc:428] RedisServer_R004: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.198961 142860288 reactor.cc:428] RedisServer_R003: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.228461 120324096 reactor.cc:419] TabletServer_R000: timer tick at 32671.8
I0912 16:19:34.228667 121933824 reactor.cc:419] TabletServer_R003: timer tick at 32671.8
I0912 16:19:34.228662 122470400 reactor.cc:419] TabletServer_R004: timer tick at 32671.8
I0912 16:19:34.228665 120860672 reactor.cc:419] TabletServer_R001: timer tick at 32671.8
I0912 16:19:34.228665 121397248 reactor.cc:419] TabletServer_R002: timer tick at 32671.8
I0912 16:19:34.228693 123543552 reactor.cc:419] TabletServer_R006: timer tick at 32671.8
I0912 16:19:34.228695 123006976 reactor.cc:419] TabletServer_R005: timer tick at 32671.8
I0912 16:19:34.228710 124080128 reactor.cc:419] TabletServer_R007: timer tick at 32671.8
I0912 16:19:34.283145 156811264 reactor.cc:419] CQLServer_R002: timer tick at 32671.9
I0912 16:19:34.283164 155738112 reactor.cc:419] CQLServer_R000: timer tick at 32671.9
I0912 16:19:34.283144 157884416 reactor.cc:419] CQLServer_R004: timer tick at 32671.9
I0912 16:19:34.283144 157347840 reactor.cc:419] CQLServer_R003: timer tick at 32671.9
I0912 16:19:34.283144 156274688 reactor.cc:419] CQLServer_R001: timer tick at 32671.9
I0912 16:19:34.283145 158420992 reactor.cc:419] CQLServer_R005: timer tick at 32671.9
I0912 16:19:34.283229 158957568 reactor.cc:419] CQLServer_R006: timer tick at 32671.9
I0912 16:19:34.283224 159494144 reactor.cc:419] CQLServer_R007: timer tick at 32671.9
I0912 16:19:34.289211 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.295449 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.295485 148226048 reactor.cc:419] RedisServer_R007: timer tick at 32671.9
I0912 16:19:34.295513 148226048 reactor.cc:428] RedisServer_R007: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.295531 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.296058 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.296083 141250560 reactor.cc:419] RedisServer_R002: timer tick at 32671.9
I0912 16:19:34.296080 138031104 reactor.cc:419] RedisServer_R000: timer tick at 32671.9
I0912 16:19:34.296097 142860288 reactor.cc:419] RedisServer_R003: timer tick at 32671.9
I0912 16:19:34.296100 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.296102 138567680 reactor.cc:419] RedisServer_R001: timer tick at 32671.9
I0912 16:19:34.296113 146616320 reactor.cc:419] RedisServer_R004: timer tick at 32671.9
I0912 16:19:34.296119 147689472 reactor.cc:419] RedisServer_R006: timer tick at 32671.9
I0912 16:19:34.296121 141250560 reactor.cc:428] RedisServer_R002: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.296115 147152896 reactor.cc:419] RedisServer_R005: timer tick at 32671.9
I0912 16:19:34.296150 138031104 reactor.cc:428] RedisServer_R000: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.296202 142860288 reactor.cc:428] RedisServer_R003: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.296247 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.296260 138567680 reactor.cc:428] RedisServer_R001: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.296301 146616320 reactor.cc:428] RedisServer_R004: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.296341 147689472 reactor.cc:428] RedisServer_R006: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.296413 147152896 reactor.cc:428] RedisServer_R005: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.296553 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.311641 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.311666 133738496 heartbeater.cc:370] P c2dbcd03e21c48118c14d85feb2c5871: Sending an incremental tablet report to master...
I0912 16:19:34.311689 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.311741 133738496 rpc_controller.cc:44] RpcController 0x700007f8abd0 constructed
I0912 16:19:34.311744 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.311775 133738496 heartbeater.cc:443] P c2dbcd03e21c48118c14d85feb2c5871: Sending heartbeat:
common {
  ts_instance {
    permanent_uuid: "c2dbcd03e21c48118c14d85feb2c5871"
    instance_seqno: 1568294335069484
  }
}
tablet_report {
  is_incremental: true
  sequence_number: 90
}
num_live_tablets: 30
config_index: 0
leader_count: 30
I0912 16:19:34.311826 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.311874 133738496 outbound_call.cc:210] OutboundCall 0x11a7ea5a0 constructed with state_: READY and RPC timeout: 10.000s
I0912 16:19:34.311892 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.311970 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.311952 133738496 reactor.cc:695] TabletServer_R007: Queueing outbound call RPC call yb.master.MasterService.TSHeartbeat -> { remote: 127.0.0.1:7100 idx: 5 protocol: 0x000000010ccbeec0 -> tcp } , state=READY. to remote 127.0.0.1:7100
I0912 16:19:34.312012 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.312048 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.312053 124080128 tcp_stream.cc:412] { local: 127.0.0.1:50206 remote: 127.0.0.1:7100 }: Added data queued_bytes_to_send_: 111
I0912 16:19:34.312075 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.312096 124080128 outbound_call.cc:346] OutboundCall 0x11a7ea5a0 (RPC call yb.master.MasterService.TSHeartbeat -> { remote: 127.0.0.1:7100 idx: 5 protocol: 0x000000010ccbeec0 -> tcp } , state=READY.) switching from READY to ON_OUTBOUND_QUEUE
I0912 16:19:34.312165 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.312175 124080128 tcp_stream.cc:199] { local: 127.0.0.1:50206 remote: 127.0.0.1:7100 }: Queued writes 111 bytes. written 111 . Status OK sending_ .size() 1
I0912 16:19:34.312230 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.312237 124080128 outbound_call.cc:346] OutboundCall 0x11a7ea5a0 (RPC call yb.master.MasterService.TSHeartbeat -> { remote: 127.0.0.1:7100 idx: 5 protocol: 0x000000010ccbeec0 -> tcp } , state=ON_OUTBOUND_QUEUE.) switching from ON_OUTBOUND_QUEUE to SENT
I0912 16:19:34.312292 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.312840 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.312860 124080128 yb_rpc.cc:492] Connection (0x0000000111ed6018) client 127.0.0.1:50206 => 127.0.0.1:7100: Updated last_read_time_=32671.816s
I0912 16:19:34.312908 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.312961 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.312958 124080128 outbound_call.cc:346] OutboundCall 0x11a7ea5a0 (RPC call yb.master.MasterService.TSHeartbeat -> { remote: 127.0.0.1:7100 idx: 5 protocol: 0x000000010ccbeec0 -> tcp } , state=SENT.) switching from SENT to FINISHED_SUCCESS
I0912 16:19:34.313011 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.313046 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.313081 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.313060 133738496 heartbeater.cc:461] P c2dbcd03e21c48118c14d85feb2c5871: Received heartbeat response:
master_instance {
  permanent_uuid: "d479da0b32e6410cb8df219e33b41b90"
  instance_seqno: 1568294335013385
}
tablet_report {
}
leader_master: true
tservers {
  registration {
    common {
      private_rpc_addresses {
        host: "127.0.0.1"
        port: 9100
      }
      http_addresses {
        host: "127.0.0.1"
        port: 9000
      }
      cloud_info {
        placement_cloud: "cloud1"
        placement_region: "datacenter1"
        placement_zone: "rack1"
      }
      placement_uuid: ""
    }
    capabilities: 2189743739
  }
  tserver_instance {
    permanent_uuid: "c2dbcd03e21c48118c14d85feb2c5871"
    instance_seqno: 1568294335069484
  }
}
ysql_catalog_version: 0
I0912 16:19:34.313119 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.313166 133738496 rpc_controller.cc:48] RpcController 0x700007f8abd0 destroyed
I0912 16:19:34.313184 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.313210 133738496 outbound_call.cc:220] OutboundCall 0x11a7ea5a0 destroyed with state_: FINISHED_SUCCESS
I0912 16:19:34.313277 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.328375 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.328418 120324096 reactor.cc:419] TabletServer_R000: timer tick at 32671.9
I0912 16:19:34.328526 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.328698 121397248 reactor.cc:419] TabletServer_R002: timer tick at 32671.9
I0912 16:19:34.328698 120860672 reactor.cc:419] TabletServer_R001: timer tick at 32671.9
I0912 16:19:34.328722 123006976 reactor.cc:419] TabletServer_R005: timer tick at 32671.9
I0912 16:19:34.328722 122470400 reactor.cc:419] TabletServer_R004: timer tick at 32671.9
I0912 16:19:34.328738 123543552 reactor.cc:419] TabletServer_R006: timer tick at 32671.9
I0912 16:19:34.328743 124080128 reactor.cc:419] TabletServer_R007: timer tick at 32671.9
I0912 16:19:34.328773 121933824 reactor.cc:419] TabletServer_R003: timer tick at 32671.9
I0912 16:19:34.328872 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.344337 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.344329 134275072 pending_op_counter.cc:27] [0x112f07a70] Update(1), result = 0
I0912 16:19:34.344441 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.344487 134275072 pending_op_counter.cc:27] [0x112f07a70] Update(-1), result = 1
I0912 16:19:34.344576 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.344648 134275072 log.cc:713] T 035fb48b34094449bb71ef08b4780082 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-035fb48b34094449bb71ef08b4780082 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.344758 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.344749 134275072 pending_op_counter.cc:27] [0x1169ead70] Update(1), result = 0
I0912 16:19:34.344866 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.344872 134275072 pending_op_counter.cc:27] [0x1169ead70] Update(-1), result = 1
I0912 16:19:34.344965 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.344972 134275072 log.cc:713] T 03d367d55bdb460c9487c464716c01d6 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-03d367d55bdb460c9487c464716c01d6 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.345089 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.345100 134275072 pending_op_counter.cc:27] [0x1124d3670] Update(1), result = 0
I0912 16:19:34.345202 134275072 pending_op_counter.cc:27] [0x1124d3670] Update(-1), result = 1
I0912 16:19:34.345218 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.345300 134275072 log.cc:713] T 073b711cacbc4c2f95995624e5ad336e P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-073b711cacbc4c2f95995624e5ad336e would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.345352 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.345383 134275072 pending_op_counter.cc:27] [0x11163e470] Update(1), result = 0
I0912 16:19:34.345417 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.345422 134275072 pending_op_counter.cc:27] [0x11163e470] Update(-1), result = 1
I0912 16:19:34.345527 134275072 log.cc:713] T 08b598d95d724cbca88b8a60ed34b85a P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-08b598d95d724cbca88b8a60ed34b85a would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.345587 134275072 pending_op_counter.cc:27] [0x1169ed170] Update(1), result = 0
I0912 16:19:34.345618 134275072 pending_op_counter.cc:27] [0x1169ed170] Update(-1), result = 1
I0912 16:19:34.345652 134275072 log.cc:713] T 12922aefce5a42bcb18c43830c77dca1 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-12922aefce5a42bcb18c43830c77dca1 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.345700 134275072 pending_op_counter.cc:27] [0x111c03670] Update(1), result = 0
I0912 16:19:34.345722 134275072 pending_op_counter.cc:27] [0x111c03670] Update(-1), result = 1
I0912 16:19:34.345739 134275072 log.cc:713] T 13137c836a644ff2b4bdd63db67b9704 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-13137c836a644ff2b4bdd63db67b9704 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.345769 134275072 pending_op_counter.cc:27] [0x1114d7170] Update(1), result = 0
I0912 16:19:34.345793 134275072 pending_op_counter.cc:27] [0x1114d7170] Update(-1), result = 1
I0912 16:19:34.345818 134275072 log.cc:713] T 17689ffda26d470b8489eb5bb7a16e82 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-17689ffda26d470b8489eb5bb7a16e82 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.345849 134275072 pending_op_counter.cc:27] [0x1110f5a70] Update(1), result = 0
I0912 16:19:34.345868 134275072 pending_op_counter.cc:27] [0x1110f5a70] Update(-1), result = 1
I0912 16:19:34.345883 134275072 log.cc:713] T 183c0aa263ee4a9aa08fa89bedb0f7b1 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-183c0aa263ee4a9aa08fa89bedb0f7b1 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.345911 134275072 pending_op_counter.cc:27] [0x1124d5170] Update(1), result = 0
I0912 16:19:34.345934 134275072 pending_op_counter.cc:27] [0x1124d5170] Update(-1), result = 1
I0912 16:19:34.345949 134275072 log.cc:713] T 2532a180889c493f85c4f10f9c47f356 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-95366513ff3e4fdea873b5cfe89b6833/tablet-2532a180889c493f85c4f10f9c47f356 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.345999 134275072 pending_op_counter.cc:27] [0x1125a1170] Update(1), result = 0
I0912 16:19:34.346019 134275072 pending_op_counter.cc:27] [0x1125a1170] Update(-1), result = 1
I0912 16:19:34.346033 134275072 log.cc:721] T 5cb6bf51f3b846bca39de498c36cf7ed P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 1 more.
I0912 16:19:34.346060 134275072 pending_op_counter.cc:27] [0x111c02d70] Update(1), result = 0
I0912 16:19:34.346076 134275072 pending_op_counter.cc:27] [0x111c02d70] Update(-1), result = 1
I0912 16:19:34.346089 134275072 log.cc:721] T 6099edbd08f642969b507401ae27d704 P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 2 more.
I0912 16:19:34.346112 134275072 pending_op_counter.cc:27] [0x1110f3670] Update(1), result = 0
I0912 16:19:34.346127 134275072 pending_op_counter.cc:27] [0x1110f3670] Update(-1), result = 1
I0912 16:19:34.346143 134275072 log.cc:713] T 651f232843f24e489590d00ee424a6e2 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-651f232843f24e489590d00ee424a6e2 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.346166 134275072 pending_op_counter.cc:27] [0x1110f2470] Update(1), result = 0
I0912 16:19:34.346181 134275072 pending_op_counter.cc:27] [0x1110f2470] Update(-1), result = 1
I0912 16:19:34.346194 134275072 log.cc:713] T 717cc00d1f3342cbb949e9de76a45773 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-717cc00d1f3342cbb949e9de76a45773 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.346216 134275072 pending_op_counter.cc:27] [0x1110f3f70] Update(1), result = 0
I0912 16:19:34.346231 134275072 pending_op_counter.cc:27] [0x1110f3f70] Update(-1), result = 1
I0912 16:19:34.346244 134275072 log.cc:713] T 77f3d502f9a349b09e381fd4470d0ab3 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-77f3d502f9a349b09e381fd4470d0ab3 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.346268 134275072 pending_op_counter.cc:27] [0x1124d3f70] Update(1), result = 0
I0912 16:19:34.346282 134275072 pending_op_counter.cc:27] [0x1124d3f70] Update(-1), result = 1
I0912 16:19:34.346297 134275072 log.cc:713] T 7be598840fa74d5db5df8df5b1e4700b P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-7be598840fa74d5db5df8df5b1e4700b would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.346320 134275072 pending_op_counter.cc:27] [0x111640870] Update(1), result = 0
I0912 16:19:34.346335 134275072 pending_op_counter.cc:27] [0x111640870] Update(-1), result = 1
I0912 16:19:34.346349 134275072 log.cc:713] T 97f40d1eb3e34a40808cc45d931f2539 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-97f40d1eb3e34a40808cc45d931f2539 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.346372 134275072 pending_op_counter.cc:27] [0x112f06870] Update(1), result = 0
I0912 16:19:34.346387 134275072 pending_op_counter.cc:27] [0x112f06870] Update(-1), result = 1
I0912 16:19:34.346401 134275072 log.cc:713] T 9eb5691dc0484592aef43c023d345bc5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-9eb5691dc0484592aef43c023d345bc5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.346426 134275072 pending_op_counter.cc:27] [0x1124d5a70] Update(1), result = 0
I0912 16:19:34.346442 134275072 pending_op_counter.cc:27] [0x1124d5a70] Update(-1), result = 1
I0912 16:19:34.346457 134275072 log.cc:713] T a193ebdbeed44a79952d5dfaeb91ae56 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-a193ebdbeed44a79952d5dfaeb91ae56 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.346463 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.346480 134275072 pending_op_counter.cc:27] [0x11a01a470] Update(1), result = 0
I0912 16:19:34.346531 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.346546 134275072 pending_op_counter.cc:27] [0x11a01a470] Update(-1), result = 1
I0912 16:19:34.346580 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.346588 134275072 log.cc:721] T a5da4a8052884d89b098c08c7ba70f05 P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 6 more.
I0912 16:19:34.346616 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.346660 134275072 pending_op_counter.cc:27] [0x111c05170] Update(1), result = 0
I0912 16:19:34.346722 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.346746 134275072 pending_op_counter.cc:27] [0x111c05170] Update(-1), result = 1
I0912 16:19:34.346784 134275072 log.cc:713] T ba061293b88e4aad985c958cfa7457be P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-ba061293b88e4aad985c958cfa7457be would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.346791 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.346837 134275072 pending_op_counter.cc:27] [0x111c04870] Update(1), result = 0
I0912 16:19:34.346892 134275072 pending_op_counter.cc:27] [0x111c04870] Update(-1), result = 1
I0912 16:19:34.346926 134275072 log.cc:713] T bd21577525644226a997f20fa54b6607 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-bd21577525644226a997f20fa54b6607 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.346900 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.346971 134275072 pending_op_counter.cc:27] [0x112f05f70] Update(1), result = 0
I0912 16:19:34.347012 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.347012 134275072 pending_op_counter.cc:27] [0x112f05f70] Update(-1), result = 1
I0912 16:19:34.347092 134275072 log.cc:713] T c003928bcaca464fbbacfe036ce004d0 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-c003928bcaca464fbbacfe036ce004d0 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.347107 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.347139 134275072 pending_op_counter.cc:27] [0x111e8b170] Update(1), result = 0
I0912 16:19:34.347203 134275072 pending_op_counter.cc:27] [0x111e8b170] Update(-1), result = 1
I0912 16:19:34.347220 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.347246 134275072 log.cc:713] T c57d94aace8b47f0b55fe8a7ab4793e5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-c57d94aace8b47f0b55fe8a7ab4793e5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.347321 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.347333 134275072 pending_op_counter.cc:27] [0x1133c3170] Update(1), result = 0
I0912 16:19:34.347393 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.347393 134275072 pending_op_counter.cc:27] [0x1133c3170] Update(-1), result = 1
I0912 16:19:34.347458 134275072 log.cc:721] T c644169d7c6a4ee5b3cc1421e3377834 P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 3 more.
I0912 16:19:34.347465 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.347512 134275072 pending_op_counter.cc:27] [0x112f04d70] Update(1), result = 0
I0912 16:19:34.347568 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.347569 134275072 pending_op_counter.cc:27] [0x112f04d70] Update(-1), result = 1
I0912 16:19:34.347630 134275072 log.cc:713] T d1256ac738c540cd8216d2eea583300c P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-d1256ac738c540cd8216d2eea583300c would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.347640 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.347672 134275072 pending_op_counter.cc:27] [0x1110f5170] Update(1), result = 0
I0912 16:19:34.347733 134275072 pending_op_counter.cc:27] [0x1110f5170] Update(-1), result = 1
I0912 16:19:34.347764 134275072 log.cc:713] T d4d795a2a63e483bbfecdba99d518d0c P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-d4d795a2a63e483bbfecdba99d518d0c would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.347769 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.347815 134275072 pending_op_counter.cc:27] [0x1124d2470] Update(1), result = 0
I0912 16:19:34.347847 134275072 pending_op_counter.cc:27] [0x1124d2470] Update(-1), result = 1
I0912 16:19:34.347869 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.347874 134275072 log.cc:713] T d9ad9c163fe648749c475274d35aacc5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-d9ad9c163fe648749c475274d35aacc5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.347929 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.347971 134275072 pending_op_counter.cc:27] [0x1133c0d70] Update(1), result = 0
I0912 16:19:34.348001 134275072 pending_op_counter.cc:27] [0x1133c0d70] Update(-1), result = 1
I0912 16:19:34.348021 134275072 log.cc:713] T ded208625a4845019d15e5140ed49b35 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-ded208625a4845019d15e5140ed49b35 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.348042 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.348057 134275072 pending_op_counter.cc:27] [0x111e89f70] Update(1), result = 0
I0912 16:19:34.348094 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.348119 134275072 pending_op_counter.cc:27] [0x111e89f70] Update(-1), result = 1
I0912 16:19:34.348170 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.348196 134275072 log.cc:713] T f22b0b72f82d43599f1c7a217e7f67c5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-f22b0b72f82d43599f1c7a217e7f67c5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.348239 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.348294 134275072 pending_op_counter.cc:27] [0x1110f4870] Update(1), result = 0
I0912 16:19:34.348327 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.348340 134275072 pending_op_counter.cc:27] [0x1110f4870] Update(-1), result = 1
I0912 16:19:34.348373 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.348414 134275072 log.cc:713] T fbf4ef0df3dd428ea2a6f5a1a3d67dc8 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-95366513ff3e4fdea873b5cfe89b6833/tablet-fbf4ef0df3dd428ea2a6f5a1a3d67dc8 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.348537 134275072 maintenance_manager.cc:219] No maintenance operations look worth doing.
I0912 16:19:34.348567 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.381386 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.381492 155738112 reactor.cc:419] CQLServer_R000: timer tick at 32672
I0912 16:19:34.381551 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.385753 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.385812 157347840 reactor.cc:419] CQLServer_R003: timer tick at 32672
I0912 16:19:34.385812 156274688 reactor.cc:419] CQLServer_R001: timer tick at 32672
I0912 16:19:34.385816 157884416 reactor.cc:419] CQLServer_R004: timer tick at 32672
I0912 16:19:34.385833 156811264 reactor.cc:419] CQLServer_R002: timer tick at 32672
I0912 16:19:34.385869 158957568 reactor.cc:419] CQLServer_R006: timer tick at 32672
I0912 16:19:34.385879 158420992 reactor.cc:419] CQLServer_R005: timer tick at 32672
I0912 16:19:34.385887 159494144 reactor.cc:419] CQLServer_R007: timer tick at 32672
I0912 16:19:34.385937 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.396960 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.396981 138031104 reactor.cc:419] RedisServer_R000: timer tick at 32672
I0912 16:19:34.396988 141250560 reactor.cc:419] RedisServer_R002: timer tick at 32672
I0912 16:19:34.396988 146616320 reactor.cc:419] RedisServer_R004: timer tick at 32672
I0912 16:19:34.396989 142860288 reactor.cc:419] RedisServer_R003: timer tick at 32672
I0912 16:19:34.396993 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.397011 138567680 reactor.cc:419] RedisServer_R001: timer tick at 32672
I0912 16:19:34.397013 147152896 reactor.cc:419] RedisServer_R005: timer tick at 32672
I0912 16:19:34.397018 138031104 reactor.cc:428] RedisServer_R000: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.397027 148226048 reactor.cc:419] RedisServer_R007: timer tick at 32672
I0912 16:19:34.397017 147689472 reactor.cc:419] RedisServer_R006: timer tick at 32672
I0912 16:19:34.397053 141250560 reactor.cc:428] RedisServer_R002: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.397083 146616320 reactor.cc:428] RedisServer_R004: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.397111 142860288 reactor.cc:428] RedisServer_R003: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.397173 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.397183 138567680 reactor.cc:428] RedisServer_R001: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.397204 147152896 reactor.cc:428] RedisServer_R005: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.397253 148226048 reactor.cc:428] RedisServer_R007: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.397282 147689472 reactor.cc:428] RedisServer_R006: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.397402 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.428659 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.428669 123006976 reactor.cc:419] TabletServer_R005: timer tick at 32672
I0912 16:19:34.428735 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.428747 123543552 reactor.cc:419] TabletServer_R006: timer tick at 32672
I0912 16:19:34.428787 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.431934 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.431936 120860672 reactor.cc:419] TabletServer_R001: timer tick at 32672
I0912 16:19:34.431936 121933824 reactor.cc:419] TabletServer_R003: timer tick at 32672
I0912 16:19:34.431947 121397248 reactor.cc:419] TabletServer_R002: timer tick at 32672
I0912 16:19:34.431962 124080128 reactor.cc:419] TabletServer_R007: timer tick at 32672
I0912 16:19:34.431970 122470400 reactor.cc:419] TabletServer_R004: timer tick at 32672
I0912 16:19:34.431957 120324096 reactor.cc:419] TabletServer_R000: timer tick at 32672
I0912 16:19:34.432029 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.481397 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.481439 155738112 reactor.cc:419] CQLServer_R000: timer tick at 32672.1
I0912 16:19:34.481470 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.482199 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.482242 157884416 reactor.cc:419] CQLServer_R004: timer tick at 32672.1
I0912 16:19:34.482268 156811264 reactor.cc:419] CQLServer_R002: timer tick at 32672.1
I0912 16:19:34.482268 156274688 reactor.cc:419] CQLServer_R001: timer tick at 32672.1
I0912 16:19:34.482286 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.482293 157347840 reactor.cc:419] CQLServer_R003: timer tick at 32672.1
I0912 16:19:34.482390 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.483049 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.483052 158420992 reactor.cc:419] CQLServer_R005: timer tick at 32672.1
I0912 16:19:34.483108 158957568 reactor.cc:419] CQLServer_R006: timer tick at 32672.1
I0912 16:19:34.483119 159494144 reactor.cc:419] CQLServer_R007: timer tick at 32672.1
I0912 16:19:34.483120 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.493716 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.493809 138567680 reactor.cc:419] RedisServer_R001: timer tick at 32672.1
I0912 16:19:34.493804 141250560 reactor.cc:419] RedisServer_R002: timer tick at 32672.1
I0912 16:19:34.493809 138031104 reactor.cc:419] RedisServer_R000: timer tick at 32672.1
I0912 16:19:34.493845 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.493877 138567680 reactor.cc:428] RedisServer_R001: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.493924 141250560 reactor.cc:428] RedisServer_R002: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.493983 138031104 reactor.cc:428] RedisServer_R000: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.493988 142860288 reactor.cc:419] RedisServer_R003: timer tick at 32672.1
I0912 16:19:34.494055 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.494200 142860288 reactor.cc:428] RedisServer_R003: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.494213 146616320 reactor.cc:419] RedisServer_R004: timer tick at 32672.1
I0912 16:19:34.494313 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.494350 146616320 reactor.cc:428] RedisServer_R004: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.494424 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.494487 147152896 reactor.cc:419] RedisServer_R005: timer tick at 32672.1
I0912 16:19:34.494491 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.494540 147152896 reactor.cc:428] RedisServer_R005: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.494635 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.494693 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.494722 147689472 reactor.cc:419] RedisServer_R006: timer tick at 32672.1
I0912 16:19:34.494740 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.494765 147689472 reactor.cc:428] RedisServer_R006: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.494859 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.494884 148226048 reactor.cc:419] RedisServer_R007: timer tick at 32672.1
I0912 16:19:34.494925 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.494940 148226048 reactor.cc:428] RedisServer_R007: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.495016 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.528401 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.528407 120324096 reactor.cc:419] TabletServer_R000: timer tick at 32672.1
I0912 16:19:34.528496 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.528585 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.528594 121397248 reactor.cc:419] TabletServer_R002: timer tick at 32672.1
I0912 16:19:34.528635 121933824 reactor.cc:419] TabletServer_R003: timer tick at 32672.1
I0912 16:19:34.528637 120860672 reactor.cc:419] TabletServer_R001: timer tick at 32672.1
I0912 16:19:34.528652 122470400 reactor.cc:419] TabletServer_R004: timer tick at 32672.1
I0912 16:19:34.528666 123006976 reactor.cc:419] TabletServer_R005: timer tick at 32672.1
I0912 16:19:34.528676 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.528695 123543552 reactor.cc:419] TabletServer_R006: timer tick at 32672.1
I0912 16:19:34.528731 124080128 reactor.cc:419] TabletServer_R007: timer tick at 32672.1
I0912 16:19:34.528852 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.581348 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.581466 155738112 reactor.cc:419] CQLServer_R000: timer tick at 32672.2
I0912 16:19:34.581519 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.584924 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.585059 158420992 reactor.cc:419] CQLServer_R005: timer tick at 32672.2
I0912 16:19:34.585058 157347840 reactor.cc:419] CQLServer_R003: timer tick at 32672.2
I0912 16:19:34.585062 157884416 reactor.cc:419] CQLServer_R004: timer tick at 32672.2
I0912 16:19:34.585064 156811264 reactor.cc:419] CQLServer_R002: timer tick at 32672.2
I0912 16:19:34.585064 156274688 reactor.cc:419] CQLServer_R001: timer tick at 32672.2
I0912 16:19:34.585081 158957568 reactor.cc:419] CQLServer_R006: timer tick at 32672.2
I0912 16:19:34.585083 159494144 reactor.cc:419] CQLServer_R007: timer tick at 32672.2
I0912 16:19:34.585891 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.597879 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.597878 138031104 reactor.cc:419] RedisServer_R000: timer tick at 32672.2
I0912 16:19:34.597926 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.597946 138031104 reactor.cc:428] RedisServer_R000: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.597993 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.598378 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.598500 147152896 reactor.cc:419] RedisServer_R005: timer tick at 32672.2
I0912 16:19:34.598502 141250560 reactor.cc:419] RedisServer_R002: timer tick at 32672.2
I0912 16:19:34.598506 142860288 reactor.cc:419] RedisServer_R003: timer tick at 32672.2
I0912 16:19:34.598523 146616320 reactor.cc:419] RedisServer_R004: timer tick at 32672.2
I0912 16:19:34.598532 138567680 reactor.cc:419] RedisServer_R001: timer tick at 32672.2
I0912 16:19:34.598553 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.598556 147689472 reactor.cc:419] RedisServer_R006: timer tick at 32672.2
I0912 16:19:34.598562 148226048 reactor.cc:419] RedisServer_R007: timer tick at 32672.2
I0912 16:19:34.598598 147152896 reactor.cc:428] RedisServer_R005: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.598655 141250560 reactor.cc:428] RedisServer_R002: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.598660 142860288 reactor.cc:428] RedisServer_R003: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.598704 146616320 reactor.cc:428] RedisServer_R004: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.598743 138567680 reactor.cc:428] RedisServer_R001: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.598811 147689472 reactor.cc:428] RedisServer_R006: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.598803 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.598847 148226048 reactor.cc:428] RedisServer_R007: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.599131 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.602015 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.602048 134275072 pending_op_counter.cc:27] [0x112f07a70] Update(1), result = 0
I0912 16:19:34.602092 134275072 pending_op_counter.cc:27] [0x112f07a70] Update(-1), result = 1
I0912 16:19:34.602100 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.602131 134275072 log.cc:713] T 035fb48b34094449bb71ef08b4780082 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-035fb48b34094449bb71ef08b4780082 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.602193 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.602236 134275072 pending_op_counter.cc:27] [0x1169ead70] Update(1), result = 0
I0912 16:19:34.602283 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.602329 134275072 pending_op_counter.cc:27] [0x1169ead70] Update(-1), result = 1
I0912 16:19:34.602411 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.602459 134275072 log.cc:713] T 03d367d55bdb460c9487c464716c01d6 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-03d367d55bdb460c9487c464716c01d6 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.602532 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.602596 134275072 pending_op_counter.cc:27] [0x1124d3670] Update(1), result = 0
I0912 16:19:34.602618 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.602644 134275072 pending_op_counter.cc:27] [0x1124d3670] Update(-1), result = 1
I0912 16:19:34.602749 134275072 log.cc:713] T 073b711cacbc4c2f95995624e5ad336e P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-073b711cacbc4c2f95995624e5ad336e would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.602766 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.602802 134275072 pending_op_counter.cc:27] [0x11163e470] Update(1), result = 0
I0912 16:19:34.602818 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.602846 134275072 pending_op_counter.cc:27] [0x11163e470] Update(-1), result = 1
I0912 16:19:34.602900 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.602931 134275072 log.cc:713] T 08b598d95d724cbca88b8a60ed34b85a P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-08b598d95d724cbca88b8a60ed34b85a would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.602990 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603077 134275072 pending_op_counter.cc:27] [0x1169ed170] Update(1), result = 0
I0912 16:19:34.603101 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603111 134275072 pending_op_counter.cc:27] [0x1169ed170] Update(-1), result = 1
I0912 16:19:34.603160 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603165 134275072 log.cc:713] T 12922aefce5a42bcb18c43830c77dca1 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-12922aefce5a42bcb18c43830c77dca1 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.603212 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603274 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603282 134275072 pending_op_counter.cc:27] [0x111c03670] Update(1), result = 0
I0912 16:19:34.603317 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603350 134275072 pending_op_counter.cc:27] [0x111c03670] Update(-1), result = 1
I0912 16:19:34.603395 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603404 134275072 log.cc:713] T 13137c836a644ff2b4bdd63db67b9704 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-13137c836a644ff2b4bdd63db67b9704 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.603440 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603468 134275072 pending_op_counter.cc:27] [0x1114d7170] Update(1), result = 0
I0912 16:19:34.603479 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603500 134275072 pending_op_counter.cc:27] [0x1114d7170] Update(-1), result = 1
I0912 16:19:34.603546 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603605 134275072 log.cc:713] T 17689ffda26d470b8489eb5bb7a16e82 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-17689ffda26d470b8489eb5bb7a16e82 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.603637 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603658 134275072 pending_op_counter.cc:27] [0x1110f5a70] Update(1), result = 0
I0912 16:19:34.603698 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603705 134275072 pending_op_counter.cc:27] [0x1110f5a70] Update(-1), result = 1
I0912 16:19:34.603761 134275072 log.cc:713] T 183c0aa263ee4a9aa08fa89bedb0f7b1 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-183c0aa263ee4a9aa08fa89bedb0f7b1 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.603775 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603811 134275072 pending_op_counter.cc:27] [0x1124d5170] Update(1), result = 0
I0912 16:19:34.603883 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603893 134275072 pending_op_counter.cc:27] [0x1124d5170] Update(-1), result = 1
I0912 16:19:34.603930 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.603940 134275072 log.cc:713] T 2532a180889c493f85c4f10f9c47f356 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-95366513ff3e4fdea873b5cfe89b6833/tablet-2532a180889c493f85c4f10f9c47f356 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.603974 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604003 134275072 pending_op_counter.cc:27] [0x1125a1170] Update(1), result = 0
I0912 16:19:34.604023 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604033 134275072 pending_op_counter.cc:27] [0x1125a1170] Update(-1), result = 1
I0912 16:19:34.604065 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604074 134275072 log.cc:721] T 5cb6bf51f3b846bca39de498c36cf7ed P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 1 more.
I0912 16:19:34.604118 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604166 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604184 134275072 pending_op_counter.cc:27] [0x111c02d70] Update(1), result = 0
I0912 16:19:34.604223 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604230 134275072 pending_op_counter.cc:27] [0x111c02d70] Update(-1), result = 1
I0912 16:19:34.604267 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604286 134275072 log.cc:721] T 6099edbd08f642969b507401ae27d704 P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 2 more.
I0912 16:19:34.604351 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604379 134275072 pending_op_counter.cc:27] [0x1110f3670] Update(1), result = 0
I0912 16:19:34.604400 134275072 pending_op_counter.cc:27] [0x1110f3670] Update(-1), result = 1
I0912 16:19:34.604403 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604420 134275072 log.cc:713] T 651f232843f24e489590d00ee424a6e2 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-651f232843f24e489590d00ee424a6e2 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.604470 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604502 134275072 pending_op_counter.cc:27] [0x1110f2470] Update(1), result = 0
I0912 16:19:34.604526 134275072 pending_op_counter.cc:27] [0x1110f2470] Update(-1), result = 1
I0912 16:19:34.604548 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604553 134275072 log.cc:713] T 717cc00d1f3342cbb949e9de76a45773 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-717cc00d1f3342cbb949e9de76a45773 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.604637 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604648 134275072 pending_op_counter.cc:27] [0x1110f3f70] Update(1), result = 0
I0912 16:19:34.604694 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604714 134275072 pending_op_counter.cc:27] [0x1110f3f70] Update(-1), result = 1
I0912 16:19:34.604770 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604782 134275072 log.cc:713] T 77f3d502f9a349b09e381fd4470d0ab3 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-77f3d502f9a349b09e381fd4470d0ab3 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.604811 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.604874 134275072 pending_op_counter.cc:27] [0x1124d3f70] Update(1), result = 0
I0912 16:19:34.604957 134275072 pending_op_counter.cc:27] [0x1124d3f70] Update(-1), result = 1
I0912 16:19:34.604981 134275072 log.cc:713] T 7be598840fa74d5db5df8df5b1e4700b P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-7be598840fa74d5db5df8df5b1e4700b would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605012 134275072 pending_op_counter.cc:27] [0x111640870] Update(1), result = 0
I0912 16:19:34.605031 134275072 pending_op_counter.cc:27] [0x111640870] Update(-1), result = 1
I0912 16:19:34.605046 134275072 log.cc:713] T 97f40d1eb3e34a40808cc45d931f2539 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-97f40d1eb3e34a40808cc45d931f2539 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605080 134275072 pending_op_counter.cc:27] [0x112f06870] Update(1), result = 0
I0912 16:19:34.605098 134275072 pending_op_counter.cc:27] [0x112f06870] Update(-1), result = 1
I0912 16:19:34.605113 134275072 log.cc:713] T 9eb5691dc0484592aef43c023d345bc5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-9eb5691dc0484592aef43c023d345bc5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605137 134275072 pending_op_counter.cc:27] [0x1124d5a70] Update(1), result = 0
I0912 16:19:34.605154 134275072 pending_op_counter.cc:27] [0x1124d5a70] Update(-1), result = 1
I0912 16:19:34.605168 134275072 log.cc:713] T a193ebdbeed44a79952d5dfaeb91ae56 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-a193ebdbeed44a79952d5dfaeb91ae56 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605208 134275072 pending_op_counter.cc:27] [0x11a01a470] Update(1), result = 0
I0912 16:19:34.605253 134275072 pending_op_counter.cc:27] [0x11a01a470] Update(-1), result = 1
I0912 16:19:34.605283 134275072 log.cc:721] T a5da4a8052884d89b098c08c7ba70f05 P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 6 more.
I0912 16:19:34.605324 134275072 pending_op_counter.cc:27] [0x111c05170] Update(1), result = 0
I0912 16:19:34.605345 134275072 pending_op_counter.cc:27] [0x111c05170] Update(-1), result = 1
I0912 16:19:34.605361 134275072 log.cc:713] T ba061293b88e4aad985c958cfa7457be P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-ba061293b88e4aad985c958cfa7457be would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605389 134275072 pending_op_counter.cc:27] [0x111c04870] Update(1), result = 0
I0912 16:19:34.605406 134275072 pending_op_counter.cc:27] [0x111c04870] Update(-1), result = 1
I0912 16:19:34.605419 134275072 log.cc:713] T bd21577525644226a997f20fa54b6607 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-bd21577525644226a997f20fa54b6607 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605446 134275072 pending_op_counter.cc:27] [0x112f05f70] Update(1), result = 0
I0912 16:19:34.605463 134275072 pending_op_counter.cc:27] [0x112f05f70] Update(-1), result = 1
I0912 16:19:34.605477 134275072 log.cc:713] T c003928bcaca464fbbacfe036ce004d0 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-c003928bcaca464fbbacfe036ce004d0 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605501 134275072 pending_op_counter.cc:27] [0x111e8b170] Update(1), result = 0
I0912 16:19:34.605516 134275072 pending_op_counter.cc:27] [0x111e8b170] Update(-1), result = 1
I0912 16:19:34.605530 134275072 log.cc:713] T c57d94aace8b47f0b55fe8a7ab4793e5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-c57d94aace8b47f0b55fe8a7ab4793e5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605556 134275072 pending_op_counter.cc:27] [0x1133c3170] Update(1), result = 0
I0912 16:19:34.605571 134275072 pending_op_counter.cc:27] [0x1133c3170] Update(-1), result = 1
I0912 16:19:34.605585 134275072 log.cc:721] T c644169d7c6a4ee5b3cc1421e3377834 P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 3 more.
I0912 16:19:34.605607 134275072 pending_op_counter.cc:27] [0x112f04d70] Update(1), result = 0
I0912 16:19:34.605631 134275072 pending_op_counter.cc:27] [0x112f04d70] Update(-1), result = 1
I0912 16:19:34.605645 134275072 log.cc:713] T d1256ac738c540cd8216d2eea583300c P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-d1256ac738c540cd8216d2eea583300c would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605669 134275072 pending_op_counter.cc:27] [0x1110f5170] Update(1), result = 0
I0912 16:19:34.605684 134275072 pending_op_counter.cc:27] [0x1110f5170] Update(-1), result = 1
I0912 16:19:34.605698 134275072 log.cc:713] T d4d795a2a63e483bbfecdba99d518d0c P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-d4d795a2a63e483bbfecdba99d518d0c would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605722 134275072 pending_op_counter.cc:27] [0x1124d2470] Update(1), result = 0
I0912 16:19:34.605736 134275072 pending_op_counter.cc:27] [0x1124d2470] Update(-1), result = 1
I0912 16:19:34.605751 134275072 log.cc:713] T d9ad9c163fe648749c475274d35aacc5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-d9ad9c163fe648749c475274d35aacc5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605775 134275072 pending_op_counter.cc:27] [0x1133c0d70] Update(1), result = 0
I0912 16:19:34.605789 134275072 pending_op_counter.cc:27] [0x1133c0d70] Update(-1), result = 1
I0912 16:19:34.605803 134275072 log.cc:713] T ded208625a4845019d15e5140ed49b35 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-ded208625a4845019d15e5140ed49b35 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605828 134275072 pending_op_counter.cc:27] [0x111e89f70] Update(1), result = 0
I0912 16:19:34.605842 134275072 pending_op_counter.cc:27] [0x111e89f70] Update(-1), result = 1
I0912 16:19:34.605856 134275072 log.cc:713] T f22b0b72f82d43599f1c7a217e7f67c5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-f22b0b72f82d43599f1c7a217e7f67c5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.605886 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.605891 134275072 pending_op_counter.cc:27] [0x1110f4870] Update(1), result = 0
I0912 16:19:34.605944 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.605949 134275072 pending_op_counter.cc:27] [0x1110f4870] Update(-1), result = 1
I0912 16:19:34.605989 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.606014 134275072 log.cc:713] T fbf4ef0df3dd428ea2a6f5a1a3d67dc8 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-95366513ff3e4fdea873b5cfe89b6833/tablet-fbf4ef0df3dd428ea2a6f5a1a3d67dc8 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.606062 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.606101 134275072 maintenance_manager.cc:219] No maintenance operations look worth doing.
I0912 16:19:34.606124 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.629892 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.629936 121397248 reactor.cc:419] TabletServer_R002: timer tick at 32672.2
I0912 16:19:34.629940 120860672 reactor.cc:419] TabletServer_R001: timer tick at 32672.2
I0912 16:19:34.629945 120324096 reactor.cc:419] TabletServer_R000: timer tick at 32672.2
I0912 16:19:34.629948 121933824 reactor.cc:419] TabletServer_R003: timer tick at 32672.2
I0912 16:19:34.629979 122470400 reactor.cc:419] TabletServer_R004: timer tick at 32672.2
I0912 16:19:34.629992 123543552 reactor.cc:419] TabletServer_R006: timer tick at 32672.2
I0912 16:19:34.629993 123006976 reactor.cc:419] TabletServer_R005: timer tick at 32672.2
I0912 16:19:34.629998 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.629995 124080128 reactor.cc:419] TabletServer_R007: timer tick at 32672.2
I0912 16:19:34.630246 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
...
I0912 16:19:34.631073 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.631105 155738112 inbound_call.cc:139] 0x00000001125cf9a0 -> CQL Call from 127.0.0.1:50223, stream id: 15: Received
I0912 16:19:34.631186 139104256 inbound_call.cc:148] 0x00000001125cf9a0 -> CQL Call from 127.0.0.1:50223, stream id: 16: Handling
I0912 16:19:34.631230 139104256 cql_service.cc:133] Handling CQL Call from 127.0.0.1:50223, stream id: 16
I0912 16:19:34.631265 139104256 cql_message.cc:204] CQL message version 0x4 flags 0x0 stream id 16 opcode 0xa length 103746537
I0912 16:19:34.631292 139104256 cql_message.h:254] CQL byte 16
I0912 16:19:34.631312 139104256 cql_message.h:266] CQL short bytes ?????NS"j??K?
I0912 16:19:34.631330 139104256 cql_message.cc:509] CQL query parameters ...
I0912 16:19:34.631348 139104256 cql_message.h:254] CQL consistency 10
I0912 16:19:34.631367 139104256 cql_message.h:254] CQL byte 37
I0912 16:19:34.631386 139104256 cql_message.h:254] CQL byte 2
I0912 16:19:34.631407 139104256 cql_message.cc:474] CQL value ...
I0912 16:19:34.631426 139104256 cql_message.h:254] CQL int 4
I0912 16:19:34.631446 139104256 cql_message.cc:488] CQL value bytes 
I0912 16:19:34.631486 139104256 cql_message.cc:474] CQL value ...
I0912 16:19:34.631506 139104256 cql_message.h:254] CQL int 103746490
I0912 16:19:34.682072 155738112 reactor.cc:419] CQLServer_R000: timer tick at 32672.3
I0912 16:19:34.682130 155738112 reactor.cc:439] CQLServer_R000: Connection Connection (0x0000000111115c38) server 127.0.0.1:50223 => 127.0.0.1:9042 not idle
I0912 16:19:34.682538 157347840 reactor.cc:419] CQLServer_R003: timer tick at 32672.3
I0912 16:19:34.682535 156274688 reactor.cc:419] CQLServer_R001: timer tick at 32672.3
I0912 16:19:34.682538 156811264 reactor.cc:419] CQLServer_R002: timer tick at 32672.3
I0912 16:19:34.682560 157884416 reactor.cc:419] CQLServer_R004: timer tick at 32672.3
I0912 16:19:34.682566 158420992 reactor.cc:419] CQLServer_R005: timer tick at 32672.3
I0912 16:19:34.682586 158957568 reactor.cc:419] CQLServer_R006: timer tick at 32672.3
I0912 16:19:34.683209 159494144 reactor.cc:419] CQLServer_R007: timer tick at 32672.3
I0912 16:19:34.696908 138031104 reactor.cc:419] RedisServer_R000: timer tick at 32672.3
I0912 16:19:34.696908 141250560 reactor.cc:419] RedisServer_R002: timer tick at 32672.3
I0912 16:19:34.696908 147689472 reactor.cc:419] RedisServer_R006: timer tick at 32672.3
I0912 16:19:34.696908 142860288 reactor.cc:419] RedisServer_R003: timer tick at 32672.3
I0912 16:19:34.696908 148226048 reactor.cc:419] RedisServer_R007: timer tick at 32672.3
I0912 16:19:34.696908 146616320 reactor.cc:419] RedisServer_R004: timer tick at 32672.3
I0912 16:19:34.697005 138567680 reactor.cc:419] RedisServer_R001: timer tick at 32672.3
I0912 16:19:34.697006 147152896 reactor.cc:419] RedisServer_R005: timer tick at 32672.3
I0912 16:19:34.700017 138031104 reactor.cc:428] RedisServer_R000: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.700048 141250560 reactor.cc:428] RedisServer_R002: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.700114 147689472 reactor.cc:428] RedisServer_R006: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.700183 142860288 reactor.cc:428] RedisServer_R003: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.700227 148226048 reactor.cc:428] RedisServer_R007: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.700279 146616320 reactor.cc:428] RedisServer_R004: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.700311 138567680 reactor.cc:428] RedisServer_R001: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.700348 147152896 reactor.cc:428] RedisServer_R005: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.731824 121933824 reactor.cc:419] TabletServer_R003: timer tick at 32672.3
I0912 16:19:34.731827 123006976 reactor.cc:419] TabletServer_R005: timer tick at 32672.3
I0912 16:19:34.731858 124080128 reactor.cc:419] TabletServer_R007: timer tick at 32672.3
I0912 16:19:34.731824 120860672 reactor.cc:419] TabletServer_R001: timer tick at 32672.3
I0912 16:19:34.731882 121397248 reactor.cc:419] TabletServer_R002: timer tick at 32672.3
I0912 16:19:34.731824 122470400 reactor.cc:419] TabletServer_R004: timer tick at 32672.3
I0912 16:19:34.731847 123543552 reactor.cc:419] TabletServer_R006: timer tick at 32672.3
I0912 16:19:34.731886 120324096 reactor.cc:419] TabletServer_R000: timer tick at 32672.3
I0912 16:19:34.782155 157347840 reactor.cc:419] CQLServer_R003: timer tick at 32672.4
I0912 16:19:34.782152 155738112 reactor.cc:419] CQLServer_R000: timer tick at 32672.4
I0912 16:19:34.782248 156811264 reactor.cc:419] CQLServer_R002: timer tick at 32672.4
I0912 16:19:34.782253 156274688 reactor.cc:419] CQLServer_R001: timer tick at 32672.4
I0912 16:19:34.782274 155738112 reactor.cc:439] CQLServer_R000: Connection Connection (0x0000000111115c38) server 127.0.0.1:50223 => 127.0.0.1:9042 not idle
I0912 16:19:34.786347 157884416 reactor.cc:419] CQLServer_R004: timer tick at 32672.4
I0912 16:19:34.786356 158957568 reactor.cc:419] CQLServer_R006: timer tick at 32672.4
I0912 16:19:34.786443 158420992 reactor.cc:419] CQLServer_R005: timer tick at 32672.4
I0912 16:19:34.786451 159494144 reactor.cc:419] CQLServer_R007: timer tick at 32672.4
I0912 16:19:34.796804 141250560 reactor.cc:419] RedisServer_R002: timer tick at 32672.4
I0912 16:19:34.796804 146616320 reactor.cc:419] RedisServer_R004: timer tick at 32672.4
I0912 16:19:34.796803 138567680 reactor.cc:419] RedisServer_R001: timer tick at 32672.4
I0912 16:19:34.796804 147152896 reactor.cc:419] RedisServer_R005: timer tick at 32672.4
I0912 16:19:34.796804 142860288 reactor.cc:419] RedisServer_R003: timer tick at 32672.4
I0912 16:19:34.796869 138031104 reactor.cc:419] RedisServer_R000: timer tick at 32672.4
I0912 16:19:34.796874 147689472 reactor.cc:419] RedisServer_R006: timer tick at 32672.4
I0912 16:19:34.796885 148226048 reactor.cc:419] RedisServer_R007: timer tick at 32672.4
I0912 16:19:34.796943 141250560 reactor.cc:428] RedisServer_R002: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.796994 146616320 reactor.cc:428] RedisServer_R004: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.797034 138567680 reactor.cc:428] RedisServer_R001: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.797070 147152896 reactor.cc:428] RedisServer_R005: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.797116 142860288 reactor.cc:428] RedisServer_R003: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.797191 138031104 reactor.cc:428] RedisServer_R000: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.797232 147689472 reactor.cc:428] RedisServer_R006: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.797274 148226048 reactor.cc:428] RedisServer_R007: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.828495 120324096 reactor.cc:419] TabletServer_R000: timer tick at 32672.4
I0912 16:19:34.828702 120860672 reactor.cc:419] TabletServer_R001: timer tick at 32672.4
I0912 16:19:34.828704 122470400 reactor.cc:419] TabletServer_R004: timer tick at 32672.4
I0912 16:19:34.828707 123543552 reactor.cc:419] TabletServer_R006: timer tick at 32672.4
I0912 16:19:34.828706 123006976 reactor.cc:419] TabletServer_R005: timer tick at 32672.4
I0912 16:19:34.828703 121397248 reactor.cc:419] TabletServer_R002: timer tick at 32672.4
I0912 16:19:34.828725 121933824 reactor.cc:419] TabletServer_R003: timer tick at 32672.4
I0912 16:19:34.828733 124080128 reactor.cc:419] TabletServer_R007: timer tick at 32672.4
I0912 16:19:34.857444 134275072 pending_op_counter.cc:27] [0x112f07a70] Update(1), result = 0
I0912 16:19:34.871341 134275072 pending_op_counter.cc:27] [0x112f07a70] Update(-1), result = 1
I0912 16:19:34.871389 134275072 log.cc:713] T 035fb48b34094449bb71ef08b4780082 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-035fb48b34094449bb71ef08b4780082 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.871441 134275072 pending_op_counter.cc:27] [0x1169ead70] Update(1), result = 0
I0912 16:19:34.871510 134275072 pending_op_counter.cc:27] [0x1169ead70] Update(-1), result = 1
I0912 16:19:34.871544 134275072 log.cc:713] T 03d367d55bdb460c9487c464716c01d6 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-03d367d55bdb460c9487c464716c01d6 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.871598 134275072 pending_op_counter.cc:27] [0x1124d3670] Update(1), result = 0
I0912 16:19:34.871649 134275072 pending_op_counter.cc:27] [0x1124d3670] Update(-1), result = 1
I0912 16:19:34.871675 134275072 log.cc:713] T 073b711cacbc4c2f95995624e5ad336e P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-073b711cacbc4c2f95995624e5ad336e would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.871706 134275072 pending_op_counter.cc:27] [0x11163e470] Update(1), result = 0
I0912 16:19:34.871729 134275072 pending_op_counter.cc:27] [0x11163e470] Update(-1), result = 1
I0912 16:19:34.871755 134275072 log.cc:713] T 08b598d95d724cbca88b8a60ed34b85a P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-08b598d95d724cbca88b8a60ed34b85a would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.871785 134275072 pending_op_counter.cc:27] [0x1169ed170] Update(1), result = 0
I0912 16:19:34.871809 134275072 pending_op_counter.cc:27] [0x1169ed170] Update(-1), result = 1
I0912 16:19:34.871834 134275072 log.cc:713] T 12922aefce5a42bcb18c43830c77dca1 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-12922aefce5a42bcb18c43830c77dca1 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.871863 134275072 pending_op_counter.cc:27] [0x111c03670] Update(1), result = 0
I0912 16:19:34.871881 134275072 pending_op_counter.cc:27] [0x111c03670] Update(-1), result = 1
I0912 16:19:34.871897 134275072 log.cc:713] T 13137c836a644ff2b4bdd63db67b9704 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-13137c836a644ff2b4bdd63db67b9704 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.871927 134275072 pending_op_counter.cc:27] [0x1114d7170] Update(1), result = 0
I0912 16:19:34.871951 134275072 pending_op_counter.cc:27] [0x1114d7170] Update(-1), result = 1
I0912 16:19:34.871973 134275072 log.cc:713] T 17689ffda26d470b8489eb5bb7a16e82 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-17689ffda26d470b8489eb5bb7a16e82 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.872006 134275072 pending_op_counter.cc:27] [0x1110f5a70] Update(1), result = 0
I0912 16:19:34.872026 134275072 pending_op_counter.cc:27] [0x1110f5a70] Update(-1), result = 1
I0912 16:19:34.872051 134275072 log.cc:713] T 183c0aa263ee4a9aa08fa89bedb0f7b1 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-183c0aa263ee4a9aa08fa89bedb0f7b1 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.872083 134275072 pending_op_counter.cc:27] [0x1124d5170] Update(1), result = 0
I0912 16:19:34.872104 134275072 pending_op_counter.cc:27] [0x1124d5170] Update(-1), result = 1
I0912 16:19:34.872120 134275072 log.cc:713] T 2532a180889c493f85c4f10f9c47f356 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-95366513ff3e4fdea873b5cfe89b6833/tablet-2532a180889c493f85c4f10f9c47f356 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.872148 134275072 pending_op_counter.cc:27] [0x1125a1170] Update(1), result = 0
I0912 16:19:34.872179 134275072 pending_op_counter.cc:27] [0x1125a1170] Update(-1), result = 1
I0912 16:19:34.872228 134275072 log.cc:721] T 5cb6bf51f3b846bca39de498c36cf7ed P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 1 more.
I0912 16:19:34.872277 134275072 pending_op_counter.cc:27] [0x111c02d70] Update(1), result = 0
I0912 16:19:34.872314 134275072 pending_op_counter.cc:27] [0x111c02d70] Update(-1), result = 1
I0912 16:19:34.872354 134275072 log.cc:721] T 6099edbd08f642969b507401ae27d704 P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 2 more.
I0912 16:19:34.872400 134275072 pending_op_counter.cc:27] [0x1110f3670] Update(1), result = 0
I0912 16:19:34.872429 134275072 pending_op_counter.cc:27] [0x1110f3670] Update(-1), result = 1
I0912 16:19:34.872457 134275072 log.cc:713] T 651f232843f24e489590d00ee424a6e2 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-651f232843f24e489590d00ee424a6e2 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.872532 134275072 pending_op_counter.cc:27] [0x1110f2470] Update(1), result = 0
I0912 16:19:34.872562 134275072 pending_op_counter.cc:27] [0x1110f2470] Update(-1), result = 1
I0912 16:19:34.872583 134275072 log.cc:713] T 717cc00d1f3342cbb949e9de76a45773 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-717cc00d1f3342cbb949e9de76a45773 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.872643 134275072 pending_op_counter.cc:27] [0x1110f3f70] Update(1), result = 0
I0912 16:19:34.872678 134275072 pending_op_counter.cc:27] [0x1110f3f70] Update(-1), result = 1
I0912 16:19:34.872725 134275072 log.cc:713] T 77f3d502f9a349b09e381fd4470d0ab3 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-77f3d502f9a349b09e381fd4470d0ab3 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.872776 134275072 pending_op_counter.cc:27] [0x1124d3f70] Update(1), result = 0
I0912 16:19:34.872809 134275072 pending_op_counter.cc:27] [0x1124d3f70] Update(-1), result = 1
I0912 16:19:34.872840 134275072 log.cc:713] T 7be598840fa74d5db5df8df5b1e4700b P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-7be598840fa74d5db5df8df5b1e4700b would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.872882 134275072 pending_op_counter.cc:27] [0x111640870] Update(1), result = 0
I0912 16:19:34.872905 134275072 pending_op_counter.cc:27] [0x111640870] Update(-1), result = 1
I0912 16:19:34.872925 134275072 log.cc:713] T 97f40d1eb3e34a40808cc45d931f2539 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-97f40d1eb3e34a40808cc45d931f2539 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.872969 134275072 pending_op_counter.cc:27] [0x112f06870] Update(1), result = 0
I0912 16:19:34.873024 134275072 pending_op_counter.cc:27] [0x112f06870] Update(-1), result = 1
I0912 16:19:34.873052 134275072 log.cc:713] T 9eb5691dc0484592aef43c023d345bc5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-9eb5691dc0484592aef43c023d345bc5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.873106 134275072 pending_op_counter.cc:27] [0x1124d5a70] Update(1), result = 0
I0912 16:19:34.873155 134275072 pending_op_counter.cc:27] [0x1124d5a70] Update(-1), result = 1
I0912 16:19:34.873206 134275072 log.cc:713] T a193ebdbeed44a79952d5dfaeb91ae56 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-a193ebdbeed44a79952d5dfaeb91ae56 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.873267 134275072 pending_op_counter.cc:27] [0x11a01a470] Update(1), result = 0
I0912 16:19:34.873306 134275072 pending_op_counter.cc:27] [0x11a01a470] Update(-1), result = 1
I0912 16:19:34.873364 134275072 log.cc:721] T a5da4a8052884d89b098c08c7ba70f05 P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 6 more.
I0912 16:19:34.873456 134275072 pending_op_counter.cc:27] [0x111c05170] Update(1), result = 0
I0912 16:19:34.873498 134275072 pending_op_counter.cc:27] [0x111c05170] Update(-1), result = 1
I0912 16:19:34.873534 134275072 log.cc:713] T ba061293b88e4aad985c958cfa7457be P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-ba061293b88e4aad985c958cfa7457be would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.873595 134275072 pending_op_counter.cc:27] [0x111c04870] Update(1), result = 0
I0912 16:19:34.873628 134275072 pending_op_counter.cc:27] [0x111c04870] Update(-1), result = 1
I0912 16:19:34.873658 134275072 log.cc:713] T bd21577525644226a997f20fa54b6607 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-bd21577525644226a997f20fa54b6607 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.873705 134275072 pending_op_counter.cc:27] [0x112f05f70] Update(1), result = 0
I0912 16:19:34.873728 134275072 pending_op_counter.cc:27] [0x112f05f70] Update(-1), result = 1
I0912 16:19:34.873747 134275072 log.cc:713] T c003928bcaca464fbbacfe036ce004d0 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-c003928bcaca464fbbacfe036ce004d0 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.873780 134275072 pending_op_counter.cc:27] [0x111e8b170] Update(1), result = 0
I0912 16:19:34.873813 134275072 pending_op_counter.cc:27] [0x111e8b170] Update(-1), result = 1
I0912 16:19:34.873842 134275072 log.cc:713] T c57d94aace8b47f0b55fe8a7ab4793e5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-c57d94aace8b47f0b55fe8a7ab4793e5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.873898 134275072 pending_op_counter.cc:27] [0x1133c3170] Update(1), result = 0
I0912 16:19:34.873929 134275072 pending_op_counter.cc:27] [0x1133c3170] Update(-1), result = 1
I0912 16:19:34.873980 134275072 log.cc:721] T c644169d7c6a4ee5b3cc1421e3377834 P c2dbcd03e21c48118c14d85feb2c5871: Too many log segments, need to GC 3 more.
I0912 16:19:34.874068 134275072 pending_op_counter.cc:27] [0x112f04d70] Update(1), result = 0
I0912 16:19:34.874125 134275072 pending_op_counter.cc:27] [0x112f04d70] Update(-1), result = 1
I0912 16:19:34.874159 134275072 log.cc:713] T d1256ac738c540cd8216d2eea583300c P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-d1256ac738c540cd8216d2eea583300c would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.874229 134275072 pending_op_counter.cc:27] [0x1110f5170] Update(1), result = 0
I0912 16:19:34.874315 134275072 pending_op_counter.cc:27] [0x1110f5170] Update(-1), result = 1
I0912 16:19:34.874441 134275072 log.cc:713] T d4d795a2a63e483bbfecdba99d518d0c P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-d4d795a2a63e483bbfecdba99d518d0c would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.874528 134275072 pending_op_counter.cc:27] [0x1124d2470] Update(1), result = 0
I0912 16:19:34.874572 134275072 pending_op_counter.cc:27] [0x1124d2470] Update(-1), result = 1
I0912 16:19:34.874649 134275072 log.cc:713] T d9ad9c163fe648749c475274d35aacc5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-d9ad9c163fe648749c475274d35aacc5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.874732 134275072 pending_op_counter.cc:27] [0x1133c0d70] Update(1), result = 0
I0912 16:19:34.874770 134275072 pending_op_counter.cc:27] [0x1133c0d70] Update(-1), result = 1
I0912 16:19:34.874816 134275072 log.cc:713] T ded208625a4845019d15e5140ed49b35 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-9325adcfa241469eaf78928cbcdcebd6/tablet-ded208625a4845019d15e5140ed49b35 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.874868 134275072 pending_op_counter.cc:27] [0x111e89f70] Update(1), result = 0
I0912 16:19:34.874900 134275072 pending_op_counter.cc:27] [0x111e89f70] Update(-1), result = 1
I0912 16:19:34.874944 134275072 log.cc:713] T f22b0b72f82d43599f1c7a217e7f67c5 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-5eccdea0aa38409aa0538cd58ea39c53/tablet-f22b0b72f82d43599f1c7a217e7f67c5 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.874991 134275072 pending_op_counter.cc:27] [0x1110f4870] Update(1), result = 0
I0912 16:19:34.875025 134275072 pending_op_counter.cc:27] [0x1110f4870] Update(-1), result = 1
I0912 16:19:34.875062 134275072 log.cc:713] T fbf4ef0df3dd428ea2a6f5a1a3d67dc8 P c2dbcd03e21c48118c14d85feb2c5871: GCing 1 in /Users/oleg/yugabyte-data/node-1/disk-1/yb-data/tserver/wals/table-95366513ff3e4fdea873b5cfe89b6833/tablet-fbf4ef0df3dd428ea2a6f5a1a3d67dc8 would not leave enough remaining segments to satisfy minimum retention requirement. Only considering 0/2
I0912 16:19:34.875118 134275072 maintenance_manager.cc:219] No maintenance operations look worth doing.
I0912 16:19:34.882467 155738112 reactor.cc:419] CQLServer_R000: timer tick at 32672.5
I0912 16:19:34.882599 155738112 reactor.cc:439] CQLServer_R000: Connection Connection (0x0000000111115c38) server 127.0.0.1:50223 => 127.0.0.1:9042 not idle
I0912 16:19:34.882784 159494144 reactor.cc:419] CQLServer_R007: timer tick at 32672.5
I0912 16:19:34.883930 156811264 reactor.cc:419] CQLServer_R002: timer tick at 32672.5
I0912 16:19:34.883935 157347840 reactor.cc:419] CQLServer_R003: timer tick at 32672.5
I0912 16:19:34.883935 157884416 reactor.cc:419] CQLServer_R004: timer tick at 32672.5
I0912 16:19:34.883935 158420992 reactor.cc:419] CQLServer_R005: timer tick at 32672.5
I0912 16:19:34.883965 156274688 reactor.cc:419] CQLServer_R001: timer tick at 32672.5
I0912 16:19:34.883971 158957568 reactor.cc:419] CQLServer_R006: timer tick at 32672.5
I0912 16:19:34.893677 138567680 reactor.cc:419] RedisServer_R001: timer tick at 32672.5
I0912 16:19:34.893677 138031104 reactor.cc:419] RedisServer_R000: timer tick at 32672.5
I0912 16:19:34.893728 141250560 reactor.cc:419] RedisServer_R002: timer tick at 32672.5
I0912 16:19:34.893744 138567680 reactor.cc:428] RedisServer_R001: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.893780 138031104 reactor.cc:428] RedisServer_R000: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.893831 141250560 reactor.cc:428] RedisServer_R002: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.893970 142860288 reactor.cc:419] RedisServer_R003: timer tick at 32672.5
I0912 16:19:34.894008 142860288 reactor.cc:428] RedisServer_R003: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.894295 146616320 reactor.cc:419] RedisServer_R004: timer tick at 32672.5
I0912 16:19:34.894330 146616320 reactor.cc:428] RedisServer_R004: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.894560 147152896 reactor.cc:419] RedisServer_R005: timer tick at 32672.5
I0912 16:19:34.894609 147152896 reactor.cc:428] RedisServer_R005: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.899696 147689472 reactor.cc:419] RedisServer_R006: timer tick at 32672.5
I0912 16:19:34.899729 147689472 reactor.cc:428] RedisServer_R006: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.899842 148226048 reactor.cc:419] RedisServer_R007: timer tick at 32672.5
I0912 16:19:34.899890 148226048 reactor.cc:428] RedisServer_R007: Skipping Idle connections check since connection_keepalive_time_ = 0
I0912 16:19:34.661298 139104256 cql_message.cc:488] CQL value bytes /
                                                                     ?{"0": "1234567890", "1": "1234567890", "2": "1234567890", "3": "1234567890", "4": "1234567890", "5": "1234567890", "6": "1234567890", "7": "1234567890", "8": "1234567890", "9": "1234567890", "10": "1234567890", "11": "1234567890", "12": "1234567890", "13": "1234567890", "14": "1234567890", "15": "1234567890", "16": "1234567890", "17": "1234567890", "18": "1234567890", "19": "1234567890", "20": "1234567890", "21": "1234567890", "22": "1234567890", "23": "1234567890", "24": "1234567890", "25": "1234567890", "26": "1234567890", "27": "1234567890", "28": "1234567890", "29": "1234567890", "30": "1234567890", "31": "1234567890", "32": "1234567890", "33": "1234567890", "34": "1234567890", "35": "1234567890", "36": "1234567890", "37": "1234567890", "38": "1234567890", "39": "1234567890", "40": "1234567890", "41": "1234567890", "42": "1234567890", "43": "1234567890", "44": "1234567890", "45": "1234567890", "46": "1234567890", "47": "1234567890", "48": "1234567890", "49": "1234567890", "50": "1234567890", "51": "1234567890", "52": "1234567890", "53": "1234567890", "54": "1234567890", "55": "1234567890", "56": "1234567890", "57": "1234567890", "58": "1234567890", "59": "1234567890", "60": "1234567890", "61": "1234567890", "62": "1234567890", "63": "1234567890", "64": "1234567890", "65": "1234567890", "66": "1234567890", "67": "1234567890", "68": "1234567890", "69": "1234567890", "70": "1234567890", "71": "1234567890", "72": "1234567890", "73": "1234567890", "74": "1234567890", "75": "1234567890", "76": "1234567890", "77": "1234567890", "78": "1234567890", "79": "1234567890", "80": "1234567890", "81": "1234567890", "82": "1234567890", "83": "1234567890", "84": "1234567890", "85": "1234567890", "86": "1234567890", "87": "1234567890", "88": "1234567890", "89": "1234567890", "90": "1234567890", "91": "1234567890", "92": "1234567890", "93": "1234567890", "94": "1234567890", "95": "1234567890", "96": "1234567890", "97": "1234567890", "98": "1234567890", "99": "1234567890", "100": "1234567890", "101": "1234567890", "102": "1234567890", "103": "1234567890", "104": "1234567890", "105": "1234567890", "106": "1234567890", "107": "1234567890", "108": "1234567890", "109": "1234567890", "110": "1234567890", "111": "1234567890", "112": "1234567890", "113": "1234567890", "114": "1234567890", "115": "1234567890", "116": "1234567890", "117": "1234567890", "118": "1234567890", "119": "1234567890", "120": "1234567890", "121": "1234567890", "122": "1234567890", "123": "1234567890", "124": "1234567890", "125": "1234567890", "126": "1234567890", "127": "1234567890", "128": "1234567890", "129": "1234567890", "130": "1234567890", "131": "1234567890", "132": "1234567890", "133": "1234567890", "134": "1234567890", "135": "1234567890", "136": "1234567890", "137": "1234567890", "138": "1234567890", "139": "1234567890", "140": "1234567890", "141": "1234567890", "142": "1234567890", "143": "1234567890", "144": "1234567890", "145": "1234567890", "146": "1234567890", "147": "1234567890", "148": "1234567890", "149": "1234567890", "150": "1234567890", "151": "1234567890", "152": "1234567890", "153": "1234567890", "154": "1234567890", "155": "1234567890", "156": "1234567890", "157": "1234567890", "158": "1234567890", "159": "1234567890", "160": "1234567890", "161": "1234567890", "162": "1234567890", "163": "1234567890", "164": "1234567890", "165": "1234567890", "166": "1234567890", "167": "1234567890", "168": "1234567890", "169": "1234567890", "170": "1234567890", "171": "1234567890", "172": "1234567890", "173": "1234567890", "174": "1234567890", "175": "1234567890", "176": "1234567890", "177": "1234567890", "178": "1234567890", "179": "1234567890", "180": "1234567890", "181": "1234567890", "182": "1234567890", "183": "1234567890", "184": "1234567890", "185": "1234567890", "186": "1234567890", "187": "1234567890", "188": "1234567890", "189": "1234567890", "190": "1234567890", "191": "1234567890", "192": "1234567890", "193": "1234567890", "194": "1234567890", "195": "1234567890", "196": "1234567890", "197": "1234567890", "198": "1234567890", "199": "1234567890", "200": "1234567890", "201": "1234567890", "202": "1234567890", "203": "1234567890", "204": "1234567890", "205": "1234567890", "206": "1234567890", "207": "1234567890", "208": "1234567890", "209": "1234567890", "210": "1234567890", "211": "1234567890", "212": "1234567890", "213": "1234567890", "214": "1234567890", "215": "1234567890", "216": "1234567890", "217": "1234567890", "218": "1234567890", "219": "1234567890", "220": "1234567890", "221": "1234567890", "222": "1234567890", "223": "1234567890", "224": "1234567890", "225": "1234567890", "226": "1234567890", "227": "1234567890", "228": "1234567890", "229": "1234567890", "230": "1234567890", "231": "1234567890", "232": "1234567890", "233": "1234567890", "234": "1234567890", "235": "1234567890", "236": "1234567890", "237": "1234567890", "238": "1234567890", "239": "1234567890", "240": "1234567890", "241": "1234567890", "242": "1234567890", "243": "1234567890", "244": "1234567890", "245": "1234567890", "246": "1234567890", "247": "1234567890", "248": "1234567890", "249": "1234567890", "250": "1234567890", "251": "1234567890", "252": "1234567890", "253": "1234567890", "254": "1234567890", "255": "1234567890", "256": "1234567890", "257": "1234567890", "258": "1234567890", "259": "1234567890", "260": "1234567890", "261": "1234567890", "262": "1234567890", "263": "1234567890", "264": "1234567890", "265": "1234567890", "266": "1234567890", "267": "1234567890", "268": "1234567890", "269": "1234567890", "270": "1234567890", "271": "1234567890", "272": "1234567890", "273": "1234567890", "274": "1234567890", "275": "1234567890", "276": "1234567890", "277": "1234567890", "278": "1234567890", "279": "1234567890", "280": "1234567890", "281": "1234567890", "282": "1234567890", "283": "1234567890", "284": "1234567890", "285": "1234567890", "286": "1234567890", "287": "1234567890", "288": "1234567890", "289": "1234567890", "290": "1234567890", "291": "1234567890", "292": "1234567890", "293": "1234567890", "294": "1234567890", "295": "1234567890", "296": "1234567890", "297": "1234567890", "298": "1234567890", "299": "1234567890", "300": "1234567890", "301": "1234567890", "302": "1234567890", "303": "1234567890", "304": "1234567890", "305": "1234567890", "306": "1234567890", "307": "1234567890", "308": "1234567890", "309": "1234567890", "310": "1234567890", "311": "1234567890", "312": "1234567890", "313": "1234567890", "314": "1234567890", "315": "1234567890", "316": "1234567890", "317": "1234567890", "318": "1234567890", "319": "1234567890", "320": "1234567890", "321": "1234567890", "322": "1234567890", "323": "1234567890", "324": "1234567890", "325": "1234567890", "326": "1234567890", "327": "1234567890", "328": "1234567890", "329": "1234567890", "330": "1234567890", "331": "1234567890", "332": "1234567890", "333": "1234567890", "334": "1234567890", "335": "1234567890", "336": "1234567890", "337": "1234567890", "338": "1234567890", "339": "1234567890", "340": "1234567890", "341": "1234567890", "342": "1234567890", "343": "1234567890", "344": "1234567890", "345": "1234567890", "346": "1234567890", "347": "1234567890", "348": "1234567890", "349": "1234567890", "350": "1234567890", "351": "1234567890", "352": "1234567890", "353": "1234567890", "354": "1234567890", "355": "1234567890", "356": "1234567890", "357": "1234567890", "358": "1234567890", "359": "1234567890", "360": "1234567890", "361": "1234567890", "362": "1234567890", "363": "1234567890", "364": "1234567890", "365": "1234567890", "366": "1234567890", "367": "1234567890", "368": "1234567890", "369": "1234567890", "370": "1234567890", "371": "1234567890", "372": "1234567890", "373": "1234567890", "374": "1234567890", "375": "1234567890", "376": "1234567890", "377": "1234567890", "378": "1234567890", "379": "1234567890", "380": "1234567890", "381": "1234567890", "382": "1234567890", "383": "1234567890", "384": "1234567890", "385": "1234567890", "386": "1234567890", "387": "1234567890", "388": "1234567890", "389": "1234567890", "390": "1234567890", "391": "1234567890", "392": "1234567890", "393": "1234567890", "394": "1234567890", "395": "1234567890", "396": "1234567890", "397": "1234567890", "398": "1234567890", "399": "1234567890", "400": "1234567890", "401": "1234567890", "402": "1234567890", "403": "1234567890", "404": "1234567890", "405": "1234567890", "406": "1234567890", "407": "1234567890", "408": "1234567890", "409": "1234567890", "410": "1234567890", "411": "1234567890", "412": "1234567890", "413": "1234567890", "414": "1234567890", "415": "1234567890", "416": "1234567890", "417": "1234567890", "418": "1234567890", "419": "1234567890", "420": "1234567890", "421": "1234567890", "422": "1234567890", "423": "1234567890", "424": "1234567890", "425": "1234567890", "426": "1234567890", "427": "1234567890", "428": "1234567890", "429": "1234567890", "430": "1234567890", "431": "1234567890", "432": "1234567890", "433": "1234567890", "434": "1234567890", "435": "1234567890", "436": "1234567890", "437": "1234567890", "438": "1234567890", "439": "1234567890", "440": "1234567890", "441": "1234567890", "442": "1234567890", "443": "1234567890", "444": "1234567890", "445": "1234567890", "446": "1234567890", "447": "1234567890", "448": "1234567890", "449": "1234567890", "450": "1234567890", "451": "1234567890", "452": "1234567890", "453": "1234567890", "454": "1234567890", "455": "1234567890", "456": "1234567890", "457": "1234567890", "458": "1234567890", "459": "1234567890", "460": "1234567890", "461": "1234567890", "462": "1234567890", "463": "1234567890", "464": "1234567890", "465": "1234567890", "466": "1234567890", "467": "1234567890", "468": "1234567890", "469": "1234567890", "470": "1234567890", "471": "1234567890", "472": "1234567890", "473": "1234567890", "474": "1234567890", "475": "1234567890", "476": "1234567890", "477": "1234567890", "478": "1234567890", "479": "1234567890", "480": "1234567890", "481": "1234567890", "482": "1234567890", "483": "1234567890", "484": "1234567890", "485": "1234567890", "486": "1234567890", "487": "1234567890", "488": "1234567890", "489": "1234567890", "490": "1234567890", "491": "1234567890", "492": "1234567890", "493": "1234567890", "494": "1234567890", "495": "1234567890", "496": "1234567890", "497": "1234567890", "498": "1234567890", "499": "1234567890", "500": "1234567890", "501": "1234567890", "502": "1234567890", "503": "1234567890", "504": "1234567890", "505": "1234567890", "506": "1234567890", "507": "1234567890", "508": "1234567890", "509": "1234567890", "510": "1234567890", "511": "1234567890", "512": "1234567890", "513": "1234567890", "514": "1234567890", "515": "1234567890", "516": "1234567890", "517": "1234567890", "518": "1234567890", "519": "1234567890", "520": "1234567890", "521": "1234567890", "522": "1234567890", "523": "1234567890", "524": "1234567890", "525": "1234567890", "526": "1234567890", "527": "1234567890", "528": "1234567890", "529": "1234567890", "530": "1234567890", "531": "1234567890", "532": "1234567890", "533": "1234567890", "534": "1234567890", "535": "1234567890", "536": "1234567890", "537": "1234567890", "538": "1234567890", "539": "1234567890", "540": "1234567890", "541": "1234567890", "542": "1234567890", "543": "1234567890", "544": "1234567890", "545": "1234567890", "546": "1234567890", "547": "1234567890", "548": "1234567890", "549": "1234567890", "550": "1234567890", "551": "1234567890", "552": "1234567890", "553": "1234567890", "554": "1234567890", "555": "1234567890", "556": "1234567890", "557": "1234567890", "558": "1234567890", "559": "1234567890", "560": "1234567890", "561": "1234567890", "562": "1234567890", "563": "1234567890", "564": "1234567890", "565": "1234567890", "566": "1234567890", "567": "1234567890", "568": "1234567890", "569": "1234567890", "570": "1234567890", "571": "1234567890", "572": "1234567890", "573": "1234567890", "574": "1234567890", "575": "1234567890", "576": "1234567890", "577": "1234567890", "578": "1234567890", "579": "1234567890", "580": "1234567890", "581": "1234567890", "582": "1234567890", "583": "1234567890", "584": "1234567890", "585": "1234567890", "586": "1234567890", "587": "1234567890", "588": "1234567890", "589": "1234567890", "590": "1234567890", "591": "1234567890", "592": "1234567890", "593": "1234567890", "594": "1234567890", "595": "1234567890", "596": "1234567890", "597": "1234567890", "598": "1234567890", "599": "1234567890", "600": "1234567890", "601": "1234567890", "602": "1234567890", "603": "1234567890", "604": "1234567890", "605": "1234567890", "606": "1234567890", "607": "1234567890", "608": "1234567890", "609": "1234567890", "610": "1234567890", "611": "1234567890", "612": "1234567890", "613": "1234567890", "614": "1234567890", "615": "1234567890", "616": "1234567890", "617": "1234567890", "618": "1234567890", "619": "1234567890", "620": "1234567890", "621": "1234567890", "622": "1234567890", "623": "1234567890", "624": "1234567890", "625": "1234567890", "626": "1234567890", "627": "1234567890", "628": "1234567890", "629": "1234567890", "630": "1234567890", "631": "1234567890", "632": "1234567890", "633": "1234567890", "634": "1234567890", "635": "1234567890", "636": "1234567890", "637": "1234567890", "638": "1234567890", "639": "1234567890", "640": "1234567890", "641": "1234567890", "642": "1234567890", "643": "1234567890", "644": "1234567890", "645": "1234567890", "646": "1234567890", "647": "1234567890", "648": "1234567890", "649": "1234567890", "650": "1234567890", "651": "1234567890", "652": "1234567890", "653": "1234567890", "654": "1234567890", "655": "1234567890", "656": "1234567890", "657": "1234567890", "658": "1234567890", "659": "1234567890", "660": "1234567890", "661": "1234567890", "662": "1234567890", "663": "1234567890", "664": "1234567890", "665": "1234567890", "666": "1234567890", "667": "1234567890", "668": "1234567890", "669": "1234567890", "670": "1234567890", "671": "1234567890", "672": "1234567890", "673": "1234567890", "674": "1234567890", "675": "1234567890", "676": "1234567890", "677": "1234567890", "678": "1234567890", "679": "1234567890", "680": "1234567890", "681": "1234567890", "682": "1234567890", "683": "1234567890", "684": "1234567890", "685": "1234567890", "686": "1234567890", "687": "1234567890", "688": "1234567890", "689": "1234567890", "690": "1234567890", "691": "1234567890", "692": "1234567890", "693": "1234567890", "694": "1234567890", "695": "1234567890", "696": "1234567890", "697": "1234567890", "698": "1234567890", "699": "1234567890", "700": "1234567890", "701": "1234567890", "702": "1234567890", "703": "1234567890", "704": "1234567890", "705": "1234567890", "706": "1234567890", "707": "1234567890", "708": "1234567890", "709": "1234567890", "710": "1234567890", "711": "1234567890", "712": "1234567890", "713": "1234567890", "714": "1234567890", "715": "1234567890", "716": "1234567890", "717": "1234567890", "718": "1234567890", "719": "1234567890", "720": "1234567890", "721": "1234567890", "722": "1234567890", "723": "1234567890", "724": "1234567890", "725": "1234567890", "726": "1234567890", "727": "1234567890", "728": "1234567890", "729": "1234567890", "730": "1234567890", "731": "1234567890", "732": "1234567890", "733": "1234567890", "734": "1234567890", "735": "1234567890", "736": "1234567890", "737": "1234567890", "738": "1234567890", "739": "1234567890", "740": "1234567890", "741": "1234567890", "742": "1234567890", "743": "1234567890", "744": "1234567890", "745": "1234567890", "746": "1234567890", "747": "1234567890", "748": "1234567890", "749": "1234567890", "750": "1234567890", "751": "1234567890", "752": "1234567890", "753": "1234567890", "754": "1234567890", "755": "1234567890", "756": "1234567890", "757": "1234567890", "758": "1234567890", "759": "1234567890", "760": "1234567890", "761": "1234567890", "762": "1234567890", "763": "1234567890", "764": "1234567890", "765": "1234567890", "766": "1234567890", "767": "1234567890", "768": "1234567890", "769": "1234567890", "770": "1234567890", "771": "1234567890", "772": "1234567890", "773": "1234567890", "774": "1234567890", "775": "1234567890", "776": "1234567890", "777": "1234567890", "778": "1234567890", "779": "1234567890", "780": "1234567890", "781": "1234567890", "782": "1234567890", "783": "1234567890", "784": "1234567890", "785": "1234567890", "786": "1234567890", "787": "1234567890", "788": "1234567890", "789": "1234567890", "790": "1234567890", "791": "1234567890", "792": "1234567890", "793": "1234567890", "794": "1234567890", "795": "1234567890", "796": "1234567890", "797": "1234567890", "798": "1234567890", "799": "1234567890", "800": "1234567890", "801": "1234567890", "802": "1234567890", "803": "1234567890", "804": "1234567890", "805": "1234567890", "806": "1234567890", "807": "1234567890", "808": "1234567890", "809": "1234567890", "810": "1234567890", "811": "1234567890", "812": "1234567890", "813": "1234567890", "814": "1234567890", "815": "1234567890", "816": "1234567890", "817": "1234567890", "818": "1234567890", "819": "1234567890", "820": "1234567890", "821": "1234567890", "822": "1234567890", "823": "1234567890", "824": "1234567890", "825": "1234567890", "826": "1234567890", "827": "1234567890", "828": "1234567890", "829": "1234567890", "830": "1234567890", "831": "1234567890", "832": "1234567890", "833": "1234567890", "834": "1234567890", "835": "1234567890", "836": "1234567890", "837": "1234567890", "838": "1234567890", "839": "1234567890", "840": "1234567890", "841": "1234567890", "842": "1234567890", "843": "1234567890", "844": "1234567890", "845": "1234567890", "846": "1234567890", "847": "1234567890", "848": "1234567890", "849": "1234567890", "850": "1234567890", "851": "1234567890", "852": "1234567890", "853": "1234567890", "854": "1234567890", "855": "1234567890", "856": "1234567890", "857": "1234567890", "858": "1234567890", "859": "1234567890", "860": "1234567890", "861": "1234567890", "862": "1234567890", "863": "1234567890", "864": "1234567890", "865": "1234567890", "866": "1234567890", "867": "1234567890", "868": "1234567890", "869": "1234567890", "870": "1234567890", "871": "1234567890", "872": "1234567890", "873": "1234567890", "874": "1234567890", "875": "1234567890", "876": "1234567890", "877": "1234567890", "878": "1234567890", "879": "1234567890", "880": "1234567890", "881": "1234567890", "882": "1234567890", "883": "1234567890", "884": "1234567890", "885": "1234567890", "886": "1234567890", "887": "1234567890", "888": "1234567890", "889": "1234567890", "890": "1234567890", "891": "1234567890", "892": "1234567890", "893": "1234567890", "894": "1234567890", "895": "1234567890", "896": "1234567890", "897": "1234567890", "898": "1234567890", "899": "1234567890", "900": "1234567890", "901": "1234567890", "902": "1234567890", "903": "1234567890", "904": "1234567890", "905": "1234567890", "906": "1234567890", "907": "1234567890", "908": "1234567890", "909": "1234567890", "910": "1234567890", "911": "1234567890", "912": "1234567890", "913": "1234567890", "914": "1234567890", "915": "1234567890", "916": "1234567890", "917": "1234567890", "918": "1234567890", "919": "1234567890", "920": "1234567890", "921": "1234567890", "922": "1234567890", "923": "1234567890", "924": "1234567890", "925": "1234567890", "926": "1234567890", "927": "1234567890", "928": "1234567890", "929": "1234567890", "930": "1234567890", "931": "1234567890", "932": "1234567890", "933": "1234567890", "934": "1234567890", "935": "1234567890", "936": "1234567890", "937": "1234567890", "938": "1234567890", "939": "1234567890", "940": "1234567890", "941": "1234567890", "942": "1234567890", "943": "1234567890", "944": "1234567890", "945": "1234567890", "946": "1234567890", "947": "1234567890", "948": "1234567890", "949": "1234567890", "950": "1234567890", "951": "1234567890", "952": "1234567890", "953": "1234567890", "954": "1234567890", "955": "1234567890", "956": "1234567890", "957": "1234567890", "958": "1234567890", "959": "1234567890", "960": "1234567890", "961": "1234567890", "962": "1234567890", "963": "1234567890", "964": "1234567890", "965": "1234567890", "966": "1234567890", "967": "1234567890", "968": "1234567890", "969": "1234567890", "970": "1234567890", "971": "1234567890", "972": "1234567890", "973": "1234567890", "974": "1234567890", "975": "1234567890", "976": "1234567890", "977": "1234567890", "978": "1234567890", "979": "1234567890", "980": "1234567890", "981": "1234567890", "982": "1234567890", "983": "1234567890", "984": "1234567890", "985": "1234567890", "986": "1234567890", "987": "1234567890", "988": "1234567890", "989": "1234567890", "990": "1234567890", "991": "1234567890", "992": "1234567890", "993": "1234567890", "994": "1234567890", "995": "1234567890", "996": "1234567890", "997": "1234567890", "998": "1234567890", "999": "1234567890", "1000": "1234567890", "1001": "1234567890", "1002": "1234567890", "1003": "1234567890", "1004": "1234567890", "1005": "1234567890", "1006": "1234567890", "1007": "1234567890", "1008": "1234567890", "1009": "1234567890", "1010": "1234567890", "1011": "1234567890", "1012": "1234567890", "1013": "1234567890", "1014": "1234567890", "1015": "1234567890", "1016": "1234567890", "1017": "1234567890", "1018": "1234567890", "1019": "1234567890", "1020": "1234567890", "1021": "1234567890", "1022": "1234567890", "1023": "1234567890", "1024": "1234567890", "1025": "1234567890", "1026": "1234567890", "1027": "1234567890", "1028": "1234567890", "1029": "1234567890", "1030": "1234567890", "1031": "1234567890", "1032": "1234567890", "1033": "1234567890", "1034": "1234567890", "1035": "1234567890", "1036": "1234567890", "1037": "1234567890", "1038": "1234567890", "1039": "1234567890", "1040": "1234567890", "1041": "1234567890", "1042": "1234567890", "1043": "1234567890", "1044": "1234567890", "1045": "1234567890", "1046": "1234567890", "1047": "1234567890", "1048": "1234567890", "1049": "1234567890", "1050": "1234567890", "1051": "1234567890", "1052": "1234567890", "1053": "1234567890", "1054": "1234567890", "1055": "1234567890", "1056": "1234567890", "1057": "1234567890", "1058": "1234567890", "1059": "1234567890", "1060": "1234567890", "1061": "1234567890", "1062": "1234567890", "1063": "1234567890", "1064": "1234567890", "1065": "1234567890", "1066": "1234567890", "1067": "1234567890", "1068": "1234567890", "1069": "1234567890", "1070": "1234567890", "1071": "1234567890", "1072": "1234567890", "1073": "1234567890", "1074": "1234567890", "1075": "1234567890", "1076": "1234567890", "1077": "1234567890", "1078": "1234567890", "1079": "1234567890", "1080": "1234567890", "1081": "1234567890", "1082": "1234567890", "1083": "1234567890", "1084": "1234567890", "1085": "1234567890", "1086": "1234567890", "1087": "1234567890", "1088": "1234567890", "1089": "1234567890", "1090": "1234567890", "1091": "1234567890", "1092": "1234567890", "1093": "1234567890", "1094": "1234567890", "1095": "1234567890", "1096": "1234567890", "1097": "1234567890", "1098": "1234567890", "1099": "1234567890", "1100": "1234567890", "1101": "1234567890", "1102": "1234567890", "1103": "1234567890", "1104": "1234567890", "1105": "1234567890", "1106": "1234567890", "1107": "1234567890", "1108": "1234567890", "1109": "1234567890", "1110": "1234567890", "1111": "1234567890", "1112": "1234567890", "1113": "1234567890", "1114": "1234567890", "1115": "1234567890", "1116": "1234567890", "1117": "1234567890", "1118": "1234567890", "1119": "1234567890", "1120": "1234567890", "1121": "1234567890", "1122": "1234567890", "1123": "1234567890", "1124": "1234567890", "1125": "1234567890", "1126": "1234567890", "1127": "1234567890", "1128": "1234567890", "1129": "1234567890", "1130": "1234567890", "1131": "1234567890", "1132": "1234567890", "1133": "1234567890", "1134": "1234567890", "1135": "1234567890", "1136": "1234567890", "1137": "1234567890", "1138": "1234567890", "1139": "1234567890", "1140": "1234567890", "1141": "1234567890", "1142": "1234567890", "1143": "1234567890", "1144": "1234567890", "1145": "1234567890", "1146": "1234567890", "1147": "1234567890", "1148": "1234567890", "1149": "1234567890", "1150": "1234567890", "1151": "1234567890", "1152": "1234567890", "1153": "1234567890", "1154": "1234567890", "1155": "1234567890", "1156": "1234567890", "1157": "1234567890", "1158": "1234567890", "1159": "1234567890", "1160": "1234567890", "1161": "1234567890", "1162": "1234567890", "1163": "1234567890", "1164": "1234567890", "1165": "1234567890", "1166": "1234567890", "1167": "1234567890", "1168": "1234567890", "1169": "1234567890", "1170": "1234567890", "1171": "1234567890", "1172": "1234567890", "1173": "1234567890", "1174": "1234567890", "1175": "1234567890", "1176": "1234567890", "1177": "1234567890", "1178": "1234567890", "1179": "1234567890", "1180": "1234567890", "1181": "1234567890", "1182": "1234567890", "1183": "1234567890", "1184": "1234567890", "1185": "1234567890", "1186": "1234567890", "1187": "1234567890", "1188": "1234567890", "1189": "1234567890", "1190": "1234567890", "1191": "1234567890", "1192": "1234567890", "1193": "1234567890", "1194": "1234567890", "1195": "1234567890", "1196": "1234567890", "1197": "1234567890", "1198": "1234567890", "1199": "1234567890", "1200": "1234567890", "1201": "1234567890", "1202": "1234567890", "1203": "1234567890", "1204": "1234567890", "1205": "1234567890", "1206": "1234567890", "1207": "1234567890", "1208": "1234567890", "1209": "1234567890", "1210": "1234567890", "1211": "1234567890", "1212": "1234567890", "1213": "1234567890", "1214": "1234567890", "1215": "1234567890", "1216": "1234567890", "1217": "1234567890", "1218": "1234567890", "1219": "1234567890", "1220": "1234567890", "1221": "1234567890", "1222": "1234567890", "1223": "1234567890", "1224": "1234567890", "1225": "1234567890", "1226": "1234567890", "1227": "1234567890", "1228": "1234567890", "1229": "1234567890", "1230": "1234567890", "1231": "1234567890", "1232": "1234567890", "1233": "1234567890", "1234": "1234567890", "1235": "1234567890", "1236": "1234567890", "1237": "1234567890", "1238": "1234567890", "1239": "1234567890", "1240": "1234567890", "1241": "1234567890", "1242": "1234567890", "1243": "1234567890", "1244": "1234567890", "1245": "1234567890", "1246": "1234567890", "1247": "1234567890", "1248": "1234567890", "1249": "1234567890", "1250": "1234567890", "1251": "1234567890", "1252": "1234567890", "1253": "1234567890", "1254": "1234567890", "1255": "1234567890", "1256": "1234567890", "1257": "1234567890", "1258": "1234567890", "1259": "1234567890", "1260": "1234567890", "1261": "1234567890", "1262": "1234567890", "1263": "1234567890", "1264": "1234567890", "1265": "1234567890", "1266": "1234567890", "1267": "1234567890", "1268": "1234567890", "1269": "1234567890", "1270": "1234567890", "1271": "1234567890", "1272": "1234567890", "1273": "1234567890", "1274": "1234567890", "1275": "1234567890", "1276": "1234567890", "1277": "1234567890", "1278": "1234567890", "1279": "1234567890", "1280": "1234567890", "1281": "1234567890", "1282": "1234567890", "1283": "1234567890", "1284": "1234567890", "1285": "1234567890", "1286": "1234567890", "1287": "1234567890", "1288": "1234567890", "1289": "1234567890", "1290": "1234567890", "1291": "1234567890", "1292": "1234567890", "1293": "1234567890", "1294": "1234567890", "1295": "1234567890", "1296": "1234567890", "1297": "1234567890", "1298": "1234567890", "1299": "1234567890", "1300": "1234567890", "1301": "1234567890", "1302": "1234567890", "1303": "1234567890", "1304": "1234567890", "1305": "1234567890", "1306": "1234567890", "1307": "1234567890", "1308": "1234567890", "1309": "1234567890", "1310": "1234567890", "1311": "1234567890", "1312": "1234567890", "1313": "1234567890", "1314": "1234567890", "1315": "1234567890", "1316": "1234567890", "1317": "1234567890", "1318": "1234567890", "1319": "1234567890", "1320": "1234567890", "1321": "1234567890", "1322": "1234567890", "1323": "1234567890", "1324": "1234567890", "1325": "1234567890", "1326": "1234567890", "1327": "1234567890", "1328": "1234567890", "1329": "1234567890", "1330": "1234567890", "1331": "1234567890", "1332": "1234567890", "1333": "1234567890", "1334": "1234567890", "1335": "1234567890", "1336": "1234567890", "1337": "1234567890", "1338": "1234567890", "1339": "1234567890", "1340": "1234567890", "1341": "1234567890", "1342": "1234567890", "1343": "1234567890", "1344": "1234567890", "1345": "1234567890", "1346": "1234567890", "1347": "1234567890", "1348": "1234567890", "1349": "1234567890", "1350": "1234567890", "1351": "1234567890", "1352": "1234567890", "1353": "1234567890", "1354": "1234567890", "1355": "1234567890", "1356": "1234567890", "1357": "1234567890", "1358": "1234567890", "1359": "1234567890", "1360": "1234567890", "1361": "1234567890", "1362": "1234567890", "1363": "1234567890", "1364": "1234567890", "1365": "1234567890", "1366": "1234567890", "1367": "1234567890", "1368": "1234567890", "1369": "1234567890", "1370": "1234567890", "1371": "1234567890", "1372": "1234567890", "1373": "1234567890", "1374": "1234567890", "1375": "1234567890", "1376": "1234567890", "1377": "1234567890", "1378": "1234567890", "1379": "1234567890", "1380": "1234567890", "1381": "1234567890", "1382": "1234567890", "1383": "1234567890", "1384": "1234567890", "1385": "1234567890", "1386": "1234567890", "1387": "1234567890", "1388": "1234567890", "1389": "1234567890", "1390": "1234567890", "1391": "1234567890", "1392": "1234567890", "1393": "1234567890", "1394": "1234567890", "1395": "1234567890", "1396": "1234567890", "1397": "1234567890", "1398": "1234567890", "1399": "1234567890", "1400": "1234567890", "1401": "1234567890", "1402": "1234567890", "1403": "1234567890", "1404": "1234567890", "1405": "1234567890", "1406": "1234567890", "1407": "1234567890", "1408": "1234567890", "1409": "1234567890", "1410": "123456
I0912 16:19:34.920866 139104256 cql_message.h:254] CQL int 5000
I0912 16:19:34.920886 139104256 cql_message.h:254] CQL long 1568294369584652
I0912 16:19:34.920907 139104256 cql_processor.cc:301] EXECUTE 93c9bd0f9daed64e5315226aafc34bf7
I0912 16:19:34.920946 139104256 hybrid_clock.cc:147] Current clock is higher than the last one. Resetting logical values. Time: { physical: 1568294374920943 }, Error: 50000
I0912 16:19:34.920979 139104256 eval_col.cc:50] WRITE request, column id = 10
I0912 16:19:34.921002 139104256 eval_col.cc:50] WRITE request, column id = 11
E0912 16:19:34.921030 139104256 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
INSERT INTO ybdemo.ugent(id, data) VALUES (?, ?)
       ^^^^
I0912 16:19:34.921111 139104256 cql_service.cc:228] DeletePreparedStatement: CQL prepared statement cache count = 0/0, memory usage = 8192
I0912 16:19:34.921135 139104256 inbound_call.cc:161] 0x00000001125cf9a0 -> CQL Call from 127.0.0.1:50223, stream id: 16: Completed handling


I0912 16:19:34.921237 155738112 tcp_stream.cc:412] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Added data queued_bytes_to_send_: 49
I0912 16:19:34.921306 155738112 tcp_stream.cc:199] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Queued writes 49 bytes. written 49 . Status OK sending_ .size() 1
I0912 16:19:34.921891 155738112 tcp_stream.cc:242] { local: 127.0.0.1:9042 remote: 127.0.0.1:50223 }: Handler(revents=1)
I0912 16:19:34.921954 155738112 inbound_call.cc:139] 0x00000001125cf9a0 -> CQL Call from 127.0.0.1:50223, stream id: 16: Received
I0912 16:19:34.922044 139640832 inbound_call.cc:148] 0x00000001125cf9a0 -> CQL Call from 127.0.0.1:50223, stream id: 17: Handling
I0912 16:19:34.922087 139640832 cql_service.cc:133] Handling CQL Call from 127.0.0.1:50223, stream id: 17
I0912 16:19:34.922112 139640832 cql_message.cc:204] CQL message version 0x4 flags 0x0 stream id 17 opcode 0x9 length 52
I0912 16:19:34.922130 139640832 cql_message.h:254] CQL int 48
I0912 16:19:34.922145 139640832 cql_message.h:266] CQL long string INSERT INTO ybdemo.ugent(id, data) VALUES (?, ?)
I0912 16:19:34.922160 139640832 cql_processor.cc:277] PREPARE INSERT INTO ybdemo.ugent(id, data) VALUES (?, ?)
I0912 16:19:34.922181 139640832 cql_service.cc:189] InsertPreparedStatement: CQL prepared statement cache count = 1/1, memory usage = 0
I0912 16:19:34.932498 120324096 reactor.cc:419] TabletServer_R000: timer tick at 32672.5
I0912 16:19:34.932502 120860672 reactor.cc:419] TabletServer_R001: timer tick at 32672.5
I0912 16:19:34.932509 121397248 reactor.cc:419] TabletServer_R002: timer tick at 32672.5
I0912 16:19:34.932510 121933824 reactor.cc:419] TabletServer_R003: timer tick at 32672.5

@OlegLoginov
Copy link
Contributor

OlegLoginov commented Sep 12, 2019

For one INSERT statement (in the user script) the TS gets a lot of CQL requests. It looks like an endless retry loop on the driver side. I'll try to reproduce it from JAVA code.

@OlegLoginov
Copy link
Contributor

OlegLoginov commented Sep 12, 2019

The issue is reproduced in JAVA bu with bigger JSON string length = 69 MB (46 MB works).
Log:

2019-09-12 19:18:27,052 (Time-limited test) [INFO - org.yb.cql.TestBindVariable.testPrepareInsertBindLongJson(TestBindVariable.java:523)] Executing INSERT for JSON data len = 69000001
2019-09-12 19:18:27,052 (Time-limited test) [INFO - org.yb.cql.TestBindVariable.testPrepareInsertBindLongJson(TestBindVariable.java:524)] -----------------------------------------------------------
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:27.702262 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:27,703 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:27.778839 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:27,779 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:27.856915 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:27,857 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:27.959961 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:27,961 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:28.069698 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:28,070 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:28.156378 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:28,156 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:28.293952 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:28,294 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:28.380549 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:28,381 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:28.451920 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:28,452 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:28.572064 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:28,574 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:28.681512 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:28,682 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:28.758363 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:28,758 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 I0912 19:18:28.850482 240726016 maintenance_manager.cc:342] we have exceeded our soft memory limit (current capacity is 92.71%).  However, there are no ops currently runnable which would free memory.
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:28.896049 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:28,896 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 E0912 19:18:28.968135 249311232 process_context.cc:180] SQL Error: Invalid Arguments. YQL value too long
ts2|pid35927|:15332|http://127.0.0.116:30534 INSERT INTO test_bind (id, data) VALUES (?, ?);
ts2|pid35927|:15332|http://127.0.0.116:30534        ^^^^
2019-09-12 19:18:28,968 (cluster1-nio-worker-4) [INFO - com.datastax.driver.core.RequestHandler$SpeculativeExecution.onSet(RequestHandler.java:618)] Query INSERT INTO test_bind (id, data) VALUES (?, ?); is not prepared on /127.0.0.116:9042, preparing before retrying executing. Seeing this message a few times is fine, but seeing it a lot may be source of performance problems
ts2|pid35927|:15332|http://127.0.0.116:30534 W0912 19:18:29.009467 244482048 binary_call_parser.cc:72] Unable to allocate read buffer because of limit, required: 69000057, blocked by: 0x00000001086aa020 -> root, consumption: 1064469797 of 1073741824
ts2|pid35927|:15332|http://127.0.0.116:30534 W0912 19:18:29.009536 244482048 tcp_stream.cc:127] { local: 127.0.0.116:9042 remote: 127.0.0.116:58514 }: Shutting down with pending inbound data ({ capacity: 408300 pos: 0 size: 408300 }, status = Invalid argument (yb/rpc/connection.cc:284): Command is greater than read buffer, exist data: 408300)
ts2|pid35927|:15332|http://127.0.0.116:30534 W0912 19:18:29.009765 244482048 tcp_stream.cc:127] { local: 127.0.0.116:9042 remote: 127.0.0.116:58514 }: Shutting down with pending inbound data ({ capacity: 408300 pos: 0 size: 408300 }, status = Service unavailable (yb/rpc/reactor.cc:90): Shutdown connection (error 58))
2019-09-12 19:18:29,018 (Time-limited test) [INFO - org.yb.cql.BaseCQLTest.tearDownAfter(BaseCQLTest.java:224)] BaseCQLTest.tearDownAfter: dropping tables / types / keyspaces
2019-09-12 19:18:29,022 (Time-limited test) [INFO - org.yb.cql.BaseCQLTest.dropTable(BaseCQLTest.java:377)] Executing drop table: DROP TABLE cql_test_keyspace.test_bind;

@OlegLoginov
Copy link
Contributor

OlegLoginov commented Sep 12, 2019

Java test:

  @Test
  public void testPrepareInsertBindLongJson() throws Exception {
    LOG.info("Begin test");

    // Create table
    String createStmt = "CREATE TABLE test_bind (id int PRIMARY KEY, data jsonb);";
    session.execute(createStmt);

    // Insert data into the test table. Bind by name.
    String insertStmt = "INSERT INTO test_bind (id, data) VALUES (?, ?);";
    PreparedStatement stmt = session.prepare(insertStmt);

    for (int j = 1; j <= 2; ++j) {
      StringBuilder builder = new StringBuilder();
      builder.append("{\"0000000\":\"1234567890\"");
      for (int i = 1; i < (j == 1 ? 5 : 3000000); ++i) {
        builder.append(String.format(",\"%07d\":\"1234567890\"", i));
      }
      String jsonStr = builder.toString() + "}";

      LOG.info("Executing INSERT for JSON data length = " + jsonStr.length());

      // Insert long JSON value.
      session.execute(stmt.bind()
              .setInt("id", j)
              .setString("data", jsonStr));
      LOG.info("INSERT executed");

      // Select data from the test table.
      String selectStmt = String.format("SELECT * FROM test_bind WHERE id = %d;", j);
      ResultSet rs = session.execute(selectStmt);
      Row row = rs.one();
      // Assert exactly 1 row is returned each time with expected column values.
      assertNotNull(row);
      assertEquals(j, row.getInt(0));
      assertEquals(jsonStr, row.getJson("data"));
    }

    LOG.info("End test");
  }

@OlegLoginov
Copy link
Contributor

Root cause: the error code mapping: (value too long) Status::InvalidArgument -> ErrorCode::INVALID_ARGUMENTS -> ErrorCode::STALE_METADATA -> Code::UNPREPARED (YCQL error code). UNPREPARED code is retryable in the driver.
(In case of Python driver the retry loop is endless, so the user will never get the error message.)

Fix: the fixed error code mapping is: (value too long) Status::NotSupported -> ErrorCode::INVALID_REQUEST -> Code::INVALID (non-retryable YCQL error code).

OlegLoginov added a commit that referenced this issue Sep 27, 2019
Summary:
Fix for #2116: YQL value too long INSERT INTO ybdemo.ugent(id, data) VALUES (?, ?) with overlong JSON data

Originally we had the codes mapping:  (value too long) Status::InvalidArgument -> ErrorCode::INVALID_ARGUMENTS -> ErrorCode::STALE_METADATA -> Code::UNPREPARED (YCQL error code).  UNPREPARED code is retryable in the driver.

Now  the error code mapping is:   (value too long) Status::NotSupported -> ErrorCode::INVALID_REQUEST -> Code::INVALID (non-retryable YCQL error code).

Test Plan: ybd --java-test org.yb.cql.TestBindVariable#testPrepareInsertBindLongJson

Reviewers: mihnea, neil, kannan

Reviewed By: neil, kannan

Subscribers: yql, kannan

Differential Revision: https://phabricator.dev.yugabyte.com/D7220
@OlegLoginov
Copy link
Contributor

Fixed by the commmit above.

OlegLoginov added a commit that referenced this issue Sep 30, 2019
Summary:
Fix for failed org.yb.cql.TestInsertValues#testLargeInsert

Old error message: SQL Error: Invalid Arguments. YQL value too long
New error message: SQL Error: Invalid Request. Value size (67108872) is longer than max value size supported (67108864)

Main fix:  7c76527

Test Plan:
ybd --java-test org.yb.cql.TestInsertValues#testLargeInsert

Reviewers: mikhail

Reviewed By: mikhail

Subscribers: yql

Differential Revision: https://phabricator.dev.yugabyte.com/D7308
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
community/request Issues created by external users kind/bug This issue is a bug
Projects
None yet
Development

No branches or pull requests

4 participants