UPSERT INTO kv (k, v) VALUES ($1, $2) 0.000ms 0.000ms === operation:plan-gist bundle _unfinished:1 _verbose:1 node:3 client:10.142.0.80:53428 hostssl: user:roachprod 0.000ms 0.000ms [/cockroach.roachpb.Internal/Batch: {count: 2, duration 105ms}] 0.000ms 0.000ms [flow: {count: 1, duration 54ms}] 0.000ms 0.000ms [columnarizer: {count: 2, duration 54ms}] 0.000ms 0.000ms [batch flow coordinator: {count: 1, duration 54ms}] 0.000ms 0.000ms [count: {count: 1, duration 54ms}] 0.000ms 0.000ms [txn coordinator send: {count: 1, duration 54ms}] 0.000ms 0.000ms [dist sender send: {count: 1, duration 53ms}] 0.000ms 0.000ms [executeWriteBatch: {count: 1, duration 4ms}] 0.000ms 0.000ms [local proposal: {count: 1, duration 170µs}] 0.000ms 0.000ms [materializer: {count: 1, duration 89µs}] 0.000ms 0.000ms [values: {count: 1, duration 28µs}] 0.036ms 0.036ms event:sql/instrumentation.go:569 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] plan-gist matching bundle collection began after the optimizer finished its part 0.061ms 0.025ms event:sql/conn_executor_exec.go:1187 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] planning ends 0.082ms 0.021ms event:sql/conn_executor_exec.go:1187 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] checking distributability 0.124ms 0.041ms event:sql/exec_util.go:1937 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] query not supported for distSQL: unsupported node 0.140ms 0.016ms event:sql/conn_executor_exec.go:1187 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] will distribute plan: false 0.160ms 0.020ms event:sql/conn_executor_exec.go:1187 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] executing after 0 retries, last retry reason: 0.176ms 0.016ms event:sql/conn_executor_exec.go:1187 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] execution starts: distributed engine 0.195ms 0.019ms event:sql/distsql_running.go:1974 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] creating DistSQL plan with isLocal=true 0.227ms 0.032ms event:sql/distsql_running.go:830 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] running DistSQL plan 0.230ms 0.003ms === operation:flow _verbose:1 node:3 client:10.142.0.80:53428 hostssl: user:roachprod 0.230ms 0.000ms [/cockroach.roachpb.Internal/Batch: {count: 2, duration 105ms}] 0.230ms 0.000ms [columnarizer: {count: 2, duration 54ms}] 0.230ms 0.000ms [batch flow coordinator: {count: 1, duration 54ms}] 0.230ms 0.000ms [count: {count: 1, duration 54ms}] 0.230ms 0.000ms [txn coordinator send: {count: 1, duration 54ms}] 0.230ms 0.000ms [dist sender send: {count: 1, duration 53ms}] 0.230ms 0.000ms [executeWriteBatch: {count: 1, duration 4ms}] 0.230ms 0.000ms [local proposal: {count: 1, duration 170µs}] 0.230ms 0.000ms [materializer: {count: 1, duration 89µs}] 0.230ms 0.000ms [values: {count: 1, duration 28µs}] 0.252ms 0.022ms event:sql/colflow/vectorized_flow.go:226 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] setting up vectorized flow 0.279ms 0.027ms event:sql/colexec/colbuilder/execplan.go:523 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] planning a row-execution processor in the vectorized flow: LocalPlanNode core needs to be wrapped 0.307ms 0.028ms event:sql/colexec/colbuilder/execplan.go:523 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] planning a row-execution processor in the vectorized flow: LocalPlanNode core needs to be wrapped 0.329ms 0.022ms event:sql/colflow/vectorized_flow.go:267 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] vectorized flow setup succeeded 0.404ms 0.075ms event:sql/flowinfra/flow.go:460 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] starting (0 processors, 0 startables) asynchronously 0.421ms 0.017ms event:sql/colflow/vectorized_flow.go:314 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] running the batch flow coordinator in the flow's goroutine 0.422ms 0.001ms === operation:batch flow coordinator _verbose:1 node:3 client:10.142.0.80:53428 hostssl: user:roachprod cockroach.flowid:788f1f7c-f6bd-4722-b1e6-aaa2928c02c3 cockroach.processorid:1 0.422ms 0.000ms [/cockroach.roachpb.Internal/Batch: {count: 2, duration 105ms}] 0.422ms 0.000ms [columnarizer: {count: 2, duration 54ms}] 0.422ms 0.000ms [count: {count: 1, duration 54ms}] 0.422ms 0.000ms [txn coordinator send: {count: 1, duration 54ms}] 0.422ms 0.000ms [dist sender send: {count: 1, duration 53ms}] 0.422ms 0.000ms [executeWriteBatch: {count: 1, duration 4ms}] 0.422ms 0.000ms [local proposal: {count: 1, duration 170µs}] 0.422ms 0.000ms [materializer: {count: 1, duration 89µs}] 0.422ms 0.000ms [values: {count: 1, duration 28µs}] 0.425ms 0.004ms === operation:columnarizer _verbose:1 node:3 client:10.142.0.80:53428 hostssl: user:roachprod cockroach.flowid:788f1f7c-f6bd-4722-b1e6-aaa2928c02c3 cockroach.processorid:1 0.425ms 0.000ms [/cockroach.roachpb.Internal/Batch: {count: 2, duration 105ms}] 0.425ms 0.000ms [count: {count: 1, duration 54ms}] 0.425ms 0.000ms [txn coordinator send: {count: 1, duration 54ms}] 0.425ms 0.000ms [dist sender send: {count: 1, duration 53ms}] 0.425ms 0.000ms [executeWriteBatch: {count: 1, duration 4ms}] 0.425ms 0.000ms [local proposal: {count: 1, duration 170µs}] 0.425ms 0.000ms [materializer: {count: 1, duration 89µs}] 0.425ms 0.000ms [columnarizer: {count: 1, duration 79µs}] 0.425ms 0.000ms [values: {count: 1, duration 28µs}] 0.427ms 0.002ms === operation:count _verbose:1 node:3 client:10.142.0.80:53428 hostssl: user:roachprod cockroach.flowid:788f1f7c-f6bd-4722-b1e6-aaa2928c02c3 cockroach.processorid:1 0.427ms 0.000ms [/cockroach.roachpb.Internal/Batch: {count: 2, duration 105ms}] 0.427ms 0.000ms [txn coordinator send: {count: 1, duration 54ms}] 0.427ms 0.000ms [dist sender send: {count: 1, duration 53ms}] 0.427ms 0.000ms [executeWriteBatch: {count: 1, duration 4ms}] 0.427ms 0.000ms [local proposal: {count: 1, duration 170µs}] 0.427ms 0.000ms [materializer: {count: 1, duration 89µs}] 0.427ms 0.000ms [columnarizer: {count: 1, duration 79µs}] 0.427ms 0.000ms [values: {count: 1, duration 28µs}] 0.430ms 0.003ms === operation:materializer _verbose:1 node:3 client:10.142.0.80:53428 hostssl: user:roachprod cockroach.flowid:788f1f7c-f6bd-4722-b1e6-aaa2928c02c3 cockroach.processorid:1 0.430ms 0.000ms [columnarizer: {count: 1, duration 79µs}] 0.430ms 0.000ms [values: {count: 1, duration 28µs}] 0.432ms 0.002ms === operation:columnarizer _verbose:1 node:3 client:10.142.0.80:53428 hostssl: user:roachprod cockroach.flowid:788f1f7c-f6bd-4722-b1e6-aaa2928c02c3 cockroach.processorid:0 0.432ms 0.000ms [values: {count: 1, duration 28µs}] 0.433ms 0.002ms === operation:values _verbose:1 node:3 client:10.142.0.80:53428 hostssl: user:roachprod cockroach.flowid:788f1f7c-f6bd-4722-b1e6-aaa2928c02c3 cockroach.processorid:0 0.510ms 0.080ms event:ComponentStats{ID: {788f1f7c-f6bd-4722-b1e6-aaa2928c02c3 PROCESSOR 0 3 }, execution time: 28µs, sql cpu time: 28µs, batches output: 1, rows output: 1} 0.483ms -0.027ms structured:{"@type":"type.googleapis.com/cockroach.sql.distsqlrun.ComponentStats","component":{"flowId":"788f1f7c-f6bd-4722-b1e6-aaa2928c02c3","type":"PROCESSOR","sqlInstanceId":3},"netRx":{"latency":{},"waitTime":{},"deserializationTime":{},"tuplesReceived":{},"bytesReceived":{},"messagesReceived":{}},"netTx":{"tuplesSent":{},"bytesSent":{},"messagesSent":{}},"kv":{"bytesRead":{},"kvPairsRead":{},"tuplesRead":{},"batchRequestsIssued":{},"kvTime":{},"contentionTime":{},"numInterfaceSteps":{},"numInternalSteps":{},"numInterfaceSeeks":{},"numInternalSeeks":{},"blockBytes":{},"blockBytesInCache":{},"keyBytes":{},"valueBytes":{},"pointCount":{},"pointsCoveredByRangeTombstones":{},"rangeKeyCount":{},"rangeKeyContainedPoints":{},"rangeKeySkippedPoints":{},"kvCpuTime":{},"numGets":{},"numScans":{},"numReverseScans":{}},"exec":{"execTime":{"valuePlusOne":"0.000028011s"},"maxAllocatedMem":{},"maxAllocatedDisk":{},"consumedRu":{},"cpuTime":{"valuePlusOne":"0.000028107s"}},"output":{"numBatches":{"valuePlusOne":"2"},"numTuples":{"valuePlusOne":"2"}},"flowStats":{"maxMemUsage":{},"maxDiskUsage":{},"consumedRu":{}}} 0.540ms 0.113ms event:sql/tablewriter.go:237 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] autocommit enabled 0.554ms 0.013ms event:sql/tablewriter.go:238 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] writing batch with 1 requests and committing 0.557ms 0.003ms === operation:txn coordinator send _verbose:1 node:3 client:10.142.0.80:53428 hostssl: user:roachprod txnID:75c434cf-e353-478b-9cc7-0d1796a905aa 0.557ms 0.000ms [/cockroach.roachpb.Internal/Batch: {count: 2, duration 105ms}] 0.557ms 0.000ms [dist sender send: {count: 1, duration 53ms}] 0.557ms 0.000ms [executeWriteBatch: {count: 1, duration 4ms}] 0.557ms 0.000ms [local proposal: {count: 1, duration 170µs}] 0.600ms 0.043ms event:kv/kvclient/kvcoord/txn_interceptor_heartbeater.go:319 [n3,client=10.142.0.80:53428,hostssl,user=roachprod,txn=75c434cf] coordinator spawns heartbeat loop 0.608ms 0.008ms === operation:dist sender send _verbose:1 node:3 client:10.142.0.80:53428 hostssl: user:roachprod txn:75c434cf 0.608ms 0.000ms [/cockroach.roachpb.Internal/Batch: {count: 2, duration 105ms}] 0.608ms 0.000ms [executeWriteBatch: {count: 1, duration 4ms}] 0.608ms 0.000ms [local proposal: {count: 1, duration 170µs}] 0.643ms 0.034ms event:kv/kvclient/kvcoord/range_iter.go:183 [n3,client=10.142.0.80:53428,hostssl,user=roachprod,txn=75c434cf] querying next range at /Table/106/1/0/0 0.712ms 0.069ms event:kv/kvclient/kvcoord/range_iter.go:220 [n3,client=10.142.0.80:53428,hostssl,user=roachprod,txn=75c434cf] key: /Table/106/1/0/0, desc: r150:/Table/106/1/{-91320515216384008-1} [(n4,s4):1, (n3,s3):2, (n2,s2):4, next=5, gen=33, sticky=9223372036.854775807,2147483647] 0.747ms 0.035ms event:kv/kvclient/kvcoord/dist_sender.go:2624 [n3,client=10.142.0.80:53428,hostssl,user=roachprod,txn=75c434cf] r150: sending batch 1 Put, 1 EndTxn to (n2,s2):4 0.769ms 0.021ms event:rpc/nodedialer/nodedialer.go:143 [n3,client=10.142.0.80:53428,hostssl,user=roachprod,txn=75c434cf] sending request to 10.142.1.6:26257 0.785ms 0.016ms event:kv/kvclient/kvcoord/transport.go:208 [n3,client=10.142.0.80:53428,hostssl,user=roachprod,txn=75c434cf] sending batch request 0.788ms 0.003ms === operation:/cockroach.roachpb.Internal/Batch _verbose:1 node:3 client:10.142.0.80:53428 hostssl: user:roachprod txn:75c434cf span.kind:client 1.621ms 0.833ms === operation:/cockroach.roachpb.Internal/Batch _verbose:1 node:2 span.kind:server request:Put [/Table/106/1/0/0], EndTxn(parallel commit) [/Table/106/1/0/0], [txn: 75c434cf], [can-forward-ts] 1.621ms 0.000ms [executeWriteBatch: {count: 1, duration 4ms}] 1.621ms 0.000ms [local proposal: {count: 1, duration 170µs}] 1.648ms 0.027ms event:server/node.go:1602 [n2] node received request: 1 Put, 1 EndTxn 1.713ms 0.064ms event:kv/kvserver/store_send.go:149 [n2,s2] executing Put [/Table/106/1/0/0], EndTxn(parallel commit) [/Table/106/1/0/0], [txn: 75c434cf], [can-forward-ts] 1.736ms 0.024ms event:kv/kvserver/replica_send.go:175 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] read-write path 1.762ms 0.026ms event:kv/kvserver/concurrency/concurrency_manager.go:209 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] sequencing request 1.780ms 0.017ms event:kv/kvserver/concurrency/concurrency_manager.go:290 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] acquiring latches 1.923ms 0.144ms event:kv/kvserver/spanlatch/manager.go:579 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] waiting to acquire write latch /Table/106/1/0/0@1729105463.671246322,0 for request Put [/Table/106/1/0/0], EndTxn(commit) [/Table/106/1/0/0], [txn: 75c434cf], [can-forward-ts], held by write latch /Table/106/1/0/0@1729105463.666914229,0 for request Put [/Table/106/1/0/0], EndTxn(commit) [/Table/106/1/0/0], [txn: a92f9f05], [can-forward-ts] 49.053ms 47.130ms event:kv/kvserver/spanlatch/manager.go:579 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] waiting to acquire write latch /Table/106/1/0/0@1729105463.671246322,0 for request Put [/Table/106/1/0/0], EndTxn(commit) [/Table/106/1/0/0], [txn: 75c434cf], [can-forward-ts], held by read latch /Table/106/1/0/0@1729105464.140304251,0 for request Get [/Table/106/1/0/0], [txn: fd1042fc], [can-forward-ts], [max_span_request_keys: 0], [target_bytes: 10485760] 49.579ms 0.526ms event:kv/kvserver/concurrency/concurrency_manager.go:334 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] scanning lock table for conflicting locks 49.582ms 0.003ms === operation:executeWriteBatch _verbose:1 node:2 store:2 range:150/4:/Table/106/1/{-91320…-1} 49.582ms 0.000ms [local proposal: {count: 1, duration 170µs}] 49.618ms 0.036ms event:kv/kvserver/replica_write.go:176 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] applied timestamp cache 49.637ms 0.019ms event:kv/kvserver/replica_write.go:432 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] executing read-write batch 49.670ms 0.033ms event:kv/kvserver/replica_write.go:526 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] attempting 1PC execution 49.866ms 0.196ms event:kv/kvserver/replica_evaluate.go:511 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] evaluated Put command header: key:"\362\211\210\210" sequence:1 > value: > , txn= : resp=header:<> , err= 49.899ms 0.032ms event:kv/kvserver/replica_proposal.go:1020 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] need consensus on write batch with op count=1 49.964ms 0.066ms event:kv/kvserver/replica_raft.go:126 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] evaluated request 49.988ms 0.024ms event:kv/kvserver/replica_raft.go:171 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] proposing command to write 0 new keys, 1 new values, 0 new intents, write batch size=39 bytes 50.012ms 0.024ms event:kv/kvserver/replica_raft.go:284 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] acquiring proposal quota (169 bytes) 50.048ms 0.036ms event:kv/kvserver/replica_raft.go:462 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] submitting proposal to proposal buffer 50.081ms 0.033ms event:kv/kvserver/replica_proposal_buf.go:575 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] flushing proposal to Raft 50.193ms 0.111ms event:kv/kvserver/replica_proposal_buf.go:1185 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] registering local trace i27814158/e928.f674 50.260ms 0.067ms event:kv/kvserver/replica_raft.go:1998 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] 4->1 MsgApp Term:13 Log:13/27814157 Range:27814158-27814158 50.309ms 0.049ms event:kv/kvserver/replica_raft.go:1998 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] 4->2 MsgApp Term:13 Log:13/27814157 Range:27814158-27814158 50.361ms 0.052ms event:kv/kvserver/replica_raft.go:1192 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] 4->AppendThread MsgStorageAppend Term:0 Log:13/27814158 Range:27814158-27814158 50.708ms 0.347ms event:kv/kvserver/replica_raft.go:1974 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] 4->4 MsgAppResp Term:13 Index:27814158 50.747ms 0.040ms event:kv/kvserver/replica_raft.go:1974 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] AppendThread->4 MsgStorageAppendResp Log:13/27814158 52.853ms 2.105ms event:kv/kvserver/replica_raft.go:619 [n2,s2,r150/4:/Table/106/1/{-91320…-1}] 1->4 MsgAppResp Term:13 Index:27814158 52.903ms 0.050ms === operation:local proposal _verbose:1 node:2 store:2 range:150/4:/Table/106/1/{-91320…-1} raft: 52.930ms 0.027ms event:kv/kvserver/replica_application_cmd.go:143 [n2,s2,r150/4:/Table/106/1/{-91320…-1},raft] ack-ing replication success to the client; application will continue async w.r.t. the client 52.985ms 0.055ms event:kv/kvserver/app_batch.go:116 [n2,s2,r150/4:/Table/106/1/{-91320…-1},raft] applying command 53.046ms 0.061ms event:kv/kvserver/replica_application_state_machine.go:178 [n2,s2,r150/4:/Table/106/1/{-91320…-1},raft] LocalResult (reply: (err: ), *kvpb.PutResponse, *kvpb.EndTxnResponse, #encountered intents: 0, #acquired locks: 0, #resolved locks: 1#updated txns: 1 #end txns: 0, PopulateBarrierResponse:false GossipFirstRange:false MaybeGossipSystemConfig:false MaybeGossipSystemConfigIfHaveFailure:false MaybeAddToSplitQueue:false MaybeGossipNodeLiveness: 53.773ms 4.194ms event:server/node.go:1696 [n2] node sending response 54.074ms 53.289ms event:kv/kvclient/kvcoord/transport.go:210 [n3,client=10.142.0.80:53428,hostssl,user=roachprod,txn=75c434cf] received batch response 54.189ms 53.768ms event:ComponentStats{ID: {788f1f7c-f6bd-4722-b1e6-aaa2928c02c3 PROCESSOR 1 3 }, execution time: 54ms, sql cpu time: 540µs, batches output: 1, rows output: 1} 54.156ms -0.033ms structured:{"@type":"type.googleapis.com/cockroach.sql.distsqlrun.ComponentStats","component":{"flowId":"788f1f7c-f6bd-4722-b1e6-aaa2928c02c3","type":"PROCESSOR","id":1,"sqlInstanceId":3},"netRx":{"latency":{},"waitTime":{},"deserializationTime":{},"tuplesReceived":{},"bytesReceived":{},"messagesReceived":{}},"netTx":{"tuplesSent":{},"bytesSent":{},"messagesSent":{}},"kv":{"bytesRead":{},"kvPairsRead":{},"tuplesRead":{},"batchRequestsIssued":{},"kvTime":{},"contentionTime":{},"numInterfaceSteps":{},"numInternalSteps":{},"numInterfaceSeeks":{},"numInternalSeeks":{},"blockBytes":{},"blockBytesInCache":{},"keyBytes":{},"valueBytes":{},"pointCount":{},"pointsCoveredByRangeTombstones":{},"rangeKeyCount":{},"rangeKeyContainedPoints":{},"rangeKeySkippedPoints":{},"kvCpuTime":{},"numGets":{},"numScans":{},"numReverseScans":{}},"exec":{"execTime":{"valuePlusOne":"0.053690639s"},"maxAllocatedMem":{},"maxAllocatedDisk":{},"consumedRu":{},"cpuTime":{"valuePlusOne":"0.000539911s"}},"output":{"numBatches":{"valuePlusOne":"2"},"numTuples":{"valuePlusOne":"2"}},"flowStats":{"maxMemUsage":{},"maxDiskUsage":{},"consumedRu":{}}} 54.236ms 53.816ms event:ComponentStats{ID: {788f1f7c-f6bd-4722-b1e6-aaa2928c02c3 FLOW 0 3 us-east1}} 54.228ms -0.008ms structured:{"@type":"type.googleapis.com/cockroach.sql.distsqlrun.ComponentStats","component":{"flowId":"788f1f7c-f6bd-4722-b1e6-aaa2928c02c3","type":"FLOW","sqlInstanceId":3,"region":"us-east1"},"netRx":{"latency":{},"waitTime":{},"deserializationTime":{},"tuplesReceived":{},"bytesReceived":{},"messagesReceived":{}},"netTx":{"tuplesSent":{},"bytesSent":{},"messagesSent":{}},"kv":{"bytesRead":{},"kvPairsRead":{},"tuplesRead":{},"batchRequestsIssued":{},"kvTime":{},"contentionTime":{},"numInterfaceSteps":{},"numInternalSteps":{},"numInterfaceSeeks":{},"numInternalSeeks":{},"blockBytes":{},"blockBytesInCache":{},"keyBytes":{},"valueBytes":{},"pointCount":{},"pointsCoveredByRangeTombstones":{},"rangeKeyCount":{},"rangeKeyContainedPoints":{},"rangeKeySkippedPoints":{},"kvCpuTime":{},"numGets":{},"numScans":{},"numReverseScans":{}},"exec":{"execTime":{},"maxAllocatedMem":{},"maxAllocatedDisk":{},"consumedRu":{},"cpuTime":{}},"output":{"numBatches":{},"numTuples":{}},"flowStats":{"maxMemUsage":{"valuePlusOne":"20481"},"maxDiskUsage":{"valuePlusOne":"1"},"consumedRu":{}}} 54.290ms 54.063ms event:sql/conn_executor_exec.go:1187 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] execution ends 54.304ms 0.014ms event:sql/conn_executor_exec.go:1187 [n3,client=10.142.0.80:53428,hostssl,user=roachprod] rows affected: 1